[52.190459] Initializing watchdogs
[52.190934]   /dev/watchdog0
[52.222150] [001/142] (960s left) perf (gen12-group-exclusive-stream-sample-oa)
[53.215596] Starting subtest: gen12-group-exclusive-stream-sample-oa
[53.310281] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.091s)
[53.415130] [002/142] (959s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[53.950243] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[64.209148] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (10.262s)
[64.475304] [003/142] (948s left) kms_content_protection (mei_interface)
[65.195325] Starting subtest: mei_interface
[65.204968] Subtest mei_interface: SKIP (0.006s)
[65.383365] [004/142] (947s left) kms_cursor_legacy (torture-bo)
[65.947352] Starting subtest: torture-bo
[65.950949] Starting dynamic subtest: pipe-A
[72.421479] Dynamic subtest pipe-A: SUCCESS (6.469s)
[72.452575] Starting dynamic subtest: pipe-B
[79.048133] Dynamic subtest pipe-B: SUCCESS (6.628s)
[79.078405] Starting dynamic subtest: pipe-C
[85.253186] Dynamic subtest pipe-C: SUCCESS (6.204s)
[85.277971] Starting dynamic subtest: pipe-D
[92.301537] Dynamic subtest pipe-D: SUCCESS (7.049s)
[92.329761] Starting dynamic subtest: all-pipes
[98.937361] Dynamic subtest all-pipes: SUCCESS (6.635s)
[98.940695] Subtest torture-bo: SUCCESS (32.989s)
[99.272147] [005/142] (913s left) gem_flink_basic (double-flink)
[99.637596] Starting subtest: double-flink
[99.641334] Subtest double-flink: SUCCESS (0.000s)
[99.795524] [006/142] (913s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_rc_ccs_cc)
[100.957639] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc
[100.963978] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.002s)
[102.164642] [007/142] (910s left) syncobj_wait (invalid-signal-one-illegal-handle)
[102.534641] Starting subtest: invalid-signal-one-illegal-handle
[102.538793] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.001s)
[102.662646] [008/142] (910s left) kms_cursor_edge_walk (128x128-top-bottom)
[103.131511] Starting subtest: 128x128-top-bottom
[103.138872] Starting dynamic subtest: pipe-A-eDP-1
[107.240279] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.100s)
[107.275930] Starting dynamic subtest: pipe-D-eDP-1
[110.710880] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.463s)
[110.714568] Subtest 128x128-top-bottom: SUCCESS (7.578s)
[111.641189] [009/142] (901s left) gem_ctx_isolation (preservation-reset)
[112.181499] Starting subtest: preservation-reset
[112.185265] Starting dynamic subtest: rcs0
[113.246886] Dynamic subtest rcs0: SUCCESS (1.063s)
[113.281194] Starting dynamic subtest: bcs0
[114.335363] Dynamic subtest bcs0: SUCCESS (1.088s)
[114.366930] Starting dynamic subtest: vcs0
[115.516598] Dynamic subtest vcs0: SUCCESS (1.180s)
[115.551560] Starting dynamic subtest: vcs1
[116.753563] Dynamic subtest vcs1: SUCCESS (1.236s)
[116.788169] Starting dynamic subtest: vecs0
[118.654271] Dynamic subtest vecs0: SUCCESS (1.900s)
[118.688116] Starting dynamic subtest: ccs0
[120.722304] Dynamic subtest ccs0: SUCCESS (2.067s)
[120.725931] Subtest preservation-reset: SUCCESS (8.542s)
[121.002407] [010/142] (892s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[121.545889] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[124.808896] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (3.263s)
[125.115210] [011/142] (888s left) gem_bad_reloc (negative-reloc)
[125.511365] Subtest negative-reloc: SKIP (0.000s)
[125.631917] [012/142] (887s left) kms_color (ctm-0-50)
[126.111202] Starting subtest: ctm-0-50
[126.114711] Starting dynamic subtest: pipe-A
[131.155323] Dynamic subtest pipe-A: SUCCESS (5.041s)
[131.185656] Starting dynamic subtest: pipe-B
[136.908842] Dynamic subtest pipe-B: SUCCESS (5.752s)
[136.941401] Starting dynamic subtest: pipe-C
[142.863143] Dynamic subtest pipe-C: SUCCESS (5.951s)
[142.909465] Starting dynamic subtest: pipe-D
[149.186508] Dynamic subtest pipe-D: SUCCESS (6.325s)
[149.189840] Subtest ctm-0-50: SUCCESS (23.077s)
[150.131630] [013/142] (863s left) gem_userptr_blits (sync-unmap-cycles)
[150.610369] Starting subtest: sync-unmap-cycles
[155.980054] Subtest sync-unmap-cycles: SUCCESS (5.370s)
[156.232691] [014/142] (857s left) v3d/v3d_submit_csd (multisync-out-syncs)
[156.721760] Subtest multisync-out-syncs: SKIP (0.000s)
[156.859136] [015/142] (856s left) sysfs_heartbeat_interval (idempotent)
[157.250200] Starting subtest: idempotent
[157.253294] Starting dynamic subtest: vecs0
[157.260530] Dynamic subtest vecs0: SUCCESS (0.007s)
[157.297854] Starting dynamic subtest: ccs0
[157.300662] Dynamic subtest ccs0: SUCCESS (0.008s)
[157.335916] Starting dynamic subtest: vcs0
[157.339057] Dynamic subtest vcs0: SUCCESS (0.006s)
[157.373412] Starting dynamic subtest: bcs0
[157.376077] Dynamic subtest bcs0: SUCCESS (0.008s)
[157.410659] Starting dynamic subtest: rcs0
[157.414393] Dynamic subtest rcs0: SUCCESS (0.009s)
[157.450130] Starting dynamic subtest: vcs1
[157.453793] Dynamic subtest vcs1: SUCCESS (0.004s)
[157.457423] Subtest idempotent: SUCCESS (0.063s)
[157.512958] [016/142] (856s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[158.072752] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[161.324610] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (3.249s)
[161.726500] [017/142] (852s left) prime_vgem (basic-write)
[162.243275] Starting subtest: basic-write
[162.274525] Subtest basic-write: SKIP (0.028s)
[162.437746] [018/142] (851s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[162.973611] Starting subtest: y-tiled-16bpp-rotate-180
[162.980570] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[163.133922] [019/142] (850s left) kms_ccs (pipe-c-missing-ccs-buffer-y_tiled_gen12_mc_ccs)
[164.630200] Starting subtest: pipe-C-missing-ccs-buffer-y_tiled_gen12_mc_ccs
[164.636881] Subtest pipe-C-missing-ccs-buffer-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[165.543852] [020/142] (848s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[166.056573] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[180.230325] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (14.180s)
[180.487911] [021/142] (833s left) kms_chamelium_hpd (dp-hpd-fast)
[181.391882] Subtest dp-hpd-fast: SKIP (0.000s)
[182.253871] [022/142] (832s left) kms_ccs (pipe-d-missing-ccs-buffer-4_tiled_mtl_mc_ccs)
[183.994852] Starting subtest: pipe-D-missing-ccs-buffer-4_tiled_mtl_mc_ccs
[184.170703] Subtest pipe-D-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SUCCESS (0.175s)
[184.836071] [023/142] (829s left) gem_userptr_blits (process-exit)
[185.293993] Starting subtest: process-exit
[185.361506] Subtest process-exit: SUCCESS (0.067s)
[185.587159] [024/142] (828s left) sw_sync (sync_expired_merge)
[185.953394] Starting subtest: sync_expired_merge
[187.030256] Subtest sync_expired_merge: SUCCESS (1.076s)
[187.162392] [025/142] (827s left) v3d/v3d_perfmon (get-values-valid-perfmon)
[187.633382] Subtest get-values-valid-perfmon: SKIP (0.000s)
[187.766910] [026/142] (826s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[188.295245] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[188.302154] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[188.449284] [027/142] (826s left) kms_ccs (pipe-a-crc-primary-basic-y_tiled_gen12_mc_ccs)
[189.370339] Starting subtest: pipe-A-crc-primary-basic-y_tiled_gen12_mc_ccs
[189.377216] Subtest pipe-A-crc-primary-basic-y_tiled_gen12_mc_ccs: SKIP (0.002s)
[190.812467] [028/142] (823s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[191.202848] Starting subtest: single-wait-all-for-submit-available-submitted
[191.207574] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[191.341977] [029/142] (823s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[191.828123] Starting subtest: primary-x-tiled-reflect-x-0
[206.497900] Subtest primary-x-tiled-reflect-x-0: SUCCESS (14.675s)
[208.119580] [030/142] (806s left) kms_properties (get_properties-sanity-non-atomic)
[208.608333] Starting subtest: get_properties-sanity-non-atomic
[208.622463] Subtest get_properties-sanity-non-atomic: SUCCESS (0.019s)
[208.767597] [031/142] (806s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[209.294576] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[209.301592] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.001s)
[209.465994] [032/142] (805s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[209.974792] Starting subtest: psr-rgb101010-draw-blt
[222.839159] Subtest psr-rgb101010-draw-blt: SUCCESS (12.864s)
[223.096466] [033/142] (792s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[223.896181] Starting subtest: plane-primary-overlay-mutable-zpos
[223.905992] Subtest plane-primary-overlay-mutable-zpos: SKIP (0.004s)
[225.251825] [034/142] (789s left) gem_exec_schedule (out-order)
[225.686788] Starting subtest: out-order
[225.690370] Starting dynamic subtest: rcs0
[226.053454] Dynamic subtest rcs0: SUCCESS (0.364s)
[226.092955] Starting dynamic subtest: bcs0
[226.339412] Dynamic subtest bcs0: SUCCESS (0.282s)
[226.378194] Starting dynamic subtest: vcs0
[226.598765] Dynamic subtest vcs0: SUCCESS (0.255s)
[226.637571] Starting dynamic subtest: vcs1
[226.875862] Dynamic subtest vcs1: SUCCESS (0.273s)
[226.913664] Starting dynamic subtest: vecs0
[227.152840] Dynamic subtest vecs0: SUCCESS (0.273s)
[227.166287] Starting dynamic subtest: ccs0
[227.425116] Dynamic subtest ccs0: SUCCESS (0.269s)
[227.428663] Subtest out-order: SUCCESS (1.739s)
[227.905485] [035/142] (787s left) drm_fdinfo (all-busy-idle-check-all)
[228.291529] Starting subtest: all-busy-idle-check-all
[228.308564] Subtest all-busy-idle-check-all: SKIP (0.014s)
[228.470779] [036/142] (786s left) gem_lmem_swapping (parallel-random-verify)
[228.864578] Subtest parallel-random-verify: SKIP (0.000s)
[228.992741] [037/142] (786s left) gem_exec_balancer (parallel-keep-submit-fence)
[230.245399] Starting subtest: parallel-keep-submit-fence
[237.069191] Subtest parallel-keep-submit-fence: SUCCESS (6.825s)
[237.202975] [038/142] (778s left) perf (non-system-wide-paranoid)
[237.855558] Starting subtest: non-system-wide-paranoid
[238.019549] Subtest non-system-wide-paranoid: SUCCESS (0.165s)
[238.132758] [039/142] (777s left) perf (blocking)
[238.764377] Starting subtest: blocking
[238.767827] Starting dynamic subtest: 0-rcs0
[248.839619] Dynamic subtest 0-rcs0: SUCCESS (10.071s)
[248.874228] Starting dynamic subtest: 1-vcs1
[258.858991] Dynamic subtest 1-vcs1: SUCCESS (10.016s)
[258.862821] Subtest blocking: SUCCESS (20.096s)
[258.968946] [040/142] (756s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[259.478092] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[259.485235] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[259.634476] [041/142] (756s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[260.113555] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[260.121398] Starting dynamic subtest: pipe-A-default-mode
[261.610372] Dynamic subtest pipe-A-default-mode: SKIP (1.480s)
[261.617901] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.493s)
[261.891462] [042/142] (753s left) gem_ctx_param (set-priority-not-supported)
[262.262303] Starting subtest: set-priority-not-supported
[262.268946] Subtest set-priority-not-supported: SKIP (0.000s)
[262.394710] [043/142] (753s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[262.903778] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[271.060577] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (8.157s)
[271.308756] [044/142] (744s left) i915_pm_freq_api (freq-reset-multiple)
[271.715766] Starting subtest: freq-reset-multiple
[271.719569] Starting dynamic subtest: gt0
[302.943898] Dynamic subtest gt0: SUCCESS (31.224s)
[302.979561] Starting dynamic subtest: gt1
[324.585767] Dynamic subtest gt1: SUCCESS (21.634s)
[324.589160] Subtest freq-reset-multiple: SUCCESS (52.865s)
[324.713448] [045/142] (691s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_mtl_rc_ccs)
[326.053084] Starting subtest: pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs
[326.739650] Subtest pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs: SUCCESS (0.686s)
[328.153112] [046/142] (688s left) kms_universal_plane (disable-primary-vs-flip-pipe-a)
[328.637742] Starting subtest: disable-primary-vs-flip-pipe-A
[330.830453] Subtest disable-primary-vs-flip-pipe-A: SUCCESS (2.191s)
[331.759158] [047/142] (684s left) kms_prop_blob (blob-multiple)
[332.132047] Starting subtest: blob-multiple
[332.135607] Subtest blob-multiple: SUCCESS (0.008s)
[332.277525] [048/142] (684s left) gem_softpin (evict-prime-sanity-check)
[332.680896] Starting subtest: evict-prime-sanity-check
[332.685182] Starting dynamic subtest: rcs0
[333.833278] Dynamic subtest rcs0: SUCCESS (1.150s)
[333.877940] Starting dynamic subtest: bcs0
[334.960199] Dynamic subtest bcs0: SUCCESS (1.122s)
[335.006724] Starting dynamic subtest: vcs0
[336.513423] Dynamic subtest vcs0: SUCCESS (1.547s)
[336.551242] Starting dynamic subtest: vcs1
[338.498753] Dynamic subtest vcs1: SUCCESS (1.980s)
[338.536173] Starting dynamic subtest: vecs0
[340.545834] Dynamic subtest vecs0: SUCCESS (2.044s)
[340.583391] Starting dynamic subtest: ccs0
[341.687374] Dynamic subtest ccs0: SUCCESS (1.139s)
[341.731312] Starting dynamic subtest: all
[342.811435] Dynamic subtest all: SUCCESS (1.120s)
[342.815419] Subtest evict-prime-sanity-check: SUCCESS (10.133s)
[342.989520] [049/142] (673s left) gem_exec_balancer (bonded-dual)
[343.415381] Starting subtest: bonded-dual
[343.421342] Subtest bonded-dual: SKIP (0.001s)
[344.445791] [050/142] (672s left) kms_vblank (pipe-b-query-forked)
[344.946084] Starting subtest: pipe-B-query-forked
[348.572107] Subtest pipe-B-query-forked: SUCCESS (3.626s)
[349.502854] [051/142] (667s left) gem_busy (extended)
[350.108052] Starting subtest: extended
[350.111414] Starting dynamic subtest: rcs0
[350.193731] Dynamic subtest rcs0: SUCCESS (0.088s)
[350.234627] Starting dynamic subtest: bcs0
[350.243856] Dynamic subtest bcs0: SUCCESS (0.046s)
[350.283950] Starting dynamic subtest: vcs0
[351.543075] Dynamic subtest vcs0: SUCCESS (1.294s)
[351.577543] Starting dynamic subtest: vcs1
[353.527162] Dynamic subtest vcs1: SUCCESS (1.980s)
[353.561645] Starting dynamic subtest: vecs0
[355.512208] Dynamic subtest vecs0: SUCCESS (1.980s)
[355.548524] Starting dynamic subtest: ccs0
[355.562277] Dynamic subtest ccs0: SUCCESS (0.048s)
[355.565714] Subtest extended: SUCCESS (5.461s)
[355.914178] [052/142] (660s left) kms_flip (flip-vs-fences-interruptible)
[356.347330] Starting subtest: flip-vs-fences-interruptible
[356.353764] Subtest flip-vs-fences-interruptible: SKIP (0.000s)
[356.518691] [053/142] (660s left) i915_pm_rpm (modeset-lpsp-stress-no-wait)
[362.630505] Starting subtest: modeset-lpsp-stress-no-wait
[376.789465] Subtest modeset-lpsp-stress-no-wait: SUCCESS (14.158s)
[377.011247] [054/142] (639s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[377.517586] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[377.526512] Starting dynamic subtest: pipe-A-eDP-1
[379.058837] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.535s)
[379.104849] Starting dynamic subtest: pipe-B-eDP-1
[381.970555] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.901s)
[382.021341] Starting dynamic subtest: pipe-C-eDP-1
[384.838499] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.857s)
[384.884352] Starting dynamic subtest: pipe-D-eDP-1
[387.676069] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.826s)
[387.679496] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (10.164s)
[389.174296] [055/142] (627s left) kms_flip (2x-flip-vs-rmfb)
[389.577206] Starting subtest: 2x-flip-vs-rmfb
[389.738096] Subtest 2x-flip-vs-rmfb: SKIP (0.160s)
[389.926190] [056/142] (627s left) vc4/vc4_tiling (get-bad-handle)
[390.400360] Subtest get-bad-handle: SKIP (0.000s)
[390.528461] [057/142] (626s left) gem_pxp (hw-rejects-pxp-context)
[393.437162] Starting subtest: hw-rejects-pxp-context
[393.441408] Subtest hw-rejects-pxp-context: SUCCESS (0.002s)
[393.636464] [058/142] (623s left) gem_pread (snoop)
[394.011120] Subtest snoop: SKIP (0.000s)
[394.155474] [059/142] (623s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[394.671836] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[394.679648] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.001s)
[394.834692] [060/142] (622s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[395.347224] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[401.816817] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (6.472s)
[402.131879] [061/142] (615s left) kms_ccs (pipe-a-missing-ccs-buffer-y_tiled_gen12_rc_ccs)
[403.136370] Starting subtest: pipe-A-missing-ccs-buffer-y_tiled_gen12_rc_ccs
[403.142892] Subtest pipe-A-missing-ccs-buffer-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[404.504366] [062/142] (612s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[404.885886] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[404.989654] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.105s)
[405.137181] [063/142] (612s left) kms_setmode (basic-clone-single-crtc)
[405.511673] Starting subtest: basic-clone-single-crtc
[405.522720] Subtest basic-clone-single-crtc: SKIP (0.003s)
[405.648744] [064/142] (612s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[406.123561] Starting subtest: nonblocking-crc-frame-sequence
[406.132434] Starting dynamic subtest: pipe-A-eDP-1
[407.446079] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.315s)
[407.494065] Starting dynamic subtest: pipe-B-eDP-1
[409.379263] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.918s)
[409.425188] Starting dynamic subtest: pipe-C-eDP-1
[411.751305] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.361s)
[411.797337] Starting dynamic subtest: pipe-D-eDP-1
[414.273193] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.514s)
[414.277119] Subtest nonblocking-crc-frame-sequence: SUCCESS (8.154s)
[415.221244] [065/142] (602s left) gem_ppgtt (shrink-vs-evict-any)
[415.615791] Starting subtest: shrink-vs-evict-any
[423.611863] Subtest shrink-vs-evict-any: SUCCESS (7.991s)
[423.791953] [066/142] (594s left) gem_ctx_persistence (heartbeat-stop)
[424.189195] Starting subtest: heartbeat-stop
[424.196454] Subtest heartbeat-stop: SKIP (0.001s)
[424.383652] [067/142] (593s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[424.876810] Starting subtest: plane-primary-toggle-with-vblank-wait
[424.880394] Starting dynamic subtest: pipe-A-eDP-1
[425.132807] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.253s)
[425.565639] Starting dynamic subtest: pipe-B-eDP-1
[426.544233] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.015s)
[426.936549] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (2.065s)
[427.895556] [068/142] (590s left) kms_psr2_sf (overlay-plane-update-continuous-sf)
[435.131117] Starting subtest: overlay-plane-update-continuous-sf
[435.140109] Starting dynamic subtest: pipe-A-eDP-1
[437.399599] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.262s)
[437.450589] Starting dynamic subtest: pipe-B-eDP-1
[439.716970] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.306s)
[439.721157] Subtest overlay-plane-update-continuous-sf: SUCCESS (4.590s)
[440.696474] [069/142] (577s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[441.210737] Starting subtest: psr-1p-pri-indfb-multidraw
[487.108617] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (45.905s)
[487.371579] [070/142] (530s left) kms_big_fb (y-tiled-32bpp-rotate-90)
[487.922684] Starting subtest: y-tiled-32bpp-rotate-90
[487.929462] Subtest y-tiled-32bpp-rotate-90: SKIP (0.000s)
[488.091963] [071/142] (530s left) syncobj_wait (reset-signaled)
[488.485906] Starting subtest: reset-signaled
[488.489600] Subtest reset-signaled: SUCCESS (0.001s)
[488.629682] [072/142] (529s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[488.997533] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[489.000979] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.002s)
[489.135118] [073/142] (529s left) gem_exec_create (forked)
[489.546890] Starting subtest: forked
[489.550264] Starting dynamic subtest: smem
[511.544960] Dynamic subtest smem: SUCCESS (21.996s)
[511.548314] Subtest forked: SUCCESS (22.001s)
[511.852349] [074/142] (506s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[512.363134] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[518.018714] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (5.664s)
[518.303092] [075/142] (500s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[518.826338] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[518.833210] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[518.992305] [076/142] (499s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[519.551585] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[522.536178] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (2.982s)
[522.932156] [077/142] (495s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[523.309301] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[523.313943] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.002s)
[523.446435] [078/142] (495s left) gem_exec_reloc (basic-active)
[523.857033] Subtest basic-active: SKIP (0.000s)
[523.988735] [079/142] (494s left) kms_vblank (pipe-d-wait-idle)
[524.464329] Starting subtest: pipe-D-wait-idle
[528.399306] Subtest pipe-D-wait-idle: SUCCESS (3.931s)
[529.347677] [080/142] (489s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_mtl_rc_ccs_cc)
[530.469481] Starting subtest: pipe-B-bad-pixel-format-4_tiled_mtl_rc_ccs_cc
[530.473273] Subtest pipe-B-bad-pixel-format-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.002s)
[531.787136] [081/142] (487s left) api_intel_bb (destroy-bb)
[532.172109] Starting subtest: destroy-bb
[532.211823] Subtest destroy-bb: SUCCESS (0.042s)
[532.381456] [082/142] (486s left) gem_exec_whisper (basic-fds-forked-all)
[532.806561] Starting subtest: basic-fds-forked-all
[588.925646] Subtest basic-fds-forked-all: SUCCESS (56.111s)
[589.196123] [083/142] (430s left) kms_getfb (getfb2-into-addfb2)
[589.574233] Starting subtest: getfb2-into-addfb2
[589.577969] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[589.773553] [084/142] (429s left) kms_cursor_crc (cursor-onscreen-32x32)
[590.427327] Starting subtest: cursor-onscreen-32x32
[590.699814] Subtest cursor-onscreen-32x32: SKIP (0.265s)
[591.583889] [085/142] (427s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-25)
[592.055322] Starting subtest: planes-unity-scaling-downscale-factor-0-25
[592.063062] Starting dynamic subtest: pipe-A-eDP-1
[592.147369] Dynamic subtest pipe-A-eDP-1: SKIP (0.083s)
[592.189765] Starting dynamic subtest: pipe-B-eDP-1
[592.320818] Dynamic subtest pipe-B-eDP-1: SKIP (0.161s)
[592.358550] Starting dynamic subtest: pipe-C-eDP-1
[592.489368] Dynamic subtest pipe-C-eDP-1: SKIP (0.163s)
[592.532813] Starting dynamic subtest: pipe-D-eDP-1
[592.568802] Dynamic subtest pipe-D-eDP-1: SKIP (0.068s)
[592.575554] Subtest planes-unity-scaling-downscale-factor-0-25: SKIP (0.511s)
[592.735555] [086/142] (426s left) kms_ccs (pipe-d-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc)
[594.407000] Starting subtest: pipe-D-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc
[594.412492] Subtest pipe-D-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[594.949192] [087/142] (424s left) kms_flip (2x-flip-vs-fences)
[595.330284] Starting subtest: 2x-flip-vs-fences
[595.336782] Subtest 2x-flip-vs-fences: SKIP (0.000s)
[595.525461] [088/142] (424s left) kms_plane_alpha_blend (alpha-basic)
[595.990523] Starting subtest: alpha-basic
[597.312676] Starting dynamic subtest: pipe-A-eDP-1
[599.803283] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.489s)
[599.996383] Starting dynamic subtest: pipe-D-eDP-1
[603.500669] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.553s)
[603.503916] Subtest alpha-basic: SUCCESS (7.512s)
[604.456691] [089/142] (415s left) gem_exec_params (rsvd2-dirt)
[604.852766] Starting subtest: rsvd2-dirt
[604.859832] Subtest rsvd2-dirt: SKIP (0.000s)
[605.022714] [090/142] (414s left) i915_pm_dc (dc6-dpms)
[606.523501] Starting subtest: dc6-dpms
[606.532964] Subtest dc6-dpms: SKIP (0.000s)
[606.676436] [091/142] (413s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc)
[608.341610] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc
[609.358973] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc: SUCCESS (1.012s)
[610.749619] [092/142] (409s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_rc_ccs_cc)
[612.534666] Starting subtest: pipe-D-bad-aux-stride-4_tiled_mtl_rc_ccs_cc
[612.542546] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.004s)
[613.165633] [093/142] (406s left) gem_exec_reloc (basic-write-read-active)
[613.566986] Subtest basic-write-read-active: SKIP (0.000s)
[613.711869] [094/142] (406s left) i915_pm_rpm (universal-planes)
[619.801354] Starting subtest: universal-planes
[619.804964] Starting dynamic subtest: plane-31
[644.795269] Dynamic subtest plane-31: SUCCESS (24.990s)
[644.845242] Starting dynamic subtest: plane-40
[673.481115] Dynamic subtest plane-40: SUCCESS (28.683s)
[673.537725] Starting dynamic subtest: plane-49
[698.476672] Dynamic subtest plane-49: SUCCESS (24.991s)
[698.523275] Starting dynamic subtest: plane-58
[723.270709] Dynamic subtest plane-58: SUCCESS (24.788s)
[723.297968] Starting dynamic subtest: plane-67
[748.231104] Dynamic subtest plane-67: SUCCESS (24.956s)
[748.289743] Starting dynamic subtest: plane-76
[776.883583] Dynamic subtest plane-76: SUCCESS (28.647s)
[776.886799] Subtest universal-planes: SUCCESS (157.085s)
[777.822542] [095/142] (242s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[778.302260] Starting subtest: addfb25-y-tiled-small-legacy
[778.309076] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[778.446655] [096/142] (241s left) gem_exec_fence (submit3)
[779.055111] Starting subtest: submit3
[779.061434] Subtest submit3: SKIP (0.000s)
[780.802754] [097/142] (239s left) gem_exec_schedule (smoketest)
[781.396472] Starting subtest: smoketest
[781.400060] Starting dynamic subtest: rcs0
[787.387344] Dynamic subtest rcs0: SUCCESS (5.920s)
[787.425830] Starting dynamic subtest: bcs0
[793.224293] Dynamic subtest bcs0: SUCCESS (5.830s)
[793.261860] Starting dynamic subtest: vcs0
[799.120489] Dynamic subtest vcs0: SUCCESS (5.890s)
[799.158104] Starting dynamic subtest: vcs1
[805.027898] Dynamic subtest vcs1: SUCCESS (5.900s)
[805.070591] Starting dynamic subtest: vecs0
[811.052653] Dynamic subtest vecs0: SUCCESS (6.026s)
[811.090731] Starting dynamic subtest: ccs0
[816.954836] Dynamic subtest ccs0: SUCCESS (5.894s)
[816.958415] Subtest smoketest: SUCCESS (35.487s)
[817.269541] [098/142] (203s left) syncobj_timeline (host-signal-ordered)
[817.661484] Starting subtest: host-signal-ordered
[817.665118] Subtest host-signal-ordered: SUCCESS (0.003s)
[817.854148] [099/142] (202s left) kms_flip (blocking-wf_vblank)
[818.245225] Starting subtest: blocking-wf_vblank
[818.271921] Starting dynamic subtest: A-eDP1
[827.737995] Dynamic subtest A-eDP1: SUCCESS (9.465s)
[827.791090] Starting dynamic subtest: B-eDP1
[837.012978] Dynamic subtest B-eDP1: SUCCESS (9.269s)
[837.064901] Starting dynamic subtest: C-eDP1
[846.283280] Dynamic subtest C-eDP1: SUCCESS (9.262s)
[846.325443] Starting dynamic subtest: D-eDP1
[855.431863] Dynamic subtest D-eDP1: SUCCESS (9.141s)
[855.435513] Subtest blocking-wf_vblank: SUCCESS (37.193s)
[856.965136] [100/142] (163s left) kms_invalid_mode (bad-hsync-start)
[857.456260] Starting subtest: bad-hsync-start
[857.459789] Starting dynamic subtest: eDP-1-pipe-A
[857.865962] Dynamic subtest eDP-1-pipe-A: SUCCESS (0.404s)
[857.910567] Starting dynamic subtest: eDP-1-pipe-B
[857.913973] Dynamic subtest eDP-1-pipe-B: SUCCESS (0.032s)
[857.952138] Starting dynamic subtest: eDP-1-pipe-C
[857.955538] Dynamic subtest eDP-1-pipe-C: SUCCESS (0.034s)
[857.992992] Starting dynamic subtest: eDP-1-pipe-D
[857.995984] Dynamic subtest eDP-1-pipe-D: SUCCESS (0.033s)
[857.998908] Subtest bad-hsync-start: SUCCESS (0.535s)
[858.798377] [101/142] (162s left) kms_vblank (pipe-b-ts-continuation-dpms-rpm)
[859.270459] Starting subtest: pipe-B-ts-continuation-dpms-rpm
[863.229940] Subtest pipe-B-ts-continuation-dpms-rpm: SUCCESS (3.955s)
[864.161756] [102/142] (156s left) perf_pmu (most-busy-check-all)
[864.547287] Starting subtest: most-busy-check-all
[864.550745] Starting dynamic subtest: rcs0
[866.487569] Dynamic subtest rcs0: SUCCESS (1.936s)
[866.537550] Starting dynamic subtest: bcs0
[868.535644] Dynamic subtest bcs0: SUCCESS (2.044s)
[868.583145] Starting dynamic subtest: vcs0
[870.520365] Dynamic subtest vcs0: SUCCESS (1.980s)
[870.571972] Starting dynamic subtest: vcs1
[872.503526] Dynamic subtest vcs1: SUCCESS (1.979s)
[872.556079] Starting dynamic subtest: vecs0
[874.487402] Dynamic subtest vecs0: SUCCESS (1.979s)
[874.539768] Starting dynamic subtest: ccs0
[876.535767] Dynamic subtest ccs0: SUCCESS (2.043s)
[876.540624] Subtest most-busy-check-all: SUCCESS (11.989s)
[876.734582] [103/142] (144s left) vc4/vc4_purgeable_bo (mark-purgeable-twice)
[877.240241] Subtest mark-purgeable-twice: SKIP (0.000s)
[877.367173] [104/142] (143s left) kms_addfb_basic (basic)
[877.749932] Starting subtest: basic
[877.753062] Subtest basic: SUCCESS (0.000s)
[878.049969] [105/142] (143s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[878.588849] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[878.596360] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[878.771606] [106/142] (142s left) gen9_exec_parse (cmd-crossing-page)
[879.121807] Subtest cmd-crossing-page: SKIP (0.000s)
[879.247401] [107/142] (142s left) gem_flink_race (flink_name)
[879.619629] Starting subtest: flink_name
[885.018096] Subtest flink_name: SUCCESS (5.396s)
[885.229512] [108/142] (136s left) api_intel_bb (simple-bb)
[885.618177] Starting subtest: simple-bb
[885.633615] Subtest simple-bb: SUCCESS (0.017s)
[885.850300] [109/142] (135s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_mtl_mc_ccs)
[887.069142] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_mtl_mc_ccs
[891.968884] Subtest pipe-C-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SUCCESS (4.901s)
[893.289511] [110/142] (128s left) gem_fenced_exec_thrash (2-spare-fences)
[893.702302] Subtest 2-spare-fences: SKIP (0.000s)
[893.820732] [111/142] (127s left) kms_flip (flip-vs-suspend)
[894.229468] Starting subtest: flip-vs-suspend
[894.261093] Starting dynamic subtest: A-eDP1
[930.254824] Dynamic subtest A-eDP1: SUCCESS (35.994s)
[930.296396] Starting dynamic subtest: B-eDP1
[965.230704] Dynamic subtest B-eDP1: SUCCESS (34.972s)
[965.280096] Starting dynamic subtest: C-eDP1
[1000.257597] Dynamic subtest C-eDP1: SUCCESS (35.022s)
[1000.304648] Starting dynamic subtest: D-eDP1
[1035.240811] Dynamic subtest D-eDP1: SUCCESS (34.980s)
[1035.261467] Subtest flip-vs-suspend: SUCCESS (141.036s)
[1036.869409] Overall timeout time exceeded, stopping.
[1036.870733] Closing watchdogs
Done.