[24.754788] Initializing watchdogs [24.754943] /dev/watchdog0 [24.773645] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d67 Intel Meteorlake (Gen12) [24.782588] [001/162] (960s left) i915_module_load (load) [24.837615] Starting subtest: load [28.057577] Subtest load: SUCCESS (3.227s) [28.096384] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [28.097198] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [28.107761] [002/162] (957s left) core_auth (basic-auth) [29.858849] Starting subtest: basic-auth [29.867470] Subtest basic-auth: SUCCESS (0.002s) [30.028989] [003/162] (955s left) core_debugfs (read-all-entries) [30.104097] Starting subtest: read-all-entries [30.175524] Subtest read-all-entries: SUCCESS (0.073s) [30.333890] [004/162] (955s left) core_sysfs (read-all-entries) [30.410944] Starting subtest: read-all-entries [30.416562] Subtest read-all-entries: SUCCESS (0.003s) [30.570141] [005/162] (954s left) fbdev (eof) [30.622136] Starting subtest: eof [30.627723] Subtest eof: SUCCESS (0.000s) [30.748541] [006/162] (954s left) fbdev (info) [30.797147] Starting subtest: info [30.802938] Subtest info: SUCCESS (0.000s) [30.913719] [007/162] (954s left) fbdev (nullptr) [30.971819] Starting subtest: nullptr [30.977520] Subtest nullptr: SUCCESS (0.000s) [31.080820] [008/162] (954s left) fbdev (read) [31.138419] Starting subtest: read [31.880410] Subtest read: SUCCESS (0.733s) [31.974459] [009/162] (953s left) fbdev (write) [32.028939] Starting subtest: write [32.540150] Subtest write: SUCCESS (0.509s) [32.640426] [010/162] (953s left) gem_basic (bad-close) [32.718705] Starting subtest: bad-close [32.729751] Subtest bad-close: SUCCESS (0.000s) [32.879813] [011/162] (952s left) gem_basic (create-close) [32.954687] Starting subtest: create-close [32.965873] Subtest create-close: SUCCESS (0.000s) [33.113345] [012/162] (952s left) gem_basic (create-fd-close) [33.174152] Starting subtest: create-fd-close [33.185169] Subtest create-fd-close: SUCCESS (0.000s) [33.329803] [013/162] (952s left) gem_busy (busy@all-engines) [33.412475] Starting subtest: busy [33.421166] Starting dynamic subtest: all-engines [33.460155] Dynamic subtest all-engines: SUCCESS (0.052s) [33.465633] Subtest busy: SUCCESS (0.052s) [34.978234] [014/162] (950s left) gem_close_race (basic-process) [35.061244] Starting subtest: basic-process [35.141829] Subtest basic-process: SUCCESS (0.080s) [35.370583] [015/162] (950s left) gem_close_race (basic-threads) [35.463187] Starting subtest: basic-threads [36.823464] Subtest basic-threads: SUCCESS (1.364s) [37.047851] [016/162] (948s left) gem_ctx_create (basic) [37.133011] Starting subtest: basic [37.138647] Subtest basic: SUCCESS (0.000s) [37.352932] [017/162] (948s left) gem_ctx_create (basic-files) [37.437740] Starting subtest: basic-files [39.446369] Subtest basic-files: SUCCESS (2.008s) [40.940682] [018/162] (945s left) gem_ctx_exec (basic) [41.016452] Starting subtest: basic [41.025410] Subtest basic: SUCCESS (0.010s) [41.189269] [019/162] (944s left) gem_exec_basic (basic) [41.260633] Starting subtest: basic [41.266318] Starting dynamic subtest: rcs0-smem [41.274228] Dynamic subtest rcs0-smem: SUCCESS (0.003s) [41.291414] Starting dynamic subtest: bcs0-smem [41.297018] Dynamic subtest bcs0-smem: SUCCESS (0.002s) [41.308058] Starting dynamic subtest: vcs0-smem [41.315781] Dynamic subtest vcs0-smem: SUCCESS (0.002s) [41.326608] Starting dynamic subtest: vcs1-smem [41.332513] Dynamic subtest vcs1-smem: SUCCESS (0.001s) [41.343966] Starting dynamic subtest: vecs0-smem [41.349502] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [41.360297] Starting dynamic subtest: ccs0-smem [41.369547] Dynamic subtest ccs0-smem: SUCCESS (0.002s) [41.377646] Subtest basic: SUCCESS (0.014s) [42.980722] [020/162] (943s left) gem_exec_create (basic) [43.063197] Starting subtest: basic [43.071331] Starting dynamic subtest: smem [46.847476] Dynamic subtest smem: SUCCESS (3.780s) [46.856607] Subtest basic: SUCCESS (3.781s) [47.086394] [021/162] (939s left) gem_exec_fence (basic-busy) [47.291827] Starting subtest: basic-busy [47.297476] Starting dynamic subtest: rcs0 [47.368421] Dynamic subtest rcs0: SUCCESS (0.081s) [47.382667] Starting dynamic subtest: bcs0 [47.425276] Dynamic subtest bcs0: SUCCESS (0.056s) [47.439420] Starting dynamic subtest: vcs0 [47.483749] Dynamic subtest vcs0: SUCCESS (0.055s) [47.498087] Starting dynamic subtest: vcs1 [47.535919] Dynamic subtest vcs1: SUCCESS (0.054s) [47.550029] Starting dynamic subtest: vecs0 [47.590060] Dynamic subtest vecs0: SUCCESS (0.053s) [47.603996] Starting dynamic subtest: ccs0 [47.650316] Dynamic subtest ccs0: SUCCESS (0.059s) [47.655923] Subtest basic-busy: SUCCESS (0.363s) [50.007177] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [50.013485] [022/162] (936s left) gem_exec_fence (basic-wait) [50.207795] Starting subtest: basic-wait [50.216625] Starting dynamic subtest: rcs0 [50.288583] Dynamic subtest rcs0: SUCCESS (0.081s) [50.302984] Starting dynamic subtest: bcs0 [50.347953] Dynamic subtest bcs0: SUCCESS (0.058s) [50.365109] Starting dynamic subtest: vcs0 [50.409492] Dynamic subtest vcs0: SUCCESS (0.061s) [50.423485] Starting dynamic subtest: vcs1 [50.469114] Dynamic subtest vcs1: SUCCESS (0.062s) [50.482141] Starting dynamic subtest: vecs0 [50.532406] Dynamic subtest vecs0: SUCCESS (0.061s) [50.546197] Starting dynamic subtest: ccs0 [50.594729] Dynamic subtest ccs0: SUCCESS (0.059s) [50.602883] Subtest basic-wait: SUCCESS (0.387s) [51.976800] [023/162] (934s left) gem_exec_fence (basic-await) [52.183408] Starting subtest: basic-await [52.189595] Starting dynamic subtest: rcs0 [52.261110] Dynamic subtest rcs0: SUCCESS (0.083s) [52.272793] Starting dynamic subtest: bcs0 [52.332296] Dynamic subtest bcs0: SUCCESS (0.074s) [52.344527] Starting dynamic subtest: vcs0 [52.409853] Dynamic subtest vcs0: SUCCESS (0.074s) [52.421031] Starting dynamic subtest: vcs1 [52.485403] Dynamic subtest vcs1: SUCCESS (0.074s) [52.496618] Starting dynamic subtest: vecs0 [52.556734] Dynamic subtest vecs0: SUCCESS (0.074s) [52.568534] Starting dynamic subtest: ccs0 [52.635504] Dynamic subtest ccs0: SUCCESS (0.075s) [52.641218] Subtest basic-await: SUCCESS (0.459s) [54.026597] [024/162] (932s left) gem_exec_fence (nb-await) [54.235740] Starting subtest: nb-await [54.241429] Starting dynamic subtest: rcs0 [54.294258] Dynamic subtest rcs0: SUCCESS (0.067s) [54.308072] Starting dynamic subtest: bcs0 [54.355190] Dynamic subtest bcs0: SUCCESS (0.059s) [54.372438] Starting dynamic subtest: vcs0 [54.410826] Dynamic subtest vcs0: SUCCESS (0.058s) [54.423035] Starting dynamic subtest: vcs1 [54.472886] Dynamic subtest vcs1: SUCCESS (0.058s) [54.489979] Starting dynamic subtest: vecs0 [54.528517] Dynamic subtest vecs0: SUCCESS (0.058s) [54.540893] Starting dynamic subtest: ccs0 [54.590146] Dynamic subtest ccs0: SUCCESS (0.058s) [54.595755] Subtest nb-await: SUCCESS (0.363s) [56.011218] [025/162] (930s left) gem_exec_gttfill (basic) [56.091833] Starting subtest: basic [97.256393] Subtest basic: FAIL (40.881s) [177.104270] Per-test timeout exceeded. Killing the current test with SIGQUIT. [189.573022] Closing watchdogs [189.577026] Initializing watchdogs [189.577079] /dev/watchdog0 [189.599863] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d67 Intel Meteorlake (Gen12) [189.607660] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [189.608930] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [189.609427] [FACT before any test] new: kernel.kmod_is_loaded.vgem: true [189.616272] [026/162] (796s left) gem_exec_parallel (engines) [189.704238] Starting subtest: engines [189.709726] Starting dynamic subtest: basic [190.029161] Dynamic subtest basic: FAIL (0.067s) [190.042883] Starting dynamic subtest: contexts [246.481126] Dynamic subtest contexts: FAIL (55.449s) [246.493641] Starting dynamic subtest: fds