[49.099322] Initializing watchdogs
[49.099563]   /dev/watchdog0
[49.115649] [001/141] (960s left) gem_ctx_persistence (engines-queued)
[49.360790] Starting subtest: engines-queued
[49.362520] Starting dynamic subtest: rcs0
[49.435485] Dynamic subtest rcs0: SUCCESS (0.074s)
[49.452425] Starting dynamic subtest: bcs0
[49.590827] Dynamic subtest bcs0: SUCCESS (0.154s)
[49.606282] Starting dynamic subtest: vcs0
[49.655183] Dynamic subtest vcs0: SUCCESS (0.063s)
[49.670489] Starting dynamic subtest: vecs0
[49.714981] Dynamic subtest vecs0: SUCCESS (0.058s)
[49.716705] Subtest engines-queued: SUCCESS (0.353s)
[49.809081] [002/141] (959s left) prime_mmap (test_aperture_limit)
[50.013692] Starting subtest: test_aperture_limit
[50.015137] Starting dynamic subtest: test_aperture_limit-smem
[50.040932] Dynamic subtest test_aperture_limit-smem: SUCCESS (0.028s)
[50.042466] Subtest test_aperture_limit: SUCCESS (0.028s)
[50.138691] [003/141] (959s left) gem_exec_balancer (individual)
[50.365105] Starting subtest: individual
[54.256836] Subtest individual: SUCCESS (3.890s)
[58.153351] [004/141] (951s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[58.470845] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[58.473471] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[58.572046] [005/141] (951s left) gem_exec_params (invalid-batch-start-offset)
[58.782368] Starting subtest: invalid-batch-start-offset
[58.794048] Subtest invalid-batch-start-offset: SUCCESS (0.011s)
[58.904626] [006/141] (950s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[59.201698] Starting subtest: flip-vs-cursor-atomic
[75.222971] Subtest flip-vs-cursor-atomic: SUCCESS (16.025s)
[75.571236] [007/141] (934s left) gem_flink_basic (basic)
[75.771401] Starting subtest: basic
[75.772869] Subtest basic: SUCCESS (0.001s)
[75.869664] [008/141] (933s left) i915_pm_dc (dc9-dpms)
[77.176494] Starting subtest: dc9-dpms
[77.183068] Subtest dc9-dpms: SKIP (0.000s)
[77.335324] [009/141] (932s left) kms_vblank (pipe-c-query-forked-busy-hang)
[77.631681] Starting subtest: pipe-C-query-forked-busy-hang
[85.009792] Subtest pipe-C-query-forked-busy-hang: SUCCESS (7.379s)
[85.356185] [010/141] (924s left) kms_flip (plain-flip-fb-recreate-interruptible)
[85.647119] Starting subtest: plain-flip-fb-recreate-interruptible
[85.651858] Starting dynamic subtest: A-DP1
[96.468275] Dynamic subtest A-DP1: SUCCESS (10.814s)
[96.497545] Starting dynamic subtest: B-DP1
[107.255683] Dynamic subtest B-DP1: SUCCESS (10.789s)
[107.272216] Starting dynamic subtest: C-DP1
[117.951482] Dynamic subtest C-DP1: SUCCESS (10.695s)
[117.957752] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (32.307s)
[118.379284] [011/141] (891s left) kms_vblank (pipe-a-wait-forked)
[118.683689] Starting subtest: pipe-A-wait-forked
[121.024855] Subtest pipe-A-wait-forked: SUCCESS (2.345s)
[121.357442] [012/141] (888s left) gem_mmap_gtt (big-copy-odd)
[121.557715] Starting subtest: big-copy-odd
[134.057225] Subtest big-copy-odd: SUCCESS (12.499s)
[134.224529] [013/141] (875s left) gem_ctx_persistence (processes)
[134.560252] Starting subtest: processes
[134.994454] Subtest processes: SUCCESS (0.435s)
[135.092933] [014/141] (874s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_mtl_rc_ccs_cc)
[135.605898] Starting subtest: pipe-B-random-ccs-data-4_tiled_mtl_rc_ccs_cc
[135.610289] Subtest pipe-B-random-ccs-data-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[136.070127] [015/141] (874s left) gem_eio (wait-wedge-1us)
[136.293949] Starting subtest: wait-wedge-1us
[136.380880] Subtest wait-wedge-1us: SUCCESS (0.088s)
[136.585450] [016/141] (873s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-blt)
[136.898904] Starting subtest: psr-1p-offscren-pri-indfb-draw-blt
[136.902175] Subtest psr-1p-offscren-pri-indfb-draw-blt: SKIP (0.000s)
[137.000779] [017/141] (873s left) gem_softpin (softpin)
[137.213390] Starting subtest: softpin
[169.613105] Subtest softpin: SUCCESS (32.397s)
[169.714571] [018/141] (840s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[170.612314] Starting subtest: syncobj-timeline-invalid-flags
[170.613959] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.001s)
[170.838064] [019/141] (839s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[171.144332] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[171.146950] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[171.251590] [020/141] (839s left) gem_userptr_blits (unsync-unmap)
[171.465841] Subtest unsync-unmap: SKIP (0.000s)
[171.635158] [021/141] (838s left) kms_content_protection (dp-mst-type-1)
[171.968305] Starting subtest: dp-mst-type-1
[171.971067] Subtest dp-mst-type-1: SKIP (0.001s)
[172.068695] [022/141] (838s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[172.388977] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[172.391824] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[172.485265] [023/141] (837s left) kms_ccs (pipe-d-bad-rotation-90-y_tiled_ccs)
[173.154404] Subtest pipe-D-bad-rotation-90-y_tiled_ccs: SKIP (0.000s)
[173.468617] [024/141] (836s left) kms_vblank (pipe-a-wait-idle-hang)
[173.771762] Starting subtest: pipe-A-wait-idle-hang
[181.011102] Subtest pipe-A-wait-idle-hang: SUCCESS (7.241s)
[181.343618] [025/141] (829s left) gem_mmap_gtt (ptrace)
[181.540612] Starting subtest: ptrace
[181.830634] Subtest ptrace: SUCCESS (0.290s)
[181.927800] [026/141] (828s left) gem_spin_batch (resubmit-new)
[182.152426] Starting subtest: resubmit-new
[182.153984] Starting dynamic subtest: rcs0
[182.163759] Dynamic subtest rcs0: SUCCESS (0.011s)
[182.187234] Starting dynamic subtest: bcs0
[182.188905] Dynamic subtest bcs0: SUCCESS (0.011s)
[182.208271] Starting dynamic subtest: vcs0
[182.209555] Dynamic subtest vcs0: SUCCESS (0.008s)
[182.227506] Starting dynamic subtest: vecs0
[182.228778] Dynamic subtest vecs0: SUCCESS (0.009s)
[182.230126] Subtest resubmit-new: SUCCESS (0.041s)
[182.427104] [027/141] (828s left) kms_vblank (pipe-b-ts-continuation-dpms-suspend)
[182.720599] Starting subtest: pipe-B-ts-continuation-dpms-suspend
[201.155739] Subtest pipe-B-ts-continuation-dpms-suspend: SUCCESS (2.078s)
[206.462969] [028/141] (825s left) gem_cs_tlb (engines)
[206.672603] Starting subtest: engines
[206.674057] Starting dynamic subtest: rcs0
[208.838293] Dynamic subtest rcs0: SUCCESS (2.162s)
[208.861502] Starting dynamic subtest: bcs0
[211.001788] Dynamic subtest bcs0: SUCCESS (2.164s)
[211.024379] Starting dynamic subtest: vcs0
[213.167242] Dynamic subtest vcs0: SUCCESS (2.164s)
[213.188976] Starting dynamic subtest: vecs0
[215.329891] Dynamic subtest vecs0: SUCCESS (2.161s)
[215.331410] Subtest engines: SUCCESS (8.653s)
[215.436728] [029/141] (816s left) gem_close_race (basic-process)
[215.672272] Starting subtest: basic-process
[215.769414] Subtest basic-process: SUCCESS (0.097s)
[215.970328] [030/141] (816s left) kms_ccs (pipe-c-crc-primary-rotation-180-y_tiled_gen12_mc_ccs)
[216.572133] Starting subtest: pipe-C-crc-primary-rotation-180-y_tiled_gen12_mc_ccs
[216.574943] Subtest pipe-C-crc-primary-rotation-180-y_tiled_gen12_mc_ccs: SKIP (0.001s)
[216.958243] [031/141] (815s left) gem_exec_reloc (basic-wc-active)
[217.168354] Starting subtest: basic-wc-active
[217.312576] Subtest basic-wc-active: SUCCESS (0.142s)
[217.490949] [032/141] (814s left) gem_eio (throttle)
[217.707201] Starting subtest: throttle
[217.747933] Subtest throttle: SUCCESS (0.042s)
[217.958228] [033/141] (814s left) kms_addfb_basic (bad-pitch-32)
[218.160041] Starting subtest: bad-pitch-32
[218.161751] Subtest bad-pitch-32: SUCCESS (0.000s)
[218.375108] [034/141] (813s left) kms_vblank (pipe-d-ts-continuation-dpms-rpm)
[218.681968] Subtest pipe-D-ts-continuation-dpms-rpm: SKIP (0.000s)
[218.758313] [035/141] (813s left) vc4/vc4_perfmon (get-values-invalid-perfmon)
[219.016838] Subtest get-values-invalid-perfmon: SKIP (0.000s)
[219.091410] [036/141] (813s left) gem_mmap_gtt (basic-wc)
[219.287606] Starting subtest: basic-wc
[219.918863] Subtest basic-wc: SUCCESS (0.631s)
[220.024568] [037/141] (812s left) core_auth (many-magics)
[220.223568] Starting subtest: many-magics
[221.454350] Subtest many-magics: SUCCESS (1.231s)
[221.558064] [038/141] (810s left) gem_exec_alignment (single)
[221.770210] Starting subtest: single
[221.781372] Subtest single: SUCCESS (0.011s)
[221.891131] [039/141] (810s left) gem_exec_fence (expired-history)
[222.532131] Starting subtest: expired-history
[228.346170] Subtest expired-history: SUCCESS (5.813s)
[228.956619] [040/141] (803s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[229.167120] Starting subtest: rel-constants-invalid-rel-gen5
[229.168737] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.001s)
[229.274506] [041/141] (803s left) perf (oa-formats)
[229.618747] Starting subtest: oa-formats
[229.620360] Starting dynamic subtest: 0-rcs0
[229.816281] Dynamic subtest 0-rcs0: SUCCESS (0.197s)
[229.817842] Subtest oa-formats: SUCCESS (0.198s)
[229.874370] [042/141] (802s left) sysfs_defaults (readonly)
[230.084585] Starting subtest: readonly
[230.086623] Starting dynamic subtest: vecs0
[230.088183] Dynamic subtest vecs0: SUCCESS (0.001s)
[230.110595] Starting dynamic subtest: vcs0
[230.111917] Dynamic subtest vcs0: SUCCESS (0.002s)
[230.133748] Starting dynamic subtest: bcs0
[230.135131] Dynamic subtest bcs0: SUCCESS (0.001s)
[230.156756] Starting dynamic subtest: rcs0
[230.158082] Dynamic subtest rcs0: SUCCESS (0.001s)
[230.159384] Subtest readonly: SUCCESS (0.010s)
[230.191239] [043/141] (802s left) core_setmaster (master-drop-set-root)
[232.202312] Starting subtest: master-drop-set-root
[232.215811] Subtest master-drop-set-root: SUCCESS (0.012s)
[232.256236] [044/141] (800s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[232.561449] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[232.564145] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[232.658714] [045/141] (799s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[232.961009] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[232.962624] Starting dynamic subtest: pipe-A-DP-1
[233.066222] Dynamic subtest pipe-A-DP-1: SUCCESS (0.103s)
[233.094987] Starting dynamic subtest: pipe-B-DP-1
[233.461196] Dynamic subtest pipe-B-DP-1: SUCCESS (0.392s)
[233.494503] Starting dynamic subtest: pipe-C-DP-1
[233.850189] Dynamic subtest pipe-C-DP-1: SUCCESS (0.386s)
[233.851914] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (0.892s)
[234.260638] [046/141] (798s left) kms_flip (2x-busy-flip)
[234.544028] Starting subtest: 2x-busy-flip
[234.546678] Subtest 2x-busy-flip: SKIP (0.003s)
[234.661305] [047/141] (798s left) kms_dsc (dsc-with-bpc-formats)
[234.961456] Subtest dsc-with-bpc-formats: SKIP (0.000s)
[235.009305] [048/141] (797s left) kms_psr (psr2_cursor_mmap_cpu)
[235.210790] Subtest psr2_cursor_mmap_cpu: SKIP (0.000s)
[235.259158] [049/141] (797s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[235.572972] Starting subtest: psr-rgb101010-draw-mmap-cpu
[235.576064] Subtest psr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[235.678299] [050/141] (797s left) prime_mmap_coherency (write)
[235.889558] Starting subtest: write
[241.312416] Subtest write: SUCCESS (5.421s)
[241.856058] [051/141] (791s left) gem_pxp (reject-modify-context-protection-off-1)
[244.580767] Subtest reject-modify-context-protection-off-1: SKIP (0.000s)
[244.675794] [052/141] (788s left) vgem_basic (create)
[244.885274] Starting subtest: create
[244.886843] Subtest create: SUCCESS (0.001s)
[244.960810] [053/141] (788s left) syncobj_timeline (single-wait-all-signaled)
[245.162928] Starting subtest: single-wait-all-signaled
[245.164652] Subtest single-wait-all-signaled: SUCCESS (0.001s)
[245.259479] [054/141] (787s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[245.563613] Starting subtest: multiplane-rotation-cropping-top
[259.586487] Subtest multiplane-rotation-cropping-top: SUCCESS (14.026s)
[259.711326] [055/141] (773s left) kms_cdclk (plane-scaling)
[259.920679] Subtest plane-scaling: SKIP (0.000s)
[259.975657] [056/141] (773s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[260.279673] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[260.282591] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[260.377917] [057/141] (772s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[260.672623] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[260.673940] Starting dynamic subtest: pipe-A-valid-mode
[261.135597] Dynamic subtest pipe-A-valid-mode: SKIP (0.462s)
[261.138157] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SKIP (0.465s)
[261.506822] [058/141] (771s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[261.818389] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[261.820986] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[261.923182] [059/141] (771s left) kms_prop_blob (blob-prop-core)
[262.122675] Starting subtest: blob-prop-core
[262.124127] Subtest blob-prop-core: SUCCESS (0.000s)
[262.206603] [060/141] (771s left) kms_getfb (getfb-handle-valid)
[262.404408] Starting subtest: getfb-handle-valid
[262.406061] Subtest getfb-handle-valid: SUCCESS (0.000s)
[262.506417] [061/141] (770s left) gem_exec_reloc (basic-wc-gtt-active)
[262.715637] Starting subtest: basic-wc-gtt-active
[262.856642] Subtest basic-wc-gtt-active: SUCCESS (0.140s)
[263.040740] [062/141] (770s left) kms_vblank (pipe-c-wait-busy)
[263.337230] Starting subtest: pipe-C-wait-busy
[266.329160] Subtest pipe-C-wait-busy: SUCCESS (2.991s)
[266.678174] [063/141] (766s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[266.981447] Starting subtest: plane-all-modeset-transition-internal-panels
[266.984101] Subtest plane-all-modeset-transition-internal-panels: SKIP (0.000s)
[267.078109] [064/141] (766s left) kms_ccs (pipe-b-ccs-on-another-bo-y_tiled_ccs)
[267.605340] Starting subtest: pipe-B-ccs-on-another-bo-y_tiled_ccs
[267.606905] Subtest pipe-B-ccs-on-another-bo-y_tiled_ccs: SUCCESS (0.002s)
[268.059371] [065/141] (765s left) prime_vgem (sync)
[268.287133] Starting subtest: sync
[268.288709] Starting dynamic subtest: rcs0
[268.318680] Dynamic subtest rcs0: SUCCESS (0.030s)
[268.344648] Starting dynamic subtest: vcs0
[268.345952] Dynamic subtest vcs0: SUCCESS (0.017s)
[268.373889] Starting dynamic subtest: vecs0
[268.375224] Dynamic subtest vecs0: SUCCESS (0.016s)
[268.376406] Subtest sync: SUCCESS (0.069s)
[268.475678] [066/141] (765s left) gem_exec_schedule (pi-shared-iova)
[268.841921] Starting subtest: pi-shared-iova
[268.843499] Starting dynamic subtest: rcs0
[268.916794] Dynamic subtest rcs0: SUCCESS (0.073s)
[268.947216] Starting dynamic subtest: bcs0
[268.991483] Dynamic subtest bcs0: SUCCESS (0.072s)
[269.024680] Starting dynamic subtest: vcs0
[269.045715] Dynamic subtest vcs0: SUCCESS (0.051s)
[269.077729] Starting dynamic subtest: vecs0
[269.101968] Dynamic subtest vecs0: SUCCESS (0.055s)
[269.103622] Subtest pi-shared-iova: SUCCESS (0.261s)
[269.326498] [067/141] (764s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[269.545036] Starting subtest: mixed-tiled-to-y-tiled-ccs
[269.546751] Starting dynamic subtest: smem
[269.849765] Dynamic subtest smem: SUCCESS (0.302s)
[269.851048] Subtest mixed-tiled-to-y-tiled-ccs: SUCCESS (0.304s)
[270.061733] [068/141] (763s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[270.375276] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[273.032117] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (2.659s)
[273.176805] [069/141] (760s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[273.474334] Starting subtest: modeset-transition-nonblocking-fencing
[273.475723] Starting dynamic subtest: 1x-outputs
[278.484957] Dynamic subtest 1x-outputs: SUCCESS (5.013s)
[278.594937] Subtest modeset-transition-nonblocking-fencing: SUCCESS (5.122s)
[278.945445] [070/141] (754s left) v3d/v3d_wait_bo (used-bo)
[279.200018] Subtest used-bo: SKIP (0.000s)
[279.276557] [071/141] (754s left) kms_atomic_interruptible (legacy-setmode)
[279.581290] Starting subtest: legacy-setmode
[279.585381] Starting dynamic subtest: DP-1-pipe-A
[286.110295] Dynamic subtest DP-1-pipe-A: SUCCESS (6.522s)
[286.111928] Subtest legacy-setmode: SUCCESS (6.527s)
[286.446473] [072/141] (747s left) kms_cursor_crc (cursor-offscreen-64x21)
[286.758390] Starting subtest: cursor-offscreen-64x21
[286.770253] Starting dynamic subtest: pipe-A-DP-1
[289.263747] Dynamic subtest pipe-A-DP-1: SUCCESS (2.493s)
[289.351082] Starting dynamic subtest: pipe-C-DP-1
[292.130679] Dynamic subtest pipe-C-DP-1: SUCCESS (2.800s)
[292.132101] Subtest cursor-offscreen-64x21: SUCCESS (5.371s)
[292.501332] [073/141] (741s left) kms_addfb_basic (master-rmfb)
[292.701970] Starting subtest: master-rmfb
[292.703435] Subtest master-rmfb: SUCCESS (0.002s)
[292.913648] [074/141] (740s left) fbdev (pan)
[293.109320] Starting subtest: pan
[293.151112] Subtest pan: SUCCESS (0.042s)
[293.246735] [075/141] (740s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[293.549431] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[293.550990] Starting dynamic subtest: pipe-A-valid-mode
[293.651083] Dynamic subtest pipe-A-valid-mode: SKIP (0.099s)
[293.653884] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.103s)
[293.972699] [076/141] (739s left) gem_watchdog (default-virtual)
[294.184342] Starting subtest: default-virtual
[295.222660] Subtest default-virtual: SUCCESS (1.037s)
[295.304948] [077/141] (738s left) sw_sync (alloc_merge_fence)
[295.502321] Starting subtest: alloc_merge_fence
[295.504113] Subtest alloc_merge_fence: SUCCESS (0.001s)
[295.572262] [078/141] (738s left) kms_ccs (pipe-a-bad-pixel-format-y_tiled_ccs)
[295.880920] Starting subtest: pipe-A-bad-pixel-format-y_tiled_ccs
[295.882369] Subtest pipe-A-bad-pixel-format-y_tiled_ccs: SUCCESS (0.001s)
[296.582083] [079/141] (737s left) kms_hdr (invalid-metadata-sizes)
[296.893729] Starting subtest: invalid-metadata-sizes
[296.896435] Subtest invalid-metadata-sizes: SKIP (0.000s)
[296.981895] [080/141] (737s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions)
[297.279261] Starting subtest: flip-vs-cursor-atomic-transitions
[307.998803] Subtest flip-vs-cursor-atomic-transitions: FAIL (10.402s)
[308.033096] [081/141] (726s left) gem_exec_balancer (full-late-pulse)
[308.258155] Starting subtest: full-late-pulse
[312.249842] Subtest full-late-pulse: SUCCESS (3.991s)
[316.166197] [082/141] (718s left) i915_hangman (engine-engine-error)
[316.390903] Starting subtest: engine-engine-error
[316.393265] Starting dynamic subtest: rcs0
[316.486117] Dynamic subtest rcs0: SUCCESS (0.095s)
[316.513762] Starting dynamic subtest: bcs0
[316.516330] Dynamic subtest bcs0: SKIP (0.002s)
[316.546446] Starting dynamic subtest: vcs0
[316.570968] Dynamic subtest vcs0: SUCCESS (0.078s)
[316.599167] Starting dynamic subtest: vecs0
[316.657001] Dynamic subtest vecs0: SUCCESS (0.083s)
[316.659042] Subtest engine-engine-error: SUCCESS (0.270s)
[316.798484] [083/141] (717s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[317.123864] Starting subtest: yf-tiled-8bpp-rotate-180
[317.126472] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[317.232176] [084/141] (717s left) kms_big_fb (linear-32bpp-rotate-180)
[317.554961] Starting subtest: linear-32bpp-rotate-180
[323.303567] Subtest linear-32bpp-rotate-180: SUCCESS (5.748s)
[323.833429] [085/141] (710s left) perf_pmu (render-node-busy)
[324.045902] Starting subtest: render-node-busy
[324.047533] Starting dynamic subtest: rcs0
[324.583464] Dynamic subtest rcs0: SUCCESS (0.537s)
[324.612587] Starting dynamic subtest: bcs0
[325.120439] Dynamic subtest bcs0: SUCCESS (0.535s)
[325.149422] Starting dynamic subtest: vcs0
[325.666356] Dynamic subtest vcs0: SUCCESS (0.544s)
[325.695425] Starting dynamic subtest: vecs0
[326.198198] Dynamic subtest vecs0: SUCCESS (0.530s)
[326.200400] Subtest render-node-busy: SUCCESS (2.154s)
[326.298584] [086/141] (708s left) kms_ccs (pipe-c-crc-primary-basic-4_tiled_mtl_mc_ccs)
[326.885117] Starting subtest: pipe-C-crc-primary-basic-4_tiled_mtl_mc_ccs
[326.888037] Subtest pipe-C-crc-primary-basic-4_tiled_mtl_mc_ccs: SKIP (0.001s)
[327.287658] [087/141] (707s left) kms_ccs (pipe-c-bad-aux-stride-y_tiled_gen12_rc_ccs_cc)
[327.923676] Starting subtest: pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs_cc
[327.926565] Subtest pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[328.273190] [088/141] (706s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_gen12_rc_ccs)
[328.927194] Subtest pipe-D-missing-ccs-buffer-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[329.249013] [089/141] (705s left) kms_lease (lease_invalid_connector)
[329.554170] Starting subtest: lease_invalid_connector
[329.555888] Starting dynamic subtest: pipe-A-DP-1
[329.557320] Dynamic subtest pipe-A-DP-1: SUCCESS (0.000s)
[329.584719] Starting dynamic subtest: pipe-B-DP-1
[329.586180] Dynamic subtest pipe-B-DP-1: SUCCESS (0.000s)
[329.609628] Starting dynamic subtest: pipe-C-DP-1
[329.610966] Dynamic subtest pipe-C-DP-1: SUCCESS (0.000s)
[329.612235] Subtest lease_invalid_connector: SUCCESS (0.012s)
[329.649850] [090/141] (704s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc)
[330.147775] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc
[330.150483] Subtest pipe-B-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc: SKIP (0.001s)
[330.637093] [091/141] (704s left) kms_rmfb (close-fd)
[330.938594] Starting subtest: close-fd
[330.940150] Starting dynamic subtest: pipe-A-DP-1
[331.060162] Dynamic subtest pipe-A-DP-1: SUCCESS (0.121s)
[331.096449] Starting dynamic subtest: pipe-B-DP-1
[331.504485] Dynamic subtest pipe-B-DP-1: SUCCESS (0.440s)
[331.533974] Starting dynamic subtest: pipe-C-DP-1
[331.923970] Dynamic subtest pipe-C-DP-1: SUCCESS (0.415s)
[331.925484] Subtest close-fd: SUCCESS (0.989s)
[332.297430] [092/141] (702s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[332.501059] Starting subtest: multi-wait-all-for-submit-submitted
[332.609679] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.108s)
[332.697489] [093/141] (702s left) api_intel_bb (destroy-bb)
[332.901475] Starting subtest: destroy-bb
[332.924538] Subtest destroy-bb: SUCCESS (0.026s)
[333.031072] [094/141] (701s left) kms_ccs (pipe-d-bad-aux-stride-yf_tiled_ccs)
[333.706730] Subtest pipe-D-bad-aux-stride-yf_tiled_ccs: SKIP (0.000s)
[334.026302] [095/141] (700s left) syncobj_timeline (multi-wait-available-submitted)
[334.231946] Starting subtest: multi-wait-available-submitted
[334.236804] Subtest multi-wait-available-submitted: SUCCESS (0.002s)
[334.325862] [096/141] (700s left) kms_cursor_edge_walk (256x256-top-edge)
[334.621735] Starting subtest: 256x256-top-edge
[334.622987] Starting dynamic subtest: pipe-A-DP-1
[338.276834] Dynamic subtest pipe-A-DP-1: SUCCESS (3.654s)
[338.308348] Starting dynamic subtest: pipe-C-DP-1
[341.771376] Dynamic subtest pipe-C-DP-1: SUCCESS (3.488s)
[341.772862] Subtest 256x256-top-edge: SUCCESS (7.152s)
[342.102701] [097/141] (692s left) perf (gen12-unprivileged-single-ctx-counters)
[342.407699] Subtest gen12-unprivileged-single-ctx-counters: SKIP (0.000s)
[342.467418] [098/141] (692s left) gem_busy (extended)
[342.833028] Starting subtest: extended
[342.834674] Starting dynamic subtest: rcs0
[342.860371] Dynamic subtest rcs0: SUCCESS (0.026s)
[342.891730] Starting dynamic subtest: bcs0
[342.893240] Dynamic subtest bcs0: SUCCESS (0.017s)
[342.919233] Starting dynamic subtest: vcs0
[342.920668] Dynamic subtest vcs0: SUCCESS (0.019s)
[342.944444] Starting dynamic subtest: vecs0
[342.945812] Dynamic subtest vecs0: SUCCESS (0.020s)
[342.947101] Subtest extended: SUCCESS (0.094s)
[343.167250] [099/141] (691s left) kms_chamelium_hpd (hdmi-hpd-fast)
[343.570371] Subtest hdmi-hpd-fast: SKIP (0.000s)
[343.854483] [100/141] (691s left) kms_ccs (pipe-b-bad-aux-stride-y_tiled_ccs)
[344.408313] Starting subtest: pipe-B-bad-aux-stride-y_tiled_ccs
[344.413064] Subtest pipe-B-bad-aux-stride-y_tiled_ccs: SUCCESS (0.002s)
[344.881827] [101/141] (690s left) kms_tiled_display (basic-test-pattern)
[345.271540] Subtest basic-test-pattern: SKIP (0.000s)
[345.330420] [102/141] (689s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[345.629342] Starting subtest: cursor-rapid-movement-32x10
[345.748222] Subtest cursor-rapid-movement-32x10: SKIP (0.115s)
[345.865160] [103/141] (689s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[346.070217] Starting subtest: invalid-multi-wait-all-unsubmitted
[346.072246] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[346.164363] [104/141] (689s left) i915_pm_rpm (system-suspend-modeset)
[348.038605] Starting subtest: system-suspend-modeset
[366.313127] Subtest system-suspend-modeset: SUCCESS (2.011s)
[371.924433] [105/141] (684s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[372.248308] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[372.251441] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[372.348988] [106/141] (684s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[372.551105] Starting subtest: invalid-signal-one-illegal-handle
[372.552877] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.001s)
[372.648885] [107/141] (683s left) api_intel_allocator (reloc-allocator)
[372.849713] Starting subtest: reloc-allocator
[372.851071] Starting dynamic subtest: basic
[372.855352] Dynamic subtest basic: SUCCESS (0.004s)
[372.885468] Starting dynamic subtest: parallel-one
[372.886771] Dynamic subtest parallel-one: SUCCESS (0.016s)
[372.910900] Starting dynamic subtest: print
[372.912222] Dynamic subtest print: SUCCESS (0.000s)
[372.936166] Starting dynamic subtest: reuse
[372.937504] Dynamic subtest reuse: SUCCESS (0.000s)
[372.961212] Starting dynamic subtest: fork-reopen-allocator
[372.962555] Dynamic subtest fork-reopen-allocator: SUCCESS (0.040s)
[372.963797] Subtest reloc-allocator: SUCCESS (0.074s)
[373.050378] [108/141] (683s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[373.381986] Starting subtest: x-tiled-64bpp-rotate-0
[381.219794] Subtest x-tiled-64bpp-rotate-0: SUCCESS (7.837s)
[382.166305] [109/141] (674s left) kms_force_connector_basic (force-edid)
[382.373302] Subtest force-edid: SKIP (0.000s)
[382.430449] [110/141] (674s left) i915_pm_dc (dc6-psr)
[383.744267] Starting subtest: dc6-psr
[383.748746] Subtest dc6-psr: SKIP (0.000s)
[383.915761] [111/141] (672s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[384.243877] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[384.247744] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[384.350095] [112/141] (672s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[384.650010] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[384.652702] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[384.749771] [113/141] (672s left) gem_softpin (allocator-nopin)
[384.959056] Starting subtest: allocator-nopin
[385.198517] Subtest allocator-nopin: SUCCESS (0.239s)
[385.331223] [114/141] (671s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[385.537653] Starting subtest: write-cpu-read-wc-unflushed
[385.800894] Subtest write-cpu-read-wc-unflushed: SUCCESS (0.265s)
[385.898077] [115/141] (671s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[386.220403] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[386.226423] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.003s)
[386.315770] [116/141] (670s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[386.722708] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[387.037619] [117/141] (670s left) drm_fdinfo (virtual-busy-idle-all)
[387.243601] Starting subtest: virtual-busy-idle-all
[387.245644] Subtest virtual-busy-idle-all: SUCCESS (0.002s)
[387.332826] [118/141] (669s left) kms_plane_lowres (tiling-4)
[387.641971] Starting subtest: tiling-4
[387.644744] Subtest tiling-4: SKIP (0.000s)
[387.732472] [119/141] (669s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-pwrite)
[388.048117] Starting subtest: fbc-1p-offscren-pri-indfb-draw-pwrite
[389.920596] Subtest fbc-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (1.876s)
[390.065069] [120/141] (667s left) v3d/v3d_submit_cl (bad-perfmon)
[390.322603] Subtest bad-perfmon: SKIP (0.000s)
[390.398328] [121/141] (666s left) kms_ccs (pipe-a-bad-aux-stride-y_tiled_ccs)
[390.846538] Starting subtest: pipe-A-bad-aux-stride-y_tiled_ccs
[390.847979] Subtest pipe-A-bad-aux-stride-y_tiled_ccs: SUCCESS (0.002s)
[391.365073] [122/141] (665s left) kms_ccs (pipe-a-crc-primary-rotation-180-4_tiled_dg2_mc_ccs)
[391.770166] Starting subtest: pipe-A-crc-primary-rotation-180-4_tiled_dg2_mc_ccs
[391.772913] Subtest pipe-A-crc-primary-rotation-180-4_tiled_dg2_mc_ccs: SKIP (0.001s)
[392.346888] [123/141] (665s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[392.660317] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[392.663839] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[392.763651] [124/141] (664s left) kms_getfb (getfb2-accept-ccs)
[392.967818] Starting subtest: getfb2-accept-ccs
[392.969514] Subtest getfb2-accept-ccs: SUCCESS (0.000s)
[393.080225] [125/141] (664s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[393.391808] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[393.395016] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[393.497091] [126/141] (663s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[393.809151] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[393.812046] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[393.913579] [127/141] (663s left) gem_exercise_blt (fast-copy-emit)
[394.123952] Starting subtest: fast-copy-emit
[394.125694] Starting dynamic subtest: linear-smem-smem-emit
[394.181847] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.056s)
[394.212911] Starting dynamic subtest: ymajor-smem-smem-emit
[394.234524] Dynamic subtest ymajor-smem-smem-emit: SUCCESS (0.050s)
[394.266230] Starting dynamic subtest: yfmajor-smem-smem-emit
[394.285241] Dynamic subtest yfmajor-smem-smem-emit: SUCCESS (0.048s)
[394.315790] Starting dynamic subtest: tile64-smem-smem-emit
[394.334902] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.047s)
[394.336949] Subtest fast-copy-emit: SUCCESS (0.212s)
[394.446836] [128/141] (663s left) v3d/v3d_perfmon (destroy-valid-perfmon)
[394.701835] Subtest destroy-valid-perfmon: SKIP (0.000s)
[394.779643] [129/141] (662s left) gem_softpin (overlap)
[394.990897] Starting subtest: overlap
[397.809821] Subtest overlap: SUCCESS (2.818s)
[397.929600] [130/141] (659s left) kms_plane (plane-position-hole)
[398.229820] Starting subtest: plane-position-hole
[398.231183] Starting dynamic subtest: pipe-A-planes
[399.743810] Dynamic subtest pipe-A-planes: SUCCESS (1.514s)
[399.777574] Starting dynamic subtest: pipe-B-planes
[401.530725] Dynamic subtest pipe-B-planes: SUCCESS (1.781s)
[401.532418] Subtest plane-position-hole: SUCCESS (3.306s)
[401.873744] [131/141] (655s left) gem_exec_schedule (u-semaphore-codependency)
[402.109312] Starting subtest: u-semaphore-codependency
[402.136624] Subtest u-semaphore-codependency: SUCCESS (0.027s)
[402.490613] [132/141] (655s left) gem_exec_whisper (basic-fds-all)
[402.716717] Starting subtest: basic-fds-all
[422.006227] Subtest basic-fds-all: SUCCESS (19.288s)
[422.224728] [133/141] (635s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[422.525912] Starting subtest: cursorB-vs-flipB-atomic
[422.528555] Subtest cursorB-vs-flipB-atomic: SKIP (0.002s)
[422.624185] [134/141] (635s left) gem_lmem_swapping (verify-random)
[422.838153] Subtest verify-random: SKIP (0.000s)
[422.905766] [135/141] (634s left) drm_read (fault-buffer)
[423.314729] Starting subtest: fault-buffer
[423.317263] Subtest fault-buffer: SUCCESS (0.002s)
[423.442084] [136/141] (634s left) kms_vblank (pipe-c-query-busy-hang)
[423.741634] Starting subtest: pipe-C-query-busy-hang
[431.562037] Subtest pipe-C-query-busy-hang: SUCCESS (7.822s)
[431.921481] [137/141] (626s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_mtl_mc_ccs)
[432.357544] Starting subtest: pipe-A-random-ccs-data-4_tiled_mtl_mc_ccs
[432.361654] Subtest pipe-A-random-ccs-data-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[432.921238] [138/141] (625s left) i915_pm_rpm (pc8-residency)
[434.721444] Starting subtest: pc8-residency
[434.726229] Subtest pc8-residency: SKIP (0.000s)
[435.194307] [139/141] (622s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[435.514649] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[435.517553] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[435.609868] [140/141] (622s left) kms_psr (primary_mmap_gtt)
[435.814433] Subtest primary_mmap_gtt: SKIP (0.000s)
[435.875636] [141/141] (622s left) kms_ccs (pipe-d-crc-primary-rotation-180-y_tiled_ccs)
[436.531404] Subtest pipe-D-crc-primary-rotation-180-y_tiled_ccs: SKIP (0.000s)
[436.851057] Closing watchdogs
Done.