[96.593324] Initializing watchdogs
[96.593557]   /dev/watchdog0
[96.632635] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[96.647404] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[96.655578] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[96.664203] [001/131] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[96.892027] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[99.402876] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SUCCESS (2.516s)
[99.619571] [002/131] (957s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[99.812845] Starting subtest: bo-too-small-due-to-tiling
[99.817597] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[99.984877] [003/131] (957s left) gem_tiled_pread_pwrite
[101.065984] [004/131] (956s left) kms_psr (fbc-pr-dpms)
[101.408676] Subtest fbc-pr-dpms: SKIP (0.000s)
[101.513761] [005/131] (956s left) gen9_exec_parse (batch-zero-length)
[101.664971] Starting subtest: batch-zero-length
[101.669390] Subtest batch-zero-length: SUCCESS (0.003s)
[101.951276] [006/131] (955s left) gem_ctx_param (invalid-get-no-zeromap)
[102.060689] Starting subtest: invalid-get-no-zeromap
[102.064994] Subtest invalid-get-no-zeromap: SUCCESS (0.000s)
[102.218642] [007/131] (955s left) prime_vgem (sync)
[102.469509] Starting subtest: sync
[102.473081] Starting dynamic subtest: rcs0
[102.492058] Dynamic subtest rcs0: SUCCESS (0.020s)
[102.510555] Starting dynamic subtest: vcs0
[102.520962] Dynamic subtest vcs0: SUCCESS (0.027s)
[102.541594] Starting dynamic subtest: vecs0
[102.545762] Dynamic subtest vecs0: SUCCESS (0.022s)
[102.549638] Subtest sync: SUCCESS (0.074s)
[102.754930] [FACT prime_vgem (sync)] new: kernel.kmod_is_loaded.vgem: true
[102.761277] [008/131] (955s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[102.965055] Starting subtest: psr-rgb565-draw-mmap-cpu
[102.972142] Subtest psr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[103.144688] [009/131] (954s left) gem_exec_schedule (timeslicing)
[103.285323] Starting subtest: timeslicing
[103.290511] Starting dynamic subtest: rcs0
[103.295284] Dynamic subtest rcs0: SUCCESS (0.010s)
[103.332867] Starting dynamic subtest: bcs0
[103.336657] Dynamic subtest bcs0: SUCCESS (0.019s)
[103.368614] Starting dynamic subtest: vcs0
[103.372815] Dynamic subtest vcs0: SUCCESS (0.007s)
[103.406352] Starting dynamic subtest: vecs0
[103.410513] Dynamic subtest vecs0: SUCCESS (0.009s)
[103.414428] Subtest timeslicing: SUCCESS (0.050s)
[103.761261] [010/131] (954s left) gem_exec_flush (basic-uc-pro-default)
[103.884788] Starting subtest: basic-uc-pro-default
[109.307234] Subtest basic-uc-pro-default: SUCCESS (5.421s)
[109.595921] [011/131] (948s left) api_intel_allocator (fork-simple-once)
[109.697777] Starting subtest: fork-simple-once
[109.734156] Subtest fork-simple-once: SUCCESS (0.037s)
[109.909388] [012/131] (948s left) gem_spin_batch (resubmit-all)
[110.031045] Starting subtest: resubmit-all
[110.034200] Starting dynamic subtest: rcs0
[110.046188] Dynamic subtest rcs0: SUCCESS (0.015s)
[110.067648] Starting dynamic subtest: bcs0
[110.072119] Dynamic subtest bcs0: SUCCESS (0.009s)
[110.092542] Starting dynamic subtest: vcs0
[110.096554] Dynamic subtest vcs0: SUCCESS (0.010s)
[110.116431] Starting dynamic subtest: vecs0
[110.120592] Dynamic subtest vecs0: SUCCESS (0.010s)
[110.124794] Subtest resubmit-all: SUCCESS (0.050s)
[110.378274] [013/131] (947s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[110.598648] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[110.692401] [014/131] (947s left) kms_content_protection (uevent)
[110.902857] Starting subtest: uevent
[110.946405] Subtest uevent: SKIP (0.034s)
[111.127291] [015/131] (947s left) i915_pm_freq_api (freq-suspend)
[111.249180] Subtest freq-suspend: SKIP (0.000s)
[111.358275] [016/131] (947s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[111.558167] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[113.522909] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SUCCESS (1.969s)
[113.745404] [017/131] (944s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[113.954833] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[115.518174] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.561s)
[115.829129] [018/131] (942s left) sysfs_heartbeat_interval (nopreempt)
[115.949790] Starting subtest: nopreempt
[115.953578] Starting dynamic subtest: vecs0
[119.624758] Dynamic subtest vecs0: SUCCESS (3.670s)
[119.644597] Starting dynamic subtest: vcs0
[123.307200] Dynamic subtest vcs0: SUCCESS (3.681s)
[123.345805] Starting dynamic subtest: bcs0
[126.978389] Dynamic subtest bcs0: SUCCESS (3.671s)
[127.016403] Starting dynamic subtest: rcs0
[130.864976] Dynamic subtest rcs0: SUCCESS (3.884s)
[130.868413] Subtest nopreempt: SUCCESS (14.912s)
[131.047650] [019/131] (927s left) kms_lease (lease-revoke)
[131.233708] Starting subtest: lease-revoke
[131.237942] Starting dynamic subtest: pipe-A-HDMI-A-1
[131.278324] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.044s)
[131.322894] Starting dynamic subtest: pipe-A-HDMI-A-2
[131.354120] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.065s)
[131.393717] Starting dynamic subtest: pipe-B-HDMI-A-1
[131.410241] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.048s)
[131.445721] Starting dynamic subtest: pipe-B-HDMI-A-2
[131.467587] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.056s)
[131.518023] Starting dynamic subtest: pipe-C-HDMI-A-1
[131.539112] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.047s)
[131.594965] Starting dynamic subtest: pipe-C-HDMI-A-2
[131.617311] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.047s)
[131.620846] Subtest lease-revoke: SUCCESS (0.388s)
[131.798415] [020/131] (927s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[132.013518] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[132.020575] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[132.197620] [021/131] (926s left) perf_pmu (interrupts)
[132.319103] Starting subtest: interrupts
[133.442674] Subtest interrupts: SUCCESS (1.115s)
[133.614174] [022/131] (925s left) i915_drm_fdinfo (busy-check-all)
[133.726739] Starting subtest: busy-check-all
[133.730277] Starting dynamic subtest: rcs0
[134.257441] Dynamic subtest rcs0: SUCCESS (0.530s)
[134.279940] Starting dynamic subtest: bcs0
[134.791700] Dynamic subtest bcs0: SUCCESS (0.531s)
[134.814520] Starting dynamic subtest: vcs0
[135.326358] Dynamic subtest vcs0: SUCCESS (0.537s)
[135.348816] Starting dynamic subtest: vecs0
[135.856573] Dynamic subtest vecs0: SUCCESS (0.528s)
[135.861052] Subtest busy-check-all: SUCCESS (2.131s)
[136.029446] [023/131] (923s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[136.227499] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[136.236585] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[136.418176] [024/131] (923s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[136.529453] Starting subtest: multi-wait-all-for-submit-available-signaled
[136.533438] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.002s)
[136.740815] [025/131] (922s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[136.949929] Starting subtest: x-tiled-32bpp-rotate-270
[137.251380] Subtest x-tiled-32bpp-rotate-270: SKIP (0.299s)
[137.535949] [026/131] (922s left) i915_suspend (forcewake)
[137.647951] Starting subtest: forcewake