[40.289569] Initializing watchdogs
[40.289895]   /dev/watchdog0
[40.312528] [001/141] (960s left) gem_exec_params (readonly)
[40.551121] Starting subtest: readonly
[40.563095] Subtest readonly: SUCCESS (0.011s)
[40.668236] [002/141] (960s left) gem_exec_fence (syncobj-timeline-chain-engines)
[41.560139] Starting subtest: syncobj-timeline-chain-engines
[41.701164] Subtest syncobj-timeline-chain-engines: SUCCESS (0.141s)
[41.932530] [003/141] (958s left) tools_test (tools_test)
[42.138428] Starting subtest: tools_test
[42.554875] Subtest tools_test: SUCCESS (0.425s)
[42.614895] [004/141] (958s left) kms_addfb_basic (bad-pitch-32)
[42.819570] Starting subtest: bad-pitch-32
[42.821159] Subtest bad-pitch-32: SUCCESS (0.000s)
[43.033112] [005/141] (957s left) vc4/vc4_perfmon (destroy-invalid-perfmon)
[43.300322] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[43.365207] [006/141] (957s left) kms_flip (dpms-vs-vblank-race)
[43.655209] Starting subtest: dpms-vs-vblank-race
[43.656668] Starting dynamic subtest: A-DP1
[48.126277] Dynamic subtest A-DP1: SUCCESS (4.472s)
[48.141424] Starting dynamic subtest: B-DP1
[52.289295] Dynamic subtest B-DP1: SUCCESS (4.161s)
[52.304622] Starting dynamic subtest: C-DP1
[56.474411] Dynamic subtest C-DP1: SUCCESS (4.185s)
[56.475838] Subtest dpms-vs-vblank-race: SUCCESS (12.825s)
[56.841460] [007/141] (944s left) kms_lease (invalid-create-leases)
[57.148388] Starting subtest: invalid-create-leases
[57.159748] Subtest invalid-create-leases: SUCCESS (0.009s)
[57.239687] [008/141] (943s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[57.544882] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[57.550127] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.003s)
[57.640717] [009/141] (943s left) kms_ccs (pipe-d-crc-primary-basic-yf_tiled_ccs)
[58.360688] Subtest pipe-D-crc-primary-basic-yf_tiled_ccs: SKIP (0.000s)
[58.656470] [010/141] (942s left) gem_mmap_offset (isolation)
[58.861371] Starting subtest: isolation
[58.876728] Subtest isolation: SUCCESS (0.012s)
[58.973298] [011/141] (942s left) gem_ctx_persistence (many-contexts)
[59.186279] Starting subtest: many-contexts
[92.009731] Subtest many-contexts: SUCCESS (32.823s)
[92.090827] [012/141] (909s left) kms_flip (2x-flip-vs-panning-interruptible)
[92.395919] Starting subtest: 2x-flip-vs-panning-interruptible
[92.398623] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.003s)
[92.491556] [013/141] (908s left) gem_busy (semaphore)
[92.861829] Starting subtest: semaphore
[92.863154] Starting dynamic subtest: rcs0
[92.904712] Dynamic subtest rcs0: SUCCESS (0.045s)
[92.923157] Starting dynamic subtest: bcs0
[92.940863] Dynamic subtest bcs0: SUCCESS (0.036s)
[92.959182] Starting dynamic subtest: vcs0
[92.966636] Dynamic subtest vcs0: SUCCESS (0.026s)
[92.983032] Starting dynamic subtest: vecs0
[93.000241] Dynamic subtest vecs0: SUCCESS (0.032s)
[93.001608] Subtest semaphore: SUCCESS (0.139s)
[93.223574] [014/141] (908s left) kms_vblank (pipe-d-ts-continuation-modeset-hang)
[93.526347] Subtest pipe-D-ts-continuation-modeset-hang: SKIP (0.000s)
[93.607773] [015/141] (907s left) gem_exec_store (dword)
[93.836190] Starting subtest: dword
[93.837767] Starting dynamic subtest: rcs0
[93.882848] Dynamic subtest rcs0: SUCCESS (0.046s)
[93.899107] Starting dynamic subtest: bcs0
[93.900467] Dynamic subtest bcs0: SUCCESS (0.017s)
[93.916641] Starting dynamic subtest: vcs0
[93.917977] Dynamic subtest vcs0: SUCCESS (0.017s)
[93.933494] Starting dynamic subtest: vecs0
[93.934749] Dynamic subtest vecs0: SUCCESS (0.015s)
[93.936050] Subtest dword: SUCCESS (0.096s)
[94.123384] [016/141] (907s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[94.440846] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[94.443331] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[94.541179] [017/141] (906s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[94.851399] Starting subtest: fbcpsr-slowdraw
[94.853976] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[94.941189] [018/141] (906s left) sw_sync (sync_merge_invalid)
[95.142671] Starting subtest: sync_merge_invalid
[95.148684] Subtest sync_merge_invalid: SUCCESS (0.006s)
[95.207072] [019/141] (906s left) gem_exec_balancer (fairslice)
[95.436460] Starting subtest: fairslice
[95.439069] Subtest fairslice: SKIP (0.000s)
[99.523625] [020/141] (901s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[99.828802] Starting subtest: cursorA-vs-flipA-varying-size
[110.887698] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.061s)
[111.229356] [021/141] (890s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_dg2_rc_ccs_cc)
[111.942612] Subtest pipe-D-bad-pixel-format-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[112.247275] [022/141] (889s left) vc4/vc4_wait_bo (unused-bo-0ns)
[112.511357] Subtest unused-bo-0ns: SKIP (0.000s)
[112.579766] [023/141] (888s left) i915_pm_rpm (modeset-stress-extra-wait)
[114.480849] Starting subtest: modeset-stress-extra-wait
[167.533830] Subtest modeset-stress-extra-wait: SUCCESS (53.051s)
[167.728054] [024/141] (833s left) kms_universal_plane (universal-plane-pipe-d-functional)
[168.028179] Subtest universal-plane-pipe-D-functional: SKIP (0.000s)
[168.110691] [025/141] (833s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[168.315347] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[168.316959] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.002s)
[168.396472] [026/141] (833s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[172.405561] Subtest parallel-dmabuf-import-out-fence: SKIP (0.000s)
[172.494621] [027/141] (829s left) kms_ccs (pipe-d-crc-primary-rotation-180-y_tiled_gen12_mc_ccs)
[173.207103] Subtest pipe-D-crc-primary-rotation-180-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[173.514901] [028/141] (828s left) gem_ctx_persistence (engines-queued)
[173.723748] Starting subtest: engines-queued
[173.725647] Starting dynamic subtest: rcs0
[173.780665] Dynamic subtest rcs0: SUCCESS (0.056s)
[173.800922] Starting dynamic subtest: bcs0
[173.930117] Dynamic subtest bcs0: SUCCESS (0.148s)
[173.946332] Starting dynamic subtest: vcs0
[173.987476] Dynamic subtest vcs0: SUCCESS (0.057s)
[174.003746] Starting dynamic subtest: vecs0
[174.045540] Dynamic subtest vecs0: SUCCESS (0.057s)
[174.046843] Subtest engines-queued: SUCCESS (0.322s)
[174.130676] [029/141] (827s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[174.438521] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[174.441132] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[174.531259] [030/141] (827s left) kms_cursor_crc (cursor-sliding-128x42)
[174.840441] Starting subtest: cursor-sliding-128x42
[174.851439] Starting dynamic subtest: pipe-A-DP-1
[178.525639] Dynamic subtest pipe-A-DP-1: SUCCESS (3.673s)
[178.598606] Starting dynamic subtest: pipe-C-DP-1
[182.541296] Dynamic subtest pipe-C-DP-1: SUCCESS (3.955s)
[182.542691] Subtest cursor-sliding-128x42: SUCCESS (7.701s)
[182.881398] [031/141] (818s left) gem_exec_capture (userptr)
[183.100288] Starting subtest: userptr
[184.393438] Subtest userptr: SUCCESS (1.295s)
[184.508830] [032/141] (817s left) kms_vblank (pipe-a-ts-continuation-dpms-suspend)
[184.804465] Starting subtest: pipe-A-ts-continuation-dpms-suspend
[202.784662] Subtest pipe-A-ts-continuation-dpms-suspend: SUCCESS (2.436s)
[208.311326] [033/141] (814s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[208.631413] Starting subtest: psr-rgb101010-draw-mmap-wc
[208.634062] Subtest psr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[208.736055] [034/141] (813s left) dmabuf (all-tests)
[208.940945] Starting subtest: all-tests
[208.942551] Starting dynamic subtest: sanitycheck
[209.012055] Dynamic subtest sanitycheck: SUCCESS (0.070s)
[209.033508] Starting dynamic subtest: dma_fence
[209.196398] Dynamic subtest dma_fence: SUCCESS (0.183s)
[209.217352] Starting dynamic subtest: dma_fence_chain
[233.010652] Dynamic subtest dma_fence_chain: SUCCESS (23.812s)
[233.031297] Starting dynamic subtest: dma_fence_unwrap
[233.083493] Dynamic subtest dma_fence_unwrap: SUCCESS (0.072s)
[233.107261] Starting dynamic subtest: dma_resv
[233.149860] Dynamic subtest dma_resv: SUCCESS (0.065s)
[233.151575] Subtest all-tests: SUCCESS (24.209s)
[233.217995] [035/141] (789s left) gem_ctx_param (invalid-size-get)
[233.426475] Starting subtest: invalid-size-get
[233.428081] Subtest invalid-size-get: SUCCESS (0.001s)
[233.517526] [036/141] (789s left) syncobj_wait (invalid-reset-illegal-handle)
[233.722699] Starting subtest: invalid-reset-illegal-handle
[233.724754] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[233.818061] [037/141] (788s left) v3d/v3d_submit_csd (bad-multisync-in-sync)
[234.080294] Subtest bad-multisync-in-sync: SKIP (0.000s)
[234.152477] [038/141] (788s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[234.472598] Starting subtest: yf-tiled-16bpp-rotate-270
[234.544322] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.068s)
[234.652830] [039/141] (787s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[234.951624] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[234.952956] Starting dynamic subtest: pipe-A-valid-mode
[235.028801] Dynamic subtest pipe-A-valid-mode: SKIP (0.075s)
[235.031925] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.078s)
[235.351026] [040/141] (787s left) gem_exec_reloc (basic-wc-read-active)
[235.563601] Starting subtest: basic-wc-read-active
[235.703484] Subtest basic-wc-read-active: SUCCESS (0.140s)
[235.900714] [041/141] (786s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_mtl_rc_ccs)
[236.516247] Starting subtest: pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs
[236.518792] Subtest pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[236.928507] [042/141] (785s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[237.131717] Starting subtest: single-wait-for-submit-available-signaled
[237.133677] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.001s)
[237.228423] [043/141] (785s left) prime_mmap (test_aperture_limit)
[237.429527] Starting subtest: test_aperture_limit
[237.431177] Starting dynamic subtest: test_aperture_limit-smem
[237.457890] Dynamic subtest test_aperture_limit-smem: SUCCESS (0.028s)
[237.459520] Subtest test_aperture_limit: SUCCESS (0.029s)
[237.561343] [044/141] (785s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[237.886513] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[237.889742] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[237.995632] [045/141] (784s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc)
[238.468818] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc
[238.475143] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc: SKIP (0.005s)
[239.023640] [046/141] (783s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[239.265620] Starting subtest: map-fixed-invalidate-overlap
[239.267055] Starting dynamic subtest: gtt
[239.638878] Dynamic subtest gtt: SUCCESS (0.371s)
[239.661323] Starting dynamic subtest: wb
[239.927674] Dynamic subtest wb: SUCCESS (0.288s)
[239.951060] Starting dynamic subtest: wc
[240.220489] Dynamic subtest wc: SUCCESS (0.292s)
[240.241978] Starting dynamic subtest: uc
[240.505412] Dynamic subtest uc: SUCCESS (0.284s)
[240.506868] Subtest map-fixed-invalidate-overlap: SUCCESS (1.240s)
[240.641190] [047/141] (782s left) kms_psr2_su (page_flip-p010)
[240.840074] Subtest page_flip-P010: SKIP (0.000s)
[240.889970] [048/141] (782s left) gem_tiled_blits (interruptible)
[242.455156] Starting subtest: interruptible
[295.293083] Subtest interruptible: SUCCESS (52.838s)
[295.411942] [049/141] (727s left) gem_exec_flush (basic-uc-ro-default)
[295.767487] Starting subtest: basic-uc-ro-default
[301.232479] Subtest basic-uc-ro-default: SUCCESS (5.465s)
[301.441726] [050/141] (721s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[301.742007] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[301.750973] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.003s)
[301.843075] [051/141] (721s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-factor-0-25)
[302.146729] Starting subtest: planes-downscale-factor-0-25-upscale-factor-0-25
[302.148058] Starting dynamic subtest: pipe-A-DP-1
[302.174790] Dynamic subtest pipe-A-DP-1: SKIP (0.025s)
[302.195928] Starting dynamic subtest: pipe-B-DP-1
[302.250981] Dynamic subtest pipe-B-DP-1: SKIP (0.071s)
[302.272099] Starting dynamic subtest: pipe-C-DP-1
[302.323454] Dynamic subtest pipe-C-DP-1: SKIP (0.070s)
[302.325930] Subtest planes-downscale-factor-0-25-upscale-factor-0-25: SKIP (0.178s)
[302.425829] [052/141] (720s left) kms_flip (plain-flip-fb-recreate-interruptible)
[302.726764] Starting subtest: plain-flip-fb-recreate-interruptible
[302.729215] Starting dynamic subtest: A-DP1
[313.779102] Dynamic subtest A-DP1: SUCCESS (11.050s)
[313.800848] Starting dynamic subtest: B-DP1
[324.495885] Dynamic subtest B-DP1: SUCCESS (10.715s)
[324.517387] Starting dynamic subtest: C-DP1
[335.228853] Dynamic subtest C-DP1: SUCCESS (10.731s)
[335.231607] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (32.506s)
[335.615454] [053/141] (687s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[335.933029] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[335.935949] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[336.045812] [054/141] (687s left) gem_spin_batch (engines)
[336.268842] Starting subtest: engines
[336.272676] Starting dynamic subtest: rcs0
[339.601888] Dynamic subtest rcs0: SUCCESS (3.326s)
[339.623003] Starting dynamic subtest: bcs0
[342.925317] Dynamic subtest bcs0: SUCCESS (3.323s)
[342.945557] Starting dynamic subtest: vcs0
[346.246777] Dynamic subtest vcs0: SUCCESS (3.321s)
[346.267084] Starting dynamic subtest: vecs0
[349.568828] Dynamic subtest vecs0: SUCCESS (3.321s)
[349.570276] Subtest engines: SUCCESS (13.298s)
[349.778522] [055/141] (673s left) api_intel_allocator (reloc-allocator)
[349.981643] Starting subtest: reloc-allocator
[349.983265] Starting dynamic subtest: basic
[349.987931] Dynamic subtest basic: SUCCESS (0.006s)
[350.011746] Starting dynamic subtest: parallel-one
[350.013159] Dynamic subtest parallel-one: SUCCESS (0.013s)
[350.034204] Starting dynamic subtest: print
[350.035482] Dynamic subtest print: SUCCESS (0.000s)
[350.059970] Starting dynamic subtest: reuse
[350.061315] Dynamic subtest reuse: SUCCESS (0.000s)
[350.081821] Starting dynamic subtest: fork-reopen-allocator
[350.083369] Dynamic subtest fork-reopen-allocator: SUCCESS (0.041s)
[350.084871] Subtest reloc-allocator: SUCCESS (0.066s)
[350.161539] [056/141] (673s left) gem_pxp (display-protected-crc)
[352.890179] Subtest display-protected-crc: SKIP (0.000s)
[352.978180] [057/141] (670s left) kms_vblank (pipe-c-ts-continuation-dpms-rpm)
[353.282498] Starting subtest: pipe-C-ts-continuation-dpms-rpm
[355.563496] Subtest pipe-C-ts-continuation-dpms-rpm: SUCCESS (2.276s)
[356.063048] [058/141] (667s left) gem_exec_capture (pi)
[356.277946] Starting subtest: pi
[356.279400] Starting dynamic subtest: rcs0
[386.086982] Dynamic subtest rcs0: SUCCESS (29.806s)
[386.109225] Starting dynamic subtest: bcs0
[415.887218] Dynamic subtest bcs0: SUCCESS (29.801s)
[415.909469] Starting dynamic subtest: vcs0
[444.993306] Dynamic subtest vcs0: SUCCESS (29.105s)
[445.015297] Starting dynamic subtest: vecs0
[474.471546] Dynamic subtest vecs0: SUCCESS (29.477s)
[474.475229] Subtest pi: SUCCESS (118.194s)
[474.566528] [059/141] (548s left) i915_pm_backlight (bad-brightness)
[475.001479] Subtest bad-brightness: SKIP (0.000s)
[475.064438] [060/141] (548s left) api_intel_bb (bb-with-allocator)
[475.273074] Starting subtest: bb-with-allocator
[475.282671] Subtest bb-with-allocator: SUCCESS (0.011s)
[475.450623] [061/141] (548s left) fbdev (unaligned-write)
[475.654046] Starting subtest: unaligned-write
[475.839978] Subtest unaligned-write: SUCCESS (0.186s)
[475.912824] [062/141] (547s left) kms_cursor_crc (cursor-random-128x42)
[476.230047] Starting subtest: cursor-random-128x42
[476.242394] Starting dynamic subtest: pipe-A-DP-1
[480.283017] Dynamic subtest pipe-A-DP-1: SUCCESS (4.040s)
[480.373911] Starting dynamic subtest: pipe-C-DP-1
[484.456492] Dynamic subtest pipe-C-DP-1: SUCCESS (4.100s)
[484.457794] Subtest cursor-random-128x42: SUCCESS (8.217s)
[484.801274] [063/141] (538s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[485.119948] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[485.122792] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[485.215393] [064/141] (538s left) kms_ccs (pipe-a-ccs-on-another-bo-yf_tiled_ccs)
[485.684857] Starting subtest: pipe-A-ccs-on-another-bo-yf_tiled_ccs
[485.686415] Subtest pipe-A-ccs-on-another-bo-yf_tiled_ccs: SUCCESS (0.001s)
[486.261242] [065/141] (537s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc)
[486.580243] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc
[486.583150] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[486.694080] [066/141] (537s left) kms_addfb_basic (master-rmfb)
[486.896477] Starting subtest: master-rmfb
[486.898017] Subtest master-rmfb: SUCCESS (0.001s)
[487.110860] [067/141] (536s left) prime_vgem (wait)
[487.331267] Starting subtest: wait
[487.333130] Starting dynamic subtest: rcs0
[487.365809] Dynamic subtest rcs0: SUCCESS (0.033s)
[487.390562] Starting dynamic subtest: vcs0
[487.392238] Dynamic subtest vcs0: SUCCESS (0.025s)
[487.415615] Starting dynamic subtest: vecs0
[487.417260] Dynamic subtest vecs0: SUCCESS (0.017s)
[487.418695] Subtest wait: SUCCESS (0.079s)
[487.527566] [068/141] (536s left) gem_exec_params (rsvd2-dirt)
[487.734939] Starting subtest: rsvd2-dirt
[487.738458] Subtest rsvd2-dirt: SKIP (0.000s)
[487.844267] [069/141] (536s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[488.151095] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[490.266590] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (2.119s)
[490.410910] [070/141] (533s left) perf_pmu (cpu-hotplug)
[490.621470] Starting subtest: cpu-hotplug
[495.172466] Subtest cpu-hotplug: SUCCESS (4.551s)
[495.262037] [071/141] (528s left) syncobj_timeline (etime-single-wait-all-submitted)
[495.478262] Starting subtest: etime-single-wait-all-submitted
[495.580667] Subtest etime-single-wait-all-submitted: SUCCESS (0.102s)
[495.678272] [072/141] (528s left) kms_vblank (pipe-c-query-idle)
[495.980502] Starting subtest: pipe-C-query-idle
[498.724013] Subtest pipe-C-query-idle: SUCCESS (2.748s)
[499.057557] [073/141] (525s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[499.365220] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[499.366659] Starting dynamic subtest: pipe-A-valid-mode
[500.086799] Dynamic subtest pipe-A-valid-mode: SKIP (0.721s)
[500.089713] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (0.724s)
[500.468307] [074/141] (523s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[500.796731] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[500.799954] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[500.883981] [075/141] (523s left) kms_plane_scaling (plane-upscale-with-pixel-format-factor-0-25)
[501.179780] Starting subtest: plane-upscale-with-pixel-format-factor-0-25
[501.181252] Starting dynamic subtest: pipe-A-DP-1
[501.836471] Dynamic subtest pipe-A-DP-1: SUCCESS (0.657s)
[501.872987] Starting dynamic subtest: pipe-B-DP-1
[502.808854] Dynamic subtest pipe-B-DP-1: SUCCESS (0.968s)
[502.836780] Starting dynamic subtest: pipe-C-DP-1
[503.182234] Dynamic subtest pipe-C-DP-1: SUCCESS (0.369s)
[503.183708] Subtest plane-upscale-with-pixel-format-factor-0-25: SUCCESS (2.006s)
[503.581038] [076/141] (520s left) kms_cursor_legacy (torture-move)
[503.888433] Starting subtest: torture-move
[503.889863] Starting dynamic subtest: pipe-A
[525.906511] Dynamic subtest pipe-A: SUCCESS (22.019s)
[525.931385] Starting dynamic subtest: pipe-B
[547.663140] Dynamic subtest pipe-B: SUCCESS (21.755s)
[547.688194] Starting dynamic subtest: pipe-C
[569.534997] Dynamic subtest pipe-C: SUCCESS (21.869s)
[569.560313] Starting dynamic subtest: all-pipes
[591.297184] Dynamic subtest all-pipes: SUCCESS (21.761s)
[591.299613] Subtest torture-move: SUCCESS (87.411s)
[591.397727] [077/141] (432s left) kms_ccs (pipe-d-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc)
[592.122243] Subtest pipe-D-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[592.442023] [078/141] (431s left) kms_ccs (pipe-b-bad-rotation-90-y_tiled_gen12_rc_ccs_cc)
[592.946815] Starting subtest: pipe-B-bad-rotation-90-y_tiled_gen12_rc_ccs_cc
[592.949887] Subtest pipe-B-bad-rotation-90-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[593.478013] [079/141] (430s left) kms_ccs (pipe-c-crc-primary-basic-4_tiled_mtl_mc_ccs)
[594.100807] Starting subtest: pipe-C-crc-primary-basic-4_tiled_mtl_mc_ccs
[594.103773] Subtest pipe-C-crc-primary-basic-4_tiled_mtl_mc_ccs: SKIP (0.001s)
[594.508958] [080/141] (429s left) perf (oa-formats)
[594.859308] Starting subtest: oa-formats
[594.860748] Starting dynamic subtest: 0-rcs0
[595.055767] Dynamic subtest 0-rcs0: SUCCESS (0.196s)
[595.057133] Subtest oa-formats: SUCCESS (0.197s)
[595.108350] [081/141] (429s left) gem_ctx_isolation (preservation-s3)
[595.330472] Starting subtest: preservation-S3
[595.336031] Starting dynamic subtest: rcs0
[613.784741] Dynamic subtest rcs0: SUCCESS (2.851s)
[613.808437] Starting dynamic subtest: bcs0
[632.784031] Dynamic subtest bcs0: SUCCESS (3.179s)
[632.807989] Starting dynamic subtest: vcs0
[651.661986] Dynamic subtest vcs0: SUCCESS (2.612s)
[651.687122] Starting dynamic subtest: vecs0
[670.644635] Dynamic subtest vecs0: SUCCESS (2.584s)
[670.646008] Subtest preservation-S3: SUCCESS (11.236s)
[674.884606] [082/141] (417s left) gem_exec_schedule (wide)
[675.251475] Starting subtest: wide
[675.252957] Starting dynamic subtest: rcs0
[695.631793] Dynamic subtest rcs0: SUCCESS (20.378s)
[695.664928] Starting dynamic subtest: bcs0
[716.983742] Dynamic subtest bcs0: SUCCESS (21.348s)
[717.029580] Starting dynamic subtest: vcs0
[737.633853] Dynamic subtest vcs0: SUCCESS (20.649s)
[737.702555] Starting dynamic subtest: vecs0
[758.617436] Dynamic subtest vecs0: SUCCESS (20.981s)
[758.622572] Subtest wide: SUCCESS (83.365s)
[759.432059] [083/141] (333s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-submitted)
[759.649185] Starting subtest: invalid-multi-wait-available-unsubmitted-submitted
[759.653824] Subtest invalid-multi-wait-available-unsubmitted-submitted: SUCCESS (0.002s)
[759.745230] [084/141] (332s left) gem_ringfill (basic-all)
[760.252661] Starting subtest: basic-all
[761.450940] Subtest basic-all: SUCCESS (1.196s)
[761.581494] [085/141] (331s left) kms_properties (crtc-properties-legacy)
[761.899506] Starting subtest: crtc-properties-legacy
[763.212278] Subtest crtc-properties-legacy: SUCCESS (1.312s)
[763.553560] [086/141] (329s left) kms_ccs (pipe-a-bad-pixel-format-yf_tiled_ccs)
[763.907259] Starting subtest: pipe-A-bad-pixel-format-yf_tiled_ccs
[763.908849] Subtest pipe-A-bad-pixel-format-yf_tiled_ccs: SUCCESS (0.002s)
[764.653692] [087/141] (328s left) perf (global-sseu-config)
[764.966810] Starting subtest: global-sseu-config
[764.968346] Starting dynamic subtest: 0-rcs0
[765.082124] Dynamic subtest 0-rcs0: SUCCESS (0.114s)
[765.083525] Subtest global-sseu-config: SUCCESS (0.116s)
[765.138900] [088/141] (327s left) kms_content_protection (lic)
[765.473044] Starting subtest: LIC
[765.508037] Starting dynamic subtest: pipe-A-DP-1
[886.025808] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[886.824405] Closing watchdogs
[886.830347] Initializing watchdogs
[886.830529]   /dev/watchdog0
[886.860033] [089/141] (206s left) kms_chamelium_hpd (hdmi-hpd)
[887.276980] Subtest hdmi-hpd: SKIP (0.000s)
[887.586396] [090/141] (205s left) gem_eio (in-flight-immediate)
[887.825250] Starting subtest: in-flight-immediate
[888.761058] Subtest in-flight-immediate: SUCCESS (0.939s)
[888.962986] [091/141] (204s left) gem_eio (unwedge-stress)
[889.189822] Starting subtest: unwedge-stress
[922.546043] Subtest unwedge-stress: SUCCESS (33.358s)
[922.730907] [092/141] (170s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[922.938397] Starting subtest: invalid-signal-one-illegal-handle
[922.940124] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.001s)
[923.032053] [093/141] (170s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[923.357438] Starting subtest: 4-tiled-32bpp-rotate-180
[923.360541] Subtest 4-tiled-32bpp-rotate-180: SKIP (0.001s)
[923.482098] [094/141] (169s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[923.797730] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[923.800528] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[923.915529] [095/141] (169s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[924.231274] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[924.234765] Subtest fbcpsr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[924.349226] [096/141] (168s left) gem_exec_reloc (basic-write-wc-active)
[924.567670] Starting subtest: basic-write-wc-active
[924.708255] Subtest basic-write-wc-active: SUCCESS (0.140s)
[924.899019] [097/141] (168s left) i915_pm_rpm (debugfs-read)
[926.728270] Starting subtest: debugfs-read
[930.260708] Subtest debugfs-read: SUCCESS (3.530s)
[930.702283] [098/141] (162s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_dg2_rc_ccs)
[931.361415] Starting subtest: pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs
[931.365698] Subtest pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[931.737668] [099/141] (161s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[931.966874] Starting subtest: mixed-tiled-to-y-tiled-ccs
[931.968574] Starting dynamic subtest: smem
[932.274215] Dynamic subtest smem: SUCCESS (0.305s)
[932.275607] Subtest mixed-tiled-to-y-tiled-ccs: SUCCESS (0.307s)
[932.489490] [100/141] (160s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[932.811211] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[932.814596] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[932.921058] [101/141] (160s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[933.243589] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[933.246426] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[933.354634] [102/141] (160s left) gem_close_race (basic-process)
[933.591739] Starting subtest: basic-process
[933.698160] Subtest basic-process: SUCCESS (0.106s)
[933.904523] [103/141] (159s left) gen9_exec_parse (valid-registers)
[934.132928] Starting subtest: valid-registers
[934.316465] Subtest valid-registers: SUCCESS (0.182s)
[934.538089] [104/141] (159s left) i915_pm_rps (waitboost)
[934.753880] Starting subtest: waitboost
[937.325756] Subtest waitboost: SUCCESS (2.572s)
[937.421920] [105/141] (156s left) kms_color (invalid-ctm-matrix-sizes)
[937.722831] Starting subtest: invalid-ctm-matrix-sizes
[937.724250] Starting dynamic subtest: pipe-A
[937.730176] Dynamic subtest pipe-A: SUCCESS (0.007s)
[937.756257] Starting dynamic subtest: pipe-B
[937.757670] Dynamic subtest pipe-B: SUCCESS (0.010s)
[937.782987] Starting dynamic subtest: pipe-C
[937.784369] Dynamic subtest pipe-C: SUCCESS (0.012s)
[937.785728] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.032s)
[937.854927] [106/141] (155s left) kms_psr (sprite_render)
[938.061392] Subtest sprite_render: SKIP (0.000s)
[938.119899] [107/141] (155s left) gem_mmap_gtt (big-bo-tiledx)
[938.326448] Starting subtest: big-bo-tiledX
[939.387821] Subtest big-bo-tiledX: SUCCESS (1.061s)
[939.613007] [108/141] (154s left) v3d/v3d_get_param (base-params)
[940.024803] Subtest base-params: SKIP (0.000s)
[940.102807] [109/141] (153s left) prime_mmap_coherency (write)
[940.323478] Starting subtest: write
[945.749915] Subtest write: SUCCESS (5.424s)
[946.341988] [110/141] (147s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_mtl_rc_ccs_cc)
[946.767774] Starting subtest: pipe-A-crc-primary-basic-4_tiled_mtl_rc_ccs_cc
[946.770573] Subtest pipe-A-crc-primary-basic-4_tiled_mtl_rc_ccs_cc: SKIP (0.001s)
[947.400976] [111/141] (146s left) kms_flip (flip-vs-rmfb)
[947.703053] Starting subtest: flip-vs-rmfb
[947.711269] Starting dynamic subtest: A-DP1
[958.479905] Dynamic subtest A-DP1: SUCCESS (10.773s)
[958.508356] Starting dynamic subtest: B-DP1
[968.925163] Dynamic subtest B-DP1: SUCCESS (10.442s)
[968.952746] Starting dynamic subtest: C-DP1
[979.366423] Dynamic subtest C-DP1: SUCCESS (10.429s)
[979.367962] Subtest flip-vs-rmfb: SUCCESS (31.656s)
[979.783186] [112/141] (114s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[980.111840] Starting subtest: fbcpsr-tiling-y
[980.114789] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[980.227583] [113/141] (113s left) gem_wait (write-busy)
[980.473079] Starting subtest: write-busy
[980.474815] Starting dynamic subtest: all-engines
[981.004013] Dynamic subtest all-engines: SUCCESS (0.530s)
[981.036978] Starting dynamic subtest: rcs0
[981.525070] Dynamic subtest rcs0: SUCCESS (0.520s)
[981.558266] Starting dynamic subtest: bcs0
[982.048944] Dynamic subtest bcs0: SUCCESS (0.523s)
[982.075802] Starting dynamic subtest: vcs0
[982.567923] Dynamic subtest vcs0: SUCCESS (0.518s)
[982.596499] Starting dynamic subtest: vecs0
[983.088852] Dynamic subtest vecs0: SUCCESS (0.520s)
[983.090204] Subtest write-busy: SUCCESS (2.616s)
[983.345046] [114/141] (110s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[983.660664] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[986.077087] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (2.417s)
[986.260103] [115/141] (107s left) kms_addfb_basic (bad-pitch-128)
[986.468173] Starting subtest: bad-pitch-128
[986.469708] Subtest bad-pitch-128: SUCCESS (0.000s)
[986.693287] [116/141] (107s left) v3d/v3d_submit_cl (bad-bo)
[986.961698] Subtest bad-bo: SKIP (0.000s)
[987.043666] [117/141] (107s left) kms_lease (lessee_list)
[987.348662] Starting subtest: lessee_list
[987.350289] Starting dynamic subtest: pipe-A-DP-1
[987.352489] Dynamic subtest pipe-A-DP-1: SUCCESS (0.002s)
[987.385055] Starting dynamic subtest: pipe-B-DP-1
[987.386421] Dynamic subtest pipe-B-DP-1: SUCCESS (0.001s)
[987.412515] Starting dynamic subtest: pipe-C-DP-1
[987.413851] Dynamic subtest pipe-C-DP-1: SUCCESS (0.001s)
[987.415139] Subtest lessee_list: SUCCESS (0.016s)
[987.459976] [118/141] (106s left) kms_plane_lowres (tiling-yf)
[987.766494] Starting subtest: tiling-yf
[987.771046] Starting dynamic subtest: pipe-A-DP-1
[993.940849] Dynamic subtest pipe-A-DP-1: SUCCESS (6.172s)
[994.085354] Starting dynamic subtest: pipe-B-DP-1
[1000.162346] Dynamic subtest pipe-B-DP-1: SUCCESS (6.102s)
[1000.274782] Starting dynamic subtest: pipe-C-DP-1
[1004.330137] Dynamic subtest pipe-C-DP-1: SUCCESS (4.081s)
[1004.444032] Subtest tiling-yf: SUCCESS (16.679s)
[1004.908207] [119/141] ( 89s left) gem_mmap_gtt (cpuset-basic-small-copy)
[1005.120293] Starting subtest: cpuset-basic-small-copy
[1010.683664] Subtest cpuset-basic-small-copy: SUCCESS (5.564s)
[1010.792745] [120/141] ( 83s left) gem_userptr_blits (sync-unmap)
[1011.161506] Starting subtest: sync-unmap
[1011.211809] Subtest sync-unmap: SUCCESS (0.049s)
[1011.356845] [121/141] ( 83s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_gen12_rc_ccs)
[1011.908446] Starting subtest: pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs
[1011.915331] Subtest pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[1012.419232] [122/141] ( 82s left) gem_exec_schedule (preempt-user)
[1012.673547] Starting subtest: preempt-user
[1012.675113] Starting dynamic subtest: rcs0
[1012.748643] Dynamic subtest rcs0: SUCCESS (0.073s)
[1012.777069] Starting dynamic subtest: bcs0
[1012.844123] Dynamic subtest bcs0: SUCCESS (0.093s)
[1012.882262] Starting dynamic subtest: vcs0
[1012.910379] Dynamic subtest vcs0: SUCCESS (0.065s)
[1012.939388] Starting dynamic subtest: vecs0
[1012.977159] Dynamic subtest vecs0: SUCCESS (0.065s)
[1012.978654] Subtest preempt-user: SUCCESS (0.304s)
[1013.353141] [123/141] ( 81s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[1013.691395] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[1015.267957] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (1.579s)
[1015.638010] [124/141] ( 78s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[1015.940602] Starting subtest: basic-flip-after-cursor-varying-size
[1017.017410] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.078s)
[1017.357825] [125/141] ( 77s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[1017.668495] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[1017.671225] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[1017.771937] [126/141] ( 76s left) kms_big_fb (linear-32bpp-rotate-180)
[1018.108559] Starting subtest: linear-32bpp-rotate-180
[1023.854653] Subtest linear-32bpp-rotate-180: SUCCESS (5.745s)
[1024.407779] [127/141] ( 70s left) kms_prop_blob (blob-prop-core)
[1024.634669] Starting subtest: blob-prop-core
[1024.636217] Subtest blob-prop-core: SUCCESS (0.000s)
[1024.741802] [128/141] ( 70s left) dmabuf_sync_file (import-multiple-read-only)
[1024.955937] Starting subtest: import-multiple-read-only
[1024.970744] Subtest import-multiple-read-only: SUCCESS (0.014s)
[1025.055271] [129/141] ( 69s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[1025.387196] Starting subtest: fbcpsr-1p-rte
[1025.390540] Subtest fbcpsr-1p-rte: SKIP (0.000s)
[1025.572159] [130/141] ( 69s left) gem_workarounds (suspend-resume-context)
[1025.818670] Starting subtest: suspend-resume-context
[1043.653977] Subtest suspend-resume-context: SUCCESS (2.071s)
[1049.847485] [131/141] ( 66s left) gem_exec_reloc (basic-cpu-read-noreloc)
[1050.067766] Starting subtest: basic-cpu-read-noreloc
[1050.079811] Subtest basic-cpu-read-noreloc: SUCCESS (0.011s)
[1050.282132] [132/141] ( 66s left) kms_ccs (pipe-b-bad-aux-stride-y_tiled_gen12_rc_ccs)
[1050.868247] Starting subtest: pipe-B-bad-aux-stride-y_tiled_gen12_rc_ccs
[1050.871254] Subtest pipe-B-bad-aux-stride-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[1051.331996] [133/141] ( 65s left) perf_pmu (event-wait)
[1051.547559] Starting subtest: event-wait
[1051.549227] Starting dynamic subtest: rcs0
[1051.552598] Dynamic subtest rcs0: SKIP (0.000s)
[1051.556124] Subtest event-wait: SKIP (0.002s)
[1051.647963] [134/141] ( 65s left) sw_sync (timeline_closed_signaled)
[1051.853226] Starting subtest: timeline_closed_signaled
[1051.855005] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[1051.931081] [135/141] ( 64s left) gem_exec_reloc (basic-wc-active)
[1052.151591] Starting subtest: basic-wc-active
[1052.294213] Subtest basic-wc-active: SUCCESS (0.142s)
[1052.498876] [136/141] ( 64s left) gem_lmem_swapping (parallel-multi)
[1052.715415] Subtest parallel-multi: SKIP (0.000s)
[1052.779830] [137/141] ( 64s left) kms_vblank (pipe-a-wait-forked-busy-hang)
[1053.092576] Starting subtest: pipe-A-wait-forked-busy-hang
[1060.310574] Subtest pipe-A-wait-forked-busy-hang: SUCCESS (7.222s)
[1060.659484] [138/141] ( 56s left) kms_ccs (pipe-b-crc-primary-rotation-180-y_tiled_gen12_mc_ccs)
[1061.193870] Starting subtest: pipe-B-crc-primary-rotation-180-y_tiled_gen12_mc_ccs
[1061.196946] Subtest pipe-B-crc-primary-rotation-180-y_tiled_gen12_mc_ccs: SKIP (0.001s)
[1061.701956] [139/141] ( 55s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[1062.036659] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[1063.416795] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.380s)
[1063.636719] [140/141] ( 53s left) kms_chamelium_color (ctm-limited-range)
[1063.944144] Subtest ctm-limited-range: SKIP (0.000s)
[1063.999436] [141/141] ( 53s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[1064.314297] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[1064.317019] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[1064.414210] Closing watchdogs
Done.