[51.648123] Initializing watchdogs
[51.648435]   /dev/watchdog0
[51.665900] [001/142] (960s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[51.999851] Starting subtest: plane-all-transition-nonblocking-fencing
[52.002466] Starting dynamic subtest: pipe-A-DP-1
[56.087648] Dynamic subtest pipe-A-DP-1: SUCCESS (4.088s)
[56.242557] Starting dynamic subtest: pipe-B-DP-1
[60.676836] Dynamic subtest pipe-B-DP-1: SUCCESS (4.451s)
[60.777656] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (8.780s)
[61.116388] [002/142] (951s left) vgem_basic (setversion)
[61.446864] Starting subtest: setversion
[61.450656] Subtest setversion: SUCCESS (0.000s)
[61.515062] [003/142] (950s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_mtl_mc_ccs)
[62.034368] Starting subtest: pipe-B-random-ccs-data-4_tiled_mtl_mc_ccs
[62.041253] Subtest pipe-B-random-ccs-data-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[62.489531] [004/142] (949s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted)
[62.702299] Starting subtest: invalid-multi-wait-all-available-unsubmitted
[62.704458] Subtest invalid-multi-wait-all-available-unsubmitted: SUCCESS (0.000s)
[62.788842] [005/142] (949s left) kms_atomic (plane-primary-legacy)
[63.397206] Starting subtest: plane-primary-legacy
[63.588708] Subtest plane-primary-legacy: SUCCESS (0.190s)
[64.020545] [006/142] (948s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[64.229466] Starting subtest: basic-write-cpu-read-gtt
[64.233853] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[64.302477] [007/142] (948s left) gem_fenced_exec_thrash (2-spare-fences)
[64.523916] Starting subtest: 2-spare-fences
[66.700807] Subtest 2-spare-fences: SUCCESS (2.175s)
[67.069366] [008/142] (945s left) gem_userptr_blits (unsync-unmap)
[67.294073] Subtest unsync-unmap: SKIP (0.000s)
[67.453878] [009/142] (944s left) v3d/v3d_perfmon (destroy-valid-perfmon)
[67.719178] Subtest destroy-valid-perfmon: SKIP (0.000s)
[67.786062] [010/142] (944s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_gen12_rc_ccs_cc)
[68.457095] Subtest pipe-D-random-ccs-data-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[68.852172] [011/142] (943s left) kms_plane_alpha_blend (constant-alpha-mid)
[69.248355] Starting subtest: constant-alpha-mid
[69.541732] Starting dynamic subtest: pipe-A-DP-1
[70.212399] Dynamic subtest pipe-A-DP-1: SUCCESS (0.688s)
[70.230787] Starting dynamic subtest: pipe-C-DP-1
[71.143047] Dynamic subtest pipe-C-DP-1: SUCCESS (0.926s)
[71.146196] Subtest constant-alpha-mid: SUCCESS (1.928s)
[71.501958] [012/142] (941s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[71.810164] Starting subtest: blocking-absolute-wf_vblank-interruptible
[71.814879] Starting dynamic subtest: A-DP1
[82.474868] Dynamic subtest A-DP1: SUCCESS (10.659s)
[82.489883] Starting dynamic subtest: B-DP1
[93.110315] Dynamic subtest B-DP1: SUCCESS (10.636s)
[93.124928] Starting dynamic subtest: C-DP1
[103.586422] Dynamic subtest C-DP1: SUCCESS (10.474s)
[103.591286] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (31.781s)
[103.993089] [013/142] (908s left) vc4/vc4_wait_bo (used-bo-0ns)
[104.256287] Subtest used-bo-0ns: SKIP (0.000s)
[104.324497] [014/142] (908s left) v3d/v3d_submit_csd (single-out-sync)
[104.589740] Subtest single-out-sync: SKIP (0.000s)
[104.655686] [015/142] (908s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs)
[105.218322] Starting subtest: pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs
[105.224758] Subtest pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs: SKIP (0.005s)
[105.670402] [016/142] (907s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[105.977292] Starting subtest: basic-flip-after-cursor-atomic
[107.050875] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.073s)
[107.387610] [017/142] (905s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[107.785372] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[108.070941] [018/142] (904s left) kms_cursor_edge_walk (64x64-top-bottom)
[108.373635] Starting subtest: 64x64-top-bottom
[108.377386] Starting dynamic subtest: pipe-A-DP-1
[112.011237] Dynamic subtest pipe-A-DP-1: SUCCESS (3.638s)
[112.025726] Starting dynamic subtest: pipe-C-DP-1
[115.478497] Dynamic subtest pipe-C-DP-1: SUCCESS (3.463s)
[115.481276] Subtest 64x64-top-bottom: SUCCESS (7.108s)
[115.790908] [019/142] (897s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[116.112045] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[118.169280] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (2.061s)
[118.289393] [020/142] (894s left) gem_exec_balancer (parallel-ordering)
[122.374982] Subtest parallel-ordering: SKIP (0.000s)
[122.471483] [021/142] (890s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[125.205279] Subtest verify-pxp-key-change-after-suspend-resume: SKIP (0.000s)
[125.289280] [022/142] (887s left) i915_suspend (basic-s3-without-i915)
[125.492255] Starting subtest: basic-s3-without-i915
[144.768201] Subtest basic-s3-without-i915: SUCCESS (3.409s)
[149.933686] [023/142] (883s left) kms_ccs (pipe-d-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[150.628378] Subtest pipe-D-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[150.956951] [024/142] (882s left) kms_ccs (pipe-c-ccs-on-another-bo-y_tiled_gen12_mc_ccs)
[151.609256] Starting subtest: pipe-C-ccs-on-another-bo-y_tiled_gen12_mc_ccs
[151.614072] Subtest pipe-C-ccs-on-another-bo-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[151.945694] [025/142] (882s left) feature_discovery (chamelium)
[152.286420] Starting subtest: chamelium
[152.290933] Subtest chamelium: SKIP (0.001s)
[152.378698] [026/142] (881s left) kms_ccs (pipe-c-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[153.001567] Starting subtest: pipe-C-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[153.005921] Subtest pipe-C-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[153.352728] [027/142] (880s left) gem_mmap_gtt (cpuset-big-copy-xy)
[153.561112] Starting subtest: cpuset-big-copy-XY
[178.866176] Subtest cpuset-big-copy-XY: SUCCESS (25.305s)
[179.310039] [028/142] (854s left) kms_prop_blob (invalid-get-prop)
[179.631259] Starting subtest: invalid-get-prop
[179.633526] Subtest invalid-get-prop: SUCCESS (0.000s)
[179.721423] [029/142] (854s left) kms_vblank (pipe-d-ts-continuation-modeset)
[180.038204] Subtest pipe-D-ts-continuation-modeset: SKIP (0.000s)
[180.121933] [030/142] (854s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_mtl_rc_ccs_cc)
[180.436513] Starting subtest: pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs_cc
[180.441824] Subtest pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[181.121213] [031/142] (853s left) gem_lmem_swapping (random-engines)
[181.365579] Subtest random-engines: SKIP (0.000s)
[181.419200] [032/142] (852s left) gem_mmap_gtt (basic-write)
[181.622898] Starting subtest: basic-write
[182.045687] Subtest basic-write: SUCCESS (0.424s)
[182.172001] [033/142] (852s left) syncobj_wait (invalid-reset-bad-pad)
[182.380354] Starting subtest: invalid-reset-bad-pad
[182.382641] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[182.470427] [034/142] (851s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[182.805480] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[182.813541] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.005s)
[182.921464] [035/142] (851s left) syncobj_wait (single-wait-all-signaled)
[183.130005] Starting subtest: single-wait-all-signaled
[183.132410] Subtest single-wait-all-signaled: SUCCESS (0.001s)
[183.219453] [036/142] (851s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[183.533636] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[183.537843] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[183.637500] [037/142] (850s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[183.958299] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[185.394933] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (1.437s)
[185.722598] [038/142] (848s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-pwrite)
[186.045096] Starting subtest: psr-1p-offscren-pri-indfb-draw-pwrite
[186.049211] Subtest psr-1p-offscren-pri-indfb-draw-pwrite: SKIP (0.000s)
[186.155110] [039/142] (848s left) kms_properties (connector-properties-legacy)
[186.463541] Starting subtest: connector-properties-legacy
[188.788372] Subtest connector-properties-legacy: SUCCESS (2.326s)
[189.105396] [040/142] (845s left) kms_frontbuffer_tracking (psr-suspend)
[189.424632] Starting subtest: psr-suspend
[189.428589] Subtest psr-suspend: SKIP (0.000s)
[189.518753] [041/142] (845s left) i915_pm_rpm (universal-planes-dpms)
[191.330201] Starting subtest: universal-planes-dpms
[191.333119] Starting dynamic subtest: plane-31
[194.066236] Dynamic subtest plane-31: SUCCESS (2.735s)
[194.088717] Starting dynamic subtest: plane-39
[197.035121] Dynamic subtest plane-39: SUCCESS (2.965s)
[197.056694] Starting dynamic subtest: plane-47
[199.920292] Dynamic subtest plane-47: SUCCESS (2.885s)
[199.942068] Starting dynamic subtest: plane-55
[202.732816] Dynamic subtest plane-55: SUCCESS (2.810s)
[202.736371] Subtest universal-planes-dpms: SUCCESS (11.404s)
[203.189937] [042/142] (831s left) gem_ctx_persistence (saturated-hostile)
[203.418725] Starting subtest: saturated-hostile
[203.421215] Starting dynamic subtest: rcs0
[203.547008] Dynamic subtest rcs0: SUCCESS (0.127s)
[203.573896] Starting dynamic subtest: bcs0
[203.667300] Dynamic subtest bcs0: SUCCESS (0.118s)
[203.689240] Starting dynamic subtest: vcs0
[203.784617] Dynamic subtest vcs0: SUCCESS (0.114s)
[203.808515] Starting dynamic subtest: vecs0
[203.919346] Dynamic subtest vecs0: SUCCESS (0.132s)
[203.921722] Subtest saturated-hostile: SUCCESS (0.501s)
[204.040086] [043/142] (830s left) gem_pxp (verify-pxp-stale-ctx-execution)
[206.768924] Subtest verify-pxp-stale-ctx-execution: SKIP (0.000s)
[206.855380] [044/142] (827s left) gem_exec_schedule (preempt-contexts)
[207.097534] Starting subtest: preempt-contexts
[207.100336] Starting dynamic subtest: rcs0
[207.243281] Dynamic subtest rcs0: SUCCESS (0.143s)
[207.270313] Starting dynamic subtest: bcs0
[207.396742] Dynamic subtest bcs0: SUCCESS (0.150s)
[207.429819] Starting dynamic subtest: vcs0
[207.533914] Dynamic subtest vcs0: SUCCESS (0.131s)
[207.562294] Starting dynamic subtest: vecs0
[207.658668] Dynamic subtest vecs0: SUCCESS (0.124s)
[207.662514] Subtest preempt-contexts: SUCCESS (0.560s)
[208.022978] [045/142] (826s left) i915_pm_rpm (modeset-non-lpsp-stress)
[209.826612] Starting subtest: modeset-non-lpsp-stress
[217.821304] Subtest modeset-non-lpsp-stress: SUCCESS (7.993s)
[218.090631] [046/142] (816s left) kms_hdr (invalid-metadata-sizes)
[218.493520] Starting subtest: invalid-metadata-sizes
[218.542739] Subtest invalid-metadata-sizes: SKIP (0.000s)
[218.665330] [047/142] (816s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[218.986700] Starting subtest: invalid-bsd1-flag-on-vebox
[219.011579] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.001s)
[219.191170] [048/142] (816s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[219.506981] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[219.511071] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[219.621132] [049/142] (815s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_gen12_mc_ccs)
[220.301355] Subtest pipe-D-missing-ccs-buffer-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[220.621169] [050/142] (814s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[220.833599] Starting subtest: invalid-signal-one-illegal-handle
[220.835997] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.001s)
[220.920497] [051/142] (814s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_dg2_rc_ccs_cc)
[221.408521] Starting subtest: pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs_cc
[221.413333] Subtest pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[221.933154] [052/142] (813s left) dmabuf_sync_file (import-multiple-read-write)
[222.138981] Starting subtest: import-multiple-read-write
[222.153801] Subtest import-multiple-read-write: SUCCESS (0.015s)
[222.232634] [053/142] (813s left) device_reset (unbind-reset-rebind)
[222.475140] Starting subtest: unbind-reset-rebind
[223.366243] Subtest unbind-reset-rebind: SUCCESS (0.886s)
[223.883631] [054/142] (811s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[224.222929] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[226.892748] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (2.675s)
[227.055598] [055/142] (808s left) kms_vblank (pipe-b-ts-continuation-dpms-suspend)
[227.369347] Starting subtest: pipe-B-ts-continuation-dpms-suspend
[246.068360] Subtest pipe-B-ts-continuation-dpms-suspend: SUCCESS (2.082s)
[251.240753] [056/142] (805s left) gem_exec_reloc (basic-gtt-wc-active)
[251.471408] Starting subtest: basic-gtt-wc-active
[251.614962] Subtest basic-gtt-wc-active: SUCCESS (0.142s)
[251.799209] [057/142] (805s left) i915_pm_dc (dc5-psr)
[253.108476] Starting subtest: dc5-psr
[253.114234] Subtest dc5-psr: SKIP (0.000s)
[253.281187] [058/142] (803s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[253.597851] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[253.601857] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[253.715411] [059/142] (803s left) kms_chamelium_hpd (dp-hpd)
[254.121812] Subtest dp-hpd: SKIP (0.000s)
[254.422436] [060/142] (802s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-25)
[254.722544] Starting subtest: planes-unity-scaling-downscale-factor-0-25
[254.724692] Starting dynamic subtest: pipe-A-DP-1
[254.754513] Dynamic subtest pipe-A-DP-1: SKIP (0.031s)
[254.783670] Starting dynamic subtest: pipe-B-DP-1
[254.841004] Dynamic subtest pipe-B-DP-1: SKIP (0.081s)
[254.865714] Starting dynamic subtest: pipe-C-DP-1
[254.935611] Dynamic subtest pipe-C-DP-1: SKIP (0.087s)
[254.940478] Subtest planes-unity-scaling-downscale-factor-0-25: SKIP (0.214s)
[255.049625] [061/142] (802s left) gem_create (create-ext-placement-all)
[255.385219] Starting subtest: create-ext-placement-all
[255.387647] Subtest create-ext-placement-all: SUCCESS (0.000s)
[255.500369] [062/142] (801s left) v3d/v3d_submit_csd (bad-bo)
[255.772141] Subtest bad-bo: SKIP (0.000s)
[255.849419] [063/142] (801s left) gem_exec_fair (basic-throttle)
[256.084670] Starting subtest: basic-throttle
[256.087003] Starting dynamic subtest: rcs0
[261.274398] Dynamic subtest rcs0: SUCCESS (5.188s)
[261.276979] Subtest basic-throttle: SUCCESS (5.191s)
[261.483770] [064/142] (795s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[261.808897] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[261.812892] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[261.916541] [065/142] (795s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[262.242045] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[262.245991] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[262.350249] [066/142] (795s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[262.658534] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[262.662732] Starting dynamic subtest: pipe-A-valid-mode
[262.756283] Dynamic subtest pipe-A-valid-mode: SKIP (0.095s)
[262.762496] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.100s)
[263.092773] [067/142] (794s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[263.302371] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[263.304914] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[263.407994] [068/142] (794s left) kms_addfb_basic (invalid-set-prop)
[263.620514] Starting subtest: invalid-set-prop
[263.622906] Subtest invalid-set-prop: SUCCESS (0.000s)
[263.824964] [069/142] (793s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[264.174639] Starting subtest: extended-modeset-hang-newfb-with-reset
[264.176928] Starting dynamic subtest: pipe-A
[267.347360] Dynamic subtest pipe-A: SUCCESS (3.169s)
[267.378207] Starting dynamic subtest: pipe-C
[270.655826] Dynamic subtest pipe-C: SUCCESS (3.302s)
[270.657889] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (6.486s)
[271.030032] [070/142] (786s left) perf_pmu (most-busy-check-all)
[271.246057] Starting subtest: most-busy-check-all
[271.248361] Starting dynamic subtest: rcs0
[271.773939] Dynamic subtest rcs0: SUCCESS (0.525s)
[271.810578] Starting dynamic subtest: bcs0
[272.308162] Dynamic subtest bcs0: SUCCESS (0.529s)
[272.341449] Starting dynamic subtest: vcs0
[272.840459] Dynamic subtest vcs0: SUCCESS (0.530s)
[272.870588] Starting dynamic subtest: vecs0
[273.370905] Dynamic subtest vecs0: SUCCESS (0.527s)
[273.373195] Subtest most-busy-check-all: SUCCESS (2.124s)
[273.478479] [071/142] (784s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[273.804831] Starting subtest: cursor-rapid-movement-512x170
[273.809314] Subtest cursor-rapid-movement-512x170: SKIP (0.003s)
[273.928333] [072/142] (783s left) prime_mmap_coherency (write)
[274.146355] Starting subtest: write
[279.549937] Subtest write: SUCCESS (5.402s)
[280.110805] [073/142] (777s left) syncobj_timeline (wait-all-for-submit-snapshot)
[280.320439] Starting subtest: wait-all-for-submit-snapshot
[280.402380] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[280.494918] [074/142] (777s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[280.819003] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[280.823039] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[280.928423] [075/142] (776s left) gen9_exec_parse (bb-start-cmd)
[281.163547] Starting subtest: bb-start-cmd
[281.169800] Subtest bb-start-cmd: SUCCESS (0.005s)
[281.395289] [076/142] (776s left) gem_ctx_engines (execute-one)
[281.625103] Starting subtest: execute-one
[284.756874] Subtest execute-one: SUCCESS (3.131s)
[284.979854] [077/142] (772s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_rc_ccs)
[285.657313] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[285.982133] [078/142] (772s left) kms_plane (plane-panning-bottom-right)
[286.294868] Starting subtest: plane-panning-bottom-right
[286.297487] Starting dynamic subtest: pipe-A-planes
[287.111906] Dynamic subtest pipe-A-planes: SUCCESS (0.813s)
[287.144610] Starting dynamic subtest: pipe-B-planes
[288.225619] Dynamic subtest pipe-B-planes: SUCCESS (1.108s)
[288.227739] Subtest plane-panning-bottom-right: SUCCESS (1.931s)
[288.580155] [079/142] (769s left) gem_softpin (reverse)
[288.802395] Starting subtest: reverse
[288.821875] Subtest reverse: SUCCESS (0.020s)
[288.945985] [080/142] (769s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[289.259496] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[289.263614] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[289.364484] [081/142] (768s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[289.692445] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[289.702844] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.004s)
[289.798449] [082/142] (768s left) gem_linear_blits (basic)
[290.511897] Starting subtest: basic
[290.531490] Subtest basic: SUCCESS (0.020s)
[290.664832] [083/142] (767s left) kms_ccs (pipe-a-crc-primary-basic-y_tiled_ccs)
[291.211332] Starting subtest: pipe-A-crc-primary-basic-y_tiled_ccs
[292.053158] Subtest pipe-A-crc-primary-basic-y_tiled_ccs: SUCCESS (0.842s)
[292.797470] [084/142] (765s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[293.109826] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[295.227486] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (2.124s)
[295.376871] [085/142] (762s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_mtl_rc_ccs)
[295.879794] Starting subtest: pipe-B-crc-primary-basic-4_tiled_mtl_rc_ccs
[295.885713] Subtest pipe-B-crc-primary-basic-4_tiled_mtl_rc_ccs: SKIP (0.001s)
[296.379357] [086/142] (761s left) kms_scaling_modes (scaling-mode-full-aspect)
[296.684811] Starting subtest: scaling-mode-full-aspect
[296.689145] Subtest scaling-mode-full-aspect: SKIP (0.001s)
[296.779972] [087/142] (761s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[297.120846] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[298.246535] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (1.125s)
[298.485252] [088/142] (759s left) kms_psr2_sf (overlay-primary-update-sf-dmg-area)
[298.698560] Subtest overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[298.760775] [089/142] (759s left) syncobj_timeline (wait-for-submit-delayed-submit)
[298.975517] Starting subtest: wait-for-submit-delayed-submit
[299.081302] Subtest wait-for-submit-delayed-submit: SUCCESS (0.105s)
[299.179528] [090/142] (759s left) kms_flip (2x-flip-vs-suspend-interruptible)
[299.490148] Starting subtest: 2x-flip-vs-suspend-interruptible
[299.498164] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.003s)
[299.612776] [091/142] (759s left) vc4/vc4_purgeable_bo (mark-purgeable)
[299.882686] Subtest mark-purgeable: SKIP (0.000s)
[299.961804] [092/142] (758s left) syncobj_wait (invalid-reset-zero-handles)
[300.175459] Starting subtest: invalid-reset-zero-handles
[300.178065] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[300.262208] [093/142] (758s left) kms_cursor_crc (cursor-onscreen-512x170)
[300.588103] Starting subtest: cursor-onscreen-512x170
[300.592279] Subtest cursor-onscreen-512x170: SKIP (0.003s)
[300.696571] [094/142] (758s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[301.033491] Starting subtest: 4-tiled-8bpp-rotate-180
[301.037607] Subtest 4-tiled-8bpp-rotate-180: SKIP (0.000s)
[301.146114] [095/142] (757s left) gem_exec_whisper (basic-fds-forked-all)
[301.382567] Starting subtest: basic-fds-forked-all
[325.428160] Subtest basic-fds-forked-all: SUCCESS (24.042s)
[325.631255] [096/142] (733s left) prime_vgem (fence-read-hang)
[325.867622] Subtest fence-read-hang: SKIP (0.000s)
[325.980011] [097/142] (732s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_mtl_rc_ccs)
[326.657512] Subtest pipe-D-crc-primary-basic-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[326.983257] [098/142] (731s left) kms_ccs (pipe-a-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc)
[327.424468] Starting subtest: pipe-A-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc
[327.430408] Subtest pipe-A-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[327.992158] [099/142] (731s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[328.305534] Starting subtest: fbc-rgb565-draw-mmap-wc
[330.458462] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (2.159s)
[330.608354] [100/142] (728s left) gem_ctx_param (vm)
[330.816164] Starting subtest: vm
[330.881315] Subtest vm: SUCCESS (0.064s)
[331.009136] [101/142] (728s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[331.318111] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[331.321632] Starting dynamic subtest: pipe-A-valid-mode
[331.411363] Dynamic subtest pipe-A-valid-mode: SKIP (0.092s)
[331.416584] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.096s)
[331.748692] [102/142] (727s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[332.068549] Starting subtest: cursorB-vs-flipA-atomic-transitions
[332.073579] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.004s)
[332.171878] [103/142] (727s left) gen7_exec_parse (bitmasks)
[332.411946] Subtest bitmasks: SKIP (0.000s)
[332.454589] [104/142] (726s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[332.784550] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[332.788651] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[332.888559] [105/142] (726s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[333.223209] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[333.228508] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[333.321822] [106/142] (726s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[333.620302] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[333.626528] Starting dynamic subtest: A-DP1
[334.886020] Dynamic subtest A-DP1: SUCCESS (1.259s)
[334.912612] Starting dynamic subtest: B-DP1
[335.985181] Dynamic subtest B-DP1: SUCCESS (1.084s)
[336.013221] Starting dynamic subtest: C-DP1
[337.038995] Dynamic subtest C-DP1: SUCCESS (1.063s)
[337.043164] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (3.421s)
[337.406045] [107/142] (722s left) gem_userptr_blits (map-fixed-invalidate)
[337.655221] Starting subtest: map-fixed-invalidate
[337.657570] Starting dynamic subtest: gtt
[338.016369] Dynamic subtest gtt: SUCCESS (0.359s)
[338.047583] Starting dynamic subtest: wb
[338.300694] Dynamic subtest wb: SUCCESS (0.281s)
[338.334974] Starting dynamic subtest: wc
[338.578728] Dynamic subtest wc: SUCCESS (0.275s)
[338.607299] Starting dynamic subtest: uc
[338.862722] Dynamic subtest uc: SUCCESS (0.281s)
[338.865103] Subtest map-fixed-invalidate: SUCCESS (1.209s)
[339.003268] [108/142] (720s left) api_intel_bb (blit-noreloc-keep-cache)
[339.211135] Starting subtest: blit-noreloc-keep-cache
[339.231411] Subtest blit-noreloc-keep-cache: SUCCESS (0.021s)
[339.380421] [109/142] (720s left) gem_ppgtt (shrink-vs-evict-pinned)
[339.591816] Starting subtest: shrink-vs-evict-pinned
[344.687181] Subtest shrink-vs-evict-pinned: SUCCESS (5.094s)
[344.770767] [110/142] (714s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[345.078830] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[345.083008] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[345.186882] [111/142] (714s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[345.494489] Starting subtest: 2x-plain-flip-ts-check-interruptible
[345.502269] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.003s)
[345.619985] [112/142] (714s left) kms_color (invalid-ctm-matrix-sizes)
[345.932634] Starting subtest: invalid-ctm-matrix-sizes
[345.934704] Starting dynamic subtest: pipe-A
[345.936728] Dynamic subtest pipe-A: SUCCESS (0.007s)
[345.968433] Starting dynamic subtest: pipe-B
[345.970622] Dynamic subtest pipe-B: SUCCESS (0.010s)
[345.998334] Starting dynamic subtest: pipe-C
[346.000444] Dynamic subtest pipe-C: SUCCESS (0.013s)
[346.002636] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.038s)
[346.069975] [113/142] (713s left) gem_exec_reloc (basic-wc-active)
[346.289420] Starting subtest: basic-wc-active
[346.436027] Subtest basic-wc-active: SUCCESS (0.146s)
[346.636923] [114/142] (713s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[346.851954] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[346.918532] [115/142] (712s left) gem_ctx_param (get-priority-new-ctx)
[347.124521] Starting subtest: get-priority-new-ctx
[347.126802] Subtest get-priority-new-ctx: SUCCESS (0.000s)
[347.220930] [116/142] (712s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[347.539805] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[347.543997] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[347.636721] [117/142] (712s left) gem_render_linear_blits (basic)
[347.851809] Starting subtest: basic
[347.934343] Subtest basic: SUCCESS (0.082s)
[348.002019] [118/142] (711s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[348.313191] Starting subtest: compare-crc-sanitycheck-xr24
[348.315590] Starting dynamic subtest: pipe-A-DP-1
[348.775531] Dynamic subtest pipe-A-DP-1: SUCCESS (0.454s)
[348.803835] Starting dynamic subtest: pipe-B-DP-1
[349.515568] Dynamic subtest pipe-B-DP-1: SUCCESS (0.741s)
[349.547091] Starting dynamic subtest: pipe-C-DP-1
[350.388964] Dynamic subtest pipe-C-DP-1: SUCCESS (0.870s)
[350.391432] Subtest compare-crc-sanitycheck-xr24: SUCCESS (2.081s)
[350.737665] [119/142] (709s left) kms_vblank (pipe-c-accuracy-idle)
[351.061050] Starting subtest: pipe-C-accuracy-idle
[353.832770] Subtest pipe-C-accuracy-idle: SUCCESS (2.787s)
[354.181986] [120/142] (705s left) kms_sequence (get-busy)
[354.499242] Starting subtest: get-busy
[354.501524] Starting dynamic subtest: DP-1-pipe-A
[356.964767] Dynamic subtest DP-1-pipe-A: SUCCESS (2.466s)
[356.992978] Starting dynamic subtest: DP-1-pipe-B
[359.707924] Dynamic subtest DP-1-pipe-B: SUCCESS (2.741s)
[359.738533] Starting dynamic subtest: DP-1-pipe-C
[362.599901] Dynamic subtest DP-1-pipe-C: SUCCESS (2.887s)
[362.602021] Subtest get-busy: SUCCESS (8.106s)
[362.932558] [121/142] (697s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[363.242648] Starting subtest: 2x-cursor-vs-flip-atomic
[363.246853] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.004s)
[363.347143] [122/142] (696s left) gem_exec_schedule (u-independent)
[363.589866] Starting subtest: u-independent
[363.592443] Starting dynamic subtest: rcs0
[363.791055] Dynamic subtest rcs0: SUCCESS (0.202s)
[363.822919] Starting dynamic subtest: bcs0
[363.992441] Dynamic subtest bcs0: SUCCESS (0.198s)
[364.021461] Starting dynamic subtest: vcs0
[364.161004] Dynamic subtest vcs0: SUCCESS (0.166s)
[364.203264] Starting dynamic subtest: vecs0
[364.338438] Dynamic subtest vecs0: SUCCESS (0.174s)
[364.340730] Subtest u-independent: SUCCESS (0.752s)
[364.682498] [123/142] (695s left) perf (blocking)
[365.034501] Starting subtest: blocking
[365.036686] Starting dynamic subtest: 0-rcs0
[375.136230] Dynamic subtest 0-rcs0: SUCCESS (10.100s)
[375.139972] Subtest blocking: SUCCESS (10.103s)
[375.196335] [124/142] (685s left) gem_exec_gttfill (multigpu-basic)
[375.547537] Starting subtest: multigpu-basic
[375.552889] Subtest multigpu-basic: SKIP (0.000s)
[375.645795] [125/142] (684s left) gem_userptr_blits (vma-merge)
[375.920817] Starting subtest: vma-merge
[396.267105] Subtest vma-merge: FAIL (20.288s)
[396.317485] [126/142] (664s left) gem_exec_params (secure-non-root)
[396.538423] Starting subtest: secure-non-root
[396.543450] Subtest secure-non-root: SKIP (0.000s)
[396.645257] [127/142] (663s left) kms_flip (2x-absolute-wf_vblank)
[396.938939] Starting subtest: 2x-absolute-wf_vblank
[396.943691] Subtest 2x-absolute-wf_vblank: SKIP (0.003s)
[397.064297] [128/142] (663s left) gem_exec_alignment (forked)
[397.283216] Starting subtest: forked
[410.255234] Killing the test because the kernel is tainted.
[412.127321] Aborting: Lockdep not active

/proc/lockdep_stats contents:
 lock-classes:                         1848 [max: 8192]
 direct dependencies:                 19482 [max: 32768]
 indirect dependencies:              161615
 all direct dependencies:            370656
 dependency chains:                   29403 [max: 131072]
 dependency chain hlocks used:       130218 [max: 655360]
 dependency chain hlocks lost:            0
 in-hardirq chains:                     230
 in-softirq chains:                     685
 in-process chains:                   28488
 stack-trace entries:                197150 [max: 1048576]
 number of stack traces:               9859
 number of stack hash chains:          7378
 combined max dependencies:       219570578
 hardirq-safe locks:                     93
 hardirq-unsafe locks:                 1094
 softirq-safe locks:                    211
 softirq-unsafe locks:                 1004
 irq-safe locks:                        228
 irq-unsafe locks:                     1094
 hardirq-read-safe locks:                 4
 hardirq-read-unsafe locks:             328
 softirq-read-safe locks:                 8
 softirq-read-unsafe locks:             323
 irq-read-safe locks:                     8
 irq-read-unsafe locks:                 328
 uncategorized locks:                   313
 unused locks:                            1
 max locking depth:                      16
 max bfs queue depth:                   439
 max lock class index:                 1847
 chain lookup misses:                 33544
 chain lookup hits:               203806134
 cyclic checks:                       34037
 redundant checks:                        0
 redundant links:                         0
 find-mask forwards checks:            5695
 find-mask backwards checks:           3001
 hardirq on events:               124475808
 hardirq off events:              124475804
 redundant hardirq ons:                  62
 redundant hardirq offs:                 71
 softirq on events:                  841625
 softirq off events:                 841625
 redundant softirq ons:                   0
 redundant softirq offs:                  0
 debug_locks:                             0

 zapped classes:                        171
 zapped lock chains:                   4078
 large chain blocks:                      1

[412.130377] Closing watchdogs
Done.