[65.327246] Initializing watchdogs [65.327309] /dev/watchdog0 [65.345267] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770] [65.354682] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0 [65.358606] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [65.360977] [001/130] (960s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled) [65.519064] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled [65.520060] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s) [65.703088] [002/130] (960s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite) [67.719118] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite [67.720929] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [68.032196] [003/130] (957s left) kms_cdclk (plane-scaling) [70.048161] Starting subtest: plane-scaling [70.049157] Starting dynamic subtest: pipe-A-HDMI-A-3 [70.091238] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.042s) [70.097940] Starting dynamic subtest: pipe-B-HDMI-A-3 [70.100103] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.002s) [70.106603] Starting dynamic subtest: pipe-C-HDMI-A-3 [70.108403] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.002s) [70.115012] Starting dynamic subtest: pipe-D-HDMI-A-3 [70.116954] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.002s) [70.118833] Subtest plane-scaling: SKIP (0.050s) [70.233845] [004/130] (955s left) kms_cursor_crc (cursor-onscreen-64x21) [72.384888] Starting subtest: cursor-onscreen-64x21 [72.388697] Starting dynamic subtest: pipe-A-HDMI-A-3 [75.060690] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (2.673s) [75.096104] Starting dynamic subtest: pipe-D-HDMI-A-3 [77.548447] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (2.460s) [77.549447] Subtest cursor-onscreen-64x21: SUCCESS (5.166s) [77.890042] [005/130] (948s left) kms_lease (possible-crtcs-filtering) [79.904437] Starting subtest: possible-crtcs-filtering [79.910156] Subtest possible-crtcs-filtering: SUCCESS (0.007s) [80.100480] [006/130] (946s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf) [82.133857] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s) [82.170303] [007/130] (944s left) kms_chamelium_color (degamma) [84.189365] Subtest degamma: SKIP (0.000s) [84.237567] [008/130] (942s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs) [86.239159] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs [86.240984] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s) [86.409460] [009/130] (939s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs) [86.519259] Starting subtest: y-tiled-ccs-to-y-tiled-ccs [86.521401] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s) [86.782285] [010/130] (939s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area) [88.795186] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s) [88.837948] [011/130] (937s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move) [90.860056] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move [90.861944] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SKIP (0.000s) [90.989802] [012/130] (935s left) kms_big_fb (x-tiled-64bpp-rotate-90) [93.064997] Starting subtest: x-tiled-64bpp-rotate-90 [93.121570] Subtest x-tiled-64bpp-rotate-90: SKIP (0.056s) [93.321363] [013/130] (933s left) gen7_exec_parse (cmd-crossing-page) [93.402152] Subtest cmd-crossing-page: SKIP (0.000s) [93.454801] [014/130] (933s left) debugfs_test (read_all_entries_display_off) [95.475118] Starting subtest: read_all_entries_display_off [96.334146] Subtest read_all_entries_display_off: SUCCESS (0.854s) [96.447589] [015/130] (930s left) kms_psr (fbc-pr-primary-mmap-cpu) [98.463088] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s) [98.509302] [016/130] (928s left) syncobj_wait (invalid-signal-one-illegal-handle) [98.581524] Starting subtest: invalid-signal-one-illegal-handle [98.582450] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s) [98.726289] [017/130] (928s left) gem_softpin (evict-single-offset) [98.805381] Starting subtest: evict-single-offset [131.291842] Subtest evict-single-offset: SUCCESS (32.485s) [131.391259] [018/130] (895s left) fbdev (eof) [131.476401] Starting subtest: eof [131.477354] Subtest eof: SUCCESS (0.000s) [131.547814] [019/130] (895s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0) [133.565498] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0 [133.567450] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s) [133.752060] [020/130] (893s left) kms_atomic_transition (plane-all-transition) [135.803191] Starting subtest: plane-all-transition [135.804325] Starting dynamic subtest: pipe-A-HDMI-A-3 [139.527552] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (3.722s) [139.576672] Starting dynamic subtest: pipe-B-HDMI-A-3 [143.299266] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (3.731s) [143.316844] Subtest plane-all-transition: SUCCESS (7.513s) [143.497377] [021/130] (883s left) kms_hdr (invalid-metadata-sizes) [145.508396] Starting subtest: invalid-metadata-sizes [145.511147] Subtest invalid-metadata-sizes: SKIP (0.000s) [145.617324] [022/130] (881s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu) [147.688156] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu [147.689900] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [147.840169] [023/130] (879s left) gem_madvise (dontneed-after-mmap) [147.930421] Starting subtest: dontneed-after-mmap [147.964037] Subtest dontneed-after-mmap: SUCCESS (0.033s) [148.102568] [024/130] (879s left) gem_exec_flush (basic-wb-ro-default) [148.180465] Subtest basic-wb-ro-default: SKIP (0.000s) [148.242371] [025/130] (879s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [150.260817] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [150.262572] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [150.405737] [026/130] (877s left) kms_flip (nonexisting-fb) [152.469114] Starting subtest: nonexisting-fb [152.473655] Starting dynamic subtest: A-HDMI-A3 [152.724157] Dynamic subtest A-HDMI-A3: SUCCESS (0.250s) [152.736588] Starting dynamic subtest: B-HDMI-A3 [152.902373] Dynamic subtest B-HDMI-A3: SUCCESS (0.178s) [152.910142] Starting dynamic subtest: C-HDMI-A3 [153.065540] Dynamic subtest C-HDMI-A3: SUCCESS (0.163s) [153.073621] Starting dynamic subtest: D-HDMI-A3 [153.227845] Dynamic subtest D-HDMI-A3: SUCCESS (0.162s) [153.228805] Subtest nonexisting-fb: SUCCESS (0.760s) [153.493397] [027/130] (874s left) kms_flip (2x-dpms-vs-vblank-race-interruptible) [155.516197] Starting subtest: 2x-dpms-vs-vblank-race-interruptible [155.529445] Subtest 2x-dpms-vs-vblank-race-interruptible: SKIP (0.012s) [155.650177] [028/130] (871s left) kms_cursor_legacy (torture-bo) [157.715227] Starting subtest: torture-bo [157.716165] Starting dynamic subtest: pipe-A [163.269780] Dynamic subtest pipe-A: SUCCESS (5.553s) [163.277795] Starting dynamic subtest: pipe-B [168.724704] Dynamic subtest pipe-B: SUCCESS (5.453s) [168.732215] Starting dynamic subtest: pipe-C [174.266520] Dynamic subtest pipe-C: SUCCESS (5.542s) [174.273928] Starting dynamic subtest: pipe-D [179.714043] Dynamic subtest pipe-D: SUCCESS (5.447s) [179.723716] Starting dynamic subtest: all-pipes [185.247460] Dynamic subtest all-pipes: SUCCESS (5.533s) [185.248379] Subtest torture-bo: SUCCESS (27.529s) [185.333450] [029/130] (842s left) gem_render_copy (x-tiled-to-vebox-yf-tiled) [185.419221] Starting subtest: x-tiled-to-vebox-yf-tiled [185.421308] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s) [185.662058] [030/130] (842s left) kms_big_fb (linear-32bpp-rotate-180) [187.690138] Starting subtest: linear-32bpp-rotate-180 [189.422301] Subtest linear-32bpp-rotate-180: SUCCESS (1.732s) [189.580734] [031/130] (838s left) kms_writeback (writeback-fb-id-xrgb2101010) [191.590626] Subtest writeback-fb-id-XRGB2101010: SKIP (0.000s) [191.639903] [032/130] (836s left) kms_psr (psr-sprite-render) [193.663480] Subtest psr-sprite-render: SKIP (0.000s) [193.707532] [033/130] (834s left) drm_fdinfo (memory-info-idle) [193.773735] Starting subtest: memory-info-idle [193.774665] Starting dynamic subtest: lmem0 [193.848364] Dynamic subtest lmem0: SUCCESS (0.074s) [193.857557] Starting dynamic subtest: smem0 [193.901082] Dynamic subtest smem0: SUCCESS (0.052s) [193.902326] Subtest memory-info-idle: SUCCESS (0.127s) [194.046571] [034/130] (833s left) kms_pm_backlight (brightness-with-dpms) [196.066663] Subtest brightness-with-dpms: SKIP (0.000s) [196.125013] [035/130] (831s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs) [198.162209] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs [198.164138] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s) [198.445652] [036/130] (829s left) gem_mmap (bad-size) [198.520175] Subtest bad-size: SKIP (0.000s) [198.592534] [037/130] (829s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render) [200.606971] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render [200.608694] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [200.751047] [038/130] (827s left) kms_draw_crc (draw-method-mmap-cpu) [202.819801] Starting subtest: draw-method-mmap-cpu [202.820698] Starting dynamic subtest: xrgb8888-untiled [203.149915] Dynamic subtest xrgb8888-untiled: SUCCESS (0.330s) [203.182344] Starting dynamic subtest: rgb565-untiled [203.482899] Dynamic subtest rgb565-untiled: SUCCESS (0.332s) [203.514408] Starting dynamic subtest: xrgb2101010-untiled [203.782970] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.299s) [203.794820] Starting dynamic subtest: xbgr16161616f-untiled [204.099596] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.316s) [204.131028] Starting dynamic subtest: xrgb8888-xtiled [204.282554] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.183s) [204.292146] Starting dynamic subtest: rgb565-xtiled [204.432230] Dynamic subtest rgb565-xtiled: SUCCESS (0.149s) [204.445645] Starting dynamic subtest: xrgb2101010-xtiled [204.598428] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.166s) [204.607907] Starting dynamic subtest: xbgr16161616f-xtiled [204.748376] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.150s) [204.756710] Starting dynamic subtest: xrgb8888-4tiled [204.916735] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.167s) [204.948029] Starting dynamic subtest: rgb565-4tiled [205.099770] Dynamic subtest rgb565-4tiled: SUCCESS (0.182s) [205.111862] Starting dynamic subtest: xrgb2101010-4tiled [205.249716] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.149s) [205.281085] Starting dynamic subtest: xbgr16161616f-4tiled [205.433094] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.182s) [205.434958] Subtest draw-method-mmap-cpu: SUCCESS (2.614s) [205.612986] [039/130] (822s left) gem_exec_whisper (basic-fds-priority-all) [205.695147] Starting subtest: basic-fds-priority-all [228.244604] Subtest basic-fds-priority-all: SUCCESS (22.547s) [228.491510] [040/130] (799s left) perf (rc6-disable) [228.848409] Starting subtest: rc6-disable [229.029705] Subtest rc6-disable: SUCCESS (0.181s) [229.126781] [041/130] (799s left) gem_pxp (create-regular-context-1) [231.732924] Starting subtest: create-regular-context-1 [231.735149] Subtest create-regular-context-1: SKIP (0.000s) [231.864760] [042/130] (796s left) kms_flip (2x-absolute-wf_vblank) [233.875111] Starting subtest: 2x-absolute-wf_vblank [233.887587] Subtest 2x-absolute-wf_vblank: SKIP (0.012s) [233.989823] [043/130] (794s left) i915_pm_rpm (gem-idle) [239.119083] Starting subtest: gem-idle [245.434647] Subtest gem-idle: SUCCESS (6.317s) [246.515662] [044/130] (782s left) api_intel_allocator (reopen-fork) [246.601273] Starting subtest: reopen-fork [250.204177] Subtest reopen-fork: SUCCESS (3.602s) [250.331981] [045/130] (778s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-25) [252.344472] Starting subtest: planes-unity-scaling-downscale-factor-0-25 [252.345431] Starting dynamic subtest: pipe-A [252.809141] Dynamic subtest pipe-A: SKIP (0.463s) [252.819281] Starting dynamic subtest: pipe-B [253.483160] Dynamic subtest pipe-B: SKIP (0.673s) [253.491666] Starting dynamic subtest: pipe-C [254.158702] Dynamic subtest pipe-C: SKIP (0.675s) [254.167266] Starting dynamic subtest: pipe-D [254.834185] Dynamic subtest pipe-D: SKIP (0.674s) [254.835950] Subtest planes-unity-scaling-downscale-factor-0-25: SKIP (2.488s) [254.962597] [046/130] (773s left) gem_mmap_gtt (basic-small-copy-xy) [255.042200] Subtest basic-small-copy-XY: SKIP (0.000s) [255.109533] [047/130] (773s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs) [257.118186] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs [257.119176] Starting dynamic subtest: pipe-A-HDMI-A-3 [257.140931] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.002s) [257.149516] Starting dynamic subtest: pipe-B-HDMI-A-3 [257.171389] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.002s) [257.180086] Starting dynamic subtest: pipe-C-HDMI-A-3 [257.204336] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.002s) [257.212577] Starting dynamic subtest: pipe-D-HDMI-A-3 [257.235524] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.002s) [257.237296] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SKIP (0.013s) [257.365662] [048/130] (771s left) gem_fenced_exec_thrash (no-spare-fences) [257.442774] Subtest no-spare-fences: SKIP (0.000s) [257.509630] [049/130] (771s left) gem_mmap_gtt (basic-write-read-distinct) [257.580436] Subtest basic-write-read-distinct: SKIP (0.000s) [257.676962] [050/130] (771s left) kms_invalid_mode (clock-too-high) [259.704166] Starting subtest: clock-too-high [259.705182] Starting dynamic subtest: pipe-A-HDMI-A-3 [259.741430] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.038s) [259.751854] Starting dynamic subtest: pipe-B-HDMI-A-3 [259.754228] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.001s) [259.762574] Starting dynamic subtest: pipe-C-HDMI-A-3 [259.764430] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.001s) [259.772840] Starting dynamic subtest: pipe-D-HDMI-A-3 [259.774645] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.001s) [259.775535] Subtest clock-too-high: SUCCESS (0.046s) [259.901491] [051/130] (769s left) syncobj_timeline (wait-all-for-submit-delayed-submit) [259.997580] Starting subtest: wait-all-for-submit-delayed-submit [260.100896] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s) [260.204638] [052/130] (768s left) kms_psr (pr-primary-page-flip) [262.220487] Subtest pr-primary-page-flip: SKIP (0.000s) [262.273915] [053/130] (766s left) gem_fence_thrash (bo-copy) [262.324519] Starting subtest: bo-copy [262.353403] Subtest bo-copy: SKIP (0.028s) [262.444700] [054/130] (766s left) kms_cursor_crc (cursor-rapid-movement-256x256) [264.627338] Starting subtest: cursor-rapid-movement-256x256 [264.631315] Starting dynamic subtest: pipe-A-HDMI-A-3 [265.043684] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.413s) [265.074279] Starting dynamic subtest: pipe-D-HDMI-A-3 [265.362350] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.295s) [265.363409] Subtest cursor-rapid-movement-256x256: SUCCESS (0.736s) [265.666124] [055/130] (763s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180) [267.689946] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180 [267.692151] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s) [267.839344] [056/130] (761s left) perf_pmu (render-node-busy-idle) [267.965728] Starting subtest: render-node-busy-idle [267.966605] Starting dynamic subtest: rcs0 [268.685871] Dynamic subtest rcs0: SUCCESS (0.719s) [268.700603] Starting dynamic subtest: bcs0 [269.357759] Dynamic subtest bcs0: SUCCESS (0.668s) [269.372792] Starting dynamic subtest: vcs0 [270.031870] Dynamic subtest vcs0: SUCCESS (0.670s) [270.046808] Starting dynamic subtest: vcs1 [270.701909] Dynamic subtest vcs1: SUCCESS (0.666s) [270.716735] Starting dynamic subtest: vecs0 [271.362768] Dynamic subtest vecs0: SUCCESS (0.657s) [271.377842] Starting dynamic subtest: vecs1 [272.030120] Dynamic subtest vecs1: SUCCESS (0.663s) [272.074869] Starting dynamic subtest: ccs0 [272.709903] Dynamic subtest ccs0: SUCCESS (0.675s) [272.712222] Subtest render-node-busy-idle: SUCCESS (4.746s) [272.814009] [057/130] (756s left) gem_exec_capture (userptr) [272.891432] Starting subtest: userptr [273.825429] Subtest userptr: SUCCESS (0.934s) [273.977813] [058/130] (755s left) kms_flip (2x-wf_vblank-ts-check-interruptible) [275.985974] Starting subtest: 2x-wf_vblank-ts-check-interruptible [275.999276] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.012s) [276.125905] [059/130] (753s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c) [276.240427] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s) [276.348844] [060/130] (753s left) gem_partial_pwrite_pread (writes-after-reads-snoop) [276.425811] Subtest writes-after-reads-snoop: SKIP (0.000s) [276.510608] [061/130] (753s left) gem_exec_balancer (parallel) [276.821375] Starting subtest: parallel [278.144966] Subtest parallel: SUCCESS (1.324s) [278.248140] [062/130] (751s left) kms_big_fb (x-tiled-16bpp-rotate-180) [280.267509] Starting subtest: x-tiled-16bpp-rotate-180 [281.865411] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.598s) [282.100685] [063/130] (747s left) i915_hangman (gt-engine-error) [282.186958] Starting subtest: gt-engine-error [282.187944] Starting dynamic subtest: rcs0 [282.986779] Dynamic subtest rcs0: SUCCESS (0.799s) [283.003062] Starting dynamic subtest: bcs0 [283.721458] Dynamic subtest bcs0: SUCCESS (0.733s) [283.735564] Starting dynamic subtest: vcs0 [284.421389] Dynamic subtest vcs0: SUCCESS (0.699s) [284.431521] Starting dynamic subtest: vcs1 [285.143691] Dynamic subtest vcs1: SUCCESS (0.721s) [285.157776] Starting dynamic subtest: vecs0 [285.894409] Dynamic subtest vecs0: SUCCESS (0.749s) [285.912054] Starting dynamic subtest: vecs1 [286.632733] Dynamic subtest vecs1: SUCCESS (0.736s) [286.647804] Starting dynamic subtest: ccs0 [287.476877] Dynamic subtest ccs0: SUCCESS (0.842s) [287.478225] Subtest gt-engine-error: SUCCESS (5.291s) [287.663768] [064/130] (742s left) kms_pipe_crc_basic (bad-source) [289.674232] Starting subtest: bad-source [289.675178] Subtest bad-source: SUCCESS (0.000s) [289.770299] [065/130] (740s left) gem_exec_reloc (basic-wc-cpu) [289.893504] Subtest basic-wc-cpu: SKIP (0.000s) [289.976363] [066/130] (739s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc) [291.986388] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc [291.988161] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SKIP (0.000s) [292.137876] [067/130] (737s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt) [294.212119] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt [294.213926] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s) [294.390697] [068/130] (735s left) gem_exec_schedule (semaphore-user) [294.536660] Starting subtest: semaphore-user [294.619024] Subtest semaphore-user: SUCCESS (0.082s) [295.011772] [069/130] (735s left) syncobj_timeline (single-wait-all-for-submit-signaled) [295.082934] Starting subtest: single-wait-all-for-submit-signaled [295.083894] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s) [295.246854] [070/130] (734s left) gem_exec_schedule (promotion) [295.333837] Starting subtest: promotion [295.334729] Starting dynamic subtest: rcs0 [295.600047] Dynamic subtest rcs0: SUCCESS (0.266s) [295.610311] Starting dynamic subtest: bcs0 [295.795245] Dynamic subtest bcs0: SUCCESS (0.194s) [295.805302] Starting dynamic subtest: vcs0 [295.995980] Dynamic subtest vcs0: SUCCESS (0.200s) [296.006087] Starting dynamic subtest: vcs1 [296.200218] Dynamic subtest vcs1: SUCCESS (0.203s) [296.210044] Starting dynamic subtest: vecs0 [296.402799] Dynamic subtest vecs0: SUCCESS (0.202s) [296.412788] Starting dynamic subtest: vecs1 [296.605212] Dynamic subtest vecs1: SUCCESS (0.201s) [296.615248] Starting dynamic subtest: ccs0 [296.805862] Dynamic subtest ccs0: SUCCESS (0.200s) [296.806708] Subtest promotion: SUCCESS (1.473s) [297.222113] [071/130] (733s left) kms_async_flips (test-cursor-atomic) [299.245053] Starting subtest: test-cursor-atomic [299.475076] Starting dynamic subtest: pipe-A-HDMI-A-3 [299.708387] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (0.234s) [299.904523] Starting dynamic subtest: pipe-B-HDMI-A-3 [299.969692] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (0.074s) [300.148158] Starting dynamic subtest: pipe-C-HDMI-A-3 [300.205318] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (0.066s) [300.379645] Starting dynamic subtest: pipe-D-HDMI-A-3 [300.444396] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (0.074s) [300.445292] Subtest test-cursor-atomic: SUCCESS (1.200s) [300.818340] [072/130] (729s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-render) [302.835203] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-render [302.836965] Subtest fbcpsr-1p-offscren-pri-indfb-draw-render: SKIP (0.000s) [302.986653] [073/130] (727s left) kms_sequence (queue-idle) [305.048287] Starting subtest: queue-idle [305.049240] Starting dynamic subtest: pipe-A-HDMI-A-3 [307.405985] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (2.357s) [307.417857] Starting dynamic subtest: pipe-B-HDMI-A-3 [309.590566] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (2.183s) [309.602224] Starting dynamic subtest: pipe-C-HDMI-A-3 [311.780519] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (2.188s) [311.792544] Starting dynamic subtest: pipe-D-HDMI-A-3 [313.991778] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (2.209s) [313.992769] Subtest queue-idle: SUCCESS (8.945s) [314.143108] [074/130] (716s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [316.154767] Starting subtest: addfb25-yf-tiled-legacy [316.155648] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [316.298096] [075/130] (714s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy) [318.371177] Starting subtest: basic-busy-flip-before-cursor-legacy [318.373147] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s) [318.476630] [076/130] (712s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf) [320.537055] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s) [320.585702] [077/130] (710s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [322.613208] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip [322.615067] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s) [322.851800] [078/130] (708s left) gen9_exec_parse (unaligned-access) [322.955177] Subtest unaligned-access: SKIP (0.000s) [323.052312] [079/130] (707s left) kms_ccs (crc-primary-basic-y-tiled-ccs) [325.068191] Starting subtest: crc-primary-basic-y-tiled-ccs [325.069127] Starting dynamic subtest: pipe-A-HDMI-A-3 [325.077830] Dynamic subtest pipe-A-HDMI-A-3: SKIP (0.001s) [325.087867] Starting dynamic subtest: pipe-B-HDMI-A-3 [325.096620] Dynamic subtest pipe-B-HDMI-A-3: SKIP (0.001s) [325.106323] Starting dynamic subtest: pipe-C-HDMI-A-3 [325.114905] Dynamic subtest pipe-C-HDMI-A-3: SKIP (0.001s) [325.124501] Starting dynamic subtest: pipe-D-HDMI-A-3 [325.133528] Dynamic subtest pipe-D-HDMI-A-3: SKIP (0.001s) [325.135242] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.009s) [325.313775] [080/130] (705s left) gem_exec_fence (syncobj-timeline-invalid-flags) [325.990879] Starting subtest: syncobj-timeline-invalid-flags [325.992595] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.001s) [326.282151] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true [326.284546] [081/130] (704s left) syncobj_timeline (etime-single-wait-all-submitted) [326.362377] Starting subtest: etime-single-wait-all-submitted [326.472193] Subtest etime-single-wait-all-submitted: SUCCESS (0.109s) [326.623138] [082/130] (704s left) gem_mmap (basic-small-bo) [326.702593] Subtest basic-small-bo: SKIP (0.000s) [326.816931] [083/130] (704s left) gem_ctx_persistence (heartbeat-stop) [326.902841] Starting subtest: heartbeat-stop [326.905071] Subtest heartbeat-stop: SKIP (0.000s) [327.031982] [084/130] (704s left) gem_ctx_persistence (userptr) [327.116781] Starting subtest: userptr [327.341729] Subtest userptr: SUCCESS (0.224s) [327.464675] [085/130] (703s left) syncobj_timeline (device-submit-unordered) [327.543998] Starting subtest: device-submit-unordered [327.544880] Subtest device-submit-unordered: SUCCESS (0.000s) [327.717420] [086/130] (703s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [329.743357] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt [329.745168] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [329.918308] [087/130] (701s left) kms_fbc_dirty_rect (fbc-dirty-rectangle-out-visible-area) [330.346357] Subtest fbc-dirty-rectangle-out-visible-area: SKIP (0.000s) [330.427052] [FACT kms_fbc_dirty_rect (fbc-dirty-rectangle-out-visible-area)] new: kernel.kmod_is_loaded.xe: true [330.429442] [088/130] (701s left) kms_vblank (query-forked-hang) [332.445386] Starting subtest: query-forked-hang [332.446377] Starting dynamic subtest: pipe-A-HDMI-A-3 [358.585727] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (26.140s) [358.599257] Starting dynamic subtest: pipe-D-HDMI-A-3 [383.518133] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (24.927s) [383.519074] Subtest query-forked-hang: SUCCESS (51.073s) [383.701117] [089/130] (647s left) kms_pm_rpm (modeset-lpsp-stress-no-wait) [388.087088] Starting subtest: modeset-lpsp-stress-no-wait [389.168430] Subtest modeset-lpsp-stress-no-wait: SKIP (1.081s) [389.312400] [090/130] (642s left) gem_ctx_exec (basic-nohangcheck) [389.427614] Starting subtest: basic-nohangcheck [389.712535] Subtest basic-nohangcheck: SUCCESS (0.285s) [389.908188] [091/130] (641s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt) [391.933890] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt [391.935519] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [392.168094] [092/130] (639s left) gem_tiled_fence_blits (basic) [392.252170] Subtest basic: SKIP (0.000s) [392.336945] [093/130] (639s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode) [394.401945] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s) [394.496820] [094/130] (637s left) kms_psr (fbc-psr2-primary-mmap-gtt) [396.530509] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.000s) [396.592604] [095/130] (635s left) gem_busy (semaphore) [396.885430] Subtest semaphore: SKIP (0.000s) [397.185501] [096/130] (635s left) kms_content_protection (legacy) [399.257083] Starting subtest: legacy [400.537592] Subtest legacy: SKIP (1.272s) [400.787773] [097/130] (631s left) gem_exec_reloc (basic-gtt-wc-noreloc) [400.873897] Subtest basic-gtt-wc-noreloc: SKIP (0.000s) [400.963419] [098/130] (631s left) gem_exec_flush (basic-uc-prw-default) [401.044088] Subtest basic-uc-prw-default: SKIP (0.000s) [401.106009] [099/130] (631s left) gem_ppgtt (flink-and-close-vma-leak) [401.192625] Starting subtest: flink-and-close-vma-leak [401.261106] Subtest flink-and-close-vma-leak: SUCCESS (0.068s) [401.461642] [100/130] (630s left) gem_ctx_param (root-set) [401.539847] Starting subtest: root-set [401.542432] Subtest root-set: SUCCESS (0.001s) [401.649416] [101/130] (630s left) kms_plane_cursor (primary) [403.675256] Starting subtest: primary [403.677162] Starting dynamic subtest: pipe-A-HDMI-A-3-size-64 [407.065588] Dynamic subtest pipe-A-HDMI-A-3-size-64: SUCCESS (3.390s) [407.125257] Starting dynamic subtest: pipe-A-HDMI-A-3-size-128 [409.749095] Dynamic subtest pipe-A-HDMI-A-3-size-128: SUCCESS (2.633s) [409.808854] Starting dynamic subtest: pipe-A-HDMI-A-3-size-256 [413.132050] Dynamic subtest pipe-A-HDMI-A-3-size-256: SUCCESS (3.333s) [413.254542] Starting dynamic subtest: pipe-B-HDMI-A-3-size-64 [416.316308] Dynamic subtest pipe-B-HDMI-A-3-size-64: SUCCESS (3.072s) [416.364405] Starting dynamic subtest: pipe-B-HDMI-A-3-size-128 [419.699692] Dynamic subtest pipe-B-HDMI-A-3-size-128: SUCCESS (3.349s) [419.743652] Starting dynamic subtest: pipe-B-HDMI-A-3-size-256 [423.083343] Dynamic subtest pipe-B-HDMI-A-3-size-256: SUCCESS (3.350s) [423.151872] Starting dynamic subtest: pipe-C-HDMI-A-3-size-64 [426.562725] Dynamic subtest pipe-C-HDMI-A-3-size-64: SUCCESS (3.421s) [426.635658] Starting dynamic subtest: pipe-C-HDMI-A-3-size-128 [430.079231] Dynamic subtest pipe-C-HDMI-A-3-size-128: SUCCESS (3.481s) [430.149060] Starting dynamic subtest: pipe-C-HDMI-A-3-size-256 [433.512648] Dynamic subtest pipe-C-HDMI-A-3-size-256: SUCCESS (3.398s) [433.617389] Starting dynamic subtest: pipe-D-HDMI-A-3-size-64 [437.045869] Dynamic subtest pipe-D-HDMI-A-3-size-64: SUCCESS (3.438s) [437.118547] Starting dynamic subtest: pipe-D-HDMI-A-3-size-128 [440.545519] Dynamic subtest pipe-D-HDMI-A-3-size-128: SUCCESS (3.464s) [440.618147] Starting dynamic subtest: pipe-D-HDMI-A-3-size-256 [444.061493] Dynamic subtest pipe-D-HDMI-A-3-size-256: SUCCESS (3.481s) [444.118556] Subtest primary: SUCCESS (40.444s) [444.311532] [102/130] (588s left) gem_exec_schedule (deep) [444.549150] Starting subtest: deep [444.550786] Starting dynamic subtest: rcs0 [457.398368] Dynamic subtest rcs0: SUCCESS (12.848s) [457.409391] Starting dynamic subtest: bcs0 [470.626110] Dynamic subtest bcs0: SUCCESS (13.226s) [470.637293] Starting dynamic subtest: vcs0 [483.859255] Dynamic subtest vcs0: SUCCESS (13.231s) [483.870802] Starting dynamic subtest: vcs1 [496.831212] Dynamic subtest vcs1: SUCCESS (12.971s) [496.842128] Starting dynamic subtest: vecs0 [509.766706] Dynamic subtest vecs0: SUCCESS (12.934s) [509.777379] Starting dynamic subtest: vecs1 [522.877835] Dynamic subtest vecs1: SUCCESS (13.110s) [522.889094] Starting dynamic subtest: ccs0 [535.681975] Dynamic subtest ccs0: SUCCESS (12.803s) [535.683061] Subtest deep: SUCCESS (91.132s) [536.550785] [103/130] (496s left) gem_exec_params (no-bsd) [536.633443] Starting subtest: no-bsd [536.635667] Subtest no-bsd: SUCCESS (0.001s) [536.831339] [104/130] (495s left) gem_mmap_offset (pf-nonblock) [536.910394] Starting subtest: pf-nonblock [536.936896] Subtest pf-nonblock: SUCCESS (0.027s) [537.086364] [105/130] (495s left) gem_exec_fence (submit) [537.292793] Starting subtest: submit [537.295127] Subtest submit: SKIP (0.000s) [538.081154] [106/130] (494s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling) [540.105177] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling [540.106136] Starting dynamic subtest: pipe-A-valid-mode [540.948440] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.842s) [540.949430] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (0.844s) [541.149244] [107/130] (491s left) perf (non-zero-reason) [541.346583] Starting subtest: non-zero-reason [541.347475] Starting dynamic subtest: 0-rcs0 [544.408749] Dynamic subtest 0-rcs0: FAIL (2.740s) [544.410440] Subtest non-zero-reason: FAIL (2.741s) [544.480570] [108/130] (488s left) kms_hdmi_inject (inject-4k) [544.606109] Starting subtest: inject-4k [544.759606] Subtest inject-4k: SUCCESS (0.154s) [545.906446] [109/130] (487s left) gem_lmem_swapping (parallel-random-verify-ccs) [551.468307] Starting subtest: parallel-random-verify-ccs [551.469261] Starting dynamic subtest: lmem0 [616.535304] Dynamic subtest lmem0: SUCCESS (65.064s) [616.536961] Subtest parallel-random-verify-ccs: SUCCESS (65.067s) [617.781127] [FACT gem_lmem_swapping (parallel-random-verify-ccs)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0 [617.782371] [FACT gem_lmem_swapping (parallel-random-verify-ccs)] deleted: kernel.kmod_is_loaded.i915: true [617.784510] [110/130] (415s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite) [623.765227] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite [626.587393] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (2.817s) [626.735019] [FACT kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0 [626.738147] [FACT kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)] new: kernel.kmod_is_loaded.i915: true [626.740709] [111/130] (406s left) gem_softpin (evict-active) [626.834486] Starting subtest: evict-active [626.854700] Subtest evict-active: SUCCESS (0.020s) [626.991937] [112/130] (406s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move) [630.215871] Starting subtest: psr-2p-primscrn-cur-indfb-move [630.217317] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s) [630.325692] [113/130] (402s left) syncobj_eventfd (binary-wait-signaled) [630.402417] Starting subtest: binary-wait-signaled [630.403139] Subtest binary-wait-signaled: SUCCESS (0.000s) [630.509261] [114/130] (402s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled) [630.588468] Starting subtest: multi-wait-all-for-submit-available-signaled [630.589214] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.000s) [630.691908] [115/130] (402s left) gem_ctx_exec (basic) [630.775210] Starting subtest: basic [630.783923] Subtest basic: SUCCESS (0.009s) [630.909345] [116/130] (402s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling) [633.011212] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling [633.012164] Starting dynamic subtest: pipe-A-valid-mode [633.057231] Dynamic subtest pipe-A-valid-mode: SKIP (0.044s) [633.059077] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.046s) [633.210581] [117/130] (400s left) gem_softpin (noreloc) [633.324233] Starting subtest: noreloc [638.823995] Subtest noreloc: SUCCESS (5.499s) [638.971513] [118/130] (394s left) gem_unref_active_buffers [639.354187] [119/130] (394s left) prime_self_import (basic-with_one_bo) [639.404211] Starting subtest: basic-with_one_bo [639.448774] Subtest basic-with_one_bo: SUCCESS (0.045s) [639.581092] [120/130] (394s left) sysfs_preempt_timeout (timeout) [639.663774] Starting subtest: timeout [639.664712] Starting dynamic subtest: vecs0 [641.399195] Dynamic subtest vecs0: SUCCESS (1.734s) [641.409429] Starting dynamic subtest: ccs0 [643.226304] Dynamic subtest ccs0: SUCCESS (1.826s) [643.236976] Starting dynamic subtest: vcs0 [644.970937] Dynamic subtest vcs0: SUCCESS (1.744s) [644.981233] Starting dynamic subtest: bcs0 [646.722077] Dynamic subtest bcs0: SUCCESS (1.750s) [646.756632] Starting dynamic subtest: vecs1 [648.494168] Dynamic subtest vecs1: SUCCESS (1.769s) [648.504133] Starting dynamic subtest: rcs0 [650.213063] Dynamic subtest rcs0: SUCCESS (1.718s) [650.225171] Starting dynamic subtest: vcs1 [651.942391] Dynamic subtest vcs1: SUCCESS (1.728s) [651.943302] Subtest timeout: SUCCESS (12.279s) [652.066019] [121/130] (381s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu) [654.096121] Starting subtest: psr-rgb101010-draw-mmap-cpu [654.097898] Subtest psr-rgb101010-draw-mmap-cpu: SKIP (0.000s) [654.283328] [122/130] (379s left) kms_cursor_legacy (flip-vs-cursor-atomic) [656.321290] Starting subtest: flip-vs-cursor-atomic [665.486285] Subtest flip-vs-cursor-atomic: SUCCESS (9.165s) [665.652484] [123/130] (368s left) kms_psr (fbc-psr-sprite-render) [667.676411] Subtest fbc-psr-sprite-render: SKIP (0.000s) [667.734338] [124/130] (366s left) kms_getfb (getfb-handle-zero) [667.819409] Starting subtest: getfb-handle-zero [667.820409] Subtest getfb-handle-zero: SUCCESS (0.000s) [667.987069] [125/130] (365s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render) [670.012526] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render [670.014283] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s) [670.176886] [126/130] (363s left) debugfs_test (read_all_entries_display_on) [672.250200] Starting subtest: read_all_entries_display_on [673.129612] Subtest read_all_entries_display_on: SUCCESS (0.880s) [673.345785] [127/130] (360s left) kms_big_fb (4-tiled-16bpp-rotate-180) [675.379289] Starting subtest: 4-tiled-16bpp-rotate-180 [677.044858] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.665s) [677.256185] [128/130] (356s left) kms_color (ctm-0-50) [679.276313] Starting subtest: ctm-0-50 [679.277249] Starting dynamic subtest: pipe-A-HDMI-A-3 [680.873202] Dynamic subtest pipe-A-HDMI-A-3: SUCCESS (1.597s) [680.886660] Starting dynamic subtest: pipe-B-HDMI-A-3 [682.234580] Dynamic subtest pipe-B-HDMI-A-3: SUCCESS (1.360s) [682.244688] Starting dynamic subtest: pipe-C-HDMI-A-3 [683.615681] Dynamic subtest pipe-C-HDMI-A-3: SUCCESS (1.379s) [683.626275] Starting dynamic subtest: pipe-D-HDMI-A-3 [684.959667] Dynamic subtest pipe-D-HDMI-A-3: SUCCESS (1.344s) [684.960565] Subtest ctm-0-50: SUCCESS (5.684s) [685.136093] [129/130] (349s left) syncobj_basic (bad-pad-handle-to-fd) [685.216436] Starting subtest: bad-pad-handle-to-fd [685.217417] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s) [685.377945] [130/130] (348s left) kms_plane (plane-position-hole-dpms) [687.402142] Starting subtest: plane-position-hole-dpms [687.816174] Starting dynamic subtest: pipe-A-plane-1 [688.308759] Dynamic subtest pipe-A-plane-1: SUCCESS (0.493s) [688.319187] Starting dynamic subtest: pipe-A-plane-2 [688.793434] Dynamic subtest pipe-A-plane-2: SUCCESS (0.484s) [688.805512] Starting dynamic subtest: pipe-A-plane-3 [689.275547] Dynamic subtest pipe-A-plane-3: SUCCESS (0.481s) [689.285767] Starting dynamic subtest: pipe-A-plane-4 [689.743581] Dynamic subtest pipe-A-plane-4: SUCCESS (0.466s) [689.755252] Starting dynamic subtest: pipe-A-plane-5 [689.761137] Dynamic subtest pipe-A-plane-5: SUCCESS (0.016s) [690.006069] Starting dynamic subtest: pipe-B-plane-1 [690.448988] Dynamic subtest pipe-B-plane-1: SUCCESS (0.451s) [690.459326] Starting dynamic subtest: pipe-B-plane-2 [690.910513] Dynamic subtest pipe-B-plane-2: SUCCESS (0.461s) [690.920809] Starting dynamic subtest: pipe-B-plane-3 [691.360599] Dynamic subtest pipe-B-plane-3: SUCCESS (0.449s) [691.371213] Starting dynamic subtest: pipe-B-plane-4 [691.797845] Dynamic subtest pipe-B-plane-4: SUCCESS (0.435s) [691.808797] Starting dynamic subtest: pipe-B-plane-5 [691.815433] Dynamic subtest pipe-B-plane-5: SUCCESS (0.017s) [691.816457] Subtest plane-position-hole-dpms: SUCCESS (4.413s) [691.985932] Closing watchdogs Done.