[93.284734] Initializing watchdogs
[93.284936]   /dev/watchdog0
[93.329552] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[93.343428] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[93.349559] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[93.358131] [001/131] (960s left) gem_exec_whisper (basic-fds-forked)
[93.540681] Starting subtest: basic-fds-forked
[106.596286] Subtest basic-fds-forked: SUCCESS (13.061s)
[106.873889] [002/131] (947s left) syncobj_wait (invalid-signal-zero-handles)
[106.979816] Starting subtest: invalid-signal-zero-handles
[106.983299] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[107.139196] [003/131] (946s left) i915_drm_fdinfo (virtual-busy-hang)
[107.243602] Starting subtest: virtual-busy-hang
[111.427985] Subtest virtual-busy-hang: SUCCESS (4.184s)
[111.594597] [004/131] (942s left) gem_exec_fence (syncobj-timeline-unused-fence)
[112.325166] Starting subtest: syncobj-timeline-unused-fence
[112.333983] Subtest syncobj-timeline-unused-fence: SUCCESS (0.006s)
[112.648206] [FACT gem_exec_fence (syncobj-timeline-unused-fence)] new: kernel.kmod_is_loaded.vgem: true
[112.654565] [005/131] (941s left) gem_ctx_isolation (preservation-reset)
[112.881843] Starting subtest: preservation-reset
[112.885484] Starting dynamic subtest: rcs0
[113.282623] Dynamic subtest rcs0: SUCCESS (0.398s)
[113.303085] Starting dynamic subtest: bcs0
[113.962462] Dynamic subtest bcs0: SUCCESS (0.677s)
[113.984152] Starting dynamic subtest: vcs0
[114.379497] Dynamic subtest vcs0: SUCCESS (0.419s)
[114.400819] Starting dynamic subtest: vecs0
[114.788023] Dynamic subtest vecs0: SUCCESS (0.408s)
[114.793664] Subtest preservation-reset: SUCCESS (1.908s)
[114.985177] [006/131] (939s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[115.186660] Starting subtest: yf-tiled-16bpp-rotate-0
[118.607146] Subtest yf-tiled-16bpp-rotate-0: SUCCESS (3.422s)
[118.966837] [007/131] (935s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[119.146492] Starting subtest: primary-y-tiled-reflect-x-90
[119.153196] Subtest primary-y-tiled-reflect-x-90: SKIP (0.003s)
[119.322150] [008/131] (935s left) kms_plane (plane-position-hole-dpms)
[119.501894] Starting subtest: plane-position-hole-dpms
[120.213850] Starting dynamic subtest: pipe-A-plane-1
[121.042791] Dynamic subtest pipe-A-plane-1: SUCCESS (0.828s)
[121.067399] Starting dynamic subtest: pipe-A-plane-2
[121.840057] Dynamic subtest pipe-A-plane-2: SUCCESS (0.799s)
[121.879575] Starting dynamic subtest: pipe-A-plane-3
[122.659172] Dynamic subtest pipe-A-plane-3: SUCCESS (0.818s)
[122.682518] Starting dynamic subtest: pipe-A-plane-4
[122.688984] Dynamic subtest pipe-A-plane-4: SUCCESS (0.014s)
[123.133592] Starting dynamic subtest: pipe-B-plane-1
[123.911107] Dynamic subtest pipe-B-plane-1: SUCCESS (0.800s)
[123.939144] Starting dynamic subtest: pipe-B-plane-2
[124.705251] Dynamic subtest pipe-B-plane-2: SUCCESS (0.793s)
[124.751489] Starting dynamic subtest: pipe-B-plane-3
[125.479996] Dynamic subtest pipe-B-plane-3: SUCCESS (0.773s)
[125.501690] Starting dynamic subtest: pipe-B-plane-4
[125.508208] Dynamic subtest pipe-B-plane-4: SUCCESS (0.008s)
[125.513249] Subtest plane-position-hole-dpms: SUCCESS (5.983s)
[125.821949] [009/131] (928s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[126.027738] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[126.034658] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[126.200747] [010/131] (928s left) kms_atomic (plane-invalid-params-fence)
[126.374896] Starting subtest: plane-invalid-params-fence
[126.378055] Starting dynamic subtest: pipe-A-HDMI-A-1
[126.879946] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.501s)
[126.885325] Subtest plane-invalid-params-fence: SUCCESS (0.505s)
[127.213210] [011/131] (927s left) syncobj_timeline (single-wait-all-signaled)
[127.322772] Starting subtest: single-wait-all-signaled
[127.327196] Subtest single-wait-all-signaled: SUCCESS (0.002s)
[127.493799] [012/131] (927s left) api_intel_allocator (reopen)
[127.595764] Starting subtest: reopen
[127.599203] Subtest reopen: SUCCESS (0.006s)
[127.776995] [013/131] (927s left) i915_query (query-topology-matches-eu-total)
[127.878948] Starting subtest: query-topology-matches-eu-total
[127.882383] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[128.042134] [014/131] (927s left) kms_psr (psr-cursor-blt)
[128.228863] Subtest psr-cursor-blt: SKIP (0.000s)
[128.342441] [015/131] (926s left) perf (oa-exponents)
[128.601856] Starting subtest: oa-exponents
[128.606189] Starting dynamic subtest: 0-rcs0
[132.224766] Dynamic subtest 0-rcs0: SUCCESS (3.626s)
[132.228572] Subtest oa-exponents: SUCCESS (3.627s)
[132.328143] [016/131] (922s left) kms_color (ctm-0-25)
[132.504780] Starting subtest: ctm-0-25
[132.507982] Starting dynamic subtest: pipe-A-HDMI-A-1
[134.577906] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.078s)
[134.616732] Starting dynamic subtest: pipe-A-HDMI-A-2
[136.393025] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.811s)
[136.443696] Starting dynamic subtest: pipe-B-HDMI-A-1
[138.145046] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (1.753s)
[138.200298] Starting dynamic subtest: pipe-B-HDMI-A-2
[139.885056] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (1.739s)
[139.906386] Starting dynamic subtest: pipe-C-HDMI-A-1
[141.680619] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.794s)
[141.701883] Starting dynamic subtest: pipe-C-HDMI-A-2
[143.450258] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.770s)
[143.493206] Subtest ctm-0-25: SUCCESS (10.951s)
[143.768684] [017/131] (911s left) gem_exec_reloc (basic-wc-cpu-active)
[143.892843] Starting subtest: basic-wc-cpu-active
[144.029403] Subtest basic-wc-cpu-active: SUCCESS (0.140s)
[144.285997] [018/131] (911s left) vgem_basic (bad-flag)
[144.401941] Starting subtest: bad-flag
[144.405513] Subtest bad-flag: SUCCESS (0.000s)
[144.549789] [019/131] (911s left) syncobj_wait (wait-delayed-signal)
[144.654848] Starting subtest: wait-delayed-signal
[144.757585] Subtest wait-delayed-signal: SUCCESS (0.105s)
[144.953274] [020/131] (910s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf)
[145.157692] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (0.000s)
[145.254132] [021/131] (910s left) kms_tiled_display (basic-test-pattern)
[145.512561] Starting subtest: basic-test-pattern
[147.233125] Subtest basic-test-pattern: FAIL (0.918s)
[147.328464] [022/131] (908s left) fbdev (write)
[147.457014] Starting subtest: write
[148.247706] Subtest write: SUCCESS (0.785s)
[148.410421] [023/131] (907s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[148.568187] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[148.574660] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[148.750124] [024/131] (907s left) kms_psr (pr-cursor-mmap-cpu)
[148.884956] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[148.993160] [025/131] (907s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled)
[149.119831] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled
[149.126249] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[149.413401] [026/131] (906s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[149.541218] Starting subtest: bo-too-small-due-to-tiling
[149.544246] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[149.731563] [027/131] (906s left) gem_mmap_wc (read-write)
[149.838060] Starting subtest: read-write
[149.849169] Subtest read-write: SUCCESS (0.008s)
[150.028538] [028/131] (906s left) gem_ctx_param (invalid-size-get)
[150.129976] Starting subtest: invalid-size-get
[150.133288] Subtest invalid-size-get: SUCCESS (0.000s)
[150.310143] [029/131] (906s left) kms_big_fb (linear-16bpp-rotate-180)
[150.456680] Starting subtest: linear-16bpp-rotate-180
[154.602338] Subtest linear-16bpp-rotate-180: SUCCESS (4.144s)
[154.995478] [030/131] (901s left) gem_exec_schedule (preempt)
[155.128970] Starting subtest: preempt
[155.132256] Starting dynamic subtest: rcs0
[155.180341] Dynamic subtest rcs0: SUCCESS (0.051s)
[155.203575] Starting dynamic subtest: bcs0
[155.255763] Dynamic subtest bcs0: SUCCESS (0.074s)
[155.277788] Starting dynamic subtest: vcs0
[155.317199] Dynamic subtest vcs0: SUCCESS (0.056s)
[155.339533] Starting dynamic subtest: vecs0
[155.369688] Dynamic subtest vecs0: SUCCESS (0.052s)
[155.374645] Subtest preempt: SUCCESS (0.243s)
[155.796025] [031/131] (901s left) i915_fb_tiling (basic-x-tiling)
[155.899273] Starting subtest: basic-x-tiling
[155.902412] Subtest basic-x-tiling: SUCCESS (0.001s)
[156.077638] [032/131] (900s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[156.224402] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[156.230757] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[156.414191] [033/131] (900s left) i915_pm_rc6_residency (rc6-idle)
[156.521427] Starting subtest: rc6-idle
[156.533629] Subtest rc6-idle: SKIP (0.011s)
[156.711502] [034/131] (900s left) gem_mmap (pf-nonblock)
[156.816182] Starting subtest: pf-nonblock
[156.820609] Subtest pf-nonblock: SUCCESS (0.005s)
[157.005206] [035/131] (900s left) kms_pm_rpm (cursor-dpms)
[158.815615] Starting subtest: cursor-dpms
[160.622254] Subtest cursor-dpms: SUCCESS (1.808s)
[160.964930] [036/131] (896s left) gem_wait (busy)
[161.104420] Starting subtest: busy
[161.107761] Starting dynamic subtest: all-engines
[161.627773] Dynamic subtest all-engines: SUCCESS (0.523s)
[161.653181] Starting dynamic subtest: rcs0
[162.144113] Dynamic subtest rcs0: SUCCESS (0.513s)
[162.165468] Starting dynamic subtest: bcs0
[162.659808] Dynamic subtest bcs0: SUCCESS (0.516s)
[162.681319] Starting dynamic subtest: vcs0
[163.179771] Dynamic subtest vcs0: SUCCESS (0.516s)
[163.201578] Starting dynamic subtest: vecs0
[163.694252] Dynamic subtest vecs0: SUCCESS (0.512s)
[163.697447] Subtest busy: SUCCESS (2.591s)
[164.013523] [037/131] (893s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[164.166594] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[164.173898] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[164.348265] [038/131] (893s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[164.506434] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[164.514328] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[164.681422] [039/131] (892s left) i915_suspend (fence-restore-untiled)
[164.791473] Starting subtest: fence-restore-untiled