[62.628477] Initializing watchdogs
[62.628550]   /dev/watchdog0
[62.650898] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[62.662480] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[62.666517] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.668900] [001/133] (960s left) kms_flip (wf_vblank-ts-check)
[62.860119] Starting subtest: wf_vblank-ts-check
[62.864186] Starting dynamic subtest: A-DP3
[82.198583] Dynamic subtest A-DP3: SUCCESS (19.118s)
[82.209365] Starting dynamic subtest: B-DP3
[90.256176] Dynamic subtest B-DP3: SUCCESS (8.045s)
[90.264667] Starting dynamic subtest: C-DP3
[98.298646] Dynamic subtest C-DP3: SUCCESS (8.037s)
[98.307064] Starting dynamic subtest: D-DP3
[106.348740] Dynamic subtest D-DP3: SUCCESS (8.046s)
[106.350508] Subtest wf_vblank-ts-check: SUCCESS (43.254s)
[106.638636] [002/133] (916s left) kms_cursor_crc (cursor-onscreen-256x256)
[107.050207] Starting subtest: cursor-onscreen-256x256
[107.064373] Starting dynamic subtest: pipe-A-DP-3
[109.789364] Dynamic subtest pipe-A-DP-3: SUCCESS (2.724s)
[109.831961] Starting dynamic subtest: pipe-D-DP-3
[112.527285] Dynamic subtest pipe-D-DP-3: SUCCESS (2.702s)
[112.528219] Subtest cursor-onscreen-256x256: SUCCESS (5.475s)
[112.860803] [003/133] (910s left) gem_mmap_gtt (big-copy-odd)
[112.927959] Subtest big-copy-odd: SKIP (0.000s)
[112.985746] [004/133] (910s left) i915_pm_rpm (sysfs-read)
[114.991451] Starting subtest: sysfs-read
[115.423708] Subtest sysfs-read: SUCCESS (0.430s)
[115.855429] [005/133] (907s left) kms_universal_plane (disable-primary-vs-flip)
[116.029912] Starting subtest: disable-primary-vs-flip
[116.030834] Starting dynamic subtest: pipe-A-DP-3
[116.582322] Dynamic subtest pipe-A-DP-3: SUCCESS (0.551s)
[116.591359] Starting dynamic subtest: pipe-B-DP-3
[117.033021] Dynamic subtest pipe-B-DP-3: SUCCESS (0.449s)
[117.041376] Starting dynamic subtest: pipe-C-DP-3
[117.455054] Dynamic subtest pipe-C-DP-3: SUCCESS (0.421s)
[117.463511] Starting dynamic subtest: pipe-D-DP-3
[117.913379] Dynamic subtest pipe-D-DP-3: SUCCESS (0.458s)
[117.914459] Subtest disable-primary-vs-flip: SUCCESS (1.883s)
[118.174674] [006/133] (905s left) perf (gen12-group-exclusive-stream-ctx-handle)
[118.528320] Starting subtest: gen12-group-exclusive-stream-ctx-handle
[118.555610] Subtest gen12-group-exclusive-stream-ctx-handle: SUCCESS (0.026s)
[118.611303] [007/133] (904s left) kms_flip_event_leak (basic)
[118.790991] Starting subtest: basic
[118.791981] Starting dynamic subtest: pipe-A-DP-3
[119.143978] Dynamic subtest pipe-A-DP-3: SUCCESS (0.351s)
[119.156982] Starting dynamic subtest: pipe-B-DP-3
[119.436284] Dynamic subtest pipe-B-DP-3: SUCCESS (0.290s)
[119.450738] Starting dynamic subtest: pipe-C-DP-3
[119.737662] Dynamic subtest pipe-C-DP-3: SUCCESS (0.300s)
[119.757611] Starting dynamic subtest: pipe-D-DP-3
[120.028060] Dynamic subtest pipe-D-DP-3: SUCCESS (0.287s)
[120.029200] Subtest basic: SUCCESS (1.236s)
[120.269131] [008/133] (903s left) gem_exec_fence (syncobj-wait)
[120.886409] Starting subtest: syncobj-wait
[120.995727] Subtest syncobj-wait: SUCCESS (0.109s)
[121.404368] [FACT gem_exec_fence (syncobj-wait)] new: kernel.kmod_is_loaded.vgem: true
[121.406436] [009/133] (902s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[121.590235] Starting subtest: primary-y-tiled-reflect-x-90
[121.591937] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[121.697562] [010/133] (902s left) kms_psr (fbc-psr-no-drrs)
[121.887717] Subtest fbc-psr-no-drrs: SKIP (0.000s)
[121.950949] [011/133] (901s left) kms_chamelium_edid (hdmi-mode-timings)
[122.216501] Subtest hdmi-mode-timings: SKIP (0.000s)
[122.357511] [012/133] (901s left) gem_exec_flush (basic-wb-ro-before-default)
[122.440579] Subtest basic-wb-ro-before-default: SKIP (0.000s)
[122.513583] [013/133] (901s left) gem_ccs (block-multicopy-inplace)
[122.597177] Starting subtest: block-multicopy-inplace
[122.598165] Starting dynamic subtest: linear-compressed-compfmt0-smem-lmem0-multicopy
[122.643071] Dynamic subtest linear-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.045s)
[122.651801] Starting dynamic subtest: linear-compressed-compfmt0-lmem0-lmem0-multicopy
[122.741463] Dynamic subtest linear-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.098s)
[122.750130] Starting dynamic subtest: xmajor-compressed-compfmt0-smem-lmem0-multicopy
[122.755913] Dynamic subtest xmajor-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.014s)
[122.764513] Starting dynamic subtest: xmajor-compressed-compfmt0-lmem0-lmem0-multicopy
[122.853502] Dynamic subtest xmajor-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.097s)
[122.862247] Starting dynamic subtest: tile4-compressed-compfmt0-smem-lmem0-multicopy
[122.867992] Dynamic subtest tile4-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.014s)
[122.876790] Starting dynamic subtest: tile4-compressed-compfmt0-lmem0-lmem0-multicopy
[122.972837] Dynamic subtest tile4-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.104s)
[122.981549] Starting dynamic subtest: tile64-compressed-compfmt0-smem-lmem0-multicopy
[122.987934] Dynamic subtest tile64-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.014s)
[122.996686] Starting dynamic subtest: tile64-compressed-compfmt0-lmem0-lmem0-multicopy
[123.084604] Dynamic subtest tile64-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.096s)
[123.085495] Subtest block-multicopy-inplace: SUCCESS (0.488s)
[123.226679] [014/133] (900s left) kms_invalid_mode (clock-too-high)
[123.411046] Starting subtest: clock-too-high
[123.411993] Starting dynamic subtest: pipe-A-DP-3
[123.517822] Dynamic subtest pipe-A-DP-3: SUCCESS (0.105s)
[123.537802] Starting dynamic subtest: pipe-B-DP-3
[123.539972] Dynamic subtest pipe-B-DP-3: SUCCESS (0.003s)
[123.550734] Starting dynamic subtest: pipe-C-DP-3
[123.552645] Dynamic subtest pipe-C-DP-3: SUCCESS (0.002s)
[123.561450] Starting dynamic subtest: pipe-D-DP-3
[123.563255] Dynamic subtest pipe-D-DP-3: SUCCESS (0.002s)
[123.564176] Subtest clock-too-high: SUCCESS (0.122s)
[123.752548] [015/133] (900s left) perf (invalid-remove-userspace-config)
[123.963393] Starting subtest: invalid-remove-userspace-config
[123.973569] Subtest invalid-remove-userspace-config: SUCCESS (0.010s)
[124.029596] [016/133] (900s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[124.223911] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[124.225605] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[124.417226] [017/133] (899s left) gem_pxp (hw-rejects-pxp-buffer)
[127.020802] Starting subtest: hw-rejects-pxp-buffer
[127.021764] Subtest hw-rejects-pxp-buffer: SUCCESS (0.000s)
[127.152997] [018/133] (897s left) vgem_basic (dmabuf-fence)
[127.218586] Starting subtest: dmabuf-fence
[127.219474] Subtest dmabuf-fence: SUCCESS (0.001s)
[127.312245] [019/133] (896s left) core_getclient (basic)
[127.389279] Starting subtest: basic
[127.390269] Subtest basic: SUCCESS (0.000s)
[127.540112] [020/133] (896s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[127.740645] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[127.742390] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[127.870682] [021/133] (896s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[128.060326] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[128.061267] Starting dynamic subtest: pipe-A-valid-mode
[129.945678] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.881s)
[129.947179] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (1.884s)
[130.188539] [022/133] (894s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[130.269261] Starting subtest: invalid-signal-one-illegal-handle
[130.270207] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[130.469211] [023/133] (894s left) kms_getfb (getfb-handle-not-fb)
[130.656721] Starting subtest: getfb-handle-not-fb
[130.657596] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[130.860959] [024/133] (893s left) kms_color (invalid-ctm-matrix-sizes)
[131.051183] Starting subtest: invalid-ctm-matrix-sizes
[131.052158] Starting dynamic subtest: pipe-A
[131.054153] Dynamic subtest pipe-A: SUCCESS (0.003s)
[131.062945] Starting dynamic subtest: pipe-B
[131.063828] Dynamic subtest pipe-B: SUCCESS (0.005s)
[131.072509] Starting dynamic subtest: pipe-C
[131.073431] Dynamic subtest pipe-C: SUCCESS (0.006s)
[131.082185] Starting dynamic subtest: pipe-D
[131.083179] Dynamic subtest pipe-D: SUCCESS (0.007s)
[131.084237] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.022s)
[131.165360] [025/133] (893s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[131.351341] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[131.352274] Starting dynamic subtest: pipe-A-DP-3
[131.360991] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[131.369709] Starting dynamic subtest: pipe-B-DP-3
[131.377983] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[131.386519] Starting dynamic subtest: pipe-C-DP-3
[131.394852] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[131.403326] Starting dynamic subtest: pipe-D-DP-3
[131.412293] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[131.414250] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[131.813035] [026/133] (893s left) i915_drm_fdinfo (memory-info-resident)
[131.882971] Starting subtest: memory-info-resident
[131.883961] Starting dynamic subtest: lmem0
[136.195704] Dynamic subtest lmem0: SUCCESS (4.311s)
[136.211689] Starting dynamic subtest: smem0
[140.515818] Dynamic subtest smem0: SUCCESS (4.317s)
[140.516990] Subtest memory-info-resident: SUCCESS (8.632s)
[140.606683] [027/133] (884s left) gem_pipe_control_store_loop (reused-buffer)
[140.691057] Starting subtest: reused-buffer
[142.868042] Subtest reused-buffer: SUCCESS (2.177s)
[143.010584] [028/133] (881s left) device_reset (unbind-reset-rebind)
[143.096463] Starting subtest: unbind-reset-rebind
[145.593120] Subtest unbind-reset-rebind: SUCCESS (2.496s)
[145.656225] [029/133] (879s left) kms_content_protection (lic-type-1)
[145.907353] Starting subtest: lic-type-1
[145.913028] Subtest lic-type-1: SKIP (0.004s)
[146.092102] [030/133] (878s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[146.290390] Starting subtest: 4-tiled-8bpp-rotate-90
[146.363420] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.073s)
[146.551137] [031/133] (878s left) kms_rotation_crc (primary-rotation-270)
[146.737477] Starting subtest: primary-rotation-270
[146.739263] Subtest primary-rotation-270: SKIP (0.001s)
[146.894220] [032/133] (878s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[149.000404] Starting subtest: modeset-non-lpsp-stress-no-wait
[151.764453] Subtest modeset-non-lpsp-stress-no-wait: SUCCESS (2.764s)
[151.946722] [033/133] (873s left) kms_async_flips (async-flip-hang)
[152.134294] Starting subtest: async-flip-hang
[152.361207] Starting dynamic subtest: pipe-A-DP-3
[178.483458] Dynamic subtest pipe-A-DP-3: SUCCESS (26.122s)
[178.690242] Starting dynamic subtest: pipe-B-DP-3
[203.501424] Dynamic subtest pipe-B-DP-3: SUCCESS (24.819s)
[203.674990] Starting dynamic subtest: pipe-C-DP-3
[228.453682] Dynamic subtest pipe-C-DP-3: SUCCESS (24.785s)
[228.671976] Starting dynamic subtest: pipe-D-DP-3
[243.507471] Dynamic subtest pipe-D-DP-3: SUCCESS (14.843s)
[243.508445] Subtest async-flip-hang: SUCCESS (91.371s)
[243.896742] [034/133] (781s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[244.083782] Starting subtest: cursorB-vs-flipB-legacy
[244.086925] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[244.232567] [035/133] (781s left) kms_lease (master-vs-lease)
[244.416284] Starting subtest: master-vs-lease
[244.418291] Subtest master-vs-lease: SUCCESS (0.002s)
[244.607775] [036/133] (780s left) kms_plane_multiple (tiling-y)
[244.792608] Starting subtest: tiling-y
[244.794420] Subtest tiling-y: SKIP (0.000s)
[244.904762] [037/133] (780s left) i915_hangman (engine-engine-hang)
[245.001669] Starting subtest: engine-engine-hang
[245.002530] Starting dynamic subtest: rcs0
[245.795432] Dynamic subtest rcs0: SUCCESS (0.794s)
[245.807256] Starting dynamic subtest: bcs0
[246.530652] Dynamic subtest bcs0: SUCCESS (0.734s)
[246.541980] Starting dynamic subtest: vcs0
[247.268509] Dynamic subtest vcs0: SUCCESS (0.737s)
[247.279764] Starting dynamic subtest: vcs1
[248.003493] Dynamic subtest vcs1: SUCCESS (0.734s)
[248.014310] Starting dynamic subtest: vecs0
[248.717368] Dynamic subtest vecs0: SUCCESS (0.713s)
[248.727131] Starting dynamic subtest: vecs1
[249.470505] Dynamic subtest vecs1: SUCCESS (0.752s)
[249.481376] Starting dynamic subtest: ccs0
[250.218307] Dynamic subtest ccs0: SUCCESS (0.747s)
[250.219233] Subtest engine-engine-hang: SUCCESS (5.217s)
[250.363063] [038/133] (775s left) gem_media_fill (media-fill)
[250.444575] Subtest media-fill: SKIP (0.000s)
[250.630547] [039/133] (775s left) kms_flip (2x-busy-flip)
[250.815605] Starting subtest: 2x-busy-flip
[250.830894] Subtest 2x-busy-flip: SKIP (0.014s)
[250.938555] [040/133] (774s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[251.135430] Starting subtest: yf-tiled-16bpp-rotate-90
[251.137214] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[251.265634] [041/133] (774s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[251.539844] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[251.686083] [042/133] (774s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[252.343882] Starting subtest: syncobj-timeline-invalid-wait
[252.348146] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.005s)
[252.662251] [043/133] (773s left) gem_workarounds (basic-read)
[252.758217] Starting subtest: basic-read
[252.796170] Subtest basic-read: SUCCESS (0.038s)
[252.934772] [044/133] (773s left) syncobj_eventfd (invalid-illegal-eventfd)
[253.013436] Starting subtest: invalid-illegal-eventfd
[253.014352] Subtest invalid-illegal-eventfd: SUCCESS (0.000s)
[253.126552] [045/133] (773s left) kms_plane_alpha_blend (alpha-7efc)
[253.310707] Starting subtest: alpha-7efc
[253.564314] Starting dynamic subtest: pipe-A-DP-3
[254.052407] Dynamic subtest pipe-A-DP-3: SUCCESS (0.488s)
[254.082544] Starting dynamic subtest: pipe-D-DP-3
[254.692449] Dynamic subtest pipe-D-DP-3: SUCCESS (0.619s)
[254.693342] Subtest alpha-7efc: SUCCESS (1.382s)
[254.963787] [046/133] (771s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[255.157970] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[255.159603] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[255.320981] [047/133] (771s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[255.507348] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[255.508257] Starting dynamic subtest: pipe-A-DP-3
[255.529515] Dynamic subtest pipe-A-DP-3: SKIP (0.002s)
[255.539416] Starting dynamic subtest: pipe-B-DP-3
[255.560948] Dynamic subtest pipe-B-DP-3: SKIP (0.002s)
[255.570521] Starting dynamic subtest: pipe-C-DP-3
[255.595848] Dynamic subtest pipe-C-DP-3: SKIP (0.002s)
[255.605452] Starting dynamic subtest: pipe-D-DP-3
[255.627927] Dynamic subtest pipe-D-DP-3: SKIP (0.002s)
[255.630340] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.014s)
[255.945216] [048/133] (770s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[256.141202] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[256.145834] Starting dynamic subtest: A-DP3
[257.062573] Dynamic subtest A-DP3: SUCCESS (0.915s)
[257.074353] Starting dynamic subtest: B-DP3
[257.750651] Dynamic subtest B-DP3: SUCCESS (0.688s)
[257.760752] Starting dynamic subtest: C-DP3
[258.429930] Dynamic subtest C-DP3: SUCCESS (0.678s)
[258.440132] Starting dynamic subtest: D-DP3
[259.114987] Dynamic subtest D-DP3: SUCCESS (0.684s)
[259.117245] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (2.975s)
[259.371111] [049/133] (767s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[259.565098] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[259.566851] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[259.690681] [050/133] (766s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[259.878591] Starting subtest: fb-8bpc-vs-panel-8bpc
[259.880873] Starting dynamic subtest: pipe-A-DP-3
[260.252350] Dynamic subtest pipe-A-DP-3: SUCCESS (0.372s)
[260.253756] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (0.375s)
[260.477637] [051/133] (766s left) gem_pxp (fail-invalid-protected-context)
[263.083481] Starting subtest: fail-invalid-protected-context
[263.085595] Subtest fail-invalid-protected-context: SKIP (0.000s)
[263.207669] [052/133] (763s left) kms_force_connector_basic (prune-stale-modes)
[263.320627] Starting subtest: prune-stale-modes
[263.343630] Subtest prune-stale-modes: SUCCESS (0.023s)
[263.648302] [053/133] (763s left) gem_render_copy_redux (interruptible)
[263.734202] Starting subtest: interruptible
[264.478478] Subtest interruptible: SUCCESS (0.744s)
[264.612393] [054/133] (762s left) perf_pmu (faulting-read)
[264.684377] Starting subtest: faulting-read
[264.685298] Starting dynamic subtest: fixed
[264.686176] Dynamic subtest fixed: SUCCESS (0.001s)
[264.687123] Subtest faulting-read: SUCCESS (0.002s)
[264.825538] [055/133] (762s left) gem_ctx_isolation (clean)
[264.914275] Starting subtest: clean
[264.915217] Starting dynamic subtest: rcs0
[265.013598] Dynamic subtest rcs0: SUCCESS (0.099s)
[265.026233] Starting dynamic subtest: bcs0
[265.123136] Dynamic subtest bcs0: SUCCESS (0.108s)
[265.135165] Starting dynamic subtest: vcs0
[265.213630] Dynamic subtest vcs0: SUCCESS (0.089s)
[265.225896] Starting dynamic subtest: vcs1
[265.336645] Dynamic subtest vcs1: SUCCESS (0.122s)
[265.349171] Starting dynamic subtest: vecs0
[265.436790] Dynamic subtest vecs0: SUCCESS (0.099s)
[265.448749] Starting dynamic subtest: vecs1
[265.538089] Dynamic subtest vecs1: SUCCESS (0.100s)
[265.550379] Starting dynamic subtest: ccs0
[265.644227] Dynamic subtest ccs0: SUCCESS (0.105s)
[265.645272] Subtest clean: SUCCESS (0.730s)
[265.949428] [056/133] (760s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[266.136285] Starting subtest: flip-vs-cursor-busy-crc-atomic
[266.896308] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.760s)
[267.154712] [057/133] (759s left) gem_mmap_gtt (isolation)
[267.237219] Subtest isolation: SKIP (0.000s)
[267.307947] [058/133] (759s left) prime_busy (hang)
[267.394200] Starting subtest: hang
[267.396411] Starting dynamic subtest: rcs0
[293.483567] Dynamic subtest rcs0: SUCCESS (26.084s)
[293.494360] Starting dynamic subtest: bcs0
[300.639585] Dynamic subtest bcs0: SUCCESS (7.155s)
[300.650526] Starting dynamic subtest: vcs0
[307.619736] Dynamic subtest vcs0: SUCCESS (6.980s)
[307.630657] Starting dynamic subtest: vcs1
[314.661816] Dynamic subtest vcs1: SUCCESS (7.039s)
[314.672168] Starting dynamic subtest: vecs0
[321.631432] Dynamic subtest vecs0: SUCCESS (6.968s)
[321.642386] Starting dynamic subtest: vecs1
[328.608204] Dynamic subtest vecs1: SUCCESS (6.975s)
[328.618897] Starting dynamic subtest: ccs0
[342.506753] Dynamic subtest ccs0: SUCCESS (13.897s)
[342.507677] Subtest hang: SUCCESS (75.108s)
[342.640873] [059/133] (684s left) kms_cursor_crc (cursor-sliding-512x512)
[343.103520] Starting subtest: cursor-sliding-512x512
[343.106968] Subtest cursor-sliding-512x512: SKIP (0.001s)
[343.397380] [060/133] (683s left) gem_exec_balancer (sequential)
[343.488192] Starting subtest: sequential
[349.467384] Subtest sequential: SUCCESS (5.979s)
[349.876372] [061/133] (677s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[350.063279] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[350.065067] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[350.215693] [062/133] (677s left) gem_softpin (full)
[350.304110] Starting subtest: full
[350.322914] Subtest full: SUCCESS (0.019s)
[350.504523] [063/133] (676s left) kms_psr (pr-sprite-plane-move)
[350.690166] Subtest pr-sprite-plane-move: SKIP (0.000s)
[350.747333] [064/133] (676s left) gem_ctx_shared (q-in-order)
[350.842683] Starting subtest: Q-in-order
[350.843568] Starting dynamic subtest: rcs0
[351.114909] Dynamic subtest rcs0: SUCCESS (0.271s)
[351.126405] Starting dynamic subtest: bcs0
[351.296787] Dynamic subtest bcs0: SUCCESS (0.181s)
[351.308399] Starting dynamic subtest: vcs0
[351.488637] Dynamic subtest vcs0: SUCCESS (0.191s)
[351.501950] Starting dynamic subtest: vcs1
[351.681919] Dynamic subtest vcs1: SUCCESS (0.192s)
[351.693449] Starting dynamic subtest: vecs0
[351.871862] Dynamic subtest vecs0: SUCCESS (0.189s)
[351.883374] Starting dynamic subtest: vecs1
[352.062158] Dynamic subtest vecs1: SUCCESS (0.189s)
[352.073502] Starting dynamic subtest: ccs0
[352.249791] Dynamic subtest ccs0: SUCCESS (0.186s)
[352.250627] Subtest Q-in-order: SUCCESS (1.407s)
[352.572904] [065/133] (674s left) kms_chamelium_audio (hdmi-audio)
[352.847999] Subtest hdmi-audio: SKIP (0.000s)
[352.994346] [066/133] (674s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[353.076275] Starting subtest: single-wait-all-for-submit-available-submitted
[353.077204] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[353.270895] [067/133] (674s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[353.755313] Starting subtest: cursor-rapid-movement-512x170
[353.757129] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[353.970835] [068/133] (673s left) kms_big_fb (linear-64bpp-rotate-270)
[354.169392] Starting subtest: linear-64bpp-rotate-270
[354.221417] Subtest linear-64bpp-rotate-270: SKIP (0.051s)
[354.438633] [069/133] (673s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[354.630416] Starting subtest: flip-vs-cursor-atomic
[364.847163] Subtest flip-vs-cursor-atomic: SUCCESS (10.216s)
[365.105124] [070/133] (662s left) gem_exec_schedule (semaphore-power)
[365.318848] Subtest semaphore-power: SKIP (0.000s)
[365.534012] [071/133] (662s left) gem_ctx_persistence (legacy-engines-hang)
[365.623236] Starting subtest: legacy-engines-hang
[365.624257] Starting dynamic subtest: render
[365.774559] Dynamic subtest render: SUCCESS (0.150s)
[365.789786] Starting dynamic subtest: bsd1
[365.871111] Dynamic subtest bsd1: SUCCESS (0.093s)
[365.916451] Starting dynamic subtest: bsd2
[365.927293] Dynamic subtest bsd2: SUCCESS (0.051s)
[365.945145] Starting dynamic subtest: blt
[365.975297] Dynamic subtest blt: SUCCESS (0.046s)
[365.989984] Starting dynamic subtest: vebox
[366.074388] Dynamic subtest vebox: SUCCESS (0.097s)
[366.080097] Subtest legacy-engines-hang: SUCCESS (0.453s)
[366.295867] [072/133] (661s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[366.482340] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[366.483129] Starting dynamic subtest: pipe-A
[366.561588] Dynamic subtest pipe-A: SUCCESS (0.080s)
[366.575150] Starting dynamic subtest: pipe-B
[366.775291] Dynamic subtest pipe-B: SUCCESS (0.213s)
[366.788947] Starting dynamic subtest: pipe-C
[366.971711] Dynamic subtest pipe-C: SUCCESS (0.195s)
[366.991640] Starting dynamic subtest: pipe-D
[367.183714] Dynamic subtest pipe-D: SUCCESS (0.210s)
[367.184794] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (0.703s)
[367.497163] [073/133] (660s left) syncobj_timeline (multi-wait-for-submit-signaled)
[367.578443] Starting subtest: multi-wait-for-submit-signaled
[367.579392] Subtest multi-wait-for-submit-signaled: SUCCESS (0.001s)
[367.746703] [074/133] (660s left) kms_atomic_interruptible (legacy-dpms)
[367.933417] Starting subtest: legacy-dpms
[367.935685] Starting dynamic subtest: pipe-A-DP-3
[374.254469] Dynamic subtest pipe-A-DP-3: SUCCESS (6.318s)
[374.255462] Subtest legacy-dpms: SUCCESS (6.321s)
[374.498065] [075/133] (653s left) syncobj_basic (bad-pad-handle-to-fd)
[374.577753] Starting subtest: bad-pad-handle-to-fd
[374.578588] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[374.772328] [076/133] (653s left) drm_read (short-buffer-nonblock)
[375.032918] Starting subtest: short-buffer-nonblock
[375.033835] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[375.266715] [077/133] (653s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[375.450417] Starting subtest: cursorB-vs-flipB-varying-size
[375.452165] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[375.555416] [078/133] (652s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[375.756144] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[375.757915] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[375.890686] [079/133] (652s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[376.089315] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[377.434120] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.343s)
[377.624898] [080/133] (651s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[377.825137] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[380.634119] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (2.809s)
[380.841840] [081/133] (647s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-sf)
[381.030197] Subtest fbc-pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[381.095457] [082/133] (647s left) kms_atomic (crtc-invalid-params)
[381.280884] Starting subtest: crtc-invalid-params
[381.283179] Starting dynamic subtest: pipe-A-DP-3
[381.778523] Dynamic subtest pipe-A-DP-3: SUCCESS (0.495s)
[381.779619] Subtest crtc-invalid-params: SUCCESS (0.498s)
[382.052999] [083/133] (646s left) kms_flip (busy-flip)
[382.238592] Starting subtest: busy-flip
[382.243345] Starting dynamic subtest: A-DP3
[383.075111] Dynamic subtest A-DP3: SUCCESS (0.832s)
[383.088769] Starting dynamic subtest: B-DP3
[383.839973] Dynamic subtest B-DP3: SUCCESS (0.763s)
[383.862564] Starting dynamic subtest: C-DP3
[384.600236] Dynamic subtest C-DP3: SUCCESS (0.757s)
[384.613850] Starting dynamic subtest: D-DP3
[385.363245] Dynamic subtest D-DP3: SUCCESS (0.761s)
[385.365622] Subtest busy-flip: SUCCESS (3.126s)
[385.701099] [084/133] (643s left) gem_exec_params (cliprects-invalid)
[385.793405] Starting subtest: cliprects-invalid
[385.794413] Subtest cliprects-invalid: SUCCESS (0.000s)
[385.999325] [085/133] (643s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[386.193427] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[388.610454] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (2.417s)
[388.816634] [086/133] (640s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[388.903399] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[388.904401] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[389.066090] [087/133] (640s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt)
[389.256248] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt
[389.257939] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[389.475694] [088/133] (639s left) syncobj_timeline (wait-zero-handles)
[389.557373] Starting subtest: wait-zero-handles
[389.558274] Subtest wait-zero-handles: SUCCESS (0.000s)
[389.766506] [089/133] (639s left) kms_psr (fbc-pr-sprite-plane-onoff)
[389.950391] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[390.006108] [090/133] (639s left) gen9_exec_parse (batch-without-end)
[390.091484] Subtest batch-without-end: SKIP (0.000s)
[390.191902] [091/133] (639s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[390.379163] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[390.380971] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[390.523344] [092/133] (639s left) syncobj_wait (wait-for-submit-snapshot)
[390.607163] Starting subtest: wait-for-submit-snapshot
[390.690841] Subtest wait-for-submit-snapshot: SUCCESS (0.083s)
[390.832922] [093/133] (638s left) gem_exec_suspend (basic-s0)
[390.921642] Starting subtest: basic-S0
[390.922549] Starting dynamic subtest: smem