[35.111245] Initializing watchdogs
[35.111323]   /dev/watchdog0
[35.118264] [001/130] (960s left) kms_lease (page-flip-implicit-plane)
[35.209194] Starting subtest: page-flip-implicit-plane
[35.211793] Starting dynamic subtest: pipe-A-eDP-1
[35.692545] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.482s)
[35.699488] Starting dynamic subtest: pipe-B-eDP-1
[36.885093] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.190s)
[36.892155] Starting dynamic subtest: pipe-C-eDP-1
[38.075654] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.192s)
[38.081066] Starting dynamic subtest: pipe-D-eDP-1
[39.301795] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.226s)
[39.304209] Subtest page-flip-implicit-plane: SUCCESS (4.092s)
[40.203404] [002/130] (955s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[40.325427] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[40.330413] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[40.411657] [003/130] (955s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-25)
[40.502246] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-25
[40.505211] Starting dynamic subtest: pipe-A
[40.519641] Dynamic subtest pipe-A: SKIP (0.011s)
[40.525810] Starting dynamic subtest: pipe-B
[40.538447] Dynamic subtest pipe-B: SKIP (0.020s)
[40.543574] Starting dynamic subtest: pipe-C
[40.560819] Dynamic subtest pipe-C: SKIP (0.022s)
[40.566038] Starting dynamic subtest: pipe-D
[40.580503] Dynamic subtest pipe-D: SKIP (0.020s)
[40.584919] Subtest planes-upscale-factor-0-25-downscale-factor-0-25: SKIP (0.073s)
[40.639331] [004/130] (955s left) gem_mmap_wc (read)
[40.693589] Subtest read: SKIP (0.000s)
[40.727312] [005/130] (954s left) kms_addfb_basic (basic)
[40.774675] Starting subtest: basic
[40.776583] Subtest basic: SUCCESS (0.000s)
[40.899368] [006/130] (954s left) kms_properties (plane-properties-atomic)
[40.988755] Starting subtest: plane-properties-atomic
[40.991133] Starting dynamic subtest: pipe-A-eDP-1
[41.551404] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.561s)
[41.558752] Starting dynamic subtest: pipe-B-eDP-1
[42.903110] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.351s)
[42.911288] Starting dynamic subtest: pipe-C-eDP-1
[44.246461] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.342s)
[44.253350] Starting dynamic subtest: pipe-D-eDP-1
[45.591514] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.344s)
[45.594401] Subtest plane-properties-atomic: SUCCESS (4.601s)
[46.477837] [007/130] (949s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[46.564100] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[46.566598] Starting dynamic subtest: pipe-A
[46.577820] Dynamic subtest pipe-A: SKIP (0.004s)
[46.585579] Starting dynamic subtest: pipe-B
[46.595854] Dynamic subtest pipe-B: SKIP (0.014s)
[46.601154] Starting dynamic subtest: pipe-C
[46.611285] Dynamic subtest pipe-C: SKIP (0.015s)
[46.616418] Starting dynamic subtest: pipe-D
[46.625429] Dynamic subtest pipe-D: SKIP (0.015s)
[46.629840] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.048s)
[46.683190] [008/130] (949s left) gem_exec_reloc (basic-wc-read-active)
[46.740026] Subtest basic-wc-read-active: SKIP (0.000s)
[46.793895] [009/130] (948s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[46.839468] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[46.874538] [010/130] (948s left) perf (polling-parameterized)
[47.016513] Starting subtest: polling-parameterized
[67.036108] Subtest polling-parameterized: SUCCESS (20.328s)
[67.062789] [011/130] (928s left) gem_exec_fence (syncobj-invalid-wait)
[67.482197] Starting subtest: syncobj-invalid-wait
[67.485295] Subtest syncobj-invalid-wait: SUCCESS (0.001s)
[69.393830] [012/130] (926s left) kms_invalid_mode (uint-max-clock)
[69.485532] Starting subtest: uint-max-clock
[69.488066] Starting dynamic subtest: pipe-A-eDP-1
[69.764125] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.279s)
[69.770323] Starting dynamic subtest: pipe-B-eDP-1
[69.772659] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[69.777924] Starting dynamic subtest: pipe-C-eDP-1
[69.780221] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[69.785351] Starting dynamic subtest: pipe-D-eDP-1
[69.787301] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[69.789250] Subtest uint-max-clock: SUCCESS (0.283s)
[70.664299] [013/130] (924s left) kms_psr (pr-primary-mmap-gtt)
[70.757563] Starting subtest: pr-primary-mmap-gtt
[70.763161] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[70.807104] [014/130] (924s left) kms_psr (fbc-psr-sprite-plane-move)
[70.901302] Starting subtest: fbc-psr-sprite-plane-move
[70.904293] Starting dynamic subtest: eDP-1
[70.909806] Dynamic subtest eDP-1: SKIP (0.000s)
[70.914894] Subtest fbc-psr-sprite-plane-move: SKIP (0.000s)
[70.950869] [015/130] (924s left) kms_chamelium_color (ctm-0-25)
[71.038208] Subtest ctm-0-25: SKIP (0.000s)
[71.057697] [016/130] (924s left) syncobj_wait (wait-for-submit-snapshot)
[71.102750] Starting subtest: wait-for-submit-snapshot
[71.183918] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[71.235717] [017/130] (924s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[71.403596] Starting subtest: cursor-rapid-movement-128x128
[71.408378] Starting dynamic subtest: pipe-A-eDP-1
[71.914742] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.507s)
[71.933253] Starting dynamic subtest: pipe-D-eDP-1
[73.159178] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.231s)
[73.161656] Subtest cursor-rapid-movement-128x128: SUCCESS (1.757s)
[74.019126] [018/130] (921s left) gem_exec_schedule (smoketest-all)
[74.088668] Starting subtest: smoketest-all
[106.424840] Subtest smoketest-all: SUCCESS (32.300s)
[107.387860] [019/130] (888s left) gem_userptr_blits (mmap-offset-banned)
[107.450863] Starting subtest: mmap-offset-banned
[107.453144] Starting dynamic subtest: wb
[107.455075] Dynamic subtest wb: SUCCESS (0.000s)
[107.462216] Starting dynamic subtest: wc
[107.464107] Dynamic subtest wc: SUCCESS (0.000s)
[107.469541] Starting dynamic subtest: uc
[107.471673] Dynamic subtest uc: SUCCESS (0.000s)
[107.473805] Subtest mmap-offset-banned: SUCCESS (0.000s)
[107.559286] [020/130] (887s left) gem_exec_capture (capture-recoverable)
[107.612672] Starting subtest: capture-recoverable
[107.618229] Subtest capture-recoverable: SUCCESS (0.003s)
[107.714120] [021/130] (887s left) gem_exec_balancer (parallel-bb-first)
[107.923710] Starting subtest: parallel-bb-first
[108.454549] Subtest parallel-bb-first: SUCCESS (0.531s)
[109.370357] [022/130] (886s left) kms_content_protection (legacy)
[109.512481] Starting subtest: legacy
[113.534292] Subtest legacy: SKIP (4.018s)
[114.765705] [023/130] (880s left) kms_lease (lease-unleased-crtc)
[114.849796] Starting subtest: lease-unleased-crtc
[114.852280] Starting dynamic subtest: pipe-A-eDP-1
[115.137233] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[115.148812] Starting dynamic subtest: pipe-B-eDP-1
[115.996750] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.860s)
[116.007163] Starting dynamic subtest: pipe-C-eDP-1
[116.009294] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.006s)
[116.014988] Starting dynamic subtest: pipe-D-eDP-1
[116.017134] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.008s)
[116.019319] Subtest lease-unleased-crtc: SUCCESS (1.162s)
[116.326412] [024/130] (879s left) gem_ctx_shared (q-promotion)
[116.380329] Starting subtest: Q-promotion
[116.382645] Starting dynamic subtest: rcs0
[116.485184] Dynamic subtest rcs0: SUCCESS (0.104s)
[116.494371] Starting dynamic subtest: vcs0
[116.562907] Dynamic subtest vcs0: SUCCESS (0.078s)
[116.574720] Starting dynamic subtest: vcs1
[116.631204] Dynamic subtest vcs1: SUCCESS (0.068s)
[116.644769] Starting dynamic subtest: vecs0
[116.704729] Dynamic subtest vecs0: SUCCESS (0.074s)
[116.718538] Starting dynamic subtest: ccs0
[116.768808] Dynamic subtest ccs0: SUCCESS (0.063s)
[116.771764] Subtest Q-promotion: SUCCESS (0.387s)
[118.438080] [025/130] (877s left) gem_ctx_param (basic-default)
[118.489251] Starting subtest: basic-default
[118.492496] Subtest basic-default: SUCCESS (0.000s)
[118.534293] [026/130] (877s left) kms_chamelium_edid (dp-edid-stress-resolution-non-4k)
[118.904878] Subtest dp-edid-stress-resolution-non-4k: SKIP (0.000s)
[119.799135] [027/130] (875s left) prime_vgem (basic-fence-flip)
[119.882129] Starting subtest: basic-fence-flip
[120.169595] Subtest basic-fence-flip: SUCCESS (0.287s)
[120.250212] [028/130] (875s left) gem_mmap_gtt (flink-race)
[120.310301] Subtest flink-race: SKIP (0.000s)
[120.349877] [029/130] (875s left) kms_chamelium_hpd (dp-hpd-fast)
[120.722332] Subtest dp-hpd-fast: SKIP (0.000s)
[121.592794] [030/130] (874s left) kms_cursor_crc (cursor-sliding-128x42)
[121.768045] Starting subtest: cursor-sliding-128x42
[121.803782] Subtest cursor-sliding-128x42: SKIP (0.032s)
[121.979135] [031/130] (873s left) gem_render_copy (y-tiled)
[122.026338] Starting subtest: y-tiled
[122.030816] Subtest y-tiled: SKIP (0.000s)
[122.181823] [032/130] (873s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[122.272233] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[123.113855] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (0.843s)
[123.213830] [033/130] (872s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[123.316025] Starting subtest: plane-use-after-nonblocking-unbind
[123.318425] Starting dynamic subtest: pipe-A-eDP-1
[123.372384] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.055s)
[123.663561] Starting dynamic subtest: pipe-B-eDP-1
[124.551624] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.897s)
[124.841172] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.524s)
[125.707571] [034/130] (870s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[125.804815] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[125.807173] Starting dynamic subtest: pipe-A-eDP-1
[125.828610] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[125.836211] Starting dynamic subtest: pipe-B-eDP-1
[125.857590] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[125.863711] Starting dynamic subtest: pipe-C-eDP-1
[125.885618] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[125.891632] Starting dynamic subtest: pipe-D-eDP-1
[125.913550] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[125.917937] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.002s)
[125.931026] [035/130] (869s left) kms_pm_rpm (i2c)
[127.544168] Starting subtest: i2c
[133.770327] Subtest i2c: SUCCESS (6.226s)
[133.835911] [036/130] (861s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[133.929956] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[138.252074] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (4.325s)
[138.349411] [037/130] (857s left) kms_psr (psr2-primary-mmap-cpu)
[138.433895] Starting subtest: psr2-primary-mmap-cpu
[138.436297] Starting dynamic subtest: eDP-1
[140.203734] Dynamic subtest eDP-1: SUCCESS (1.768s)
[140.206197] Subtest psr2-primary-mmap-cpu: SUCCESS (1.769s)
[141.069581] [038/130] (854s left) i915_pm_rpm (gem-idle)
[142.597772] Starting subtest: gem-idle
[148.801852] Subtest gem-idle: SUCCESS (6.205s)
[149.086102] [039/130] (846s left) kms_content_protection (dp-mst-lic-type-0)
[150.431571] Starting subtest: dp-mst-lic-type-0
[150.436029] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[150.506809] [040/130] (845s left) gem_exec_gttfill (basic)
[150.559381] Starting subtest: basic
[152.777538] Subtest basic: SUCCESS (2.216s)
[152.937087] [041/130] (842s left) gem_pxp (display-protected-crc)
[155.544717] Subtest display-protected-crc: SKIP (0.000s)
[155.586712] [042/130] (840s left) kms_plane_multiple (tiling-y)
[155.683868] Starting subtest: tiling-y
[155.688542] Subtest tiling-y: SKIP (0.000s)
[155.734570] [043/130] (840s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[155.825540] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[155.827877] Starting dynamic subtest: pipe-A-default-mode
[158.688801] Dynamic subtest pipe-A-default-mode: SUCCESS (2.860s)
[158.691420] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.861s)
[159.564736] [044/130] (836s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[159.626036] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[159.663366] [045/130] (836s left) gen7_exec_parse (bitmasks)
[159.715307] Subtest bitmasks: SKIP (0.000s)
[159.749696] [046/130] (836s left) i915_pm_rps (fence-order)
[159.807553] Starting subtest: fence-order
[163.129812] Subtest fence-order: SUCCESS (3.320s)
[163.188693] [047/130] (832s left) gem_exec_flush (basic-wb-rw-default)
[163.248884] Starting subtest: basic-wb-rw-default
[168.683994] Subtest basic-wb-rw-default: SUCCESS (5.435s)
[168.845774] [048/130] (827s left) gem_userptr_blits (map-fixed-invalidate-busy)
[168.901030] Starting subtest: map-fixed-invalidate-busy
[168.905425] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[169.006315] [049/130] (826s left) syncobj_wait (multi-wait-for-submit-submitted-signaled)
[169.048989] Starting subtest: multi-wait-for-submit-submitted-signaled
[169.051548] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[169.118767] [050/130] (826s left) kms_fbcon_fbt (psr)
[169.164789] Starting subtest: psr
[174.590764] Subtest psr: SUCCESS (5.421s)
[174.640134] [051/130] (821s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[174.736725] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[174.740677] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[174.794508] [052/130] (821s left) syncobj_basic (bad-create-flags)
[174.830386] Starting subtest: bad-create-flags
[174.832956] Subtest bad-create-flags: SUCCESS (0.000s)
[174.883797] [053/130] (821s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[174.928677] Starting subtest: multi-wait-for-submit-submitted-signaled
[174.930828] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[174.982052] [054/130] (821s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[175.026787] Starting subtest: invalid-signal-one-illegal-handle
[175.029082] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[175.084740] [055/130] (820s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[175.166811] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[175.169208] Starting dynamic subtest: pipe-A-eDP-1
[175.191629] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[175.200286] Starting dynamic subtest: pipe-B-eDP-1
[175.222545] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[175.230761] Starting dynamic subtest: pipe-C-eDP-1
[175.252485] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[175.259336] Starting dynamic subtest: pipe-D-eDP-1
[175.281892] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[175.286056] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.003s)
[175.299807] [056/130] (820s left) perf (gen8-unprivileged-single-ctx-counters)
[175.384235] Starting subtest: gen8-unprivileged-single-ctx-counters
[175.389099] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[175.404619] [057/130] (820s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[175.501587] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[175.505622] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[175.566427] [058/130] (820s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[175.653615] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[175.657705] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[175.709545] [059/130] (820s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[175.803252] Starting subtest: 2x-flip-vs-modeset-vs-hang
[175.817947] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.014s)
[175.869046] [060/130] (820s left) syncobj_eventfd (binary-wait-signaled)
[175.921242] Starting subtest: binary-wait-signaled
[175.923067] Subtest binary-wait-signaled: SUCCESS (0.000s)
[175.985151] [061/130] (820s left) gem_mmap (big-bo)
[176.025804] Subtest big-bo: SKIP (0.000s)
[176.060429] [062/130] (820s left) kms_plane_scaling (2x-scaler-multi-pipe)
[176.135820] Starting subtest: 2x-scaler-multi-pipe
[176.139529] Subtest 2x-scaler-multi-pipe: SKIP (0.001s)
[176.229267] [063/130] (819s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[176.319305] Starting subtest: blocking-absolute-wf_vblank-interruptible
[176.321211] Starting dynamic subtest: A-eDP1
[185.101175] Dynamic subtest A-eDP1: SUCCESS (8.780s)
[185.108771] Starting dynamic subtest: B-eDP1
[193.869348] Dynamic subtest B-eDP1: SUCCESS (8.766s)
[193.881414] Starting dynamic subtest: C-eDP1
[202.625794] Dynamic subtest C-eDP1: SUCCESS (8.755s)
[202.635834] Starting dynamic subtest: D-eDP1
[211.392628] Dynamic subtest D-eDP1: SUCCESS (8.766s)
[211.398560] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (35.076s)
[212.618067] [064/130] (783s left) gem_exec_store (basic)
[212.678378] Starting subtest: basic
[212.755354] Subtest basic: SUCCESS (0.078s)
[212.914144] [065/130] (783s left) api_intel_bb (offset-control)
[212.965478] Starting subtest: offset-control
[212.970557] Subtest offset-control: SUCCESS (0.006s)
[213.071660] [066/130] (783s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc)
[213.173250] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc
[217.452364] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc: SUCCESS (4.281s)
[217.562900] [067/130] (778s left) gem_lmem_evict (dontneed-evict-race)
[217.596045] Subtest dontneed-evict-race: SKIP (0.000s)
[217.623093] [068/130] (778s left) gem_exec_parallel (userptr)
[217.673380] Starting subtest: userptr
[217.676491] Starting dynamic subtest: rcs0
[219.043301] Dynamic subtest rcs0: SUCCESS (1.371s)
[219.053961] Starting dynamic subtest: bcs0
[220.372099] Dynamic subtest bcs0: SUCCESS (1.328s)
[220.379669] Starting dynamic subtest: vcs0
[222.377345] Dynamic subtest vcs0: SUCCESS (2.003s)
[222.385508] Starting dynamic subtest: vcs1
[224.361778] Dynamic subtest vcs1: SUCCESS (1.984s)
[224.371632] Starting dynamic subtest: vecs0
[226.347311] Dynamic subtest vecs0: SUCCESS (1.984s)
[226.356371] Starting dynamic subtest: ccs0
[227.762187] Dynamic subtest ccs0: SUCCESS (1.416s)
[227.764551] Subtest userptr: SUCCESS (10.090s)
[227.928884] [069/130] (768s left) kms_psr (fbc-psr2-cursor-render)
[228.023034] Starting subtest: fbc-psr2-cursor-render
[228.025457] Starting dynamic subtest: eDP-1
[228.030118] Dynamic subtest eDP-1: SKIP (0.000s)
[228.034752] Subtest fbc-psr2-cursor-render: SKIP (0.000s)
[228.073733] [070/130] (768s left) gem_exec_schedule (preempt-queue-chain)
[228.146626] Subtest preempt-queue-chain: SKIP (0.000s)
[228.405387] [071/130] (767s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[228.488979] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[228.493123] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[228.556014] [072/130] (767s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-ccs)
[228.601491] Starting subtest: y-tiled-ccs-to-yf-tiled-ccs
[228.607126] Subtest y-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[228.761150] [073/130] (767s left) drm_fdinfo (memory-info-shared)
[228.793284] Starting subtest: memory-info-shared
[228.795572] Starting dynamic subtest: smem0
[228.848730] Dynamic subtest smem0: SUCCESS (0.058s)
[228.851567] Subtest memory-info-shared: SUCCESS (0.058s)
[228.899124] [074/130] (767s left) prime_vgem (basic-fence-mmap)
[228.972451] Starting subtest: basic-fence-mmap
[228.977321] Subtest basic-fence-mmap: SKIP (0.000s)
[229.057919] [075/130] (767s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc)
[229.142800] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc
[229.145158] Starting dynamic subtest: pipe-A-eDP-1
[229.167325] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[229.173748] Starting dynamic subtest: pipe-B-eDP-1
[229.195550] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[229.201808] Starting dynamic subtest: pipe-C-eDP-1
[229.223679] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[229.229831] Starting dynamic subtest: pipe-D-eDP-1
[229.252082] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[229.256528] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[229.270778] [076/130] (767s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[229.367915] Starting subtest: psr-2p-primscrn-spr-indfb-move
[229.371506] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[229.438045] [077/130] (766s left) gem_sync (basic-store-all)
[229.497270] Starting subtest: basic-store-all
[232.138766] Subtest basic-store-all: SUCCESS (2.649s)
[232.298295] [078/130] (764s left) kms_getfb (getfb2-handle-not-fb)
[232.345409] Starting subtest: getfb2-handle-not-fb
[232.383703] Subtest getfb2-handle-not-fb: SUCCESS (0.041s)
[232.459794] [079/130] (763s left) gem_exec_schedule (independent)
[232.522883] Starting subtest: independent
[232.525158] Starting dynamic subtest: rcs0
[234.345443] Dynamic subtest rcs0: SUCCESS (1.832s)
[234.355679] Starting dynamic subtest: vcs0
[236.420556] Dynamic subtest vcs0: SUCCESS (2.074s)
[236.431994] Starting dynamic subtest: vcs1
[238.401735] Dynamic subtest vcs1: SUCCESS (1.980s)
[238.411683] Starting dynamic subtest: vecs0
[240.362523] Dynamic subtest vecs0: SUCCESS (1.960s)
[240.369921] Starting dynamic subtest: ccs0
[242.345862] Dynamic subtest ccs0: SUCCESS (1.982s)
[242.348580] Subtest independent: SUCCESS (9.832s)
[242.625183] [080/130] (753s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[242.738454] Starting subtest: basic-busy-flip-before-cursor-atomic
[242.743035] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[242.826355] [081/130] (753s left) gem_softpin (noreloc)
[242.880311] Starting subtest: noreloc
[248.295872] Subtest noreloc: SUCCESS (5.414s)
[248.385073] [082/130] (748s left) gem_ctx_persistence (legacy-engines-persistence)
[248.446619] Starting subtest: legacy-engines-persistence
[248.449264] Starting dynamic subtest: render
[249.231204] Dynamic subtest render: SUCCESS (0.784s)
[249.240458] Starting dynamic subtest: bsd1
[250.002277] Dynamic subtest bsd1: SUCCESS (0.770s)
[250.010208] Starting dynamic subtest: bsd2
[250.771069] Dynamic subtest bsd2: SUCCESS (0.768s)
[250.779643] Starting dynamic subtest: blt
[251.537968] Dynamic subtest blt: SUCCESS (0.766s)
[251.547976] Starting dynamic subtest: vebox
[252.313787] Dynamic subtest vebox: SUCCESS (0.774s)
[252.316941] Subtest legacy-engines-persistence: SUCCESS (3.867s)
[252.426148] [083/130] (744s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[257.570043] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[257.572494] Starting dynamic subtest: pipe-A-eDP-1
[258.416486] Dynamic subtest pipe-A-eDP-1: SKIP (0.846s)
[258.425311] Starting dynamic subtest: pipe-B-eDP-1
[259.630879] Dynamic subtest pipe-B-eDP-1: SKIP (1.212s)
[259.635378] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (2.061s)
[260.878290] [084/130] (735s left) syncobj_timeline (single-wait-all-available-submitted)
[260.933726] Starting subtest: single-wait-all-available-submitted
[260.936112] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[260.997220] [085/130] (735s left) gem_softpin (full)
[261.044143] Starting subtest: full
[261.051526] Subtest full: SUCCESS (0.008s)
[261.147227] [086/130] (735s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[261.237695] Starting subtest: yf-tiled-16bpp-rotate-180
[261.242085] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[261.297125] [087/130] (735s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[261.394490] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[261.398570] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[261.457770] [088/130] (735s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[261.507212] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[261.613924] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.107s)
[261.662961] [089/130] (734s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[261.773730] Starting subtest: 2x-cursor-vs-flip-atomic
[261.778449] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.002s)
[261.874442] [090/130] (734s left) kms_atomic (plane-immutable-zpos)
[261.968901] Starting subtest: plane-immutable-zpos
[261.971283] Starting dynamic subtest: pipe-A-eDP-1
[262.570931] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.601s)
[262.573522] Subtest plane-immutable-zpos: SUCCESS (0.602s)
[263.471124] [091/130] (733s left) api_intel_bb (add-remove-objects)
[263.527991] Starting subtest: add-remove-objects
[263.534355] Subtest add-remove-objects: SUCCESS (0.008s)
[263.623353] [092/130] (732s left) i915_suspend (forcewake)
[263.666556] Starting subtest: forcewake
[295.795635] Subtest forcewake: SUCCESS (1.984s)
[296.844670] [093/130] (730s left) gem_workarounds (reset-context)
[296.897913] Starting subtest: reset-context
[297.045330] Subtest reset-context: SUCCESS (0.152s)
[297.162547] [094/130] (730s left) gem_close_race (basic-threads)
[297.220057] Starting subtest: basic-threads
[298.385046] Subtest basic-threads: SUCCESS (1.206s)
[298.541184] [095/130] (729s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[298.632163] Starting subtest: psr-rgb565-draw-mmap-gtt
[298.636279] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[298.692982] [096/130] (729s left) perf (whitelisted-registers-userspace-config)
[298.764637] Starting subtest: whitelisted-registers-userspace-config
[298.766972] Subtest whitelisted-registers-userspace-config: SUCCESS (0.000s)
[298.788764] [097/130] (728s left) gem_pread (display)
[298.834948] Subtest display: SKIP (0.000s)
[298.860313] [098/130] (728s left) kms_draw_crc (draw-method-blt)
[298.945007] Starting subtest: draw-method-blt
[298.947182] Starting dynamic subtest: xrgb8888-untiled
[299.136618] Dynamic subtest xrgb8888-untiled: SUCCESS (0.199s)
[299.145667] Starting dynamic subtest: rgb565-untiled
[299.319133] Dynamic subtest rgb565-untiled: SUCCESS (0.189s)
[299.327638] Starting dynamic subtest: xrgb2101010-untiled
[299.491756] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.178s)
[299.499520] Starting dynamic subtest: xrgb8888-xtiled
[299.589059] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.100s)
[299.597604] Starting dynamic subtest: rgb565-xtiled
[299.698041] Dynamic subtest rgb565-xtiled: SUCCESS (0.111s)
[299.707413] Starting dynamic subtest: xrgb2101010-xtiled
[299.784289] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.088s)
[299.791924] Starting dynamic subtest: xrgb8888-4tiled
[299.881610] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.100s)
[299.888967] Starting dynamic subtest: rgb565-4tiled
[299.979297] Dynamic subtest rgb565-4tiled: SUCCESS (0.100s)
[299.986687] Starting dynamic subtest: xrgb2101010-4tiled
[300.065623] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.089s)
[300.067610] Subtest draw-method-blt: SUCCESS (1.155s)
[300.176885] [099/130] (727s left) i915_fb_tiling
[300.266993] [100/130] (727s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[300.352759] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[300.356157] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[300.421067] [101/130] (727s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[300.513773] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc
[300.517808] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[300.572713] [102/130] (727s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[305.671804] Starting subtest: pr-primary-plane-update-sf-dmg-area
[305.677063] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.002s)
[306.571728] [103/130] (721s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[306.669547] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[311.003903] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.374s)
[311.106248] [104/130] (716s left) drm_fdinfo (all-busy-idle-check-all)
[311.139577] Starting subtest: all-busy-idle-check-all
[311.143857] Subtest all-busy-idle-check-all: SKIP (0.004s)
[311.183920] [105/130] (716s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[311.277999] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[313.977287] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (2.716s)
[314.100828] [106/130] (713s left) perf_pmu (busy-hang)
[314.127181] Starting subtest: busy-hang
[314.129057] Starting dynamic subtest: rcs0
[315.399440] Dynamic subtest rcs0: SUCCESS (1.273s)
[315.409018] Starting dynamic subtest: bcs0
[316.629272] Dynamic subtest bcs0: SUCCESS (1.236s)
[316.641069] Starting dynamic subtest: vcs0
[319.011714] Dynamic subtest vcs0: SUCCESS (2.389s)
[319.019568] Starting dynamic subtest: vcs1
[321.073815] Dynamic subtest vcs1: SUCCESS (2.063s)
[321.083742] Starting dynamic subtest: vecs0
[323.055293] Dynamic subtest vecs0: SUCCESS (1.987s)
[323.066166] Starting dynamic subtest: ccs0
[324.285121] Dynamic subtest ccs0: SUCCESS (1.231s)
[324.288069] Subtest busy-hang: SUCCESS (10.183s)
[324.365309] [107/130] (703s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[324.466367] Starting subtest: short-flip-after-cursor-toggle
[325.378224] Subtest short-flip-after-cursor-toggle: SUCCESS (0.914s)
[326.283684] [108/130] (701s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[326.365805] Starting subtest: fbc-pr-cursor-mmap-cpu
[326.370235] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[326.414772] [109/130] (701s left) kms_vrr (negative-basic)
[326.508657] Starting subtest: negative-basic
[326.515212] Starting dynamic subtest: pipe-A-eDP-1
[332.424529] Dynamic subtest pipe-A-eDP-1: FAIL (5.765s)
[332.580593] Subtest negative-basic: FAIL (6.070s)
[333.456069] [110/130] (694s left) syncobj_wait (multi-wait-all-submitted-signaled)
[333.505645] Starting subtest: multi-wait-all-submitted-signaled
[333.612927] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.106s)
[333.666862] [111/130] (694s left) gem_ctx_param (invalid-get-no-zeromap)
[333.717074] Starting subtest: invalid-get-no-zeromap
[333.720228] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[333.810082] [112/130] (693s left) perf (short-reads)
[333.882183] Starting subtest: short-reads
[333.894391] Subtest short-reads: SUCCESS (0.012s)
[333.918740] [113/130] (693s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[334.004107] Starting subtest: fbc-2p-pri-indfb-multidraw
[334.008504] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[334.077797] [114/130] (693s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[334.168990] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[334.824987] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.658s)
[334.961617] [115/130] (692s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[335.047171] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[338.257097] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (3.212s)
[338.393654] [116/130] (689s left) kms_prop_blob (blob-prop-core)
[338.439626] Starting subtest: blob-prop-core
[338.441745] Subtest blob-prop-core: SUCCESS (0.000s)
[338.504483] [117/130] (689s left) perf_pmu (render-node-busy-idle)
[338.554433] Starting subtest: render-node-busy-idle
[338.557102] Starting dynamic subtest: rcs0
[339.239205] Dynamic subtest rcs0: SUCCESS (0.684s)
[339.249401] Starting dynamic subtest: bcs0
[339.898555] Dynamic subtest bcs0: SUCCESS (0.658s)
[339.909512] Starting dynamic subtest: vcs0
[341.544514] Dynamic subtest vcs0: SUCCESS (1.644s)
[341.555044] Starting dynamic subtest: vcs1
[343.529071] Dynamic subtest vcs1: SUCCESS (1.983s)
[343.540467] Starting dynamic subtest: vecs0
[345.512977] Dynamic subtest vecs0: SUCCESS (1.983s)
[345.523869] Starting dynamic subtest: ccs0
[346.178076] Dynamic subtest ccs0: SUCCESS (0.666s)
[346.181243] Subtest render-node-busy-idle: SUCCESS (7.624s)
[346.228188] [118/130] (681s left) kms_ccs (bad-rotation-90-4-tiled-lnl-ccs)
[346.323805] Starting subtest: bad-rotation-90-4-tiled-lnl-ccs
[346.328324] Subtest bad-rotation-90-4-tiled-lnl-ccs: SKIP (0.000s)
[346.373780] [119/130] (681s left) gem_mmap_offset (partial-unmap)
[346.419709] Starting subtest: partial-unmap
[346.421981] Starting dynamic subtest: smem0
[346.927151] Dynamic subtest smem0: SUCCESS (0.507s)
[346.930099] Subtest partial-unmap: SUCCESS (0.508s)
[346.996047] [120/130] (680s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[347.098650] Starting subtest: 4-tiled-64bpp-rotate-270
[347.114348] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.018s)
[347.214174] [121/130] (680s left) gem_create (create-clear)
[347.266794] Starting subtest: create-clear
[347.269234] Starting dynamic subtest: smem0
[379.788869] Dynamic subtest smem0: SUCCESS (32.529s)
[379.790752] Subtest create-clear: SUCCESS (32.530s)
[379.950791] [122/130] (647s left) kms_universal_plane (universal-plane-functional)
[380.042772] Starting subtest: universal-plane-functional
[380.045003] Starting dynamic subtest: pipe-A-eDP-1
[383.962899] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.919s)
[383.971745] Starting dynamic subtest: pipe-B-eDP-1
[388.576074] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.614s)
[388.584863] Starting dynamic subtest: pipe-C-eDP-1
[393.008642] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.431s)
[393.016945] Starting dynamic subtest: pipe-D-eDP-1
[397.497431] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.486s)
[397.500306] Subtest universal-plane-functional: SUCCESS (17.455s)
[398.415250] [123/130] (629s left) kms_psr (psr-sprite-mmap-cpu)
[398.513902] Starting subtest: psr-sprite-mmap-cpu
[398.516248] Starting dynamic subtest: eDP-1
[400.333389] Dynamic subtest eDP-1: SUCCESS (1.817s)
[400.335863] Subtest psr-sprite-mmap-cpu: SUCCESS (1.818s)
[401.235521] [124/130] (626s left) gem_busy (parallel)
[401.407132] Starting subtest: parallel
[401.409539] Starting dynamic subtest: rcs0
[403.497094] Dynamic subtest rcs0: SUCCESS (2.087s)
[403.512537] Starting dynamic subtest: bcs0
[405.545502] Dynamic subtest bcs0: SUCCESS (2.046s)
[405.560102] Starting dynamic subtest: vcs0
[407.529118] Dynamic subtest vcs0: SUCCESS (1.982s)
[407.542178] Starting dynamic subtest: vcs1
[409.513325] Dynamic subtest vcs1: SUCCESS (1.983s)
[409.524227] Starting dynamic subtest: vecs0
[411.496998] Dynamic subtest vecs0: SUCCESS (1.982s)
[411.507502] Starting dynamic subtest: ccs0
[413.545672] Dynamic subtest ccs0: SUCCESS (2.047s)
[413.549071] Subtest parallel: SUCCESS (12.134s)
[413.758050] [125/130] (614s left) sysfs_heartbeat_interval (idempotent)
[413.807985] Starting subtest: idempotent
[413.811018] Starting dynamic subtest: vecs0
[413.813745] Dynamic subtest vecs0: SUCCESS (0.001s)
[413.821161] Starting dynamic subtest: ccs0
[413.823560] Dynamic subtest ccs0: SUCCESS (0.001s)
[413.830867] Starting dynamic subtest: vcs0
[413.833132] Dynamic subtest vcs0: SUCCESS (0.000s)
[413.840724] Starting dynamic subtest: bcs0
[413.842639] Dynamic subtest bcs0: SUCCESS (0.000s)
[413.849806] Starting dynamic subtest: rcs0
[413.852105] Dynamic subtest rcs0: SUCCESS (0.000s)
[413.859131] Starting dynamic subtest: vcs1
[413.861372] Dynamic subtest vcs1: SUCCESS (0.000s)
[413.863581] Subtest idempotent: SUCCESS (0.005s)
[413.878397] [126/130] (614s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[413.958723] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[413.960845] Starting dynamic subtest: pipe-A-default-mode
[416.765632] Dynamic subtest pipe-A-default-mode: SUCCESS (2.803s)
[416.767782] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.805s)
[417.676635] [127/130] (610s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[417.783527] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[417.787795] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[417.853329] [128/130] (610s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[417.964065] Starting subtest: cursorA-vs-flipA-toggle
[429.080359] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.115s)
[429.965075] [129/130] (598s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[430.053155] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[434.668482] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.619s)
[434.756986] [130/130] (593s left) kms_flip (2x-nonexisting-fb-interruptible)
[434.851926] Starting subtest: 2x-nonexisting-fb-interruptible
[434.868285] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.014s)
[434.917176] Closing watchdogs
Done.