[55.949989] Initializing watchdogs [55.951123] /dev/watchdog0 [55.963200] [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] [55.966879] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [55.969252] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [55.972521] [001/130] (960s left) gem_eio (wait-10ms) [56.176177] Starting subtest: wait-10ms [56.377967] Subtest wait-10ms: SUCCESS (0.203s) [56.535727] [002/130] (959s left) kms_cursor_crc (cursor-onscreen-512x170) [56.812786] Starting subtest: cursor-onscreen-512x170 [56.816857] Subtest cursor-onscreen-512x170: SKIP (0.001s) [56.950638] [003/130] (959s left) kms_hdmi_inject (inject-4k) [57.300202] Starting subtest: inject-4k [57.600369] Subtest inject-4k: SUCCESS (0.303s) [58.114288] [004/130] (958s left) gem_exec_schedule (u-semaphore-noskip) [58.200575] Starting subtest: u-semaphore-noskip [58.593394] Subtest u-semaphore-noskip: SUCCESS (0.397s) [58.917341] [005/130] (957s left) gem_exec_balancer (parallel-out-fence) [59.159961] Starting subtest: parallel-out-fence [60.114171] Subtest parallel-out-fence: SUCCESS (0.953s) [60.224226] [006/130] (956s left) kms_chamelium_hpd (vga-hpd) [60.622306] Subtest vga-hpd: SKIP (0.000s) [61.461264] [007/130] (955s left) kms_invalid_mode (bad-vtotal) [61.563254] Starting subtest: bad-vtotal [61.565560] Starting dynamic subtest: pipe-A-eDP-1 [61.844447] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.281s) [61.852464] Starting dynamic subtest: pipe-B-eDP-1 [61.854777] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [61.861318] Starting dynamic subtest: pipe-C-eDP-1 [61.863618] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [61.870071] Starting dynamic subtest: pipe-D-eDP-1 [61.872042] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [61.873995] Subtest bad-vtotal: SUCCESS (0.285s) [62.681375] [008/130] (954s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite) [62.796530] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite [67.138274] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (4.345s) [67.277796] [009/130] (949s left) kms_vrr (seamless-rr-switch-drrs) [67.376082] Starting subtest: seamless-rr-switch-drrs [67.382793] Subtest seamless-rr-switch-drrs: SKIP (0.000s) [67.439603] [010/130] (949s left) kms_flip (plain-flip-fb-recreate-interruptible) [67.546929] Starting subtest: plain-flip-fb-recreate-interruptible [67.549297] Starting dynamic subtest: A-eDP1 [76.449213] Dynamic subtest A-eDP1: SUCCESS (8.902s) [76.457620] Starting dynamic subtest: B-eDP1 [85.348995] Dynamic subtest B-eDP1: SUCCESS (8.899s) [85.356331] Starting dynamic subtest: C-eDP1 [94.233918] Dynamic subtest C-eDP1: SUCCESS (8.884s) [94.240841] Starting dynamic subtest: D-eDP1 [103.037106] Dynamic subtest D-eDP1: SUCCESS (8.802s) [103.039083] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.492s) [104.178844] [011/130] (912s left) kms_big_fb (y-tiled-16bpp-rotate-90) [104.292335] Starting subtest: y-tiled-16bpp-rotate-90 [104.296670] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s) [104.375225] [012/130] (912s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc) [104.476772] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc [107.792584] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (3.321s) [107.919709] [013/130] (908s left) kms_big_fb (linear-64bpp-rotate-90) [108.026375] Starting subtest: linear-64bpp-rotate-90 [108.045067] Subtest linear-64bpp-rotate-90: SKIP (0.019s) [108.169840] [014/130] (908s left) kms_psr (psr2-cursor-render) [108.266756] Starting subtest: psr2-cursor-render [108.268813] Starting dynamic subtest: eDP-1 [109.931097] Dynamic subtest eDP-1: SUCCESS (1.663s) [109.933506] Subtest psr2-cursor-render: SUCCESS (1.664s) [110.775540] [015/130] (906s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling) [110.873077] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling [110.875573] Starting dynamic subtest: pipe-A-default-mode [111.161182] Dynamic subtest pipe-A-default-mode: SKIP (0.283s) [111.166066] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.284s) [111.977342] [016/130] (904s left) gem_mmap_offset (perf) [112.031032] Starting subtest: perf [112.033241] Starting dynamic subtest: smem0 [112.378205] Dynamic subtest smem0: SUCCESS (0.345s) [112.380080] Subtest perf: SUCCESS (0.345s) [112.454802] [017/130] (904s left) kms_atomic (plane-cursor-legacy) [112.547547] Starting subtest: plane-cursor-legacy [112.549839] Starting dynamic subtest: pipe-A-eDP-1 [112.913210] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.362s) [112.915108] Subtest plane-cursor-legacy: SUCCESS (0.362s) [113.779985] [018/130] (903s left) sw_sync (sync_multi_consumer) [113.815682] Starting subtest: sync_multi_consumer [114.352787] Subtest sync_multi_consumer: SUCCESS (0.536s) [114.403508] [019/130] (902s left) syncobj_wait (reset-unsignaled) [114.461573] Starting subtest: reset-unsignaled [114.463985] Subtest reset-unsignaled: SUCCESS (0.000s) [114.570898] [020/130] (902s left) gem_vm_create (isolation) [114.638189] Starting subtest: isolation [114.648088] Subtest isolation: SUCCESS (0.010s) [114.767142] [021/130] (902s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs) [114.868886] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs [114.871273] Starting dynamic subtest: pipe-A-eDP-1 [114.894077] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [114.902649] Starting dynamic subtest: pipe-B-eDP-1 [114.924310] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [114.931533] Starting dynamic subtest: pipe-C-eDP-1 [114.953661] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [114.961856] Starting dynamic subtest: pipe-D-eDP-1 [114.984074] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [114.988417] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.002s) [117.074468] [022/130] (900s left) kms_chamelium_edid (dp-edid-read) [117.466022] Subtest dp-edid-read: SKIP (0.000s) [118.255344] [023/130] (898s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling) [118.354061] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling [118.356476] Starting dynamic subtest: pipe-A-default-mode [118.640712] Dynamic subtest pipe-A-default-mode: SKIP (0.281s) [118.645445] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.282s) [119.478086] [024/130] (897s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted) [119.530314] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted [119.631426] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.102s) [119.700390] [025/130] (897s left) kms_flip (flip-vs-expired-vblank-interruptible) [119.792642] Starting subtest: flip-vs-expired-vblank-interruptible [119.794963] Starting dynamic subtest: A-eDP1 [128.458576] Dynamic subtest A-eDP1: SUCCESS (8.665s) [128.467263] Starting dynamic subtest: B-eDP1 [137.156587] Dynamic subtest B-eDP1: SUCCESS (8.697s) [137.165763] Starting dynamic subtest: C-eDP1 [145.844041] Dynamic subtest C-eDP1: SUCCESS (8.687s) [145.851249] Starting dynamic subtest: D-eDP1 [154.543072] Dynamic subtest D-eDP1: SUCCESS (8.698s) [154.545631] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.752s) [155.769284] [026/130] (861s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt) [155.877275] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt [161.049950] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (5.176s) [161.167197] [027/130] (856s left) kms_addfb_basic (no-handle) [161.226194] Starting subtest: no-handle [161.228449] Subtest no-handle: SUCCESS (0.000s) [161.392532] [028/130] (855s left) fbdev (nullptr) [161.443740] Starting subtest: nullptr [161.446143] Subtest nullptr: SUCCESS (0.000s) [161.497253] [029/130] (855s left) gem_ctx_persistence (legacy-engines-hang) [161.551542] Starting subtest: legacy-engines-hang [161.553927] Starting dynamic subtest: render [161.707463] Dynamic subtest render: SUCCESS (0.155s) [161.720674] Starting dynamic subtest: bsd1 [161.825384] Dynamic subtest bsd1: SUCCESS (0.116s) [161.835779] Starting dynamic subtest: bsd2 [161.898924] Dynamic subtest bsd2: SUCCESS (0.072s) [161.907683] Starting dynamic subtest: blt [162.041638] Dynamic subtest blt: SUCCESS (0.141s) [162.051280] Starting dynamic subtest: vebox [162.156371] Dynamic subtest vebox: SUCCESS (0.114s) [162.160412] Subtest legacy-engines-hang: SUCCESS (0.605s) [162.226175] [030/130] (855s left) kms_plane_scaling (plane-downscale-factor-0-25-with-rotation) [162.326584] Starting subtest: plane-downscale-factor-0-25-with-rotation [162.328499] Starting dynamic subtest: pipe-A [162.342730] Dynamic subtest pipe-A: SKIP (0.011s) [162.349591] Starting dynamic subtest: pipe-B [162.365864] Dynamic subtest pipe-B: SKIP (0.022s) [162.378499] Starting dynamic subtest: pipe-C [162.386758] Dynamic subtest pipe-C: SKIP (0.020s) [162.394653] Starting dynamic subtest: pipe-D [162.406567] Dynamic subtest pipe-D: SKIP (0.020s) [162.409861] Subtest plane-downscale-factor-0-25-with-rotation: SKIP (0.074s) [162.494569] [031/130] (854s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc) [162.596361] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc [162.598173] Starting dynamic subtest: pipe-A-eDP-1 [178.477693] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.455s) [178.487708] Starting dynamic subtest: pipe-B-eDP-1 [195.371321] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.369s) [195.379040] Starting dynamic subtest: pipe-C-eDP-1 [212.351496] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.201s) [212.362030] Starting dynamic subtest: pipe-D-eDP-1 [229.328461] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.226s) [229.330952] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (8.256s) [230.565705] [032/130] (845s left) syncobj_wait (signal) [230.622267] Starting subtest: signal [230.624622] Subtest signal: SUCCESS (0.000s) [230.715916] [033/130] (845s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt) [230.820342] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt [230.825056] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [230.938122] [034/130] (845s left) vgem_basic (mmap) [231.014715] Starting subtest: mmap [231.026749] Subtest mmap: SUCCESS (0.014s) [231.073402] [FACT vgem_basic (mmap)] new: kernel.kmod_is_loaded.vgem: true [231.077078] [035/130] (844s left) kms_cursor_edge_walk (256x256-top-bottom) [231.169678] Starting subtest: 256x256-top-bottom [231.172578] Starting dynamic subtest: pipe-A-eDP-1 [234.610378] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.436s) [234.620262] Starting dynamic subtest: pipe-D-eDP-1 [237.793892] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.177s) [237.796781] Subtest 256x256-top-bottom: SUCCESS (6.617s) [238.572698] [036/130] (837s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset) [238.677710] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset [238.680576] Starting dynamic subtest: A-eDP1 [242.320914] Dynamic subtest A-eDP1: SUCCESS (3.639s) [242.329352] Starting dynamic subtest: B-eDP1 [245.619708] Dynamic subtest B-eDP1: SUCCESS (3.297s) [245.627291] Starting dynamic subtest: C-eDP1 [248.917598] Dynamic subtest C-eDP1: SUCCESS (3.296s) [248.925831] Starting dynamic subtest: D-eDP1 [252.208102] Dynamic subtest D-eDP1: SUCCESS (3.287s) [252.210671] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.525s) [253.077688] [037/130] (823s left) kms_pm_dc (dc5-dpms) [254.184892] Starting subtest: dc5-dpms [256.230053] Subtest dc5-dpms: SUCCESS (2.045s) [256.306272] [038/130] (819s left) kms_chamelium_hpd (common-hpd-after-suspend) [256.703923] Subtest common-hpd-after-suspend: SKIP (0.000s) [257.461773] [039/130] (818s left) kms_frontbuffer_tracking (psr-slowdraw) [257.559614] Starting subtest: psr-slowdraw [280.570286] Subtest psr-slowdraw: SUCCESS (23.005s) [280.722213] [040/130] (795s left) i915_suspend (sysfs-reader) [280.785316] Starting subtest: sysfs-reader [299.265006] Subtest sysfs-reader: SUCCESS (3.118s) [299.301198] [041/130] (792s left) syncobj_timeline (invalid-query-bad-pad) [299.358696] Starting subtest: invalid-query-bad-pad [299.360309] Subtest invalid-query-bad-pad: SUCCESS (0.000s) [299.466131] [042/130] (792s left) gem_exec_schedule (preempt-contexts) [299.537791] Starting subtest: preempt-contexts [299.539724] Starting dynamic subtest: rcs0 [299.587067] Dynamic subtest rcs0: SUCCESS (0.053s) [299.594830] Starting dynamic subtest: bcs0 [299.635625] Dynamic subtest bcs0: SUCCESS (0.050s) [299.643878] Starting dynamic subtest: vcs0 [299.679841] Dynamic subtest vcs0: SUCCESS (0.043s) [299.689540] Starting dynamic subtest: vcs1 [299.724780] Dynamic subtest vcs1: SUCCESS (0.045s) [299.732792] Starting dynamic subtest: vecs0 [299.763075] Dynamic subtest vecs0: SUCCESS (0.037s) [299.773222] Starting dynamic subtest: ccs0 [299.802288] Dynamic subtest ccs0: SUCCESS (0.039s) [299.804077] Subtest preempt-contexts: SUCCESS (0.270s) [300.859910] [043/130] (790s left) gem_exec_reloc (basic-write-read-active) [300.942466] Subtest basic-write-read-active: SKIP (0.000s) [301.020166] [044/130] (790s left) gem_create (create-ext-set-pat) [301.197746] Starting subtest: create-ext-set-pat [301.200679] Subtest create-ext-set-pat: SUCCESS (0.001s) [301.315936] [045/130] (790s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen) [301.424652] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen [305.959671] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.534s) [306.079751] [046/130] (785s left) drm_fdinfo (busy) [306.123201] Starting subtest: busy [306.125272] Starting dynamic subtest: rcs0 [306.163618] Dynamic subtest rcs0: SKIP (0.041s) [306.174128] Starting dynamic subtest: bcs0 [306.178260] Dynamic subtest bcs0: SKIP (0.000s) [306.186039] Starting dynamic subtest: vcs0 [306.190406] Dynamic subtest vcs0: SKIP (0.000s) [306.198159] Starting dynamic subtest: vcs1 [306.202255] Dynamic subtest vcs1: SKIP (0.000s) [306.210418] Starting dynamic subtest: vecs0 [306.215337] Dynamic subtest vecs0: SKIP (0.000s) [306.229052] Starting dynamic subtest: ccs0 [306.233702] Dynamic subtest ccs0: SKIP (0.000s) [306.238130] Subtest busy: SKIP (0.046s) [306.272493] [047/130] (785s left) kms_lease (lease-revoke) [306.369397] Starting subtest: lease-revoke [306.371694] Starting dynamic subtest: pipe-A-eDP-1 [306.417857] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.045s) [306.426215] Starting dynamic subtest: pipe-B-eDP-1 [306.461882] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.045s) [306.471852] Starting dynamic subtest: pipe-C-eDP-1 [306.501493] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.039s) [306.510589] Starting dynamic subtest: pipe-D-eDP-1 [306.553719] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.051s) [306.556091] Subtest lease-revoke: SUCCESS (0.184s) [306.675044] [048/130] (785s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt) [306.778262] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt [306.782693] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [306.880808] [049/130] (785s left) kms_cursor_crc (cursor-rapid-movement-256x256) [307.098317] Starting subtest: cursor-rapid-movement-256x256 [307.102743] Starting dynamic subtest: pipe-A-eDP-1 [307.652301] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.549s) [307.681265] Starting dynamic subtest: pipe-D-eDP-1 [308.851494] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.175s) [308.854049] Subtest cursor-rapid-movement-256x256: SUCCESS (1.750s) [309.674821] [050/130] (782s left) gem_exec_reloc (basic-cpu) [309.748689] Subtest basic-cpu: SKIP (0.000s) [309.810590] [051/130] (782s left) syncobj_timeline (wait-any-interrupted) [309.870750] Starting subtest: wait-any-interrupted [309.975934] Subtest wait-any-interrupted: SUCCESS (0.105s) [310.049776] [052/130] (781s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt) [310.157346] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt [314.738818] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (4.580s) [314.883741] [053/130] (777s left) gem_softpin (evict-prime) [314.934281] Starting subtest: evict-prime [314.936681] Starting dynamic subtest: rcs0 [316.121952] Dynamic subtest rcs0: SUCCESS (1.186s) [316.134362] Starting dynamic subtest: bcs0 [317.263750] Dynamic subtest bcs0: SUCCESS (1.140s) [317.275693] Starting dynamic subtest: vcs0 [318.780584] Dynamic subtest vcs0: SUCCESS (1.513s) [318.792429] Starting dynamic subtest: vcs1 [320.755025] Dynamic subtest vcs1: SUCCESS (1.971s) [320.768185] Starting dynamic subtest: vecs0 [322.802306] Dynamic subtest vecs0: SUCCESS (2.044s) [322.814754] Starting dynamic subtest: ccs0 [323.935803] Dynamic subtest ccs0: SUCCESS (1.132s) [323.947445] Starting dynamic subtest: all [325.765133] Dynamic subtest all: SUCCESS (1.825s) [325.768021] Subtest evict-prime: SUCCESS (10.824s) [325.880527] [054/130] (766s left) gem_lmem_swapping (parallel-random-verify) [325.926037] Subtest parallel-random-verify: SKIP (0.000s) [325.965998] [055/130] (766s left) kms_content_protection (dp-mst-type-1) [326.133235] Starting subtest: dp-mst-type-1 [326.137915] Subtest dp-mst-type-1: SKIP (0.000s) [326.280055] [056/130] (765s left) syncobj_timeline (signal-point-0) [326.345418] Starting subtest: signal-point-0 [326.347451] Subtest signal-point-0: SUCCESS (0.000s) [326.452177] [057/130] (765s left) gem_exec_whisper (basic-queues-forked) [326.516580] Starting subtest: basic-queues-forked [350.616456] Subtest basic-queues-forked: SUCCESS (24.086s) [350.796437] [058/130] (741s left) gem_busy (semaphore) [350.992386] Subtest semaphore: SKIP (0.000s) [351.238920] [059/130] (741s left) kms_rotation_crc (primary-rotation-180) [351.332679] Starting subtest: primary-rotation-180 [358.247608] Subtest primary-rotation-180: SUCCESS (6.910s) [359.384846] [060/130] (732s left) gem_ppgtt (shrink-vs-evict-pinned) [359.449288] Starting subtest: shrink-vs-evict-pinned [364.723503] Subtest shrink-vs-evict-pinned: SUCCESS (5.270s) [364.775501] [061/130] (727s left) kms_ccs (crc-primary-basic-4-tiled-lnl-ccs) [364.878677] Starting subtest: crc-primary-basic-4-tiled-lnl-ccs [364.883609] Subtest crc-primary-basic-4-tiled-lnl-ccs: SKIP (0.000s) [365.993869] [062/130] (726s left) kms_psr (psr2-sprite-mmap-gtt) [366.085937] Starting subtest: psr2-sprite-mmap-gtt [366.088267] Starting dynamic subtest: eDP-1 [367.564080] Dynamic subtest eDP-1: SKIP (1.472s) [367.569112] Subtest psr2-sprite-mmap-gtt: SKIP (1.473s) [367.747682] [063/130] (724s left) kms_psr2_su (page_flip-xrgb8888) [367.808269] Subtest page_flip-XRGB8888: SKIP (0.000s) [367.868104] [064/130] (724s left) i915_hangman (engine-engine-error) [367.932817] Starting subtest: engine-engine-error [367.934737] Starting dynamic subtest: rcs0 [369.716139] Dynamic subtest rcs0: SUCCESS (1.782s) [369.728786] Starting dynamic subtest: bcs0 [371.761683] Dynamic subtest bcs0: SUCCESS (2.042s) [371.774517] Starting dynamic subtest: vcs0 [373.745921] Dynamic subtest vcs0: SUCCESS (1.981s) [373.758523] Starting dynamic subtest: vcs1 [375.730354] Dynamic subtest vcs1: SUCCESS (1.980s) [375.743128] Starting dynamic subtest: vecs0 [377.713350] Dynamic subtest vecs0: SUCCESS (1.980s) [377.724993] Starting dynamic subtest: ccs0 [379.697931] Dynamic subtest ccs0: SUCCESS (1.981s) [379.700860] Subtest engine-engine-error: SUCCESS (11.762s) [379.780559] [065/130] (712s left) gem_exec_balancer (bonded-dual) [379.842994] Starting subtest: bonded-dual [379.847377] Subtest bonded-dual: SKIP (0.000s) [380.142541] [066/130] (712s left) gem_eio (in-flight-internal-10ms) [380.340400] Starting subtest: in-flight-internal-10ms [381.842065] Subtest in-flight-internal-10ms: SUCCESS (1.501s) [382.009541] [067/130] (710s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-mc-ccs) [382.103725] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-mc-ccs [382.106000] Starting dynamic subtest: pipe-A-eDP-1 [382.130147] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [382.140370] Starting dynamic subtest: pipe-B-eDP-1 [382.162718] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [382.171987] Starting dynamic subtest: pipe-C-eDP-1 [382.195874] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [382.205279] Starting dynamic subtest: pipe-D-eDP-1 [382.229014] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [382.233387] Subtest crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.005s) [384.298314] [068/130] (708s left) gem_exec_schedule (manyslice) [384.369487] Starting subtest: manyslice [384.371525] Starting dynamic subtest: rcs0 [389.504438] Dynamic subtest rcs0: SUCCESS (5.140s) [389.521237] Starting dynamic subtest: bcs0 [395.627337] Dynamic subtest bcs0: SUCCESS (6.117s) [395.648005] Starting dynamic subtest: vcs0 [401.190045] Dynamic subtest vcs0: SUCCESS (5.567s) [401.207831] Starting dynamic subtest: vcs1 [407.252608] Dynamic subtest vcs1: SUCCESS (6.066s) [407.267901] Starting dynamic subtest: vecs0 [412.819645] Dynamic subtest vecs0: SUCCESS (5.568s) [412.835063] Starting dynamic subtest: ccs0 [418.475285] Dynamic subtest ccs0: SUCCESS (5.656s) [418.478103] Subtest manyslice: SUCCESS (34.122s) [418.808387] [069/130] (673s left) perf (gen12-mi-rpc) [418.913739] Starting subtest: gen12-mi-rpc [418.916018] Starting dynamic subtest: rcs0 [418.941005] Dynamic subtest rcs0: SUCCESS (0.026s) [418.942938] Subtest gen12-mi-rpc: SUCCESS (0.027s) [418.983059] [070/130] (673s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt) [419.123738] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt [419.128027] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s) [419.225620] [071/130] (673s left) kms_psr (fbc-pr-suspend) [419.323783] Starting subtest: fbc-pr-suspend [419.327984] Subtest fbc-pr-suspend: SKIP (0.000s) [419.386914] [072/130] (673s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs) [419.486469] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs [419.488756] Starting dynamic subtest: pipe-A-eDP-1 [420.244497] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.747s) [420.253517] Starting dynamic subtest: pipe-B-eDP-1 [421.979713] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.736s) [421.990331] Starting dynamic subtest: pipe-C-eDP-1 [423.778537] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.798s) [423.788391] Starting dynamic subtest: pipe-D-eDP-1 [425.579113] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.799s) [425.581139] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.085s) [426.781788] [073/130] (666s left) gem_exec_reloc (basic-wc-cpu-active) [426.853639] Subtest basic-wc-cpu-active: SKIP (0.000s) [426.927003] [074/130] (665s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [427.036899] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc [427.040888] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [427.124133] [075/130] (665s left) kms_pm_rpm (fences-dpms) [428.961046] Starting subtest: fences-dpms [428.966749] Subtest fences-dpms: SKIP (0.000s) [429.390794] [076/130] (663s left) gem_eio (throttle) [429.617742] Starting subtest: throttle [429.924692] Subtest throttle: SUCCESS (0.307s) [430.063756] [077/130] (662s left) gem_mmap_wc (set-cache-level) [430.116368] Subtest set-cache-level: SKIP (0.000s) [430.173695] [078/130] (662s left) kms_getfb (getfb2-into-addfb2) [430.235766] Starting subtest: getfb2-into-addfb2 [430.238248] Subtest getfb2-into-addfb2: SUCCESS (0.000s) [430.339674] [079/130] (662s left) api_intel_bb (render-ccs) [430.394276] Starting subtest: render-ccs [430.752564] Subtest render-ccs: SUCCESS (0.356s) [430.843257] [080/130] (662s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip) [430.942460] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip [431.884985] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.944s) [432.035000] [081/130] (661s left) gem_fenced_exec_thrash (no-spare-fences-interruptible) [432.103828] Subtest no-spare-fences-interruptible: SKIP (0.000s) [432.171084] [082/130] (660s left) kms_big_fb (y-tiled-addfb-size-overflow) [432.287407] Starting subtest: y-tiled-addfb-size-overflow [432.291154] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s) [432.378085] [083/130] (660s left) gem_mmap_gtt (basic-write-gtt) [432.435554] Subtest basic-write-gtt: SKIP (0.000s) [432.498358] [084/130] (660s left) kms_flip_event_leak (basic) [432.594337] Starting subtest: basic [432.596328] Starting dynamic subtest: pipe-A-eDP-1 [433.048726] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.450s) [433.059769] Starting dynamic subtest: pipe-B-eDP-1 [434.181133] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.130s) [434.191020] Starting dynamic subtest: pipe-C-eDP-1 [435.266319] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.085s) [435.277096] Starting dynamic subtest: pipe-D-eDP-1 [436.364161] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.097s) [436.366320] Subtest basic: SUCCESS (3.768s) [437.182734] [085/130] (656s left) vgem_basic (bad-handle) [437.228465] Starting subtest: bad-handle [437.230506] Subtest bad-handle: SUCCESS (0.000s) [437.278827] [086/130] (655s left) i915_pm_rc6_residency (rc6-idle) [437.335426] Starting subtest: rc6-idle [437.369479] Starting dynamic subtest: gt0-rcs0 [449.582977] Dynamic subtest gt0-rcs0: SUCCESS (12.213s) [449.592530] Starting dynamic subtest: gt0-bcs0 [461.837584] Dynamic subtest gt0-bcs0: SUCCESS (12.252s) [461.848391] Starting dynamic subtest: gt0-ccs0 [474.093322] Dynamic subtest gt0-ccs0: SUCCESS (12.252s) [474.104344] Starting dynamic subtest: gt1-vcs0 [486.310293] Dynamic subtest gt1-vcs0: SUCCESS (12.212s) [486.318988] Starting dynamic subtest: gt1-vecs0 [498.947775] Dynamic subtest gt1-vecs0: SUCCESS (12.632s) [498.950300] Subtest rc6-idle: SUCCESS (61.604s) [499.764347] [087/130] (593s left) gen3_mixed_blits [499.889822] [088/130] (593s left) syncobj_wait (wait-all-for-submit-complex) [499.958448] Starting subtest: wait-all-for-submit-complex [500.173826] Subtest wait-all-for-submit-complex: SUCCESS (0.214s) [500.254767] [089/130] (593s left) gem_exec_fence (basic-busy) [500.440251] Starting subtest: basic-busy [500.442691] Starting dynamic subtest: rcs0 [500.560862] Dynamic subtest rcs0: SUCCESS (0.121s) [500.572429] Starting dynamic subtest: bcs0 [500.631692] Dynamic subtest bcs0: SUCCESS (0.069s) [500.643141] Starting dynamic subtest: vcs0 [500.700689] Dynamic subtest vcs0: SUCCESS (0.067s) [500.712316] Starting dynamic subtest: vcs1 [500.766528] Dynamic subtest vcs1: SUCCESS (0.064s) [500.777691] Starting dynamic subtest: vecs0 [500.830994] Dynamic subtest vecs0: SUCCESS (0.063s) [500.843389] Starting dynamic subtest: ccs0 [500.897751] Dynamic subtest ccs0: SUCCESS (0.065s) [500.900244] Subtest basic-busy: SUCCESS (0.459s) [502.819808] [090/130] (590s left) gem_sync (basic-all) [502.892815] Starting subtest: basic-all [506.646895] Subtest basic-all: SUCCESS (3.762s) [506.825599] [091/130] (586s left) gem_mmap_gtt (cpuset-basic-small-copy-odd) [506.883817] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s) [506.943375] [092/130] (586s left) kms_cursor_legacy (flip-vs-cursor-atomic) [507.069066] Starting subtest: flip-vs-cursor-atomic [513.847666] Subtest flip-vs-cursor-atomic: SUCCESS (6.774s) [514.684769] [093/130] (578s left) kms_psr (pr-cursor-blt) [514.792588] Starting subtest: pr-cursor-blt [514.796940] Subtest pr-cursor-blt: SKIP (0.000s) [514.859454] [094/130] (578s left) syncobj_eventfd (binary-wait-signaled) [514.912288] Starting subtest: binary-wait-signaled [514.914296] Subtest binary-wait-signaled: SUCCESS (0.000s) [515.025908] [095/130] (578s left) i915_pm_rps (waitboost) [515.083122] Starting subtest: waitboost [515.152159] Subtest waitboost: FAIL (0.013s) [515.221770] [096/130] (578s left) kms_rotation_crc (multiplane-rotation-cropping-bottom) [515.326013] Starting subtest: multiplane-rotation-cropping-bottom [522.476757] Subtest multiplane-rotation-cropping-bottom: SUCCESS (7.149s) [522.580750] [097/130] (571s left) api_intel_bb (intel-bb-blit-y) [522.648476] Starting subtest: intel-bb-blit-y [527.125446] Subtest intel-bb-blit-y: SUCCESS (4.476s) [527.272990] [098/130] (566s left) kms_flip (2x-flip-vs-expired-vblank-interruptible) [527.375431] Starting subtest: 2x-flip-vs-expired-vblank-interruptible [527.388082] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s) [527.494788] [099/130] (566s left) kms_cursor_legacy (cursorb-vs-flipa-atomic) [527.612252] Starting subtest: cursorB-vs-flipA-atomic [527.616428] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s) [527.707188] [100/130] (566s left) kms_vblank (query-idle-hang) [527.799325] Starting subtest: query-idle-hang [527.801726] Starting dynamic subtest: pipe-A-eDP-1 [554.566277] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.770s) [554.583044] Starting dynamic subtest: pipe-D-eDP-1 [569.474293] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.905s) [569.476453] Subtest query-idle-hang: SUCCESS (41.679s) [570.272845] [101/130] (523s left) kms_atomic_interruptible (legacy-cursor) [570.373912] Starting subtest: legacy-cursor [570.376214] Starting dynamic subtest: pipe-A-eDP-1 [577.770380] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.396s) [577.772782] Subtest legacy-cursor: SUCCESS (7.397s) [578.573507] [102/130] (515s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted) [578.635535] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted [578.637430] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s) [578.751060] [103/130] (515s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible) [578.815039] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s) [578.898065] [104/130] (515s left) kms_dsc (dsc-with-bpc-formats) [578.993916] Starting subtest: dsc-with-bpc-formats [579.105611] Subtest dsc-with-bpc-formats: SKIP (0.003s) [579.138974] [105/130] (514s left) kms_plane_lowres (tiling-x) [579.238707] Starting subtest: tiling-x [579.241756] Starting dynamic subtest: pipe-A-eDP-1 [579.246703] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [579.543182] Starting dynamic subtest: pipe-B-eDP-1 [579.548246] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [579.564851] Starting dynamic subtest: pipe-C-eDP-1 [579.569372] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [579.580528] Starting dynamic subtest: pipe-D-eDP-1 [579.584884] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [579.589291] Subtest tiling-x: SKIP (0.299s) [580.369208] [106/130] (513s left) gem_ctx_persistence (engines-hostile) [580.431750] Starting subtest: engines-hostile [580.434188] Starting dynamic subtest: rcs0 [580.586799] Dynamic subtest rcs0: SUCCESS (0.154s) [580.599500] Starting dynamic subtest: bcs0 [580.704905] Dynamic subtest bcs0: SUCCESS (0.116s) [580.716369] Starting dynamic subtest: vcs0 [580.824392] Dynamic subtest vcs0: SUCCESS (0.118s) [580.837334] Starting dynamic subtest: vcs1 [580.923251] Dynamic subtest vcs1: SUCCESS (0.097s) [580.935475] Starting dynamic subtest: vecs0 [581.046010] Dynamic subtest vecs0: SUCCESS (0.120s) [581.058774] Starting dynamic subtest: ccs0 [581.150245] Dynamic subtest ccs0: SUCCESS (0.103s) [581.152733] Subtest engines-hostile: SUCCESS (0.719s) [581.224181] [107/130] (512s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0) [581.321772] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0 [581.325723] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s) [581.431594] [108/130] (512s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [581.538705] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [581.542315] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [581.645512] [109/130] (512s left) gem_pxp (verify-pxp-stale-ctx-execution) [581.769551] Starting subtest: verify-pxp-stale-ctx-execution [582.829542] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.058s) [582.978913] [110/130] (511s left) api_intel_allocator (fork-simple-once) [583.034699] Starting subtest: fork-simple-once [583.046910] Subtest fork-simple-once: SUCCESS (0.015s) [583.180155] [111/130] (510s left) kms_big_fb (4-tiled-16bpp-rotate-180) [583.286080] Starting subtest: 4-tiled-16bpp-rotate-180 [584.575616] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.291s) [584.748978] [112/130] (509s left) kms_frontbuffer_tracking (psr-2p-rte) [584.848289] Starting subtest: psr-2p-rte [584.852535] Subtest psr-2p-rte: SKIP (0.000s) [584.944286] [113/130] (509s left) gem_render_copy_redux (flink) [585.009402] Starting subtest: flink [585.936563] Subtest flink: SUCCESS (0.928s) [586.078673] [114/130] (508s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-gtt) [586.184704] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-gtt [586.188054] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [586.268995] [115/130] (508s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers) [586.357423] Starting subtest: plane-downscale-factor-0-75-with-modifiers [586.359389] Starting dynamic subtest: pipe-A [586.873203] Dynamic subtest pipe-A: SUCCESS (0.515s) [586.883253] Starting dynamic subtest: pipe-B [588.390197] Dynamic subtest pipe-B: SUCCESS (1.516s) [588.401375] Starting dynamic subtest: pipe-C [589.993133] Dynamic subtest pipe-C: SUCCESS (1.602s) [590.003723] Starting dynamic subtest: pipe-D [591.591568] Dynamic subtest pipe-D: SUCCESS (1.597s) [591.593962] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (5.234s) [592.771187] [116/130] (501s left) gem_eio (hibernate) [593.006233] Starting subtest: hibernate [608.008525] Subtest hibernate: SUCCESS (15.003s) [608.139694] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 2466 [max: 8192] direct dependencies: 29740 [max: 524288] indirect dependencies: 256709 all direct dependencies: 646065 dependency chains: 46735 [max: 524288] dependency chain hlocks used: 207283 [max: 2621440] dependency chain hlocks lost: 0 in-hardirq chains: 436 in-softirq chains: 1010 in-process chains: 45289 stack-trace entries: 302478 [max: 524288] number of stack traces: 13873 number of stack hash chains: 9326 combined max dependencies: 2829569846 hardirq-safe locks: 141 hardirq-unsafe locks: 1385 softirq-safe locks: 280 softirq-unsafe locks: 1278 irq-safe locks: 304 irq-unsafe locks: 1385 hardirq-read-safe locks: 5 hardirq-read-unsafe locks: 526 softirq-read-safe locks: 10 softirq-read-unsafe locks: 521 irq-read-safe locks: 10 irq-read-unsafe locks: 526 uncategorized locks: 388 unused locks: 1 max locking depth: 17 max bfs queue depth: 440 max lock class index: 2465 debug_locks: 0 zapped classes: 3 zapped lock chains: 166 large chain blocks: 1 [608.151175] Closing watchdogs Done.