[52.182357] Initializing watchdogs
[52.182590]   /dev/watchdog0
[52.215259] [001/142] (960s left) perf_pmu (render-node-busy-idle)
[52.571580] Starting subtest: render-node-busy-idle
[52.590036] Starting dynamic subtest: rcs0
[53.228775] Dynamic subtest rcs0: SUCCESS (0.656s)
[53.262058] Starting dynamic subtest: bcs0
[53.866616] Dynamic subtest bcs0: SUCCESS (0.647s)
[53.896521] Starting dynamic subtest: vcs0
[54.509171] Dynamic subtest vcs0: SUCCESS (0.637s)
[54.540337] Starting dynamic subtest: vecs0
[55.163045] Dynamic subtest vecs0: SUCCESS (0.635s)
[55.194062] Subtest render-node-busy-idle: SUCCESS (2.577s)
[55.295552] [002/142] (957s left) prime_vgem (shrink)
[56.091510] Starting subtest: shrink
[56.117775] Subtest shrink: SUCCESS (0.023s)
[56.249225] [003/142] (956s left) kms_psr (psr2_sprite_mmap_gtt)
[56.575620] Subtest psr2_sprite_mmap_gtt: SKIP (0.000s)
[56.657389] [004/142] (956s left) kms_psr (psr2_sprite_blt)
[56.977864] Subtest psr2_sprite_blt: SKIP (0.000s)
[57.055952] [005/142] (955s left) syncobj_timeline (multi-wait-available-submitted)
[57.355903] Starting subtest: multi-wait-available-submitted
[57.374655] Subtest multi-wait-available-submitted: SUCCESS (0.002s)
[57.456405] [006/142] (955s left) gem_pxp (verify-pxp-stale-buf-execution)
[60.488284] Subtest verify-pxp-stale-buf-execution: SKIP (0.000s)
[60.584860] [007/142] (952s left) syncobj_timeline (signal-point-0)
[60.879913] Starting subtest: signal-point-0
[60.898550] Subtest signal-point-0: SUCCESS (0.001s)
[60.983103] [008/142] (952s left) gem_ctx_shared (create-shared-gtt)
[61.281261] Starting subtest: create-shared-gtt
[63.600107] Subtest create-shared-gtt: SUCCESS (2.152s)
[63.681577] [009/142] (949s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[63.984163] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[64.003216] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.001s)
[64.082676] [010/142] (949s left) kms_ccs (pipe-b-crc-primary-rotation-180-y_tiled_ccs)
[64.669160] Starting subtest: pipe-B-crc-primary-rotation-180-y_tiled_ccs
[65.577105] Subtest pipe-B-crc-primary-rotation-180-y_tiled_ccs: SUCCESS (0.908s)
[66.224513] [011/142] (947s left) gem_exec_params (invalid-ring)
[66.525190] Starting subtest: invalid-ring
[66.546242] Subtest invalid-ring: SUCCESS (0.001s)
[66.644314] [012/142] (947s left) gem_exec_balancer (smoke)
[66.945852] Starting subtest: smoke
[70.813988] Subtest smoke: SUCCESS (3.700s)
[74.716073] [013/142] (939s left) kms_content_protection (dp-mst-lic-type-0)
[75.133045] Starting subtest: dp-mst-lic-type-0
[75.172915] Subtest dp-mst-lic-type-0: SKIP (0.001s)
[75.273712] [014/142] (938s left) gem_eio (in-flight-suspend)
[81.644362] Starting subtest: in-flight-suspend
[114.064212] Subtest in-flight-suspend: SUCCESS (8.443s)
[119.482464] [015/142] (929s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[119.857173] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[119.876277] Starting dynamic subtest: pipe-A-DP-1
[120.016642] Dynamic subtest pipe-A-DP-1: SUCCESS (0.159s)
[120.160782] Starting dynamic subtest: pipe-B-DP-1
[120.689116] Dynamic subtest pipe-B-DP-1: SUCCESS (0.569s)
[120.798034] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (0.930s)
[121.161162] [016/142] (928s left) kms_chamelium_color (ctm-red-to-blue)
[121.540663] Subtest ctm-red-to-blue: SKIP (0.000s)
[121.652934] [017/142] (927s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[122.077467] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[123.523412] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.477s)
[123.709689] [018/142] (925s left) kms_ccs (pipe-a-bad-rotation-90-y_tiled_gen12_rc_ccs)
[124.176981] Starting subtest: pipe-A-bad-rotation-90-y_tiled_gen12_rc_ccs
[124.219811] Subtest pipe-A-bad-rotation-90-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[124.813338] [019/142] (924s left) gem_lmem_evict (dontneed-evict-race)
[125.128746] Subtest dontneed-evict-race: SKIP (0.000s)
[125.238246] [020/142] (924s left) dumb_buffer (map-valid)
[125.543518] Starting subtest: map-valid
[125.564266] Subtest map-valid: SUCCESS (0.000s)
[125.673342] [021/142] (924s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[126.095938] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[126.133013] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[126.246695] [022/142] (923s left) kms_invalid_mode (zero-hdisplay)
[126.650928] Starting subtest: zero-hdisplay
[126.669597] Starting dynamic subtest: DP-1-pipe-A
[126.741593] Dynamic subtest DP-1-pipe-A: SUCCESS (0.105s)
[126.783133] Starting dynamic subtest: DP-1-pipe-B
[126.805541] Dynamic subtest DP-1-pipe-B: SUCCESS (0.014s)
[126.853387] Starting dynamic subtest: DP-1-pipe-C
[126.872293] Dynamic subtest DP-1-pipe-C: SUCCESS (0.014s)
[126.890933] Subtest zero-hdisplay: SUCCESS (0.140s)
[127.144504] [023/142] (922s left) kms_ccs (pipe-a-random-ccs-data-y_tiled_gen12_mc_ccs)
[127.629107] Starting subtest: pipe-A-random-ccs-data-y_tiled_gen12_mc_ccs
[127.694123] Subtest pipe-A-random-ccs-data-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[128.258325] [024/142] (921s left) v3d/v3d_submit_cl (bad-extension)
[128.625960] Subtest bad-extension: SKIP (0.000s)
[128.738145] [025/142] (921s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_mc_ccs)
[129.339679] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_mc_ccs
[129.381276] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[129.812608] [026/142] (920s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[130.172346] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[130.192297] Starting dynamic subtest: pipe-A-valid-mode
[130.628009] Dynamic subtest pipe-A-valid-mode: SKIP (0.423s)
[130.667089] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.425s)
[130.996460] [027/142] (919s left) gem_mmap_gtt (basic-small-copy-xy)
[131.297290] Starting subtest: basic-small-copy-XY
[135.710827] Subtest basic-small-copy-XY: SUCCESS (4.431s)
[135.849985] [028/142] (914s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[136.403443] Starting subtest: primary-yf-tiled-reflect-x-0
[136.440719] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.003s)
[136.557263] [029/142] (914s left) sysfs_preempt_timeout (timeout)
[136.853160] Starting subtest: timeout
[136.875076] Starting dynamic subtest: vecs0
[137.757696] Dynamic subtest vecs0: SUCCESS (0.898s)
[137.804572] Starting dynamic subtest: vcs0
[138.637384] Dynamic subtest vcs0: SUCCESS (0.873s)
[138.677001] Starting dynamic subtest: bcs0
[139.516082] Dynamic subtest bcs0: SUCCESS (0.877s)
[139.558067] Starting dynamic subtest: rcs0
[140.391470] Dynamic subtest rcs0: SUCCESS (0.886s)
[140.413029] Subtest timeout: SUCCESS (3.538s)
[140.504532] [030/142] (910s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[140.926975] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[140.969312] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[141.080354] [031/142] (909s left) gem_exec_schedule (fairslice)
[141.578497] Starting subtest: fairslice
[141.597229] Starting dynamic subtest: rcs0
[143.465505] Dynamic subtest rcs0: SUCCESS (2.032s)
[143.510081] Starting dynamic subtest: bcs0
[145.502751] Dynamic subtest bcs0: SUCCESS (2.034s)
[145.541047] Starting dynamic subtest: vcs0
[147.530118] Dynamic subtest vcs0: SUCCESS (2.029s)
[147.570339] Starting dynamic subtest: vecs0
[149.554025] Dynamic subtest vecs0: SUCCESS (2.027s)
[149.572598] Subtest fairslice: SUCCESS (8.124s)
[149.908198] [032/142] (901s left) kms_psr (primary_mmap_gtt)
[150.226050] Subtest primary_mmap_gtt: SKIP (0.000s)
[150.312303] [033/142] (900s left) kms_writeback (writeback-check-output)
[150.788975] Subtest writeback-check-output: SKIP (0.000s)
[150.898544] [034/142] (900s left) kms_flip (2x-flip-vs-expired-vblank)
[151.282523] Starting subtest: 2x-flip-vs-expired-vblank
[151.324113] Subtest 2x-flip-vs-expired-vblank: SKIP (0.003s)
[151.433638] [035/142] (899s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_gen12_rc_ccs)
[152.088011] Starting subtest: pipe-C-bad-rotation-90-y_tiled_gen12_rc_ccs
[152.126816] Subtest pipe-C-bad-rotation-90-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[152.517785] [036/142] (898s left) kms_vblank (pipe-b-wait-idle)
[152.905596] Starting subtest: pipe-B-wait-idle
[155.571126] Subtest pipe-B-wait-idle: SUCCESS (2.680s)
[155.953452] [037/142] (895s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[156.255482] Starting subtest: multi-wait-for-submit-unsubmitted
[156.330848] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.106s)
[156.468160] [038/142] (895s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[156.886635] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[156.929965] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[157.038384] [039/142] (894s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[157.433988] Starting subtest: y-tiled-64bpp-rotate-180
[165.511327] Subtest y-tiled-64bpp-rotate-180: SUCCESS (8.098s)
[166.479727] [040/142] (885s left) kms_concurrent (pipe-d)
[166.889980] Subtest pipe-D: SKIP (0.000s)
[167.078461] [041/142] (884s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[167.493014] Starting subtest: psr-rgb101010-draw-blt
[167.530188] Subtest psr-rgb101010-draw-blt: SKIP (0.000s)
[167.647793] [042/142] (884s left) vc4/vc4_create_bo (create-bo-zeroed)
[167.993958] Subtest create-bo-zeroed: SKIP (0.000s)
[168.080993] [043/142] (883s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[168.536721] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[168.585813] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[168.723389] [044/142] (883s left) syncobj_wait (wait-delayed-signal)
[169.033640] Starting subtest: wait-delayed-signal
[169.107480] Subtest wait-delayed-signal: SUCCESS (0.103s)
[169.227936] [045/142] (882s left) kms_vblank (pipe-b-query-idle-hang)
[169.599213] Starting subtest: pipe-B-query-idle-hang
[177.181658] Subtest pipe-B-query-idle-hang: SUCCESS (7.455s)
[177.448998] [046/142] (874s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[177.917756] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[177.962828] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[178.096948] [047/142] (874s left) kms_cursor_crc (cursor-random-256x85)
[178.491456] Starting subtest: cursor-random-256x85
[178.510387] Starting dynamic subtest: pipe-A-DP-1
[182.314778] Dynamic subtest pipe-A-DP-1: SUCCESS (3.805s)
[182.414924] Starting dynamic subtest: pipe-C-DP-1
[186.525144] Dynamic subtest pipe-C-DP-1: SUCCESS (4.139s)
[186.544978] Subtest cursor-random-256x85: SUCCESS (8.025s)
[186.927407] [048/142] (865s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[187.288291] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[187.326041] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.003s)
[187.443512] [049/142] (865s left) kms_chamelium_hpd (hdmi-hpd)
[187.917983] Subtest hdmi-hpd: SKIP (0.000s)
[188.246786] [050/142] (864s left) syncobj_basic (bad-destroy-pad)
[188.550326] Starting subtest: bad-destroy-pad
[188.569293] Subtest bad-destroy-pad: SUCCESS (0.000s)
[188.677695] [051/142] (864s left) gem_mmap_wc (bad-offset)
[188.976922] Starting subtest: bad-offset
[188.995838] Subtest bad-offset: SUCCESS (0.001s)
[189.111661] [052/142] (863s left) kms_dsc (dsc-with-output-formats)
[189.492536] Subtest dsc-with-output-formats: SKIP (0.000s)
[189.606417] [053/142] (863s left) gem_flink_race (flink_name)
[189.877033] Starting subtest: flink_name
[195.566756] Subtest flink_name: SUCCESS (5.375s)
[195.654321] [054/142] (857s left) kms_ccs (pipe-a-bad-aux-stride-4_tiled_mtl_rc_ccs_cc)
[196.193282] Starting subtest: pipe-A-bad-aux-stride-4_tiled_mtl_rc_ccs_cc
[196.237935] Subtest pipe-A-bad-aux-stride-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[196.751423] [055/142] (856s left) kms_ccs (pipe-b-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[197.344576] Starting subtest: pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[197.381775] Subtest pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[197.827513] [056/142] (855s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_ccs)
[198.610304] Subtest pipe-D-missing-ccs-buffer-y_tiled_ccs: SKIP (0.000s)
[198.938544] [057/142] (854s left) kms_ccs (pipe-d-bad-aux-stride-yf_tiled_ccs)
[199.684704] Subtest pipe-D-bad-aux-stride-yf_tiled_ccs: SKIP (0.000s)
[200.039209] [058/142] (853s left) kms_plane (plane-position-hole)
[200.437546] Starting subtest: plane-position-hole
[200.456190] Starting dynamic subtest: pipe-A-planes
[201.931842] Dynamic subtest pipe-A-planes: SUCCESS (1.486s)
[201.979340] Starting dynamic subtest: pipe-B-planes
[203.737728] Dynamic subtest pipe-B-planes: SUCCESS (1.811s)
[203.759505] Subtest plane-position-hole: SUCCESS (3.304s)
[204.112363] [059/142] (849s left) gem_softpin (noreloc-interruptible)
[204.412322] Starting subtest: noreloc-interruptible
[209.799762] Subtest noreloc-interruptible: SUCCESS (5.406s)
[209.960252] [060/142] (843s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[210.716555] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[211.050656] [061/142] (842s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[211.489329] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[211.529813] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[211.642571] [062/142] (842s left) api_intel_allocator (standalone)
[211.947082] Starting subtest: standalone
[211.968012] Subtest standalone: SUCCESS (0.058s)
[212.080825] [063/142] (842s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[212.524357] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[212.561387] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[212.675380] [064/142] (841s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-render)
[213.110553] Starting subtest: psr-1p-offscren-pri-indfb-draw-render
[213.154101] Subtest psr-1p-offscren-pri-indfb-draw-render: SKIP (0.000s)
[213.264179] [065/142] (841s left) kms_ccs (pipe-a-crc-primary-rotation-180-y_tiled_gen12_mc_ccs)
[213.742205] Starting subtest: pipe-A-crc-primary-rotation-180-y_tiled_gen12_mc_ccs
[213.779629] Subtest pipe-A-crc-primary-rotation-180-y_tiled_gen12_mc_ccs: SKIP (0.001s)
[214.331690] [066/142] (840s left) i915_pm_rpm (fences-dpms)
[216.279973] Starting subtest: fences-dpms
[219.146665] Subtest fences-dpms: SUCCESS (2.868s)
[219.417964] [067/142] (835s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[219.741753] Starting subtest: invalid-multi-wait-all-unsubmitted
[219.760629] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[219.877060] [068/142] (834s left) v3d/v3d_submit_cl (bad-multisync-in-sync)
[220.227163] Subtest bad-multisync-in-sync: SKIP (0.000s)
[220.334844] [069/142] (834s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[220.727972] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[220.749211] Starting dynamic subtest: pipe-A-valid-mode
[221.375845] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.656s)
[221.397728] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SUCCESS (0.660s)
[221.754208] [070/142] (833s left) kms_prime (basic-crc-hybrid)
[223.921673] Subtest basic-crc-hybrid: SKIP (0.000s)
[224.056134] [071/142] (830s left) gem_exec_schedule (manyslice)
[224.578012] Starting subtest: manyslice
[224.606466] Starting dynamic subtest: rcs0
[226.132410] Dynamic subtest rcs0: SUCCESS (1.762s)
[226.242190] Starting dynamic subtest: bcs0
[226.844798] Dynamic subtest bcs0: SUCCESS (0.716s)
[226.898544] Starting dynamic subtest: vcs0
[227.680847] Dynamic subtest vcs0: SUCCESS (0.827s)
[227.779090] Starting dynamic subtest: vecs0
[228.367100] Dynamic subtest vecs0: SUCCESS (0.696s)
[228.391962] Subtest manyslice: SUCCESS (4.009s)
[228.747727] [072/142] (826s left) gem_ringfill (basic-all)
[229.301965] Starting subtest: basic-all
[230.467774] Subtest basic-all: SUCCESS (1.193s)
[230.617529] [073/142] (824s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[231.059575] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[231.096716] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[231.213443] [074/142] (824s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[231.514780] Starting subtest: writes-after-reads-uncached
[252.312493] Subtest writes-after-reads-uncached: SUCCESS (20.809s)
[252.466998] [075/142] (802s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[252.911550] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[252.961006] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[253.102769] [076/142] (802s left) i915_pm_rps (engine-order)
[253.390180] Starting subtest: engine-order
[268.794378] Subtest engine-order: FAIL (15.087s)
[269.120407] [077/142] (786s left) kms_atomic (plane-primary-legacy)
[269.602365] Starting subtest: plane-primary-legacy
[269.783473] Subtest plane-primary-legacy: SUCCESS (0.179s)
[270.260013] [078/142] (785s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[270.624977] Starting subtest: nonblocking-crc-frame-sequence
[270.646526] Starting dynamic subtest: pipe-A-DP-1
[271.252550] Dynamic subtest pipe-A-DP-1: SUCCESS (0.607s)
[271.304347] Starting dynamic subtest: pipe-B-DP-1
[272.139105] Dynamic subtest pipe-B-DP-1: SUCCESS (0.888s)
[272.189032] Starting dynamic subtest: pipe-C-DP-1
[273.161618] Dynamic subtest pipe-C-DP-1: SUCCESS (1.010s)
[273.181142] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.519s)
[273.516367] [079/142] (782s left) sw_sync (alloc_timeline)
[273.791919] Starting subtest: alloc_timeline
[273.813365] Subtest alloc_timeline: SUCCESS (0.000s)
[273.927384] [080/142] (782s left) vc4/vc4_tiling (get-bad-modifier)
[274.263771] Subtest get-bad-modifier: SKIP (0.000s)
[274.374179] [081/142] (781s left) gem_mmap_gtt (big-bo-tiledx)
[274.670500] Starting subtest: big-bo-tiledX
[276.186861] Subtest big-bo-tiledX: SUCCESS (1.543s)
[276.438231] [082/142] (779s left) kms_sequence (queue-busy)
[276.989048] Starting subtest: queue-busy
[277.010039] Starting dynamic subtest: DP-1-pipe-A
[279.506562] Dynamic subtest DP-1-pipe-A: SUCCESS (2.499s)
[279.559377] Starting dynamic subtest: DP-1-pipe-B
[282.242196] Dynamic subtest DP-1-pipe-B: SUCCESS (2.737s)
[282.290241] Starting dynamic subtest: DP-1-pipe-C
[285.134790] Dynamic subtest DP-1-pipe-C: SUCCESS (2.888s)
[285.153856] Subtest queue-busy: SUCCESS (8.136s)
[285.487713] [083/142] (770s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[285.856097] Starting subtest: cursorA-vs-flipB-atomic-transitions
[285.893739] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.002s)
[286.012769] [084/142] (770s left) gem_ctx_param (invalid-get-no-zeromap)
[286.306810] Starting subtest: invalid-get-no-zeromap
[286.334488] Subtest invalid-get-no-zeromap: SUCCESS (0.000s)
[286.448759] [085/142] (770s left) gem_wait (await)
[286.768934] Starting subtest: await
[286.787639] Starting dynamic subtest: all-engines
[287.807564] Dynamic subtest all-engines: SUCCESS (1.040s)
[287.850019] Starting dynamic subtest: rcs0
[288.834566] Dynamic subtest rcs0: SUCCESS (1.025s)
[288.874558] Starting dynamic subtest: bcs0
[289.868190] Dynamic subtest bcs0: SUCCESS (1.035s)
[289.910775] Starting dynamic subtest: vcs0
[290.899518] Dynamic subtest vcs0: SUCCESS (1.026s)
[290.939390] Starting dynamic subtest: vecs0
[291.929572] Dynamic subtest vecs0: SUCCESS (1.029s)
[291.951222] Subtest await: SUCCESS (5.166s)
[292.194202] [086/142] (764s left) sw_sync (timeline_closed_signaled)
[292.510018] Starting subtest: timeline_closed_signaled
[292.528934] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[292.623011] [087/142] (764s left) gem_userptr_blits (sd-probe)
[292.981946] Starting subtest: sd-probe
[293.003173] Subtest sd-probe: SUCCESS (0.002s)
[293.113454] [088/142] (763s left) kms_ccs (pipe-c-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[293.824164] Starting subtest: pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[293.868003] Subtest pipe-C-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[294.229086] [089/142] (762s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs)
[294.963913] Starting subtest: pipe-C-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs
[295.010577] Subtest pipe-C-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs: SKIP (0.004s)
[295.366607] [090/142] (761s left) vc4/vc4_perfmon (destroy-valid-perfmon)
[295.765625] Subtest destroy-valid-perfmon: SKIP (0.000s)
[295.900951] [091/142] (761s left) kms_vblank (pipe-b-ts-continuation-modeset)
[296.271180] Starting subtest: pipe-B-ts-continuation-modeset
[297.299117] Subtest pipe-B-ts-continuation-modeset: SUCCESS (1.010s)
[297.660711] [092/142] (759s left) perf (non-zero-reason)
[298.107096] Starting subtest: non-zero-reason
[298.128950] Starting dynamic subtest: 0-rcs0
[334.133060] Dynamic subtest 0-rcs0: SUCCESS (35.853s)
[334.153611] Subtest non-zero-reason: SUCCESS (35.856s)
[334.263080] [093/142] (723s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[334.989226] Subtest pipe-D-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[335.332697] [094/142] (722s left) kms_flip (2x-nonexisting-fb-interruptible)
[335.729741] Starting subtest: 2x-nonexisting-fb-interruptible
[335.772228] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.003s)
[335.883308] [095/142] (721s left) device_reset (unbind-reset-rebind)
[336.212552] Starting subtest: unbind-reset-rebind
[337.135971] Subtest unbind-reset-rebind: SUCCESS (0.934s)
[337.693407] [096/142] (719s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[339.744137] Starting subtest: gem-execbuf-stress-pc8
[339.833989] Subtest gem-execbuf-stress-pc8: SKIP (0.048s)
[340.280334] [097/142] (717s left) i915_pm_dc (dc5-dpms-negative)
[341.673202] Starting subtest: dc5-dpms-negative
[345.962800] Subtest dc5-dpms-negative: SUCCESS (4.301s)
[346.188747] [098/142] (711s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[346.490643] Starting subtest: invalid-smem-bo-on-discrete
[346.528013] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[346.708846] [099/142] (711s left) kms_rotation_crc (sprite-rotation-90)
[347.100471] Starting subtest: sprite-rotation-90
[353.261650] Subtest sprite-rotation-90: SUCCESS (6.181s)
[353.726562] [100/142] (704s left) kms_cursor_crc (cursor-offscreen-256x256)
[354.121819] Starting subtest: cursor-offscreen-256x256
[354.142855] Starting dynamic subtest: pipe-A-DP-1
[356.644638] Dynamic subtest pipe-A-DP-1: SUCCESS (2.496s)
[356.785044] Starting dynamic subtest: pipe-C-DP-1
[359.535419] Dynamic subtest pipe-C-DP-1: SUCCESS (2.820s)
[359.562620] Subtest cursor-offscreen-256x256: SUCCESS (5.403s)
[359.951554] [101/142] (698s left) gem_exec_schedule (timeslicing)
[360.423417] Starting subtest: timeslicing
[360.443705] Starting dynamic subtest: rcs0
[360.462365] Dynamic subtest rcs0: SUCCESS (0.009s)
[360.504178] Starting dynamic subtest: bcs0
[360.522699] Dynamic subtest bcs0: SUCCESS (0.008s)
[360.565947] Starting dynamic subtest: vcs0
[360.587420] Dynamic subtest vcs0: SUCCESS (0.006s)
[360.631993] Starting dynamic subtest: vecs0
[360.652513] Dynamic subtest vecs0: SUCCESS (0.006s)
[360.672249] Subtest timeslicing: SUCCESS (0.040s)
[360.765687] [102/142] (697s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[361.221038] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[361.257955] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[361.375596] [103/142] (697s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[361.749428] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[361.770384] Starting dynamic subtest: pipe-A-DP-1
[362.092551] Dynamic subtest pipe-A-DP-1: SUCCESS (0.326s)
[362.156086] Starting dynamic subtest: pipe-B-DP-1
[362.484649] Dynamic subtest pipe-B-DP-1: SUCCESS (0.385s)
[362.554427] Starting dynamic subtest: pipe-C-DP-1
[362.597459] Dynamic subtest pipe-C-DP-1: SKIP (0.094s)
[362.618725] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SUCCESS (0.820s)
[363.029831] [104/142] (695s left) gem_exec_fence (syncobj-signal)
[363.758784] Starting subtest: syncobj-signal
[363.779568] Subtest syncobj-signal: SUCCESS (0.008s)
[364.157208] [105/142] (694s left) syncobj_wait (wait-all-interrupted)
[364.457174] Starting subtest: wait-all-interrupted
[364.542043] Subtest wait-all-interrupted: SUCCESS (0.108s)
[364.679759] [106/142] (694s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[364.976460] Starting subtest: no-spare-fences-busy
[367.181368] Subtest no-spare-fences-busy: SUCCESS (2.195s)
[367.354638] [107/142] (691s left) gem_exec_fair (basic-pace)
[367.833625] Starting subtest: basic-pace
[367.855271] Starting dynamic subtest: rcs0
[372.504319] Dynamic subtest rcs0: SUCCESS (4.848s)
[372.552223] Starting dynamic subtest: bcs0
[372.592293] Dynamic subtest bcs0: SKIP (0.007s)
[372.636044] Starting dynamic subtest: vcs0
[377.331054] Dynamic subtest vcs0: SUCCESS (4.812s)
[377.374283] Starting dynamic subtest: vecs0
[382.151199] Dynamic subtest vecs0: SUCCESS (4.817s)
[382.170093] Subtest basic-pace: SUCCESS (14.498s)
[382.391572] [108/142] (676s left) prime_mmap (test_dup)
[382.690410] Starting subtest: test_dup
[382.712234] Starting dynamic subtest: test_dup-smem
[382.732953] Dynamic subtest test_dup-smem: SUCCESS (0.006s)
[382.753685] Subtest test_dup: SUCCESS (0.009s)
[382.847233] [109/142] (676s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[383.279216] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[383.316678] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[383.433528] [110/142] (675s left) drm_fdinfo (basics)
[383.732389] Starting subtest: basics
[383.759594] Subtest basics: SUCCESS (0.000s)
[383.860570] [111/142] (675s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[384.161747] Starting subtest: cpuset-medium-copy-XY
[396.476643] Subtest cpuset-medium-copy-XY: SUCCESS (12.346s)
[396.639506] [112/142] (662s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[397.196862] Starting subtest: 2x-flip-vs-cursor-legacy
[397.234262] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.004s)
[397.355040] [113/142] (662s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[397.802275] Starting subtest: psr-farfromfence-mmap-gtt
[397.841756] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[397.959861] [114/142] (661s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[398.446640] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[398.772012] [115/142] (660s left) kms_frontbuffer_tracking (fbcpsr-badstride)
[399.223167] Starting subtest: fbcpsr-badstride
[399.260159] Subtest fbcpsr-badstride: SKIP (0.000s)
[399.377639] [116/142] (660s left) gen9_exec_parse (unaligned-access)
[399.673386] Starting subtest: unaligned-access
[399.749816] Subtest unaligned-access: SUCCESS (0.076s)
[400.025633] [117/142] (659s left) gem_ctx_persistence (legacy-engines-mixed-process)
[400.322835] Starting subtest: legacy-engines-mixed-process
[400.341246] Starting dynamic subtest: render
[400.663748] Dynamic subtest render: SUCCESS (0.358s)
[400.711129] Starting dynamic subtest: bsd
[401.021522] Dynamic subtest bsd: SUCCESS (0.350s)
[401.069620] Starting dynamic subtest: blt
[401.395170] Dynamic subtest blt: SUCCESS (0.360s)
[401.442751] Starting dynamic subtest: vebox
[401.753536] Dynamic subtest vebox: SUCCESS (0.354s)
[401.772899] Subtest legacy-engines-mixed-process: SUCCESS (1.437s)
[401.915417] [118/142] (658s left) gem_busy (busy)
[402.221015] Starting subtest: busy
[402.244046] Starting dynamic subtest: all-engines
[402.315841] Dynamic subtest all-engines: SUCCESS (0.101s)
[402.365153] Starting dynamic subtest: rcs0
[402.383612] Dynamic subtest rcs0: SUCCESS (0.017s)
[402.428295] Starting dynamic subtest: bcs0
[402.449726] Dynamic subtest bcs0: SUCCESS (0.018s)
[402.493402] Starting dynamic subtest: vcs0
[402.514636] Dynamic subtest vcs0: SUCCESS (0.017s)
[402.557999] Starting dynamic subtest: vecs0
[402.576715] Dynamic subtest vecs0: SUCCESS (0.014s)
[402.595346] Subtest busy: SUCCESS (0.182s)
[402.811047] [119/142] (657s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[403.209636] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[403.250385] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[403.372695] [120/142] (656s left) gem_exec_flush (basic-uc-pro-default)
[403.714159] Starting subtest: basic-uc-pro-default
[409.157235] Subtest basic-uc-pro-default: SUCCESS (5.466s)
[409.357267] [121/142] (650s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs)
[409.867187] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs
[409.906506] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs: SKIP (0.005s)
[410.448001] [122/142] (650s left) gem_pxp (reject-modify-context-protection-off-1)
[413.429724] Subtest reject-modify-context-protection-off-1: SKIP (0.000s)
[413.588778] [123/142] (646s left) gem_exec_params (invalid-bsd2-flag-on-render)
[413.864102] Starting subtest: invalid-bsd2-flag-on-render
[413.885956] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.001s)
[413.985553] [124/142] (646s left) gem_mmap_gtt (hang)
[414.280455] Starting subtest: hang
[419.735083] Subtest hang: SUCCESS (5.486s)
[419.900866] [125/142] (640s left) gem_exec_store (dword)
[420.260062] Starting subtest: dword
[420.278979] Starting dynamic subtest: rcs0
[420.300633] Dynamic subtest rcs0: SUCCESS (0.052s)
[420.349087] Starting dynamic subtest: bcs0
[420.369718] Dynamic subtest bcs0: SUCCESS (0.025s)
[420.414286] Starting dynamic subtest: vcs0
[420.433726] Dynamic subtest vcs0: SUCCESS (0.023s)
[420.479229] Starting dynamic subtest: vecs0
[420.498143] Dynamic subtest vecs0: SUCCESS (0.022s)
[420.516850] Subtest dword: SUCCESS (0.134s)
[420.622234] [126/142] (640s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[421.061662] Starting subtest: x-tiled-32bpp-rotate-270
[421.145328] Subtest x-tiled-32bpp-rotate-270: SKIP (0.075s)
[421.306647] [127/142] (639s left) syncobj_timeline (invalid-wait-zero-handles)
[421.656425] Starting subtest: invalid-wait-zero-handles
[421.678029] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[421.814702] [128/142] (639s left) i915_query (query-garbage)
[422.123139] Starting subtest: query-garbage
[422.143857] Subtest query-garbage: SUCCESS (0.000s)
[422.242278] [129/142] (638s left) syncobj_wait (invalid-signal-bad-pad)
[422.545953] Starting subtest: invalid-signal-bad-pad
[422.564803] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[422.662091] [130/142] (638s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[423.105206] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[423.148242] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[423.275435] [131/142] (638s left) drm_read (short-buffer-block)
[423.969259] Starting subtest: short-buffer-block
[423.988275] Subtest short-buffer-block: SUCCESS (0.000s)
[424.128422] [132/142] (637s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-pwrite)
[424.573749] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-pwrite
[424.623626] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-pwrite: SKIP (0.000s)
[424.758984] [133/142] (636s left) gem_tiled_pread_pwrite
[426.055180] [134/142] (635s left) kms_rotation_crc (exhaust-fences)
[426.604731] Starting subtest: exhaust-fences
[428.294676] Subtest exhaust-fences: SUCCESS (1.680s)
[428.505569] [135/142] (633s left) kms_addfb_basic (invalid-set-prop)
[428.801648] Starting subtest: invalid-set-prop
[428.820467] Subtest invalid-set-prop: SUCCESS (0.000s)
[429.018833] [136/142] (632s left) kms_addfb_basic (unused-pitches)
[429.310348] Starting subtest: unused-pitches
[429.329082] Subtest unused-pitches: SUCCESS (0.000s)
[429.522640] [137/142] (632s left) kms_cursor_crc (cursor-sliding-512x170)
[429.909925] Starting subtest: cursor-sliding-512x170
[429.950019] Subtest cursor-sliding-512x170: SKIP (0.002s)
[430.071772] [138/142] (632s left) kms_vblank (pipe-a-ts-continuation-idle)
[430.456798] Starting subtest: pipe-A-ts-continuation-idle
[430.692960] Subtest pipe-A-ts-continuation-idle: SUCCESS (0.238s)
[431.061302] [139/142] (631s left) gem_exec_balancer (hog)
[431.380208] Starting subtest: hog
[435.259338] Subtest hog: SUCCESS (3.705s)
[439.371210] [140/142] (623s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[439.801565] Starting subtest: yf-tiled-64bpp-rotate-270
[439.838566] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[439.953246] [141/142] (622s left) kms_plane_cursor (overlay)
[440.342577] Starting subtest: overlay
[440.379647] Starting dynamic subtest: pipe-A-DP-1-size-64
[442.736594] Dynamic subtest pipe-A-DP-1-size-64: SUCCESS (2.415s)
[442.824217] Starting dynamic subtest: pipe-A-DP-1-size-128
[445.240527] Dynamic subtest pipe-A-DP-1-size-128: SUCCESS (2.449s)
[445.333818] Starting dynamic subtest: pipe-A-DP-1-size-256
[447.720995] Dynamic subtest pipe-A-DP-1-size-256: SUCCESS (2.431s)
[447.946837] Starting dynamic subtest: pipe-B-DP-1-size-64
[450.555630] Dynamic subtest pipe-B-DP-1-size-64: SUCCESS (2.673s)
[450.642197] Starting dynamic subtest: pipe-B-DP-1-size-128
[453.045163] Dynamic subtest pipe-B-DP-1-size-128: SUCCESS (2.432s)
[453.129069] Starting dynamic subtest: pipe-B-DP-1-size-256
[455.516668] Dynamic subtest pipe-B-DP-1-size-256: SUCCESS (2.432s)
[455.733827] Starting dynamic subtest: pipe-C-DP-1-size-64
[458.336994] Dynamic subtest pipe-C-DP-1-size-64: SUCCESS (2.671s)
[458.424832] Starting dynamic subtest: pipe-C-DP-1-size-128
[460.802076] Dynamic subtest pipe-C-DP-1-size-128: SUCCESS (2.415s)
[460.892863] Starting dynamic subtest: pipe-C-DP-1-size-256
[463.289783] Dynamic subtest pipe-C-DP-1-size-256: SUCCESS (2.432s)
[463.445143] Subtest overlay: SUCCESS (23.121s)
[463.828081] [142/142] (598s left) gem_mmap_wc (read-write-distinct)
[464.135010] Starting subtest: read-write-distinct
[464.153972] Subtest read-write-distinct: SUCCESS (0.030s)
[464.269213] Closing watchdogs
Done.