[41.571588] Initializing watchdogs
[41.571666]   /dev/watchdog0
[41.580536] [001/142] (960s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[41.695524] Starting subtest: basic-flip-before-cursor-legacy
[42.744226] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.049s)
[43.647795] [002/142] (958s left) kms_chamelium_frames (dp-crc-fast)
[44.048602] Subtest dp-crc-fast: SKIP (0.000s)
[44.890239] [003/142] (957s left) gem_exec_schedule (u-semaphore-noskip)
[44.973330] Starting subtest: u-semaphore-noskip
[45.407767] Subtest u-semaphore-noskip: SUCCESS (0.443s)
[45.726408] [004/142] (956s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[45.778074] Starting subtest: single-wait-all-for-submit-signaled
[45.780247] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.001s)
[45.842792] [005/142] (956s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[45.904571] Starting subtest: primary-yf-tiled-reflect-x-180
[45.966659] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[46.074310] [006/142] (956s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_mc_ccs)
[46.496925] Starting subtest: pipe-D-bad-aux-stride-4_tiled_mtl_mc_ccs
[46.576041] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_mc_ccs: SUCCESS (0.077s)
[47.318680] [007/142] (954s left) syncobj_basic (bad-pad-handle-to-fd)
[47.372823] Starting subtest: bad-pad-handle-to-fd
[47.375672] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[47.432379] [008/142] (954s left) i915_pm_freq_mult (media-freq)
[47.484619] Starting subtest: media-freq
[47.486854] Starting dynamic subtest: gt0
[47.491248] Dynamic subtest gt0: SKIP (0.000s)
[47.497348] Starting dynamic subtest: gt1
[47.501472] Dynamic subtest gt1: SKIP (0.000s)
[47.505710] Subtest media-freq: SKIP (0.000s)
[47.531217] [009/142] (954s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[47.628702] Starting subtest: cursorB-vs-flipA-atomic
[47.636042] Subtest cursorB-vs-flipA-atomic: SKIP (0.005s)
[47.767281] [010/142] (954s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[47.839377] Starting subtest: psr-rgb565-draw-pwrite
[52.783651] Subtest psr-rgb565-draw-pwrite: SUCCESS (4.948s)
[52.882883] [011/142] (949s left) kms_fbcon_fbt (fbc)
[52.945113] Starting subtest: fbc
[58.413065] Subtest fbc: SUCCESS (5.468s)
[58.477482] [012/142] (943s left) kms_cursor_crc (cursor-alpha-transparent)
[58.560049] Starting subtest: cursor-alpha-transparent
[58.562069] Starting dynamic subtest: pipe-A-eDP-1
[59.192050] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.631s)
[59.201372] Starting dynamic subtest: pipe-D-eDP-1
[60.580276] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.386s)
[60.582345] Subtest cursor-alpha-transparent: SUCCESS (2.020s)
[61.465221] [013/142] (940s left) gem_flink_basic (basic)
[61.503138] Starting subtest: basic
[61.505040] Subtest basic: SUCCESS (0.000s)
[61.591469] [014/142] (940s left) sw_sync (sync_multi_consumer)
[61.634747] Starting subtest: sync_multi_consumer
[62.133841] Subtest sync_multi_consumer: SUCCESS (0.500s)
[62.183257] [015/142] (940s left) gem_exec_whisper (basic-fds-all)
[62.231568] Starting subtest: basic-fds-all
[80.419642] Subtest basic-fds-all: SUCCESS (18.184s)
[80.591812] [016/142] (921s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[80.675679] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[80.680318] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[80.761592] [017/142] (921s left) kms_cdclk (plane-scaling)
[80.847671] Starting subtest: plane-scaling
[80.850612] Starting dynamic subtest: pipe-A-eDP-1
[81.132640] Dynamic subtest pipe-A-eDP-1: SKIP (0.285s)
[81.144494] Starting dynamic subtest: pipe-B-eDP-1
[81.149530] Dynamic subtest pipe-B-eDP-1: SKIP (0.004s)
[81.160752] Starting dynamic subtest: pipe-C-eDP-1
[81.164857] Dynamic subtest pipe-C-eDP-1: SKIP (0.003s)
[81.174394] Starting dynamic subtest: pipe-D-eDP-1
[81.178664] Dynamic subtest pipe-D-eDP-1: SKIP (0.002s)
[81.182914] Subtest plane-scaling: SKIP (0.297s)
[82.050929] [018/142] (920s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[82.148349] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[82.152701] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[82.219506] [019/142] (920s left) kms_rotation_crc (cursor-rotation-180)
[82.275328] Starting subtest: cursor-rotation-180
[83.871856] Subtest cursor-rotation-180: SUCCESS (1.596s)
[85.122075] [020/142] (917s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-5)
[85.191699] Starting subtest: planes-upscale-20x20-downscale-factor-0-5
[85.194046] Starting dynamic subtest: pipe-A-eDP-1
[85.198696] Dynamic subtest pipe-A-eDP-1: SKIP (0.003s)
[85.204798] Starting dynamic subtest: pipe-B-eDP-1
[85.209462] Dynamic subtest pipe-B-eDP-1: SKIP (0.013s)
[85.215225] Starting dynamic subtest: pipe-C-eDP-1
[85.226649] Dynamic subtest pipe-C-eDP-1: SKIP (0.014s)
[85.240391] Starting dynamic subtest: pipe-D-eDP-1
[85.244285] Dynamic subtest pipe-D-eDP-1: SKIP (0.012s)
[85.248114] Subtest planes-upscale-20x20-downscale-factor-0-5: SKIP (0.044s)
[85.308956] [021/142] (917s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[85.373768] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[90.556771] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (5.187s)
[90.656348] [022/142] (911s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[90.750516] Starting subtest: yf-tiled-8bpp-rotate-0
[90.754801] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[90.833366] [023/142] (911s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_dg2_rc_ccs_cc)
[91.200483] Starting subtest: pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs_cc
[91.205585] Subtest pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[92.098785] [024/142] (910s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-signaled)
[92.144362] Starting subtest: invalid-multi-wait-available-unsubmitted-signaled
[92.146780] Subtest invalid-multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[92.191073] [025/142] (910s left) perf_pmu (busy-accuracy-98)
[92.249093] Starting subtest: busy-accuracy-98
[92.251798] Starting dynamic subtest: rcs0
[95.288099] Dynamic subtest rcs0: SUCCESS (3.034s)
[95.302939] Starting dynamic subtest: bcs0
[98.337455] Dynamic subtest bcs0: SUCCESS (3.050s)
[98.349247] Starting dynamic subtest: vcs0
[101.381224] Dynamic subtest vcs0: SUCCESS (3.044s)
[101.393882] Starting dynamic subtest: vcs1
[104.423912] Dynamic subtest vcs1: SUCCESS (3.042s)
[104.435768] Starting dynamic subtest: vecs0
[107.464558] Dynamic subtest vecs0: SUCCESS (3.041s)
[107.476091] Starting dynamic subtest: ccs0
[110.516249] Dynamic subtest ccs0: SUCCESS (3.051s)
[110.519060] Subtest busy-accuracy-98: SUCCESS (18.264s)
[110.619371] [026/142] (892s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[110.673243] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[110.781142] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.109s)
[110.848855] [027/142] (891s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[110.936878] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[110.941299] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[111.017786] [028/142] (891s left) kms_plane (plane-position-hole-dpms)
[111.087152] Starting subtest: plane-position-hole-dpms
[111.089550] Starting dynamic subtest: pipe-A-planes
[121.976692] Dynamic subtest pipe-A-planes: SUCCESS (10.890s)
[121.988597] Starting dynamic subtest: pipe-B-planes
[133.622240] Dynamic subtest pipe-B-planes: SUCCESS (11.642s)
[133.624759] Subtest plane-position-hole-dpms: SUCCESS (22.535s)
[134.481842] [029/142] (868s left) gem_exec_balancer (nohangcheck)
[134.715589] Starting subtest: nohangcheck
[135.336378] Subtest nohangcheck: SUCCESS (0.620s)
[135.428726] [030/142] (867s left) i915_power (sanity)
[135.471657] Starting subtest: sanity
[139.494859] Subtest sanity: SUCCESS (4.016s)
[141.478703] [031/142] (861s left) syncobj_timeline (multi-wait-for-submit-signaled)
[141.534067] Starting subtest: multi-wait-for-submit-signaled
[141.536073] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[141.594518] [032/142] (861s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[141.675306] Starting subtest: y-tiled-64bpp-rotate-0
[141.679232] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[141.760052] [033/142] (861s left) api_intel_bb (object-noreloc-keep-cache-simple)
[141.815440] Starting subtest: object-noreloc-keep-cache-simple
[141.817456] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.003s)
[141.906520] [034/142] (860s left) gem_unfence_active_buffers
[142.063251] [035/142] (860s left) gem_basic (create-fd-close)
[142.116086] Starting subtest: create-fd-close
[142.120163] Subtest create-fd-close: SUCCESS (0.000s)
[142.201239] [036/142] (860s left) api_intel_bb (simple-bb)
[142.255103] Starting subtest: simple-bb
[142.257425] Subtest simple-bb: SUCCESS (0.003s)
[142.352259] [037/142] (860s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25)
[142.433574] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25
[142.436233] Starting dynamic subtest: pipe-A-eDP-1
[142.441020] Dynamic subtest pipe-A-eDP-1: SKIP (0.003s)
[142.448061] Starting dynamic subtest: pipe-B-eDP-1
[142.452147] Dynamic subtest pipe-B-eDP-1: SKIP (0.012s)
[142.458357] Starting dynamic subtest: pipe-C-eDP-1
[142.465942] Dynamic subtest pipe-C-eDP-1: SKIP (0.012s)
[142.472727] Starting dynamic subtest: pipe-D-eDP-1
[142.476880] Dynamic subtest pipe-D-eDP-1: SKIP (0.012s)
[142.481235] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.043s)
[142.554804] [038/142] (860s left) kms_vblank (pipe-c-ts-continuation-modeset-rpm)
[142.603839] Starting subtest: pipe-C-ts-continuation-modeset-rpm
[146.295506] Subtest pipe-C-ts-continuation-modeset-rpm: SUCCESS (3.694s)
[147.202747] [039/142] (855s left) gem_unref_active_buffers
[147.384130] [040/142] (855s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[147.455374] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[147.460235] Starting dynamic subtest: pipe-A-default-mode
[147.756424] Dynamic subtest pipe-A-default-mode: SKIP (0.293s)
[147.761546] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.297s)
[148.674417] [041/142] (854s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[148.758428] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[153.690615] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.938s)
[153.801725] [042/142] (849s left) kms_flip (flip-vs-rmfb)
[153.865601] Starting subtest: flip-vs-rmfb
[153.868054] Starting dynamic subtest: A-eDP1
[162.609834] Dynamic subtest A-eDP1: SUCCESS (8.745s)
[162.621541] Starting dynamic subtest: B-eDP1
[171.376720] Dynamic subtest B-eDP1: SUCCESS (8.765s)
[171.388817] Starting dynamic subtest: C-eDP1
[180.134468] Dynamic subtest C-eDP1: SUCCESS (8.755s)
[180.146892] Starting dynamic subtest: D-eDP1
[188.836742] Dynamic subtest D-eDP1: SUCCESS (8.702s)
[188.844354] Subtest flip-vs-rmfb: SUCCESS (34.982s)
[190.148493] [043/142] (812s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[190.214950] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[190.217451] Starting dynamic subtest: pipe-A-default-mode
[190.519833] Dynamic subtest pipe-A-default-mode: SKIP (0.299s)
[190.524116] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.301s)
[191.413536] [044/142] (811s left) gem_wait (invalid-buf)
[191.468203] Starting subtest: invalid-buf
[191.470611] Subtest invalid-buf: SUCCESS (0.000s)
[191.667995] [045/142] (811s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[191.762204] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[192.786564] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.023s)
[192.905542] [046/142] (810s left) gen9_exec_parse (bb-secure)
[192.957634] Subtest bb-secure: SKIP (0.000s)
[192.994612] [047/142] (810s left) gem_create (create-ext-placement-all)
[193.162809] Starting subtest: create-ext-placement-all
[193.165586] Subtest create-ext-placement-all: SUCCESS (0.000s)
[193.237444] [048/142] (809s left) drm_fdinfo (idle)
[193.297775] Starting subtest: idle
[193.300227] Starting dynamic subtest: rcs0
[193.808766] Dynamic subtest rcs0: SUCCESS (0.512s)
[193.820403] Starting dynamic subtest: bcs0
[194.314026] Dynamic subtest bcs0: SUCCESS (0.502s)
[194.326053] Starting dynamic subtest: vcs0
[194.818597] Dynamic subtest vcs0: SUCCESS (0.502s)
[194.830717] Starting dynamic subtest: vcs1
[195.322328] Dynamic subtest vcs1: SUCCESS (0.502s)
[195.334284] Starting dynamic subtest: vecs0
[195.826530] Dynamic subtest vecs0: SUCCESS (0.503s)
[195.838525] Starting dynamic subtest: ccs0
[196.330940] Dynamic subtest ccs0: SUCCESS (0.503s)
[196.333758] Subtest idle: SUCCESS (3.034s)
[196.397559] [049/142] (806s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[196.538632] Starting subtest: cursor-rapid-movement-512x170
[196.543672] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[196.645542] [050/142] (806s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[196.697107] Starting subtest: multi-wait-all-for-submit-unsubmitted
[196.796989] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.101s)
[196.861949] [051/142] (806s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[196.921255] Starting subtest: multi-wait-for-submit-unsubmitted
[197.030205] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.107s)
[197.094951] [052/142] (806s left) kms_lease (lease_again)
[197.170273] Starting subtest: lease_again
[197.172823] Starting dynamic subtest: pipe-A-eDP-1
[197.175378] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[197.186801] Starting dynamic subtest: pipe-B-eDP-1
[197.188723] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[197.197640] Starting dynamic subtest: pipe-C-eDP-1
[197.199959] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[197.207251] Starting dynamic subtest: pipe-D-eDP-1
[197.209491] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[197.211811] Subtest lease_again: SUCCESS (0.006s)
[197.227918] [053/142] (806s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[197.288802] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[197.292784] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[197.373749] [054/142] (805s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[197.437200] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[197.446738] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.013s)
[197.515216] [055/142] (805s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[197.620260] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[197.624782] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[197.700083] [056/142] (805s left) kms_vblank (pipe-d-accuracy-idle)
[197.773227] Starting subtest: pipe-D-accuracy-idle
[200.667021] Subtest pipe-D-accuracy-idle: SUCCESS (2.894s)
[201.594418] [057/142] (801s left) perf (create-destroy-userspace-config)
[201.734267] Starting subtest: create-destroy-userspace-config
[201.752791] Subtest create-destroy-userspace-config: SUCCESS (0.021s)
[201.776774] [058/142] (801s left) gem_exec_fence (syncobj-channel)
[202.221979] Starting subtest: syncobj-channel
[203.239209] Subtest syncobj-channel: SUCCESS (1.013s)
[203.560946] [059/142] (799s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[203.642312] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[203.646786] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[203.728916] [060/142] (799s left) kms_concurrent (pipe-b)
[203.830666] Starting subtest: pipe-B
[203.832621] Starting dynamic subtest: eDP-1
[205.450700] Dynamic subtest eDP-1: SUCCESS (1.620s)
[205.453249] Subtest pipe-B: SUCCESS (1.623s)
[206.343946] [061/142] (797s left) kms_pm_lpsp (screens-disabled)
[207.432485] Starting subtest: screens-disabled
[207.437092] Subtest screens-disabled: SKIP (0.000s)
[207.501806] [062/142] (796s left) gem_linear_blits (basic)
[207.861581] Starting subtest: basic
[207.864703] Subtest basic: SUCCESS (0.004s)
[207.987944] [063/142] (795s left) kms_color (ctm-0-75)
[208.097124] Starting subtest: ctm-0-75
[208.100218] Starting dynamic subtest: pipe-A
[213.295883] Dynamic subtest pipe-A: SUCCESS (5.199s)
[213.309174] Starting dynamic subtest: pipe-B
[219.280122] Dynamic subtest pipe-B: SUCCESS (5.981s)
[219.293517] Starting dynamic subtest: pipe-C
[224.936276] Dynamic subtest pipe-C: SUCCESS (5.656s)
[224.950175] Starting dynamic subtest: pipe-D
[230.633045] Dynamic subtest pipe-D: SUCCESS (5.692s)
[230.635572] Subtest ctm-0-75: SUCCESS (22.533s)
[231.543493] [064/142] (772s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[231.638779] Starting subtest: cursor-vs-flip-varying-size
[260.714736] Subtest cursor-vs-flip-varying-size: SUCCESS (29.075s)
[261.639112] [065/142] (741s left) gem_exec_fence (parallel)
[261.803097] Starting subtest: parallel
[261.805707] Starting dynamic subtest: rcs0
[263.952039] Dynamic subtest rcs0: SUCCESS (2.150s)
[263.964308] Starting dynamic subtest: bcs0
[266.103749] Dynamic subtest bcs0: SUCCESS (2.151s)
[266.116549] Starting dynamic subtest: vcs0
[268.254178] Dynamic subtest vcs0: SUCCESS (2.150s)
[268.266101] Starting dynamic subtest: vcs1
[270.402467] Dynamic subtest vcs1: SUCCESS (2.148s)
[270.415825] Starting dynamic subtest: vecs0
[272.553063] Dynamic subtest vecs0: SUCCESS (2.150s)
[272.566217] Starting dynamic subtest: ccs0
[274.707945] Dynamic subtest ccs0: SUCCESS (2.154s)
[274.710048] Subtest parallel: SUCCESS (12.907s)
[275.533197] [066/142] (728s left) kms_psr (sprite_blt)
[275.599447] Starting subtest: sprite_blt
[277.430675] Subtest sprite_blt: SUCCESS (1.831s)
[278.345257] [067/142] (725s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[278.390623] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[278.394914] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[278.568817] [068/142] (725s left) kms_cursor_crc (cursor-sliding-512x512)
[278.710311] Starting subtest: cursor-sliding-512x512
[278.715195] Subtest cursor-sliding-512x512: SKIP (0.001s)
[278.821126] [069/142] (724s left) gem_ctx_persistence (engines-mixed-process)
[278.875350] Starting subtest: engines-mixed-process
[278.877928] Starting dynamic subtest: rcs0
[280.455677] Dynamic subtest rcs0: SUCCESS (1.578s)
[280.469543] Starting dynamic subtest: bcs0
[282.440506] Dynamic subtest bcs0: SUCCESS (1.987s)
[282.455214] Starting dynamic subtest: vcs0
[302.488945] Dynamic subtest vcs0: SUCCESS (20.047s)
[302.502851] Starting dynamic subtest: vcs1
[322.542025] Dynamic subtest vcs1: SUCCESS (20.050s)
[322.556423] Starting dynamic subtest: vecs0
[342.593807] Dynamic subtest vecs0: SUCCESS (20.053s)
[342.608695] Starting dynamic subtest: ccs0
[344.458791] Dynamic subtest ccs0: SUCCESS (1.863s)
[344.461402] Subtest engines-mixed-process: SUCCESS (65.585s)
[344.533404] [070/142] (659s left) kms_atomic (crtc-invalid-params-fence)
[344.619806] Starting subtest: crtc-invalid-params-fence
[344.679476] Subtest crtc-invalid-params-fence: SUCCESS (0.060s)
[345.862629] [071/142] (657s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[345.929492] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[347.201994] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: FAIL (1.124s)
[347.224662] [072/142] (656s left) kms_vblank (pipe-d-wait-idle)
[347.278133] Starting subtest: pipe-D-wait-idle
[350.172884] Subtest pipe-D-wait-idle: SUCCESS (2.895s)
[351.110850] [073/142] (652s left) v3d/v3d_submit_cl (bad-bo)
[351.190053] Subtest bad-bo: SKIP (0.000s)
[351.234373] [074/142] (652s left) kms_getfb (getfb-handle-not-fb)
[351.283655] Starting subtest: getfb-handle-not-fb
[351.293453] Subtest getfb-handle-not-fb: SUCCESS (0.012s)
[351.381470] [075/142] (652s left) kms_ccs (pipe-c-crc-primary-basic-y_tiled_gen12_mc_ccs)
[351.749352] Starting subtest: pipe-C-crc-primary-basic-y_tiled_gen12_mc_ccs
[351.755046] Subtest pipe-C-crc-primary-basic-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[352.645399] [076/142] (651s left) kms_ccs (pipe-c-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[353.054668] Starting subtest: pipe-C-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[353.057529] Subtest pipe-C-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SUCCESS (0.000s)
[353.912654] [077/142] (650s left) kms_ccs (pipe-d-bad-rotation-90-y_tiled_gen12_rc_ccs)
[354.331148] Starting subtest: pipe-D-bad-rotation-90-y_tiled_gen12_rc_ccs
[354.336960] Subtest pipe-D-bad-rotation-90-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[355.206616] [078/142] (648s left) kms_vblank (pipe-c-query-forked)
[355.269411] Starting subtest: pipe-C-query-forked
[358.226127] Subtest pipe-C-query-forked: SUCCESS (2.958s)
[359.163295] [079/142] (644s left) kms_hdr (bpc-switch)
[359.229923] Starting subtest: bpc-switch
[360.399388] Starting dynamic subtest: pipe-A-eDP-1
[363.981084] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.584s)
[363.983492] Subtest bpc-switch: SUCCESS (4.754s)
[364.094233] [080/142] (639s left) gem_exec_flush (basic-wb-prw-default)
[364.150676] Starting subtest: basic-wb-prw-default
[369.596142] Subtest basic-wb-prw-default: SUCCESS (5.450s)
[369.778146] [081/142] (634s left) vc4/vc4_purgeable_bo (mark-unpurgeable-purged)
[369.870474] Subtest mark-unpurgeable-purged: SKIP (0.000s)
[369.915729] [082/142] (634s left) gem_eio (wait-wedge-1us)
[370.158077] Starting subtest: wait-wedge-1us
[371.522569] Subtest wait-wedge-1us: SUCCESS (1.363s)
[373.473485] [083/142] (630s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[373.559833] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[373.564355] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[373.654666] [084/142] (630s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[373.739204] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[373.743332] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[373.826397] [085/142] (630s left) gen7_exec_parse (oacontrol-tracking)
[373.884079] Subtest oacontrol-tracking: SKIP (0.000s)
[373.909522] [086/142] (630s left) gem_pipe_control_store_loop (reused-buffer)
[373.955575] Starting subtest: reused-buffer
[376.104140] Subtest reused-buffer: SUCCESS (2.150s)
[376.196731] [087/142] (628s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc)
[376.597223] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc
[376.602532] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.002s)
[377.476994] [088/142] (626s left) kms_psr (psr2_primary_mmap_gtt)
[377.564092] Starting subtest: psr2_primary_mmap_gtt
[379.050239] Subtest psr2_primary_mmap_gtt: SKIP (1.484s)
[379.228167] [089/142] (625s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[379.287989] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[379.290465] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[379.353496] [090/142] (624s left) gem_lmem_swapping (massive)
[379.406580] Subtest massive: SKIP (0.000s)
[379.430759] [091/142] (624s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-blt)
[379.491907] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-blt
[384.243406] Subtest fbcpsr-1p-offscren-pri-indfb-draw-blt: SUCCESS (4.754s)
[384.373993] [092/142] (619s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[384.467395] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[384.471126] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[384.553056] [093/142] (619s left) kms_ccs (pipe-b-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[384.940456] Starting subtest: pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[384.943148] Subtest pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SUCCESS (0.000s)
[385.851827] [094/142] (618s left) kms_flip (plain-flip-ts-check-interruptible)
[385.920594] Starting subtest: plain-flip-ts-check-interruptible
[385.927443] Starting dynamic subtest: A-eDP1
[394.862733] Dynamic subtest A-eDP1: SUCCESS (8.935s)
[394.878020] Starting dynamic subtest: B-eDP1
[403.819836] Dynamic subtest B-eDP1: SUCCESS (8.955s)
[403.835215] Starting dynamic subtest: C-eDP1
[412.772283] Dynamic subtest C-eDP1: SUCCESS (8.947s)
[412.786265] Starting dynamic subtest: D-eDP1
[421.665420] Dynamic subtest D-eDP1: SUCCESS (8.896s)
[421.672896] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.752s)
[422.919115] [095/142] (581s left) kms_getfb (getfb-handle-zero)
[422.960579] Starting subtest: getfb-handle-zero
[422.963013] Subtest getfb-handle-zero: SUCCESS (0.000s)
[423.049424] [096/142] (581s left) kms_ccs (pipe-d-random-ccs-data-4_tiled_mtl_rc_ccs)
[423.440140] Starting subtest: pipe-D-random-ccs-data-4_tiled_mtl_rc_ccs
[424.240465] Subtest pipe-D-random-ccs-data-4_tiled_mtl_rc_ccs: SUCCESS (0.801s)
[425.477707] [097/142] (579s left) kms_psr (psr2_cursor_plane_onoff)
[425.533709] Starting subtest: psr2_cursor_plane_onoff
[427.270765] Subtest psr2_cursor_plane_onoff: SUCCESS (1.739s)
[428.154744] [098/142] (576s left) gem_mmap_gtt (cpuset-big-copy-odd)
[428.207530] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[428.243725] [099/142] (576s left) kms_color (ctm-blue-to-red)
[428.323715] Starting subtest: ctm-blue-to-red
[428.325745] Starting dynamic subtest: pipe-A
[429.066017] Dynamic subtest pipe-A: SUCCESS (0.745s)
[429.078927] Starting dynamic subtest: pipe-B
[430.535111] Dynamic subtest pipe-B: SUCCESS (1.467s)
[430.552021] Starting dynamic subtest: pipe-C
[431.951315] Dynamic subtest pipe-C: SUCCESS (1.416s)
[431.963473] Starting dynamic subtest: pipe-D
[433.424668] Dynamic subtest pipe-D: SUCCESS (1.472s)
[433.427174] Subtest ctm-blue-to-red: SUCCESS (5.105s)
[434.362130] [100/142] (570s left) gem_exec_schedule (preempt-contexts)
[434.447895] Starting subtest: preempt-contexts
[434.450447] Starting dynamic subtest: rcs0
[434.490441] Dynamic subtest rcs0: SUCCESS (0.041s)
[434.503677] Starting dynamic subtest: bcs0
[434.516597] Dynamic subtest bcs0: SUCCESS (0.028s)
[434.524550] Starting dynamic subtest: vcs0
[434.541271] Dynamic subtest vcs0: SUCCESS (0.022s)
[434.555403] Starting dynamic subtest: vcs1
[434.564321] Dynamic subtest vcs1: SUCCESS (0.022s)
[434.573639] Starting dynamic subtest: vecs0
[434.588721] Dynamic subtest vecs0: SUCCESS (0.025s)
[434.601795] Starting dynamic subtest: ccs0
[434.608121] Dynamic subtest ccs0: SUCCESS (0.020s)
[434.610483] Subtest preempt-contexts: SUCCESS (0.162s)
[436.559886] [101/142] (568s left) kms_vblank (pipe-a-wait-busy-hang)
[436.652168] Starting subtest: pipe-A-wait-busy-hang
[442.957559] Subtest pipe-A-wait-busy-hang: SUCCESS (6.306s)
[443.835873] [102/142] (560s left) sw_sync (alloc_fence)
[443.890555] Starting subtest: alloc_fence
[443.893172] Subtest alloc_fence: SUCCESS (0.000s)
[443.947536] [103/142] (560s left) kms_ccs (pipe-c-bad-aux-stride-y_tiled_gen12_rc_ccs_cc)
[444.326307] Starting subtest: pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs_cc
[444.332135] Subtest pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[445.179619] [104/142] (559s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-pwrite)
[445.249922] Starting subtest: psr-1p-offscren-pri-shrfb-draw-pwrite
[450.197974] Subtest psr-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (4.950s)
[450.326121] [105/142] (554s left) gem_exec_balancer (parallel-contexts)
[450.569087] Starting subtest: parallel-contexts
[451.640101] Subtest parallel-contexts: SUCCESS (1.070s)
[452.670592] [106/142] (552s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[452.767466] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[453.467149] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.703s)
[453.627536] [107/142] (551s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted-submitted-sign
[453.675074] Starting subtest: invalid-multi-wait-all-available-unsubmitted-submitted-signaled
[453.677429] Subtest invalid-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[453.741904] [108/142] (551s left) gem_mmap (basic-small-bo)
[453.797048] Subtest basic-small-bo: SKIP (0.000s)
[453.830388] [109/142] (550s left) kms_ccs (pipe-a-bad-pixel-format-yf_tiled_ccs)
[453.911628] Starting subtest: pipe-A-bad-pixel-format-yf_tiled_ccs
[453.916537] Subtest pipe-A-bad-pixel-format-yf_tiled_ccs: SKIP (0.000s)
[455.064176] [110/142] (549s left) vc4/vc4_wait_bo (used-bo)
[455.136376] Subtest used-bo: SKIP (0.000s)
[455.188172] [111/142] (549s left) kms_ccs (pipe-a-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[455.551526] Starting subtest: pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[455.553989] Subtest pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.000s)
[456.418754] [112/142] (548s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[456.471284] Starting subtest: addfb25-y-tiled-small-legacy
[456.475091] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[456.552699] [113/142] (548s left) kms_flip (2x-plain-flip-ts-check)
[456.612463] Starting subtest: 2x-plain-flip-ts-check
[456.621195] Subtest 2x-plain-flip-ts-check: SKIP (0.012s)
[456.690465] [114/142] (548s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[456.776951] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[456.782422] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.002s)
[456.914677] [115/142] (548s left) perf (invalid-open-flags)
[457.022494] Starting subtest: invalid-open-flags
[457.024865] Subtest invalid-open-flags: SUCCESS (0.002s)
[457.054056] [116/142] (547s left) gem_mmap_offset (clear)
[457.117791] Starting subtest: clear
[457.119938] Starting dynamic subtest: smem0
[479.159162] Dynamic subtest smem0: SUCCESS (22.040s)
[479.161584] Subtest clear: SUCCESS (22.041s)
[479.364431] [117/142] (525s left) gem_pxp (reject-modify-context-protection-off-2)
[481.951943] Subtest reject-modify-context-protection-off-2: SKIP (0.000s)
[482.020961] [118/142] (523s left) gem_vm_create (create-ext)
[482.070632] Starting subtest: create-ext
[482.075341] Subtest create-ext: SUCCESS (0.005s)
[482.172196] [119/142] (522s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[482.248050] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[482.252268] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[482.323725] [120/142] (522s left) kms_cursor_legacy (single-move)
[482.403544] Starting subtest: single-move
[482.405975] Starting dynamic subtest: pipe-A
[487.785113] Dynamic subtest pipe-A: SUCCESS (5.377s)
[487.799686] Starting dynamic subtest: pipe-B
[493.163157] Dynamic subtest pipe-B: SUCCESS (5.377s)
[493.170053] Starting dynamic subtest: pipe-C
[498.542292] Dynamic subtest pipe-C: SUCCESS (5.377s)
[498.556809] Starting dynamic subtest: pipe-D
[503.917602] Dynamic subtest pipe-D: SUCCESS (5.377s)
[503.931863] Starting dynamic subtest: all-pipes
[509.293181] Dynamic subtest all-pipes: SUCCESS (5.376s)
[509.295712] Subtest single-move: SUCCESS (26.886s)
[509.393138] [121/142] (495s left) kms_ccs (pipe-d-crc-primary-basic-y_tiled_gen12_mc_ccs)
[509.799953] Starting subtest: pipe-D-crc-primary-basic-y_tiled_gen12_mc_ccs
[509.805255] Subtest pipe-D-crc-primary-basic-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[510.664378] [122/142] (494s left) gem_softpin (allocator-fork)
[510.720886] Starting subtest: allocator-fork
[512.926692] Subtest allocator-fork: SUCCESS (2.206s)
[513.023773] [123/142] (492s left) gem_mmap_gtt (basic)
[513.070547] Subtest basic: SKIP (0.000s)
[513.088588] [124/142] (492s left) kms_plane_alpha_blend (alpha-transparent-fb)
[513.153846] Starting subtest: alpha-transparent-fb
[513.388651] Starting dynamic subtest: pipe-A-eDP-1
[514.055933] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.666s)
[514.071343] Starting dynamic subtest: pipe-D-eDP-1
[515.496878] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.437s)
[515.499079] Subtest alpha-transparent-fb: SUCCESS (2.341s)
[516.409803] [125/142] (488s left) gem_mmap_gtt (basic-small-copy-odd)
[516.460645] Subtest basic-small-copy-odd: SKIP (0.000s)
[516.484614] [126/142] (488s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[516.546881] Starting subtest: x-tiled-8bpp-rotate-270
[516.560401] Subtest x-tiled-8bpp-rotate-270: SKIP (0.016s)
[516.655961] [127/142] (488s left) v3d/v3d_perfmon (create-perfmon-0)
[516.728235] Subtest create-perfmon-0: SKIP (0.000s)
[516.773646] [128/142] (488s left) gem_ctx_sseu (invalid-sseu)
[516.814860] Subtest invalid-sseu: SKIP (0.000s)
[516.832973] [129/142] (488s left) gem_ppgtt (flink-and-close-vma-leak)
[516.869221] Starting subtest: flink-and-close-vma-leak
[516.906384] Subtest flink-and-close-vma-leak: SUCCESS (0.039s)
[517.008582] [130/142] (488s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[517.118755] Starting subtest: flip-vs-cursor-crc-legacy
[517.813993] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.694s)
[518.725031] [131/142] (486s left) gem_ctx_persistence (engines-hang)
[518.779943] Starting subtest: engines-hang
[518.782518] Starting dynamic subtest: rcs0
[518.872764] Dynamic subtest rcs0: SUCCESS (0.093s)
[518.888072] Starting dynamic subtest: bcs0
[518.957645] Dynamic subtest bcs0: SUCCESS (0.084s)
[518.972549] Starting dynamic subtest: vcs0
[519.783652] Dynamic subtest vcs0: FAIL (0.762s)
[519.798557] Starting dynamic subtest: vcs1
[522.582908] Dynamic subtest vcs1: SUCCESS (2.860s)
[522.598262] Starting dynamic subtest: vecs0
[522.615475] Dynamic subtest vecs0: SUCCESS (0.032s)
[522.630062] Starting dynamic subtest: ccs0
[522.691836] Dynamic subtest ccs0: SUCCESS (0.075s)
[522.694748] Subtest engines-hang: FAIL (3.914s)
[522.795428] [132/142] (482s left) vc4/vc4_dmabuf_poll (poll-read-waits-until-write-done)
[522.870332] Subtest poll-read-waits-until-write-done: SKIP (0.000s)
[522.922282] [133/142] (482s left) perf_pmu (most-busy-idle-check-all)
[522.968695] Starting subtest: most-busy-idle-check-all
[522.971288] Starting dynamic subtest: rcs0
[524.448333] Dynamic subtest rcs0: SUCCESS (1.477s)
[524.463757] Starting dynamic subtest: bcs0
[526.430279] Dynamic subtest bcs0: SUCCESS (1.982s)
[526.446078] Starting dynamic subtest: vcs0
[528.414318] Dynamic subtest vcs0: SUCCESS (1.982s)
[528.430789] Starting dynamic subtest: vcs1
[530.398370] Dynamic subtest vcs1: SUCCESS (1.982s)
[530.414189] Starting dynamic subtest: vecs0
[532.446182] Dynamic subtest vecs0: SUCCESS (2.046s)
[532.461812] Starting dynamic subtest: ccs0
[534.430271] Dynamic subtest ccs0: SUCCESS (1.982s)
[534.435329] Subtest most-busy-idle-check-all: SUCCESS (11.463s)
[534.504737] [134/142] (470s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[534.599275] Starting subtest: fbcpsr-tiling-4
[539.640454] Subtest fbcpsr-tiling-4: SUCCESS (5.043s)
[539.764345] [135/142] (465s left) gem_render_copy_redux (normal)
[539.838919] Starting subtest: normal
[540.146760] Subtest normal: SUCCESS (0.305s)
[540.238714] [136/142] (465s left) i915_pm_rpm (i2c)
[541.756161] Starting subtest: i2c
[551.470382] Subtest i2c: SUCCESS (9.713s)
[551.546857] [137/142] (454s left) sysfs_preempt_timeout (timeout)
[551.604162] Starting subtest: timeout
[551.608619] Starting dynamic subtest: vecs0
[561.463267] Dynamic subtest vecs0: SUCCESS (9.859s)
[561.479721] Starting dynamic subtest: ccs0
[562.634997] Dynamic subtest ccs0: SUCCESS (1.173s)
[562.646506] Starting dynamic subtest: vcs0
[572.467495] Dynamic subtest vcs0: SUCCESS (9.828s)
[572.484853] Starting dynamic subtest: bcs0
[573.569559] Dynamic subtest bcs0: SUCCESS (1.105s)
[573.585272] Starting dynamic subtest: rcs0
[574.661967] Dynamic subtest rcs0: SUCCESS (1.091s)
[574.677768] Starting dynamic subtest: vcs1
[584.438356] Dynamic subtest vcs1: SUCCESS (9.771s)
[584.440954] Subtest timeout: SUCCESS (32.835s)
[584.508753] [138/142] (421s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[584.890084] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[584.892921] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SUCCESS (0.000s)
[585.798717] [139/142] (419s left) kms_vblank (pipe-b-ts-continuation-modeset)
[585.875598] Starting subtest: pipe-B-ts-continuation-modeset
[588.595528] Subtest pipe-B-ts-continuation-modeset: SUCCESS (2.720s)
[589.497237] [140/142] (416s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_mtl_rc_ccs_cc)
[589.881406] Starting subtest: pipe-B-crc-primary-basic-4_tiled_mtl_rc_ccs_cc
[590.946645] Subtest pipe-B-crc-primary-basic-4_tiled_mtl_rc_ccs_cc: SUCCESS (1.065s)
[592.121472] [141/142] (413s left) gem_ccs (block-copy-compressed)
[592.179547] Starting subtest: block-copy-compressed
[592.183548] Subtest block-copy-compressed: SKIP (0.000s)
[592.243811] [142/142] (413s left) gem_exec_schedule (lateslice)
[592.324166] Starting subtest: lateslice
[592.326653] Starting dynamic subtest: rcs0
[592.328992] Dynamic subtest rcs0: SUCCESS (0.013s)
[592.345361] Starting dynamic subtest: bcs0
[592.347848] Dynamic subtest bcs0: SUCCESS (0.012s)
[592.364220] Starting dynamic subtest: vcs0
[592.366689] Dynamic subtest vcs0: SUCCESS (0.012s)
[592.379703] Starting dynamic subtest: vcs1
[592.381642] Dynamic subtest vcs1: SUCCESS (0.013s)
[592.397171] Starting dynamic subtest: vecs0
[592.399524] Dynamic subtest vecs0: SUCCESS (0.012s)
[592.411887] Starting dynamic subtest: ccs0
[592.414189] Dynamic subtest ccs0: SUCCESS (0.013s)
[592.416471] Subtest lateslice: SUCCESS (0.078s)
[593.532788] Closing watchdogs
Done.