[61.494932] Initializing watchdogs
[61.494977]   /dev/watchdog0
[61.508730] [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]
[61.512990] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.515358] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.518836] [001/133] (960s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled)
[61.608067] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[61.610504] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[61.715183] [002/133] (960s left) kms_color (ctm-max)
[61.812054] Starting subtest: ctm-max
[61.814022] Starting dynamic subtest: pipe-A-eDP-1
[62.541608] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.729s)
[62.549468] Starting dynamic subtest: pipe-B-eDP-1
[63.951816] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.404s)
[63.960931] Starting dynamic subtest: pipe-C-eDP-1
[65.310836] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.355s)
[65.319081] Starting dynamic subtest: pipe-D-eDP-1
[66.642787] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[66.645086] Subtest ctm-max: SUCCESS (4.820s)
[67.473863] [003/133] (954s left) gem_eio (in-flight-1us)
[67.717849] Starting subtest: in-flight-1us
[75.785830] Subtest in-flight-1us: SUCCESS (8.058s)
[75.976721] [FACT gem_eio (in-flight-1us)] new: kernel.kmod_is_loaded.vgem: true
[75.979865] [004/133] (946s left) kms_cursor_legacy (basic-busy-flip-before-cursor-legacy)
[76.091639] Starting subtest: basic-busy-flip-before-cursor-legacy
[76.096062] Subtest basic-busy-flip-before-cursor-legacy: SKIP (0.000s)
[76.235405] [005/133] (945s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[76.325652] Starting subtest: fbc-psr2-sprite-plane-onoff
[76.327870] Starting dynamic subtest: eDP-1
[76.331983] Dynamic subtest eDP-1: SKIP (0.000s)
[76.336088] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.000s)
[76.396626] [006/133] (945s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[76.490268] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[76.492172] Starting dynamic subtest: pipe-A-eDP-1
[76.644874] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.146s)
[76.654469] Starting dynamic subtest: pipe-B-eDP-1
[76.669531] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.013s)
[76.676999] Starting dynamic subtest: pipe-C-eDP-1
[76.693488] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.011s)
[76.700878] Starting dynamic subtest: pipe-D-eDP-1
[76.716431] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[76.718212] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.184s)
[78.909204] [007/133] (943s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[79.013232] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[79.017917] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[79.107371] [008/133] (943s left) kms_flip (flip-vs-panning)
[79.190120] Starting subtest: flip-vs-panning
[79.192526] Starting dynamic subtest: A-eDP1
[87.928917] Dynamic subtest A-eDP1: SUCCESS (8.735s)
[87.937454] Starting dynamic subtest: B-eDP1
[96.655998] Dynamic subtest B-eDP1: SUCCESS (8.723s)
[96.663900] Starting dynamic subtest: C-eDP1
[105.370114] Dynamic subtest C-eDP1: SUCCESS (8.711s)
[105.380350] Starting dynamic subtest: D-eDP1
[114.105426] Dynamic subtest D-eDP1: SUCCESS (8.733s)
[114.107476] Subtest flip-vs-panning: SUCCESS (34.910s)
[115.269761] [009/133] (907s left) gem_exec_fence (submit3)
[115.453641] Starting subtest: submit3
[115.457611] Subtest submit3: SKIP (0.000s)
[116.967368] [010/133] (905s left) api_intel_allocator (gem-pool)
[117.023935] Starting subtest: gem-pool
[117.031770] Subtest gem-pool: SUCCESS (0.010s)
[117.151986] [011/133] (905s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[117.207228] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[117.263597] [012/133] (905s left) i915_pm_rpm (sysfs-read)
[119.097439] Starting subtest: sysfs-read
[120.258939] Subtest sysfs-read: SUCCESS (1.159s)
[120.641077] [013/133] (901s left) kms_cursor_crc (cursor-onscreen-64x64)
[120.834650] Starting subtest: cursor-onscreen-64x64
[120.839426] Starting dynamic subtest: pipe-A-eDP-1
[122.936565] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.095s)
[122.964667] Starting dynamic subtest: pipe-D-eDP-1
[125.742024] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.785s)
[125.744423] Subtest cursor-onscreen-64x64: SUCCESS (4.905s)
[126.590092] [014/133] (895s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[126.703511] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[126.708144] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[126.799249] [015/133] (895s left) gem_userptr_blits (forbidden-operations)
[126.865550] Starting subtest: forbidden-operations
[126.869831] Subtest forbidden-operations: SKIP (0.000s)
[126.975460] [016/133] (895s left) kms_big_fb (linear-64bpp-rotate-270)
[127.064052] Starting subtest: linear-64bpp-rotate-270
[127.082348] Subtest linear-64bpp-rotate-270: SKIP (0.019s)
[127.194932] [017/133] (895s left) kms_atomic_transition (plane-all-transition)
[127.290828] Starting subtest: plane-all-transition
[127.293158] Starting dynamic subtest: pipe-A-eDP-1
[129.790128] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.498s)
[130.111123] Starting dynamic subtest: pipe-B-eDP-1
[133.321245] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.218s)
[133.619588] Subtest plane-all-transition: SUCCESS (6.326s)
[134.454073] [018/133] (888s left) api_intel_bb (reset-flags)
[134.512754] Starting subtest: reset-flags
[134.518835] Subtest reset-flags: SUCCESS (0.007s)
[134.640772] [019/133] (888s left) gem_sync (basic-many-each)
[134.701124] Starting subtest: basic-many-each
[136.992337] Subtest basic-many-each: SUCCESS (2.298s)
[137.174816] [020/133] (885s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[137.279384] Starting subtest: yf-tiled-8bpp-rotate-0
[137.283473] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[137.386235] [021/133] (885s left) gem_exec_balancer (nohangcheck)
[137.636942] Starting subtest: nohangcheck
[138.505844] Subtest nohangcheck: SUCCESS (0.868s)
[138.605667] [022/133] (884s left) kms_plane_alpha_blend (alpha-7efc)
[138.690918] Starting subtest: alpha-7efc
[138.969698] Starting dynamic subtest: pipe-A-eDP-1
[139.567525] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.597s)
[139.594108] Starting dynamic subtest: pipe-D-eDP-1
[140.875090] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.288s)
[140.878269] Subtest alpha-7efc: SUCCESS (2.186s)
[141.717290] [023/133] (881s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[141.814533] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[141.818803] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[141.914191] [024/133] (881s left) gem_softpin (evict-snoop-interruptible)
[141.974079] Starting subtest: evict-snoop-interruptible
[141.978524] Subtest evict-snoop-interruptible: SKIP (0.000s)
[142.095909] [025/133] (880s left) kms_hdmi_inject (inject-audio)
[142.440621] Starting subtest: inject-audio
[142.716806] Subtest inject-audio: SUCCESS (0.277s)
[143.279235] [026/133] (879s left) gem_bad_reloc (negative-reloc-bltcopy)
[143.344542] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[143.406886] [027/133] (879s left) gem_userptr_blits (unsync-overlap)
[143.475051] Subtest unsync-overlap: SKIP (0.000s)
[143.611108] [028/133] (879s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[143.725330] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[143.729690] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[143.880426] [029/133] (879s left) gem_eio (in-flight-internal-immediate)
[144.109628] Starting subtest: in-flight-internal-immediate
[146.017265] Subtest in-flight-internal-immediate: SUCCESS (1.906s)
[146.142358] [030/133] (877s left) kms_pm_backlight (brightness-with-dpms)
[146.240204] Starting subtest: brightness-with-dpms
[146.290786] Starting dynamic subtest: eDP-1
[148.719734] Dynamic subtest eDP-1: SUCCESS (2.427s)
[148.721663] Subtest brightness-with-dpms: SUCCESS (2.479s)
[149.544115] [031/133] (873s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[149.594354] Starting subtest: y-tiled-ccs-to-yf-tiled
[149.598909] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[149.788092] [032/133] (873s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-wc)
[149.885236] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-wc
[149.889218] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[149.984841] [033/133] (873s left) dmabuf_sync_file (import-multiple-read-only)
[150.037941] Starting subtest: import-multiple-read-only
[150.039870] Subtest import-multiple-read-only: SUCCESS (0.003s)
[150.091458] [034/133] (873s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[150.141740] Starting subtest: framebuffer-vs-set-tiling
[150.146160] Subtest framebuffer-vs-set-tiling: SKIP (0.000s)
[150.258333] [035/133] (873s left) gem_exec_capture (userptr)
[150.328990] Starting subtest: userptr
[151.163742] Subtest userptr: SUCCESS (0.833s)
[151.293776] [036/133] (872s left) fbdev (nullptr)
[151.333954] Starting subtest: nullptr
[151.336511] Subtest nullptr: SUCCESS (0.000s)
[151.392441] [037/133] (872s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[151.496667] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[157.006509] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.514s)
[158.151896] [038/133] (865s left) kms_big_fb (x-tiled-64bpp-rotate-270)
[158.243940] Starting subtest: x-tiled-64bpp-rotate-270
[158.262879] Subtest x-tiled-64bpp-rotate-270: SKIP (0.018s)
[158.375109] [039/133] (865s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[158.461656] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[158.466139] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[158.544402] [040/133] (865s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[158.631899] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[158.635841] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[158.702652] [041/133] (864s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[158.787683] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[158.791409] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[158.868905] [042/133] (864s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[158.934759] Subtest writes-after-reads-uncached: SKIP (0.000s)
[159.010337] [043/133] (864s left) kms_hdr (static-toggle-dpms)
[159.105432] Starting subtest: static-toggle-dpms
[159.110768] Subtest static-toggle-dpms: SKIP (0.000s)
[159.176622] [044/133] (864s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[159.235531] Starting subtest: etime-multi-wait-for-submit-submitted
[159.345119] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.108s)
[159.432619] [045/133] (864s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[159.520059] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[159.523977] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[159.621780] [046/133] (864s left) perf_pmu (idle)
[159.658226] Starting subtest: idle
[159.660307] Starting dynamic subtest: rcs0
[160.211072] Dynamic subtest rcs0: SUCCESS (0.552s)
[160.222106] Starting dynamic subtest: bcs0
[160.731699] Dynamic subtest bcs0: SUCCESS (0.518s)
[160.742650] Starting dynamic subtest: vcs0
[161.259317] Dynamic subtest vcs0: SUCCESS (0.526s)
[161.270430] Starting dynamic subtest: vcs1
[161.784708] Dynamic subtest vcs1: SUCCESS (0.523s)
[161.795678] Starting dynamic subtest: vecs0
[162.306052] Dynamic subtest vecs0: SUCCESS (0.519s)
[162.317106] Starting dynamic subtest: ccs0
[162.826276] Dynamic subtest ccs0: SUCCESS (0.518s)
[162.829029] Subtest idle: SUCCESS (3.170s)
[162.942844] [047/133] (860s left) kms_chamelium_color (degamma)
[163.033878] Subtest degamma: SKIP (0.000s)
[163.077785] [048/133] (860s left) gem_pxp (create-regular-context-2)
[163.199764] Starting subtest: create-regular-context-2
[163.202223] Subtest create-regular-context-2: SUCCESS (0.002s)
[163.313740] [049/133] (860s left) gem_softpin (allocator-basic-reserve)
[163.372662] Starting subtest: allocator-basic-reserve
[163.444296] Subtest allocator-basic-reserve: SUCCESS (0.074s)
[163.571072] [050/133] (860s left) kms_plane_multiple (2x-tiling-none)
[163.670091] Starting subtest: 2x-tiling-none
[163.674244] Subtest 2x-tiling-none: SKIP (0.000s)
[163.745822] [051/133] (860s left) kms_feature_discovery (psr1)
[163.846228] Starting subtest: psr1
[163.848108] Subtest psr1: SUCCESS (0.000s)
[163.920967] [052/133] (860s left) kms_getfb (getfb-handle-protection)
[164.010519] Starting subtest: getfb-handle-protection
[164.032397] Subtest getfb-handle-protection: SUCCESS (0.023s)
[164.152391] [053/133] (859s left) kms_prop_blob (blob-prop-core)
[164.195540] Starting subtest: blob-prop-core
[164.197379] Subtest blob-prop-core: SUCCESS (0.000s)
[164.297949] [054/133] (859s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[164.393548] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[164.395527] Starting dynamic subtest: pipe-A
[165.443984] Dynamic subtest pipe-A: SUCCESS (1.052s)
[165.453058] Starting dynamic subtest: pipe-B
[167.601118] Dynamic subtest pipe-B: SUCCESS (2.156s)
[167.610151] Starting dynamic subtest: pipe-C
[168.769732] Dynamic subtest pipe-C: SUCCESS (1.167s)
[168.779529] Starting dynamic subtest: pipe-D
[169.974827] Dynamic subtest pipe-D: SUCCESS (1.205s)
[169.977176] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.584s)
[171.131203] [055/133] (853s left) gem_create (create-size-update)
[171.201220] Starting subtest: create-size-update
[171.203148] Subtest create-size-update: SUCCESS (0.000s)
[171.408786] [056/133] (852s left) syncobj_timeline (transfer-timeline-point)
[171.470222] Starting subtest: transfer-timeline-point
[171.472672] Subtest transfer-timeline-point: SUCCESS (0.001s)
[171.587707] [057/133] (852s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[171.693056] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[171.695437] Starting dynamic subtest: pipe-A-eDP-1
[172.184282] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.465s)
[172.192823] Starting dynamic subtest: pipe-B-eDP-1
[173.680639] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.496s)
[173.690889] Starting dynamic subtest: pipe-C-eDP-1
[175.107146] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.423s)
[175.118023] Starting dynamic subtest: pipe-D-eDP-1
[176.491464] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.384s)
[176.493727] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.773s)
[177.646994] [058/133] (846s left) vgem_basic (dmabuf-fence)
[177.698795] Starting subtest: dmabuf-fence
[177.701011] Subtest dmabuf-fence: SUCCESS (0.000s)
[177.756977] [059/133] (846s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[177.854627] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[177.859025] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[177.951472] [060/133] (846s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[178.044170] Starting subtest: random-ccs-data-y-tiled-ccs
[178.046421] Starting dynamic subtest: pipe-A-eDP-1
[178.071526] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[178.082679] Starting dynamic subtest: pipe-B-eDP-1
[178.106269] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[178.114061] Starting dynamic subtest: pipe-C-eDP-1
[178.137258] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[178.145144] Starting dynamic subtest: pipe-D-eDP-1
[178.168739] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[178.173101] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.005s)
[180.326187] [061/133] (844s left) kms_addfb_basic (bad-pitch-63)
[180.390779] Starting subtest: bad-pitch-63
[180.392789] Subtest bad-pitch-63: SUCCESS (0.000s)
[180.531521] [062/133] (843s left) gem_exec_schedule (u-semaphore-user)
[180.598891] Starting subtest: u-semaphore-user
[180.652357] Subtest u-semaphore-user: SUCCESS (0.057s)
[181.949520] [063/133] (842s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[182.049259] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[182.051798] Starting dynamic subtest: pipe-A-eDP-1
[182.132578] Dynamic subtest pipe-A-eDP-1: SKIP (0.088s)
[182.143499] Starting dynamic subtest: pipe-B-eDP-1
[183.273803] Dynamic subtest pipe-B-eDP-1: SKIP (1.140s)
[183.278434] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.231s)
[184.536455] [064/133] (840s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[184.639774] Starting subtest: extended-modeset-hang-oldfb-with-reset
[184.642155] Starting dynamic subtest: pipe-A
[196.855900] Dynamic subtest pipe-A: SUCCESS (12.217s)
[196.867398] Starting dynamic subtest: pipe-D
[209.369789] Dynamic subtest pipe-D: SUCCESS (12.509s)
[209.372821] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.730s)
[210.235801] [065/133] (814s left) gem_mmap_gtt (cpuset-big-copy-xy)
[210.295462] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[210.388407] [066/133] (814s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[210.519468] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[221.691066] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.171s)
[222.521668] [067/133] (802s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[222.617321] Starting subtest: basic-modeset-with-all-modifiers-formats
[222.991896] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[223.780804] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.789s)
[223.796628] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[223.802389] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.019s)
[223.811362] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[223.824564] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.020s)
[223.833135] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[223.980136] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.155s)
[223.989273] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[224.002228] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.021s)
[224.010866] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[224.024432] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[224.032831] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[224.046764] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[224.055539] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[224.068646] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.021s)
[224.077587] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[224.091167] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.021s)
[224.099890] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[224.113396] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.021s)
[224.122673] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[224.135630] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.021s)
[224.144313] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[224.157885] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.021s)
[224.166871] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[224.313283] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.155s)
[224.323032] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[224.335342] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.021s)
[224.344140] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[224.357785] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.021s)
[224.366522] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[224.380042] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.020s)
[224.388401] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[224.402779] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s)
[224.414320] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[224.424589] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.019s)
[224.433113] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[224.446715] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[224.455075] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[224.469289] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.021s)
[224.478952] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[224.491255] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.021s)
[224.502463] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[224.647220] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.153s)
[224.656870] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[224.669313] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.021s)
[224.677779] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[224.691188] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.021s)
[224.699249] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[224.713435] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.021s)
[224.721520] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[224.735738] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.021s)
[224.743912] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[224.757811] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.021s)
[224.765848] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[224.779957] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.021s)
[224.788096] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[224.802316] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[224.810796] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[224.824589] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.021s)
[225.206150] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.589s)
[226.026361] [068/133] (798s left) i915_query (query-regions-sanity-check)
[226.079346] Starting subtest: query-regions-sanity-check
[226.087082] Subtest query-regions-sanity-check: SUCCESS (0.010s)
[226.197529] [069/133] (798s left) gem_exec_whisper (basic-normal-all)
[226.265941] Starting subtest: basic-normal-all
[228.705150] Subtest basic-normal-all: SUCCESS (2.438s)
[228.887133] [070/133] (795s left) gem_mmap_offset (blt-coherency)
[228.942296] Starting subtest: blt-coherency
[228.952761] Subtest blt-coherency: SUCCESS (0.013s)
[229.073044] [071/133] (795s left) kms_flip (flip-vs-suspend)
[229.165424] Starting subtest: flip-vs-suspend
[229.167772] Starting dynamic subtest: A-eDP1
[262.164383] Dynamic subtest A-eDP1: SUCCESS (4.350s)
[262.178130] Starting dynamic subtest: B-eDP1
[311.158792] Dynamic subtest B-eDP1: SUCCESS (4.480s)
[311.169730] Starting dynamic subtest: C-eDP1
[360.170116] Dynamic subtest C-eDP1: SUCCESS (4.583s)
[360.180618] Starting dynamic subtest: D-eDP1
[409.174522] Dynamic subtest D-eDP1: SUCCESS (4.552s)
[409.177564] Subtest flip-vs-suspend: SUCCESS (17.975s)
[410.391910] [072/133] (614s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[410.536229] Starting subtest: basic-busy-flip-before-cursor-atomic
[410.541135] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[410.681000] [073/133] (614s left) kms_psr (psr-cursor-blt)
[410.772639] Starting subtest: psr-cursor-blt
[410.775481] Starting dynamic subtest: eDP-1
[412.463743] Dynamic subtest eDP-1: SUCCESS (1.679s)
[412.466533] Subtest psr-cursor-blt: SUCCESS (1.680s)
[413.318182] [074/133] (611s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[413.417295] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[413.419507] Starting dynamic subtest: pipe-A-eDP-1
[424.786227] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.332s)
[424.796307] Starting dynamic subtest: pipe-B-eDP-1
[436.874193] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.072s)
[436.876724] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (23.408s)
[437.678427] [075/133] (587s left) gem_exec_reloc (basic-write-read-active)
[437.744260] Subtest basic-write-read-active: SKIP (0.000s)
[437.799522] [076/133] (587s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[437.902126] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[442.574058] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.640s)
[443.735531] [077/133] (581s left) kms_flip (2x-nonexisting-fb)
[443.843596] Starting subtest: 2x-nonexisting-fb
[443.854144] Subtest 2x-nonexisting-fb: SKIP (0.012s)
[443.939239] [078/133] (581s left) gem_ctx_isolation (preservation-reset)
[444.124515] Starting subtest: preservation-reset
[444.127340] Starting dynamic subtest: rcs0
[444.402451] Dynamic subtest rcs0: SUCCESS (0.280s)
[444.412822] Starting dynamic subtest: bcs0
[444.725655] Dynamic subtest bcs0: SUCCESS (0.318s)
[444.740512] Starting dynamic subtest: vcs0
[445.045240] Dynamic subtest vcs0: SUCCESS (0.315s)
[445.055529] Starting dynamic subtest: vcs1
[446.977080] Dynamic subtest vcs1: SUCCESS (1.912s)
[446.990275] Starting dynamic subtest: vecs0
[448.926999] Dynamic subtest vecs0: SUCCESS (1.933s)
[448.939290] Starting dynamic subtest: ccs0
[450.997284] Dynamic subtest ccs0: SUCCESS (2.057s)
[451.000254] Subtest preservation-reset: SUCCESS (6.824s)
[451.135951] [079/133] (574s left) syncobj_eventfd (invalid-bad-pad)
[451.193612] Starting subtest: invalid-bad-pad
[451.196505] Subtest invalid-bad-pad: SUCCESS (0.000s)
[451.300611] [080/133] (573s left) kms_universal_plane (cursor-fb-leak)
[451.392453] Starting subtest: cursor-fb-leak
[451.395279] Starting dynamic subtest: pipe-A-eDP-1
[451.752605] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.360s)
[451.764759] Starting dynamic subtest: pipe-B-eDP-1
[453.200296] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.438s)
[453.215799] Starting dynamic subtest: pipe-C-eDP-1
[454.563625] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.355s)
[454.575939] Starting dynamic subtest: pipe-D-eDP-1
[455.983528] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.414s)
[455.986276] Subtest cursor-fb-leak: SUCCESS (4.577s)
[457.203289] [081/133] (568s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[457.318722] Starting subtest: cursor-vs-flip-varying-size
[488.546155] Subtest cursor-vs-flip-varying-size: SUCCESS (31.207s)
[489.423678] [082/133] (535s left) prime_busy (hang)
[489.490620] Starting subtest: hang
[489.493420] Starting dynamic subtest: rcs0
[515.218911] Dynamic subtest rcs0: SUCCESS (25.718s)
[515.228463] Starting dynamic subtest: bcs0
[522.310786] Dynamic subtest bcs0: SUCCESS (7.087s)
[522.321028] Starting dynamic subtest: vcs0
[529.340879] Dynamic subtest vcs0: SUCCESS (7.029s)
[529.351055] Starting dynamic subtest: vcs1
[536.320726] Dynamic subtest vcs1: SUCCESS (6.973s)
[536.330571] Starting dynamic subtest: vecs0
[543.294979] Dynamic subtest vecs0: SUCCESS (6.979s)
[543.305078] Starting dynamic subtest: ccs0
[557.195344] Dynamic subtest ccs0: SUCCESS (13.899s)
[557.197602] Subtest hang: SUCCESS (67.692s)
[557.304863] [083/133] (468s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[557.374074] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[557.376735] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[557.465324] [084/133] (467s left) gem_exec_balancer (parallel-keep-in-fence)
[557.723490] Starting subtest: parallel-keep-in-fence
[559.158032] Subtest parallel-keep-in-fence: SUCCESS (1.434s)
[559.266057] [085/133] (466s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[559.382085] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[559.386704] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[559.477945] [086/133] (466s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[559.587744] Starting subtest: flip-vs-cursor-legacy
[567.121631] Subtest flip-vs-cursor-legacy: SUCCESS (7.531s)
[567.924961] [087/133] (457s left) i915_hangman (gt-engine-error)
[567.988153] Starting subtest: gt-engine-error
[567.990726] Starting dynamic subtest: rcs0
[569.619769] Dynamic subtest rcs0: SUCCESS (1.632s)
[569.633595] Starting dynamic subtest: bcs0
[571.603434] Dynamic subtest bcs0: SUCCESS (1.981s)
[571.616759] Starting dynamic subtest: vcs0
[573.588024] Dynamic subtest vcs0: SUCCESS (1.981s)
[573.601231] Starting dynamic subtest: vcs1
[575.635503] Dynamic subtest vcs1: SUCCESS (2.044s)
[575.651488] Starting dynamic subtest: vecs0
[577.621961] Dynamic subtest vecs0: SUCCESS (1.983s)
[577.635015] Starting dynamic subtest: ccs0
[579.603010] Dynamic subtest ccs0: SUCCESS (1.978s)
[579.605927] Subtest gt-engine-error: SUCCESS (11.616s)
[579.690356] [088/133] (445s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[579.801441] Starting subtest: 4-tiled-64bpp-rotate-0
[581.458529] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (1.657s)
[581.625032] [089/133] (444s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[581.730491] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[581.734974] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[581.821083] [090/133] (443s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[581.924909] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[582.805740] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.881s)
[582.970867] [091/133] (442s left) gem_userptr_blits (invalid-mmap-offset-unsync)
[583.039126] Subtest invalid-mmap-offset-unsync: SKIP (0.000s)
[583.156167] [092/133] (442s left) prime_self_import (basic-with_one_bo_two_files)
[583.196757] Starting subtest: basic-with_one_bo_two_files
[583.210988] Subtest basic-with_one_bo_two_files: SUCCESS (0.015s)
[583.308551] [093/133] (442s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[583.358290] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[583.405155] [094/133] (442s left) gem_exec_params (invalid-batch-start-offset)
[583.456351] Starting subtest: invalid-batch-start-offset
[583.459175] Subtest invalid-batch-start-offset: SUCCESS (0.002s)
[583.590338] [095/133] (442s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[583.693270] Starting subtest: y-tiled-8bpp-rotate-270
[583.698594] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[583.793184] [096/133] (442s left) gem_eio (create)
[584.008519] Starting subtest: create
[584.309018] Subtest create: SUCCESS (0.298s)
[584.423588] [097/133] (441s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[584.510302] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[584.512324] Starting dynamic subtest: pipe-A-eDP-1
[584.531869] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[584.542329] Starting dynamic subtest: pipe-B-eDP-1
[584.563589] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[584.573302] Starting dynamic subtest: pipe-C-eDP-1
[584.593502] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[584.602875] Starting dynamic subtest: pipe-D-eDP-1
[584.622345] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[584.626205] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[586.721837] [098/133] (439s left) kms_psr (pr-primary-blt)
[586.816547] Starting subtest: pr-primary-blt
[586.821775] Subtest pr-primary-blt: SKIP (0.000s)
[586.903482] [099/133] (439s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[587.159430] Starting subtest: cursor-rapid-movement-256x256
[587.162106] Starting dynamic subtest: pipe-A-eDP-1
[587.720290] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.559s)
[587.752926] Starting dynamic subtest: pipe-D-eDP-1
[588.926303] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.182s)
[588.929119] Subtest cursor-rapid-movement-256x256: SUCCESS (1.768s)
[589.726292] [100/133] (436s left) gem_exec_params (sol-reset-invalid)
[589.795843] Starting subtest: sol-reset-invalid
[589.798494] Subtest sol-reset-invalid: SUCCESS (0.003s)
[591.751118] [101/133] (434s left) gem_exec_params (invalid-fence-in)
[591.815481] Starting subtest: invalid-fence-in
[591.817869] Subtest invalid-fence-in: SUCCESS (0.000s)
[591.927997] [102/133] (434s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[592.024306] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[592.029233] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[592.122758] [103/133] (434s left) syncobj_wait (wait-all-complex)
[592.173745] Starting subtest: wait-all-complex
[592.391342] Subtest wait-all-complex: SUCCESS (0.218s)
[592.474354] [104/133] (433s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[592.586610] Starting subtest: fbcpsr-rgb101010-draw-render
[599.254508] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (6.670s)
[599.380051] [105/133] (427s left) kms_lease (lease-unleased-connector)
[599.465976] Starting subtest: lease-unleased-connector
[599.468294] Starting dynamic subtest: pipe-A-eDP-1
[599.473479] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[599.484015] Starting dynamic subtest: pipe-B-eDP-1
[599.486312] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[599.498161] Starting dynamic subtest: pipe-C-eDP-1
[599.500658] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.006s)
[599.510804] Starting dynamic subtest: pipe-D-eDP-1
[599.513226] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.006s)
[599.515472] Subtest lease-unleased-connector: SUCCESS (0.030s)
[599.617060] [106/133] (426s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[599.990492] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[600.807075] [107/133] (425s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[600.901719] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[604.821235] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (3.922s)
[606.051570] [108/133] (420s left) kms_fbcon_fbt (fbc)
[606.107717] Starting subtest: fbc
[612.555542] Subtest fbc: SUCCESS (6.445s)
[613.731665] [109/133] (412s left) kms_vblank (wait-idle-hang)
[613.829586] Starting subtest: wait-idle-hang
[613.832625] Starting dynamic subtest: pipe-A-eDP-1
[640.439131] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.603s)
[640.456015] Starting dynamic subtest: pipe-D-eDP-1
[665.411292] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.962s)
[665.414045] Subtest wait-idle-hang: SUCCESS (51.569s)
[666.253146] [110/133] (360s left) kms_cursor_edge_walk (256x256-top-edge)
[666.350855] Starting subtest: 256x256-top-edge
[666.353653] Starting dynamic subtest: pipe-A-eDP-1
[669.769781] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.421s)
[669.781300] Starting dynamic subtest: pipe-D-eDP-1
[672.954661] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.181s)
[672.957596] Subtest 256x256-top-edge: SUCCESS (6.606s)
[673.826599] [111/133] (352s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[673.888643] Starting subtest: multi-wait-all-for-submit-available-signaled
[673.891535] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[673.986068] [112/133] (352s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[674.072413] Starting subtest: fbc-psr2-cursor-plane-onoff
[674.074803] Starting dynamic subtest: eDP-1
[674.080072] Dynamic subtest eDP-1: SKIP (0.000s)
[674.085027] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[674.149429] [113/133] (352s left) kms_pm_rpm (modeset-lpsp)
[675.769158] Starting subtest: modeset-lpsp
[677.231546] Subtest modeset-lpsp: SUCCESS (1.462s)
[677.320566] [114/133] (349s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[677.431530] Starting subtest: cursorB-vs-flipB-atomic
[677.439442] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[677.576484] [115/133] (349s left) kms_vrr (seamless-rr-switch-drrs)
[677.664522] Starting subtest: seamless-rr-switch-drrs
[677.671303] Subtest seamless-rr-switch-drrs: SKIP (0.000s)
[677.737506] [116/133] (349s left) gem_exec_params (dr4-dirt)
[677.792803] Starting subtest: DR4-dirt
[677.795533] Subtest DR4-dirt: SUCCESS (0.000s)
[677.889816] [117/133] (349s left) gem_exec_schedule (preempt-other-chain)
[677.971625] Starting subtest: preempt-other-chain
[677.974032] Starting dynamic subtest: rcs0
[678.046773] Dynamic subtest rcs0: SUCCESS (0.082s)
[678.063118] Starting dynamic subtest: bcs0
[678.121007] Dynamic subtest bcs0: SUCCESS (0.074s)
[678.135600] Starting dynamic subtest: vcs0
[678.201888] Dynamic subtest vcs0: SUCCESS (0.080s)
[678.212282] Starting dynamic subtest: vcs1
[678.282377] Dynamic subtest vcs1: SUCCESS (0.080s)
[678.295830] Starting dynamic subtest: vecs0
[678.367661] Dynamic subtest vecs0: SUCCESS (0.084s)
[678.380619] Starting dynamic subtest: ccs0
[678.459127] Dynamic subtest ccs0: SUCCESS (0.092s)
[678.461740] Subtest preempt-other-chain: SUCCESS (0.496s)
[679.754864] [118/133] (347s left) i915_drm_fdinfo (memory-info-shared)
[679.798659] Starting subtest: memory-info-shared
[679.801068] Starting dynamic subtest: smem0
[679.838111] Dynamic subtest smem0: SUCCESS (0.041s)
[679.840641] Subtest memory-info-shared: SUCCESS (0.042s)
[679.916289] [119/133] (347s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[680.013887] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[686.616159] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.607s)
[686.747574] [120/133] (340s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[686.850917] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[693.750355] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (6.905s)
[693.855476] [121/133] (333s left) gem_ctx_persistence (legacy-engines-hostile)
[693.907772] Starting subtest: legacy-engines-hostile
[693.910659] Starting dynamic subtest: render
[694.023032] Dynamic subtest render: SUCCESS (0.115s)
[694.035763] Starting dynamic subtest: bsd1
[694.116098] Dynamic subtest bsd1: SUCCESS (0.090s)
[694.128562] Starting dynamic subtest: bsd2
[694.212956] Dynamic subtest bsd2: SUCCESS (0.094s)
[694.226744] Starting dynamic subtest: blt
[694.303989] Dynamic subtest blt: SUCCESS (0.088s)
[694.317569] Starting dynamic subtest: vebox
[694.400214] Dynamic subtest vebox: SUCCESS (0.093s)
[694.403003] Subtest legacy-engines-hostile: SUCCESS (0.494s)
[694.495489] [122/133] (332s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[694.593433] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[694.595715] Starting dynamic subtest: pipe-A-eDP-1
[694.618464] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[694.630165] Starting dynamic subtest: pipe-B-eDP-1
[694.653611] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[694.664346] Starting dynamic subtest: pipe-C-eDP-1
[694.688516] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[694.699768] Starting dynamic subtest: pipe-D-eDP-1
[694.723400] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[694.727479] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.004s)
[696.816391] [123/133] (330s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[696.875845] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[696.977449] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.103s)
[697.059305] [124/133] (330s left) gem_sync (basic-each)
[697.139128] Starting subtest: basic-each
[699.727274] Subtest basic-each: SUCCESS (2.603s)
[699.920907] [125/133] (327s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[700.043712] Starting subtest: cursor-vs-flip-atomic-transitions
[730.188735] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.147s)
[731.018609] [126/133] (296s left) gem_mmap_gtt (basic-small-copy-xy)
[731.079120] Subtest basic-small-copy-XY: SKIP (0.000s)
[731.144886] [127/133] (296s left) gem_pxp (reject-modify-context-protection-off-3)
[731.250756] Starting subtest: reject-modify-context-protection-off-3
[731.253544] Subtest reject-modify-context-protection-off-3: SUCCESS (0.001s)
[731.372600] [128/133] (296s left) sysfs_heartbeat_interval (nopreempt)
[731.426020] Starting subtest: nopreempt
[731.428538] Starting dynamic subtest: vecs0
[740.654645] Dynamic subtest vecs0: SUCCESS (9.225s)
[740.670488] Starting dynamic subtest: ccs0
[744.665035] Dynamic subtest ccs0: SUCCESS (4.009s)
[744.677986] Starting dynamic subtest: vcs0
[754.674735] Dynamic subtest vcs0: SUCCESS (10.009s)
[754.687556] Starting dynamic subtest: bcs0
[758.654907] Dynamic subtest bcs0: SUCCESS (3.976s)
[758.667334] Starting dynamic subtest: rcs0
[762.657543] Dynamic subtest rcs0: SUCCESS (4.001s)
[762.672627] Starting dynamic subtest: vcs1
[772.650426] Dynamic subtest vcs1: SUCCESS (9.989s)
[772.653610] Subtest nopreempt: SUCCESS (41.219s)
[772.735951] [129/133] (254s left) kms_content_protection (content-type-change)
[772.884657] Starting subtest: content-type-change
[772.889727] Subtest content-type-change: SKIP (0.000s)
[773.004977] [130/133] (254s left) kms_psr (psr-suspend)
[773.103030] Starting subtest: psr-suspend
[773.105219] Starting dynamic subtest: eDP-1
[790.594162] Dynamic subtest eDP-1: SUCCESS (3.021s)
[790.596496] Subtest psr-suspend: SUCCESS (3.022s)
[791.401734] [131/133] (236s left) gen9_exec_parse (batch-invalid-length)
[791.461788] Subtest batch-invalid-length: SKIP (0.000s)
[791.522129] [132/133] (236s left) kms_atomic_transition (modeset-transition)
[791.641274] Starting subtest: modeset-transition
[791.643412] Starting dynamic subtest: 1x-outputs
[802.454972] Dynamic subtest 1x-outputs: SUCCESS (10.829s)
[802.457388] Subtest modeset-transition: SUCCESS (10.830s)
[803.295011] [133/133] (224s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[803.384572] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[803.386880] Starting dynamic subtest: pipe-A-default-mode
[806.058560] Dynamic subtest pipe-A-default-mode: SUCCESS (2.675s)
[806.060857] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.677s)
[806.892732] 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.