[91.123839] Initializing watchdogs
[91.124039]   /dev/watchdog0
[91.163371] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[91.178730] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.186632] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[91.192261] [001/133] (960s left) kms_content_protection (legacy-hdcp14)
[91.416251] Starting subtest: legacy-hdcp14
[91.456674] Subtest legacy-hdcp14: SKIP (0.035s)
[91.598043] [002/133] (960s left) gem_ctx_shared (q-smoketest)
[91.740801] Starting subtest: Q-smoketest
[91.744274] Starting dynamic subtest: rcs0
[97.172109] Dynamic subtest rcs0: SUCCESS (5.425s)
[97.192187] Starting dynamic subtest: bcs0
[102.605562] Dynamic subtest bcs0: SUCCESS (5.428s)
[102.624944] Starting dynamic subtest: vcs0
[108.037210] Dynamic subtest vcs0: SUCCESS (5.427s)
[108.057143] Starting dynamic subtest: vecs0
[113.465414] Dynamic subtest vecs0: SUCCESS (5.425s)
[113.468373] Subtest Q-smoketest: SUCCESS (21.709s)
[113.710552] [003/133] (938s left) core_debugfs (read-all-entries)
[113.819219] Starting subtest: read-all-entries
[113.911226] Subtest read-all-entries: SUCCESS (0.092s)
[114.042187] [004/133] (937s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[114.147793] Starting subtest: cpuset-medium-copy-odd
[124.230400] Subtest cpuset-medium-copy-odd: SUCCESS (10.079s)
[124.384828] [005/133] (927s left) gen7_exec_parse (chained-batch)
[124.637237] Subtest chained-batch: SKIP (0.000s)
[124.737056] [006/133] (927s left) gem_ccs (block-multicopy-inplace)
[124.847236] Subtest block-multicopy-inplace: SKIP (0.000s)
[124.947986] [007/133] (927s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[125.067971] Starting subtest: single-wait-all-for-submit-available-signaled
[125.070115] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.002s)
[125.207712] [008/133] (927s left) kms_pm_lpsp (kms-lpsp)
[126.414921] Starting subtest: kms-lpsp
[126.418062] Subtest kms-lpsp: SKIP (0.001s)
[126.543802] [009/133] (925s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[126.752193] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[126.755903] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[126.892970] [010/133] (925s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[127.088125] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[127.092109] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[127.228159] [011/133] (925s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[127.421086] Starting subtest: 2x-flip-vs-cursor-legacy
[128.404337] Subtest 2x-flip-vs-cursor-legacy: SUCCESS (0.981s)
[128.752859] [012/133] (923s left) perf_pmu (most-busy-check-all)
[128.871216] Starting subtest: most-busy-check-all
[128.873450] Starting dynamic subtest: rcs0
[129.397893] Dynamic subtest rcs0: SUCCESS (0.528s)
[129.419288] Starting dynamic subtest: bcs0
[129.922776] Dynamic subtest bcs0: SUCCESS (0.522s)
[129.943642] Starting dynamic subtest: vcs0
[130.449967] Dynamic subtest vcs0: SUCCESS (0.525s)
[130.470611] Starting dynamic subtest: vecs0
[130.977151] Dynamic subtest vecs0: SUCCESS (0.525s)
[130.978975] Subtest most-busy-check-all: SUCCESS (2.105s)
[131.123574] [013/133] (921s left) vgem_basic (busy-fence)
[131.313635] Starting subtest: busy-fence
[131.316169] Subtest busy-fence: SUCCESS (0.003s)
[131.436980] [FACT vgem_basic (busy-fence)] new: kernel.kmod_is_loaded.vgem: true
[131.442266] [014/133] (921s left) gem_mmap_offset (mmap-boundaries)
[131.545061] Starting subtest: mmap-boundaries
[131.546671] Starting dynamic subtest: smem0
[132.446547] Dynamic subtest smem0: SUCCESS (0.896s)
[132.450511] Subtest mmap-boundaries: SUCCESS (0.897s)
[132.633868] [015/133] (920s left) gem_ctx_persistence (legacy-engines-mixed-process)
[132.749108] Starting subtest: legacy-engines-mixed-process
[132.752105] Starting dynamic subtest: render
[133.158122] Dynamic subtest render: SUCCESS (0.409s)
[133.212107] Starting dynamic subtest: bsd
[133.578189] Dynamic subtest bsd: SUCCESS (0.409s)
[133.621266] Starting dynamic subtest: blt
[133.966901] Dynamic subtest blt: SUCCESS (0.388s)
[134.012148] Starting dynamic subtest: vebox
[134.377948] Dynamic subtest vebox: SUCCESS (0.403s)
[134.379632] Subtest legacy-engines-mixed-process: SUCCESS (1.630s)
[134.531178] [016/133] (918s left) kms_atomic_interruptible (universal-setplane-cursor)
[134.722060] Starting subtest: universal-setplane-cursor
[134.726048] Starting dynamic subtest: pipe-A-HDMI-A-1
[141.566767] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.839s)
[141.569294] Subtest universal-setplane-cursor: SUCCESS (6.844s)
[141.888886] [017/133] (911s left) kms_psr2_su (page_flip-xrgb8888)
[141.998161] Subtest page_flip-XRGB8888: SKIP (0.000s)
[142.113037] [018/133] (911s left) perf (invalid-open-flags)
[142.353109] Starting subtest: invalid-open-flags
[142.354838] Subtest invalid-open-flags: SUCCESS (0.001s)
[142.458121] [019/133] (910s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[142.657223] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[142.660922] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[142.806996] [020/133] (910s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[142.990065] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[142.999220] Starting dynamic subtest: pipe-A-valid-mode
[143.566684] Dynamic subtest pipe-A-valid-mode: SKIP (0.571s)
[143.570428] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SKIP (0.576s)
[144.060477] [021/133] (909s left) gem_mmap_gtt (big-bo)
[144.161736] Starting subtest: big-bo
[144.439158] Subtest big-bo: SUCCESS (0.271s)
[144.628469] [022/133] (908s left) gem_workarounds (reset-context)
[144.906501] Starting subtest: reset-context
[144.934436] Subtest reset-context: SUCCESS (0.026s)
[145.095618] [023/133] (908s left) api_intel_allocator (fork-simple-once)
[145.198101] Starting subtest: fork-simple-once
[145.233941] Subtest fork-simple-once: SUCCESS (0.039s)
[145.376480] [024/133] (908s left) gem_mmap_wc (fault-concurrent)
[145.483431] Starting subtest: fault-concurrent
[145.803241] Subtest fault-concurrent: SUCCESS (0.323s)
[145.961534] [025/133] (907s left) kms_legacy_colorkey (invalid-plane)
[146.156689] Starting subtest: invalid-plane
[146.160575] Starting dynamic subtest: zero-id
[146.162204] Dynamic subtest zero-id: SUCCESS (0.000s)
[146.186173] Starting dynamic subtest: outrange-id
[146.191106] Dynamic subtest outrange-id: SUCCESS (0.000s)
[146.195105] Subtest invalid-plane: SUCCESS (0.001s)
[146.310516] [026/133] (907s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[146.492103] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[146.600184] [027/133] (907s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[146.718109] Starting subtest: basic-cpu-gtt-noreloc
[146.733696] Subtest basic-cpu-gtt-noreloc: SUCCESS (0.014s)
[146.961218] [028/133] (907s left) kms_psr (psr-primary-blt)
[147.149160] Subtest psr-primary-blt: SKIP (0.000s)
[147.252832] [029/133] (907s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[147.369513] Starting subtest: mixed-tiled-to-y-tiled-ccs
[147.371379] Starting dynamic subtest: smem
[147.612817] Dynamic subtest smem: SUCCESS (0.245s)
[147.614520] Subtest mixed-tiled-to-y-tiled-ccs: SUCCESS (0.246s)
[147.880181] [030/133] (906s left) gem_userptr_blits (input-checking)
[147.996148] Starting subtest: input-checking
[147.999112] Subtest input-checking: SUCCESS (0.000s)
[148.211122] [031/133] (906s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[148.411051] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[150.871362] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (2.460s)
[151.141173] [032/133] (903s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[151.324067] Starting subtest: bad-pixel-format-4-tiled-mtl-mc-ccs
[151.327070] Starting dynamic subtest: pipe-A-HDMI-A-1
[151.349083] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[151.390076] Starting dynamic subtest: pipe-A-HDMI-A-2
[151.417564] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s)
[151.461300] Starting dynamic subtest: pipe-B-HDMI-A-1
[151.486034] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[151.506977] Starting dynamic subtest: pipe-B-HDMI-A-2
[151.522915] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s)
[151.544089] Starting dynamic subtest: pipe-C-HDMI-A-1
[151.560155] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[151.581689] Starting dynamic subtest: pipe-C-HDMI-A-2
[151.596668] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s)
[151.599748] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SKIP (0.017s)
[152.379296] [033/133] (902s left) gem_eio (in-flight-contexts-1us)
[152.511248] Starting subtest: in-flight-contexts-1us
[163.990761] Subtest in-flight-contexts-1us: SUCCESS (11.485s)
[164.246576] [034/133] (890s left) gem_exec_balancer (full-late)
[164.374860] Starting subtest: full-late
[168.998216] Subtest full-late: SUCCESS (4.625s)
[169.268239] [035/133] (885s left) kms_frontbuffer_tracking (basic)
[169.467345] Starting subtest: basic
[172.918939] Subtest basic: SUCCESS (3.449s)
[173.163275] [036/133] (881s left) prime_busy (before-wait)
[173.271900] Starting subtest: before-wait
[173.283363] Starting dynamic subtest: rcs0
[173.297831] Dynamic subtest rcs0: SUCCESS (0.014s)
[173.326508] Starting dynamic subtest: bcs0
[173.328074] Dynamic subtest bcs0: SUCCESS (0.020s)
[173.350361] Starting dynamic subtest: vcs0
[173.352098] Dynamic subtest vcs0: SUCCESS (0.017s)
[173.373619] Starting dynamic subtest: vecs0
[173.376205] Dynamic subtest vecs0: SUCCESS (0.014s)
[173.464474] Subtest before-wait: SUCCESS (0.194s)
[173.633533] [037/133] (881s left) kms_pipe_crc_basic (suspend-read-crc)
[173.815687] Starting subtest: suspend-read-crc
[173.820088] Starting dynamic subtest: pipe-A-HDMI-A-1