[84.671928] Initializing watchdogs
[84.671991]   /dev/watchdog0
[84.685601] [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]
[84.689912] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[84.692092] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[84.695456] [001/130] (960s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[84.826197] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[84.828545] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[84.935361] [002/130] (960s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[85.051255] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[90.670158] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.624s)
[90.763258] [003/130] (954s left) kms_cdclk (plane-scaling)
[90.864614] Starting subtest: plane-scaling
[90.867571] Starting dynamic subtest: pipe-A-eDP-1
[91.144775] Dynamic subtest pipe-A-eDP-1: SKIP (0.281s)
[91.153051] Starting dynamic subtest: pipe-B-eDP-1
[91.157744] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[91.165191] Starting dynamic subtest: pipe-C-eDP-1
[91.169661] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[91.176882] Starting dynamic subtest: pipe-D-eDP-1
[91.181247] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[91.185565] Subtest plane-scaling: SKIP (0.287s)
[92.006085] [004/130] (953s left) kms_cursor_crc (cursor-onscreen-64x21)
[92.178080] Starting subtest: cursor-onscreen-64x21
[92.220781] Subtest cursor-onscreen-64x21: SKIP (0.038s)
[92.444686] [005/130] (952s left) kms_lease (possible-crtcs-filtering)
[92.550028] Starting subtest: possible-crtcs-filtering
[92.557258] Subtest possible-crtcs-filtering: SUCCESS (0.005s)
[92.650429] [006/130] (952s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf)
[97.668786] Starting subtest: psr2-cursor-plane-move-continuous-exceed-fully-sf
[97.670876] Starting dynamic subtest: pipe-A-eDP-1
[109.391591] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.725s)
[109.398254] Starting dynamic subtest: pipe-B-eDP-1
[121.110278] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.715s)
[121.112607] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SUCCESS (23.443s)
[121.961563] [007/130] (923s left) kms_chamelium_color (degamma)
[122.059530] Subtest degamma: SKIP (0.000s)
[122.100055] [008/130] (923s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[122.199097] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[122.203792] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[123.286838] [009/130] (922s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[123.353560] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[123.358211] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[123.527962] [010/130] (921s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[128.534084] Starting subtest: fbc-pr-overlay-plane-update-sf-dmg-area
[128.539504] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[129.388244] [011/130] (916s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[129.495511] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[134.780054] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (5.286s)
[134.903700] [012/130] (910s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[135.006075] Starting subtest: x-tiled-64bpp-rotate-90
[135.025324] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[135.151846] [013/130] (910s left) gen7_exec_parse (cmd-crossing-page)
[135.226314] Subtest cmd-crossing-page: SKIP (0.000s)
[135.306985] [014/130] (910s left) debugfs_test (read_all_entries_display_off)
[135.403770] Starting subtest: read_all_entries_display_off
[136.404520] Subtest read_all_entries_display_off: SUCCESS (0.999s)
[136.706033] [015/130] (908s left) kms_psr (fbc-pr-primary-mmap-cpu)
[136.806469] Starting subtest: fbc-pr-primary-mmap-cpu
[136.811307] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[136.871850] [016/130] (908s left) syncobj_wait (invalid-signal-one-illegal-handle)
[136.933104] Starting subtest: invalid-signal-one-illegal-handle
[136.935491] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[137.037239] [017/130] (908s left) gem_softpin (evict-single-offset)
[137.096295] Starting subtest: evict-single-offset
[165.936578] Subtest evict-single-offset: SUCCESS (28.835s)
[166.008905] [018/130] (879s left) fbdev (eof)
[166.091533] Starting subtest: eof
[166.093868] Subtest eof: SUCCESS (0.000s)
[166.143062] [019/130] (879s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0)
[166.266302] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0
[166.270514] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[166.356848] [020/130] (879s left) kms_atomic_transition (plane-all-transition)
[166.465469] Starting subtest: plane-all-transition
[166.467863] Starting dynamic subtest: pipe-A-eDP-1
[168.971911] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.504s)
[169.256827] Starting dynamic subtest: pipe-B-eDP-1
[172.535904] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.285s)
[172.815725] Subtest plane-all-transition: SUCCESS (6.347s)
[173.644438] [021/130] (872s left) kms_hdr (invalid-metadata-sizes)
[173.748997] Starting subtest: invalid-metadata-sizes
[173.755619] Subtest invalid-metadata-sizes: SKIP (0.000s)
[173.813360] [022/130] (872s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[173.926650] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[173.930847] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[174.033824] [023/130] (871s left) gem_madvise (dontneed-after-mmap)
[174.068515] Starting subtest: dontneed-after-mmap
[174.091402] Subtest dontneed-after-mmap: SUCCESS (0.022s)
[174.178669] [024/130] (871s left) gem_exec_flush (basic-wb-ro-default)
[174.244798] Starting subtest: basic-wb-ro-default
[179.730447] Subtest basic-wb-ro-default: SUCCESS (5.485s)
[179.911064] [025/130] (866s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[180.025232] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[180.029268] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[180.123995] [026/130] (865s left) kms_flip (nonexisting-fb)
[180.221206] Starting subtest: nonexisting-fb
[180.223412] Starting dynamic subtest: A-eDP1
[181.445281] Dynamic subtest A-eDP1: SUCCESS (1.223s)
[181.454276] Starting dynamic subtest: B-eDP1
[182.665667] Dynamic subtest B-eDP1: SUCCESS (1.220s)
[182.676983] Starting dynamic subtest: C-eDP1
[183.890944] Dynamic subtest C-eDP1: SUCCESS (1.224s)
[183.900853] Starting dynamic subtest: D-eDP1
[185.123319] Dynamic subtest D-eDP1: SUCCESS (1.232s)
[185.128435] Subtest nonexisting-fb: SUCCESS (4.907s)
[186.378368] [027/130] (859s left) kms_flip (2x-dpms-vs-vblank-race-interruptible)
[186.491931] Starting subtest: 2x-dpms-vs-vblank-race-interruptible
[186.505097] Subtest 2x-dpms-vs-vblank-race-interruptible: SKIP (0.011s)
[186.571808] [028/130] (859s left) kms_cursor_legacy (torture-bo)
[186.695748] Starting subtest: torture-bo
[186.698030] Starting dynamic subtest: pipe-A
[192.410914] Dynamic subtest pipe-A: SUCCESS (5.711s)
[192.425999] Starting dynamic subtest: pipe-B
[199.815320] Dynamic subtest pipe-B: SUCCESS (7.404s)
[199.823183] Starting dynamic subtest: pipe-C
[206.249849] Dynamic subtest pipe-C: SUCCESS (6.433s)
[206.259389] Starting dynamic subtest: pipe-D
[212.717611] Dynamic subtest pipe-D: SUCCESS (6.467s)
[212.727808] Starting dynamic subtest: all-pipes
[218.747694] Dynamic subtest all-pipes: SUCCESS (6.028s)
[218.750046] Subtest torture-bo: SUCCESS (32.048s)
[218.882964] [029/130] (827s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[218.933564] Starting subtest: x-tiled-to-vebox-yf-tiled
[218.937771] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[219.108312] [030/130] (827s left) kms_big_fb (linear-32bpp-rotate-180)
[219.211875] Starting subtest: linear-32bpp-rotate-180
[221.035794] Subtest linear-32bpp-rotate-180: SUCCESS (1.823s)
[221.207799] [031/130] (824s left) kms_writeback (writeback-fb-id-xrgb2101010)
[221.304581] Subtest writeback-fb-id-XRGB2101010: SKIP (0.000s)
[221.341073] [032/130] (824s left) kms_psr (psr-sprite-render)
[221.435702] Starting subtest: psr-sprite-render
[221.437550] Starting dynamic subtest: eDP-1
[223.202083] Dynamic subtest eDP-1: SUCCESS (1.765s)
[223.204231] Subtest psr-sprite-render: SUCCESS (1.766s)
[224.038008] [033/130] (822s left) drm_fdinfo (memory-info-idle)
[224.079926] Starting subtest: memory-info-idle
[224.082310] Starting dynamic subtest: smem0
[224.145055] Dynamic subtest smem0: SUCCESS (0.067s)
[224.147712] Subtest memory-info-idle: SUCCESS (0.068s)
[224.211124] [034/130] (822s left) kms_pm_backlight (brightness-with-dpms)
[224.307311] Starting subtest: brightness-with-dpms
[224.371065] Starting dynamic subtest: eDP-1
[226.729211] Dynamic subtest eDP-1: SUCCESS (2.356s)
[226.731546] Subtest brightness-with-dpms: SUCCESS (2.420s)
[227.582837] [035/130] (818s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[227.690047] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[227.694732] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[228.815789] [036/130] (817s left) gem_mmap (bad-size)
[228.887389] Subtest bad-size: SKIP (0.000s)
[228.940130] [037/130] (817s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[229.041382] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[229.045712] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[229.125201] [038/130] (817s left) kms_draw_crc (draw-method-mmap-cpu)
[229.224590] Starting subtest: draw-method-mmap-cpu
[229.226998] Starting dynamic subtest: xrgb8888-untiled
[229.439678] Dynamic subtest xrgb8888-untiled: SUCCESS (0.218s)
[229.447729] Starting dynamic subtest: rgb565-untiled
[229.639654] Dynamic subtest rgb565-untiled: SUCCESS (0.199s)
[229.647613] Starting dynamic subtest: xrgb2101010-untiled
[229.828684] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.189s)
[229.837488] Starting dynamic subtest: xbgr16161616f-untiled
[230.050181] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[230.057318] Starting dynamic subtest: xrgb8888-xtiled
[230.173264] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[230.182118] Starting dynamic subtest: rgb565-xtiled
[230.305859] Dynamic subtest rgb565-xtiled: SUCCESS (0.132s)
[230.314842] Starting dynamic subtest: xrgb2101010-xtiled
[230.427980] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s)
[230.434875] Starting dynamic subtest: xbgr16161616f-xtiled
[230.550428] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.122s)
[230.557399] Starting dynamic subtest: xrgb8888-4tiled
[230.683849] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[230.691043] Starting dynamic subtest: rgb565-4tiled
[230.817127] Dynamic subtest rgb565-4tiled: SUCCESS (0.133s)
[230.823829] Starting dynamic subtest: xrgb2101010-4tiled
[230.939256] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.122s)
[230.946628] Starting dynamic subtest: xbgr16161616f-4tiled
[231.083729] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.144s)
[231.085589] Subtest draw-method-mmap-cpu: SUCCESS (1.863s)
[231.183797] [039/130] (815s left) gem_exec_whisper (basic-fds-priority-all)
[231.250267] Starting subtest: basic-fds-priority-all
[252.884118] Subtest basic-fds-priority-all: SUCCESS (21.629s)
[253.067686] [040/130] (793s left) perf (rc6-disable)
[253.198026] Starting subtest: rc6-disable
[253.398097] Subtest rc6-disable: SUCCESS (0.199s)
[253.442866] [041/130] (793s left) gem_pxp (create-regular-context-1)
[253.566940] Starting subtest: create-regular-context-1
[253.569082] Subtest create-regular-context-1: SUCCESS (0.002s)
[253.689866] [042/130] (792s left) kms_flip (2x-absolute-wf_vblank)
[253.796226] Starting subtest: 2x-absolute-wf_vblank
[253.807511] Subtest 2x-absolute-wf_vblank: SKIP (0.011s)
[253.877349] [043/130] (792s left) i915_pm_rpm (gem-idle)
[256.120690] Starting subtest: gem-idle
[262.331162] Subtest gem-idle: SUCCESS (6.212s)
[262.646662] [044/130] (783s left) api_intel_allocator (reopen-fork)
[263.888341] Starting subtest: reopen-fork
[267.164599] Subtest reopen-fork: SUCCESS (3.278s)
[267.237909] [045/130] (779s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-25)
[267.336169] Starting subtest: planes-unity-scaling-downscale-factor-0-25
[267.338502] Starting dynamic subtest: pipe-A
[267.353509] Dynamic subtest pipe-A: SKIP (0.012s)
[267.362318] Starting dynamic subtest: pipe-B
[267.377571] Dynamic subtest pipe-B: SKIP (0.022s)
[267.385802] Starting dynamic subtest: pipe-C
[267.400719] Dynamic subtest pipe-C: SKIP (0.023s)
[267.408737] Starting dynamic subtest: pipe-D
[267.420925] Dynamic subtest pipe-D: SKIP (0.022s)
[267.424806] Subtest planes-unity-scaling-downscale-factor-0-25: SKIP (0.080s)
[267.532361] [046/130] (779s left) gem_mmap_gtt (basic-small-copy-xy)
[267.592823] Subtest basic-small-copy-XY: SKIP (0.000s)
[267.648741] [047/130] (779s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[267.748098] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[267.750507] Starting dynamic subtest: pipe-A-eDP-1
[268.201059] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.428s)
[268.209575] Starting dynamic subtest: pipe-B-eDP-1
[269.540615] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.334s)
[269.548428] Starting dynamic subtest: pipe-C-eDP-1
[270.938886] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.397s)
[270.949125] Starting dynamic subtest: pipe-D-eDP-1
[272.264073] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.324s)
[272.266261] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.487s)
[273.442087] [048/130] (773s left) gem_fenced_exec_thrash (no-spare-fences)
[273.512058] Subtest no-spare-fences: SKIP (0.000s)
[273.596569] [049/130] (773s left) gem_mmap_gtt (basic-write-read-distinct)
[273.660123] Subtest basic-write-read-distinct: SKIP (0.000s)
[273.717626] [050/130] (773s left) kms_invalid_mode (clock-too-high)
[273.819101] Starting subtest: clock-too-high
[273.821388] Starting dynamic subtest: pipe-A-eDP-1
[273.825664] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[273.834319] Starting dynamic subtest: pipe-B-eDP-1
[273.838743] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[273.847066] Starting dynamic subtest: pipe-C-eDP-1
[273.851020] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[273.859097] Starting dynamic subtest: pipe-D-eDP-1
[273.863245] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[273.867265] Subtest clock-too-high: SKIP (0.004s)
[273.896594] [051/130] (772s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[273.960952] Starting subtest: wait-all-for-submit-delayed-submit
[274.061054] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s)
[274.128332] [052/130] (772s left) kms_psr (pr-primary-page-flip)
[274.224294] Starting subtest: pr-primary-page-flip
[274.228731] Subtest pr-primary-page-flip: SKIP (0.000s)
[274.295273] [053/130] (772s left) gem_fence_thrash (bo-copy)
[274.332077] Starting subtest: bo-copy
[274.354610] Subtest bo-copy: SKIP (0.019s)
[274.409578] [054/130] (772s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[274.612493] Starting subtest: cursor-rapid-movement-256x256
[274.617450] Starting dynamic subtest: pipe-A-eDP-1
[275.168753] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.551s)
[275.197581] Starting dynamic subtest: pipe-D-eDP-1
[276.361060] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.170s)
[276.363368] Subtest cursor-rapid-movement-256x256: SUCCESS (1.747s)
[277.195320] [055/130] (769s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[277.307986] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[277.312394] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[277.414893] [056/130] (769s left) perf_pmu (render-node-busy-idle)
[277.501525] Starting subtest: render-node-busy-idle
[277.503963] Starting dynamic subtest: rcs0
[278.209646] Dynamic subtest rcs0: SUCCESS (0.707s)
[278.220157] Starting dynamic subtest: bcs0
[278.870071] Dynamic subtest bcs0: SUCCESS (0.658s)
[278.880870] Starting dynamic subtest: vcs0
[280.941363] Dynamic subtest vcs0: SUCCESS (2.068s)
[280.952531] Starting dynamic subtest: vcs1
[282.924999] Dynamic subtest vcs1: SUCCESS (1.982s)
[282.936164] Starting dynamic subtest: vecs0
[284.910031] Dynamic subtest vecs0: SUCCESS (1.982s)
[284.921076] Starting dynamic subtest: ccs0
[285.580069] Dynamic subtest ccs0: SUCCESS (0.670s)
[285.582824] Subtest render-node-busy-idle: SUCCESS (8.079s)
[285.651210] [057/130] (761s left) gem_exec_capture (userptr)
[285.716495] Starting subtest: userptr
[286.550907] Subtest userptr: SUCCESS (0.833s)
[286.692562] [058/130] (760s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[286.814877] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[286.827164] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.011s)
[286.894580] [059/130] (760s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[286.956354] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[287.015231] [060/130] (760s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[287.071914] Subtest writes-after-reads-snoop: SKIP (0.000s)
[287.159888] [061/130] (760s left) gem_exec_balancer (parallel)
[287.412467] Starting subtest: parallel
[288.209339] Subtest parallel: SUCCESS (0.797s)
[288.975194] [062/130] (758s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[289.079691] Starting subtest: x-tiled-16bpp-rotate-180
[290.525451] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.446s)
[290.694127] [063/130] (756s left) i915_hangman (gt-engine-error)
[290.757923] Starting subtest: gt-engine-error
[290.760293] Starting dynamic subtest: rcs0
[291.917295] Dynamic subtest rcs0: SUCCESS (1.160s)
[291.928832] Starting dynamic subtest: bcs0
[293.901269] Dynamic subtest bcs0: SUCCESS (1.981s)
[293.912592] Starting dynamic subtest: vcs0
[295.885294] Dynamic subtest vcs0: SUCCESS (1.981s)
[295.896900] Starting dynamic subtest: vcs1
[297.867055] Dynamic subtest vcs1: SUCCESS (1.979s)
[297.878052] Starting dynamic subtest: vecs0
[299.915041] Dynamic subtest vecs0: SUCCESS (2.046s)
[299.926216] Starting dynamic subtest: ccs0
[301.899358] Dynamic subtest ccs0: SUCCESS (1.982s)
[301.902416] Subtest gt-engine-error: SUCCESS (11.143s)
[301.973132] [064/130] (745s left) kms_pipe_crc_basic (bad-source)
[302.075447] Starting subtest: bad-source
[302.077855] Subtest bad-source: SUCCESS (0.000s)
[302.143525] [065/130] (745s left) gem_exec_reloc (basic-wc-cpu)
[302.216399] Subtest basic-wc-cpu: SKIP (0.000s)
[302.289966] [066/130] (745s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc)
[302.389288] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc
[305.476360] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (3.090s)
[305.582360] [067/130] (741s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[305.694582] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[310.187053] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (4.499s)
[310.316302] [068/130] (737s left) gem_exec_schedule (semaphore-user)
[310.407591] Starting subtest: semaphore-user
[310.456071] Subtest semaphore-user: SUCCESS (0.057s)
[311.948308] [069/130] (735s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[311.998054] Starting subtest: single-wait-all-for-submit-signaled
[312.000492] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[312.096846] [070/130] (735s left) gem_exec_schedule (promotion)
[312.160384] Starting subtest: promotion
[312.162603] Starting dynamic subtest: rcs0
[312.273793] Dynamic subtest rcs0: SUCCESS (0.116s)
[312.283923] Starting dynamic subtest: vcs0
[312.356819] Dynamic subtest vcs0: SUCCESS (0.083s)
[312.367572] Starting dynamic subtest: vcs1
[312.437623] Dynamic subtest vcs1: SUCCESS (0.080s)
[312.446421] Starting dynamic subtest: vecs0
[312.519132] Dynamic subtest vecs0: SUCCESS (0.081s)
[312.529554] Starting dynamic subtest: ccs0
[312.596655] Dynamic subtest ccs0: SUCCESS (0.077s)
[312.599438] Subtest promotion: SUCCESS (0.439s)
[313.992190] [071/130] (733s left) kms_async_flips (test-cursor-atomic)
[314.091394] Starting subtest: test-cursor-atomic
[314.095900] Subtest test-cursor-atomic: SKIP (0.000s)
[315.286454] [072/130] (732s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-render)
[315.398017] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-render
[320.208766] Subtest fbcpsr-1p-offscren-pri-indfb-draw-render: SUCCESS (4.815s)
[320.349276] [073/130] (727s left) kms_sequence (queue-idle)
[320.463855] Starting subtest: queue-idle
[320.466139] Starting dynamic subtest: pipe-A-eDP-1
[322.291267] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.825s)
[322.299081] Starting dynamic subtest: pipe-B-eDP-1
[324.699022] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.407s)
[324.707754] Starting dynamic subtest: pipe-C-eDP-1
[327.092239] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.391s)
[327.100918] Starting dynamic subtest: pipe-D-eDP-1
[329.528971] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.436s)
[329.531502] Subtest queue-idle: SUCCESS (9.063s)
[330.329646] [074/130] (717s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[330.428290] Starting subtest: addfb25-yf-tiled-legacy
[330.430504] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[330.529980] [075/130] (717s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[330.650716] Starting subtest: basic-busy-flip-before-cursor-legacy
[330.655708] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[330.742800] [076/130] (716s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[335.792764] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf
[335.797500] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[336.623173] [077/130] (711s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[336.737173] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[336.741122] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[336.840104] [078/130] (710s left) gen9_exec_parse (unaligned-access)
[336.897553] Subtest unaligned-access: SKIP (0.000s)
[336.978963] [079/130] (710s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[337.071321] Starting subtest: crc-primary-basic-y-tiled-ccs
[337.073558] Starting dynamic subtest: pipe-A-eDP-1
[337.096242] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[337.104837] Starting dynamic subtest: pipe-B-eDP-1
[337.126796] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[337.135308] Starting dynamic subtest: pipe-C-eDP-1
[337.157674] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[337.168190] Starting dynamic subtest: pipe-D-eDP-1
[337.190295] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[337.194332] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.005s)
[339.184950] [080/130] (708s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[339.806789] Starting subtest: syncobj-timeline-invalid-flags
[339.809618] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[340.989469] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[340.992760] [081/130] (706s left) syncobj_timeline (etime-single-wait-all-submitted)
[341.051725] Starting subtest: etime-single-wait-all-submitted
[341.161655] Subtest etime-single-wait-all-submitted: SUCCESS (0.108s)
[341.232944] [082/130] (706s left) gem_mmap (basic-small-bo)
[341.292668] Subtest basic-small-bo: SKIP (0.000s)
[341.350802] [083/130] (706s left) gem_ctx_persistence (heartbeat-stop)
[341.408548] Starting subtest: heartbeat-stop
[341.413460] Subtest heartbeat-stop: SKIP (0.000s)
[341.524738] [084/130] (706s left) gem_ctx_persistence (userptr)
[341.594391] Starting subtest: userptr
[341.826683] Subtest userptr: SUCCESS (0.232s)
[341.945722] [085/130] (706s left) syncobj_timeline (device-submit-unordered)
[342.012565] Starting subtest: device-submit-unordered
[342.014880] Subtest device-submit-unordered: SUCCESS (0.000s)
[342.108355] [086/130] (705s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[342.204359] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[342.207809] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[342.312398] [087/130] (705s left) kms_fbc_dirty_rect (fbc-dirty-rectangle-out-visible-area)
[342.604776] Subtest fbc-dirty-rectangle-out-visible-area: SKIP (0.000s)
[342.652936] [FACT kms_fbc_dirty_rect (fbc-dirty-rectangle-out-visible-area)] new: kernel.kmod_is_loaded.xe: true
[342.656623] [088/130] (705s left) kms_vblank (query-forked-hang)
[342.750387] Starting subtest: query-forked-hang
[342.752793] Starting dynamic subtest: pipe-A-eDP-1
[369.700685] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.946s)
[369.717226] Starting dynamic subtest: pipe-D-eDP-1
[394.665547] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.961s)
[394.667975] Subtest query-forked-hang: SUCCESS (51.912s)
[395.536199] [089/130] (652s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[397.085680] Starting subtest: modeset-lpsp-stress-no-wait
[406.939477] Subtest modeset-lpsp-stress-no-wait: SUCCESS (9.853s)
[407.013057] [090/130] (641s left) gem_ctx_exec (basic-nohangcheck)
[407.075770] Starting subtest: basic-nohangcheck
[407.339749] Subtest basic-nohangcheck: SUCCESS (0.265s)
[407.449183] [091/130] (640s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[407.557662] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[407.562288] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[407.658712] [092/130] (640s left) gem_tiled_fence_blits (basic)
[407.718259] Subtest basic: SKIP (0.000s)
[407.782275] [093/130] (640s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[408.163088] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[408.995537] [094/130] (639s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[409.098322] Starting subtest: fbc-psr2-primary-mmap-gtt
[409.100608] Starting dynamic subtest: eDP-1
[409.105095] Dynamic subtest eDP-1: SKIP (0.000s)
[409.109471] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[409.169144] [095/130] (639s left) gem_busy (semaphore)
[409.366365] Subtest semaphore: SKIP (0.000s)
[409.605912] [096/130] (638s left) kms_content_protection (legacy)
[409.780810] Starting subtest: legacy
[413.628072] Subtest legacy: SKIP (3.844s)
[414.822988] [097/130] (633s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[414.873748] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[414.952155] [098/130] (633s left) gem_exec_flush (basic-uc-prw-default)
[415.020683] Starting subtest: basic-uc-prw-default
[420.510745] Subtest basic-uc-prw-default: SUCCESS (5.491s)
[420.691512] [099/130] (627s left) gem_ppgtt (flink-and-close-vma-leak)
[420.766348] Starting subtest: flink-and-close-vma-leak
[420.813441] Subtest flink-and-close-vma-leak: SUCCESS (0.047s)
[420.947475] [100/130] (627s left) gem_ctx_param (root-set)
[421.016066] Starting subtest: root-set
[421.018449] Subtest root-set: SUCCESS (0.001s)
[421.133949] [101/130] (627s left) kms_plane_cursor (primary)
[421.251132] Starting subtest: primary
[421.255610] Starting dynamic subtest: pipe-A-eDP-1-size-64
[424.033102] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.779s)
[424.057389] Starting dynamic subtest: pipe-A-eDP-1-size-128
[426.855986] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.804s)
[426.880489] Starting dynamic subtest: pipe-A-eDP-1-size-256
[429.643688] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.771s)
[429.960592] Starting dynamic subtest: pipe-B-eDP-1-size-64
[433.397381] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.443s)
[433.420476] Starting dynamic subtest: pipe-B-eDP-1-size-128
[436.197073] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.781s)
[436.221102] Starting dynamic subtest: pipe-B-eDP-1-size-256
[439.008625] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.793s)
[439.329855] Starting dynamic subtest: pipe-C-eDP-1-size-64
[442.432249] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.113s)
[442.458158] Starting dynamic subtest: pipe-C-eDP-1-size-128
[444.854946] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.402s)
[444.882081] Starting dynamic subtest: pipe-C-eDP-1-size-256
[447.232456] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.357s)
[447.546647] Starting dynamic subtest: pipe-D-eDP-1-size-64
[450.688530] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.150s)
[450.714062] Starting dynamic subtest: pipe-D-eDP-1-size-128
[453.099163] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.391s)
[453.127325] Starting dynamic subtest: pipe-D-eDP-1-size-256
[455.521044] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.402s)
[455.816233] Subtest primary: SUCCESS (34.561s)
[456.615940] [102/130] (591s left) gem_exec_schedule (deep)
[456.804752] Starting subtest: deep
[456.807098] Starting dynamic subtest: rcs0
[461.046067] Dynamic subtest rcs0: SKIP (4.237s)
[461.057133] Starting dynamic subtest: vcs0
[471.233863] Dynamic subtest vcs0: SUCCESS (10.189s)
[471.247733] Starting dynamic subtest: vcs1
[481.625493] Dynamic subtest vcs1: SUCCESS (10.390s)
[481.641083] Starting dynamic subtest: vecs0
[492.500809] Dynamic subtest vecs0: SUCCESS (10.873s)
[492.513856] Starting dynamic subtest: ccs0
[504.786873] Dynamic subtest ccs0: SUCCESS (12.286s)
[504.789251] Subtest deep: SUCCESS (47.980s)
[506.402200] [103/130] (542s left) gem_exec_params (no-bsd)
[506.469367] Starting subtest: no-bsd
[506.474710] Subtest no-bsd: SUCCESS (0.005s)
[508.043054] [104/130] (540s left) gem_mmap_offset (pf-nonblock)
[508.101317] Starting subtest: pf-nonblock
[508.114651] Subtest pf-nonblock: SUCCESS (0.015s)
[508.236913] [105/130] (540s left) gem_exec_fence (submit)
[508.415551] Starting subtest: submit
[508.420142] Subtest submit: SKIP (0.000s)
[510.030938] [106/130] (538s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[510.134380] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[510.136660] Starting dynamic subtest: pipe-A-default-mode
[512.826416] Dynamic subtest pipe-A-default-mode: SUCCESS (2.688s)
[512.828740] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.690s)
[513.684960] [107/130] (535s left) perf (non-zero-reason)
[513.766744] Starting subtest: non-zero-reason
[513.769285] Starting dynamic subtest: 0-rcs0
[519.210265] Dynamic subtest 0-rcs0: SUCCESS (5.441s)
[519.219837] Starting dynamic subtest: 1-vcs0
[523.170040] Dynamic subtest 1-vcs0: SUCCESS (3.959s)
[523.173080] Subtest non-zero-reason: SUCCESS (9.402s)
[523.212328] [108/130] (525s left) kms_hdmi_inject (inject-4k)
[523.551597] Starting subtest: inject-4k
[523.861362] Subtest inject-4k: SUCCESS (0.311s)
[524.447862] [109/130] (524s left) gem_lmem_swapping (parallel-random-verify-ccs)
[524.494363] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[524.532397] [110/130] (524s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[524.644545] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[529.006047] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (4.365s)
[529.154966] [111/130] (519s left) gem_softpin (evict-active)
[529.222909] Starting subtest: evict-active
[529.228854] Subtest evict-active: SUCCESS (0.006s)
[529.364768] [112/130] (519s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[529.462827] Starting subtest: psr-2p-primscrn-cur-indfb-move
[529.466711] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[529.564320] [113/130] (519s left) syncobj_eventfd (binary-wait-signaled)
[529.618328] Starting subtest: binary-wait-signaled
[529.620615] Subtest binary-wait-signaled: SUCCESS (0.000s)
[529.727064] [114/130] (519s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[529.790337] Starting subtest: multi-wait-all-for-submit-available-signaled
[529.792489] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[529.906896] [115/130] (519s left) gem_ctx_exec (basic)
[529.962254] Starting subtest: basic
[529.968815] Subtest basic: SUCCESS (0.007s)
[530.108917] [116/130] (519s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[530.209567] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[530.211549] Starting dynamic subtest: pipe-A-default-mode
[530.498554] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[530.502954] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.286s)
[531.302992] [117/130] (517s left) gem_softpin (noreloc)
[531.366501] Starting subtest: noreloc
[536.762575] Subtest noreloc: SUCCESS (5.395s)
[536.894133] [118/130] (512s left) gem_unref_active_buffers
[537.136330] [119/130] (512s left) prime_self_import (basic-with_one_bo)
[537.171954] Starting subtest: basic-with_one_bo
[537.194446] Subtest basic-with_one_bo: SUCCESS (0.024s)
[537.298479] [120/130] (511s left) sysfs_preempt_timeout (timeout)
[537.365230] Starting subtest: timeout
[537.367748] Starting dynamic subtest: vecs0
[546.941117] Dynamic subtest vecs0: SUCCESS (9.571s)
[546.952440] Starting dynamic subtest: ccs0
[548.293198] Dynamic subtest ccs0: SUCCESS (1.351s)
[548.303765] Starting dynamic subtest: vcs0
[557.950093] Dynamic subtest vcs0: SUCCESS (9.653s)
[557.962213] Starting dynamic subtest: bcs0
[559.214493] Dynamic subtest bcs0: SUCCESS (1.264s)
[559.225138] Starting dynamic subtest: rcs0
[560.546037] Dynamic subtest rcs0: SUCCESS (1.329s)
[560.556457] Starting dynamic subtest: vcs1
[569.924542] Dynamic subtest vcs1: SUCCESS (9.375s)
[569.927091] Subtest timeout: SUCCESS (32.554s)
[570.001548] [121/130] (479s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[570.112431] Starting subtest: psr-rgb101010-draw-mmap-cpu
[575.009841] Subtest psr-rgb101010-draw-mmap-cpu: SUCCESS (4.898s)
[575.120034] [122/130] (474s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[575.247250] Starting subtest: flip-vs-cursor-atomic
[582.153579] Subtest flip-vs-cursor-atomic: SUCCESS (6.907s)
[583.000633] [123/130] (466s left) kms_psr (fbc-psr-sprite-render)
[583.099160] Starting subtest: fbc-psr-sprite-render
[583.101391] Starting dynamic subtest: eDP-1
[583.105466] Dynamic subtest eDP-1: SKIP (0.000s)
[583.109635] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[583.168049] [124/130] (466s left) kms_getfb (getfb-handle-zero)
[583.225060] Starting subtest: getfb-handle-zero
[583.226858] Subtest getfb-handle-zero: SUCCESS (0.000s)
[583.305185] [125/130] (466s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render)
[583.408454] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render
[583.412612] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[583.493048] [126/130] (465s left) debugfs_test (read_all_entries_display_on)
[583.606126] Starting subtest: read_all_entries_display_on
[584.009874] Subtest read_all_entries_display_on: SUCCESS (0.404s)
[584.152130] [127/130] (465s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[584.262038] Starting subtest: 4-tiled-16bpp-rotate-180
[585.617695] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.355s)
[585.779878] [128/130] (463s left) kms_color (ctm-0-50)
[585.881678] Starting subtest: ctm-0-50
[585.883667] Starting dynamic subtest: pipe-A-eDP-1
[589.344431] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.458s)
[589.355194] Starting dynamic subtest: pipe-B-eDP-1
[593.537884] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.195s)
[593.547171] Starting dynamic subtest: pipe-C-eDP-1
[597.488114] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.950s)
[597.498214] Starting dynamic subtest: pipe-D-eDP-1
[601.394264] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.904s)
[601.396966] Subtest ctm-0-50: SUCCESS (15.510s)
[602.196657] [129/130] (447s left) syncobj_basic (bad-pad-handle-to-fd)
[602.254433] Starting subtest: bad-pad-handle-to-fd
[602.256378] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[602.344189] [130/130] (447s left) kms_plane (plane-position-hole-dpms)
[602.439141] Starting subtest: plane-position-hole-dpms
[602.941469] Starting dynamic subtest: pipe-A-plane-1
[605.315817] Dynamic subtest pipe-A-plane-1: SUCCESS (2.374s)
[605.325044] Starting dynamic subtest: pipe-A-plane-2
[607.648988] Dynamic subtest pipe-A-plane-2: SUCCESS (2.331s)
[607.658827] Starting dynamic subtest: pipe-A-plane-3
[610.017202] Dynamic subtest pipe-A-plane-3: SUCCESS (2.368s)
[610.027388] Starting dynamic subtest: pipe-A-plane-4
[612.305082] Dynamic subtest pipe-A-plane-4: SUCCESS (2.286s)
[612.313778] Starting dynamic subtest: pipe-A-plane-5
[612.345488] Dynamic subtest pipe-A-plane-5: SUCCESS (0.041s)
[613.447479] Starting dynamic subtest: pipe-B-plane-1
[615.837652] Dynamic subtest pipe-B-plane-1: SUCCESS (2.398s)
[615.847157] Starting dynamic subtest: pipe-B-plane-2
[618.215511] Dynamic subtest pipe-B-plane-2: SUCCESS (2.377s)
[618.225601] Starting dynamic subtest: pipe-B-plane-3
[620.517464] Dynamic subtest pipe-B-plane-3: SUCCESS (2.300s)
[620.535025] Starting dynamic subtest: pipe-B-plane-4
[622.816330] Dynamic subtest pipe-B-plane-4: SUCCESS (2.299s)
[622.824986] Starting dynamic subtest: pipe-B-plane-5
[622.855533] Dynamic subtest pipe-B-plane-5: SUCCESS (0.037s)
[622.857869] Subtest plane-position-hole-dpms: SUCCESS (20.413s)
[623.593352] Closing watchdogs
Done.