[42.473695] Initializing watchdogs
[42.473735]   /dev/watchdog0
[42.480173] [001/142] (960s left) kms_lease (lease_unleased_crtc)
[42.575968] Starting subtest: lease_unleased_crtc
[42.578553] Starting dynamic subtest: pipe-A-eDP-1
[42.877226] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.300s)
[42.886768] Starting dynamic subtest: pipe-B-eDP-1
[43.702031] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.824s)
[43.715112] Starting dynamic subtest: pipe-C-eDP-1
[43.720824] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.015s)
[43.728410] Starting dynamic subtest: pipe-D-eDP-1
[43.734069] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.012s)
[43.736514] Subtest lease_unleased_crtc: SUCCESS (1.157s)
[44.049684] [002/142] (958s left) kms_dsc (dsc-with-output-formats-with-bpc)
[44.118804] Starting subtest: dsc-with-output-formats-with-bpc
[44.123698] Subtest dsc-with-output-formats-with-bpc: SKIP (0.001s)
[44.177227] [003/142] (958s left) api_intel_allocator (reserve-simple)
[44.248859] Starting subtest: reserve-simple
[44.254659] Subtest reserve-simple: SUCCESS (0.007s)
[44.339768] [004/142] (958s left) vgem_basic (bad-handle)
[44.454996] Starting subtest: bad-handle
[44.457374] Subtest bad-handle: SUCCESS (0.000s)
[44.501262] [005/142] (958s left) feature_discovery (display-4x)
[44.582989] Starting subtest: display-4x
[44.587795] Subtest display-4x: SKIP (0.000s)
[44.640754] [006/142] (958s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[44.721907] Starting subtest: x-tiled-16bpp-rotate-0
[46.048517] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.329s)
[46.189412] [007/142] (956s left) gem_render_copy (yf-tiled-to-vebox-linear)
[46.250660] Starting subtest: yf-tiled-to-vebox-linear
[46.254684] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[46.424406] [008/142] (956s left) gem_userptr_blits (coherency-sync)
[46.497353] Starting subtest: coherency-sync
[46.813066] Subtest coherency-sync: SKIP (0.293s)
[46.899445] [009/142] (956s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[47.048867] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[47.053143] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[47.128574] [010/142] (956s left) gem_exec_whisper (basic-forked)
[47.203998] Starting subtest: basic-forked
[69.037531] Subtest basic-forked: SUCCESS (21.831s)
[69.216506] [011/142] (934s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-blt)
[69.313046] Starting subtest: fbc-1p-offscren-pri-indfb-draw-blt
[72.244614] Subtest fbc-1p-offscren-pri-indfb-draw-blt: SUCCESS (2.937s)
[72.373141] [012/142] (930s left) kms_ccs (pipe-a-crc-primary-rotation-180-y_tiled_gen12_rc_ccs)
[72.759291] Starting subtest: pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs
[72.764588] Subtest pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs: SKIP (0.001s)
[73.668983] [013/142] (929s left) kms_vblank (pipe-a-query-busy)
[73.728676] Starting subtest: pipe-A-query-busy
[75.603250] Subtest pipe-A-query-busy: SUCCESS (1.877s)
[76.550926] [014/142] (926s left) kms_psr (psr2_primary_mmap_cpu)
[76.644546] Starting subtest: psr2_primary_mmap_cpu
[78.444570] Subtest psr2_primary_mmap_cpu: SUCCESS (1.799s)
[79.364787] [015/142] (923s left) i915_pm_freq_api (freq-reset-multiple)
[79.428872] Starting subtest: freq-reset-multiple
[79.431388] Starting dynamic subtest: gt0
[89.987062] Dynamic subtest gt0: SUCCESS (10.556s)
[89.992651] Starting dynamic subtest: gt1
[99.480972] Dynamic subtest gt1: SUCCESS (9.493s)
[99.484033] Subtest freq-reset-multiple: SUCCESS (20.050s)
[99.557586] [016/142] (903s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[99.654754] Starting subtest: compare-crc-sanitycheck-xr24
[99.657200] Starting dynamic subtest: pipe-A-eDP-1
[100.314020] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.657s)
[100.327605] Starting dynamic subtest: pipe-B-eDP-1
[101.765107] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.449s)
[101.779659] Starting dynamic subtest: pipe-C-eDP-1
[103.074461] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.304s)
[103.086859] Starting dynamic subtest: pipe-D-eDP-1
[104.367359] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.296s)
[104.370215] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.714s)
[105.272517] [017/142] (898s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[105.324780] Starting subtest: plane-all-transition-nonblocking-fencing
[105.327103] Starting dynamic subtest: pipe-A-eDP-1
[107.831469] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.506s)
[108.144273] Starting dynamic subtest: pipe-B-eDP-1
[111.471833] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.341s)
[111.772466] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.446s)
[112.696918] [018/142] (890s left) kms_ccs (pipe-d-crc-primary-basic-y_tiled_gen12_mc_ccs)
[113.117543] Starting subtest: pipe-D-crc-primary-basic-y_tiled_gen12_mc_ccs
[113.123108] Subtest pipe-D-crc-primary-basic-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[113.975847] [019/142] (889s left) kms_chamelium_color (ctm-negative)
[114.068271] Subtest ctm-negative: SKIP (0.000s)
[114.085751] [020/142] (889s left) i915_pm_rps (basic-api)
[114.135029] Starting subtest: basic-api
[114.140049] Subtest basic-api: SKIP (0.000s)
[114.196158] [021/142] (889s left) kms_ccs (pipe-a-random-ccs-data-yf_tiled_ccs)
[114.565511] Starting subtest: pipe-A-random-ccs-data-yf_tiled_ccs
[114.572813] Subtest pipe-A-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[115.434390] [022/142] (888s left) gem_tiling_max_stride
[115.493323] [023/142] (888s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[115.564244] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[116.638327] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (1.071s)
[117.568867] [024/142] (885s left) vc4/vc4_perfmon (create-perfmon-0)
[117.668454] Subtest create-perfmon-0: SKIP (0.000s)
[117.717592] [025/142] (885s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[117.796279] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[117.798734] Starting dynamic subtest: pipe-A-default-mode
[118.083242] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[118.088184] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.286s)
[118.981199] [026/142] (884s left) vgem_basic (second-client)
[119.056401] Starting subtest: second-client
[119.078748] Subtest second-client: SUCCESS (0.022s)
[119.127785] [027/142] (884s left) sw_sync (alloc_merge_fence)
[119.175800] Starting subtest: alloc_merge_fence
[119.178204] Subtest alloc_merge_fence: SUCCESS (0.000s)
[119.222538] [028/142] (884s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[119.300965] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[123.952952] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (4.655s)
[124.028591] [029/142] (879s left) gem_partial_pwrite_pread (writes-after-reads-display)
[124.105346] Subtest writes-after-reads-display: SKIP (0.000s)
[124.163642] [030/142] (879s left) kms_chamelium_audio (hdmi-audio)
[124.531803] Subtest hdmi-audio: SKIP (0.000s)
[125.408092] [031/142] (878s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[125.485186] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[130.741948] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (5.261s)
[130.865716] [032/142] (872s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[130.927669] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[130.948549] [033/142] (872s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move)
[131.038419] Starting subtest: psr-1p-primscrn-spr-indfb-move
[136.682314] Subtest psr-1p-primscrn-spr-indfb-move: SUCCESS (5.646s)
[136.795335] [034/142] (867s left) syncobj_wait (invalid-reset-illegal-handle)
[136.852745] Starting subtest: invalid-reset-illegal-handle
[136.855041] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[136.918759] [035/142] (866s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[136.995424] Starting subtest: cursorA-vs-flipA-toggle
[148.145956] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.149s)
[149.058879] [036/142] (854s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[149.267322] Starting subtest: cursor-rapid-movement-max-size
[149.274480] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[149.364214] [037/142] (854s left) prime_vgem (basic-fence-mmap)
[149.446303] Starting subtest: basic-fence-mmap
[149.452151] Subtest basic-fence-mmap: SKIP (0.000s)
[149.533816] [038/142] (854s left) kms_vblank (pipe-b-wait-forked-busy-hang)
[149.611564] Starting subtest: pipe-B-wait-forked-busy-hang
[162.450760] Subtest pipe-B-wait-forked-busy-hang: SUCCESS (12.842s)
[163.382042] [039/142] (840s left) i915_pm_rpm (dpms-mode-unset-non-lpsp)
[165.618858] Starting subtest: dpms-mode-unset-non-lpsp
[168.990076] Subtest dpms-mode-unset-non-lpsp: SKIP (3.368s)
[169.820465] [040/142] (834s left) gem_ctx_persistence (userptr)
[169.880958] Starting subtest: userptr
[170.013647] Subtest userptr: SUCCESS (0.132s)
[170.085215] [041/142] (833s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[170.168206] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[170.172473] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[170.251693] [042/142] (833s left) syncobj_wait (wait-any-snapshot)
[170.308651] Starting subtest: wait-any-snapshot
[170.391583] Subtest wait-any-snapshot: SUCCESS (0.083s)
[170.461610] [043/142] (833s left) kms_plane_scaling (plane-downscale-with-modifiers-factor-0-25)
[170.523290] Starting subtest: plane-downscale-with-modifiers-factor-0-25
[170.525801] Starting dynamic subtest: pipe-A-eDP-1
[170.555146] Dynamic subtest pipe-A-eDP-1: SKIP (0.026s)
[170.563019] Starting dynamic subtest: pipe-B-eDP-1
[170.586841] Dynamic subtest pipe-B-eDP-1: SKIP (0.033s)
[170.594018] Starting dynamic subtest: pipe-C-eDP-1
[170.635866] Dynamic subtest pipe-C-eDP-1: SKIP (0.048s)
[170.642059] Starting dynamic subtest: pipe-D-eDP-1
[170.675721] Dynamic subtest pipe-D-eDP-1: SKIP (0.034s)
[170.680394] Subtest plane-downscale-with-modifiers-factor-0-25: SKIP (0.145s)
[170.777231] [044/142] (833s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[170.859407] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[175.997670] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SUCCESS (5.141s)
[176.111497] [045/142] (827s left) prime_busy (after)
[176.167924] Starting subtest: after
[176.173691] Starting dynamic subtest: rcs0
[176.204935] Dynamic subtest rcs0: SUCCESS (0.031s)
[176.219759] Starting dynamic subtest: bcs0
[176.248358] Dynamic subtest bcs0: SUCCESS (0.041s)
[176.262768] Starting dynamic subtest: vcs0
[176.287422] Dynamic subtest vcs0: SUCCESS (0.038s)
[176.301818] Starting dynamic subtest: vcs1
[176.307172] Dynamic subtest vcs1: SUCCESS (0.019s)
[176.321389] Starting dynamic subtest: vecs0
[176.332076] Dynamic subtest vecs0: SUCCESS (0.024s)
[176.345841] Starting dynamic subtest: ccs0
[176.357434] Dynamic subtest ccs0: SUCCESS (0.024s)
[176.469102] Subtest after: SUCCESS (0.302s)
[176.537846] [046/142] (827s left) gem_exec_fence (syncobj-timeline-wait)
[177.107373] Starting subtest: syncobj-timeline-wait
[178.507802] Subtest syncobj-timeline-wait: SUCCESS (1.401s)
[180.522343] [047/142] (823s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[180.579060] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[180.581112] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[180.629801] [048/142] (823s left) i915_suspend (sysfs-reader)
[180.673159] Starting subtest: sysfs-reader
[198.531378] Subtest sysfs-reader: SUCCESS (3.125s)
[198.567020] [049/142] (820s left) kms_hdr (invalid-metadata-sizes)
[198.634534] Starting subtest: invalid-metadata-sizes
[198.638488] Subtest invalid-metadata-sizes: SKIP (0.000s)
[198.681449] [050/142] (820s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[198.752431] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[198.756379] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[198.850829] [051/142] (820s left) gem_mmap_gtt (basic-small-copy-xy)
[198.904614] Subtest basic-small-copy-XY: SKIP (0.000s)
[198.941945] [052/142] (820s left) prime_vgem (coherency-blt)
[198.983022] Starting subtest: coherency-blt
[199.033569] Subtest coherency-blt: FAIL (0.021s)
[199.117929] [053/142] (819s left) gen9_exec_parse (bb-large)
[199.177383] Subtest bb-large: SKIP (0.000s)
[199.204268] [054/142] (819s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_gen12_rc_ccs_cc)
[199.585585] Starting subtest: pipe-C-bad-rotation-90-y_tiled_gen12_rc_ccs_cc
[199.591329] Subtest pipe-C-bad-rotation-90-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[200.463284] [055/142] (818s left) drm_fdinfo (all-busy-check-all)
[200.537357] Starting subtest: all-busy-check-all
[200.542151] Subtest all-busy-check-all: SKIP (0.002s)
[200.604966] [056/142] (818s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[200.704762] Starting subtest: primary-y-tiled-reflect-x-180
[200.764276] Subtest primary-y-tiled-reflect-x-180: SKIP (0.059s)
[200.874210] [057/142] (818s left) kms_rotation_crc (cursor-rotation-180)
[200.939194] Starting subtest: cursor-rotation-180
[202.545553] Subtest cursor-rotation-180: SUCCESS (1.606s)
[203.866264] [058/142] (815s left) perf (buffer-fill)
[204.011825] Starting subtest: buffer-fill
[204.014257] Starting dynamic subtest: 0-rcs0
[204.946550] Dynamic subtest 0-rcs0: FAIL (0.792s)
[204.954337] Starting dynamic subtest: 1-vcs0
[208.245961] Dynamic subtest 1-vcs0: SUCCESS (3.441s)
[208.248993] Subtest buffer-fill: FAIL (4.234s)
[208.282983] [059/142] (810s left) gem_madvise (dontneed-before-exec)
[208.345799] Starting subtest: dontneed-before-exec
[208.353215] Subtest dontneed-before-exec: SKIP (0.001s)
[208.403695] [060/142] (810s left) kms_draw_crc (draw-method-mmap-wc)
[208.497805] Starting subtest: draw-method-mmap-wc
[208.500233] Starting dynamic subtest: xrgb8888-untiled
[208.742254] Dynamic subtest xrgb8888-untiled: SUCCESS (0.246s)
[208.756236] Starting dynamic subtest: rgb565-untiled
[209.000510] Dynamic subtest rgb565-untiled: SUCCESS (0.257s)
[209.018548] Starting dynamic subtest: xrgb2101010-untiled
[209.241828] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.240s)
[209.257838] Starting dynamic subtest: xrgb8888-xtiled
[209.385855] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.143s)
[209.402505] Starting dynamic subtest: rgb565-xtiled
[209.530273] Dynamic subtest rgb565-xtiled: SUCCESS (0.143s)
[209.544598] Starting dynamic subtest: xrgb2101010-xtiled
[209.674736] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.143s)
[209.691771] Starting dynamic subtest: xrgb8888-4tiled
[209.842301] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.166s)
[209.858634] Starting dynamic subtest: rgb565-4tiled
[209.986199] Dynamic subtest rgb565-4tiled: SUCCESS (0.143s)
[209.999380] Starting dynamic subtest: xrgb2101010-4tiled
[210.131872] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.143s)
[210.133996] Subtest draw-method-mmap-wc: SUCCESS (1.636s)
[210.220293] [061/142] (809s left) v3d/v3d_submit_cl (job-perfmon)
[210.307444] Subtest job-perfmon: SKIP (0.000s)
[210.360186] [062/142] (808s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[210.427757] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[210.444187] [063/142] (808s left) kms_cursor_crc (cursor-random-64x64)
[210.534714] Starting subtest: cursor-random-64x64
[210.542522] Starting dynamic subtest: pipe-A-eDP-1
[213.952212] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.409s)
[213.992719] Starting dynamic subtest: pipe-D-eDP-1
[218.058670] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.083s)
[218.061256] Subtest cursor-random-64x64: SUCCESS (7.524s)
[218.955426] [064/142] (800s left) kms_vblank (pipe-b-ts-continuation-modeset)
[219.009556] Starting subtest: pipe-B-ts-continuation-modeset
[221.780567] Subtest pipe-B-ts-continuation-modeset: SUCCESS (2.772s)
[222.682490] [065/142] (796s left) gem_exec_flush (basic-wb-prw-default)
[222.748816] Starting subtest: basic-wb-prw-default
[228.196699] Subtest basic-wb-prw-default: SUCCESS (5.451s)
[228.383895] [066/142] (791s left) kms_pm_backlight (fade-with-dpms)
[228.457064] Starting subtest: fade-with-dpms
[230.729013] Starting dynamic subtest: eDP-1
[233.394466] Dynamic subtest eDP-1: SUCCESS (2.665s)
[233.396762] Subtest fade-with-dpms: SUCCESS (4.939s)
[234.326982] [067/142] (785s left) gem_exec_params (invalid-ring2)
[234.398088] Starting subtest: invalid-ring2
[234.400565] Subtest invalid-ring2: SUCCESS (0.000s)
[234.480855] [068/142] (785s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[234.537793] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[234.539724] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[234.600849] [069/142] (784s left) gem_mmap_wc (bad-offset)
[234.655939] Subtest bad-offset: SKIP (0.000s)
[234.680098] [070/142] (784s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs)
[235.075800] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs
[235.081229] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs: SKIP (0.001s)
[235.930601] [071/142] (783s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[235.999074] Starting subtest: invalid-single-wait-all-unsubmitted
[236.001448] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[236.068198] [072/142] (783s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[236.165360] Starting subtest: y-tiled-8bpp-rotate-270
[236.169510] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[236.254090] [073/142] (783s left) kms_async_flips (alternate-sync-async-flip)
[236.310029] Starting subtest: alternate-sync-async-flip
[236.692250] Starting dynamic subtest: pipe-A-eDP-1
[239.511964] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.820s)
[239.898656] Starting dynamic subtest: pipe-B-eDP-1
[242.746814] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.859s)
[243.128300] Starting dynamic subtest: pipe-C-eDP-1
[245.946286] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.832s)
[246.335270] Starting dynamic subtest: pipe-D-eDP-1
[249.209631] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.890s)
[249.211772] Subtest alternate-sync-async-flip: SUCCESS (12.903s)
[250.523547] [074/142] (769s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_rc_ccs_cc)
[250.875189] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc
[250.880160] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.001s)
[251.802097] [075/142] (767s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[251.898980] Starting subtest: cursorB-vs-flipB-varying-size
[251.903726] Subtest cursorB-vs-flipB-varying-size: SKIP (0.002s)
[252.047350] [076/142] (767s left) kms_vblank (pipe-d-ts-continuation-idle)
[252.099389] Starting subtest: pipe-D-ts-continuation-idle
[253.678216] Subtest pipe-D-ts-continuation-idle: SUCCESS (1.579s)
[254.619991] [077/142] (765s left) gem_pxp (reject-modify-context-protection-off-3)
[257.203692] Subtest reject-modify-context-protection-off-3: SKIP (0.000s)
[257.270295] [078/142] (762s left) vc4/vc4_dmabuf_poll (poll-read-waits-until-write-done)
[257.367078] Subtest poll-read-waits-until-write-done: SKIP (0.000s)
[257.420598] [079/142] (762s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[257.485839] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[257.510076] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.025s)
[257.586678] [080/142] (762s left) kms_busy (basic-hang)
[257.667377] Starting subtest: basic-hang
[257.669882] Starting dynamic subtest: flip-pipe-A
[258.291906] Dynamic subtest flip-pipe-A: SUCCESS (0.621s)
[258.312209] Starting dynamic subtest: modeset-pipe-A
[261.973875] Dynamic subtest modeset-pipe-A: SUCCESS (3.681s)
[261.995601] Starting dynamic subtest: flip-pipe-D
[263.348544] Dynamic subtest flip-pipe-D: SUCCESS (1.370s)
[263.367146] Starting dynamic subtest: modeset-pipe-D
[267.019369] Dynamic subtest modeset-pipe-D: SUCCESS (3.668s)
[267.021954] Subtest basic-hang: SUCCESS (9.352s)
[267.931710] [081/142] (751s left) kms_vblank (pipe-a-query-busy-hang)
[267.994619] Starting subtest: pipe-A-query-busy-hang
[279.219124] Subtest pipe-A-query-busy-hang: SUCCESS (11.223s)
[280.156019] [082/142] (739s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[280.210940] Starting subtest: modeset-transition-nonblocking-fencing
[280.213503] Starting dynamic subtest: 1x-outputs
[303.339338] Dynamic subtest 1x-outputs: SUCCESS (23.130s)
[303.645580] Subtest modeset-transition-nonblocking-fencing: SUCCESS (23.434s)
[304.539021] [083/142] (715s left) gem_set_tiling_vs_pwrite
[304.624996] [084/142] (715s left) gem_exec_schedule (promotion)
[304.695488] Starting subtest: promotion
[304.697380] Starting dynamic subtest: rcs0
[304.774270] Dynamic subtest rcs0: SUCCESS (0.084s)
[304.792275] Starting dynamic subtest: bcs0
[304.845949] Dynamic subtest bcs0: SUCCESS (0.069s)
[304.864518] Starting dynamic subtest: vcs0
[304.942204] Dynamic subtest vcs0: SUCCESS (0.096s)
[304.959387] Starting dynamic subtest: vcs1
[305.013746] Dynamic subtest vcs1: SUCCESS (0.070s)
[305.032536] Starting dynamic subtest: vecs0
[305.076233] Dynamic subtest vecs0: SUCCESS (0.061s)
[305.094074] Starting dynamic subtest: ccs0
[305.159031] Dynamic subtest ccs0: SUCCESS (0.082s)
[305.161476] Subtest promotion: SUCCESS (0.470s)
[306.282459] [085/142] (713s left) perf_pmu (busy-accuracy-98)
[306.346089] Starting subtest: busy-accuracy-98
[306.348523] Starting dynamic subtest: rcs0
[309.462938] Dynamic subtest rcs0: FAIL (3.059s)
[309.480560] Starting dynamic subtest: bcs0
[312.519501] Dynamic subtest bcs0: FAIL (3.062s)
[312.537130] Starting dynamic subtest: vcs0
[315.518407] Dynamic subtest vcs0: SUCCESS (3.042s)
[315.538803] Starting dynamic subtest: vcs1
[318.564232] Dynamic subtest vcs1: SUCCESS (3.046s)
[318.582829] Starting dynamic subtest: vecs0
[321.611275] Dynamic subtest vecs0: SUCCESS (3.046s)
[321.627954] Starting dynamic subtest: ccs0
[324.724591] Dynamic subtest ccs0: FAIL (3.063s)
[324.726643] Subtest busy-accuracy-98: FAIL (18.327s)
[324.786481] [086/142] (695s left) gem_ccs (block-copy-uncompressed)
[324.832514] Starting subtest: block-copy-uncompressed
[324.835168] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[324.851052] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.019s)
[324.863816] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[324.872806] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.021s)
[324.890630] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[324.893351] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[324.903739] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[324.910701] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.019s)
[324.913067] Subtest block-copy-uncompressed: SUCCESS (0.079s)
[325.024692] [087/142] (695s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[325.113968] Starting subtest: yf-tiled-8bpp-rotate-0
[325.118444] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[325.205618] [088/142] (694s left) kms_ccs (pipe-c-bad-aux-stride-4_tiled_mtl_mc_ccs)
[325.604104] Starting subtest: pipe-C-bad-aux-stride-4_tiled_mtl_mc_ccs
[325.685657] Subtest pipe-C-bad-aux-stride-4_tiled_mtl_mc_ccs: SUCCESS (0.082s)
[326.449588] [089/142] (693s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[326.492903] Starting subtest: framebuffer-vs-set-tiling
[326.497656] Subtest framebuffer-vs-set-tiling: SKIP (0.000s)
[326.594396] [090/142] (693s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[326.947742] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[327.796541] [091/142] (692s left) syncobj_timeline (reset-unsignaled)
[327.873978] Starting subtest: reset-unsignaled
[327.876383] Subtest reset-unsignaled: SUCCESS (0.000s)
[327.941487] [092/142] (692s left) gem_ctx_engines (execute-oneforall)
[328.003886] Starting subtest: execute-oneforall
[328.159836] Subtest execute-oneforall: SUCCESS (0.155s)
[329.338425] [093/142] (690s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[329.400039] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[329.402488] Starting dynamic subtest: pipe-A-eDP-1
[329.407153] Dynamic subtest pipe-A-eDP-1: SKIP (0.003s)
[329.415960] Starting dynamic subtest: pipe-B-eDP-1
[329.421509] Dynamic subtest pipe-B-eDP-1: SKIP (0.014s)
[329.430045] Starting dynamic subtest: pipe-C-eDP-1
[329.440016] Dynamic subtest pipe-C-eDP-1: SKIP (0.015s)
[329.448873] Starting dynamic subtest: pipe-D-eDP-1
[329.454021] Dynamic subtest pipe-D-eDP-1: SKIP (0.014s)
[329.458533] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.049s)
[329.549645] [094/142] (690s left) kms_ccs (pipe-a-bad-aux-stride-y_tiled_gen12_rc_ccs_cc)
[329.927666] Starting subtest: pipe-A-bad-aux-stride-y_tiled_gen12_rc_ccs_cc
[329.933630] Subtest pipe-A-bad-aux-stride-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[330.842219] [095/142] (689s left) gem_exec_balancer (hang)
[331.082770] Starting subtest: hang
[332.449853] Subtest hang: SUCCESS (1.368s)
[332.519269] [096/142] (687s left) gen7_exec_parse (cmd-crossing-page)
[332.577506] Subtest cmd-crossing-page: SKIP (0.000s)
[332.597815] [097/142] (687s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[332.675042] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[332.677621] Starting dynamic subtest: pipe-A-default-mode
[335.591606] Dynamic subtest pipe-A-default-mode: SUCCESS (2.914s)
[335.594067] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (2.916s)
[336.538947] [098/142] (683s left) gem_ctx_param (root-set)
[336.585371] Starting subtest: root-set
[336.587939] Subtest root-set: SUCCESS (0.000s)
[336.656971] [099/142] (683s left) syncobj_wait (multi-wait-for-submit-submitted)
[336.718923] Starting subtest: multi-wait-for-submit-submitted
[336.823409] Subtest multi-wait-for-submit-submitted: SUCCESS (0.105s)
[336.896830] [100/142] (683s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[336.968169] Starting subtest: multi-wait-for-submit-available-submitted
[336.970570] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.000s)
[337.035270] [101/142] (683s left) syncobj_timeline (invalid-single-wait-all-available-unsubmitted)
[337.096686] Starting subtest: invalid-single-wait-all-available-unsubmitted
[337.098717] Subtest invalid-single-wait-all-available-unsubmitted: SUCCESS (0.000s)
[337.163261] [102/142] (683s left) kms_ccs (pipe-d-bad-aux-stride-y_tiled_gen12_mc_ccs)
[337.614239] Starting subtest: pipe-D-bad-aux-stride-y_tiled_gen12_mc_ccs
[337.619021] Subtest pipe-D-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[338.460635] [103/142] (682s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-blt)
[338.543384] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-blt
[341.554224] Subtest fbc-1p-offscren-pri-shrfb-draw-blt: SUCCESS (3.015s)
[341.685498] [104/142] (678s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[341.744846] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[346.958750] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (5.215s)
[347.095715] [105/142] (673s left) vgem_basic (mmap)
[347.140145] Starting subtest: mmap
[347.145914] Subtest mmap: SUCCESS (0.007s)
[347.206865] [106/142] (673s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[347.299644] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[347.303764] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[347.384980] [107/142] (673s left) sysfs_timeslice_duration (invalid)
[347.457541] Starting subtest: invalid
[347.460148] Starting dynamic subtest: vecs0
[347.462654] Dynamic subtest vecs0: SUCCESS (0.000s)
[347.475031] Starting dynamic subtest: ccs0
[347.477022] Dynamic subtest ccs0: SUCCESS (0.000s)
[347.484597] Starting dynamic subtest: vcs0
[347.486389] Dynamic subtest vcs0: SUCCESS (0.001s)
[347.493376] Starting dynamic subtest: bcs0
[347.495468] Dynamic subtest bcs0: SUCCESS (0.000s)
[347.502076] Starting dynamic subtest: rcs0
[347.504229] Dynamic subtest rcs0: SUCCESS (0.000s)
[347.510615] Starting dynamic subtest: vcs1
[347.512770] Dynamic subtest vcs1: SUCCESS (0.000s)
[347.514879] Subtest invalid: SUCCESS (0.011s)
[347.528652] [108/142] (673s left) v3d/v3d_perfmon (get-values-invalid-pad)
[347.593502] Subtest get-values-invalid-pad: SKIP (0.000s)
[347.647148] [109/142] (673s left) gem_exec_balancer (nop)
[347.694535] Starting subtest: nop
[393.958328] Subtest nop: SUCCESS (46.263s)
[394.231651] [110/142] (626s left) kms_psr (dpms)
[394.312436] Starting subtest: dpms
[397.371911] Subtest dpms: SUCCESS (3.056s)
[398.299848] [111/142] (622s left) kms_frontbuffer_tracking (psr-suspend)
[398.408046] Starting subtest: psr-suspend
[435.138948] Subtest psr-suspend: SUCCESS (6.117s)
[435.242393] [112/142] (616s left) kms_psr (psr2_sprite_blt)
[435.304882] Starting subtest: psr2_sprite_blt
[437.124469] Subtest psr2_sprite_blt: SUCCESS (1.820s)
[438.071181] [113/142] (613s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[438.144624] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[438.148950] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[438.239803] [114/142] (613s left) kms_pm_dc (dc9-dpms)
[439.330494] Starting subtest: dc9-dpms
[440.462057] Subtest dc9-dpms: SUCCESS (1.132s)
[440.525078] [115/142] (610s left) kms_vblank (pipe-b-ts-continuation-idle)
[440.588929] Starting subtest: pipe-B-ts-continuation-idle
[442.118665] Subtest pipe-B-ts-continuation-idle: SUCCESS (1.529s)
[443.062455] [116/142] (608s left) gem_lmem_swapping (parallel-random)
[443.129870] Subtest parallel-random: SKIP (0.000s)
[443.169572] [117/142] (608s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[443.227094] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[443.938241] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.716s)
[444.136762] [118/142] (607s left) gen3_render_linear_blits
[444.242990] [119/142] (607s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[444.285042] Starting subtest: single-wait-for-submit-unsubmitted
[444.383626] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.101s)
[444.455432] [120/142] (607s left) kms_rotation_crc (sprite-rotation-270)
[444.530045] Starting subtest: sprite-rotation-270
[444.535408] Subtest sprite-rotation-270: SKIP (0.001s)
[444.589018] [121/142] (607s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[444.656387] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[445.242506] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.592s)
[445.391841] [122/142] (606s left) kms_universal_plane (universal-plane-pageflip-windowed-pipe-a)
[445.469142] Starting subtest: universal-plane-pageflip-windowed-pipe-A
[446.045795] Subtest universal-plane-pageflip-windowed-pipe-A: SUCCESS (0.579s)
[446.964348] [123/142] (604s left) gem_exec_schedule (semaphore-codependency)
[447.027778] Starting subtest: semaphore-codependency
[447.036252] Subtest semaphore-codependency: SUCCESS (0.008s)
[447.370530] [124/142] (604s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_mtl_mc_ccs)
[447.805186] Starting subtest: pipe-B-random-ccs-data-4_tiled_mtl_mc_ccs
[448.961463] Subtest pipe-B-random-ccs-data-4_tiled_mtl_mc_ccs: SUCCESS (1.158s)
[450.159110] [125/142] (601s left) gem_tiled_partial_pwrite_pread (reads)
[450.234936] Subtest reads: SKIP (0.000s)
[450.278554] [126/142] (601s left) kms_cursor_crc (cursor-onscreen-128x42)
[450.405957] Starting subtest: cursor-onscreen-128x42
[450.411693] Starting dynamic subtest: pipe-A-eDP-1
[452.543612] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.133s)
[452.591770] Starting dynamic subtest: pipe-D-eDP-1
[455.476327] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.892s)
[455.478917] Subtest cursor-onscreen-128x42: SUCCESS (5.069s)
[456.366092] [127/142] (595s left) kms_ccs (pipe-c-missing-ccs-buffer-4_tiled_mtl_mc_ccs)
[456.741098] Starting subtest: pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs
[456.766320] Subtest pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SUCCESS (0.025s)
[457.576780] [128/142] (594s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_ccs)
[457.966105] Starting subtest: pipe-B-bad-pixel-format-y_tiled_ccs
[457.971427] Subtest pipe-B-bad-pixel-format-y_tiled_ccs: SKIP (0.000s)
[458.863480] [129/142] (593s left) gem_workarounds (suspend-resume)
[458.938493] Starting subtest: suspend-resume
[475.549962] Subtest suspend-resume: SUCCESS (1.171s)
[475.659201] [130/142] (591s left) gem_exec_fence (long-history)
[476.093427] Starting subtest: long-history
[483.229916] Subtest long-history: SUCCESS (7.138s)
[483.654801] [131/142] (583s left) gem_spin_batch (legacy)
[483.717092] Starting subtest: legacy
[483.719650] Starting dynamic subtest: default
[486.952524] Dynamic subtest default: SUCCESS (3.231s)
[486.970917] Starting dynamic subtest: render
[490.186077] Dynamic subtest render: SUCCESS (3.231s)
[490.204715] Starting dynamic subtest: bsd
[493.420254] Dynamic subtest bsd: SUCCESS (3.232s)
[493.438642] Starting dynamic subtest: bsd1
[496.649530] Dynamic subtest bsd1: SUCCESS (3.228s)
[496.667711] Starting dynamic subtest: bsd2
[499.882462] Dynamic subtest bsd2: SUCCESS (3.232s)
[499.900945] Starting dynamic subtest: blt
[503.117955] Dynamic subtest blt: SUCCESS (3.234s)
[503.136381] Starting dynamic subtest: vebox
[506.350621] Dynamic subtest vebox: SUCCESS (3.230s)
[506.353741] Subtest legacy: SUCCESS (22.630s)
[508.346822] [132/142] (559s left) gem_ctx_persistence (heartbeat-hostile)
[508.413285] Starting subtest: heartbeat-hostile
[508.418282] Subtest heartbeat-hostile: SKIP (0.000s)
[508.486202] [133/142] (558s left) kms_ccs (pipe-b-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc)
[508.876091] Starting subtest: pipe-B-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc
[508.882085] Subtest pipe-B-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[509.748237] [134/142] (557s left) gem_lmem_swapping (verify-random-ccs)
[509.802069] Subtest verify-random-ccs: SKIP (0.000s)
[509.837902] [135/142] (557s left) vgem_basic (debugfs)
[509.895784] Starting subtest: debugfs
[509.897957] Subtest debugfs: SUCCESS (0.000s)
[509.931371] [136/142] (557s left) kms_color (ctm-0-50)
[510.027315] Starting subtest: ctm-0-50
[510.029378] Starting dynamic subtest: pipe-A
[513.712689] Dynamic subtest pipe-A: SUCCESS (3.686s)
[513.729071] Starting dynamic subtest: pipe-B
[518.215262] Dynamic subtest pipe-B: SUCCESS (4.501s)
[518.234239] Starting dynamic subtest: pipe-C
[522.465678] Dynamic subtest pipe-C: SUCCESS (4.250s)
[522.482108] Starting dynamic subtest: pipe-D
[526.712127] Dynamic subtest pipe-D: SUCCESS (4.245s)
[526.715099] Subtest ctm-0-50: SUCCESS (16.686s)
[527.617555] [137/142] (539s left) core_auth (getclient-master-drop)
[527.687962] Starting subtest: getclient-master-drop
[527.693459] Subtest getclient-master-drop: SUCCESS (0.003s)
[527.763015] [138/142] (539s left) gem_softpin (allocator-basic-reserve)
[527.810499] Starting subtest: allocator-basic-reserve
[527.864403] Subtest allocator-basic-reserve: SUCCESS (0.054s)
[527.967886] [139/142] (539s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[528.038660] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[528.042642] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[528.117874] [140/142] (539s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_ccs)
[528.568052] Starting subtest: pipe-D-missing-ccs-buffer-y_tiled_ccs
[528.573498] Subtest pipe-D-missing-ccs-buffer-y_tiled_ccs: SKIP (0.000s)
[529.416730] [141/142] (538s left) gem_exec_schedule (deep)
[529.628320] Starting subtest: deep
[529.631029] Starting dynamic subtest: rcs0
[536.270972] Dynamic subtest rcs0: SUCCESS (6.643s)
[536.288846] Starting dynamic subtest: bcs0
[543.669791] Dynamic subtest bcs0: SUCCESS (7.397s)
[543.690475] Starting dynamic subtest: vcs0
[550.273017] Dynamic subtest vcs0: SUCCESS (6.602s)
[550.280564] Starting dynamic subtest: vcs1
[556.827805] Dynamic subtest vcs1: SUCCESS (6.554s)
[556.848568] Starting dynamic subtest: vecs0
[563.320476] Dynamic subtest vecs0: SUCCESS (6.491s)
[563.328612] Starting dynamic subtest: ccs0
[570.871064] Dynamic subtest ccs0: SUCCESS (7.551s)
[570.873796] Subtest deep: SUCCESS (41.243s)
[571.295419] [142/142] (496s left) gem_exec_fence (basic-wait-all)
[571.352850] Starting subtest: basic-wait-all
[571.423704] Subtest basic-wait-all: SUCCESS (0.072s)
[573.318344] Closing watchdogs
Done.