[49.443797] Initializing watchdogs
[49.444321]   /dev/watchdog0
[49.478236] [001/142] (960s left) gem_mmap_offset (blt-coherency)
[49.889197] Starting subtest: blt-coherency
[49.943245] Subtest blt-coherency: SUCCESS (0.056s)
[50.176724] [002/142] (959s left) dumb_buffer (invalid-bpp)
[50.546654] Starting subtest: invalid-bpp
[50.551088] Subtest invalid-bpp: SUCCESS (0.000s)
[50.696903] [003/142] (959s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[51.214121] Starting subtest: fbcpsr-rgb565-draw-blt
[62.974973] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (11.761s)
[63.188420] [004/142] (946s left) gem_exec_reloc (basic-softpin)
[63.341893] Subtest basic-softpin: SKIP (0.000s)
[63.432047] [005/142] (946s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-render)
[63.619284] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-render
[74.599167] Subtest fbc-1p-offscren-pri-shrfb-draw-render: SUCCESS (10.984s)
[74.798919] [006/142] (935s left) device_reset (unbind-cold-reset-rebind)
[75.603971] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[75.735779] [007/142] (934s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_mtl_rc_ccs_cc)
[76.875518] Starting subtest: pipe-B-crc-primary-basic-4_tiled_mtl_rc_ccs_cc
[78.046811] Subtest pipe-B-crc-primary-basic-4_tiled_mtl_rc_ccs_cc: SUCCESS (1.171s)
[79.622332] [008/142] (930s left) vgem_basic (mmap)
[80.136904] Starting subtest: mmap
[80.167362] Subtest mmap: SUCCESS (0.033s)
[80.284840] [009/142] (930s left) kms_flip (2x-dpms-vs-vblank-race)
[80.676165] Starting subtest: 2x-dpms-vs-vblank-race
[80.837638] Subtest 2x-dpms-vs-vblank-race: SKIP (0.160s)
[81.016965] [010/142] (929s left) gem_exec_balancer (indices)
[81.445215] Starting subtest: indices
[87.482794] Subtest indices: SUCCESS (6.039s)
[88.143412] [011/142] (922s left) drm_fdinfo (most-busy-check-all)
[88.526766] Starting subtest: most-busy-check-all
[88.530578] Starting dynamic subtest: rcs0
[88.544800] Dynamic subtest rcs0: SKIP (0.015s)
[88.580174] Starting dynamic subtest: bcs0
[88.587601] Dynamic subtest bcs0: SKIP (0.003s)
[88.621059] Starting dynamic subtest: vcs0
[88.627696] Dynamic subtest vcs0: SKIP (0.003s)
[88.659094] Starting dynamic subtest: vcs1
[88.665220] Dynamic subtest vcs1: SKIP (0.003s)
[88.695494] Starting dynamic subtest: vecs0
[88.701553] Dynamic subtest vecs0: SKIP (0.003s)
[88.730875] Starting dynamic subtest: ccs0
[88.736918] Dynamic subtest ccs0: SKIP (0.003s)
[88.742837] Subtest most-busy-check-all: SKIP (0.033s)
[88.789343] [012/142] (921s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[89.360425] Starting subtest: x-tiled-16bpp-rotate-0
[94.182353] Subtest x-tiled-16bpp-rotate-0: SUCCESS (4.823s)
[94.664671] [013/142] (916s left) sw_sync (alloc_fence_invalid_timeline)
[95.027798] Starting subtest: alloc_fence_invalid_timeline
[95.032058] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[95.144648] [014/142] (915s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary)
[95.656841] Starting subtest: fbc-indfb-scaledprimary
[100.587796] Subtest fbc-indfb-scaledprimary: SUCCESS (4.933s)
[100.858119] [015/142] (909s left) i915_pm_rpm (system-suspend-modeset)
[107.071509] Starting subtest: system-suspend-modeset
[132.599524] Subtest system-suspend-modeset: SUCCESS (25.529s)
[133.240261] [016/142] (877s left) syncobj_wait (single-wait-signaled)
[133.620825] Starting subtest: single-wait-signaled
[133.624419] Subtest single-wait-signaled: SUCCESS (0.002s)
[133.767530] [017/142] (877s left) syncobj_timeline (wait-any-snapshot)
[134.143314] Starting subtest: wait-any-snapshot
[134.223179] Subtest wait-any-snapshot: SUCCESS (0.084s)
[134.364701] [018/142] (876s left) perf_pmu (gt-awake)
[134.756391] Starting subtest: gt-awake
[143.546638] Subtest gt-awake: SUCCESS (8.789s)
[143.731780] [019/142] (867s left) gem_exec_balancer (semaphore)
[144.158181] Starting subtest: semaphore
[152.524665] Subtest semaphore: SUCCESS (8.365s)
[153.539158] [020/142] (857s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[153.922565] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[154.029801] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[154.202442] [021/142] (857s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[154.714964] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[154.721808] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[154.875074] [022/142] (856s left) kms_chamelium_audio (hdmi-audio)
[155.758276] Subtest hdmi-audio: SKIP (0.000s)
[156.652364] [023/142] (854s left) kms_ccs (pipe-a-crc-primary-rotation-180-4_tiled_dg2_mc_ccs)
[157.605940] Starting subtest: pipe-A-crc-primary-rotation-180-4_tiled_dg2_mc_ccs
[157.612350] Subtest pipe-A-crc-primary-rotation-180-4_tiled_dg2_mc_ccs: SKIP (0.002s)
[159.065041] [024/142] (852s left) template (a)
[159.436109] Starting subtest: A
[159.439799] Subtest A: SUCCESS (0.000s)
[159.567595] [025/142] (852s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[160.075859] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[175.869218] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (15.796s)
[176.144595] [026/142] (835s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[176.688641] Starting subtest: y-tiled-16bpp-rotate-90
[176.695637] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[176.832586] [027/142] (834s left) kms_ccs (pipe-c-bad-pixel-format-y_tiled_gen12_rc_ccs)
[178.202196] Starting subtest: pipe-C-bad-pixel-format-y_tiled_gen12_rc_ccs
[178.209077] Subtest pipe-C-bad-pixel-format-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[179.261344] [028/142] (832s left) kms_content_protection (content_type_change)
[179.942350] Starting subtest: content_type_change
[184.135470] Subtest content_type_change: SKIP (4.190s)
[185.668483] [029/142] (826s left) gem_mmap_gtt (bad-object)
[186.044229] Subtest bad-object: SKIP (0.000s)
[186.156760] [030/142] (825s left) gem_exec_params (mmapped)
[186.562154] Starting subtest: mmapped
[186.566950] Subtest mmapped: SUCCESS (0.010s)
[186.737298] [031/142] (825s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[187.222309] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[187.235226] Starting dynamic subtest: pipe-A-default-mode
[187.638243] Dynamic subtest pipe-A-default-mode: SKIP (0.401s)
[187.645291] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.414s)
[188.571877] [032/142] (823s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs)
[189.662431] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs
[189.678450] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs: SKIP (0.013s)
[191.008856] [033/142] (821s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[191.529659] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[191.536383] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[191.691892] [034/142] (820s left) kms_psr2_sf (cursor-plane-move-continuous-exceed-sf)
[199.006768] Starting subtest: cursor-plane-move-continuous-exceed-sf
[199.014821] Starting dynamic subtest: pipe-A-eDP-1
[210.607174] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.589s)
[210.654723] Starting dynamic subtest: pipe-B-eDP-1
[222.273716] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.658s)
[222.277417] Subtest cursor-plane-move-continuous-exceed-sf: SUCCESS (23.268s)
[223.227161] [035/142] (789s left) kms_atomic_interruptible (legacy-cursor)
[223.725881] Starting subtest: legacy-cursor
[223.735288] Starting dynamic subtest: eDP-1-pipe-A
[231.482560] Dynamic subtest eDP-1-pipe-A: SUCCESS (7.745s)
[231.486347] Subtest legacy-cursor: SUCCESS (7.756s)
[232.437800] [036/142] (780s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[232.951409] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[232.957896] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[233.118147] [037/142] (779s left) i915_pm_dc (dc5-psr)
[234.607691] Starting subtest: dc5-psr
[238.123446] Subtest dc5-psr: SUCCESS (3.516s)
[239.450820] [038/142] (773s left) gem_exec_schedule (preempt-queue-chain)
[239.889862] Subtest preempt-queue-chain: SKIP (0.000s)
[240.315972] [039/142] (772s left) gem_exec_params (sol-reset-invalid)
[240.712534] Starting subtest: sol-reset-invalid
[240.726863] Subtest sol-reset-invalid: SUCCESS (0.010s)
[242.691827] [040/142] (770s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25)
[243.181748] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25
[243.192685] Starting dynamic subtest: pipe-A-eDP-1
[243.232381] Dynamic subtest pipe-A-eDP-1: SKIP (0.039s)
[243.280172] Starting dynamic subtest: pipe-B-eDP-1
[243.385562] Dynamic subtest pipe-B-eDP-1: SKIP (0.143s)
[243.433432] Starting dynamic subtest: pipe-C-eDP-1
[243.534385] Dynamic subtest pipe-C-eDP-1: SKIP (0.133s)
[243.575804] Starting dynamic subtest: pipe-D-eDP-1
[243.598981] Dynamic subtest pipe-D-eDP-1: SKIP (0.062s)
[243.605256] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.413s)
[243.737734] [041/142] (769s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs)
[245.543128] Starting subtest: pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs
[245.559813] Subtest pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs: SKIP (0.013s)
[246.150990] [042/142] (766s left) gem_exec_schedule (semaphore-user)
[246.597263] Starting subtest: semaphore-user
[246.646616] Subtest semaphore-user: SUCCESS (0.047s)
[248.641683] [043/142] (764s left) kms_plane_scaling (plane-downscale-with-rotation-factor-0-5)
[249.147252] Starting subtest: plane-downscale-with-rotation-factor-0-5
[249.157163] Starting dynamic subtest: pipe-A-eDP-1
[249.299316] Dynamic subtest pipe-A-eDP-1: SKIP (0.135s)
[249.341047] Starting dynamic subtest: pipe-B-eDP-1
[249.557161] Dynamic subtest pipe-B-eDP-1: SKIP (0.250s)
[249.605084] Starting dynamic subtest: pipe-C-eDP-1
[249.821127] Dynamic subtest pipe-C-eDP-1: SKIP (0.251s)
[249.861323] Starting dynamic subtest: pipe-D-eDP-1
[250.065313] Dynamic subtest pipe-D-eDP-1: SKIP (0.234s)
[250.071449] Subtest plane-downscale-with-rotation-factor-0-5: SKIP (0.916s)
[250.261915] [044/142] (762s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-gtt)
[250.774574] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-gtt
[250.781541] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[250.946822] [045/142] (762s left) kms_vblank (pipe-d-query-forked)
[251.430428] Starting subtest: pipe-D-query-forked
[255.851734] Subtest pipe-D-query-forked: SUCCESS (4.418s)
[256.795713] [046/142] (756s left) kms_vblank (pipe-a-ts-continuation-dpms-rpm)
[257.274889] Starting subtest: pipe-A-ts-continuation-dpms-rpm
[260.138869] Subtest pipe-A-ts-continuation-dpms-rpm: SUCCESS (2.866s)
[261.096604] [047/142] (752s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[261.599672] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[261.606297] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[261.774900] [048/142] (751s left) kms_async_flips (alternate-sync-async-flip)
[262.247214] Starting subtest: alternate-sync-async-flip
[264.478265] Starting dynamic subtest: pipe-A-eDP-1
[266.400816] Dynamic subtest pipe-A-eDP-1: FAIL (1.334s)
[267.932698] Starting dynamic subtest: pipe-B-eDP-1
[270.429320] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.540s)
[272.316749] Starting dynamic subtest: pipe-C-eDP-1
[274.765344] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.485s)
[276.274969] Starting dynamic subtest: pipe-D-eDP-1
[278.743516] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.508s)
[278.746924] Subtest alternate-sync-async-flip: FAIL (16.501s)
[280.368630] [049/142] (733s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[280.811869] Starting subtest: y-tiled-ccs-to-yf-tiled
[280.818087] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[281.083537] [050/142] (732s left) perf_pmu (interrupts)
[281.469886] Starting subtest: interrupts
[282.745691] Subtest interrupts: SUCCESS (1.275s)
[282.909714] [051/142] (730s left) kms_busy (extended-pageflip-hang-oldfb)
[283.430745] Starting subtest: extended-pageflip-hang-oldfb
[283.439879] Starting dynamic subtest: pipe-A
[284.469556] Dynamic subtest pipe-A: SUCCESS (1.030s)
[284.529759] Starting dynamic subtest: pipe-D
[285.973454] Dynamic subtest pipe-D: SUCCESS (1.480s)
[285.977141] Subtest extended-pageflip-hang-oldfb: SUCCESS (2.547s)
[286.923859] [052/142] (726s left) kms_flip (flip-vs-wf_vblank-interruptible)
[287.343923] Starting subtest: flip-vs-wf_vblank-interruptible
[287.377558] Starting dynamic subtest: A-eDP1
[289.882410] Dynamic subtest A-eDP1: SUCCESS (2.505s)
[289.927261] Starting dynamic subtest: B-eDP1
[291.988361] Dynamic subtest B-eDP1: SUCCESS (2.102s)
[292.032806] Starting dynamic subtest: C-eDP1
[294.321781] Dynamic subtest C-eDP1: SUCCESS (2.326s)
[294.341175] Starting dynamic subtest: D-eDP1
[296.622500] Dynamic subtest D-eDP1: SUCCESS (2.300s)
[296.651952] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (9.308s)
[298.191860] [053/142] (715s left) gem_lmem_swapping (random)
[298.590722] Subtest random: SKIP (0.000s)
[298.705051] [054/142] (715s left) kms_flip (flip-vs-panning-interruptible)
[299.130673] Starting subtest: flip-vs-panning-interruptible
[299.168596] Starting dynamic subtest: A-eDP1
[308.634764] Dynamic subtest A-eDP1: SUCCESS (9.472s)
[308.675674] Starting dynamic subtest: B-eDP1
[317.917343] Dynamic subtest B-eDP1: SUCCESS (9.277s)
[317.962748] Starting dynamic subtest: C-eDP1
[327.225897] Dynamic subtest C-eDP1: SUCCESS (9.304s)
[327.269183] Starting dynamic subtest: D-eDP1
[336.546766] Dynamic subtest D-eDP1: SUCCESS (9.317s)
[336.574721] Subtest flip-vs-panning-interruptible: SUCCESS (37.443s)
[338.074922] [055/142] (676s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[338.559890] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[338.570485] Starting dynamic subtest: pipe-A-default-mode
[338.978412] Dynamic subtest pipe-A-default-mode: SKIP (0.400s)
[338.985776] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.413s)
[339.922723] [056/142] (674s left) kms_chamelium_frames (hdmi-crc-single)
[340.818544] Subtest hdmi-crc-single: SKIP (0.000s)
[341.682103] [057/142] (672s left) gem_userptr_blits (dmabuf-unsync)
[342.091387] Subtest dmabuf-unsync: SKIP (0.000s)
[342.376323] [058/142] (672s left) kms_atomic (plane-overlay-legacy)
[343.194881] Starting subtest: plane-overlay-legacy
[343.461066] Subtest plane-overlay-legacy: SUCCESS (0.271s)
[344.817096] [059/142] (669s left) kms_vblank (pipe-b-wait-busy)
[345.312131] Starting subtest: pipe-B-wait-busy
[348.582799] Subtest pipe-B-wait-busy: SUCCESS (3.273s)
[349.519771] [060/142] (665s left) kms_ccs (pipe-d-crc-primary-rotation-180-y_tiled_ccs)
[351.192114] Starting subtest: pipe-D-crc-primary-rotation-180-y_tiled_ccs
[351.198237] Subtest pipe-D-crc-primary-rotation-180-y_tiled_ccs: SKIP (0.002s)
[351.906711] [061/142] (662s left) gem_ctx_engines (invalid-engines)
[352.341454] Starting subtest: invalid-engines
[352.376269] Subtest invalid-engines: SUCCESS (0.036s)
[352.681159] [062/142] (662s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_dg2_mc_ccs)
[354.260252] Starting subtest: pipe-D-bad-pixel-format-4_tiled_dg2_mc_ccs
[354.266146] Subtest pipe-D-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[355.040606] [063/142] (659s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[355.533061] Starting subtest: fbcpsr-tiling-linear
[365.879923] Subtest fbcpsr-tiling-linear: SUCCESS (10.348s)
[366.159061] [064/142] (648s left) gem_tiled_blits (basic)
[366.554923] Subtest basic: SKIP (0.000s)
[366.673921] [065/142] (648s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[367.056068] Starting subtest: multi-wait-all-available-submitted-signaled
[367.059694] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.003s)
[367.202127] [066/142] (647s left) v3d/v3d_submit_csd (bad-multisync-pad)
[367.685957] Subtest bad-multisync-pad: SKIP (0.000s)
[367.819984] [067/142] (647s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[368.351386] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[371.315604] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (2.961s)
[371.625312] [068/142] (643s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[372.154092] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[383.303522] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (11.151s)
[383.573670] [069/142] (631s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_dg2_rc_ccs)
[384.801643] Starting subtest: pipe-B-random-ccs-data-4_tiled_dg2_rc_ccs
[384.811148] Subtest pipe-B-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[385.964567] [070/142] (629s left) prime_mmap (test_userptr)
[386.350255] Starting subtest: test_userptr
[386.353905] Starting dynamic subtest: test_userptr-smem
[386.357419] Dynamic subtest test_userptr-smem: SUCCESS (0.002s)
[386.360846] Subtest test_userptr: SUCCESS (0.008s)
[386.517736] [071/142] (628s left) kms_hdr (static-toggle)
[386.995686] Starting subtest: static-toggle
[387.002659] Subtest static-toggle: SKIP (0.001s)
[387.143906] [072/142] (628s left) syncobj_timeline (invalid-signal-illegal-handle)
[387.528064] Starting subtest: invalid-signal-illegal-handle
[387.531588] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[387.670130] [073/142] (628s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[388.180856] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[388.187373] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[388.344232] [074/142] (627s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[388.894971] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[391.827189] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (2.928s)
[392.137460] [075/142] (623s left) v3d/v3d_job_submission (multiple-singlesync-to-multisync)
[392.612233] Subtest multiple-singlesync-to-multisync: SKIP (0.000s)
[392.743351] [076/142] (623s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[393.163574] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[393.336172] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.168s)
[393.511180] [077/142] (622s left) kms_cursor_crc (cursor-random-32x10)
[394.190892] Starting subtest: cursor-random-32x10
[394.508268] Subtest cursor-random-32x10: SKIP (0.306s)
[395.372596] [078/142] (620s left) kms_scaling_modes (scaling-mode-none)
[395.856103] Starting subtest: scaling-mode-none
[395.859546] Starting dynamic subtest: eDP-1-pipe-A
[396.170687] Dynamic subtest eDP-1-pipe-A: SKIP (0.307s)
[396.200383] Starting dynamic subtest: eDP-1-pipe-B
[396.357297] Dynamic subtest eDP-1-pipe-B: SKIP (0.175s)
[396.407100] Starting dynamic subtest: eDP-1-pipe-C
[396.525957] Dynamic subtest eDP-1-pipe-C: SKIP (0.160s)
[396.581519] Starting dynamic subtest: eDP-1-pipe-D
[396.701551] Dynamic subtest eDP-1-pipe-D: SKIP (0.166s)
[396.708850] Subtest scaling-mode-none: SKIP (0.848s)
[396.902279] [079/142] (619s left) kms_ccs (pipe-d-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[398.677374] Starting subtest: pipe-D-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[398.681104] Subtest pipe-D-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SUCCESS (0.003s)
[399.319049] [080/142] (616s left) gem_pwrite (basic-exhaustion)
[399.693666] Subtest basic-exhaustion: SKIP (0.000s)
[399.823617] [081/142] (616s left) gen9_exec_parse (basic-rejected)
[400.221850] Subtest basic-rejected: SKIP (0.000s)
[400.350567] [082/142] (616s left) gen9_exec_parse (valid-registers)
[400.753863] Subtest valid-registers: SKIP (0.000s)
[400.878518] [083/142] (615s left) kms_cursor_legacy (single-move)
[401.466606] Starting subtest: single-move
[401.470352] Starting dynamic subtest: pipe-A
[406.915174] Dynamic subtest pipe-A: SUCCESS (5.445s)
[406.958878] Starting dynamic subtest: pipe-B
[412.373442] Dynamic subtest pipe-B: SUCCESS (5.455s)
[412.417728] Starting dynamic subtest: pipe-C
[417.834893] Dynamic subtest pipe-C: SUCCESS (5.456s)
[417.884199] Starting dynamic subtest: pipe-D
[423.289293] Dynamic subtest pipe-D: SUCCESS (5.450s)
[423.335332] Starting dynamic subtest: all-pipes
[428.761453] Dynamic subtest all-pipes: SUCCESS (5.468s)
[428.764935] Subtest single-move: SUCCESS (27.296s)
[429.122824] [084/142] (587s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[429.650408] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[429.657293] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.001s)
[429.814222] [085/142] (586s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[430.319132] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[430.326594] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[430.494564] [086/142] (586s left) prime_mmap (test_correct)
[430.876069] Starting subtest: test_correct
[430.879574] Starting dynamic subtest: test_correct-smem
[431.412598] Dynamic subtest test_correct-smem: SUCCESS (0.538s)
[431.416513] Subtest test_correct: SUCCESS (0.545s)
[431.581546] [087/142] (585s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[432.093558] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[432.099961] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[432.269288] [088/142] (584s left) kms_flip (basic-flip-vs-modeset)
[432.667088] Starting subtest: basic-flip-vs-modeset
[432.692591] Starting dynamic subtest: A-eDP1
[436.050416] Dynamic subtest A-eDP1: SUCCESS (3.352s)
[436.088383] Starting dynamic subtest: B-eDP1
[438.489615] Dynamic subtest B-eDP1: SUCCESS (2.437s)
[438.527969] Starting dynamic subtest: C-eDP1
[440.930987] Dynamic subtest C-eDP1: SUCCESS (2.435s)
[440.969865] Starting dynamic subtest: D-eDP1
[443.365239] Dynamic subtest D-eDP1: SUCCESS (2.432s)
[443.388209] Subtest basic-flip-vs-modeset: SUCCESS (10.727s)
[444.316440] [089/142] (572s left) gem_softpin (safe-alignment)
[444.722065] Starting subtest: safe-alignment
[444.824687] Subtest safe-alignment: SUCCESS (0.102s)
[446.657086] [090/142] (570s left) vc4/vc4_wait_bo (unused-bo-0ns)
[447.127768] Subtest unused-bo-0ns: SKIP (0.000s)
[447.263107] [091/142] (569s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff)
[447.776333] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff
[447.783381] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[447.948699] [092/142] (569s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[448.459238] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[448.466168] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[448.642239] [093/142] (568s left) gem_ctx_sseu (engines)
[449.042553] Subtest engines: SKIP (0.000s)
[449.158948] [094/142] (568s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[450.065493] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[450.933516] [095/142] (566s left) kms_psr (sprite_mmap_cpu)
[451.417897] Starting subtest: sprite_mmap_cpu
[454.527291] Subtest sprite_mmap_cpu: SUCCESS (3.108s)
[455.466980] [096/142] (562s left) syncobj_basic (bad-pad-handle-to-fd)
[455.844576] Starting subtest: bad-pad-handle-to-fd
[455.848239] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[455.989466] [097/142] (561s left) kms_plane_lowres (tiling-x)
[456.463453] Starting subtest: tiling-x
[456.472419] Starting dynamic subtest: pipe-A-eDP-1
[456.479055] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[456.927840] Starting dynamic subtest: pipe-B-eDP-1
[456.935066] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[456.979141] Starting dynamic subtest: pipe-C-eDP-1
[456.986359] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[457.030825] Starting dynamic subtest: pipe-D-eDP-1
[457.037756] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[457.044868] Subtest tiling-x: SKIP (0.513s)
[457.824231] [098/142] (560s left) gem_exec_fence (invalid-timeline-fence-array)
[460.029613] Starting subtest: invalid-timeline-fence-array
[460.051116] Subtest invalid-timeline-fence-array: SUCCESS (0.019s)
[460.693477] [099/142] (557s left) syncobj_timeline (single-wait-available-signaled)
[461.081490] Starting subtest: single-wait-available-signaled
[461.084990] Subtest single-wait-available-signaled: SUCCESS (0.001s)
[461.260931] [100/142] (556s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0)
[461.827455] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0
[461.834371] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[461.982379] [101/142] (556s left) kms_psr2_sf (primary-plane-update-sf-dmg-area)
[469.360475] Starting subtest: primary-plane-update-sf-dmg-area
[469.369189] Starting dynamic subtest: pipe-A-eDP-1
[477.289220] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.920s)
[477.325181] Starting dynamic subtest: pipe-B-eDP-1
[485.181771] Dynamic subtest pipe-B-eDP-1: SUCCESS (7.881s)
[485.185620] Subtest primary-plane-update-sf-dmg-area: SUCCESS (15.824s)
[486.109196] [102/142] (532s left) gem_exec_whisper (basic-fds-all)
[486.525251] Starting subtest: basic-fds-all
[508.554146] Subtest basic-fds-all: SUCCESS (22.026s)
[508.936293] [103/142] (509s left) kms_vblank (pipe-a-ts-continuation-modeset)
[509.418155] Starting subtest: pipe-A-ts-continuation-modeset
[511.254477] Subtest pipe-A-ts-continuation-modeset: SUCCESS (1.836s)
[512.139125] [104/142] (506s left) gem_ccs (suspend-resume)
[512.289195] Starting subtest: suspend-resume
[512.295106] Subtest suspend-resume: SKIP (0.000s)
[512.412786] [105/142] (505s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[512.672628] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[521.250588] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (8.580s)
[521.486754] [106/142] (496s left) gem_softpin (evict-snoop-interruptible)
[521.729612] Starting subtest: evict-snoop-interruptible
[521.735219] Subtest evict-snoop-interruptible: SKIP (0.000s)
[521.834949] [107/142] (496s left) kms_vblank (pipe-a-wait-busy-hang)
[522.068659] Starting subtest: pipe-A-wait-busy-hang
[536.796688] Subtest pipe-A-wait-busy-hang: SUCCESS (14.727s)
[537.751104] [108/142] (480s left) kms_vblank (pipe-c-ts-continuation-idle)
[538.214937] Starting subtest: pipe-C-ts-continuation-idle
[540.284765] Subtest pipe-C-ts-continuation-idle: SUCCESS (2.070s)
[541.235245] [109/142] (477s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[541.623556] Starting subtest: etime-single-wait-for-submit-unsubmitted
[541.725526] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.102s)
[541.882199] [110/142] (476s left) gem_exec_reloc (basic-write-cpu)
[542.291834] Subtest basic-write-cpu: SKIP (0.000s)
[542.427348] [111/142] (476s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[542.833778] Starting subtest: legacy-engines-hostile-preempt
[542.837602] Starting dynamic subtest: render
[542.912552] Dynamic subtest render: SUCCESS (0.076s)
[542.965122] Starting dynamic subtest: bsd1
[544.529250] Dynamic subtest bsd1: SUCCESS (1.610s)
[544.572483] Starting dynamic subtest: bsd2
[546.515706] Dynamic subtest bsd2: SUCCESS (1.981s)
[546.572941] Starting dynamic subtest: blt
[546.577076] Dynamic subtest blt: SUCCESS (0.051s)
[546.620726] Starting dynamic subtest: vebox
[548.556824] Dynamic subtest vebox: SUCCESS (1.977s)
[548.560975] Subtest legacy-engines-hostile-preempt: SUCCESS (5.723s)
[548.744255] [112/142] (470s left) vc4/vc4_dmabuf_poll (poll-write-waits-until-write-done)
[549.267818] Subtest poll-write-waits-until-write-done: SKIP (0.000s)
[549.411579] [113/142] (469s left) perf_pmu (most-busy-idle-check-all)
[549.813913] Starting subtest: most-busy-idle-check-all
[549.817875] Starting dynamic subtest: rcs0
[551.542694] Dynamic subtest rcs0: SUCCESS (1.726s)
[551.590642] Starting dynamic subtest: bcs0
[553.526144] Dynamic subtest bcs0: SUCCESS (1.979s)
[553.573172] Starting dynamic subtest: vcs0
[555.510963] Dynamic subtest vcs0: SUCCESS (1.980s)
[555.552721] Starting dynamic subtest: vcs1
[557.558509] Dynamic subtest vcs1: SUCCESS (2.043s)
[557.600228] Starting dynamic subtest: vecs0
[559.542131] Dynamic subtest vecs0: SUCCESS (1.980s)
[559.583446] Starting dynamic subtest: ccs0
[561.527254] Dynamic subtest ccs0: SUCCESS (1.980s)
[561.530943] Subtest most-busy-idle-check-all: SUCCESS (11.714s)
[561.719291] [114/142] (457s left) gem_create (create-valid-nonaligned)
[562.107212] Starting subtest: create-valid-nonaligned
[562.111370] Subtest create-valid-nonaligned: SUCCESS (0.001s)
[562.451650] [115/142] (456s left) kms_ccs (pipe-c-crc-primary-basic-y_tiled_ccs)
[563.912096] Starting subtest: pipe-C-crc-primary-basic-y_tiled_ccs
[563.918558] Subtest pipe-C-crc-primary-basic-y_tiled_ccs: SKIP (0.002s)
[564.870996] [116/142] (454s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y_tiled_ccs)
[566.050466] Starting subtest: pipe-C-crc-sprite-planes-basic-y_tiled_ccs
[566.061337] Subtest pipe-C-crc-sprite-planes-basic-y_tiled_ccs: SKIP (0.004s)
[566.710627] [117/142] (452s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs)
[568.007143] Starting subtest: pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs
[568.024940] Subtest pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs: SKIP (0.013s)
[569.108400] [118/142] (450s left) api_intel_bb (bb-with-allocator)
[569.495269] Starting subtest: bb-with-allocator
[569.512500] Subtest bb-with-allocator: SUCCESS (0.018s)
[569.698842] [119/142] (449s left) kms_cursor_crc (cursor-onscreen-max-size)
[571.020770] Starting subtest: cursor-onscreen-max-size
[571.029919] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[571.301213] [120/142] (448s left) i915_pm_freq_mult (media-freq)
[571.678132] Starting subtest: media-freq
[571.681690] Starting dynamic subtest: gt0
[571.688746] Dynamic subtest gt0: SKIP (0.000s)
[571.734957] Starting dynamic subtest: gt1
[571.742247] Dynamic subtest gt1: SKIP (0.000s)
[571.749121] Subtest media-freq: SKIP (0.009s)
[571.833626] [121/142] (447s left) kms_flip (2x-flip-vs-wf_vblank)
[572.247676] Starting subtest: 2x-flip-vs-wf_vblank
[572.414893] Subtest 2x-flip-vs-wf_vblank: SKIP (0.168s)
[572.613120] [122/142] (447s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[573.753738] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs_cc
[573.760076] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[575.040492] [123/142] (444s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[575.444073] Starting subtest: y-tiled-to-vebox-y-tiled
[575.450270] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[575.722603] [124/142] (444s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[576.221166] Starting subtest: psr-rgb565-draw-render
[592.354226] Subtest psr-rgb565-draw-render: SUCCESS (16.136s)
[592.647095] [125/142] (427s left) gem_exec_fence (syncobj-timeline-signal)
[594.804616] Starting subtest: syncobj-timeline-signal
[594.830697] Subtest syncobj-timeline-signal: SUCCESS (0.027s)
[595.134177] [126/142] (425s left) syncobj_basic (create-signaled)
[595.519422] Starting subtest: create-signaled
[595.523134] Subtest create-signaled: SUCCESS (0.000s)
[595.666296] [127/142] (424s left) gem_eio (wait-wedge-10ms)
[597.103655] Starting subtest: wait-wedge-10ms
[600.181987] Subtest wait-wedge-10ms: SUCCESS (3.085s)
[601.675783] [128/142] (418s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-cpu)
[602.193450] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-cpu
[611.876071] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (9.691s)
[612.171947] [129/142] (408s left) vc4/vc4_perfmon (get-values-valid-perfmon)
[612.651855] Subtest get-values-valid-perfmon: SKIP (0.000s)
[612.790963] [130/142] (407s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[613.265593] Starting subtest: disable-crc-after-crtc
[613.274632] Starting dynamic subtest: pipe-A-eDP-1
[615.531638] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.254s)
[615.592500] Starting dynamic subtest: pipe-B-eDP-1
[618.489345] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.945s)
[618.542374] Starting dynamic subtest: pipe-C-eDP-1
[621.701286] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.202s)
[621.753954] Starting dynamic subtest: pipe-D-eDP-1
[625.134367] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.422s)
[625.138266] Subtest disable-crc-after-crtc: SUCCESS (11.865s)
[626.087508] [131/142] (394s left) api_intel_allocator (execbuf-with-allocator)
[626.468236] Starting subtest: execbuf-with-allocator
[626.486209] Subtest execbuf-with-allocator: SUCCESS (0.019s)
[626.683209] [132/142] (394s left) gem_mmap_gtt (basic-small-bo)
[627.062078] Subtest basic-small-bo: SKIP (0.000s)
[627.181352] [133/142] (393s left) gem_tiled_fence_blits (normal)
[627.578288] Subtest normal: SKIP (0.000s)
[627.700463] [134/142] (393s left) perf (whitelisted-registers-userspace-config)
[628.654464] Starting subtest: whitelisted-registers-userspace-config
[628.658298] Subtest whitelisted-registers-userspace-config: SUCCESS (0.005s)
[628.762511] [135/142] (392s left) kms_cursor_edge_walk (64x64-top-edge)
[629.264854] Starting subtest: 64x64-top-edge
[629.273941] Starting dynamic subtest: pipe-A-eDP-1
[633.165398] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.890s)
[633.223671] Starting dynamic subtest: pipe-D-eDP-1
[636.634670] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.456s)
[636.638287] Subtest 64x64-top-edge: SUCCESS (7.368s)
[637.580768] [136/142] (383s left) gem_softpin (noreloc-s3)
[637.981708] Starting subtest: noreloc-S3
[655.829342] Subtest noreloc-S3: SUCCESS (17.847s)
[656.032065] [137/142] (365s left) kms_vblank (pipe-d-ts-continuation-suspend)
[656.534791] Starting subtest: pipe-D-ts-continuation-suspend
[676.139501] Subtest pipe-D-ts-continuation-suspend: SUCCESS (19.608s)
[677.104795] [138/142] (344s left) kms_vblank (pipe-c-wait-forked-busy)
[677.588295] Starting subtest: pipe-C-wait-forked-busy
[681.724383] Subtest pipe-C-wait-forked-busy: SUCCESS (4.136s)
[682.684212] [139/142] (338s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_mtl_rc_ccs)
[683.170437] Starting subtest: pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs
[683.174264] Subtest pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs: SUCCESS (0.001s)
[685.086616] [140/142] (336s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[685.628363] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[690.092622] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (4.463s)
[691.172590] [141/142] (330s left) gem_exec_nop (basic-sequential)
[691.595271] Starting subtest: basic-sequential
[709.498975] Subtest basic-sequential: SUCCESS (17.900s)
[709.846369] [142/142] (311s left) i915_pm_rpm (gem-idle)
[715.952591] Starting subtest: gem-idle
[725.019579] Subtest gem-idle: SUCCESS (9.067s)
[725.808208] Closing watchdogs
Done.