[90.155808] Initializing watchdogs
[90.155980]   /dev/watchdog0
[90.195166] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[90.209636] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[90.217574] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[90.223304] [001/133] (960s left) kms_chamelium_hpd (dp-hpd-fast)
[90.835164] Subtest dp-hpd-fast: SKIP (0.000s)
[91.166974] [002/133] (959s left) gem_render_copy (y-tiled)
[91.292228] Starting subtest: y-tiled
[91.295134] Starting dynamic subtest: smem
[91.523509] Dynamic subtest smem: SUCCESS (0.235s)
[91.525655] Subtest y-tiled: SUCCESS (0.236s)
[91.777545] [003/133] (959s left) syncobj_timeline (host-signal-ordered)
[91.884920] Starting subtest: host-signal-ordered
[91.888247] Subtest host-signal-ordered: SUCCESS (0.003s)
[92.022769] [004/133] (958s left) kms_prop_blob (blob-prop-lifetime)
[92.120266] Starting subtest: blob-prop-lifetime
[92.123771] Subtest blob-prop-lifetime: SUCCESS (0.002s)
[92.261311] [005/133] (958s left) kms_frontbuffer_tracking (psr-2p-rte)
[92.460709] Starting subtest: psr-2p-rte
[92.464714] Subtest psr-2p-rte: SKIP (0.000s)
[92.588865] [006/133] (958s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[92.763057] Starting subtest: basic-modeset-with-all-modifiers-formats
[93.380226] Starting dynamic subtest: pipe-A-HDMI-A-1-yf-RGB565
[93.733492] Dynamic subtest pipe-A-HDMI-A-1-yf-RGB565: SUCCESS (0.356s)
[93.749635] Starting dynamic subtest: pipe-A-HDMI-A-1-yf-XRGB8888
[93.778776] Dynamic subtest pipe-A-HDMI-A-1-yf-XRGB8888: SUCCESS (0.048s)
[93.796469] Starting dynamic subtest: pipe-A-HDMI-A-1-yf-XRGB2101010
[93.829997] Dynamic subtest pipe-A-HDMI-A-1-yf-XRGB2101010: SUCCESS (0.049s)
[93.845997] Starting dynamic subtest: pipe-A-HDMI-A-1-yf-YUYV
[93.879753] Dynamic subtest pipe-A-HDMI-A-1-yf-YUYV: SUCCESS (0.049s)
[93.896038] Starting dynamic subtest: pipe-A-HDMI-A-1-yf-XYUV8888
[93.930695] Dynamic subtest pipe-A-HDMI-A-1-yf-XYUV8888: SUCCESS (0.050s)
[93.954208] Starting dynamic subtest: pipe-A-HDMI-A-1-y-RGB565
[94.193871] Dynamic subtest pipe-A-HDMI-A-1-y-RGB565: SUCCESS (0.266s)
[94.214506] Starting dynamic subtest: pipe-A-HDMI-A-1-y-XRGB8888
[94.258827] Dynamic subtest pipe-A-HDMI-A-1-y-XRGB8888: SUCCESS (0.065s)
[94.277504] Starting dynamic subtest: pipe-A-HDMI-A-1-y-XRGB2101010
[94.311297] Dynamic subtest pipe-A-HDMI-A-1-y-XRGB2101010: SUCCESS (0.049s)
[94.327838] Starting dynamic subtest: pipe-A-HDMI-A-1-y-XRGB16161616F
[94.358581] Dynamic subtest pipe-A-HDMI-A-1-y-XRGB16161616F: SUCCESS (0.049s)
[94.375610] Starting dynamic subtest: pipe-A-HDMI-A-1-y-YUYV
[94.412433] Dynamic subtest pipe-A-HDMI-A-1-y-YUYV: SUCCESS (0.048s)
[94.429599] Starting dynamic subtest: pipe-A-HDMI-A-1-y-XYUV8888
[94.459680] Dynamic subtest pipe-A-HDMI-A-1-y-XYUV8888: SUCCESS (0.048s)
[94.476098] Starting dynamic subtest: pipe-A-HDMI-A-1-x-RGB565
[94.577367] Dynamic subtest pipe-A-HDMI-A-1-x-RGB565: SUCCESS (0.114s)
[94.593824] Starting dynamic subtest: pipe-A-HDMI-A-1-x-XRGB8888
[94.627497] Dynamic subtest pipe-A-HDMI-A-1-x-XRGB8888: SUCCESS (0.047s)
[94.644763] Starting dynamic subtest: pipe-A-HDMI-A-1-x-XRGB2101010
[94.676605] Dynamic subtest pipe-A-HDMI-A-1-x-XRGB2101010: SUCCESS (0.048s)
[94.694470] Starting dynamic subtest: pipe-A-HDMI-A-1-x-XRGB16161616F
[94.723620] Dynamic subtest pipe-A-HDMI-A-1-x-XRGB16161616F: SUCCESS (0.049s)
[94.740712] Starting dynamic subtest: pipe-A-HDMI-A-1-x-YUYV
[94.774764] Dynamic subtest pipe-A-HDMI-A-1-x-YUYV: SUCCESS (0.048s)
[94.791570] Starting dynamic subtest: pipe-A-HDMI-A-1-x-XYUV8888
[94.823677] Dynamic subtest pipe-A-HDMI-A-1-x-XYUV8888: SUCCESS (0.047s)
[95.272879] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.522s)
[95.672030] [007/133] (955s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[95.869790] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[95.875205] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[96.006165] [008/133] (955s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[96.110339] Starting subtest: invalid-signal-one-illegal-handle
[96.113268] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[96.240372] [009/133] (955s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[96.432272] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[98.866476] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (2.442s)
[99.192746] [010/133] (952s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[99.315650] Starting subtest: y-tiled-ccs-to-yf-tiled
[99.317416] Starting dynamic subtest: smem
[99.642803] Dynamic subtest smem: SUCCESS (0.330s)
[99.644561] Subtest y-tiled-ccs-to-yf-tiled: SUCCESS (0.331s)
[99.896355] [011/133] (951s left) kms_cursor_crc (cursor-onscreen-max-size)
[100.093379] Starting subtest: cursor-onscreen-max-size
[100.102103] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[100.223881] [012/133] (951s left) gem_partial_pwrite_pread (write)
[100.350485] Starting subtest: write
[114.746228] Subtest write: SUCCESS (14.422s)
[114.904361] [013/133] (936s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[115.089056] Starting subtest: cursor-vs-flip-atomic
[155.065739] Subtest cursor-vs-flip-atomic: SUCCESS (40.029s)
[155.373924] [014/133] (896s left) kms_big_fb (y-tiled-addfb-size-overflow)
[155.590213] Starting subtest: y-tiled-addfb-size-overflow
[155.591774] Subtest y-tiled-addfb-size-overflow: SUCCESS (0.002s)
[155.719172] [015/133] (896s left) kms_big_fb (x-tiled-64bpp-rotate-180)
[155.920557] Starting subtest: x-tiled-64bpp-rotate-180
[160.634501] Subtest x-tiled-64bpp-rotate-180: SUCCESS (4.714s)
[161.149116] [016/133] (890s left) gem_ctx_persistence (heartbeat-hang)
[161.261945] Starting subtest: heartbeat-hang
[162.404532] Subtest heartbeat-hang: SUCCESS (1.141s)
[162.532149] [017/133] (889s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[162.726511] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[165.100264] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SUCCESS (2.372s)
[165.300594] [018/133] (886s left) syncobj_wait (invalid-wait-illegal-handle)
[165.403509] Starting subtest: invalid-wait-illegal-handle
[165.406152] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[165.528143] [019/133] (886s left) gem_exec_fence (basic-await)
[165.779054] Starting subtest: basic-await
[165.780662] Starting dynamic subtest: rcs0
[165.896795] Dynamic subtest rcs0: SUCCESS (0.117s)
[165.918102] Starting dynamic subtest: bcs0
[166.008144] Dynamic subtest bcs0: SUCCESS (0.112s)
[166.027163] Starting dynamic subtest: vcs0
[166.121845] Dynamic subtest vcs0: SUCCESS (0.112s)
[166.148221] Starting dynamic subtest: vecs0
[166.240252] Dynamic subtest vecs0: SUCCESS (0.114s)
[166.242228] Subtest basic-await: SUCCESS (0.462s)
[167.018219] [FACT gem_exec_fence (basic-await)] new: kernel.kmod_is_loaded.vgem: true
[167.022995] [020/133] (885s left) gem_exec_fence (invalid-timeline-fence-array)
[167.697186] Starting subtest: invalid-timeline-fence-array
[167.707186] Subtest invalid-timeline-fence-array: SUCCESS (0.010s)
[167.975221] [021/133] (884s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[168.158441] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[168.161240] Starting dynamic subtest: pipe-A
[168.510212] Dynamic subtest pipe-A: SUCCESS (0.357s)
[168.531461] Starting dynamic subtest: pipe-B
[168.906773] Dynamic subtest pipe-B: SUCCESS (0.396s)
[168.933280] Starting dynamic subtest: pipe-C
[168.987846] Dynamic subtest pipe-C: SUCCESS (0.082s)
[168.990443] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (0.838s)
[169.468928] [022/133] (883s left) kms_chamelium_color (ctm-0-50)
[169.657126] Subtest ctm-0-50: SKIP (0.000s)
[169.756942] [023/133] (882s left) gem_exec_whisper (basic-contexts)
[169.890195] Starting subtest: basic-contexts
[177.011089] Subtest basic-contexts: SUCCESS (7.126s)
[177.264064] [024/133] (875s left) kms_plane_alpha_blend (alpha-opaque-fb)
[177.451153] Starting subtest: alpha-opaque-fb
[177.611054] Starting dynamic subtest: pipe-A-HDMI-A-1
[178.506350] Dynamic subtest pipe-A-HDMI-A-1: FAIL (0.468s)
[178.528154] Starting dynamic subtest: pipe-C-HDMI-A-1
[179.109931] Dynamic subtest pipe-C-HDMI-A-1: FAIL (0.470s)
[179.111450] Subtest alpha-opaque-fb: FAIL (1.108s)
[179.211802] [025/133] (873s left) gem_exec_schedule (preempt-self)
[179.344165] Starting subtest: preempt-self
[179.345714] Starting dynamic subtest: rcs0
[179.380083] Dynamic subtest rcs0: SUCCESS (0.029s)
[179.400091] Starting dynamic subtest: bcs0
[179.415755] Dynamic subtest bcs0: SUCCESS (0.039s)
[179.446080] Starting dynamic subtest: vcs0
[179.448247] Dynamic subtest vcs0: SUCCESS (0.030s)
[179.469283] Starting dynamic subtest: vecs0
[179.482684] Dynamic subtest vecs0: SUCCESS (0.035s)
[179.485054] Subtest preempt-self: SUCCESS (0.138s)
[179.883525] [026/133] (873s left) i915_drm_fdinfo (idle)
[179.993567] Starting subtest: idle
[179.995136] Starting dynamic subtest: rcs0
[180.502724] Dynamic subtest rcs0: SUCCESS (0.509s)
[180.525131] Starting dynamic subtest: bcs0
[181.012327] Dynamic subtest bcs0: SUCCESS (0.507s)
[181.033583] Starting dynamic subtest: vcs0
[181.522351] Dynamic subtest vcs0: SUCCESS (0.509s)
[181.544596] Starting dynamic subtest: vecs0
[182.036474] Dynamic subtest vecs0: SUCCESS (0.509s)
[182.039162] Subtest idle: SUCCESS (2.044s)
[182.181439] [027/133] (870s left) gem_softpin (allocator-basic-reserve)
[182.294434] Starting subtest: allocator-basic-reserve
[182.535303] Subtest allocator-basic-reserve: SUCCESS (0.230s)
[182.760105] [028/133] (870s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[182.967571] Starting subtest: 4-tiled-32bpp-rotate-270
[182.970848] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.001s)
[183.110413] [029/133] (870s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[183.307323] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[183.419971] [030/133] (869s left) syncobj_basic (bad-pad-handle-to-fd)
[183.525318] Starting subtest: bad-pad-handle-to-fd
[183.527086] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[183.661146] [031/133] (869s left) kms_big_fb (linear-32bpp-rotate-180)
[183.861188] Starting subtest: linear-32bpp-rotate-180
[188.628424] Subtest linear-32bpp-rotate-180: SUCCESS (4.770s)
[189.042251] [032/133] (864s left) gem_eio (in-flight-internal-10ms)
[189.183817] Starting subtest: in-flight-internal-10ms
[189.310268] Subtest in-flight-internal-10ms: SUCCESS (0.132s)
[189.569085] [033/133] (864s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[189.757332] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[189.864921] [034/133] (863s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled)
[189.971147] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[189.975325] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[190.156608] [035/133] (863s left) i915_query (query-topology-matches-eu-total)
[190.259213] Starting subtest: query-topology-matches-eu-total
[190.262161] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[190.424612] [036/133] (863s left) kms_plane (plane-position-hole-dpms)
[190.604080] Starting subtest: plane-position-hole-dpms
[191.358079] Starting dynamic subtest: pipe-A-plane-1
[192.317350] Dynamic subtest pipe-A-plane-1: SUCCESS (0.954s)
[192.362632] Starting dynamic subtest: pipe-A-plane-2
[193.166889] Dynamic subtest pipe-A-plane-2: SUCCESS (0.852s)
[193.188996] Starting dynamic subtest: pipe-A-plane-3
[194.107133] Dynamic subtest pipe-A-plane-3: SUCCESS (0.940s)
[194.153434] Starting dynamic subtest: pipe-A-plane-4
[194.165456] Dynamic subtest pipe-A-plane-4: SUCCESS (0.008s)
[194.616141] Starting dynamic subtest: pipe-B-plane-1
[195.429186] Dynamic subtest pipe-B-plane-1: SUCCESS (0.858s)
[195.474441] Starting dynamic subtest: pipe-B-plane-2
[196.253225] Dynamic subtest pipe-B-plane-2: SUCCESS (0.823s)
[196.278103] Starting dynamic subtest: pipe-B-plane-3
[197.139237] Dynamic subtest pipe-B-plane-3: SUCCESS (0.882s)
[197.162527] Starting dynamic subtest: pipe-B-plane-4
[197.167943] Dynamic subtest pipe-B-plane-4: SUCCESS (0.011s)
[197.170230] Subtest plane-position-hole-dpms: SUCCESS (6.559s)
[197.471093] [037/133] (856s left) api_intel_allocator (alloc-simple)
[197.590267] Starting subtest: alloc-simple
[197.593669] Subtest alloc-simple: SUCCESS (0.005s)
[197.735064] [038/133] (856s left) perf (low-oa-exponent-permissions)
[197.957089] Starting subtest: low-oa-exponent-permissions
[198.052559] Subtest low-oa-exponent-permissions: SUCCESS (0.104s)
[198.172531] [039/133] (856s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[198.384964] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[198.389411] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[198.537366] [040/133] (855s left) gem_softpin (invalid)
[198.653475] Starting subtest: invalid
[198.667519] Subtest invalid: SUCCESS (0.013s)
[198.838468] [041/133] (855s left) gem_flink_basic (bad-open)
[198.941212] Starting subtest: bad-open
[198.943140] Subtest bad-open: SUCCESS (0.000s)
[199.131556] [042/133] (855s left) kms_chamelium_edid (dp-edid-stress-resolution-non-4k)
[199.731167] Subtest dp-edid-stress-resolution-non-4k: SKIP (0.000s)
[200.049039] [043/133] (854s left) gem_tiled_fence_blits (basic)
[201.070859] Starting subtest: basic
[201.100900] Subtest basic: SUCCESS (0.034s)
[201.288397] [044/133] (853s left) kms_content_protection (srm)
[201.647823] Starting subtest: srm
[201.674411] Subtest srm: SKIP (0.020s)
[201.817444] [045/133] (853s left) kms_psr (pr-primary-blt)
[202.011065] Subtest pr-primary-blt: SKIP (0.000s)
[202.120290] [046/133] (852s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[202.308988] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[202.312277] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SKIP (0.000s)
[202.463309] [047/133] (852s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[202.674054] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[202.677682] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[202.859371] [048/133] (852s left) gem_exec_schedule (preempt-queue-chain)
[203.022218] Starting subtest: preempt-queue-chain
[203.024372] Starting dynamic subtest: rcs0
[203.371108] Dynamic subtest rcs0: SUCCESS (0.348s)
[203.401012] Starting dynamic subtest: bcs0
[203.709827] Dynamic subtest bcs0: SUCCESS (0.337s)
[203.733704] Starting dynamic subtest: vcs0
[204.077085] Dynamic subtest vcs0: SUCCESS (0.359s)
[204.100088] Starting dynamic subtest: vecs0
[204.432206] Dynamic subtest vecs0: SUCCESS (0.354s)
[204.436961] Subtest preempt-queue-chain: SUCCESS (1.415s)
[204.832904] [049/133] (850s left) kms_pm_rpm (system-suspend-modeset)
[206.788506] Starting subtest: system-suspend-modeset