[55.251643] Initializing watchdogs
[55.252216]   /dev/watchdog0
[55.285535] [001/142] (960s left) kms_chamelium_edid (dp-edid-resolution-list)
[56.256185] Subtest dp-edid-resolution-list: SKIP (0.000s)
[57.102873] [002/142] (958s left) kms_psr (dpms)
[57.588818] Starting subtest: dpms
[61.558669] Subtest dpms: SUCCESS (3.965s)
[62.511640] [003/142] (953s left) gem_softpin (evict-single-offset)
[62.922189] Starting subtest: evict-single-offset
[97.531549] Subtest evict-single-offset: SUCCESS (34.607s)
[97.696377] [004/142] (918s left) kms_ccs (pipe-c-crc-primary-basic-4_tiled_dg2_rc_ccs)
[99.000353] Starting subtest: pipe-C-crc-primary-basic-4_tiled_dg2_rc_ccs
[99.006571] Subtest pipe-C-crc-primary-basic-4_tiled_dg2_rc_ccs: SKIP (0.002s)
[99.992451] [005/142] (916s left) prime_mmap (test_correct_cpu_write)
[100.374176] Starting subtest: test_correct_cpu_write
[100.377990] Starting dynamic subtest: test_correct_cpu_write-smem
[100.381463] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.001s)
[100.385112] Subtest test_correct_cpu_write: SUCCESS (0.002s)
[100.523635] [006/142] (915s left) gem_pxp (reject-modify-context-protection-on)
[103.429863] Subtest reject-modify-context-protection-on: SKIP (0.000s)
[103.585483] [007/142] (912s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[104.156440] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[105.981184] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (1.824s)
[106.875812] [008/142] (909s left) drm_fdinfo (idle)
[107.262961] Starting subtest: idle
[107.266792] Starting dynamic subtest: rcs0
[107.793470] Dynamic subtest rcs0: SUCCESS (0.530s)
[107.827553] Starting dynamic subtest: bcs0
[108.300227] Dynamic subtest bcs0: SUCCESS (0.505s)
[108.333080] Starting dynamic subtest: vcs0
[108.804911] Dynamic subtest vcs0: SUCCESS (0.504s)
[108.837450] Starting dynamic subtest: vcs1
[109.313642] Dynamic subtest vcs1: SUCCESS (0.504s)
[109.346302] Starting dynamic subtest: vecs0
[109.813728] Dynamic subtest vecs0: SUCCESS (0.505s)
[109.846615] Starting dynamic subtest: ccs0
[110.321471] Dynamic subtest ccs0: SUCCESS (0.505s)
[110.325655] Subtest idle: SUCCESS (3.057s)
[110.478706] [009/142] (905s left) gem_exec_fair (basic-flow)
[110.917055] Subtest basic-flow: SKIP (0.000s)
[111.062193] [010/142] (905s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[111.473157] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[111.479846] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[111.760542] [011/142] (904s left) kms_lease (cursor_implicit_plane)
[112.254991] Starting subtest: cursor_implicit_plane
[112.263795] Starting dynamic subtest: pipe-A-eDP-1
[112.947716] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.686s)
[112.982580] Starting dynamic subtest: pipe-B-eDP-1
[114.225783] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.273s)
[114.266118] Starting dynamic subtest: pipe-C-eDP-1
[115.856945] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.623s)
[115.890259] Starting dynamic subtest: pipe-D-eDP-1
[117.771540] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.908s)
[117.775722] Subtest cursor_implicit_plane: SUCCESS (5.514s)
[118.708786] [012/142] (897s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[119.084402] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[119.088138] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.002s)
[119.203323] [013/142] (897s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[119.699365] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[119.706518] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[119.848578] [014/142] (896s left) kms_plane_scaling (planes-downscale-factor-0-5)
[120.314990] Starting subtest: planes-downscale-factor-0-5
[120.321933] Starting dynamic subtest: pipe-A-eDP-1
[120.369905] Dynamic subtest pipe-A-eDP-1: SKIP (0.045s)
[120.404851] Starting dynamic subtest: pipe-B-eDP-1
[120.524979] Dynamic subtest pipe-B-eDP-1: SKIP (0.144s)
[120.561097] Starting dynamic subtest: pipe-C-eDP-1
[120.679743] Dynamic subtest pipe-C-eDP-1: SKIP (0.149s)
[120.715145] Starting dynamic subtest: pipe-D-eDP-1
[120.835264] Dynamic subtest pipe-D-eDP-1: SKIP (0.147s)
[120.841456] Subtest planes-downscale-factor-0-5: SKIP (0.515s)
[120.986686] [015/142] (895s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[121.493224] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[121.499997] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[121.646530] [016/142] (895s left) kms_vblank (pipe-a-query-forked-hang)
[122.115378] Starting subtest: pipe-A-query-forked-hang
[136.816035] Subtest pipe-A-query-forked-hang: SUCCESS (14.700s)
[137.762592] [017/142] (879s left) kms_plane_alpha_blend (alpha-transparent-fb)
[138.235973] Starting subtest: alpha-transparent-fb
[139.975290] Starting dynamic subtest: pipe-A-eDP-1
[141.233251] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.260s)
[141.270122] Starting dynamic subtest: pipe-D-eDP-1
[143.284651] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.042s)
[143.287509] Subtest alpha-transparent-fb: SUCCESS (5.047s)
[144.220250] [018/142] (872s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[144.593092] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[144.701886] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.107s)
[144.839115] [019/142] (872s left) gem_exec_schedule (u-lateslice)
[145.268942] Starting subtest: u-lateslice
[145.272145] Starting dynamic subtest: rcs0
[145.324953] Dynamic subtest rcs0: SUCCESS (0.060s)
[145.360083] Starting dynamic subtest: bcs0
[145.368396] Dynamic subtest bcs0: SUCCESS (0.041s)
[145.403131] Starting dynamic subtest: vcs0
[145.412047] Dynamic subtest vcs0: SUCCESS (0.043s)
[145.446075] Starting dynamic subtest: vcs1
[145.455784] Dynamic subtest vcs1: SUCCESS (0.043s)
[145.490671] Starting dynamic subtest: vecs0
[145.498498] Dynamic subtest vecs0: SUCCESS (0.041s)
[145.534432] Starting dynamic subtest: ccs0
[145.541865] Dynamic subtest ccs0: SUCCESS (0.043s)
[145.548564] Subtest u-lateslice: SUCCESS (0.278s)
[146.668684] [020/142] (870s left) gem_workarounds (reset-fd)
[147.134217] Starting subtest: reset-fd
[148.510409] Subtest reset-fd: SUCCESS (1.376s)
[148.710450] [021/142] (868s left) kms_psr (psr2_primary_mmap_gtt)
[149.189803] Starting subtest: psr2_primary_mmap_gtt
[152.130841] Subtest psr2_primary_mmap_gtt: SKIP (2.938s)
[152.474192] [022/142] (864s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_mtl_rc_ccs)
[154.130229] Starting subtest: pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs
[154.832719] Subtest pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs: SUCCESS (0.702s)
[156.162894] [023/142] (861s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[157.437881] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[157.441342] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SUCCESS (0.003s)
[158.531046] [024/142] (858s left) kms_prop_blob (invalid-set-prop-any)
[158.906181] Starting subtest: invalid-set-prop-any
[158.909997] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[159.039466] [025/142] (858s left) v3d/v3d_perfmon (get-values-valid-perfmon)
[159.518547] Subtest get-values-valid-perfmon: SKIP (0.000s)
[159.634371] [026/142] (858s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[160.054468] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[160.220870] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.162s)
[160.378555] [027/142] (857s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[160.872149] Starting subtest: fbcpsr-tiling-4
[172.046067] Subtest fbcpsr-tiling-4: SUCCESS (11.174s)
[172.288585] [028/142] (845s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_dg2_rc_ccs)
[173.849327] Starting subtest: pipe-D-bad-pixel-format-4_tiled_dg2_rc_ccs
[173.855129] Subtest pipe-D-bad-pixel-format-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[174.634847] [029/142] (843s left) api_intel_bb (intel-bb-blit-none)
[175.019092] Starting subtest: intel-bb-blit-none
[188.702511] Subtest intel-bb-blit-none: SUCCESS (13.688s)
[188.899313] [030/142] (829s left) gem_exec_balancer (bonded-semaphore)
[189.328619] Starting subtest: bonded-semaphore
[189.335140] Subtest bonded-semaphore: SKIP (0.000s)
[190.342685] [031/142] (827s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[190.899476] Starting subtest: yf-tiled-8bpp-rotate-90
[190.907257] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.001s)
[191.068578] [032/142] (827s left) syncobj_timeline (invalid-wait-bad-flags)
[191.438977] Starting subtest: invalid-wait-bad-flags
[191.442774] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[191.574226] [033/142] (826s left) prime_udl
[192.041927] [034/142] (826s left) gem_mmap_offset (bad-extensions)
[192.409406] Starting subtest: bad-extensions
[192.413132] Subtest bad-extensions: SUCCESS (0.000s)
[192.563094] [035/142] (825s left) i915_suspend (basic-s2idle-without-i915)
[192.921488] Starting subtest: basic-s2idle-without-i915
[216.077295] Subtest basic-s2idle-without-i915: SUCCESS (23.156s)
[216.181083] [036/142] (802s left) gem_close_race (basic-process)
[216.544354] Starting subtest: basic-process
[216.658222] Subtest basic-process: SUCCESS (0.113s)
[216.928737] [037/142] (801s left) kms_ccs (pipe-c-bad-aux-stride-y_tiled_gen12_mc_ccs)
[218.234346] Starting subtest: pipe-C-bad-aux-stride-y_tiled_gen12_mc_ccs
[218.240305] Subtest pipe-C-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[218.978520] [038/142] (799s left) kms_vblank (pipe-d-wait-busy)
[219.474379] Starting subtest: pipe-D-wait-busy
[223.501761] Subtest pipe-D-wait-busy: SUCCESS (4.027s)
[224.442904] [039/142] (794s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_dg2_rc_ccs)
[225.645328] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs
[225.651833] Subtest pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs: SKIP (0.002s)
[226.880713] [040/142] (791s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[227.386981] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[242.223326] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (14.837s)
[242.518105] [041/142] (776s left) prime_self_import (basic-llseek-bad)
[242.873931] Starting subtest: basic-llseek-bad
[242.887469] Subtest basic-llseek-bad: SUCCESS (0.010s)
[243.044683] [042/142] (775s left) kms_flip (2x-nonexisting-fb-interruptible)
[243.465376] Starting subtest: 2x-nonexisting-fb-interruptible
[243.638321] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.168s)
[243.804356] [043/142] (775s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[244.285928] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[244.295206] Starting dynamic subtest: pipe-A-eDP-1
[246.480214] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.187s)
[246.526237] Starting dynamic subtest: pipe-B-eDP-1
[250.008153] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.518s)
[250.056788] Starting dynamic subtest: pipe-C-eDP-1
[253.627628] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.608s)
[253.680941] Starting dynamic subtest: pipe-D-eDP-1
[256.956931] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.323s)
[256.960516] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (12.674s)
[258.470225] [044/142] (760s left) syncobj_timeline (single-wait-available-signaled)
[258.868165] Starting subtest: single-wait-available-signaled
[258.872627] Subtest single-wait-available-signaled: SUCCESS (0.001s)
[259.018248] [045/142] (760s left) api_intel_bb (purge-bb)
[259.385817] Starting subtest: purge-bb
[259.394302] Subtest purge-bb: SUCCESS (0.012s)
[259.571349] [046/142] (759s left) i915_pm_rpm (cursor-dpms)
[265.684783] Starting subtest: cursor-dpms
[297.870441] Subtest cursor-dpms: SUCCESS (32.185s)
[299.096084] [047/142] (720s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[299.598269] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[299.604645] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[299.755116] [048/142] (719s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[300.288332] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[303.145074] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (2.856s)
[303.433340] [049/142] (716s left) perf (gen12-unprivileged-single-ctx-counters)
[304.363383] Starting subtest: gen12-unprivileged-single-ctx-counters
[304.366822] Starting dynamic subtest: rcs0
[304.642544] Dynamic subtest rcs0: SUCCESS (0.277s)
[304.646127] Subtest gen12-unprivileged-single-ctx-counters: SUCCESS (0.283s)
[304.755840] [050/142] (714s left) gem_mmap_gtt (cpuset-basic-small-copy)
[305.129370] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[305.248249] [051/142] (714s left) sw_sync (sync_multi_producer_single_consumer)
[305.606156] Starting subtest: sync_multi_producer_single_consumer
[306.689692] Subtest sync_multi_producer_single_consumer: SUCCESS (1.082s)
[306.849963] [052/142] (712s left) gem_bad_reloc (negative-reloc-lut)
[307.251060] Subtest negative-reloc-lut: SKIP (0.000s)
[307.396594] [053/142] (712s left) kms_lease (lessee_list)
[307.882486] Starting subtest: lessee_list
[307.893317] Starting dynamic subtest: pipe-A-eDP-1
[307.897162] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.004s)
[307.940221] Starting dynamic subtest: pipe-B-eDP-1
[307.944105] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.003s)
[307.988204] Starting dynamic subtest: pipe-C-eDP-1
[307.992240] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.003s)
[308.030919] Starting dynamic subtest: pipe-D-eDP-1
[308.034056] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.003s)
[308.036987] Subtest lessee_list: SUCCESS (0.046s)
[308.103755] [054/142] (711s left) perf (whitelisted-registers-userspace-config)
[308.731359] Starting subtest: whitelisted-registers-userspace-config
[308.734937] Subtest whitelisted-registers-userspace-config: SUCCESS (0.005s)
[308.833765] [055/142] (711s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[309.354130] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[314.837658] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (5.490s)
[315.109344] [056/142] (704s left) kms_chamelium_frames (dp-crc-fast)
[315.982468] Subtest dp-crc-fast: SKIP (0.000s)
[316.860472] [057/142] (703s left) kms_ccs (pipe-d-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[318.479334] Starting subtest: pipe-D-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[318.483056] Subtest pipe-D-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SUCCESS (0.002s)
[319.093121] [058/142] (701s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[319.298202] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[319.303078] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[319.394539] [059/142] (700s left) gem_close_race (multigpu-basic-process)
[319.539189] Starting subtest: multigpu-basic-process
[319.543700] Subtest multigpu-basic-process: SKIP (0.000s)
[319.761044] [060/142] (700s left) gem_userptr_blits (nohangcheck)
[320.139338] Starting subtest: nohangcheck
[321.703605] Subtest nohangcheck: SUCCESS (1.564s)
[321.880878] [061/142] (698s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[322.448797] Starting subtest: cursor-vs-flip-atomic-transitions
[360.177884] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (37.728s)
[361.122420] [062/142] (659s left) kms_concurrent (pipe-d)
[361.625292] Starting subtest: pipe-D
[361.633953] Starting dynamic subtest: eDP-1
[363.562995] Dynamic subtest eDP-1: SUCCESS (1.928s)
[363.567139] Subtest pipe-D: SUCCESS (1.939s)
[364.498717] [063/142] (656s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[365.009152] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[365.016345] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[365.184315] [064/142] (655s left) gem_exec_suspend (basic)
[365.608276] Starting subtest: basic
[365.612857] Starting dynamic subtest: smem
[369.513463] Dynamic subtest smem: SUCCESS (3.901s)
[369.517240] Subtest basic: SUCCESS (3.905s)
[369.825754] [065/142] (650s left) sw_sync (sync_random_merge)
[370.189795] Starting subtest: sync_random_merge
[370.469116] Subtest sync_random_merge: SUCCESS (0.279s)
[370.619423] [066/142] (650s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[370.989787] Starting subtest: single-wait-for-submit-unsubmitted
[371.092669] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[371.264701] [067/142] (649s left) gem_wait (invalid-buf)
[371.656335] Starting subtest: invalid-buf
[371.660489] Subtest invalid-buf: SUCCESS (0.000s)
[372.100657] [068/142] (648s left) kms_addfb_basic (addfb25-bad-modifier)
[372.471896] Starting subtest: addfb25-bad-modifier
[372.475680] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[372.762370] [069/142] (648s left) gem_exec_fence (syncobj-timeline-wait)
[375.067865] Starting subtest: syncobj-timeline-wait
[375.618539] Subtest syncobj-timeline-wait: SUCCESS (0.557s)
[377.702432] [070/142] (643s left) syncobj_timeline (multi-wait-signaled)
[378.084950] Starting subtest: multi-wait-signaled
[378.088091] Subtest multi-wait-signaled: SUCCESS (0.004s)
[378.258045] [071/142] (642s left) gem_ctx_isolation (preservation)
[378.686179] Starting subtest: preservation
[378.689586] Starting dynamic subtest: rcs0
[379.234124] Dynamic subtest rcs0: SUCCESS (0.545s)
[379.276602] Starting dynamic subtest: bcs0
[379.869615] Dynamic subtest bcs0: SUCCESS (0.632s)
[379.917621] Starting dynamic subtest: vcs0
[380.484148] Dynamic subtest vcs0: SUCCESS (0.610s)
[380.530504] Starting dynamic subtest: vcs1
[382.023149] Dynamic subtest vcs1: SUCCESS (1.534s)
[382.068692] Starting dynamic subtest: vecs0
[384.030544] Dynamic subtest vecs0: SUCCESS (2.003s)
[384.077103] Starting dynamic subtest: ccs0
[386.047295] Dynamic subtest ccs0: SUCCESS (2.012s)
[386.050980] Subtest preservation: SUCCESS (7.362s)
[386.386461] [072/142] (634s left) gem_userptr_blits (create-destroy-sync)
[386.840418] Starting subtest: create-destroy-sync
[391.903275] Subtest create-destroy-sync: SUCCESS (5.061s)
[392.154957] [073/142] (629s left) kms_ccs (pipe-a-bad-aux-stride-yf_tiled_ccs)
[393.156137] Starting subtest: pipe-A-bad-aux-stride-yf_tiled_ccs
[393.163837] Subtest pipe-A-bad-aux-stride-yf_tiled_ccs: SKIP (0.000s)
[393.988980] [074/142] (627s left) gen9_exec_parse (cmd-crossing-page)
[394.385433] Subtest cmd-crossing-page: SKIP (0.000s)
[394.518479] [075/142] (627s left) gem_exec_balancer (noheartbeat)
[395.701661] Starting subtest: noheartbeat
[395.708642] Subtest noheartbeat: SKIP (0.001s)
[395.860636] [076/142] (625s left) kms_async_flips (test-cursor)
[396.338660] Starting subtest: test-cursor
[396.344512] Subtest test-cursor: SKIP (0.001s)
[398.297000] [077/142] (623s left) gen7_exec_parse (load-register-reg)
[398.693522] Subtest load-register-reg: SKIP (0.000s)
[398.800574] [078/142] (623s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[399.102623] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[399.107498] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[399.242332] [079/142] (622s left) kms_vblank (pipe-b-ts-continuation-modeset-rpm)
[399.421102] Starting subtest: pipe-B-ts-continuation-modeset-rpm
[403.286207] Subtest pipe-B-ts-continuation-modeset-rpm: SUCCESS (3.863s)
[404.228045] [080/142] (617s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[404.747435] Starting subtest: fbc-1p-pri-indfb-multidraw
[432.326662] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (27.581s)
[432.627791] [081/142] (589s left) kms_flip (nonexisting-fb)
[433.024245] Starting subtest: nonexisting-fb
[433.056352] Starting dynamic subtest: A-eDP1
[434.820511] Dynamic subtest A-eDP1: SUCCESS (1.768s)
[434.865943] Starting dynamic subtest: B-eDP1
[436.452404] Dynamic subtest B-eDP1: SUCCESS (1.626s)
[436.511076] Starting dynamic subtest: C-eDP1
[438.047881] Dynamic subtest C-eDP1: SUCCESS (1.590s)
[438.094890] Starting dynamic subtest: D-eDP1
[439.631700] Dynamic subtest D-eDP1: SUCCESS (1.579s)
[439.658867] Subtest nonexisting-fb: SUCCESS (6.639s)
[441.255726] [082/142] (580s left) vgem_basic (unload)
[441.620196] Starting subtest: unload
[470.818519] Subtest unload: SUCCESS (29.201s)
[471.086969] [083/142] (551s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[471.596166] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[482.120401] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (10.528s)
[482.392585] [084/142] (539s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_gen12_mc_ccs)
[483.782463] Starting subtest: pipe-C-bad-rotation-90-y_tiled_gen12_mc_ccs
[483.789284] Subtest pipe-C-bad-rotation-90-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[484.789937] [085/142] (537s left) sw_sync (sync_busy)
[485.153517] Starting subtest: sync_busy
[485.165543] Subtest sync_busy: SUCCESS (0.013s)
[485.309496] [086/142] (537s left) gem_fence_thrash (bo-write-verify-threaded-y)
[485.666050] Starting subtest: bo-write-verify-threaded-y
[485.682907] Subtest bo-write-verify-threaded-y: SKIP (0.009s)
[485.801520] [087/142] (536s left) kms_getfb (getfb-handle-zero)
[486.186188] Starting subtest: getfb-handle-zero
[486.189942] Subtest getfb-handle-zero: SUCCESS (0.000s)
[486.355582] [088/142] (536s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[486.902921] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[490.224066] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (3.322s)
[490.528467] [089/142] (532s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs)
[492.096299] Starting subtest: pipe-C-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs
[492.113080] Subtest pipe-C-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs: SKIP (0.014s)
[492.960969] [090/142] (529s left) i915_pm_backlight (fade)
[493.455591] Starting subtest: fade
[493.808483] Starting dynamic subtest: eDP-1
[496.738969] Dynamic subtest eDP-1: SUCCESS (2.932s)
[496.744135] Subtest fade: SUCCESS (3.285s)
[497.689240] [091/142] (525s left) v3d/v3d_submit_cl (simple-flush-cache)
[498.167413] Subtest simple-flush-cache: SKIP (0.000s)
[498.299914] [092/142] (524s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0)
[498.830148] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0
[498.837342] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[498.980373] [093/142] (524s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[499.525396] Starting subtest: 4-tiled-8bpp-rotate-0
[503.713946] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (4.185s)
[504.028098] [094/142] (519s left) kms_dsc (dsc-with-bpc)
[504.507492] Starting subtest: dsc-with-bpc
[504.514408] Subtest dsc-with-bpc: SKIP (0.006s)
[504.662196] [095/142] (518s left) gem_ctx_shared (q-in-order)
[505.080233] Starting subtest: Q-in-order
[505.083839] Starting dynamic subtest: rcs0
[505.428906] Dynamic subtest rcs0: SUCCESS (0.346s)
[505.492600] Starting dynamic subtest: bcs0
[505.672116] Dynamic subtest bcs0: SUCCESS (0.240s)
[505.735712] Starting dynamic subtest: vcs0
[505.926153] Dynamic subtest vcs0: SUCCESS (0.251s)
[505.988788] Starting dynamic subtest: vcs1
[506.179913] Dynamic subtest vcs1: SUCCESS (0.250s)
[506.241079] Starting dynamic subtest: vecs0
[506.435320] Dynamic subtest vecs0: SUCCESS (0.252s)
[506.496964] Starting dynamic subtest: ccs0
[506.732591] Dynamic subtest ccs0: SUCCESS (0.293s)
[506.736381] Subtest Q-in-order: SUCCESS (1.652s)
[507.663131] [096/142] (515s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[508.181544] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[508.188270] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[508.351815] [097/142] (515s left) perf (polling-parameterized)
[508.956944] Starting subtest: polling-parameterized
[529.054432] Subtest polling-parameterized: SUCCESS (20.096s)
[529.175226] [098/142] (494s left) gem_exec_schedule (lateslice)
[529.610477] Starting subtest: lateslice
[529.613751] Starting dynamic subtest: rcs0
[529.667855] Dynamic subtest rcs0: SUCCESS (0.061s)
[529.720796] Starting dynamic subtest: bcs0
[529.725835] Dynamic subtest bcs0: SUCCESS (0.046s)
[529.778413] Starting dynamic subtest: vcs0
[529.781911] Dynamic subtest vcs0: SUCCESS (0.046s)
[529.832885] Starting dynamic subtest: vcs1
[529.836142] Dynamic subtest vcs1: SUCCESS (0.041s)
[529.884545] Starting dynamic subtest: vecs0
[529.888238] Dynamic subtest vecs0: SUCCESS (0.042s)
[529.936291] Starting dynamic subtest: ccs0
[529.939287] Dynamic subtest ccs0: SUCCESS (0.049s)
[529.942334] Subtest lateslice: SUCCESS (0.310s)
[531.710496] [099/142] (491s left) gem_lmem_swapping (parallel-random-verify-ccs)
[532.089054] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[532.189321] [100/142] (491s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_dg2_rc_ccs)
[533.306563] Starting subtest: pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs
[533.313309] Subtest pipe-B-bad-pixel-format-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[534.592300] [101/142] (489s left) kms_vblank (pipe-c-query-busy-hang)
[535.070758] Starting subtest: pipe-C-query-busy-hang
[550.858928] Subtest pipe-C-query-busy-hang: SUCCESS (15.784s)
[551.803836] [102/142] (472s left) v3d/v3d_create_bo (create-bo-invalid-flags)
[552.284589] Subtest create-bo-invalid-flags: SKIP (0.000s)
[552.419229] [103/142] (471s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[552.891832] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[552.905757] Starting dynamic subtest: pipe-A-default-mode
[554.978103] Dynamic subtest pipe-A-default-mode: SKIP (2.070s)
[554.984154] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (2.083s)
[555.231985] [104/142] (468s left) kms_ccs (pipe-a-bad-rotation-90-4_tiled_mtl_rc_ccs_cc)
[556.115753] Starting subtest: pipe-A-bad-rotation-90-4_tiled_mtl_rc_ccs_cc
[556.929258] Subtest pipe-A-bad-rotation-90-4_tiled_mtl_rc_ccs_cc: SUCCESS (0.808s)
[558.815746] [105/142] (465s left) perf (invalid-create-userspace-config)
[559.425740] Starting subtest: invalid-create-userspace-config
[559.435268] Subtest invalid-create-userspace-config: SUCCESS (0.008s)
[559.535578] [106/142] (464s left) kms_atomic (test-only)
[560.322203] Starting subtest: test-only
[563.249792] Subtest test-only: SUCCESS (2.924s)
[564.196690] [107/142] (460s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-submitted)
[564.569363] Starting subtest: invalid-multi-wait-available-unsubmitted-submitted
[564.572455] Subtest invalid-multi-wait-available-unsubmitted-submitted: SUCCESS (0.002s)
[564.720111] [108/142] (459s left) kms_flip (plain-flip-ts-check)
[565.110431] Starting subtest: plain-flip-ts-check
[565.138668] Starting dynamic subtest: A-eDP1
[574.567783] Dynamic subtest A-eDP1: SUCCESS (9.429s)
[574.618156] Starting dynamic subtest: B-eDP1
[583.839667] Dynamic subtest B-eDP1: SUCCESS (9.267s)
[583.890166] Starting dynamic subtest: C-eDP1
[593.086500] Dynamic subtest C-eDP1: SUCCESS (9.241s)
[593.135577] Starting dynamic subtest: D-eDP1
[602.369687] Dynamic subtest D-eDP1: SUCCESS (9.277s)
[602.396746] Subtest plain-flip-ts-check: SUCCESS (37.292s)
[603.774382] [109/142] (420s left) gem_exec_whisper (basic-normal-all)
[604.214982] Starting subtest: basic-normal-all
[625.956675] Subtest basic-normal-all: FAIL (21.724s)
[627.695309] [110/142] (396s left) i915_pm_rpm (gem-evict-pwrite)
[633.906779] Starting subtest: gem-evict-pwrite
[633.914679] Subtest gem-evict-pwrite: SKIP (0.000s)
[634.770531] [111/142] (389s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[635.285364] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[635.291883] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[635.463055] [112/142] (389s left) kms_color (ctm-0-75)
[635.945501] Starting subtest: ctm-0-75
[635.949637] Starting dynamic subtest: pipe-A
[642.840218] Dynamic subtest pipe-A: SUCCESS (6.890s)
[642.885999] Starting dynamic subtest: pipe-B
[650.446277] Dynamic subtest pipe-B: SUCCESS (7.603s)
[650.492504] Starting dynamic subtest: pipe-C
[658.180493] Dynamic subtest pipe-C: SUCCESS (7.731s)
[658.226258] Starting dynamic subtest: pipe-D
[666.137752] Dynamic subtest pipe-D: SUCCESS (7.955s)
[666.141322] Subtest ctm-0-75: SUCCESS (30.195s)
[667.098161] [113/142] (357s left) kms_vblank (pipe-b-ts-continuation-suspend)
[667.575969] Starting subtest: pipe-B-ts-continuation-suspend
[686.190718] Subtest pipe-B-ts-continuation-suspend: SUCCESS (18.612s)
[687.152114] [114/142] (337s left) kms_cursor_crc (cursor-random-max-size)
[688.464454] Starting subtest: cursor-random-max-size
[688.474652] Subtest cursor-random-max-size: SKIP (0.000s)
[688.727477] [115/142] (336s left) gem_exec_schedule (fifo)
[689.171933] Starting subtest: fifo
[689.175572] Starting dynamic subtest: rcs0
[689.533162] Dynamic subtest rcs0: SUCCESS (0.363s)
[689.584359] Starting dynamic subtest: bcs0
[689.784061] Dynamic subtest bcs0: SUCCESS (0.246s)
[689.829492] Starting dynamic subtest: vcs0
[690.025827] Dynamic subtest vcs0: SUCCESS (0.238s)
[690.075989] Starting dynamic subtest: vcs1
[690.279891] Dynamic subtest vcs1: SUCCESS (0.250s)
[690.331320] Starting dynamic subtest: vecs0
[690.536102] Dynamic subtest vecs0: SUCCESS (0.251s)
[690.581949] Starting dynamic subtest: ccs0
[690.934632] Dynamic subtest ccs0: SUCCESS (0.395s)
[690.938412] Subtest fifo: SUCCESS (1.769s)
[691.794853] [116/142] (333s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_rc_ccs)
[693.463531] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs
[694.713424] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs: SUCCESS (1.250s)
[696.065528] [117/142] (329s left) vc4/vc4_wait_bo (bad-bo)
[696.533477] Subtest bad-bo: SKIP (0.000s)
[696.668385] [118/142] (328s left) kms_vblank (pipe-d-query-forked)
[697.141641] Starting subtest: pipe-D-query-forked
[701.575994] Subtest pipe-D-query-forked: SUCCESS (4.429s)
[702.525091] [119/142] (322s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[703.022166] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[709.534428] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.514s)
[709.820720] [120/142] (315s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[710.389337] Starting subtest: basic-flip-before-cursor-legacy
[711.788175] Subtest basic-flip-before-cursor-legacy: SUCCESS (1.397s)
[712.743403] [121/142] (312s left) kms_selftest (drm_damage)
[713.099960] Starting subtest: drm_damage
[713.113165] Subtest drm_damage: SKIP (0.008s)
[713.253941] [122/142] (312s left) kms_fbcon_fbt (fbc)
[713.648357] Starting subtest: fbc
[719.368655] Subtest fbc: SUCCESS (5.717s)
[719.566669] [123/142] (306s left) core_getversion
[720.083067] [124/142] (305s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[720.653924] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[720.661271] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[721.005428] [125/142] (305s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[721.503817] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[721.510917] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[721.680448] [126/142] (304s left) gem_ctx_persistence (legacy-engines-hang)
[722.083626] Starting subtest: legacy-engines-hang
[722.087635] Starting dynamic subtest: render
[722.187911] Dynamic subtest render: SUCCESS (0.106s)
[722.251407] Starting dynamic subtest: bsd1
[722.254588] Dynamic subtest bsd1: SUCCESS (0.044s)
[722.306792] Starting dynamic subtest: bsd2
[722.310610] Dynamic subtest bsd2: SUCCESS (0.052s)
[722.362816] Starting dynamic subtest: blt
[722.366472] Dynamic subtest blt: SUCCESS (0.058s)
[722.416769] Starting dynamic subtest: vebox
[722.420412] Dynamic subtest vebox: SUCCESS (0.042s)
[722.423332] Subtest legacy-engines-hang: SUCCESS (0.331s)
[722.567976] [127/142] (303s left) kms_vblank (pipe-b-ts-continuation-modeset)
[723.059362] Starting subtest: pipe-B-ts-continuation-modeset
[726.069872] Subtest pipe-B-ts-continuation-modeset: SUCCESS (3.010s)
[727.025427] [128/142] (299s left) gem_pread (uncached)
[727.395624] Subtest uncached: SKIP (0.000s)
[727.530940] [129/142] (298s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[728.046189] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[740.683824] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (12.639s)
[740.950861] [130/142] (285s left) prime_mmap (test_forked_cpu_write)
[741.315777] Starting subtest: test_forked_cpu_write
[741.319420] Starting dynamic subtest: test_forked_cpu_write-smem
[741.378215] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.065s)
[741.382150] Subtest test_forked_cpu_write: SUCCESS (0.070s)
[741.543710] [131/142] (285s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_ccs)
[742.621290] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_ccs
[742.638045] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_ccs: SKIP (0.014s)
[743.977859] [132/142] (282s left) gem_exec_parallel (contexts)
[744.400648] Starting subtest: contexts
[744.404507] Starting dynamic subtest: rcs0
[750.968782] Dynamic subtest rcs0: SUCCESS (6.567s)
[751.012653] Starting dynamic subtest: bcs0
[756.960812] Dynamic subtest bcs0: SUCCESS (5.986s)
[757.011104] Starting dynamic subtest: vcs0
[762.021518] Dynamic subtest vcs0: SUCCESS (5.056s)
[762.094490] Starting dynamic subtest: vcs1
[767.923658] Dynamic subtest vcs1: SUCCESS (5.895s)
[767.977969] Starting dynamic subtest: vecs0
[774.025220] Dynamic subtest vecs0: SUCCESS (6.097s)
[774.082216] Starting dynamic subtest: ccs0
[780.881727] Dynamic subtest ccs0: SUCCESS (6.850s)
[780.885619] Subtest contexts: SUCCESS (36.485s)
[781.233866] [133/142] (245s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[781.772870] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[781.779499] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[781.955832] [134/142] (244s left) gem_exec_params (cliprects-invalid)
[782.351611] Starting subtest: cliprects-invalid
[782.356088] Subtest cliprects-invalid: SUCCESS (0.002s)
[782.530763] [135/142] (244s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[782.910384] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[783.033351] [136/142] (244s left) vc4/vc4_perfmon (create-perfmon-exceed)
[783.502876] Subtest create-perfmon-exceed: SKIP (0.000s)
[783.639504] [137/142] (243s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[784.180859] Starting subtest: cursorA-vs-flipA-legacy
[796.009530] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.831s)
[796.979386] [138/142] (230s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_rc_ccs)
[797.905882] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs
[797.913979] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs: SKIP (0.003s)
[799.382810] [139/142] (227s left) kms_content_protection (dp-mst-type-1)
[800.079405] Starting subtest: dp-mst-type-1
[800.086234] Subtest dp-mst-type-1: SKIP (0.001s)
[800.292285] [140/142] (227s left) vc4/vc4_perfmon (create-perfmon-0)
[800.778447] Subtest create-perfmon-0: SKIP (0.000s)
[800.922456] [141/142] (226s left) kms_cursor_crc (cursor-onscreen-128x128)
[801.832247] Starting subtest: cursor-onscreen-128x128
[801.868775] Starting dynamic subtest: pipe-A-eDP-1
[805.827058] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.961s)
[806.041832] Starting dynamic subtest: pipe-D-eDP-1
[811.059896] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.063s)
[811.063902] Subtest cursor-onscreen-128x128: SUCCESS (9.226s)
[812.157518] [142/142] (215s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[812.645881] Starting subtest: fbc-rgb101010-draw-mmap-wc
[817.959102] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (5.315s)
[818.100196] Closing watchdogs
Done.