[50.326446] Initializing watchdogs
[50.326662]   /dev/watchdog0
[50.342320] [001/138] (960s left) kms_cursor_crc (cursor-onscreen-64x64)
[50.613970] Starting subtest: cursor-onscreen-64x64
[50.649267] Starting dynamic subtest: pipe-A-DP-1
[53.212757] Dynamic subtest pipe-A-DP-1: SUCCESS (2.562s)
[53.294453] Starting dynamic subtest: pipe-C-DP-1
[55.739330] Dynamic subtest pipe-C-DP-1: SUCCESS (2.453s)
[55.740524] Subtest cursor-onscreen-64x64: SUCCESS (5.124s)
[56.113414] [002/138] (954s left) kms_cursor_crc (cursor-sliding-32x10)
[56.358344] Starting subtest: cursor-sliding-32x10
[56.478754] Subtest cursor-sliding-32x10: SKIP (0.118s)
[56.593134] [003/138] (954s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[56.842901] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[56.845593] Subtest psr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[56.938361] [004/138] (954s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[57.088697] Starting subtest: framebuffer-vs-set-tiling
[57.090167] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[57.273597] [005/138] (953s left) kms_ccs (pipe-c-ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[57.736716] Starting subtest: pipe-C-ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[57.739488] Subtest pipe-C-ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[58.062834] [006/138] (952s left) kms_plane_scaling (planes-downscale-factor-0-75)
[58.299833] Starting subtest: planes-downscale-factor-0-75
[58.301312] Starting dynamic subtest: pipe-A-DP-1
[58.397428] Dynamic subtest pipe-A-DP-1: SUCCESS (0.096s)
[58.415318] Starting dynamic subtest: pipe-B-DP-1
[58.808374] Dynamic subtest pipe-B-DP-1: SUCCESS (0.408s)
[58.828313] Starting dynamic subtest: pipe-C-DP-1
[58.910125] Dynamic subtest pipe-C-DP-1: SKIP (0.096s)
[58.911725] Subtest planes-downscale-factor-0-75: SUCCESS (0.610s)
[59.312702] [007/138] (951s left) v3d/v3d_submit_csd (bad-multisync-pad)
[59.518260] Subtest bad-multisync-pad: SKIP (0.000s)
[59.580752] [008/138] (951s left) gem_ctx_param (set-priority-invalid-size)
[59.722995] Starting subtest: set-priority-invalid-size
[59.724711] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[59.797211] [009/138] (951s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[60.030926] Starting subtest: addfb25-yf-tiled-legacy
[60.032326] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[60.130136] [010/138] (951s left) gem_reg_read (timestamp-moving)
[60.271858] Starting subtest: timestamp-moving
[61.272646] Subtest timestamp-moving: SUCCESS (1.001s)
[61.347243] [011/138] (949s left) kms_ccs (pipe-a-ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[61.707467] Starting subtest: pipe-A-ccs-on-another-bo-4-tiled-mtl-rc-ccs
[61.710037] Subtest pipe-A-ccs-on-another-bo-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[62.131630] [012/138] (949s left) gem_tiled_blits (basic)
[63.397968] Starting subtest: basic
[63.473093] Subtest basic: SUCCESS (0.072s)
[63.612812] [013/138] (947s left) kms_chamelium_color (ctm-0-25)
[63.974912] Subtest ctm-0-25: SKIP (0.000s)
[64.017310] [014/138] (947s left) gem_fence_thrash (bo-write-verify-threaded-none)
[64.152951] Starting subtest: bo-write-verify-threaded-none
[66.122433] Subtest bo-write-verify-threaded-none: SUCCESS (1.969s)
[66.223155] [015/138] (945s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[67.065371] Starting subtest: syncobj-timeline-invalid-wait
[67.068831] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.004s)
[67.302881] [016/138] (944s left) gem_ctx_persistence (legacy-engines-queued)
[67.455127] Starting subtest: legacy-engines-queued
[67.456499] Starting dynamic subtest: render
[67.510421] Dynamic subtest render: SUCCESS (0.052s)
[67.528231] Starting dynamic subtest: bsd
[67.575497] Dynamic subtest bsd: SUCCESS (0.063s)
[67.592326] Starting dynamic subtest: blt
[67.731103] Dynamic subtest blt: SUCCESS (0.154s)
[67.748674] Starting dynamic subtest: vebox
[67.789765] Dynamic subtest vebox: SUCCESS (0.057s)
[67.791721] Subtest legacy-engines-queued: SUCCESS (0.334s)
[67.867272] [017/138] (943s left) kms_sequence (get-forked-busy)
[68.111517] Starting subtest: get-forked-busy
[68.113121] Starting dynamic subtest: pipe-A-DP-1
[70.610867] Dynamic subtest pipe-A-DP-1: SUCCESS (2.499s)
[70.628359] Starting dynamic subtest: pipe-B-DP-1
[73.279839] Dynamic subtest pipe-B-DP-1: SUCCESS (2.666s)
[73.299251] Starting dynamic subtest: pipe-C-DP-1
[75.968859] Dynamic subtest pipe-C-DP-1: SUCCESS (2.686s)
[75.970485] Subtest get-forked-busy: SUCCESS (7.858s)
[76.280444] [018/138] (935s left) v3d/v3d_get_bo_offset (create-get-offsets)
[76.483167] Subtest create-get-offsets: SKIP (0.000s)
[76.545139] [019/138] (934s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[76.766908] Starting subtest: flip-vs-dpms-off-vs-modeset
[76.768521] Starting dynamic subtest: A-DP1
[78.201745] Dynamic subtest A-DP1: SUCCESS (1.434s)
[78.218958] Starting dynamic subtest: B-DP1
[79.322913] Dynamic subtest B-DP1: SUCCESS (1.121s)
[79.339582] Starting dynamic subtest: C-DP1
[80.427728] Dynamic subtest C-DP1: SUCCESS (1.104s)
[80.429201] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (3.665s)
[80.778512] [020/138] (930s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[81.068165] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[81.070675] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[81.171634] [021/138] (930s left) kms_getfb (getfb2-handle-zero)
[81.312495] Starting subtest: getfb2-handle-zero
[81.314379] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[81.408848] [022/138] (930s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[81.657437] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[81.660060] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[81.754143] [023/138] (929s left) kms_color (ctm-signed)
[82.000829] Starting subtest: ctm-signed
[82.002210] Starting dynamic subtest: pipe-A
[83.191312] Dynamic subtest pipe-A: SUCCESS (1.192s)
[83.210047] Starting dynamic subtest: pipe-B
[84.547617] Dynamic subtest pipe-B: SUCCESS (1.349s)
[84.568230] Starting dynamic subtest: pipe-C
[85.886223] Dynamic subtest pipe-C: SUCCESS (1.345s)
[85.887423] Subtest ctm-signed: SUCCESS (3.888s)
[86.225748] [024/138] (925s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[86.660889] Starting subtest: pipe-B-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[86.666727] Subtest pipe-B-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[87.043952] [025/138] (924s left) kms_ccs (pipe-a-crc-primary-rotation-180-y-tiled-ccs)
[87.385467] Starting subtest: pipe-A-crc-primary-rotation-180-y-tiled-ccs
[88.041982] Subtest pipe-A-crc-primary-rotation-180-y-tiled-ccs: SUCCESS (0.654s)
[88.599217] [026/138] (923s left) kms_flip (flip-vs-rmfb-interruptible)
[88.831230] Starting subtest: flip-vs-rmfb-interruptible
[88.836461] Starting dynamic subtest: A-DP1
[99.303419] Dynamic subtest A-DP1: SUCCESS (10.466s)
[99.319156] Starting dynamic subtest: B-DP1
[109.722608] Dynamic subtest B-DP1: SUCCESS (10.416s)
[109.742038] Starting dynamic subtest: C-DP1
[120.155315] Dynamic subtest C-DP1: SUCCESS (10.430s)
[120.159978] Subtest flip-vs-rmfb-interruptible: SUCCESS (31.326s)
[120.573828] [027/138] (891s left) gem_userptr_blits (unsync-unmap-after-close)
[120.730237] Subtest unsync-unmap-after-close: SKIP (0.000s)
[120.887356] [028/138] (890s left) gem_wait (invalid-flags)
[121.038164] Starting subtest: invalid-flags
[121.039551] Subtest invalid-flags: SUCCESS (0.000s)
[121.304428] [029/138] (890s left) kms_prime (basic-crc-vgem)
[123.337812] Starting subtest: basic-crc-vgem
[123.339168] Starting dynamic subtest: second-to-first
[124.550829] Dynamic subtest second-to-first: SUCCESS (1.214s)
[124.553474] Subtest basic-crc-vgem: SUCCESS (1.216s)
[124.659725] [030/138] (887s left) gem_exec_params (sol-reset-invalid)
[124.810402] Starting subtest: sol-reset-invalid
[124.815087] Subtest sol-reset-invalid: SUCCESS (0.003s)
[124.922143] [031/138] (886s left) v3d/v3d_perfmon (create-two-perfmon)
[125.120132] Subtest create-two-perfmon: SKIP (0.000s)
[125.187548] [032/138] (886s left) gem_userptr_blits (relocations)
[125.338673] Starting subtest: relocations
[125.343850] Subtest relocations: SKIP (0.004s)
[125.505681] [033/138] (886s left) dmabuf_sync_file (import-multiple-read-write)
[125.651922] Starting subtest: import-multiple-read-write
[125.664820] Subtest import-multiple-read-write: SUCCESS (0.013s)
[125.738606] [034/138] (886s left) gem_exec_reloc (basic-cpu-gtt-active)
[125.891495] Starting subtest: basic-cpu-gtt-active
[126.030518] Subtest basic-cpu-gtt-active: SUCCESS (0.138s)
[126.222431] [035/138] (885s left) gem_exec_schedule (preempt-other)
[126.397779] Starting subtest: preempt-other
[126.399386] Starting dynamic subtest: rcs0
[126.479994] Dynamic subtest rcs0: SUCCESS (0.080s)
[126.500456] Starting dynamic subtest: bcs0
[126.560861] Dynamic subtest bcs0: SUCCESS (0.079s)
[126.580805] Starting dynamic subtest: vcs0
[126.642046] Dynamic subtest vcs0: SUCCESS (0.079s)
[126.663184] Starting dynamic subtest: vecs0
[126.724974] Dynamic subtest vecs0: SUCCESS (0.080s)
[126.726447] Subtest preempt-other: SUCCESS (0.328s)
[127.074476] [036/138] (885s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[127.337040] Starting subtest: 4-tiled-64bpp-rotate-90
[127.339975] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.000s)
[127.420776] [037/138] (884s left) sw_sync (sync_expired_merge)
[127.557605] Starting subtest: sync_expired_merge
[128.633144] Subtest sync_expired_merge: SUCCESS (1.074s)
[128.704779] [038/138] (883s left) gem_exec_reloc (basic-range)
[128.857275] Starting subtest: basic-range
[128.937041] Subtest basic-range: SUCCESS (0.078s)
[129.122678] [039/138] (883s left) kms_addfb_basic (invalid-set-prop)
[129.265461] Starting subtest: invalid-set-prop
[129.266861] Subtest invalid-set-prop: SUCCESS (0.000s)
[129.455376] [040/138] (882s left) kms_pm_dc (dc9-dpms)
[130.703988] Starting subtest: dc9-dpms
[130.710168] Subtest dc9-dpms: SKIP (0.000s)
[130.872898] [041/138] (881s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[131.343310] Starting subtest: pipe-C-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[131.348457] Subtest pipe-C-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[131.672636] [042/138] (880s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-ccs)
[131.834062] Starting subtest: y-tiled-ccs-to-yf-tiled-ccs
[131.835475] Starting dynamic subtest: smem
[132.267515] Dynamic subtest smem: SUCCESS (0.432s)
[132.270627] Subtest y-tiled-ccs-to-yf-tiled-ccs: SUCCESS (0.434s)
[132.473437] [043/138] (879s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[132.711768] Starting subtest: cursorA-vs-flipA-atomic
[143.750236] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.039s)
[144.081550] [044/138] (868s left) gem_close_race (multigpu-basic-process)
[144.255511] Starting subtest: multigpu-basic-process
[144.258370] Subtest multigpu-basic-process: SKIP (0.000s)
[144.450373] [045/138] (867s left) syncobj_timeline (etime-multi-wait-for-submit-available-unsubmitted)
[144.593459] Starting subtest: etime-multi-wait-for-submit-available-unsubmitted
[144.696087] Subtest etime-multi-wait-for-submit-available-unsubmitted: SUCCESS (0.102s)
[144.778983] [046/138] (867s left) i915_hangman (hangcheck-unterminated)
[144.942426] Starting subtest: hangcheck-unterminated
[154.259034] Subtest hangcheck-unterminated: SUCCESS (9.317s)
[154.380205] [047/138] (858s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[154.636372] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[154.639333] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[154.730255] [048/138] (857s left) gem_exec_balancer (full-pulse)
[154.898707] Starting subtest: full-pulse
[158.895858] Subtest full-pulse: SUCCESS (3.997s)
[162.882589] [049/138] (849s left) kms_big_fb (linear-addfb)
[163.142518] Starting subtest: linear-addfb
[163.144156] Subtest linear-addfb: SUCCESS (0.000s)
[163.251184] [050/138] (849s left) kms_chamelium_frames (hdmi-crc-single)
[163.576035] Subtest hdmi-crc-single: SKIP (0.000s)
[163.867414] [051/138] (848s left) gem_exec_fence (long-history)
[164.459118] Starting subtest: long-history
[170.229476] Subtest long-history: SUCCESS (5.770s)
[170.926579] [052/138] (841s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[171.182248] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[171.185023] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[171.275078] [053/138] (841s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-signaled)
[171.415667] Starting subtest: invalid-multi-wait-available-unsubmitted-signaled
[171.417159] Subtest invalid-multi-wait-available-unsubmitted-signaled: SUCCESS (0.001s)
[171.508618] [054/138] (841s left) kms_busy (extended-modeset-hang-oldfb)
[171.756908] Starting subtest: extended-modeset-hang-oldfb
[171.758533] Starting dynamic subtest: pipe-A
[179.378898] Dynamic subtest pipe-A: SUCCESS (7.618s)
[179.404558] Starting dynamic subtest: pipe-C
[187.379300] Dynamic subtest pipe-C: SUCCESS (7.995s)
[187.380805] Subtest extended-modeset-hang-oldfb: SUCCESS (15.626s)
[187.736460] [055/138] (825s left) gem_lmem_swapping (verify)
[187.885736] Subtest verify: SKIP (0.000s)
[187.936375] [056/138] (824s left) kms_async_flips (test-time-stamp)
[188.176697] Starting subtest: test-time-stamp
[188.324431] Starting dynamic subtest: pipe-A-DP-1
[188.705854] Dynamic subtest pipe-A-DP-1: SUCCESS (0.380s)
[188.930193] Starting dynamic subtest: pipe-B-DP-1
[189.193717] Dynamic subtest pipe-B-DP-1: SUCCESS (0.291s)
[189.408044] Starting dynamic subtest: pipe-C-DP-1
[189.706410] Dynamic subtest pipe-C-DP-1: SUCCESS (0.323s)
[189.707728] Subtest test-time-stamp: SUCCESS (1.533s)
[190.166395] [057/138] (822s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[190.404056] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[190.409319] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.004s)
[190.515556] [058/138] (822s left) kms_ccs (pipe-b-bad-aux-stride-y-tiled-gen12-mc-ccs)
[190.945367] Starting subtest: pipe-B-bad-aux-stride-y-tiled-gen12-mc-ccs
[190.948196] Subtest pipe-B-bad-aux-stride-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[191.330706] [059/138] (821s left) gem_readwrite (beyond-eob)
[191.471921] Starting subtest: beyond-EOB
[191.475223] Subtest beyond-EOB: SUCCESS (0.000s)
[191.577962] [060/138] (821s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[191.835837] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[194.140510] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (2.308s)
[194.280152] [061/138] (818s left) gem_mmap_wc (write-prefaulted)
[194.421643] Starting subtest: write-prefaulted
[194.737496] Subtest write-prefaulted: SUCCESS (0.316s)
[194.829600] [062/138] (818s left) gem_exec_reloc (basic-cpu-read)
[194.979453] Starting subtest: basic-cpu-read
[195.000110] Subtest basic-cpu-read: SUCCESS (0.020s)
[195.196405] [063/138] (817s left) vc4/vc4_tiling (set-bad-handle)
[195.395702] Subtest set-bad-handle: SKIP (0.000s)
[195.462222] [064/138] (817s left) prime_busy (after-wait)
[195.610727] Starting subtest: after-wait
[195.628786] Starting dynamic subtest: rcs0
[195.638842] Dynamic subtest rcs0: SUCCESS (0.010s)
[195.663749] Starting dynamic subtest: bcs0
[195.665046] Dynamic subtest bcs0: SUCCESS (0.017s)
[195.685750] Starting dynamic subtest: vcs0
[195.687176] Dynamic subtest vcs0: SUCCESS (0.021s)
[195.708040] Starting dynamic subtest: vecs0
[195.709477] Dynamic subtest vecs0: SUCCESS (0.016s)
[195.821109] Subtest after-wait: SUCCESS (0.210s)
[195.912809] [065/138] (817s left) kms_ccs (pipe-d-crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[196.380700] Subtest pipe-D-crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[196.704370] [066/138] (816s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[196.951058] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[196.953989] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[197.037989] [067/138] (816s left) kms_psr2_sf (cursor-plane-update-sf)
[197.180950] Subtest cursor-plane-update-sf: SKIP (0.000s)
[197.239759] [068/138] (816s left) i915_pm_rps (reset)
[197.389473] Starting subtest: reset
[200.969596] Subtest reset: SUCCESS (3.580s)
[201.056652] [069/138] (812s left) gem_pxp (hw-rejects-pxp-buffer)
[203.719995] Starting subtest: hw-rejects-pxp-buffer
[203.721470] Subtest hw-rejects-pxp-buffer: SUCCESS (0.001s)
[203.837756] [070/138] (809s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[204.082221] Starting subtest: fbc-rgb101010-draw-render
[206.749359] Subtest fbc-rgb101010-draw-render: SUCCESS (2.668s)
[206.871617] [071/138] (806s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[207.118258] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[209.182408] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (2.067s)
[209.321947] [072/138] (804s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[209.564729] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[209.566238] Starting dynamic subtest: pipe-A-valid-mode
[209.663925] Dynamic subtest pipe-A-valid-mode: SKIP (0.096s)
[209.666854] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.100s)
[209.977489] [073/138] (803s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[210.237503] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[210.240502] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[210.328843] [074/138] (803s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[210.473471] Starting subtest: single-wait-for-submit-available-signaled
[210.474975] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.001s)
[210.563298] [075/138] (803s left) kms_ccs (pipe-c-bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[210.997996] Starting subtest: pipe-C-bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[211.000641] Subtest pipe-C-bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s)
[211.371467] [076/138] (802s left) kms_cursor_legacy (forked-bo)
[211.604862] Starting subtest: forked-bo
[211.606209] Starting dynamic subtest: pipe-A
[217.073677] Dynamic subtest pipe-A: SUCCESS (5.463s)
[217.093852] Starting dynamic subtest: pipe-B
[222.547091] Dynamic subtest pipe-B: SUCCESS (5.473s)
[222.568161] Starting dynamic subtest: pipe-C
[228.032880] Dynamic subtest pipe-C: SUCCESS (5.483s)
[228.052482] Starting dynamic subtest: all-pipes
[233.502111] Dynamic subtest all-pipes: SUCCESS (5.466s)
[233.503767] Subtest forked-bo: SUCCESS (21.897s)
[233.593912] [077/138] (780s left) kms_plane_cursor (primary)
[233.859950] Starting subtest: primary
[233.867210] Starting dynamic subtest: pipe-A-DP-1-size-64
[236.280516] Dynamic subtest pipe-A-DP-1-size-64: SUCCESS (2.423s)
[236.336633] Starting dynamic subtest: pipe-A-DP-1-size-128
[238.732662] Dynamic subtest pipe-A-DP-1-size-128: SUCCESS (2.414s)
[238.782205] Starting dynamic subtest: pipe-A-DP-1-size-256
[241.180377] Dynamic subtest pipe-A-DP-1-size-256: SUCCESS (2.415s)
[241.331621] Starting dynamic subtest: pipe-B-DP-1-size-64
[243.963449] Dynamic subtest pipe-B-DP-1-size-64: SUCCESS (2.648s)
[244.019538] Starting dynamic subtest: pipe-B-DP-1-size-128
[246.410697] Dynamic subtest pipe-B-DP-1-size-128: SUCCESS (2.415s)
[246.464825] Starting dynamic subtest: pipe-B-DP-1-size-256
[248.877352] Dynamic subtest pipe-B-DP-1-size-256: SUCCESS (2.432s)
[249.032130] Starting dynamic subtest: pipe-C-DP-1-size-64
[251.660085] Dynamic subtest pipe-C-DP-1-size-64: SUCCESS (2.648s)
[251.712505] Starting dynamic subtest: pipe-C-DP-1-size-128
[254.110302] Dynamic subtest pipe-C-DP-1-size-128: SUCCESS (2.415s)
[254.162876] Starting dynamic subtest: pipe-C-DP-1-size-256
[256.560160] Dynamic subtest pipe-C-DP-1-size-256: SUCCESS (2.415s)
[256.675793] Subtest primary: SUCCESS (22.820s)
[256.999968] [078/138] (756s left) gem_exec_schedule (deep)
[257.305155] Starting subtest: deep
[257.306469] Starting dynamic subtest: rcs0
[271.184158] Dynamic subtest rcs0: SUCCESS (13.869s)
[271.204829] Starting dynamic subtest: bcs0
[286.094818] Dynamic subtest bcs0: SUCCESS (14.906s)
[286.117981] Starting dynamic subtest: vcs0
[299.862435] Dynamic subtest vcs0: SUCCESS (13.769s)
[299.891822] Starting dynamic subtest: vecs0
[313.827744] Dynamic subtest vecs0: SUCCESS (13.963s)
[313.830367] Subtest deep: SUCCESS (56.524s)
[314.903776] [079/138] (698s left) gem_exec_fence (basic-wait-all)
[315.071926] Starting subtest: basic-wait-all
[315.102657] Subtest basic-wait-all: SUCCESS (0.033s)
[315.902314] [080/138] (697s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[316.159294] Starting subtest: y-tiled-16bpp-rotate-270
[316.261043] Subtest y-tiled-16bpp-rotate-270: SKIP (0.100s)
[316.384837] [081/138] (697s left) kms_plane_scaling (plane-downscale-factor-0-75-with-rotation)
[316.622738] Starting subtest: plane-downscale-factor-0-75-with-rotation
[316.624258] Starting dynamic subtest: pipe-A-DP-1
[316.731980] Dynamic subtest pipe-A-DP-1: SKIP (0.106s)
[316.754454] Starting dynamic subtest: pipe-B-DP-1
[317.136875] Dynamic subtest pipe-B-DP-1: SKIP (0.401s)
[317.160413] Starting dynamic subtest: pipe-C-DP-1
[317.525445] Dynamic subtest pipe-C-DP-1: SKIP (0.382s)
[317.528149] Subtest plane-downscale-factor-0-75-with-rotation: SKIP (0.905s)
[317.921453] [082/138] (695s left) v3d/v3d_wait_bo (used-bo-0ns)
[318.132132] Subtest used-bo-0ns: SKIP (0.000s)
[318.203094] [083/138] (695s left) perf (global-sseu-config-invalid)
[318.482567] Starting subtest: global-sseu-config-invalid
[318.484210] Starting dynamic subtest: 0-rcs0
[318.525508] Dynamic subtest 0-rcs0: SUCCESS (0.041s)
[318.526954] Subtest global-sseu-config-invalid: SUCCESS (0.044s)
[318.591290] [084/138] (695s left) gem_close (basic)
[318.738867] Starting subtest: basic
[318.740354] Subtest basic: SUCCESS (0.000s)
[318.851077] [085/138] (695s left) gem_exec_capture (capture)
[319.005982] Starting subtest: capture
[319.007327] Starting dynamic subtest: rcs0-smem
[320.294153] Dynamic subtest rcs0-smem: SUCCESS (1.285s)
[320.313951] Starting dynamic subtest: bcs0-smem
[321.577616] Dynamic subtest bcs0-smem: SUCCESS (1.281s)
[321.597537] Starting dynamic subtest: vcs0-smem
[322.857182] Dynamic subtest vcs0-smem: SUCCESS (1.277s)
[322.877327] Starting dynamic subtest: vecs0-smem
[324.133476] Dynamic subtest vecs0-smem: SUCCESS (1.276s)
[324.135219] Subtest capture: SUCCESS (5.129s)
[324.252647] [086/138] (689s left) kms_chamelium_hpd (vga-hpd-fast)
[324.574972] Subtest vga-hpd-fast: SKIP (0.000s)
[324.854744] [087/138] (689s left) kms_psr (psr2_dpms)
[325.000966] Subtest psr2_dpms: SKIP (0.000s)
[325.050940] [088/138] (689s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[325.478777] Starting subtest: pipe-B-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[325.485039] Subtest pipe-B-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[325.858692] [089/138] (688s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[326.105859] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[326.108433] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[326.209697] [090/138] (688s left) gem_exec_flush (basic-wb-prw-default)
[326.375924] Starting subtest: basic-wb-prw-default
[331.847251] Subtest basic-wb-prw-default: SUCCESS (5.471s)
[332.060743] [091/138] (682s left) sw_sync (alloc_merge_fence)
[332.199136] Starting subtest: alloc_merge_fence
[332.200475] Subtest alloc_merge_fence: SUCCESS (0.001s)
[332.276739] [092/138] (682s left) tools_test (tools_test)
[332.424208] Starting subtest: tools_test
[332.716364] Subtest tools_test: SUCCESS (0.298s)
[332.792621] [093/138] (681s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[332.935427] Starting subtest: multi-wait-for-submit-available-submitted
[332.937285] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.002s)
[333.027107] [094/138] (681s left) kms_psr (primary_mmap_cpu)
[333.169476] Subtest primary_mmap_cpu: SKIP (0.000s)
[333.228852] [095/138] (681s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[333.472591] Starting subtest: psr-rgb101010-draw-mmap-wc
[333.475194] Subtest psr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[333.575587] [096/138] (680s left) kms_vblank (ts-continuation-modeset)
[333.816642] Starting subtest: ts-continuation-modeset
[333.820772] Starting dynamic subtest: pipe-A-DP-1
[334.450627] Dynamic subtest pipe-A-DP-1: SUCCESS (0.629s)
[334.476163] Starting dynamic subtest: pipe-C-DP-1
[335.247488] Dynamic subtest pipe-C-DP-1: SUCCESS (0.789s)
[335.250319] Subtest ts-continuation-modeset: SUCCESS (1.432s)
[335.593734] [097/138] (678s left) kms_ccs (pipe-d-bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[336.084148] Subtest pipe-D-bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s)
[336.416909] [098/138] (678s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[336.676798] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[338.109363] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.431s)
[338.267231] [099/138] (676s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-pwrite)
[338.518848] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-pwrite
[338.521572] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-pwrite: SKIP (0.000s)
[338.634108] [100/138] (676s left) api_intel_bb (delta-check)
[338.778088] Starting subtest: delta-check
[338.787999] Subtest delta-check: SUCCESS (0.010s)
[338.897910] [101/138] (675s left) kms_ccs (pipe-c-random-ccs-data-4-tiled-dg2-mc-ccs)
[339.339044] Starting subtest: pipe-C-random-ccs-data-4-tiled-dg2-mc-ccs
[339.343153] Subtest pipe-C-random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[339.684179] [102/138] (675s left) gem_mmap_gtt (hang-user)
[339.830315] Starting subtest: hang-user
[355.228652] Subtest hang-user: SUCCESS (15.396s)
[355.347597] [103/138] (659s left) kms_ccs (pipe-d-missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc)
[355.837357] Subtest pipe-D-missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s)
[356.167970] [104/138] (658s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[356.332210] Starting subtest: y-tiled-to-vebox-x-tiled
[356.334789] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[356.533955] [105/138] (658s left) device_reset (unbind-cold-reset-rebind)
[356.712963] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[356.799371] [106/138] (658s left) gem_fence_thrash (bo-write-verify-y)
[356.942268] Starting subtest: bo-write-verify-y
[358.187337] Subtest bo-write-verify-y: SUCCESS (1.249s)
[358.267341] [107/138] (656s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[358.513438] Starting subtest: primary-x-tiled-reflect-x-180
[358.515998] Subtest primary-x-tiled-reflect-x-180: SKIP (0.003s)
[358.600653] [108/138] (656s left) kms_flip (2x-blocking-absolute-wf_vblank)
[358.823665] Starting subtest: 2x-blocking-absolute-wf_vblank
[358.826156] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.003s)
[358.934755] [109/138] (656s left) perf_pmu (module-unload)
[359.093991] Starting subtest: module-unload
[397.666524] Subtest module-unload: SUCCESS (38.573s)
[397.706187] [110/138] (617s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[399.207800] Starting subtest: fbc-rgb101010-draw-mmap-wc
[401.928486] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (2.723s)
[402.150243] [111/138] (612s left) gen7_exec_parse (basic-allowed)
[402.315277] Subtest basic-allowed: SKIP (0.000s)
[402.370303] [112/138] (612s left) gem_eio (hibernate)
[402.541914] Starting subtest: hibernate
[418.880720] Subtest hibernate: SUCCESS (16.326s)
[424.076620] [113/138] (596s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[424.221440] Starting subtest: multi-wait-all-for-submit-signaled
[424.223092] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[424.314992] [114/138] (595s left) drm_fdinfo (virtual-idle)
[424.463697] Starting subtest: virtual-idle
[426.496481] Subtest virtual-idle: SUCCESS (2.029s)
[426.584617] [115/138] (593s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[426.833687] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[426.836392] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[426.933338] [116/138] (593s left) kms_lease (lease-revoke)
[427.168757] Starting subtest: lease-revoke
[427.172858] Starting dynamic subtest: pipe-A-DP-1
[427.281142] Dynamic subtest pipe-A-DP-1: SUCCESS (0.104s)
[427.307265] Starting dynamic subtest: pipe-B-DP-1
[427.391087] Dynamic subtest pipe-B-DP-1: SUCCESS (0.108s)
[427.420520] Starting dynamic subtest: pipe-C-DP-1
[427.496391] Dynamic subtest pipe-C-DP-1: SUCCESS (0.099s)
[427.498280] Subtest lease-revoke: SUCCESS (0.326s)
[427.599879] [117/138] (592s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[427.835624] Starting subtest: plane-all-modeset-transition-internal-panels
[427.838421] Subtest plane-all-modeset-transition-internal-panels: SKIP (0.000s)
[427.914961] [118/138] (592s left) gem_exec_whisper (basic-queues-forked)
[428.084076] Starting subtest: basic-queues-forked
[439.564676] Subtest basic-queues-forked: SUCCESS (11.479s)
[439.769988] [119/138] (580s left) gem_eio (create)
[439.931977] Starting subtest: create
[439.985295] Subtest create: SUCCESS (0.053s)
[440.182905] [120/138] (580s left) kms_atomic_transition (plane-all-modeset-transition-fencing)
[440.420196] Starting subtest: plane-all-modeset-transition-fencing
[440.421507] Starting dynamic subtest: pipe-A-DP-1
[498.429801] Dynamic subtest pipe-A-DP-1: SUCCESS (58.007s)
[498.556373] Starting dynamic subtest: pipe-B-DP-1
[556.344316] Dynamic subtest pipe-B-DP-1: SUCCESS (57.816s)
[556.444677] Subtest plane-all-modeset-transition-fencing: SUCCESS (116.023s)
[556.802889] [121/138] (463s left) gem_ctx_exec (basic-close-race)
[556.962196] Starting subtest: basic-close-race
[562.449401] Subtest basic-close-race: SUCCESS (5.485s)
[562.904515] [122/138] (457s left) kms_atomic_interruptible (legacy-pageflip)
[563.143249] Starting subtest: legacy-pageflip
[563.147492] Starting dynamic subtest: pipe-A-DP-1
[569.667096] Dynamic subtest pipe-A-DP-1: SUCCESS (6.516s)
[569.668508] Subtest legacy-pageflip: SUCCESS (6.521s)
[569.992975] [123/138] (450s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[570.238981] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[570.241684] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[570.341769] [124/138] (450s left) gem_exec_params (invalid-fence-in)
[570.489378] Starting subtest: invalid-fence-in
[570.490926] Subtest invalid-fence-in: SUCCESS (0.001s)
[570.592483] [125/138] (450s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[570.836080] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[570.838649] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[570.942224] [126/138] (449s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[571.201637] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[571.205076] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[571.308896] [127/138] (449s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[571.544920] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[571.549416] Starting dynamic subtest: pipe-A-valid-mode
[571.664922] Dynamic subtest pipe-A-valid-mode: SKIP (0.117s)
[571.668067] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.121s)
[572.012326] [128/138] (448s left) gem_exec_schedule (u-semaphore-resolve)
[572.190580] Starting subtest: u-semaphore-resolve
[572.244104] Subtest u-semaphore-resolve: SUCCESS (0.053s)
[572.613208] [129/138] (448s left) kms_legacy_colorkey (invalid-plane)
[572.858673] Starting subtest: invalid-plane
[572.860342] Starting dynamic subtest: zero-id
[572.861961] Dynamic subtest zero-id: SUCCESS (0.000s)
[572.884885] Starting dynamic subtest: outrange-id
[572.886467] Dynamic subtest outrange-id: SUCCESS (0.000s)
[572.887780] Subtest invalid-plane: SUCCESS (0.006s)
[572.948289] [130/138] (448s left) kms_getfb (getfb-handle-zero)
[573.088297] Starting subtest: getfb-handle-zero
[573.090161] Subtest getfb-handle-zero: SUCCESS (0.000s)
[573.194663] [131/138] (447s left) kms_content_protection (uevent)
[573.465277] Starting subtest: uevent
[573.505334] Starting dynamic subtest: pipe-A-DP-1
[608.032193] Dynamic subtest pipe-A-DP-1: FAIL (34.452s)
[608.086147] Subtest uevent: FAIL (34.617s)
[608.428841] [132/138] (412s left) gem_userptr_blits (nohangcheck)
[608.642211] Starting subtest: nohangcheck
[608.972649] Subtest nohangcheck: SUCCESS (0.331s)
[609.095822] [133/138] (411s left) i915_getparams_basic (basic-subslice-total)
[609.235266] Starting subtest: basic-subslice-total
[609.238617] Subtest basic-subslice-total: SUCCESS (0.000s)
[609.328182] [134/138] (411s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[609.571959] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[609.574569] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[609.676612] [135/138] (411s left) kms_ccs (pipe-a-bad-pixel-format-4-tiled-dg2-rc-ccs)
[609.913530] Starting subtest: pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs
[609.915989] Subtest pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[610.493350] [136/138] (410s left) syncobj_timeline (wait-for-submit-delayed-submit)
[610.637680] Starting subtest: wait-for-submit-delayed-submit
[610.741343] Subtest wait-for-submit-delayed-submit: SUCCESS (0.103s)
[610.842384] [137/138] (410s left) kms_flip (2x-modeset-vs-vblank-race)
[611.063927] Starting subtest: 2x-modeset-vs-vblank-race
[611.066482] Subtest 2x-modeset-vs-vblank-race: SKIP (0.003s)
[611.191114] [138/138] (410s left) kms_ccs (pipe-b-bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[611.581159] Starting subtest: pipe-B-bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[611.583691] Subtest pipe-B-bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[611.994642] Closing watchdogs
Done.