[89.176944] Initializing watchdogs
[89.177246]   /dev/watchdog0
[89.216324] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[89.230665] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.239574] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.244880] [001/133] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[89.457445] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[91.460139] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SUCCESS (1.999s)
[91.683193] [002/133] (958s left) i915_pm_rpm (system-suspend-devices)
[93.633615] Starting subtest: system-suspend-devices
[99.786353] Subtest system-suspend-devices: SUCCESS (5.880s)
[108.929953] [003/133] (950s left) kms_chamelium_frames (dp-crc-multiple)
[109.521310] Subtest dp-crc-multiple: SKIP (0.000s)
[109.851748] [004/133] (949s left) kms_addfb_basic (basic)
[109.959273] Starting subtest: basic
[109.961881] Subtest basic: SUCCESS (0.000s)
[110.170804] [005/133] (948s left) gen9_exec_parse (batch-zero-length)
[110.303640] Starting subtest: batch-zero-length
[110.304913] Subtest batch-zero-length: SUCCESS (0.004s)
[110.570694] [006/133] (948s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[110.769056] Starting subtest: fbcpsr-2p-rte
[110.773358] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[110.904151] [007/133] (948s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[111.080342] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[111.087698] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[111.795532] [008/133] (947s left) gem_ctx_isolation (dirty-switch)
[111.924953] Starting subtest: dirty-switch
[111.926694] Starting dynamic subtest: rcs0
[112.522389] Dynamic subtest rcs0: SUCCESS (0.598s)
[112.540879] Starting dynamic subtest: bcs0
[113.491945] Dynamic subtest bcs0: SUCCESS (0.963s)
[113.517176] Starting dynamic subtest: vcs0
[114.077996] Dynamic subtest vcs0: SUCCESS (0.589s)
[114.101054] Starting dynamic subtest: vecs0
[114.665707] Dynamic subtest vecs0: SUCCESS (0.585s)
[114.667163] Subtest dirty-switch: SUCCESS (2.741s)
[114.900820] [009/133] (944s left) fbdev (pan)
[114.999641] Starting subtest: pan
[115.054556] Subtest pan: SUCCESS (0.055s)
[115.182279] [010/133] (944s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier)
[115.367513] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier
[115.372316] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier: SKIP (0.000s)
[115.498722] [011/133] (944s left) gem_readwrite (read-bad-handle)
[115.603663] Starting subtest: read-bad-handle
[115.606277] Subtest read-bad-handle: SUCCESS (0.000s)
[115.780252] [012/133] (943s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[115.885425] Starting subtest: invalid-multi-wait-all-unsubmitted
[115.886714] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.001s)
[116.015242] [013/133] (943s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[116.214272] Starting subtest: 4-tiled-8bpp-rotate-180
[116.218924] Subtest 4-tiled-8bpp-rotate-180: SKIP (0.000s)
[116.346937] [014/133] (943s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-signaled)
[116.451226] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[116.452722] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[116.584938] [015/133] (943s left) kms_cursor_crc (cursor-random-128x42)
[116.779289] Starting subtest: cursor-random-128x42
[116.824100] Starting dynamic subtest: pipe-A-HDMI-A-1
[121.001946] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.177s)
[121.119480] Starting dynamic subtest: pipe-C-HDMI-A-1
[124.928205] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.848s)
[124.929957] Subtest cursor-random-128x42: SUCCESS (8.147s)
[125.284282] [016/133] (934s left) kms_atomic_transition (plane-toggle-modeset-transition)
[125.464274] Starting subtest: plane-toggle-modeset-transition
[125.466083] Starting dynamic subtest: pipe-A-HDMI-A-1
[128.836292] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (3.369s)
[128.861043] Starting dynamic subtest: pipe-A-HDMI-A-2
[131.940998] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (3.097s)
[131.965214] Starting dynamic subtest: pipe-B-HDMI-A-1
[134.790917] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.842s)
[134.816229] Starting dynamic subtest: pipe-B-HDMI-A-2
[137.638916] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.837s)
[137.641638] Subtest plane-toggle-modeset-transition: SUCCESS (12.174s)
[137.928464] [017/133] (922s left) gem_exec_parallel (fds)
[138.060163] Starting subtest: fds
[138.062072] Starting dynamic subtest: rcs0
[143.029169] Dynamic subtest rcs0: SUCCESS (4.969s)
[143.049336] Starting dynamic subtest: bcs0
[148.505061] Dynamic subtest bcs0: SUCCESS (5.473s)
[148.527165] Starting dynamic subtest: vcs0
[153.471376] Dynamic subtest vcs0: SUCCESS (4.968s)
[153.492079] Starting dynamic subtest: vecs0
[158.631991] Dynamic subtest vecs0: SUCCESS (5.160s)
[158.633460] Subtest fds: SUCCESS (20.575s)
[158.896432] [018/133] (901s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[159.087858] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[159.091673] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[159.230712] [019/133] (901s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[159.435140] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[161.930295] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (2.492s)
[162.229972] [020/133] (898s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[162.421099] Starting subtest: x-tiled-8bpp-rotate-0
[165.762887] Subtest x-tiled-8bpp-rotate-0: SUCCESS (3.340s)
[166.027800] [021/133] (894s left) syncobj_timeline (invalid-transfer-illegal-handle)
[166.130435] Starting subtest: invalid-transfer-illegal-handle
[166.132591] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[166.263849] [022/133] (894s left) sw_sync (sync_merge_invalid)
[166.363073] Starting subtest: sync_merge_invalid
[166.370262] Subtest sync_merge_invalid: SUCCESS (0.006s)
[166.470322] [023/133] (894s left) kms_chamelium_hpd (dp-hpd)
[167.065153] Subtest dp-hpd: SKIP (0.000s)
[167.320673] [024/133] (893s left) vgem_basic (bad-handle)
[167.580167] Starting subtest: bad-handle
[167.581248] Subtest bad-handle: SUCCESS (0.000s)
[167.708191] [FACT vgem_basic (bad-handle)] new: kernel.kmod_is_loaded.vgem: true
[167.714336] [025/133] (893s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[167.921178] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[167.923546] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.001s)
[168.065583] [026/133] (892s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[168.280326] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[171.310123] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SUCCESS (3.026s)
[171.517144] [027/133] (889s left) kms_atomic_transition (modeset-transition-nonblocking)
[171.707170] Starting subtest: modeset-transition-nonblocking
[171.711085] Starting dynamic subtest: 1x-outputs
[175.231879] Dynamic subtest 1x-outputs: SUCCESS (3.529s)
[175.253926] Starting dynamic subtest: 2x-outputs
[182.582824] Dynamic subtest 2x-outputs: SUCCESS (7.348s)
[182.584472] Subtest modeset-transition-nonblocking: SUCCESS (10.882s)
[182.862839] [028/133] (878s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[182.969761] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[182.971280] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[183.147624] [029/133] (878s left) gem_exec_whisper (basic-queues-all)
[183.272467] Starting subtest: basic-queues-all
[191.623178] Subtest basic-queues-all: SUCCESS (8.348s)
[191.883768] [030/133] (869s left) kms_vblank (query-busy)
[192.056069] Starting subtest: query-busy
[192.064419] Starting dynamic subtest: pipe-A-HDMI-A-1
[194.934694] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.872s)
[194.958436] Starting dynamic subtest: pipe-A-HDMI-A-2
[197.573761] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.635s)
[197.627088] Starting dynamic subtest: pipe-C-HDMI-A-1
[200.164130] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.576s)
[200.194618] Starting dynamic subtest: pipe-C-HDMI-A-2
[202.792255] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.620s)
[202.793945] Subtest query-busy: SUCCESS (10.737s)
[203.146989] [031/133] (858s left) kms_flip (2x-nonexisting-fb-interruptible)
[203.343952] Starting subtest: 2x-nonexisting-fb-interruptible
[203.350107] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[204.120069] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (0.768s)
[204.167174] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[204.924082] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (0.800s)
[204.970693] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[205.644017] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (0.715s)
[205.650072] Subtest 2x-nonexisting-fb-interruptible: SUCCESS (2.298s)
[206.303636] [032/133] (855s left) perf_pmu (semaphore-wait-idle)
[206.419956] Starting subtest: semaphore-wait-idle
[206.422870] Starting dynamic subtest: rcs0
[206.941533] Dynamic subtest rcs0: SUCCESS (0.518s)
[206.965100] Starting dynamic subtest: bcs0
[207.459096] Dynamic subtest bcs0: SUCCESS (0.516s)
[207.483071] Starting dynamic subtest: vcs0
[207.978100] Dynamic subtest vcs0: SUCCESS (0.514s)
[208.000084] Starting dynamic subtest: vecs0
[208.497224] Dynamic subtest vecs0: SUCCESS (0.515s)
[208.498470] Subtest semaphore-wait-idle: SUCCESS (2.077s)
[208.650407] [033/133] (852s left) kms_plane_alpha_blend (alpha-transparent-fb)
[208.835076] Starting subtest: alpha-transparent-fb
[208.985064] Starting dynamic subtest: pipe-A-HDMI-A-1
[209.751738] Dynamic subtest pipe-A-HDMI-A-1: FAIL (0.448s)
[209.772979] Starting dynamic subtest: pipe-C-HDMI-A-1
[210.344566] Dynamic subtest pipe-C-HDMI-A-1: FAIL (0.468s)
[210.345761] Subtest alpha-transparent-fb: FAIL (1.079s)
[210.457853] [034/133] (851s left) perf_pmu (busy)
[210.571991] Starting subtest: busy
[210.575371] Starting dynamic subtest: rcs0
[211.111096] Dynamic subtest rcs0: SUCCESS (0.532s)
[211.135654] Starting dynamic subtest: bcs0
[211.643231] Dynamic subtest bcs0: SUCCESS (0.533s)
[211.665952] Starting dynamic subtest: vcs0
[212.176254] Dynamic subtest vcs0: SUCCESS (0.529s)
[212.198184] Starting dynamic subtest: vecs0
[212.711120] Dynamic subtest vecs0: SUCCESS (0.536s)
[212.712567] Subtest busy: SUCCESS (2.139s)
[212.875253] [035/133] (848s left) gem_exec_schedule (pi-shared-iova)
[213.132178] Starting subtest: pi-shared-iova
[213.134453] Starting dynamic subtest: rcs0
[213.193080] Dynamic subtest rcs0: SUCCESS (0.057s)
[213.219156] Starting dynamic subtest: bcs0
[213.270078] Dynamic subtest bcs0: SUCCESS (0.073s)
[213.313170] Starting dynamic subtest: vcs0
[213.327156] Dynamic subtest vcs0: SUCCESS (0.049s)
[213.367416] Starting dynamic subtest: vecs0
[213.377541] Dynamic subtest vecs0: SUCCESS (0.050s)
[213.380065] Subtest pi-shared-iova: SUCCESS (0.244s)
[213.664750] [036/133] (848s left) i915_suspend (forcewake)
[213.770052] Starting subtest: forcewake