[91.861424] Initializing watchdogs
[91.861671]   /dev/watchdog0
[91.903758] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[91.918389] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.925540] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[91.934788] [001/156] (960s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[92.140535] Starting subtest: cursorB-vs-flipA-toggle
[104.196351] Subtest cursorB-vs-flipA-toggle: SUCCESS (11.945s)
[104.808960] [002/156] (948s left) template (a)
[105.277365] Starting subtest: A
[105.393355] Subtest A: SUCCESS (0.000s)
[105.473809] [003/156] (947s left) i915_pm_rps (engine-order)
[105.616233] Starting subtest: engine-order
[116.517464] Subtest engine-order: SUCCESS (10.901s)
[116.716749] [004/156] (936s left) kms_frontbuffer_tracking (fbchdr-farfromfence-mmap-gtt)
[116.927350] Starting subtest: fbchdr-farfromfence-mmap-gtt
[117.761049] Subtest fbchdr-farfromfence-mmap-gtt: SUCCESS (0.836s)
[118.046641] [005/156] (935s left) kms_psr (psr2-sprite-plane-move)
[118.250069] Subtest psr2-sprite-plane-move: SKIP (0.000s)
[118.360577] [006/156] (934s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[118.580138] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-wc
[119.360138] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (0.777s)
[119.663438] [007/156] (933s left) syncobj_eventfd (timeline-wait)
[119.778496] Starting subtest: timeline-wait
[119.788546] Subtest timeline-wait: SUCCESS (0.012s)
[119.930303] [008/156] (933s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[120.121950] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[120.124956] Starting dynamic subtest: pipe-A-HDMI-A-1
[120.164948] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[120.207480] Starting dynamic subtest: pipe-A-HDMI-A-2
[120.260077] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.003s)
[120.287928] Starting dynamic subtest: pipe-B-HDMI-A-1
[120.333768] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.003s)
[120.357813] Starting dynamic subtest: pipe-B-HDMI-A-2
[120.391268] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.002s)
[120.413630] Starting dynamic subtest: pipe-C-HDMI-A-1
[120.445910] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[120.471424] Starting dynamic subtest: pipe-C-HDMI-A-2
[120.503645] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[120.510032] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.030s)
[121.629891] [009/156] (932s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[122.220754] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[122.432632] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[122.520373] [010/156] (931s left) gem_basic (create-fd-close)
[122.635647] Starting subtest: create-fd-close
[122.642772] Subtest create-fd-close: SUCCESS (0.000s)
[122.772969] [011/156] (931s left) gem_ctx_persistence (legacy-engines-cleanup)
[122.895706] Starting subtest: legacy-engines-cleanup
[122.899369] Starting dynamic subtest: render
[122.932092] Dynamic subtest render: SUCCESS (0.031s)
[122.962391] Starting dynamic subtest: bsd
[122.966119] Dynamic subtest bsd: SUCCESS (0.014s)
[122.992471] Starting dynamic subtest: blt
[122.996868] Dynamic subtest blt: SUCCESS (0.040s)
[123.023873] Starting dynamic subtest: vebox
[123.027661] Dynamic subtest vebox: SUCCESS (0.017s)
[123.031666] Subtest legacy-engines-cleanup: SUCCESS (0.115s)
[123.159348] [012/156] (931s left) api_intel_bb (intel-bb-blit-y)
[123.274214] Starting subtest: intel-bb-blit-y
[126.552434] Subtest intel-bb-blit-y: SUCCESS (3.284s)
[126.739488] [013/156] (927s left) kms_dp_link_training (non-uhbr-sst)
[126.934380] Starting subtest: non-uhbr-sst
[126.954861] Subtest non-uhbr-sst: SKIP (0.000s)
[127.078576] [014/156] (927s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[127.275095] Starting subtest: cursorA-vs-flipB-varying-size
[138.754524] Subtest cursorA-vs-flipB-varying-size: SUCCESS (11.360s)
[139.398257] [015/156] (915s left) kms_plane (pixel-format-y-tiled-ccs-modifier)
[139.942987] Starting subtest: pixel-format-y-tiled-ccs-modifier
[139.999471] Starting dynamic subtest: pipe-A-plane-0
[140.316956] Dynamic subtest pipe-A-plane-0: SUCCESS (0.317s)
[140.340617] Starting dynamic subtest: pipe-A-plane-1
[140.598556] Dynamic subtest pipe-A-plane-1: SUCCESS (0.279s)
[140.621246] Starting dynamic subtest: pipe-A-plane-2
[140.864652] Dynamic subtest pipe-A-plane-2: SUCCESS (0.264s)
[140.888463] Starting dynamic subtest: pipe-A-plane-3
[141.131082] Dynamic subtest pipe-A-plane-3: SUCCESS (0.264s)
[141.160592] Starting dynamic subtest: pipe-A-plane-4
[141.170593] Dynamic subtest pipe-A-plane-4: SKIP (0.000s)
[141.527035] Starting dynamic subtest: pipe-B-plane-0
[141.777420] Dynamic subtest pipe-B-plane-0: SUCCESS (0.266s)
[141.801548] Starting dynamic subtest: pipe-B-plane-1
[142.059138] Dynamic subtest pipe-B-plane-1: SUCCESS (0.281s)
[142.082461] Starting dynamic subtest: pipe-B-plane-2
[142.325868] Dynamic subtest pipe-B-plane-2: SUCCESS (0.264s)
[142.349151] Starting dynamic subtest: pipe-B-plane-3
[142.593407] Dynamic subtest pipe-B-plane-3: SUCCESS (0.265s)
[142.629976] Starting dynamic subtest: pipe-B-plane-4
[142.639338] Dynamic subtest pipe-B-plane-4: SKIP (0.000s)
[142.802437] Subtest pixel-format-y-tiled-ccs-modifier: SUCCESS (3.016s)
[143.155509] [016/156] (911s left) dmabuf_sync_file (export-before-signal)
[143.376056] Starting subtest: export-before-signal
[143.380515] Subtest export-before-signal: SUCCESS (0.005s)
[143.542921] [FACT dmabuf_sync_file (export-before-signal)] new: kernel.kmod_is_loaded.vgem: true
[143.550150] [017/156] (911s left) kms_lease (lease-get)
[143.750079] Starting subtest: lease-get
[143.753529] Starting dynamic subtest: pipe-A-HDMI-A-1
[143.758092] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.002s)
[143.811797] Starting dynamic subtest: pipe-A-HDMI-A-2
[143.816718] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.002s)
[143.852123] Starting dynamic subtest: pipe-B-HDMI-A-1
[143.867682] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.006s)
[143.895955] Starting dynamic subtest: pipe-B-HDMI-A-2
[143.899061] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.002s)
[143.922401] Starting dynamic subtest: pipe-C-HDMI-A-1
[143.925634] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.003s)
[143.947849] Starting dynamic subtest: pipe-C-HDMI-A-2
[143.950997] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.002s)
[143.954198] Subtest lease-get: SUCCESS (0.066s)
[144.052934] [018/156] (911s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[144.263971] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[144.270667] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[144.419898] [019/156] (910s left) gem_mmap_wc (coherency)
[144.534625] Starting subtest: coherency
[144.611417] Subtest coherency: SUCCESS (0.078s)
[144.768784] [020/156] (910s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[144.894284] Starting subtest: invalid-bsd2-flag-on-vebox
[144.897552] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.001s)
[145.051404] [021/156] (910s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-indfb-pgflip-blt)
[145.267525] Starting subtest: fbchdr-2p-scndscrn-indfb-pgflip-blt
[146.643548] Subtest fbchdr-2p-scndscrn-indfb-pgflip-blt: SUCCESS (1.279s)
[147.106744] [022/156] (908s left) kms_hdr (bpc-switch-suspend)
[147.313449] Starting subtest: bpc-switch-suspend
[147.317283] Starting dynamic subtest: pipe-A-HDMI-A-1-XRGB2101010
[162.901671] Dynamic subtest pipe-A-HDMI-A-1-XRGB2101010: SKIP (15.577s)
[162.944408] Starting dynamic subtest: pipe-A-HDMI-A-1-XRGB16161616F
[178.206797] Dynamic subtest pipe-A-HDMI-A-1-XRGB16161616F: SKIP (15.308s)
[178.237277] Starting dynamic subtest: pipe-A-HDMI-A-2-XRGB2101010
[193.545087] Dynamic subtest pipe-A-HDMI-A-2-XRGB2101010: SKIP (15.328s)
[193.596448] Starting dynamic subtest: pipe-A-HDMI-A-2-XRGB16161616F
[208.819306] Dynamic subtest pipe-A-HDMI-A-2-XRGB16161616F: SKIP (15.267s)
[208.830086] Subtest bpc-switch-suspend: SKIP (61.504s)
[209.300204] [023/156] (846s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[209.526488] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[209.535803] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.001s)
[209.687179] [024/156] (846s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-onoff)
[209.892910] Starting subtest: fbc-2p-primscrn-cur-indfb-onoff
[212.898948] Subtest fbc-2p-primscrn-cur-indfb-onoff: SUCCESS (3.014s)
[213.135377] [025/156] (843s left) perf (oa-exponents)
[213.381145] Starting subtest: oa-exponents
[213.384836] Starting dynamic subtest: 0-rcs0
[217.008877] Dynamic subtest 0-rcs0: SUCCESS (3.642s)
[217.013354] Subtest oa-exponents: SUCCESS (3.645s)
[217.134965] [026/156] (839s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[217.348736] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[217.356586] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[217.505714] [027/156] (838s left) gem_eio (hibernate)
[217.652450] Starting subtest: hibernate
[233.134171] Subtest hibernate: SUCCESS (15.477s)
[241.421813] [028/156] (823s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-pgflip-blt)
[241.647082] Starting subtest: hdr-1p-primscrn-shrfb-pgflip-blt
[242.607052] Subtest hdr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (0.960s)
[242.922605] [029/156] (821s left) kms_ccs (crc-primary-rotation-180-4-tiled-bmg-ccs)
[243.118502] Starting subtest: crc-primary-rotation-180-4-tiled-bmg-ccs
[243.130066] Subtest crc-primary-rotation-180-4-tiled-bmg-ccs: SKIP (0.000s)
[243.874770] [030/156] (820s left) perf_pmu (init-wait)
[243.995721] Starting subtest: init-wait
[243.998866] Starting dynamic subtest: rcs0
[244.002090] Dynamic subtest rcs0: SUCCESS (0.004s)
[244.049972] Starting dynamic subtest: bcs0
[244.055074] Dynamic subtest bcs0: SUCCESS (0.002s)
[244.083624] Starting dynamic subtest: vcs0
[244.087602] Dynamic subtest vcs0: SUCCESS (0.002s)
[244.114932] Starting dynamic subtest: vecs0
[244.118953] Dynamic subtest vecs0: SUCCESS (0.002s)
[244.123137] Subtest init-wait: SUCCESS (0.023s)
[244.227987] [031/156] (820s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[244.340523] Starting subtest: invalid-smem-bo-on-discrete
[244.347199] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[244.583908] [032/156] (820s left) gem_barrier_race (remote-request)
[244.718834] Starting subtest: remote-request
[244.722712] Starting dynamic subtest: rcs0
[249.816353] Dynamic subtest rcs0: SUCCESS (5.096s)
[249.820465] Subtest remote-request: SUCCESS (5.101s)
[249.995957] [033/156] (815s left) kms_addfb_basic (bad-pitch-32)
[250.111514] Starting subtest: bad-pitch-32
[250.115490] Subtest bad-pitch-32: SUCCESS (0.000s)
[250.376380] [034/156] (814s left) gem_exec_fence (syncobj-import)
[251.087707] Starting subtest: syncobj-import
[251.152904] Subtest syncobj-import: SUCCESS (0.007s)
[251.756366] [035/156] (813s left) kms_frontbuffer_tracking (fbc-tiling-4)
[252.101066] Starting subtest: fbc-tiling-4
[252.107450] Subtest fbc-tiling-4: SKIP (0.000s)
[252.220272] [036/156] (813s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[252.443134] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[252.450696] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[252.614788] [037/156] (813s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[252.831186] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[253.867286] Subtest hdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SUCCESS (1.034s)
[254.100523] [038/156] (811s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[254.311508] Starting subtest: plane-lut1d-ctm3x4
[256.299059] Subtest plane-lut1d-ctm3x4: SKIP (1.982s)
[256.656976] [039/156] (809s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[256.778819] Starting subtest: invalid-single-wait-unsubmitted
[256.782119] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.001s)
[256.938279] [040/156] (809s left) kms_atomic (plane-immutable-zpos)
[257.128471] Starting subtest: plane-immutable-zpos
[257.131497] Starting dynamic subtest: pipe-A-HDMI-A-1
[257.923418] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.793s)
[257.928227] Subtest plane-immutable-zpos: SUCCESS (0.799s)
[258.288811] [041/156] (808s left) gem_userptr_blits (map-fixed-invalidate-busy)
[258.442623] Starting subtest: map-fixed-invalidate-busy
[258.447168] Starting dynamic subtest: gtt
[261.953185] Dynamic subtest gtt: SUCCESS (3.510s)
[261.985846] Starting dynamic subtest: wb
[265.362540] Dynamic subtest wb: SUCCESS (3.404s)
[265.393949] Starting dynamic subtest: wc
[268.757621] Dynamic subtest wc: SUCCESS (3.392s)
[268.789904] Starting dynamic subtest: uc
[272.146149] Dynamic subtest uc: SUCCESS (3.385s)
[272.150085] Subtest map-fixed-invalidate-busy: SUCCESS (13.707s)
[272.370570] [042/156] (794s left) gem_lmem_swapping (heavy-multi)
[272.491170] Subtest heavy-multi: SKIP (0.000s)
[272.615246] [043/156] (794s left) kms_draw_crc (draw-method-mmap-cpu)
[272.815062] Starting subtest: draw-method-mmap-cpu
[272.818712] Starting dynamic subtest: xrgb8888-untiled
[273.309895] Dynamic subtest xrgb8888-untiled: SUCCESS (0.494s)
[273.344572] Starting dynamic subtest: rgb565-untiled
[273.575703] Dynamic subtest rgb565-untiled: SUCCESS (0.262s)
[273.605932] Starting dynamic subtest: xrgb2101010-untiled
[273.843903] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.263s)
[273.876968] Starting dynamic subtest: xbgr16161616f-untiled
[274.128334] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.280s)
[274.157202] Starting dynamic subtest: xrgb8888-xtiled
[274.311168] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.180s)
[274.339951] Starting dynamic subtest: rgb565-xtiled
[274.494533] Dynamic subtest rgb565-xtiled: SUCCESS (0.179s)
[274.523634] Starting dynamic subtest: xrgb2101010-xtiled
[274.661330] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.162s)
[274.690530] Starting dynamic subtest: xbgr16161616f-xtiled
[274.844528] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.180s)
[274.873437] Starting dynamic subtest: xrgb8888-ytiled
[275.042921] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.196s)
[275.072934] Starting dynamic subtest: rgb565-ytiled
[275.228338] Dynamic subtest rgb565-ytiled: SUCCESS (0.180s)
[275.257907] Starting dynamic subtest: xrgb2101010-ytiled
[275.411532] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.179s)
[275.442565] Starting dynamic subtest: xbgr16161616f-ytiled
[275.593821] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.179s)
[275.598049] Subtest draw-method-mmap-cpu: SUCCESS (2.781s)
[275.871612] [044/156] (790s left) gem_fence_thrash (bo-write-verify-x)
[275.993361] Starting subtest: bo-write-verify-x
[277.271190] Subtest bo-write-verify-x: SUCCESS (1.281s)
[277.437156] [045/156] (789s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-plflip-blt)
[277.650148] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-plflip-blt
[277.656392] Subtest fbcpsrhdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[277.826667] [046/156] (789s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-render)
[278.046719] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-render
[278.053369] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[278.206608] [047/156] (788s left) kms_pm_rpm (package-g7)
[280.160628] Starting subtest: package-g7
[280.172051] Subtest package-g7: SKIP (0.000s)
[280.583086] [048/156] (786s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[280.797242] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[283.655585] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SUCCESS (2.863s)
[283.862211] [049/156] (783s left) gem_render_copy (linear-to-vebox-y-tiled)
[283.991956] Starting subtest: linear-to-vebox-y-tiled
[283.998573] Subtest linear-to-vebox-y-tiled: SKIP (0.000s)
[284.263204] [050/156] (783s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[284.489094] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[285.801754] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (1.314s)
[286.163767] [051/156] (781s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[286.297390] Starting subtest: y-tiled-to-vebox-x-tiled
[286.305462] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[286.579189] [052/156] (781s left) i915_suspend (basic-s3-without-i915)
[286.686437] Starting subtest: basic-s3-without-i915