[86.943530] Initializing watchdogs
[86.943701]   /dev/watchdog0
[86.983968] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[86.998626] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[87.005604] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[87.013884] [001/133] (960s left) fbdev (info)
[87.135283] Starting subtest: info
[87.139445] Subtest info: SUCCESS (0.000s)
[87.299102] [002/133] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[87.503168] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[87.510338] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[87.652666] [003/133] (960s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[87.869124] Starting subtest: yf-tiled-8bpp-rotate-180
[87.876639] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[88.018405] [004/133] (959s left) vgem_basic (unload)
[88.124808] Starting subtest: unload
[117.144712] Subtest unload: SUCCESS (29.026s)
[117.352669] [FACT vgem_basic (unload)] new: kernel.kmod_is_loaded.vgem: true
[117.361244] [005/133] (930s left) api_intel_bb (intel-bb-blit-none)
[117.475169] Starting subtest: intel-bb-blit-none
[121.200437] Subtest intel-bb-blit-none: SUCCESS (3.722s)
[121.361811] [006/133] (926s left) kms_psr (pr-dpms)
[121.553235] Subtest pr-dpms: SKIP (0.000s)
[121.671674] [007/133] (926s left) syncobj_timeline (etime-multi-wait-all-submitted)
[121.785097] Starting subtest: etime-multi-wait-all-submitted
[121.895131] Subtest etime-multi-wait-all-submitted: SUCCESS (0.108s)
[122.058673] [008/133] (926s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[122.262071] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[122.269640] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[122.411155] [009/133] (925s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[122.630077] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[122.637539] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[122.779476] [010/133] (925s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[122.971783] Starting subtest: plane-primary-overlay-mutable-zpos
[124.041823] Subtest plane-primary-overlay-mutable-zpos: SKIP (1.065s)
[124.571964] [011/133] (923s left) sysfs_timeslice_duration (timeout)
[124.706715] Starting subtest: timeout
[124.710345] Starting dynamic subtest: vecs0
[125.546653] Dynamic subtest vecs0: SUCCESS (0.836s)
[125.569044] Starting dynamic subtest: vcs0
[126.411430] Dynamic subtest vcs0: SUCCESS (0.868s)
[126.431769] Starting dynamic subtest: bcs0
[127.264608] Dynamic subtest bcs0: SUCCESS (0.850s)
[127.287486] Starting dynamic subtest: rcs0
[128.126339] Dynamic subtest rcs0: SUCCESS (0.861s)
[128.130263] Subtest timeout: SUCCESS (3.421s)
[128.271753] [012/133] (920s left) gem_exec_reloc (basic-cpu-read-active)
[128.388786] Starting subtest: basic-cpu-read-active
[128.535162] Subtest basic-cpu-read-active: SUCCESS (0.144s)
[128.756751] [013/133] (919s left) gem_userptr_blits (huge-split)
[128.917319] Starting subtest: huge-split
[128.925709] Subtest huge-split: SUCCESS (0.010s)
[129.091457] [014/133] (919s left) gem_exec_schedule (submit-late-slice)
[129.237876] Starting subtest: submit-late-slice
[129.243276] Starting dynamic subtest: rcs0
[129.287281] Dynamic subtest rcs0: SUCCESS (0.052s)
[129.313252] Starting dynamic subtest: bcs0
[129.336490] Dynamic subtest bcs0: SUCCESS (0.049s)
[129.360936] Starting dynamic subtest: vcs0
[129.381703] Dynamic subtest vcs0: SUCCESS (0.045s)
[129.409249] Starting dynamic subtest: vecs0
[129.432893] Dynamic subtest vecs0: SUCCESS (0.048s)
[129.436876] Subtest submit-late-slice: SUCCESS (0.199s)
[129.821299] [015/133] (919s left) i915_drm_fdinfo (virtual-idle)
[129.932290] Starting subtest: virtual-idle
[131.963943] Subtest virtual-idle: SUCCESS (2.029s)
[132.105463] [016/133] (916s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[132.314225] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[134.823902] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (2.510s)
[135.123848] [017/133] (913s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[135.322092] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[135.433485] [018/133] (913s left) kms_psr (psr2-sprite-mmap-gtt)
[135.632621] Subtest psr2-sprite-mmap-gtt: SKIP (0.000s)
[135.746560] [019/133] (913s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[135.941085] Starting subtest: cursor-vs-flip-varying-size
[181.813952] Subtest cursor-vs-flip-varying-size: SUCCESS (45.871s)
[182.143797] [020/133] (867s left) gem_exec_async (concurrent-writes)
[182.273895] Starting subtest: concurrent-writes
[182.277381] Starting dynamic subtest: rcs0
[182.292565] Dynamic subtest rcs0: SUCCESS (0.017s)
[182.323360] Starting dynamic subtest: bcs0
[182.326924] Dynamic subtest bcs0: SUCCESS (0.013s)
[182.347948] Starting dynamic subtest: vcs0
[182.352084] Dynamic subtest vcs0: SUCCESS (0.011s)
[182.372606] Starting dynamic subtest: vecs0
[182.376592] Dynamic subtest vecs0: SUCCESS (0.012s)
[182.380525] Subtest concurrent-writes: SUCCESS (0.056s)
[182.623059] [021/133] (866s left) i915_drm_fdinfo (memory-info-resident)
[182.738591] Starting subtest: memory-info-resident
[182.745207] Starting dynamic subtest: smem0
[183.867636] Dynamic subtest smem0: SUCCESS (1.129s)
[183.871854] Subtest memory-info-resident: SUCCESS (1.130s)
[184.004894] [022/133] (865s left) kms_dsc (dsc-with-bpc)
[184.188027] Subtest dsc-with-bpc: SKIP (0.000s)
[184.300578] [023/133] (865s left) gem_ctx_exec (basic)
[184.420585] Starting subtest: basic
[184.425558] Subtest basic: SUCCESS (0.006s)
[184.590754] [024/133] (865s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[184.836163] Starting subtest: 2x-flip-vs-cursor-legacy
[185.718414] Subtest 2x-flip-vs-cursor-legacy: SUCCESS (0.883s)
[186.057091] [025/133] (863s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[186.256048] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[186.488249] Starting dynamic subtest: pipe-A-plane-0
[187.202666] Dynamic subtest pipe-A-plane-0: SUCCESS (0.716s)
[187.224488] Starting dynamic subtest: pipe-A-plane-1
[187.903312] Dynamic subtest pipe-A-plane-1: SUCCESS (0.699s)
[187.923488] Starting dynamic subtest: pipe-A-plane-2
[188.354186] Dynamic subtest pipe-A-plane-2: SUCCESS (0.449s)
[188.374635] Starting dynamic subtest: pipe-A-plane-3
[188.803376] Dynamic subtest pipe-A-plane-3: SUCCESS (0.449s)
[189.214189] Starting dynamic subtest: pipe-B-plane-0
[189.866693] Dynamic subtest pipe-B-plane-0: SUCCESS (0.667s)
[189.888234] Starting dynamic subtest: pipe-B-plane-1
[190.532150] Dynamic subtest pipe-B-plane-1: SUCCESS (0.666s)
[190.552509] Starting dynamic subtest: pipe-B-plane-2
[190.981811] Dynamic subtest pipe-B-plane-2: SUCCESS (0.449s)
[191.002160] Starting dynamic subtest: pipe-B-plane-3
[191.430203] Dynamic subtest pipe-B-plane-3: SUCCESS (0.449s)
[191.658997] Subtest pixel-format-yf-tiled-modifier-source-clamping: SUCCESS (5.404s)
[191.986114] [026/133] (858s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[192.176828] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[192.181967] Starting dynamic subtest: pipe-A
[193.015363] Dynamic subtest pipe-A: SUCCESS (0.838s)
[193.057292] Starting dynamic subtest: pipe-B
[193.915194] Dynamic subtest pipe-B: SUCCESS (0.899s)
[193.956482] Starting dynamic subtest: pipe-C
[194.794269] Dynamic subtest pipe-C: SUCCESS (0.878s)
[194.798454] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (2.618s)
[195.379460] [027/133] (854s left) i915_suspend (debugfs-reader)
[195.498252] Starting subtest: debugfs-reader