[50.445668] Initializing watchdogs
[50.446156]   /dev/watchdog0
[50.478459] [001/141] (960s left) kms_cursor_crc (cursor-sliding-64x64)
[51.308695] Starting subtest: cursor-sliding-64x64
[51.397892] Starting dynamic subtest: pipe-A-eDP-1
[56.775398] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.380s)
[56.877187] Starting dynamic subtest: pipe-D-eDP-1
[62.401817] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.557s)
[62.405792] Subtest cursor-sliding-64x64: SUCCESS (11.092s)
[63.634601] [002/141] (947s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[64.140461] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[64.146714] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[64.294044] [003/141] (946s left) gem_eio (throttle)
[65.570665] Starting subtest: throttle
[67.690170] Subtest throttle: SUCCESS (2.120s)
[69.675346] [004/141] (941s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[70.170378] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[76.792215] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.625s)
[76.973323] [005/141] (934s left) i915_pm_rpm (dpms-lpsp)
[82.777878] Starting subtest: dpms-lpsp
[83.593711] Subtest dpms-lpsp: SUCCESS (0.815s)
[83.777429] [006/141] (927s left) sysfs_heartbeat_interval (nopreempt)
[84.165887] Starting subtest: nopreempt
[84.170271] Starting dynamic subtest: vecs0
[93.506987] Dynamic subtest vecs0: SUCCESS (9.338s)
[93.541233] Starting dynamic subtest: ccs0
[94.595779] Dynamic subtest ccs0: FAIL (1.008s)
[94.623372] Starting dynamic subtest: vcs0
[103.496800] Dynamic subtest vcs0: SUCCESS (8.980s)
[103.532525] Starting dynamic subtest: bcs0
[104.502329] Dynamic subtest bcs0: FAIL (0.917s)
[104.538339] Starting dynamic subtest: rcs0
[105.678778] Dynamic subtest rcs0: FAIL (1.179s)
[105.712471] Starting dynamic subtest: vcs1
[115.520804] Dynamic subtest vcs1: SUCCESS (9.921s)
[115.524588] Subtest nopreempt: FAIL (31.352s)
[115.686751] [007/141] (895s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[116.272577] Starting subtest: short-flip-after-cursor-toggle
[118.680743] Subtest short-flip-after-cursor-toggle: SUCCESS (2.405s)
[119.259762] [008/141] (892s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[119.804588] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[119.811144] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[119.960087] [009/141] (891s left) kms_cursor_crc (cursor-sliding-32x32)
[120.594819] Starting subtest: cursor-sliding-32x32
[120.910233] Subtest cursor-sliding-32x32: SKIP (0.307s)
[121.798486] [010/141] (889s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[122.169608] Starting subtest: multi-wait-all-for-submit-unsubmitted
[122.277773] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.107s)
[122.406756] [011/141] (889s left) gem_userptr_blits (sync-unmap)
[122.864144] Starting subtest: sync-unmap
[122.940059] Subtest sync-unmap: SUCCESS (0.076s)
[123.160612] [012/141] (888s left) gem_exec_suspend (basic-s3)
[123.586323] Starting subtest: basic-S3
[123.589461] Starting dynamic subtest: smem
[151.527439] Dynamic subtest smem: SUCCESS (27.938s)
[151.530831] Subtest basic-S3: SUCCESS (27.940s)
[151.833076] [013/141] (860s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[152.362304] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[165.120818] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (12.762s)
[165.370503] [014/141] (846s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[165.945593] Starting subtest: basic-flip-after-cursor-varying-size
[167.410220] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.466s)
[168.358620] [015/141] (843s left) kms_psr (psr2_primary_mmap_cpu)
[168.842778] Starting subtest: psr2_primary_mmap_cpu
[171.542795] Subtest psr2_primary_mmap_cpu: SUCCESS (2.699s)
[172.454272] [016/141] (839s left) gem_exec_fence (keep-in-fence)
[173.042471] Starting subtest: keep-in-fence
[173.046443] Starting dynamic subtest: rcs0
[173.133218] Dynamic subtest rcs0: SUCCESS (0.091s)
[173.177702] Starting dynamic subtest: bcs0
[173.224720] Dynamic subtest bcs0: SUCCESS (0.089s)
[173.267088] Starting dynamic subtest: vcs0
[174.516167] Dynamic subtest vcs0: SUCCESS (1.287s)
[174.557831] Starting dynamic subtest: vcs1
[176.499960] Dynamic subtest vcs1: SUCCESS (1.981s)
[176.543183] Starting dynamic subtest: vecs0
[178.549346] Dynamic subtest vecs0: SUCCESS (2.045s)
[178.591152] Starting dynamic subtest: ccs0
[180.647294] Dynamic subtest ccs0: SUCCESS (2.097s)
[180.650773] Subtest keep-in-fence: SUCCESS (7.607s)
[182.942748] [017/141] (829s left) sw_sync (alloc_merge_fence)
[183.308787] Starting subtest: alloc_merge_fence
[183.312868] Subtest alloc_merge_fence: SUCCESS (0.002s)
[183.433980] [018/141] (828s left) gem_create (create-ext-set-pat)
[183.966214] Starting subtest: create-ext-set-pat
[183.970892] Subtest create-ext-set-pat: SUCCESS (0.002s)
[184.160403] [019/141] (828s left) gem_ctx_param (root-set)
[184.540299] Starting subtest: root-set
[184.543748] Subtest root-set: SUCCESS (0.002s)
[184.712707] [020/141] (827s left) kms_cursor_crc (cursor-onscreen-128x128)
[185.606444] Starting subtest: cursor-onscreen-128x128
[185.636593] Starting dynamic subtest: pipe-A-eDP-1
[189.645696] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.009s)
[189.847455] Starting dynamic subtest: pipe-D-eDP-1
[193.525839] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.722s)
[193.530374] Subtest cursor-onscreen-128x128: SUCCESS (7.924s)
[194.511910] [021/141] (818s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[195.025775] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[195.032604] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[195.197297] [022/141] (817s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_dg2_mc_ccs)
[196.867162] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_dg2_mc_ccs
[196.872820] Subtest pipe-D-crc-primary-rotation-180-4_tiled_dg2_mc_ccs: SKIP (0.002s)
[197.575212] [023/141] (815s left) gem_vm_create (invalid-destroy)
[197.968445] Starting subtest: invalid-destroy
[197.971356] Subtest invalid-destroy: SUCCESS (0.003s)
[198.139920] [024/141] (814s left) kms_scaling_modes (scaling-mode-full)
[198.612576] Starting subtest: scaling-mode-full
[198.616170] Starting dynamic subtest: eDP-1-pipe-A
[198.970467] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.353s)
[199.020433] Starting dynamic subtest: eDP-1-pipe-B
[200.610169] Dynamic subtest eDP-1-pipe-B: SUCCESS (1.631s)
[200.658912] Starting dynamic subtest: eDP-1-pipe-C
[202.312595] Dynamic subtest eDP-1-pipe-C: SUCCESS (1.696s)
[202.359685] Starting dynamic subtest: eDP-1-pipe-D
[203.961787] Dynamic subtest eDP-1-pipe-D: SUCCESS (1.638s)
[203.965382] Subtest scaling-mode-full: SUCCESS (5.351s)
[205.484845] [025/141] (807s left) kms_ccs (pipe-a-ccs-on-another-bo-y_tiled_ccs)
[206.312186] Starting subtest: pipe-A-ccs-on-another-bo-y_tiled_ccs
[206.319174] Subtest pipe-A-ccs-on-another-bo-y_tiled_ccs: SKIP (0.000s)
[207.206355] [026/141] (805s left) kms_atomic_interruptible (universal-setplane-primary)
[207.721173] Starting subtest: universal-setplane-primary
[207.730172] Starting dynamic subtest: eDP-1-pipe-A
[215.572655] Dynamic subtest eDP-1-pipe-A: SUCCESS (7.839s)
[215.576146] Subtest universal-setplane-primary: SUCCESS (7.851s)
[216.528090] [027/141] (796s left) kms_plane_alpha_blend (alpha-opaque-fb)
[217.008860] Starting subtest: alpha-opaque-fb
[218.746269] Starting dynamic subtest: pipe-A-eDP-1
[220.124841] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.378s)
[220.174237] Starting dynamic subtest: pipe-D-eDP-1
[222.069173] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.935s)
[222.072139] Subtest alpha-opaque-fb: SUCCESS (5.061s)
[223.017188] [028/141] (790s left) kms_ccs (pipe-a-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[224.038390] Starting subtest: pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[224.042645] Subtest pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.003s)
[225.389262] [029/141] (787s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_rc_ccs)
[226.486289] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs
[226.493332] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[227.748117] [030/141] (785s left) prime_self_import (export-vs-gem_close-race)
[228.122478] Starting subtest: export-vs-gem_close-race
[233.201938] Subtest export-vs-gem_close-race: SUCCESS (5.077s)
[233.362464] [031/141] (780s left) i915_pm_backlight (fade-with-suspend)
[233.842691] Starting subtest: fade-with-suspend
[251.409780] Starting dynamic subtest: eDP-1
[254.197452] Dynamic subtest eDP-1: SUCCESS (2.784s)
[254.200568] Subtest fade-with-suspend: SUCCESS (20.358s)
[255.109934] [032/141] (758s left) kms_big_fb (linear-32bpp-rotate-180)
[255.375539] Starting subtest: linear-32bpp-rotate-180
[259.595077] Subtest linear-32bpp-rotate-180: SUCCESS (4.219s)
[260.044082] [033/141] (753s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[260.593361] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[260.600254] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[260.747151] [034/141] (752s left) kms_addfb_basic (bad-pitch-128)
[261.121398] Starting subtest: bad-pitch-128
[261.124804] Subtest bad-pitch-128: SUCCESS (0.000s)
[261.415730] [035/141] (752s left) kms_content_protection (srm)
[262.099441] Starting subtest: srm
[266.294274] Subtest srm: SKIP (4.197s)
[267.911956] [036/141] (745s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[268.446648] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[268.453227] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[268.621981] [037/141] (745s left) gem_exec_whisper (basic-forked)
[269.048629] Starting subtest: basic-forked
[295.459248] Subtest basic-forked: FAIL (25.923s)
[295.531769] [038/141] (718s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[296.080272] Starting subtest: x-tiled-32bpp-rotate-0
[301.260720] Subtest x-tiled-32bpp-rotate-0: SUCCESS (5.180s)
[301.885353] [039/141] (712s left) vc4/vc4_perfmon (create-two-perfmon)
[302.364276] Subtest create-two-perfmon: SKIP (0.000s)
[302.505452] [040/141] (711s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[303.068323] Starting subtest: cursor-vs-flip-atomic
[337.138998] Subtest cursor-vs-flip-atomic: SUCCESS (34.068s)
[338.086237] [041/141] (676s left) kms_invalid_mode (int-max-clock)
[338.599570] Starting subtest: int-max-clock
[338.603148] Starting dynamic subtest: eDP-1-pipe-A
[339.017523] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.412s)
[339.072227] Starting dynamic subtest: eDP-1-pipe-B
[339.076242] Dynamic subtest eDP-1-pipe-B: SUCCESS (0.041s)
[339.124444] Starting dynamic subtest: eDP-1-pipe-C
[339.128070] Dynamic subtest eDP-1-pipe-C: SUCCESS (0.041s)
[339.174742] Starting dynamic subtest: eDP-1-pipe-D
[339.178284] Dynamic subtest eDP-1-pipe-D: SUCCESS (0.040s)
[339.181603] Subtest int-max-clock: SUCCESS (0.572s)
[339.946397] [042/141] (674s left) gem_exec_async (concurrent-writes)
[340.371683] Starting subtest: concurrent-writes
[340.375166] Starting dynamic subtest: rcs0
[340.422151] Dynamic subtest rcs0: SUCCESS (0.048s)
[340.490350] Starting dynamic subtest: bcs0
[340.494220] Dynamic subtest bcs0: SUCCESS (0.043s)
[340.545421] Starting dynamic subtest: vcs0
[340.548952] Dynamic subtest vcs0: SUCCESS (0.034s)
[340.588275] Starting dynamic subtest: vcs1
[340.591562] Dynamic subtest vcs1: SUCCESS (0.035s)
[340.637187] Starting dynamic subtest: vecs0
[340.640173] Dynamic subtest vecs0: SUCCESS (0.025s)
[340.686140] Starting dynamic subtest: ccs0
[340.689129] Dynamic subtest ccs0: SUCCESS (0.025s)
[340.692113] Subtest concurrent-writes: SUCCESS (0.234s)
[340.925632] [043/141] (673s left) kms_psr2_sf (overlay-plane-move-continuous-sf)
[348.213259] Starting subtest: overlay-plane-move-continuous-sf
[348.221463] Starting dynamic subtest: pipe-A-eDP-1
[355.004826] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.777s)
[355.056053] Starting dynamic subtest: pipe-B-eDP-1
[361.783163] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.776s)
[361.786973] Subtest overlay-plane-move-continuous-sf: SUCCESS (13.568s)
[362.728654] [044/141] (651s left) kms_psr (sprite_render)
[363.232288] Starting subtest: sprite_render
[366.632994] Subtest sprite_render: SUCCESS (3.399s)
[367.577248] [045/141] (647s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[368.088301] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[368.095234] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[368.254742] [046/141] (646s left) v3d/v3d_submit_cl (bad-flag)
[368.730775] Subtest bad-flag: SKIP (0.000s)
[368.870182] [047/141] (645s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[369.378970] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[369.386199] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[369.556065] [048/141] (645s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[370.005767] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[370.012489] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[370.157816] [049/141] (644s left) gem_eio (in-flight-immediate)
[372.212178] Starting subtest: in-flight-immediate
[409.176407] Subtest in-flight-immediate: SUCCESS (36.966s)
[410.685246] [050/141] (604s left) gem_exec_balancer (parallel-out-fence)
[411.940578] Starting subtest: parallel-out-fence
[417.970908] Subtest parallel-out-fence: SUCCESS (6.032s)
[418.126133] [051/141] (597s left) drm_fdinfo (busy-hang)
[418.526997] Starting subtest: busy-hang
[418.530765] Starting dynamic subtest: rcs0
[418.537575] Dynamic subtest rcs0: SKIP (0.004s)
[418.588405] Starting dynamic subtest: bcs0
[418.595326] Dynamic subtest bcs0: SKIP (0.002s)
[418.650798] Starting dynamic subtest: vcs0
[418.657255] Dynamic subtest vcs0: SKIP (0.002s)
[418.705219] Starting dynamic subtest: vcs1
[418.711626] Dynamic subtest vcs1: SKIP (0.002s)
[418.759164] Starting dynamic subtest: vecs0
[418.765429] Dynamic subtest vecs0: SKIP (0.002s)
[418.812935] Starting dynamic subtest: ccs0
[418.819345] Dynamic subtest ccs0: SKIP (0.002s)
[418.825581] Subtest busy-hang: SKIP (0.038s)
[418.894516] [052/141] (596s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_mtl_rc_ccs)
[420.355877] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs
[421.697320] Subtest pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs: SUCCESS (1.342s)
[423.136411] [053/141] (592s left) gem_ctx_param (non-root-set)
[423.523331] Starting subtest: non-root-set
[423.586755] Subtest non-root-set: SUCCESS (0.067s)
[423.740574] [054/141] (591s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[424.217860] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[424.228189] Starting dynamic subtest: pipe-A-default-mode
[427.990115] Dynamic subtest pipe-A-default-mode: SUCCESS (3.758s)
[427.993565] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (3.771s)
[428.952393] [055/141] (586s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[429.460762] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[429.467646] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[429.638174] [056/141] (585s left) kms_universal_plane (universal-plane-pipe-b-functional)
[430.117977] Starting subtest: universal-plane-pipe-B-functional
[437.374224] Subtest universal-plane-pipe-B-functional: SUCCESS (7.254s)
[438.319118] [057/141] (577s left) gem_ctx_persistence (smoketest)
[438.734735] Starting subtest: smoketest
[470.751721] Subtest smoketest: SUCCESS (32.023s)
[470.941526] [058/141] (544s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[471.444765] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[471.460308] Starting dynamic subtest: pipe-A-default-mode
[471.841210] Dynamic subtest pipe-A-default-mode: SKIP (0.375s)
[471.848775] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.388s)
[472.743442] [059/141] (543s left) api_intel_bb (full-batch)
[473.129344] Starting subtest: full-batch
[473.141139] Subtest full-batch: SUCCESS (0.018s)
[473.327121] [060/141] (542s left) gem_exec_schedule (preempt-self)
[473.772364] Starting subtest: preempt-self
[473.776843] Starting dynamic subtest: rcs0
[473.863139] Dynamic subtest rcs0: SUCCESS (0.088s)
[473.915107] Starting dynamic subtest: bcs0
[473.942524] Dynamic subtest bcs0: SUCCESS (0.075s)
[473.995291] Starting dynamic subtest: vcs0
[474.022932] Dynamic subtest vcs0: SUCCESS (0.076s)
[474.072758] Starting dynamic subtest: vcs1
[474.098129] Dynamic subtest vcs1: SUCCESS (0.072s)
[474.142686] Starting dynamic subtest: vecs0
[474.171122] Dynamic subtest vecs0: SUCCESS (0.069s)
[474.217681] Starting dynamic subtest: ccs0
[474.250466] Dynamic subtest ccs0: SUCCESS (0.075s)
[474.253923] Subtest preempt-self: SUCCESS (0.480s)
[475.718922] [061/141] (540s left) kms_vblank (pipe-c-wait-busy)
[476.221850] Starting subtest: pipe-C-wait-busy
[479.886912] Subtest pipe-C-wait-busy: SUCCESS (3.661s)
[480.844048] [062/141] (535s left) kms_addfb_basic (bad-pitch-32)
[481.224735] Starting subtest: bad-pitch-32
[481.227823] Subtest bad-pitch-32: SUCCESS (0.000s)
[481.507275] [063/141] (534s left) syncobj_wait (wait-for-submit-delayed-submit)
[481.893163] Starting subtest: wait-for-submit-delayed-submit
[481.994868] Subtest wait-for-submit-delayed-submit: SUCCESS (0.105s)
[482.151245] [064/141] (534s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[482.713704] Starting subtest: cursorA-vs-flipA-varying-size
[494.569113] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.853s)
[495.532491] [065/141] (520s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc)
[496.826840] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc
[496.830437] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.003s)
[497.967155] [066/141] (518s left) i915_query (query-topology-unsupported)
[498.345099] Starting subtest: query-topology-unsupported
[498.352969] Subtest query-topology-unsupported: SKIP (0.000s)
[498.501265] [067/141] (518s left) v3d/v3d_submit_cl (single-in-sync)
[498.981639] Subtest single-in-sync: SKIP (0.000s)
[499.121693] [068/141] (517s left) kms_universal_plane (universal-plane-pipe-c-sanity)
[499.611203] Starting subtest: universal-plane-pipe-C-sanity
[501.986507] Subtest universal-plane-pipe-C-sanity: SUCCESS (2.376s)
[502.946825] [069/141] (513s left) gen3_render_tiledx_blits
[503.448829] [070/141] (513s left) gen9_exec_parse (bb-large)
[503.859178] Subtest bb-large: SKIP (0.000s)
[503.992474] [071/141] (513s left) gem_exec_reloc (basic-wc-cpu)
[504.396596] Subtest basic-wc-cpu: SKIP (0.000s)
[504.534568] [072/141] (512s left) gem_exec_fence (submit-chain)
[505.127835] Starting subtest: submit-chain
[505.199282] Subtest submit-chain: SUCCESS (0.072s)
[507.511598] [073/141] (509s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[508.006401] Starting subtest: primary-y-tiled-reflect-x-180
[508.165364] Subtest primary-y-tiled-reflect-x-180: SKIP (0.158s)
[508.428365] [074/141] (508s left) gem_exec_schedule (implicit-read-write)
[508.869910] Starting subtest: implicit-read-write
[508.872917] Starting dynamic subtest: rcs0
[509.267182] Dynamic subtest rcs0: SUCCESS (0.398s)
[509.321751] Starting dynamic subtest: bcs0
[509.575980] Dynamic subtest bcs0: SUCCESS (0.304s)
[509.627425] Starting dynamic subtest: vcs0
[509.840384] Dynamic subtest vcs0: SUCCESS (0.261s)
[509.890174] Starting dynamic subtest: vcs1
[510.106872] Dynamic subtest vcs1: SUCCESS (0.262s)
[510.156065] Starting dynamic subtest: vecs0
[510.373465] Dynamic subtest vecs0: SUCCESS (0.262s)
[510.423699] Starting dynamic subtest: ccs0
[510.693448] Dynamic subtest ccs0: SUCCESS (0.316s)
[510.697316] Subtest implicit-read-write: SUCCESS (1.829s)
[511.830524] [075/141] (505s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[512.409288] Starting subtest: yf-tiled-64bpp-rotate-90
[512.416235] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[512.593426] [076/141] (504s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[513.103246] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[513.109979] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[513.276527] [077/141] (504s left) fbdev (pan)
[513.656231] Starting subtest: pan
[513.700488] Subtest pan: SUCCESS (0.044s)
[513.875323] [078/141] (503s left) kms_vblank (pipe-d-wait-busy)
[514.349511] Starting subtest: pipe-D-wait-busy
[518.366750] Subtest pipe-D-wait-busy: SUCCESS (4.017s)
[519.324149] [079/141] (498s left) gem_pread (exhaustion)
[519.705958] Subtest exhaustion: SKIP (0.000s)
[519.841518] [080/141] (498s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[520.153571] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[520.262117] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.108s)
[520.415341] [081/141] (497s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[520.931905] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[531.205777] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (10.278s)
[531.480755] [082/141] (486s left) i915_pm_rps (basic-api)
[531.883345] Starting subtest: basic-api
[531.890295] Subtest basic-api: SKIP (0.003s)
[532.037281] [083/141] (486s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[532.535429] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[544.635238] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (12.102s)
[544.911275] [084/141] (473s left) kms_flip (dpms-off-confusion)
[545.306290] Starting subtest: dpms-off-confusion
[545.333063] Starting dynamic subtest: A-eDP1
[554.593474] Dynamic subtest A-eDP1: SUCCESS (9.261s)
[554.647143] Starting dynamic subtest: B-eDP1
[563.685629] Dynamic subtest B-eDP1: SUCCESS (9.087s)
[563.738183] Starting dynamic subtest: C-eDP1
[572.780159] Dynamic subtest C-eDP1: SUCCESS (9.089s)
[572.842041] Starting dynamic subtest: D-eDP1
[581.843959] Dynamic subtest D-eDP1: SUCCESS (9.058s)
[581.869163] Subtest dpms-off-confusion: SUCCESS (36.568s)
[583.485779] [085/141] (434s left) perf_pmu (busy-accuracy-98)
[583.889317] Starting subtest: busy-accuracy-98
[583.892912] Starting dynamic subtest: rcs0
[586.994807] Dynamic subtest rcs0: SUCCESS (3.100s)
[587.046925] Starting dynamic subtest: bcs0
[590.105861] Dynamic subtest bcs0: SUCCESS (3.107s)
[590.157931] Starting dynamic subtest: vcs0
[593.204166] Dynamic subtest vcs0: SUCCESS (3.094s)
[593.258164] Starting dynamic subtest: vcs1
[596.308315] Dynamic subtest vcs1: SUCCESS (3.099s)
[596.358991] Starting dynamic subtest: vecs0
[599.410056] Dynamic subtest vecs0: SUCCESS (3.098s)
[599.460671] Starting dynamic subtest: ccs0
[602.523112] Dynamic subtest ccs0: SUCCESS (3.108s)
[602.526787] Subtest busy-accuracy-98: SUCCESS (18.632s)
[602.752645] [086/141] (415s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs)
[604.348412] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs
[604.365434] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs: SKIP (0.014s)
[605.189628] [087/141] (413s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[605.700351] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[605.826526] [088/141] (412s left) perf_pmu (invalid-open)
[606.216289] Starting subtest: invalid-open
[606.219839] Subtest invalid-open: SUCCESS (0.001s)
[606.397558] [089/141] (412s left) kms_chamelium_color (gamma)
[606.878669] Subtest gamma: SKIP (0.000s)
[606.998969] [090/141] (411s left) gem_ctx_persistence (heartbeat-close)
[607.389445] Starting subtest: heartbeat-close
[607.396203] Subtest heartbeat-close: SKIP (0.001s)
[607.563545] [091/141] (411s left) gem_partial_pwrite_pread (reads-display)
[607.963325] Subtest reads-display: SKIP (0.000s)
[608.105347] [092/141] (410s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[608.620368] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[623.911632] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (15.295s)
[624.176520] [093/141] (394s left) kms_ccs (pipe-c-random-ccs-data-y_tiled_ccs)
[625.643756] Starting subtest: pipe-C-random-ccs-data-y_tiled_ccs
[625.652768] Subtest pipe-C-random-ccs-data-y_tiled_ccs: SKIP (0.000s)
[626.580885] [094/141] (392s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[627.100295] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[627.107252] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[627.265462] [095/141] (392s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[627.813739] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[627.820606] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[627.986620] [096/141] (391s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[628.466765] Starting subtest: bo-too-small-due-to-tiling
[628.473790] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[628.650922] [097/141] (390s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[629.231344] Starting subtest: cursor-vs-flip-legacy
[667.985131] Subtest cursor-vs-flip-legacy: SUCCESS (38.750s)
[668.944596] [098/141] (350s left) prime_vgem (fence-wait)
[669.384440] Starting subtest: fence-wait
[669.388211] Starting dynamic subtest: rcs0
[670.458859] Dynamic subtest rcs0: SUCCESS (1.071s)
[670.507964] Starting dynamic subtest: bcs0
[671.527095] Dynamic subtest bcs0: SUCCESS (1.065s)
[671.576362] Starting dynamic subtest: vcs0
[672.597168] Dynamic subtest vcs0: SUCCESS (1.066s)
[672.648315] Starting dynamic subtest: vcs1
[674.481523] Dynamic subtest vcs1: SUCCESS (1.879s)
[674.533832] Starting dynamic subtest: vecs0
[676.528802] Dynamic subtest vecs0: SUCCESS (2.043s)
[676.580805] Starting dynamic subtest: ccs0
[678.575514] Dynamic subtest ccs0: SUCCESS (2.043s)
[678.579526] Subtest fence-wait: SUCCESS (9.192s)
[678.749779] [099/141] (340s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[679.166760] Starting subtest: y-tiled-ccs-to-y-tiled
[679.173723] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[679.486413] [100/141] (340s left) kms_flip (2x-blocking-absolute-wf_vblank)
[679.883485] Starting subtest: 2x-blocking-absolute-wf_vblank
[680.043883] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.161s)
[680.253405] [101/141] (339s left) prime_mmap_coherency (ioctl-errors)
[680.660803] Starting subtest: ioctl-errors
[693.468779] Subtest ioctl-errors: SUCCESS (12.812s)
[693.751134] [102/141] (326s left) kms_vblank (pipe-c-ts-continuation-dpms-suspend)
[694.378906] Starting subtest: pipe-C-ts-continuation-dpms-suspend
[714.032494] Subtest pipe-C-ts-continuation-dpms-suspend: SUCCESS (19.652s)
[714.993355] [103/141] (305s left) gem_mmap_gtt (cpuset-big-copy-odd)
[715.373877] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[715.500427] [104/141] (304s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_gen12_mc_ccs)
[717.154304] Starting subtest: pipe-D-random-ccs-data-y_tiled_gen12_mc_ccs
[717.163899] Subtest pipe-D-random-ccs-data-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[717.863450] [105/141] (302s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-25)
[718.345379] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-25
[718.349639] Starting dynamic subtest: pipe-A-eDP-1
[718.450188] Dynamic subtest pipe-A-eDP-1: SKIP (0.095s)
[718.514291] Starting dynamic subtest: pipe-B-eDP-1
[718.656588] Dynamic subtest pipe-B-eDP-1: SKIP (0.194s)
[718.719898] Starting dynamic subtest: pipe-C-eDP-1
[718.863243] Dynamic subtest pipe-C-eDP-1: SKIP (0.195s)
[718.920293] Starting dynamic subtest: pipe-D-eDP-1
[719.068620] Dynamic subtest pipe-D-eDP-1: SKIP (0.195s)
[719.075760] Subtest planes-upscale-factor-0-25-downscale-factor-0-25: SKIP (0.724s)
[719.269291] [106/141] (301s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_mc_ccs)
[720.420785] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_mc_ccs
[720.426823] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_mc_ccs: SKIP (0.002s)
[721.610905] [107/141] (298s left) gem_softpin (overlap)
[722.021895] Starting subtest: overlap
[729.375587] Subtest overlap: SUCCESS (7.351s)
[729.599417] [108/141] (290s left) kms_atomic_transition (modeset-transition-fencing)
[730.093214] Starting subtest: modeset-transition-fencing
[730.096868] Starting dynamic subtest: 1x-outputs
[761.237060] Dynamic subtest 1x-outputs: SUCCESS (31.140s)
[761.760287] Subtest modeset-transition-fencing: SUCCESS (31.665s)
[762.708110] [109/141] (257s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_mtl_rc_ccs)
[764.307089] Starting subtest: pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs
[765.006796] Subtest pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs: SUCCESS (0.699s)
[766.352647] [110/141] (254s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[766.872534] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[766.879169] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[767.059545] [111/141] (253s left) syncobj_timeline (multi-wait-available-signaled)
[767.452784] Starting subtest: multi-wait-available-signaled
[767.456392] Subtest multi-wait-available-signaled: SUCCESS (0.005s)
[767.611400] [112/141] (253s left) i915_pm_rpm (system-suspend)
[773.727062] Starting subtest: system-suspend
[797.231927] Subtest system-suspend: SUCCESS (23.512s)
[798.187610] [113/141] (222s left) kms_plane_scaling (plane-downscale-with-pixel-format-factor-0-5)
[798.665625] Starting subtest: plane-downscale-with-pixel-format-factor-0-5
[798.674097] Starting dynamic subtest: pipe-A-eDP-1
[798.783459] Dynamic subtest pipe-A-eDP-1: SKIP (0.107s)
[798.836949] Starting dynamic subtest: pipe-B-eDP-1
[798.990157] Dynamic subtest pipe-B-eDP-1: SKIP (0.197s)
[799.050718] Starting dynamic subtest: pipe-C-eDP-1
[799.220729] Dynamic subtest pipe-C-eDP-1: SKIP (0.215s)
[799.277713] Starting dynamic subtest: pipe-D-eDP-1
[799.438230] Dynamic subtest pipe-D-eDP-1: SKIP (0.210s)
[799.444565] Subtest plane-downscale-with-pixel-format-factor-0-5: SKIP (0.772s)
[799.646878] [114/141] (221s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_mtl_mc_ccs)
[801.014071] Starting subtest: pipe-C-bad-rotation-90-4_tiled_mtl_mc_ccs
[804.073509] Subtest pipe-C-bad-rotation-90-4_tiled_mtl_mc_ccs: SUCCESS (3.053s)
[805.513494] [115/141] (215s left) v3d/v3d_wait_bo (map-bo-1ns)
[805.986908] Subtest map-bo-1ns: SKIP (0.000s)
[806.135227] [116/141] (215s left) kms_chamelium_frames (hdmi-crc-fast)
[807.032848] Subtest hdmi-crc-fast: SKIP (0.000s)
[807.914208] [117/141] (213s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[808.432309] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[821.028222] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (12.602s)
[821.322456] [118/141] (200s left) syncobj_wait (invalid-reset-zero-handles)
[821.698687] Starting subtest: invalid-reset-zero-handles
[821.702548] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[821.860190] [119/141] (199s left) syncobj_timeline (invalid-query-illegal-handle)
[822.232877] Starting subtest: invalid-query-illegal-handle
[822.236762] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[822.394581] [120/141] (199s left) kms_fbcon_fbt (fbc)
[822.801147] Starting subtest: fbc
[828.529917] Subtest fbc: SUCCESS (5.724s)
[828.743675] [121/141] (193s left) gem_spin_batch (resubmit-new)
[829.166427] Starting subtest: resubmit-new
[829.170275] Starting dynamic subtest: rcs0
[829.190334] Dynamic subtest rcs0: SUCCESS (0.022s)
[829.254184] Starting dynamic subtest: bcs0
[829.257762] Dynamic subtest bcs0: SUCCESS (0.012s)
[829.314993] Starting dynamic subtest: vcs0
[829.318201] Dynamic subtest vcs0: SUCCESS (0.013s)
[829.370848] Starting dynamic subtest: vcs1
[829.373896] Dynamic subtest vcs1: SUCCESS (0.014s)
[829.432952] Starting dynamic subtest: vecs0
[829.436517] Dynamic subtest vecs0: SUCCESS (0.012s)
[829.489269] Starting dynamic subtest: ccs0
[829.492712] Dynamic subtest ccs0: SUCCESS (0.013s)
[829.496252] Subtest resubmit-new: SUCCESS (0.110s)
[830.659418] [122/141] (191s left) gem_exec_schedule (u-semaphore-user)
[831.109278] Starting subtest: u-semaphore-user
[831.164860] Subtest u-semaphore-user: SUCCESS (0.056s)
[832.671139] [123/141] (189s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[833.162838] Starting subtest: FB-8BPC-Vs-Panel-8BPC
[833.166585] Starting dynamic subtest: pipe-A-eDP-1
[834.951489] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.780s)
[834.955218] Subtest FB-8BPC-Vs-Panel-8BPC: SUCCESS (1.785s)
[835.909234] [124/141] (186s left) kms_lease (lease-uevent)
[836.398756] Starting subtest: lease-uevent
[837.432180] Subtest lease-uevent: SUCCESS (1.036s)
[837.588988] [125/141] (184s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[838.141627] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[841.919617] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (3.776s)
[842.398306] [126/141] (179s left) api_intel_bb (offset-control)
[842.773572] Starting subtest: offset-control
[842.798091] Subtest offset-control: SUCCESS (0.030s)
[842.971765] [127/141] (179s left) gem_lmem_swapping (smem-oom)
[843.369249] Subtest smem-oom: SKIP (0.000s)
[843.496238] [128/141] (179s left) gem_exec_flush (basic-wb-ro-before-default)
[843.928178] Starting subtest: basic-wb-ro-before-default
[849.817854] Subtest basic-wb-ro-before-default: SUCCESS (5.889s)
[850.140336] [129/141] (172s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-pwrite)
[850.664648] Starting subtest: fbc-1p-offscren-pri-indfb-draw-pwrite
[860.824216] Subtest fbc-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (10.166s)
[861.086655] [130/141] (161s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[861.458798] Starting subtest: single-wait-all-for-submit-available-submitted
[861.462582] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[861.620191] [131/141] (161s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[862.111571] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[862.118387] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[862.282105] [132/141] (160s left) gem_ringfill (engines-basic)
[868.536364] Starting subtest: engines-basic
[868.540451] Starting dynamic subtest: rcs0
[868.602405] Dynamic subtest rcs0: SUCCESS (0.065s)
[868.664956] Starting dynamic subtest: bcs0
[868.668922] Dynamic subtest bcs0: SUCCESS (0.054s)
[868.723720] Starting dynamic subtest: vcs0
[870.502813] Dynamic subtest vcs0: SUCCESS (1.837s)
[870.557903] Starting dynamic subtest: vcs1
[872.486608] Dynamic subtest vcs1: SUCCESS (1.980s)
[872.541187] Starting dynamic subtest: vecs0
[874.535708] Dynamic subtest vecs0: SUCCESS (2.044s)
[874.591136] Starting dynamic subtest: ccs0
[874.595066] Dynamic subtest ccs0: SUCCESS (0.055s)
[874.598664] Subtest engines-basic: SUCCESS (6.059s)
[874.791468] [133/141] (148s left) gem_ctx_exec (basic-close-race)
[875.180482] Starting subtest: basic-close-race
[881.225586] Subtest basic-close-race: SUCCESS (6.047s)
[887.520174] [134/141] (135s left) kms_ccs (pipe-c-bad-aux-stride-4_tiled_mtl_rc_ccs)
[889.060685] Starting subtest: pipe-C-bad-aux-stride-4_tiled_mtl_rc_ccs
[889.065142] Subtest pipe-C-bad-aux-stride-4_tiled_mtl_rc_ccs: SUCCESS (0.003s)
[889.923318] [135/141] (133s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_ccs)
[891.016522] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_ccs
[891.034535] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_ccs: SKIP (0.015s)
[892.336565] [136/141] (130s left) kms_properties (connector-properties-atomic)
[892.830517] Starting subtest: connector-properties-atomic
[894.119053] Subtest connector-properties-atomic: SUCCESS (1.289s)
[895.020752] [137/141] (128s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[895.423454] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[895.429814] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[895.709655] [138/141] (127s left) prime_vgem (basic-read)
[896.117805] Starting subtest: basic-read
[896.141731] Subtest basic-read: SKIP (0.026s)
[896.331086] [139/141] (127s left) i915_suspend (basic-s3-without-i915)
[896.694908] Starting subtest: basic-s3-without-i915
[900.605486] Subtest basic-s3-without-i915: SKIP (3.902s)
[904.288630] [140/141] (119s left) gem_mmap_wc (bad-object)
[904.560694] Subtest bad-object: SKIP (0.000s)
[904.673573] [141/141] (119s left) kms_color (ctm-negative)
[905.143288] Starting subtest: ctm-negative
[905.146707] Starting dynamic subtest: pipe-A
[906.506803] Dynamic subtest pipe-A: SUCCESS (1.362s)
[906.562630] Starting dynamic subtest: pipe-B
[908.463700] Dynamic subtest pipe-B: SUCCESS (1.954s)
[908.503738] Starting dynamic subtest: pipe-C
[910.819604] Dynamic subtest pipe-C: SUCCESS (2.349s)
[910.874585] Starting dynamic subtest: pipe-D
[913.353060] Dynamic subtest pipe-D: SUCCESS (2.531s)
[913.356738] Subtest ctm-negative: SUCCESS (8.211s)
[914.312420] Closing watchdogs
Done.