[91.611780] Initializing watchdogs
[91.611959]   /dev/watchdog0
[91.653433] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[91.668648] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.675563] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[91.683884] [001/133] (960s left) fbdev (info)
[91.803414] Starting subtest: info
[91.807496] Subtest info: SUCCESS (0.000s)
[91.980156] [002/133] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[92.192160] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[92.199333] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[92.336707] [003/133] (960s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[92.545931] Starting subtest: yf-tiled-8bpp-rotate-180
[92.553429] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[92.700308] [004/133] (959s left) vgem_basic (unload)
[92.810658] Starting subtest: unload
[121.843226] Subtest unload: SUCCESS (29.024s)
[122.075931] [FACT vgem_basic (unload)] new: kernel.kmod_is_loaded.vgem: true
[122.082392] [005/133] (930s left) api_intel_bb (intel-bb-blit-none)
[122.196226] Starting subtest: intel-bb-blit-none
[125.921816] Subtest intel-bb-blit-none: SUCCESS (3.725s)
[126.117811] [006/133] (926s left) kms_psr (pr-dpms)
[126.302056] Subtest pr-dpms: SKIP (0.000s)
[126.416124] [007/133] (926s left) syncobj_timeline (etime-multi-wait-all-submitted)
[126.527406] Starting subtest: etime-multi-wait-all-submitted
[126.639432] Subtest etime-multi-wait-all-submitted: SUCCESS (0.109s)
[126.785329] [008/133] (926s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[126.991136] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[126.998384] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.001s)
[127.137200] [009/133] (925s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[127.367067] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[127.377087] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[127.507984] [010/133] (925s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[127.715791] Starting subtest: plane-primary-overlay-mutable-zpos
[128.805282] Subtest plane-primary-overlay-mutable-zpos: SKIP (1.076s)
[129.334454] [011/133] (923s left) sysfs_timeslice_duration (timeout)
[129.465141] Starting subtest: timeout
[129.468875] Starting dynamic subtest: vecs0
[130.329235] Dynamic subtest vecs0: SUCCESS (0.861s)
[130.357115] Starting dynamic subtest: vcs0
[131.168154] Dynamic subtest vcs0: SUCCESS (0.836s)
[131.191775] Starting dynamic subtest: bcs0
[132.020934] Dynamic subtest bcs0: SUCCESS (0.846s)
[132.044585] Starting dynamic subtest: rcs0
[132.872697] Dynamic subtest rcs0: SUCCESS (0.856s)
[132.876824] Subtest timeout: SUCCESS (3.406s)
[133.020209] [012/133] (920s left) gem_exec_reloc (basic-cpu-read-active)
[133.148997] Starting subtest: basic-cpu-read-active
[133.293693] Subtest basic-cpu-read-active: SUCCESS (0.144s)
[133.521538] [013/133] (919s left) gem_userptr_blits (huge-split)
[133.697165] Starting subtest: huge-split
[133.708367] Subtest huge-split: SUCCESS (0.011s)
[133.897415] [014/133] (919s left) gem_exec_schedule (submit-late-slice)
[134.041969] Starting subtest: submit-late-slice
[134.045794] Starting dynamic subtest: rcs0
[134.097091] Dynamic subtest rcs0: SUCCESS (0.057s)
[134.120938] Starting dynamic subtest: bcs0
[134.149705] Dynamic subtest bcs0: SUCCESS (0.054s)
[134.180567] Starting dynamic subtest: vcs0
[134.202771] Dynamic subtest vcs0: SUCCESS (0.052s)
[134.228330] Starting dynamic subtest: vecs0
[134.253921] Dynamic subtest vecs0: SUCCESS (0.050s)
[134.258357] Subtest submit-late-slice: SUCCESS (0.217s)
[134.654271] [015/133] (918s left) i915_drm_fdinfo (virtual-idle)
[134.776321] Starting subtest: virtual-idle
[136.807828] Subtest virtual-idle: SUCCESS (2.030s)
[136.952175] [016/133] (916s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[137.163729] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[139.724168] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (2.531s)
[140.110805] [017/133] (913s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[140.319319] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[140.430492] [018/133] (913s left) kms_psr (psr2-sprite-mmap-gtt)
[140.633065] Subtest psr2-sprite-mmap-gtt: SKIP (0.000s)
[140.745727] [019/133] (913s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[140.943076] Starting subtest: cursor-vs-flip-varying-size
[187.854189] Subtest cursor-vs-flip-varying-size: SUCCESS (46.917s)
[188.216626] [020/133] (865s left) gem_exec_async (concurrent-writes)
[188.356144] Starting subtest: concurrent-writes
[188.361138] Starting dynamic subtest: rcs0
[188.376854] Dynamic subtest rcs0: SUCCESS (0.021s)
[188.407361] Starting dynamic subtest: bcs0
[188.410833] Dynamic subtest bcs0: SUCCESS (0.012s)
[188.435089] Starting dynamic subtest: vcs0
[188.438423] Dynamic subtest vcs0: SUCCESS (0.014s)
[188.459186] Starting dynamic subtest: vecs0
[188.463588] Dynamic subtest vecs0: SUCCESS (0.014s)
[188.467577] Subtest concurrent-writes: SUCCESS (0.066s)
[188.699201] [021/133] (865s left) i915_drm_fdinfo (memory-info-resident)
[188.818897] Starting subtest: memory-info-resident
[188.826404] Starting dynamic subtest: smem0
[189.904956] Dynamic subtest smem0: SUCCESS (1.091s)
[189.909492] Subtest memory-info-resident: SUCCESS (1.092s)
[190.046871] [022/133] (864s left) kms_dsc (dsc-with-bpc)
[190.236397] Subtest dsc-with-bpc: SKIP (0.000s)
[190.364368] [023/133] (864s left) gem_ctx_exec (basic)
[190.487217] Starting subtest: basic
[190.492165] Subtest basic: SUCCESS (0.006s)
[190.649293] [024/133] (863s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[190.840258] Starting subtest: 2x-flip-vs-cursor-legacy
[191.725234] Subtest 2x-flip-vs-cursor-legacy: SUCCESS (0.885s)
[192.057441] [025/133] (862s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[192.250050] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[192.467925] Starting dynamic subtest: pipe-A-plane-0
[193.201493] Dynamic subtest pipe-A-plane-0: SUCCESS (0.733s)
[193.221914] Starting dynamic subtest: pipe-A-plane-1
[193.917758] Dynamic subtest pipe-A-plane-1: SUCCESS (0.716s)
[193.941545] Starting dynamic subtest: pipe-A-plane-2
[194.366147] Dynamic subtest pipe-A-plane-2: SUCCESS (0.449s)
[194.390979] Starting dynamic subtest: pipe-A-plane-3
[194.817175] Dynamic subtest pipe-A-plane-3: SUCCESS (0.449s)
[195.264459] Starting dynamic subtest: pipe-B-plane-0
[195.930829] Dynamic subtest pipe-B-plane-0: SUCCESS (0.683s)
[195.951927] Starting dynamic subtest: pipe-B-plane-1
[196.630928] Dynamic subtest pipe-B-plane-1: SUCCESS (0.699s)
[196.651272] Starting dynamic subtest: pipe-B-plane-2
[197.081229] Dynamic subtest pipe-B-plane-2: SUCCESS (0.449s)
[197.106602] Starting dynamic subtest: pipe-B-plane-3
[197.530410] Dynamic subtest pipe-B-plane-3: SUCCESS (0.449s)
[197.763491] Subtest pixel-format-yf-tiled-modifier-source-clamping: SUCCESS (5.512s)
[198.196168] [026/133] (856s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[198.395791] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[198.400153] Starting dynamic subtest: pipe-A
[199.265865] Dynamic subtest pipe-A: SUCCESS (0.872s)
[199.305646] Starting dynamic subtest: pipe-B
[200.161962] Dynamic subtest pipe-B: SUCCESS (0.897s)
[200.203131] Starting dynamic subtest: pipe-C
[201.041144] Dynamic subtest pipe-C: SUCCESS (0.877s)
[201.045190] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (2.649s)
[201.622441] [027/133] (853s left) i915_suspend (debugfs-reader)
[201.741651] Starting subtest: debugfs-reader