[91.066487] Initializing watchdogs
[91.066656]   /dev/watchdog0
[91.106646] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[91.123167] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.129966] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[91.138330] [001/132] (960s left) kms_lease (possible-crtcs-filtering)
[91.346598] Starting subtest: possible-crtcs-filtering
[91.357747] Subtest possible-crtcs-filtering: SUCCESS (0.013s)
[91.514400] [002/132] (960s left) kms_psr (fbc-psr2-primary-blt)
[91.716091] Subtest fbc-psr2-primary-blt: SKIP (0.000s)
[91.820359] [003/132] (960s left) syncobj_wait (invalid-wait-illegal-handle)
[91.934538] Starting subtest: invalid-wait-illegal-handle
[91.937781] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[92.077561] [004/132] (959s left) kms_psr (fbc-psr-no-drrs)
[92.269516] Subtest fbc-psr-no-drrs: SKIP (0.000s)
[92.371549] [005/132] (959s left) prime_busy (before-wait)
[92.496403] Starting subtest: before-wait
[92.508157] Starting dynamic subtest: rcs0
[92.520103] Dynamic subtest rcs0: SUCCESS (0.011s)
[92.551716] Starting dynamic subtest: bcs0
[92.556185] Dynamic subtest bcs0: SUCCESS (0.015s)
[92.576086] Starting dynamic subtest: vcs0
[92.580276] Dynamic subtest vcs0: SUCCESS (0.018s)
[92.598617] Starting dynamic subtest: vecs0
[92.602828] Dynamic subtest vecs0: SUCCESS (0.014s)
[92.679326] Subtest before-wait: SUCCESS (0.186s)
[92.829256] [006/132] (959s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[93.012068] Starting subtest: sprite-rotation-90-pos-100-0
[108.376031] Subtest sprite-rotation-90-pos-100-0: SUCCESS (15.366s)
[108.628998] [007/132] (943s left) gem_exec_balancer (full-late-pulse)
[108.770108] Starting subtest: full-late-pulse
[113.393866] Subtest full-late-pulse: SUCCESS (4.624s)
[113.660620] [008/132] (938s left) gem_ctx_persistence (engines-mixed-process)
[113.779964] Starting subtest: engines-mixed-process
[113.783384] Starting dynamic subtest: rcs0
[114.211054] Dynamic subtest rcs0: SUCCESS (0.420s)
[114.268265] Starting dynamic subtest: bcs0
[114.588374] Dynamic subtest bcs0: SUCCESS (0.388s)
[114.620621] Starting dynamic subtest: vcs0
[114.997177] Dynamic subtest vcs0: SUCCESS (0.395s)
[115.034880] Starting dynamic subtest: vecs0
[115.391158] Dynamic subtest vecs0: SUCCESS (0.391s)
[115.400042] Subtest engines-mixed-process: SUCCESS (1.609s)
[115.560407] [009/132] (936s left) gem_lmem_swapping (verify)
[115.681356] Subtest verify: SKIP (0.000s)
[115.785596] [010/132] (936s left) vgem_basic (sysfs)
[115.999081] Starting subtest: sysfs
[116.003759] Subtest sysfs: SUCCESS (0.001s)
[116.114495] [FACT vgem_basic (sysfs)] new: kernel.kmod_is_loaded.vgem: true
[116.121258] [011/132] (936s left) kms_atomic (test-only)
[116.300936] Starting subtest: test-only
[116.630428] Starting dynamic subtest: pipe-A-HDMI-A-1
[117.685269] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.057s)
[117.688734] Subtest test-only: SUCCESS (1.388s)
[118.044725] [012/132] (934s left) kms_flip (plain-flip-ts-check)
[118.247603] Starting subtest: plain-flip-ts-check
[118.254101] Starting dynamic subtest: A-HDMI-A1
[124.206533] Dynamic subtest A-HDMI-A1: SUCCESS (5.961s)
[124.228619] Starting dynamic subtest: B-HDMI-A1
[129.885141] Dynamic subtest B-HDMI-A1: SUCCESS (5.672s)
[129.906629] Starting dynamic subtest: C-HDMI-A1
[135.554228] Dynamic subtest C-HDMI-A1: SUCCESS (5.667s)
[135.579871] Starting dynamic subtest: A-HDMI-A2
[141.267489] Dynamic subtest A-HDMI-A2: SUCCESS (5.710s)
[141.290035] Starting dynamic subtest: B-HDMI-A2
[146.946164] Dynamic subtest B-HDMI-A2: SUCCESS (5.675s)
[146.967999] Starting dynamic subtest: C-HDMI-A2
[152.610077] Dynamic subtest C-HDMI-A2: SUCCESS (5.660s)
[152.616076] Subtest plain-flip-ts-check: SUCCESS (34.368s)
[153.144657] [013/132] (899s left) gem_ctx_shared (single-timeline)
[153.284868] Starting subtest: single-timeline
[153.307753] Subtest single-timeline: SUCCESS (0.014s)
[153.572964] [014/132] (899s left) gem_watchdog (default-virtual)
[153.711032] Starting subtest: default-virtual
[154.724606] Subtest default-virtual: SUCCESS (1.027s)
[154.862067] [015/132] (898s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[155.077179] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[155.081057] Starting dynamic subtest: pipe-A-HDMI-A-1
[155.124534] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[155.167368] Starting dynamic subtest: pipe-A-HDMI-A-2
[155.217354] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.003s)
[155.257403] Starting dynamic subtest: pipe-B-HDMI-A-1
[155.292611] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.003s)
[155.315453] Starting dynamic subtest: pipe-B-HDMI-A-2
[155.350693] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.002s)
[155.371455] Starting dynamic subtest: pipe-C-HDMI-A-1
[155.403699] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.003s)
[155.424948] Starting dynamic subtest: pipe-C-HDMI-A-2
[155.497698] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[155.504821] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SKIP (0.033s)
[156.128926] [016/132] (896s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[156.328075] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[156.463900] [017/132] (896s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[156.648677] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[156.656077] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[156.797918] [018/132] (896s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[157.006112] Starting subtest: y-tiled-64bpp-rotate-180
[164.014141] Subtest y-tiled-64bpp-rotate-180: SUCCESS (7.008s)
[164.531351] [019/132] (888s left) gem_mmap_gtt (basic-small-copy-xy)
[164.640785] Starting subtest: basic-small-copy-XY
[167.683819] Subtest basic-small-copy-XY: SUCCESS (3.045s)
[167.850955] [020/132] (885s left) gem_exec_schedule (pi-shared-iova)
[168.290834] Starting subtest: pi-shared-iova
[168.294258] Starting dynamic subtest: rcs0
[168.353349] Dynamic subtest rcs0: SUCCESS (0.059s)
[168.377088] Starting dynamic subtest: bcs0
[168.429491] Dynamic subtest bcs0: SUCCESS (0.074s)
[168.466194] Starting dynamic subtest: vcs0
[168.480734] Dynamic subtest vcs0: SUCCESS (0.050s)
[168.516103] Starting dynamic subtest: vecs0
[168.531694] Dynamic subtest vecs0: SUCCESS (0.049s)
[168.535074] Subtest pi-shared-iova: SUCCESS (0.240s)
[168.797163] [021/132] (884s left) gem_spin_batch (spin-all-new)
[168.930111] Starting subtest: spin-all-new
[168.957482] Subtest spin-all-new: SUCCESS (0.027s)
[169.230126] [022/132] (884s left) gem_exec_schedule (fifo)
[169.369436] Starting subtest: fifo
[169.374255] Starting dynamic subtest: rcs0
[169.483674] Dynamic subtest rcs0: SUCCESS (0.112s)
[169.514174] Starting dynamic subtest: bcs0
[169.608886] Dynamic subtest bcs0: SUCCESS (0.129s)
[169.642223] Starting dynamic subtest: vcs0
[169.728962] Dynamic subtest vcs0: SUCCESS (0.113s)
[169.758500] Starting dynamic subtest: vecs0
[169.842660] Dynamic subtest vecs0: SUCCESS (0.111s)
[169.845867] Subtest fifo: SUCCESS (0.473s)
[170.248919] [023/132] (883s left) kms_busy (basic)
[170.451167] Starting subtest: basic
[170.454653] Starting dynamic subtest: flip
[171.451092] Dynamic subtest flip: SUCCESS (0.995s)
[171.490480] Starting dynamic subtest: modeset
[172.787845] Dynamic subtest modeset: SUCCESS (1.334s)
[172.792831] Subtest basic: SUCCESS (2.334s)
[173.189656] [024/132] (880s left) kms_pm_rpm (i2c)
[175.104303] Starting subtest: i2c
[176.832752] Subtest i2c: SUCCESS (1.722s)
[177.171353] [025/132] (876s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-blt)
[177.377894] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-blt
[177.383990] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[177.567846] [026/132] (876s left) gem_ctx_persistence (heartbeat-hang)
[177.689230] Starting subtest: heartbeat-hang
[178.844782] Subtest heartbeat-hang: SUCCESS (1.158s)
[179.015424] [027/132] (875s left) gen9_exec_parse (batch-zero-length)
[179.145375] Starting subtest: batch-zero-length
[179.149552] Subtest batch-zero-length: SUCCESS (0.004s)
[179.420571] [028/132] (874s left) gem_userptr_blits (create-destroy-sync)
[179.562805] Starting subtest: create-destroy-sync
[184.589251] Subtest create-destroy-sync: SUCCESS (5.025s)
[184.771496] [029/132] (869s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-pwrite)
[184.980687] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-pwrite
[184.990097] Subtest psr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[185.154811] [030/132] (869s left) gem_exec_schedule (reorder-wide)
[185.429584] Starting subtest: reorder-wide
[185.434380] Starting dynamic subtest: rcs0
[185.575740] Dynamic subtest rcs0: SUCCESS (0.144s)
[185.622649] Starting dynamic subtest: bcs0
[185.888642] Dynamic subtest bcs0: SUCCESS (0.314s)
[185.920673] Starting dynamic subtest: vcs0
[186.054971] Dynamic subtest vcs0: SUCCESS (0.162s)
[186.087470] Starting dynamic subtest: vecs0
[186.225829] Dynamic subtest vecs0: SUCCESS (0.156s)
[186.229324] Subtest reorder-wide: SUCCESS (0.784s)
[186.490938] [031/132] (868s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[186.701509] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[186.710102] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[186.857289] [032/132] (867s left) gem_workarounds (suspend-resume-context)
[187.006402] Starting subtest: suspend-resume-context