[87.862527] Initializing watchdogs
[87.862699]   /dev/watchdog0
[87.901439] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[87.916745] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[87.923764] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[87.932044] [001/133] (960s left) kms_color_pipeline (plane-lut1d)
[88.137137] Starting subtest: plane-lut1d
[89.733668] Subtest plane-lut1d: SKIP (1.587s)
[90.011481] [002/133] (958s left) kms_cursor_legacy (single-move)
[90.196053] Starting subtest: single-move
[90.202074] Starting dynamic subtest: pipe-A
[95.603068] Dynamic subtest pipe-A: SUCCESS (5.400s)
[95.622690] Starting dynamic subtest: pipe-B
[101.011246] Dynamic subtest pipe-B: SUCCESS (5.410s)
[101.030712] Starting dynamic subtest: pipe-C
[106.420918] Dynamic subtest pipe-C: SUCCESS (5.407s)
[106.538901] Starting dynamic subtest: all-pipes
[111.820555] Dynamic subtest all-pipes: SUCCESS (5.401s)
[111.824028] Subtest single-move: SUCCESS (21.622s)
[111.960257] [003/133] (936s left) gem_ctx_param (get-priority-new-ctx)
[112.072081] Starting subtest: get-priority-new-ctx
[112.076596] Subtest get-priority-new-ctx: SUCCESS (0.002s)
[112.197144] [004/133] (936s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[112.386107] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[112.390702] Starting dynamic subtest: pipe-A
[113.459346] Dynamic subtest pipe-A: SUCCESS (1.076s)
[113.495791] Starting dynamic subtest: pipe-B
[114.604922] Dynamic subtest pipe-B: SUCCESS (1.145s)
[114.639739] Starting dynamic subtest: pipe-C
[115.049494] Dynamic subtest pipe-C: SUCCESS (0.443s)
[115.054232] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (2.666s)
[115.584063] [005/133] (933s left) gem_eio (in-flight-contexts-immediate)
[115.739805] Starting subtest: in-flight-contexts-immediate
[127.010666] Subtest in-flight-contexts-immediate: SUCCESS (11.261s)
[127.236719] [006/133] (921s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[127.438754] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[127.445929] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[127.584568] [007/133] (921s left) kms_psr (fbc-psr2-basic)
[127.784057] Subtest fbc-psr2-basic: SKIP (0.000s)
[127.891696] [008/133] (921s left) gem_mmap_wc (bad-object)
[128.007188] Starting subtest: bad-object
[128.012144] Subtest bad-object: SUCCESS (0.000s)
[128.132131] [009/133] (921s left) gem_ctx_bad_destroy (double-destroy)
[128.242347] Starting subtest: double-destroy
[128.246560] Subtest double-destroy: SUCCESS (0.000s)
[128.365294] [010/133] (920s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[128.475550] Starting subtest: single-wait-all-for-submit-unsubmitted
[128.579595] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.107s)
[128.746724] [011/133] (920s left) gem_eio (execbuf)
[128.873380] Starting subtest: execbuf
[128.927751] Subtest execbuf: SUCCESS (0.051s)
[129.218504] [012/133] (920s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[129.425248] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[129.432091] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[129.610839] [013/133] (919s left) kms_chamelium_color (ctm-green-to-red)
[129.799025] Subtest ctm-green-to-red: SKIP (0.000s)
[129.912102] [014/133] (919s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[130.118093] Starting subtest: psr-rgb565-draw-mmap-gtt
[130.125174] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[130.253578] [015/133] (919s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[130.437650] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[130.446163] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SKIP (0.000s)
[130.568645] [016/133] (919s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[130.752083] Starting subtest: disable-crc-after-crtc
[130.757077] Starting dynamic subtest: pipe-A-HDMI-A-1
[131.896074] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.138s)
[131.920726] Starting dynamic subtest: pipe-B-HDMI-A-2
[132.765396] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.862s)
[132.792461] Starting dynamic subtest: pipe-C-HDMI-A-1
[133.602909] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.832s)
[133.609236] Subtest disable-crc-after-crtc: SUCCESS (2.848s)
[133.921966] [017/133] (916s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[134.112104] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[134.115166] Starting dynamic subtest: pipe-A-HDMI-A-1
[134.167582] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[134.210659] Starting dynamic subtest: pipe-A-HDMI-A-2
[134.255159] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s)
[134.276119] Starting dynamic subtest: pipe-B-HDMI-A-1
[134.307391] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[134.327893] Starting dynamic subtest: pipe-B-HDMI-A-2
[134.360416] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s)
[134.379875] Starting dynamic subtest: pipe-C-HDMI-A-1
[134.411655] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[134.435491] Starting dynamic subtest: pipe-C-HDMI-A-2
[134.466922] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[134.473631] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.018s)
[135.106473] [018/133] (914s left) kms_psr (pr-primary-render)
[135.301095] Subtest pr-primary-render: SKIP (0.000s)
[135.401057] [019/133] (914s left) gem_userptr_blits (map-fixed-invalidate-busy)
[135.543142] Starting subtest: map-fixed-invalidate-busy
[135.547414] Starting dynamic subtest: gtt
[139.006262] Dynamic subtest gtt: SUCCESS (3.458s)
[139.027724] Starting dynamic subtest: wb
[142.349201] Dynamic subtest wb: SUCCESS (3.346s)
[142.370848] Starting dynamic subtest: wc
[145.701146] Dynamic subtest wc: SUCCESS (3.351s)
[145.722671] Starting dynamic subtest: uc
[149.061327] Dynamic subtest uc: SUCCESS (3.354s)
[149.066275] Subtest map-fixed-invalidate-busy: SUCCESS (13.515s)
[149.224281] [020/133] (901s left) kms_atomic (test-only)
[149.415712] Starting subtest: test-only
[149.757540] Starting dynamic subtest: pipe-A-HDMI-A-1
[150.818518] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.062s)
[150.821882] Subtest test-only: SUCCESS (1.408s)
[151.230893] [021/133] (899s left) kms_big_fb (linear-16bpp-rotate-90)
[151.460150] Starting subtest: linear-16bpp-rotate-90
[151.833046] Subtest linear-16bpp-rotate-90: SKIP (0.371s)
[152.090072] [022/133] (898s left) gem_exec_balancer (parallel-contexts)
[152.345123] Subtest parallel-contexts: SKIP (0.000s)
[152.486330] [023/133] (898s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-gtt)
[152.713063] Starting subtest: fbc-rgb101010-draw-mmap-gtt
[155.715678] Subtest fbc-rgb101010-draw-mmap-gtt: SUCCESS (3.010s)
[155.970198] [024/133] (894s left) kms_cursor_crc (cursor-offscreen-32x32)
[156.161076] Starting subtest: cursor-offscreen-32x32
[156.353475] Subtest cursor-offscreen-32x32: SKIP (0.184s)
[156.506150] [025/133] (894s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[156.706075] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[168.226414] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.461s)
[168.998680] [026/133] (882s left) gem_exec_parallel (fds)
[169.536436] Starting subtest: fds
[169.541366] Starting dynamic subtest: rcs0
[175.472107] Dynamic subtest rcs0: SUCCESS (6.120s)
[175.506204] Starting dynamic subtest: bcs0
[187.145853] Dynamic subtest bcs0: SUCCESS (11.675s)
[187.187891] Starting dynamic subtest: vcs0
[199.576751] Dynamic subtest vcs0: SUCCESS (12.426s)
[199.615560] Starting dynamic subtest: vecs0
[211.123682] Dynamic subtest vecs0: SUCCESS (11.547s)
[211.128088] Subtest fds: SUCCESS (41.780s)
[211.390320] [027/133] (840s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[211.592160] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[211.597894] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[211.737323] [028/133] (839s left) gem_exec_schedule (preempt-other)
[211.888102] Starting subtest: preempt-other
[211.892381] Starting dynamic subtest: rcs0
[211.951373] Dynamic subtest rcs0: SUCCESS (0.063s)
[211.981308] Starting dynamic subtest: bcs0
[212.014360] Dynamic subtest bcs0: SUCCESS (0.061s)
[212.048120] Starting dynamic subtest: vcs0
[212.072876] Dynamic subtest vcs0: SUCCESS (0.060s)
[212.101876] Starting dynamic subtest: vecs0
[212.144606] Dynamic subtest vecs0: SUCCESS (0.063s)
[212.148219] Subtest preempt-other: SUCCESS (0.260s)
[212.571466] [029/133] (839s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[212.679591] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[212.791034] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.111s)
[212.926183] [030/133] (838s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[213.138661] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[213.145149] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[213.276602] [031/133] (838s left) kms_feature_discovery (psr1)
[213.474070] Starting subtest: psr1
[213.480397] Subtest psr1: SKIP (0.000s)
[213.611178] [032/133] (838s left) kms_psr (psr2-sprite-plane-move)
[213.804076] Subtest psr2-sprite-plane-move: SKIP (0.000s)
[213.915799] [033/133] (838s left) i915_drm_fdinfo (busy)
[214.036892] Starting subtest: busy
[214.040177] Starting dynamic subtest: rcs0
[214.584207] Dynamic subtest rcs0: SUCCESS (0.542s)
[214.605469] Starting dynamic subtest: bcs0
[215.110529] Dynamic subtest bcs0: SUCCESS (0.532s)
[215.139313] Starting dynamic subtest: vcs0
[215.648586] Dynamic subtest vcs0: SUCCESS (0.531s)
[215.674038] Starting dynamic subtest: vecs0
[216.181471] Dynamic subtest vecs0: SUCCESS (0.532s)
[216.185530] Subtest busy: SUCCESS (2.150s)
[216.327637] [034/133] (835s left) perf_pmu (pmu-read)
[216.466301] Starting subtest: pmu-read
[216.471097] Subtest pmu-read: SUCCESS (0.008s)
[216.621056] [035/133] (835s left) gem_softpin (evict-active)
[216.740743] Starting subtest: evict-active
[216.765439] Subtest evict-active: SUCCESS (0.024s)
[216.925229] [036/133] (835s left) gem_basic (create-fd-close)
[217.040433] Starting subtest: create-fd-close
[217.048433] Subtest create-fd-close: SUCCESS (0.000s)
[217.184667] [037/133] (835s left) prime_busy (hang-wait)
[217.306110] Starting subtest: hang-wait
[217.309884] Starting dynamic subtest: rcs0
[223.674898] Dynamic subtest rcs0: SUCCESS (6.358s)
[223.696911] Starting dynamic subtest: bcs0
[230.643860] Dynamic subtest bcs0: SUCCESS (6.968s)
[230.665413] Starting dynamic subtest: vcs0
[237.620567] Dynamic subtest vcs0: SUCCESS (6.972s)
[237.642091] Starting dynamic subtest: vecs0
[244.659591] Dynamic subtest vecs0: SUCCESS (7.037s)
[244.663817] Subtest hang-wait: SUCCESS (27.351s)
[244.790565] [038/133] (807s left) gem_softpin (evict-snoop)
[244.915075] Starting subtest: evict-snoop
[244.922565] Subtest evict-snoop: SKIP (0.000s)
[245.059230] [039/133] (807s left) dmabuf_sync_file (import-multiple-read-only)
[245.315654] Starting subtest: import-multiple-read-only
[245.328737] Subtest import-multiple-read-only: SUCCESS (0.015s)
[245.456985] [FACT dmabuf_sync_file (import-multiple-read-only)] new: kernel.kmod_is_loaded.vgem: true
[245.464433] [040/133] (807s left) gem_ctx_shared (detached-shared-gtt)
[245.590457] Starting subtest: detached-shared-gtt
[247.742337] Subtest detached-shared-gtt: SUCCESS (2.151s)
[248.030676] [041/133] (804s left) gem_mmap_offset (bad-extensions)
[248.145133] Starting subtest: bad-extensions
[248.152243] Subtest bad-extensions: SUCCESS (0.000s)
[248.285964] [042/133] (804s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[248.478472] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[248.482185] Starting dynamic subtest: pipe-A
[248.817392] Dynamic subtest pipe-A: SUCCESS (0.340s)
[248.862513] Starting dynamic subtest: pipe-B
[249.263072] Dynamic subtest pipe-B: SUCCESS (0.443s)
[249.308164] Starting dynamic subtest: pipe-C
[249.350849] Dynamic subtest pipe-C: SUCCESS (0.085s)
[249.355348] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (0.877s)
[249.863127] [043/133] (803s left) i915_query (query-topology-matches-eu-total)
[249.973171] Starting subtest: query-topology-matches-eu-total
[249.976490] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[250.127605] [044/133] (802s left) gem_eio (in-flight-suspend)
[250.261134] Starting subtest: in-flight-suspend