[62.773386] Initializing watchdogs
[62.773620]   /dev/watchdog0
[62.830377] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:5a85 Intel Broxton (Gen9) HD Graphics 500
[62.861805] [001/161] (960s left) i915_module_load (load)
[63.131053] Starting subtest: load
[67.245493] Subtest load: SUCCESS (4.174s)
[67.410707] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[67.418888] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[67.465061] [002/161] (956s left) core_auth (basic-auth)
[67.788216] Starting subtest: basic-auth
[67.812058] Subtest basic-auth: SUCCESS (0.002s)
[68.107142] [003/161] (955s left) debugfs_test (read_all_entries)
[68.443296] Starting subtest: read_all_entries
[68.681550] Subtest read_all_entries: SUCCESS (0.239s)
[69.052636] [004/161] (954s left) debugfs_test (basic-hwmon)
[69.371914] Starting subtest: basic-hwmon
[69.414757] Subtest basic-hwmon: SKIP (0.000s)
[69.706797] [005/161] (954s left) debugfs_test (sysfs)
[70.049453] Starting subtest: sysfs
[70.214420] Subtest sysfs: SUCCESS (0.202s)
[70.531589] [006/161] (953s left) fbdev (eof)
[70.762726] Starting subtest: eof
[70.784300] Subtest eof: SUCCESS (0.002s)
[70.946546] [007/161] (953s left) fbdev (info)
[71.148942] Starting subtest: info
[71.170495] Subtest info: SUCCESS (0.000s)
[71.324466] [008/161] (953s left) fbdev (nullptr)
[71.566112] Starting subtest: nullptr
[71.585606] Subtest nullptr: SUCCESS (0.000s)
[71.736758] [009/161] (953s left) fbdev (read)
[71.969800] Starting subtest: read
[73.441451] Subtest read: SUCCESS (1.501s)
[73.599599] [010/161] (951s left) fbdev (write)
[73.831715] Starting subtest: write
[74.578039] Subtest write: SUCCESS (0.776s)
[74.751325] [011/161] (950s left) gem_basic (bad-close)
[75.011278] Starting subtest: bad-close
[75.067369] Subtest bad-close: SUCCESS (0.000s)
[75.254858] [012/161] (950s left) gem_basic (create-close)
[75.559202] Starting subtest: create-close
[75.611791] Subtest create-close: SUCCESS (0.000s)
[75.789654] [013/161] (949s left) gem_basic (create-fd-close)
[76.068278] Starting subtest: create-fd-close
[76.124452] Subtest create-fd-close: SUCCESS (0.001s)
[76.338062] [014/161] (949s left) gem_busy (busy@all-engines)
[76.610325] Starting subtest: busy
[76.633088] Starting dynamic subtest: all-engines
[76.707773] Dynamic subtest all-engines: SUCCESS (0.110s)
[76.727755] Subtest busy: SUCCESS (0.111s)
[77.184990] [015/161] (948s left) gem_close_race (basic-process)
[77.459081] Starting subtest: basic-process
[77.566940] Subtest basic-process: SUCCESS (0.120s)
[77.854106] [016/161] (948s left) gem_close_race (basic-threads)
[78.113685] Starting subtest: basic-threads
[79.378450] Subtest basic-threads: SUCCESS (1.268s)
[79.660982] [017/161] (946s left) gem_ctx_create (basic)
[79.944443] Starting subtest: basic
[79.964415] Subtest basic: SUCCESS (0.000s)
[80.215446] [018/161] (946s left) gem_ctx_create (basic-files)
[80.453282] Starting subtest: basic-files
[82.520071] Subtest basic-files: SUCCESS (2.048s)
[82.834083] [019/161] (943s left) gem_ctx_exec (basic)
[83.103289] Starting subtest: basic
[83.126172] Subtest basic: SUCCESS (0.010s)
[83.293679] [020/161] (943s left) gem_exec_basic (basic)
[83.561876] Starting subtest: basic
[83.584717] Starting dynamic subtest: rcs0-smem
[83.606576] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[83.652654] Starting dynamic subtest: bcs0-smem
[83.672690] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[83.722553] Starting dynamic subtest: vcs0-smem
[83.744479] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[83.797303] Starting dynamic subtest: vecs0-smem
[83.817589] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[83.840647] Subtest basic: SUCCESS (0.015s)
[83.994977] [021/161] (942s left) gem_exec_create (basic)
[84.269865] Starting subtest: basic
[84.289987] Starting dynamic subtest: smem
[86.337087] Dynamic subtest smem: SUCCESS (2.082s)
[86.360184] Subtest basic: SUCCESS (2.083s)
[86.616455] [022/161] (940s left) gem_exec_fence (basic-busy)
[87.024278] Starting subtest: basic-busy
[87.044813] Starting dynamic subtest: rcs0
[87.095359] Dynamic subtest rcs0: SUCCESS (0.036s)
[87.146607] Starting dynamic subtest: bcs0
[87.189359] Dynamic subtest bcs0: SUCCESS (0.032s)
[87.254246] Starting dynamic subtest: vcs0
[87.293792] Dynamic subtest vcs0: SUCCESS (0.029s)
[87.336097] Starting dynamic subtest: vecs0
[87.378423] Dynamic subtest vecs0: SUCCESS (0.060s)
[87.398541] Subtest basic-busy: SUCCESS (0.160s)
[88.058219] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[88.081264] [023/161] (938s left) gem_exec_fence (basic-wait)
[88.485365] Starting subtest: basic-wait
[88.506811] Starting dynamic subtest: rcs0
[88.526793] Dynamic subtest rcs0: SUCCESS (0.036s)
[88.591364] Starting dynamic subtest: bcs0
[88.613596] Dynamic subtest bcs0: SUCCESS (0.038s)
[88.666538] Starting dynamic subtest: vcs0
[88.686978] Dynamic subtest vcs0: SUCCESS (0.070s)
[88.731459] Starting dynamic subtest: vecs0
[88.753669] Dynamic subtest vecs0: SUCCESS (0.039s)
[88.773659] Subtest basic-wait: SUCCESS (0.186s)
[89.461179] [024/161] (937s left) gem_exec_fence (basic-await)
[89.842827] Starting subtest: basic-await
[89.865757] Starting dynamic subtest: rcs0
[90.011419] Dynamic subtest rcs0: SUCCESS (0.167s)
[90.054874] Starting dynamic subtest: bcs0
[90.180604] Dynamic subtest bcs0: SUCCESS (0.165s)
[90.222975] Starting dynamic subtest: vcs0
[90.348712] Dynamic subtest vcs0: SUCCESS (0.168s)
[90.396156] Starting dynamic subtest: vecs0
[90.517272] Dynamic subtest vecs0: SUCCESS (0.170s)
[90.539740] Subtest basic-await: SUCCESS (0.674s)
[91.353426] [025/161] (936s left) gem_exec_fence (nb-await)
[91.740069] Starting subtest: nb-await
[91.762205] Starting dynamic subtest: rcs0
[91.816642] Dynamic subtest rcs0: SUCCESS (0.079s)
[91.865305] Starting dynamic subtest: bcs0
[91.915580] Dynamic subtest bcs0: SUCCESS (0.097s)
[91.962588] Starting dynamic subtest: vcs0
[92.017297] Dynamic subtest vcs0: SUCCESS (0.099s)
[92.078083] Starting dynamic subtest: vecs0
[92.098465] Dynamic subtest vecs0: SUCCESS (0.073s)
[92.121643] Subtest nb-await: SUCCESS (0.351s)
[92.925495] [026/161] (934s left) gem_exec_gttfill (basic)
[93.181141] Starting subtest: basic
[123.062870] Subtest basic: SUCCESS (29.899s)
[123.388608] [027/161] (904s left) gem_exec_parallel (engines)
[123.788330] Starting subtest: engines
[123.807885] Starting dynamic subtest: basic
[128.076323] Dynamic subtest basic: SUCCESS (4.129s)
[128.119625] Starting dynamic subtest: contexts
[133.433528] Dynamic subtest contexts: SUCCESS (5.357s)
[133.481851] Starting dynamic subtest: fds
[138.985056] Dynamic subtest fds: SUCCESS (5.596s)
[139.029118] Starting dynamic subtest: userptr
[143.236164] Dynamic subtest userptr: SUCCESS (4.209s)
[143.265175] Subtest engines: SUCCESS (19.293s)
[143.453291] [028/161] (884s left) gem_exec_store (basic)
[143.794962] Starting subtest: basic
[143.817357] Subtest basic: SUCCESS (0.085s)
[144.105147] [029/161] (884s left) gem_flink_basic (bad-flink)
[144.377111] Starting subtest: bad-flink
[144.397279] Subtest bad-flink: SUCCESS (0.000s)
[144.589523] [030/161] (883s left) gem_flink_basic (bad-open)
[144.846908] Starting subtest: bad-open
[144.868701] Subtest bad-open: SUCCESS (0.000s)
[145.034693] [031/161] (883s left) gem_flink_basic (basic)
[145.290589] Starting subtest: basic
[145.313788] Subtest basic: SUCCESS (0.000s)
[145.478615] [032/161] (883s left) gem_flink_basic (double-flink)
[145.721038] Starting subtest: double-flink
[145.740988] Subtest double-flink: SUCCESS (0.000s)
[145.912538] [033/161] (882s left) gem_flink_basic (flink-lifetime)
[146.173253] Starting subtest: flink-lifetime
[146.195057] Subtest flink-lifetime: SUCCESS (0.003s)
[146.360495] [034/161] (882s left) gem_huc_copy (huc-copy)
[146.581956] Starting subtest: huc-copy
[146.622338] Subtest huc-copy: SKIP (0.000s)
[146.788936] [035/161] (882s left) gem_linear_blits (basic)
[147.183237] Starting subtest: basic
[147.226696] Subtest basic: SUCCESS (0.028s)
[147.504332] [036/161] (881s left) gem_mmap (basic)
[147.921975] Starting subtest: basic
[148.030199] Subtest basic: SUCCESS (0.001s)
[148.219300] [037/161] (881s left) gem_mmap_gtt (basic)
[148.465585] Starting subtest: basic
[148.485636] Subtest basic: SUCCESS (0.003s)
[148.652070] [038/161] (881s left) gem_render_linear_blits (basic)
[148.873830] Starting subtest: basic
[148.980828] Subtest basic: SUCCESS (0.097s)
[149.145193] [039/161] (880s left) gem_render_tiled_blits (basic)
[149.398737] Starting subtest: basic
[149.521828] Subtest basic: SUCCESS (0.134s)
[149.708906] [040/161] (880s left) gem_ringfill (basic-all)
[150.297212] Starting subtest: basic-all
[151.498720] Subtest basic-all: SUCCESS (1.236s)
[151.707545] [041/161] (878s left) gem_softpin (allocator-basic)
[151.957940] Starting subtest: allocator-basic
[152.207832] Subtest allocator-basic: SUCCESS (0.268s)
[152.435671] [042/161] (877s left) gem_softpin (allocator-basic-reserve)
[152.678993] Starting subtest: allocator-basic-reserve
[152.916675] Subtest allocator-basic-reserve: SUCCESS (0.284s)
[153.152100] [043/161] (877s left) gem_softpin (safe-alignment)
[153.392399] Starting subtest: safe-alignment
[153.412175] Subtest safe-alignment: SUCCESS (0.036s)
[153.635357] [044/161] (877s left) gem_sync (basic-all)
[154.075209] Starting subtest: basic-all
[156.179405] Subtest basic-all: SUCCESS (2.278s)
[156.481095] [045/161] (874s left) gem_sync (basic-each)
[156.934753] Starting subtest: basic-each
[159.366724] Subtest basic-each: SUCCESS (2.627s)
[159.680492] [046/161] (871s left) gem_tiled_blits (basic)
[160.013322] Starting subtest: basic
[160.074725] Subtest basic: SUCCESS (0.058s)
[160.351845] [047/161] (870s left) gem_tiled_fence_blits (basic)
[160.874591] Starting subtest: basic
[160.921706] Subtest basic: SUCCESS (0.044s)
[161.215356] [048/161] (870s left) gem_tiled_pread_basic
[162.092528] [049/161] (869s left) gem_wait (busy@all-engines)
[162.392610] Starting subtest: busy
[162.413182] Starting dynamic subtest: all-engines
[162.915269] Dynamic subtest all-engines: SUCCESS (0.531s)
[162.935435] Subtest busy: SUCCESS (0.532s)
[163.259092] [050/161] (868s left) gem_wait (wait@all-engines)
[163.521781] Starting subtest: wait
[163.543709] Starting dynamic subtest: all-engines
[164.581545] Dynamic subtest all-engines: SUCCESS (1.048s)
[164.603138] Subtest wait: SUCCESS (1.049s)
[164.922898] [051/161] (867s left) i915_getparams_basic (basic-eu-total)
[165.172513] Starting subtest: basic-eu-total
[165.213973] Subtest basic-eu-total: SUCCESS (0.000s)
[165.383576] [052/161] (866s left) i915_getparams_basic (basic-subslice-total)
[165.645938] Starting subtest: basic-subslice-total
[165.687874] Subtest basic-subslice-total: SUCCESS (0.000s)
[165.854515] [053/161] (866s left) i915_hangman (error-state-basic)
[166.103496] Starting subtest: error-state-basic
[166.124392] Subtest error-state-basic: SUCCESS (0.026s)
[166.327622] [054/161] (866s left) i915_pciid
[166.759679] [055/161] (865s left) kms_addfb_basic (addfb25-4-tiled)
[167.190990] Starting subtest: addfb25-4-tiled
[167.210571] Subtest addfb25-4-tiled: SUCCESS (0.001s)
[167.407360] [056/161] (865s left) kms_addfb_basic (addfb25-bad-modifier)
[167.768385] Starting subtest: addfb25-bad-modifier
[167.789397] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[167.984191] [057/161] (865s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[168.332677] Starting subtest: addfb25-framebuffer-vs-set-tiling
[168.354717] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[168.545827] [058/161] (864s left) kms_addfb_basic (addfb25-modifier-no-flag)
[168.893920] Starting subtest: addfb25-modifier-no-flag
[168.916271] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s)
[169.088960] [059/161] (864s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[169.443500] Starting subtest: addfb25-x-tiled-legacy
[169.465647] Subtest addfb25-x-tiled-legacy: SUCCESS (0.000s)
[169.640448] [060/161] (863s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[169.999450] Starting subtest: addfb25-x-tiled-mismatch-legacy
[170.020474] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[170.194626] [061/161] (863s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[170.536258] Starting subtest: addfb25-yf-tiled-legacy
[170.556662] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[170.750680] [062/161] (863s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[171.099919] Starting subtest: addfb25-y-tiled-legacy
[171.125177] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[171.307660] [063/161] (862s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[171.675954] Starting subtest: addfb25-y-tiled-small-legacy
[171.702693] Subtest addfb25-y-tiled-small-legacy: SUCCESS (0.001s)
[171.884519] [064/161] (862s left) kms_addfb_basic (bad-pitch-0)
[172.145251] Starting subtest: bad-pitch-0
[172.166907] Subtest bad-pitch-0: SUCCESS (0.000s)
[172.420705] [065/161] (861s left) kms_addfb_basic (bad-pitch-1024)
[172.656899] Starting subtest: bad-pitch-1024
[172.676651] Subtest bad-pitch-1024: SUCCESS (0.000s)
[172.955654] [066/161] (861s left) kms_addfb_basic (bad-pitch-128)
[173.195557] Starting subtest: bad-pitch-128
[173.217596] Subtest bad-pitch-128: SUCCESS (0.000s)
[173.496300] [067/161] (861s left) kms_addfb_basic (bad-pitch-256)
[173.753136] Starting subtest: bad-pitch-256
[173.774894] Subtest bad-pitch-256: SUCCESS (0.000s)
[174.038830] [068/161] (860s left) kms_addfb_basic (bad-pitch-32)
[174.297320] Starting subtest: bad-pitch-32
[174.318989] Subtest bad-pitch-32: SUCCESS (0.000s)
[174.573765] [069/161] (860s left) kms_addfb_basic (bad-pitch-63)
[174.810954] Starting subtest: bad-pitch-63
[174.830336] Subtest bad-pitch-63: SUCCESS (0.000s)
[175.123648] [070/161] (860s left) kms_addfb_basic (bad-pitch-65536)
[175.361980] Starting subtest: bad-pitch-65536
[175.383216] Subtest bad-pitch-65536: SUCCESS (0.001s)
[175.673885] [071/161] (859s left) kms_addfb_basic (bad-pitch-999)
[175.934825] Starting subtest: bad-pitch-999
[175.955811] Subtest bad-pitch-999: SUCCESS (0.000s)
[176.202579] [072/161] (859s left) kms_addfb_basic (basic)
[176.454707] Starting subtest: basic
[176.476259] Subtest basic: SUCCESS (0.000s)
[176.743604] [073/161] (858s left) kms_addfb_basic (basic-x-tiled-legacy)
[177.003804] Starting subtest: basic-x-tiled-legacy
[177.022756] Subtest basic-x-tiled-legacy: SUCCESS (0.000s)
[177.286765] [074/161] (858s left) kms_addfb_basic (basic-y-tiled-legacy)
[177.523949] Starting subtest: basic-y-tiled-legacy
[177.544816] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[177.842211] [075/161] (858s left) kms_addfb_basic (bo-too-small)
[178.194321] Starting subtest: bo-too-small
[178.215417] Subtest bo-too-small: SUCCESS (0.001s)
[178.392816] [076/161] (857s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[178.742667] Starting subtest: bo-too-small-due-to-tiling
[178.764381] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[178.936496] [077/161] (857s left) kms_addfb_basic (clobberred-modifier)
[179.200660] Starting subtest: clobberred-modifier
[179.222061] Subtest clobberred-modifier: SUCCESS (0.000s)
[179.473420] [078/161] (857s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[179.735323] Starting subtest: framebuffer-vs-set-tiling
[179.757131] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[180.026178] [079/161] (856s left) kms_addfb_basic (invalid-get-prop)
[180.311458] Starting subtest: invalid-get-prop
[180.339059] Subtest invalid-get-prop: SUCCESS (0.000s)
[180.592787] [080/161] (856s left) kms_addfb_basic (invalid-get-prop-any)
[180.896285] Starting subtest: invalid-get-prop-any
[180.924503] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[181.180088] [081/161] (855s left) kms_addfb_basic (invalid-set-prop)
[181.474016] Starting subtest: invalid-set-prop
[181.500906] Subtest invalid-set-prop: SUCCESS (0.000s)
[181.745681] [082/161] (855s left) kms_addfb_basic (invalid-set-prop-any)
[182.002382] Starting subtest: invalid-set-prop-any
[182.023797] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[182.292116] [083/161] (855s left) kms_addfb_basic (no-handle)
[182.529324] Starting subtest: no-handle
[182.548733] Subtest no-handle: SUCCESS (0.000s)
[182.825630] [084/161] (854s left) kms_addfb_basic (size-max)
[183.168772] Starting subtest: size-max
[183.187791] Subtest size-max: SUCCESS (0.000s)
[183.375277] [085/161] (854s left) kms_addfb_basic (small-bo)
[183.717703] Starting subtest: small-bo
[183.736902] Subtest small-bo: SUCCESS (0.000s)
[183.910630] [086/161] (853s left) kms_addfb_basic (tile-pitch-mismatch)
[184.146390] Starting subtest: tile-pitch-mismatch
[184.168636] Subtest tile-pitch-mismatch: SUCCESS (0.001s)
[184.472437] [087/161] (853s left) kms_addfb_basic (too-high)
[184.819765] Starting subtest: too-high
[184.842667] Subtest too-high: SUCCESS (0.002s)
[185.026858] [088/161] (853s left) kms_addfb_basic (too-wide)
[185.370557] Starting subtest: too-wide
[185.392065] Subtest too-wide: SUCCESS (0.000s)
[185.565626] [089/161] (852s left) kms_addfb_basic (unused-handle)
[185.827912] Starting subtest: unused-handle
[185.850244] Subtest unused-handle: SUCCESS (0.000s)
[186.108290] [090/161] (852s left) kms_addfb_basic (unused-modifier)
[186.365762] Starting subtest: unused-modifier
[186.385208] Subtest unused-modifier: SUCCESS (0.000s)
[186.660520] [091/161] (852s left) kms_addfb_basic (unused-offsets)
[186.878111] Starting subtest: unused-offsets
[186.898816] Subtest unused-offsets: SUCCESS (0.000s)
[187.185391] [092/161] (851s left) kms_addfb_basic (unused-pitches)
[187.420166] Starting subtest: unused-pitches
[187.439287] Subtest unused-pitches: SUCCESS (0.000s)
[187.719094] [093/161] (851s left) kms_busy (basic)
[188.069702] Starting subtest: basic
[188.089996] Starting dynamic subtest: flip
[188.899944] Dynamic subtest flip: SUCCESS (0.814s)
[188.947650] Starting dynamic subtest: modeset
[190.364456] Dynamic subtest modeset: SUCCESS (1.469s)
[190.391684] Subtest basic: SUCCESS (2.287s)
[190.838454] [094/161] (848s left) kms_prop_blob (basic)
[191.109335] Starting subtest: basic
[191.130218] Subtest basic: SUCCESS (0.000s)
[191.300840] [095/161] (848s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[191.665180] Starting subtest: basic-busy-flip-before-cursor-atomic
[191.707822] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[191.876142] [096/161] (847s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[192.221819] Starting subtest: basic-busy-flip-before-cursor-legacy
[192.263811] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[192.432103] [097/161] (847s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[192.781083] Starting subtest: basic-flip-after-cursor-atomic
[193.787058] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.995s)
[194.220564] [098/161] (845s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[194.570822] Starting subtest: basic-flip-after-cursor-legacy
[195.587090] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.994s)
[195.988308] [099/161] (844s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[196.343725] Starting subtest: basic-flip-after-cursor-varying-size
[197.317066] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.984s)
[197.759983] [100/161] (842s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[198.112173] Starting subtest: basic-flip-before-cursor-atomic
[199.082895] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.981s)
[199.531758] [101/161] (840s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[199.867924] Starting subtest: basic-flip-before-cursor-legacy
[200.889763] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.000s)
[201.310606] [102/161] (839s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[201.658289] Starting subtest: basic-flip-before-cursor-varying-size
[202.630427] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.989s)
[203.111972] [103/161] (837s left) kms_dsc (dsc-basic)
[203.492937] Subtest dsc-basic: SKIP (0.000s)
[203.670304] [104/161] (837s left) kms_flip (basic-flip-vs-dpms)
[204.058231] Starting subtest: basic-flip-vs-dpms
[204.079124] Starting dynamic subtest: A-DP1
[205.286240] Dynamic subtest A-DP1: SUCCESS (1.251s)
[205.332471] Starting dynamic subtest: B-DP1
[206.431267] Dynamic subtest B-DP1: SUCCESS (1.143s)
[206.477530] Starting dynamic subtest: C-DP1
[207.585795] Dynamic subtest C-DP1: SUCCESS (1.149s)
[207.611567] Subtest basic-flip-vs-dpms: SUCCESS (3.553s)
[208.061758] [105/161] (833s left) kms_flip (basic-flip-vs-modeset)
[208.411789] Starting subtest: basic-flip-vs-modeset
[208.433419] Starting dynamic subtest: A-DP1
[209.680387] Dynamic subtest A-DP1: SUCCESS (1.259s)
[209.731739] Starting dynamic subtest: B-DP1
[210.812664] Dynamic subtest B-DP1: SUCCESS (1.140s)
[210.863502] Starting dynamic subtest: C-DP1
[211.956366] Dynamic subtest C-DP1: SUCCESS (1.149s)
[211.980040] Subtest basic-flip-vs-modeset: SUCCESS (3.558s)
[212.420342] [106/161] (828s left) kms_flip (basic-flip-vs-wf_vblank)
[212.800698] Starting subtest: basic-flip-vs-wf_vblank
[212.823225] Starting dynamic subtest: A-DP1
[214.223851] Dynamic subtest A-DP1: SUCCESS (1.450s)
[214.270158] Starting dynamic subtest: B-DP1
[215.640983] Dynamic subtest B-DP1: SUCCESS (1.418s)
[215.687044] Starting dynamic subtest: C-DP1
[217.058398] Dynamic subtest C-DP1: SUCCESS (1.415s)
[217.078136] Subtest basic-flip-vs-wf_vblank: SUCCESS (4.295s)
[217.600246] [107/161] (823s left) kms_flip (basic-plain-flip)
[217.967178] Starting subtest: basic-plain-flip
[217.986493] Starting dynamic subtest: A-DP1
[219.108374] Dynamic subtest A-DP1: SUCCESS (1.165s)
[219.156861] Starting dynamic subtest: B-DP1
[220.252241] Dynamic subtest B-DP1: SUCCESS (1.133s)
[220.304694] Starting dynamic subtest: C-DP1
[221.395757] Dynamic subtest C-DP1: SUCCESS (1.137s)
[221.420391] Subtest basic-plain-flip: SUCCESS (3.447s)
[221.919464] [108/161] (819s left) kms_force_connector_basic (force-connector-state)
[222.217851] Subtest force-connector-state: SKIP (0.000s)
[222.393334] [109/161] (819s left) kms_force_connector_basic (force-edid)
[222.672761] Subtest force-edid: SKIP (0.000s)
[222.851558] [110/161] (819s left) kms_force_connector_basic (force-load-detect)
[223.119705] Subtest force-load-detect: SKIP (0.000s)
[223.292571] [111/161] (818s left) kms_force_connector_basic (prune-stale-modes)
[223.552789] Subtest prune-stale-modes: SKIP (0.000s)
[223.727442] [112/161] (818s left) kms_frontbuffer_tracking (basic)
[224.148946] Starting subtest: basic
[227.898064] Subtest basic: SUCCESS (3.807s)
[228.116658] [113/161] (814s left) kms_hdmi_inject (inject-audio)
[228.405101] Subtest inject-audio: SKIP (0.000s)
[228.579931] [114/161] (814s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[228.939119] Starting subtest: compare-crc-sanitycheck-xr24
[228.960847] Starting dynamic subtest: pipe-A-DP-1
[229.421560] Dynamic subtest pipe-A-DP-1: SUCCESS (0.488s)
[229.467760] Starting dynamic subtest: pipe-B-DP-1
[230.069170] Dynamic subtest pipe-B-DP-1: SUCCESS (0.630s)
[230.116518] Starting dynamic subtest: pipe-C-DP-1
[230.715913] Dynamic subtest pipe-C-DP-1: SUCCESS (0.639s)
[230.737824] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.771s)
[231.180239] [115/161] (811s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[231.515294] Starting subtest: compare-crc-sanitycheck-nv12
[231.535656] Starting dynamic subtest: pipe-A-DP-1
[231.576425] Dynamic subtest pipe-A-DP-1: SKIP (0.001s)
[231.629221] Starting dynamic subtest: pipe-B-DP-1
[231.669273] Dynamic subtest pipe-B-DP-1: SKIP (0.001s)
[231.720456] Starting dynamic subtest: pipe-C-DP-1
[231.775918] Dynamic subtest pipe-C-DP-1: SKIP (0.001s)
[231.827319] Subtest compare-crc-sanitycheck-nv12: SKIP (0.017s)
[232.040589] [116/161] (811s left) kms_pipe_crc_basic (hang-read-crc)
[232.412198] Starting subtest: hang-read-crc
[232.434667] Starting dynamic subtest: pipe-A-DP-1
[233.734129] Dynamic subtest pipe-A-DP-1: SUCCESS (1.318s)
[233.781374] Starting dynamic subtest: pipe-B-DP-1
[235.227212] Dynamic subtest pipe-B-DP-1: SUCCESS (1.486s)
[235.307997] Starting dynamic subtest: pipe-C-DP-1
[236.714634] Dynamic subtest pipe-C-DP-1: SUCCESS (1.479s)
[236.756474] Subtest hang-read-crc: SUCCESS (4.298s)
[237.131759] [117/161] (806s left) kms_pipe_crc_basic (nonblocking-crc)
[237.486924] Starting subtest: nonblocking-crc
[237.507353] Starting dynamic subtest: pipe-A-DP-1
[238.146269] Dynamic subtest pipe-A-DP-1: SUCCESS (0.648s)
[238.193170] Starting dynamic subtest: pipe-B-DP-1
[238.949434] Dynamic subtest pipe-B-DP-1: SUCCESS (0.801s)
[238.996616] Starting dynamic subtest: pipe-C-DP-1
[239.766975] Dynamic subtest pipe-C-DP-1: SUCCESS (0.813s)
[239.786866] Subtest nonblocking-crc: SUCCESS (2.277s)
[240.185397] [118/161] (803s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[240.521726] Starting subtest: nonblocking-crc-frame-sequence
[240.544332] Starting dynamic subtest: pipe-A-DP-1
[241.170102] Dynamic subtest pipe-A-DP-1: SUCCESS (0.651s)
[241.224037] Starting dynamic subtest: pipe-B-DP-1
[241.975803] Dynamic subtest pipe-B-DP-1: SUCCESS (0.792s)
[242.056960] Starting dynamic subtest: pipe-C-DP-1
[242.782353] Dynamic subtest pipe-C-DP-1: SUCCESS (0.799s)
[242.806167] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.257s)
[243.200735] [119/161] (800s left) kms_pipe_crc_basic (read-crc)
[243.553238] Starting subtest: read-crc
[243.574470] Starting dynamic subtest: pipe-A-DP-1
[244.125410] Dynamic subtest pipe-A-DP-1: SUCCESS (0.571s)
[244.175585] Starting dynamic subtest: pipe-B-DP-1
[244.866851] Dynamic subtest pipe-B-DP-1: SUCCESS (0.734s)
[244.936297] Starting dynamic subtest: pipe-C-DP-1
[245.610355] Dynamic subtest pipe-C-DP-1: SUCCESS (0.738s)
[245.630143] Subtest read-crc: SUCCESS (2.058s)
[246.037455] [120/161] (797s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[246.400049] Starting subtest: read-crc-frame-sequence
[246.422209] Starting dynamic subtest: pipe-A-DP-1
[246.969394] Dynamic subtest pipe-A-DP-1: SUCCESS (0.574s)
[247.031214] Starting dynamic subtest: pipe-B-DP-1
[247.715840] Dynamic subtest pipe-B-DP-1: SUCCESS (0.735s)
[247.764968] Starting dynamic subtest: pipe-C-DP-1
[248.451088] Dynamic subtest pipe-C-DP-1: SUCCESS (0.730s)
[248.472565] Subtest read-crc-frame-sequence: SUCCESS (2.056s)
[248.871682] [121/161] (795s left) kms_pm_backlight (basic-brightness)
[249.254086] Subtest basic-brightness: SKIP (0.000s)
[249.426134] [122/161] (794s left) kms_pm_rpm (basic-pci-d3-state)
[251.263316] Starting subtest: basic-pci-d3-state
[251.920027] Subtest basic-pci-d3-state: SUCCESS (0.659s)
[252.242575] [123/161] (792s left) kms_pm_rpm (basic-rte)
[252.479193] Starting subtest: basic-rte
[254.669121] Subtest basic-rte: SUCCESS (2.224s)
[255.660631] [124/161] (788s left) kms_psr (psr-primary-page-flip)
[256.051788] Subtest psr-primary-page-flip: SKIP (0.000s)
[256.224914] [125/161] (788s left) kms_psr (psr-cursor-plane-move)
[256.582715] Subtest psr-cursor-plane-move: SKIP (0.000s)
[256.761268] [126/161] (788s left) kms_psr (psr-sprite-plane-onoff)
[257.130849] Subtest psr-sprite-plane-onoff: SKIP (0.000s)
[257.302738] [127/161] (787s left) kms_psr (psr-primary-mmap-gtt)
[257.680790] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[257.855261] [128/161] (787s left) kms_setmode (basic-clone-single-crtc)
[258.104228] Starting subtest: basic-clone-single-crtc
[258.171066] Subtest basic-clone-single-crtc: SKIP (0.000s)
[258.346726] [129/161] (787s left) i915_pm_rps (basic-api)
[258.618690] Starting subtest: basic-api
[258.640848] Subtest basic-api: SUCCESS (0.007s)
[258.853874] [130/161] (786s left) prime_self_import (basic-llseek-bad)
[259.106454] Starting subtest: basic-llseek-bad
[259.151081] Subtest basic-llseek-bad: SUCCESS (0.005s)
[259.325737] [131/161] (786s left) prime_self_import (basic-llseek-size)
[259.546626] Starting subtest: basic-llseek-size
[259.587871] Subtest basic-llseek-size: SUCCESS (0.008s)
[259.767866] [132/161] (786s left) prime_self_import (basic-with_fd_dup)
[260.012673] Starting subtest: basic-with_fd_dup
[260.054462] Subtest basic-with_fd_dup: SUCCESS (0.008s)
[260.228208] [133/161] (785s left) prime_self_import (basic-with_one_bo)
[260.468991] Starting subtest: basic-with_one_bo
[260.514871] Subtest basic-with_one_bo: SUCCESS (0.010s)
[260.686510] [134/161] (785s left) prime_self_import (basic-with_one_bo_two_files)
[260.920360] Starting subtest: basic-with_one_bo_two_files
[260.968383] Subtest basic-with_one_bo_two_files: SUCCESS (0.007s)
[261.154087] [135/161] (785s left) prime_self_import (basic-with_two_bos)
[261.397180] Starting subtest: basic-with_two_bos
[261.448805] Subtest basic-with_two_bos: SUCCESS (0.008s)
[261.646920] [136/161] (785s left) prime_vgem (basic-fence-flip)
[261.944198] Starting subtest: basic-fence-flip
[262.589479] Subtest basic-fence-flip: SUCCESS (0.671s)
[262.793475] [137/161] (784s left) prime_vgem (basic-fence-mmap)
[263.067984] Starting subtest: basic-fence-mmap
[263.112705] Subtest basic-fence-mmap: SUCCESS (0.074s)
[263.330785] [138/161] (783s left) prime_vgem (basic-fence-read)
[263.597156] Starting subtest: basic-fence-read
[263.700750] Subtest basic-fence-read: SUCCESS (0.141s)
[263.917224] [139/161] (783s left) prime_vgem (basic-gtt)
[264.194104] Starting subtest: basic-gtt
[264.214689] Subtest basic-gtt: SUCCESS (0.032s)
[264.409609] [140/161] (783s left) prime_vgem (basic-read)
[264.695288] Starting subtest: basic-read
[264.740140] Subtest basic-read: SUCCESS (0.066s)
[264.940132] [141/161] (782s left) prime_vgem (basic-write)
[265.203774] Starting subtest: basic-write
[265.253095] Subtest basic-write: SUCCESS (0.093s)
[265.461951] [142/161] (782s left) vgem_basic (setversion)
[265.712675] Starting subtest: setversion
[265.757982] Subtest setversion: SUCCESS (0.000s)
[265.931587] [143/161] (782s left) vgem_basic (create)
[266.176419] Starting subtest: create
[266.196963] Subtest create: SUCCESS (0.001s)
[266.373225] [144/161] (781s left) vgem_basic (debugfs)
[266.619173] Starting subtest: debugfs
[266.641695] Subtest debugfs: SUCCESS (0.001s)
[266.822899] [145/161] (781s left) vgem_basic (dmabuf-export)
[267.069315] Starting subtest: dmabuf-export
[267.113544] Subtest dmabuf-export: SUCCESS (0.005s)
[267.285711] [146/161] (781s left) vgem_basic (dmabuf-fence)
[267.549551] Starting subtest: dmabuf-fence
[267.571379] Subtest dmabuf-fence: SUCCESS (0.002s)
[267.754223] [147/161] (781s left) vgem_basic (dmabuf-fence-before)
[268.015623] Starting subtest: dmabuf-fence-before
[268.035867] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[268.212344] [148/161] (780s left) vgem_basic (dmabuf-mmap)
[268.459350] Starting subtest: dmabuf-mmap
[268.481959] Subtest dmabuf-mmap: SUCCESS (0.037s)
[268.668195] [149/161] (780s left) vgem_basic (mmap)
[268.930801] Starting subtest: mmap
[268.952147] Subtest mmap: SUCCESS (0.023s)
[269.125117] [150/161] (780s left) vgem_basic (second-client)
[269.388296] Starting subtest: second-client
[269.429660] Subtest second-client: SUCCESS (0.032s)
[269.600803] [151/161] (779s left) vgem_basic (sysfs)
[269.867485] Starting subtest: sysfs
[269.887718] Subtest sysfs: SUCCESS (0.001s)
[270.066359] [152/161] (779s left) core_hotunplug (unbind-rebind)
[270.446769] Starting subtest: unbind-rebind
[275.082029] Subtest unbind-rebind: SUCCESS (4.627s)
[275.254908] [153/161] (774s left) vgem_basic (unload)
[275.517613] Starting subtest: unload
[304.878858] Subtest unload: SUCCESS (29.248s)
[305.066707] [154/161] (745s left) i915_module_load (reload)
[305.315414] Starting subtest: reload
[311.270556] Subtest reload: SUCCESS (5.967s)
[311.552583] [155/161] (738s left) gem_lmem_swapping (basic)
[311.859281] Subtest basic: SKIP (0.000s)
[312.029407] [156/161] (738s left) gem_lmem_swapping (parallel-random-engines)
[312.295776] Subtest parallel-random-engines: SKIP (0.000s)
[312.469612] [157/161] (738s left) gem_lmem_swapping (random-engines)
[312.739845] Subtest random-engines: SKIP (0.000s)
[312.917129] [158/161] (738s left) gem_lmem_swapping (verify-random)
[313.209217] Subtest verify-random: SKIP (0.000s)
[313.384420] [159/161] (737s left) i915_pm_rpm (module-reload)
[315.501836] Starting subtest: module-reload
[330.186906] Subtest module-reload: SUCCESS (14.705s)
[330.309473] [FACT i915_pm_rpm (module-reload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[330.313736] [FACT i915_pm_rpm (module-reload)] deleted: kernel.kmod_is_loaded.i915: true
[330.340811] [160/161] (721s left) i915_selftest (live)
[330.587925] Starting subtest: live
[330.610931] Starting dynamic subtest: sanitycheck
[334.633146] Dynamic subtest sanitycheck: SUCCESS (4.066s)
[334.694147] Starting dynamic subtest: uncore
[338.592184] Dynamic subtest uncore: SUCCESS (3.955s)
[338.633052] Starting dynamic subtest: workarounds
[342.590800] Dynamic subtest workarounds: SUCCESS (3.994s)
[342.650129] Starting dynamic subtest: gt_engines
[347.156489] Dynamic subtest gt_engines: SUCCESS (4.558s)
[347.215731] Starting dynamic subtest: gt_timelines
[370.958246] Dynamic subtest gt_timelines: SUCCESS (23.794s)
[371.017763] Starting dynamic subtest: gt_contexts
[375.153233] Dynamic subtest gt_contexts: SUCCESS (4.198s)
[375.211358] Starting dynamic subtest: gt_lrc
[381.960705] Dynamic subtest gt_lrc: SUCCESS (6.800s)
[382.004445] Starting dynamic subtest: gt_mocs
[386.101666] Dynamic subtest gt_mocs: SUCCESS (4.136s)
[386.160430] Starting dynamic subtest: gt_pm
[398.634609] Dynamic subtest gt_pm: SUCCESS (12.529s)
[398.676262] Starting dynamic subtest: gt_heartbeat
[402.744694] Dynamic subtest gt_heartbeat: SUCCESS (4.107s)
[402.803473] Starting dynamic subtest: gt_tlb
[408.058305] Dynamic subtest gt_tlb: SUCCESS (5.309s)
[408.101468] Starting dynamic subtest: requests
[418.384250] Dynamic subtest requests: SUCCESS (10.319s)
[418.470455] Starting dynamic subtest: migrate
[425.601473] Dynamic subtest migrate: SUCCESS (7.215s)
[425.643337] Starting dynamic subtest: active
[429.647390] Dynamic subtest active: SUCCESS (4.044s)
[429.688421] Starting dynamic subtest: objects
[436.172778] Dynamic subtest objects: SUCCESS (6.519s)
[436.214211] Starting dynamic subtest: mman
[446.219489] Dynamic subtest mman: SUCCESS (10.041s)
[446.261602] Starting dynamic subtest: dmabuf
[450.132844] Dynamic subtest dmabuf: SUCCESS (3.908s)
[450.176381] Starting dynamic subtest: vma
[454.096778] Dynamic subtest vma: SUCCESS (3.961s)
[454.157978] Starting dynamic subtest: coherency
[461.571843] Dynamic subtest coherency: SUCCESS (7.470s)
[461.615555] Starting dynamic subtest: gtt
[472.669089] Dynamic subtest gtt: SUCCESS (11.095s)
[472.710483] Starting dynamic subtest: gem
[478.194722] Dynamic subtest gem: SUCCESS (5.517s)
[478.257804] Starting dynamic subtest: evict
[487.360611] Dynamic subtest evict: SUCCESS (9.163s)
[487.445838] Starting dynamic subtest: hugepages
[497.944563] Dynamic subtest hugepages: SUCCESS (10.581s)
[497.988926] Starting dynamic subtest: gem_contexts
[524.840863] Dynamic subtest gem_contexts: SUCCESS (26.721s)
[524.892587] Starting dynamic subtest: client
[528.762762] Dynamic subtest client: SUCCESS (4.087s)
[528.847402] Starting dynamic subtest: gem_migrate
[532.686922] Dynamic subtest gem_migrate: SUCCESS (3.918s)
[532.730781] Starting dynamic subtest: reset
[547.236220] Dynamic subtest reset: SUCCESS (14.546s)
[547.295342] Starting dynamic subtest: memory_region
[551.149395] Dynamic subtest memory_region: SUCCESS (3.909s)
[551.211021] Starting dynamic subtest: hangcheck
[578.346615] Dynamic subtest hangcheck: SUCCESS (27.187s)
[578.389700] Starting dynamic subtest: execlists
[592.874798] Dynamic subtest execlists: SUCCESS (14.525s)
[592.960540] Starting dynamic subtest: ring_submission
[596.949352] Dynamic subtest ring_submission: SUCCESS (4.070s)
[597.007994] Starting dynamic subtest: perf
[601.241974] Dynamic subtest perf: SUCCESS (4.289s)
[601.286183] Starting dynamic subtest: slpc
[605.161281] Dynamic subtest slpc: SUCCESS (3.913s)
[605.205449] Starting dynamic subtest: guc
[609.013170] Dynamic subtest guc: SUCCESS (3.845s)
[609.070657] Starting dynamic subtest: guc_multi_lrc
[613.029154] Dynamic subtest guc_multi_lrc: SUCCESS (4.010s)
[613.091303] Starting dynamic subtest: guc_hang
[616.993993] Dynamic subtest guc_hang: SUCCESS (3.965s)
[617.038131] Starting dynamic subtest: late_gt_pm
[621.001569] Dynamic subtest late_gt_pm: SUCCESS (4.002s)
[621.022089] Subtest live: SUCCESS (290.435s)
[621.174973] [161/161] (430s left) dmabuf (all-tests)
[621.413729] Starting subtest: all-tests
[621.436611] Starting dynamic subtest: sanitycheck
[621.500402] Dynamic subtest sanitycheck: SUCCESS (0.085s)
[621.542423] Starting dynamic subtest: dma_fence
[621.748415] Dynamic subtest