[21.549221] Initializing watchdogs [21.549277] /dev/watchdog0 [21.568208] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d67 Intel Meteorlake (Gen12) [21.577261] [001/162] (960s left) i915_module_load (load) [21.631687] Starting subtest: load [24.833722] Subtest load: SUCCESS (3.212s) [24.867514] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [24.868337] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [24.874848] [002/162] (957s left) core_auth (basic-auth) [25.885197] Starting subtest: basic-auth [25.891171] Subtest basic-auth: SUCCESS (0.003s) [26.026184] [003/162] (956s left) core_debugfs (read-all-entries) [26.088220] Starting subtest: read-all-entries [26.166747] Subtest read-all-entries: SUCCESS (0.083s) [26.291916] [004/162] (955s left) core_sysfs (read-all-entries) [26.365129] Starting subtest: read-all-entries [26.370936] Subtest read-all-entries: SUCCESS (0.003s) [26.528073] [005/162] (955s left) fbdev (eof) [26.585092] Starting subtest: eof [26.590836] Subtest eof: SUCCESS (0.000s) [26.700742] [006/162] (955s left) fbdev (info) [26.741796] Starting subtest: info [26.747428] Subtest info: SUCCESS (0.000s) [26.850526] [007/162] (955s left) fbdev (nullptr) [26.905225] Starting subtest: nullptr [26.910889] Subtest nullptr: SUCCESS (0.000s) [27.008470] [008/162] (955s left) fbdev (read) [27.066587] Starting subtest: read [27.827485] Subtest read: SUCCESS (0.752s) [27.925784] [009/162] (954s left) fbdev (write) [27.970165] Starting subtest: write [28.482189] Subtest write: SUCCESS (0.506s) [28.551390] [010/162] (953s left) gem_basic (bad-close) [28.623456] Starting subtest: bad-close [28.634655] Subtest bad-close: SUCCESS (0.000s) [28.757372] [011/162] (953s left) gem_basic (create-close) [28.831067] Starting subtest: create-close [28.842155] Subtest create-close: SUCCESS (0.000s) [28.940736] [012/162] (953s left) gem_basic (create-fd-close) [29.016227] Starting subtest: create-fd-close [29.027254] Subtest create-fd-close: SUCCESS (0.000s) [29.162489] [013/162] (953s left) gem_busy (busy@all-engines) [29.242535] Starting subtest: busy [29.248126] Starting dynamic subtest: all-engines [29.308738] Dynamic subtest all-engines: SUCCESS (0.066s) [29.314515] Subtest busy: SUCCESS (0.067s) [30.956770] [014/162] (951s left) gem_close_race (basic-process) [31.052671] Starting subtest: basic-process [31.103090] Subtest basic-process: SUCCESS (0.057s) [31.313281] [015/162] (951s left) gem_close_race (basic-threads) [31.388494] Starting subtest: basic-threads [32.726975] Subtest basic-threads: SUCCESS (1.339s) [32.935111] [016/162] (949s left) gem_ctx_create (basic) [33.008244] Starting subtest: basic [33.014601] Subtest basic: SUCCESS (0.000s) [33.225615] [017/162] (949s left) gem_ctx_create (basic-files) [33.310774] Starting subtest: basic-files [35.323803] Subtest basic-files: SUCCESS (2.009s) [37.029272] [018/162] (945s left) gem_ctx_exec (basic) [37.111919] Starting subtest: basic [37.123840] Subtest basic: SUCCESS (0.013s) [37.303639] [019/162] (945s left) gem_exec_basic (basic) [37.380013] Starting subtest: basic [37.385484] Starting dynamic subtest: rcs0-smem [37.391053] Dynamic subtest rcs0-smem: SUCCESS (0.003s) [37.404397] Starting dynamic subtest: bcs0-smem [37.409889] Dynamic subtest bcs0-smem: SUCCESS (0.002s) [37.418910] Starting dynamic subtest: vcs0-smem [37.424494] Dynamic subtest vcs0-smem: SUCCESS (0.002s) [37.433667] Starting dynamic subtest: vcs1-smem [37.439225] Dynamic subtest vcs1-smem: SUCCESS (0.001s) [37.448320] Starting dynamic subtest: vecs0-smem [37.453907] Dynamic subtest vecs0-smem: SUCCESS (0.002s) [37.463111] Starting dynamic subtest: ccs0-smem [37.468613] Dynamic subtest ccs0-smem: SUCCESS (0.002s) [37.474190] Subtest basic: SUCCESS (0.013s) [38.902820] [020/162] (943s left) gem_exec_create (basic) [38.967740] Starting subtest: basic [38.973612] Starting dynamic subtest: smem [41.912657] Dynamic subtest smem: SUCCESS (2.945s) [41.918938] Subtest basic: SUCCESS (2.946s) [42.128999] [021/162] (940s left) gem_exec_fence (basic-busy) [42.318021] Starting subtest: basic-busy [42.323743] Starting dynamic subtest: rcs0 [42.398435] Dynamic subtest rcs0: SUCCESS (0.080s) [42.413115] Starting dynamic subtest: bcs0 [42.448444] Dynamic subtest bcs0: SUCCESS (0.049s) [42.462513] Starting dynamic subtest: vcs0 [42.507627] Dynamic subtest vcs0: SUCCESS (0.058s) [42.521620] Starting dynamic subtest: vcs1 [42.556409] Dynamic subtest vcs1: SUCCESS (0.048s) [42.570248] Starting dynamic subtest: vecs0 [42.617124] Dynamic subtest vecs0: SUCCESS (0.060s) [42.631054] Starting dynamic subtest: ccs0 [42.686413] Dynamic subtest ccs0: SUCCESS (0.068s) [42.692126] Subtest basic-busy: SUCCESS (0.369s) [44.999719] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [45.005910] [022/162] (937s left) gem_exec_fence (basic-wait) [45.198407] Starting subtest: basic-wait [45.204029] Starting dynamic subtest: rcs0 [45.285767] Dynamic subtest rcs0: SUCCESS (0.087s) [45.298763] Starting dynamic subtest: bcs0 [45.341619] Dynamic subtest bcs0: SUCCESS (0.055s) [45.354047] Starting dynamic subtest: vcs0 [45.397424] Dynamic subtest vcs0: SUCCESS (0.055s) [45.409818] Starting dynamic subtest: vcs1 [45.453450] Dynamic subtest vcs1: SUCCESS (0.055s) [45.466141] Starting dynamic subtest: vecs0 [45.508540] Dynamic subtest vecs0: SUCCESS (0.054s) [45.520998] Starting dynamic subtest: ccs0 [45.565652] Dynamic subtest ccs0: SUCCESS (0.056s) [45.571421] Subtest basic-wait: SUCCESS (0.368s) [46.922262] [023/162] (936s left) gem_exec_fence (basic-await) [47.123839] Starting subtest: basic-await [47.130065] Starting dynamic subtest: rcs0 [47.205699] Dynamic subtest rcs0: SUCCESS (0.082s) [47.216922] Starting dynamic subtest: bcs0 [47.280302] Dynamic subtest bcs0: SUCCESS (0.074s) [47.291515] Starting dynamic subtest: vcs0 [47.355024] Dynamic subtest vcs0: SUCCESS (0.074s) [47.366157] Starting dynamic subtest: vcs1 [47.429724] Dynamic subtest vcs1: SUCCESS (0.073s) [47.441031] Starting dynamic subtest: vecs0 [47.504859] Dynamic subtest vecs0: SUCCESS (0.074s) [47.516374] Starting dynamic subtest: ccs0 [47.578733] Dynamic subtest ccs0: SUCCESS (0.073s) [47.584304] Subtest basic-await: SUCCESS (0.455s) [48.940843] [024/162] (934s left) gem_exec_fence (nb-await) [49.143176] Starting subtest: nb-await [49.149014] Starting dynamic subtest: rcs0 [49.206105] Dynamic subtest rcs0: SUCCESS (0.068s) [49.219130] Starting dynamic subtest: bcs0 [49.265617] Dynamic subtest bcs0: SUCCESS (0.059s) [49.278710] Starting dynamic subtest: vcs0 [49.325119] Dynamic subtest vcs0: SUCCESS (0.058s) [49.340061] Starting dynamic subtest: vcs1 [49.383404] Dynamic subtest vcs1: SUCCESS (0.057s) [49.397948] Starting dynamic subtest: vecs0 [49.440482] Dynamic subtest vecs0: SUCCESS (0.058s) [49.453039] Starting dynamic subtest: ccs0 [49.499103] Dynamic subtest ccs0: SUCCESS (0.058s) [49.505055] Subtest nb-await: SUCCESS (0.362s) [50.976820] [025/162] (932s left) gem_exec_gttfill (basic) [51.058883] Starting subtest: basic [92.612638] Subtest basic: FAIL (41.306s) [171.496185] Per-test timeout exceeded. Killing the current test with SIGQUIT. [174.729745] Closing watchdogs [174.733743] Initializing watchdogs [174.733786] /dev/watchdog0 [174.756665] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d67 Intel Meteorlake (Gen12) [174.764562] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [174.765977] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [174.766477] [FACT before any test] new: kernel.kmod_is_loaded.vgem: true [174.773875] [026/162] (808s left) gem_exec_parallel (engines) [174.880678] Starting subtest: engines [174.886802] Starting dynamic subtest: basic [175.202009] Dynamic subtest basic: FAIL (0.057s) [175.219052] Starting dynamic subtest: contexts [231.238136] Dynamic subtest contexts: FAIL (55.307s) [231.252587] Starting dynamic subtest: fds