[88.034125] Initializing watchdogs
[88.034327]   /dev/watchdog0
[88.075002] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[88.088981] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.096411] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.101382] [001/131] (960s left) gem_exec_whisper (basic-fds-forked)
[88.248074] Starting subtest: basic-fds-forked
[97.615926] Subtest basic-fds-forked: SUCCESS (9.363s)
[97.894888] [002/131] (950s left) syncobj_wait (invalid-signal-zero-handles)
[97.990994] Starting subtest: invalid-signal-zero-handles
[97.992190] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[98.149141] [003/131] (950s left) i915_drm_fdinfo (virtual-busy-hang)
[98.275927] Starting subtest: virtual-busy-hang
[102.470465] Subtest virtual-busy-hang: SUCCESS (4.192s)
[102.631095] [004/131] (946s left) gem_exec_fence (syncobj-timeline-unused-fence)
[103.351254] Starting subtest: syncobj-timeline-unused-fence
[103.357793] Subtest syncobj-timeline-unused-fence: SUCCESS (0.007s)
[103.655070] [FACT gem_exec_fence (syncobj-timeline-unused-fence)] new: kernel.kmod_is_loaded.vgem: true
[103.658516] [005/131] (945s left) gem_ctx_isolation (preservation-reset)
[103.881169] Starting subtest: preservation-reset
[103.883609] Starting dynamic subtest: rcs0
[104.272676] Dynamic subtest rcs0: SUCCESS (0.392s)
[104.292752] Starting dynamic subtest: bcs0
[104.942879] Dynamic subtest bcs0: SUCCESS (0.667s)
[104.962432] Starting dynamic subtest: vcs0
[105.345687] Dynamic subtest vcs0: SUCCESS (0.403s)
[105.364547] Starting dynamic subtest: vecs0
[105.730906] Dynamic subtest vecs0: SUCCESS (0.386s)
[105.737034] Subtest preservation-reset: SUCCESS (1.856s)
[105.926922] [006/131] (943s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[106.121603] Starting subtest: yf-tiled-16bpp-rotate-0
[109.386837] Subtest yf-tiled-16bpp-rotate-0: SUCCESS (3.264s)
[109.768616] [007/131] (939s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[109.939115] Starting subtest: primary-y-tiled-reflect-x-90
[109.945930] Subtest primary-y-tiled-reflect-x-90: SKIP (0.003s)
[110.103990] [008/131] (939s left) kms_plane (plane-position-hole-dpms)
[110.286827] Starting subtest: plane-position-hole-dpms
[111.023580] Starting dynamic subtest: pipe-A-plane-1
[111.846520] Dynamic subtest pipe-A-plane-1: SUCCESS (0.827s)
[111.866549] Starting dynamic subtest: pipe-A-plane-2
[112.660744] Dynamic subtest pipe-A-plane-2: SUCCESS (0.812s)
[112.712634] Starting dynamic subtest: pipe-A-plane-3
[113.628349] Dynamic subtest pipe-A-plane-3: SUCCESS (0.964s)
[113.647495] Starting dynamic subtest: pipe-A-plane-4
[113.650607] Dynamic subtest pipe-A-plane-4: SUCCESS (0.024s)
[114.131843] Starting dynamic subtest: pipe-B-plane-1
[114.900602] Dynamic subtest pipe-B-plane-1: SUCCESS (0.804s)
[114.921047] Starting dynamic subtest: pipe-B-plane-2
[115.714980] Dynamic subtest pipe-B-plane-2: SUCCESS (0.813s)
[115.733540] Starting dynamic subtest: pipe-B-plane-3
[116.524308] Dynamic subtest pipe-B-plane-3: SUCCESS (0.810s)
[116.546590] Starting dynamic subtest: pipe-B-plane-4
[116.550804] Dynamic subtest pipe-B-plane-4: SUCCESS (0.015s)
[116.552312] Subtest plane-position-hole-dpms: SUCCESS (6.260s)
[116.867590] [009/131] (932s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[117.061488] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[117.064164] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[117.232334] [010/131] (932s left) kms_atomic (plane-invalid-params-fence)
[117.406525] Starting subtest: plane-invalid-params-fence
[117.409767] Starting dynamic subtest: pipe-A-HDMI-A-1
[117.901803] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.491s)
[117.904370] Subtest plane-invalid-params-fence: SUCCESS (0.495s)
[118.217211] [011/131] (931s left) syncobj_timeline (single-wait-all-signaled)
[118.322351] Starting subtest: single-wait-all-signaled
[118.326383] Subtest single-wait-all-signaled: SUCCESS (0.002s)
[118.485878] [012/131] (931s left) api_intel_allocator (reopen)
[118.580499] Starting subtest: reopen
[118.586587] Subtest reopen: SUCCESS (0.005s)
[118.756261] [013/131] (930s left) i915_query (query-topology-matches-eu-total)
[118.847730] Starting subtest: query-topology-matches-eu-total
[118.849857] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[119.021274] [014/131] (930s left) kms_psr (psr-cursor-blt)
[119.190080] Subtest psr-cursor-blt: SKIP (0.000s)
[119.293307] [015/131] (930s left) perf (oa-exponents)
[119.536115] Starting subtest: oa-exponents
[119.536983] Starting dynamic subtest: 0-rcs0
[123.264364] Dynamic subtest 0-rcs0: SUCCESS (3.727s)
[123.265615] Subtest oa-exponents: SUCCESS (3.728s)
[123.379370] [016/131] (926s left) kms_color (ctm-0-25)
[123.550769] Starting subtest: ctm-0-25
[123.551657] Starting dynamic subtest: pipe-A-HDMI-A-1
[125.716250] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.163s)
[125.741441] Starting dynamic subtest: pipe-A-HDMI-A-2
[127.597996] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.880s)
[127.654912] Starting dynamic subtest: pipe-B-HDMI-A-1
[129.412052] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.814s)
[129.470208] Starting dynamic subtest: pipe-B-HDMI-A-2
[131.243881] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (1.830s)
[131.292588] Starting dynamic subtest: pipe-C-HDMI-A-1
[133.068086] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.821s)
[133.086499] Starting dynamic subtest: pipe-C-HDMI-A-2
[134.885751] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.815s)
[134.887949] Subtest ctm-0-25: SUCCESS (11.329s)
[135.200835] [017/131] (914s left) gem_exec_reloc (basic-wc-cpu-active)
[135.323122] Starting subtest: basic-wc-cpu-active
[135.458360] Subtest basic-wc-cpu-active: SUCCESS (0.136s)
[135.717684] [018/131] (914s left) vgem_basic (bad-flag)
[135.823903] Starting subtest: bad-flag
[135.824914] Subtest bad-flag: SUCCESS (0.000s)
[135.977700] [019/131] (914s left) syncobj_wait (wait-delayed-signal)
[136.075801] Starting subtest: wait-delayed-signal
[136.183413] Subtest wait-delayed-signal: SUCCESS (0.105s)
[136.357298] [020/131] (913s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf)
[136.547535] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (0.000s)
[136.648218] [021/131] (913s left) kms_tiled_display (basic-test-pattern)
[136.912352] Starting subtest: basic-test-pattern
[138.069059] Subtest basic-test-pattern: FAIL (0.935s)
[138.148493] [022/131] (912s left) fbdev (write)
[138.265625] Starting subtest: write
[139.052572] Subtest write: SUCCESS (0.786s)
[139.226427] [023/131] (911s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[139.366283] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[139.367902] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[139.547305] [024/131] (911s left) kms_psr (pr-cursor-mmap-cpu)
[139.672609] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[139.777751] [025/131] (910s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled)
[139.893983] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled
[139.897620] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[140.171143] [026/131] (910s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[140.301984] Starting subtest: bo-too-small-due-to-tiling
[140.304874] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[140.482389] [027/131] (910s left) gem_mmap_wc (read-write)
[140.582247] Starting subtest: read-write
[140.593592] Subtest read-write: SUCCESS (0.011s)
[140.767576] [028/131] (910s left) gem_ctx_param (invalid-size-get)
[140.860801] Starting subtest: invalid-size-get
[140.862276] Subtest invalid-size-get: SUCCESS (0.000s)
[141.039624] [029/131] (910s left) kms_big_fb (linear-16bpp-rotate-180)
[141.201196] Starting subtest: linear-16bpp-rotate-180
[145.415153] Subtest linear-16bpp-rotate-180: SUCCESS (4.212s)
[145.764375] [030/131] (905s left) gem_exec_schedule (preempt)
[145.893100] Starting subtest: preempt
[145.894420] Starting dynamic subtest: rcs0
[145.941588] Dynamic subtest rcs0: SUCCESS (0.049s)
[145.962253] Starting dynamic subtest: bcs0
[146.027447] Dynamic subtest bcs0: SUCCESS (0.082s)
[146.049074] Starting dynamic subtest: vcs0
[146.084767] Dynamic subtest vcs0: SUCCESS (0.052s)
[146.104509] Starting dynamic subtest: vecs0
[146.134857] Dynamic subtest vecs0: SUCCESS (0.051s)
[146.136699] Subtest preempt: SUCCESS (0.245s)
[146.552624] [031/131] (904s left) i915_fb_tiling (basic-x-tiling)
[146.646720] Starting subtest: basic-x-tiling
[146.648728] Subtest basic-x-tiling: SUCCESS (0.001s)
[146.839829] [032/131] (904s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[146.975952] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[146.980914] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[147.150555] [033/131] (904s left) i915_pm_rc6_residency (rc6-idle)
[147.250949] Starting subtest: rc6-idle
[147.265280] Subtest rc6-idle: SKIP (0.014s)
[147.441624] [034/131] (904s left) gem_mmap (pf-nonblock)
[147.539927] Starting subtest: pf-nonblock
[147.545805] Subtest pf-nonblock: SUCCESS (0.006s)
[147.739235] [035/131] (903s left) kms_pm_rpm (cursor-dpms)
[149.567271] Starting subtest: cursor-dpms
[151.345072] Subtest cursor-dpms: SUCCESS (1.778s)
[151.680359] [036/131] (900s left) gem_wait (busy)
[151.811854] Starting subtest: busy
[151.817472] Starting dynamic subtest: all-engines
[152.335345] Dynamic subtest all-engines: SUCCESS (0.522s)
[152.366575] Starting dynamic subtest: rcs0
[152.865898] Dynamic subtest rcs0: SUCCESS (0.521s)
[152.906332] Starting dynamic subtest: bcs0
[153.405222] Dynamic subtest bcs0: SUCCESS (0.538s)
[153.428653] Starting dynamic subtest: vcs0
[153.920738] Dynamic subtest vcs0: SUCCESS (0.514s)
[153.944709] Starting dynamic subtest: vecs0
[154.440765] Dynamic subtest vecs0: SUCCESS (0.516s)
[154.442492] Subtest busy: SUCCESS (2.630s)
[154.775932] [037/131] (897s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[154.916480] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[154.918338] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[155.080754] [038/131] (896s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[155.236819] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[155.240330] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[155.413838] [039/131] (896s left) i915_suspend (fence-restore-untiled)
[155.516250] Starting subtest: fence-restore-untiled