[39.443369] Initializing watchdogs
[39.443399]   /dev/watchdog0
[39.449924] [001/130] (960s left) gem_mmap_wc (write-read)
[39.507377] Subtest write-read: SKIP (0.000s)
[39.547290] [002/130] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[39.648836] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[44.282325] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.635s)
[44.379978] [003/130] (955s left) kms_cursor_crc (cursor-offscreen-256x256)
[44.577756] Starting subtest: cursor-offscreen-256x256
[44.580150] Starting dynamic subtest: pipe-A-eDP-1
[46.500153] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.917s)
[46.518845] Starting dynamic subtest: pipe-D-eDP-1
[49.159386] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.644s)
[49.161793] Subtest cursor-offscreen-256x256: SUCCESS (4.581s)
[50.026219] [004/130] (949s left) gem_pwrite_snooped
[50.114300] [005/130] (949s left) kms_cursor_crc (cursor-sliding-256x256)
[50.286303] Starting subtest: cursor-sliding-256x256
[50.288664] Starting dynamic subtest: pipe-A-eDP-1
[53.461327] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.171s)
[53.478697] Starting dynamic subtest: pipe-D-eDP-1
[57.399755] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.923s)
[57.402647] Subtest cursor-sliding-256x256: SUCCESS (7.111s)
[58.267997] [006/130] (941s left) gem_render_copy (linear)
[58.323679] Starting subtest: linear
[58.326164] Starting dynamic subtest: smem
[58.362915] Dynamic subtest smem: SUCCESS (0.039s)
[58.365573] Subtest linear: SUCCESS (0.039s)
[58.566785] [007/130] (941s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[58.665172] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[58.667887] Starting dynamic subtest: pipe-A-eDP-1
[59.351958] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.677s)
[59.357863] Starting dynamic subtest: pipe-B-eDP-1
[60.960809] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.609s)
[60.967763] Starting dynamic subtest: pipe-C-eDP-1
[62.525494] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.563s)
[62.531832] Starting dynamic subtest: pipe-D-eDP-1
[64.062204] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.535s)
[64.064448] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.386s)
[65.293487] [008/130] (934s left) syncobj_timeline (multi-wait-for-submit-signaled)
[65.343066] Starting subtest: multi-wait-for-submit-signaled
[65.345459] Subtest multi-wait-for-submit-signaled: SUCCESS (0.001s)
[65.415021] [009/130] (934s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[65.467622] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[65.503700] [010/130] (934s left) gem_mmap_gtt (bad-object)
[65.557808] Subtest bad-object: SKIP (0.000s)
[65.595010] [011/130] (934s left) gem_ctx_persistence (many-contexts)
[65.645282] Starting subtest: many-contexts
[103.329357] Subtest many-contexts: SUCCESS (37.684s)
[103.386466] [012/130] (896s left) gem_render_copy_redux (interruptible)
[103.428074] Starting subtest: interruptible
[103.798272] Subtest interruptible: SUCCESS (0.371s)
[103.865244] [013/130] (896s left) gem_tiling_max_stride
[103.938721] [014/130] (896s left) gem_close_race (basic-threads)
[103.989190] Starting subtest: basic-threads
[105.199864] Subtest basic-threads: SUCCESS (1.210s)
[105.359951] [015/130] (894s left) gem_mmap_gtt (basic-small-copy)
[105.401052] Subtest basic-small-copy: SKIP (0.000s)
[105.432451] [016/130] (894s left) kms_cursor_crc (cursor-random-64x64)
[105.558505] Starting subtest: cursor-random-64x64
[105.562973] Starting dynamic subtest: pipe-A-eDP-1
[108.868840] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.306s)
[108.885897] Starting dynamic subtest: pipe-D-eDP-1
[112.938872] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.056s)
[112.941785] Subtest cursor-random-64x64: SUCCESS (7.379s)
[113.805783] [017/130] (886s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[118.921213] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[118.923867] Starting dynamic subtest: pipe-A-eDP-1
[131.046990] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.125s)
[131.052771] Starting dynamic subtest: pipe-B-eDP-1
[143.146288] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.096s)
[143.148635] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (24.224s)
[144.018171] [018/130] (856s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[144.104782] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[144.107065] Starting dynamic subtest: pipe-A
[144.116067] Dynamic subtest pipe-A: SKIP (0.004s)
[144.122101] Starting dynamic subtest: pipe-B
[144.130879] Dynamic subtest pipe-B: SKIP (0.013s)
[144.136449] Starting dynamic subtest: pipe-C
[144.145392] Dynamic subtest pipe-C: SKIP (0.013s)
[144.150776] Starting dynamic subtest: pipe-D
[144.158173] Dynamic subtest pipe-D: SKIP (0.014s)
[144.161811] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.044s)
[144.218025] [019/130] (855s left) gem_exercise_blt (fast-copy-emit)
[144.269958] Starting subtest: fast-copy-emit
[144.272521] Starting dynamic subtest: linear-smem-smem-emit
[144.289553] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.021s)
[144.297436] Starting dynamic subtest: xmajor-smem-smem-emit
[144.307060] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.017s)
[144.312506] Starting dynamic subtest: tile4-smem-smem-emit
[144.324262] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.017s)
[144.329762] Starting dynamic subtest: tile64-smem-smem-emit
[144.339958] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.015s)
[144.342332] Subtest fast-copy-emit: SUCCESS (0.072s)
[144.440277] [020/130] (855s left) kms_addfb_basic (basic-x-tiled-legacy)
[144.486331] Starting subtest: basic-x-tiled-legacy
[144.491515] Subtest basic-x-tiled-legacy: SKIP (0.000s)
[144.589214] [021/130] (855s left) kms_selftest (drm_plane_helper)
[144.635661] Starting subtest: drm_plane_helper
[144.643735] Starting dynamic subtest: drm_test_check_plane_state
[144.735520] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.092s)
[144.743106] Starting dynamic subtest: drm_test_check_invalid_plane_state
[144.836714] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.101s)
[144.839148] Subtest drm_plane_helper: SUCCESS (0.201s)
[144.980772] [022/130] (855s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[145.076043] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[149.379797] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.305s)
[149.492125] [023/130] (850s left) kms_pwrite_crc
[151.118878] [024/130] (849s left) api_intel_allocator (execbuf-with-allocator)
[151.167164] Starting subtest: execbuf-with-allocator
[151.171811] Subtest execbuf-with-allocator: SUCCESS (0.006s)
[151.252665] [025/130] (849s left) kms_chamelium_hpd (dp-hpd-fast)
[151.623056] Subtest dp-hpd-fast: SKIP (0.000s)
[152.503581] [026/130] (847s left) kms_pm_rpm (i2c)
[154.515566] Starting subtest: i2c
[160.402096] Subtest i2c: SUCCESS (5.886s)
[160.466599] [027/130] (839s left) gem_pxp (reject-modify-context-protection-off-2)
[163.044565] Subtest reject-modify-context-protection-off-2: SKIP (0.000s)
[163.089642] [028/130] (837s left) drm_read (empty-nonblock)
[163.239159] Starting subtest: empty-nonblock
[163.242194] Subtest empty-nonblock: SUCCESS (0.000s)
[163.354252] [029/130] (836s left) gem_ctx_isolation (dirty-create)
[163.403792] Starting subtest: dirty-create
[163.406587] Starting dynamic subtest: rcs0
[163.537779] Dynamic subtest rcs0: SUCCESS (0.134s)
[163.545311] Starting dynamic subtest: bcs0
[163.695056] Dynamic subtest bcs0: SUCCESS (0.157s)
[163.701506] Starting dynamic subtest: vcs0
[163.882406] Dynamic subtest vcs0: SUCCESS (0.187s)
[163.888132] Starting dynamic subtest: vcs1
[165.511617] Dynamic subtest vcs1: SUCCESS (1.628s)
[165.519279] Starting dynamic subtest: vecs0
[167.542245] Dynamic subtest vecs0: SUCCESS (2.030s)
[167.550462] Starting dynamic subtest: ccs0
[169.522800] Dynamic subtest ccs0: SUCCESS (1.980s)
[169.525705] Subtest dirty-create: SUCCESS (6.118s)
[169.684418] [030/130] (830s left) prime_mmap_coherency (ioctl-errors)
[169.731803] Starting subtest: ioctl-errors
[181.842830] Subtest ioctl-errors: SUCCESS (12.111s)
[181.948255] [031/130] (818s left) kms_legacy_colorkey (basic)
[182.078682] Starting subtest: basic
[182.080855] Starting dynamic subtest: pipe-A
[182.123954] Dynamic subtest pipe-A: SUCCESS (0.045s)
[182.131155] Starting dynamic subtest: pipe-B
[182.132764] Dynamic subtest pipe-B: SUCCESS (0.000s)
[182.141234] Starting dynamic subtest: pipe-C
[182.143221] Dynamic subtest pipe-C: SUCCESS (0.000s)
[182.151246] Starting dynamic subtest: pipe-D
[182.153033] Dynamic subtest pipe-D: SUCCESS (0.000s)
[182.154782] Subtest basic: SUCCESS (0.048s)
[182.167919] [032/130] (818s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[182.269156] Starting subtest: pipe-fbc-rte
[182.274942] Starting dynamic subtest: pipe-B-eDP-1
[186.710375] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.445s)
[186.719150] Subtest pipe-fbc-rte: SUCCESS (4.447s)
[187.981414] [033/130] (812s left) gem_tiled_wc
[188.061450] [034/130] (812s left) gem_mmap_offset (pf-nonblock)
[188.106668] Starting subtest: pf-nonblock
[188.112487] Subtest pf-nonblock: SUCCESS (0.006s)
[188.205889] [035/130] (812s left) kms_psr (fbc-psr-cursor-blt)
[188.289912] Starting subtest: fbc-psr-cursor-blt
[188.292249] Starting dynamic subtest: eDP-1
[188.296716] Dynamic subtest eDP-1: SKIP (0.000s)
[188.301187] Subtest fbc-psr-cursor-blt: SKIP (0.000s)
[188.337038] [036/130] (812s left) syncobj_wait (wait-any-snapshot)
[188.395620] Starting subtest: wait-any-snapshot
[188.478355] Subtest wait-any-snapshot: SUCCESS (0.082s)
[188.530166] [037/130] (811s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[188.625821] Starting subtest: primary-y-tiled-reflect-x-0
[188.687614] Subtest primary-y-tiled-reflect-x-0: SKIP (0.059s)
[188.755793] [038/130] (811s left) prime_self_import (basic-llseek-size)
[188.782156] Starting subtest: basic-llseek-size
[188.805278] Subtest basic-llseek-size: SUCCESS (0.022s)
[188.867345] [039/130] (811s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[188.921622] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[188.926929] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[189.079554] [040/130] (811s left) gem_mmap_gtt (big-bo-tiledx)
[189.131414] Subtest big-bo-tiledX: SKIP (0.000s)
[189.172373] [041/130] (811s left) kms_plane (plane-panning-bottom-right-suspend)
[189.256216] Starting subtest: plane-panning-bottom-right-suspend
[189.258221] Starting dynamic subtest: pipe-A
[206.946441] Dynamic subtest pipe-A: SUCCESS (2.723s)
[206.954896] Starting dynamic subtest: pipe-B
[225.872126] Dynamic subtest pipe-B: SUCCESS (3.403s)
[225.874569] Subtest plane-panning-bottom-right-suspend: SUCCESS (6.128s)
[226.798830] [042/130] (804s left) syncobj_timeline (host-signal-ordered)
[226.851468] Starting subtest: host-signal-ordered
[226.853311] Subtest host-signal-ordered: SUCCESS (0.000s)
[226.914678] [043/130] (804s left) gem_ctx_shared (q-smoketest)
[226.964292] Starting subtest: Q-smoketest
[226.967063] Starting dynamic subtest: rcs0
[232.479862] Dynamic subtest rcs0: SUCCESS (5.462s)
[232.486821] Starting dynamic subtest: bcs0
[237.926040] Dynamic subtest bcs0: SUCCESS (5.447s)
[237.937437] Starting dynamic subtest: vcs0
[243.374788] Dynamic subtest vcs0: SUCCESS (5.443s)
[243.385253] Starting dynamic subtest: vcs1
[248.841597] Dynamic subtest vcs1: SUCCESS (5.470s)
[248.848887] Starting dynamic subtest: vecs0
[254.303476] Dynamic subtest vecs0: SUCCESS (5.455s)
[254.316902] Starting dynamic subtest: ccs0
[259.772047] Dynamic subtest ccs0: SUCCESS (5.467s)
[259.774432] Subtest Q-smoketest: SUCCESS (32.748s)
[259.937531] [044/130] (771s left) gem_spin_batch (spin-all)
[259.984878] Starting subtest: spin-all
[259.996104] Subtest spin-all: SUCCESS (0.011s)
[261.903179] [045/130] (769s left) gem_eio (in-flight-contexts-immediate)
[262.135918] Starting subtest: in-flight-contexts-immediate
[275.856429] Subtest in-flight-contexts-immediate: SUCCESS (13.716s)
[276.010169] [046/130] (755s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[276.105389] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[276.118843] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.013s)
[276.172973] [047/130] (754s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[276.275531] Starting subtest: 4-tiled-8bpp-rotate-270
[276.294745] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.019s)
[276.396294] [048/130] (754s left) kms_flip (flip-vs-rmfb-interruptible)
[276.490413] Starting subtest: flip-vs-rmfb-interruptible
[276.495342] Starting dynamic subtest: A-eDP1
[285.215023] Dynamic subtest A-eDP1: SUCCESS (8.721s)
[285.223156] Starting dynamic subtest: B-eDP1
[293.920012] Dynamic subtest B-eDP1: SUCCESS (8.702s)
[293.927868] Starting dynamic subtest: C-eDP1
[302.612096] Dynamic subtest C-eDP1: SUCCESS (8.690s)
[302.620266] Starting dynamic subtest: D-eDP1
[311.316113] Dynamic subtest D-eDP1: SUCCESS (8.702s)
[311.320746] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.823s)
[312.543644] [049/130] (718s left) kms_flip (flip-vs-modeset-vs-hang)
[312.635517] Starting subtest: flip-vs-modeset-vs-hang
[312.637862] Starting dynamic subtest: A-eDP1
[316.664186] Dynamic subtest A-eDP1: SUCCESS (4.026s)
[316.671361] Starting dynamic subtest: D-eDP1
[319.832947] Dynamic subtest D-eDP1: SUCCESS (3.168s)
[319.835160] Subtest flip-vs-modeset-vs-hang: SUCCESS (7.199s)
[320.141637] [050/130] (711s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size)
[320.257217] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size
[349.954212] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (29.702s)
[350.814766] [051/130] (680s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[350.912964] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[355.980705] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (5.069s)
[356.088163] [052/130] (675s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[356.192769] Starting subtest: psr-rgb565-draw-render
[360.846268] Subtest psr-rgb565-draw-render: SUCCESS (4.657s)
[360.948384] [053/130] (670s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[360.993411] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[360.995630] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[361.048333] [054/130] (670s left) gem_exec_reloc (basic-wc-active)
[361.100316] Subtest basic-wc-active: SKIP (0.000s)
[361.161082] [055/130] (670s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[361.246208] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[361.924715] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.681s)
[362.060874] [056/130] (669s left) syncobj_timeline (wait-all-delayed-signal)
[362.111639] Starting subtest: wait-all-delayed-signal
[362.212251] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[362.269463] [057/130] (668s left) kms_invalid_mode (uint-max-clock)
[362.353421] Starting subtest: uint-max-clock
[362.355758] Starting dynamic subtest: pipe-A-eDP-1
[362.632847] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.279s)
[362.640546] Starting dynamic subtest: pipe-B-eDP-1
[362.643325] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[362.650293] Starting dynamic subtest: pipe-C-eDP-1
[362.653127] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[362.659995] Starting dynamic subtest: pipe-D-eDP-1
[362.662537] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[362.664963] Subtest uint-max-clock: SUCCESS (0.287s)
[363.554599] [058/130] (667s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[363.644989] Starting subtest: fbc-2p-pri-indfb-multidraw
[363.649203] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[363.714499] [059/130] (667s left) prime_mmap (test_map_unmap)
[363.765864] Starting subtest: test_map_unmap
[363.767762] Starting dynamic subtest: test_map_unmap-smem
[363.784200] Dynamic subtest test_map_unmap-smem: SUCCESS (0.021s)
[363.786912] Subtest test_map_unmap: SUCCESS (0.022s)
[363.873581] [060/130] (667s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[363.923487] Starting subtest: rel-constants-invalid-rel-gen5
[363.926274] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[364.001163] [061/130] (667s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[364.099621] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[364.103518] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[364.186592] [062/130] (667s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-blt)
[364.284450] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-blt
[367.091071] Subtest fbc-1p-offscren-pri-shrfb-draw-blt: SUCCESS (2.812s)
[367.213610] [063/130] (664s left) gem_exec_schedule (promotion)
[367.277179] Starting subtest: promotion
[367.278852] Starting dynamic subtest: rcs0
[367.362060] Dynamic subtest rcs0: SUCCESS (0.092s)
[367.372110] Starting dynamic subtest: vcs0
[367.431863] Dynamic subtest vcs0: SUCCESS (0.070s)
[367.438546] Starting dynamic subtest: vcs1
[367.501182] Dynamic subtest vcs1: SUCCESS (0.069s)
[367.510434] Starting dynamic subtest: vecs0
[367.570398] Dynamic subtest vecs0: SUCCESS (0.069s)
[367.579885] Starting dynamic subtest: ccs0
[367.652452] Dynamic subtest ccs0: SUCCESS (0.082s)
[367.655051] Subtest promotion: SUCCESS (0.382s)
[368.979234] [064/130] (662s left) drm_fdinfo (context-close-stress)
[369.012203] Starting subtest: context-close-stress
[379.754917] Subtest context-close-stress: SUCCESS (10.742s)
[379.814974] [065/130] (651s left) gen9_exec_parse (bb-large)
[380.026274] Subtest bb-large: SKIP (0.000s)
[380.062153] [066/130] (651s left) i915_pm_rpm (reg-read-ioctl)
[382.085301] Starting subtest: reg-read-ioctl
[384.387519] Subtest reg-read-ioctl: SUCCESS (2.303s)
[384.714784] [067/130] (646s left) kms_feature_discovery (display-1x)
[384.810954] Starting subtest: display-1x
[384.813277] Subtest display-1x: SUCCESS (0.000s)
[384.861851] [068/130] (646s left) fbdev (eof)
[384.900176] Starting subtest: eof
[384.902627] Subtest eof: SUCCESS (0.000s)
[384.934784] [069/130] (646s left) template (b)
[384.980744] Starting subtest: B
[384.982768] Subtest B: SUCCESS (0.000s)
[385.050626] [070/130] (646s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[385.132173] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[385.134495] Starting dynamic subtest: pipe-A-eDP-1
[385.160486] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[385.168679] Starting dynamic subtest: pipe-B-eDP-1
[385.193097] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[385.199417] Starting dynamic subtest: pipe-C-eDP-1
[385.223833] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[385.230450] Starting dynamic subtest: pipe-D-eDP-1
[385.254838] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[385.259292] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.003s)
[387.490099] [071/130] (644s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[387.575821] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[388.088326] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.514s)
[388.225471] [072/130] (643s left) vgem_basic (bad-fence)
[388.312012] Starting subtest: bad-fence
[388.313926] Subtest bad-fence: SUCCESS (0.000s)
[388.348312] [073/130] (643s left) kms_lease (lease-invalid-plane)
[388.438182] Starting subtest: lease-invalid-plane
[388.440450] Starting dynamic subtest: pipe-A-eDP-1
[388.442677] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[388.450594] Starting dynamic subtest: pipe-B-eDP-1
[388.452610] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[388.459447] Starting dynamic subtest: pipe-C-eDP-1
[388.461581] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[388.468419] Starting dynamic subtest: pipe-D-eDP-1
[388.470293] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[388.472198] Subtest lease-invalid-plane: SUCCESS (0.003s)
[388.488787] [074/130] (643s left) perf_pmu (busy-start)
[388.519770] Starting subtest: busy-start
[388.522197] Starting dynamic subtest: rcs0
[391.068443] Dynamic subtest rcs0: SUCCESS (2.549s)
[391.077306] Starting dynamic subtest: bcs0
[393.616916] Dynamic subtest bcs0: SUCCESS (2.546s)
[393.627094] Starting dynamic subtest: vcs0
[396.861929] Dynamic subtest vcs0: SUCCESS (3.244s)
[396.872431] Starting dynamic subtest: vcs1
[400.830530] Dynamic subtest vcs1: SUCCESS (3.967s)
[400.840895] Starting dynamic subtest: vecs0
[404.862414] Dynamic subtest vecs0: SUCCESS (4.030s)
[404.871717] Starting dynamic subtest: ccs0
[407.429408] Dynamic subtest ccs0: SUCCESS (2.546s)
[407.432267] Subtest busy-start: SUCCESS (18.888s)
[407.480658] [075/130] (624s left) gem_exec_whisper (basic-fds-all)
[407.543226] Starting subtest: basic-fds-all
[418.604169] Subtest basic-fds-all: SUCCESS (11.058s)
[418.762316] [076/130] (612s left) gem_ctx_persistence (engines-persistence)
[418.813236] Starting subtest: engines-persistence
[418.815610] Starting dynamic subtest: rcs0
[419.565084] Dynamic subtest rcs0: SUCCESS (0.750s)
[419.574475] Starting dynamic subtest: bcs0
[420.364180] Dynamic subtest bcs0: SUCCESS (0.799s)
[420.375604] Starting dynamic subtest: vcs0
[421.122566] Dynamic subtest vcs0: SUCCESS (0.757s)
[421.134615] Starting dynamic subtest: vcs1
[421.865390] Dynamic subtest vcs1: SUCCESS (0.742s)
[421.874178] Starting dynamic subtest: vecs0
[422.655526] Dynamic subtest vecs0: SUCCESS (0.789s)
[422.666012] Starting dynamic subtest: ccs0
[423.418675] Dynamic subtest ccs0: SUCCESS (0.762s)
[423.421722] Subtest engines-persistence: SUCCESS (4.604s)
[423.475105] [077/130] (608s left) gem_exec_capture (capture-invisible)
[423.525788] Starting subtest: capture-invisible
[423.528032] Starting dynamic subtest: smem0
[423.532223] Dynamic subtest smem0: SKIP (0.000s)
[423.536387] Subtest capture-invisible: SKIP (0.000s)
[423.617926] [078/130] (607s left) kms_prop_blob (blob-prop-lifetime)
[423.663098] Starting subtest: blob-prop-lifetime
[423.665747] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[423.736048] [079/130] (607s left) kms_flip_event_leak (basic)
[423.824345] Starting subtest: basic
[423.826653] Starting dynamic subtest: pipe-A-eDP-1
[424.231063] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.404s)
[424.240707] Starting dynamic subtest: pipe-B-eDP-1
[425.424319] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.192s)
[425.434258] Starting dynamic subtest: pipe-C-eDP-1
[426.634455] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.207s)
[426.643952] Starting dynamic subtest: pipe-D-eDP-1
[427.781205] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.147s)
[427.783847] Subtest basic: SUCCESS (3.954s)
[428.644842] [080/130] (602s left) api_intel_bb (simple-bb-ctx)
[428.688739] Starting subtest: simple-bb-ctx
[428.694927] Subtest simple-bb-ctx: SUCCESS (0.008s)
[428.765153] [081/130] (602s left) kms_psr2_su (page_flip-p010)
[428.824558] Subtest page_flip-P010: SKIP (0.000s)
[428.864051] [082/130] (602s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[428.960226] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[428.962576] Starting dynamic subtest: pipe-A-default-mode
[431.765420] Dynamic subtest pipe-A-default-mode: SUCCESS (2.802s)
[431.767867] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.803s)
[432.673517] [083/130] (599s left) gem_softpin (zero)
[432.736014] Starting subtest: zero
[432.741057] Subtest zero: SUCCESS (0.004s)
[432.851198] [084/130] (598s left) gem_busy (close-race)
[432.896472] Starting subtest: close-race
[435.177523] Subtest close-race: FAIL (2.221s)
[435.453391] [085/130] (596s left) kms_chamelium_edid (vga-edid-read)
[435.860779] Subtest vga-edid-read: SKIP (0.000s)
[436.751009] [086/130] (594s left) gem_exec_params (larger-than-life-batch)
[436.791681] Starting subtest: larger-than-life-batch
[438.087074] Subtest larger-than-life-batch: SUCCESS (1.295s)
[438.871171] [087/130] (592s left) kms_cursor_legacy (forked-bo)
[439.025867] Starting subtest: forked-bo
[439.028660] Starting dynamic subtest: pipe-A
[444.447519] Dynamic subtest pipe-A: SUCCESS (5.419s)
[444.457248] Starting dynamic subtest: pipe-B
[449.866885] Dynamic subtest pipe-B: SUCCESS (5.418s)
[449.875150] Starting dynamic subtest: pipe-C
[455.281794] Dynamic subtest pipe-C: SUCCESS (5.415s)
[455.289060] Starting dynamic subtest: pipe-D
[460.700117] Dynamic subtest pipe-D: SUCCESS (5.417s)
[460.710714] Starting dynamic subtest: all-pipes
[466.130538] Dynamic subtest all-pipes: SUCCESS (5.430s)
[466.132819] Subtest forked-bo: SUCCESS (27.102s)
[466.209220] [088/130] (565s left) gem_exec_fence (invalid-fence-array)
[466.605023] Starting subtest: invalid-fence-array
[466.607800] Subtest invalid-fence-array: SUCCESS (0.002s)
[467.875923] [089/130] (563s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[467.960955] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[467.965154] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[468.016841] [090/130] (563s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[468.095195] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[468.097565] Starting dynamic subtest: pipe-A
[468.106747] Dynamic subtest pipe-A: SKIP (0.004s)
[468.115696] Starting dynamic subtest: pipe-B
[468.125100] Dynamic subtest pipe-B: SKIP (0.013s)
[468.132731] Starting dynamic subtest: pipe-C
[468.142090] Dynamic subtest pipe-C: SKIP (0.013s)
[468.149591] Starting dynamic subtest: pipe-D
[468.157574] Dynamic subtest pipe-D: SKIP (0.014s)
[468.161401] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.045s)
[468.216435] [091/130] (563s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[468.309598] Starting subtest: psr-1p-primscrn-cur-indfb-move
[473.393500] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (5.087s)
[473.504191] [092/130] (558s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[473.600809] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[476.800088] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (3.203s)
[476.935582] [093/130] (554s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[477.031276] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[477.035667] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[477.114009] [094/130] (554s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[477.204060] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[477.207503] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[477.270147] [095/130] (554s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[477.355736] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[477.359153] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[477.420148] [096/130] (554s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[477.520585] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[477.524193] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[477.588715] [097/130] (554s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[477.680019] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[477.682067] Starting dynamic subtest: pipe-A-eDP-1
[478.008600] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.327s)
[478.016743] Starting dynamic subtest: pipe-B-eDP-1
[478.033770] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.003s)
[478.040528] Starting dynamic subtest: pipe-C-eDP-1
[478.056337] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.005s)
[478.062976] Starting dynamic subtest: pipe-D-eDP-1
[478.078523] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[478.080285] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.343s)
[480.418592] [098/130] (551s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[480.512297] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[480.517857] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[481.695647] [099/130] (550s left) kms_psr (pr-no-drrs)
[481.781287] Starting subtest: pr-no-drrs
[481.785854] Subtest pr-no-drrs: SKIP (0.000s)
[481.834148] [100/130] (550s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[486.952764] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[486.958233] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s)
[487.840682] [101/130] (544s left) prime_mmap (test_reprime)
[487.880817] Starting subtest: test_reprime
[487.882655] Starting dynamic subtest: test_reprime-smem
[487.901777] Dynamic subtest test_reprime-smem: SUCCESS (0.022s)
[487.904106] Subtest test_reprime: SUCCESS (0.023s)
[487.968543] [102/130] (544s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[488.068199] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[488.072659] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[488.138579] [103/130] (543s left) kms_scaling_modes (scaling-mode-full)
[488.227103] Starting subtest: scaling-mode-full
[488.229345] Starting dynamic subtest: pipe-A-eDP-1
[488.320739] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.092s)
[488.330826] Starting dynamic subtest: pipe-B-eDP-1
[489.587970] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.265s)
[489.603258] Starting dynamic subtest: pipe-C-eDP-1
[490.781818] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.194s)
[490.791219] Starting dynamic subtest: pipe-D-eDP-1
[491.996074] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.213s)
[491.998410] Subtest scaling-mode-full: SUCCESS (3.769s)
[493.216493] [104/130] (538s left) i915_hangman (gt-engine-hang)
[493.275060] Starting subtest: gt-engine-hang
[493.277331] Starting dynamic subtest: rcs0
[494.811873] Dynamic subtest rcs0: SUCCESS (1.535s)
[494.822905] Starting dynamic subtest: bcs0
[496.860168] Dynamic subtest bcs0: SUCCESS (2.047s)
[496.870877] Starting dynamic subtest: vcs0
[498.844979] Dynamic subtest vcs0: SUCCESS (1.983s)
[498.855709] Starting dynamic subtest: vcs1
[500.827986] Dynamic subtest vcs1: SUCCESS (1.982s)
[500.835998] Starting dynamic subtest: vecs0
[502.812199] Dynamic subtest vecs0: SUCCESS (1.983s)
[502.822211] Starting dynamic subtest: ccs0
[504.860346] Dynamic subtest ccs0: SUCCESS (2.046s)
[504.863574] Subtest gt-engine-hang: SUCCESS (11.583s)
[504.919807] [105/130] (527s left) syncobj_timeline (invalid-signal-illegal-handle)
[504.976323] Starting subtest: invalid-signal-illegal-handle
[504.978905] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[505.042349] [106/130] (527s left) gem_exec_balancer (full)
[505.087222] Starting subtest: full
[509.310857] Subtest full: SUCCESS (4.224s)
[509.569188] [107/130] (522s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[509.653413] Starting subtest: flip-vs-dpms-off-vs-modeset
[509.655825] Starting dynamic subtest: A-eDP1
[513.437352] Dynamic subtest A-eDP1: SUCCESS (3.779s)
[513.446152] Starting dynamic subtest: B-eDP1
[516.824666] Dynamic subtest B-eDP1: SUCCESS (3.388s)
[516.832669] Starting dynamic subtest: C-eDP1
[520.221734] Dynamic subtest C-eDP1: SUCCESS (3.393s)
[520.230998] Starting dynamic subtest: D-eDP1
[523.609345] Dynamic subtest D-eDP1: SUCCESS (3.388s)
[523.612051] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.955s)
[524.515824] [108/130] (507s left) syncobj_wait (wait-all-interrupted)
[524.559313] Starting subtest: wait-all-interrupted
[524.663611] Subtest wait-all-interrupted: SUCCESS (0.103s)
[524.714596] [109/130] (507s left) syncobj_eventfd (binary-wait)
[524.771429] Starting subtest: binary-wait
[524.779410] Subtest binary-wait: SUCCESS (0.010s)
[524.832761] [110/130] (507s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[524.923778] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[524.927932] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[525.014487] [111/130] (507s left) kms_color (ctm-blue-to-red)
[525.099508] Starting subtest: ctm-blue-to-red
[525.102137] Starting dynamic subtest: pipe-A-eDP-1
[525.733913] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.633s)
[525.743125] Starting dynamic subtest: pipe-B-eDP-1
[527.162855] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.427s)
[527.171419] Starting dynamic subtest: pipe-C-eDP-1
[528.516305] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.353s)
[528.525996] Starting dynamic subtest: pipe-D-eDP-1
[529.855183] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.339s)
[529.857555] Subtest ctm-blue-to-red: SUCCESS (4.753s)
[530.719391] [112/130] (501s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[530.824030] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[530.828169] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[530.883000] [113/130] (501s left) kms_vblank (wait-forked-hang)
[530.971148] Starting subtest: wait-forked-hang
[530.973343] Starting dynamic subtest: pipe-A-eDP-1
[557.678213] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.702s)
[557.693004] Starting dynamic subtest: pipe-D-eDP-1
[583.603734] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.919s)
[583.606107] Subtest wait-forked-hang: SUCCESS (52.625s)
[584.483092] [114/130] (447s left) gem_ccs (ctrl-surf-copy)
[584.535405] Starting subtest: ctrl-surf-copy
[584.540053] Subtest ctrl-surf-copy: SKIP (0.000s)
[584.607827] [115/130] (447s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[584.718721] Starting subtest: flip-vs-cursor-busy-crc-legacy
[585.511062] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.793s)
[586.401580] [116/130] (445s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[586.506077] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[586.510974] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[586.577085] [117/130] (445s left) kms_atomic (test-only)
[586.656281] Starting subtest: test-only
[586.721233] Starting dynamic subtest: pipe-A-eDP-1
[589.285116] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.566s)
[589.287561] Subtest test-only: SUCCESS (2.627s)
[590.178007] [118/130] (442s left) perf (gen12-invalid-class-instance)
[590.314899] Starting subtest: gen12-invalid-class-instance
[590.324528] Subtest gen12-invalid-class-instance: SUCCESS (0.011s)
[590.359146] [119/130] (441s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[590.440396] Starting subtest: cursorB-vs-flipA-varying-size
[590.445110] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[590.480963] [120/130] (441s left) i915_query (query-topology-kernel-writes)
[590.531259] Starting subtest: query-topology-kernel-writes
[590.533839] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[590.596308] [121/130] (441s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[590.696883] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[590.701108] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[590.774247] [122/130] (441s left) kms_psr (psr2-sprite-plane-move)
[590.867285] Starting subtest: psr2-sprite-plane-move
[590.869675] Starting dynamic subtest: eDP-1
[593.290503] Dynamic subtest eDP-1: FAIL (2.259s)
[593.292823] Subtest psr2-sprite-plane-move: FAIL (2.260s)
[593.310203] [123/130] (439s left) gem_exec_reloc (basic-write-wc-noreloc)
[593.351195] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[593.420593] [124/130] (438s left) kms_psr (fbc-psr2-no-drrs)
[593.508116] Starting subtest: fbc-psr2-no-drrs
[593.510277] Starting dynamic subtest: eDP-1
[593.514269] Dynamic subtest eDP-1: SKIP (0.000s)
[593.518529] Subtest fbc-psr2-no-drrs: SKIP (0.001s)
[593.552010] [125/130] (438s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[593.645419] Starting subtest: 2x-cursor-vs-flip-legacy
[593.650019] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s)
[593.702519] [126/130] (438s left) i915_pm_rps (basic-api)
[593.756974] Starting subtest: basic-api
[593.761012] Subtest basic-api: SKIP (0.001s)
[593.810440] [127/130] (438s left) kms_big_fb (linear-64bpp-rotate-180)
[593.905972] Starting subtest: linear-64bpp-rotate-180
[595.365103] Subtest linear-64bpp-rotate-180: SUCCESS (1.459s)
[595.521339] [128/130] (436s left) gem_exec_schedule (lateslice)
[595.582634] Starting subtest: lateslice
[595.584787] Starting dynamic subtest: rcs0
[595.589344] Dynamic subtest rcs0: SUCCESS (0.017s)
[595.596410] Starting dynamic subtest: bcs0
[595.603189] Dynamic subtest bcs0: SUCCESS (0.015s)
[595.610651] Starting dynamic subtest: vcs0
[595.618687] Dynamic subtest vcs0: SUCCESS (0.015s)
[595.626100] Starting dynamic subtest: vcs1
[595.634274] Dynamic subtest vcs1: SUCCESS (0.015s)
[595.641741] Starting dynamic subtest: vecs0
[595.650025] Dynamic subtest vecs0: SUCCESS (0.015s)
[595.657409] Starting dynamic subtest: ccs0
[595.665251] Dynamic subtest ccs0: SUCCESS (0.015s)
[595.667648] Subtest lateslice: SUCCESS (0.095s)
[596.928443] [129/130] (435s left) gem_exec_reloc (basic-gtt-read-noreloc)
[596.974282] Subtest basic-gtt-read-noreloc: SKIP (0.000s)
[597.041506] [130/130] (435s left) perf_pmu (semaphore-wait)
[597.070661] Starting subtest: semaphore-wait
[597.072997] Starting dynamic subtest: rcs0
[597.584463] Dynamic subtest rcs0: SUCCESS (0.512s)
[597.594007] Starting dynamic subtest: bcs0
[598.097127] Dynamic subtest bcs0: SUCCESS (0.511s)
[598.106666] Starting dynamic subtest: vcs0
[598.609299] Dynamic subtest vcs0: SUCCESS (0.511s)
[598.620888] Starting dynamic subtest: vcs1
[599.122965] Dynamic subtest vcs1: SUCCESS (0.513s)
[599.134542] Starting dynamic subtest: vecs0
[599.636090] Dynamic subtest vecs0: SUCCESS (0.512s)
[599.647782] Starting dynamic subtest: ccs0
[600.150541] Dynamic subtest ccs0: SUCCESS (0.513s)
[600.153855] Subtest semaphore-wait: SUCCESS (3.079s)
[600.252162] Closing watchdogs
Done.