[47.384310] Initializing watchdogs
[47.384374]   /dev/watchdog0
[47.399974] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:9a49 Intel Tigerlake (Gen12) TigerLake-LP GT2 [Iris Xe Graphics]
[47.405510] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[47.408336] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[47.410181] [001/133] (960s left) kms_flip (wf_vblank-ts-check)
[47.498316] Starting subtest: wf_vblank-ts-check
[47.500026] Starting dynamic subtest: A-HDMI-A1
[47.895397] Dynamic subtest A-HDMI-A1: FAIL (0.388s)
[47.900449] Starting dynamic subtest: B-HDMI-A1
[66.582029] Dynamic subtest B-HDMI-A1: SUCCESS (18.950s)
[66.587300] Starting dynamic subtest: C-HDMI-A1
[74.458125] Dynamic subtest C-HDMI-A1: SUCCESS (7.891s)
[74.464184] Starting dynamic subtest: D-HDMI-A1
[82.316049] Dynamic subtest D-HDMI-A1: SUCCESS (7.886s)
[82.317780] Subtest wf_vblank-ts-check: FAIL (35.122s)
[82.506375] [002/133] (925s left) kms_cursor_crc (cursor-onscreen-256x256)
[82.596508] Starting subtest: cursor-onscreen-256x256
[82.620521] Starting dynamic subtest: pipe-A-HDMI-A-1
[83.791478] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.181s)
[83.824932] Starting dynamic subtest: pipe-D-HDMI-A-1
[84.968893] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.150s)
[84.969682] Subtest cursor-onscreen-256x256: SUCCESS (2.372s)
[85.093640] [003/133] (922s left) gem_mmap_gtt (big-copy-odd)
[85.128644] Starting subtest: big-copy-odd
[92.144765] Subtest big-copy-odd: SUCCESS (7.015s)
[92.209601] [004/133] (915s left) i915_pm_rpm (sysfs-read)
[93.674639] Starting subtest: sysfs-read
[94.873145] Subtest sysfs-read: SUCCESS (1.198s)
[95.036154] [005/133] (913s left) kms_universal_plane (disable-primary-vs-flip)
[95.122699] Starting subtest: disable-primary-vs-flip
[95.123383] Starting dynamic subtest: pipe-A-HDMI-A-1
[95.449425] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.326s)
[95.457789] Starting dynamic subtest: pipe-B-HDMI-A-1
[95.733432] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.282s)
[95.741125] Starting dynamic subtest: pipe-C-HDMI-A-1
[96.018282] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.284s)
[96.026655] Starting dynamic subtest: pipe-D-HDMI-A-1
[96.330478] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.311s)
[96.331243] Subtest disable-primary-vs-flip: SUCCESS (1.208s)
[96.458015] [006/133] (911s left) perf (gen12-group-exclusive-stream-ctx-handle)
[96.563561] Starting subtest: gen12-group-exclusive-stream-ctx-handle
[96.573493] Subtest gen12-group-exclusive-stream-ctx-handle: SUCCESS (0.010s)
[96.609930] [007/133] (911s left) kms_flip_event_leak (basic)
[96.698872] Starting subtest: basic
[96.700517] Starting dynamic subtest: pipe-A-HDMI-A-1
[96.887685] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.188s)
[96.896552] Starting dynamic subtest: pipe-B-HDMI-A-1
[97.056711] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.167s)
[97.065313] Starting dynamic subtest: pipe-C-HDMI-A-1
[97.211709] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.154s)
[97.218831] Starting dynamic subtest: pipe-D-HDMI-A-1
[97.363065] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.150s)
[97.363813] Subtest basic: SUCCESS (0.664s)
[97.510755] [008/133] (910s left) gem_exec_fence (syncobj-wait)
[97.866840] Starting subtest: syncobj-wait
[97.894590] Subtest syncobj-wait: SUCCESS (0.028s)
[98.226187] [FACT gem_exec_fence (syncobj-wait)] new: kernel.kmod_is_loaded.vgem: true
[98.227786] [009/133] (909s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[98.313547] Starting subtest: primary-y-tiled-reflect-x-90
[103.307619] Subtest primary-y-tiled-reflect-x-90: SUCCESS (4.993s)
[103.481809] [010/133] (904s left) kms_psr (fbc-psr-no-drrs)
[103.572799] Subtest fbc-psr-no-drrs: SKIP (0.000s)
[103.609447] [011/133] (904s left) kms_chamelium_edid (hdmi-mode-timings)
[103.748694] Subtest hdmi-mode-timings: SKIP (0.000s)
[103.837672] [012/133] (904s left) gem_exec_flush (basic-wb-ro-before-default)
[103.882216] Starting subtest: basic-wb-ro-before-default
[109.323383] Subtest basic-wb-ro-before-default: SUCCESS (5.441s)
[109.530542] [013/133] (898s left) gem_ccs (block-multicopy-inplace)
[109.569940] Starting subtest: block-multicopy-inplace
[109.571357] Subtest block-multicopy-inplace: SKIP (0.000s)
[109.630062] [014/133] (898s left) kms_invalid_mode (clock-too-high)
[109.717149] Starting subtest: clock-too-high
[109.717865] Starting dynamic subtest: pipe-A-HDMI-A-1
[109.779018] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.062s)
[109.788174] Starting dynamic subtest: pipe-B-HDMI-A-1
[109.790572] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.010s)
[109.800071] Starting dynamic subtest: pipe-C-HDMI-A-1
[109.802670] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.009s)
[109.809642] Starting dynamic subtest: pipe-D-HDMI-A-1
[109.811918] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.009s)
[109.812674] Subtest clock-too-high: SUCCESS (0.095s)
[109.973746] [015/133] (898s left) perf (invalid-remove-userspace-config)
[110.051501] Starting subtest: invalid-remove-userspace-config
[110.062255] Subtest invalid-remove-userspace-config: SUCCESS (0.011s)
[110.102970] [016/133] (898s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[110.190738] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[110.192150] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[110.295880] [017/133] (898s left) gem_pxp (hw-rejects-pxp-buffer)
[110.397182] Starting subtest: hw-rejects-pxp-buffer
[110.399191] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[110.465104] [018/133] (898s left) vgem_basic (dmabuf-fence)
[110.503353] Starting subtest: dmabuf-fence
[110.504038] Subtest dmabuf-fence: SUCCESS (0.001s)
[110.563087] [019/133] (898s left) core_getclient (basic)
[110.595424] Starting subtest: basic
[110.596103] Subtest basic: SUCCESS (0.000s)
[110.672254] [020/133] (897s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[110.766952] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[111.395365] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (0.628s)
[111.516223] [021/133] (897s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[111.600733] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[111.602078] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SKIP (0.000s)
[111.662948] [022/133] (897s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[111.697222] Starting subtest: invalid-signal-one-illegal-handle
[111.697940] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[111.772164] [023/133] (896s left) kms_getfb (getfb-handle-not-fb)
[111.857631] Starting subtest: getfb-handle-not-fb
[111.858357] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[111.954015] [024/133] (896s left) kms_color (invalid-ctm-matrix-sizes)
[112.037839] Starting subtest: invalid-ctm-matrix-sizes
[112.038545] Starting dynamic subtest: pipe-A
[112.041624] Dynamic subtest pipe-A: SUCCESS (0.004s)
[112.048458] Starting dynamic subtest: pipe-B
[112.049174] Dynamic subtest pipe-B: SUCCESS (0.005s)
[112.055876] Starting dynamic subtest: pipe-C
[112.056576] Dynamic subtest pipe-C: SUCCESS (0.007s)
[112.063137] Starting dynamic subtest: pipe-D
[112.063837] Dynamic subtest pipe-D: SUCCESS (0.007s)
[112.064505] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.024s)
[112.121714] [025/133] (896s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[112.205936] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[112.206706] Starting dynamic subtest: pipe-A-HDMI-A-1
[112.213583] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[112.221750] Starting dynamic subtest: pipe-B-HDMI-A-1
[112.228674] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[112.236641] Starting dynamic subtest: pipe-C-HDMI-A-1
[112.243437] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[112.251378] Starting dynamic subtest: pipe-D-HDMI-A-1
[112.258345] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.001s)
[112.259748] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[112.443800] [026/133] (896s left) i915_drm_fdinfo (memory-info-resident)
[112.481117] Starting subtest: memory-info-resident
[112.481853] Starting dynamic subtest: smem0
[113.755059] Dynamic subtest smem0: SUCCESS (1.273s)
[113.756021] Subtest memory-info-resident: SUCCESS (1.274s)
[113.848856] [027/133] (895s left) gem_pipe_control_store_loop (reused-buffer)
[113.886042] Starting subtest: reused-buffer
[116.047634] Subtest reused-buffer: SUCCESS (2.152s)
[116.147907] [028/133] (892s left) device_reset (unbind-reset-rebind)
[116.194309] Starting subtest: unbind-reset-rebind
[117.984169] Subtest unbind-reset-rebind: SUCCESS (1.781s)
[118.017335] [029/133] (890s left) kms_content_protection (lic-type-1)
[118.110192] Starting subtest: lic-type-1
[118.125400] Subtest lic-type-1: SKIP (0.013s)
[118.200772] [030/133] (890s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[118.294482] Starting subtest: 4-tiled-8bpp-rotate-90
[118.295859] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.000s)
[118.381986] [031/133] (890s left) kms_rotation_crc (primary-rotation-270)
[118.466730] Starting subtest: primary-rotation-270
[123.469170] Subtest primary-rotation-270: SUCCESS (5.002s)
[123.661438] [032/133] (885s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[125.053396] Starting subtest: modeset-non-lpsp-stress-no-wait
[125.151017] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.097s)
[125.267910] [033/133] (883s left) kms_async_flips (async-flip-hang)
[125.354764] Starting subtest: async-flip-hang
[125.473989] Starting dynamic subtest: pipe-A-HDMI-A-1
[140.411181] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (14.905s)
[140.513814] Starting dynamic subtest: pipe-B-HDMI-A-1
[155.770588] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (15.229s)
[155.877191] Starting dynamic subtest: pipe-C-HDMI-A-1
[170.618486] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (14.714s)
[170.726482] Starting dynamic subtest: pipe-D-HDMI-A-1
[185.465898] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (14.713s)
[185.466606] Subtest async-flip-hang: SUCCESS (60.077s)
[185.652074] [034/133] (823s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[185.746589] Starting subtest: cursorB-vs-flipB-legacy
[185.748088] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[185.808904] [035/133] (823s left) kms_lease (master-vs-lease)
[185.894820] Starting subtest: master-vs-lease
[185.895570] Subtest master-vs-lease: SUCCESS (0.001s)
[185.958166] [036/133] (823s left) kms_plane_multiple (tiling-y)
[186.042904] Starting subtest: tiling-y
[186.043607] Starting dynamic subtest: pipe-A-HDMI-A-1
[186.662887] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.619s)
[186.673558] Starting dynamic subtest: pipe-B-HDMI-A-1
[187.304713] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.640s)
[187.316824] Starting dynamic subtest: pipe-C-HDMI-A-1
[187.942061] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.635s)
[187.950959] Starting dynamic subtest: pipe-D-HDMI-A-1
[188.559715] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.616s)
[188.560411] Subtest tiling-y: SUCCESS (2.518s)
[188.712775] [037/133] (820s left) i915_hangman (engine-engine-hang)
[188.760947] Starting subtest: engine-engine-hang
[188.761646] Starting dynamic subtest: rcs0
[189.322849] Dynamic subtest rcs0: SUCCESS (0.561s)
[189.338319] Starting dynamic subtest: bcs0
[189.899814] Dynamic subtest bcs0: SUCCESS (0.574s)
[189.910405] Starting dynamic subtest: vcs0
[190.474347] Dynamic subtest vcs0: SUCCESS (0.572s)
[190.484280] Starting dynamic subtest: vcs1
[191.058792] Dynamic subtest vcs1: SUCCESS (0.582s)
[191.068612] Starting dynamic subtest: vecs0
[191.636665] Dynamic subtest vecs0: SUCCESS (0.575s)
[191.637680] Subtest engine-engine-hang: SUCCESS (2.876s)
[191.704989] [038/133] (817s left) gem_media_fill (media-fill)
[191.745118] Starting subtest: media-fill
[191.745813] Starting dynamic subtest: smem
[191.750311] Dynamic subtest smem: SUCCESS (0.005s)
[191.751060] Subtest media-fill: SUCCESS (0.006s)
[191.954816] [039/133] (817s left) kms_flip (2x-busy-flip)
[192.041929] Starting subtest: 2x-busy-flip
[192.050936] Subtest 2x-busy-flip: SKIP (0.009s)
[192.131555] [040/133] (817s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[192.236540] Starting subtest: yf-tiled-16bpp-rotate-90
[192.237934] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[192.336053] [041/133] (817s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[192.482896] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[192.572799] [042/133] (816s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[193.037682] Starting subtest: syncobj-timeline-invalid-wait
[193.038617] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.003s)
[193.206522] [043/133] (816s left) gem_workarounds (basic-read)
[193.253821] Starting subtest: basic-read
[193.261826] Subtest basic-read: SUCCESS (0.007s)
[193.353214] [044/133] (816s left) syncobj_eventfd (invalid-illegal-eventfd)
[193.387821] Starting subtest: invalid-illegal-eventfd
[193.388496] Subtest invalid-illegal-eventfd: SUCCESS (0.000s)
[193.451070] [045/133] (816s left) kms_plane_alpha_blend (alpha-7efc)
[193.537944] Starting subtest: alpha-7efc
[193.606731] Starting dynamic subtest: pipe-A-HDMI-A-1
[193.911391] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.306s)
[193.944910] Starting dynamic subtest: pipe-D-HDMI-A-1
[194.371996] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.436s)
[194.372843] Subtest alpha-7efc: SUCCESS (0.835s)
[194.542681] [046/133] (815s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[194.637062] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[194.638421] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[194.733093] [047/133] (814s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[194.817667] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[194.819912] Starting dynamic subtest: pipe-A-HDMI-A-1
[195.466081] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.643s)
[195.483029] Starting dynamic subtest: pipe-B-HDMI-A-1
[195.967135] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.496s)
[195.981256] Starting dynamic subtest: pipe-C-HDMI-A-1
[196.467033] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.496s)
[196.480855] Starting dynamic subtest: pipe-D-HDMI-A-1
[196.967658] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.496s)
[196.968736] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SUCCESS (2.148s)
[197.156416] [048/133] (812s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[197.248402] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[197.251232] Starting dynamic subtest: A-HDMI-A1
[197.912585] Dynamic subtest A-HDMI-A1: SUCCESS (0.661s)
[197.921070] Starting dynamic subtest: B-HDMI-A1
[198.588805] Dynamic subtest B-HDMI-A1: SUCCESS (0.674s)
[198.596958] Starting dynamic subtest: C-HDMI-A1
[199.179754] Dynamic subtest C-HDMI-A1: SUCCESS (0.588s)
[199.186842] Starting dynamic subtest: D-HDMI-A1
[199.775090] Dynamic subtest D-HDMI-A1: SUCCESS (0.593s)
[199.775829] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (2.527s)
[199.926240] [049/133] (809s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[200.023735] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[200.025100] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[200.108464] [050/133] (809s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[200.193997] Starting subtest: fb-8bpc-vs-panel-8bpc
[200.195739] Starting dynamic subtest: pipe-A-HDMI-A-1
[200.388862] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.193s)
[200.389586] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (0.195s)
[200.542393] [051/133] (809s left) gem_pxp (fail-invalid-protected-context)
[200.640935] Starting subtest: fail-invalid-protected-context
[200.642062] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[200.714972] [052/133] (809s left) kms_force_connector_basic (prune-stale-modes)
[200.847790] Starting subtest: prune-stale-modes
[200.999749] Subtest prune-stale-modes: SUCCESS (0.151s)
[201.155840] [053/133] (808s left) gem_render_copy_redux (interruptible)
[201.194306] Starting subtest: interruptible
[201.522736] Subtest interruptible: SUCCESS (0.324s)
[201.615994] [054/133] (808s left) perf_pmu (faulting-read)
[201.652342] Starting subtest: faulting-read
[201.653062] Starting dynamic subtest: gtt
[201.654830] Dynamic subtest gtt: SUCCESS (0.001s)
[201.662344] Starting dynamic subtest: wb
[201.663056] Dynamic subtest wb: SUCCESS (0.001s)
[201.670221] Starting dynamic subtest: wc
[201.670907] Dynamic subtest wc: SUCCESS (0.000s)
[201.677909] Starting dynamic subtest: uc
[201.678584] Dynamic subtest uc: SUCCESS (0.000s)
[201.679242] Subtest faulting-read: SUCCESS (0.006s)
[201.756231] [055/133] (808s left) gem_ctx_isolation (clean)
[201.797509] Starting subtest: clean
[201.798219] Starting dynamic subtest: rcs0
[201.821983] Dynamic subtest rcs0: SUCCESS (0.024s)
[201.830714] Starting dynamic subtest: bcs0
[201.878558] Dynamic subtest bcs0: SUCCESS (0.055s)
[201.887556] Starting dynamic subtest: vcs0
[201.945229] Dynamic subtest vcs0: SUCCESS (0.066s)
[201.953596] Starting dynamic subtest: vcs1
[202.007275] Dynamic subtest vcs1: SUCCESS (0.061s)
[202.015699] Starting dynamic subtest: vecs0
[202.070193] Dynamic subtest vecs0: SUCCESS (0.062s)
[202.071016] Subtest clean: SUCCESS (0.273s)
[202.242129] [056/133] (807s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[202.329450] Starting subtest: flip-vs-cursor-busy-crc-atomic
[202.694564] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.365s)
[202.883233] [057/133] (807s left) gem_mmap_gtt (isolation)
[202.921101] Starting subtest: isolation
[202.924269] Subtest isolation: SUCCESS (0.001s)
[203.010126] [058/133] (807s left) prime_busy (hang)
[203.048349] Starting subtest: hang
[203.050416] Starting dynamic subtest: rcs0
[216.698763] Dynamic subtest rcs0: SUCCESS (13.615s)
[216.705830] Starting dynamic subtest: bcs0
[223.546540] Dynamic subtest bcs0: SUCCESS (6.846s)
[223.554951] Starting dynamic subtest: vcs0
[230.586416] Dynamic subtest vcs0: SUCCESS (7.039s)
[230.593525] Starting dynamic subtest: vcs1
[237.627062] Dynamic subtest vcs1: SUCCESS (7.039s)
[237.634100] Starting dynamic subtest: vecs0
[244.603235] Dynamic subtest vecs0: SUCCESS (6.975s)
[244.604250] Subtest hang: SUCCESS (41.519s)
[244.679508] [059/133] (765s left) kms_cursor_crc (cursor-sliding-512x512)
[244.774417] Starting subtest: cursor-sliding-512x512
[244.776859] Subtest cursor-sliding-512x512: SKIP (0.001s)
[244.875983] [060/133] (765s left) gem_exec_balancer (sequential)
[244.918123] Starting subtest: sequential
[248.378709] Subtest sequential: SUCCESS (3.459s)
[248.561881] [061/133] (761s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[248.652572] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[248.653942] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[248.743513] [062/133] (761s left) gem_softpin (full)
[248.781380] Starting subtest: full
[248.787906] Subtest full: SUCCESS (0.006s)
[248.886387] [063/133] (761s left) kms_psr (pr-sprite-plane-move)
[248.972660] Subtest pr-sprite-plane-move: SKIP (0.000s)
[249.013967] [064/133] (761s left) gem_ctx_shared (q-in-order)
[249.057601] Starting subtest: Q-in-order
[249.058325] Starting dynamic subtest: rcs0
[249.109151] Dynamic subtest rcs0: SUCCESS (0.051s)
[249.120393] Starting dynamic subtest: bcs0
[249.163780] Dynamic subtest bcs0: SUCCESS (0.052s)
[249.176478] Starting dynamic subtest: vcs0
[249.216469] Dynamic subtest vcs0: SUCCESS (0.051s)
[249.229335] Starting dynamic subtest: vcs1
[249.270330] Dynamic subtest vcs1: SUCCESS (0.052s)
[249.283308] Starting dynamic subtest: vecs0
[249.324765] Dynamic subtest vecs0: SUCCESS (0.052s)
[249.326068] Subtest Q-in-order: SUCCESS (0.268s)
[249.512627] [065/133] (760s left) kms_chamelium_audio (hdmi-audio)
[249.650563] Subtest hdmi-audio: SKIP (0.000s)
[249.747998] [066/133] (760s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[249.783414] Starting subtest: single-wait-all-for-submit-available-submitted
[249.784105] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[249.854061] [067/133] (760s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[249.945201] Starting subtest: cursor-rapid-movement-512x170
[249.946758] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[250.026117] [068/133] (760s left) kms_big_fb (linear-64bpp-rotate-270)
[250.122851] Starting subtest: linear-64bpp-rotate-270
[250.176974] Subtest linear-64bpp-rotate-270: SKIP (0.054s)
[250.275525] [069/133] (760s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[250.362093] Starting subtest: flip-vs-cursor-atomic
[256.787533] Subtest flip-vs-cursor-atomic: SUCCESS (6.425s)
[256.927665] [070/133] (753s left) gem_exec_schedule (semaphore-power)
[257.096686] Starting subtest: semaphore-power
[258.156319] Subtest semaphore-power: SUCCESS (1.049s)
[258.334545] [071/133] (752s left) gem_ctx_persistence (legacy-engines-hang)
[258.372341] Starting subtest: legacy-engines-hang
[258.374177] Starting dynamic subtest: render
[258.416596] Dynamic subtest render: SUCCESS (0.043s)
[258.436316] Starting dynamic subtest: bsd1
[258.460642] Dynamic subtest bsd1: SUCCESS (0.041s)
[258.477581] Starting dynamic subtest: bsd2
[258.506600] Dynamic subtest bsd2: SUCCESS (0.043s)
[258.524383] Starting dynamic subtest: blt
[258.546558] Dynamic subtest blt: SUCCESS (0.037s)
[258.562953] Starting dynamic subtest: vebox
[258.583565] Dynamic subtest vebox: SUCCESS (0.034s)
[258.584616] Subtest legacy-engines-hang: SUCCESS (0.212s)
[258.661689] [072/133] (752s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[258.749951] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[258.750668] Starting dynamic subtest: pipe-A
[258.882580] Dynamic subtest pipe-A: SUCCESS (0.132s)
[258.898512] Starting dynamic subtest: pipe-B
[259.034944] Dynamic subtest pipe-B: SUCCESS (0.151s)
[259.046976] Starting dynamic subtest: pipe-C
[259.188498] Dynamic subtest pipe-C: SUCCESS (0.152s)
[259.197278] Starting dynamic subtest: pipe-D
[259.349197] Dynamic subtest pipe-D: SUCCESS (0.160s)
[259.349929] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (0.600s)
[259.528791] [073/133] (751s left) syncobj_timeline (multi-wait-for-submit-signaled)
[259.564944] Starting subtest: multi-wait-for-submit-signaled
[259.565655] Subtest multi-wait-for-submit-signaled: SUCCESS (0.001s)
[259.628637] [074/133] (751s left) kms_atomic_interruptible (legacy-dpms)
[259.714664] Starting subtest: legacy-dpms
[259.716416] Starting dynamic subtest: pipe-A-HDMI-A-1
[265.952765] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.236s)
[265.953464] Subtest legacy-dpms: SUCCESS (6.238s)
[266.094067] [075/133] (744s left) syncobj_basic (bad-pad-handle-to-fd)
[266.131749] Starting subtest: bad-pad-handle-to-fd
[266.132465] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[266.219791] [076/133] (744s left) drm_read (short-buffer-nonblock)
[266.331082] Starting subtest: short-buffer-nonblock
[266.331780] Subtest short-buffer-nonblock: SUCCESS (0.000s)
[266.442268] [077/133] (744s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[266.527914] Starting subtest: cursorB-vs-flipB-varying-size
[266.529230] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[266.590243] [078/133] (744s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[266.678740] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[266.680081] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[266.775686] [079/133] (744s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[266.870803] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[267.538366] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (0.667s)
[267.668607] [080/133] (743s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[267.758853] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[269.105236] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (1.346s)
[269.222662] [081/133] (741s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-sf)
[269.315331] Subtest fbc-pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[269.362462] [082/133] (741s left) kms_atomic (crtc-invalid-params)
[269.448917] Starting subtest: crtc-invalid-params
[269.450674] Starting dynamic subtest: pipe-A-HDMI-A-1
[269.571677] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.121s)
[269.572341] Subtest crtc-invalid-params: SUCCESS (0.123s)
[269.705087] [083/133] (741s left) kms_flip (busy-flip)
[269.799785] Starting subtest: busy-flip
[269.802435] Starting dynamic subtest: A-HDMI-A1
[270.475948] Dynamic subtest A-HDMI-A1: SUCCESS (0.673s)
[270.486767] Starting dynamic subtest: B-HDMI-A1
[271.144103] Dynamic subtest B-HDMI-A1: SUCCESS (0.661s)
[271.155457] Starting dynamic subtest: C-HDMI-A1
[271.810055] Dynamic subtest C-HDMI-A1: SUCCESS (0.663s)
[271.822834] Starting dynamic subtest: D-HDMI-A1
[272.475492] Dynamic subtest D-HDMI-A1: SUCCESS (0.662s)
[272.477770] Subtest busy-flip: SUCCESS (2.677s)
[272.619807] [084/133] (738s left) gem_exec_params (cliprects-invalid)
[272.660887] Starting subtest: cliprects-invalid
[272.661649] Subtest cliprects-invalid: SUCCESS (0.000s)
[272.751787] [085/133] (738s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[272.843895] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[273.942725] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (1.099s)
[274.059512] [086/133] (737s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[274.095630] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[274.096373] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[274.172117] [087/133] (737s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt)
[274.266751] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt
[274.268120] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[274.351196] [088/133] (736s left) syncobj_timeline (wait-zero-handles)
[274.385675] Starting subtest: wait-zero-handles
[274.386355] Subtest wait-zero-handles: SUCCESS (0.000s)
[274.444857] [089/133] (736s left) kms_psr (fbc-pr-sprite-plane-onoff)
[274.531985] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[274.572710] [090/133] (736s left) gen9_exec_parse (batch-without-end)
[274.611309] Subtest batch-without-end: SKIP (0.000s)
[274.656640] [091/133] (736s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[274.746729] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[274.748076] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[274.835559] [092/133] (736s left) syncobj_wait (wait-for-submit-snapshot)
[274.870536] Starting subtest: wait-for-submit-snapshot
[274.952766] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[275.027119] [093/133] (736s left) gem_exec_suspend (basic-s0)
[275.070056] Starting subtest: basic-S0
[275.070784] Starting dynamic subtest: smem
[293.034211] Dynamic subtest smem: SUCCESS (2.721s)
[293.034934] Subtest basic-S0: SUCCESS (2.722s)
[301.235304] [094/133] (718s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[301.329018] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[301.330366] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[301.425205] [095/133] (718s left) perf (invalid-open-flags)
[301.509847] Starting subtest: invalid-open-flags
[301.510546] Subtest invalid-open-flags: SUCCESS (0.000s)
[301.546576] [096/133] (718s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[301.635940] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[301.637318] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[301.723235] [097/133] (717s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[301.808956] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[301.810298] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[301.888170] [098/133] (717s left) kms_addfb_basic (unused-modifier)
[301.921021] Starting subtest: unused-modifier
[301.921697] Subtest unused-modifier: SUCCESS (0.000s)
[302.061191] [099/133] (717s left) perf (enable-disable)
[302.135468] Starting subtest: enable-disable
[302.136181] Starting dynamic subtest: 0-rcs0
[304.400794] Dynamic subtest 0-rcs0: SUCCESS (2.337s)
[304.401507] Subtest enable-disable: SUCCESS (2.338s)
[304.435752] [100/133] (715s left) gem_exec_whisper (basic-queues-forked-all)
[304.498315] Starting subtest: basic-queues-forked-all
[307.168197] Subtest basic-queues-forked-all: SUCCESS (2.736s)
[307.349455] [101/133] (712s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[307.389151] Starting subtest: y-tiled-to-vebox-yf-tiled
[307.389826] Starting dynamic subtest: smem
[307.453878] Dynamic subtest smem: SUCCESS (0.067s)
[307.454578] Subtest y-tiled-to-vebox-yf-tiled: SUCCESS (0.068s)
[307.634821] [102/133] (712s left) kms_addfb_basic (bad-pitch-0)
[307.666760] Starting subtest: bad-pitch-0
[307.667448] Subtest bad-pitch-0: SUCCESS (0.000s)
[307.811645] [103/133] (712s left) gem_ctx_engines (execute-one)
[307.852343] Starting subtest: execute-one
[309.072349] Subtest execute-one: SUCCESS (1.232s)
[309.243928] [104/133] (710s left) gem_wait (invalid-buf)
[309.280334] Starting subtest: invalid-buf
[309.281001] Subtest invalid-buf: SUCCESS (0.000s)
[309.490107] [105/133] (710s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[309.577017] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (0.000s)
[309.618010] [106/133] (710s left) kms_pm_dc (dc3co-vpb-simulation)
[310.708743] Starting subtest: dc3co-vpb-simulation
[310.710161] Subtest dc3co-vpb-simulation: SKIP (0.000s)
[310.771321] [107/133] (709s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[310.862483] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[312.063466] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (1.215s)
[312.181834] [108/133] (707s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[312.272160] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[312.273531] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[312.364955] [109/133] (707s left) kms_plane_scaling (invalid-parameters)
[312.458023] Starting subtest: invalid-parameters
[312.458706] Starting dynamic subtest: less-than-1-height-src
[312.475165] Dynamic subtest less-than-1-height-src: SUCCESS (0.016s)
[312.483405] Starting dynamic subtest: less-than-1-width-src
[312.490412] Dynamic subtest less-than-1-width-src: SUCCESS (0.015s)
[312.491086] Subtest invalid-parameters: SUCCESS (0.033s)
[312.597474] [110/133] (707s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[312.693902] Starting subtest: yf-tiled-8bpp-rotate-0
[312.695311] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[312.804163] [111/133] (707s left) kms_psr (psr-cursor-plane-move)
[312.892225] Subtest psr-cursor-plane-move: SKIP (0.000s)
[312.938161] [112/133] (707s left) i915_suspend (basic-s3-without-i915)
[312.971161] Starting subtest: basic-s3-without-i915