[111.584953] Initializing watchdogs
[111.585206]   /dev/watchdog0
[111.624265] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[111.638996] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[111.647618] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[111.653417] [001/133] (960s left) perf_pmu (render-node-busy)
[111.791335] Starting subtest: render-node-busy
[111.795412] Starting dynamic subtest: rcs0
[112.320304] Dynamic subtest rcs0: SUCCESS (0.528s)
[112.338270] Starting dynamic subtest: bcs0
[112.853047] Dynamic subtest bcs0: SUCCESS (0.533s)
[112.876865] Starting dynamic subtest: vcs0
[113.380587] Dynamic subtest vcs0: SUCCESS (0.524s)
[113.403517] Starting dynamic subtest: vecs0
[113.903622] Dynamic subtest vecs0: SUCCESS (0.525s)
[113.904794] Subtest render-node-busy: SUCCESS (2.114s)
[114.059578] [002/133] (958s left) gem_exec_schedule (fairslice-all)
[114.188236] Starting subtest: fairslice-all
[116.302746] Subtest fairslice-all: SUCCESS (2.114s)
[116.710098] [003/133] (955s left) gem_eio (wait-immediate)
[116.846565] Starting subtest: wait-immediate
[116.908417] Subtest wait-immediate: SUCCESS (0.060s)
[117.131634] [004/133] (955s left) gem_ctx_engines (execute-oneforall)
[117.259983] Starting subtest: execute-oneforall
[117.918406] Subtest execute-oneforall: SUCCESS (0.655s)
[118.200485] [005/133] (954s left) gem_exec_flush (basic-uc-pro-default)
[118.341638] Starting subtest: basic-uc-pro-default
[123.773936] Subtest basic-uc-pro-default: SUCCESS (5.431s)
[124.033667] [006/133] (948s left) gem_ctx_shared (q-in-order)
[124.159127] Starting subtest: Q-in-order
[124.162081] Starting dynamic subtest: rcs0
[124.334889] Dynamic subtest rcs0: SUCCESS (0.164s)
[124.366175] Starting dynamic subtest: bcs0
[124.423876] Dynamic subtest bcs0: SUCCESS (0.093s)
[124.457768] Starting dynamic subtest: vcs0
[124.506855] Dynamic subtest vcs0: SUCCESS (0.084s)
[124.535461] Starting dynamic subtest: vecs0
[124.601335] Dynamic subtest vecs0: SUCCESS (0.087s)
[124.602575] Subtest Q-in-order: SUCCESS (0.435s)
[124.871403] [FACT gem_ctx_shared (q-in-order)] new: kernel.kmod_is_loaded.vgem: true
[124.877052] [007/133] (947s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[125.064099] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[125.068197] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[135.882268] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (10.810s)
[135.903249] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[146.575859] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.691s)
[146.596404] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[157.286772] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.701s)
[157.288954] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SUCCESS (32.215s)
[157.940329] [008/133] (914s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[158.142351] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[161.039428] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (2.894s)
[161.346073] [009/133] (911s left) kms_psr (pr-sprite-mmap-cpu)
[161.519617] Subtest pr-sprite-mmap-cpu: SKIP (0.000s)
[161.620562] [010/133] (911s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[162.206175] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[162.542148] [011/133] (910s left) kms_vblank (ts-continuation-modeset-hang)
[162.721173] Starting subtest: ts-continuation-modeset-hang
[162.723246] Starting dynamic subtest: pipe-A-HDMI-A-1
[169.855693] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (7.132s)
[169.900405] Starting dynamic subtest: pipe-A-HDMI-A-2
[176.837612] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (6.978s)
[176.893571] Starting dynamic subtest: pipe-C-HDMI-A-1
[183.860449] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (7.011s)
[183.885408] Starting dynamic subtest: pipe-C-HDMI-A-2
[190.832923] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (6.963s)
[190.834991] Subtest ts-continuation-modeset-hang: SUCCESS (28.116s)
[191.167467] [012/133] (882s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[191.353878] Starting subtest: cursorA-vs-flipB-varying-size
[202.695243] Subtest cursorA-vs-flipB-varying-size: SUCCESS (11.341s)
[203.073248] [013/133] (870s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier)
[203.281761] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier
[203.284444] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier: SKIP (0.000s)
[203.427790] [014/133] (869s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[203.647856] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[205.363971] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.717s)
[205.662357] [015/133] (867s left) kms_pm_rpm (dpms-non-lpsp)
[207.583436] Starting subtest: dpms-non-lpsp
[207.753063] Subtest dpms-non-lpsp: SUCCESS (0.162s)
[208.112584] [016/133] (865s left) kms_frontbuffer_tracking (psr-2p-rte)
[208.321934] Starting subtest: psr-2p-rte
[208.324500] Subtest psr-2p-rte: SKIP (0.000s)
[208.479332] [017/133] (865s left) gem_exec_reloc (basic-wc-noreloc)
[208.598967] Starting subtest: basic-wc-noreloc
[208.602796] Subtest basic-wc-noreloc: SUCCESS (0.010s)
[208.829114] [018/133] (864s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[209.020069] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[209.137137] [019/133] (864s left) i915_hangman (gt-engine-hang)
[209.272263] Starting subtest: gt-engine-hang
[209.274160] Starting dynamic subtest: rcs0
[209.860666] Dynamic subtest rcs0: SUCCESS (0.590s)
[209.887108] Starting dynamic subtest: bcs0
[210.452124] Dynamic subtest bcs0: SUCCESS (0.584s)
[210.475523] Starting dynamic subtest: vcs0
[211.032237] Dynamic subtest vcs0: SUCCESS (0.578s)
[211.056282] Starting dynamic subtest: vecs0
[211.606655] Dynamic subtest vecs0: SUCCESS (0.572s)
[211.608153] Subtest gt-engine-hang: SUCCESS (2.337s)
[211.768851] [020/133] (862s left) syncobj_timeline (wait-all-snapshot)
[211.874247] Starting subtest: wait-all-snapshot
[211.967118] Subtest wait-all-snapshot: SUCCESS (0.088s)
[212.117287] [021/133] (861s left) gem_exec_reloc (basic-write-read)
[212.229997] Starting subtest: basic-write-read
[212.244694] Subtest basic-write-read: SUCCESS (0.017s)
[212.478263] [022/133] (861s left) sw_sync (alloc_merge_fence)
[212.579296] Starting subtest: alloc_merge_fence
[212.580958] Subtest alloc_merge_fence: SUCCESS (0.001s)
[212.714531] [023/133] (861s left) prime_vgem (basic-fence-read)
[212.843406] Starting subtest: basic-fence-read
[212.980362] Subtest basic-fence-read: SUCCESS (0.135s)
[213.130300] [024/133] (861s left) gem_pwrite (basic-self)
[213.235387] Starting subtest: basic-self
[213.441076] Subtest basic-self: SUCCESS (0.206s)
[213.632143] [025/133] (860s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[213.833880] Starting subtest: 4-tiled-addfb-size-offset-overflow
[213.839156] Subtest 4-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[213.978162] [026/133] (860s left) gem_exec_schedule (noreorder-corked)
[214.240455] Starting subtest: noreorder-corked
[214.245033] Starting dynamic subtest: rcs0
[215.357733] Dynamic subtest rcs0: SUCCESS (1.114s)
[215.385187] Starting dynamic subtest: bcs0
[216.490144] Dynamic subtest bcs0: SUCCESS (1.125s)
[216.513107] Starting dynamic subtest: vcs0
[217.599828] Dynamic subtest vcs0: SUCCESS (1.111s)
[217.625673] Starting dynamic subtest: vecs0
[218.721077] Dynamic subtest vecs0: SUCCESS (1.117s)
[218.722279] Subtest noreorder-corked: SUCCESS (4.481s)
[218.981592] [027/133] (855s left) kms_invalid_mode (overflow-vrefresh)
[219.162464] Starting subtest: overflow-vrefresh
[219.166407] Starting dynamic subtest: pipe-A-HDMI-A-1
[219.574239] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.410s)
[219.617342] Starting dynamic subtest: pipe-A-HDMI-A-2
[219.620321] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.005s)
[219.645269] Starting dynamic subtest: pipe-B-HDMI-A-1
[219.646360] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.003s)
[219.666735] Starting dynamic subtest: pipe-B-HDMI-A-2
[219.672137] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.003s)
[219.713619] Starting dynamic subtest: pipe-C-HDMI-A-1
[219.715101] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.003s)
[219.741792] Starting dynamic subtest: pipe-C-HDMI-A-2
[219.748400] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.003s)
[219.753165] Subtest overflow-vrefresh: SUCCESS (0.462s)
[219.963266] [028/133] (854s left) kms_cursor_crc (cursor-onscreen-128x128)
[220.174065] Starting subtest: cursor-onscreen-128x128
[220.235576] Starting dynamic subtest: pipe-A-HDMI-A-1
[222.955472] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.720s)
[223.074583] Starting dynamic subtest: pipe-C-HDMI-A-1
[225.411764] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.381s)
[225.413820] Subtest cursor-onscreen-128x128: SUCCESS (5.239s)
[225.785761] [029/133] (849s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[225.994124] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[225.998488] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[226.149617] [030/133] (848s left) gem_exec_fence (syncobj-timeline-repeat)
[226.833678] Starting subtest: syncobj-timeline-repeat
[228.119343] Subtest syncobj-timeline-repeat: SUCCESS (1.283s)
[228.385189] [031/133] (846s left) kms_color (ctm-signed)
[228.569089] Starting subtest: ctm-signed
[228.570828] Starting dynamic subtest: pipe-A-HDMI-A-1
[229.537450] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.967s)
[229.584422] Starting dynamic subtest: pipe-A-HDMI-A-2
[230.224660] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.682s)
[230.259144] Starting dynamic subtest: pipe-B-HDMI-A-1
[230.887190] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.660s)
[230.929312] Starting dynamic subtest: pipe-B-HDMI-A-2
[231.566634] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.677s)
[231.605577] Starting dynamic subtest: pipe-C-HDMI-A-1
[232.238833] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.665s)
[232.270451] Starting dynamic subtest: pipe-C-HDMI-A-2
[232.893304] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.652s)
[232.898272] Subtest ctm-signed: SUCCESS (4.327s)
[233.216204] [032/133] (841s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[233.431143] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[233.434197] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[233.580627] [033/133] (841s left) kms_rotation_crc (bad-pixel-format)
[233.771093] Starting subtest: bad-pixel-format
[237.861279] Subtest bad-pixel-format: SUCCESS (4.094s)
[238.171244] [034/133] (837s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[238.354271] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[238.363056] Starting dynamic subtest: pipe-A-HDMI-A-1
[238.383962] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[238.430323] Starting dynamic subtest: pipe-A-HDMI-A-2
[238.482183] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s)
[238.516084] Starting dynamic subtest: pipe-B-HDMI-A-1
[238.527280] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[238.547494] Starting dynamic subtest: pipe-B-HDMI-A-2
[238.559107] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s)
[238.579154] Starting dynamic subtest: pipe-C-HDMI-A-1
[238.590324] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[238.610852] Starting dynamic subtest: pipe-C-HDMI-A-2
[238.622102] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[238.624270] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SKIP (0.043s)
[239.370155] [035/133] (836s left) gem_exec_balancer (invalid-bonds)
[239.492275] Starting subtest: invalid-bonds
[239.498523] Subtest invalid-bonds: SUCCESS (0.008s)
[239.767541] [036/133] (835s left) kms_plane (pixel-format-y-tiled-ccs-modifier)
[239.950184] Starting subtest: pixel-format-y-tiled-ccs-modifier
[240.185420] Starting dynamic subtest: pipe-A-plane-0
[240.485886] Dynamic subtest pipe-A-plane-0: SUCCESS (0.298s)
[240.507588] Starting dynamic subtest: pipe-A-plane-1
[240.752293] Dynamic subtest pipe-A-plane-1: SUCCESS (0.263s)
[240.773501] Starting dynamic subtest: pipe-A-plane-2
[241.034665] Dynamic subtest pipe-A-plane-2: SUCCESS (0.281s)
[241.055014] Starting dynamic subtest: pipe-A-plane-3
[241.316601] Dynamic subtest pipe-A-plane-3: SUCCESS (0.280s)
[241.346235] Starting dynamic subtest: pipe-A-plane-4
[241.357156] Dynamic subtest pipe-A-plane-4: SKIP (0.000s)
[241.734163] Starting dynamic subtest: pipe-B-plane-0
[241.979214] Dynamic subtest pipe-B-plane-0: SUCCESS (0.266s)
[242.006113] Starting dynamic subtest: pipe-B-plane-1
[242.264340] Dynamic subtest pipe-B-plane-1: SUCCESS (0.280s)
[242.286249] Starting dynamic subtest: pipe-B-plane-2
[242.548375] Dynamic subtest pipe-B-plane-2: SUCCESS (0.281s)
[242.571172] Starting dynamic subtest: pipe-B-plane-3
[242.815444] Dynamic subtest pipe-B-plane-3: SUCCESS (0.265s)
[242.843504] Starting dynamic subtest: pipe-B-plane-4
[242.849241] Dynamic subtest pipe-B-plane-4: SKIP (0.000s)
[243.064650] Subtest pixel-format-y-tiled-ccs-modifier: SUCCESS (3.111s)
[243.389548] [037/133] (832s left) gem_exec_schedule (wide)
[243.659999] Starting subtest: wide
[243.663204] Starting dynamic subtest: rcs0
[263.214364] Dynamic subtest rcs0: SUCCESS (19.541s)
[263.368099] Starting dynamic subtest: bcs0
[283.715080] Dynamic subtest bcs0: SUCCESS (20.490s)
[283.768809] Starting dynamic subtest: vcs0
[303.844341] Dynamic subtest vcs0: SUCCESS (20.122s)
[303.974040] Starting dynamic subtest: vecs0
[323.896145] Dynamic subtest vecs0: SUCCESS (20.044s)
[323.900509] Subtest wide: SUCCESS (80.233s)
[324.862636] [038/133] (750s left) kms_flip (2x-modeset-vs-vblank-race)
[325.041186] Starting subtest: 2x-modeset-vs-vblank-race
[325.044094] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[329.755507] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (4.713s)
[329.806115] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[334.388316] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (4.624s)
[334.436999] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[339.058667] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (4.659s)
[339.062059] Subtest 2x-modeset-vs-vblank-race: SUCCESS (14.023s)
[339.695095] [039/133] (736s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[339.898273] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[339.903135] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[340.052662] [040/133] (735s left) i915_suspend (basic-s2idle-without-i915)
[340.153385] Starting subtest: basic-s2idle-without-i915
[363.163365] Subtest basic-s2idle-without-i915: SUCCESS (8.259s)
[372.344892] [041/133] (712s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[372.560096] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[372.566494] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[372.720457] [042/133] (712s left) gem_close (many-handles-one-vma)
[372.839856] Starting subtest: many-handles-one-vma
[372.930356] Subtest many-handles-one-vma: SUCCESS (0.087s)
[373.070445] [043/133] (712s left) gem_fenced_exec_thrash (too-many-fences)
[373.183790] Starting subtest: too-many-fences
[375.360365] Subtest too-many-fences: SUCCESS (2.176s)
[375.534215] [044/133] (709s left) kms_getfb (getfb-handle-closed)
[375.729917] Starting subtest: getfb-handle-closed
[375.732220] Subtest getfb-handle-closed: SUCCESS (0.000s)
[375.885360] [045/133] (709s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[376.002417] Starting subtest: yf-tiled-ccs-to-x-tiled
[376.006003] Starting dynamic subtest: smem
[376.245472] Dynamic subtest smem: SUCCESS (0.241s)
[376.246619] Subtest yf-tiled-ccs-to-x-tiled: SUCCESS (0.244s)
[376.517461] [046/133] (708s left) kms_psr (psr2-suspend)
[376.709265] Subtest psr2-suspend: SKIP (0.000s)
[376.824949] [047/133] (708s left) kms_hdr (brightness-with-hdr)
[377.018162] Starting subtest: brightness-with-hdr
[377.028309] Subtest brightness-with-hdr: SKIP (0.002s)
[377.163608] [048/133] (708s left) i915_drm_fdinfo (busy-check-all)
[377.279281] Starting subtest: busy-check-all
[377.282138] Starting dynamic subtest: rcs0
[377.812118] Dynamic subtest rcs0: SUCCESS (0.534s)
[377.840997] Starting dynamic subtest: bcs0
[378.350100] Dynamic subtest bcs0: SUCCESS (0.535s)
[378.375034] Starting dynamic subtest: vcs0
[378.888044] Dynamic subtest vcs0: SUCCESS (0.532s)
[378.911922] Starting dynamic subtest: vecs0
[379.415479] Dynamic subtest vecs0: SUCCESS (0.529s)
[379.419677] Subtest busy-check-all: SUCCESS (2.148s)
[379.570473] [049/133] (706s left) gem_eio (in-flight-suspend)
[379.709307] Starting subtest: in-flight-suspend