[64.930679] Initializing watchdogs
[64.930970]   /dev/watchdog0
[64.946438] [001/141] (960s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[65.199712] Starting subtest: yf-tiled-ccs-to-yf-tiled
[65.201335] Starting dynamic subtest: smem
[65.637219] Dynamic subtest smem: SUCCESS (0.437s)
[65.638739] Subtest yf-tiled-ccs-to-yf-tiled: SUCCESS (0.437s)
[65.843213] [002/141] (959s left) v3d/v3d_submit_cl (bad-extension)
[66.109854] Subtest bad-extension: SKIP (0.000s)
[66.174581] [003/141] (959s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[66.495451] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[66.498574] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[66.589330] [004/141] (958s left) gem_pread (uncached)
[66.792726] Starting subtest: uncached
[71.396206] Subtest uncached: SUCCESS (4.599s)
[71.489237] [005/141] (954s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[73.574933] Starting subtest: gem-execbuf-stress-pc8
[73.636968] Subtest gem-execbuf-stress-pc8: SKIP (0.056s)
[74.065525] [006/141] (951s left) gem_ctx_persistence (engines-queued)
[74.276683] Starting subtest: engines-queued
[74.278221] Starting dynamic subtest: rcs0
[74.336776] Dynamic subtest rcs0: SUCCESS (0.060s)
[74.353456] Starting dynamic subtest: bcs0
[74.490738] Dynamic subtest bcs0: SUCCESS (0.154s)
[74.506231] Starting dynamic subtest: vcs0
[74.550803] Dynamic subtest vcs0: SUCCESS (0.060s)
[74.568533] Starting dynamic subtest: vecs0
[74.606725] Dynamic subtest vecs0: SUCCESS (0.056s)
[74.608795] Subtest engines-queued: SUCCESS (0.330s)
[74.697229] [007/141] (950s left) prime_vgem (shrink)
[74.996473] Starting subtest: shrink
[75.019761] Subtest shrink: SUCCESS (0.022s)
[75.129137] [008/141] (950s left) perf (disabled-read-error)
[75.483185] Starting subtest: disabled-read-error
[75.578625] Subtest disabled-read-error: SUCCESS (0.095s)
[75.627770] [009/141] (950s left) kms_prop_blob (blob-multiple)
[75.830366] Starting subtest: blob-multiple
[75.833623] Subtest blob-multiple: SUCCESS (0.003s)
[75.911567] [010/141] (949s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[76.221032] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[78.678169] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (2.461s)
[78.830570] [011/141] (946s left) kms_ccs (pipe-c-missing-ccs-buffer-y_tiled_gen12_rc_ccs)
[79.512828] Starting subtest: pipe-C-missing-ccs-buffer-y_tiled_gen12_rc_ccs
[79.515469] Subtest pipe-C-missing-ccs-buffer-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[79.873496] [012/141] (945s left) gem_create (create-ext-placement-each)
[80.207834] Starting subtest: create-ext-placement-each
[80.209573] Subtest create-ext-placement-each: SUCCESS (0.000s)
[80.306931] [013/141] (945s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-signaled)
[80.511387] Starting subtest: invalid-multi-wait-available-unsubmitted-signaled
[80.514522] Subtest invalid-multi-wait-available-unsubmitted-signaled: SUCCESS (0.002s)
[80.589534] [014/141] (945s left) v3d/v3d_submit_csd (bad-multisync-extension)
[80.856310] Subtest bad-multisync-extension: SKIP (0.000s)
[80.923651] [015/141] (944s left) dmabuf_sync_file (export-wait-after-attach)
[81.139554] Starting subtest: export-wait-after-attach
[81.143037] Subtest export-wait-after-attach: SUCCESS (0.004s)
[81.206655] [016/141] (944s left) kms_flip (2x-plain-flip-fb-recreate)
[81.498358] Starting subtest: 2x-plain-flip-fb-recreate
[81.500937] Subtest 2x-plain-flip-fb-recreate: SKIP (0.003s)
[81.607099] [017/141] (944s left) gem_mmap_wc (copy)
[81.808417] Starting subtest: copy
[82.153072] Subtest copy: SUCCESS (0.344s)
[82.245081] [018/141] (943s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[82.650128] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[82.653660] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[82.744289] [019/141] (943s left) gen9_exec_parse (bb-start-out)
[83.075772] Starting subtest: bb-start-out
[83.088826] Subtest bb-start-out: SUCCESS (0.009s)
[83.306783] [020/141] (942s left) kms_psr (suspend)
[83.509479] Subtest suspend: SKIP (0.000s)
[83.555941] [021/141] (942s left) syncobj_basic (bad-destroy-pad)
[83.753744] Starting subtest: bad-destroy-pad
[83.755265] Subtest bad-destroy-pad: SUCCESS (0.000s)
[83.839164] [022/141] (942s left) kms_ccs (pipe-a-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[84.141216] Starting subtest: pipe-A-bad-pixel-format-y_tiled_gen12_rc_ccs_cc
[84.145379] Subtest pipe-A-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[84.880962] [023/141] (941s left) kms_chamelium_audio (dp-audio)
[85.278186] Subtest dp-audio: SKIP (0.000s)
[85.559133] [024/141] (940s left) syncobj_wait (wait-any-complex)
[85.761339] Starting subtest: wait-any-complex
[85.825153] Subtest wait-any-complex: SUCCESS (0.063s)
[85.903407] [025/141] (940s left) perf (non-sampling-read-error)
[86.202152] Starting subtest: non-sampling-read-error
[86.242205] Subtest non-sampling-read-error: SUCCESS (0.040s)
[86.286077] [026/141] (940s left) gem_exec_schedule (implicit-write-read)
[86.529780] Starting subtest: implicit-write-read
[86.531258] Starting dynamic subtest: rcs0
[86.680820] Dynamic subtest rcs0: SUCCESS (0.147s)
[86.703063] Starting dynamic subtest: bcs0
[86.831284] Dynamic subtest bcs0: SUCCESS (0.151s)
[86.855143] Starting dynamic subtest: vcs0
[86.963650] Dynamic subtest vcs0: SUCCESS (0.133s)
[86.985195] Starting dynamic subtest: vecs0
[87.093516] Dynamic subtest vecs0: SUCCESS (0.129s)
[87.095253] Subtest implicit-write-read: SUCCESS (0.563s)
[87.437847] [027/141] (938s left) i915_pm_rc6_residency (rc6-fence)
[87.638728] Starting subtest: rc6-fence
[102.891440] Subtest rc6-fence: SUCCESS (15.253s)
[103.138238] [028/141] (923s left) kms_chamelium_color (ctm-0-50)
[103.441635] Subtest ctm-0-50: SKIP (0.000s)
[103.486950] [029/141] (922s left) gem_softpin (allocator-fork)
[103.692953] Starting subtest: allocator-fork
[106.057538] Subtest allocator-fork: SUCCESS (2.363s)
[106.155166] [030/141] (920s left) perf_pmu (busy-double-start)
[106.365755] Starting subtest: busy-double-start
[106.367221] Starting dynamic subtest: rcs0
[109.921040] Dynamic subtest rcs0: SUCCESS (3.554s)
[109.937405] Starting dynamic subtest: bcs0
[113.478613] Dynamic subtest bcs0: SUCCESS (3.558s)
[113.495346] Starting dynamic subtest: vcs0
[117.024742] Dynamic subtest vcs0: SUCCESS (3.545s)
[117.041154] Starting dynamic subtest: vecs0
[120.573862] Dynamic subtest vecs0: SUCCESS (3.548s)
[120.575431] Subtest busy-double-start: SUCCESS (14.207s)
[120.653876] [031/141] (905s left) kms_ccs (pipe-c-crc-primary-basic-y_tiled_ccs)
[121.281343] Starting subtest: pipe-C-crc-primary-basic-y_tiled_ccs
[121.283918] Subtest pipe-C-crc-primary-basic-y_tiled_ccs: SKIP (0.001s)
[121.679947] [032/141] (904s left) kms_psr (psr2_sprite_mmap_gtt)
[121.883573] Subtest psr2_sprite_mmap_gtt: SKIP (0.000s)
[121.928583] [033/141] (904s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[122.245547] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[122.248514] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[122.346492] [034/141] (904s left) kms_cursor_crc (cursor-random-512x512)
[122.658379] Starting subtest: cursor-random-512x512
[122.663197] Subtest cursor-random-512x512: SKIP (0.003s)
[122.762932] [035/141] (903s left) kms_pipe_crc_basic (hang-read-crc)
[123.063161] Starting subtest: hang-read-crc
[123.064617] Starting dynamic subtest: pipe-A-DP-1
[124.304515] Dynamic subtest pipe-A-DP-1: SUCCESS (1.238s)
[124.323798] Starting dynamic subtest: pipe-B-DP-1
[125.956874] Dynamic subtest pipe-B-DP-1: SUCCESS (1.647s)
[125.975468] Starting dynamic subtest: pipe-C-DP-1
[127.853697] Dynamic subtest pipe-C-DP-1: SUCCESS (1.894s)
[127.855406] Subtest hang-read-crc: SUCCESS (4.793s)
[128.165748] [036/141] (898s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[128.476149] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[130.709888] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (2.238s)
[130.848136] [037/141] (895s left) gem_request_retire (retire-vma-not-inactive)
[131.057872] Starting subtest: retire-vma-not-inactive
[131.089589] Subtest retire-vma-not-inactive: SUCCESS (0.029s)
[131.197992] [038/141] (895s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[131.501954] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[131.504624] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[131.597670] [039/141] (895s left) kms_invalid_mode (bad-hsync-end)
[131.898753] Starting subtest: bad-hsync-end
[131.900849] Starting dynamic subtest: DP-1-pipe-A
[132.001282] Dynamic subtest DP-1-pipe-A: SUCCESS (0.103s)
[132.022643] Starting dynamic subtest: DP-1-pipe-B
[132.024354] Dynamic subtest DP-1-pipe-B: SUCCESS (0.014s)
[132.042942] Starting dynamic subtest: DP-1-pipe-C
[132.044346] Dynamic subtest DP-1-pipe-C: SUCCESS (0.014s)
[132.045584] Subtest bad-hsync-end: SUCCESS (0.141s)
[132.347644] [040/141] (894s left) feature_discovery (display-3x)
[132.649550] Starting subtest: display-3x
[132.652237] Subtest display-3x: SKIP (0.000s)
[132.730522] [041/141] (894s left) gem_ctx_param (set-priority-range)
[132.935028] Starting subtest: set-priority-range
[132.999269] Subtest set-priority-range: SUCCESS (0.066s)
[133.082911] [042/141] (893s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[133.401610] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[134.726999] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.325s)
[134.900487] [043/141] (891s left) gem_userptr_blits (unsync-overlap)
[135.119127] Subtest unsync-overlap: SKIP (0.000s)
[135.282912] [044/141] (891s left) gem_exec_balancer (bonded-pair)
[135.514212] Starting subtest: bonded-pair
[139.296474] Subtest bonded-pair: SUCCESS (3.781s)
[143.233563] [045/141] (883s left) kms_universal_plane (cursor-fb-leak-pipe-a)
[143.534044] Starting subtest: cursor-fb-leak-pipe-A
[143.863474] Subtest cursor-fb-leak-pipe-A: SUCCESS (0.333s)
[144.188187] [046/141] (882s left) kms_ccs (pipe-b-missing-ccs-buffer-y_tiled_ccs)
[144.752175] Starting subtest: pipe-B-missing-ccs-buffer-y_tiled_ccs
[144.753697] Subtest pipe-B-missing-ccs-buffer-y_tiled_ccs: SUCCESS (0.001s)
[145.251467] [047/141] (881s left) kms_busy (extended-pageflip-hang-oldfb)
[145.576340] Starting subtest: extended-pageflip-hang-oldfb
[145.580808] Starting dynamic subtest: pipe-A
[145.892828] Dynamic subtest pipe-A: SUCCESS (0.313s)
[145.916835] Starting dynamic subtest: pipe-C
[146.365554] Dynamic subtest pipe-C: SUCCESS (0.464s)
[146.367367] Subtest extended-pageflip-hang-oldfb: SUCCESS (0.788s)
[146.710709] [048/141] (880s left) perf_pmu (interrupts-sync)
[146.917619] Starting subtest: interrupts-sync
[148.046478] Subtest interrupts-sync: SUCCESS (1.127s)
[148.144067] [049/141] (878s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[148.484996] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[148.487736] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[148.576302] [050/141] (878s left) prime_self_import (basic-llseek-size)
[148.779782] Starting subtest: basic-llseek-size
[148.784784] Subtest basic-llseek-size: SUCCESS (0.006s)
[148.875708] [051/141] (878s left) api_intel_bb (object-reloc-keep-cache)
[149.085298] Starting subtest: object-reloc-keep-cache
[149.104490] Subtest object-reloc-keep-cache: SUCCESS (0.019s)
[149.210767] [052/141] (878s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[149.517490] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[149.520050] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[149.608261] [053/141] (877s left) gem_mmap_offset (bad-flags)
[149.807934] Starting subtest: bad-flags
[149.809522] Subtest bad-flags: SUCCESS (0.000s)
[149.907831] [054/141] (877s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[150.212955] Starting subtest: psr-1p-primscrn-cur-indfb-move
[150.215568] Subtest psr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[150.308391] [055/141] (877s left) kms_plane_multiple (tiling-4)
[150.609111] Starting subtest: tiling-4
[150.611602] Subtest tiling-4: SKIP (0.000s)
[150.691507] [056/141] (876s left) gem_exec_schedule (u-lateslice)
[150.922331] Starting subtest: u-lateslice
[150.923692] Starting dynamic subtest: rcs0
[150.955520] Dynamic subtest rcs0: SUCCESS (0.033s)
[150.974371] Starting dynamic subtest: bcs0
[150.991550] Dynamic subtest bcs0: SUCCESS (0.035s)
[151.012140] Starting dynamic subtest: vcs0
[151.026685] Dynamic subtest vcs0: SUCCESS (0.034s)
[151.046492] Starting dynamic subtest: vecs0
[151.058442] Dynamic subtest vecs0: SUCCESS (0.032s)
[151.060464] Subtest u-lateslice: SUCCESS (0.137s)
[151.409092] [057/141] (875s left) gem_exec_schedule (pi-distinct-iova)
[151.770050] Starting subtest: pi-distinct-iova
[151.771601] Starting dynamic subtest: rcs0
[151.852435] Dynamic subtest rcs0: SUCCESS (0.081s)
[151.872102] Starting dynamic subtest: bcs0
[151.929865] Dynamic subtest bcs0: SUCCESS (0.077s)
[151.950505] Starting dynamic subtest: vcs0
[151.987798] Dynamic subtest vcs0: SUCCESS (0.057s)
[152.007580] Starting dynamic subtest: vecs0
[152.047202] Dynamic subtest vecs0: SUCCESS (0.060s)
[152.048509] Subtest pi-distinct-iova: SUCCESS (0.277s)
[152.275228] [058/141] (875s left) gem_mmap_gtt (basic-write-read-distinct)
[152.478630] Starting subtest: basic-write-read-distinct
[152.510406] Subtest basic-write-read-distinct: SUCCESS (0.032s)
[152.623595] [059/141] (874s left) kms_cursor_edge_walk (256x256-top-edge)
[152.923161] Starting subtest: 256x256-top-edge
[152.924486] Starting dynamic subtest: pipe-A-DP-1
[156.593402] Dynamic subtest pipe-A-DP-1: SUCCESS (3.670s)
[156.616226] Starting dynamic subtest: pipe-C-DP-1
[160.085765] Dynamic subtest pipe-C-DP-1: SUCCESS (3.487s)
[160.087069] Subtest 256x256-top-edge: SUCCESS (7.165s)
[160.383586] [060/141] (867s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[160.682639] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[160.683951] Starting dynamic subtest: pipe-A-DP-1
[160.759788] Dynamic subtest pipe-A-DP-1: SUCCESS (0.077s)
[160.792474] Starting dynamic subtest: pipe-B-DP-1
[161.132086] Dynamic subtest pipe-B-DP-1: SUCCESS (0.369s)
[161.157359] Starting dynamic subtest: pipe-C-DP-1
[161.232336] Dynamic subtest pipe-C-DP-1: SKIP (0.095s)
[161.233646] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SUCCESS (0.552s)
[161.643359] [061/141] (865s left) vc4/vc4_wait_bo (unused-bo-1ns)
[161.905824] Subtest unused-bo-1ns: SKIP (0.000s)
[161.975710] [062/141] (865s left) gem_wait (write-wait)
[162.212105] Starting subtest: write-wait
[162.213824] Starting dynamic subtest: all-engines
[163.252809] Dynamic subtest all-engines: SUCCESS (1.040s)
[163.271691] Starting dynamic subtest: rcs0
[164.276992] Dynamic subtest rcs0: SUCCESS (1.023s)
[164.295325] Starting dynamic subtest: bcs0
[165.311510] Dynamic subtest bcs0: SUCCESS (1.034s)
[165.330574] Starting dynamic subtest: vcs0
[166.344292] Dynamic subtest vcs0: SUCCESS (1.032s)
[166.363671] Starting dynamic subtest: vecs0
[167.377555] Dynamic subtest vecs0: SUCCESS (1.033s)
[167.378919] Subtest write-wait: SUCCESS (5.165s)
[167.628283] [063/141] (859s left) kms_vblank (pipe-b-query-busy)
[167.930698] Starting subtest: pipe-B-query-busy
[170.792312] Subtest pipe-B-query-busy: SUCCESS (2.864s)
[171.109839] [064/141] (856s left) gem_exec_whisper (basic-fds-forked-all)
[171.337704] Starting subtest: basic-fds-forked-all
[195.195419] Subtest basic-fds-forked-all: SUCCESS (23.856s)
[195.393343] [065/141] (832s left) gem_ctx_persistence (process)
[195.603146] Starting subtest: process
[195.729452] Subtest process: SUCCESS (0.125s)
[195.810564] [066/141] (831s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[196.108899] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[196.111725] Starting dynamic subtest: pipe-A-valid-mode
[196.233970] Dynamic subtest pipe-A-valid-mode: SKIP (0.124s)
[196.237449] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.127s)
[196.554262] [067/141] (831s left) gem_exec_reloc (basic-wc-cpu-active)
[196.769195] Starting subtest: basic-wc-cpu-active
[196.918528] Subtest basic-wc-cpu-active: SUCCESS (0.149s)
[197.103873] [068/141] (830s left) gem_madvise (dontneed-before-mmap)
[197.303597] Starting subtest: dontneed-before-mmap
[197.319424] Subtest dontneed-before-mmap: SUCCESS (0.016s)
[197.420784] [069/141] (830s left) gem_exec_endless (dispatch)
[197.655728] Starting subtest: dispatch
[197.657615] Starting dynamic subtest: rcs0
[197.669425] Dynamic subtest rcs0: SUCCESS (0.011s)
[197.696788] Starting dynamic subtest: bcs0
[197.699474] Dynamic subtest bcs0: SKIP (0.001s)
[197.717380] Starting dynamic subtest: vcs0
[197.719878] Dynamic subtest vcs0: SUCCESS (0.009s)
[197.737693] Starting dynamic subtest: vecs0
[197.740174] Dynamic subtest vecs0: SUCCESS (0.010s)
[197.741350] Subtest dispatch: SUCCESS (0.034s)
[197.787450] [070/141] (830s left) vc4/vc4_create_bo (create-bo-0)
[198.049616] Subtest create-bo-0: SKIP (0.000s)
[198.121255] [071/141] (829s left) kms_flip (2x-flip-vs-rmfb)
[198.413986] Starting subtest: 2x-flip-vs-rmfb
[198.416565] Subtest 2x-flip-vs-rmfb: SKIP (0.003s)
[198.521350] [072/141] (829s left) kms_color (gamma)
[198.822388] Starting subtest: gamma
[198.823677] Starting dynamic subtest: pipe-A
[199.362783] Dynamic subtest pipe-A: SUCCESS (0.542s)
[199.385825] Starting dynamic subtest: pipe-B
[200.169438] Dynamic subtest pipe-B: SUCCESS (0.806s)
[200.193794] Starting dynamic subtest: pipe-C
[201.162342] Dynamic subtest pipe-C: SUCCESS (0.991s)
[201.163639] Subtest gamma: SUCCESS (2.341s)
[201.488101] [073/141] (826s left) kms_vblank (pipe-a-query-busy)
[201.787421] Starting subtest: pipe-A-query-busy
[204.249181] Subtest pipe-A-query-busy: SUCCESS (2.460s)
[204.573359] [074/141] (823s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[204.882407] Starting subtest: short-flip-after-cursor-atomic-transitions
[206.002276] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (1.123s)
[206.320125] [075/141] (821s left) i915_hangman (gt-engine-error)
[206.545928] Starting subtest: gt-engine-error
[206.547591] Starting dynamic subtest: rcs0
[206.634864] Dynamic subtest rcs0: SUCCESS (0.088s)
[206.658284] Starting dynamic subtest: bcs0
[206.661352] Dynamic subtest bcs0: SKIP (0.002s)
[206.683260] Starting dynamic subtest: vcs0
[206.713792] Dynamic subtest vcs0: SUCCESS (0.075s)
[206.732761] Starting dynamic subtest: vecs0
[206.794927] Dynamic subtest vecs0: SUCCESS (0.080s)
[206.796458] Subtest gt-engine-error: SUCCESS (0.249s)
[206.918638] [076/141] (821s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_dg2_rc_ccs)
[207.648089] Subtest pipe-D-crc-primary-basic-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[207.952451] [077/141] (820s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[208.251923] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[208.253229] Starting dynamic subtest: pipe-A-valid-mode
[208.376839] Dynamic subtest pipe-A-valid-mode: SKIP (0.122s)
[208.379512] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.125s)
[208.705646] [078/141] (819s left) syncobj_timeline (multi-wait-available-signaled)
[208.915965] Starting subtest: multi-wait-available-signaled
[208.919877] Subtest multi-wait-available-signaled: SUCCESS (0.002s)
[209.005902] [079/141] (819s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[209.210598] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[209.315136] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.106s)
[209.406268] [080/141] (818s left) perf (global-sseu-config)
[209.701322] Starting subtest: global-sseu-config
[209.702775] Starting dynamic subtest: 0-rcs0
[209.812498] Dynamic subtest 0-rcs0: SUCCESS (0.109s)
[209.813909] Subtest global-sseu-config: SUCCESS (0.110s)
[209.855517] [081/141] (818s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[210.172043] Starting subtest: psr-rgb101010-draw-blt
[210.174716] Subtest psr-rgb101010-draw-blt: SKIP (0.000s)
[210.255381] [082/141] (818s left) kms_vblank (pipe-d-ts-continuation-dpms-suspend)
[210.565496] Subtest pipe-D-ts-continuation-dpms-suspend: SKIP (0.000s)
[210.655873] [083/141] (817s left) gem_tiling_max_stride
[211.022966] [084/141] (817s left) gem_exec_gttfill (basic)
[211.251527] Starting subtest: basic
[238.992754] Subtest basic: SUCCESS (27.741s)
[239.189606] [085/141] (789s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[239.635316] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[239.637960] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[239.724482] [086/141] (788s left) v3d/v3d_get_param (get-bad-flags)
[240.000642] Subtest get-bad-flags: SKIP (0.000s)
[240.072441] [087/141] (788s left) kms_ccs (pipe-b-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc)
[240.652418] Starting subtest: pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc
[240.656482] Subtest pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[241.137443] [088/141] (787s left) core_auth (many-magics)
[241.341810] Starting subtest: many-magics
[242.539225] Subtest many-magics: SUCCESS (1.199s)
[242.654139] [089/141] (785s left) gem_mmap (bad-object)
[242.854071] Starting subtest: bad-object
[242.855681] Subtest bad-object: SUCCESS (0.000s)
[242.954276] [090/141] (785s left) gem_exec_fence (syncobj-channel)
[243.627629] Starting subtest: syncobj-channel
[244.703453] Subtest syncobj-channel: SUCCESS (1.074s)
[245.054497] [091/141] (783s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[245.259504] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[245.363153] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.103s)
[245.452485] [092/141] (783s left) api_intel_allocator (reloc-allocator)
[245.654410] Starting subtest: reloc-allocator
[245.656913] Starting dynamic subtest: basic
[245.658602] Dynamic subtest basic: SUCCESS (0.005s)
[245.680202] Starting dynamic subtest: parallel-one
[245.681514] Dynamic subtest parallel-one: SUCCESS (0.010s)
[245.701034] Starting dynamic subtest: print
[245.702340] Dynamic subtest print: SUCCESS (0.000s)
[245.721930] Starting dynamic subtest: reuse
[245.723436] Dynamic subtest reuse: SUCCESS (0.000s)
[245.742610] Starting dynamic subtest: fork-reopen-allocator
[245.743891] Dynamic subtest fork-reopen-allocator: SUCCESS (0.042s)
[245.745433] Subtest reloc-allocator: SUCCESS (0.061s)
[245.808740] [093/141] (782s left) drm_mm (all-tests)
[246.012463] Starting subtest: all-tests
[246.015106] Subtest all-tests: SKIP (0.000s)
[246.090700] [094/141] (782s left) gen3_render_tiledx_blits
[246.352407] [095/141] (782s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[246.654601] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[246.657198] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[246.770902] [096/141] (782s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_mc_ccs)
[247.354842] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_mc_ccs
[247.357676] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[247.820546] [097/141] (781s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[248.156988] Starting subtest: x-tiled-8bpp-rotate-270
[248.227809] Subtest x-tiled-8bpp-rotate-270: SKIP (0.070s)
[248.338359] [098/141] (780s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-pwrite)
[248.644757] Starting subtest: psr-1p-offscren-pri-indfb-draw-pwrite
[248.647507] Subtest psr-1p-offscren-pri-indfb-draw-pwrite: SKIP (0.000s)
[248.737434] [099/141] (780s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[249.051989] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[249.054623] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[249.153745] [100/141] (779s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[249.461872] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[249.464435] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[249.570487] [101/141] (779s left) gem_exec_balancer (sliced)
[249.801640] Starting subtest: sliced
[253.558333] Subtest sliced: SUCCESS (3.757s)
[257.439384] [102/141] (771s left) kms_atomic_transition (modeset-transition-fencing)
[257.742414] Starting subtest: modeset-transition-fencing
[257.743832] Starting dynamic subtest: 1x-outputs
[262.597176] Dynamic subtest 1x-outputs: SUCCESS (4.858s)
[262.719749] Subtest modeset-transition-fencing: SUCCESS (4.982s)
[263.059597] [103/141] (766s left) gem_vm_create (create-ext)
[263.274461] Starting subtest: create-ext
[263.282220] Subtest create-ext: SUCCESS (0.006s)
[263.390330] [104/141] (765s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[263.696356] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[263.698945] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[263.809278] [105/141] (765s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[264.118517] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[264.121193] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[264.225326] [106/141] (765s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[264.544079] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[264.547788] Starting dynamic subtest: pipe-A-DP-1
[265.531307] Dynamic subtest pipe-A-DP-1: SUCCESS (0.986s)
[265.553870] Starting dynamic subtest: pipe-B-DP-1
[266.754097] Dynamic subtest pipe-B-DP-1: SUCCESS (1.219s)
[266.776673] Starting dynamic subtest: pipe-C-DP-1
[267.703965] Dynamic subtest pipe-C-DP-1: SUCCESS (0.946s)
[267.705428] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (3.162s)
[268.119686] [107/141] (761s left) gem_exec_whisper (basic-queues-priority-all)
[268.348064] Starting subtest: basic-queues-priority-all
[290.947600] Subtest basic-queues-priority-all: SUCCESS (22.598s)
[291.148833] [108/141] (738s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[291.860115] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[292.174215] [109/141] (737s left) perf (invalid-remove-userspace-config)
[292.504545] Starting subtest: invalid-remove-userspace-config
[292.546893] Subtest invalid-remove-userspace-config: SUCCESS (0.039s)
[292.592317] [110/141] (736s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[292.897330] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[303.980515] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.084s)
[304.308228] [111/141] (725s left) kms_cursor_crc (cursor-alpha-transparent)
[304.634697] Starting subtest: cursor-alpha-transparent
[304.636247] Starting dynamic subtest: pipe-A-DP-1
[305.159451] Dynamic subtest pipe-A-DP-1: SUCCESS (0.523s)
[305.190688] Starting dynamic subtest: pipe-C-DP-1
[306.002986] Dynamic subtest pipe-C-DP-1: SUCCESS (0.832s)
[306.004284] Subtest cursor-alpha-transparent: SUCCESS (1.369s)
[306.365180] [112/141] (723s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[306.686792] Starting subtest: yf-tiled-64bpp-rotate-90
[306.689729] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[306.795962] [113/141] (722s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[307.077920] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[307.080397] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.003s)
[307.196661] [114/141] (722s left) kms_big_fb (linear-8bpp-rotate-90)
[307.523342] Starting subtest: linear-8bpp-rotate-90
[307.602536] Subtest linear-8bpp-rotate-90: SKIP (0.076s)
[307.729610] [115/141] (721s left) i915_selftest (live)
[309.436677] Starting subtest: live
[309.438498] Starting dynamic subtest: sanitycheck
[311.077500] Dynamic subtest sanitycheck: SUCCESS (1.639s)
[311.093561] Starting dynamic subtest: uncore
[312.348242] Dynamic subtest uncore: SUCCESS (1.269s)
[312.362921] Starting dynamic subtest: workarounds
[313.731166] Dynamic subtest workarounds: SUCCESS (1.378s)
[313.745322] Starting dynamic subtest: gt_engines
[315.368377] Dynamic subtest gt_engines: SUCCESS (1.636s)
[315.384856] Starting dynamic subtest: gt_timelines
[331.854316] Dynamic subtest gt_timelines: SUCCESS (16.484s)
[331.868759] Starting dynamic subtest: gt_contexts
[333.157236] Dynamic subtest gt_contexts: SUCCESS (1.302s)
[333.171646] Starting dynamic subtest: gt_lrc
[337.100498] Dynamic subtest gt_lrc: SUCCESS (3.941s)
[337.117445] Starting dynamic subtest: gt_mocs
[338.422167] Dynamic subtest gt_mocs: SUCCESS (1.321s)
[338.436557] Starting dynamic subtest: gt_pm
[346.551390] Dynamic subtest gt_pm: SUCCESS (8.127s)
[346.568675] Starting dynamic subtest: gt_heartbeat
[347.828185] Dynamic subtest gt_heartbeat: SUCCESS (1.276s)
[347.842469] Starting dynamic subtest: gt_tlb
[349.823354] Dynamic subtest gt_tlb: SUCCESS (1.994s)
[349.840294] Starting dynamic subtest: requests
[357.401390] Dynamic subtest requests: SUCCESS (7.574s)
[357.418184] Starting dynamic subtest: migrate
[361.957363] Dynamic subtest migrate: SUCCESS (4.556s)
[361.971833] Starting dynamic subtest: active
[363.192373] Dynamic subtest active: SUCCESS (1.234s)
[363.209605] Starting dynamic subtest: objects
[367.297510] Dynamic subtest objects: SUCCESS (4.103s)
[367.313594] Starting dynamic subtest: mman
[375.729597] Dynamic subtest mman: SUCCESS (8.431s)
[375.746184] Starting dynamic subtest: dmabuf
[376.993151] Dynamic subtest dmabuf: SUCCESS (1.262s)
[377.007421] Starting dynamic subtest: vma
[378.180335] Dynamic subtest vma: SUCCESS (1.186s)
[378.197991] Starting dynamic subtest: coherency
[384.121418] Dynamic subtest coherency: SUCCESS (5.940s)
[384.137411] Starting dynamic subtest: gtt
[392.848439] Dynamic subtest gtt: SUCCESS (8.725s)
[392.862408] Starting dynamic subtest: gem
[394.207340] Dynamic subtest gem: SUCCESS (1.358s)
[394.221475] Starting dynamic subtest: evict
[398.529337] Dynamic subtest evict: SUCCESS (4.320s)
[398.543425] Starting dynamic subtest: hugepages
[404.960356] Dynamic subtest hugepages: SUCCESS (6.429s)
[404.974898] Starting dynamic subtest: gem_contexts
[424.826610] Dynamic subtest gem_contexts: SUCCESS (19.863s)
[424.841194] Starting dynamic subtest: client
[426.059360] Dynamic subtest client: SUCCESS (1.232s)
[426.076267] Starting dynamic subtest: gem_migrate
[427.232101] Dynamic subtest gem_migrate: SUCCESS (1.172s)
[427.249102] Starting dynamic subtest: reset
[433.750047] Dynamic subtest reset: SUCCESS (6.516s)
[433.764353] Starting dynamic subtest: memory_region
[434.974285] Dynamic subtest memory_region: SUCCESS (1.223s)
[434.988514] Starting dynamic subtest: hangcheck
[459.318141] Dynamic subtest hangcheck: SUCCESS (24.342s)
[459.332324] Starting dynamic subtest: execlists
[470.027503] Dynamic subtest execlists: SUCCESS (10.707s)
[470.044227] Starting dynamic subtest: ring_submission
[471.212333] Dynamic subtest ring_submission: SUCCESS (1.183s)
[471.229410] Starting dynamic subtest: perf
[472.608870] Dynamic subtest perf: SUCCESS (1.395s)
[472.624677] Starting dynamic subtest: slpc
[473.812462] Dynamic subtest slpc: SUCCESS (1.202s)
[473.830141] Starting dynamic subtest: guc
[474.964138] Dynamic subtest guc: SUCCESS (1.151s)
[474.978466] Starting dynamic subtest: guc_multi_lrc
[476.154081] Dynamic subtest guc_multi_lrc: SUCCESS (1.189s)
[476.168521] Starting dynamic subtest: guc_hang
[477.391194] Dynamic subtest guc_hang: SUCCESS (1.236s)
[477.405523] Starting dynamic subtest: late_gt_pm
[478.615244] Dynamic subtest late_gt_pm: SUCCESS (1.223s)
[478.616674] Subtest live: SUCCESS (169.178s)
[478.817644] [116/141] (550s left) gem_pwrite (basic-exhaustion)
[480.332721] Starting subtest: basic-exhaustion
[502.066650] Subtest basic-exhaustion: SUCCESS (21.733s)
[502.144845] [117/141] (527s left) syncobj_timeline (wait-any-snapshot)
[502.353737] Starting subtest: wait-any-snapshot
[502.437160] Subtest wait-any-snapshot: SUCCESS (0.083s)
[502.532093] [118/141] (527s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[502.866582] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[502.945866] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.078s)
[503.062381] [119/141] (526s left) kms_scaling_modes (scaling-mode-center)
[503.359921] Starting subtest: scaling-mode-center
[503.362745] Subtest scaling-mode-center: SKIP (0.001s)
[503.445644] [120/141] (526s left) syncobj_timeline (single-wait-all-available-signaled)
[503.649540] Starting subtest: single-wait-all-available-signaled
[503.651276] Subtest single-wait-all-available-signaled: SUCCESS (0.001s)
[503.745755] [121/141] (526s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[504.062689] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[504.065336] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[504.162305] [122/141] (525s left) kms_ccs (pipe-c-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[504.778894] Starting subtest: pipe-C-bad-pixel-format-y_tiled_gen12_rc_ccs_cc
[504.783090] Subtest pipe-C-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[505.202448] [123/141] (524s left) kms_vblank (pipe-a-query-forked-hang)
[505.507305] Starting subtest: pipe-A-query-forked-hang
[513.008324] Subtest pipe-A-query-forked-hang: SUCCESS (7.503s)
[513.349533] [124/141] (516s left) kms_addfb_basic (no-handle)
[513.554374] Starting subtest: no-handle
[513.555786] Subtest no-handle: SUCCESS (0.000s)
[513.765769] [125/141] (516s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[514.063807] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[514.065315] Starting dynamic subtest: pipe-A-valid-mode
[514.801459] Dynamic subtest pipe-A-valid-mode: SKIP (0.733s)
[514.803998] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (0.736s)
[515.191798] [126/141] (514s left) kms_ccs (pipe-a-crc-primary-rotation-180-yf_tiled_ccs)
[515.612441] Starting subtest: pipe-A-crc-primary-rotation-180-yf_tiled_ccs
[516.263588] Subtest pipe-A-crc-primary-rotation-180-yf_tiled_ccs: SUCCESS (0.650s)
[516.943221] [127/141] (513s left) gem_exec_nop (basic-series)
[517.185773] Starting subtest: basic-series
[520.242991] Subtest basic-series: SUCCESS (3.056s)
[520.457905] [128/141] (509s left) gem_tiled_pread_pwrite
[522.214290] [129/141] (507s left) kms_flip (basic-flip-vs-modeset)
[522.619684] Starting subtest: basic-flip-vs-modeset
[522.620949] Starting dynamic subtest: A-DP1
[524.099983] Dynamic subtest A-DP1: SUCCESS (1.478s)
[524.120774] Starting dynamic subtest: B-DP1
[525.225742] Dynamic subtest B-DP1: SUCCESS (1.124s)
[525.246699] Starting dynamic subtest: C-DP1
[526.379803] Dynamic subtest C-DP1: SUCCESS (1.150s)
[526.381211] Subtest basic-flip-vs-modeset: SUCCESS (3.761s)
[526.730348] [130/141] (503s left) kms_cursor_crc (cursor-sliding-32x32)
[527.042409] Starting subtest: cursor-sliding-32x32
[527.158572] Subtest cursor-sliding-32x32: SKIP (0.113s)
[527.277485] [131/141] (502s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[527.583596] Starting subtest: addfb25-yf-tiled-legacy
[527.584895] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[527.676733] [132/141] (502s left) gem_fence_thrash (bo-copy)
[527.874668] Starting subtest: bo-copy
[529.259213] Subtest bo-copy: SUCCESS (1.383s)
[529.360353] [133/141] (500s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[529.751622] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[530.024439] [134/141] (500s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[530.227383] Starting subtest: cpuset-medium-copy-odd
[541.849535] Subtest cpuset-medium-copy-odd: SUCCESS (11.623s)
[542.018281] [135/141] (488s left) gem_exec_flush (basic-wb-pro-default)
[542.375310] Starting subtest: basic-wb-pro-default
[547.851673] Subtest basic-wb-pro-default: SUCCESS (5.476s)
[548.052450] [136/141] (482s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_dg2_rc_ccs)
[548.483881] Starting subtest: pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs
[548.487885] Subtest pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[549.071374] [137/141] (481s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[549.385807] Starting subtest: fbcpsr-tiling-linear
[549.388495] Subtest fbcpsr-tiling-linear: SKIP (0.000s)
[549.488506] [138/141] (481s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs)
[550.202444] Subtest pipe-D-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[550.521303] [139/141] (480s left) gem_exec_params (invalid-flag)
[550.732775] Starting subtest: invalid-flag
[550.735178] Subtest invalid-flag: SUCCESS (0.001s)
[550.837278] [140/141] (479s left) kms_ccs (pipe-b-bad-aux-stride-4_tiled_mtl_rc_ccs_cc)
[551.428580] Starting subtest: pipe-B-bad-aux-stride-4_tiled_mtl_rc_ccs_cc
[551.431099] Subtest pipe-B-bad-aux-stride-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[551.881512] [141/141] (478s left) gem_ctx_isolation (preservation-s3)
[552.105617] Starting subtest: preservation-S3
[552.107313] Starting dynamic subtest: rcs0
[570.051415] Dynamic subtest rcs0: SUCCESS (2.721s)
[570.075562] Starting dynamic subtest: bcs0
[589.100211] Dynamic subtest bcs0: SUCCESS (2.801s)
[589.125926] Starting dynamic subtest: vcs0
[607.971488] Dynamic subtest vcs0: SUCCESS (2.524s)
[607.995233] Starting dynamic subtest: vecs0
[626.991427] Dynamic subtest vecs0: SUCCESS (2.468s)
[626.992691] Subtest preservation-S3: SUCCESS (10.520s)
[631.299406] Closing watchdogs
Done.