[26.030523] Initializing watchdogs
[26.030665]   /dev/watchdog0
[26.049798] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d67 Intel Meteorlake (Gen12) 
[26.057969] [001/162] (960s left) i915_module_load (load)
[26.106439] Starting subtest: load
[29.302819] Subtest load: SUCCESS (3.201s)
[29.342323] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[29.343576] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true
[29.359256] [002/162] (957s left) core_auth (basic-auth)
[30.825300] Starting subtest: basic-auth
[30.831231] Subtest basic-auth: SUCCESS (0.002s)
[31.000390] [003/162] (955s left) core_debugfs (read-all-entries)
[31.079247] Starting subtest: read-all-entries
[31.156711] Subtest read-all-entries: SUCCESS (0.078s)
[31.300973] [004/162] (955s left) core_sysfs (read-all-entries)
[31.370402] Starting subtest: read-all-entries
[31.375996] Subtest read-all-entries: SUCCESS (0.003s)
[31.516255] [005/162] (955s left) fbdev (eof)
[31.573089] Starting subtest: eof
[31.578724] Subtest eof: SUCCESS (0.000s)
[31.701225] [006/162] (955s left) fbdev (info)
[31.748387] Starting subtest: info
[31.757302] Subtest info: SUCCESS (0.000s)
[31.864551] [007/162] (954s left) fbdev (nullptr)
[31.926701] Starting subtest: nullptr
[31.935395] Subtest nullptr: SUCCESS (0.000s)
[32.027071] [008/162] (954s left) fbdev (read)
[32.075051] Starting subtest: read
[32.800829] Subtest read: SUCCESS (0.722s)
[32.894465] [009/162] (954s left) fbdev (write)
[32.952838] Starting subtest: write
[33.477641] Subtest write: SUCCESS (0.514s)
[33.577661] [010/162] (953s left) gem_basic (bad-close)
[33.637427] Starting subtest: bad-close
[33.648688] Subtest bad-close: SUCCESS (0.000s)
[33.778784] [011/162] (953s left) gem_basic (create-close)
[33.843208] Starting subtest: create-close
[33.854393] Subtest create-close: SUCCESS (0.000s)
[33.980187] [012/162] (953s left) gem_basic (create-fd-close)
[34.047185] Starting subtest: create-fd-close
[34.058378] Subtest create-fd-close: SUCCESS (0.000s)
[34.199527] [013/162] (952s left) gem_busy (busy@all-engines)
[34.279563] Starting subtest: busy
[34.286878] Starting dynamic subtest: all-engines
[34.347030] Dynamic subtest all-engines: SUCCESS (0.066s)
[34.352569] Subtest busy: SUCCESS (0.066s)
[35.914462] [014/162] (951s left) gem_close_race (basic-process)
[36.003199] Starting subtest: basic-process
[36.030369] Subtest basic-process: SUCCESS (0.035s)
[36.267584] [015/162] (950s left) gem_close_race (basic-threads)
[36.347509] Starting subtest: basic-threads
[37.677257] Subtest basic-threads: SUCCESS (1.333s)
[37.913104] [016/162] (949s left) gem_ctx_create (basic)
[37.989602] Starting subtest: basic
[37.995186] Subtest basic: SUCCESS (0.000s)
[38.212788] [017/162] (949s left) gem_ctx_create (basic-files)
[38.292307] Starting subtest: basic-files
[40.303412] Subtest basic-files: SUCCESS (2.007s)
[41.933036] [018/162] (945s left) gem_ctx_exec (basic)
[42.008503] Starting subtest: basic
[42.014230] Subtest basic: SUCCESS (0.008s)
[42.222009] [019/162] (945s left) gem_exec_basic (basic)
[42.297723] Starting subtest: basic
[42.303447] Starting dynamic subtest: rcs0-smem
[42.308989] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[42.323128] Starting dynamic subtest: bcs0-smem
[42.328710] Dynamic subtest bcs0-smem: SUCCESS (0.001s)
[42.339920] Starting dynamic subtest: vcs0-smem
[42.345489] Dynamic subtest vcs0-smem: SUCCESS (0.002s)
[42.356294] Starting dynamic subtest: vcs1-smem
[42.361882] Dynamic subtest vcs1-smem: SUCCESS (0.002s)
[42.372482] Starting dynamic subtest: vecs0-smem
[42.377999] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[42.388704] Starting dynamic subtest: ccs0-smem
[42.394224] Dynamic subtest ccs0-smem: SUCCESS (0.002s)
[42.399680] Subtest basic: SUCCESS (0.011s)
[43.919592] [020/162] (943s left) gem_exec_create (basic)
[43.998627] Starting subtest: basic
[44.004254] Starting dynamic subtest: smem
[47.801509] Dynamic subtest smem: SUCCESS (3.806s)
[47.807461] Subtest basic: SUCCESS (3.807s)
[48.031804] [021/162] (939s left) gem_exec_fence (basic-busy)
[48.224063] Starting subtest: basic-busy
[48.229647] Starting dynamic subtest: rcs0
[48.341861] Dynamic subtest rcs0: SUCCESS (0.117s)
[48.357146] Starting dynamic subtest: bcs0
[48.398571] Dynamic subtest bcs0: SUCCESS (0.056s)
[48.412974] Starting dynamic subtest: vcs0
[48.457269] Dynamic subtest vcs0: SUCCESS (0.058s)
[48.471483] Starting dynamic subtest: vcs1
[48.518353] Dynamic subtest vcs1: SUCCESS (0.060s)
[48.532592] Starting dynamic subtest: vecs0
[48.577364] Dynamic subtest vecs0: SUCCESS (0.058s)
[48.591569] Starting dynamic subtest: ccs0
[48.625540] Dynamic subtest ccs0: SUCCESS (0.048s)
[48.631258] Subtest basic-busy: SUCCESS (0.402s)
[51.003327] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[51.009876] [022/162] (936s left) gem_exec_fence (basic-wait)
[51.207045] Starting subtest: basic-wait
[51.213067] Starting dynamic subtest: rcs0
[51.321052] Dynamic subtest rcs0: SUCCESS (0.119s)
[51.336132] Starting dynamic subtest: bcs0
[51.382613] Dynamic subtest bcs0: SUCCESS (0.061s)
[51.397314] Starting dynamic subtest: vcs0
[51.441532] Dynamic subtest vcs0: SUCCESS (0.058s)
[51.456129] Starting dynamic subtest: vcs1
[51.501418] Dynamic subtest vcs1: SUCCESS (0.059s)
[51.516132] Starting dynamic subtest: vecs0
[51.562398] Dynamic subtest vecs0: SUCCESS (0.060s)
[51.577139] Starting dynamic subtest: ccs0
[51.617627] Dynamic subtest ccs0: SUCCESS (0.054s)
[51.623317] Subtest basic-wait: SUCCESS (0.417s)
[53.009818] [023/162] (934s left) gem_exec_fence (basic-await)
[53.204489] Starting subtest: basic-await
[53.210187] Starting dynamic subtest: rcs0
[53.287139] Dynamic subtest rcs0: SUCCESS (0.082s)
[53.301740] Starting dynamic subtest: bcs0
[53.362504] Dynamic subtest bcs0: SUCCESS (0.075s)
[53.377737] Starting dynamic subtest: vcs0
[53.438230] Dynamic subtest vcs0: SUCCESS (0.075s)
[53.453118] Starting dynamic subtest: vcs1
[53.512749] Dynamic subtest vcs1: SUCCESS (0.074s)
[53.528036] Starting dynamic subtest: vecs0
[53.587409] Dynamic subtest vecs0: SUCCESS (0.074s)
[53.602350] Starting dynamic subtest: ccs0
[53.664813] Dynamic subtest ccs0: SUCCESS (0.076s)
[53.670640] Subtest basic-await: SUCCESS (0.461s)
[56.000559] [024/162] (931s left) gem_exec_fence (nb-await)
[56.195728] Starting subtest: nb-await
[56.202085] Starting dynamic subtest: rcs0
[56.264703] Dynamic subtest rcs0: SUCCESS (0.069s)
[56.277622] Starting dynamic subtest: bcs0
[56.323811] Dynamic subtest bcs0: SUCCESS (0.058s)
[56.336407] Starting dynamic subtest: vcs0
[56.383638] Dynamic subtest vcs0: SUCCESS (0.059s)
[56.396401] Starting dynamic subtest: vcs1
[56.443042] Dynamic subtest vcs1: SUCCESS (0.059s)
[56.456179] Starting dynamic subtest: vecs0
[56.501697] Dynamic subtest vecs0: SUCCESS (0.058s)
[56.514180] Starting dynamic subtest: ccs0
[56.562136] Dynamic subtest ccs0: SUCCESS (0.060s)
[56.568149] Subtest nb-await: SUCCESS (0.367s)
[57.977552] [025/162] (929s left) gem_exec_gttfill (basic)
[58.047431] Starting subtest: basic
[86.443996] Subtest basic: FAIL (28.154s)
[178.589381] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[178.822938] Closing watchdogs
[178.829358] Initializing watchdogs
[178.829392]   /dev/watchdog0
[178.844601] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d67 Intel Meteorlake (Gen12) 
[178.850437] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[178.851279] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[178.851633] [FACT before any test] new: kernel.kmod_is_loaded.vgem: true
[178.863897] [026/162] (808s left) gem_exec_parallel (engines)
[178.947105] Starting subtest: engines
[178.952830] Starting dynamic subtest: basic
[207.967236] Dynamic subtest basic: FAIL (28.407s)
[208.104068] Starting dynamic subtest: contexts
[208.167738] Dynamic subtest contexts: SKIP (0.001s)
[208.234393] Starting dynamic subtest: fds
[266.843633] Dynamic subtest fds: FAIL (58.640s)
[266.858308] Starting dynamic subtest: userptr