[46.898635] Initializing watchdogs
[46.898867]   /dev/watchdog0
[46.935139] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[46.947770] [001/162] (960s left) i915_module_load (load)
[47.065907] Starting subtest: load
[49.817846] Subtest load: SUCCESS (2.753s)
[49.984719] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[49.989729] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[49.995611] [002/162] (957s left) core_auth (basic-auth)
[50.167414] Starting subtest: basic-auth
[50.168957] Subtest basic-auth: SUCCESS (0.002s)
[50.318425] [003/162] (957s left) core_debugfs (read-all-entries)
[50.415623] Starting subtest: read-all-entries
[50.502949] Subtest read-all-entries: SUCCESS (0.086s)
[50.643192] [004/162] (957s left) core_sysfs (read-all-entries)
[50.738881] Starting subtest: read-all-entries
[50.742876] Subtest read-all-entries: SUCCESS (0.005s)
[50.892568] [005/162] (956s left) fbdev (eof)
[51.003820] Starting subtest: eof
[51.006253] Subtest eof: SUCCESS (0.001s)
[51.166120] [006/162] (956s left) fbdev (info)
[51.258979] Starting subtest: info
[51.260509] Subtest info: SUCCESS (0.000s)
[51.412278] [007/162] (956s left) fbdev (nullptr)
[51.521199] Starting subtest: nullptr
[51.522233] Subtest nullptr: SUCCESS (0.000s)
[51.662141] [008/162] (956s left) fbdev (read)
[51.772691] Starting subtest: read
[53.344497] Subtest read: SUCCESS (1.575s)
[53.504705] [009/162] (954s left) fbdev (write)
[53.620131] Starting subtest: write
[54.416719] Subtest write: SUCCESS (0.797s)
[54.565423] [010/162] (953s left) gem_basic (bad-close)
[54.661251] Starting subtest: bad-close
[54.664420] Subtest bad-close: SUCCESS (0.000s)
[54.831452] [011/162] (953s left) gem_basic (create-close)
[54.929628] Starting subtest: create-close
[54.933682] Subtest create-close: SUCCESS (0.000s)
[55.082844] [012/162] (953s left) gem_basic (create-fd-close)
[55.184109] Starting subtest: create-fd-close
[55.186367] Subtest create-fd-close: SUCCESS (0.001s)
[55.343173] [013/162] (953s left) gem_busy (busy@all-engines)
[55.481118] Starting subtest: busy
[55.482181] Starting dynamic subtest: all-engines
[55.557284] Dynamic subtest all-engines: SUCCESS (0.078s)
[55.558445] Subtest busy: SUCCESS (0.079s)
[55.996719] [014/162] (952s left) gem_close_race (basic-process)
[56.125361] Starting subtest: basic-process
[56.197808] Subtest basic-process: SUCCESS (0.072s)
[56.466702] [015/162] (952s left) gem_close_race (basic-threads)
[56.592043] Starting subtest: basic-threads
[57.834792] Subtest basic-threads: SUCCESS (1.242s)
[58.078981] [016/162] (950s left) gem_ctx_create (basic)
[58.200861] Starting subtest: basic
[58.201991] Subtest basic: SUCCESS (0.000s)
[58.447398] [017/162] (950s left) gem_ctx_create (basic-files)
[58.578480] Starting subtest: basic-files
[60.608618] Subtest basic-files: SUCCESS (2.061s)
[60.885328] [018/162] (948s left) gem_ctx_exec (basic)
[60.984769] Starting subtest: basic
[60.992407] Subtest basic: SUCCESS (0.007s)
[61.172924] [019/162] (947s left) gem_exec_basic (basic)
[61.282661] Starting subtest: basic
[61.283732] Starting dynamic subtest: rcs0-smem
[61.285454] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[61.301080] Starting dynamic subtest: bcs0-smem
[61.302747] Dynamic subtest bcs0-smem: SUCCESS (0.003s)
[61.327726] Starting dynamic subtest: vcs0-smem
[61.329300] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[61.348468] Starting dynamic subtest: vecs0-smem
[61.350223] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[61.351742] Subtest basic: SUCCESS (0.011s)
[61.547439] [020/162] (947s left) gem_exec_create (basic)
[61.663783] Starting subtest: basic
[61.664968] Starting dynamic subtest: smem
[63.712391] Dynamic subtest smem: SUCCESS (2.077s)
[63.713829] Subtest basic: SUCCESS (2.078s)
[63.965966] [021/162] (945s left) gem_exec_fence (basic-busy)
[64.219337] Starting subtest: basic-busy
[64.220770] Starting dynamic subtest: rcs0
[64.241170] Dynamic subtest rcs0: SUCCESS (0.024s)
[64.258354] Starting dynamic subtest: bcs0
[64.273368] Dynamic subtest bcs0: SUCCESS (0.032s)
[64.288846] Starting dynamic subtest: vcs0
[64.296373] Dynamic subtest vcs0: SUCCESS (0.024s)
[64.314434] Starting dynamic subtest: vecs0
[64.320130] Dynamic subtest vecs0: SUCCESS (0.021s)
[64.321467] Subtest basic-busy: SUCCESS (0.105s)
[65.091318] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[65.097343] [022/162] (944s left) gem_exec_fence (basic-wait)
[65.338932] Starting subtest: basic-wait
[65.340283] Starting dynamic subtest: rcs0
[65.354176] Dynamic subtest rcs0: SUCCESS (0.016s)
[65.371027] Starting dynamic subtest: bcs0
[65.386954] Dynamic subtest bcs0: SUCCESS (0.030s)
[65.402698] Starting dynamic subtest: vcs0
[65.419655] Dynamic subtest vcs0: SUCCESS (0.029s)
[65.436492] Starting dynamic subtest: vecs0
[65.443633] Dynamic subtest vecs0: SUCCESS (0.024s)
[65.444760] Subtest basic-wait: SUCCESS (0.107s)
[66.177446] [023/162] (943s left) gem_exec_fence (basic-await)
[66.425090] Starting subtest: basic-await
[66.426405] Starting dynamic subtest: rcs0
[66.539675] Dynamic subtest rcs0: SUCCESS (0.113s)
[66.566862] Starting dynamic subtest: bcs0
[66.678452] Dynamic subtest bcs0: SUCCESS (0.139s)
[66.696804] Starting dynamic subtest: vcs0
[66.803230] Dynamic subtest vcs0: SUCCESS (0.121s)
[66.836150] Starting dynamic subtest: vecs0
[66.930347] Dynamic subtest vecs0: SUCCESS (0.127s)
[66.931497] Subtest basic-await: SUCCESS (0.510s)
[67.995848] [024/162] (941s left) gem_exec_fence (nb-await)
[68.441260] Starting subtest: nb-await
[68.445278] Starting dynamic subtest: rcs0
[68.523384] Dynamic subtest rcs0: SUCCESS (0.088s)
[68.553096] Starting dynamic subtest: bcs0
[68.616454] Dynamic subtest bcs0: SUCCESS (0.094s)
[68.646456] Starting dynamic subtest: vcs0
[68.701057] Dynamic subtest vcs0: SUCCESS (0.084s)
[68.731699] Starting dynamic subtest: vecs0
[68.784218] Dynamic subtest vecs0: SUCCESS (0.084s)
[68.786283] Subtest nb-await: SUCCESS (0.356s)
[69.661491] [025/162] (939s left) gem_exec_gttfill (basic)
[69.842190] Starting subtest: basic
[80.198173] Subtest basic: SUCCESS (10.383s)
[80.469215] [026/162] (929s left) gem_exec_parallel (engines)
[80.753916] Starting subtest: engines
[80.754979] Starting dynamic subtest: basic
[84.165117] Dynamic subtest basic: SUCCESS (3.414s)
[84.183859] Starting dynamic subtest: contexts
[93.621066] Dynamic subtest contexts: SUCCESS (9.456s)
[93.659611] Starting dynamic subtest: fds
[101.471623] Dynamic subtest fds: SUCCESS (7.852s)
[101.492030] Starting dynamic subtest: userptr
[104.863252] Dynamic subtest userptr: SUCCESS (3.392s)
[104.864910] Subtest engines: SUCCESS (24.118s)
[105.142216] [027/162] (904s left) gem_exec_store (basic)
[105.269226] Starting subtest: basic
[105.334241] Subtest basic: SUCCESS (0.064s)
[105.621595] [028/162] (904s left) gem_flink_basic (bad-flink)
[105.719142] Starting subtest: bad-flink
[105.720407] Subtest bad-flink: SUCCESS (0.000s)
[105.885303] [029/162] (904s left) gem_flink_basic (bad-open)
[105.980387] Starting subtest: bad-open
[105.981741] Subtest bad-open: SUCCESS (0.000s)
[106.153154] [030/162] (903s left) gem_flink_basic (basic)
[106.246066] Starting subtest: basic
[106.247140] Subtest basic: SUCCESS (0.000s)
[106.443551] [031/162] (903s left) gem_flink_basic (double-flink)
[106.538964] Starting subtest: double-flink
[106.540223] Subtest double-flink: SUCCESS (0.000s)
[106.712455] [032/162] (903s left) gem_flink_basic (flink-lifetime)
[106.809369] Starting subtest: flink-lifetime
[106.810622] Subtest flink-lifetime: SUCCESS (0.003s)
[106.968502] [033/162] (903s left) gem_huc_copy (huc-copy)
[107.076690] Starting subtest: huc-copy
[107.079639] Subtest huc-copy: SKIP (0.000s)
[107.235679] [034/162] (903s left) gem_linear_blits (basic)
[107.503169] Starting subtest: basic
[107.511161] Subtest basic: SUCCESS (0.013s)
[107.707659] [035/162] (902s left) gem_mmap (basic)
[107.964648] Starting subtest: basic
[107.972170] Subtest basic: SUCCESS (0.001s)
[108.137562] [036/162] (902s left) gem_mmap_gtt (basic)
[108.236324] Starting subtest: basic
[108.237390] Subtest basic: SUCCESS (0.002s)
[108.401165] [037/162] (902s left) gem_render_linear_blits (basic)
[108.509261] Starting subtest: basic
[108.584412] Subtest basic: SUCCESS (0.071s)
[108.692485] [038/162] (902s left) gem_render_tiled_blits (basic)
[108.795348] Starting subtest: basic
[108.893079] Subtest basic: SUCCESS (0.095s)
[109.017614] [039/162] (901s left) gem_ringfill (basic-all)
[109.369628] Starting subtest: basic-all
[110.568170] Subtest basic-all: SUCCESS (1.195s)
[110.756398] [040/162] (900s left) gem_softpin (allocator-basic)
[110.863476] Starting subtest: allocator-basic
[111.093212] Subtest allocator-basic: SUCCESS (0.218s)
[111.280960] [041/162] (899s left) gem_softpin (allocator-basic-reserve)
[111.388442] Starting subtest: allocator-basic-reserve
[111.612632] Subtest allocator-basic-reserve: SUCCESS (0.223s)
[111.786716] [042/162] (899s left) gem_softpin (safe-alignment)
[111.900222] Starting subtest: safe-alignment
[111.923487] Subtest safe-alignment: SUCCESS (0.025s)
[112.146952] [043/162] (899s left) gem_sync (basic-all)
[112.276064] Starting subtest: basic-all
[114.576992] Subtest basic-all: SUCCESS (2.304s)
[114.863658] [044/162] (896s left) gem_sync (basic-each)
[114.986170] Starting subtest: basic-each
[117.262857] Subtest basic-each: SUCCESS (2.278s)
[117.545120] [045/162] (894s left) gem_tiled_blits (basic)
[117.739195] Starting subtest: basic
[117.777719] Subtest basic: SUCCESS (0.039s)
[117.976907] [046/162] (893s left) gem_tiled_fence_blits (basic)
[118.352543] Starting subtest: basic
[118.385624] Subtest basic: SUCCESS (0.034s)
[118.572851] [047/162] (893s left) gem_tiled_pread_basic
[119.203681] [048/162] (892s left) gem_wait (busy@all-engines)
[119.339389] Starting subtest: busy
[119.341191] Starting dynamic subtest: all-engines
[119.858617] Dynamic subtest all-engines: SUCCESS (0.519s)
[119.859671] Subtest busy: SUCCESS (0.520s)
[120.171813] [049/162] (891s left) gem_wait (wait@all-engines)
[120.299220] Starting subtest: wait
[120.301157] Starting dynamic subtest: all-engines
[121.339100] Dynamic subtest all-engines: SUCCESS (1.038s)
[121.342046] Subtest wait: SUCCESS (1.040s)
[121.645066] [050/162] (890s left) i915_getparams_basic (basic-eu-total)
[121.738434] Starting subtest: basic-eu-total
[121.740722] Subtest basic-eu-total: SUCCESS (0.000s)
[121.885143] [051/162] (890s left) i915_getparams_basic (basic-subslice-total)
[121.982189] Starting subtest: basic-subslice-total
[121.984481] Subtest basic-subslice-total: SUCCESS (0.000s)
[122.152069] [052/162] (890s left) i915_hangman (error-state-basic)