[36.806755] Initializing watchdogs [36.806807] /dev/watchdog0 [36.812234] [001/140] (960s left) gem_ctx_bad_destroy (invalid-pad) [36.859528] Starting subtest: invalid-pad [36.860381] Subtest invalid-pad: SUCCESS (0.000s) [36.914077] [002/140] (960s left) fbdev (write) [36.983396] Starting subtest: write [38.530963] Subtest write: SUCCESS (1.548s) [38.579760] [003/140] (958s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render) [38.699438] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render [41.250536] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (2.551s) [41.364563] [004/140] (955s left) v3d/v3d_submit_cl (single-in-sync) [41.449812] Subtest single-in-sync: SKIP (0.000s) [41.499444] [005/140] (955s left) kms_writeback (writeback-pixel-formats) [41.692459] Subtest writeback-pixel-formats: SKIP (0.000s) [41.722637] [006/140] (955s left) kms_pm_lpsp (screens-disabled) [42.843381] Starting subtest: screens-disabled [42.845161] Subtest screens-disabled: SKIP (0.000s) [43.000717] [007/140] (954s left) gem_userptr_blits (readonly-unsync) [43.079334] Subtest readonly-unsync: SKIP (0.000s) [43.218212] [008/140] (954s left) syncobj_timeline (etime-single-wait-submitted) [43.303967] Starting subtest: etime-single-wait-submitted [43.409816] Subtest etime-single-wait-submitted: SUCCESS (0.105s) [43.470731] [009/140] (954s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c) [43.516010] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s) [43.539540] [010/140] (953s left) gem_ctx_param (basic) [43.583360] Starting subtest: basic [43.584275] Subtest basic: SUCCESS (0.000s) [43.651045] [011/140] (953s left) kms_rotation_crc (multiplane-rotation-cropping-top) [43.777433] Starting subtest: multiplane-rotation-cropping-top [55.304288] Subtest multiplane-rotation-cropping-top: SUCCESS (11.526s) [55.452374] [012/140] (942s left) kms_ccs (pipe-d-crc-primary-basic-y-tiled-ccs) [55.755947] Starting subtest: pipe-D-crc-primary-basic-y-tiled-ccs [55.757795] Subtest pipe-D-crc-primary-basic-y-tiled-ccs: SKIP (0.000s) [55.918877] [013/140] (941s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-gtt) [56.067162] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-gtt [56.075895] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [56.146658] [014/140] (941s left) kms_atomic_transition (plane-toggle-modeset-transition) [56.294284] Starting subtest: plane-toggle-modeset-transition [56.295204] Starting dynamic subtest: pipe-A-DP-4 [58.924427] Dynamic subtest pipe-A-DP-4: SUCCESS (2.629s) [58.930599] Starting dynamic subtest: pipe-B-DP-4 [61.182811] Dynamic subtest pipe-B-DP-4: SUCCESS (2.255s) [61.183678] Subtest plane-toggle-modeset-transition: SUCCESS (4.888s) [61.364267] [015/140] (936s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc) [61.519596] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc [61.521397] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [61.606754] [016/140] (936s left) kms_ccs (pipe-b-ccs-on-another-bo-4-tiled-mtl-rc-ccs) [61.860821] Starting subtest: pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs [61.862663] Subtest pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs: SKIP (0.000s) [62.054638] [017/140] (935s left) vc4/vc4_perfmon (get-values-invalid-pointer) [62.129568] Subtest get-values-invalid-pointer: SKIP (0.000s) [62.180903] [018/140] (935s left) kms_flip (absolute-wf_vblank) [62.326258] Starting subtest: absolute-wf_vblank [62.332290] Starting dynamic subtest: A-DP4 [70.223069] Dynamic subtest A-DP4: SUCCESS (7.891s) [70.229431] Starting dynamic subtest: B-DP4 [77.983186] Dynamic subtest B-DP4: SUCCESS (7.759s) [77.988773] Starting dynamic subtest: C-DP4 [85.740969] Dynamic subtest C-DP4: SUCCESS (7.757s) [85.747540] Starting dynamic subtest: D-DP4 [93.498248] Dynamic subtest D-DP4: SUCCESS (7.757s) [93.499098] Subtest absolute-wf_vblank: SUCCESS (31.173s) [93.791615] [019/140] (903s left) gem_wait (write-busy) [93.883161] Starting subtest: write-busy [93.884159] Starting dynamic subtest: all-engines [94.475740] Dynamic subtest all-engines: SUCCESS (0.592s) [94.481669] Starting dynamic subtest: rcs0 [95.016347] Dynamic subtest rcs0: SUCCESS (0.540s) [95.022388] Starting dynamic subtest: bcs0 [95.556398] Dynamic subtest bcs0: SUCCESS (0.540s) [95.562476] Starting dynamic subtest: vcs0 [96.097183] Dynamic subtest vcs0: SUCCESS (0.540s) [96.103098] Starting dynamic subtest: vcs1 [96.638289] Dynamic subtest vcs1: SUCCESS (0.541s) [96.644407] Starting dynamic subtest: vecs0 [97.179414] Dynamic subtest vecs0: SUCCESS (0.541s) [97.185397] Starting dynamic subtest: vecs1 [97.719243] Dynamic subtest vecs1: SUCCESS (0.540s) [97.725264] Starting dynamic subtest: ccs0 [98.259284] Dynamic subtest ccs0: SUCCESS (0.539s) [98.265291] Starting dynamic subtest: ccs1 [98.799115] Dynamic subtest ccs1: SUCCESS (0.539s) [98.805125] Starting dynamic subtest: ccs2 [99.338403] Dynamic subtest ccs2: SUCCESS (0.539s) [99.344297] Starting dynamic subtest: ccs3 [99.878385] Dynamic subtest ccs3: SUCCESS (0.539s) [99.879294] Subtest write-busy: SUCCESS (5.995s) [100.115803] [020/140] (897s left) kms_prop_blob (invalid-get-prop-any) [100.159071] Starting subtest: invalid-get-prop-any [100.159933] Subtest invalid-get-prop-any: SUCCESS (0.000s) [100.215561] [021/140] (897s left) gem_softpin (safe-alignment) [100.306588] Starting subtest: safe-alignment [100.363953] Subtest safe-alignment: SUCCESS (0.056s) [100.498231] [022/140] (897s left) kms_flip (blocking-wf_vblank) [100.655121] Starting subtest: blocking-wf_vblank [100.660735] Starting dynamic subtest: A-DP4 [108.779233] Dynamic subtest A-DP4: SUCCESS (8.118s) [108.785715] Starting dynamic subtest: B-DP4 [116.809309] Dynamic subtest B-DP4: SUCCESS (8.029s) [116.815522] Starting dynamic subtest: C-DP4 [124.852846] Dynamic subtest C-DP4: SUCCESS (8.043s) [124.859152] Starting dynamic subtest: D-DP4 [132.907123] Dynamic subtest D-DP4: SUCCESS (8.054s) [132.908107] Subtest blocking-wf_vblank: SUCCESS (32.253s) [133.185056] [023/140] (864s left) syncobj_wait (wait-all-for-submit-complex) [133.242531] Starting subtest: wait-all-for-submit-complex [133.462136] Subtest wait-all-for-submit-complex: SUCCESS (0.218s) [133.538160] [024/140] (864s left) kms_hdr (invalid-metadata-sizes) [133.655051] Starting subtest: invalid-metadata-sizes [137.109568] Starting dynamic subtest: pipe-A-DP-4 [137.110521] Dynamic subtest pipe-A-DP-4: SUCCESS (0.001s) [137.111480] Subtest invalid-metadata-sizes: SUCCESS (3.456s) [137.343169] [025/140] (860s left) kms_flip (flip-vs-suspend-interruptible) [137.472208] Starting subtest: flip-vs-suspend-interruptible [137.478386] Starting dynamic subtest: A-DP4 [176.842183] Dynamic subtest A-DP4: SUCCESS (7.690s) [176.850219] Starting dynamic subtest: B-DP4 [216.795757] Dynamic subtest B-DP4: SUCCESS (7.071s) [216.812924] Starting dynamic subtest: C-DP4 [256.785970] Dynamic subtest C-DP4: SUCCESS (7.118s) [256.794113] Starting dynamic subtest: D-DP4 [296.771240] Dynamic subtest D-DP4: SUCCESS (7.167s) [296.772020] Subtest flip-vs-suspend-interruptible: SUCCESS (29.055s) [296.995265] [026/140] (831s left) kms_cursor_legacy (cursor-vs-flip-varying-size) [297.121507] Starting subtest: cursor-vs-flip-varying-size [326.518880] Subtest cursor-vs-flip-varying-size: SUCCESS (29.395s) [326.720453] [027/140] (801s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling) [326.890406] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling [326.891212] Starting dynamic subtest: pipe-A-valid-mode [328.634010] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.742s) [328.635277] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (1.743s) [328.857595] [028/140] (799s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs) [329.152271] Starting subtest: pipe-C-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs [339.895826] Subtest pipe-C-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SUCCESS (10.743s) [340.196015] [029/140] (788s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt) [340.338381] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt [340.339937] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s) [340.424062] [030/140] (787s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite) [340.560735] Starting subtest: fbc-rgb101010-draw-pwrite [343.403798] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (2.844s) [343.505079] [031/140] (784s left) gem_exec_reloc (basic-write-gtt) [343.554423] Subtest basic-write-gtt: SKIP (0.000s) [343.595333] [032/140] (784s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [343.723878] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip [343.725820] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s) [343.788388] [033/140] (784s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear) [343.913396] Starting subtest: fbcpsr-tiling-linear [343.915095] Subtest fbcpsr-tiling-linear: SKIP (0.000s) [343.992092] [034/140] (784s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff) [344.131992] Starting subtest: psr-2p-primscrn-spr-indfb-onoff [344.133770] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s) [344.205047] [035/140] (784s left) kms_big_fb (y-tiled-64bpp-rotate-0) [344.335972] Starting subtest: y-tiled-64bpp-rotate-0 [344.337757] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s) [344.407501] [036/140] (783s left) gem_exec_create (basic) [344.471356] Starting subtest: basic [344.472292] Starting dynamic subtest: smem [346.567918] Dynamic subtest smem: SUCCESS (2.096s) [346.575529] Starting dynamic subtest: lmem0 [348.668351] Dynamic subtest lmem0: SUCCESS (2.100s) [348.669290] Subtest basic: SUCCESS (4.197s) [348.851005] [037/140] (779s left) kms_lease (lease-get) [349.000586] Starting subtest: lease-get [349.002692] Starting dynamic subtest: pipe-A-DP-4 [349.003586] Dynamic subtest pipe-A-DP-4: SUCCESS (0.001s) [349.011702] Starting dynamic subtest: pipe-B-DP-4 [349.012519] Dynamic subtest pipe-B-DP-4: SUCCESS (0.001s) [349.020012] Starting dynamic subtest: pipe-C-DP-4 [349.021141] Dynamic subtest pipe-C-DP-4: SUCCESS (0.001s) [349.029054] Starting dynamic subtest: pipe-D-DP-4 [349.030523] Dynamic subtest pipe-D-DP-4: SUCCESS (0.001s) [349.031608] Subtest lease-get: SUCCESS (0.009s) [349.070394] [038/140] (779s left) kms_ccs (pipe-b-missing-ccs-buffer-4-tiled-mtl-mc-ccs) [349.294319] Starting subtest: pipe-B-missing-ccs-buffer-4-tiled-mtl-mc-ccs [349.295995] Subtest pipe-B-missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.000s) [349.511648] [039/140] (778s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled) [349.590933] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled [349.592748] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s) [349.787417] [040/140] (778s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size) [349.909517] Starting subtest: basic-busy-flip-before-cursor-varying-size [349.911532] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s) [349.967717] [041/140] (778s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-25) [350.083559] Starting subtest: planes-unity-scaling-downscale-factor-0-25 [350.095542] Starting dynamic subtest: pipe-A-DP-4 [350.110277] Dynamic subtest pipe-A-DP-4: SKIP (0.013s) [350.128206] Starting dynamic subtest: pipe-B-DP-4 [350.150153] Dynamic subtest pipe-B-DP-4: SKIP (0.027s) [350.167404] Starting dynamic subtest: pipe-C-DP-4 [350.188715] Dynamic subtest pipe-C-DP-4: SKIP (0.027s) [350.207470] Starting dynamic subtest: pipe-D-DP-4 [350.228910] Dynamic subtest pipe-D-DP-4: SKIP (0.027s) [350.230340] Subtest planes-unity-scaling-downscale-factor-0-25: SKIP (0.145s) [350.321890] [042/140] (778s left) v3d/v3d_wait_bo (bad-pad) [350.402001] Subtest bad-pad: SKIP (0.000s) [350.462008] [043/140] (778s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [350.607727] Starting subtest: basic-flip-after-cursor-legacy [351.626220] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.019s) [351.859127] [044/140] (776s left) kms_ccs (pipe-c-bad-rotation-90-4-tiled-mtl-rc-ccs-cc) [352.145854] Starting subtest: pipe-C-bad-rotation-90-4-tiled-mtl-rc-ccs-cc [352.147642] Subtest pipe-C-bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s) [352.347209] [045/140] (776s left) kms_vblank (query-forked-hang) [352.485955] Starting subtest: query-forked-hang [352.488148] Starting dynamic subtest: pipe-A-DP-4 [366.316024] Dynamic subtest pipe-A-DP-4: SUCCESS (13.828s) [366.324751] Starting dynamic subtest: pipe-D-DP-4 [391.261112] Dynamic subtest pipe-D-DP-4: SUCCESS (24.943s) [391.262079] Subtest query-forked-hang: SUCCESS (38.775s) [391.463101] [046/140] (737s left) kms_big_fb (4-tiled-addfb-size-overflow) [391.628221] Starting subtest: 4-tiled-addfb-size-overflow [391.629213] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.001s) [391.727936] [047/140] (736s left) v3d/v3d_job_submission (array-job-submission) [391.820002] Subtest array-job-submission: SKIP (0.000s) [391.906888] [048/140] (736s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff) [392.059438] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff [394.803986] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (2.745s) [395.001224] [049/140] (733s left) kms_pipe_crc_basic (suspend-read-crc) [395.121980] Starting subtest: suspend-read-crc [395.124109] Starting dynamic subtest: pipe-A-DP-4 [416.404733] Dynamic subtest pipe-A-DP-4: SUCCESS (4.839s) [416.418553] Starting dynamic subtest: pipe-B-DP-4 [437.377775] Dynamic subtest pipe-B-DP-4: SUCCESS (4.556s) [437.394330] Starting dynamic subtest: pipe-C-DP-4 [458.361265] Dynamic subtest pipe-C-DP-4: SUCCESS (4.487s) [458.376532] Starting dynamic subtest: pipe-D-DP-4 [479.394966] Dynamic subtest pipe-D-DP-4: SUCCESS (4.567s) [479.396004] Subtest suspend-read-crc: SUCCESS (18.459s) [479.606425] [050/140] (715s left) kms_ccs (pipe-d-random-ccs-data-y-tiled-gen12-rc-ccs-cc) [479.893661] Starting subtest: pipe-D-random-ccs-data-y-tiled-gen12-rc-ccs-cc [479.896340] Subtest pipe-D-random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [480.067186] [051/140] (714s left) kms_content_protection (lic) [480.281313] Starting subtest: lic [480.314816] Starting dynamic subtest: pipe-A-DP-4 [600.363827] Per-test timeout exceeded. Killing the current test with SIGQUIT. [600.690408] Closing watchdogs [600.691948] Initializing watchdogs [600.691992] /dev/watchdog0 [600.702046] [052/140] (593s left) kms_big_fb (yf-tiled-32bpp-rotate-270) [600.823757] Starting subtest: yf-tiled-32bpp-rotate-270 [600.825691] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s) [600.900507] [053/140] (593s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc) [601.022389] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc [601.024106] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [601.100725] [054/140] (593s left) kms_cursor_crc (cursor-random-512x170) [601.405437] Starting subtest: cursor-random-512x170 [601.408539] Subtest cursor-random-512x170: SKIP (0.001s) [601.536122] [055/140] (593s left) gem_exec_schedule (preempt-other-chain) [601.627182] Starting subtest: preempt-other-chain [601.628009] Starting dynamic subtest: rcs0 [602.964821] Dynamic subtest rcs0: SUCCESS (1.337s) [602.973285] Starting dynamic subtest: bcs0 [604.296947] Dynamic subtest bcs0: SUCCESS (1.331s) [604.305443] Starting dynamic subtest: vcs0 [606.909795] Dynamic subtest vcs0: SUCCESS (2.612s) [606.919378] Starting dynamic subtest: vcs1 [609.146706] Dynamic subtest vcs1: SUCCESS (2.236s) [609.155031] Starting dynamic subtest: vecs0 [610.569827] Dynamic subtest vecs0: SUCCESS (1.423s) [610.578131] Starting dynamic subtest: vecs1 [611.891060] Dynamic subtest vecs1: SUCCESS (1.321s) [611.899785] Starting dynamic subtest: ccs0 [613.195542] Dynamic subtest ccs0: SUCCESS (1.304s) [613.203849] Starting dynamic subtest: ccs1 [615.728099] Dynamic subtest ccs1: SUCCESS (2.532s) [615.736865] Starting dynamic subtest: ccs2 [617.740614] Dynamic subtest ccs2: SUCCESS (2.012s) [617.748987] Starting dynamic subtest: ccs3 [619.060978] Dynamic subtest ccs3: SUCCESS (1.320s) [619.061784] Subtest preempt-other-chain: SUCCESS (17.434s) [619.419041] [056/140] (575s left) api_intel_bb (object-reloc-purge-cache) [619.464085] Starting subtest: object-reloc-purge-cache [619.465935] Subtest object-reloc-purge-cache: SKIP (0.002s) [619.572859] [057/140] (575s left) perf_pmu (busy-start) [619.638092] Starting subtest: busy-start [619.638966] Starting dynamic subtest: rcs0 [622.207174] Dynamic subtest rcs0: SUCCESS (2.568s) [622.221537] Starting dynamic subtest: bcs0 [624.752382] Dynamic subtest bcs0: SUCCESS (2.542s) [624.765589] Starting dynamic subtest: vcs0 [627.301389] Dynamic subtest vcs0: SUCCESS (2.546s) [627.314969] Starting dynamic subtest: vcs1 [629.849452] Dynamic subtest vcs1: SUCCESS (2.545s) [629.863618] Starting dynamic subtest: vecs0 [632.398465] Dynamic subtest vecs0: SUCCESS (2.546s) [632.412082] Starting dynamic subtest: vecs1 [634.947638] Dynamic subtest vecs1: SUCCESS (2.546s) [634.961595] Starting dynamic subtest: ccs0 [637.497480] Dynamic subtest ccs0: SUCCESS (2.547s) [637.510467] Starting dynamic subtest: ccs1 [640.046393] Dynamic subtest ccs1: SUCCESS (2.546s) [640.060527] Starting dynamic subtest: ccs2 [642.595372] Dynamic subtest ccs2: SUCCESS (2.546s) [642.609358] Starting dynamic subtest: ccs3 [645.142020] Dynamic subtest ccs3: SUCCESS (2.545s) [645.143269] Subtest busy-start: SUCCESS (25.504s) [645.210709] [058/140] (549s left) kms_cursor_legacy (flip-vs-cursor-toggle) [645.327378] Starting subtest: flip-vs-cursor-toggle [653.893892] Subtest flip-vs-cursor-toggle: SUCCESS (8.566s) [654.086532] [059/140] (540s left) kms_ccs (pipe-a-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc) [654.298917] Starting subtest: pipe-A-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc [654.300709] Subtest pipe-A-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [654.536404] [060/140] (540s left) kms_psr (pr_sprite_plane_onoff) [654.693676] Subtest pr_sprite_plane_onoff: SKIP (0.000s) [654.717858] [061/140] (540s left) kms_chamelium_hpd (common-hpd-after-suspend) [654.917333] Subtest common-hpd-after-suspend: SKIP (0.000s) [655.038859] [062/140] (539s left) gem_partial_pwrite_pread (write-uncached) [655.106400] Subtest write-uncached: SKIP (0.000s) [655.149080] [063/140] (539s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions) [655.273565] Starting subtest: cursorA-vs-flipA-atomic-transitions [666.227450] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (10.954s) [666.438503] [064/140] (528s left) kms_psr (psr_cursor_mmap_cpu) [666.588125] Subtest psr_cursor_mmap_cpu: SKIP (0.000s) [666.607881] [065/140] (528s left) kms_dither (fb-8bpc-vs-panel-8bpc) [666.723731] Starting subtest: fb-8bpc-vs-panel-8bpc [666.724632] Starting dynamic subtest: pipe-A-DP-4 [667.117124] Dynamic subtest pipe-A-DP-4: SUCCESS (0.390s) [667.118899] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (0.393s) [667.323903] [066/140] (527s left) kms_ccs (pipe-a-bad-rotation-90-4-tiled-mtl-rc-ccs) [667.553354] Starting subtest: pipe-A-bad-rotation-90-4-tiled-mtl-rc-ccs [667.555317] Subtest pipe-A-bad-rotation-90-4-tiled-mtl-rc-ccs: SKIP (0.001s) [667.803385] [067/140] (527s left) kms_ccs (pipe-a-bad-aux-stride-y-tiled-gen12-rc-ccs-cc) [668.074177] Starting subtest: pipe-A-bad-aux-stride-y-tiled-gen12-rc-ccs-cc [668.076127] Subtest pipe-A-bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [668.308486] [068/140] (526s left) gen7_exec_parse (chained-batch) [668.385826] Subtest chained-batch: SKIP (0.000s) [668.408567] [069/140] (526s left) gem_ctx_persistence (legacy-engines-mixed) [668.457902] Starting subtest: legacy-engines-mixed [668.458756] Starting dynamic subtest: render [668.515543] Dynamic subtest render: SUCCESS (0.057s) [668.529397] Starting dynamic subtest: bsd1 [668.557630] Dynamic subtest bsd1: SUCCESS (0.041s) [668.577019] Starting dynamic subtest: bsd2 [668.601830] Dynamic subtest bsd2: SUCCESS (0.042s) [668.628561] Starting dynamic subtest: blt [668.651732] Dynamic subtest blt: SUCCESS (0.048s) [668.673233] Starting dynamic subtest: vebox [668.705045] Dynamic subtest vebox: SUCCESS (0.051s) [668.706408] Subtest legacy-engines-mixed: SUCCESS (0.248s) [668.850271] [070/140] (526s left) kms_ccs (pipe-d-ccs-on-another-bo-y-tiled-ccs) [669.121699] Starting subtest: pipe-D-ccs-on-another-bo-y-tiled-ccs [669.123500] Subtest pipe-D-ccs-on-another-bo-y-tiled-ccs: SKIP (0.000s) [669.294825] [071/140] (525s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite) [669.481946] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite [669.483849] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [669.564429] [072/140] (525s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu) [669.695751] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu [669.697679] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [669.781362] [073/140] (525s left) gem_ctx_persistence (engines-hostile) [669.847188] Starting subtest: engines-hostile [669.848206] Starting dynamic subtest: rcs0 [669.981847] Dynamic subtest rcs0: SUCCESS (0.133s) [670.023515] Starting dynamic subtest: bcs0 [670.029616] Dynamic subtest bcs0: SUCCESS (0.045s) [670.046513] Starting dynamic subtest: vcs0 [670.115115] Dynamic subtest vcs0: SUCCESS (0.084s) [670.151521] Starting dynamic subtest: vcs1 [670.154893] Dynamic subtest vcs1: SUCCESS (0.037s) [670.170042] Starting dynamic subtest: vecs0 [670.188352] Dynamic subtest vecs0: SUCCESS (0.033s) [670.199315] Starting dynamic subtest: vecs1 [670.267535] Dynamic subtest vecs1: SUCCESS (0.077s) [670.308206] Starting dynamic subtest: ccs0 [670.309513] Dynamic subtest ccs0: SUCCESS (0.036s) [670.324991] Starting dynamic subtest: ccs1 [670.374628] Dynamic subtest ccs1: SUCCESS (0.067s) [670.391247] Starting dynamic subtest: ccs2 [670.461576] Dynamic subtest ccs2: SUCCESS (0.085s) [670.500272] Starting dynamic subtest: ccs3 [670.510696] Dynamic subtest ccs3: SUCCESS (0.047s) [670.511847] Subtest engines-hostile: SUCCESS (0.664s) [670.656208] [074/140] (524s left) gem_exercise_blt (fast-copy) [670.709660] Starting subtest: fast-copy [670.710469] Starting dynamic subtest: linear-smem-smem [670.744866] Dynamic subtest linear-smem-smem: SUCCESS (0.035s) [670.753726] Starting dynamic subtest: linear-smem-lmem0 [670.758376] Dynamic subtest linear-smem-lmem0: SUCCESS (0.013s) [670.767187] Starting dynamic subtest: linear-lmem0-smem [670.847703] Dynamic subtest linear-lmem0-smem: SUCCESS (0.088s) [670.856417] Starting dynamic subtest: linear-lmem0-lmem0 [670.934186] Dynamic subtest linear-lmem0-lmem0: SUCCESS (0.086s) [670.942865] Starting dynamic subtest: xmajor-smem-smem [670.949109] Dynamic subtest xmajor-smem-smem: SUCCESS (0.014s) [670.957701] Starting dynamic subtest: xmajor-smem-lmem0 [670.962686] Dynamic subtest xmajor-smem-lmem0: SUCCESS (0.013s) [670.971372] Starting dynamic subtest: xmajor-lmem0-smem [671.059590] Dynamic subtest xmajor-lmem0-smem: SUCCESS (0.096s) [671.068222] Starting dynamic subtest: xmajor-lmem0-lmem0 [671.150829] Dynamic subtest xmajor-lmem0-lmem0: SUCCESS (0.090s) [671.159432] Starting dynamic subtest: tile4-smem-smem [671.164891] Dynamic subtest tile4-smem-smem: SUCCESS (0.013s) [671.173728] Starting dynamic subtest: tile4-smem-lmem0 [671.178626] Dynamic subtest tile4-smem-lmem0: SUCCESS (0.013s) [671.188214] Starting dynamic subtest: tile4-lmem0-smem [671.278885] Dynamic subtest tile4-lmem0-smem: SUCCESS (0.099s) [671.287381] Starting dynamic subtest: tile4-lmem0-lmem0 [671.373001] Dynamic subtest tile4-lmem0-lmem0: SUCCESS (0.093s) [671.381554] Starting dynamic subtest: tile64-smem-smem [671.387995] Dynamic subtest tile64-smem-smem: SUCCESS (0.014s) [671.396521] Starting dynamic subtest: tile64-smem-lmem0 [671.401519] Dynamic subtest tile64-smem-lmem0: SUCCESS (0.013s) [671.410035] Starting dynamic subtest: tile64-lmem0-smem [671.497622] Dynamic subtest tile64-lmem0-smem: SUCCESS (0.095s) [671.506068] Starting dynamic subtest: tile64-lmem0-lmem0 [671.603786] Dynamic subtest tile64-lmem0-lmem0: SUCCESS (0.105s) [671.604614] Subtest fast-copy: SUCCESS (0.894s) [671.716686] [075/140] (523s left) i915_pm_rpm (system-suspend) [673.547921] Starting subtest: system-suspend [694.492146] Subtest system-suspend: SUCCESS (4.923s) [694.832808] [076/140] (516s left) syncobj_timeline (wait-all-delayed-signal) [694.901343] Starting subtest: wait-all-delayed-signal [695.003480] Subtest wait-all-delayed-signal: SUCCESS (0.102s) [695.075747] [077/140] (516s left) kms_atomic (plane-cursor-legacy) [695.189695] Starting subtest: plane-cursor-legacy [695.192178] Starting dynamic subtest: pipe-A-DP-4 [695.709089] Dynamic subtest pipe-A-DP-4: SUCCESS (0.517s) [695.710097] Subtest plane-cursor-legacy: SUCCESS (0.519s) [695.968528] [078/140] (515s left) api_intel_allocator (open-vm) [696.021651] Starting subtest: open-vm [696.037672] Subtest open-vm: SUCCESS (0.016s) [696.149353] [079/140] (515s left) gem_exec_fence (syncobj-wait) [696.900794] Starting subtest: syncobj-wait [697.029769] Subtest syncobj-wait: SUCCESS (0.129s) [697.400854] [080/140] (514s left) kms_cursor_crc (cursor-sliding-32x32) [697.578332] Starting subtest: cursor-sliding-32x32 [697.621780] Subtest cursor-sliding-32x32: SKIP (0.041s) [697.940829] [081/140] (513s left) perf (disabled-read-error) [698.276288] Starting subtest: disabled-read-error [698.304112] Subtest disabled-read-error: SUCCESS (0.029s) [698.329679] [082/140] (513s left) syncobj_wait (invalid-single-wait-all-unsubmitted) [698.375668] Starting subtest: invalid-single-wait-all-unsubmitted [698.376501] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s) [698.434577] [083/140] (513s left) kms_fence_pin_leak [698.508097] [084/140] (513s left) gem_exec_suspend (basic-s0) [698.560682] Starting subtest: basic-S0 [698.561709] Starting dynamic subtest: smem [719.850748] Dynamic subtest smem: SUCCESS (6.680s) [719.860256] Starting dynamic subtest: lmem0 [740.790562] Dynamic subtest lmem0: SUCCESS (6.491s) [740.791652] Subtest basic-S0: SUCCESS (13.173s) [740.964710] [085/140] (499s left) perf (invalid-remove-userspace-config) [741.119697] Starting subtest: invalid-remove-userspace-config [741.129505] Subtest invalid-remove-userspace-config: SUCCESS (0.010s) [741.162493] [086/140] (499s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc) [741.282585] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc [741.284325] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [741.371766] [087/140] (499s left) gem_userptr_blits (create-destroy-unsync) [741.436939] Subtest create-destroy-unsync: SKIP (0.000s) [741.594588] [088/140] (499s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt) [741.725917] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt [741.727652] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s) [741.825261] [089/140] (499s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted) [741.893636] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted [741.994016] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.101s) [742.112409] [090/140] (498s left) kms_flip (plain-flip-interruptible) [742.238136] Starting subtest: plain-flip-interruptible [742.244775] Starting dynamic subtest: A-DP4 [743.056026] Dynamic subtest A-DP4: SUCCESS (0.811s) [743.072721] Starting dynamic subtest: B-DP4 [743.789904] Dynamic subtest B-DP4: SUCCESS (0.733s) [743.801659] Starting dynamic subtest: C-DP4 [744.526470] Dynamic subtest C-DP4: SUCCESS (0.736s) [744.537067] Starting dynamic subtest: D-DP4 [745.261573] Dynamic subtest D-DP4: SUCCESS (0.734s) [745.262578] Subtest plain-flip-interruptible: SUCCESS (3.024s) [745.540360] [091/140] (495s left) kms_ccs (pipe-c-bad-aux-stride-yf-tiled-ccs) [745.795007] Starting subtest: pipe-C-bad-aux-stride-yf-tiled-ccs [745.796868] Subtest pipe-C-bad-aux-stride-yf-tiled-ccs: SKIP (0.000s) [745.994777] [092/140] (495s left) perf (low-oa-exponent-permissions) [746.150249] Starting subtest: low-oa-exponent-permissions [746.190267] Subtest low-oa-exponent-permissions: SUCCESS (0.040s) [746.214501] [093/140] (494s left) kms_ccs (pipe-c-crc-primary-basic-4-tiled-dg2-rc-ccs-cc) [746.464961] Starting subtest: pipe-C-crc-primary-basic-4-tiled-dg2-rc-ccs-cc [746.836936] Subtest pipe-C-crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SUCCESS (0.371s) [747.220769] [094/140] (493s left) gem_mmap_offset (close-race) [747.270766] Starting subtest: close-race [767.318515] Subtest close-race: SUCCESS (20.048s) [767.414480] [095/140] (473s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu) [767.570861] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu [767.572662] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [767.650962] [096/140] (473s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [767.788776] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [768.976939] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.188s) [769.099270] [097/140] (472s left) i915_query (query-regions-garbage-items) [769.144818] Starting subtest: query-regions-garbage-items [769.145769] Subtest query-regions-garbage-items: SUCCESS (0.000s) [769.217261] [098/140] (471s left) gem_pread (self) [769.262658] Subtest self: SKIP (0.000s) [769.304314] [099/140] (471s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels) [769.425360] Starting subtest: plane-all-modeset-transition-internal-panels [769.427461] Subtest plane-all-modeset-transition-internal-panels: SKIP (0.000s) [769.499288] [100/140] (471s left) kms_frontbuffer_tracking (fbc-2p-rte) [769.627782] Starting subtest: fbc-2p-rte [769.629530] Subtest fbc-2p-rte: SKIP (0.000s) [769.699586] [101/140] (471s left) kms_async_flips (test-time-stamp) [769.827890] Starting subtest: test-time-stamp [770.021796] Starting dynamic subtest: pipe-A-DP-4 [770.302390] Dynamic subtest pipe-A-DP-4: SUCCESS (0.278s) [770.524290] Starting dynamic subtest: pipe-B-DP-4 [770.674472] Dynamic subtest pipe-B-DP-4: SUCCESS (0.160s) [770.863679] Starting dynamic subtest: pipe-C-DP-4 [771.005023] Dynamic subtest pipe-C-DP-4: SUCCESS (0.148s) [771.214110] Starting dynamic subtest: pipe-D-DP-4 [771.356256] Dynamic subtest pipe-D-DP-4: SUCCESS (0.152s) [771.357625] Subtest test-time-stamp: SUCCESS (1.528s) [771.759915] [102/140] (469s left) perf_pmu (render-node-busy) [771.832076] Starting subtest: render-node-busy [771.833083] Starting dynamic subtest: rcs0 [772.425653] Dynamic subtest rcs0: SUCCESS (0.593s) [772.439322] Starting dynamic subtest: bcs0 [772.989488] Dynamic subtest bcs0: SUCCESS (0.560s) [773.003299] Starting dynamic subtest: vcs0 [773.553569] Dynamic subtest vcs0: SUCCESS (0.561s) [773.567659] Starting dynamic subtest: vcs1 [774.112206] Dynamic subtest vcs1: SUCCESS (0.556s) [774.125496] Starting dynamic subtest: vecs0 [774.671735] Dynamic subtest vecs0: SUCCESS (0.557s) [774.685587] Starting dynamic subtest: vecs1 [775.235577] Dynamic subtest vecs1: SUCCESS (0.561s) [775.249496] Starting dynamic subtest: ccs0 [775.803611] Dynamic subtest ccs0: SUCCESS (0.565s) [775.817398] Starting dynamic subtest: ccs1 [776.375498] Dynamic subtest ccs1: SUCCESS (0.569s) [776.389139] Starting dynamic subtest: ccs2 [776.940662] Dynamic subtest ccs2: SUCCESS (0.562s) [776.954549] Starting dynamic subtest: ccs3 [777.505502] Dynamic subtest ccs3: SUCCESS (0.562s) [777.507512] Subtest render-node-busy: SUCCESS (5.675s) [777.591807] [103/140] (463s left) gem_render_copy (yf-tiled) [777.640114] Starting subtest: yf-tiled [777.641750] Subtest yf-tiled: SKIP (0.000s) [777.858487] [104/140] (463s left) kms_ccs (pipe-b-bad-rotation-90-4-tiled-mtl-rc-ccs) [778.087076] Starting subtest: pipe-B-bad-rotation-90-4-tiled-mtl-rc-ccs [778.088736] Subtest pipe-B-bad-rotation-90-4-tiled-mtl-rc-ccs: SKIP (0.000s) [778.316629] [105/140] (463s left) kms_big_fb (x-tiled-8bpp-rotate-0) [778.463176] Starting subtest: x-tiled-8bpp-rotate-0 [779.852948] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.390s) [779.981082] [106/140] (461s left) kms_chamelium_color (ctm-0-75) [780.096296] Subtest ctm-0-75: SKIP (0.000s) [780.131876] [107/140] (461s left) gem_ctx_sseu (mmap-args) [780.180906] Subtest mmap-args: SKIP (0.000s) [780.216439] [108/140] (461s left) kms_plane_lowres (tiling-x) [780.339415] Starting subtest: tiling-x [780.340333] Starting dynamic subtest: pipe-A-DP-4 [791.156910] Dynamic subtest pipe-A-DP-4: SUCCESS (10.816s) [791.271734] Starting dynamic subtest: pipe-B-DP-4 [801.494997] Dynamic subtest pipe-B-DP-4: SUCCESS (10.232s) [801.611105] Starting dynamic subtest: pipe-C-DP-4 [811.863433] Dynamic subtest pipe-C-DP-4: SUCCESS (10.261s) [811.964319] Starting dynamic subtest: pipe-D-DP-4 [822.252081] Dynamic subtest pipe-D-DP-4: SUCCESS (10.298s) [822.356576] Subtest tiling-x: SUCCESS (42.018s) [822.571402] [109/140] (418s left) gem_unfence_active_buffers [822.772516] [110/140] (418s left) drm_fdinfo (virtual-busy-hang-all) [822.854109] Starting subtest: virtual-busy-hang-all [822.855845] Subtest virtual-busy-hang-all: SKIP (0.000s) [822.913077] [111/140] (418s left) kms_ccs (pipe-a-crc-primary-basic-y-tiled-gen12-rc-ccs) [823.103215] Starting subtest: pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs [823.104986] Subtest pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.000s) [823.338652] [112/140] (418s left) perf_pmu (interrupts) [823.423290] Starting subtest: interrupts [824.524411] Subtest interrupts: SUCCESS (1.101s) [824.623745] [113/140] (416s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu) [824.744798] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu [824.746546] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [824.825940] [114/140] (416s left) gem_ctx_exec (basic) [824.884320] Starting subtest: basic [824.886676] Subtest basic: SUCCESS (0.003s) [825.014953] [115/140] (416s left) kms_color (ctm-0-75) [825.159513] Starting subtest: ctm-0-75 [825.160506] Starting dynamic subtest: pipe-A [827.371388] Dynamic subtest pipe-A: SUCCESS (2.211s) [827.391265] Starting dynamic subtest: pipe-B [829.445704] Dynamic subtest pipe-B: SUCCESS (2.073s) [829.457735] Starting dynamic subtest: pipe-C [831.556337] Dynamic subtest pipe-C: SUCCESS (2.111s) [831.566981] Starting dynamic subtest: pipe-D [833.661084] Dynamic subtest pipe-D: SUCCESS (2.103s) [833.662166] Subtest ctm-0-75: SUCCESS (8.502s) [833.911044] [116/140] (407s left) gem_lmem_swapping (smem-oom) [838.463962] Starting subtest: smem-oom [838.465858] Starting dynamic subtest: lmem0 [858.439746] Dynamic subtest lmem0: SUCCESS (19.974s) [858.442872] Subtest smem-oom: SUCCESS (19.977s) [859.663531] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1714 [max: 8192] direct dependencies: 17190 [max: 32768] indirect dependencies: 117604 all direct dependencies: 326155 dependency chains: 24591 [max: 131072] dependency chain hlocks used: 106753 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 188 in-softirq chains: 622 in-process chains: 23781 stack-trace entries: 189260 [max: 1048576] number of stack traces: 9521 number of stack hash chains: 7222 combined max dependencies: 2800259154 hardirq-safe locks: 93 hardirq-unsafe locks: 1013 softirq-safe locks: 199 softirq-unsafe locks: 922 irq-safe locks: 212 irq-unsafe locks: 1013 hardirq-read-safe locks: 3 hardirq-read-unsafe locks: 313 softirq-read-safe locks: 7 softirq-read-unsafe locks: 308 irq-read-safe locks: 7 irq-read-unsafe locks: 313 uncategorized locks: 290 unused locks: 1 max locking depth: 16 max bfs queue depth: 447 max lock class index: 1927 chain lookup misses: 31224 chain lookup hits: 416814580 cyclic checks: 31691 redundant checks: 0 redundant links: 0 find-mask forwards checks: 5406 find-mask backwards checks: 2877 hardirq on events: 220282588 hardirq off events: 220282583 redundant hardirq ons: 62 redundant hardirq offs: 149 softirq on events: 404282 softirq off events: 404282 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 218 zapped lock chains: 6528 large chain blocks: 1 [859.666306] Closing watchdogs Done.