[110.133459] Initializing watchdogs
[110.133661]   /dev/watchdog0
[110.174398] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[110.189159] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[110.195425] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[110.201222] [001/133] (960s left) syncobj_timeline (etime-single-wait-all-submitted)
[110.324682] Starting subtest: etime-single-wait-all-submitted
[110.436319] Subtest etime-single-wait-all-submitted: SUCCESS (0.105s)
[110.582836] [002/133] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[110.801850] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[112.895453] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SUCCESS (2.094s)
[113.105479] [003/133] (957s left) kms_vblank (ts-continuation-dpms-rpm)
[113.301129] Starting subtest: ts-continuation-dpms-rpm
[113.303264] Starting dynamic subtest: pipe-A-HDMI-A-1
[115.649959] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.348s)
[115.694458] Starting dynamic subtest: pipe-A-HDMI-A-2
[116.654450] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.997s)
[116.683774] Starting dynamic subtest: pipe-C-HDMI-A-1
[117.642140] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.977s)
[117.665778] Starting dynamic subtest: pipe-C-HDMI-A-2
[118.557985] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.905s)
[118.560095] Subtest ts-continuation-dpms-rpm: SUCCESS (5.257s)
[118.865466] [004/133] (952s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[119.059091] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[119.063893] Starting dynamic subtest: pipe-A
[119.835431] Dynamic subtest pipe-A: SUCCESS (0.778s)
[119.875099] Starting dynamic subtest: pipe-B
[120.749155] Dynamic subtest pipe-B: SUCCESS (0.911s)
[120.770415] Starting dynamic subtest: pipe-C
[121.612694] Dynamic subtest pipe-C: SUCCESS (0.863s)
[121.614169] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (2.557s)
[122.141242] [005/133] (948s left) kms_psr (fbc-pr-cursor-blt)
[122.333331] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[122.429546] [006/133] (948s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[122.656244] Starting subtest: 4-tiled-64bpp-rotate-270
[122.659837] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.000s)
[122.791265] [007/133] (948s left) gem_exec_fence (syncobj-timeline-export)
[123.585457] Starting subtest: syncobj-timeline-export
[123.594695] Subtest syncobj-timeline-export: SUCCESS (0.010s)
[123.865064] [FACT gem_exec_fence (syncobj-timeline-export)] new: kernel.kmod_is_loaded.vgem: true
[123.870768] [008/133] (947s left) i915_pm_rc6_residency (rc6-fence)
[124.003134] Starting subtest: rc6-fence
[124.030407] Starting dynamic subtest: gt0
[139.290073] Dynamic subtest gt0: SUCCESS (15.258s)
[139.292378] Subtest rc6-fence: SUCCESS (15.289s)
[139.467676] [009/133] (931s left) gem_exec_schedule (fairslice-all)
[139.602203] Starting subtest: fairslice-all
[141.720535] Subtest fairslice-all: SUCCESS (2.119s)
[142.120875] [010/133] (929s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[142.328773] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[144.820057] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (2.485s)
[145.034385] [011/133] (926s left) perf_pmu (gt-awake)
[145.152837] Starting subtest: gt-awake
[147.714441] Subtest gt-awake: SUCCESS (2.561s)
[147.881968] [012/133] (923s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[148.078080] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[148.082167] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[148.219165] [013/133] (923s left) syncobj_wait (wait-all-interrupted)
[148.329051] Starting subtest: wait-all-interrupted
[148.439487] Subtest wait-all-interrupted: SUCCESS (0.108s)
[148.586424] [014/133] (923s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[148.783330] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[151.295613] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (2.511s)
[151.604149] [015/133] (920s left) gem_exec_reloc (basic-write-cpu-active)
[151.731521] Starting subtest: basic-write-cpu-active
[151.879058] Subtest basic-write-cpu-active: SUCCESS (0.144s)
[152.102922] [016/133] (919s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[152.309324] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[155.144121] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (2.834s)
[155.469407] [017/133] (916s left) api_intel_bb (reset-bb)
[155.573229] Starting subtest: reset-bb
[155.581765] Subtest reset-bb: SUCCESS (0.007s)
[155.736513] [018/133] (916s left) syncobj_timeline (multi-wait-submitted-signaled)
[155.843251] Starting subtest: multi-wait-submitted-signaled
[155.847228] Subtest multi-wait-submitted-signaled: SUCCESS (0.003s)
[155.988460] [019/133] (916s left) gem_exec_schedule (preempt-queue-contexts)
[156.126366] Starting subtest: preempt-queue-contexts
[156.127766] Starting dynamic subtest: rcs0
[156.912146] Dynamic subtest rcs0: SUCCESS (0.780s)
[156.979310] Starting dynamic subtest: bcs0
[157.661128] Dynamic subtest bcs0: SUCCESS (0.755s)
[157.738522] Starting dynamic subtest: vcs0
[158.456184] Dynamic subtest vcs0: SUCCESS (0.783s)
[158.502057] Starting dynamic subtest: vecs0
[159.196594] Dynamic subtest vecs0: SUCCESS (0.753s)
[159.198999] Subtest preempt-queue-contexts: SUCCESS (3.076s)
[159.587288] [020/133] (912s left) i915_suspend (fence-restore-tiled2untiled)
[159.686434] Starting subtest: fence-restore-tiled2untiled