[87.955571] Initializing watchdogs [87.955619] /dev/watchdog0 [87.968947] [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] [87.973064] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [87.975471] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [87.978825] [001/130] (960s left) syncobj_wait (invalid-reset-zero-handles) [88.105192] Starting subtest: invalid-reset-zero-handles [88.107377] Subtest invalid-reset-zero-handles: SUCCESS (0.000s) [88.198787] [002/130] (960s left) kms_atomic_interruptible (legacy-pageflip) [88.307749] Starting subtest: legacy-pageflip [88.310182] Starting dynamic subtest: pipe-A-eDP-1 [95.750827] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.443s) [95.753049] Subtest legacy-pageflip: SUCCESS (7.443s) [96.586732] [003/130] (951s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling) [96.689991] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling [96.692398] Starting dynamic subtest: pipe-A-default-mode [96.979066] Dynamic subtest pipe-A-default-mode: SKIP (0.286s) [96.983657] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.287s) [97.805601] [004/130] (950s left) kms_fbcon_fbt (psr-suspend) [97.868441] Starting subtest: psr-suspend [144.843432] Subtest psr-suspend: SUCCESS (17.882s) [144.920805] [005/130] (932s left) gem_ctx_persistence (legacy-engines-hostile-preempt) [144.986090] Starting subtest: legacy-engines-hostile-preempt [144.988453] Starting dynamic subtest: render [145.078699] Dynamic subtest render: SUCCESS (0.092s) [145.090280] Starting dynamic subtest: bsd1 [147.007416] Dynamic subtest bsd1: SUCCESS (1.926s) [147.017164] Starting dynamic subtest: bsd2 [148.990350] Dynamic subtest bsd2: SUCCESS (1.982s) [149.000531] Starting dynamic subtest: blt [149.084483] Dynamic subtest blt: SUCCESS (0.095s) [149.092974] Starting dynamic subtest: vebox [151.011721] Dynamic subtest vebox: SUCCESS (1.925s) [151.014411] Subtest legacy-engines-hostile-preempt: SUCCESS (6.025s) [151.091310] [006/130] (926s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs) [151.155894] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs [151.160089] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s) [151.329679] [007/130] (926s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-gtt) [151.436896] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-gtt [151.441056] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [151.539785] [008/130] (926s left) gem_userptr_blits (stress-mm-invalidate-close) [151.600439] Starting subtest: stress-mm-invalidate-close [153.752623] Subtest stress-mm-invalidate-close: SUCCESS (2.149s) [153.871266] [009/130] (923s left) gem_exec_balancer (parallel) [154.116519] Starting subtest: parallel [154.897592] Subtest parallel: SUCCESS (0.781s) [156.080026] [010/130] (921s left) kms_plane (plane-position-covered) [156.175386] Starting subtest: plane-position-covered [156.705321] Starting dynamic subtest: pipe-A-plane-1 [157.953008] Dynamic subtest pipe-A-plane-1: SUCCESS (1.248s) [157.961760] Starting dynamic subtest: pipe-A-plane-2 [159.191698] Dynamic subtest pipe-A-plane-2: SUCCESS (1.237s) [159.200536] Starting dynamic subtest: pipe-A-plane-3 [160.429290] Dynamic subtest pipe-A-plane-3: SUCCESS (1.239s) [160.438161] Starting dynamic subtest: pipe-A-plane-4 [161.641379] Dynamic subtest pipe-A-plane-4: SUCCESS (1.212s) [161.650350] Starting dynamic subtest: pipe-A-plane-5 [161.681170] Dynamic subtest pipe-A-plane-5: SUCCESS (0.038s) [162.797207] Starting dynamic subtest: pipe-B-plane-1 [163.998412] Dynamic subtest pipe-B-plane-1: SUCCESS (1.209s) [164.008456] Starting dynamic subtest: pipe-B-plane-2 [165.251410] Dynamic subtest pipe-B-plane-2: SUCCESS (1.251s) [165.260152] Starting dynamic subtest: pipe-B-plane-3 [166.456268] Dynamic subtest pipe-B-plane-3: SUCCESS (1.207s) [166.465875] Starting dynamic subtest: pipe-B-plane-4 [167.684606] Dynamic subtest pipe-B-plane-4: SUCCESS (1.228s) [167.693863] Starting dynamic subtest: pipe-B-plane-5 [167.724314] Dynamic subtest pipe-B-plane-5: SUCCESS (0.038s) [167.726452] Subtest plane-position-covered: SUCCESS (11.547s) [168.477950] [011/130] (909s left) kms_invalid_mode (uint-max-clock) [168.578840] Starting subtest: uint-max-clock [168.581137] Starting dynamic subtest: pipe-A-eDP-1 [168.859637] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.281s) [168.869412] Starting dynamic subtest: pipe-B-eDP-1 [168.871705] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [168.878725] Starting dynamic subtest: pipe-C-eDP-1 [168.881060] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [168.890576] Starting dynamic subtest: pipe-D-eDP-1 [168.892819] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [168.895019] Subtest uint-max-clock: SUCCESS (0.285s) [169.698996] [012/130] (908s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling) [169.805721] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling [169.808175] Starting dynamic subtest: pipe-A-default-mode [170.170853] Dynamic subtest pipe-A-default-mode: SKIP (0.362s) [170.175725] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.363s) [170.918925] [013/130] (907s left) kms_psr (psr-suspend) [171.021712] Starting subtest: psr-suspend [171.023918] Starting dynamic subtest: eDP-1 [188.323005] Dynamic subtest eDP-1: SUCCESS (2.894s) [188.325525] Subtest psr-suspend: SUCCESS (2.895s) [189.175500] [014/130] (903s left) gem_partial_pwrite_pread (writes-after-reads-snoop) [189.252547] Subtest writes-after-reads-snoop: SKIP (0.000s) [189.332903] [015/130] (903s left) kms_dp_link_training (uhbr-mst) [189.436341] Starting subtest: uhbr-mst [189.445445] Subtest uhbr-mst: SKIP (0.000s) [189.504693] [016/130] (902s left) kms_atomic_transition (plane-all-modeset-transition) [189.610488] Starting subtest: plane-all-modeset-transition [189.615286] Subtest plane-all-modeset-transition: SKIP (0.000s) [189.686397] [017/130] (902s left) kms_getfb (getfb2-handle-zero) [189.751106] Starting subtest: getfb2-handle-zero [189.753529] Subtest getfb2-handle-zero: SUCCESS (0.000s) [189.860591] [018/130] (902s left) perf_pmu (busy-double-start) [189.901957] Starting subtest: busy-double-start [189.904242] Starting dynamic subtest: rcs0 [193.539717] Dynamic subtest rcs0: FAIL (3.576s) [193.549528] Starting dynamic subtest: bcs0 [197.090415] Dynamic subtest bcs0: SUCCESS (3.611s) [197.099614] Starting dynamic subtest: vcs0 [200.923238] Dynamic subtest vcs0: SUCCESS (3.833s) [200.933132] Starting dynamic subtest: vcs1 [204.951359] Dynamic subtest vcs1: SUCCESS (4.027s) [204.959978] Starting dynamic subtest: vecs0 [208.980081] Dynamic subtest vecs0: FAIL (3.957s) [208.989545] Starting dynamic subtest: ccs0 [212.544239] Dynamic subtest ccs0: FAIL (3.563s) [212.546646] Subtest busy-double-start: FAIL (22.570s) [212.585585] [019/130] (879s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt) [212.694720] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt [217.852091] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (5.160s) [218.002490] [020/130] (874s left) kms_ccs (crc-primary-basic-y-tiled-ccs) [218.104253] Starting subtest: crc-primary-basic-y-tiled-ccs [218.106590] Starting dynamic subtest: pipe-A-eDP-1 [218.130912] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [218.140591] Starting dynamic subtest: pipe-B-eDP-1 [218.162495] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [218.170522] Starting dynamic subtest: pipe-C-eDP-1 [218.192332] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [218.200474] Starting dynamic subtest: pipe-D-eDP-1 [218.224626] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [218.228942] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.004s) [220.281801] [021/130] (872s left) kms_atomic_interruptible (atomic-setmode) [220.388136] Starting subtest: atomic-setmode [220.390524] Starting dynamic subtest: pipe-A-eDP-1 [227.266313] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.877s) [227.268947] Subtest atomic-setmode: SUCCESS (6.878s) [228.121871] [022/130] (864s left) kms_atomic (atomic-plane-damage) [228.223208] Starting subtest: atomic-plane-damage [228.301641] Starting dynamic subtest: pipe-A-eDP-1 [228.909034] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.606s) [228.911444] Subtest atomic-plane-damage: SUCCESS (0.684s) [229.752903] [023/130] (862s left) kms_psr (fbc-psr-cursor-render) [229.864116] Starting subtest: fbc-psr-cursor-render [229.866120] Starting dynamic subtest: eDP-1 [229.870377] Dynamic subtest eDP-1: SKIP (0.000s) [229.874621] Subtest fbc-psr-cursor-render: SKIP (0.001s) [229.927985] [024/130] (862s left) kms_properties (crtc-properties-atomic) [230.028232] Starting subtest: crtc-properties-atomic [230.030609] Starting dynamic subtest: pipe-A-eDP-1 [230.477235] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.447s) [230.486750] Starting dynamic subtest: pipe-B-eDP-1 [231.603830] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.126s) [231.613415] Starting dynamic subtest: pipe-C-eDP-1 [232.711657] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.106s) [232.721117] Starting dynamic subtest: pipe-D-eDP-1 [233.848961] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.137s) [233.851244] Subtest crtc-properties-atomic: SUCCESS (3.819s) [234.641840] [025/130] (858s left) kms_content_protection (mei-interface) [234.816519] Starting subtest: mei-interface [234.820999] Subtest mei-interface: SKIP (0.001s) [234.954676] [026/130] (857s left) kms_frontbuffer_tracking (fbc-suspend) [235.058858] Starting subtest: fbc-suspend [270.359297] Subtest fbc-suspend: SUCCESS (6.193s) [270.516596] [027/130] (851s left) gem_lmem_swapping (parallel-random-verify) [270.558451] Subtest parallel-random-verify: SKIP (0.000s) [270.597965] [028/130] (851s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc) [270.705131] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc [275.587326] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.885s) [275.707726] [029/130] (846s left) perf (non-system-wide-paranoid) [275.811680] Starting subtest: non-system-wide-paranoid [275.852675] Subtest non-system-wide-paranoid: SUCCESS (0.042s) [275.895387] [030/130] (846s left) kms_big_fb (x-tiled-addfb) [275.996488] Starting subtest: x-tiled-addfb [275.998705] Subtest x-tiled-addfb: SUCCESS (0.000s) [276.097639] [031/130] (845s left) gem_ringfill (engines-basic) [281.563623] Starting subtest: engines-basic [281.565747] Starting dynamic subtest: rcs0 [281.615586] Dynamic subtest rcs0: SUCCESS (0.070s) [281.627139] Starting dynamic subtest: bcs0 [281.659570] Dynamic subtest bcs0: SUCCESS (0.043s) [281.669358] Starting dynamic subtest: vcs0 [281.702671] Dynamic subtest vcs0: SUCCESS (0.043s) [281.711129] Starting dynamic subtest: vcs1 [281.744866] Dynamic subtest vcs1: SUCCESS (0.041s) [281.753953] Starting dynamic subtest: vecs0 [281.786385] Dynamic subtest vecs0: SUCCESS (0.040s) [281.797122] Starting dynamic subtest: ccs0 [281.831185] Dynamic subtest ccs0: SUCCESS (0.044s) [281.833595] Subtest engines-basic: SUCCESS (0.286s) [281.901383] [FACT gem_ringfill (engines-basic)] new: kernel.kmod_is_loaded.vgem: true [281.904545] [032/130] (840s left) i915_query (query-regions-sanity-check) [281.962344] Starting subtest: query-regions-sanity-check [281.973914] Subtest query-regions-sanity-check: SUCCESS (0.015s) [282.095189] [033/130] (840s left) gem_exec_whisper (basic-fds-priority-all) [282.169809] Starting subtest: basic-fds-priority-all [305.521559] Subtest basic-fds-priority-all: SUCCESS (23.345s) [305.699823] [034/130] (816s left) kms_atomic (test-only) [305.803872] Starting subtest: test-only [305.859390] Starting dynamic subtest: pipe-A-eDP-1 [308.340153] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.479s) [308.342651] Subtest test-only: SUCCESS (2.535s) [309.129926] [035/130] (813s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes) [309.186542] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s) [309.246237] [036/130] (813s left) kms_pm_dc (dc5-psr) [310.353128] Starting subtest: dc5-psr [313.322682] Subtest dc5-psr: SUCCESS (2.970s) [314.149793] [037/130] (808s left) gem_create (create-ext-cpu-access-big) [314.322735] Starting subtest: create-ext-cpu-access-big [314.327759] Subtest create-ext-cpu-access-big: SKIP (0.001s) [314.393843] [038/130] (807s left) kms_addfb_basic (framebuffer-vs-set-tiling) [314.454641] Starting subtest: framebuffer-vs-set-tiling [314.459508] Subtest framebuffer-vs-set-tiling: SKIP (0.000s) [314.578568] [039/130] (807s left) kms_plane_lowres (tiling-none) [314.670200] Starting subtest: tiling-none [314.672381] Starting dynamic subtest: pipe-A-eDP-1 [314.676984] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [314.978436] Starting dynamic subtest: pipe-B-eDP-1 [314.983160] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [314.991442] Starting dynamic subtest: pipe-C-eDP-1 [314.996119] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [315.003709] Starting dynamic subtest: pipe-D-eDP-1 [315.007859] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [315.012375] Subtest tiling-none: SKIP (0.303s) [315.781060] [040/130] (806s left) kms_sequence (get-busy) [315.869002] Starting subtest: get-busy [315.871384] Starting dynamic subtest: pipe-A-eDP-1 [317.806260] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.933s) [317.814970] Starting dynamic subtest: pipe-B-eDP-1 [320.381150] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.573s) [320.396603] Starting dynamic subtest: pipe-C-eDP-1 [322.981333] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.600s) [322.990345] Starting dynamic subtest: pipe-D-eDP-1 [325.517192] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.535s) [325.519556] Subtest get-busy: SUCCESS (9.647s) [326.301070] [041/130] (796s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip) [326.414438] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip [326.418651] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s) [326.521774] [042/130] (795s left) kms_vblank (wait-idle-hang) [326.613031] Starting subtest: wait-idle-hang [326.615338] Starting dynamic subtest: pipe-A-eDP-1 [353.281252] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.665s) [353.296574] Starting dynamic subtest: pipe-D-eDP-1 [378.314899] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.030s) [378.317352] Subtest wait-idle-hang: SUCCESS (51.699s) [379.146468] [043/130] (743s left) gem_mmap_offset (pf-nonblock) [379.204133] Starting subtest: pf-nonblock [379.209230] Subtest pf-nonblock: SUCCESS (0.008s) [379.320023] [044/130] (743s left) kms_big_fb (x-tiled-16bpp-rotate-0) [379.418286] Starting subtest: x-tiled-16bpp-rotate-0 [380.821286] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.402s) [380.981456] [045/130] (741s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size) [381.105584] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size [392.318617] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.210s) [393.174646] [046/130] (729s left) api_intel_bb (misplaced-blitter) [393.238633] Starting subtest: misplaced-blitter [393.245758] Subtest misplaced-blitter: SUCCESS (0.008s) [393.383530] [047/130] (729s left) gem_mmap_gtt (big-copy-xy) [393.448209] Subtest big-copy-XY: SKIP (0.000s) [393.504426] [048/130] (729s left) kms_setmode (clone-exclusive-crtc) [393.560490] Starting subtest: clone-exclusive-crtc [393.567232] Subtest clone-exclusive-crtc: SKIP (0.000s) [393.665845] [049/130] (729s left) perf_pmu (module-unload) [393.749376] Starting subtest: module-unload [440.033704] Subtest module-unload: SUCCESS (46.281s) [440.063073] [FACT perf_pmu (module-unload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [440.063807] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true [440.066970] [050/130] (682s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move) [441.676230] Starting subtest: fbc-2p-primscrn-cur-indfb-move [441.680808] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s) [441.743034] [FACT kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [441.744771] [FACT kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)] new: kernel.kmod_is_loaded.i915: true [441.745351] [FACT kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)] new: kernel.kmod_is_loaded.xe: true [441.748805] [051/130] (681s left) syncobj_timeline (reset-signaled) [441.813350] Starting subtest: reset-signaled [441.815704] Subtest reset-signaled: SUCCESS (0.000s) [441.910176] [052/130] (680s left) kms_lease (lease-revoke) [442.008126] Starting subtest: lease-revoke [442.010261] Starting dynamic subtest: pipe-A-eDP-1 [442.070716] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.064s) [442.079224] Starting dynamic subtest: pipe-B-eDP-1 [442.115129] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.043s) [442.124043] Starting dynamic subtest: pipe-C-eDP-1 [442.167892] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.052s) [442.178981] Starting dynamic subtest: pipe-D-eDP-1 [442.216220] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.047s) [442.218702] Subtest lease-revoke: SUCCESS (0.211s) [442.342021] [053/130] (680s left) gem_exec_reloc (basic-gtt-cpu-active) [442.395756] Subtest basic-gtt-cpu-active: SKIP (0.000s) [442.470036] [054/130] (680s left) gem_ctx_engines (execute-oneforall) [442.541277] Starting subtest: execute-oneforall [442.984041] Subtest execute-oneforall: SUCCESS (0.443s) [444.671266] [055/130] (678s left) gem_exec_suspend (basic-s4-devices) [444.737638] Starting subtest: basic-S4-devices [444.740073] Starting dynamic subtest: smem [456.520248] Dynamic subtest smem: SUCCESS (11.780s) [456.522962] Subtest basic-S4-devices: SUCCESS (11.781s) [456.682942] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 2393 [max: 8192] direct dependencies: 28306 [max: 524288] indirect dependencies: 222888 all direct dependencies: 611510 dependency chains: 44170 [max: 524288] dependency chain hlocks used: 197443 [max: 2621440] dependency chain hlocks lost: 0 in-hardirq chains: 347 in-softirq chains: 946 in-process chains: 42877 stack-trace entries: 338991 [max: 524288] number of stack traces: 15000 number of stack hash chains: 9790 combined max dependencies: 1245800280 hardirq-safe locks: 132 hardirq-unsafe locks: 1382 softirq-safe locks: 267 softirq-unsafe locks: 1276 irq-safe locks: 290 irq-unsafe locks: 1382 hardirq-read-safe locks: 5 hardirq-read-unsafe locks: 459 softirq-read-safe locks: 9 softirq-read-unsafe locks: 454 irq-read-safe locks: 9 irq-read-unsafe locks: 459 uncategorized locks: 399 unused locks: 1 max locking depth: 18 max bfs queue depth: 443 max lock class index: 2405 debug_locks: 0 zapped classes: 594 zapped lock chains: 16992 large chain blocks: 1 [456.696634] [FACT gem_exec_suspend (basic-s4-devices)] new: kernel.is_tainted.taint_warn: true [456.697392] Closing watchdogs Done.