[89.207106] Initializing watchdogs
[89.207406]   /dev/watchdog0
[89.248758] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[89.263856] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.270948] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.276562] [001/133] (960s left) syncobj_timeline (etime-single-wait-all-submitted)
[89.407378] Starting subtest: etime-single-wait-all-submitted
[89.521720] Subtest etime-single-wait-all-submitted: SUCCESS (0.109s)
[89.658241] [002/133] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[89.866535] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[92.031494] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SUCCESS (2.167s)
[92.240346] [003/133] (957s left) kms_vblank (ts-continuation-dpms-rpm)
[92.429225] Starting subtest: ts-continuation-dpms-rpm
[92.431217] Starting dynamic subtest: pipe-A-HDMI-A-1
[94.733072] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.301s)
[94.752083] Starting dynamic subtest: pipe-A-HDMI-A-2
[95.776997] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.042s)
[95.809644] Starting dynamic subtest: pipe-C-HDMI-A-1
[96.898485] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.103s)
[96.924873] Starting dynamic subtest: pipe-C-HDMI-A-2
[97.760690] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.859s)
[97.765408] Subtest ts-continuation-dpms-rpm: SUCCESS (5.338s)
[98.083584] [004/133] (951s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[98.273082] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[98.275096] Starting dynamic subtest: pipe-A
[99.024105] Dynamic subtest pipe-A: SUCCESS (0.751s)
[99.064354] Starting dynamic subtest: pipe-B
[99.923186] Dynamic subtest pipe-B: SUCCESS (0.898s)
[99.960754] Starting dynamic subtest: pipe-C
[100.838068] Dynamic subtest pipe-C: SUCCESS (0.914s)
[100.839286] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (2.565s)
[101.415587] [005/133] (948s left) kms_psr (fbc-pr-cursor-blt)
[101.604222] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[101.705453] [006/133] (948s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[101.911371] Starting subtest: 4-tiled-64bpp-rotate-270
[101.916096] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.000s)
[102.034350] [007/133] (948s left) gem_exec_fence (syncobj-timeline-export)
[102.827216] Starting subtest: syncobj-timeline-export
[102.832270] Subtest syncobj-timeline-export: SUCCESS (0.008s)
[103.095527] [FACT gem_exec_fence (syncobj-timeline-export)] new: kernel.kmod_is_loaded.vgem: true
[103.100522] [008/133] (947s left) i915_pm_rc6_residency (rc6-fence)
[103.203072] Starting subtest: rc6-fence
[103.214052] Starting dynamic subtest: gt0
[118.457615] Dynamic subtest gt0: SUCCESS (15.243s)
[118.460072] Subtest rc6-fence: SUCCESS (15.259s)
[118.616204] [009/133] (931s left) gem_exec_schedule (fairslice-all)
[118.751683] Starting subtest: fairslice-all
[120.873651] Subtest fairslice-all: SUCCESS (2.121s)
[121.265382] [010/133] (929s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[121.469138] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[123.959019] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (2.490s)
[124.133212] [011/133] (926s left) perf_pmu (gt-awake)
[124.252628] Starting subtest: gt-awake
[126.818932] Subtest gt-awake: SUCCESS (2.568s)
[126.962685] [012/133] (923s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[127.160103] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[127.163185] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[127.313725] [013/133] (923s left) syncobj_wait (wait-all-interrupted)
[127.420516] Starting subtest: wait-all-interrupted
[127.528109] Subtest wait-all-interrupted: SUCCESS (0.105s)
[127.662906] [014/133] (923s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[127.858203] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[130.340666] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (2.479s)
[130.633075] [015/133] (920s left) gem_exec_reloc (basic-write-cpu-active)
[130.756973] Starting subtest: basic-write-cpu-active
[130.903801] Subtest basic-write-cpu-active: SUCCESS (0.145s)
[131.131712] [016/133] (919s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[131.346950] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[134.155788] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (2.809s)
[134.432899] [017/133] (916s left) api_intel_bb (reset-bb)
[134.538908] Starting subtest: reset-bb
[134.540912] Subtest reset-bb: SUCCESS (0.007s)
[134.700813] [018/133] (916s left) syncobj_timeline (multi-wait-submitted-signaled)
[134.809139] Starting subtest: multi-wait-submitted-signaled
[134.813619] Subtest multi-wait-submitted-signaled: SUCCESS (0.004s)
[134.948181] [019/133] (916s left) gem_exec_schedule (preempt-queue-contexts)
[135.091858] Starting subtest: preempt-queue-contexts
[135.093370] Starting dynamic subtest: rcs0
[135.882632] Dynamic subtest rcs0: SUCCESS (0.786s)
[135.936090] Starting dynamic subtest: bcs0
[136.626131] Dynamic subtest bcs0: SUCCESS (0.751s)
[136.674502] Starting dynamic subtest: vcs0
[137.397990] Dynamic subtest vcs0: SUCCESS (0.762s)
[137.434935] Starting dynamic subtest: vecs0
[138.128949] Dynamic subtest vecs0: SUCCESS (0.731s)
[138.129991] Subtest preempt-queue-contexts: SUCCESS (3.034s)
[138.514924] [020/133] (912s left) i915_suspend (fence-restore-tiled2untiled)
[138.615955] Starting subtest: fence-restore-tiled2untiled