[34.536333] Initializing watchdogs
[34.536460]   /dev/watchdog0
[34.549216] [001/140] (960s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[34.597744] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[34.600174] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[34.653223] [002/140] (960s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[34.739490] Starting subtest: 4-tiled-16bpp-rotate-270
[34.771189] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.030s)
[34.858486] [003/140] (960s left) syncobj_timeline (single-wait-for-submit-signaled)
[34.914068] Starting subtest: single-wait-for-submit-signaled
[34.916722] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[34.974808] [004/140] (960s left) perf (non-sampling-read-error)
[35.130430] Starting subtest: non-sampling-read-error
[35.142233] Subtest non-sampling-read-error: SUCCESS (0.013s)
[35.176189] [005/140] (959s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[35.238538] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[35.242189] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[35.326094] [006/140] (959s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[35.422586] Starting subtest: 2x-cursor-vs-flip-atomic
[35.428833] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.005s)
[35.572954] [007/140] (959s left) kms_pm_dc (dc5-dpms-negative)
[36.673412] Starting subtest: dc5-dpms-negative
[36.678241] Subtest dc5-dpms-negative: SKIP (0.000s)
[36.732891] [008/140] (958s left) v3d/v3d_submit_csd (multisync-out-syncs)
[36.787412] Subtest multisync-out-syncs: SKIP (0.000s)
[36.833395] [009/140] (958s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc)
[37.272292] Starting subtest: pipe-D-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc
[37.278439] Subtest pipe-D-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.001s)
[38.127160] [010/140] (957s left) gem_ppgtt (shrink-vs-evict-pinned)
[38.196089] Starting subtest: shrink-vs-evict-pinned
[43.485078] Subtest shrink-vs-evict-pinned: SUCCESS (5.289s)
[43.559865] [011/140] (951s left) gen3_render_tiledx_blits
[43.626999] [012/140] (951s left) kms_ccs (pipe-a-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[44.004944] Starting subtest: pipe-A-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[44.007510] Subtest pipe-A-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.001s)
[44.859804] [013/140] (950s left) syncobj_wait (wait-any-snapshot)
[44.915067] Starting subtest: wait-any-snapshot
[44.996485] Subtest wait-any-snapshot: SUCCESS (0.081s)
[45.058475] [014/140] (950s left) kms_frontbuffer_tracking (basic)
[45.119455] Starting subtest: basic
[48.442774] Subtest basic: SUCCESS (3.328s)
[48.525238] [015/140] (946s left) kms_psr2_su (frontbuffer-xrgb8888)
[48.578348] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[48.599484] [016/140] (946s left) kms_ccs (pipe-c-bad-rotation-90-4-tiled-mtl-rc-ccs)
[48.989234] Starting subtest: pipe-C-bad-rotation-90-4-tiled-mtl-rc-ccs
[49.772697] Subtest pipe-C-bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.782s)
[50.953473] [017/140] (944s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[51.022986] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[51.027022] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[51.099878] [018/140] (944s left) gem_mmap_gtt (basic-write-read-distinct)
[51.144853] Subtest basic-write-read-distinct: SKIP (0.000s)
[51.177499] [019/140] (944s left) vc4/vc4_perfmon (create-single-perfmon)
[51.232588] Subtest create-single-perfmon: SKIP (0.000s)
[51.274798] [020/140] (944s left) kms_ccs (pipe-b-bad-aux-stride-yf-tiled-ccs)
[51.648787] Starting subtest: pipe-B-bad-aux-stride-yf-tiled-ccs
[51.653919] Subtest pipe-B-bad-aux-stride-yf-tiled-ccs: SKIP (0.000s)
[52.485596] [021/140] (943s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[52.557226] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[52.561981] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[52.632356] [022/140] (942s left) kms_ccs (pipe-b-random-ccs-data-4-tiled-dg2-mc-ccs)
[53.004553] Starting subtest: pipe-B-random-ccs-data-4-tiled-dg2-mc-ccs
[53.012829] Subtest pipe-B-random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[53.875853] [023/140] (941s left) gem_exec_reloc (basic-gtt-active)
[53.944041] Subtest basic-gtt-active: SKIP (0.000s)
[53.995720] [024/140] (941s left) perf_pmu (busy-start)
[54.042484] Starting subtest: busy-start
[54.044938] Starting dynamic subtest: rcs0
[56.590234] Dynamic subtest rcs0: SUCCESS (2.550s)
[56.603369] Starting dynamic subtest: bcs0
[59.161743] Dynamic subtest bcs0: SUCCESS (2.549s)
[59.175214] Starting dynamic subtest: vcs0
[62.460160] Dynamic subtest vcs0: SUCCESS (3.318s)
[62.472935] Starting dynamic subtest: vcs1
[66.492553] Dynamic subtest vcs1: SUCCESS (4.032s)
[66.505483] Starting dynamic subtest: vecs0
[70.461279] Dynamic subtest vecs0: SUCCESS (3.968s)
[70.473714] Starting dynamic subtest: ccs0
[73.030878] Dynamic subtest ccs0: SUCCESS (2.550s)
[73.033828] Subtest busy-start: SUCCESS (18.969s)
[73.079193] [025/140] (922s left) gem_mmap_offset (perf)
[73.118766] Starting subtest: perf
[73.120707] Starting dynamic subtest: smem0
[73.489729] Dynamic subtest smem0: SUCCESS (0.371s)
[73.492120] Subtest perf: SUCCESS (0.371s)
[73.568902] [026/140] (922s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[73.634578] Starting subtest: fbc-rgb565-draw-blt
[76.726327] Subtest fbc-rgb565-draw-blt: SUCCESS (3.096s)
[76.853248] [027/140] (918s left) kms_getfb (getfb2-accept-ccs)
[76.911095] Starting subtest: getfb2-accept-ccs
[76.913240] Subtest getfb2-accept-ccs: SUCCESS (0.001s)
[76.993045] [028/140] (918s left) kms_ccs (pipe-d-crc-primary-basic-yf-tiled-ccs)
[77.400205] Starting subtest: pipe-D-crc-primary-basic-yf-tiled-ccs
[77.406380] Subtest pipe-D-crc-primary-basic-yf-tiled-ccs: SKIP (0.000s)
[78.258249] [029/140] (917s left) kms_cursor_edge_walk (64x64-right-edge)
[78.321531] Starting subtest: 64x64-right-edge
[78.324324] Starting dynamic subtest: pipe-A-eDP-1
[81.890185] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.567s)
[81.903336] Starting dynamic subtest: pipe-D-eDP-1
[85.112353] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.220s)
[85.115148] Subtest 64x64-right-edge: SUCCESS (6.790s)
[86.003763] [030/140] (909s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-pwrite)
[86.072856] Starting subtest: psr-1p-offscren-pri-shrfb-draw-pwrite
[91.096863] Subtest psr-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (5.029s)
[91.213078] [031/140] (904s left) gem_workarounds (basic-read)
[91.286244] Starting subtest: basic-read
[91.299550] Subtest basic-read: SUCCESS (0.013s)
[91.388382] [032/140] (904s left) sw_sync (sync_merge)
[91.429154] Starting subtest: sync_merge
[91.431457] Subtest sync_merge: SUCCESS (0.000s)
[91.471038] [033/140] (904s left) kms_ccs (pipe-a-bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[91.524360] Starting subtest: pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[91.529152] Subtest pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[92.722265] [034/140] (903s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[92.810823] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[92.815323] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[92.888106] [035/140] (902s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-render)
[92.948067] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-render
[95.975478] Subtest fbc-1p-offscren-pri-shrfb-draw-render: SUCCESS (3.032s)
[96.102319] [036/140] (899s left) kms_ccs (pipe-a-missing-ccs-buffer-y-tiled-ccs)
[96.483160] Starting subtest: pipe-A-missing-ccs-buffer-y-tiled-ccs
[96.488754] Subtest pipe-A-missing-ccs-buffer-y-tiled-ccs: SKIP (0.000s)
[97.394808] [037/140] (898s left) i915_query (query-regions-garbage-items)
[97.449089] Starting subtest: query-regions-garbage-items
[97.451524] Subtest query-regions-garbage-items: SUCCESS (0.000s)
[97.500252] [038/140] (898s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[97.583285] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[97.587584] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[97.671373] [039/140] (898s left) gem_ctx_param (set-priority-range)
[97.727407] Starting subtest: set-priority-range
[97.742529] Subtest set-priority-range: SUCCESS (0.018s)
[97.829996] [040/140] (898s left) gem_mmap_gtt (fault-concurrent)
[97.888814] Subtest fault-concurrent: SKIP (0.000s)
[97.909837] [041/140] (898s left) gem_exec_whisper (basic-sync-all)
[97.958884] Starting subtest: basic-sync-all
[107.310616] Subtest basic-sync-all: SUCCESS (9.349s)
[107.485851] [042/140] (888s left) sw_sync (alloc_fence_invalid_timeline)
[107.535866] Starting subtest: alloc_fence_invalid_timeline
[107.538149] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[107.584028] [043/140] (888s left) kms_psr (psr2_primary_mmap_cpu)
[107.650837] Starting subtest: psr2_primary_mmap_cpu
[107.653339] Starting dynamic subtest: eDP-1
[109.495899] Dynamic subtest eDP-1: SUCCESS (1.843s)
[109.498434] Subtest psr2_primary_mmap_cpu: SUCCESS (1.844s)
[110.387813] [044/140] (885s left) kms_ccs (pipe-d-crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[110.798562] Starting subtest: pipe-D-crc-primary-rotation-180-y-tiled-gen12-mc-ccs
[110.803964] Subtest pipe-D-crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[111.667772] [045/140] (884s left) kms_big_fb (linear-8bpp-rotate-0)
[111.743431] Starting subtest: linear-8bpp-rotate-0
[112.932649] Subtest linear-8bpp-rotate-0: SUCCESS (1.190s)
[113.055818] [046/140] (883s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[113.126130] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[113.129792] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[113.194848] [047/140] (882s left) gem_ctx_persistence (engines-hang)
[113.249930] Starting subtest: engines-hang
[113.252160] Starting dynamic subtest: rcs0
[113.335463] Dynamic subtest rcs0: SUCCESS (0.089s)
[113.348403] Starting dynamic subtest: bcs0
[113.402913] Dynamic subtest bcs0: SUCCESS (0.068s)
[113.415825] Starting dynamic subtest: vcs0
[113.509478] Dynamic subtest vcs0: SUCCESS (0.103s)
[113.523067] Starting dynamic subtest: vcs1
[113.591878] Dynamic subtest vcs1: SUCCESS (0.084s)
[113.605280] Starting dynamic subtest: vecs0
[113.675720] Dynamic subtest vecs0: SUCCESS (0.083s)
[113.690210] Starting dynamic subtest: ccs0
[113.758808] Dynamic subtest ccs0: SUCCESS (0.082s)
[113.761471] Subtest engines-hang: SUCCESS (0.512s)
[113.826820] [048/140] (882s left) gem_exec_capture (pi)
[113.890920] Starting subtest: pi
[113.893161] Starting dynamic subtest: rcs0
[118.580885] Dynamic subtest rcs0: SUCCESS (4.685s)
[118.592448] Starting dynamic subtest: bcs0
[123.005238] Dynamic subtest bcs0: SUCCESS (4.428s)
[123.016992] Starting dynamic subtest: vcs0
[127.426410] Dynamic subtest vcs0: SUCCESS (4.417s)
[127.437432] Starting dynamic subtest: vcs1
[131.832100] Dynamic subtest vcs1: SUCCESS (4.408s)
[131.846212] Starting dynamic subtest: vecs0
[136.193257] Dynamic subtest vecs0: SUCCESS (4.361s)
[136.205549] Starting dynamic subtest: ccs0
[140.610097] Dynamic subtest ccs0: SUCCESS (4.416s)
[140.613089] Subtest pi: SUCCESS (26.719s)
[140.683495] [049/140] (855s left) tools_test (sysfs_l3_parity)
[140.821086] Starting subtest: sysfs_l3_parity
[140.893883] Subtest sysfs_l3_parity: SKIP (0.089s)
[140.942789] [050/140] (855s left) syncobj_timeline (etime-single-wait-for-submit-available-unsubmitted)
[140.996201] Starting subtest: etime-single-wait-for-submit-available-unsubmitted
[141.104597] Subtest etime-single-wait-for-submit-available-unsubmitted: SUCCESS (0.107s)
[141.176376] [051/140] (855s left) gem_close (many-handles-one-vma)
[141.240737] Starting subtest: many-handles-one-vma
[141.285635] Subtest many-handles-one-vma: SUCCESS (0.045s)
[141.380417] [052/140] (854s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[141.462311] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[141.465037] Starting dynamic subtest: pipe-A-eDP-1
[141.473672] Dynamic subtest pipe-A-eDP-1: SKIP (0.004s)
[141.487084] Starting dynamic subtest: pipe-B-eDP-1
[141.492092] Dynamic subtest pipe-B-eDP-1: SKIP (0.013s)
[141.504541] Starting dynamic subtest: pipe-C-eDP-1
[141.509408] Dynamic subtest pipe-C-eDP-1: SKIP (0.013s)
[141.520042] Starting dynamic subtest: pipe-D-eDP-1
[141.524044] Dynamic subtest pipe-D-eDP-1: SKIP (0.013s)
[141.528158] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.046s)
[141.575751] [053/140] (854s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[141.671895] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[141.676058] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[141.747909] [054/140] (854s left) gen9_exec_parse (valid-registers)
[141.807987] Subtest valid-registers: SKIP (0.000s)
[141.845566] [055/140] (854s left) kms_flip (flip-vs-panning-vs-hang)
[141.890883] Starting subtest: flip-vs-panning-vs-hang
[141.893243] Starting dynamic subtest: A-eDP1
[180.978698] Dynamic subtest A-eDP1: SUCCESS (39.091s)
[180.992048] Starting dynamic subtest: D-eDP1
[220.987962] Dynamic subtest D-eDP1: SUCCESS (40.009s)
[220.993311] Subtest flip-vs-panning-vs-hang: SUCCESS (79.106s)
[222.261810] [056/140] (774s left) vc4/vc4_tiling (set-bad-flags)
[222.349816] Subtest set-bad-flags: SKIP (0.000s)
[222.396948] [057/140] (774s left) gem_pwrite_snooped
[222.479644] [058/140] (773s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[222.831755] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[223.703168] [059/140] (772s left) drm_fdinfo (memory-info-purgeable)
[223.763416] Starting subtest: memory-info-purgeable
[223.765677] Starting dynamic subtest: smem0
[223.793300] Dynamic subtest smem0: SKIP (0.033s)
[223.798508] Subtest memory-info-purgeable: SKIP (0.033s)
[223.857537] [060/140] (772s left) gem_exec_schedule (pi-shared-iova)
[224.049320] Starting subtest: pi-shared-iova
[224.051795] Starting dynamic subtest: rcs0
[224.141759] Dynamic subtest rcs0: SUCCESS (0.095s)
[224.155967] Starting dynamic subtest: vcs0
[224.191495] Dynamic subtest vcs0: SUCCESS (0.050s)
[224.205689] Starting dynamic subtest: vcs1
[224.235693] Dynamic subtest vcs1: SUCCESS (0.044s)
[224.249687] Starting dynamic subtest: vecs0
[224.273708] Dynamic subtest vecs0: SUCCESS (0.038s)
[224.279416] Starting dynamic subtest: ccs0
[224.341114] Dynamic subtest ccs0: SUCCESS (0.067s)
[224.343451] Subtest pi-shared-iova: SUCCESS (0.295s)
[225.551153] [061/140] (770s left) kms_draw_crc (draw-method-mmap-gtt)
[225.623800] Starting subtest: draw-method-mmap-gtt
[225.627935] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[225.708136] [062/140] (770s left) drm_fdinfo (busy-hang)
[225.776891] Starting subtest: busy-hang
[225.779299] Starting dynamic subtest: rcs0
[225.783991] Dynamic subtest rcs0: SKIP (0.001s)
[225.793535] Starting dynamic subtest: bcs0
[225.798193] Dynamic subtest bcs0: SKIP (0.000s)
[225.811195] Starting dynamic subtest: vcs0
[225.815485] Dynamic subtest vcs0: SKIP (0.000s)
[225.827066] Starting dynamic subtest: vcs1
[225.831230] Dynamic subtest vcs1: SKIP (0.000s)
[225.840627] Starting dynamic subtest: vecs0
[225.844800] Dynamic subtest vecs0: SKIP (0.000s)
[225.853319] Starting dynamic subtest: ccs0
[225.857651] Dynamic subtest ccs0: SKIP (0.000s)
[225.861849] Subtest busy-hang: SKIP (0.005s)
[225.880329] [063/140] (770s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[225.941474] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[226.596910] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.659s)
[226.760957] [064/140] (769s left) gem_ctx_shared (disjoint-timelines)
[226.829265] Starting subtest: disjoint-timelines
[226.876521] Subtest disjoint-timelines: SUCCESS (0.050s)
[227.085655] [065/140] (769s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[227.172244] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[231.726207] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.557s)
[231.836757] [066/140] (764s left) kms_psr (psr_sprite_plane_move)
[231.904073] Starting subtest: psr_sprite_plane_move
[231.906503] Starting dynamic subtest: eDP-1
[233.736764] Dynamic subtest eDP-1: SUCCESS (1.834s)
[233.739703] Subtest psr_sprite_plane_move: SUCCESS (1.835s)
[234.611217] [067/140] (762s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[234.669610] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[234.673733] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[234.746360] [068/140] (761s left) gem_exec_schedule (u-lateslice)
[234.810272] Starting subtest: u-lateslice
[234.812647] Starting dynamic subtest: rcs0
[234.838121] Dynamic subtest rcs0: SUCCESS (0.030s)
[234.853038] Starting dynamic subtest: bcs0
[234.867957] Dynamic subtest bcs0: SUCCESS (0.029s)
[234.881653] Starting dynamic subtest: vcs0
[234.891461] Dynamic subtest vcs0: SUCCESS (0.023s)
[234.904077] Starting dynamic subtest: vcs1
[234.914162] Dynamic subtest vcs1: SUCCESS (0.022s)
[234.926066] Starting dynamic subtest: vecs0
[234.935042] Dynamic subtest vecs0: SUCCESS (0.020s)
[234.947077] Starting dynamic subtest: ccs0
[234.955441] Dynamic subtest ccs0: SUCCESS (0.020s)
[234.957934] Subtest u-lateslice: SUCCESS (0.148s)
[236.507548] [069/140] (760s left) gem_ringfill (engines-basic)
[242.432414] Starting subtest: engines-basic
[242.434766] Starting dynamic subtest: rcs0
[242.493074] Dynamic subtest rcs0: SUCCESS (0.063s)
[242.507699] Starting dynamic subtest: bcs0
[242.539466] Dynamic subtest bcs0: SUCCESS (0.046s)
[242.553615] Starting dynamic subtest: vcs0
[242.584775] Dynamic subtest vcs0: SUCCESS (0.045s)
[242.599037] Starting dynamic subtest: vcs1
[242.631365] Dynamic subtest vcs1: SUCCESS (0.046s)
[242.649199] Starting dynamic subtest: vecs0
[242.678585] Dynamic subtest vecs0: SUCCESS (0.047s)
[242.694320] Starting dynamic subtest: ccs0
[242.726157] Dynamic subtest ccs0: SUCCESS (0.047s)
[242.729473] Subtest engines-basic: SUCCESS (0.297s)
[242.795794] [070/140] (753s left) kms_flip (2x-plain-flip-fb-recreate)
[242.849275] Starting subtest: 2x-plain-flip-fb-recreate
[242.869745] Subtest 2x-plain-flip-fb-recreate: SKIP (0.021s)
[242.942828] [071/140] (753s left) kms_chamelium_color (ctm-0-25)
[243.004228] Subtest ctm-0-25: SKIP (0.000s)
[243.025234] [072/140] (753s left) kms_ccs (pipe-d-bad-pixel-format-y-tiled-gen12-rc-ccs)
[243.425949] Starting subtest: pipe-D-bad-pixel-format-y-tiled-gen12-rc-ccs
[243.430986] Subtest pipe-D-bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[244.278155] [073/140] (752s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[244.348658] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[244.352554] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[244.429625] [074/140] (752s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[244.499516] Starting subtest: yf-tiled-16bpp-rotate-180
[244.504139] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[244.570739] [075/140] (752s left) gen7_exec_parse (basic-allocation)
[244.630530] Subtest basic-allocation: SKIP (0.000s)
[244.655340] [076/140] (752s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[245.068919] Starting subtest: pipe-C-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[245.074853] Subtest pipe-C-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.001s)
[245.892809] [077/140] (751s left) syncobj_timeline (multi-wait-all-available-submitted)
[245.948824] Starting subtest: multi-wait-all-available-submitted
[245.951084] Subtest multi-wait-all-available-submitted: SUCCESS (0.000s)
[246.009646] [078/140] (750s left) kms_sequence (get-busy)
[246.069570] Starting subtest: get-busy
[246.071778] Starting dynamic subtest: pipe-A-eDP-1
[247.993918] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.923s)
[248.001584] Starting dynamic subtest: pipe-B-eDP-1
[250.677398] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.681s)
[250.693519] Starting dynamic subtest: pipe-C-eDP-1
[253.339737] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.660s)
[253.353184] Starting dynamic subtest: pipe-D-eDP-1
[256.014039] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.673s)
[256.016574] Subtest get-busy: SUCCESS (9.945s)
[256.951521] [079/140] (740s left) kms_flip (2x-flip-vs-suspend)
[257.008993] Starting subtest: 2x-flip-vs-suspend
[257.021203] Subtest 2x-flip-vs-suspend: SKIP (0.014s)
[257.090415] [080/140] (739s left) syncobj_wait (single-wait-submitted)
[257.146493] Starting subtest: single-wait-submitted
[257.248402] Subtest single-wait-submitted: SUCCESS (0.105s)
[257.317406] [081/140] (739s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[257.406560] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[257.409270] Starting dynamic subtest: pipe-A-default-mode
[260.315222] Dynamic subtest pipe-A-default-mode: SUCCESS (2.908s)
[260.317736] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.910s)
[261.235920] [082/140] (735s left) gem_ctx_sseu (mmap-args)
[261.281675] Subtest mmap-args: SKIP (0.000s)
[261.311358] [083/140] (735s left) gem_exec_reloc (basic-cpu-gtt)
[261.371589] Subtest basic-cpu-gtt: SKIP (0.000s)
[261.432543] [084/140] (735s left) kms_flip (flip-vs-fences)
[261.503970] Starting subtest: flip-vs-fences
[261.508296] Subtest flip-vs-fences: SKIP (0.000s)
[261.578791] [085/140] (735s left) i915_pm_rps (basic-api)
[261.633095] Starting subtest: basic-api
[261.638153] Subtest basic-api: SKIP (0.000s)
[261.683151] [086/140] (735s left) kms_cursor_crc (cursor-random-64x21)
[261.823530] Starting subtest: cursor-random-64x21
[261.825876] Starting dynamic subtest: pipe-A-eDP-1
[265.550096] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.723s)
[265.581235] Starting dynamic subtest: pipe-D-eDP-1
[269.999288] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.424s)
[270.001795] Subtest cursor-random-64x21: SUCCESS (8.174s)
[270.880265] [087/140] (726s left) kms_pm_rpm (modeset-pc8-residency-stress)
[272.596666] Starting subtest: modeset-pc8-residency-stress
[272.602844] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[273.184258] [088/140] (724s left) kms_flip (plain-flip-interruptible)
[273.255269] Starting subtest: plain-flip-interruptible
[273.262524] Starting dynamic subtest: A-eDP1
[275.028461] Dynamic subtest A-eDP1: SUCCESS (1.765s)
[275.043755] Starting dynamic subtest: B-eDP1
[276.754411] Dynamic subtest B-eDP1: SUCCESS (1.725s)
[276.770067] Starting dynamic subtest: C-eDP1
[278.471332] Dynamic subtest C-eDP1: SUCCESS (1.715s)
[278.487188] Starting dynamic subtest: D-eDP1
[280.275832] Dynamic subtest D-eDP1: SUCCESS (1.803s)
[280.285787] Subtest plain-flip-interruptible: SUCCESS (7.028s)
[281.517068] [089/140] (715s left) gem_exec_nop (basic-parallel)
[281.570659] Starting subtest: basic-parallel
[297.405152] Subtest basic-parallel: SUCCESS (15.842s)
[297.614900] [090/140] (699s left) gem_eio (in-flight-contexts-1us)
[297.869233] Starting subtest: in-flight-contexts-1us
[311.521841] Subtest in-flight-contexts-1us: SUCCESS (13.652s)
[311.652963] [091/140] (685s left) syncobj_wait (invalid-single-wait-unsubmitted)
[311.719781] Starting subtest: invalid-single-wait-unsubmitted
[311.722415] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[311.788999] [092/140] (685s left) kms_vblank (wait-forked-hang)
[311.866230] Starting subtest: wait-forked-hang
[311.868596] Starting dynamic subtest: pipe-A-eDP-1
[313.703204] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.835s)
[313.720859] Starting dynamic subtest: pipe-D-eDP-1
[316.292278] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.582s)
[316.294904] Subtest wait-forked-hang: SUCCESS (4.427s)
[317.173366] [093/140] (680s left) kms_prop_blob (blob-prop-lifetime)
[317.231672] Starting subtest: blob-prop-lifetime
[317.234329] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[317.295039] [094/140] (680s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[317.379168] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[317.383581] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[317.460262] [095/140] (679s left) gen9_exec_parse (bb-start-far)
[317.515417] Subtest bb-start-far: SKIP (0.000s)
[317.542594] [096/140] (679s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[317.610054] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[317.613957] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[317.683514] [097/140] (679s left) kms_plane_lowres (tiling-y)
[317.745025] Starting subtest: tiling-y
[317.749652] Subtest tiling-y: SKIP (0.000s)
[317.800779] [098/140] (679s left) gem_userptr_blits (sync-overlap)
[317.862596] Starting subtest: sync-overlap
[317.864597] Subtest sync-overlap: SUCCESS (0.000s)
[317.964572] [099/140] (679s left) gem_ccs (ctrl-surf-copy)
[318.020138] Starting subtest: ctrl-surf-copy
[318.025332] Subtest ctrl-surf-copy: SKIP (0.000s)
[318.081458] [100/140] (679s left) gem_eio (banned)
[318.286639] Starting subtest: banned
[318.895564] Subtest banned: SUCCESS (0.610s)
[319.008194] [101/140] (678s left) kms_vblank (ts-continuation-dpms-suspend)
[319.073925] Starting subtest: ts-continuation-dpms-suspend
[319.075939] Starting dynamic subtest: pipe-A-eDP-1
[336.975886] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.908s)
[336.996376] Starting dynamic subtest: pipe-D-eDP-1
[355.014977] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.616s)
[355.017538] Subtest ts-continuation-dpms-suspend: SUCCESS (4.532s)
[355.945918] [102/140] (673s left) sw_sync (sync_multi_producer_single_consumer)
[356.005280] Starting subtest: sync_multi_producer_single_consumer
[357.078411] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[357.126922] [103/140] (671s left) kms_cursor_crc (cursor-offscreen-512x512)
[357.380533] Starting subtest: cursor-offscreen-512x512
[357.385302] Subtest cursor-offscreen-512x512: SKIP (0.002s)
[357.527962] [104/140] (671s left) gem_mmap_gtt (basic-small-copy)
[357.571212] Subtest basic-small-copy: SKIP (0.000s)
[357.585651] [105/140] (671s left) kms_plane_alpha_blend (alpha-transparent-fb)
[357.645219] Starting subtest: alpha-transparent-fb
[357.966128] Starting dynamic subtest: pipe-A-eDP-1
[358.603756] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.636s)
[358.618538] Starting dynamic subtest: pipe-D-eDP-1
[360.139914] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.533s)
[360.142508] Subtest alpha-transparent-fb: SUCCESS (2.497s)
[361.065279] [106/140] (668s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[361.642704] Starting subtest: syncobj-backward-timeline-chain-engines
[361.755577] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.113s)
[362.940789] [107/140] (666s left) core_auth (getclient-simple)
[362.998739] Starting subtest: getclient-simple
[363.002836] Subtest getclient-simple: SUCCESS (0.001s)
[363.063673] [108/140] (666s left) kms_getfb (getfb-handle-protection)
[363.099318] Starting subtest: getfb-handle-protection
[363.122298] Subtest getfb-handle-protection: SUCCESS (0.026s)
[363.225127] [109/140] (665s left) syncobj_eventfd (binary-wait)
[363.286471] Starting subtest: binary-wait
[363.292794] Subtest binary-wait: SUCCESS (0.010s)
[363.360837] [110/140] (665s left) kms_chamelium_hpd (hdmi-hpd-fast)
[363.696085] Subtest hdmi-hpd-fast: SKIP (0.000s)
[364.558356] [111/140] (664s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[364.640021] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt
[369.403438] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.768s)
[369.536753] [112/140] (659s left) kms_color (legacy-gamma)
[369.611298] Starting subtest: legacy-gamma
[369.613652] Starting dynamic subtest: pipe-A
[370.400858] Dynamic subtest pipe-A: SUCCESS (0.789s)
[370.418535] Starting dynamic subtest: pipe-B
[371.962216] Dynamic subtest pipe-B: SUCCESS (1.560s)
[371.982288] Starting dynamic subtest: pipe-C
[373.406538] Dynamic subtest pipe-C: SUCCESS (1.444s)
[373.423085] Starting dynamic subtest: pipe-D
[374.878808] Dynamic subtest pipe-D: SUCCESS (1.470s)
[374.881389] Subtest legacy-gamma: SUCCESS (5.268s)
[375.788016] [113/140] (653s left) kms_async_flips (crc)
[375.852663] Starting subtest: crc
[376.220599] Starting dynamic subtest: pipe-A-eDP-1
[379.715176] Dynamic subtest pipe-A-eDP-1: FAIL (3.366s)
[379.978955] Starting dynamic subtest: pipe-B-eDP-1
[383.431809] Dynamic subtest pipe-B-eDP-1: FAIL (3.330s)
[383.686027] Starting dynamic subtest: pipe-C-eDP-1
[387.138742] Dynamic subtest pipe-C-eDP-1: FAIL (3.328s)
[387.391900] Starting dynamic subtest: pipe-D-eDP-1
[390.855948] Dynamic subtest pipe-D-eDP-1: FAIL (3.331s)
[390.857754] Subtest crc: FAIL (14.858s)
[391.979317] [114/140] (637s left) gem_softpin (allocator-evict)
[392.049104] Starting subtest: allocator-evict
[392.051519] Starting dynamic subtest: rcs0
[414.867071] Dynamic subtest rcs0: SUCCESS (22.816s)
[414.885127] Starting dynamic subtest: bcs0
[438.205339] Dynamic subtest bcs0: SUCCESS (23.338s)
[438.221831] Starting dynamic subtest: vcs0
[461.565568] Dynamic subtest vcs0: SUCCESS (23.359s)
[461.579464] Starting dynamic subtest: vcs1
[484.681961] Dynamic subtest vcs1: SUCCESS (23.112s)
[484.700074] Starting dynamic subtest: vecs0
[508.159143] Dynamic subtest vecs0: SUCCESS (23.478s)
[508.177468] Starting dynamic subtest: ccs0
[531.586718] Dynamic subtest ccs0: SUCCESS (23.426s)
[531.589482] Subtest allocator-evict: SUCCESS (139.538s)
[531.663058] [115/140] (497s left) kms_ccs (pipe-a-crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[532.094290] Starting subtest: pipe-A-crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[534.624378] Subtest pipe-A-crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (2.529s)
[535.824156] [116/140] (493s left) prime_busy (hang)
[535.871295] Starting subtest: hang
[535.873378] Starting dynamic subtest: rcs0
[549.399421] Dynamic subtest rcs0: SUCCESS (13.530s)
[549.406522] Starting dynamic subtest: bcs0
[556.550438] Dynamic subtest bcs0: SUCCESS (7.149s)
[556.557143] Starting dynamic subtest: vcs0
[563.539574] Dynamic subtest vcs0: SUCCESS (6.988s)
[563.545862] Starting dynamic subtest: vcs1
[570.564274] Dynamic subtest vcs1: SUCCESS (7.026s)
[570.576110] Starting dynamic subtest: vecs0
[577.563783] Dynamic subtest vecs0: SUCCESS (6.997s)
[577.570345] Starting dynamic subtest: ccs0
[591.377134] Dynamic subtest ccs0: SUCCESS (13.814s)
[591.379038] Subtest hang: SUCCESS (55.508s)
[591.479211] [117/140] (437s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[591.559328] Starting subtest: psr-rgb565-draw-blt
[596.590283] Subtest psr-rgb565-draw-blt: SUCCESS (5.035s)
[596.710163] [118/140] (432s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[596.773855] Starting subtest: x-tiled-64bpp-rotate-90
[596.790682] Subtest x-tiled-64bpp-rotate-90: SKIP (0.018s)
[596.886813] [119/140] (432s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[596.956284] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[596.960955] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[597.045149] [120/140] (432s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[597.103589] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[597.107403] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[597.189808] [121/140] (432s left) syncobj_timeline (wait-for-submit-delayed-submit)
[597.269749] Starting subtest: wait-for-submit-delayed-submit
[597.372362] Subtest wait-for-submit-delayed-submit: SUCCESS (0.102s)
[597.446234] [122/140] (432s left) gem_create (create-ext-placement-all)
[597.613869] Starting subtest: create-ext-placement-all
[597.616873] Subtest create-ext-placement-all: SUCCESS (0.000s)
[597.711368] [123/140] (431s left) kms_ccs (pipe-b-crc-primary-basic-y-tiled-ccs)
[598.123434] Starting subtest: pipe-B-crc-primary-basic-y-tiled-ccs
[598.129487] Subtest pipe-B-crc-primary-basic-y-tiled-ccs: SKIP (0.000s)
[599.012031] [124/140] (430s left) v3d/v3d_perfmon (create-perfmon-exceed)
[599.105572] Subtest create-perfmon-exceed: SKIP (0.000s)
[599.156309] [125/140] (430s left) gem_exec_schedule (preempt-queue)
[599.221495] Subtest preempt-queue: SKIP (0.000s)
[599.512302] [126/140] (430s left) gem_render_copy (y-tiled)
[599.587390] Starting subtest: y-tiled
[599.592485] Subtest y-tiled: SKIP (0.000s)
[599.769731] [127/140] (429s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[599.824046] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[599.841365] [128/140] (429s left) kms_lease (page-flip-implicit-plane)
[599.901207] Starting subtest: page-flip-implicit-plane
[599.903771] Starting dynamic subtest: pipe-A-eDP-1
[600.378176] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.477s)
[600.392967] Starting dynamic subtest: pipe-B-eDP-1
[601.590045] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.208s)
[601.605538] Starting dynamic subtest: pipe-C-eDP-1
[602.781297] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.189s)
[602.798658] Starting dynamic subtest: pipe-D-eDP-1
[604.003705] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.218s)
[604.006314] Subtest page-flip-implicit-plane: SUCCESS (4.103s)
[604.907313] [129/140] (424s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-render)
[604.993597] Starting subtest: psr-1p-offscren-pri-shrfb-draw-render
[609.713351] Subtest psr-1p-offscren-pri-shrfb-draw-render: SUCCESS (4.723s)
[609.849192] [130/140] (419s left) gem_exec_balancer (full-late-pulse)
[609.915421] Starting subtest: full-late-pulse
[614.463795] Subtest full-late-pulse: SUCCESS (4.547s)
[614.714153] [131/140] (415s left) v3d/v3d_submit_csd (bad-multisync-in-sync)
[614.796831] Subtest bad-multisync-in-sync: SKIP (0.000s)
[614.855214] [132/140] (415s left) gem_exec_flush (basic-wb-prw-default)
[614.906400] Starting subtest: basic-wb-prw-default
[620.409704] Subtest basic-wb-prw-default: SUCCESS (5.491s)
[620.582941] [133/140] (409s left) kms_ccs (pipe-c-crc-primary-basic-y-tiled-gen12-mc-ccs)
[620.984612] Starting subtest: pipe-C-crc-primary-basic-y-tiled-gen12-mc-ccs
[620.990683] Subtest pipe-C-crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[621.866952] [134/140] (408s left) kms_ccs (pipe-b-missing-ccs-buffer-yf-tiled-ccs)
[622.240602] Starting subtest: pipe-B-missing-ccs-buffer-yf-tiled-ccs
[622.245914] Subtest pipe-B-missing-ccs-buffer-yf-tiled-ccs: SKIP (0.000s)
[623.154548] [135/140] (406s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[623.213448] Starting subtest: mixed-tiled-to-y-tiled-ccs
[623.217733] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[623.392050] [136/140] (406s left) kms_plane_scaling (2x-scaler-multi-pipe)
[623.460270] Starting subtest: 2x-scaler-multi-pipe
[623.465487] Subtest 2x-scaler-multi-pipe: SKIP (0.001s)
[623.539430] [137/140] (406s left) api_intel_bb (destroy-bb)
[623.605291] Starting subtest: destroy-bb
[623.612401] Subtest destroy-bb: SUCCESS (0.010s)
[623.693815] [138/140] (406s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[623.812276] Starting subtest: cursorB-vs-flipA-legacy
[623.817126] Subtest cursorB-vs-flipA-legacy: SKIP (0.003s)
[623.951040] [139/140] (406s left) gem_exec_fence (syncobj-timeline-export)
[624.521109] Starting subtest: syncobj-timeline-export
[624.531144] Subtest syncobj-timeline-export: SUCCESS (0.011s)
[625.968901] [140/140] (404s left) gem_media_vme
[626.058493] Closing watchdogs
Done.