[52.090887] Initializing watchdogs
[52.092183]   /dev/watchdog0
[52.107952] [001/138] (960s left) gem_exec_schedule (pi-common)
[52.692429] Starting subtest: pi-common
[52.694180] Starting dynamic subtest: rcs0
[52.887333] Dynamic subtest rcs0: SUCCESS (0.195s)
[52.909453] Starting dynamic subtest: bcs0
[52.986309] Dynamic subtest bcs0: SUCCESS (0.095s)
[53.003295] Starting dynamic subtest: vcs0
[53.076777] Dynamic subtest vcs0: SUCCESS (0.094s)
[53.093485] Starting dynamic subtest: vecs0
[53.174212] Dynamic subtest vecs0: SUCCESS (0.094s)
[53.175519] Subtest pi-common: SUCCESS (0.479s)
[53.379123] [002/138] (959s left) kms_getfb (getfb-handle-zero)
[53.521789] Starting subtest: getfb-handle-zero
[53.523533] Subtest getfb-handle-zero: SUCCESS (0.000s)
[53.621705] [003/138] (959s left) gem_mmap_wc (set-cache-level)
[53.766704] Starting subtest: set-cache-level
[53.772775] Subtest set-cache-level: SUCCESS (0.008s)
[53.855485] [004/138] (958s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[54.104933] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[54.107712] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[54.205633] [005/138] (958s left) kms_ccs (pipe-d-crc-primary-basic-4-tiled-dg2-rc-ccs)
[54.685354] Subtest pipe-D-crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[55.007553] [006/138] (957s left) vgem_basic (create)
[55.156323] Starting subtest: create
[55.157653] Subtest create: SUCCESS (0.001s)
[55.225792] [007/138] (957s left) drm_fdinfo (virtual-busy-hang)
[55.411477] Starting subtest: virtual-busy-hang
[59.642738] Subtest virtual-busy-hang: SUCCESS (4.233s)
[59.721612] [008/138] (953s left) perf (low-oa-exponent-permissions)
[59.969715] Starting subtest: low-oa-exponent-permissions
[60.128954] Subtest low-oa-exponent-permissions: SUCCESS (0.159s)
[60.172683] [009/138] (952s left) prime_mmap (test_refcounting)
[60.314193] Starting subtest: test_refcounting
[60.315430] Starting dynamic subtest: test_refcounting-smem
[60.320870] Dynamic subtest test_refcounting-smem: SUCCESS (0.008s)
[60.322711] Subtest test_refcounting: SUCCESS (0.008s)
[60.421849] [010/138] (952s left) kms_big_fb (yf-tiled-addfb)
[60.688492] Starting subtest: yf-tiled-addfb
[60.689869] Subtest yf-tiled-addfb: SUCCESS (0.000s)
[60.770950] [011/138] (952s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[61.006324] Starting subtest: sprite-rotation-90-pos-100-0
[67.262559] Subtest sprite-rotation-90-pos-100-0: SUCCESS (6.256s)
[67.690166] [012/138] (945s left) gem_ccs (suspend-resume)
[67.842908] Subtest suspend-resume: SKIP (0.000s)
[67.888317] [013/138] (945s left) prime_self_import (export-vs-gem_close-race)
[68.024151] Starting subtest: export-vs-gem_close-race
[73.052958] Subtest export-vs-gem_close-race: SUCCESS (5.027s)
[73.136379] [014/138] (939s left) kms_psr (sprite_blt)
[73.277654] Subtest sprite_blt: SKIP (0.000s)
[73.319953] [015/138] (939s left) kms_plane_scaling (plane-downscale-factor-0-25-with-pixel-format)
[73.552715] Starting subtest: plane-downscale-factor-0-25-with-pixel-format
[73.560504] Starting dynamic subtest: pipe-A-DP-1
[73.602162] Dynamic subtest pipe-A-DP-1: SKIP (0.042s)
[73.622369] Starting dynamic subtest: pipe-B-DP-1
[73.699092] Dynamic subtest pipe-B-DP-1: SKIP (0.088s)
[73.719284] Starting dynamic subtest: pipe-C-DP-1
[73.804993] Dynamic subtest pipe-C-DP-1: SKIP (0.094s)
[73.807590] Subtest plane-downscale-factor-0-25-with-pixel-format: SKIP (0.249s)
[73.921115] [016/138] (939s left) gem_exec_fence (submit)
[74.222321] Starting subtest: submit
[74.223951] Starting dynamic subtest: rcs0
[74.248493] Dynamic subtest rcs0: SUCCESS (0.025s)
[74.269278] Starting dynamic subtest: bcs0
[74.276146] Dynamic subtest bcs0: SUCCESS (0.027s)
[74.293355] Starting dynamic subtest: vcs0
[74.297843] Dynamic subtest vcs0: SUCCESS (0.023s)
[74.319657] Starting dynamic subtest: vecs0
[74.321661] Dynamic subtest vecs0: SUCCESS (0.022s)
[74.323175] Subtest submit: SUCCESS (0.099s)
[74.989953] [017/138] (938s left) gem_exec_flush (basic-batch-kernel-default-uc)
[75.160035] Starting subtest: basic-batch-kernel-default-uc
[80.894967] Subtest basic-batch-kernel-default-uc: SUCCESS (5.734s)
[81.089219] [018/138] (932s left) gem_mmap (bad-size)
[81.231663] Starting subtest: bad-size
[81.233314] Subtest bad-size: SUCCESS (0.002s)
[81.336919] [019/138] (931s left) gem_exec_store (basic)
[81.500286] Starting subtest: basic
[81.574183] Subtest basic: SUCCESS (0.074s)
[81.773457] [020/138] (931s left) vc4/vc4_perfmon (destroy-invalid-perfmon)
[81.974523] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[82.039738] [021/138] (931s left) syncobj_timeline (reset-signaled)
[82.180619] Starting subtest: reset-signaled
[82.182331] Subtest reset-signaled: SUCCESS (0.001s)
[82.252538] [022/138] (931s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[82.502536] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[82.504939] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[82.602673] [023/138] (930s left) gem_ctx_persistence (process)
[82.749296] Starting subtest: process
[82.870394] Subtest process: SUCCESS (0.120s)
[82.953712] [024/138] (930s left) kms_cursor_crc (cursor-random-64x64)
[83.194773] Starting subtest: cursor-random-64x64
[83.205509] Starting dynamic subtest: pipe-A-DP-1
[87.186335] Dynamic subtest pipe-A-DP-1: SUCCESS (3.977s)
[87.278525] Starting dynamic subtest: pipe-C-DP-1
[91.177446] Dynamic subtest pipe-C-DP-1: SUCCESS (3.913s)
[91.178694] Subtest cursor-random-64x64: SUCCESS (7.983s)
[91.550786] [025/138] (921s left) kms_ccs (pipe-b-crc-primary-basic-y-tiled-gen12-rc-ccs)
[91.934418] Starting subtest: pipe-B-crc-primary-basic-y-tiled-gen12-rc-ccs
[91.937184] Subtest pipe-B-crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.001s)
[92.340232] [026/138] (921s left) perf_pmu (busy-idle-check-all)
[92.491278] Starting subtest: busy-idle-check-all
[92.493579] Starting dynamic subtest: rcs0
[93.121738] Dynamic subtest rcs0: SUCCESS (0.633s)
[93.138998] Starting dynamic subtest: bcs0
[93.751722] Dynamic subtest bcs0: SUCCESS (0.630s)
[93.768787] Starting dynamic subtest: vcs0
[94.381219] Dynamic subtest vcs0: SUCCESS (0.628s)
[94.398238] Starting dynamic subtest: vecs0
[95.011444] Dynamic subtest vecs0: SUCCESS (0.630s)
[95.013049] Subtest busy-idle-check-all: SUCCESS (2.521s)
[95.092110] [027/138] (918s left) i915_pm_rpm (sysfs-read)
[96.924273] Starting subtest: sysfs-read
[97.416791] Subtest sysfs-read: SUCCESS (0.489s)
[97.911307] [028/138] (915s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[98.149321] Starting subtest: cursorB-vs-flipA-atomic
[98.153507] Subtest cursorB-vs-flipA-atomic: SKIP (0.004s)
[98.238978] [029/138] (915s left) kms_flip (2x-flip-vs-modeset)
[98.467021] Starting subtest: 2x-flip-vs-modeset
[98.469421] Subtest 2x-flip-vs-modeset: SKIP (0.003s)
[98.573511] [030/138] (915s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[98.819609] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[101.070791] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (2.254s)
[101.223516] [031/138] (912s left) syncobj_basic (bad-destroy-pad)
[101.365662] Starting subtest: bad-destroy-pad
[101.367208] Subtest bad-destroy-pad: SUCCESS (0.000s)
[101.441119] [032/138] (912s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[101.684113] Starting subtest: fbcpsr-tiling-4
[101.686682] Subtest fbcpsr-tiling-4: SKIP (0.000s)
[101.772148] [033/138] (911s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[102.014302] Starting subtest: psr-rgb565-draw-mmap-gtt
[102.016835] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[102.105836] [034/138] (911s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[102.346649] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[102.349176] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[102.422429] [035/138] (911s left) syncobj_eventfd (binary-wait-before-signal)
[102.563669] Starting subtest: binary-wait-before-signal
[102.596243] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[102.671258] [036/138] (911s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite)
[102.917205] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite
[102.919678] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[103.005644] [037/138] (910s left) gem_workarounds (reset-context)
[103.178962] Starting subtest: reset-context
[103.204946] Subtest reset-context: SUCCESS (0.025s)
[103.306770] [038/138] (910s left) kms_chamelium_audio (hdmi-audio)
[103.634408] Subtest hdmi-audio: SKIP (0.000s)
[103.921457] [039/138] (910s left) kms_addfb_basic (basic-y-tiled-legacy)
[104.064419] Starting subtest: basic-y-tiled-legacy
[104.065803] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[104.248232] [040/138] (909s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[104.497940] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[104.500575] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[104.581871] [041/138] (909s left) gem_exec_params (invalid-ring)
[104.730401] Starting subtest: invalid-ring
[104.731828] Subtest invalid-ring: SUCCESS (0.001s)
[104.830678] [042/138] (909s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[104.991024] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[104.992352] Starting dynamic subtest: smem
[105.322137] Dynamic subtest smem: SUCCESS (0.331s)
[105.323645] Subtest yf-tiled-ccs-to-y-tiled-ccs: SUCCESS (0.331s)
[105.517477] [043/138] (908s left) kms_ccs (pipe-c-ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[105.969410] Starting subtest: pipe-C-ccs-on-another-bo-y-tiled-gen12-mc-ccs
[105.972168] Subtest pipe-C-ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[106.299831] [044/138] (907s left) kms_chamelium_color (degamma)
[106.540086] Subtest degamma: SKIP (0.000s)
[106.582752] [045/138] (907s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[106.832059] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[106.834563] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[106.916491] [046/138] (907s left) i915_query (hwconfig_table)
[107.057456] Starting subtest: hwconfig_table
[107.060398] Subtest hwconfig_table: SKIP (0.000s)
[107.130948] [047/138] (907s left) kms_big_fb (x-tiled-addfb-size-offset-overflow)
[107.388919] Starting subtest: x-tiled-addfb-size-offset-overflow
[107.391985] Subtest x-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[107.498136] [048/138] (906s left) gem_spin_batch (engines)
[107.662903] Starting subtest: engines
[107.664840] Starting dynamic subtest: rcs0
[110.989124] Dynamic subtest rcs0: SUCCESS (3.324s)
[111.005863] Starting dynamic subtest: bcs0
[114.312283] Dynamic subtest bcs0: SUCCESS (3.323s)
[114.328639] Starting dynamic subtest: vcs0
[117.633513] Dynamic subtest vcs0: SUCCESS (3.321s)
[117.650061] Starting dynamic subtest: vecs0
[120.955039] Dynamic subtest vecs0: SUCCESS (3.321s)
[120.956672] Subtest engines: SUCCESS (13.290s)
[121.166712] [049/138] (893s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[121.404618] Starting subtest: cursorA-vs-flipA-legacy
[132.441172] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.037s)
[132.772931] [050/138] (881s left) kms_vblank (query-idle-hang)
[133.008728] Starting subtest: query-idle-hang
[133.012591] Starting dynamic subtest: pipe-A-DP-1
[140.014376] Dynamic subtest pipe-A-DP-1: SUCCESS (7.004s)
[140.035887] Starting dynamic subtest: pipe-C-DP-1
[146.990393] Dynamic subtest pipe-C-DP-1: SUCCESS (6.970s)
[146.991991] Subtest query-idle-hang: SUCCESS (13.983s)
[147.339448] [051/138] (867s left) kms_plane_scaling (planes-downscale-factor-0-5)
[147.575917] Starting subtest: planes-downscale-factor-0-5
[147.586319] Starting dynamic subtest: pipe-A-DP-1
[147.922715] Dynamic subtest pipe-A-DP-1: SUCCESS (0.337s)
[147.950652] Starting dynamic subtest: pipe-B-DP-1
[148.346311] Dynamic subtest pipe-B-DP-1: SUCCESS (0.413s)
[148.354607] Subtest planes-downscale-factor-0-5: SUCCESS (0.780s)
[148.769799] [052/138] (865s left) gem_ctx_persistence (engines-hostile-preempt)
[148.922333] Starting subtest: engines-hostile-preempt
[148.923866] Starting dynamic subtest: rcs0
[149.018103] Dynamic subtest rcs0: SUCCESS (0.096s)
[149.036267] Starting dynamic subtest: bcs0
[149.116602] Dynamic subtest bcs0: SUCCESS (0.097s)
[149.134852] Starting dynamic subtest: vcs0
[149.207233] Dynamic subtest vcs0: SUCCESS (0.090s)
[149.224772] Starting dynamic subtest: vecs0
[149.295878] Dynamic subtest vecs0: SUCCESS (0.088s)
[149.297324] Subtest engines-hostile-preempt: SUCCESS (0.374s)
[149.385328] [053/138] (865s left) gem_ctx_exec (basic-invalid-context)
[149.530308] Starting subtest: basic-invalid-context
[149.536392] Subtest basic-invalid-context: SUCCESS (0.007s)
[149.652714] [054/138] (864s left) kms_flip (flip-vs-panning)
[149.876427] Starting subtest: flip-vs-panning
[149.877746] Starting dynamic subtest: A-DP1
[160.470993] Dynamic subtest A-DP1: SUCCESS (10.592s)
[160.488083] Starting dynamic subtest: B-DP1
[170.976567] Dynamic subtest B-DP1: SUCCESS (10.506s)
[170.993852] Starting dynamic subtest: C-DP1
[181.499345] Dynamic subtest C-DP1: SUCCESS (10.521s)
[181.503288] Subtest flip-vs-panning: SUCCESS (31.628s)
[181.924132] [055/138] (832s left) kms_ccs (pipe-d-missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[182.399032] Subtest pipe-D-missing-ccs-buffer-4-tiled-mtl-rc-ccs: SKIP (0.000s)
[182.717301] [056/138] (831s left) kms_cursor_crc (cursor-sliding-512x512)
[182.971526] Starting subtest: cursor-sliding-512x512
[182.976443] Subtest cursor-sliding-512x512: SKIP (0.003s)
[183.083638] [057/138] (831s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[183.349928] Starting subtest: x-tiled-64bpp-rotate-90
[183.437288] Subtest x-tiled-64bpp-rotate-90: SKIP (0.086s)
[183.547075] [058/138] (831s left) v3d/v3d_submit_csd (multiple-job-submission)
[183.750192] Subtest multiple-job-submission: SKIP (0.000s)
[183.816695] [059/138] (830s left) gem_eio (create-ext)
[183.980953] Starting subtest: create-ext
[184.015779] Subtest create-ext: SUCCESS (0.037s)
[184.218365] [060/138] (830s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[184.477488] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[184.496195] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.019s)
[184.615346] [061/138] (830s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[184.875457] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[184.878199] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[184.964924] [062/138] (829s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[185.215880] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[185.219405] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[185.315581] [063/138] (829s left) gem_lmem_swapping (parallel-random)
[185.466203] Subtest parallel-random: SKIP (0.000s)
[185.515257] [064/138] (829s left) kms_ccs (pipe-c-random-ccs-data-yf-tiled-ccs)
[185.956927] Starting subtest: pipe-C-random-ccs-data-yf-tiled-ccs
[185.960672] Subtest pipe-C-random-ccs-data-yf-tiled-ccs: SKIP (0.000s)
[186.302380] [065/138] (828s left) kms_pm_backlight (fade)
[186.537600] Subtest fade: SKIP (0.000s)
[186.587913] [066/138] (828s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[186.823629] Starting subtest: modeset-transition-nonblocking-fencing
[186.824999] Starting dynamic subtest: 1x-outputs
[191.737077] Dynamic subtest 1x-outputs: SUCCESS (4.916s)
[191.854888] Subtest modeset-transition-nonblocking-fencing: SUCCESS (5.031s)
[192.193777] [067/138] (822s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-submitted)
[192.342488] Starting subtest: invalid-multi-wait-available-unsubmitted-submitted
[192.343847] Subtest invalid-multi-wait-available-unsubmitted-submitted: SUCCESS (0.002s)
[192.425460] [068/138] (822s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[192.669507] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[194.789754] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SUCCESS (2.121s)
[194.926174] [069/138] (820s left) kms_ccs (pipe-d-bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[195.394063] Subtest pipe-D-bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s)
[195.712669] [070/138] (819s left) kms_ccs (pipe-a-bad-pixel-format-4-tiled-mtl-mc-ccs)
[195.963392] Starting subtest: pipe-A-bad-pixel-format-4-tiled-mtl-mc-ccs
[195.966581] Subtest pipe-A-bad-pixel-format-4-tiled-mtl-mc-ccs: SKIP (0.000s)
[196.520870] [071/138] (818s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[196.766188] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[196.768686] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[196.855988] [072/138] (818s left) v3d/v3d_get_param (get-bad-flags)
[197.053507] Subtest get-bad-flags: SKIP (0.000s)
[197.120812] [073/138] (818s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[197.377887] Starting subtest: fbcpsr-suspend
[197.380966] Subtest fbcpsr-suspend: SKIP (0.000s)
[197.471796] [074/138] (817s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[197.705248] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[197.706491] Starting dynamic subtest: pipe-A-valid-mode
[198.425139] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.718s)
[198.426373] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SUCCESS (0.722s)
[198.763379] [075/138] (816s left) gem_mmap_gtt (big-bo-tiledx)
[198.905093] Starting subtest: big-bo-tiledX
[201.025124] Subtest big-bo-tiledX: SUCCESS (2.118s)
[201.269590] [076/138] (814s left) kms_flip (2x-dpms-vs-vblank-race-interruptible)
[201.629614] Starting subtest: 2x-dpms-vs-vblank-race-interruptible
[201.635393] Subtest 2x-dpms-vs-vblank-race-interruptible: SKIP (0.005s)
[201.726852] [077/138] (813s left) sysfs_heartbeat_interval (mixed)
[201.883668] Starting subtest: mixed
[201.885157] Starting dynamic subtest: vecs0
[207.138289] Dynamic subtest vecs0: SUCCESS (5.254s)
[207.160033] Starting dynamic subtest: vcs0
[212.400762] Dynamic subtest vcs0: SUCCESS (5.260s)
[212.422697] Starting dynamic subtest: bcs0
[217.664988] Dynamic subtest bcs0: SUCCESS (5.262s)
[217.686560] Starting dynamic subtest: rcs0
[222.922760] Dynamic subtest rcs0: SUCCESS (5.254s)
[222.924363] Subtest mixed: SUCCESS (21.042s)
[223.009897] [078/138] (792s left) syncobj_wait (multi-wait-signaled)
[223.170162] Starting subtest: multi-wait-signaled
[223.171543] Subtest multi-wait-signaled: SUCCESS (0.001s)
[223.259724] [079/138] (792s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[223.602210] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[223.896660] [080/138] (791s left) kms_psr2_sf (cursor-plane-move-continuous-exceed-sf)
[224.041440] Subtest cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[224.091120] [081/138] (791s left) syncobj_wait (wait-all-delayed-signal)
[224.233218] Starting subtest: wait-all-delayed-signal
[224.334552] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[224.423496] [082/138] (791s left) kms_color (legacy-gamma)
[224.669248] Starting subtest: legacy-gamma
[224.670634] Starting dynamic subtest: pipe-A
[225.247751] Dynamic subtest pipe-A: SUCCESS (0.579s)
[225.269757] Starting dynamic subtest: pipe-B
[225.985754] Dynamic subtest pipe-B: SUCCESS (0.734s)
[226.005909] Starting dynamic subtest: pipe-C
[226.745385] Dynamic subtest pipe-C: SUCCESS (0.759s)
[226.746794] Subtest legacy-gamma: SUCCESS (2.078s)
[227.068955] [083/138] (788s left) kms_universal_plane (cursor-fb-leak)
[227.305346] Starting subtest: cursor-fb-leak
[227.309032] Starting dynamic subtest: pipe-A-DP-1
[227.624105] Dynamic subtest pipe-A-DP-1: SUCCESS (0.317s)
[227.649653] Starting dynamic subtest: pipe-B-DP-1
[228.121980] Dynamic subtest pipe-B-DP-1: SUCCESS (0.492s)
[228.146469] Starting dynamic subtest: pipe-C-DP-1
[228.629382] Dynamic subtest pipe-C-DP-1: SUCCESS (0.502s)
[228.630867] Subtest cursor-fb-leak: SUCCESS (1.323s)
[228.962611] [084/138] (786s left) kms_ccs (pipe-a-ccs-on-another-bo-yf-tiled-ccs)
[229.316639] Starting subtest: pipe-A-ccs-on-another-bo-yf-tiled-ccs
[229.318129] Subtest pipe-A-ccs-on-another-bo-yf-tiled-ccs: SUCCESS (0.002s)
[229.760631] [085/138] (785s left) kms_dsc (dsc-with-formats)
[230.001255] Subtest dsc-with-formats: SKIP (0.000s)
[230.060548] [086/138] (785s left) gem_exec_params (secure-non-master)
[230.207488] Starting subtest: secure-non-master
[230.210636] Subtest secure-non-master: SKIP (0.000s)
[230.309638] [087/138] (785s left) gem_unfence_active_buffers
[230.759613] [088/138] (785s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[231.007937] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[231.013076] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[231.108823] [089/138] (784s left) kms_ccs (pipe-b-bad-pixel-format-4-tiled-dg2-mc-ccs)
[231.479669] Starting subtest: pipe-B-bad-pixel-format-4-tiled-dg2-mc-ccs
[231.482419] Subtest pipe-B-bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[231.885155] [090/138] (784s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[232.363928] Starting subtest: pipe-C-crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[232.369118] Subtest pipe-C-crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SKIP (0.004s)
[232.700345] [091/138] (783s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[232.866627] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[232.869250] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[233.065118] [092/138] (782s left) sw_sync (sync_random_merge)
[233.207360] Starting subtest: sync_random_merge
[233.357361] Subtest sync_random_merge: SUCCESS (0.151s)
[233.431345] [093/138] (782s left) gem_exec_fence (syncobj-wait)
[234.053934] Starting subtest: syncobj-wait
[234.092170] Subtest syncobj-wait: SUCCESS (0.037s)
[234.452711] [094/138] (781s left) gem_mmap_offset (clear)
[234.597998] Starting subtest: clear
[234.600095] Starting dynamic subtest: smem0
[257.126236] Dynamic subtest smem0: SUCCESS (22.522s)
[257.127520] Subtest clear: SUCCESS (22.524s)
[257.466327] [095/138] (758s left) gem_flink_race (flink_name)
[257.608112] Starting subtest: flink_name
[262.981700] Subtest flink_name: SUCCESS (5.375s)
[263.083799] [096/138] (753s left) kms_addfb_basic (unused-offsets)
[263.225684] Starting subtest: unused-offsets
[263.227450] Subtest unused-offsets: SUCCESS (0.000s)
[263.415748] [097/138] (752s left) syncobj_timeline (single-wait-signaled)
[263.561738] Starting subtest: single-wait-signaled
[263.563686] Subtest single-wait-signaled: SUCCESS (0.001s)
[263.650266] [098/138] (752s left) api_intel_allocator (gem-pool)
[263.790153] Starting subtest: gem-pool
[263.806670] Subtest gem-pool: SUCCESS (0.016s)
[263.916640] [099/138] (752s left) gem_flink_basic (double-flink)
[264.057771] Starting subtest: double-flink
[264.059933] Subtest double-flink: SUCCESS (0.000s)
[264.166501] [100/138] (752s left) gem_bad_reloc (negative-reloc-bltcopy)
[264.320332] Starting subtest: negative-reloc-bltcopy
[267.696351] Subtest negative-reloc-bltcopy: SUCCESS (3.375s)
[268.000120] [101/138] (748s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[268.243819] Starting subtest: plane-all-modeset-transition-internal-panels
[268.246339] Subtest plane-all-modeset-transition-internal-panels: SKIP (0.000s)
[268.333905] [102/138] (748s left) gem_exec_capture (capture-invisible)
[268.492643] Starting subtest: capture-invisible
[268.494207] Starting dynamic subtest: smem0
[268.497508] Dynamic subtest smem0: SKIP (0.000s)
[268.499978] Subtest capture-invisible: SKIP (0.002s)
[268.582958] [103/138] (747s left) i915_module_load (reload-with-fault-injection)
[268.719343] Starting subtest: reload-with-fault-injection
[273.295561] Subtest reload-with-fault-injection: SUCCESS (4.576s)
[273.331549] [104/138] (743s left) prime_busy (hang-wait)
[274.697360] Starting subtest: hang-wait
[274.703192] Starting dynamic subtest: rcs0
[281.884971] Dynamic subtest rcs0: SUCCESS (7.179s)
[281.906380] Starting dynamic subtest: bcs0
[288.921229] Dynamic subtest bcs0: SUCCESS (7.035s)
[288.939279] Starting dynamic subtest: vcs0
[295.898298] Dynamic subtest vcs0: SUCCESS (6.972s)
[295.916156] Starting dynamic subtest: vecs0
[302.937553] Dynamic subtest vecs0: SUCCESS (7.036s)
[302.939290] Subtest hang-wait: SUCCESS (28.238s)
[303.087489] [105/138] (713s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[303.326613] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[303.332189] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.003s)
[303.422855] [106/138] (713s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[303.863879] Starting subtest: pipe-B-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[303.870223] Subtest pipe-B-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[304.244786] [107/138] (712s left) gem_exec_reloc (basic-cpu-wc-noreloc)
[304.401256] Starting subtest: basic-cpu-wc-noreloc
[304.417382] Subtest basic-cpu-wc-noreloc: SUCCESS (0.016s)
[304.610700] [108/138] (712s left) kms_ccs (pipe-b-random-ccs-data-y-tiled-gen12-mc-ccs)
[305.003905] Starting subtest: pipe-B-random-ccs-data-y-tiled-gen12-mc-ccs
[305.007842] Subtest pipe-B-random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[305.401250] [109/138] (711s left) api_intel_allocator (default-alignment)
[305.544536] Starting subtest: default-alignment
[305.548414] Subtest default-alignment: SUCCESS (0.006s)
[305.649756] [110/138] (711s left) kms_prop_blob (basic)
[305.790575] Starting subtest: basic
[305.792343] Subtest basic: SUCCESS (0.000s)
[305.883975] [111/138] (710s left) gem_basic (create-close)
[306.024662] Starting subtest: create-close
[306.027632] Subtest create-close: SUCCESS (0.000s)
[306.137407] [112/138] (710s left) kms_invalid_mode (bad-hsync-start)
[306.378827] Starting subtest: bad-hsync-start
[306.381531] Starting dynamic subtest: pipe-A-DP-1
[306.477832] Dynamic subtest pipe-A-DP-1: SUCCESS (0.100s)
[306.500237] Starting dynamic subtest: pipe-B-DP-1
[306.501512] Dynamic subtest pipe-B-DP-1: SUCCESS (0.002s)
[306.527555] Starting dynamic subtest: pipe-C-DP-1
[306.530213] Dynamic subtest pipe-C-DP-1: SUCCESS (0.002s)
[306.532467] Subtest bad-hsync-start: SUCCESS (0.117s)
[306.829746] [113/138] (710s left) v3d/v3d_submit_csd (bad-bo)
[307.026414] Subtest bad-bo: SKIP (0.000s)
[307.095628] [114/138] (709s left) kms_plane_alpha_blend (alpha-7efc)
[307.333431] Starting subtest: alpha-7efc
[307.653848] Starting dynamic subtest: pipe-A-DP-1
[308.119291] Dynamic subtest pipe-A-DP-1: SUCCESS (0.465s)
[308.202360] Starting dynamic subtest: pipe-C-DP-1
[309.027232] Dynamic subtest pipe-C-DP-1: SUCCESS (0.838s)
[309.028591] Subtest alpha-7efc: SUCCESS (1.692s)
[309.397679] [115/138] (707s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[309.652368] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[309.655906] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[309.764747] [116/138] (707s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[310.019616] Starting subtest: fbc-rgb565-draw-mmap-gtt
[312.174533] Subtest fbc-rgb565-draw-mmap-gtt: SUCCESS (2.157s)
[312.313768] [117/138] (704s left) kms_psr (psr2_primary_blt)
[312.459730] Subtest psr2_primary_blt: SKIP (0.000s)
[312.515091] [118/138] (704s left) kms_writeback (writeback-fb-id)
[312.844144] Subtest writeback-fb-id: SKIP (0.000s)
[312.897348] [119/138] (704s left) gem_sync (basic-many-each)
[313.064825] Starting subtest: basic-many-each
[315.487701] Subtest basic-many-each: SUCCESS (2.423s)
[315.700324] [120/138] (701s left) gem_exec_capture (pi)
[315.854356] Starting subtest: pi
[315.855784] Starting dynamic subtest: rcs0
[345.762123] Dynamic subtest rcs0: SUCCESS (29.904s)
[345.782582] Starting dynamic subtest: bcs0
[375.724368] Dynamic subtest bcs0: SUCCESS (29.961s)
[375.747118] Starting dynamic subtest: vcs0
[406.416762] Dynamic subtest vcs0: SUCCESS (30.690s)
[406.441219] Starting dynamic subtest: vecs0
[435.805266] Dynamic subtest vecs0: SUCCESS (29.383s)
[435.806709] Subtest pi: SUCCESS (119.950s)
[435.898428] [121/138] (581s left) i915_fb_tiling
[436.263723] [122/138] (581s left) syncobj_timeline (invalid-wait-zero-handles)
[436.423428] Starting subtest: invalid-wait-zero-handles
[436.425274] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[436.513973] [123/138] (580s left) kms_ccs (pipe-b-bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[436.905651] Starting subtest: pipe-B-bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[436.908708] Subtest pipe-B-bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s)
[437.329747] [124/138] (580s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[437.579068] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[437.581662] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[437.676327] [125/138] (579s left) gem_pxp (create-protected-buffer)
[440.338522] Subtest create-protected-buffer: SKIP (0.000s)
[440.426206] [126/138] (577s left) api_intel_bb (misplaced-blitter)
[440.571275] Starting subtest: misplaced-blitter
[440.586182] Subtest misplaced-blitter: SUCCESS (0.013s)
[440.693231] [127/138] (576s left) kms_ccs (pipe-a-crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[441.047204] Starting subtest: pipe-A-crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[441.050366] Subtest pipe-A-crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SKIP (0.001s)
[441.512719] [128/138] (576s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[441.763897] Starting subtest: plane-all-transition-nonblocking-fencing
[441.765596] Starting dynamic subtest: pipe-A-DP-1
[445.788123] Dynamic subtest pipe-A-DP-1: SUCCESS (4.023s)
[445.907687] Starting dynamic subtest: pipe-B-DP-1
[450.339330] Dynamic subtest pipe-B-DP-1: SUCCESS (4.449s)
[450.437565] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (8.672s)
[450.795150] [129/138] (566s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[451.045854] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[451.047295] Starting dynamic subtest: pipe-A-valid-mode
[451.802623] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.751s)
[451.804377] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SUCCESS (0.756s)
[452.150453] [130/138] (565s left) kms_flip (2x-absolute-wf_vblank)
[452.373166] Starting subtest: 2x-absolute-wf_vblank
[452.375951] Subtest 2x-absolute-wf_vblank: SKIP (0.003s)
[452.500850] [131/138] (565s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[452.745721] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[452.748862] Subtest psr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[452.849711] [132/138] (564s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[453.097055] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[453.100710] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[453.200108] [133/138] (564s left) gem_wait (await)
[453.382127] Starting subtest: await
[453.384427] Starting dynamic subtest: all-engines
[454.424974] Dynamic subtest all-engines: SUCCESS (1.042s)
[454.447410] Starting dynamic subtest: rcs0
[455.454675] Dynamic subtest rcs0: SUCCESS (1.027s)
[455.480445] Starting dynamic subtest: bcs0
[456.484643] Dynamic subtest bcs0: SUCCESS (1.027s)
[456.509183] Starting dynamic subtest: vcs0
[457.515404] Dynamic subtest vcs0: SUCCESS (1.027s)
[457.540334] Starting dynamic subtest: vecs0
[458.545264] Dynamic subtest vecs0: SUCCESS (1.025s)
[458.549738] Subtest await: SUCCESS (5.166s)
[458.803219] [134/138] (558s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[459.082043] Starting subtest: y-tiled-8bpp-rotate-180
[462.160702] Subtest y-tiled-8bpp-rotate-180: SUCCESS (3.077s)
[462.401233] [135/138] (555s left) gem_exec_schedule (semaphore-user)
[462.585075] Starting subtest: semaphore-user
[462.603588] Subtest semaphore-user: SUCCESS (0.019s)
[462.969417] [136/138] (554s left) kms_plane_multiple (tiling-4)
[463.205062] Starting subtest: tiling-4
[463.208071] Subtest tiling-4: SKIP (0.000s)
[463.300834] [137/138] (554s left) fbdev (pan)
[463.438834] Starting subtest: pan
[463.493891] Subtest pan: SUCCESS (0.054s)
[463.586266] [138/138] (554s left) kms_content_protection (uevent)
[463.865898] Starting subtest: uevent
[463.895502] Starting dynamic subtest: pipe-A-DP-1
[498.791022] Dynamic subtest pipe-A-DP-1: FAIL (34.808s)
[498.856292] Subtest uevent: FAIL (34.989s)
[499.194526] Closing watchdogs
Done.