[65.079636] Initializing watchdogs [65.079699] /dev/watchdog0 [65.093387] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics] [65.104918] [001/162] (960s left) i915_module_load (load) [65.150551] Starting subtest: load [69.128308] Subtest load: SUCCESS (3.969s) [69.169692] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [69.171259] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [69.178024] [002/162] (956s left) core_auth (basic-auth) [70.888092] Starting subtest: basic-auth [70.893710] Subtest basic-auth: SUCCESS (0.001s) [71.035998] [003/162] (954s left) core_debugfs (read-all-entries) [71.103089] Starting subtest: read-all-entries [71.184107] Subtest read-all-entries: SUCCESS (0.083s) [71.281851] [004/162] (954s left) core_sysfs (read-all-entries) [71.350122] Starting subtest: read-all-entries [71.355708] Subtest read-all-entries: SUCCESS (0.004s) [71.481353] [005/162] (954s left) fbdev (eof) [71.532996] Starting subtest: eof [71.538554] Subtest eof: SUCCESS (0.000s) [71.626203] [006/162] (954s left) fbdev (info) [71.669987] Starting subtest: info [71.675788] Subtest info: SUCCESS (0.000s) [71.758560] [007/162] (954s left) fbdev (nullptr) [71.822619] Starting subtest: nullptr [71.828418] Subtest nullptr: SUCCESS (0.000s) [71.889334] [008/162] (954s left) fbdev (read) [71.933507] Starting subtest: read [72.442043] Subtest read: SUCCESS (0.507s) [72.524674] [009/162] (953s left) fbdev (write) [72.571726] Starting subtest: write [72.926089] Subtest write: SUCCESS (0.347s) [73.003412] [010/162] (952s left) gem_basic (bad-close) [73.068060] Starting subtest: bad-close [73.079047] Subtest bad-close: SUCCESS (0.000s) [73.186661] [011/162] (952s left) gem_basic (create-close) [73.256489] Starting subtest: create-close [73.267957] Subtest create-close: SUCCESS (0.000s) [73.395538] [012/162] (952s left) gem_basic (create-fd-close) [73.477061] Starting subtest: create-fd-close [73.489609] Subtest create-fd-close: SUCCESS (0.000s) [73.592879] [013/162] (952s left) gem_busy (busy@all-engines) [73.653976] Starting subtest: busy [73.659464] Starting dynamic subtest: all-engines [73.693740] Dynamic subtest all-engines: SUCCESS (0.036s) [73.701528] Subtest busy: SUCCESS (0.036s) [74.912644] [014/162] (951s left) gem_close_race (basic-process) [75.003710] Starting subtest: basic-process [75.061962] Subtest basic-process: SUCCESS (0.062s) [75.273111] [015/162] (950s left) gem_close_race (basic-threads) [75.351083] Starting subtest: basic-threads [76.739738] Subtest basic-threads: SUCCESS (1.391s) [76.947508] [016/162] (949s left) gem_ctx_create (basic) [77.021439] Starting subtest: basic [77.027450] Subtest basic: SUCCESS (0.000s) [77.226033] [017/162] (949s left) gem_ctx_create (basic-files) [77.307951] Starting subtest: basic-files [79.322837] Subtest basic-files: SUCCESS (2.011s) [80.893194] [018/162] (945s left) gem_ctx_exec (basic) [80.961321] Starting subtest: basic [80.966887] Subtest basic: SUCCESS (0.006s) [81.112503] [019/162] (945s left) gem_exec_basic (basic) [81.185282] Starting subtest: basic [81.196646] Starting dynamic subtest: rcs0-smem [81.202080] Dynamic subtest rcs0-smem: SUCCESS (0.003s) [81.212409] Starting dynamic subtest: bcs0-smem [81.217819] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [81.228000] Starting dynamic subtest: vcs0-smem [81.233496] Dynamic subtest vcs0-smem: SUCCESS (0.001s) [81.243528] Starting dynamic subtest: vcs1-smem [81.249036] Dynamic subtest vcs1-smem: SUCCESS (0.001s) [81.259016] Starting dynamic subtest: vecs0-smem [81.264722] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [81.274822] Starting dynamic subtest: ccs0-smem [81.280211] Dynamic subtest ccs0-smem: SUCCESS (0.001s) [81.285594] Subtest basic: SUCCESS (0.011s) [82.937918] [020/162] (943s left) gem_exec_create (basic) [83.015094] Starting subtest: basic [83.020670] Starting dynamic subtest: smem [86.850580] Dynamic subtest smem: SUCCESS (3.824s) [86.856114] Subtest basic: SUCCESS (3.825s) [87.058454] [021/162] (939s left) gem_exec_fence (basic-busy) [87.252254] Starting subtest: basic-busy [87.258188] Starting dynamic subtest: rcs0 [87.346527] Dynamic subtest rcs0: SUCCESS (0.096s) [87.357527] Starting dynamic subtest: bcs0 [87.400335] Dynamic subtest bcs0: SUCCESS (0.053s) [87.410464] Starting dynamic subtest: vcs0 [87.457336] Dynamic subtest vcs0: SUCCESS (0.056s) [87.469282] Starting dynamic subtest: vcs1 [87.512995] Dynamic subtest vcs1: SUCCESS (0.055s) [87.524775] Starting dynamic subtest: vecs0 [87.571048] Dynamic subtest vecs0: SUCCESS (0.057s) [87.582896] Starting dynamic subtest: ccs0 [87.633448] Dynamic subtest ccs0: SUCCESS (0.062s) [87.639046] Subtest basic-busy: SUCCESS (0.383s) [89.946906] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [89.953033] [022/162] (936s left) gem_exec_fence (basic-wait) [90.158998] Starting subtest: basic-wait [90.164608] Starting dynamic subtest: rcs0 [90.258847] Dynamic subtest rcs0: SUCCESS (0.097s) [90.274245] Starting dynamic subtest: bcs0 [90.307735] Dynamic subtest bcs0: SUCCESS (0.052s) [90.319678] Starting dynamic subtest: vcs0 [90.359067] Dynamic subtest vcs0: SUCCESS (0.051s) [90.370780] Starting dynamic subtest: vcs1 [90.413094] Dynamic subtest vcs1: SUCCESS (0.054s) [90.424961] Starting dynamic subtest: vecs0 [90.468203] Dynamic subtest vecs0: SUCCESS (0.054s) [90.480098] Starting dynamic subtest: ccs0 [90.522634] Dynamic subtest ccs0: SUCCESS (0.054s) [90.528142] Subtest basic-wait: SUCCESS (0.366s) [92.037406] [023/162] (934s left) gem_exec_fence (basic-await) [92.220356] Starting subtest: basic-await [92.226165] Starting dynamic subtest: rcs0 [92.294435] Dynamic subtest rcs0: SUCCESS (0.076s) [92.308068] Starting dynamic subtest: bcs0 [92.365877] Dynamic subtest bcs0: SUCCESS (0.071s) [92.376989] Starting dynamic subtest: vcs0 [92.441501] Dynamic subtest vcs0: SUCCESS (0.072s) [92.457076] Starting dynamic subtest: vcs1 [92.509726] Dynamic subtest vcs1: SUCCESS (0.071s) [92.525127] Starting dynamic subtest: vecs0 [92.581037] Dynamic subtest vecs0: SUCCESS (0.070s) [92.594791] Starting dynamic subtest: ccs0 [92.655991] Dynamic subtest ccs0: SUCCESS (0.071s) [92.661917] Subtest basic-await: SUCCESS (0.434s) [94.016538] [024/162] (932s left) gem_exec_fence (nb-await) [94.197375] Starting subtest: nb-await [94.205813] Starting dynamic subtest: rcs0 [94.264946] Dynamic subtest rcs0: SUCCESS (0.067s) [94.280354] Starting dynamic subtest: bcs0 [94.321077] Dynamic subtest bcs0: SUCCESS (0.056s) [94.333637] Starting dynamic subtest: vcs0 [94.381036] Dynamic subtest vcs0: SUCCESS (0.059s) [94.393250] Starting dynamic subtest: vcs1 [94.438333] Dynamic subtest vcs1: SUCCESS (0.057s) [94.450557] Starting dynamic subtest: vecs0 [94.493677] Dynamic subtest vecs0: SUCCESS (0.057s) [94.506367] Starting dynamic subtest: ccs0 [94.551999] Dynamic subtest ccs0: SUCCESS (0.057s) [94.560073] Subtest nb-await: SUCCESS (0.357s) [96.000370] [025/162] (930s left) gem_exec_gttfill (basic) [96.071020] Starting subtest: basic [136.752892] Subtest basic: FAIL (40.437s) [216.189704] Per-test timeout exceeded. Killing the current test with SIGQUIT. [321.169892] Closing watchdogs [321.173467] Initializing watchdogs [321.173503] /dev/watchdog0 [321.192132] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics] [321.201336] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [321.205810] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [321.206858] [FACT before any test] new: kernel.kmod_is_loaded.vgem: true [321.213974] [026/162] (705s left) gem_exec_parallel (engines) [321.296154] Starting subtest: engines [321.301648] Starting dynamic subtest: basic [321.574063] Dynamic subtest basic: FAIL (0.057s) [321.587626] Starting dynamic subtest: contexts [375.953803] Dynamic subtest contexts: FAIL (53.664s) [375.967429] Starting dynamic subtest: fds