[53.778871] Initializing watchdogs
[53.779426]   /dev/watchdog0
[53.812424] [001/142] (960s left) kms_properties (invalid-properties-legacy)
[54.340562] Starting subtest: invalid-properties-legacy
[55.875864] Subtest invalid-properties-legacy: SUCCESS (1.536s)
[56.023657] [002/142] (958s left) syncobj_timeline (wait-delayed-signal)
[56.412600] Starting subtest: wait-delayed-signal
[56.516879] Subtest wait-delayed-signal: SUCCESS (0.106s)
[56.642386] [003/142] (957s left) gem_exec_fence (basic-await)
[57.247151] Starting subtest: basic-await
[57.250977] Starting dynamic subtest: rcs0
[57.506245] Dynamic subtest rcs0: SUCCESS (0.260s)
[57.536431] Starting dynamic subtest: bcs0
[57.739482] Dynamic subtest bcs0: SUCCESS (0.232s)
[57.767096] Starting dynamic subtest: vcs0
[57.959633] Dynamic subtest vcs0: SUCCESS (0.219s)
[57.986329] Starting dynamic subtest: vcs1
[58.182857] Dynamic subtest vcs1: SUCCESS (0.223s)
[58.216542] Starting dynamic subtest: vecs0
[58.403947] Dynamic subtest vecs0: SUCCESS (0.220s)
[58.433547] Starting dynamic subtest: ccs0
[58.627399] Dynamic subtest ccs0: SUCCESS (0.222s)
[58.630599] Subtest basic-await: SUCCESS (1.382s)
[60.819513] [004/142] (953s left) kms_plane_scaling (planes-downscale-factor-0-25-unity-scaling)
[61.300976] Starting subtest: planes-downscale-factor-0-25-unity-scaling
[61.307769] Starting dynamic subtest: pipe-A-eDP-1
[61.412195] Dynamic subtest pipe-A-eDP-1: SKIP (0.101s)
[61.436602] Starting dynamic subtest: pipe-B-eDP-1
[61.586471] Dynamic subtest pipe-B-eDP-1: SKIP (0.166s)
[61.610724] Starting dynamic subtest: pipe-C-eDP-1
[61.750704] Dynamic subtest pipe-C-eDP-1: SKIP (0.159s)
[61.775095] Starting dynamic subtest: pipe-D-eDP-1
[61.917321] Dynamic subtest pipe-D-eDP-1: SKIP (0.160s)
[61.923931] Subtest planes-downscale-factor-0-25-unity-scaling: SKIP (0.614s)
[62.070628] [005/142] (952s left) v3d/v3d_submit_csd (valid-multisync-submission)
[62.530318] Subtest valid-multisync-submission: SKIP (0.000s)
[62.605580] [006/142] (952s left) kms_vblank (pipe-c-query-idle-hang)
[63.068591] Starting subtest: pipe-C-query-idle-hang
[78.664635] Subtest pipe-C-query-idle-hang: SUCCESS (15.593s)
[79.637473] [007/142] (935s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[80.146558] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[80.153420] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[80.299304] [008/142] (934s left) kms_ccs (pipe-a-ccs-on-another-bo-4_tiled_mtl_mc_ccs)
[81.360202] Starting subtest: pipe-A-ccs-on-another-bo-4_tiled_mtl_mc_ccs
[81.545063] Subtest pipe-A-ccs-on-another-bo-4_tiled_mtl_mc_ccs: SUCCESS (0.180s)
[82.905518] [009/142] (931s left) sw_sync (sync_random_merge)
[83.277897] Starting subtest: sync_random_merge
[83.557848] Subtest sync_random_merge: SUCCESS (0.278s)
[83.682187] [010/142] (931s left) v3d/v3d_submit_csd (bad-perfmon)
[84.121751] Subtest bad-perfmon: SKIP (0.000s)
[84.215818] [011/142] (930s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[84.642215] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[84.648476] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[84.778132] [012/142] (930s left) gem_ctx_create (basic-files)
[85.175255] Starting subtest: basic-files
[87.245535] Subtest basic-files: SUCCESS (2.070s)
[88.660855] [013/142] (926s left) kms_ccs (pipe-d-ccs-on-another-bo-yf_tiled_ccs)
[90.371184] Starting subtest: pipe-D-ccs-on-another-bo-yf_tiled_ccs
[90.377843] Subtest pipe-D-ccs-on-another-bo-yf_tiled_ccs: SKIP (0.000s)
[90.985566] [014/142] (924s left) gem_ctx_freq (sysfs)
[91.397887] Starting subtest: sysfs
[91.401794] Starting dynamic subtest: gt0
[97.064507] Dynamic subtest gt0: SUCCESS (5.666s)
[97.103829] Starting dynamic subtest: gt1
[138.541918] Dynamic subtest gt1: SUCCESS (41.474s)
[138.546124] Subtest sysfs: SUCCESS (47.142s)
[138.704469] [015/142] (876s left) perf_pmu (init-wait)
[139.104507] Starting subtest: init-wait
[139.107908] Starting dynamic subtest: rcs0
[139.111476] Dynamic subtest rcs0: SUCCESS (0.004s)
[139.146093] Starting dynamic subtest: bcs0
[139.149571] Dynamic subtest bcs0: SUCCESS (0.002s)
[139.184652] Starting dynamic subtest: vcs0
[139.188185] Dynamic subtest vcs0: SUCCESS (0.002s)
[139.221258] Starting dynamic subtest: vcs1
[139.224515] Dynamic subtest vcs1: SUCCESS (0.002s)
[139.257380] Starting dynamic subtest: vecs0
[139.260421] Dynamic subtest vecs0: SUCCESS (0.002s)
[139.291827] Starting dynamic subtest: ccs0
[139.295194] Dynamic subtest ccs0: SUCCESS (0.003s)
[139.298461] Subtest init-wait: SUCCESS (0.017s)
[139.349384] [016/142] (876s left) kms_plane_multiple (tiling-yf)
[139.839118] Starting subtest: tiling-yf
[139.845889] Subtest tiling-yf: SKIP (0.000s)
[139.966144] [017/142] (875s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[141.020917] Starting subtest: cursor-rapid-movement-256x256
[141.055177] Starting dynamic subtest: pipe-A-eDP-1
[142.624932] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.571s)
[142.815166] Starting dynamic subtest: pipe-D-eDP-1
[145.506367] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.722s)
[145.510824] Subtest cursor-rapid-movement-256x256: SUCCESS (4.483s)
[146.445566] [018/142] (869s left) kms_flip (flip-vs-modeset-vs-hang)
[146.848129] Starting subtest: flip-vs-modeset-vs-hang
[146.857969] Starting dynamic subtest: A-eDP1
[176.408246] Dynamic subtest A-eDP1: SUCCESS (29.547s)
[176.439622] Starting dynamic subtest: D-eDP1
[216.354123] Dynamic subtest D-eDP1: SUCCESS (39.944s)
[216.357781] Subtest flip-vs-modeset-vs-hang: SUCCESS (69.514s)
[216.842586] [019/142] (798s left) kms_ccs (pipe-b-bad-aux-stride-4_tiled_mtl_rc_ccs)
[217.918103] Starting subtest: pipe-B-bad-aux-stride-4_tiled_mtl_rc_ccs
[217.921484] Subtest pipe-B-bad-aux-stride-4_tiled_mtl_rc_ccs: SUCCESS (0.002s)
[218.626645] [020/142] (797s left) api_intel_bb (blit-noreloc-purge-cache)
[218.989802] Starting subtest: blit-noreloc-purge-cache
[219.030954] Subtest blit-noreloc-purge-cache: SUCCESS (0.044s)
[219.195466] [021/142] (796s left) kms_chamelium_audio (dp-audio)
[220.082987] Subtest dp-audio: SKIP (0.000s)
[220.960984] [022/142] (794s left) gem_exec_params (dr1-dirt)
[221.356068] Starting subtest: DR1-dirt
[221.359871] Subtest DR1-dirt: SUCCESS (0.001s)
[221.513897] [023/142] (794s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[222.021358] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[222.028145] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[222.174653] [024/142] (793s left) gem_mmap (pf-nonblock)
[222.551424] Subtest pf-nonblock: SKIP (0.000s)
[222.649849] [025/142] (793s left) gem_mmap_gtt (fault-concurrent-x)
[223.015261] Subtest fault-concurrent-X: SKIP (0.000s)
[223.115989] [026/142] (792s left) i915_module_load (reload-no-display)
[223.479401] Starting subtest: reload-no-display
[231.558377] Subtest reload-no-display: SUCCESS (8.079s)
[231.622749] [027/142] (784s left) v3d/v3d_perfmon (create-two-perfmon)
[232.015810] Subtest create-two-perfmon: SKIP (0.000s)
[232.073308] [028/142] (784s left) kms_force_connector_basic (force-edid)
[238.430531] Starting subtest: force-edid
[238.773699] Subtest force-edid: SUCCESS (0.344s)
[238.997736] [029/142] (777s left) kms_ccs (pipe-c-missing-ccs-buffer-yf_tiled_ccs)
[240.293765] Starting subtest: pipe-C-missing-ccs-buffer-yf_tiled_ccs
[240.300429] Subtest pipe-C-missing-ccs-buffer-yf_tiled_ccs: SKIP (0.000s)
[241.078665] [030/142] (775s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[241.580833] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[256.700692] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (15.124s)
[256.953567] [031/142] (759s left) kms_psr (psr2_primary_render)
[257.447085] Starting subtest: psr2_primary_render
[260.628157] Subtest psr2_primary_render: SUCCESS (3.180s)
[261.569069] [032/142] (754s left) kms_ccs (pipe-a-crc-primary-rotation-180-y_tiled_gen12_rc_ccs)
[262.505873] Starting subtest: pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs
[262.512980] Subtest pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs: SKIP (0.002s)
[263.927486] [033/142] (752s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[264.438595] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[274.571046] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (10.136s)
[274.837363] [034/142] (741s left) gem_exec_schedule (preempt-other)
[275.294414] Starting subtest: preempt-other
[275.297990] Starting dynamic subtest: rcs0
[275.649070] Dynamic subtest rcs0: SUCCESS (0.351s)
[275.686098] Starting dynamic subtest: bcs0
[276.003468] Dynamic subtest bcs0: SUCCESS (0.352s)
[276.047779] Starting dynamic subtest: vcs0
[276.338979] Dynamic subtest vcs0: SUCCESS (0.329s)
[276.375863] Starting dynamic subtest: vcs1
[276.682891] Dynamic subtest vcs1: SUCCESS (0.340s)
[276.720748] Starting dynamic subtest: vecs0
[277.009205] Dynamic subtest vecs0: SUCCESS (0.323s)
[277.044449] Starting dynamic subtest: ccs0
[277.344537] Dynamic subtest ccs0: SUCCESS (0.333s)
[277.348200] Subtest preempt-other: SUCCESS (2.050s)
[278.687381] [035/142] (738s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[279.244456] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[279.251995] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[279.404617] [036/142] (737s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[279.914456] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[285.435296] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (5.531s)
[285.698335] [037/142] (731s left) kms_vblank (pipe-d-ts-continuation-modeset)
[286.173933] Starting subtest: pipe-D-ts-continuation-modeset
[289.986128] Subtest pipe-D-ts-continuation-modeset: SUCCESS (3.814s)
[290.927520] [038/142] (726s left) perf_pmu (busy-idle)
[291.326285] Starting subtest: busy-idle
[291.329564] Starting dynamic subtest: rcs0
[292.042614] Dynamic subtest rcs0: SUCCESS (0.719s)
[292.080333] Starting dynamic subtest: bcs0
[292.733334] Dynamic subtest bcs0: SUCCESS (0.687s)
[292.770721] Starting dynamic subtest: vcs0
[294.507716] Dynamic subtest vcs0: SUCCESS (1.769s)
[294.545184] Starting dynamic subtest: vcs1
[296.490267] Dynamic subtest vcs1: SUCCESS (1.979s)
[296.526986] Starting dynamic subtest: vecs0
[298.539612] Dynamic subtest vecs0: SUCCESS (2.046s)
[298.576400] Starting dynamic subtest: ccs0
[299.232069] Dynamic subtest ccs0: SUCCESS (0.691s)
[299.235380] Subtest busy-idle: SUCCESS (7.913s)
[299.410207] [039/142] (717s left) kms_cursor_crc (cursor-alpha-transparent)
[299.904270] Starting subtest: cursor-alpha-transparent
[299.912582] Starting dynamic subtest: pipe-A-eDP-1
[301.311520] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.398s)
[301.353424] Starting dynamic subtest: pipe-D-eDP-1
[303.996215] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.675s)
[303.999309] Subtest cursor-alpha-transparent: SUCCESS (4.095s)
[305.499251] [040/142] (711s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[306.010456] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[306.017637] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[306.164812] [041/142] (711s left) kms_async_flips (test-time-stamp)
[306.647127] Starting subtest: test-time-stamp
[308.891632] Starting dynamic subtest: pipe-A-eDP-1
[310.141343] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.248s)
[312.429691] Starting dynamic subtest: pipe-B-eDP-1
[312.908627] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.514s)
[313.803793] Starting dynamic subtest: pipe-C-eDP-1
[314.223143] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.455s)
[314.824153] Starting dynamic subtest: pipe-D-eDP-1
[315.439074] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.647s)
[315.442825] Subtest test-time-stamp: SUCCESS (8.790s)
[316.798107] [042/142] (700s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[317.325786] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[317.332538] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[317.448334] [043/142] (699s left) kms_vblank (pipe-a-query-forked-hang)
[317.957531] Starting subtest: pipe-A-query-forked-hang
[332.825376] Subtest pipe-A-query-forked-hang: SUCCESS (14.870s)
[333.769775] [044/142] (683s left) kms_psr (psr2_sprite_render)
[334.250378] Starting subtest: psr2_sprite_render
[337.591918] Subtest psr2_sprite_render: SUCCESS (3.338s)
[338.524885] [045/142] (678s left) core_getversion
[339.039411] [046/142] (678s left) api_intel_bb (purge-bb)
[339.412826] Starting subtest: purge-bb
[339.423823] Subtest purge-bb: SUCCESS (0.011s)
[339.599628] [047/142] (678s left) gem_exec_parallel (basic)
[340.039120] Starting subtest: basic
[340.042209] Starting dynamic subtest: rcs0
[344.437494] Dynamic subtest rcs0: SUCCESS (4.401s)
[344.482407] Starting dynamic subtest: bcs0
[348.633254] Dynamic subtest bcs0: SUCCESS (4.190s)
[348.687432] Starting dynamic subtest: vcs0
[351.679782] Dynamic subtest vcs0: SUCCESS (3.040s)
[351.725283] Starting dynamic subtest: vcs1
[355.509536] Dynamic subtest vcs1: SUCCESS (3.823s)
[355.547847] Starting dynamic subtest: vecs0
[358.453081] Dynamic subtest vecs0: SUCCESS (2.939s)
[358.501245] Starting dynamic subtest: ccs0
[363.059251] Dynamic subtest ccs0: SUCCESS (4.600s)
[363.063861] Subtest basic: SUCCESS (23.027s)
[363.388493] [048/142] (654s left) kms_psr2_sf (cursor-plane-move-continuous-exceed-fully-sf)
[370.282708] Starting subtest: cursor-plane-move-continuous-exceed-fully-sf
[370.287658] Starting dynamic subtest: pipe-A-eDP-1
[382.231397] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.946s)
[382.274783] Starting dynamic subtest: pipe-B-eDP-1
[394.191646] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.949s)
[394.194724] Subtest cursor-plane-move-continuous-exceed-fully-sf: SUCCESS (23.914s)
[395.138732] [049/142] (622s left) prime_mmap (test_map_unmap)
[395.516118] Starting subtest: test_map_unmap
[395.519515] Starting dynamic subtest: test_map_unmap-smem
[396.034890] Dynamic subtest test_map_unmap-smem: SUCCESS (0.520s)
[396.038539] Subtest test_map_unmap: SUCCESS (0.524s)
[396.192088] [050/142] (621s left) kms_psr (sprite_plane_move)
[396.684232] Starting subtest: sprite_plane_move
[399.920846] Subtest sprite_plane_move: SUCCESS (3.236s)
[400.858837] [051/142] (617s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[401.400573] Starting subtest: 4-tiled-64bpp-rotate-0
[408.210449] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (6.808s)
[409.030050] [052/142] (609s left) prime_self_import (basic-with_two_bos)
[409.402388] Starting subtest: basic-with_two_bos
[409.414843] Subtest basic-with_two_bos: SUCCESS (0.015s)
[409.639788] [053/142] (608s left) perf (buffer-fill)
[410.611598] Starting subtest: buffer-fill
[410.615129] Starting dynamic subtest: 0-rcs0
[414.460479] Dynamic subtest 0-rcs0: SUCCESS (3.848s)
[414.498750] Starting dynamic subtest: 1-vcs0
[421.794808] Dynamic subtest 1-vcs0: SUCCESS (7.331s)
[421.798667] Subtest buffer-fill: SUCCESS (11.186s)
[421.909698] [054/142] (596s left) kms_plane (plane-position-covered)
[422.405385] Starting subtest: plane-position-covered
[422.415352] Starting dynamic subtest: pipe-A-planes
[430.447180] Dynamic subtest pipe-A-planes: SUCCESS (8.034s)
[430.491288] Starting dynamic subtest: pipe-B-planes
[438.763966] Dynamic subtest pipe-B-planes: SUCCESS (8.306s)
[438.767923] Subtest plane-position-covered: SUCCESS (16.361s)
[439.674263] [055/142] (578s left) i915_suspend (forcewake)
[440.061193] Starting subtest: forcewake
[474.089858] Subtest forcewake: SUCCESS (34.029s)
[474.238337] [056/142] (544s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[474.761265] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[474.767925] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[474.933229] [057/142] (543s left) gem_exec_params (no-vebox)
[475.332225] Starting subtest: no-vebox
[475.341949] Subtest no-vebox: SUCCESS (0.006s)
[476.695053] [058/142] (541s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs)
[478.426976] Starting subtest: pipe-D-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs
[481.523927] Subtest pipe-D-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs: SUCCESS (3.097s)
[482.951309] [059/142] (535s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[483.830329] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[484.698154] [060/142] (534s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[485.255351] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[490.553300] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: FAIL (4.481s)
[490.626641] [061/142] (528s left) perf (low-oa-exponent-permissions)
[491.290986] Starting subtest: low-oa-exponent-permissions
[491.512572] Subtest low-oa-exponent-permissions: SUCCESS (0.228s)
[491.624310] [062/142] (527s left) gen9_exec_parse (allowed-all)
[492.036177] Subtest allowed-all: SKIP (0.000s)
[492.164482] [063/142] (526s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[492.681532] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[492.687695] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[492.856517] [064/142] (526s left) gem_exec_fence (syncobj-timeline-export)
[495.077108] Starting subtest: syncobj-timeline-export
[495.090335] Subtest syncobj-timeline-export: SUCCESS (0.021s)
[495.724509] [065/142] (523s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[496.105905] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[496.209665] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.103s)
[496.389691] [066/142] (522s left) i915_pm_rpm (system-suspend-execbuf)
[498.747881] Starting subtest: system-suspend-execbuf
[598.941344] Subtest system-suspend-execbuf: SUCCESS (100.193s)
[599.815738] [067/142] (419s left) gem_exec_suspend (basic-s0)
[600.231608] Starting subtest: basic-S0
[600.235148] Starting dynamic subtest: smem
[626.540555] Dynamic subtest smem: SUCCESS (26.310s)
[626.544692] Subtest basic-S0: SUCCESS (26.315s)
[626.856987] [068/142] (392s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[627.407279] Starting subtest: fbc-2p-pri-indfb-multidraw
[627.414201] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[627.578529] [069/142] (391s left) prime_vgem (fence-flip-hang)
[628.005199] Subtest fence-flip-hang: SKIP (0.000s)
[628.148284] [070/142] (391s left) kms_vblank (pipe-b-accuracy-idle)
[628.622204] Starting subtest: pipe-B-accuracy-idle
[631.723651] Subtest pipe-B-accuracy-idle: SUCCESS (3.103s)
[632.678896] [071/142] (387s left) kms_addfb_basic (basic)
[633.071442] Starting subtest: basic
[633.075127] Subtest basic: SUCCESS (0.000s)
[633.361650] [072/142] (386s left) kms_flip (blocking-absolute-wf_vblank)
[633.754704] Starting subtest: blocking-absolute-wf_vblank
[633.783194] Starting dynamic subtest: A-eDP1
[643.106551] Dynamic subtest A-eDP1: SUCCESS (9.323s)
[643.156209] Starting dynamic subtest: B-eDP1
[652.265436] Dynamic subtest B-eDP1: SUCCESS (9.154s)
[652.321624] Starting dynamic subtest: C-eDP1
[661.409951] Dynamic subtest C-eDP1: SUCCESS (9.139s)
[661.458700] Starting dynamic subtest: D-eDP1
[670.560737] Dynamic subtest D-eDP1: SUCCESS (9.145s)
[670.586238] Subtest blocking-absolute-wf_vblank: SUCCESS (36.836s)
[672.185937] [073/142] (347s left) gem_pxp (reject-modify-context-protection-off-2)
[675.089452] Subtest reject-modify-context-protection-off-2: SKIP (0.000s)
[675.265292] [074/142] (344s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[675.814134] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[681.008348] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: FAIL (4.379s)
[681.082232] [075/142] (338s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[681.481377] Subtest writes-after-reads: SKIP (0.000s)
[681.616956] [076/142] (338s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_rc_ccs_cc)
[682.781752] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc
[682.788822] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.002s)
[684.031123] [077/142] (336s left) kms_universal_plane (universal-plane-pageflip-windowed-pipe-d)
[684.513770] Starting subtest: universal-plane-pageflip-windowed-pipe-D
[687.083989] Subtest universal-plane-pageflip-windowed-pipe-D: SUCCESS (2.570s)
[688.041163] [078/142] (332s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc)
[689.113362] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc
[689.128936] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.013s)
[690.427833] [079/142] (330s left) gem_busy (extended)
[691.028855] Starting subtest: extended
[691.032812] Starting dynamic subtest: rcs0
[691.121425] Dynamic subtest rcs0: SUCCESS (0.091s)
[691.175174] Starting dynamic subtest: bcs0
[691.178851] Dynamic subtest bcs0: SUCCESS (0.050s)
[691.226390] Starting dynamic subtest: vcs0
[692.522079] Dynamic subtest vcs0: SUCCESS (1.341s)
[692.570151] Starting dynamic subtest: vcs1
[694.506333] Dynamic subtest vcs1: SUCCESS (1.979s)
[694.553321] Starting dynamic subtest: vecs0
[696.491581] Dynamic subtest vecs0: SUCCESS (1.979s)
[696.537903] Starting dynamic subtest: ccs0
[696.548735] Dynamic subtest ccs0: SUCCESS (0.050s)
[696.552378] Subtest extended: SUCCESS (5.517s)
[696.912675] [080/142] (323s left) gem_ctx_persistence (engines-hostile-preempt)
[697.323413] Starting subtest: engines-hostile-preempt
[697.327429] Starting dynamic subtest: rcs0
[697.396555] Dynamic subtest rcs0: SUCCESS (0.077s)
[697.453290] Starting dynamic subtest: bcs0
[697.474211] Dynamic subtest bcs0: SUCCESS (0.073s)
[697.532454] Starting dynamic subtest: vcs0
[699.527420] Dynamic subtest vcs0: SUCCESS (2.048s)
[699.577066] Starting dynamic subtest: vcs1
[701.509203] Dynamic subtest vcs1: SUCCESS (1.977s)
[701.556816] Starting dynamic subtest: vecs0
[703.540251] Dynamic subtest vecs0: SUCCESS (2.026s)
[703.590436] Starting dynamic subtest: ccs0
[703.617610] Dynamic subtest ccs0: SUCCESS (0.075s)
[703.621153] Subtest engines-hostile-preempt: SUCCESS (6.302s)
[703.810123] [081/142] (316s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[704.336852] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[704.343776] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[704.515760] [082/142] (316s left) syncobj_timeline (invalid-single-wait-available-unsubmitted)
[704.896074] Starting subtest: invalid-single-wait-available-unsubmitted
[704.899404] Subtest invalid-single-wait-available-unsubmitted: SUCCESS (0.000s)
[705.054417] [083/142] (315s left) gem_spin_batch (legacy)
[705.471615] Starting subtest: legacy
[705.474486] Starting dynamic subtest: default
[708.716525] Dynamic subtest default: SUCCESS (3.238s)
[708.765915] Starting dynamic subtest: render
[711.949666] Dynamic subtest render: SUCCESS (3.228s)
[711.998677] Starting dynamic subtest: bsd
[715.282858] Dynamic subtest bsd: SUCCESS (3.329s)
[715.331915] Starting dynamic subtest: bsd1
[718.615125] Dynamic subtest bsd1: SUCCESS (3.327s)
[718.661409] Starting dynamic subtest: bsd2
[721.846911] Dynamic subtest bsd2: SUCCESS (3.227s)
[721.893957] Starting dynamic subtest: blt
[725.081853] Dynamic subtest blt: SUCCESS (3.230s)
[725.130419] Starting dynamic subtest: vebox
[728.314349] Dynamic subtest vebox: SUCCESS (3.228s)
[728.318088] Subtest legacy: SUCCESS (22.841s)
[728.689268] [084/142] (292s left) gem_exec_gttfill (multigpu-basic)
[729.242698] Starting subtest: multigpu-basic
[729.249203] Subtest multigpu-basic: SKIP (0.000s)
[729.430619] [085/142] (291s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted-submitted)
[729.803547] Starting subtest: invalid-multi-wait-all-available-unsubmitted-submitted
[729.806720] Subtest invalid-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.003s)
[729.997561] [086/142] (291s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[730.407060] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[730.413278] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[730.715347] [087/142] (290s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[731.222388] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[731.229821] Starting dynamic subtest: pipe-A-default-mode
[731.651272] Dynamic subtest pipe-A-default-mode: SKIP (0.418s)
[731.657092] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.432s)
[732.595159] [088/142] (288s left) kms_ccs (pipe-c-crc-primary-basic-4_tiled_mtl_mc_ccs)
[733.985720] Starting subtest: pipe-C-crc-primary-basic-4_tiled_mtl_mc_ccs
[738.959561] Subtest pipe-C-crc-primary-basic-4_tiled_mtl_mc_ccs: SUCCESS (4.966s)
[740.168175] [089/142] (281s left) gem_caching (read-writes)
[740.569677] Subtest read-writes: SKIP (0.000s)
[740.697991] [090/142] (280s left) syncobj_wait (invalid-signal-one-illegal-handle)
[741.078849] Starting subtest: invalid-signal-one-illegal-handle
[741.082972] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.001s)
[741.234808] [091/142] (280s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_mtl_rc_ccs_cc)
[742.442552] Starting subtest: pipe-B-random-ccs-data-4_tiled_mtl_rc_ccs_cc
[743.652559] Subtest pipe-B-random-ccs-data-4_tiled_mtl_rc_ccs_cc: SUCCESS (1.211s)
[745.267477] [092/142] (276s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[745.853907] Starting subtest: 2x-long-cursor-vs-flip-legacy
[745.874118] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.017s)
[746.207292] [093/142] (275s left) kms_dp_aux_dev
[746.844145] [094/142] (274s left) gem_eio (in-flight-internal-10ms)
[748.334153] Starting subtest: in-flight-internal-10ms
[751.111945] Subtest in-flight-internal-10ms: SUCCESS (2.781s)
[752.647320] [095/142] (269s left) gem_render_copy_redux (flink)
[753.084649] Starting subtest: flink
[760.016364] Subtest flink: SUCCESS (6.932s)
[760.218943] [096/142] (261s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[760.737106] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[769.878036] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (9.146s)
[770.058717] [097/142] (251s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[770.332366] Starting subtest: y-tiled-64bpp-rotate-0
[770.339277] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[770.494051] [098/142] (251s left) dmabuf_sync_file (import-multiple-read-write)
[770.873634] Starting subtest: import-multiple-read-write
[770.894404] Subtest import-multiple-read-write: SUCCESS (0.022s)
[771.040086] [099/142] (251s left) i915_pm_rpm (cursor-dpms)
[773.782326] Starting subtest: cursor-dpms
[809.462929] Subtest cursor-dpms: SUCCESS (35.680s)
[810.679624] [100/142] (211s left) gem_ctx_shared (q-in-order)
[811.102611] Starting subtest: Q-in-order
[811.106186] Starting dynamic subtest: rcs0
[811.441539] Dynamic subtest rcs0: SUCCESS (0.336s)
[811.506481] Starting dynamic subtest: bcs0
[811.693603] Dynamic subtest bcs0: SUCCESS (0.248s)
[811.754913] Starting dynamic subtest: vcs0
[811.927810] Dynamic subtest vcs0: SUCCESS (0.230s)
[811.987281] Starting dynamic subtest: vcs1
[812.170808] Dynamic subtest vcs1: SUCCESS (0.240s)
[812.237507] Starting dynamic subtest: vecs0
[812.409922] Dynamic subtest vecs0: SUCCESS (0.237s)
[812.474847] Starting dynamic subtest: ccs0
[812.684354] Dynamic subtest ccs0: SUCCESS (0.271s)
[812.688085] Subtest Q-in-order: SUCCESS (1.584s)
[813.803569] [101/142] (208s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[814.402335] Starting subtest: cursorB-vs-flipB-atomic
[814.413835] Subtest cursorB-vs-flipB-atomic: SKIP (0.008s)
[814.756345] [102/142] (207s left) syncobj_wait (invalid-reset-bad-pad)
[815.140998] Starting subtest: invalid-reset-bad-pad
[815.145143] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[815.305433] [103/142] (207s left) kms_cursor_crc (cursor-offscreen-128x42)
[816.275245] Starting subtest: cursor-offscreen-128x42
[816.305353] Starting dynamic subtest: pipe-A-eDP-1
[819.043447] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.737s)
[819.258847] Starting dynamic subtest: pipe-D-eDP-1
[823.238455] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.031s)
[823.242160] Subtest cursor-offscreen-128x42: SUCCESS (6.961s)
[824.161552] [104/142] (198s left) gem_exec_schedule (reorder-wide)
[824.777992] Subtest reorder-wide: SKIP (0.000s)
[825.054365] [105/142] (197s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[825.461765] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[825.468116] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[825.749976] [106/142] (197s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[826.253309] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[826.260269] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[826.426854] [107/142] (196s left) kms_ccs (pipe-c-bad-aux-stride-y_tiled_gen12_rc_ccs)
[827.983629] Starting subtest: pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs
[827.990091] Subtest pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[828.845335] [108/142] (194s left) kms_vblank (pipe-a-query-forked)
[829.323355] Starting subtest: pipe-A-query-forked
[831.728617] Subtest pipe-A-query-forked: SUCCESS (2.404s)
[832.688728] [109/142] (190s left) vc4/vc4_perfmon (get-values-invalid-pointer)
[833.167561] Subtest get-values-invalid-pointer: SKIP (0.000s)
[833.309966] [110/142] (189s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[833.878841] Starting subtest: short-flip-before-cursor-toggle
[835.241494] Subtest short-flip-before-cursor-toggle: SUCCESS (1.360s)
[836.200245] [111/142] (187s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_dg2_rc_ccs)
[837.368191] Starting subtest: pipe-B-crc-primary-basic-4_tiled_dg2_rc_ccs
[837.374772] Subtest pipe-B-crc-primary-basic-4_tiled_dg2_rc_ccs: SKIP (0.002s)
[838.583122] [112/142] (184s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[839.100193] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[839.107824] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[839.267726] [113/142] (184s left) kms_lease (cursor_implicit_plane)
[839.752215] Starting subtest: cursor_implicit_plane
[839.761535] Starting dynamic subtest: pipe-A-eDP-1
[840.452698] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.692s)
[840.515471] Starting dynamic subtest: pipe-B-eDP-1
[841.724908] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.261s)
[841.784801] Starting dynamic subtest: pipe-C-eDP-1
[843.350307] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.614s)
[843.404930] Starting dynamic subtest: pipe-D-eDP-1
[845.533832] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.178s)
[845.537956] Subtest cursor_implicit_plane: SUCCESS (5.783s)
[846.482676] [114/142] (177s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[846.994744] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[847.001589] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[847.174154] [115/142] (176s left) vc4/vc4_tiling (set-bad-handle)
[847.651259] Subtest set-bad-handle: SKIP (0.000s)
[847.787195] [116/142] (176s left) gem_exec_big (single)
[848.185254] Starting subtest: single
[858.690670] Subtest single: SUCCESS (10.507s)
[859.998128] [117/142] (163s left) kms_addfb_basic (bad-pitch-63)
[860.516995] Starting subtest: bad-pitch-63
[860.520237] Subtest bad-pitch-63: SUCCESS (0.000s)
[860.815365] [118/142] (163s left) kms_vblank (pipe-b-wait-busy-hang)
[861.304744] Starting subtest: pipe-B-wait-busy-hang
[876.840603] Subtest pipe-B-wait-busy-hang: SUCCESS (15.536s)
[877.794985] [119/142] (146s left) prime_mmap (test_aperture_limit)
[878.173363] Starting subtest: test_aperture_limit
[878.176986] Starting dynamic subtest: test_aperture_limit-smem
[878.247774] Dynamic subtest test_aperture_limit-smem: SUCCESS (0.077s)
[878.252512] Subtest test_aperture_limit: SUCCESS (0.082s)
[878.430661] [120/142] (145s left) syncobj_timeline (multi-wait-submitted-signaled)
[878.828801] Starting subtest: multi-wait-submitted-signaled
[878.838982] Subtest multi-wait-submitted-signaled: SUCCESS (0.004s)
[878.988458] [121/142] (145s left) gem_lmem_swapping (random-engines)
[879.385280] Subtest random-engines: SKIP (0.000s)
[879.512229] [122/142] (144s left) kms_pipe_crc_basic (nonblocking-crc)
[880.000115] Starting subtest: nonblocking-crc
[880.008967] Starting dynamic subtest: pipe-A-eDP-1
[881.180901] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.174s)
[881.245109] Starting dynamic subtest: pipe-B-eDP-1
[883.145629] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.948s)
[883.212692] Starting dynamic subtest: pipe-C-eDP-1
[885.310738] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.158s)
[885.382139] Starting dynamic subtest: pipe-D-eDP-1
[887.849942] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.522s)
[887.854310] Subtest nonblocking-crc: SUCCESS (7.847s)
[888.806797] [123/142] (135s left) gem_exec_balancer (hang)
[890.056485] Starting subtest: hang
[898.205232] Subtest hang: SUCCESS (8.152s)
[898.372791] [124/142] (126s left) syncobj_timeline (wait-all-for-submit-snapshot)
[898.763388] Starting subtest: wait-all-for-submit-snapshot
[898.846894] Subtest wait-all-for-submit-snapshot: SUCCESS (0.084s)
[899.037487] [125/142] (125s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[899.566184] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt
[910.676741] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (11.119s)
[910.979230] [126/142] (113s left) i915_pm_rpm (modeset-stress-extra-wait)
[913.675659] Starting subtest: modeset-stress-extra-wait
[986.195361] Subtest modeset-stress-extra-wait: SUCCESS (72.517s)
[986.398500] [127/142] ( 38s left) kms_chamelium_hpd (dp-hpd)
[987.274293] Subtest dp-hpd: SKIP (0.000s)
[988.156329] [128/142] ( 36s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[988.632580] Starting subtest: plane-use-after-nonblocking-unbind
[988.641371] Starting dynamic subtest: pipe-A-eDP-1
[988.886342] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.246s)
[989.308297] Starting dynamic subtest: pipe-B-eDP-1
[990.142628] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.877s)
[990.466412] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.834s)
[991.481069] [129/142] ( 33s left) kms_ccs (pipe-d-crc-primary-rotation-180-yf_tiled_ccs)
[992.980885] Starting subtest: pipe-D-crc-primary-rotation-180-yf_tiled_ccs
[992.988897] Subtest pipe-D-crc-primary-rotation-180-yf_tiled_ccs: SKIP (0.001s)
[993.493605] [130/142] ( 31s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[994.014349] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[994.020965] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[994.319115] [131/142] ( 31s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[994.538749] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[994.544026] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[994.646757] [132/142] ( 30s left) dumb_buffer (map-uaf)
[994.776727] Starting subtest: map-uaf
[994.785144] Subtest map-uaf: SUCCESS (0.010s)
[994.918841] [133/142] ( 30s left) gem_softpin (evict-active-interruptible)
[995.336709] Starting subtest: evict-active-interruptible
[995.356840] Subtest evict-active-interruptible: SUCCESS (0.022s)
[995.554217] [134/142] ( 29s left) gen9_exec_parse (bb-start-param)
[995.952821] Subtest bb-start-param: SKIP (0.000s)
[996.079595] [135/142] ( 29s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[996.579398] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[1010.632980] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (14.058s)
[1010.911106] [136/142] ( 14s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[1011.497457] Starting subtest: extended-modeset-hang-newfb-with-reset
[1011.506176] Starting dynamic subtest: pipe-A
[1019.060491] Dynamic subtest pipe-A: SUCCESS (7.551s)
[1019.136629] Starting dynamic subtest: pipe-D
[1026.602082] Dynamic subtest pipe-D: SUCCESS (7.517s)
[1026.605861] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (15.104s)
[1027.557459] Overall timeout time exceeded, stopping.
[1027.558761] Closing watchdogs
Done.