[89.296129] Initializing watchdogs
[89.296343]   /dev/watchdog0
[89.336627] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[89.351558] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.358859] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.364381] [001/133] (960s left) gem_exec_params (batch-first)
[89.500754] Starting subtest: batch-first
[89.514393] Subtest batch-first: SUCCESS (0.013s)
[89.670338] [002/133] (960s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[89.794569] Starting subtest: y-tiled-ccs-to-y-tiled
[89.797112] Starting dynamic subtest: smem
[90.034681] Dynamic subtest smem: SUCCESS (0.241s)
[90.036373] Subtest y-tiled-ccs-to-y-tiled: SUCCESS (0.244s)
[90.291274] [003/133] (959s left) gem_userptr_blits (map-fixed-invalidate-busy)
[90.424719] Starting subtest: map-fixed-invalidate-busy
[90.427646] Starting dynamic subtest: gtt
[93.917570] Dynamic subtest gtt: SUCCESS (3.492s)
[93.936150] Starting dynamic subtest: wb
[97.227739] Dynamic subtest wb: SUCCESS (3.311s)
[97.247307] Starting dynamic subtest: wc
[100.547604] Dynamic subtest wc: SUCCESS (3.317s)
[100.565529] Starting dynamic subtest: uc
[103.874144] Dynamic subtest uc: SUCCESS (3.323s)
[103.877107] Subtest map-fixed-invalidate-busy: SUCCESS (13.446s)
[104.039729] [004/133] (946s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[104.234139] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.000s)
[104.328701] [005/133] (945s left) gem_mmap_wc (copy)
[104.435185] Starting subtest: copy
[104.651069] Subtest copy: SUCCESS (0.220s)
[104.788239] [006/133] (945s left) gem_exec_schedule (noreorder-corked)
[105.048184] Starting subtest: noreorder-corked
[105.049824] Starting dynamic subtest: rcs0
[106.171432] Dynamic subtest rcs0: SUCCESS (1.121s)
[106.192165] Starting dynamic subtest: bcs0
[107.291101] Dynamic subtest bcs0: SUCCESS (1.115s)
[107.308589] Starting dynamic subtest: vcs0
[108.408196] Dynamic subtest vcs0: SUCCESS (1.120s)
[108.428284] Starting dynamic subtest: vecs0
[109.532119] Dynamic subtest vecs0: SUCCESS (1.121s)
[109.533720] Subtest noreorder-corked: SUCCESS (4.482s)
[109.772686] [007/133] (940s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[109.875558] Starting subtest: invalid-single-wait-all-unsubmitted
[109.878090] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[110.007302] [008/133] (940s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[110.200146] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[113.106190] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (2.905s)
[113.305993] [009/133] (937s left) kms_joiner (basic-ultra-joiner)
[113.499141] Starting subtest: basic-ultra-joiner
[113.503756] Subtest basic-ultra-joiner: SKIP (0.000s)
[113.639341] [010/133] (936s left) kms_prop_blob (invalid-set-prop-any)
[113.743098] Starting subtest: invalid-set-prop-any
[113.744927] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[113.874163] [011/133] (936s left) prime_vgem (basic-read)
[114.098357] Starting subtest: basic-read
[114.164410] Subtest basic-read: SUCCESS (0.066s)
[114.313137] [FACT prime_vgem (basic-read)] new: kernel.kmod_is_loaded.vgem: true
[114.318404] [012/133] (936s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[114.522509] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[114.526700] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[114.666350] [013/133] (936s left) kms_pm_rpm (system-suspend-idle)
[116.613378] Starting subtest: system-suspend-idle
[133.929961] Subtest system-suspend-idle: SUCCESS (2.273s)
[143.340856] [014/133] (916s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[143.557716] Starting subtest: cursor-rapid-movement-512x170
[143.562926] Subtest cursor-rapid-movement-512x170: SKIP (0.003s)
[143.709851] [015/133] (916s left) kms_cdclk (plane-scaling)
[143.812509] Subtest plane-scaling: SKIP (0.000s)
[143.918942] [016/133] (916s left) gem_basic (create-close)
[144.022581] Starting subtest: create-close
[144.025776] Subtest create-close: SUCCESS (0.000s)
[144.180872] [017/133] (916s left) gem_exec_reloc (basic-wc-noreloc)
[144.299177] Starting subtest: basic-wc-noreloc
[144.309322] Subtest basic-wc-noreloc: SUCCESS (0.010s)
[144.543668] [018/133] (915s left) kms_feature_discovery (psr1)
[144.737730] Starting subtest: psr1
[144.740932] Subtest psr1: SKIP (0.000s)
[144.877232] [019/133] (915s left) kms_cursor_crc (cursor-onscreen-64x64)
[145.075515] Starting subtest: cursor-onscreen-64x64
[145.128882] Starting dynamic subtest: pipe-A-HDMI-A-1
[147.818406] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.687s)
[147.941847] Starting dynamic subtest: pipe-C-HDMI-A-1
[150.321739] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.410s)
[150.323421] Subtest cursor-onscreen-64x64: SUCCESS (5.246s)
[150.649251] [020/133] (909s left) gem_pipe_control_store_loop (fresh-buffer)
[150.774351] Starting subtest: fresh-buffer
[152.927990] Subtest fresh-buffer: SUCCESS (2.156s)
[153.085069] [021/133] (907s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[153.285226] Starting subtest: psr-rgb565-draw-mmap-cpu
[153.288842] Subtest psr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[153.430780] [022/133] (907s left) i915_hangman (engine-engine-error)
[153.561698] Starting subtest: engine-engine-error
[153.563441] Starting dynamic subtest: rcs0
[153.647502] Dynamic subtest rcs0: SUCCESS (0.085s)
[153.673622] Starting dynamic subtest: bcs0
[153.679764] Dynamic subtest bcs0: SKIP (0.003s)
[153.712552] Starting dynamic subtest: vcs0
[153.726200] Dynamic subtest vcs0: SUCCESS (0.071s)
[153.748713] Starting dynamic subtest: vecs0
[153.798206] Dynamic subtest vecs0: SUCCESS (0.072s)
[153.800263] Subtest engine-engine-error: SUCCESS (0.238s)
[154.014916] [023/133] (906s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[154.223212] Starting subtest: yf-tiled-16bpp-rotate-90
[154.569517] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.343s)
[154.835314] [024/133] (906s left) syncobj_basic (bad-fd-to-handle)
[154.946631] Starting subtest: bad-fd-to-handle
[154.948484] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[155.087669] [025/133] (905s left) gem_lmem_swapping (parallel-random)
[155.205215] Subtest parallel-random: SKIP (0.000s)
[155.307779] [026/133] (905s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[155.488607] Starting subtest: primary-yf-tiled-reflect-x-0
[155.493549] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.003s)
[155.635580] [027/133] (905s left) kms_psr (fbc-pr-primary-mmap-gtt)
[155.817538] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[155.926246] [028/133] (905s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[156.108611] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[156.110612] Starting dynamic subtest: pipe-A-HDMI-A-1
[156.135799] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[156.180490] Starting dynamic subtest: pipe-A-HDMI-A-2
[156.225506] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s)
[156.266400] Starting dynamic subtest: pipe-B-HDMI-A-1
[156.289664] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[156.334665] Starting dynamic subtest: pipe-B-HDMI-A-2
[156.350037] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s)
[156.369354] Starting dynamic subtest: pipe-C-HDMI-A-1
[156.384291] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[156.403688] Starting dynamic subtest: pipe-C-HDMI-A-2
[156.418775] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[156.421874] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SKIP (0.021s)
[157.043572] [029/133] (904s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[157.239673] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[160.353003] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (3.110s)
[160.629323] [030/133] (900s left) gen9_exec_parse (allowed-single)
[160.754350] Starting subtest: allowed-single
[160.765638] Subtest allowed-single: SUCCESS (0.009s)
[161.029829] [031/133] (900s left) kms_flip (2x-flip-vs-suspend)
[161.207893] Starting subtest: 2x-flip-vs-suspend
[161.217506] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2