[89.512402] Initializing watchdogs
[89.512568]   /dev/watchdog0
[89.553379] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[89.568190] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.574611] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.582551] [001/156] (960s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[89.813621] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-mmap-wc
[89.822990] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[89.963319] [002/156] (960s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[90.190470] Starting subtest: fbc-indfb-scaledprimary
[91.476128] Subtest fbc-indfb-scaledprimary: SUCCESS (1.292s)
[91.797279] [003/156] (958s left) kms_psr (fbc-pr-cursor-blt)
[91.995247] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[92.104641] [004/156] (958s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt)
[92.316306] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt
[92.323916] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[92.466560] [005/156] (957s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[92.599102] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[92.607102] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.001s)
[92.863896] [006/156] (957s left) gem_mmap_offset (partial-unmap)
[92.979854] Starting subtest: partial-unmap
[92.983487] Starting dynamic subtest: smem0
[93.092957] Dynamic subtest smem0: SUCCESS (0.122s)
[93.097337] Subtest partial-unmap: SUCCESS (0.124s)
[93.273927] [007/156] (957s left) gem_mmap_wc (coherency)
[93.386788] Starting subtest: coherency
[93.457392] Subtest coherency: SUCCESS (0.077s)
[93.598066] [008/156] (957s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-render)
[93.809776] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-render
[93.818959] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[93.950989] [009/156] (956s left) gem_exec_schedule (u-fairslice)
[94.115225] Starting subtest: u-fairslice
[94.118588] Starting dynamic subtest: rcs0
[96.155454] Dynamic subtest rcs0: SUCCESS (2.037s)
[96.176196] Starting dynamic subtest: bcs0
[98.192534] Dynamic subtest bcs0: SUCCESS (2.036s)
[98.214101] Starting dynamic subtest: vcs0
[100.223293] Dynamic subtest vcs0: SUCCESS (2.030s)
[100.244227] Starting dynamic subtest: vecs0
[102.251429] Dynamic subtest vecs0: SUCCESS (2.029s)
[102.255430] Subtest u-fairslice: SUCCESS (8.135s)
[102.633154] [010/156] (948s left) sw_sync (sync_busy)
[102.745927] Starting subtest: sync_busy
[102.758884] Subtest sync_busy: SUCCESS (0.012s)
[102.880473] [011/156] (948s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[103.088073] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[103.096525] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[103.242290] [012/156] (947s left) gem_spin_batch (legacy-resubmit)
[103.371043] Starting subtest: legacy-resubmit
[103.375650] Starting dynamic subtest: default
[103.386235] Dynamic subtest default: SUCCESS (0.007s)
[103.415153] Starting dynamic subtest: render
[103.420162] Dynamic subtest render: SUCCESS (0.005s)
[103.443931] Starting dynamic subtest: bsd
[103.448124] Dynamic subtest bsd: SUCCESS (0.009s)
[103.469578] Starting dynamic subtest: bsd1
[103.473667] Dynamic subtest bsd1: SUCCESS (0.009s)
[103.494493] Starting dynamic subtest: blt
[103.498601] Dynamic subtest blt: SUCCESS (0.010s)
[103.519643] Starting dynamic subtest: vebox
[103.523982] Dynamic subtest vebox: SUCCESS (0.005s)
[103.528314] Subtest legacy-resubmit: SUCCESS (0.057s)
[103.698256] [013/156] (947s left) fbdev (unaligned-write)
[103.829513] Starting subtest: unaligned-write
[104.041196] Subtest unaligned-write: SUCCESS (0.211s)
[104.164194] [014/156] (947s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[104.366578] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[104.373876] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[104.500923] [015/156] (946s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[104.713390] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[104.721507] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[104.861422] [016/156] (946s left) kms_content_protection (dp-mst-type-0-suspend-resume)
[105.085960] Starting subtest: dp-mst-type-0-suspend-resume
[105.093297] Subtest dp-mst-type-0-suspend-resume: SKIP (0.001s)
[105.231725] [017/156] (946s left) gem_exec_fence (submit-chain)
[105.499728] Starting subtest: submit-chain
[105.524134] Subtest submit-chain: SUCCESS (0.025s)
[106.299767] [FACT gem_exec_fence (submit-chain)] new: kernel.kmod_is_loaded.vgem: true
[106.306456] [018/156] (945s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[106.522948] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[106.530576] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[106.702775] [019/156] (945s left) gem_eio (wait-wedge-immediate)
[106.846870] Starting subtest: wait-wedge-immediate
[107.975736] Subtest wait-wedge-immediate: SUCCESS (1.131s)
[108.211327] [020/156] (943s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[108.407473] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[108.413730] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[108.563167] [021/156] (943s left) kms_addfb_basic (unused-handle)
[108.681515] Starting subtest: unused-handle
[108.685515] Subtest unused-handle: SUCCESS (0.000s)
[108.920402] [022/156] (943s left) sriov_basic (enable-vfs-bind-unbind-each)
[109.039158] Subtest enable-vfs-bind-unbind-each: SKIP (0.000s)
[109.160775] [023/156] (943s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping)
[109.350880] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping
[109.358235] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[109.521504] [024/156] (942s left) gem_ctx_persistence (engines-mixed-process)
[109.651703] Starting subtest: engines-mixed-process
[109.655194] Starting dynamic subtest: rcs0
[110.151406] Dynamic subtest rcs0: SUCCESS (0.428s)
[110.216018] Starting dynamic subtest: bcs0
[110.621467] Dynamic subtest bcs0: SUCCESS (0.419s)
[110.688319] Starting dynamic subtest: vcs0
[110.952251] Dynamic subtest vcs0: SUCCESS (0.375s)
[111.018692] Starting dynamic subtest: vecs0
[111.336663] Dynamic subtest vecs0: SUCCESS (0.388s)
[111.341245] Subtest engines-mixed-process: SUCCESS (1.620s)
[111.438402] [025/156] (941s left) gem_ctx_param (invalid-size-set)
[111.555636] Starting subtest: invalid-size-set
[111.558881] Subtest invalid-size-set: SUCCESS (0.001s)
[111.710026] [026/156] (940s left) gem_caching (read-writes)
[111.839309] Starting subtest: read-writes
[134.021663] Subtest read-writes: SUCCESS (22.191s)
[134.211349] [027/156] (918s left) gem_userptr_blits (unsync-overlap)
[134.351272] Subtest unsync-overlap: SKIP (0.000s)
[134.540916] [028/156] (918s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[134.747744] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[134.755101] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[134.902309] [029/156] (917s left) i915_pm_rpm (sysfs-read)
[136.884740] Starting subtest: sysfs-read
[137.233138] Subtest sysfs-read: SUCCESS (0.336s)
[137.626559] [030/156] (915s left) gem_watchdog (default-virtual)
[137.769822] Starting subtest: default-virtual
[138.794968] Subtest default-virtual: SUCCESS (1.031s)
[138.931202] [031/156] (914s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[139.147829] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[141.760072] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SUCCESS (2.611s)
[141.956343] [032/156] (911s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[142.148098] Starting subtest: cursorB-vs-flipA-legacy
[153.564437] Subtest cursorB-vs-flipA-legacy: SUCCESS (11.411s)
[153.966294] [033/156] (899s left) gem_ctx_engines (invalid-engines)
[154.106077] Starting subtest: invalid-engines
[154.120525] Subtest invalid-engines: SUCCESS (0.014s)
[154.390911] [034/156] (898s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[154.600851] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[154.606974] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[154.758060] [035/156] (898s left) gem_ctx_sseu (invalid-sseu)
[154.880595] Subtest invalid-sseu: SKIP (0.000s)
[154.999194] [036/156] (898s left) syncobj_timeline (invalid-transfer-non-existent-point)
[155.112187] Starting subtest: invalid-transfer-non-existent-point
[155.115611] Subtest invalid-transfer-non-existent-point: SUCCESS (0.001s)
[155.285813] [037/156] (898s left) gem_softpin (noreloc-s3)
[155.416193] Starting subtest: noreloc-S3