[23.331294] Initializing watchdogs [23.331355] /dev/watchdog0 [23.350031] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d67 Intel Meteorlake (Gen12) [23.358131] [001/162] (960s left) i915_module_load (load) [23.409693] Starting subtest: load [26.455181] Subtest load: SUCCESS (3.055s) [26.494487] [FACT i915_module_load (load)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [26.495719] [FACT i915_module_load (load)] new: kernel.kmod_is_loaded.i915: true [26.502513] [002/162] (957s left) core_auth (basic-auth) [27.812448] Starting subtest: basic-auth [27.818562] Subtest basic-auth: SUCCESS (0.002s) [27.963690] [003/162] (955s left) core_debugfs (read-all-entries) [28.041604] Starting subtest: read-all-entries [28.122222] Subtest read-all-entries: SUCCESS (0.085s) [28.283986] [004/162] (955s left) core_sysfs (read-all-entries) [28.349394] Starting subtest: read-all-entries [28.355124] Subtest read-all-entries: SUCCESS (0.002s) [28.496767] [005/162] (955s left) fbdev (eof) [28.555620] Starting subtest: eof [28.561277] Subtest eof: SUCCESS (0.000s) [28.679486] [006/162] (955s left) fbdev (info) [28.732381] Starting subtest: info [28.738069] Subtest info: SUCCESS (0.000s) [28.829605] [007/162] (955s left) fbdev (nullptr) [28.888447] Starting subtest: nullptr [28.893980] Subtest nullptr: SUCCESS (0.000s) [28.996550] [008/162] (955s left) fbdev (read) [29.038950] Starting subtest: read [29.775978] Subtest read: SUCCESS (0.729s) [29.839658] [009/162] (954s left) fbdev (write) [29.893768] Starting subtest: write [30.401761] Subtest write: SUCCESS (0.505s) [30.472928] [010/162] (953s left) gem_basic (bad-close) [30.542426] Starting subtest: bad-close [30.553605] Subtest bad-close: SUCCESS (0.000s) [30.663696] [011/162] (953s left) gem_basic (create-close) [30.734416] Starting subtest: create-close [30.745504] Subtest create-close: SUCCESS (0.000s) [30.856014] [012/162] (953s left) gem_basic (create-fd-close) [30.921895] Starting subtest: create-fd-close [30.932993] Subtest create-fd-close: SUCCESS (0.000s) [31.055778] [013/162] (953s left) gem_busy (busy@all-engines) [31.126173] Starting subtest: busy [31.131753] Starting dynamic subtest: all-engines [31.184974] Dynamic subtest all-engines: SUCCESS (0.059s) [31.190689] Subtest busy: SUCCESS (0.059s) [32.917423] [014/162] (951s left) gem_close_race (basic-process) [33.011240] Starting subtest: basic-process [33.075524] Subtest basic-process: SUCCESS (0.068s) [33.299366] [015/162] (951s left) gem_close_race (basic-threads) [33.373198] Starting subtest: basic-threads [34.745443] Subtest basic-threads: SUCCESS (1.372s) [34.941848] [016/162] (949s left) gem_ctx_create (basic) [35.023904] Starting subtest: basic [35.029491] Subtest basic: SUCCESS (0.000s) [35.230871] [017/162] (949s left) gem_ctx_create (basic-files) [35.303541] Starting subtest: basic-files [37.312472] Subtest basic-files: SUCCESS (2.010s) [38.926336] [018/162] (945s left) gem_ctx_exec (basic) [38.999417] Starting subtest: basic [39.004895] Subtest basic: SUCCESS (0.008s) [39.166222] [019/162] (945s left) gem_exec_basic (basic) [39.239394] Starting subtest: basic [39.244907] Starting dynamic subtest: rcs0-smem [39.250437] Dynamic subtest rcs0-smem: SUCCESS (0.003s) [39.264135] Starting dynamic subtest: bcs0-smem [39.269625] Dynamic subtest bcs0-smem: SUCCESS (0.001s) [39.280295] Starting dynamic subtest: vcs0-smem [39.285836] Dynamic subtest vcs0-smem: SUCCESS (0.002s) [39.296417] Starting dynamic subtest: vcs1-smem [39.301889] Dynamic subtest vcs1-smem: SUCCESS (0.002s) [39.312428] Starting dynamic subtest: vecs0-smem [39.317903] Dynamic subtest vecs0-smem: SUCCESS (0.001s) [39.328512] Starting dynamic subtest: ccs0-smem [39.333994] Dynamic subtest ccs0-smem: SUCCESS (0.002s) [39.339515] Subtest basic: SUCCESS (0.012s) [40.987028] [020/162] (943s left) gem_exec_create (basic) [41.068112] Starting subtest: basic [41.073733] Starting dynamic subtest: smem [44.854863] Dynamic subtest smem: SUCCESS (3.788s) [44.860603] Subtest basic: SUCCESS (3.789s) [45.082755] [021/162] (939s left) gem_exec_fence (basic-busy) [45.279941] Starting subtest: basic-busy [45.285906] Starting dynamic subtest: rcs0 [45.373560] Dynamic subtest rcs0: SUCCESS (0.091s) [45.388186] Starting dynamic subtest: bcs0 [45.432682] Dynamic subtest bcs0: SUCCESS (0.056s) [45.447076] Starting dynamic subtest: vcs0 [45.491322] Dynamic subtest vcs0: SUCCESS (0.056s) [45.505310] Starting dynamic subtest: vcs1 [45.552241] Dynamic subtest vcs1: SUCCESS (0.059s) [45.566453] Starting dynamic subtest: vecs0 [45.610305] Dynamic subtest vecs0: SUCCESS (0.056s) [45.624320] Starting dynamic subtest: ccs0 [45.669544] Dynamic subtest ccs0: SUCCESS (0.057s) [45.675430] Subtest basic-busy: SUCCESS (0.380s) [47.975093] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [47.981567] [022/162] (936s left) gem_exec_fence (basic-wait) [48.183604] Starting subtest: basic-wait [48.189550] Starting dynamic subtest: rcs0 [48.290134] Dynamic subtest rcs0: SUCCESS (0.104s) [48.304928] Starting dynamic subtest: bcs0 [48.354505] Dynamic subtest bcs0: SUCCESS (0.063s) [48.369186] Starting dynamic subtest: vcs0 [48.421270] Dynamic subtest vcs0: SUCCESS (0.065s) [48.435554] Starting dynamic subtest: vcs1 [48.485202] Dynamic subtest vcs1: SUCCESS (0.062s) [48.499582] Starting dynamic subtest: vecs0 [48.545345] Dynamic subtest vecs0: SUCCESS (0.058s) [48.558615] Starting dynamic subtest: ccs0 [48.596942] Dynamic subtest ccs0: SUCCESS (0.050s) [48.602753] Subtest basic-wait: SUCCESS (0.406s) [49.936241] [023/162] (934s left) gem_exec_fence (basic-await) [50.136441] Starting subtest: basic-await [50.142263] Starting dynamic subtest: rcs0 [50.216380] Dynamic subtest rcs0: SUCCESS (0.080s) [50.231193] Starting dynamic subtest: bcs0 [50.292970] Dynamic subtest bcs0: SUCCESS (0.075s) [50.304372] Starting dynamic subtest: vcs0 [50.369812] Dynamic subtest vcs0: SUCCESS (0.073s) [50.381001] Starting dynamic subtest: vcs1 [50.443090] Dynamic subtest vcs1: SUCCESS (0.073s) [50.454286] Starting dynamic subtest: vecs0 [50.517710] Dynamic subtest vecs0: SUCCESS (0.072s) [50.528849] Starting dynamic subtest: ccs0 [50.591695] Dynamic subtest ccs0: SUCCESS (0.072s) [50.597626] Subtest basic-await: SUCCESS (0.451s) [51.963832] [024/162] (932s left) gem_exec_fence (nb-await) [52.158402] Starting subtest: nb-await [52.164061] Starting dynamic subtest: rcs0 [52.226509] Dynamic subtest rcs0: SUCCESS (0.068s) [52.238188] Starting dynamic subtest: bcs0 [52.287240] Dynamic subtest bcs0: SUCCESS (0.059s) [52.299484] Starting dynamic subtest: vcs0 [52.346340] Dynamic subtest vcs0: SUCCESS (0.058s) [52.358588] Starting dynamic subtest: vcs1 [52.405462] Dynamic subtest vcs1: SUCCESS (0.058s) [52.417719] Starting dynamic subtest: vecs0 [52.467823] Dynamic subtest vecs0: SUCCESS (0.058s) [52.482512] Starting dynamic subtest: ccs0 [52.523357] Dynamic subtest ccs0: SUCCESS (0.057s) [52.529122] Subtest nb-await: SUCCESS (0.362s) [54.042175] [025/162] (930s left) gem_exec_gttfill (basic) [54.130222] Starting subtest: basic [95.743392] Subtest basic: FAIL (41.245s) [174.565426] Per-test timeout exceeded. Killing the current test with SIGQUIT. [188.018869] Closing watchdogs [188.022666] Initializing watchdogs [188.022709] /dev/watchdog0 [188.046755] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d67 Intel Meteorlake (Gen12) [188.054688] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [188.055986] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [188.056767] [FACT before any test] new: kernel.kmod_is_loaded.vgem: true [188.063739] [026/162] (796s left) gem_exec_parallel (engines) [188.162795] Starting subtest: engines [188.168559] Starting dynamic subtest: basic [188.449998] Dynamic subtest basic: FAIL (0.063s) [188.462020] Starting dynamic subtest: contexts [245.921691] Dynamic subtest contexts: FAIL (56.508s) [245.934515] Starting dynamic subtest: fds