[89.485151] Initializing watchdogs
[89.485411]   /dev/watchdog0
[89.527348] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[89.542481] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.550285] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.557172] [001/156] (960s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[89.796163] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc
[89.802306] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.001s)
[89.943693] [002/156] (960s left) gem_ctx_shared (q-promotion)
[90.080622] Starting subtest: Q-promotion
[90.082238] Starting dynamic subtest: rcs0
[90.256724] Dynamic subtest rcs0: SUCCESS (0.172s)
[90.291387] Starting dynamic subtest: bcs0
[90.394235] Dynamic subtest bcs0: SUCCESS (0.137s)
[90.425512] Starting dynamic subtest: vcs0
[90.501076] Dynamic subtest vcs0: SUCCESS (0.106s)
[90.533889] Starting dynamic subtest: vecs0
[90.597249] Dynamic subtest vecs0: SUCCESS (0.093s)
[90.600709] Subtest Q-promotion: SUCCESS (0.514s)
[90.871387] [FACT gem_ctx_shared (q-promotion)] new: kernel.kmod_is_loaded.vgem: true
[90.876248] [003/156] (959s left) gem_exec_schedule (in-order)
[91.021535] Starting subtest: in-order
[91.023092] Starting dynamic subtest: rcs0
[91.144129] Dynamic subtest rcs0: SUCCESS (0.120s)
[91.175486] Starting dynamic subtest: bcs0
[91.296879] Dynamic subtest bcs0: SUCCESS (0.150s)
[91.334582] Starting dynamic subtest: vcs0
[91.425173] Dynamic subtest vcs0: SUCCESS (0.132s)
[91.489305] Starting dynamic subtest: vecs0
[91.573187] Dynamic subtest vecs0: SUCCESS (0.145s)
[91.578555] Subtest in-order: SUCCESS (0.552s)
[91.998242] [004/156] (958s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[92.197101] Starting subtest: psr-rgb565-draw-mmap-cpu
[92.202998] Subtest psr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[92.348162] [005/156] (958s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[92.981167] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[93.285618] [006/156] (957s left) kms_scaling_modes (scaling-mode-full)
[93.484474] Starting subtest: scaling-mode-full
[93.500189] Subtest scaling-mode-full: SKIP (0.001s)
[93.618890] [007/156] (957s left) gem_ctx_engines (execute-one)
[93.754694] Starting subtest: execute-one
[96.538705] Subtest execute-one: SUCCESS (2.783s)
[96.809554] [008/156] (953s left) kms_sequence (get-idle)
[96.988216] Starting subtest: get-idle
[96.991662] Starting dynamic subtest: pipe-A-HDMI-A-1
[99.785532] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.795s)
[99.824666] Starting dynamic subtest: pipe-A-HDMI-A-2
[102.271690] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.478s)
[102.307089] Starting dynamic subtest: pipe-B-HDMI-A-1
[104.716852] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.443s)
[104.746070] Starting dynamic subtest: pipe-B-HDMI-A-2
[107.183779] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.460s)
[107.226372] Starting dynamic subtest: pipe-C-HDMI-A-1
[109.611568] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.425s)
[109.632592] Starting dynamic subtest: pipe-C-HDMI-A-2
[112.085633] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.467s)
[112.089255] Subtest get-idle: SUCCESS (15.094s)
[112.410792] [009/156] (938s left) kms_cursor_legacy (forked-move)
[112.604732] Starting subtest: forked-move
[112.607908] Starting dynamic subtest: pipe-A
[118.108120] Dynamic subtest pipe-A: SUCCESS (5.491s)
[118.161668] Starting dynamic subtest: pipe-B
[123.528405] Dynamic subtest pipe-B: SUCCESS (5.423s)
[123.551653] Starting dynamic subtest: pipe-C
[128.948644] Dynamic subtest pipe-C: SUCCESS (5.419s)
[128.972067] Starting dynamic subtest: all-pipes
[134.379499] Dynamic subtest all-pipes: SUCCESS (5.430s)
[134.381255] Subtest forked-move: SUCCESS (21.771s)
[134.544207] [010/156] (916s left) kms_async_flips (test-time-stamp-atomic)
[134.727734] Starting subtest: test-time-stamp-atomic
[135.330327] Starting dynamic subtest: pipe-A-HDMI-A-1
[135.531471] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.202s)
[135.898176] Starting dynamic subtest: pipe-A-HDMI-A-2
[136.084225] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.206s)
[136.488149] Starting dynamic subtest: pipe-B-HDMI-A-1
[136.649628] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.237s)
[136.991292] Starting dynamic subtest: pipe-B-HDMI-A-2
[137.202423] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.233s)
[137.544382] Starting dynamic subtest: pipe-C-HDMI-A-1
[137.772814] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.252s)
[138.111596] Starting dynamic subtest: pipe-C-HDMI-A-2
[138.293255] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.214s)
[138.294787] Subtest test-time-stamp-atomic: SUCCESS (3.565s)
[138.929906] [011/156] (912s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[139.129079] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[150.547720] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SUCCESS (11.420s)
[150.885868] [012/156] (900s left) kms_pm_rpm (pc8-residency)
[152.827504] Starting subtest: pc8-residency
[152.835693] Subtest pc8-residency: SKIP (0.000s)
[153.279107] [013/156] (897s left) kms_content_protection (lic-type-1)
[153.497213] Starting subtest: lic-type-1
[153.515659] Subtest lic-type-1: SKIP (0.016s)
[153.663252] [014/156] (897s left) kms_frontbuffer_tracking (hdr-1p-pri-indfb-multidraw)
[153.892486] Starting subtest: hdr-1p-pri-indfb-multidraw
[153.897551] Subtest hdr-1p-pri-indfb-multidraw: SKIP (0.000s)
[154.045959] [015/156] (897s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[154.275722] Starting subtest: yf-tiled-16bpp-rotate-180
[157.880326] Subtest yf-tiled-16bpp-rotate-180: SUCCESS (3.603s)
[158.278521] [016/156] (893s left) kms_atomic_interruptible (legacy-dpms)
[158.476537] Starting subtest: legacy-dpms
[158.480494] Starting dynamic subtest: pipe-A-HDMI-A-1
[165.385105] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.906s)
[165.388126] Subtest legacy-dpms: SUCCESS (6.912s)
[165.748437] [017/156] (885s left) api_intel_allocator (alloc-simple)
[165.860980] Starting subtest: alloc-simple
[165.863554] Subtest alloc-simple: SUCCESS (0.004s)
[166.011969] [018/156] (885s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[166.195074] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.000s)
[166.304098] [019/156] (885s left) gem_exec_balancer (full-late-pulse)
[166.465840] Starting subtest: full-late-pulse
[171.116472] Subtest full-late-pulse: SUCCESS (4.651s)
[171.415712] [020/156] (880s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping)
[171.605278] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping
[171.608545] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[171.748900] [021/156] (880s left) gem_exec_store (pages)
[171.881106] Starting subtest: pages
[171.882852] Starting dynamic subtest: rcs0
[171.969869] Dynamic subtest rcs0: SUCCESS (0.088s)
[171.998194] Starting dynamic subtest: bcs0
[172.051932] Dynamic subtest bcs0: SUCCESS (0.079s)
[172.078170] Starting dynamic subtest: vcs0
[172.147182] Dynamic subtest vcs0: SUCCESS (0.090s)
[172.169201] Starting dynamic subtest: vecs0
[172.228901] Dynamic subtest vecs0: SUCCESS (0.081s)
[172.231956] Subtest pages: SUCCESS (0.351s)
[172.502889] [022/156] (879s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[172.613927] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[172.722787] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.109s)
[172.866389] [023/156] (879s left) syncobj_basic (bad-flags-handle-to-fd)
[172.971629] Starting subtest: bad-flags-handle-to-fd
[172.973348] Subtest bad-flags-handle-to-fd: SUCCESS (0.000s)
[173.129797] [024/156] (879s left) kms_vrr (negative-basic)
[173.321641] Starting subtest: negative-basic
[173.327610] Subtest negative-basic: SKIP (0.000s)
[173.466544] [025/156] (878s left) kms_ccs (bad-rotation-90-y-tiled-ccs)
[173.643749] Starting subtest: bad-rotation-90-y-tiled-ccs
[173.647193] Starting dynamic subtest: pipe-A-HDMI-A-1
[173.798047] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.122s)
[173.847713] Starting dynamic subtest: pipe-A-HDMI-A-2
[173.878253] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.050s)
[173.927110] Starting dynamic subtest: pipe-B-HDMI-A-1
[173.951053] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.050s)
[174.002487] Starting dynamic subtest: pipe-B-HDMI-A-2
[174.039105] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.046s)
[174.060093] Starting dynamic subtest: pipe-C-HDMI-A-1
[174.077443] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[174.101018] Starting dynamic subtest: pipe-C-HDMI-A-2
[174.116626] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s)
[174.118176] Subtest bad-rotation-90-y-tiled-ccs: SUCCESS (0.294s)
[174.973118] [026/156] (877s left) kms_invalid_mode (overflow-vrefresh)
[175.174429] Starting subtest: overflow-vrefresh
[175.185351] Starting dynamic subtest: pipe-A-HDMI-A-1
[175.648689] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.468s)
[175.708516] Starting dynamic subtest: pipe-A-HDMI-A-2
[175.712451] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.003s)
[175.763302] Starting dynamic subtest: pipe-B-HDMI-A-1
[175.766390] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.004s)
[175.810953] Starting dynamic subtest: pipe-B-HDMI-A-2
[175.812531] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.006s)
[175.837055] Starting dynamic subtest: pipe-C-HDMI-A-1
[175.838654] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.003s)
[175.861777] Starting dynamic subtest: pipe-C-HDMI-A-2
[175.863440] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.002s)
[175.864997] Subtest overflow-vrefresh: SUCCESS (0.520s)
[176.029740] [027/156] (876s left) gem_userptr_blits (unsync-overlap)
[176.167681] Subtest unsync-overlap: SKIP (0.000s)
[176.360852] [028/156] (876s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[176.582749] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[176.587888] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[176.743406] [029/156] (876s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[176.940133] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[176.943343] Starting dynamic subtest: A-HDMI-A1
[178.495359] Dynamic subtest A-HDMI-A1: SUCCESS (1.550s)
[178.522515] Starting dynamic subtest: B-HDMI-A1
[179.552342] Dynamic subtest B-HDMI-A1: SUCCESS (1.050s)
[179.576792] Starting dynamic subtest: C-HDMI-A1
[180.623321] Dynamic subtest C-HDMI-A1: SUCCESS (1.068s)
[180.653194] Starting dynamic subtest: A-HDMI-A2
[181.804776] Dynamic subtest A-HDMI-A2: SUCCESS (1.176s)
[181.838729] Starting dynamic subtest: B-HDMI-A2
[182.919672] Dynamic subtest B-HDMI-A2: SUCCESS (1.111s)
[182.947383] Starting dynamic subtest: C-HDMI-A2
[183.980256] Dynamic subtest C-HDMI-A2: SUCCESS (1.051s)
[183.982114] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (7.042s)
[184.383836] [030/156] (868s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[184.592104] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[184.597222] Subtest hdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[184.754243] [031/156] (868s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[184.975605] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[184.979438] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[185.132486] [032/156] (867s left) kms_big_fb (linear-16bpp-rotate-90)
[185.339193] Starting subtest: linear-16bpp-rotate-90
[185.671813] Subtest linear-16bpp-rotate-90: SKIP (0.332s)
[185.970641] [033/156] (867s left) gem_exec_schedule (fairslice)
[186.123143] Starting subtest: fairslice
[186.125964] Starting dynamic subtest: rcs0
[188.163355] Dynamic subtest rcs0: SUCCESS (2.034s)
[188.185801] Starting dynamic subtest: bcs0
[190.194440] Dynamic subtest bcs0: SUCCESS (2.029s)
[190.218254] Starting dynamic subtest: vcs0
[192.227330] Dynamic subtest vcs0: SUCCESS (2.027s)
[192.249925] Starting dynamic subtest: vecs0
[194.251847] Dynamic subtest vecs0: SUCCESS (2.024s)
[194.254386] Subtest fairslice: SUCCESS (8.130s)
[194.656024] [034/156] (858s left) gem_exec_fence (syncobj-timeline-export)
[195.338852] Starting subtest: syncobj-timeline-export
[195.342579] Subtest syncobj-timeline-export: SUCCESS (0.008s)
[195.621106] [035/156] (857s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[195.821998] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[195.826064] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[195.984836] [036/156] (857s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[196.171517] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[196.280922] [037/156] (857s left) kms_hdmi_inject (inject-audio)
[196.864424] Starting subtest: inject-audio
[197.140668] Subtest inject-audio: SUCCESS (0.276s)
[197.635799] [038/156] (856s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[197.849894] Starting subtest: x-tiled-16bpp-rotate-180
[201.271556] Subtest x-tiled-16bpp-rotate-180: SUCCESS (3.420s)
[201.692326] [039/156] (852s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[201.911166] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[201.915441] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[202.073721] [040/156] (851s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[202.279661] Starting subtest: extended-pageflip-modeset-hang-oldfb
[202.282491] Starting dynamic subtest: pipe-A
[206.843847] Dynamic subtest pipe-A: SUCCESS (4.558s)
[206.882233] Starting dynamic subtest: pipe-C
[210.843383] Dynamic subtest pipe-C: SUCCESS (3.996s)
[210.845204] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (8.572s)
[211.275232] [041/156] (842s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[211.529199] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite
[211.535768] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[211.691593] [042/156] (842s left) gem_exec_suspend (basic-s3)
[211.824149] Starting subtest: basic-S3
[211.825933] Starting dynamic subtest: smem