[91.930363] Initializing watchdogs
[91.930532]   /dev/watchdog0
[91.970864] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600]
[91.985804] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[91.993161] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[92.001332] [001/133] (960s left) gem_ctx_exec (basic-close-race)
[92.132226] Starting subtest: basic-close-race
[97.618733] Subtest basic-close-race: SUCCESS (5.486s)
[97.792876] [002/133] (954s left) gem_exec_reloc (basic-wc-cpu)
[97.916887] Starting subtest: basic-wc-cpu
[97.935372] Subtest basic-wc-cpu: SUCCESS (0.018s)
[98.159865] [003/133] (954s left) tools_test (tools_test)
[98.291140] Starting subtest: tools_test
[98.467967] Subtest tools_test: SUCCESS (0.199s)
[98.592666] [004/133] (954s left) vgem_basic (busy-fence)
[98.796053] Starting subtest: busy-fence
[98.799622] Subtest busy-fence: SUCCESS (0.004s)
[98.913661] [FACT vgem_basic (busy-fence)] new: kernel.kmod_is_loaded.vgem: true
[98.920093] [005/133] (953s left) kms_psr2_su (frontbuffer-xrgb8888)
[99.039207] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[99.146952] [006/133] (953s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[101.801823] Starting subtest: verify-pxp-execution-after-suspend-resume
[101.810696] Subtest verify-pxp-execution-after-suspend-resume: SKIP (0.000s)
[101.938064] [007/133] (951s left) kms_addfb_basic (bad-pitch-32)
[102.048107] Starting subtest: bad-pitch-32
[102.052423] Subtest bad-pitch-32: SUCCESS (0.000s)
[102.301203] [008/133] (950s left) gem_softpin (noreloc)
[102.430652] Starting subtest: noreloc
[107.925246] Subtest noreloc: SUCCESS (5.486s)
[108.085955] [009/133] (945s left) api_intel_allocator (reserve-simple)
[108.197599] Starting subtest: reserve-simple
[108.202027] Subtest reserve-simple: SUCCESS (0.004s)
[108.353833] [010/133] (945s left) kms_invalid_mode (bad-vsync-end)
[108.544574] Starting subtest: bad-vsync-end
[108.549509] Starting dynamic subtest: pipe-A-HDMI-A-1
[108.946903] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.408s)
[108.987216] Starting dynamic subtest: pipe-A-HDMI-A-2
[108.992316] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.002s)
[109.019785] Starting dynamic subtest: pipe-B-HDMI-A-1
[109.024417] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.006s)
[109.061373] Starting dynamic subtest: pipe-B-HDMI-A-2
[109.066384] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.003s)
[109.103301] Starting dynamic subtest: pipe-C-HDMI-A-1
[109.108265] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.004s)
[109.145182] Starting dynamic subtest: pipe-C-HDMI-A-2
[109.151193] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.002s)
[109.155716] Subtest bad-vsync-end: SUCCESS (0.452s)
[109.346731] [011/133] (944s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[109.536028] Starting subtest: primary-4-tiled-reflect-x-0
[109.544806] Subtest primary-4-tiled-reflect-x-0: SKIP (0.004s)
[109.687822] [012/133] (943s left) kms_frontbuffer_tracking (psr-rgb101010-draw-render)
[109.901089] Starting subtest: psr-rgb101010-draw-render
[109.907162] Subtest psr-rgb101010-draw-render: SKIP (0.000s)
[110.052277] [013/133] (943s left) gem_ctx_param (invalid-ctx-set)
[110.163285] Starting subtest: invalid-ctx-set
[110.168130] Subtest invalid-ctx-set: SUCCESS (0.000s)
[110.315347] [014/133] (943s left) i915_drm_fdinfo (virtual-busy-all)
[110.426916] Starting subtest: virtual-busy-all
[110.430304] Subtest virtual-busy-all: SUCCESS (0.002s)
[110.585450] [015/133] (943s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[110.790107] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[113.788341] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (3.002s)
[114.047794] [016/133] (939s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[114.158526] Starting subtest: wait-all-for-submit-delayed-submit
[114.265189] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.106s)
[114.429208] [017/133] (939s left) kms_psr (pr-basic)
[114.613110] Subtest pr-basic: SKIP (0.000s)
[114.725428] [018/133] (939s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[114.936231] Starting subtest: x-tiled-64bpp-rotate-0
[119.918106] Subtest x-tiled-64bpp-rotate-0: SUCCESS (4.981s)
[120.432308] [019/133] (933s left) kms_cursor_edge_walk (64x64-right-edge)
[120.617087] Starting subtest: 64x64-right-edge
[120.622956] Starting dynamic subtest: pipe-A-HDMI-A-1
[124.535729] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (3.912s)
[124.557076] Starting dynamic subtest: pipe-C-HDMI-A-1
[128.043191] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.506s)
[128.048499] Subtest 64x64-right-edge: SUCCESS (7.426s)
[128.363764] [020/133] (926s left) kms_cursor_crc (cursor-size-hints)
[128.561195] Starting subtest: cursor-size-hints
[128.564150] Starting dynamic subtest: pipe-A-HDMI-A-1
[129.473994] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.908s)
[129.503353] Starting dynamic subtest: pipe-C-HDMI-A-1
[130.037612] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.561s)
[130.041613] Subtest cursor-size-hints: SUCCESS (1.476s)
[130.409413] [021/133] (924s left) kms_psr (psr2-cursor-mmap-gtt)
[130.611071] Subtest psr2-cursor-mmap-gtt: SKIP (0.000s)
[130.715503] [022/133] (923s left) syncobj_wait (reset-during-wait-for-submit)
[130.830775] Starting subtest: reset-during-wait-for-submit
[130.931620] Subtest reset-during-wait-for-submit: SUCCESS (0.105s)
[131.084246] [023/133] (923s left) kms_dsc (dsc-with-bpc-formats)
[131.265068] Subtest dsc-with-bpc-formats: SKIP (0.000s)
[131.382556] [024/133] (923s left) gem_exec_capture (capture)
[131.508971] Starting subtest: capture
[131.515071] Starting dynamic subtest: rcs0-smem
[132.795733] Dynamic subtest rcs0-smem: SUCCESS (1.285s)
[132.816411] Starting dynamic subtest: bcs0-smem
[134.105025] Dynamic subtest bcs0-smem: SUCCESS (1.283s)
[134.124617] Starting dynamic subtest: vcs0-smem
[135.366780] Dynamic subtest vcs0-smem: SUCCESS (1.289s)
[135.387609] Starting dynamic subtest: vecs0-smem
[136.647048] Dynamic subtest vecs0-smem: SUCCESS (1.276s)
[136.652568] Subtest capture: SUCCESS (5.136s)
[136.803945] [025/133] (918s left) gem_exec_schedule (out-order)
[136.947222] Starting subtest: out-order
[136.950404] Starting dynamic subtest: rcs0
[137.067442] Dynamic subtest rcs0: SUCCESS (0.120s)
[137.099832] Starting dynamic subtest: bcs0
[137.206858] Dynamic subtest bcs0: SUCCESS (0.135s)
[137.238966] Starting dynamic subtest: vcs0
[137.331419] Dynamic subtest vcs0: SUCCESS (0.119s)
[137.359106] Starting dynamic subtest: vecs0
[137.450131] Dynamic subtest vecs0: SUCCESS (0.120s)
[137.453699] Subtest out-order: SUCCESS (0.502s)
[137.854481] [026/133] (917s left) gem_exec_reloc (basic-write-cpu-active)
[137.976267] Starting subtest: basic-write-cpu-active
[138.111777] Subtest basic-write-cpu-active: SUCCESS (0.139s)
[138.340591] [027/133] (916s left) gem_ccs (block-multicopy-compressed)
[138.464309] Subtest block-multicopy-compressed: SKIP (0.000s)
[138.578697] [028/133] (916s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[138.766161] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[138.883068] [029/133] (916s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[139.005932] Starting subtest: writes-after-reads-uncached
[159.394856] Subtest writes-after-reads-uncached: SUCCESS (20.391s)
[159.582835] [030/133] (895s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[159.793370] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[159.800311] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s)
[159.956328] [031/133] (895s left) kms_pm_backlight (basic-brightness)
[160.143247] Subtest basic-brightness: SKIP (0.000s)
[160.261787] [032/133] (895s left) perf_pmu (render-node-busy-idle)
[160.393234] Starting subtest: render-node-busy-idle
[160.396699] Starting dynamic subtest: rcs0
[161.118415] Dynamic subtest rcs0: SUCCESS (0.630s)
[161.183411] Starting dynamic subtest: bcs0
[161.771352] Dynamic subtest bcs0: SUCCESS (0.628s)
[161.837943] Starting dynamic subtest: vcs0
[162.282485] Dynamic subtest vcs0: SUCCESS (0.630s)
[162.305878] Starting dynamic subtest: vecs0
[162.914709] Dynamic subtest vecs0: SUCCESS (0.628s)
[162.919259] Subtest render-node-busy-idle: SUCCESS (2.520s)
[163.058197] [033/133] (892s left) gem_softpin (evict-active)
[163.181750] Starting subtest: evict-active
[163.193395] Subtest evict-active: SUCCESS (0.017s)
[163.357964] [034/133] (892s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[163.562084] Starting subtest: fbc-rgb101010-draw-render
[166.556326] Subtest fbc-rgb101010-draw-render: SUCCESS (3.003s)
[166.822586] [035/133] (889s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[167.011928] Starting subtest: 2x-flip-vs-rmfb-interruptible
[167.016694] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[177.775283] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (10.743s)
[177.813040] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[188.524552] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.759s)
[188.559180] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[199.230306] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.697s)
[199.237112] Subtest 2x-flip-vs-rmfb-interruptible: SUCCESS (32.218s)
[199.852611] [036/133] (856s left) gem_exec_flush (basic-wb-rw-default)
[199.987056] Starting subtest: basic-wb-rw-default
[205.404204] Subtest basic-wb-rw-default: SUCCESS (5.419s)
[205.672204] [037/133] (850s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[206.389176] Starting subtest: syncobj-backward-timeline-chain-engines
[206.512494] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.122s)
[206.788932] [038/133] (849s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-5)
[206.972616] Starting subtest: planes-upscale-20x20-downscale-factor-0-5
[206.977073] Starting dynamic subtest: pipe-A
[207.287719] Dynamic subtest pipe-A: SUCCESS (0.317s)
[207.314941] Starting dynamic subtest: pipe-B
[207.681137] Dynamic subtest pipe-B: SUCCESS (0.390s)
[207.706138] Starting dynamic subtest: pipe-C
[207.771669] Dynamic subtest pipe-C: SUCCESS (0.083s)
[207.775370] Subtest planes-upscale-20x20-downscale-factor-0-5: SUCCESS (0.800s)
[208.267774] [039/133] (848s left) i915_pm_rps (min-max-config-loaded)
[208.398141] Starting subtest: min-max-config-loaded
[208.775687] Subtest min-max-config-loaded: SUCCESS (0.374s)
[208.931894] [040/133] (847s left) gem_media_vme
[209.174631] [041/133] (847s left) gem_exec_parallel (fds)
[209.304350] Starting subtest: fds
[209.307837] Starting dynamic subtest: rcs0
[214.685222] Dynamic subtest rcs0: SUCCESS (5.380s)
[214.711533] Starting dynamic subtest: bcs0
[221.352660] Dynamic subtest bcs0: SUCCESS (6.664s)
[221.376222] Starting dynamic subtest: vcs0
[226.578378] Dynamic subtest vcs0: SUCCESS (5.222s)
[226.614457] Starting dynamic subtest: vecs0
[241.337471] Dynamic subtest vecs0: SUCCESS (14.754s)
[241.340704] Subtest fds: SUCCESS (32.036s)
[241.619443] [042/133] (815s left) syncobj_basic (illegal-fd-to-handle)
[241.729694] Starting subtest: illegal-fd-to-handle
[241.733511] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[241.876883] [043/133] (814s left) gem_softpin (invalid)
[241.996690] Starting subtest: invalid
[242.011320] Subtest invalid: SUCCESS (0.011s)
[242.165780] [044/133] (814s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[242.387406] Starting subtest: fbcpsr-rgb565-draw-blt
[242.394544] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[242.549089] [045/133] (814s left) device_reset (unbind-reset-rebind)
[242.690629] Starting subtest: unbind-reset-rebind
[248.995150] Subtest unbind-reset-rebind: SUCCESS (6.301s)
[249.107321] [046/133] (807s left) kms_vblank (wait-forked-hang)
[249.300108] Starting subtest: wait-forked-hang
[249.304906] Starting dynamic subtest: pipe-A-HDMI-A-1
[256.854106] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (7.545s)
[256.909397] Starting dynamic subtest: pipe-A-HDMI-A-2
[263.896156] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (7.039s)
[263.970064] Starting dynamic subtest: pipe-C-HDMI-A-1
[270.863953] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (6.954s)
[270.907687] Starting dynamic subtest: pipe-C-HDMI-A-2
[277.802153] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (6.931s)
[277.806241] Subtest wait-forked-hang: SUCCESS (28.507s)
[278.110245] [047/133] (779s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[278.334154] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[278.340608] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[278.491805] [048/133] (778s left) perf_pmu (semaphore-wait)
[278.616812] Starting subtest: semaphore-wait
[278.620362] Starting dynamic subtest: rcs0
[279.138577] Dynamic subtest rcs0: SUCCESS (0.517s)
[279.164103] Starting dynamic subtest: bcs0
[279.658082] Dynamic subtest bcs0: SUCCESS (0.514s)
[279.695124] Starting dynamic subtest: vcs0
[280.186643] Dynamic subtest vcs0: SUCCESS (0.526s)
[280.214115] Starting dynamic subtest: vecs0
[280.705073] Dynamic subtest vecs0: SUCCESS (0.513s)
[280.708368] Subtest semaphore-wait: SUCCESS (2.089s)
[280.871827] [049/133] (776s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[281.079984] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[281.089619] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[281.227137] [050/133] (776s left) gem_exec_schedule (u-semaphore-resolve)
[281.365468] Starting subtest: u-semaphore-resolve
[281.405220] Subtest u-semaphore-resolve: SUCCESS (0.041s)
[281.827210] [051/133] (775s left) api_intel_bb (purge-bb)
[281.940041] Starting subtest: purge-bb
[281.944840] Subtest purge-bb: SUCCESS (0.006s)
[282.088299] [052/133] (775s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[282.294040] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[282.300914] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[282.460228] [053/133] (775s left) kms_color (invalid-ctm-matrix-sizes)
[282.668041] Starting subtest: invalid-ctm-matrix-sizes
[282.672258] Starting dynamic subtest: pipe-A
[282.675640] Dynamic subtest pipe-A: SUCCESS (0.013s)
[282.721027] Starting dynamic subtest: pipe-B
[282.726590] Dynamic subtest pipe-B: SUCCESS (0.030s)
[282.756163] Starting dynamic subtest: pipe-C
[282.760153] Dynamic subtest pipe-C: SUCCESS (0.025s)
[282.763206] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.082s)
[282.937154] [054/133] (774s left) i915_query (query-topology-garbage-items)
[283.050778] Starting subtest: query-topology-garbage-items
[283.053978] Subtest query-topology-garbage-items: SUCCESS (0.001s)
[283.206846] [055/133] (774s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[283.417051] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[283.536957] [056/133] (774s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[283.763096] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[283.775944] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.004s)
[283.926187] [057/133] (774s left) syncobj_wait (multi-wait-submitted-signaled)
[284.039573] Starting subtest: multi-wait-submitted-signaled
[284.044476] Subtest multi-wait-submitted-signaled: SUCCESS (0.002s)
[284.189266] [058/133] (774s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[284.388489] Starting subtest: cursorB-vs-flipA-varying-size
[295.796392] Subtest cursorB-vs-flipA-varying-size: SUCCESS (11.349s)
[296.587468] [059/133] (762s left) kms_pm_rpm (system-suspend-modeset)
[298.696914] Starting subtest: system-suspend-modeset