[88.111849] Initializing watchdogs
[88.112147]   /dev/watchdog0
[88.150713] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[88.165452] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.172635] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.178247] [001/134] (960s left) gem_exec_capture (capture)
[88.311485] Starting subtest: capture
[88.314391] Starting dynamic subtest: rcs0-smem
[89.603319] Dynamic subtest rcs0-smem: SUCCESS (1.292s)
[89.633945] Starting dynamic subtest: bcs0-smem
[90.877844] Dynamic subtest bcs0-smem: SUCCESS (1.273s)
[90.907804] Starting dynamic subtest: vcs0-smem
[92.159839] Dynamic subtest vcs0-smem: SUCCESS (1.282s)
[92.175049] Starting dynamic subtest: vecs0-smem
[93.455227] Dynamic subtest vecs0-smem: SUCCESS (1.295s)
[93.458130] Subtest capture: SUCCESS (5.147s)
[93.619240] [002/134] (955s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[93.825538] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[94.086337] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.261s)
[94.365900] [003/134] (954s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[94.557398] Starting subtest: x-tiled-16bpp-rotate-270
[94.862442] Subtest x-tiled-16bpp-rotate-270: SKIP (0.303s)
[95.116272] [004/134] (953s left) syncobj_wait (reset-multiple-signaled)
[95.223724] Starting subtest: reset-multiple-signaled
[95.227491] Subtest reset-multiple-signaled: SUCCESS (0.003s)
[95.394697] [005/134] (953s left) kms_psr (psr-sprite-mmap-cpu)
[95.571822] Subtest psr-sprite-mmap-cpu: SKIP (0.000s)
[95.661359] [006/134] (953s left) kms_pm_rpm (basic-rte)
[95.753395] Starting subtest: basic-rte
[97.967655] Subtest basic-rte: SUCCESS (2.204s)
[98.705532] [007/134] (950s left) kms_big_fb (yf-tiled-addfb)
[98.898893] Starting subtest: yf-tiled-addfb
[98.900526] Subtest yf-tiled-addfb: SUCCESS (0.001s)
[99.070504] [008/134] (950s left) syncobj_timeline (multi-wait-all-available-signaled)
[99.167674] Starting subtest: multi-wait-all-available-signaled
[99.172876] Subtest multi-wait-all-available-signaled: SUCCESS (0.003s)
[99.327307] [009/134] (949s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[99.532961] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[99.536989] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[99.688527] [010/134] (949s left) gem_spin_batch (user-each)
[99.813330] Starting subtest: user-each
[103.203461] Subtest user-each: SUCCESS (3.389s)
[103.491378] [011/134] (945s left) gem_exec_schedule (preempt-contexts)
[103.631610] Starting subtest: preempt-contexts
[103.632711] Starting dynamic subtest: rcs0
[103.741077] Dynamic subtest rcs0: SUCCESS (0.106s)
[103.758768] Starting dynamic subtest: bcs0
[103.880479] Dynamic subtest bcs0: SUCCESS (0.142s)
[103.904643] Starting dynamic subtest: vcs0
[103.993481] Dynamic subtest vcs0: SUCCESS (0.110s)
[104.014103] Starting dynamic subtest: vecs0
[104.106455] Dynamic subtest vecs0: SUCCESS (0.110s)
[104.108204] Subtest preempt-contexts: SUCCESS (0.480s)
[104.493876] [012/134] (945s left) drm_read (short-buffer-block)
[104.902698] Starting subtest: short-buffer-block
[104.905825] Subtest short-buffer-block: SUCCESS (0.001s)
[105.195925] [013/134] (944s left) gem_mmap_offset (mmap-unmap)
[105.291889] Starting subtest: mmap-unmap
[105.292939] Starting dynamic subtest: smem0
[193.388383] Dynamic subtest smem0: SUCCESS (88.093s)
[193.389303] Subtest mmap-unmap: SUCCESS (88.095s)
[193.582650] [014/134] (856s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[193.762728] Starting subtest: cursor-vs-flip-atomic-transitions
[233.812812] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (40.051s)
[234.121081] [015/134] (815s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[234.313906] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[234.316339] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[234.470608] [016/134] (815s left) kms_vblank (wait-busy)
[234.639651] Starting subtest: wait-busy
[234.645962] Starting dynamic subtest: pipe-A-HDMI-A-1
[237.533305] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.887s)
[237.567319] Starting dynamic subtest: pipe-A-HDMI-A-2
[240.137040] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.595s)
[240.159961] Starting dynamic subtest: pipe-C-HDMI-A-1
[242.737190] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.593s)
[242.773069] Starting dynamic subtest: pipe-C-HDMI-A-2
[245.325851] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.582s)
[245.327280] Subtest wait-busy: SUCCESS (10.685s)
[245.675862] [017/134] (804s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[245.884888] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[245.887276] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[246.039331] [018/134] (803s left) perf (gen12-unprivileged-single-ctx-counters)
[246.252385] Starting subtest: gen12-unprivileged-single-ctx-counters
[246.254160] Subtest gen12-unprivileged-single-ctx-counters: SKIP (0.000s)
[246.344522] [019/134] (803s left) kms_addfb_basic (invalid-set-prop)
[246.440666] Starting subtest: invalid-set-prop
[246.442634] Subtest invalid-set-prop: SUCCESS (0.000s)
[246.674107] [020/134] (803s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[246.787219] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[246.788872] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[247.064565] [021/134] (803s left) gem_exec_params (dr1-dirt)
[247.171407] Starting subtest: DR1-dirt
[247.172525] Subtest DR1-dirt: SUCCESS (0.001s)
[247.338712] [022/134] (802s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[247.537533] Starting subtest: cursor-rapid-movement-64x21
[247.582850] Starting dynamic subtest: pipe-A-HDMI-A-1
[248.453395] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.870s)
[248.557511] Starting dynamic subtest: pipe-C-HDMI-A-1
[249.087180] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.568s)
[249.089350] Subtest cursor-rapid-movement-64x21: SUCCESS (1.550s)
[249.410404] [023/134] (800s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[249.603900] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[249.606707] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[249.774722] [024/134] (800s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc)
[249.955841] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc
[249.962619] Starting dynamic subtest: pipe-A-HDMI-A-1
[249.973178] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[250.065923] Starting dynamic subtest: pipe-A-HDMI-A-2
[250.074706] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s)
[250.101400] Starting dynamic subtest: pipe-B-HDMI-A-1
[250.109355] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[250.125536] Starting dynamic subtest: pipe-B-HDMI-A-2
[250.134750] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.015s)
[250.152821] Starting dynamic subtest: pipe-C-HDMI-A-1
[250.160561] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[250.177579] Starting dynamic subtest: pipe-C-HDMI-A-2
[250.188138] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[250.190590] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SKIP (0.042s)
[250.918541] [025/134] (799s left) gem_exec_schedule (wide)
[251.172917] Starting subtest: wide
[251.173878] Starting dynamic subtest: rcs0
[270.703440] Dynamic subtest rcs0: SUCCESS (19.511s)
[270.734155] Starting dynamic subtest: bcs0
[291.135572] Dynamic subtest bcs0: SUCCESS (20.441s)
[291.174760] Starting dynamic subtest: vcs0
[311.433556] Dynamic subtest vcs0: SUCCESS (20.285s)
[311.476111] Starting dynamic subtest: vecs0
[332.086414] Dynamic subtest vecs0: SUCCESS (20.644s)
[332.088084] Subtest wide: SUCCESS (80.904s)
[333.040105] [FACT gem_exec_schedule (wide)] new: kernel.kmod_is_loaded.vgem: true
[333.044477] [026/134] (717s left) kms_chamelium_color (gamma)
[333.215589] Subtest gamma: SKIP (0.000s)
[333.322740] [027/134] (717s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions)
[333.512068] Starting subtest: flip-vs-cursor-atomic-transitions
[347.131192] Subtest flip-vs-cursor-atomic-transitions: FAIL (13.342s)
[347.220763] [028/134] (703s left) sw_sync (alloc_timeline)
[347.338090] Starting subtest: alloc_timeline
[347.340543] Subtest alloc_timeline: SUCCESS (0.000s)
[347.501371] [029/134] (703s left) gem_pread (bench)
[347.608444] Starting subtest: bench
[350.748186] Subtest bench: SUCCESS (3.139s)
[350.925109] [030/134] (700s left) gem_exec_whisper (basic-sync)
[351.046606] Starting subtest: basic-sync
[356.045183] Subtest basic-sync: SUCCESS (4.997s)
[356.339924] [031/134] (694s left) gem_ctx_param (set-priority-invalid-size)
[356.437769] Starting subtest: set-priority-invalid-size
[356.438997] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[356.597352] [032/134] (694s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[356.784583] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[359.413033] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SUCCESS (2.627s)
[359.672712] [033/134] (691s left) gem_busy (busy)
[359.795347] Starting subtest: busy
[359.796251] Starting dynamic subtest: all-engines
[359.876358] Dynamic subtest all-engines: SUCCESS (0.078s)
[359.896150] Starting dynamic subtest: rcs0
[359.897090] Dynamic subtest rcs0: SUCCESS (0.015s)
[359.916809] Starting dynamic subtest: bcs0
[359.917663] Dynamic subtest bcs0: SUCCESS (0.017s)
[359.936601] Starting dynamic subtest: vcs0
[359.937432] Dynamic subtest vcs0: SUCCESS (0.013s)
[359.956056] Starting dynamic subtest: vecs0
[359.956962] Dynamic subtest vecs0: SUCCESS (0.017s)
[359.957898] Subtest busy: SUCCESS (0.150s)
[360.379780] [034/134] (691s left) i915_pm_rpm (gem-pread)
[362.325559] Starting subtest: gem-pread
[362.940737] Subtest gem-pread: SUCCESS (0.662s)
[363.272778] [035/134] (688s left) kms_cursor_crc (cursor-suspend)
[363.454981] Starting subtest: cursor-suspend
[363.458549] Starting dynamic subtest: pipe-A-HDMI-A-1