[57.163602] Initializing watchdogs
[57.163649]   /dev/watchdog0
[57.178871] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.183433] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.185711] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.189161] [001/133] (960s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled)
[57.263960] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[57.266400] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[57.379719] [002/133] (960s left) kms_color (ctm-max)
[57.493258] Starting subtest: ctm-max
[57.495516] Starting dynamic subtest: pipe-A-eDP-1
[58.235736] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.745s)
[58.244068] Starting dynamic subtest: pipe-B-eDP-1
[59.688771] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.455s)
[59.697366] Starting dynamic subtest: pipe-C-eDP-1
[61.058816] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.372s)
[61.066925] Starting dynamic subtest: pipe-D-eDP-1
[62.380037] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.323s)
[62.382431] Subtest ctm-max: SUCCESS (4.898s)
[63.177168] [003/133] (954s left) gem_eio (in-flight-1us)
[63.334948] Starting subtest: in-flight-1us
[70.820680] Subtest in-flight-1us: SUCCESS (7.490s)
[70.969185] [FACT gem_eio (in-flight-1us)] new: kernel.kmod_is_loaded.vgem: true
[70.972542] [004/133] (946s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[71.089158] Starting subtest: basic-busy-flip-before-cursor-legacy
[71.093701] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[71.235385] [005/133] (946s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[71.318065] Starting subtest: fbc-psr2-sprite-plane-onoff
[71.320279] Starting dynamic subtest: eDP-1
[71.324452] Dynamic subtest eDP-1: SKIP (0.000s)
[71.328742] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.000s)
[71.390186] [006/133] (946s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[71.481784] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[71.484130] Starting dynamic subtest: pipe-A-eDP-1
[71.637099] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.147s)
[71.646549] Starting dynamic subtest: pipe-B-eDP-1
[71.663878] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.012s)
[71.672687] Starting dynamic subtest: pipe-C-eDP-1
[71.689389] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.012s)
[71.698483] Starting dynamic subtest: pipe-D-eDP-1
[71.713385] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.016s)
[71.714916] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.190s)
[73.935617] [007/133] (943s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[74.038391] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[74.042211] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[74.146152] [008/133] (943s left) kms_flip (flip-vs-panning)
[74.247086] Starting subtest: flip-vs-panning
[74.249461] Starting dynamic subtest: A-eDP1
[82.964684] Dynamic subtest A-eDP1: SUCCESS (8.721s)
[82.972673] Starting dynamic subtest: B-eDP1
[91.692500] Dynamic subtest B-eDP1: SUCCESS (8.726s)
[91.700743] Starting dynamic subtest: C-eDP1
[100.415024] Dynamic subtest C-eDP1: SUCCESS (8.722s)
[100.425503] Starting dynamic subtest: D-eDP1
[109.149164] Dynamic subtest D-eDP1: SUCCESS (8.733s)
[109.153904] Subtest flip-vs-panning: SUCCESS (34.911s)
[110.300229] [009/133] (907s left) gem_exec_fence (submit3)
[110.472220] Starting subtest: submit3
[110.477173] Subtest submit3: SKIP (0.000s)
[111.972580] [010/133] (906s left) api_intel_allocator (gem-pool)
[112.029412] Starting subtest: gem-pool
[112.034761] Subtest gem-pool: SUCCESS (0.008s)
[112.120238] [011/133] (905s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[112.179254] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[112.235013] [012/133] (905s left) i915_pm_rpm (sysfs-read)
[114.068512] Starting subtest: sysfs-read
[115.243978] Subtest sysfs-read: SUCCESS (1.173s)
[115.617237] [013/133] (902s left) kms_cursor_crc (cursor-onscreen-64x64)
[115.818849] Starting subtest: cursor-onscreen-64x64
[115.821326] Starting dynamic subtest: pipe-A-eDP-1
[117.943323] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.123s)
[117.973043] Starting dynamic subtest: pipe-D-eDP-1
[120.667939] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.701s)
[120.670401] Subtest cursor-onscreen-64x64: SUCCESS (4.850s)
[121.488274] [014/133] (896s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[121.586266] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[121.590361] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[121.673949] [015/133] (896s left) gem_userptr_blits (forbidden-operations)
[121.743797] Starting subtest: forbidden-operations
[121.747896] Subtest forbidden-operations: SKIP (0.000s)
[121.855368] [016/133] (896s left) kms_big_fb (linear-64bpp-rotate-270)
[121.954629] Starting subtest: linear-64bpp-rotate-270
[121.973604] Subtest linear-64bpp-rotate-270: SKIP (0.018s)
[122.085546] [017/133] (896s left) kms_atomic_transition (plane-all-transition)
[122.174124] Starting subtest: plane-all-transition
[122.176356] Starting dynamic subtest: pipe-A-eDP-1
[124.666989] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[124.972689] Starting dynamic subtest: pipe-B-eDP-1
[128.207456] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.242s)
[128.520674] Subtest plane-all-transition: SUCCESS (6.347s)
[129.341097] [018/133] (889s left) api_intel_bb (reset-flags)
[129.399376] Starting subtest: reset-flags
[129.404236] Subtest reset-flags: SUCCESS (0.007s)
[129.534228] [019/133] (888s left) gem_sync (basic-many-each)
[129.619767] Starting subtest: basic-many-each
[131.904680] Subtest basic-many-each: SUCCESS (2.296s)
[132.094394] [020/133] (886s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[132.195966] Starting subtest: yf-tiled-8bpp-rotate-0
[132.200174] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[132.292825] [021/133] (886s left) gem_exec_balancer (nohangcheck)
[132.548063] Starting subtest: nohangcheck
[133.457108] Subtest nohangcheck: SUCCESS (0.908s)
[133.597723] [022/133] (884s left) kms_plane_alpha_blend (alpha-7efc)
[133.683432] Starting subtest: alpha-7efc
[133.964550] Starting dynamic subtest: pipe-A-eDP-1
[134.606648] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.640s)
[134.635345] Starting dynamic subtest: pipe-D-eDP-1
[135.968114] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.336s)
[135.970489] Subtest alpha-7efc: SUCCESS (2.284s)
[136.814634] [023/133] (881s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[136.919732] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[136.924268] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[136.999761] [024/133] (881s left) gem_softpin (evict-snoop-interruptible)
[137.062851] Starting subtest: evict-snoop-interruptible
[137.067506] Subtest evict-snoop-interruptible: SKIP (0.000s)
[137.184204] [025/133] (881s left) kms_hdmi_inject (inject-audio)
[137.540348] Starting subtest: inject-audio
[137.816225] Subtest inject-audio: SUCCESS (0.278s)
[138.376110] [026/133] (880s left) gem_bad_reloc (negative-reloc-bltcopy)
[138.433055] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[138.503390] [027/133] (880s left) gem_userptr_blits (unsync-overlap)
[138.563264] Subtest unsync-overlap: SKIP (0.000s)
[138.677604] [028/133] (880s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[138.792343] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[138.796875] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[138.938929] [029/133] (879s left) gem_eio (in-flight-internal-immediate)
[139.167529] Starting subtest: in-flight-internal-immediate
[140.970511] Subtest in-flight-internal-immediate: SUCCESS (1.802s)
[141.115907] [030/133] (877s left) kms_pm_backlight (brightness-with-dpms)
[141.214011] Starting subtest: brightness-with-dpms
[141.263766] Starting dynamic subtest: eDP-1
[143.708307] Dynamic subtest eDP-1: SUCCESS (2.444s)
[143.710673] Subtest brightness-with-dpms: SUCCESS (2.496s)
[144.544819] [031/133] (874s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[144.601467] Starting subtest: y-tiled-ccs-to-yf-tiled
[144.606039] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[144.794546] [032/133] (874s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-wc)
[144.888949] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-wc
[144.893274] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[144.989184] [033/133] (874s left) dmabuf_sync_file (import-multiple-read-only)
[145.029075] Starting subtest: import-multiple-read-only
[145.031365] Subtest import-multiple-read-only: SUCCESS (0.003s)
[145.085752] [034/133] (873s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[145.151863] Starting subtest: framebuffer-vs-set-tiling
[145.156341] Subtest framebuffer-vs-set-tiling: SKIP (0.000s)
[145.282588] [035/133] (873s left) gem_exec_capture (userptr)
[145.350703] Starting subtest: userptr
[146.185858] Subtest userptr: SUCCESS (0.835s)
[146.332454] [036/133] (872s left) fbdev (nullptr)
[146.369780] Starting subtest: nullptr
[146.372072] Subtest nullptr: SUCCESS (0.000s)
[146.425419] [037/133] (872s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[146.517836] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[151.835480] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.321s)
[153.059564] [038/133] (866s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[153.159710] Starting subtest: x-tiled-64bpp-rotate-270
[153.182894] Subtest x-tiled-64bpp-rotate-270: SKIP (0.023s)
[153.286372] [039/133] (865s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[153.386594] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[153.390946] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[153.497706] [040/133] (865s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[153.587265] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[153.591601] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[153.674037] [041/133] (865s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[153.780387] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[153.784767] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[153.869418] [042/133] (865s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[153.941659] Subtest writes-after-reads-uncached: SKIP (0.000s)
[154.009536] [043/133] (865s left) kms_hdr (static-toggle-dpms)
[154.102253] Starting subtest: static-toggle-dpms
[154.107767] Subtest static-toggle-dpms: SKIP (0.000s)
[154.176489] [044/133] (865s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[154.234597] Starting subtest: etime-multi-wait-for-submit-submitted
[154.337205] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.102s)
[154.413927] [045/133] (865s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[154.519762] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[154.523881] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[154.617922] [046/133] (864s left) perf_pmu (idle)
[154.655598] Starting subtest: idle
[154.657600] Starting dynamic subtest: rcs0
[155.199301] Dynamic subtest rcs0: SUCCESS (0.543s)
[155.211871] Starting dynamic subtest: bcs0
[155.715541] Dynamic subtest bcs0: SUCCESS (0.514s)
[155.727177] Starting dynamic subtest: vcs0
[156.234468] Dynamic subtest vcs0: SUCCESS (0.517s)
[156.246506] Starting dynamic subtest: vcs1
[156.752962] Dynamic subtest vcs1: SUCCESS (0.515s)
[156.764929] Starting dynamic subtest: vecs0
[157.267423] Dynamic subtest vecs0: SUCCESS (0.512s)
[157.279041] Starting dynamic subtest: ccs0
[157.788231] Dynamic subtest ccs0: SUCCESS (0.518s)
[157.791210] Subtest idle: SUCCESS (3.135s)
[157.911252] [047/133] (861s left) kms_chamelium_color (degamma)
[158.015893] Subtest degamma: SKIP (0.000s)
[158.068286] [048/133] (861s left) gem_pxp (create-regular-context-2)
[158.193733] Starting subtest: create-regular-context-2
[158.196154] Subtest create-regular-context-2: SUCCESS (0.003s)
[158.304562] [049/133] (861s left) gem_softpin (allocator-basic-reserve)
[158.369512] Starting subtest: allocator-basic-reserve
[158.444666] Subtest allocator-basic-reserve: SUCCESS (0.075s)
[158.585890] [050/133] (861s left) kms_plane_multiple (2x-tiling-none)
[158.680198] Starting subtest: 2x-tiling-none
[158.684495] Subtest 2x-tiling-none: SKIP (0.000s)
[158.750857] [051/133] (860s left) kms_feature_discovery (psr1)
[158.855423] Starting subtest: psr1
[158.857188] Subtest psr1: SUCCESS (0.000s)
[158.927498] [052/133] (860s left) kms_getfb (getfb-handle-protection)
[159.028805] Starting subtest: getfb-handle-protection
[159.050324] Subtest getfb-handle-protection: SUCCESS (0.024s)
[159.180734] [053/133] (860s left) kms_prop_blob (blob-prop-core)
[159.240534] Starting subtest: blob-prop-core
[159.242426] Subtest blob-prop-core: SUCCESS (0.000s)
[159.350081] [054/133] (860s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[159.441492] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[159.443474] Starting dynamic subtest: pipe-A
[160.489244] Dynamic subtest pipe-A: SUCCESS (1.050s)
[160.498292] Starting dynamic subtest: pipe-B
[162.601785] Dynamic subtest pipe-B: SUCCESS (2.111s)
[162.618080] Starting dynamic subtest: pipe-C
[163.757831] Dynamic subtest pipe-C: SUCCESS (1.153s)
[163.771157] Starting dynamic subtest: pipe-D
[164.965200] Dynamic subtest pipe-D: SUCCESS (1.205s)
[164.967623] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.527s)
[166.135464] [055/133] (853s left) gem_create (create-size-update)
[166.196329] Starting subtest: create-size-update
[166.198347] Subtest create-size-update: SUCCESS (0.000s)
[166.414731] [056/133] (853s left) syncobj_timeline (transfer-timeline-point)
[166.459471] Starting subtest: transfer-timeline-point
[166.461779] Subtest transfer-timeline-point: SUCCESS (0.000s)
[166.558325] [057/133] (853s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[166.653090] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[166.655390] Starting dynamic subtest: pipe-A-eDP-1
[167.131542] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.450s)
[167.142791] Starting dynamic subtest: pipe-B-eDP-1
[168.598165] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.464s)
[168.610181] Starting dynamic subtest: pipe-C-eDP-1
[169.986867] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.390s)
[169.997727] Starting dynamic subtest: pipe-D-eDP-1
[171.379301] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.391s)
[171.381591] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.700s)
[172.518971] [058/133] (847s left) vgem_basic (dmabuf-fence)
[172.571269] Starting subtest: dmabuf-fence
[172.573675] Subtest dmabuf-fence: SUCCESS (0.001s)
[172.630300] [059/133] (847s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[172.727481] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[172.731658] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[172.828917] [060/133] (847s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[172.920879] Starting subtest: random-ccs-data-y-tiled-ccs
[172.923040] Starting dynamic subtest: pipe-A-eDP-1
[172.947473] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[172.957416] Starting dynamic subtest: pipe-B-eDP-1
[172.979760] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[172.988070] Starting dynamic subtest: pipe-C-eDP-1
[173.010594] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[173.018573] Starting dynamic subtest: pipe-D-eDP-1
[173.041418] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[173.045576] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.005s)
[175.135508] [061/133] (845s left) kms_addfb_basic (bad-pitch-63)
[175.190592] Starting subtest: bad-pitch-63
[175.192526] Subtest bad-pitch-63: SUCCESS (0.000s)
[175.340314] [062/133] (844s left) gem_exec_schedule (u-semaphore-user)
[175.409642] Starting subtest: u-semaphore-user
[175.464402] Subtest u-semaphore-user: SUCCESS (0.059s)
[176.969969] [063/133] (843s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[177.063761] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[177.066055] Starting dynamic subtest: pipe-A-eDP-1
[177.162069] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[177.171148] Starting dynamic subtest: pipe-B-eDP-1
[178.278172] Dynamic subtest pipe-B-eDP-1: SKIP (1.114s)
[178.282782] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.216s)
[179.526503] [064/133] (840s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[179.621561] Starting subtest: extended-modeset-hang-oldfb-with-reset
[179.623714] Starting dynamic subtest: pipe-A
[191.456253] Dynamic subtest pipe-A: SUCCESS (11.832s)
[191.465218] Starting dynamic subtest: pipe-D
[203.748309] Dynamic subtest pipe-D: SUCCESS (12.289s)
[203.750643] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.126s)
[204.636309] [065/133] (815s left) gem_mmap_gtt (cpuset-big-copy-xy)
[204.692693] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[204.754062] [066/133] (815s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[204.870861] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[216.025405] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.158s)
[216.831447] [067/133] (803s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[216.941774] Starting subtest: basic-modeset-with-all-modifiers-formats
[217.328650] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[218.066901] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.740s)
[218.076263] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[218.089065] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[218.097988] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[218.111328] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.021s)
[218.119957] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[218.266941] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.155s)
[218.276556] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[218.288962] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.021s)
[218.298108] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[218.310887] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.021s)
[218.319888] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[218.333490] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.021s)
[218.342289] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[218.355758] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[218.366193] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[218.378019] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.021s)
[218.387889] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[218.400590] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.021s)
[218.410113] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[218.422514] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.021s)
[218.431190] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[218.444760] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.021s)
[218.453603] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[218.600260] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.155s)
[218.609999] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[218.622311] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.021s)
[218.631295] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[218.644672] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[218.655461] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[218.667206] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.021s)
[218.676176] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[218.689147] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.021s)
[218.698181] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[218.711332] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.021s)
[218.719980] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[218.733739] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[218.743496] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[218.755887] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.021s)
[218.765625] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[218.778052] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.021s)
[218.789195] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[218.934027] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.155s)
[218.944025] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[218.956372] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.021s)
[218.974287] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[218.980843] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.021s)
[218.998795] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[219.000829] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.021s)
[219.018567] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[219.024564] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.021s)
[219.042449] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[219.048495] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.021s)
[219.067571] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[219.069590] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.021s)
[219.079365] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[219.089031] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.021s)
[219.097383] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[219.111378] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[219.492462] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.552s)
[220.309108] [068/133] (800s left) i915_query (query-regions-sanity-check)
[220.359059] Starting subtest: query-regions-sanity-check
[220.376961] Subtest query-regions-sanity-check: SUCCESS (0.020s)
[220.498079] [069/133] (799s left) gem_exec_whisper (basic-normal-all)
[220.555826] Starting subtest: basic-normal-all
[222.988065] Subtest basic-normal-all: SUCCESS (2.430s)
[223.175032] [070/133] (797s left) gem_mmap_offset (blt-coherency)
[223.226133] Starting subtest: blt-coherency
[223.235921] Subtest blt-coherency: SUCCESS (0.012s)
[223.348768] [071/133] (797s left) kms_flip (flip-vs-suspend)
[223.448327] Starting subtest: flip-vs-suspend
[223.450557] Starting dynamic subtest: A-eDP1
[257.958207] Dynamic subtest A-eDP1: SUCCESS (5.175s)
[257.974345] Starting dynamic subtest: B-eDP1
[291.957729] Dynamic subtest B-eDP1: SUCCESS (4.221s)
[291.971391] Starting dynamic subtest: C-eDP1
[325.968707] Dynamic subtest C-eDP1: SUCCESS (4.280s)
[325.979732] Starting dynamic subtest: D-eDP1
[359.963505] Dynamic subtest D-eDP1: SUCCESS (4.242s)
[359.965906] Subtest flip-vs-suspend: SUCCESS (17.929s)
[367.185970] [072/133] (659s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[367.305374] Starting subtest: basic-busy-flip-before-cursor-atomic
[367.310192] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[367.466960] [073/133] (659s left) kms_psr (psr-cursor-blt)
[367.559623] Starting subtest: psr-cursor-blt
[367.561861] Starting dynamic subtest: eDP-1
[369.238855] Dynamic subtest eDP-1: SUCCESS (1.692s)
[369.241040] Subtest psr-cursor-blt: SUCCESS (1.693s)
[370.060191] [074/133] (656s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[370.154380] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[370.156681] Starting dynamic subtest: pipe-A-eDP-1
[381.429842] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.316s)
[381.440841] Starting dynamic subtest: pipe-B-eDP-1
[393.406160] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.985s)
[393.408405] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (23.304s)
[394.207791] [075/133] (632s left) gem_exec_reloc (basic-write-read-active)
[394.266717] Subtest basic-write-read-active: SKIP (0.000s)
[394.334474] [076/133] (632s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[394.434974] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[399.056845] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.629s)
[400.212891] [077/133] (626s left) kms_flip (2x-nonexisting-fb)
[400.306782] Starting subtest: 2x-nonexisting-fb
[400.318862] Subtest 2x-nonexisting-fb: SKIP (0.011s)
[400.400998] [078/133] (626s left) gem_ctx_isolation (preservation-reset)
[400.574568] Starting subtest: preservation-reset
[400.577004] Starting dynamic subtest: rcs0
[400.860506] Dynamic subtest rcs0: SUCCESS (0.290s)
[400.873538] Starting dynamic subtest: bcs0
[401.219767] Dynamic subtest bcs0: SUCCESS (0.358s)
[401.230377] Starting dynamic subtest: vcs0
[401.546980] Dynamic subtest vcs0: SUCCESS (0.326s)
[401.559618] Starting dynamic subtest: vcs1
[402.773359] Dynamic subtest vcs1: SUCCESS (1.226s)
[402.785178] Starting dynamic subtest: vecs0
[404.815036] Dynamic subtest vecs0: SUCCESS (2.041s)
[404.828396] Starting dynamic subtest: ccs0
[406.797647] Dynamic subtest ccs0: SUCCESS (1.982s)
[406.800410] Subtest preservation-reset: SUCCESS (6.233s)
[406.923249] [079/133] (619s left) syncobj_eventfd (invalid-bad-pad)
[406.983965] Starting subtest: invalid-bad-pad
[406.986287] Subtest invalid-bad-pad: SUCCESS (0.000s)
[407.084316] [080/133] (619s left) kms_universal_plane (cursor-fb-leak)
[407.193980] Starting subtest: cursor-fb-leak
[407.196217] Starting dynamic subtest: pipe-A-eDP-1
[407.550597] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.359s)
[407.565269] Starting dynamic subtest: pipe-B-eDP-1
[408.898752] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.346s)
[408.914422] Starting dynamic subtest: pipe-C-eDP-1
[410.286258] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.385s)
[410.301999] Starting dynamic subtest: pipe-D-eDP-1
[411.675077] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.386s)
[411.677574] Subtest cursor-fb-leak: SUCCESS (4.487s)
[412.889787] [081/133] (614s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[413.014183] Starting subtest: cursor-vs-flip-varying-size
[444.256502] Subtest cursor-vs-flip-varying-size: SUCCESS (31.257s)
[445.072717] [082/133] (581s left) prime_busy (hang)
[445.134443] Starting subtest: hang
[445.136828] Starting dynamic subtest: rcs0
[471.040642] Dynamic subtest rcs0: SUCCESS (25.886s)
[471.049857] Starting dynamic subtest: bcs0
[478.179544] Dynamic subtest bcs0: SUCCESS (7.129s)
[478.188877] Starting dynamic subtest: vcs0
[485.161028] Dynamic subtest vcs0: SUCCESS (6.975s)
[485.171400] Starting dynamic subtest: vcs1
[492.135779] Dynamic subtest vcs1: SUCCESS (6.971s)
[492.146118] Starting dynamic subtest: vecs0
[499.167112] Dynamic subtest vecs0: SUCCESS (7.032s)
[499.177265] Starting dynamic subtest: ccs0
[513.011071] Dynamic subtest ccs0: SUCCESS (13.838s)
[513.013462] Subtest hang: SUCCESS (67.838s)
[513.120931] [083/133] (513s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[513.170410] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[513.172878] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[513.267016] [084/133] (513s left) gem_exec_balancer (parallel-keep-in-fence)
[513.535839] Starting subtest: parallel-keep-in-fence
[514.704815] Subtest parallel-keep-in-fence: SUCCESS (1.169s)
[514.833910] [085/133] (512s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[514.932681] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[514.937180] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[515.007979] [086/133] (512s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[515.124661] Starting subtest: flip-vs-cursor-legacy
[522.581143] Subtest flip-vs-cursor-legacy: SUCCESS (7.457s)
[523.386497] [087/133] (503s left) i915_hangman (gt-engine-error)
[523.472524] Starting subtest: gt-engine-error
[523.474808] Starting dynamic subtest: rcs0
[525.493059] Dynamic subtest rcs0: SUCCESS (2.020s)
[525.506954] Starting dynamic subtest: bcs0
[527.476591] Dynamic subtest bcs0: SUCCESS (1.980s)
[527.489879] Starting dynamic subtest: vcs0
[529.460576] Dynamic subtest vcs0: SUCCESS (1.980s)
[529.473656] Starting dynamic subtest: vcs1
[531.444917] Dynamic subtest vcs1: SUCCESS (1.981s)
[531.459683] Starting dynamic subtest: vecs0
[533.427243] Dynamic subtest vecs0: SUCCESS (1.979s)
[533.442828] Starting dynamic subtest: ccs0
[535.477393] Dynamic subtest ccs0: SUCCESS (2.047s)
[535.480763] Subtest gt-engine-error: SUCCESS (12.005s)
[535.612967] [088/133] (491s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[535.732945] Starting subtest: 4-tiled-64bpp-rotate-0
[537.359391] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (1.626s)
[537.531626] [089/133] (489s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[537.627475] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[537.630827] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[537.727698] [090/133] (489s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[537.819729] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[538.678612] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.859s)
[538.856056] [091/133] (488s left) gem_userptr_blits (invalid-mmap-offset-unsync)
[538.921583] Subtest invalid-mmap-offset-unsync: SKIP (0.000s)
[539.037694] [092/133] (488s left) prime_self_import (basic-with_one_bo_two_files)
[539.075975] Starting subtest: basic-with_one_bo_two_files
[539.087225] Subtest basic-with_one_bo_two_files: SUCCESS (0.014s)
[539.183735] [093/133] (488s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[539.236543] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[539.284441] [094/133] (488s left) gem_exec_params (invalid-batch-start-offset)
[539.354786] Starting subtest: invalid-batch-start-offset
[539.357267] Subtest invalid-batch-start-offset: SUCCESS (0.002s)
[539.488398] [095/133] (488s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[539.588535] Starting subtest: y-tiled-8bpp-rotate-270
[539.592300] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[539.684687] [096/133] (487s left) gem_eio (create)
[539.902761] Starting subtest: create
[540.217630] Subtest create: SUCCESS (0.314s)
[540.342751] [097/133] (487s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[540.438342] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[540.440438] Starting dynamic subtest: pipe-A-eDP-1
[540.460148] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[540.472481] Starting dynamic subtest: pipe-B-eDP-1
[540.490155] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[540.499569] Starting dynamic subtest: pipe-C-eDP-1
[540.517447] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[540.528620] Starting dynamic subtest: pipe-D-eDP-1
[540.546775] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[540.550297] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[542.707790] [098/133] (485s left) kms_psr (pr-primary-blt)
[542.802716] Starting subtest: pr-primary-blt
[542.806843] Subtest pr-primary-blt: SKIP (0.000s)
[542.895154] [099/133] (484s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[543.154021] Starting subtest: cursor-rapid-movement-256x256
[543.158828] Starting dynamic subtest: pipe-A-eDP-1
[543.704691] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.544s)
[543.735335] Starting dynamic subtest: pipe-D-eDP-1
[544.884438] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.158s)
[544.887007] Subtest cursor-rapid-movement-256x256: SUCCESS (1.730s)
[545.709825] [100/133] (482s left) gem_exec_params (sol-reset-invalid)
[545.760861] Starting subtest: sol-reset-invalid
[545.763180] Subtest sol-reset-invalid: SUCCESS (0.002s)
[547.544386] [101/133] (480s left) gem_exec_params (invalid-fence-in)
[547.605157] Starting subtest: invalid-fence-in
[547.607520] Subtest invalid-fence-in: SUCCESS (0.000s)
[547.719638] [102/133] (480s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[547.806332] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[547.810681] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[547.907282] [103/133] (480s left) syncobj_wait (wait-all-complex)
[547.954304] Starting subtest: wait-all-complex
[548.171901] Subtest wait-all-complex: SUCCESS (0.216s)
[548.258898] [104/133] (479s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[548.359985] Starting subtest: fbcpsr-rgb101010-draw-render
[555.021769] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (6.666s)
[555.147648] [105/133] (472s left) kms_lease (lease-unleased-connector)
[555.250540] Starting subtest: lease-unleased-connector
[555.252707] Starting dynamic subtest: pipe-A-eDP-1
[555.254828] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[555.265977] Starting dynamic subtest: pipe-B-eDP-1
[555.268193] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[555.278964] Starting dynamic subtest: pipe-C-eDP-1
[555.281246] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[555.291477] Starting dynamic subtest: pipe-D-eDP-1
[555.293262] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.006s)
[555.295120] Subtest lease-unleased-connector: SUCCESS (0.033s)
[555.392315] [106/133] (472s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[555.778302] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[556.573726] [107/133] (471s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[556.692672] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[560.590831] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (3.901s)
[561.724238] [108/133] (466s left) kms_fbcon_fbt (fbc)
[561.779329] Starting subtest: fbc
[568.237365] Subtest fbc: SUCCESS (6.456s)
[569.381224] [109/133] (458s left) kms_vblank (wait-idle-hang)
[569.470274] Starting subtest: wait-idle-hang
[569.472097] Starting dynamic subtest: pipe-A-eDP-1
[596.308681] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.836s)
[596.326531] Starting dynamic subtest: pipe-D-eDP-1
[611.292176] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.982s)
[611.294593] Subtest wait-idle-hang: SUCCESS (41.822s)
[612.093623] [110/133] (416s left) kms_cursor_edge_walk (256x256-top-edge)
[612.181755] Starting subtest: 256x256-top-edge
[612.184044] Starting dynamic subtest: pipe-A-eDP-1
[615.620928] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.440s)
[615.631831] Starting dynamic subtest: pipe-D-eDP-1
[618.826668] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.204s)
[618.829013] Subtest 256x256-top-edge: SUCCESS (6.647s)
[619.698497] [111/133] (408s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[619.759069] Starting subtest: multi-wait-all-for-submit-available-signaled
[619.761377] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[619.854136] [112/133] (408s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[619.951815] Starting subtest: fbc-psr2-cursor-plane-onoff
[619.954154] Starting dynamic subtest: eDP-1
[619.958564] Dynamic subtest eDP-1: SKIP (0.000s)
[619.963167] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[620.028234] [113/133] (408s left) kms_pm_rpm (modeset-lpsp)
[621.614060] Starting subtest: modeset-lpsp
[623.080742] Subtest modeset-lpsp: SUCCESS (1.469s)
[623.175424] [114/133] (405s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[623.325114] Starting subtest: cursorB-vs-flipB-atomic
[623.329968] Subtest cursorB-vs-flipB-atomic: SKIP (0.002s)
[623.499110] [115/133] (405s left) kms_vrr (seamless-rr-switch-drrs)
[623.605891] Starting subtest: seamless-rr-switch-drrs
[623.612632] Subtest seamless-rr-switch-drrs: SKIP (0.000s)
[623.684972] [116/133] (404s left) gem_exec_params (dr4-dirt)
[623.743015] Starting subtest: DR4-dirt
[623.745338] Subtest DR4-dirt: SUCCESS (0.000s)
[623.840378] [117/133] (404s left) gem_exec_schedule (preempt-other-chain)
[623.915982] Starting subtest: preempt-other-chain
[623.918413] Starting dynamic subtest: rcs0
[624.027052] Dynamic subtest rcs0: SUCCESS (0.109s)
[624.045005] Starting dynamic subtest: bcs0
[624.113244] Dynamic subtest bcs0: SUCCESS (0.086s)
[624.136219] Starting dynamic subtest: vcs0
[624.200813] Dynamic subtest vcs0: SUCCESS (0.087s)
[624.213063] Starting dynamic subtest: vcs1
[624.286413] Dynamic subtest vcs1: SUCCESS (0.083s)
[624.306189] Starting dynamic subtest: vecs0
[624.369561] Dynamic subtest vecs0: SUCCESS (0.083s)
[624.384094] Starting dynamic subtest: ccs0
[624.453430] Dynamic subtest ccs0: SUCCESS (0.084s)
[624.456028] Subtest preempt-other-chain: SUCCESS (0.538s)
[625.521730] [118/133] (403s left) i915_drm_fdinfo (memory-info-shared)
[625.566037] Starting subtest: memory-info-shared
[625.568238] Starting dynamic subtest: smem0
[625.609929] Dynamic subtest smem0: SUCCESS (0.046s)
[625.612572] Subtest memory-info-shared: SUCCESS (0.046s)
[625.689851] [119/133] (403s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[625.788037] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[632.364109] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.578s)
[632.497603] [120/133] (396s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[632.598406] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[639.542497] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (6.948s)
[639.682543] [121/133] (389s left) gem_ctx_persistence (legacy-engines-hostile)
[639.751631] Starting subtest: legacy-engines-hostile
[639.753756] Starting dynamic subtest: render
[639.862231] Dynamic subtest render: SUCCESS (0.113s)
[639.875905] Starting dynamic subtest: bsd1
[639.967273] Dynamic subtest bsd1: SUCCESS (0.103s)
[639.982787] Starting dynamic subtest: bsd2
[640.070016] Dynamic subtest bsd2: SUCCESS (0.100s)
[640.084263] Starting dynamic subtest: blt
[640.172153] Dynamic subtest blt: SUCCESS (0.099s)
[640.186247] Starting dynamic subtest: vebox
[640.265082] Dynamic subtest vebox: SUCCESS (0.090s)
[640.267577] Subtest legacy-engines-hostile: SUCCESS (0.517s)
[640.354046] [122/133] (388s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[640.455473] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[640.457613] Starting dynamic subtest: pipe-A-eDP-1
[640.476322] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[640.486642] Starting dynamic subtest: pipe-B-eDP-1
[640.503215] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[640.512646] Starting dynamic subtest: pipe-C-eDP-1
[640.529590] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[640.539406] Starting dynamic subtest: pipe-D-eDP-1
[640.556757] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[640.560226] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.005s)
[642.690564] [123/133] (386s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[642.743978] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[642.850637] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.106s)
[642.934449] [124/133] (385s left) gem_sync (basic-each)
[643.029863] Starting subtest: basic-each
[645.411618] Subtest basic-each: SUCCESS (2.392s)
[645.602004] [125/133] (383s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[645.735434] Starting subtest: cursor-vs-flip-atomic-transitions
[676.032759] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.295s)
[676.885530] [126/133] (352s left) gem_mmap_gtt (basic-small-copy-xy)
[676.946868] Subtest basic-small-copy-XY: SKIP (0.000s)
[677.002166] [127/133] (352s left) gem_pxp (reject-modify-context-protection-off-3)
[677.102113] Starting subtest: reject-modify-context-protection-off-3
[677.104448] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[677.222845] [128/133] (351s left) sysfs_heartbeat_interval (nopreempt)
[677.286150] Starting subtest: nopreempt
[677.288471] Starting dynamic subtest: vecs0
[686.482619] Dynamic subtest vecs0: SUCCESS (9.191s)
[686.496457] Starting dynamic subtest: ccs0
[690.534447] Dynamic subtest ccs0: SUCCESS (4.052s)
[690.547145] Starting dynamic subtest: vcs0
[700.496808] Dynamic subtest vcs0: SUCCESS (9.961s)
[700.509784] Starting dynamic subtest: bcs0
[704.417073] Dynamic subtest bcs0: SUCCESS (3.917s)
[704.431160] Starting dynamic subtest: rcs0
[708.436751] Dynamic subtest rcs0: SUCCESS (4.017s)
[708.449256] Starting dynamic subtest: vcs1
[718.487986] Dynamic subtest vcs1: SUCCESS (10.047s)
[718.490631] Subtest nopreempt: SUCCESS (41.196s)
[718.568932] [129/133] (310s left) kms_content_protection (content-type-change)
[718.722905] Starting subtest: content-type-change
[718.727239] Subtest content-type-change: SKIP (0.000s)
[718.852607] [130/133] (310s left) kms_psr (psr-suspend)
[718.945703] Starting subtest: psr-suspend
[718.947912] Starting dynamic subtest: eDP-1
[737.376329] Dynamic subtest eDP-1: SUCCESS (3.317s)
[737.378257] Subtest psr-suspend: SUCCESS (3.318s)
[744.206515] [131/133] (291s left) gen9_exec_parse (batch-invalid-length)
[744.276335] Subtest batch-invalid-length: SKIP (0.000s)
[744.376932] [132/133] (290s left) kms_atomic_transition (modeset-transition)
[744.467351] Starting subtest: modeset-transition
[744.469269] Starting dynamic subtest: 1x-outputs
[755.340087] Dynamic subtest 1x-outputs: SUCCESS (10.862s)
[755.342492] Subtest modeset-transition: SUCCESS (10.864s)
[756.181479] [133/133] (279s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[756.264063] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[756.266690] Starting dynamic subtest: pipe-A-default-mode
[758.999008] Dynamic subtest pipe-A-default-mode: SUCCESS (2.734s)
[759.001341] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.735s)
[759.801210] Closing watchdogs
results: parsing output: 0/ for test: syncobj_wait
results: parsing output: 1/ for test: kms_color
results: parsing output: 2/ for test: gem_eio
results: parsing output: 3/ for test: kms_cursor_legacy
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: kms_ccs
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_flip
results: parsing output: 8/ for test: gem_exec_fence
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: kms_pipe_b_c_ivb
results: parsing output: 11/ for test: i915_pm_rpm
results: parsing output: 12/ for test: kms_cursor_crc
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: gem_userptr_blits
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_atomic_transition
results: parsing output: 17/ for test: api_intel_bb
results: parsing output: 18/ for test: gem_sync
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: gem_exec_balancer
results: parsing output: 21/ for test: kms_plane_alpha_blend
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: gem_softpin
results: parsing output: 24/ for test: kms_hdmi_inject
results: parsing output: 25/ for test: gem_bad_reloc
results: parsing output: 26/ for test: gem_userptr_blits
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: gem_eio
results: parsing output: 29/ for test: kms_pm_backlight
results: parsing output: 30/ for test: gem_render_copy
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: dmabuf_sync_file
results: parsing output: 33/ for test: kms_addfb_basic
results: parsing output: 34/ for test: gem_exec_capture
results: parsing output: 35/ for test: fbdev
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_plane
results: parsing output: 40/ for test: kms_flip_scaled_crc
results: parsing output: 41/ for test: gem_partial_pwrite_pread
results: parsing output: 42/ for test: kms_hdr
results: parsing output: 43/ for test: syncobj_timeline
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: perf_pmu
results: parsing output: 46/ for test: kms_chamelium_color
results: parsing output: 47/ for test: gem_pxp
results: parsing output: 48/ for test: gem_softpin
results: parsing output: 49/ for test: kms_plane_multiple
results: parsing output: 50/ for test: kms_feature_discovery
results: parsing output: 51/ for test: kms_getfb
results: parsing output: 52/ for test: kms_prop_blob
results: parsing output: 53/ for test: kms_plane_scaling
results: parsing output: 54/ for test: gem_create
results: parsing output: 55/ for test: syncobj_timeline
results: parsing output: 56/ for test: kms_ccs
results: parsing output: 57/ for test: vgem_basic
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_addfb_basic
results: parsing output: 61/ for test: gem_exec_schedule
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: kms_busy
results: parsing output: 64/ for test: gem_mmap_gtt
results: parsing output: 65/ for test: kms_cursor_legacy
results: parsing output: 66/ for test: kms_async_flips
results: parsing output: 67/ for test: i915_query
results: parsing output: 68/ for test: gem_exec_whisper
results: parsing output: 69/ for test: gem_mmap_offset
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_cursor_legacy
results: parsing output: 72/ for test: kms_psr
results: parsing output: 73/ for test: kms_psr2_sf
results: parsing output: 74/ for test: gem_exec_reloc
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_flip
results: parsing output: 77/ for test: gem_ctx_isolation
results: parsing output: 78/ for test: syncobj_eventfd
results: parsing output: 79/ for test: kms_universal_plane
results: parsing output: 80/ for test: kms_cursor_legacy
results: parsing output: 81/ for test: prime_busy
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: gem_exec_balancer
results: parsing output: 84/ for test: kms_plane
results: parsing output: 85/ for test: kms_cursor_legacy
results: parsing output: 86/ for test: i915_hangman
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_big_fb
results: parsing output: 90/ for test: gem_userptr_blits
results: parsing output: 91/ for test: prime_self_import
results: parsing output: 92/ for test: gem_lmem_swapping
results: parsing output: 93/ for test: gem_exec_params
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: gem_eio
results: parsing output: 96/ for test: kms_ccs
results: parsing output: 97/ for test: kms_psr
results: parsing output: 98/ for test: kms_cursor_crc
results: parsing output: 99/ for test: gem_exec_params
results: parsing output: 100/ for test: gem_exec_params
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: syncobj_wait
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_lease
results: parsing output: 105/ for test: kms_chamelium_hpd
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_fbcon_fbt
results: parsing output: 108/ for test: kms_vblank
results: parsing output: 109/ for test: kms_cursor_edge_walk
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: kms_psr
results: parsing output: 112/ for test: kms_pm_rpm
results: parsing output: 113/ for test: kms_cursor_legacy
results: parsing output: 114/ for test: kms_vrr
results: parsing output: 115/ for test: gem_exec_params
results: parsing output: 116/ for test: gem_exec_schedule
results: parsing output: 117/ for test: i915_drm_fdinfo
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gem_ctx_persistence
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: syncobj_wait
results: parsing output: 123/ for test: gem_sync
results: parsing output: 124/ for test: kms_cursor_legacy
results: parsing output: 125/ for test: gem_mmap_gtt
results: parsing output: 126/ for test: gem_pxp
results: parsing output: 127/ for test: sysfs_heartbeat_interval
results: parsing output: 128/ for test: kms_content_protection
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: gen9_exec_parse
results: parsing output: 131/ for test: kms_atomic_transition
results: parsing output: 132/ for test: kms_flip_scaled_crc
Done.