[49.312067] Initializing watchdogs
[49.312283]   /dev/watchdog0
[49.329648] [001/140] (960s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[49.491460] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[49.493128] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.002s)
[49.571802] [002/140] (960s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[49.836507] Starting subtest: 4-tiled-16bpp-rotate-270
[49.839988] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.000s)
[49.918083] [003/140] (959s left) syncobj_timeline (single-wait-for-submit-signaled)
[50.059689] Starting subtest: single-wait-for-submit-signaled
[50.061223] Subtest single-wait-for-submit-signaled: SUCCESS (0.001s)
[50.134167] [004/140] (959s left) perf (non-sampling-read-error)
[50.425797] Starting subtest: non-sampling-read-error
[50.480442] Subtest non-sampling-read-error: SUCCESS (0.055s)
[50.518701] [005/140] (959s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[50.765168] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[50.767678] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[50.852210] [006/140] (959s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[51.087469] Starting subtest: 2x-cursor-vs-flip-atomic
[51.093897] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.004s)
[51.169130] [007/140] (958s left) kms_pm_dc (dc5-dpms-negative)
[52.414312] Starting subtest: dc5-dpms-negative
[56.732334] Subtest dc5-dpms-negative: SUCCESS (4.319s)
[56.886157] [008/140] (953s left) v3d/v3d_submit_csd (multisync-out-syncs)
[57.071584] Subtest multisync-out-syncs: SKIP (0.000s)
[57.135727] [009/140] (952s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc)
[57.630348] Subtest pipe-D-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[57.951063] [010/140] (952s left) gem_ppgtt (shrink-vs-evict-pinned)
[58.100646] Starting subtest: shrink-vs-evict-pinned
[63.234448] Subtest shrink-vs-evict-pinned: SUCCESS (5.131s)
[63.320882] [011/140] (946s left) gen3_render_tiledx_blits
[63.501981] [012/140] (946s left) kms_ccs (pipe-a-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[63.860071] Starting subtest: pipe-A-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[63.862539] Subtest pipe-A-ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s)
[64.283606] [013/140] (945s left) syncobj_wait (wait-any-snapshot)
[64.428422] Starting subtest: wait-any-snapshot
[64.511092] Subtest wait-any-snapshot: SUCCESS (0.082s)
[64.584487] [014/140] (945s left) kms_frontbuffer_tracking (basic)
[64.822937] Starting subtest: basic
[67.717151] Subtest basic: SUCCESS (2.896s)
[67.835301] [015/140] (942s left) kms_psr2_su (frontbuffer-xrgb8888)
[67.975183] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[68.019724] [016/140] (942s left) kms_ccs (pipe-c-bad-rotation-90-4-tiled-mtl-rc-ccs)
[68.447728] Starting subtest: pipe-C-bad-rotation-90-4-tiled-mtl-rc-ccs
[68.450158] Subtest pipe-C-bad-rotation-90-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[68.805061] [017/140] (941s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-render)
[69.054217] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-render
[69.056664] Subtest fbc-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[69.139041] [018/140] (941s left) gem_mmap_gtt (basic-write-read-distinct)
[69.277627] Starting subtest: basic-write-read-distinct
[69.313437] Subtest basic-write-read-distinct: SUCCESS (0.034s)
[69.421382] [019/140] (941s left) vc4/vc4_perfmon (create-single-perfmon)
[69.604956] Subtest create-single-perfmon: SKIP (0.000s)
[69.669932] [020/140] (940s left) kms_ccs (pipe-b-bad-aux-stride-yf-tiled-ccs)
[70.085271] Starting subtest: pipe-B-bad-aux-stride-yf-tiled-ccs
[70.086706] Subtest pipe-B-bad-aux-stride-yf-tiled-ccs: SUCCESS (0.001s)
[70.463267] [021/140] (940s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[70.704243] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[70.706685] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[70.794558] [022/140] (939s left) kms_ccs (pipe-b-random-ccs-data-4-tiled-dg2-mc-ccs)
[71.190339] Starting subtest: pipe-B-random-ccs-data-4-tiled-dg2-mc-ccs
[71.194103] Subtest pipe-B-random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[71.582875] [023/140] (938s left) gem_exec_reloc (basic-gtt-active)
[71.733296] Starting subtest: basic-gtt-active
[71.873182] Subtest basic-gtt-active: SUCCESS (0.139s)
[72.048737] [024/140] (938s left) perf_pmu (busy-start)
[72.193452] Starting subtest: busy-start
[72.195032] Starting dynamic subtest: rcs0
[74.717878] Dynamic subtest rcs0: SUCCESS (2.524s)
[74.733966] Starting dynamic subtest: bcs0
[77.245653] Dynamic subtest bcs0: SUCCESS (2.528s)
[77.261376] Starting dynamic subtest: vcs0
[79.763692] Dynamic subtest vcs0: SUCCESS (2.518s)
[79.779928] Starting dynamic subtest: vecs0
[82.290225] Dynamic subtest vecs0: SUCCESS (2.524s)
[82.291646] Subtest busy-start: SUCCESS (10.094s)
[82.367552] [025/140] (928s left) gem_mmap_offset (perf)
[82.504658] Starting subtest: perf
[82.506196] Starting dynamic subtest: smem0
[82.805155] Dynamic subtest smem0: SUCCESS (0.299s)
[82.806533] Subtest perf: SUCCESS (0.299s)
[82.915953] [026/140] (927s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[83.156254] Starting subtest: fbc-rgb565-draw-blt
[85.417168] Subtest fbc-rgb565-draw-blt: SUCCESS (2.261s)
[85.533819] [027/140] (925s left) kms_getfb (getfb2-accept-ccs)
[85.672946] Starting subtest: getfb2-accept-ccs
[85.674695] Subtest getfb2-accept-ccs: SUCCESS (0.000s)
[85.766682] [028/140] (924s left) kms_ccs (pipe-d-crc-primary-basic-yf-tiled-ccs)
[86.243228] Subtest pipe-D-crc-primary-basic-yf-tiled-ccs: SKIP (0.000s)
[86.556216] [029/140] (924s left) kms_cursor_edge_walk (64x64-right-edge)
[86.786551] Starting subtest: 64x64-right-edge
[86.787877] Starting dynamic subtest: pipe-A-DP-1
[90.645726] Dynamic subtest pipe-A-DP-1: SUCCESS (3.858s)
[90.663017] Starting dynamic subtest: pipe-C-DP-1
[94.180255] Dynamic subtest pipe-C-DP-1: SUCCESS (3.531s)
[94.181543] Subtest 64x64-right-edge: SUCCESS (7.395s)
[94.494451] [030/140] (916s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-pwrite)
[94.743613] Starting subtest: psr-1p-offscren-pri-shrfb-draw-pwrite
[94.746125] Subtest psr-1p-offscren-pri-shrfb-draw-pwrite: SKIP (0.000s)
[94.843379] [031/140] (916s left) gem_workarounds (basic-read)
[95.015036] Starting subtest: basic-read
[95.041426] Subtest basic-read: SUCCESS (0.023s)
[95.142539] [032/140] (915s left) sw_sync (sync_merge)
[95.280755] Starting subtest: sync_merge
[95.282171] Subtest sync_merge: SUCCESS (0.001s)
[95.342178] [033/140] (915s left) kms_ccs (pipe-a-bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[95.575763] Starting subtest: pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[95.578900] Subtest pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[96.126997] [034/140] (914s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[96.370852] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[96.373356] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[96.459577] [035/140] (914s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-render)
[96.701953] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-render
[98.628354] Subtest fbc-1p-offscren-pri-shrfb-draw-render: SUCCESS (1.930s)
[98.761563] [036/140] (912s left) kms_ccs (pipe-a-missing-ccs-buffer-y-tiled-ccs)
[99.113239] Starting subtest: pipe-A-missing-ccs-buffer-y-tiled-ccs
[99.114615] Subtest pipe-A-missing-ccs-buffer-y-tiled-ccs: SUCCESS (0.001s)
[99.554619] [037/140] (911s left) i915_query (query-regions-garbage-items)
[99.694690] Starting subtest: query-regions-garbage-items
[99.696066] Subtest query-regions-garbage-items: SUCCESS (0.001s)
[99.770886] [038/140] (911s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[100.026398] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[101.301399] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (1.274s)
[101.639957] [039/140] (909s left) gem_ctx_param (set-priority-range)
[101.779887] Starting subtest: set-priority-range
[101.850274] Subtest set-priority-range: SUCCESS (0.067s)
[101.940278] [040/140] (909s left) gem_mmap_gtt (fault-concurrent)
[102.076850] Starting subtest: fault-concurrent
[105.597244] Subtest fault-concurrent: SUCCESS (3.516s)
[105.769602] [041/140] (905s left) gem_exec_whisper (basic-sync-all)
[105.933241] Starting subtest: basic-sync-all
[115.997440] Subtest basic-sync-all: SUCCESS (10.063s)
[116.206256] [042/140] (895s left) sw_sync (alloc_fence_invalid_timeline)
[116.344242] Starting subtest: alloc_fence_invalid_timeline
[116.345577] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[116.403829] [043/140] (894s left) kms_psr (psr2_primary_mmap_cpu)
[116.637231] Subtest psr2_primary_mmap_cpu: SKIP (0.000s)
[116.688442] [044/140] (894s left) kms_ccs (pipe-d-crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[117.163395] Subtest pipe-D-crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[117.479110] [045/140] (893s left) kms_big_fb (linear-8bpp-rotate-0)
[117.740574] Starting subtest: linear-8bpp-rotate-0
[121.608214] Subtest linear-8bpp-rotate-0: SUCCESS (3.867s)
[121.828607] [046/140] (889s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[122.073636] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[122.076141] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[122.160410] [047/140] (889s left) gem_ctx_persistence (engines-hang)
[122.307467] Starting subtest: engines-hang
[122.308828] Starting dynamic subtest: rcs0
[122.349492] Dynamic subtest rcs0: SUCCESS (0.043s)
[122.367030] Starting dynamic subtest: bcs0
[122.371519] Dynamic subtest bcs0: SKIP (0.005s)
[122.388912] Starting dynamic subtest: vcs0
[122.400269] Dynamic subtest vcs0: SUCCESS (0.042s)
[122.419999] Starting dynamic subtest: vecs0
[122.440882] Dynamic subtest vecs0: SUCCESS (0.042s)
[122.442278] Subtest engines-hang: SUCCESS (0.133s)
[122.527352] [048/140] (888s left) gem_exec_capture (pi)
[122.677899] Starting subtest: pi
[122.679268] Starting dynamic subtest: rcs0
[153.027104] Dynamic subtest rcs0: SUCCESS (30.348s)
[153.046027] Starting dynamic subtest: bcs0
[182.860360] Dynamic subtest bcs0: SUCCESS (29.834s)
[182.882277] Starting dynamic subtest: vcs0
[212.631701] Dynamic subtest vcs0: SUCCESS (29.771s)
[212.651666] Starting dynamic subtest: vecs0
[242.459348] Dynamic subtest vecs0: SUCCESS (29.826s)
[242.460987] Subtest pi: SUCCESS (119.781s)
[242.548302] [049/140] (768s left) tools_test (sysfs_l3_parity)
[242.806783] Starting subtest: sysfs_l3_parity
[242.995315] Subtest sysfs_l3_parity: SKIP (0.194s)
[243.062551] [050/140] (768s left) syncobj_timeline (etime-single-wait-for-submit-available-unsubmitted)
[243.217862] Starting subtest: etime-single-wait-for-submit-available-unsubmitted
[243.323432] Subtest etime-single-wait-for-submit-available-unsubmitted: SUCCESS (0.105s)
[243.412099] [051/140] (768s left) gem_close (many-handles-one-vma)
[243.559410] Starting subtest: many-handles-one-vma
[243.645676] Subtest many-handles-one-vma: SUCCESS (0.085s)
[243.746172] [052/140] (767s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[243.982380] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[243.989466] Starting dynamic subtest: pipe-A-DP-1
[244.301850] Dynamic subtest pipe-A-DP-1: SUCCESS (0.312s)
[244.334351] Starting dynamic subtest: pipe-B-DP-1
[244.689501] Dynamic subtest pipe-B-DP-1: SUCCESS (0.378s)
[244.698431] Subtest planes-downscale-factor-0-5-upscale-20x20: SUCCESS (0.717s)
[245.127822] [053/140] (766s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[245.377500] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[245.380064] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[245.477516] [054/140] (766s left) gen9_exec_parse (valid-registers)
[245.638861] Starting subtest: valid-registers
[245.804416] Subtest valid-registers: SUCCESS (0.166s)
[246.030412] [055/140] (765s left) kms_flip (flip-vs-panning-vs-hang)
[246.248284] Starting subtest: flip-vs-panning-vs-hang
[246.249878] Starting dynamic subtest: A-DP1
[260.895227] Dynamic subtest A-DP1: SUCCESS (14.647s)
[260.913222] Starting dynamic subtest: C-DP1
[273.883613] Dynamic subtest C-DP1: SUCCESS (12.986s)
[273.884884] Subtest flip-vs-panning-vs-hang: SUCCESS (27.638s)
[274.304481] [056/140] (737s left) vc4/vc4_tiling (set-bad-flags)
[274.496994] Subtest set-bad-flags: SKIP (0.000s)
[274.563619] [057/140] (737s left) gem_pwrite_snooped
[274.830977] [058/140] (737s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[275.157124] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[275.438567] [059/140] (736s left) drm_fdinfo (memory-info-purgeable)
[275.579462] Starting subtest: memory-info-purgeable
[275.581083] Starting dynamic subtest: smem0
[275.595196] Dynamic subtest smem0: SKIP (0.014s)
[275.597827] Subtest memory-info-purgeable: SKIP (0.014s)
[275.682406] [060/140] (736s left) gem_exec_schedule (pi-shared-iova)
[275.985726] Starting subtest: pi-shared-iova
[275.987278] Starting dynamic subtest: rcs0
[276.064134] Dynamic subtest rcs0: SUCCESS (0.078s)
[276.083522] Starting dynamic subtest: bcs0
[276.136926] Dynamic subtest bcs0: SUCCESS (0.071s)
[276.160307] Starting dynamic subtest: vcs0
[276.193399] Dynamic subtest vcs0: SUCCESS (0.053s)
[276.215191] Starting dynamic subtest: vecs0
[276.246639] Dynamic subtest vecs0: SUCCESS (0.054s)
[276.248103] Subtest pi-shared-iova: SUCCESS (0.260s)
[276.468742] [061/140] (735s left) kms_draw_crc (draw-method-mmap-gtt)
[276.703392] Starting subtest: draw-method-mmap-gtt
[276.704665] Starting dynamic subtest: xrgb8888-untiled
[276.994669] Dynamic subtest xrgb8888-untiled: SUCCESS (0.290s)
[277.013338] Starting dynamic subtest: rgb565-untiled
[277.259723] Dynamic subtest rgb565-untiled: SUCCESS (0.266s)
[277.278361] Starting dynamic subtest: xrgb2101010-untiled
[277.544187] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.283s)
[277.562675] Starting dynamic subtest: xrgb8888-xtiled
[277.677210] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s)
[277.695961] Starting dynamic subtest: rgb565-xtiled
[277.809773] Dynamic subtest rgb565-xtiled: SUCCESS (0.133s)
[277.828931] Starting dynamic subtest: xrgb2101010-xtiled
[277.942786] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.133s)
[277.962477] Starting dynamic subtest: xrgb8888-ytiled
[278.092849] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.149s)
[278.112367] Starting dynamic subtest: rgb565-ytiled
[278.226515] Dynamic subtest rgb565-ytiled: SUCCESS (0.133s)
[278.246753] Starting dynamic subtest: xrgb2101010-ytiled
[278.359698] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.133s)
[278.361527] Subtest draw-method-mmap-gtt: SUCCESS (1.658s)
[278.466788] [062/140] (733s left) drm_fdinfo (busy-hang)
[278.613417] Starting subtest: busy-hang
[278.614839] Starting dynamic subtest: rcs0
[279.658689] Dynamic subtest rcs0: SUCCESS (1.046s)
[279.678407] Starting dynamic subtest: bcs0
[280.701597] Dynamic subtest bcs0: SUCCESS (1.042s)
[280.721669] Starting dynamic subtest: vcs0
[281.736205] Dynamic subtest vcs0: SUCCESS (1.034s)
[281.756399] Starting dynamic subtest: vecs0
[282.771425] Dynamic subtest vecs0: SUCCESS (1.034s)
[282.772918] Subtest busy-hang: SUCCESS (4.160s)
[282.847201] [063/140] (729s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[283.101891] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[283.104818] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[283.216825] [064/140] (728s left) gem_ctx_shared (disjoint-timelines)
[283.381619] Starting subtest: disjoint-timelines
[283.458056] Subtest disjoint-timelines: SUCCESS (0.075s)
[283.682153] [065/140] (728s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[283.923189] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[283.925647] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[284.015780] [066/140] (728s left) kms_psr (psr_sprite_plane_move)
[284.248486] Subtest psr_sprite_plane_move: SKIP (0.000s)
[284.300351] [067/140] (727s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[284.537149] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[284.539800] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[284.647895] [068/140] (727s left) gem_exec_schedule (u-lateslice)
[284.818659] Starting subtest: u-lateslice
[284.820181] Starting dynamic subtest: rcs0
[284.854547] Dynamic subtest rcs0: SUCCESS (0.035s)
[284.875919] Starting dynamic subtest: bcs0
[284.892568] Dynamic subtest bcs0: SUCCESS (0.036s)
[284.914108] Starting dynamic subtest: vcs0
[284.925583] Dynamic subtest vcs0: SUCCESS (0.031s)
[284.945629] Starting dynamic subtest: vecs0
[284.955211] Dynamic subtest vecs0: SUCCESS (0.030s)
[284.956972] Subtest u-lateslice: SUCCESS (0.137s)
[285.317945] [069/140] (726s left) gem_ringfill (engines-basic)
[285.740616] Starting subtest: engines-basic
[285.742639] Starting dynamic subtest: rcs0
[285.769853] Dynamic subtest rcs0: SUCCESS (0.028s)
[285.791318] Starting dynamic subtest: bcs0
[285.795400] Dynamic subtest bcs0: SUCCESS (0.023s)
[285.816517] Starting dynamic subtest: vcs0
[285.818136] Dynamic subtest vcs0: SUCCESS (0.018s)
[285.838077] Starting dynamic subtest: vecs0
[285.840101] Dynamic subtest vecs0: SUCCESS (0.024s)
[285.841905] Subtest engines-basic: SUCCESS (0.098s)
[285.933142] [070/140] (726s left) kms_flip (2x-plain-flip-fb-recreate)
[286.154919] Starting subtest: 2x-plain-flip-fb-recreate
[286.157560] Subtest 2x-plain-flip-fb-recreate: SKIP (0.003s)
[286.266400] [071/140] (726s left) kms_chamelium_color (ctm-0-25)
[286.510257] Subtest ctm-0-25: SKIP (0.000s)
[286.566422] [072/140] (725s left) kms_ccs (pipe-d-bad-pixel-format-y-tiled-gen12-rc-ccs)
[287.032627] Subtest pipe-D-bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[287.354278] [073/140] (725s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[287.604931] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[287.607692] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[287.702905] [074/140] (724s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[287.954374] Starting subtest: yf-tiled-16bpp-rotate-180
[291.464493] Subtest yf-tiled-16bpp-rotate-180: SUCCESS (3.509s)
[291.787988] [075/140] (720s left) gen7_exec_parse (basic-allocation)
[291.939445] Subtest basic-allocation: SKIP (0.000s)
[291.986114] [076/140] (720s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[292.465193] Starting subtest: pipe-C-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[292.470431] Subtest pipe-C-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[292.794099] [077/140] (719s left) syncobj_timeline (multi-wait-all-available-submitted)
[292.935650] Starting subtest: multi-wait-all-available-submitted
[292.937134] Subtest multi-wait-all-available-submitted: SUCCESS (0.002s)
[293.027593] [078/140] (719s left) kms_sequence (get-busy)
[293.259057] Starting subtest: get-busy
[293.260598] Starting dynamic subtest: pipe-A-DP-1
[295.716634] Dynamic subtest pipe-A-DP-1: SUCCESS (2.456s)
[295.739386] Starting dynamic subtest: pipe-B-DP-1
[298.354313] Dynamic subtest pipe-B-DP-1: SUCCESS (2.634s)
[298.377044] Starting dynamic subtest: pipe-C-DP-1
[301.017648] Dynamic subtest pipe-C-DP-1: SUCCESS (2.661s)
[301.018955] Subtest get-busy: SUCCESS (7.761s)
[301.338810] [079/140] (711s left) kms_flip (2x-flip-vs-suspend)
[301.556747] Starting subtest: 2x-flip-vs-suspend
[301.559343] Subtest 2x-flip-vs-suspend: SKIP (0.003s)
[301.672524] [080/140] (711s left) syncobj_wait (single-wait-submitted)
[301.816329] Starting subtest: single-wait-submitted
[301.923734] Subtest single-wait-submitted: SUCCESS (0.108s)
[302.004072] [081/140] (710s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[302.240500] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[302.241826] Starting dynamic subtest: pipe-A-valid-mode
[302.331043] Dynamic subtest pipe-A-valid-mode: SKIP (0.087s)
[302.334609] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SKIP (0.090s)
[302.644892] [082/140] (710s left) gem_ctx_sseu (mmap-args)
[302.794030] Subtest mmap-args: SKIP (0.000s)
[302.846302] [083/140] (710s left) gem_exec_reloc (basic-cpu-gtt)
[302.994352] Starting subtest: basic-cpu-gtt
[303.012336] Subtest basic-cpu-gtt: SUCCESS (0.018s)
[303.195289] [084/140] (709s left) kms_flip (flip-vs-fences)
[303.413620] Starting subtest: flip-vs-fences
[303.416961] Starting dynamic subtest: A-DP1
[314.018161] Dynamic subtest A-DP1: SUCCESS (10.600s)
[314.038025] Starting dynamic subtest: B-DP1
[324.898329] Dynamic subtest B-DP1: SUCCESS (10.876s)
[324.918097] Starting dynamic subtest: C-DP1
[335.508980] Dynamic subtest C-DP1: SUCCESS (10.610s)
[335.510778] Subtest flip-vs-fences: SUCCESS (32.096s)
[335.952373] [085/140] (676s left) i915_pm_rps (basic-api)
[336.099281] Starting subtest: basic-api
[336.106516] Subtest basic-api: SUCCESS (0.007s)
[336.216391] [086/140] (676s left) kms_cursor_crc (cursor-random-64x21)
[336.456581] Starting subtest: cursor-random-64x21
[336.468280] Starting dynamic subtest: pipe-A-DP-1
[340.255163] Dynamic subtest pipe-A-DP-1: SUCCESS (3.786s)
[340.342678] Starting dynamic subtest: pipe-C-DP-1
[344.236158] Dynamic subtest pipe-C-DP-1: SUCCESS (3.913s)
[344.237446] Subtest cursor-random-64x21: SUCCESS (7.780s)
[344.596407] [087/140] (668s left) kms_pm_rpm (modeset-pc8-residency-stress)
[346.407471] Starting subtest: modeset-pc8-residency-stress
[346.412355] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[346.909680] [088/140] (666s left) kms_flip (plain-flip-interruptible)
[347.144674] Starting subtest: plain-flip-interruptible
[347.149292] Starting dynamic subtest: A-DP1
[348.325476] Dynamic subtest A-DP1: SUCCESS (1.175s)
[348.347753] Starting dynamic subtest: B-DP1
[349.417653] Dynamic subtest B-DP1: SUCCESS (1.091s)
[349.440433] Starting dynamic subtest: C-DP1
[350.532926] Dynamic subtest C-DP1: SUCCESS (1.112s)
[350.535088] Subtest plain-flip-interruptible: SUCCESS (3.389s)
[350.958363] [089/140] (662s left) gem_exec_nop (basic-parallel)
[351.121915] Starting subtest: basic-parallel
[354.267075] Subtest basic-parallel: SUCCESS (3.145s)
[354.488752] [090/140] (658s left) gem_eio (in-flight-contexts-1us)
[354.662125] Starting subtest: in-flight-contexts-1us
[366.197741] Subtest in-flight-contexts-1us: SUCCESS (11.544s)
[366.406694] [091/140] (646s left) syncobj_wait (invalid-single-wait-unsubmitted)
[366.547223] Starting subtest: invalid-single-wait-unsubmitted
[366.549031] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[366.639338] [092/140] (646s left) kms_vblank (wait-forked-hang)
[366.870202] Starting subtest: wait-forked-hang
[366.873930] Starting dynamic subtest: pipe-A-DP-1
[369.234155] Dynamic subtest pipe-A-DP-1: SUCCESS (2.356s)
[369.258469] Starting dynamic subtest: pipe-C-DP-1
[371.785970] Dynamic subtest pipe-C-DP-1: SUCCESS (2.549s)
[371.787756] Subtest wait-forked-hang: SUCCESS (4.918s)
[372.126269] [093/140] (641s left) kms_prop_blob (blob-prop-lifetime)
[372.265134] Starting subtest: blob-prop-lifetime
[372.266523] Subtest blob-prop-lifetime: SUCCESS (0.002s)
[372.358210] [094/140] (640s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[372.612680] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[374.019352] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.405s)
[374.159833] [095/140] (639s left) gen9_exec_parse (bb-start-far)
[374.324847] Starting subtest: bb-start-far
[374.328488] Subtest bb-start-far: SUCCESS (0.004s)
[374.542517] [096/140] (638s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[374.784319] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[374.786851] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[374.892569] [097/140] (638s left) kms_plane_lowres (tiling-y)
[375.123945] Starting subtest: tiling-y
[375.125397] Starting dynamic subtest: pipe-A-DP-1
[381.381205] Dynamic subtest pipe-A-DP-1: SUCCESS (6.256s)
[381.497570] Starting dynamic subtest: pipe-B-DP-1
[387.598190] Dynamic subtest pipe-B-DP-1: SUCCESS (6.119s)
[387.735057] Starting dynamic subtest: pipe-C-DP-1
[391.908378] Dynamic subtest pipe-C-DP-1: SUCCESS (4.195s)
[392.019427] Subtest tiling-y: SUCCESS (16.897s)
[392.498358] [098/140] (620s left) gem_userptr_blits (sync-overlap)
[392.684870] Starting subtest: sync-overlap
[392.686372] Subtest sync-overlap: SUCCESS (0.000s)
[392.828428] [099/140] (620s left) gem_ccs (ctrl-surf-copy)
[392.974437] Subtest ctrl-surf-copy: SKIP (0.000s)
[393.029571] [100/140] (620s left) gem_eio (banned)
[393.189385] Starting subtest: banned
[393.315849] Subtest banned: SUCCESS (0.131s)
[393.511485] [101/140] (620s left) kms_vblank (ts-continuation-dpms-suspend)
[393.745686] Starting subtest: ts-continuation-dpms-suspend
[393.749693] Starting dynamic subtest: pipe-A-DP-1
[410.338198] Dynamic subtest pipe-A-DP-1: SUCCESS (1.777s)
[410.372067] Starting dynamic subtest: pipe-C-DP-1
[428.114313] Dynamic subtest pipe-C-DP-1: SUCCESS (1.937s)
[428.116880] Subtest ts-continuation-dpms-suspend: SUCCESS (3.726s)
[434.544606] [102/140] (615s left) sw_sync (sync_multi_producer_single_consumer)
[434.691045] Starting subtest: sync_multi_producer_single_consumer
[435.769523] Subtest sync_multi_producer_single_consumer: SUCCESS (1.078s)
[435.846792] [103/140] (614s left) kms_cursor_crc (cursor-offscreen-512x512)
[436.094619] Starting subtest: cursor-offscreen-512x512
[436.100334] Subtest cursor-offscreen-512x512: SKIP (0.003s)
[436.212313] [104/140] (614s left) gem_mmap_gtt (basic-small-copy)
[436.353727] Starting subtest: basic-small-copy
[439.442028] Subtest basic-small-copy: SUCCESS (3.087s)
[439.531673] [105/140] (610s left) kms_plane_alpha_blend (alpha-transparent-fb)
[439.907483] Starting subtest: alpha-transparent-fb
[440.224711] Starting dynamic subtest: pipe-A-DP-1
[441.004757] Dynamic subtest pipe-A-DP-1: FAIL (0.405s)
[441.030248] Starting dynamic subtest: pipe-C-DP-1
[441.643189] Dynamic subtest pipe-C-DP-1: FAIL (0.655s)
[441.644923] Subtest alpha-transparent-fb: FAIL (1.387s)
[441.731116] [106/140] (608s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[442.486036] Starting subtest: syncobj-backward-timeline-chain-engines
[442.613607] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.130s)
[442.857955] [107/140] (607s left) core_auth (getclient-simple)
[442.993089] Starting subtest: getclient-simple
[442.999265] Subtest getclient-simple: SUCCESS (0.004s)
[443.089511] [108/140] (607s left) kms_getfb (getfb-handle-protection)
[443.231715] Starting subtest: getfb-handle-protection
[443.273910] Subtest getfb-handle-protection: SUCCESS (0.044s)
[443.390684] [109/140] (607s left) syncobj_eventfd (binary-wait)
[443.550329] Starting subtest: binary-wait
[443.561574] Subtest binary-wait: SUCCESS (0.011s)
[443.657919] [110/140] (606s left) kms_chamelium_hpd (hdmi-hpd-fast)
[443.996635] Subtest hdmi-hpd-fast: SKIP (0.000s)
[444.303972] [111/140] (606s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[444.546505] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt
[444.549481] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SKIP (0.000s)
[444.646302] [112/140] (606s left) kms_color (legacy-gamma)
[444.878736] Starting subtest: legacy-gamma
[444.880169] Starting dynamic subtest: pipe-A
[445.467629] Dynamic subtest pipe-A: SUCCESS (0.585s)
[445.515225] Starting dynamic subtest: pipe-B
[446.225701] Dynamic subtest pipe-B: SUCCESS (0.760s)
[446.255020] Starting dynamic subtest: pipe-C
[446.983269] Dynamic subtest pipe-C: SUCCESS (0.754s)
[446.984971] Subtest legacy-gamma: SUCCESS (2.106s)
[447.337059] [113/140] (603s left) kms_async_flips (crc)
[447.573793] Starting subtest: crc
[447.756415] Starting dynamic subtest: pipe-A-DP-1
[450.184244] Dynamic subtest pipe-A-DP-1: SUCCESS (2.429s)
[450.400236] Starting dynamic subtest: pipe-B-DP-1
[452.721884] Dynamic subtest pipe-B-DP-1: SUCCESS (2.343s)
[452.943335] Starting dynamic subtest: pipe-C-DP-1
[455.259136] Dynamic subtest pipe-C-DP-1: SUCCESS (2.347s)
[455.260539] Subtest crc: SUCCESS (7.689s)
[455.767989] [114/140] (595s left) gem_softpin (allocator-evict)
[455.918679] Starting subtest: allocator-evict
[455.920551] Starting dynamic subtest: rcs0
[491.004228] Dynamic subtest rcs0: SUCCESS (35.083s)
[491.030631] Starting dynamic subtest: bcs0
[528.731520] Dynamic subtest bcs0: SUCCESS (37.724s)
[528.759394] Starting dynamic subtest: vcs0
[565.443196] Dynamic subtest vcs0: SUCCESS (36.709s)
[565.468686] Starting dynamic subtest: vecs0
[602.295155] Dynamic subtest vecs0: SUCCESS (36.849s)
[602.297728] Subtest allocator-evict: SUCCESS (146.377s)
[602.386402] [115/140] (448s left) kms_ccs (pipe-a-crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[602.885044] Starting subtest: pipe-A-crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[602.887755] Subtest pipe-A-crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SKIP (0.001s)
[603.342346] [116/140] (447s left) prime_busy (hang)
[603.491613] Starting subtest: hang
[603.493566] Starting dynamic subtest: rcs0
[610.542622] Dynamic subtest rcs0: SUCCESS (7.046s)
[610.567577] Starting dynamic subtest: bcs0
[617.519663] Dynamic subtest bcs0: SUCCESS (6.973s)
[617.545307] Starting dynamic subtest: vcs0
[624.495167] Dynamic subtest vcs0: SUCCESS (6.973s)
[624.520181] Starting dynamic subtest: vecs0
[631.534349] Dynamic subtest vecs0: SUCCESS (7.038s)
[631.536120] Subtest hang: SUCCESS (28.041s)
[631.642971] [117/140] (419s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[631.890573] Starting subtest: psr-rgb565-draw-blt
[631.893503] Subtest psr-rgb565-draw-blt: SKIP (0.000s)
[631.992742] [118/140] (419s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[632.252748] Starting subtest: x-tiled-64bpp-rotate-90
[632.345176] Subtest x-tiled-64bpp-rotate-90: SKIP (0.090s)
[632.460131] [119/140] (418s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[632.709689] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[632.712954] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[632.812591] [120/140] (418s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[633.060098] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[633.062801] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[633.159588] [121/140] (417s left) syncobj_timeline (wait-for-submit-delayed-submit)
[633.320555] Starting subtest: wait-for-submit-delayed-submit
[633.425176] Subtest wait-for-submit-delayed-submit: SUCCESS (0.104s)
[633.527497] [122/140] (417s left) gem_create (create-ext-placement-all)
[633.796774] Starting subtest: create-ext-placement-all
[633.798596] Subtest create-ext-placement-all: SUCCESS (0.000s)
[633.910386] [123/140] (417s left) kms_ccs (pipe-b-crc-primary-basic-y-tiled-ccs)
[634.300803] Starting subtest: pipe-B-crc-primary-basic-y-tiled-ccs
[635.136864] Subtest pipe-B-crc-primary-basic-y-tiled-ccs: SUCCESS (0.836s)
[635.642037] [124/140] (415s left) v3d/v3d_perfmon (create-perfmon-exceed)
[635.845207] Subtest create-perfmon-exceed: SKIP (0.000s)
[635.921968] [125/140] (415s left) gem_exec_schedule (preempt-queue)
[636.092985] Starting subtest: preempt-queue
[636.094703] Starting dynamic subtest: rcs0
[636.496164] Dynamic subtest rcs0: SUCCESS (0.400s)
[636.525059] Starting dynamic subtest: bcs0
[636.915774] Dynamic subtest bcs0: SUCCESS (0.417s)
[636.949976] Starting dynamic subtest: vcs0
[637.320370] Dynamic subtest vcs0: SUCCESS (0.401s)
[637.350566] Starting dynamic subtest: vecs0
[637.718469] Dynamic subtest vecs0: SUCCESS (0.394s)
[637.721020] Subtest preempt-queue: SUCCESS (1.626s)
[638.090450] [126/140] (413s left) gem_render_copy (y-tiled)
[638.253149] Starting subtest: y-tiled
[638.255074] Starting dynamic subtest: smem
[638.544076] Dynamic subtest smem: SUCCESS (0.287s)
[638.545508] Subtest y-tiled: SUCCESS (0.290s)
[638.759004] [127/140] (412s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[638.901797] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[638.958371] [128/140] (412s left) kms_lease (page-flip-implicit-plane)
[639.192633] Starting subtest: page-flip-implicit-plane
[639.194564] Starting dynamic subtest: pipe-A-DP-1
[639.488970] Dynamic subtest pipe-A-DP-1: SUCCESS (0.294s)
[639.519142] Starting dynamic subtest: pipe-B-DP-1
[639.962264] Dynamic subtest pipe-B-DP-1: SUCCESS (0.469s)
[639.994651] Starting dynamic subtest: pipe-C-DP-1
[640.454523] Dynamic subtest pipe-C-DP-1: SUCCESS (0.487s)
[640.456191] Subtest page-flip-implicit-plane: SUCCESS (1.266s)
[640.787155] [129/140] (410s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-render)
[641.030831] Starting subtest: psr-1p-offscren-pri-shrfb-draw-render
[641.033904] Subtest psr-1p-offscren-pri-shrfb-draw-render: SKIP (0.000s)
[641.137547] [130/140] (410s left) gem_exec_balancer (full-late-pulse)
[641.301266] Starting subtest: full-late-pulse
[645.151743] Subtest full-late-pulse: SUCCESS (3.850s)
[648.955041] [131/140] (402s left) v3d/v3d_submit_csd (bad-multisync-in-sync)
[649.160572] Subtest bad-multisync-in-sync: SKIP (0.000s)
[649.236314] [132/140] (402s left) gem_exec_flush (basic-wb-prw-default)
[649.401083] Starting subtest: basic-wb-prw-default
[654.873066] Subtest basic-wb-prw-default: SUCCESS (5.473s)
[655.086552] [133/140] (396s left) kms_ccs (pipe-c-crc-primary-basic-y-tiled-gen12-mc-ccs)
[655.509833] Starting subtest: pipe-C-crc-primary-basic-y-tiled-gen12-mc-ccs
[655.512907] Subtest pipe-C-crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.001s)
[655.887965] [134/140] (395s left) kms_ccs (pipe-b-missing-ccs-buffer-yf-tiled-ccs)
[656.298733] Starting subtest: pipe-B-missing-ccs-buffer-yf-tiled-ccs
[656.300262] Subtest pipe-B-missing-ccs-buffer-yf-tiled-ccs: SUCCESS (0.001s)
[656.711915] [135/140] (395s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[656.870149] Starting subtest: mixed-tiled-to-y-tiled-ccs
[656.871621] Starting dynamic subtest: smem
[657.177058] Dynamic subtest smem: SUCCESS (0.302s)
[657.178499] Subtest mixed-tiled-to-y-tiled-ccs: SUCCESS (0.304s)
[657.377771] [136/140] (394s left) kms_plane_scaling (2x-scaler-multi-pipe)
[657.618183] Starting subtest: 2x-scaler-multi-pipe
[657.624281] Subtest 2x-scaler-multi-pipe: SKIP (0.004s)
[657.709442] [137/140] (394s left) api_intel_bb (destroy-bb)
[657.852102] Starting subtest: destroy-bb
[657.878634] Subtest destroy-bb: SUCCESS (0.026s)
[657.992386] [138/140] (393s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[658.231416] Starting subtest: cursorB-vs-flipA-legacy
[658.235170] Subtest cursorB-vs-flipA-legacy: SKIP (0.004s)
[658.325606] [139/140] (393s left) gem_exec_fence (syncobj-timeline-export)
[659.070703] Starting subtest: syncobj-timeline-export
[659.078306] Subtest syncobj-timeline-export: SUCCESS (0.008s)
[659.308548] [140/140] (392s left) gem_media_vme
[659.525250] Closing watchdogs
Done.