[87.989526] Initializing watchdogs
[87.989709]   /dev/watchdog0
[88.029311] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[88.043917] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[88.050879] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[88.058938] [001/133] (960s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[88.283699] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[91.283586] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (3.008s)
[91.585987] [002/133] (957s left) perf_pmu (semaphore-busy)
[91.712796] Starting subtest: semaphore-busy
[91.717183] Starting dynamic subtest: rcs0
[93.262227] Dynamic subtest rcs0: SUCCESS (1.547s)
[93.282835] Starting dynamic subtest: bcs0
[94.820996] Dynamic subtest bcs0: SUCCESS (1.556s)
[94.841969] Starting dynamic subtest: vcs0
[96.365409] Dynamic subtest vcs0: SUCCESS (1.542s)
[96.386470] Starting dynamic subtest: vecs0
[97.913638] Dynamic subtest vecs0: SUCCESS (1.543s)
[97.917292] Subtest semaphore-busy: SUCCESS (6.197s)
[98.054286] [003/133] (950s left) kms_rotation_crc (primary-rotation-180)
[98.259544] Starting subtest: primary-rotation-180
[109.661093] Subtest primary-rotation-180: SUCCESS (11.403s)
[109.893781] [004/133] (938s left) kms_properties (connector-properties-legacy)
[110.078121] Starting subtest: connector-properties-legacy
[110.083085] Starting dynamic subtest: pipe-A-HDMI-A-1
[111.994279] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.911s)
[112.042077] Starting dynamic subtest: pipe-A-HDMI-A-2
[113.669165] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.668s)
[113.672588] Subtest connector-properties-legacy: SUCCESS (3.591s)
[113.945859] [005/133] (934s left) kms_atomic_error (invalid-api-usage)
[114.059828] Starting subtest: invalid-api-usage
[114.183434] Subtest invalid-api-usage: FAIL (0.063s)
[114.265290] [006/133] (934s left) kms_psr (fbc-psr-primary-page-flip)
[114.454079] Subtest fbc-psr-primary-page-flip: SKIP (0.000s)
[114.560746] [007/133] (934s left) gem_mmap_gtt (hang)
[114.673415] Starting subtest: hang
[120.108101] Subtest hang: SUCCESS (5.439s)
[120.247716] [008/133] (928s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[120.479055] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc
[120.482677] Starting dynamic subtest: pipe-A-HDMI-A-1
[120.533533] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[120.598030] Starting dynamic subtest: pipe-A-HDMI-A-2
[120.655599] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s)
[120.718500] Starting dynamic subtest: pipe-B-HDMI-A-1
[120.775032] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[120.837081] Starting dynamic subtest: pipe-B-HDMI-A-2
[120.882096] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s)
[120.923087] Starting dynamic subtest: pipe-C-HDMI-A-1
[120.965512] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[121.004072] Starting dynamic subtest: pipe-C-HDMI-A-2
[121.049046] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[121.056664] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SKIP (0.042s)
[121.911213] [009/133] (927s left) gem_mmap_gtt (zero-extend)
[122.136431] Starting subtest: zero-extend
[122.140049] Subtest zero-extend: SUCCESS (0.000s)
[122.399099] [010/133] (927s left) gem_ctx_param (invalid-set-ringsize)
[122.520215] Starting subtest: invalid-set-ringsize
[122.526235] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[122.666243] [011/133] (926s left) gem_wait (write-wait)
[122.827857] Starting subtest: write-wait
[122.833125] Starting dynamic subtest: all-engines
[123.957126] Dynamic subtest all-engines: SUCCESS (1.123s)
[124.004628] Starting dynamic subtest: rcs0
[124.997129] Dynamic subtest rcs0: SUCCESS (1.039s)
[125.027645] Starting dynamic subtest: bcs0
[126.039133] Dynamic subtest bcs0: SUCCESS (1.035s)
[126.061647] Starting dynamic subtest: vcs0
[127.055849] Dynamic subtest vcs0: SUCCESS (1.022s)
[127.079181] Starting dynamic subtest: vecs0
[128.100239] Dynamic subtest vecs0: SUCCESS (1.035s)
[128.104496] Subtest write-wait: SUCCESS (5.258s)
[128.384312] [FACT gem_wait (write-wait)] new: kernel.kmod_is_loaded.vgem: true
[128.391083] [012/133] (921s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[128.604223] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[131.075164] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (2.474s)
[131.354906] [013/133] (918s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[131.565931] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[131.580113] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.003s)
[131.736504] [014/133] (918s left) api_intel_allocator (two-level-inception-interruptible)
[131.849025] Starting subtest: two-level-inception-interruptible
[137.411538] Subtest two-level-inception-interruptible: SUCCESS (5.565s)
[137.558175] [015/133] (912s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-onoff)
[137.759206] Starting subtest: fbc-2p-primscrn-spr-indfb-onoff
[140.708549] Subtest fbc-2p-primscrn-spr-indfb-onoff: SUCCESS (2.949s)
[140.957127] [016/133] (909s left) gem_exec_schedule (in-order)
[141.100145] Starting subtest: in-order
[141.103632] Starting dynamic subtest: rcs0
[141.220101] Dynamic subtest rcs0: SUCCESS (0.119s)
[141.254866] Starting dynamic subtest: bcs0
[141.359302] Dynamic subtest bcs0: SUCCESS (0.136s)
[141.391232] Starting dynamic subtest: vcs0
[141.484242] Dynamic subtest vcs0: SUCCESS (0.124s)
[141.517294] Starting dynamic subtest: vecs0
[141.599112] Dynamic subtest vecs0: SUCCESS (0.119s)
[141.603848] Subtest in-order: SUCCESS (0.502s)
[141.990360] [017/133] (908s left) gem_workarounds (suspend-resume-fd)
[142.131339] Starting subtest: suspend-resume-fd