[71.119258] Initializing watchdogs
[71.119733]   /dev/watchdog0
[71.152896] [001/142] (960s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[71.701443] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[71.704966] Starting dynamic subtest: pipe-A-eDP-1
[71.981460] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.277s)
[72.012688] Starting dynamic subtest: pipe-B-eDP-1
[73.392856] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.404s)
[73.426661] Starting dynamic subtest: pipe-C-eDP-1
[74.798567] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.401s)
[74.813307] Starting dynamic subtest: pipe-D-eDP-1
[76.136374] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.330s)
[76.140721] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (4.436s)
[77.842143] [002/142] (953s left) prime_mmap (test_correct)
[78.223910] Starting subtest: test_correct
[78.227851] Starting dynamic subtest: test_correct-smem
[78.759077] Dynamic subtest test_correct-smem: SUCCESS (0.536s)
[78.762741] Subtest test_correct: SUCCESS (0.536s)
[78.909881] [003/142] (952s left) sw_sync (sync_busy_fork)
[79.272505] Starting subtest: sync_busy_fork
[80.339063] Subtest sync_busy_fork: SUCCESS (1.065s)
[80.460266] [004/142] (951s left) gem_ctx_persistence (engines-hang)
[80.875100] Starting subtest: engines-hang
[80.879293] Starting dynamic subtest: rcs0
[80.970105] Dynamic subtest rcs0: SUCCESS (0.094s)
[81.009438] Starting dynamic subtest: bcs0
[81.018660] Dynamic subtest bcs0: SUCCESS (0.048s)
[81.048623] Starting dynamic subtest: vcs0
[81.074445] Dynamic subtest vcs0: SUCCESS (0.056s)
[81.109247] Starting dynamic subtest: vcs1
[81.141267] Dynamic subtest vcs1: SUCCESS (0.065s)
[81.176694] Starting dynamic subtest: vecs0
[81.217356] Dynamic subtest vecs0: SUCCESS (0.076s)
[81.243558] Starting dynamic subtest: ccs0
[81.264695] Dynamic subtest ccs0: SUCCESS (0.046s)
[81.271511] Subtest engines-hang: SUCCESS (0.391s)
[81.437517] [005/142] (950s left) kms_ccs (pipe-c-crc-primary-basic-y_tiled_ccs)
[82.580527] Starting subtest: pipe-C-crc-primary-basic-y_tiled_ccs
[82.587791] Subtest pipe-C-crc-primary-basic-y_tiled_ccs: SKIP (0.001s)
[83.398348] [006/142] (948s left) kms_vblank (pipe-b-ts-continuation-idle)
[83.873898] Starting subtest: pipe-B-ts-continuation-idle
[85.560158] Subtest pipe-B-ts-continuation-idle: SUCCESS (1.687s)
[86.524816] [007/142] (945s left) kms_ccs (pipe-a-bad-pixel-format-y_tiled_gen12_mc_ccs)
[87.018402] Starting subtest: pipe-A-bad-pixel-format-y_tiled_gen12_mc_ccs
[87.024909] Subtest pipe-A-bad-pixel-format-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[88.499825] [008/142] (943s left) vgem_basic (dmabuf-export)
[88.991532] Starting subtest: dmabuf-export
[89.006749] Subtest dmabuf-export: SUCCESS (0.018s)
[89.162360] [009/142] (943s left) kms_plane_alpha_blend (alpha-transparent-fb)
[89.541405] Starting subtest: alpha-transparent-fb
[90.807561] Starting dynamic subtest: pipe-A-eDP-1
[92.092595] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.285s)
[92.129331] Starting dynamic subtest: pipe-D-eDP-1
[94.064196] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.965s)
[94.068069] Subtest alpha-transparent-fb: SUCCESS (4.524s)
[95.004948] [010/142] (937s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[95.884744] Starting subtest: cursor-rapid-movement-128x128
[95.917144] Starting dynamic subtest: pipe-A-eDP-1
[97.434867] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.518s)
[97.611556] Starting dynamic subtest: pipe-D-eDP-1
[99.083911] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.499s)
[99.088144] Subtest cursor-rapid-movement-128x128: SUCCESS (3.194s)
[99.994182] [011/142] (932s left) drm_fdinfo (most-busy-idle-check-all)
[100.393079] Starting subtest: most-busy-idle-check-all
[100.396776] Starting dynamic subtest: rcs0
[100.409456] Dynamic subtest rcs0: SKIP (0.013s)
[100.442198] Starting dynamic subtest: bcs0
[100.448427] Dynamic subtest bcs0: SKIP (0.002s)
[100.488659] Starting dynamic subtest: vcs0
[100.495617] Dynamic subtest vcs0: SKIP (0.002s)
[100.523659] Starting dynamic subtest: vcs1
[100.529905] Dynamic subtest vcs1: SKIP (0.003s)
[100.562539] Starting dynamic subtest: vecs0
[100.568740] Dynamic subtest vecs0: SKIP (0.002s)
[100.599119] Starting dynamic subtest: ccs0
[100.604915] Dynamic subtest ccs0: SKIP (0.002s)
[100.610770] Subtest most-busy-idle-check-all: SKIP (0.028s)
[100.659425] [012/142] (931s left) kms_panel_fitting (atomic-fastset)
[101.165724] Starting subtest: atomic-fastset
[101.170344] Starting dynamic subtest: pipe-A-eDP-1
[101.635143] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.465s)
[101.667175] Starting dynamic subtest: pipe-B-eDP-1
[103.431826] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.792s)
[103.465054] Starting dynamic subtest: pipe-C-eDP-1
[105.186840] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.751s)
[105.213933] Starting dynamic subtest: pipe-D-eDP-1
[106.964557] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.773s)
[106.968410] Subtest atomic-fastset: SUCCESS (5.798s)
[108.528909] [013/142] (923s left) i915_pm_backlight (fade-with-suspend)
[109.022974] Starting subtest: fade-with-suspend
[125.714429] Starting dynamic subtest: eDP-1
[128.601659] Dynamic subtest eDP-1: SUCCESS (2.883s)
[128.605733] Subtest fade-with-suspend: SUCCESS (19.580s)
[129.556818] [014/142] (903s left) feature_discovery (psr1)
[130.041548] Starting subtest: psr1
[130.045343] Subtest psr1: SUCCESS (0.001s)
[130.181085] [015/142] (902s left) gem_workarounds (suspend-resume-fd)
[130.632156] Starting subtest: suspend-resume-fd
[147.751301] Subtest suspend-resume-fd: SUCCESS (17.119s)
[147.939792] [016/142] (884s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs)
[149.470716] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs
[149.486404] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs: SKIP (0.013s)
[150.285418] [017/142] (882s left) gem_fence_thrash (bo-copy)
[150.649907] Starting subtest: bo-copy
[150.664893] Subtest bo-copy: SKIP (0.009s)
[150.782271] [018/142] (882s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-blt)
[151.291653] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-blt
[162.899403] Subtest fbc-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (11.611s)
[163.180471] [019/142] (869s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-gtt)
[163.695313] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-gtt
[163.702144] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[163.865441] [020/142] (869s left) gem_exec_reloc (basic-wc-gtt)
[164.270615] Subtest basic-wc-gtt: SKIP (0.000s)
[164.396959] [021/142] (868s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-mmap-wc)
[164.916243] Starting subtest: psr-1p-offscren-pri-indfb-draw-mmap-wc
[179.129154] Subtest psr-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (14.219s)
[179.296636] [022/142] (853s left) gem_exec_params (sol-reset-invalid)
[179.490382] Starting subtest: sol-reset-invalid
[179.497893] Subtest sol-reset-invalid: SUCCESS (0.006s)
[180.645591] [023/142] (852s left) perf (non-system-wide-paranoid)
[181.067536] Starting subtest: non-system-wide-paranoid
[181.149616] Subtest non-system-wide-paranoid: SUCCESS (0.082s)
[181.206078] [024/142] (852s left) gem_exec_reloc (basic-write-gtt)
[181.364113] Subtest basic-write-gtt: SKIP (0.000s)
[181.477583] [025/142] (851s left) gem_ctx_isolation (preservation-s3)
[181.890504] Starting subtest: preservation-S3
[181.893766] Starting dynamic subtest: rcs0
[198.900480] Dynamic subtest rcs0: SUCCESS (17.007s)
[198.935337] Starting dynamic subtest: bcs0
[215.933124] Dynamic subtest bcs0: SUCCESS (17.030s)
[215.972716] Starting dynamic subtest: vcs0
[232.915487] Dynamic subtest vcs0: SUCCESS (16.980s)
[232.962237] Starting dynamic subtest: vcs1
[251.907116] Dynamic subtest vcs1: SUCCESS (18.988s)
[251.949504] Starting dynamic subtest: vecs0
[270.934245] Dynamic subtest vecs0: SUCCESS (19.024s)
[270.975240] Starting dynamic subtest: ccs0
[289.760747] Dynamic subtest ccs0: SUCCESS (18.823s)
[289.764444] Subtest preservation-S3: SUCCESS (107.870s)
[290.089289] [026/142] (743s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[290.596799] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[290.603421] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[290.777170] [027/142] (742s left) kms_getfb (getfb-handle-zero)
[291.145549] Starting subtest: getfb-handle-zero
[291.149310] Subtest getfb-handle-zero: SUCCESS (0.000s)
[291.316992] [028/142] (742s left) kms_ccs (pipe-a-crc-primary-rotation-180-4_tiled_mtl_mc_ccs)
[292.268594] Starting subtest: pipe-A-crc-primary-rotation-180-4_tiled_mtl_mc_ccs
[298.985587] Subtest pipe-A-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SUCCESS (6.721s)
[300.238650] [029/142] (733s left) gem_create (create-size-update)
[300.622072] Starting subtest: create-size-update
[300.626272] Subtest create-size-update: SUCCESS (0.000s)
[300.946630] [030/142] (732s left) perf (whitelisted-registers-userspace-config)
[301.585204] Starting subtest: whitelisted-registers-userspace-config
[301.588880] Subtest whitelisted-registers-userspace-config: SUCCESS (0.004s)
[301.694287] [031/142] (732s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[302.227655] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[302.235177] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[302.395647] [032/142] (731s left) perf_pmu (render-node-busy-idle)
[302.810644] Starting subtest: render-node-busy-idle
[302.814393] Starting dynamic subtest: rcs0
[303.516989] Dynamic subtest rcs0: SUCCESS (0.707s)
[303.561632] Starting dynamic subtest: bcs0
[304.200246] Dynamic subtest bcs0: SUCCESS (0.679s)
[304.257074] Starting dynamic subtest: vcs0
[304.965984] Dynamic subtest vcs0: FAIL (0.713s)
[305.007917] Starting dynamic subtest: vcs1
[306.233052] Dynamic subtest vcs1: FAIL (1.262s)
[306.274275] Starting dynamic subtest: vecs0
[309.495714] Dynamic subtest vecs0: SUCCESS (3.307s)
[309.538066] Starting dynamic subtest: ccs0
[310.173418] Dynamic subtest ccs0: SUCCESS (0.675s)
[310.177168] Subtest render-node-busy-idle: FAIL (7.367s)
[310.355513] [033/142] (723s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[310.943778] Starting subtest: cursorB-vs-flipB-legacy
[310.956087] Subtest cursorB-vs-flipB-legacy: SKIP (0.008s)
[311.260226] [034/142] (722s left) vc4/vc4_purgeable_bo (free-purged-bo)
[311.736876] Subtest free-purged-bo: SKIP (0.000s)
[311.869469] [035/142] (722s left) kms_plane (pixel-format-source-clamping)
[312.370404] Starting subtest: pixel-format-source-clamping
[312.380126] Starting dynamic subtest: pipe-A-planes
[330.395838] Dynamic subtest pipe-A-planes: SUCCESS (18.015s)
[330.446066] Starting dynamic subtest: pipe-B-planes
[354.538884] Dynamic subtest pipe-B-planes: FAIL (23.347s)
[354.542720] Subtest pixel-format-source-clamping: FAIL (41.385s)
[354.660239] [036/142] (679s left) i915_pm_rpm (legacy-planes-dpms)
[360.760309] Starting subtest: legacy-planes-dpms
[360.763853] Starting dynamic subtest: plane-40
[385.439091] Dynamic subtest plane-40: SUCCESS (24.682s)
[385.495055] Starting dynamic subtest: plane-49
[410.119952] Dynamic subtest plane-49: SUCCESS (24.677s)
[410.165624] Starting dynamic subtest: plane-58
[434.872062] Dynamic subtest plane-58: SUCCESS (24.747s)
[434.917673] Starting dynamic subtest: plane-67
[459.481450] Dynamic subtest plane-67: SUCCESS (24.607s)
[459.484818] Subtest legacy-planes-dpms: SUCCESS (98.730s)
[460.801209] [037/142] (573s left) gen9_exec_parse (bb-secure)
[461.197570] Subtest bb-secure: SKIP (0.000s)
[461.329322] [038/142] (573s left) kms_psr (cursor_mmap_cpu)
[461.827281] Starting subtest: cursor_mmap_cpu
[464.814261] Subtest cursor_mmap_cpu: SUCCESS (2.985s)
[465.759769] [039/142] (568s left) core_hotunplug (unbind-rebind)
[466.206338] Starting subtest: unbind-rebind
[473.777489] Subtest unbind-rebind: SUCCESS (7.576s)
[473.881237] [040/142] (560s left) kms_cursor_crc (cursor-onscreen-256x256)
[476.195433] Starting subtest: cursor-onscreen-256x256
[476.271133] Starting dynamic subtest: pipe-A-eDP-1
[480.251521] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.981s)
[480.453142] Starting dynamic subtest: pipe-D-eDP-1
[485.380657] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.972s)
[485.384365] Subtest cursor-onscreen-256x256: SUCCESS (9.188s)
[486.345685] [041/142] (548s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_mtl_mc_ccs)
[487.527727] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_mtl_mc_ccs
[496.192439] Subtest pipe-B-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SUCCESS (8.665s)
[497.693880] [042/142] (537s left) kms_ccs (pipe-d-random-ccs-data-4_tiled_dg2_rc_ccs)
[499.393306] Starting subtest: pipe-D-random-ccs-data-4_tiled_dg2_rc_ccs
[499.402316] Subtest pipe-D-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[500.085797] [043/142] (534s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[500.600979] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[500.607894] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[500.773161] [044/142] (534s left) gem_ctx_sseu (mmap-args)
[501.179949] Subtest mmap-args: SKIP (0.000s)
[501.301780] [045/142] (533s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[501.794838] Starting subtest: primary-yf-tiled-reflect-x-180
[501.940839] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.148s)
[502.202855] [046/142] (533s left) i915_pm_rc6_residency (rc6-accuracy)
[503.820899] Subtest rc6-accuracy: SKIP (0.000s)
[503.966433] [047/142] (531s left) syncobj_timeline (signal-array)
[504.345646] Starting subtest: signal-array
[504.349296] Subtest signal-array: SUCCESS (0.002s)
[504.499901] [048/142] (530s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[504.895411] Starting subtest: etime-multi-wait-all-submitted-signaled
[505.007294] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.112s)
[505.166414] [049/142] (530s left) gem_flink_race (flink_close)
[505.541167] Starting subtest: flink_close
[510.622335] Subtest flink_close: SUCCESS (5.084s)
[510.809611] [050/142] (524s left) v3d/v3d_submit_cl (bad-in-sync)
[511.312343] Subtest bad-in-sync: SKIP (0.000s)
[511.448143] [051/142] (524s left) perf (non-zero-reason)
[512.299280] Starting subtest: non-zero-reason
[512.302774] Starting dynamic subtest: 0-rcs0
[523.860969] Dynamic subtest 0-rcs0: SUCCESS (11.559s)
[523.881453] Starting dynamic subtest: 1-vcs0
[531.946076] Dynamic subtest 1-vcs0: SUCCESS (8.082s)
[531.949692] Subtest non-zero-reason: SUCCESS (19.647s)
[532.040719] [052/142] (503s left) gem_exec_schedule (promotion)
[532.477843] Starting subtest: promotion
[532.481827] Starting dynamic subtest: rcs0
[532.985297] Dynamic subtest rcs0: SUCCESS (0.505s)
[533.040098] Starting dynamic subtest: bcs0
[533.382333] Dynamic subtest bcs0: SUCCESS (0.393s)
[533.434685] Starting dynamic subtest: vcs0
[533.707740] Dynamic subtest vcs0: SUCCESS (0.320s)
[533.759465] Starting dynamic subtest: vcs1
[533.998847] Dynamic subtest vcs1: SUCCESS (0.286s)
[534.054955] Starting dynamic subtest: vecs0
[534.294971] Dynamic subtest vecs0: SUCCESS (0.292s)
[534.347668] Starting dynamic subtest: ccs0
[534.706527] Dynamic subtest ccs0: SUCCESS (0.408s)
[534.710609] Subtest promotion: SUCCESS (2.230s)
[535.247332] [053/142] (500s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[535.639812] Starting subtest: multi-wait-for-submit-available-submitted
[535.644146] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.004s)
[535.835345] [054/142] (500s left) perf (disabled-read-error)
[536.509118] Starting subtest: disabled-read-error
[536.570679] Subtest disabled-read-error: SUCCESS (0.063s)
[536.696446] [055/142] (499s left) gem_mmap_wc (bad-offset)
[537.081421] Subtest bad-offset: SKIP (0.000s)
[537.216987] [056/142] (498s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[537.729995] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[537.737129] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[537.915299] [057/142] (498s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[538.800385] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[539.675806] [058/142] (496s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[540.058672] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[540.062855] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[540.217890] [059/142] (496s left) kms_chamelium_frames (hdmi-crc-multiple)
[541.111954] Subtest hdmi-crc-multiple: SKIP (0.000s)
[541.990300] [060/142] (494s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[542.504594] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[553.750480] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (11.247s)
[553.912289] [061/142] (482s left) gem_exec_reloc (basic-concurrent0)
[554.317991] Subtest basic-concurrent0: SKIP (0.000s)
[554.459358] [062/142] (482s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[554.878858] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[555.049134] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.166s)
[555.235582] [063/142] (481s left) gem_mmap_gtt (flink-race)
[555.614485] Subtest flink-race: SKIP (0.000s)
[555.750098] [064/142] (481s left) syncobj_basic (test-valid-cycle)
[556.122288] Starting subtest: test-valid-cycle
[556.126957] Subtest test-valid-cycle: SUCCESS (0.000s)
[556.281182] [065/142] (480s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[556.769579] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[556.782738] Starting dynamic subtest: pipe-A-default-mode
[557.183981] Dynamic subtest pipe-A-default-mode: SKIP (0.398s)
[557.191119] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.412s)
[558.132645] [066/142] (478s left) kms_ccs (pipe-d-bad-aux-stride-y_tiled_gen12_mc_ccs)
[559.913386] Starting subtest: pipe-D-bad-aux-stride-y_tiled_gen12_mc_ccs
[559.920537] Subtest pipe-D-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[560.531243] [067/142] (476s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[561.036150] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[561.043193] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[561.211840] [068/142] (476s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[561.753244] Starting subtest: y-tiled-64bpp-rotate-90
[561.760233] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[561.927583] [069/142] (475s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[562.439414] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu
[574.019884] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (11.587s)
[574.306152] [070/142] (463s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[574.832159] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[574.839246] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[575.010425] [071/142] (462s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[575.518816] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[580.953365] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (5.443s)
[581.236346] [072/142] (456s left) gem_pxp (create-regular-buffer)
[584.152743] Subtest create-regular-buffer: SKIP (0.000s)
[584.339601] [073/142] (453s left) vgem_slow (nohang)
[584.738975] Starting subtest: nohang
[595.193257] Subtest nohang: SUCCESS (10.458s)
[595.358589] [074/142] (442s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[595.792137] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[595.966904] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.171s)
[596.147292] [075/142] (441s left) gem_exec_schedule (smoketest-all)
[596.590708] Starting subtest: smoketest-all
[629.470337] Subtest smoketest-all: SUCCESS (32.811s)
[630.710617] [076/142] (407s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[631.122932] Starting subtest: y-tiled-ccs-to-x-tiled
[631.129307] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[631.441081] [077/142] (406s left) kms_invalid_mode (bad-hsync-end)
[631.935582] Starting subtest: bad-hsync-end
[631.950054] Starting dynamic subtest: eDP-1-pipe-A
[632.351056] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.404s)
[632.413183] Starting dynamic subtest: eDP-1-pipe-B
[632.417077] Dynamic subtest eDP-1-pipe-B: SUCCESS (0.038s)
[632.472194] Starting dynamic subtest: eDP-1-pipe-C
[632.476306] Dynamic subtest eDP-1-pipe-C: SUCCESS (0.038s)
[632.531203] Starting dynamic subtest: eDP-1-pipe-D
[632.535069] Dynamic subtest eDP-1-pipe-D: SUCCESS (0.038s)
[632.538805] Subtest bad-hsync-end: SUCCESS (0.557s)
[633.284552] [078/142] (404s left) gem_exec_fence (syncobj-repeat)
[635.195936] Starting subtest: syncobj-repeat
[636.629828] Subtest syncobj-repeat: SUCCESS (1.433s)
[637.103771] [079/142] (401s left) kms_ccs (pipe-a-bad-aux-stride-4_tiled_mtl_rc_ccs_cc)
[638.167089] Starting subtest: pipe-A-bad-aux-stride-4_tiled_mtl_rc_ccs_cc
[638.170804] Subtest pipe-A-bad-aux-stride-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.003s)
[639.490149] [080/142] (398s left) vgem_basic (bad-handle)
[639.860954] Starting subtest: bad-handle
[639.864580] Subtest bad-handle: SUCCESS (0.000s)
[640.004102] [081/142] (398s left) gem_eio (execbuf)
[641.956890] Starting subtest: execbuf
[645.051938] Subtest execbuf: SUCCESS (3.092s)
[646.671517] [082/142] (391s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[647.091293] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[647.098120] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[647.381505] [083/142] (391s left) kms_writeback (writeback-fb-id)
[647.977902] Subtest writeback-fb-id: SKIP (0.000s)
[648.108245] [084/142] (390s left) kms_ccs (pipe-b-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc)
[649.370695] Starting subtest: pipe-B-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc
[649.376365] Subtest pipe-B-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[650.513997] [085/142] (388s left) kms_psr (psr2_cursor_plane_onoff)
[650.996296] Starting subtest: psr2_cursor_plane_onoff
[654.015152] Subtest psr2_cursor_plane_onoff: SUCCESS (3.017s)
[654.972553] [086/142] (384s left) kms_addfb_basic (addfb25-bad-modifier)
[655.347628] Starting subtest: addfb25-bad-modifier
[655.351182] Subtest addfb25-bad-modifier: SUCCESS (0.002s)
[655.640786] [087/142] (383s left) v3d/v3d_submit_csd (bad-perfmon)
[656.116983] Subtest bad-perfmon: SKIP (0.000s)
[656.265549] [088/142] (382s left) kms_big_fb (linear-64bpp-rotate-180)
[656.795084] Starting subtest: linear-64bpp-rotate-180
[662.948587] Subtest linear-64bpp-rotate-180: SUCCESS (6.148s)
[663.746355] [089/142] (375s left) api_intel_bb (intel-bb-blit-y)
[664.134344] Starting subtest: intel-bb-blit-y
[676.796799] Subtest intel-bb-blit-y: SUCCESS (12.665s)
[677.028526] [090/142] (362s left) kms_content_protection (uevent)
[677.748157] Starting subtest: uevent
[681.863195] Subtest uevent: SKIP (4.116s)
[683.399340] [091/142] (356s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_ccs)
[684.779235] Starting subtest: pipe-C-bad-rotation-90-y_tiled_ccs
[684.784994] Subtest pipe-C-bad-rotation-90-y_tiled_ccs: SKIP (0.000s)
[685.810685] [092/142] (353s left) gem_mmap_gtt (medium-copy-odd)
[686.181476] Subtest medium-copy-odd: SKIP (0.000s)
[686.311167] [093/142] (353s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[686.684345] Starting subtest: single-wait-for-submit-unsubmitted
[686.790556] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.106s)
[686.951371] [094/142] (352s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[687.465500] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[687.472221] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[687.639059] [095/142] (352s left) gem_eio (in-flight-immediate)
[689.700861] Starting subtest: in-flight-immediate
[728.777942] Subtest in-flight-immediate: SUCCESS (39.075s)
[730.640905] [096/142] (309s left) kms_async_flips (alternate-sync-async-flip)
[731.135135] Starting subtest: alternate-sync-async-flip
[733.303499] Starting dynamic subtest: pipe-A-eDP-1
[735.170276] Dynamic subtest pipe-A-eDP-1: FAIL (1.261s)
[736.789563] Starting dynamic subtest: pipe-B-eDP-1
[739.259619] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.525s)
[741.083357] Starting dynamic subtest: pipe-C-eDP-1
[743.578722] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.548s)
[745.040656] Starting dynamic subtest: pipe-D-eDP-1
[747.528567] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.540s)
[747.532563] Subtest alternate-sync-async-flip: FAIL (16.398s)
[749.114359] [097/142] (290s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_mtl_mc_ccs)
[750.279096] Starting subtest: pipe-B-crc-primary-basic-4_tiled_mtl_mc_ccs
[759.017641] Subtest pipe-B-crc-primary-basic-4_tiled_mtl_mc_ccs: SUCCESS (8.738s)
[760.725739] [098/142] (279s left) gem_exec_whisper (basic-fds-priority-all)
[761.150534] Starting subtest: basic-fds-priority-all
[807.556830] Subtest basic-fds-priority-all: SUCCESS (46.400s)
[807.919641] [099/142] (232s left) gem_vm_create (invalid-create)
[808.328460] Starting subtest: invalid-create
[808.337236] Subtest invalid-create: SUCCESS (0.004s)
[808.533266] [100/142] (231s left) gem_lmem_swapping (heavy-random)
[808.935739] Subtest heavy-random: SKIP (0.000s)
[809.081870] [101/142] (231s left) gem_exec_balancer (individual)
[809.520025] Starting subtest: individual
[817.373534] Subtest individual: SUCCESS (7.848s)
[818.412634] [102/142] (222s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[818.924595] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[818.931706] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[819.107202] [103/142] (221s left) kms_scaling_modes (scaling-mode-full)
[819.578355] Starting subtest: scaling-mode-full
[819.581700] Starting dynamic subtest: eDP-1-pipe-A
[819.902891] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.322s)
[819.962545] Starting dynamic subtest: eDP-1-pipe-B
[821.489956] Dynamic subtest eDP-1-pipe-B: SUCCESS (1.578s)
[821.562183] Starting dynamic subtest: eDP-1-pipe-C
[823.180969] Dynamic subtest eDP-1-pipe-C: SUCCESS (1.682s)
[823.244548] Starting dynamic subtest: eDP-1-pipe-D
[824.852352] Dynamic subtest eDP-1-pipe-D: SUCCESS (1.661s)
[824.856579] Subtest scaling-mode-full: SUCCESS (5.277s)
[826.374411] [104/142] (214s left) gem_exec_schedule (submit-early-slice)
[826.830371] Starting subtest: submit-early-slice
[826.833990] Starting dynamic subtest: rcs0
[826.973898] Dynamic subtest rcs0: SUCCESS (0.141s)
[827.034097] Starting dynamic subtest: bcs0
[827.103215] Dynamic subtest bcs0: SUCCESS (0.126s)
[827.162147] Starting dynamic subtest: vcs0
[827.224955] Dynamic subtest vcs0: SUCCESS (0.117s)
[827.286835] Starting dynamic subtest: vcs1
[827.365892] Dynamic subtest vcs1: SUCCESS (0.137s)
[827.430971] Starting dynamic subtest: vecs0
[827.502564] Dynamic subtest vecs0: SUCCESS (0.131s)
[827.563654] Starting dynamic subtest: ccs0
[827.631828] Dynamic subtest ccs0: SUCCESS (0.126s)
[827.635768] Subtest submit-early-slice: SUCCESS (0.802s)
[828.718209] [105/142] (212s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[829.211936] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[829.219429] Starting dynamic subtest: pipe-A-default-mode
[830.763288] Dynamic subtest pipe-A-default-mode: SKIP (1.536s)
[830.770182] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.549s)
[831.049212] [106/142] (209s left) sysfs_heartbeat_interval (invalid)
[831.447779] Starting subtest: invalid
[831.456768] Starting dynamic subtest: vecs0
[831.460379] Dynamic subtest vecs0: SUCCESS (0.001s)
[831.522925] Starting dynamic subtest: ccs0
[831.526912] Dynamic subtest ccs0: SUCCESS (0.001s)
[831.587982] Starting dynamic subtest: vcs0
[831.591654] Dynamic subtest vcs0: SUCCESS (0.001s)
[831.651440] Starting dynamic subtest: bcs0
[831.654865] Dynamic subtest bcs0: SUCCESS (0.001s)
[831.712615] Starting dynamic subtest: rcs0
[831.716149] Dynamic subtest rcs0: SUCCESS (0.001s)
[831.774287] Starting dynamic subtest: vcs1
[831.777698] Dynamic subtest vcs1: SUCCESS (0.001s)
[831.781146] Subtest invalid: SUCCESS (0.035s)
[831.858242] [107/142] (209s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[832.404555] Starting subtest: 4-tiled-16bpp-rotate-0
[836.861961] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (4.455s)
[837.334742] [108/142] (203s left) kms_mmap_write_crc (main)
[837.853451] Starting subtest: main
[837.862230] Starting dynamic subtest: pipe-A-eDP-1
[847.560688] Dynamic subtest pipe-A-eDP-1: SUCCESS (9.695s)
[847.564349] Subtest main: SUCCESS (9.704s)
[848.546513] [109/142] (192s left) gem_spin_batch (resubmit-new-all)
[848.967483] Starting subtest: resubmit-new-all
[848.971286] Starting dynamic subtest: rcs0
[849.002557] Dynamic subtest rcs0: SUCCESS (0.034s)
[849.066630] Starting dynamic subtest: bcs0
[849.070270] Dynamic subtest bcs0: SUCCESS (0.024s)
[849.132482] Starting dynamic subtest: vcs0
[849.136169] Dynamic subtest vcs0: SUCCESS (0.022s)
[849.195538] Starting dynamic subtest: vcs1
[849.198981] Dynamic subtest vcs1: SUCCESS (0.023s)
[849.256799] Starting dynamic subtest: vecs0
[849.260296] Dynamic subtest vecs0: SUCCESS (0.022s)
[849.320504] Starting dynamic subtest: ccs0
[849.323577] Dynamic subtest ccs0: SUCCESS (0.023s)
[849.326549] Subtest resubmit-new-all: SUCCESS (0.170s)
[850.709331] [110/142] (190s left) kms_ccs (pipe-d-crc-primary-basic-y_tiled_gen12_rc_ccs_cc)
[852.361220] Starting subtest: pipe-D-crc-primary-basic-y_tiled_gen12_rc_ccs_cc
[852.367814] Subtest pipe-D-crc-primary-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.003s)
[853.125539] [111/142] (188s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[853.640689] Starting subtest: fbc-farfromfence-mmap-gtt
[853.647699] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[853.837201] [112/142] (187s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[854.381096] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[858.243119] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (3.861s)
[858.772819] [113/142] (182s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[859.285807] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[859.292766] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[859.470365] [114/142] (182s left) kms_atomic_transition (plane-all-transition-nonblocking)
[859.953770] Starting subtest: plane-all-transition-nonblocking
[859.962449] Starting dynamic subtest: pipe-A-eDP-1
[864.112799] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.152s)
[864.480643] Starting dynamic subtest: pipe-B-eDP-1
[868.663856] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.226s)
[869.002885] Subtest plane-all-transition-nonblocking: SUCCESS (9.047s)
[869.925992] [115/142] (171s left) kms_hdmi_inject (inject-4k)
[870.497726] Starting subtest: inject-4k
[870.902609] Subtest inject-4k: SUCCESS (0.408s)
[871.469112] [116/142] (170s left) gem_exec_create (legacy)
[871.782882] Starting subtest: legacy
[871.785992] Starting dynamic subtest: smem
[875.522556] Dynamic subtest smem: SUCCESS (3.737s)
[875.526280] Subtest legacy: SUCCESS (3.741s)
[875.855253] [117/142] (166s left) kms_addfb_basic (clobberred-modifier)
[876.241103] Starting subtest: clobberred-modifier
[876.247942] Subtest clobberred-modifier: SKIP (0.000s)
[876.542136] [118/142] (165s left) kms_flip (2x-flip-vs-panning-interruptible)
[876.976514] Starting subtest: 2x-flip-vs-panning-interruptible
[877.149513] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.168s)
[877.335664] [119/142] (164s left) gem_eio (unwedge-stress)
[879.357353] Starting subtest: unwedge-stress
[966.546173] Subtest unwedge-stress: SUCCESS (87.185s)
[968.670885] [120/142] ( 73s left) kms_cursor_edge_walk (128x128-top-edge)
[969.160988] Starting subtest: 128x128-top-edge
[969.165178] Starting dynamic subtest: pipe-A-eDP-1
[973.043154] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.875s)
[973.110735] Starting dynamic subtest: pipe-D-eDP-1
[976.418618] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.364s)
[976.422504] Subtest 128x128-top-edge: SUCCESS (7.261s)
[977.407884] [121/142] ( 65s left) kms_dsc (dsc-basic)
[977.885575] Starting subtest: dsc-basic
[977.892338] Subtest dsc-basic: SKIP (0.003s)
[978.052365] [122/142] ( 64s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_ccs)
[979.107987] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_ccs
[979.124838] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_ccs: SKIP (0.013s)
[980.292946] [123/142] ( 62s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[980.697363] Starting subtest: basic-flip-before-cursor-legacy
[982.103093] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.408s)
[983.033783] [124/142] ( 59s left) i915_query (engine-info)
[983.194551] Starting subtest: engine-info
[983.206706] Subtest engine-info: SUCCESS (0.014s)
[983.369688] [125/142] ( 59s left) gem_partial_pwrite_pread (reads)
[983.751071] Subtest reads: SKIP (0.000s)
[983.908527] [126/142] ( 58s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_mc_ccs)
[985.353896] Starting subtest: pipe-D-bad-pixel-format-y_tiled_gen12_mc_ccs
[985.359920] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[985.937540] [127/142] ( 57s left) gem_eio (create-ext)
[987.796127] Starting subtest: create-ext
[990.731194] Subtest create-ext: SUCCESS (2.933s)
[992.669743] [128/142] ( 50s left) v3d/v3d_perfmon (get-values-invalid-pad)
[993.147262] Subtest get-values-invalid-pad: SKIP (0.000s)
[993.297458] [129/142] ( 49s left) syncobj_timeline (invalid-signal-illegal-handle)
[993.681558] Starting subtest: invalid-signal-illegal-handle
[993.685091] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[993.859457] [130/142] ( 49s left) kms_prime (d3hot)
[996.136888] Subtest D3hot: SKIP (0.000s)
[996.320769] [131/142] ( 47s left) kms_tiled_display (basic-test-pattern)
[996.827841] Subtest basic-test-pattern: SKIP (0.000s)
[996.968185] [132/142] ( 46s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[997.492813] Starting subtest: yf-tiled-8bpp-rotate-0
[997.500053] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[997.685604] [133/142] ( 45s left) kms_addfb_basic (invalid-get-prop-any)
[998.069620] Starting subtest: invalid-get-prop-any
[998.072789] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[998.355298] [134/142] ( 45s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_mtl_rc_ccs)
[999.263136] Starting subtest: pipe-B-bad-pixel-format-4_tiled_mtl_rc_ccs
[999.266869] Subtest pipe-B-bad-pixel-format-4_tiled_mtl_rc_ccs: SUCCESS (0.001s)
[1000.190714] [135/142] ( 43s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[1000.757163] Starting subtest: basic-busy-flip-before-cursor-atomic
[1000.764454] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[1001.053967] [136/142] ( 42s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[1001.449917] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[1001.456928] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[1001.744328] [137/142] ( 42s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[1002.123343] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[1002.230822] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.106s)
[1002.396559] [138/142] ( 41s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[1002.880247] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[1005.431752] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (2.546s)
[1005.718928] [139/142] ( 38s left) gem_eio (suspend)
[1007.458156] Starting subtest: suspend
[1022.385845] Subtest suspend: SUCCESS (14.925s)
[1023.707244] [140/142] ( 20s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[1024.196229] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[1024.203084] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[1024.387756] [141/142] ( 20s left) kms_color (ctm-green-to-red)
[1024.870443] Starting subtest: ctm-green-to-red
[1024.874195] Starting dynamic subtest: pipe-A
[1026.063231] Dynamic subtest pipe-A: SUCCESS (1.188s)
[1026.111956] Starting dynamic subtest: pipe-B
[1027.988560] Dynamic subtest pipe-B: SUCCESS (1.923s)
[1028.046209] Starting dynamic subtest: pipe-C
[1029.621956] Dynamic subtest pipe-C: SUCCESS (1.623s)
[1029.678619] Starting dynamic subtest: pipe-D
[1031.161261] Dynamic subtest pipe-D: SUCCESS (1.535s)
[1031.164171] Subtest ctm-green-to-red: SUCCESS (6.284s)
[1032.111209] [142/142] ( 12s left) kms_universal_plane (universal-plane-pipe-a-functional)
[1032.604771] Starting subtest: universal-plane-pipe-A-functional
[1037.507400] Subtest universal-plane-pipe-A-functional: SUCCESS (4.905s)
[1038.404133] Closing watchdogs
Done.