[68.958229] Initializing watchdogs
[68.958706]   /dev/watchdog0
[69.043697] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[69.083778] [001/161] (960s left) i915_module_load (load)
[69.534871] Starting subtest: load
[73.823527] Subtest load: SUCCESS (4.302s)
[74.025873] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[74.032203] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[74.078591] [002/161] (955s left) core_auth (basic-auth)
[74.408408] Starting subtest: basic-auth
[74.431124] Subtest basic-auth: SUCCESS (0.002s)
[74.582210] [003/161] (955s left) debugfs_test (read_all_entries)
[74.831657] Starting subtest: read_all_entries
[75.008716] Subtest read_all_entries: SUCCESS (0.158s)
[75.300913] [004/161] (954s left) debugfs_test (basic-hwmon)
[75.566484] Starting subtest: basic-hwmon
[75.604831] Subtest basic-hwmon: SKIP (0.000s)
[75.829802] [005/161] (954s left) debugfs_test (sysfs)
[76.095286] Starting subtest: sysfs
[76.229780] Subtest sysfs: SUCCESS (0.174s)
[76.528681] [006/161] (953s left) fbdev (eof)
[76.784978] Starting subtest: eof
[76.804705] Subtest eof: SUCCESS (0.003s)
[76.958546] [007/161] (953s left) fbdev (info)
[77.172463] Starting subtest: info
[77.192319] Subtest info: SUCCESS (0.000s)
[77.344685] [008/161] (953s left) fbdev (nullptr)
[77.571897] Starting subtest: nullptr
[77.594234] Subtest nullptr: SUCCESS (0.000s)
[77.749219] [009/161] (953s left) fbdev (read)
[77.994115] Starting subtest: read
[79.464466] Subtest read: SUCCESS (1.499s)
[79.623827] [010/161] (951s left) fbdev (write)
[79.833583] Starting subtest: write
[80.610619] Subtest write: SUCCESS (0.776s)
[80.784819] [011/161] (950s left) gem_basic (bad-close)
[81.027459] Starting subtest: bad-close
[81.067353] Subtest bad-close: SUCCESS (0.000s)
[81.222123] [012/161] (950s left) gem_basic (create-close)
[81.490227] Starting subtest: create-close
[81.530453] Subtest create-close: SUCCESS (0.000s)
[81.687267] [013/161] (949s left) gem_basic (create-fd-close)
[81.953268] Starting subtest: create-fd-close
[81.993170] Subtest create-fd-close: SUCCESS (0.000s)
[82.147350] [014/161] (949s left) gem_busy (busy@all-engines)
[82.407041] Starting subtest: busy
[82.429401] Starting dynamic subtest: all-engines
[82.509462] Dynamic subtest all-engines: SUCCESS (0.119s)
[82.531991] Subtest busy: SUCCESS (0.120s)
[82.991130] [015/161] (948s left) gem_close_race (basic-process)
[83.243241] Starting subtest: basic-process
[83.349555] Subtest basic-process: SUCCESS (0.115s)
[83.640380] [016/161] (948s left) gem_close_race (basic-threads)
[83.902893] Starting subtest: basic-threads
[85.194819] Subtest basic-threads: SUCCESS (1.296s)
[85.481149] [017/161] (946s left) gem_ctx_create (basic)
[85.753940] Starting subtest: basic
[85.773764] Subtest basic: SUCCESS (0.000s)
[86.033511] [018/161] (946s left) gem_ctx_create (basic-files)
[86.300570] Starting subtest: basic-files
[88.338713] Subtest basic-files: SUCCESS (2.040s)
[88.661533] [019/161] (943s left) gem_ctx_exec (basic)
[88.915156] Starting subtest: basic
[88.936550] Subtest basic: SUCCESS (0.010s)
[89.091252] [020/161] (943s left) gem_exec_basic (basic)
[89.370276] Starting subtest: basic
[89.390962] Starting dynamic subtest: rcs0-smem
[89.411634] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[89.461730] Starting dynamic subtest: bcs0-smem
[89.483969] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[89.531151] Starting dynamic subtest: vcs0-smem
[89.551242] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[89.611657] Starting dynamic subtest: vecs0-smem
[89.633928] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[89.654552] Subtest basic: SUCCESS (0.014s)
[89.809735] [021/161] (943s left) gem_exec_create (basic)
[90.081687] Starting subtest: basic
[90.102656] Starting dynamic subtest: smem
[92.163751] Dynamic subtest smem: SUCCESS (2.082s)
[92.183864] Subtest basic: SUCCESS (2.083s)
[92.428201] [022/161] (940s left) gem_exec_fence (basic-busy)
[92.834406] Starting subtest: basic-busy
[92.856915] Starting dynamic subtest: rcs0
[92.899548] Dynamic subtest rcs0: SUCCESS (0.035s)
[92.946166] Starting dynamic subtest: bcs0
[92.990172] Dynamic subtest bcs0: SUCCESS (0.033s)
[93.031228] Starting dynamic subtest: vcs0
[93.074477] Dynamic subtest vcs0: SUCCESS (0.034s)
[93.117217] Starting dynamic subtest: vecs0
[93.159795] Dynamic subtest vecs0: SUCCESS (0.045s)
[93.182453] Subtest basic-busy: SUCCESS (0.148s)
[93.829827] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[93.852995] [023/161] (939s left) gem_exec_fence (basic-wait)
[94.240995] Starting subtest: basic-wait
[94.261773] Starting dynamic subtest: rcs0
[94.284984] Dynamic subtest rcs0: SUCCESS (0.032s)
[94.327970] Starting dynamic subtest: bcs0
[94.347430] Dynamic subtest bcs0: SUCCESS (0.031s)
[94.390706] Starting dynamic subtest: vcs0
[94.417987] Dynamic subtest vcs0: SUCCESS (0.043s)
[94.459585] Starting dynamic subtest: vecs0
[94.480498] Dynamic subtest vecs0: SUCCESS (0.031s)
[94.502523] Subtest basic-wait: SUCCESS (0.139s)
[95.186296] [024/161] (938s left) gem_exec_fence (basic-await)
[95.569867] Starting subtest: basic-await
[95.593102] Starting dynamic subtest: rcs0
[95.717735] Dynamic subtest rcs0: SUCCESS (0.167s)
[95.761819] Starting dynamic subtest: bcs0
[95.884453] Dynamic subtest bcs0: SUCCESS (0.164s)
[95.928317] Starting dynamic subtest: vcs0
[96.047663] Dynamic subtest vcs0: SUCCESS (0.163s)
[96.094125] Starting dynamic subtest: vecs0
[96.214412] Dynamic subtest vecs0: SUCCESS (0.168s)
[96.235341] Subtest basic-await: SUCCESS (0.665s)
[97.043195] [025/161] (936s left) gem_exec_fence (nb-await)
[97.439279] Starting subtest: nb-await
[97.461273] Starting dynamic subtest: rcs0
[97.521794] Dynamic subtest rcs0: SUCCESS (0.083s)
[97.567481] Starting dynamic subtest: bcs0
[97.611992] Dynamic subtest bcs0: SUCCESS (0.071s)
[97.672599] Starting dynamic subtest: vcs0
[97.696185] Dynamic subtest vcs0: SUCCESS (0.076s)
[97.755625] Starting dynamic subtest: vecs0
[97.775693] Dynamic subtest vecs0: SUCCESS (0.072s)
[97.795479] Subtest nb-await: SUCCESS (0.306s)
[98.558305] [026/161] (935s left) gem_exec_gttfill (basic)
[98.806477] Starting subtest: basic
[128.799935] Subtest basic: SUCCESS (29.994s)
[129.138144] [027/161] (904s left) gem_exec_parallel (engines)
[129.550206] Starting subtest: engines
[129.569572] Starting dynamic subtest: basic
[133.921111] Dynamic subtest basic: SUCCESS (4.203s)
[133.966559] Starting dynamic subtest: contexts
[139.338463] Dynamic subtest contexts: SUCCESS (5.419s)
[139.384260] Starting dynamic subtest: fds
[144.881060] Dynamic subtest fds: SUCCESS (5.548s)
[144.930748] Starting dynamic subtest: userptr
[149.014568] Dynamic subtest userptr: SUCCESS (4.131s)
[149.036648] Subtest engines: SUCCESS (19.302s)
[149.212810] [028/161] (884s left) gem_exec_store (basic)
[149.553879] Starting subtest: basic
[149.574365] Subtest basic: SUCCESS (0.080s)
[149.843488] [029/161] (884s left) gem_flink_basic (bad-flink)
[150.092411] Starting subtest: bad-flink
[150.112429] Subtest bad-flink: SUCCESS (0.000s)
[150.281303] [030/161] (884s left) gem_flink_basic (bad-open)
[150.521721] Starting subtest: bad-open
[150.543760] Subtest bad-open: SUCCESS (0.000s)
[150.710161] [031/161] (883s left) gem_flink_basic (basic)
[150.949475] Starting subtest: basic
[150.969408] Subtest basic: SUCCESS (0.000s)
[151.138639] [032/161] (883s left) gem_flink_basic (double-flink)
[151.380778] Starting subtest: double-flink
[151.403119] Subtest double-flink: SUCCESS (0.000s)
[151.569561] [033/161] (883s left) gem_flink_basic (flink-lifetime)
[151.805299] Starting subtest: flink-lifetime
[151.825241] Subtest flink-lifetime: SUCCESS (0.003s)
[151.993232] [034/161] (883s left) gem_huc_copy (huc-copy)
[152.234726] Starting subtest: huc-copy
[152.276349] Subtest huc-copy: SKIP (0.000s)
[152.445286] [035/161] (882s left) gem_linear_blits (basic)
[152.834212] Starting subtest: basic
[152.877285] Subtest basic: SUCCESS (0.030s)
[153.155239] [036/161] (882s left) gem_mmap (basic)
[153.565901] Starting subtest: basic
[153.670119] Subtest basic: SUCCESS (0.001s)
[153.836579] [037/161] (881s left) gem_mmap_gtt (basic)
[154.104199] Starting subtest: basic
[154.126386] Subtest basic: SUCCESS (0.002s)
[154.291958] [038/161] (881s left) gem_render_linear_blits (basic)
[154.552962] Starting subtest: basic
[154.660622] Subtest basic: SUCCESS (0.099s)
[154.846182] [039/161] (881s left) gem_render_tiled_blits (basic)
[155.111177] Starting subtest: basic
[155.241123] Subtest basic: SUCCESS (0.124s)
[155.408575] [040/161] (880s left) gem_ringfill (basic-all)
[155.981557] Starting subtest: basic-all
[157.188537] Subtest basic-all: SUCCESS (1.231s)
[157.417443] [041/161] (878s left) gem_softpin (allocator-basic)
[157.689569] Starting subtest: allocator-basic
[157.911956] Subtest allocator-basic: SUCCESS (0.265s)
[158.128436] [042/161] (878s left) gem_softpin (allocator-basic-reserve)
[158.375676] Starting subtest: allocator-basic-reserve
[158.621892] Subtest allocator-basic-reserve: SUCCESS (0.289s)
[158.858900] [043/161] (877s left) gem_softpin (safe-alignment)
[159.101412] Starting subtest: safe-alignment
[159.121281] Subtest safe-alignment: SUCCESS (0.036s)
[159.344746] [044/161] (877s left) gem_sync (basic-all)
[159.814363] Starting subtest: basic-all
[161.877746] Subtest basic-all: SUCCESS (2.263s)
[162.191101] [045/161] (874s left) gem_sync (basic-each)
[162.666852] Starting subtest: basic-each
[164.833061] Subtest basic-each: SUCCESS (2.405s)
[165.130735] [046/161] (871s left) gem_tiled_blits (basic)
[165.478978] Starting subtest: basic
[165.536812] Subtest basic: SUCCESS (0.059s)
[165.820594] [047/161] (871s left) gem_tiled_fence_blits (basic)
[166.356481] Starting subtest: basic
[166.399681] Subtest basic: SUCCESS (0.045s)
[166.694323] [048/161] (870s left) gem_tiled_pread_basic
[167.543750] [049/161] (870s left) gem_wait (busy@all-engines)
[167.818954] Starting subtest: busy
[167.841944] Starting dynamic subtest: all-engines
[168.355530] Dynamic subtest all-engines: SUCCESS (0.533s)
[168.377753] Subtest busy: SUCCESS (0.534s)
[168.711783] [050/161] (869s left) gem_wait (wait@all-engines)
[168.953911] Starting subtest: wait
[168.975856] Starting dynamic subtest: all-engines
[170.009132] Dynamic subtest all-engines: SUCCESS (1.050s)
[170.029714] Subtest wait: SUCCESS (1.051s)
[170.351975] [051/161] (867s left) i915_getparams_basic (basic-eu-total)
[170.602256] Starting subtest: basic-eu-total
[170.644241] Subtest basic-eu-total: SUCCESS (0.000s)
[170.812499] [052/161] (867s left) i915_getparams_basic (basic-subslice-total)
[171.082378] Starting subtest: basic-subslice-total
[171.124451] Subtest basic-subslice-total: SUCCESS (0.000s)
[171.296943] [053/161] (867s left) i915_hangman (error-state-basic)
[171.541656] Starting subtest: error-state-basic
[171.562470] Subtest error-state-basic: SUCCESS (0.022s)
[171.766190] [054/161] (866s left) i915_pciid
[172.195405] [055/161] (866s left) kms_addfb_basic (addfb25-4-tiled)
[172.603754] Starting subtest: addfb25-4-tiled
[172.624341] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[172.814867] [056/161] (865s left) kms_addfb_basic (addfb25-bad-modifier)
[173.160570] Starting subtest: addfb25-bad-modifier
[173.180854] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[173.360481] [057/161] (865s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[173.710144] Starting subtest: addfb25-framebuffer-vs-set-tiling
[173.732544] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[173.910393] [058/161] (865s left) kms_addfb_basic (addfb25-modifier-no-flag)
[174.265179] Starting subtest: addfb25-modifier-no-flag
[174.287312] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[174.461309] [059/161] (864s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[174.807181] Starting subtest: addfb25-x-tiled-legacy
[174.828146] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[175.019203] [060/161] (864s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[175.378618] Starting subtest: addfb25-x-tiled-mismatch-legacy
[175.405901] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[175.591877] [061/161] (864s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[175.964482] Starting subtest: addfb25-yf-tiled-legacy
[175.987605] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[176.173629] [062/161] (863s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[176.506487] Starting subtest: addfb25-y-tiled-legacy
[176.527803] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[176.716438] [063/161] (863s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[177.061273] Starting subtest: addfb25-y-tiled-small-legacy
[177.083374] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[177.261234] [064/161] (862s left) kms_addfb_basic (bad-pitch-0)
[177.497517] Starting subtest: bad-pitch-0
[177.517058] Subtest bad-pitch-0: SUCCESS (0.000s)
[177.808722] [065/161] (862s left) kms_addfb_basic (bad-pitch-1024)
[178.069225] Starting subtest: bad-pitch-1024
[178.088503] Subtest bad-pitch-1024: SUCCESS (0.000s)
[178.364097] [066/161] (862s left) kms_addfb_basic (bad-pitch-128)
[178.625424] Starting subtest: bad-pitch-128
[178.645798] Subtest bad-pitch-128: SUCCESS (0.000s)
[178.912760] [067/161] (861s left) kms_addfb_basic (bad-pitch-256)
[179.154207] Starting subtest: bad-pitch-256
[179.174673] Subtest bad-pitch-256: SUCCESS (0.000s)
[179.464835] [068/161] (861s left) kms_addfb_basic (bad-pitch-32)
[179.722040] Starting subtest: bad-pitch-32
[179.742857] Subtest bad-pitch-32: SUCCESS (0.000s)
[180.011758] [069/161] (860s left) kms_addfb_basic (bad-pitch-63)
[180.258101] Starting subtest: bad-pitch-63
[180.278981] Subtest bad-pitch-63: SUCCESS (0.000s)
[180.559656] [070/161] (860s left) kms_addfb_basic (bad-pitch-65536)
[180.803447] Starting subtest: bad-pitch-65536
[180.823975] Subtest bad-pitch-65536: SUCCESS (0.001s)
[181.116263] [071/161] (860s left) kms_addfb_basic (bad-pitch-999)
[181.359458] Starting subtest: bad-pitch-999
[181.381254] Subtest bad-pitch-999: SUCCESS (0.000s)
[181.686363] [072/161] (859s left) kms_addfb_basic (basic)
[181.953791] Starting subtest: basic
[181.973202] Subtest basic: SUCCESS (0.000s)
[182.247635] [073/161] (859s left) kms_addfb_basic (basic-x-tiled-legacy)
[182.507363] Starting subtest: basic-x-tiled-legacy
[182.528885] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[182.785638] [074/161] (859s left) kms_addfb_basic (basic-y-tiled-legacy)
[183.043991] Starting subtest: basic-y-tiled-legacy
[183.065692] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[183.342471] [075/161] (858s left) kms_addfb_basic (bo-too-small)
[183.686020] Starting subtest: bo-too-small
[183.705275] Subtest bo-too-small: SUCCESS (0.001s)
[183.877513] [076/161] (858s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[184.228920] Starting subtest: bo-too-small-due-to-tiling
[184.248418] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[184.430302] [077/161] (857s left) kms_addfb_basic (clobberred-modifier)
[184.678486] Starting subtest: clobberred-modifier
[184.708919] Subtest clobberred-modifier: SUCCESS (0.000s)
[184.989999] [078/161] (857s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[185.254065] Starting subtest: framebuffer-vs-set-tiling
[185.279870] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[185.547463] [079/161] (857s left) kms_addfb_basic (invalid-get-prop)
[185.806679] Starting subtest: invalid-get-prop
[185.825475] Subtest invalid-get-prop: SUCCESS (0.000s)
[186.110495] [080/161] (856s left) kms_addfb_basic (invalid-get-prop-any)
[186.355339] Starting subtest: invalid-get-prop-any
[186.374495] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[186.660837] [081/161] (856s left) kms_addfb_basic (invalid-set-prop)
[186.923352] Starting subtest: invalid-set-prop
[186.942640] Subtest invalid-set-prop: SUCCESS (0.000s)
[187.212717] [082/161] (855s left) kms_addfb_basic (invalid-set-prop-any)
[187.453900] Starting subtest: invalid-set-prop-any
[187.476104] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[187.761962] [083/161] (855s left) kms_addfb_basic (no-handle)
[187.998837] Starting subtest: no-handle
[188.018159] Subtest no-handle: SUCCESS (0.000s)
[188.295144] [084/161] (855s left) kms_addfb_basic (size-max)
[188.639552] Starting subtest: size-max
[188.659373] Subtest size-max: SUCCESS (0.000s)
[188.831429] [085/161] (854s left) kms_addfb_basic (small-bo)
[189.175467] Starting subtest: small-bo
[189.202283] Subtest small-bo: SUCCESS (0.000s)
[189.383471] [086/161] (854s left) kms_addfb_basic (tile-pitch-mismatch)
[189.639663] Starting subtest: tile-pitch-mismatch
[189.659701] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[189.917967] [087/161] (854s left) kms_addfb_basic (too-high)
[190.263650] Starting subtest: too-high
[190.285200] Subtest too-high: SUCCESS (0.002s)
[190.463450] [088/161] (853s left) kms_addfb_basic (too-wide)
[190.804879] Starting subtest: too-wide
[190.827105] Subtest too-wide: SUCCESS (0.000s)
[190.993846] [089/161] (853s left) kms_addfb_basic (unused-handle)
[191.255891] Starting subtest: unused-handle
[191.276158] Subtest unused-handle: SUCCESS (0.000s)
[191.547335] [090/161] (852s left) kms_addfb_basic (unused-modifier)
[191.811095] Starting subtest: unused-modifier
[191.830249] Subtest unused-modifier: SUCCESS (0.000s)
[192.095961] [091/161] (852s left) kms_addfb_basic (unused-offsets)
[192.337537] Starting subtest: unused-offsets
[192.357763] Subtest unused-offsets: SUCCESS (0.000s)
[192.613768] [092/161] (852s left) kms_addfb_basic (unused-pitches)
[192.871448] Starting subtest: unused-pitches
[192.892720] Subtest unused-pitches: SUCCESS (0.000s)
[193.160926] [093/161] (851s left) kms_busy (basic)
[193.514299] Starting subtest: basic
[193.534804] Starting dynamic subtest: flip
[194.335807] Dynamic subtest flip: SUCCESS (0.821s)
[194.401066] Starting dynamic subtest: modeset
[195.816750] Dynamic subtest modeset: SUCCESS (1.472s)
[195.837655] Subtest basic: SUCCESS (2.297s)
[196.287302] [094/161] (848s left) kms_prop_blob (basic)
[196.533887] Starting subtest: basic
[196.557031] Subtest basic: SUCCESS (0.000s)
[196.723683] [095/161] (848s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[197.068771] Starting subtest: basic-busy-flip-before-cursor-atomic
[197.108267] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[197.280594] [096/161] (848s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[197.630290] Starting subtest: basic-busy-flip-before-cursor-legacy
[197.670356] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[197.840863] [097/161] (847s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[198.206759] Starting subtest: basic-flip-after-cursor-atomic
[199.219057] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.002s)
[199.663187] [098/161] (846s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[199.990662] Starting subtest: basic-flip-after-cursor-legacy
[200.989231] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.990s)
[201.436861] [099/161] (844s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[201.783805] Starting subtest: basic-flip-after-cursor-varying-size
[202.796276] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.996s)
[203.210988] [100/161] (842s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[203.564114] Starting subtest: basic-flip-before-cursor-atomic
[204.572819] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.998s)
[205.003468] [101/161] (841s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[205.348284] Starting subtest: basic-flip-before-cursor-legacy
[206.324382] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.982s)
[206.788246] [102/161] (839s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[207.159183] Starting subtest: basic-flip-before-cursor-varying-size
[208.133954] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.997s)
[208.587216] [103/161] (838s left) kms_dsc (dsc-basic)
[208.967868] Subtest dsc-basic: SKIP (0.000s)
[209.136680] [104/161] (837s left) kms_flip (basic-flip-vs-dpms)
[209.516668] Starting subtest: basic-flip-vs-dpms
[209.538681] Starting dynamic subtest: A-DP1
[210.766471] Dynamic subtest A-DP1: SUCCESS (1.264s)
[210.814896] Starting dynamic subtest: B-DP1
[211.923140] Dynamic subtest B-DP1: SUCCESS (1.162s)
[211.971492] Starting dynamic subtest: C-DP1
[213.095300] Dynamic subtest C-DP1: SUCCESS (1.160s)
[213.117244] Subtest basic-flip-vs-dpms: SUCCESS (3.595s)
[213.583487] [105/161] (833s left) kms_flip (basic-flip-vs-modeset)
[213.959249] Starting subtest: basic-flip-vs-modeset
[213.980697] Starting dynamic subtest: A-DP1
[215.237484] Dynamic subtest A-DP1: SUCCESS (1.279s)
[215.285813] Starting dynamic subtest: B-DP1
[216.384669] Dynamic subtest B-DP1: SUCCESS (1.166s)
[216.434088] Starting dynamic subtest: C-DP1
[217.567118] Dynamic subtest C-DP1: SUCCESS (1.165s)
[217.586610] Subtest basic-flip-vs-modeset: SUCCESS (3.620s)
[218.026841] [106/161] (829s left) kms_flip (basic-flip-vs-wf_vblank)
[218.400576] Starting subtest: basic-flip-vs-wf_vblank
[218.420920] Starting dynamic subtest: A-DP1
[219.822794] Dynamic subtest A-DP1: SUCCESS (1.442s)
[219.869682] Starting dynamic subtest: B-DP1
[221.241699] Dynamic subtest B-DP1: SUCCESS (1.417s)
[221.287604] Starting dynamic subtest: C-DP1
[222.667197] Dynamic subtest C-DP1: SUCCESS (1.423s)
[222.687967] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.294s)
[223.212563] [107/161] (824s left) kms_flip (basic-plain-flip)
[223.588451] Starting subtest: basic-plain-flip
[223.611279] Starting dynamic subtest: A-DP1
[224.743994] Dynamic subtest A-DP1: SUCCESS (1.164s)
[224.793604] Starting dynamic subtest: B-DP1
[225.875993] Dynamic subtest B-DP1: SUCCESS (1.136s)
[225.924869] Starting dynamic subtest: C-DP1
[227.016261] Dynamic subtest C-DP1: SUCCESS (1.139s)
[227.039027] Subtest basic-plain-flip: SUCCESS (3.452s)
[227.546799] [108/161] (819s left) kms_force_connector_basic (force-connector-state)
[227.819543] Subtest force-connector-state: SKIP (0.000s)
[227.991882] [109/161] (819s left) kms_force_connector_basic (force-edid)
[228.265813] Subtest force-edid: SKIP (0.000s)
[228.436152] [110/161] (819s left) kms_force_connector_basic (force-load-detect)
[228.701292] Subtest force-load-detect: SKIP (0.000s)
[228.873927] [111/161] (819s left) kms_force_connector_basic (prune-stale-modes)
[229.140335] Subtest prune-stale-modes: SKIP (0.000s)
[229.314351] [112/161] (818s left) kms_frontbuffer_tracking (basic)
[229.743701] Starting subtest: basic
[233.557614] Subtest basic: SUCCESS (3.879s)
[233.780642] [113/161] (814s left) kms_hdmi_inject (inject-audio)
[234.052561] Subtest inject-audio: SKIP (0.000s)
[234.251210] [114/161] (814s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[234.612568] Starting subtest: compare-crc-sanitycheck-xr24
[234.633834] Starting dynamic subtest: pipe-A-DP-1
[235.069120] Dynamic subtest pipe-A-DP-1: SUCCESS (0.462s)
[235.121254] Starting dynamic subtest: pipe-B-DP-1
[235.710100] Dynamic subtest pipe-B-DP-1: SUCCESS (0.635s)
[235.759217] Starting dynamic subtest: pipe-C-DP-1
[236.370648] Dynamic subtest pipe-C-DP-1: SUCCESS (0.640s)
[236.394770] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.754s)
[236.827418] [115/161] (812s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[237.184569] Starting subtest: compare-crc-sanitycheck-nv12
[237.207337] Starting dynamic subtest: pipe-A-DP-1
[237.247761] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[237.321076] Starting dynamic subtest: pipe-B-DP-1
[237.364125] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[237.411383] Starting dynamic subtest: pipe-C-DP-1
[237.456058] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[237.496758] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[237.668177] [116/161] (811s left) kms_pipe_crc_basic (hang-read-crc)
[238.004351] Starting subtest: hang-read-crc
[238.026906] Starting dynamic subtest: pipe-A-DP-1
[239.329056] Dynamic subtest pipe-A-DP-1: SUCCESS (1.323s)
[239.398914] Starting dynamic subtest: pipe-B-DP-1
[240.808940] Dynamic subtest pipe-B-DP-1: SUCCESS (1.473s)
[240.857669] Starting dynamic subtest: pipe-C-DP-1
[242.317959] Dynamic subtest pipe-C-DP-1: SUCCESS (1.504s)
[242.339626] Subtest hang-read-crc: SUCCESS (4.315s)
[242.744663] [117/161] (806s left) kms_pipe_crc_basic (nonblocking-crc)
[243.103307] Starting subtest: nonblocking-crc
[243.123344] Starting dynamic subtest: pipe-A-DP-1
[243.740349] Dynamic subtest pipe-A-DP-1: SUCCESS (0.632s)
[243.808297] Starting dynamic subtest: pipe-B-DP-1
[244.544395] Dynamic subtest pipe-B-DP-1: SUCCESS (0.806s)
[244.593815] Starting dynamic subtest: pipe-C-DP-1
[245.349846] Dynamic subtest pipe-C-DP-1: SUCCESS (0.796s)
[245.376542] Subtest nonblocking-crc: SUCCESS (2.250s)
[245.808096] [118/161] (803s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[246.178912] Starting subtest: nonblocking-crc-frame-sequence
[246.200457] Starting dynamic subtest: pipe-A-DP-1
[246.813424] Dynamic subtest pipe-A-DP-1: SUCCESS (0.636s)
[246.860620] Starting dynamic subtest: pipe-B-DP-1
[247.596434] Dynamic subtest pipe-B-DP-1: SUCCESS (0.793s)
[247.645753] Starting dynamic subtest: pipe-C-DP-1
[248.434733] Dynamic subtest pipe-C-DP-1: SUCCESS (0.817s)
[248.455194] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.261s)
[248.855270] [119/161] (800s left) kms_pipe_crc_basic (read-crc)
[249.219293] Starting subtest: read-crc
[249.238574] Starting dynamic subtest: pipe-A-DP-1
[249.801238] Dynamic subtest pipe-A-DP-1: SUCCESS (0.580s)
[249.868788] Starting dynamic subtest: pipe-B-DP-1
[250.535721] Dynamic subtest pipe-B-DP-1: SUCCESS (0.732s)
[250.588221] Starting dynamic subtest: pipe-C-DP-1
[251.296759] Dynamic subtest pipe-C-DP-1: SUCCESS (0.748s)
[251.316531] Subtest read-crc: SUCCESS (2.076s)
[251.717147] [120/161] (798s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[252.077865] Starting subtest: read-crc-frame-sequence
[252.097135] Starting dynamic subtest: pipe-A-DP-1
[252.630340] Dynamic subtest pipe-A-DP-1: SUCCESS (0.558s)
[252.687146] Starting dynamic subtest: pipe-B-DP-1
[253.394325] Dynamic subtest pipe-B-DP-1: SUCCESS (0.746s)
[253.452639] Starting dynamic subtest: pipe-C-DP-1
[254.165710] Dynamic subtest pipe-C-DP-1: SUCCESS (0.759s)
[254.190282] Subtest read-crc-frame-sequence: SUCCESS (2.080s)
[254.582122] [121/161] (795s left) kms_pm_backlight (basic-brightness)
[254.971452] Subtest basic-brightness: SKIP (0.000s)
[255.163244] [122/161] (794s left) kms_pm_rpm (basic-pci-d3-state)
[257.002171] Starting subtest: basic-pci-d3-state
[257.671433] Subtest basic-pci-d3-state: SUCCESS (0.667s)
[257.992631] [123/161] (792s left) kms_pm_rpm (basic-rte)
[258.230233] Starting subtest: basic-rte
[260.449693] Subtest basic-rte: SUCCESS (2.248s)
[261.433787] [124/161] (789s left) kms_psr (psr-primary-page-flip)
[261.819780] Subtest psr-primary-page-flip: SKIP (0.000s)
[261.990876] [125/161] (788s left) kms_psr (psr-cursor-plane-move)
[262.365402] Subtest psr-cursor-plane-move: SKIP (0.000s)
[262.547328] [126/161] (788s left) kms_psr (psr-sprite-plane-onoff)
[262.921827] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[263.093599] [127/161] (787s left) kms_psr (psr-primary-mmap-gtt)
[263.462108] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[263.633819] [128/161] (787s left) kms_setmode (basic-clone-single-crtc)
[263.897447] Starting subtest: basic-clone-single-crtc
[263.962560] Subtest basic-clone-single-crtc: SKIP (0.000s)
[264.132752] [129/161] (787s left) i915_pm_rps (basic-api)
[264.390058] Starting subtest: basic-api
[264.412622] Subtest basic-api: SUCCESS (0.008s)
[264.612141] [130/161] (786s left) prime_self_import (basic-llseek-bad)
[264.865506] Starting subtest: basic-llseek-bad
[264.908387] Subtest basic-llseek-bad: SUCCESS (0.005s)
[265.078747] [131/161] (786s left) prime_self_import (basic-llseek-size)
[265.299903] Starting subtest: basic-llseek-size
[265.342139] Subtest basic-llseek-size: SUCCESS (0.008s)
[265.514546] [132/161] (786s left) prime_self_import (basic-with_fd_dup)
[265.750988] Starting subtest: basic-with_fd_dup
[265.794826] Subtest basic-with_fd_dup: SUCCESS (0.008s)
[265.967282] [133/161] (786s left) prime_self_import (basic-with_one_bo)
[266.188554] Starting subtest: basic-with_one_bo
[266.228343] Subtest basic-with_one_bo: SUCCESS (0.008s)
[266.408708] [134/161] (785s left) prime_self_import (basic-with_one_bo_two_files)
[266.650772] Starting subtest: basic-with_one_bo_two_files
[266.692934] Subtest basic-with_one_bo_two_files: SUCCESS (0.006s)
[266.867886] [135/161] (785s left) prime_self_import (basic-with_two_bos)
[267.107134] Starting subtest: basic-with_two_bos
[267.152894] Subtest basic-with_two_bos: SUCCESS (0.007s)
[267.324125] [136/161] (785s left) prime_vgem (basic-fence-flip)
[267.590563] Starting subtest: basic-fence-flip
[268.227870] Subtest basic-fence-flip: SUCCESS (0.674s)
[268.433841] [137/161] (784s left) prime_vgem (basic-fence-mmap)
[268.716417] Starting subtest: basic-fence-mmap
[268.760966] Subtest basic-fence-mmap: SUCCESS (0.071s)
[268.953626] [138/161] (784s left) prime_vgem (basic-fence-read)
[269.235590] Starting subtest: basic-fence-read
[269.345691] Subtest basic-fence-read: SUCCESS (0.143s)
[269.552368] [139/161] (783s left) prime_vgem (basic-gtt)
[269.815827] Starting subtest: basic-gtt
[269.838423] Subtest basic-gtt: SUCCESS (0.035s)
[270.037260] [140/161] (783s left) prime_vgem (basic-read)
[270.300452] Starting subtest: basic-read
[270.350808] Subtest basic-read: SUCCESS (0.066s)
[270.553931] [141/161] (782s left) prime_vgem (basic-write)
[270.841489] Starting subtest: basic-write
[270.892572] Subtest basic-write: SUCCESS (0.097s)
[271.102891] [142/161] (782s left) vgem_basic (setversion)
[271.374469] Starting subtest: setversion
[271.416953] Subtest setversion: SUCCESS (0.000s)
[271.588074] [143/161] (782s left) vgem_basic (create)
[271.844309] Starting subtest: create
[271.867323] Subtest create: SUCCESS (0.001s)
[272.038087] [144/161] (782s left) vgem_basic (debugfs)
[272.299753] Starting subtest: debugfs
[272.319299] Subtest debugfs: SUCCESS (0.001s)
[272.491473] [145/161] (781s left) vgem_basic (dmabuf-export)
[272.741266] Starting subtest: dmabuf-export
[272.780361] Subtest dmabuf-export: SUCCESS (0.013s)
[272.953172] [146/161] (781s left) vgem_basic (dmabuf-fence)
[273.214215] Starting subtest: dmabuf-fence
[273.233984] Subtest dmabuf-fence: SUCCESS (0.002s)
[273.407901] [147/161] (781s left) vgem_basic (dmabuf-fence-before)
[273.650248] Starting subtest: dmabuf-fence-before
[273.672623] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[273.841457] [148/161] (780s left) vgem_basic (dmabuf-mmap)
[274.082515] Starting subtest: dmabuf-mmap
[274.102237] Subtest dmabuf-mmap: SUCCESS (0.038s)
[274.272988] [149/161] (780s left) vgem_basic (mmap)
[274.535595] Starting subtest: mmap
[274.557543] Subtest mmap: SUCCESS (0.024s)
[274.725393] [150/161] (780s left) vgem_basic (second-client)
[275.002493] Starting subtest: second-client
[275.057935] Subtest second-client: SUCCESS (0.032s)
[275.243310] [151/161] (780s left) vgem_basic (sysfs)
[275.530414] Starting subtest: sysfs
[275.557384] Subtest sysfs: SUCCESS (0.001s)
[275.741584] [152/161] (779s left) core_hotunplug (unbind-rebind)
[276.131339] Starting subtest: unbind-rebind
[280.777096] Subtest unbind-rebind: SUCCESS (4.651s)
[280.951110] [153/161] (774s left) vgem_basic (unload)
[281.205499] Starting subtest: unload
[310.534626] Subtest unload: SUCCESS (29.192s)
[310.704881] [154/161] (745s left) i915_module_load (reload)
[310.962641] Starting subtest: reload
[316.754170] Subtest reload: SUCCESS (5.828s)
[317.012819] [155/161] (739s left) gem_lmem_swapping (basic)
[317.328354] Subtest basic: SKIP (0.000s)
[317.522808] [156/161] (738s left) gem_lmem_swapping (parallel-random-engines)
[317.809614] Subtest parallel-random-engines: SKIP (0.000s)
[317.979299] [157/161] (738s left) gem_lmem_swapping (random-engines)
[318.266869] Subtest random-engines: SKIP (0.000s)
[318.475698] [158/161] (738s left) gem_lmem_swapping (verify-random)
[318.801230] Subtest verify-random: SKIP (0.000s)
[318.988878] [159/161] (738s left) i915_pm_rpm (module-reload)
[321.148917] Starting subtest: module-reload
[336.130490] Subtest module-reload: SUCCESS (14.998s)
[336.251737] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[336.255402] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[336.280237] [160/161] (720s left) i915_selftest (live)
[336.515448] Starting subtest: live
[336.535263] Starting dynamic subtest: sanitycheck
[340.516346] Dynamic subtest sanitycheck: SUCCESS (4.004s)
[340.574497] Starting dynamic subtest: uncore
[344.506041] Dynamic subtest uncore: SUCCESS (3.986s)
[344.549584] Starting dynamic subtest: workarounds
[348.866481] Dynamic subtest workarounds: SUCCESS (4.350s)
[348.912389] Starting dynamic subtest: gt_engines
[353.162966] Dynamic subtest gt_engines: SUCCESS (4.294s)
[353.224365] Starting dynamic subtest: gt_timelines
[376.907451] Dynamic subtest gt_timelines: SUCCESS (23.739s)
[376.948537] Starting dynamic subtest: gt_contexts
[381.121728] Dynamic subtest gt_contexts: SUCCESS (4.208s)
[381.165396] Starting dynamic subtest: gt_lrc
[388.218556] Dynamic subtest gt_lrc: SUCCESS (7.096s)
[388.262419] Starting dynamic subtest: gt_mocs
[392.098273] Dynamic subtest gt_mocs: SUCCESS (3.873s)
[392.141271] Starting dynamic subtest: gt_pm
[405.687620] Dynamic subtest gt_pm: SUCCESS (13.586s)
[405.748623] Starting dynamic subtest: gt_heartbeat
[409.526264] Dynamic subtest gt_heartbeat: SUCCESS (3.836s)
[409.567050] Starting dynamic subtest: gt_tlb
[414.732347] Dynamic subtest gt_tlb: SUCCESS (5.196s)
[414.773233] Starting dynamic subtest: requests
[425.013049] Dynamic subtest requests: SUCCESS (10.281s)
[425.069790] Starting dynamic subtest: migrate
[432.201383] Dynamic subtest migrate: SUCCESS (7.181s)
[432.242903] Starting dynamic subtest: active
[436.236313] Dynamic subtest active: SUCCESS (4.026s)
[436.279568] Starting dynamic subtest: objects
[442.426560] Dynamic subtest objects: SUCCESS (6.191s)
[442.471275] Starting dynamic subtest: mman
[453.242240] Dynamic subtest mman: SUCCESS (10.811s)
[453.283491] Starting dynamic subtest: dmabuf
[457.074208] Dynamic subtest dmabuf: SUCCESS (3.830s)
[457.117890] Starting dynamic subtest: vma
[461.082064] Dynamic subtest vma: SUCCESS (3.999s)
[461.125128] Starting dynamic subtest: coherency
[468.789633] Dynamic subtest coherency: SUCCESS (7.704s)
[468.832746] Starting dynamic subtest: gtt
[480.445059] Dynamic subtest gtt: SUCCESS (11.653s)
[480.486235] Starting dynamic subtest: gem
[485.799195] Dynamic subtest gem: SUCCESS (5.348s)
[485.842508] Starting dynamic subtest: evict
[494.664122] Dynamic subtest evict: SUCCESS (8.860s)
[494.721929] Starting dynamic subtest: hugepages
[505.360774] Dynamic subtest hugepages: SUCCESS (10.692s)
[505.404218] Starting dynamic subtest: gem_contexts
[532.076981] Dynamic subtest gem_contexts: SUCCESS (26.714s)
[532.118713] Starting dynamic subtest: client
[536.040604] Dynamic subtest client: SUCCESS (3.960s)
[536.084764] Starting dynamic subtest: gem_migrate
[539.782994] Dynamic subtest gem_migrate: SUCCESS (3.732s)
[539.830496] Starting dynamic subtest: reset
[554.842351] Dynamic subtest reset: SUCCESS (15.059s)
[554.896224] Starting dynamic subtest: memory_region
[558.758313] Dynamic subtest memory_region: SUCCESS (3.914s)
[558.803394] Starting dynamic subtest: hangcheck
[585.947851] Dynamic subtest hangcheck: SUCCESS (27.184s)
[586.006539] Starting dynamic subtest: execlists
[600.941934] Dynamic subtest execlists: SUCCESS (14.991s)
[601.000980] Starting dynamic subtest: ring_submission
[604.989770] Dynamic subtest ring_submission: SUCCESS (4.043s)
[605.050164] Starting dynamic subtest: perf
[609.132911] Dynamic subtest perf: SUCCESS (4.139s)
[609.176677] Starting dynamic subtest: slpc
[612.845558] Dynamic subtest slpc: SUCCESS (3.710s)
[612.907535] Starting dynamic subtest: guc
[616.625978] Dynamic subtest guc: SUCCESS (3.778s)
[616.670217] Starting dynamic subtest: guc_multi_lrc
[620.523497] Dynamic subtest guc_multi_lrc: SUCCESS (3.892s)
[620.609305] Starting dynamic subtest: guc_hang
[624.530557] Dynamic subtest guc_hang: SUCCESS (3.999s)
[624.613358] Starting dynamic subtest: late_gt_pm
[628.647388] Dynamic subtest late_gt_pm: SUCCESS (4.117s)
[628.668942] Subtest live: SUCCESS (292.140s)
[628.823655] [161/161] (428s left) dmabuf (all-tests)
[629.076773] Starting subtest: all-tests
[629.097397] Starting dynamic subtest: sanitycheck
[629.159183] Dynamic subtest sanitycheck: SUCCESS (0.081s)
[629.204322] Starting dynamic subtest: dma_fence
[629.414120] Dynamic subtest