[58.538790] Initializing watchdogs
[58.538836]   /dev/watchdog0
[58.551156] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[58.555125] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.557184] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.560299] [001/130] (960s left) syncobj_timeline (etime-multi-wait-for-submit-available-unsubmitted)
[58.670656] Starting subtest: etime-multi-wait-for-submit-available-unsubmitted
[58.775837] Subtest etime-multi-wait-for-submit-available-unsubmitted: SUCCESS (0.103s)
[58.842832] [002/130] (960s left) gem_eio (in-flight-contexts-1us)
[58.993882] Starting subtest: in-flight-contexts-1us
[70.969197] Subtest in-flight-contexts-1us: SUCCESS (11.975s)
[71.338405] [003/130] (947s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[71.587333] Starting subtest: crc-primary-basic-y-tiled-ccs
[71.589812] Starting dynamic subtest: pipe-A-eDP-1
[71.613328] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[71.624084] Starting dynamic subtest: pipe-B-eDP-1
[71.646185] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[71.653220] Starting dynamic subtest: pipe-C-eDP-1
[71.675594] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[71.682797] Starting dynamic subtest: pipe-D-eDP-1
[71.705631] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[71.709860] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.007s)
[73.702167] [004/130] (945s left) kms_flip (2x-plain-flip-fb-recreate)
[73.805248] Starting subtest: 2x-plain-flip-fb-recreate
[73.816048] Subtest 2x-plain-flip-fb-recreate: SKIP (0.010s)
[73.885730] [005/130] (945s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[73.943548] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[73.945937] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[74.035198] [006/130] (945s left) gem_tiled_wb
[74.144419] [007/130] (945s left) gem_exec_reloc (basic-cpu-gtt-active)
[74.201427] Subtest basic-cpu-gtt-active: SKIP (0.000s)
[74.275069] [008/130] (945s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[74.372594] Starting subtest: fbcpsr-rgb565-draw-render
[79.137868] Subtest fbcpsr-rgb565-draw-render: SUCCESS (4.769s)
[79.263461] [009/130] (940s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[79.324776] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[79.379843] [010/130] (939s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt)
[79.484951] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt
[79.489103] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[79.573696] [011/130] (939s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[79.672014] Starting subtest: plane-downscale-factor-0-5-with-rotation
[79.674429] Starting dynamic subtest: pipe-A
[79.683827] Dynamic subtest pipe-A: SKIP (0.004s)
[79.692506] Starting dynamic subtest: pipe-B
[79.701652] Dynamic subtest pipe-B: SKIP (0.017s)
[79.709744] Starting dynamic subtest: pipe-C
[79.718791] Dynamic subtest pipe-C: SKIP (0.018s)
[79.725741] Starting dynamic subtest: pipe-D
[79.733807] Dynamic subtest pipe-D: SKIP (0.015s)
[79.738336] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.053s)
[79.818546] [012/130] (939s left) sw_sync (alloc_merge_fence)
[79.852808] Starting subtest: alloc_merge_fence
[79.855213] Subtest alloc_merge_fence: SUCCESS (0.000s)
[79.894215] [013/130] (939s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[79.996858] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[83.371014] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (3.379s)
[83.488057] [014/130] (935s left) gem_ctx_param (invalid-ctx-set)
[83.532565] Starting subtest: invalid-ctx-set
[83.534932] Subtest invalid-ctx-set: SUCCESS (0.001s)
[83.619691] [015/130] (935s left) gem_lmem_swapping (parallel-random)
[83.658070] Subtest parallel-random: SKIP (0.000s)
[83.688407] [016/130] (935s left) kms_pm_dc (dc3co-vpb-simulation)
[84.791717] Starting subtest: dc3co-vpb-simulation
[94.161449] Subtest dc3co-vpb-simulation: SKIP (9.363s)
[94.302407] [017/130] (925s left) sw_sync (timeline_closed_signaled)
[94.337259] Starting subtest: timeline_closed_signaled
[94.339661] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[94.379641] [018/130] (925s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[94.483841] Starting subtest: fbc-psr-sprite-mmap-cpu
[94.486296] Starting dynamic subtest: eDP-1
[94.490827] Dynamic subtest eDP-1: SKIP (0.000s)
[94.495358] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.000s)
[94.547116] [019/130] (925s left) gem_ctx_param (non-root-set)
[94.601486] Starting subtest: non-root-set
[94.622623] Subtest non-root-set: SUCCESS (0.021s)
[94.707851] [020/130] (924s left) api_intel_bb (object-noreloc-purge-cache-simple)
[94.763967] Starting subtest: object-noreloc-purge-cache-simple
[94.768989] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.005s)
[94.893575] [021/130] (924s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs)
[94.990891] Starting subtest: missing-ccs-buffer-yf-tiled-ccs
[94.993314] Starting dynamic subtest: pipe-A-eDP-1
[95.020253] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[95.028867] Starting dynamic subtest: pipe-B-eDP-1
[95.049221] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[95.055332] Starting dynamic subtest: pipe-C-eDP-1
[95.076478] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[95.082793] Starting dynamic subtest: pipe-D-eDP-1
[95.104988] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[95.109411] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.002s)
[97.128092] [022/130] (922s left) gem_readwrite (read-bad-handle)
[97.187756] Subtest read-bad-handle: SKIP (0.000s)
[97.240467] [023/130] (922s left) syncobj_timeline (signal-point-0)
[97.295923] Starting subtest: signal-point-0
[97.298318] Subtest signal-point-0: SUCCESS (0.000s)
[97.401438] [024/130] (922s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[97.495234] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[98.716160] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.222s)
[98.874462] [025/130] (920s left) kms_plane_cursor (viewport)
[98.979305] Starting subtest: viewport
[98.983068] Starting dynamic subtest: pipe-A-eDP-1-size-64
[101.773164] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.791s)
[101.831833] Starting dynamic subtest: pipe-A-eDP-1-size-128
[104.707277] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.882s)
[104.764378] Starting dynamic subtest: pipe-A-eDP-1-size-256
[107.608919] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.850s)
[107.951489] Starting dynamic subtest: pipe-B-eDP-1-size-64
[111.469629] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.524s)
[111.526501] Starting dynamic subtest: pipe-B-eDP-1-size-128
[114.359080] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.838s)
[114.417976] Starting dynamic subtest: pipe-B-eDP-1-size-256
[117.248411] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.837s)
[117.594031] Starting dynamic subtest: pipe-C-eDP-1-size-64
[120.670386] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.085s)
[120.704467] Starting dynamic subtest: pipe-C-eDP-1-size-128
[123.156623] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.457s)
[123.195074] Starting dynamic subtest: pipe-C-eDP-1-size-256
[125.634497] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.446s)
[125.962776] Starting dynamic subtest: pipe-D-eDP-1-size-64
[129.097430] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.143s)
[129.133930] Starting dynamic subtest: pipe-D-eDP-1-size-128
[131.609426] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.480s)
[131.644178] Starting dynamic subtest: pipe-D-eDP-1-size-256
[134.109250] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.469s)
[134.424628] Subtest viewport: SUCCESS (35.441s)
[135.233134] [026/130] (884s left) perf (gen12-group-exclusive-stream-sample-oa)
[135.337783] Starting subtest: gen12-group-exclusive-stream-sample-oa
[135.372316] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.033s)
[135.412217] [027/130] (884s left) gem_ccs (block-copy-uncompressed)
[135.477051] Starting subtest: block-copy-uncompressed
[135.479333] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[135.500491] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.023s)
[135.507945] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[135.518054] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[135.524781] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[135.535430] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[135.542340] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[135.554218] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[135.556965] Subtest block-copy-uncompressed: SUCCESS (0.077s)
[135.680917] [028/130] (884s left) gem_vm_create (create-ext)
[135.749396] Starting subtest: create-ext
[135.754974] Subtest create-ext: SUCCESS (0.005s)
[135.891910] [029/130] (884s left) gem_ctx_param (set-priority-not-supported)
[135.944921] Starting subtest: set-priority-not-supported
[135.949742] Subtest set-priority-not-supported: SKIP (0.000s)
[136.007787] [030/130] (883s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-20x20)
[136.111112] Starting subtest: planes-downscale-factor-0-25-upscale-20x20
[136.113395] Starting dynamic subtest: pipe-A
[136.128706] Dynamic subtest pipe-A: SKIP (0.011s)
[136.136797] Starting dynamic subtest: pipe-B
[136.150137] Dynamic subtest pipe-B: SKIP (0.021s)
[136.157232] Starting dynamic subtest: pipe-C
[136.169612] Dynamic subtest pipe-C: SKIP (0.020s)
[136.176478] Starting dynamic subtest: pipe-D
[136.189067] Dynamic subtest pipe-D: SKIP (0.020s)
[136.192845] Subtest planes-downscale-factor-0-25-upscale-20x20: SKIP (0.074s)
[136.269974] [031/130] (883s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[136.378456] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[139.827453] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (3.452s)
[139.966236] [032/130] (880s left) kms_dp_link_training (non-uhbr-sst)
[140.063075] Starting subtest: non-uhbr-sst
[140.070532] Subtest non-uhbr-sst: SKIP (0.000s)
[140.129286] [033/130] (879s left) kms_flip (flip-vs-modeset-vs-hang)
[140.219269] Starting subtest: flip-vs-modeset-vs-hang
[140.221181] Starting dynamic subtest: A-eDP1
[191.360794] Dynamic subtest A-eDP1: SUCCESS (51.137s)
[191.370732] Starting dynamic subtest: D-eDP1
[241.409436] Dynamic subtest D-eDP1: SUCCESS (50.047s)
[241.411455] Subtest flip-vs-modeset-vs-hang: SUCCESS (101.188s)
[241.792576] [034/130] (778s left) gem_ctx_persistence (hostile)
[241.857137] Starting subtest: hostile
[252.003383] Subtest hostile: SUCCESS (10.142s)
[252.128550] [035/130] (768s left) kms_color (deep-color)
[252.234203] Starting subtest: deep-color
[253.249023] Starting dynamic subtest: pipe-A-eDP-1-gamma
[253.955355] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.705s)
[253.963713] Starting dynamic subtest: pipe-A-eDP-1-degamma
[255.424289] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.467s)
[255.432859] Starting dynamic subtest: pipe-A-eDP-1-ctm
[256.824352] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.400s)
[257.626180] Starting dynamic subtest: pipe-B-eDP-1-gamma
[258.312806] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.691s)
[258.321628] Starting dynamic subtest: pipe-B-eDP-1-degamma
[259.663688] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.350s)
[259.673125] Starting dynamic subtest: pipe-B-eDP-1-ctm
[261.099569] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.435s)
[261.902640] Starting dynamic subtest: pipe-C-eDP-1-gamma
[262.568690] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.670s)
[262.577325] Starting dynamic subtest: pipe-C-eDP-1-degamma
[263.922232] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.353s)
[263.932202] Starting dynamic subtest: pipe-C-eDP-1-ctm
[265.256861] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.334s)
[266.080790] Starting dynamic subtest: pipe-D-eDP-1-gamma
[266.748252] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.671s)
[266.760888] Starting dynamic subtest: pipe-D-eDP-1-degamma
[268.091109] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.342s)
[268.101184] Starting dynamic subtest: pipe-D-eDP-1-ctm
[269.415231] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.324s)
[269.417525] Subtest deep-color: SUCCESS (17.180s)
[270.203641] [036/130] (749s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-render)
[270.313664] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-render
[275.110991] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-render: SUCCESS (4.800s)
[275.238357] [037/130] (744s left) kms_chamelium_audio (dp-audio)
[275.630647] Subtest dp-audio: SKIP (0.000s)
[276.459938] [038/130] (743s left) kms_frontbuffer_tracking (psr-suspend)
[276.566407] Starting subtest: psr-suspend
[312.636913] Subtest psr-suspend: SUCCESS (5.461s)
[312.741420] [039/130] (738s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[312.864157] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[312.903067] [040/130] (738s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[313.007468] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[317.574026] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (4.565s)
[317.681590] [041/130] (733s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-ccs)
[317.739116] Starting subtest: y-tiled-ccs-to-yf-tiled-ccs
[317.744198] Subtest y-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[317.913188] [042/130] (733s left) kms_flip (plain-flip-fb-recreate-interruptible)
[318.028223] Starting subtest: plain-flip-fb-recreate-interruptible
[318.030935] Starting dynamic subtest: A-eDP1
[326.897789] Dynamic subtest A-eDP1: SUCCESS (8.864s)
[326.907065] Starting dynamic subtest: B-eDP1
[335.726993] Dynamic subtest B-eDP1: SUCCESS (8.825s)
[335.736409] Starting dynamic subtest: C-eDP1
[344.543105] Dynamic subtest C-eDP1: SUCCESS (8.812s)
[344.551733] Starting dynamic subtest: D-eDP1
[353.371313] Dynamic subtest D-eDP1: SUCCESS (8.824s)
[353.376135] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.334s)
[354.539696] [043/130] (696s left) gem_render_copy_redux (flink-interruptible)
[354.594529] Starting subtest: flink-interruptible
[355.482883] Subtest flink-interruptible: SUCCESS (0.889s)
[355.615632] [044/130] (695s left) kms_flip (2x-dpms-vs-vblank-race)
[355.709722] Starting subtest: 2x-dpms-vs-vblank-race
[355.721080] Subtest 2x-dpms-vs-vblank-race: SKIP (0.011s)
[355.794052] [045/130] (695s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[355.896189] Starting subtest: psr-2p-primscrn-cur-indfb-move
[355.900934] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[356.005748] [046/130] (695s left) prime_vgem (basic-fence-read)
[356.130619] Starting subtest: basic-fence-read
[356.139452] Subtest basic-fence-read: SKIP (0.007s)
[356.241954] [FACT prime_vgem (basic-fence-read)] new: kernel.kmod_is_loaded.vgem: true
[356.245424] [047/130] (694s left) prime_self_import (basic-with_two_bos)
[356.284127] Starting subtest: basic-with_two_bos
[356.299247] Subtest basic-with_two_bos: SUCCESS (0.015s)
[356.394378] [048/130] (694s left) gem_partial_pwrite_pread (write-snoop)
[356.452304] Subtest write-snoop: SKIP (0.000s)
[356.522432] [049/130] (694s left) gem_sync (basic-store-all)
[356.591127] Starting subtest: basic-store-all
[359.628015] Subtest basic-store-all: SUCCESS (3.047s)
[359.809454] [050/130] (691s left) gem_mmap (big-bo)
[359.861685] Subtest big-bo: SKIP (0.000s)
[359.916801] [051/130] (691s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[360.028066] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[360.033086] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[360.133501] [052/130] (691s left) kms_prop_blob (blob-prop-lifetime)
[360.193416] Starting subtest: blob-prop-lifetime
[360.196324] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[360.301755] [053/130] (691s left) gem_exec_schedule (implicit-boths)
[360.372143] Starting subtest: implicit-boths
[360.374992] Starting dynamic subtest: rcs0
[360.467195] Dynamic subtest rcs0: SUCCESS (0.107s)
[360.483104] Starting dynamic subtest: vcs0
[360.557250] Dynamic subtest vcs0: SUCCESS (0.090s)
[360.568727] Starting dynamic subtest: vcs1
[360.637193] Dynamic subtest vcs1: SUCCESS (0.080s)
[360.648250] Starting dynamic subtest: vecs0
[360.710749] Dynamic subtest vecs0: SUCCESS (0.073s)
[360.721578] Starting dynamic subtest: ccs0
[360.788001] Dynamic subtest ccs0: SUCCESS (0.077s)
[360.790303] Subtest implicit-boths: SUCCESS (0.430s)
[361.620642] [054/130] (689s left) perf (invalid-create-userspace-config)
[361.710910] Starting subtest: invalid-create-userspace-config
[361.714036] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[361.748637] [055/130] (689s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[361.867227] Starting subtest: 4-tiled-8bpp-rotate-90
[361.889420] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.022s)
[362.019317] [056/130] (689s left) gem_exec_fence (basic-await)
[362.197383] Starting subtest: basic-await
[362.200424] Starting dynamic subtest: rcs0
[362.278860] Dynamic subtest rcs0: SUCCESS (0.081s)
[362.289499] Starting dynamic subtest: bcs0
[362.358952] Dynamic subtest bcs0: SUCCESS (0.079s)
[362.369896] Starting dynamic subtest: vcs0
[362.439769] Dynamic subtest vcs0: SUCCESS (0.079s)
[362.450394] Starting dynamic subtest: vcs1
[362.517570] Dynamic subtest vcs1: SUCCESS (0.077s)
[362.528404] Starting dynamic subtest: vecs0
[362.596635] Dynamic subtest vecs0: SUCCESS (0.078s)
[362.607414] Starting dynamic subtest: ccs0
[362.674439] Dynamic subtest ccs0: SUCCESS (0.077s)
[362.677414] Subtest basic-await: SUCCESS (0.477s)
[364.658385] [057/130] (686s left) syncobj_wait (invalid-signal-one-illegal-handle)
[364.709056] Starting subtest: invalid-signal-one-illegal-handle
[364.711745] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[364.811660] [058/130] (686s left) syncobj_basic (create-signaled)
[364.871252] Starting subtest: create-signaled
[364.873694] Subtest create-signaled: SUCCESS (0.000s)
[364.965855] [059/130] (686s left) gem_exec_schedule (u-semaphore-codependency)
[365.045935] Starting subtest: u-semaphore-codependency
[365.060567] Subtest u-semaphore-codependency: SUCCESS (0.023s)
[365.426777] [060/130] (686s left) kms_writeback (writeback-pixel-formats)
[365.535152] Subtest writeback-pixel-formats: SKIP (0.000s)
[365.575019] [061/130] (686s left) sriov_basic (bind-unbind-vf)
[365.641578] Starting subtest: bind-unbind-vf
[365.644688] Starting dynamic subtest: vf-1
[365.732402] Dynamic subtest vf-1: FAIL (0.014s)
[365.741422] Starting dynamic subtest: vf-2
[365.829920] Dynamic subtest vf-2: FAIL (0.003s)
[365.839828] Starting dynamic subtest: vf-3
[365.927298] Dynamic subtest vf-3: FAIL (0.003s)
[365.936400] Starting dynamic subtest: vf-4
[366.024886] Dynamic subtest vf-4: FAIL (0.003s)
[366.034184] Starting dynamic subtest: vf-5
[366.121887] Dynamic subtest vf-5: FAIL (0.003s)
[366.130415] Starting dynamic subtest: vf-6
[366.218005] Dynamic subtest vf-6: FAIL (0.003s)
[366.227346] Starting dynamic subtest: vf-7
[366.314035] Dynamic subtest vf-7: FAIL (0.003s)
[366.323586] Starting dynamic subtest: vf-random
[366.398031] Dynamic subtest vf-random: FAIL (0.003s)
[366.406406] Starting dynamic subtest: vf-last
[366.480087] Dynamic subtest vf-last: FAIL (0.598s)
[366.482268] Subtest bind-unbind-vf: FAIL (0.639s)
[366.514714] [062/130] (685s left) gem_request_retire (retire-vma-not-inactive)
[366.571794] Starting subtest: retire-vma-not-inactive
[366.633417] Subtest retire-vma-not-inactive: SUCCESS (0.058s)
[366.762924] [063/130] (684s left) perf (invalid-open-flags)
[366.839880] Starting subtest: invalid-open-flags
[366.842135] Subtest invalid-open-flags: SUCCESS (0.000s)
[366.883927] [064/130] (684s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[366.937043] Starting subtest: invalid-multi-wait-unsubmitted
[366.939700] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[367.042625] [065/130] (684s left) syncobj_wait (reset-during-wait-for-submit)
[367.091682] Starting subtest: reset-during-wait-for-submit
[367.195421] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[367.267694] [066/130] (684s left) kms_addfb_basic (basic-y-tiled-legacy)
[367.322283] Starting subtest: basic-y-tiled-legacy
[367.327251] Subtest basic-y-tiled-legacy: SKIP (0.000s)
[367.470060] [067/130] (684s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[367.571452] Starting subtest: fbc-psr2-cursor-plane-onoff
[367.574175] Starting dynamic subtest: eDP-1
[367.579201] Dynamic subtest eDP-1: SKIP (0.000s)
[367.584209] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[367.636981] [068/130] (684s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[367.743818] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[367.746175] Starting dynamic subtest: pipe-A-default-mode
[370.345343] Dynamic subtest pipe-A-default-mode: SUCCESS (2.598s)
[370.348078] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.599s)
[371.123769] [069/130] (680s left) i915_pm_rc6_residency (rc6-fence)
[371.188587] Starting subtest: rc6-fence
[371.216032] Starting dynamic subtest: gt0
[384.474921] Dynamic subtest gt0: SUCCESS (13.253s)
[384.492453] Starting dynamic subtest: gt1
[399.484916] Dynamic subtest gt1: SUCCESS (15.004s)
[399.488331] Subtest rc6-fence: SUCCESS (28.289s)
[399.556248] [070/130] (652s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[399.617411] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[399.620281] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[399.711649] [071/130] (652s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[399.994235] Starting subtest: cursor-rapid-movement-max-size
[400.001416] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[400.120229] [072/130] (651s left) fbdev (unaligned-read)
[400.161441] Starting subtest: unaligned-read
[400.176450] Subtest unaligned-read: SUCCESS (0.016s)
[400.223587] [073/130] (651s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[400.313584] Starting subtest: primary-yf-tiled-reflect-x-270
[400.318885] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[400.381138] [074/130] (651s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[400.491215] Starting subtest: y-tiled-8bpp-rotate-0
[400.496238] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[400.596071] [075/130] (651s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[405.597564] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[405.600398] Starting dynamic subtest: pipe-A-eDP-1
[406.381996] Dynamic subtest pipe-A-eDP-1: SKIP (0.779s)
[406.390940] Starting dynamic subtest: pipe-B-eDP-1
[407.505321] Dynamic subtest pipe-B-eDP-1: SKIP (1.122s)
[407.510678] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.906s)
[408.670762] [076/130] (643s left) gem_pxp (reject-modify-context-protection-off-2)
[408.789667] Starting subtest: reject-modify-context-protection-off-2
[408.792431] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[408.905623] [077/130] (643s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[409.012424] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[409.016997] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[409.095671] [078/130] (643s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[409.193481] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[412.712202] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (3.524s)
[412.873237] [079/130] (639s left) kms_scaling_modes (scaling-mode-full)
[412.970105] Starting subtest: scaling-mode-full
[412.972381] Starting dynamic subtest: pipe-A-eDP-1
[413.080893] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.110s)
[413.093262] Starting dynamic subtest: pipe-B-eDP-1
[414.335144] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.251s)
[414.344928] Starting dynamic subtest: pipe-C-eDP-1
[415.444760] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.109s)
[415.459967] Starting dynamic subtest: pipe-D-eDP-1
[416.566715] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.121s)
[416.569441] Subtest scaling-mode-full: SUCCESS (3.598s)
[417.811946] [080/130] (634s left) kms_lease (invalid-create-leases)
[417.905914] Starting subtest: invalid-create-leases
[417.914149] Subtest invalid-create-leases: SUCCESS (0.006s)
[418.009859] [081/130] (634s left) kms_psr (pr-sprite-blt)
[418.112466] Starting subtest: pr-sprite-blt
[418.118220] Subtest pr-sprite-blt: SKIP (0.000s)
[418.184154] [082/130] (634s left) i915_pm_rps (fence-order)
[418.243240] Starting subtest: fence-order
[420.742278] Subtest fence-order: SUCCESS (2.497s)
[420.830545] [083/130] (631s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[420.883632] Starting subtest: invalid-signal-one-illegal-handle
[420.886304] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[420.993045] [084/130] (631s left) gem_exec_whisper (basic-contexts-forked)
[421.064981] Starting subtest: basic-contexts-forked
[445.924259] Subtest basic-contexts-forked: SUCCESS (24.860s)
[446.102203] [085/130] (606s left) kms_psr (psr-sprite-plane-move)
[446.204210] Starting subtest: psr-sprite-plane-move
[446.206829] Starting dynamic subtest: eDP-1
[447.942115] Dynamic subtest eDP-1: SUCCESS (1.739s)
[447.944678] Subtest psr-sprite-plane-move: SUCCESS (1.739s)
[448.751709] [086/130] (603s left) api_intel_bb (lot-of-buffers)
[448.814404] Starting subtest: lot-of-buffers
[449.709828] Subtest lot-of-buffers: SUCCESS (0.898s)
[450.168213] [087/130] (602s left) kms_pm_lpsp (screens-disabled)
[451.269290] Starting subtest: screens-disabled
[451.274276] Subtest screens-disabled: SKIP (0.000s)
[451.334908] [088/130] (601s left) dmabuf_sync_file (export-before-signal)
[451.383167] Starting subtest: export-before-signal
[451.385833] Subtest export-before-signal: SUCCESS (0.001s)
[451.435860] [089/130] (601s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[451.544870] Starting subtest: yf-tiled-16bpp-rotate-90
[451.550169] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[451.658121] [090/130] (601s left) gem_ctx_persistence (legacy-engines-persistence)
[451.719654] Starting subtest: legacy-engines-persistence
[451.723869] Starting dynamic subtest: render
[452.545871] Dynamic subtest render: SUCCESS (0.825s)
[452.557228] Starting dynamic subtest: bsd1
[453.330680] Dynamic subtest bsd1: SUCCESS (0.782s)
[453.342246] Starting dynamic subtest: bsd2
[454.164490] Dynamic subtest bsd2: SUCCESS (0.831s)
[454.175532] Starting dynamic subtest: blt
[454.953618] Dynamic subtest blt: SUCCESS (0.786s)
[454.964425] Starting dynamic subtest: vebox
[455.750791] Dynamic subtest vebox: SUCCESS (0.794s)
[455.753833] Subtest legacy-engines-persistence: SUCCESS (4.032s)
[455.827782] [091/130] (596s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[455.923945] Starting subtest: bad-aux-stride-y-tiled-ccs
[455.926407] Starting dynamic subtest: pipe-A-eDP-1
[455.951028] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[455.960410] Starting dynamic subtest: pipe-B-eDP-1
[455.985330] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[455.994098] Starting dynamic subtest: pipe-C-eDP-1
[456.019160] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[456.027963] Starting dynamic subtest: pipe-D-eDP-1
[456.054090] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[456.059019] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s)
[458.113647] [092/130] (594s left) kms_feature_discovery (display-3x)
[458.215425] Starting subtest: display-3x
[458.220883] Subtest display-3x: SKIP (0.000s)
[458.281849] [093/130] (594s left) gem_exec_balancer (hang)
[458.531023] Starting subtest: hang
[460.570060] Subtest hang: SUCCESS (2.041s)
[460.712392] [094/130] (592s left) kms_cursor_edge_walk (128x128-top-bottom)
[460.819801] Starting subtest: 128x128-top-bottom
[460.822349] Starting dynamic subtest: pipe-A-eDP-1
[464.262447] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.440s)
[464.272431] Starting dynamic subtest: pipe-D-eDP-1
[467.378284] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.113s)
[467.380815] Subtest 128x128-top-bottom: SUCCESS (6.556s)
[468.182220] [095/130] (584s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[468.288708] Starting subtest: addfb25-y-tiled-small-legacy
[468.292864] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[468.391005] [096/130] (584s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[468.495204] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[468.500225] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[468.605857] [097/130] (584s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[468.714994] Starting subtest: fbc-modesetfrombusy
[471.517376] Subtest fbc-modesetfrombusy: SUCCESS (2.806s)
[471.630945] [098/130] (581s left) perf_pmu (gt-awake)
[471.672027] Starting subtest: gt-awake
[480.576068] Subtest gt-awake: SUCCESS (8.900s)
[480.670675] [099/130] (572s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[480.785300] Starting subtest: y-tiled-addfb-size-offset-overflow
[480.790664] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[480.895887] [100/130] (572s left) kms_flip (2x-nonexisting-fb)
[480.997174] Starting subtest: 2x-nonexisting-fb
[481.008181] Subtest 2x-nonexisting-fb: SKIP (0.010s)
[481.133889] [101/130] (571s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[481.237925] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[481.243184] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[481.335058] [102/130] (571s left) kms_hdr (static-toggle)
[481.437179] Starting subtest: static-toggle
[481.445249] Subtest static-toggle: SKIP (0.000s)
[481.507508] [103/130] (571s left) gem_spin_batch (resubmit-all)
[481.585009] Starting subtest: resubmit-all
[481.588027] Starting dynamic subtest: rcs0
[481.601443] Dynamic subtest rcs0: SUCCESS (0.016s)
[481.611234] Starting dynamic subtest: bcs0
[481.614046] Dynamic subtest bcs0: SUCCESS (0.002s)
[481.623342] Starting dynamic subtest: vcs0
[481.625671] Dynamic subtest vcs0: SUCCESS (0.002s)
[481.633995] Starting dynamic subtest: vcs1
[481.636197] Dynamic subtest vcs1: SUCCESS (0.002s)
[481.644208] Starting dynamic subtest: vecs0
[481.646223] Dynamic subtest vecs0: SUCCESS (0.002s)
[481.654159] Starting dynamic subtest: ccs0
[481.656223] Dynamic subtest ccs0: SUCCESS (0.002s)
[481.658185] Subtest resubmit-all: SUCCESS (0.029s)
[483.549887] [104/130] (569s left) kms_joiner (invalid-modeset-force-big-joiner)
[483.657980] Starting subtest: invalid-modeset-force-big-joiner
[483.660157] Starting dynamic subtest: big_joiner_on_last_pipe
[483.995783] Dynamic subtest big_joiner_on_last_pipe: SUCCESS (0.345s)
[483.998411] Subtest invalid-modeset-force-big-joiner: SUCCESS (0.346s)
[484.758349] [105/130] (568s left) gem_ctx_engines (execute-allforone)
[484.818886] Starting subtest: execute-allforone
[484.835294] Subtest execute-allforone: SUCCESS (0.016s)
[486.571673] [106/130] (566s left) prime_self_import (reimport-vs-gem_close-race)
[486.612453] Starting subtest: reimport-vs-gem_close-race
[491.718443] Subtest reimport-vs-gem_close-race: SUCCESS (5.102s)
[491.790917] [107/130] (561s left) gem_mmap_wc (fault-concurrent)
[491.846261] Subtest fault-concurrent: SKIP (0.000s)
[491.921214] [108/130] (561s left) kms_content_protection (content-type-change)
[492.099959] Starting subtest: content-type-change
[495.410063] Subtest content-type-change: SKIP (3.307s)
[496.576771] [109/130] (556s left) gem_render_copy (yf-tiled-to-vebox-linear)
[496.631440] Starting subtest: yf-tiled-to-vebox-linear
[496.636021] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[496.808799] [110/130] (556s left) kms_chamelium_color (degamma)
[496.910227] Subtest degamma: SKIP (0.000s)
[496.950636] [111/130] (556s left) kms_big_fb (linear-64bpp-rotate-180)
[497.054426] Starting subtest: linear-64bpp-rotate-180
[499.432327] Subtest linear-64bpp-rotate-180: SUCCESS (2.377s)
[499.670742] [112/130] (553s left) kms_big_fb (linear-16bpp-rotate-270)
[499.775269] Starting subtest: linear-16bpp-rotate-270
[499.795141] Subtest linear-16bpp-rotate-270: SKIP (0.019s)
[499.926760] [113/130] (553s left) kms_chamelium_hpd (common-hpd-after-suspend)
[500.308791] Subtest common-hpd-after-suspend: SKIP (0.000s)
[501.081256] [114/130] (552s left) kms_psr (fbc-pr-sprite-plane-move)
[501.185825] Starting subtest: fbc-pr-sprite-plane-move
[501.191217] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[501.250905] [115/130] (552s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[501.364900] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[505.878114] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (4.516s)
[506.020623] [116/130] (547s left) kms_cursor_crc (cursor-onscreen-512x512)
[506.255446] Starting subtest: cursor-onscreen-512x512
[506.260699] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[506.421510] [117/130] (547s left) kms_psr2_sf (pr-overlay-plane-move-continuous-sf)
[511.392242] Starting subtest: pr-overlay-plane-move-continuous-sf
[511.398094] Subtest pr-overlay-plane-move-continuous-sf: SKIP (0.002s)
[512.248804] [118/130] (541s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[512.350110] Starting subtest: bad-rotation-90-yf-tiled-ccs
[512.352844] Starting dynamic subtest: pipe-A-eDP-1
[512.378934] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[512.389238] Starting dynamic subtest: pipe-B-eDP-1
[512.414863] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[512.424070] Starting dynamic subtest: pipe-C-eDP-1
[512.450286] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[512.459996] Starting dynamic subtest: pipe-D-eDP-1
[512.488207] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[512.494009] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[514.494655] [119/130] (539s left) gen9_exec_parse (secure-batches)
[514.560429] Subtest secure-batches: SKIP (0.000s)
[514.630486] [120/130] (539s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[514.725300] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[519.918447] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (5.196s)
[520.027755] [121/130] (533s left) gem_ctx_param (invalid-size-get)
[520.090080] Starting subtest: invalid-size-get
[520.092790] Subtest invalid-size-get: SUCCESS (0.001s)
[520.201630] [122/130] (533s left) gem_exec_schedule (pi-userfault)
[520.390429] Starting subtest: pi-userfault
[520.393077] Starting dynamic subtest: rcs0
[520.398607] Dynamic subtest rcs0: SUCCESS (0.010s)
[520.410333] Starting dynamic subtest: bcs0
[520.412762] Dynamic subtest bcs0: SUCCESS (0.007s)
[520.421830] Starting dynamic subtest: vcs0
[520.424202] Dynamic subtest vcs0: SUCCESS (0.011s)
[520.433173] Starting dynamic subtest: vcs1
[520.435259] Dynamic subtest vcs1: SUCCESS (0.009s)
[520.444450] Starting dynamic subtest: vecs0
[520.447211] Dynamic subtest vecs0: SUCCESS (0.009s)
[520.456456] Starting dynamic subtest: ccs0
[520.459430] Dynamic subtest ccs0: SUCCESS (0.009s)
[520.462083] Subtest pi-userfault: SUCCESS (0.059s)
[520.629113] [123/130] (533s left) sysfs_heartbeat_interval (invalid)
[520.700248] Starting subtest: invalid
[520.703191] Starting dynamic subtest: vecs0
[520.706100] Dynamic subtest vecs0: SUCCESS (0.000s)
[520.716973] Starting dynamic subtest: ccs0
[520.719215] Dynamic subtest ccs0: SUCCESS (0.000s)
[520.727665] Starting dynamic subtest: vcs0
[520.729714] Dynamic subtest vcs0: SUCCESS (0.000s)
[520.737847] Starting dynamic subtest: bcs0
[520.740005] Dynamic subtest bcs0: SUCCESS (0.000s)
[520.748103] Starting dynamic subtest: rcs0
[520.750193] Dynamic subtest rcs0: SUCCESS (0.000s)
[520.758089] Starting dynamic subtest: vcs1
[520.760178] Dynamic subtest vcs1: SUCCESS (0.000s)
[520.762121] Subtest invalid: SUCCESS (0.005s)
[520.818136] [124/130] (533s left) gem_userptr_blits (coherency-sync)
[520.890707] Starting subtest: coherency-sync
[521.383410] Subtest coherency-sync: SKIP (0.452s)
[521.504139] [125/130] (532s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[521.663253] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[521.668226] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[521.747745] [126/130] (532s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[522.298308] Starting subtest: syncobj-timeline-invalid-wait
[522.301167] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.003s)
[523.571490] [127/130] (530s left) gem_mmap_offset (pf-nonblock)
[523.635649] Starting subtest: pf-nonblock
[523.642313] Subtest pf-nonblock: SUCCESS (0.008s)
[523.776517] [128/130] (530s left) perf (polling)
[523.885213] Starting subtest: polling
[523.888694] Starting dynamic subtest: 0-rcs0
[533.921009] Dynamic subtest 0-rcs0: SUCCESS (10.033s)
[533.931560] Starting dynamic subtest: 1-vecs0
[543.938999] Dynamic subtest 1-vecs0: SUCCESS (10.017s)
[543.941402] Subtest polling: SUCCESS (20.052s)
[543.985650] [129/130] (510s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[544.042275] Starting subtest: wait-all-for-submit-delayed-submit
[544.145565] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[544.222158] [130/130] (509s left) tools_test (tools_test)
[544.277924] Starting subtest: tools_test
[544.334954] Subtest tools_test: SUCCESS (0.076s)
[544.387777] Closing watchdogs
Done.