[51.555513] Initializing watchdogs
[51.555860]   /dev/watchdog0
[51.572037] [001/142] (960s left) syncobj_timeline (invalid-single-wait-all-available-unsubmitted)
[51.804611] Starting subtest: invalid-single-wait-all-available-unsubmitted
[51.806647] Subtest invalid-single-wait-all-available-unsubmitted: SUCCESS (0.000s)
[51.891288] [002/142] (960s left) kms_ccs (pipe-a-random-ccs-data-y_tiled_ccs)
[52.336964] Starting subtest: pipe-A-random-ccs-data-y_tiled_ccs
[52.793226] Subtest pipe-A-random-ccs-data-y_tiled_ccs: SUCCESS (0.454s)
[53.431637] [003/142] (958s left) perf_pmu (rc6)
[53.661450] Starting subtest: rc6
[53.663084] Starting dynamic subtest: gt0
[60.004357] Dynamic subtest gt0: SUCCESS (6.341s)
[60.018946] Starting dynamic subtest: runtime-pm-gt0
[67.581659] Dynamic subtest runtime-pm-gt0: SUCCESS (7.577s)
[67.596204] Starting dynamic subtest: runtime-pm-long-gt0
[80.055433] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.475s)
[80.070249] Starting dynamic subtest: other-idle-gt0
[80.074954] Dynamic subtest other-idle-gt0: SKIP (0.000s)
[80.076635] Subtest rc6: SUCCESS (26.395s)
[80.342885] [004/142] (931s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[80.661063] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[80.663667] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[80.720859] [005/142] (931s left) i915_pm_rpm (pm-tiling)
[82.825684] Starting subtest: pm-tiling
[84.924349] Subtest pm-tiling: SUCCESS (2.096s)
[85.099305] [006/142] (927s left) gem_ppgtt (blt-vs-render-ctxn)
[85.314182] Starting subtest: blt-vs-render-ctxN
[118.850210] Subtest blt-vs-render-ctxN: SUCCESS (33.533s)
[118.920683] [007/142] (893s left) perf (stress-open-close)
[119.381274] Starting subtest: stress-open-close
[119.382693] Starting dynamic subtest: 0-rcs0
[121.644228] Dynamic subtest 0-rcs0: SUCCESS (2.263s)
[121.645753] Subtest stress-open-close: SUCCESS (2.264s)
[121.708489] [008/142] (890s left) drm_fdinfo (busy-idle-check-all)
[121.919460] Starting subtest: busy-idle-check-all
[121.921105] Starting dynamic subtest: rcs0
[122.549117] Dynamic subtest rcs0: SUCCESS (0.629s)
[122.567465] Starting dynamic subtest: bcs0
[123.178338] Dynamic subtest bcs0: SUCCESS (0.629s)
[123.195602] Starting dynamic subtest: vcs0
[123.808432] Dynamic subtest vcs0: SUCCESS (0.630s)
[123.825447] Starting dynamic subtest: vecs0
[124.438597] Dynamic subtest vecs0: SUCCESS (0.630s)
[124.440070] Subtest busy-idle-check-all: SUCCESS (2.519s)
[124.483742] [009/142] (887s left) i915_hangman (detector)
[124.710254] Starting subtest: detector
[124.711748] Starting dynamic subtest: rcs0
[134.339036] Dynamic subtest rcs0: SUCCESS (9.629s)
[134.359520] Starting dynamic subtest: bcs0
[144.368903] Dynamic subtest bcs0: SUCCESS (10.030s)
[144.386630] Starting dynamic subtest: vcs0
[154.361125] Dynamic subtest vcs0: SUCCESS (9.992s)
[154.378939] Starting dynamic subtest: vecs0
[164.338876] Dynamic subtest vecs0: SUCCESS (9.978s)
[164.340653] Subtest detector: SUCCESS (39.629s)
[164.418136] [010/142] (847s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[164.758660] Starting subtest: 4-tiled-64bpp-rotate-0
[164.761797] Subtest 4-tiled-64bpp-rotate-0: SKIP (0.001s)
[164.834288] [011/142] (847s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[165.145902] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[165.148724] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[165.217543] [012/142] (847s left) sw_sync (timeline_closed_signaled)
[165.415871] Starting subtest: timeline_closed_signaled
[165.417481] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[165.453661] [013/142] (847s left) core_setmaster (master-drop-set-shared-fd)
[167.381202] Starting subtest: master-drop-set-shared-fd
[167.432706] Subtest master-drop-set-shared-fd: SUCCESS (0.051s)
[167.468719] [014/142] (845s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[167.676765] Starting subtest: invalid-bsd1-flag-on-blt
[167.678718] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.001s)
[167.749336] [015/142] (844s left) kms_color (ctm-0-50)
[168.046584] Starting subtest: ctm-0-50
[168.047871] Starting dynamic subtest: pipe-A
[169.929126] Dynamic subtest pipe-A: SUCCESS (1.880s)
[169.959795] Starting dynamic subtest: pipe-B
[172.152322] Dynamic subtest pipe-B: SUCCESS (2.228s)
[172.175027] Starting dynamic subtest: pipe-C
[174.394897] Dynamic subtest pipe-C: SUCCESS (2.241s)
[174.396256] Subtest ctm-0-50: SUCCESS (6.350s)
[174.701074] [016/142] (837s left) syncobj_basic (bad-pad-fd-to-handle)
[174.904466] Starting subtest: bad-pad-fd-to-handle
[174.905946] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[174.945061] [017/142] (837s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[175.260480] Starting subtest: y-tiled-16bpp-rotate-90
[175.355968] Subtest y-tiled-16bpp-rotate-90: SKIP (0.093s)
[175.430601] [018/142] (837s left) vc4/vc4_wait_bo (used-bo)
[175.677763] Subtest used-bo: SKIP (0.000s)
[175.712994] [019/142] (837s left) i915_pm_backlight (fade)
[176.011062] Subtest fade: SKIP (0.000s)
[176.063377] [020/142] (836s left) gem_exec_reloc (basic-scanout)
[176.375374] Starting subtest: basic-scanout
[176.376659] Starting dynamic subtest: rcs0
[176.876594] Dynamic subtest rcs0: SUCCESS (0.504s)
[176.896541] Starting dynamic subtest: bcs0
[177.092245] Dynamic subtest bcs0: SUCCESS (0.215s)
[177.110588] Starting dynamic subtest: vcs0
[177.308511] Dynamic subtest vcs0: SUCCESS (0.216s)
[177.326721] Starting dynamic subtest: vecs0
[177.528726] Dynamic subtest vecs0: SUCCESS (0.218s)
[177.530971] Subtest basic-scanout: SUCCESS (1.156s)
[177.613261] [021/142] (835s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_mtl_rc_ccs_cc)
[178.294518] Subtest pipe-D-bad-rotation-90-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[178.596502] [022/142] (834s left) kms_vblank (pipe-b-wait-idle)
[178.904881] Starting subtest: pipe-B-wait-idle
[181.600612] Subtest pipe-B-wait-idle: SUCCESS (2.698s)
[181.903676] [023/142] (830s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[182.229851] Starting subtest: 4-tiled-16bpp-rotate-270
[182.233244] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.000s)
[182.298433] [024/142] (830s left) kms_addfb_basic (bo-too-small)
[182.596673] Starting subtest: bo-too-small
[182.597955] Subtest bo-too-small: SUCCESS (0.001s)
[182.664791] [025/142] (830s left) kms_ccs (pipe-b-bad-rotation-90-y_tiled_gen12_rc_ccs)
[183.150793] Starting subtest: pipe-B-bad-rotation-90-y_tiled_gen12_rc_ccs
[183.153454] Subtest pipe-B-bad-rotation-90-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[183.620922] [026/142] (829s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[183.947860] Starting subtest: yf-tiled-8bpp-rotate-270
[183.951283] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[184.016055] [027/142] (828s left) gem_exec_balancer (hang)
[188.020582] Starting subtest: hang
[191.569933] Subtest hang: SUCCESS (3.552s)
[191.633088] [028/142] (821s left) syncobj_wait (wait-for-submit-snapshot)
[191.838583] Starting subtest: wait-for-submit-snapshot
[191.923719] Subtest wait-for-submit-snapshot: SUCCESS (0.083s)
[191.964856] [029/142] (821s left) kms_ccs (pipe-b-crc-primary-rotation-180-y_tiled_ccs)
[192.471856] Starting subtest: pipe-B-crc-primary-rotation-180-y_tiled_ccs
[193.136634] Subtest pipe-B-crc-primary-rotation-180-y_tiled_ccs: SUCCESS (0.663s)
[193.707591] [030/142] (819s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[193.912395] Starting subtest: etime-multi-wait-all-submitted-signaled
[194.017634] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.103s)
[194.067939] [031/142] (819s left) vc4/vc4_perfmon (destroy-valid-perfmon)
[194.316249] Subtest destroy-valid-perfmon: SKIP (0.000s)
[194.353290] [032/142] (818s left) kms_ccs (pipe-d-random-ccs-data-yf_tiled_ccs)
[195.021101] Subtest pipe-D-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[195.293584] [033/142] (817s left) kms_ccs (pipe-c-missing-ccs-buffer-y_tiled_gen12_rc_ccs)
[195.967800] Starting subtest: pipe-C-missing-ccs-buffer-y_tiled_gen12_rc_ccs
[195.970498] Subtest pipe-C-missing-ccs-buffer-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[196.314982] [034/142] (816s left) kms_psr (cursor_render)
[196.521714] Subtest cursor_render: SKIP (0.000s)
[196.561857] [035/142] (816s left) kms_rotation_crc (primary-rotation-90)
[196.872351] Starting subtest: primary-rotation-90
[202.980934] Subtest primary-rotation-90: SUCCESS (6.109s)
[203.370729] [036/142] (809s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[203.683869] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[203.686581] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[203.747792] [037/142] (809s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[204.067645] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[204.070331] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[204.131594] [038/142] (809s left) gem_exec_fair (basic-none-rrul)
[204.363150] Starting subtest: basic-none-rrul
[204.364432] Starting dynamic subtest: rcs0
[209.705628] Dynamic subtest rcs0: SUCCESS (5.342s)
[209.707111] Subtest basic-none-rrul: SUCCESS (5.344s)
[209.867310] [039/142] (803s left) kms_cursor_crc (cursor-random-32x32)
[210.181734] Starting subtest: cursor-random-32x32
[210.300090] Subtest cursor-random-32x32: SKIP (0.116s)
[210.381788] [040/142] (803s left) gem_ctx_sseu (mmap-args)
[210.594305] Subtest mmap-args: SKIP (0.000s)
[210.650339] [041/142] (802s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[210.960501] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[210.963189] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[211.031985] [042/142] (802s left) gem_exec_params (batch-first)
[211.240355] Starting subtest: batch-first
[211.251770] Subtest batch-first: SUCCESS (0.011s)
[211.331445] [043/142] (802s left) vgem_basic (create)
[211.612939] Starting subtest: create
[211.616288] Subtest create: SUCCESS (0.002s)
[211.654788] [044/142] (802s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc)
[211.956911] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc
[211.959585] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[212.014735] [045/142] (801s left) gem_pxp (create-regular-context-1)
[214.734852] Subtest create-regular-context-1: SKIP (0.000s)
[214.781301] [046/142] (798s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[215.092520] Starting subtest: fbcpsr-tiling-linear
[215.095127] Subtest fbcpsr-tiling-linear: SKIP (0.000s)
[215.165231] [047/142] (798s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[215.493711] Starting subtest: x-tiled-8bpp-rotate-90
[215.569624] Subtest x-tiled-8bpp-rotate-90: SKIP (0.073s)
[215.631715] [048/142] (798s left) gem_spin_batch (legacy)
[215.855735] Starting subtest: legacy
[215.857304] Starting dynamic subtest: default
[219.183409] Dynamic subtest default: SUCCESS (3.323s)
[219.205276] Starting dynamic subtest: render
[222.507817] Dynamic subtest render: SUCCESS (3.322s)
[222.526944] Starting dynamic subtest: bsd
[225.831559] Dynamic subtest bsd: SUCCESS (3.322s)
[225.849702] Starting dynamic subtest: bsd1
[229.151874] Dynamic subtest bsd1: SUCCESS (3.320s)
[229.169896] Starting dynamic subtest: blt
[232.474826] Dynamic subtest blt: SUCCESS (3.321s)
[232.493327] Starting dynamic subtest: vebox
[235.796439] Dynamic subtest vebox: SUCCESS (3.320s)
[235.797951] Subtest legacy: SUCCESS (19.939s)
[235.966632] [049/142] (777s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_mc_ccs)
[236.631998] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[236.915453] [050/142] (776s left) gem_exec_schedule (preempt-engines)
[237.155427] Starting subtest: preempt-engines
[237.156938] Starting dynamic subtest: rcs0
[237.460524] Dynamic subtest rcs0: SUCCESS (0.302s)
[237.483072] Starting dynamic subtest: bcs0
[237.854701] Dynamic subtest bcs0: SUCCESS (0.394s)
[237.875774] Starting dynamic subtest: vcs0
[238.141530] Dynamic subtest vcs0: SUCCESS (0.282s)
[238.161283] Starting dynamic subtest: vecs0
[238.420585] Dynamic subtest vecs0: SUCCESS (0.279s)
[238.421947] Subtest preempt-engines: SUCCESS (1.263s)
[238.762272] [051/142] (775s left) gem_mmap_gtt (big-copy-xy)
[238.965135] Starting subtest: big-copy-XY
[251.695838] Subtest big-copy-XY: SUCCESS (12.729s)
[251.795203] [052/142] (762s left) kms_chamelium_color (ctm-red-to-blue)
[252.226449] Subtest ctm-red-to-blue: SKIP (0.000s)
[252.279072] [053/142] (761s left) kms_cursor_crc (cursor-onscreen-512x170)
[252.616621] Starting subtest: cursor-onscreen-512x170
[252.621840] Subtest cursor-onscreen-512x170: SKIP (0.003s)
[252.694285] [054/142] (761s left) kms_vblank (pipe-b-ts-continuation-idle)
[252.997232] Starting subtest: pipe-B-ts-continuation-idle
[253.638900] Subtest pipe-B-ts-continuation-idle: SUCCESS (0.644s)
[253.953871] [055/142] (760s left) kms_ccs (pipe-b-missing-ccs-buffer-4_tiled_mtl_rc_ccs)
[254.496785] Starting subtest: pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs
[254.499425] Subtest pipe-B-missing-ccs-buffer-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[254.930845] [056/142] (759s left) gem_exec_suspend (basic-s0)
[255.160098] Starting subtest: basic-S0
[255.161631] Starting dynamic subtest: smem
[275.447652] Dynamic subtest smem: SUCCESS (20.286s)
[275.449219] Subtest basic-S0: SUCCESS (20.288s)
[280.623673] [057/142] (738s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_dg2_rc_ccs)
[281.281307] Subtest pipe-D-crc-primary-basic-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[281.569690] [058/142] (737s left) v3d/v3d_submit_cl (multisync-out-syncs)
[281.842981] Subtest multisync-out-syncs: SKIP (0.000s)
[281.884514] [059/142] (737s left) vc4/vc4_label_bo (set-bad-handle)
[282.142530] Subtest set-bad-handle: SKIP (0.000s)
[282.182544] [060/142] (737s left) gem_ctx_persistence (hang)
[282.394343] Starting subtest: hang
[282.402599] Subtest hang: SKIP (0.007s)
[282.481058] [061/142] (736s left) gem_exec_await (wide-contexts)
[282.890629] Starting subtest: wide-contexts
[304.729767] Subtest wide-contexts: SUCCESS (21.839s)
[304.931315] [062/142] (714s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[305.361317] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[305.364100] Subtest psr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[305.415847] [063/142] (714s left) kms_psr2_sf (primary-plane-update-sf-dmg-area)
[305.620660] Subtest primary-plane-update-sf-dmg-area: SKIP (0.000s)
[305.667136] [064/142] (713s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[305.973288] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[305.975833] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[306.048544] [065/142] (713s left) gem_flink_basic (flink-lifetime)
[306.248918] Starting subtest: flink-lifetime
[306.250495] Subtest flink-lifetime: SUCCESS (0.003s)
[306.314688] [066/142] (713s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[306.620388] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[306.622987] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[306.681937] [067/142] (712s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[306.989412] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[306.992089] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[307.048827] [068/142] (712s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs)
[307.508452] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs
[307.514729] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs: SKIP (0.005s)
[308.011134] [069/142] (711s left) kms_setmode (basic-clone-single-crtc)
[308.215915] Starting subtest: basic-clone-single-crtc
[308.219748] Subtest basic-clone-single-crtc: SKIP (0.000s)
[308.272115] [070/142] (711s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_gen12_mc_ccs)
[308.849444] Starting subtest: pipe-C-bad-rotation-90-y_tiled_gen12_mc_ccs
[308.852201] Subtest pipe-C-bad-rotation-90-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[309.229265] [071/142] (710s left) kms_invalid_mode (zero-clock)
[309.540970] Starting subtest: zero-clock
[309.542348] Starting dynamic subtest: DP-1-pipe-A
[309.639388] Dynamic subtest DP-1-pipe-A: SUCCESS (0.100s)
[309.672917] Starting dynamic subtest: DP-1-pipe-B
[309.674486] Dynamic subtest DP-1-pipe-B: SUCCESS (0.014s)
[309.696882] Starting dynamic subtest: DP-1-pipe-C
[309.698376] Dynamic subtest DP-1-pipe-C: SUCCESS (0.015s)
[309.699665] Subtest zero-clock: SUCCESS (0.139s)
[309.995662] [072/142] (709s left) gem_reg_read (timestamp-monotonic)
[310.199874] Starting subtest: timestamp-monotonic
[314.315117] Subtest timestamp-monotonic: SUCCESS (4.114s)
[314.372351] [073/142] (705s left) kms_color (ctm-signed)
[314.671646] Starting subtest: ctm-signed
[314.672943] Starting dynamic subtest: pipe-A
[315.855078] Dynamic subtest pipe-A: SUCCESS (1.184s)
[315.879840] Starting dynamic subtest: pipe-B
[317.339176] Dynamic subtest pipe-B: SUCCESS (1.482s)
[317.365229] Starting dynamic subtest: pipe-C
[318.961366] Dynamic subtest pipe-C: SUCCESS (1.621s)
[318.962666] Subtest ctm-signed: SUCCESS (4.292s)
[319.277324] [074/142] (700s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[319.586781] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[319.589815] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[319.655903] [075/142] (700s left) kms_ccs (pipe-a-bad-pixel-format-yf_tiled_ccs)
[319.954930] Starting subtest: pipe-A-bad-pixel-format-yf_tiled_ccs
[319.956334] Subtest pipe-A-bad-pixel-format-yf_tiled_ccs: SUCCESS (0.001s)
[320.611699] [076/142] (699s left) kms_rotation_crc (sprite-rotation-180)
[320.918151] Starting subtest: sprite-rotation-180
[325.278795] Subtest sprite-rotation-180: SUCCESS (4.361s)
[325.657664] [077/142] (694s left) gen9_exec_parse (basic-rejected)
[325.885662] Starting subtest: basic-rejected
[325.889471] Subtest basic-rejected: SUCCESS (0.003s)
[326.085317] [078/142] (693s left) gem_exec_balancer (parallel)
[330.084109] Subtest parallel: SKIP (0.000s)
[330.151567] [079/142] (689s left) gem_exec_params (no-vebox)
[330.362779] Starting subtest: no-vebox
[330.366772] Subtest no-vebox: SUCCESS (0.002s)
[330.434643] [080/142] (689s left) gem_busy (close-race)
[330.664760] Starting subtest: close-race
[352.849636] Subtest close-race: SUCCESS (22.185s)
[353.204365] [081/142] (667s left) gem_ctx_create (basic)
[353.549487] Starting subtest: basic
[353.550876] Subtest basic: SUCCESS (0.000s)
[353.719638] [082/142] (666s left) fbdev (nullptr)
[353.928820] Starting subtest: nullptr
[353.930482] Subtest nullptr: SUCCESS (0.000s)
[353.974888] [083/142] (666s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[354.199989] Starting subtest: single-wait-for-submit-available-submitted
[354.201327] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.001s)
[354.250156] [084/142] (666s left) v3d/v3d_get_param (get-bad-param)
[354.513335] Subtest get-bad-param: SKIP (0.000s)
[354.554786] [085/142] (665s left) api_intel_allocator (default-alignment)
[354.757608] Starting subtest: default-alignment
[354.760794] Subtest default-alignment: SUCCESS (0.004s)
[354.833690] [086/142] (665s left) i915_query (query-regions-sanity-check)
[355.036848] Starting subtest: query-regions-sanity-check
[355.074319] Subtest query-regions-sanity-check: SUCCESS (0.038s)
[355.152429] [087/142] (665s left) gem_ctx_isolation (dirty-switch)
[355.378364] Starting subtest: dirty-switch
[355.379786] Starting dynamic subtest: rcs0
[357.065303] Dynamic subtest rcs0: SUCCESS (1.683s)
[357.087030] Starting dynamic subtest: bcs0
[359.211421] Dynamic subtest bcs0: SUCCESS (2.145s)
[359.234035] Starting dynamic subtest: vcs0
[360.810706] Dynamic subtest vcs0: SUCCESS (1.597s)
[360.833459] Starting dynamic subtest: vecs0
[362.395267] Dynamic subtest vecs0: SUCCESS (1.583s)
[362.396696] Subtest dirty-switch: SUCCESS (7.016s)
[362.568782] [088/142] (657s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[362.890243] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[362.893296] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[362.952735] [089/142] (657s left) syncobj_timeline (wait-delayed-signal)
[363.156288] Starting subtest: wait-delayed-signal
[363.261427] Subtest wait-delayed-signal: SUCCESS (0.104s)
[363.321668] [090/142] (657s left) gem_exec_reloc (basic-gtt-cpu)
[363.536917] Starting subtest: basic-gtt-cpu
[363.556837] Subtest basic-gtt-cpu: SUCCESS (0.020s)
[363.703789] [091/142] (656s left) kms_ccs (pipe-a-bad-aux-stride-y_tiled_gen12_mc_ccs)
[364.174969] Starting subtest: pipe-A-bad-aux-stride-y_tiled_gen12_mc_ccs
[364.177599] Subtest pipe-A-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[364.687256] [092/142] (655s left) kms_flip (2x-nonexisting-fb)
[364.972326] Starting subtest: 2x-nonexisting-fb
[364.975026] Subtest 2x-nonexisting-fb: SKIP (0.003s)
[365.065469] [093/142] (655s left) kms_flip (2x-plain-flip-fb-recreate)
[365.365221] Starting subtest: 2x-plain-flip-fb-recreate
[365.367951] Subtest 2x-plain-flip-fb-recreate: SKIP (0.003s)
[365.449375] [094/142] (655s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[365.747577] Starting subtest: cursorA-vs-flipB-varying-size
[365.750565] Subtest cursorA-vs-flipB-varying-size: SKIP (0.002s)
[365.798727] [095/142] (655s left) api_intel_bb (intel-bb-blit-none)
[366.002163] Starting subtest: intel-bb-blit-none
[369.647234] Subtest intel-bb-blit-none: SUCCESS (3.643s)
[369.731609] [096/142] (651s left) i915_pm_rc6_residency (rc6-idle)
[369.938710] Starting subtest: rc6-idle
[369.948775] Starting dynamic subtest: rcs0
[382.222921] Dynamic subtest rcs0: SUCCESS (12.276s)
[382.245675] Starting dynamic subtest: bcs0
[394.495969] Dynamic subtest bcs0: SUCCESS (12.271s)
[394.520605] Starting dynamic subtest: vcs0
[406.770232] Dynamic subtest vcs0: SUCCESS (12.272s)
[406.795045] Starting dynamic subtest: vecs0
[419.045862] Dynamic subtest vecs0: SUCCESS (12.273s)
[419.047692] Subtest rc6-idle: SUCCESS (49.110s)
[419.298428] [097/142] (601s left) kms_lease (multimaster-lease)
[419.599109] Starting subtest: multimaster-lease
[419.606698] Subtest multimaster-lease: SUCCESS (0.009s)
[419.665574] [098/142] (601s left) gem_exec_schedule (u-submit-early-slice)
[419.906535] Starting subtest: u-submit-early-slice
[419.908165] Starting dynamic subtest: rcs0
[419.975920] Dynamic subtest rcs0: SUCCESS (0.068s)
[420.003991] Starting dynamic subtest: bcs0
[420.040751] Dynamic subtest bcs0: SUCCESS (0.061s)
[420.069700] Starting dynamic subtest: vcs0
[420.097415] Dynamic subtest vcs0: SUCCESS (0.055s)
[420.124334] Starting dynamic subtest: vecs0
[420.153466] Dynamic subtest vecs0: SUCCESS (0.054s)
[420.155266] Subtest u-submit-early-slice: SUCCESS (0.248s)
[420.481799] [099/142] (600s left) syncobj_timeline (etime-single-wait-for-submit-available-unsubmitted)
[420.688303] Starting subtest: etime-single-wait-for-submit-available-unsubmitted
[420.793679] Subtest etime-single-wait-for-submit-available-unsubmitted: SUCCESS (0.104s)
[420.847937] [100/142] (600s left) perf (short-reads)
[421.149492] Starting subtest: short-reads
[421.203903] Subtest short-reads: SUCCESS (0.052s)
[421.249161] [101/142] (599s left) kms_chamelium_hpd (common-hpd-after-suspend)
[421.640870] Subtest common-hpd-after-suspend: SKIP (0.000s)
[421.932269] [102/142] (599s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[422.251553] Starting subtest: psr-2p-primscrn-cur-indfb-move
[422.254283] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[422.324179] [103/142] (598s left) gem_exec_reloc (basic-cpu-wc-noreloc)
[422.536852] Starting subtest: basic-cpu-wc-noreloc
[422.549658] Subtest basic-cpu-wc-noreloc: SUCCESS (0.013s)
[422.708630] [104/142] (598s left) gem_lmem_swapping (verify-random)
[422.921762] Subtest verify-random: SKIP (0.000s)
[422.975869] [105/142] (598s left) syncobj_timeline (reset-during-wait-for-submit)
[423.181109] Starting subtest: reset-during-wait-for-submit
[423.286983] Subtest reset-during-wait-for-submit: SUCCESS (0.104s)
[423.341315] [106/142] (597s left) gem_mmap_wc (write-prefaulted)
[423.542574] Starting subtest: write-prefaulted
[423.841525] Subtest write-prefaulted: SUCCESS (0.299s)
[423.892100] [107/142] (597s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[424.097960] Starting subtest: addfb25-x-tiled-mismatch-legacy
[424.099515] Subtest addfb25-x-tiled-mismatch-legacy: SUCCESS (0.001s)
[424.274582] [108/142] (597s left) kms_psr2_sf (cursor-plane-move-continuous-exceed-fully-sf)
[424.481731] Subtest cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[424.526251] [109/142] (596s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_mtl_mc_ccs)
[424.924654] Starting subtest: pipe-A-crc-primary-basic-4_tiled_mtl_mc_ccs
[424.927584] Subtest pipe-A-crc-primary-basic-4_tiled_mtl_mc_ccs: SKIP (0.001s)
[425.472617] [110/142] (595s left) gen3_render_linear_blits
[425.735430] [111/142] (595s left) kms_vblank (pipe-d-ts-continuation-modeset-hang)
[426.042711] Subtest pipe-D-ts-continuation-modeset-hang: SKIP (0.000s)
[426.100757] [112/142] (595s left) gem_lmem_swapping (parallel-random-verify-ccs)
[426.312373] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[426.368922] [113/142] (595s left) kms_selftest (drm_cmdline)
[426.564424] Starting subtest: drm_cmdline
[426.570442] Subtest drm_cmdline: SKIP (0.004s)
[426.613972] [114/142] (595s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[426.834956] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[426.836540] Starting dynamic subtest: smem
[427.251681] Dynamic subtest smem: SUCCESS (0.413s)
[427.253113] Subtest mixed-tiled-to-yf-tiled-ccs: SUCCESS (0.416s)
[427.417674] [115/142] (594s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[427.737096] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[427.739989] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[427.800760] [116/142] (593s left) kms_atomic_interruptible (universal-setplane-cursor)
[428.113699] Starting subtest: universal-setplane-cursor
[428.117753] Starting dynamic subtest: DP-1-pipe-A
[434.692180] Dynamic subtest DP-1-pipe-A: SUCCESS (6.573s)
[434.693645] Subtest universal-setplane-cursor: SUCCESS (6.578s)
[435.018286] [117/142] (586s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[435.338513] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[435.341431] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[435.413832] [118/142] (586s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[435.719840] Starting subtest: basic-flip-before-cursor-varying-size
[436.988274] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.266s)
[437.299603] [119/142] (584s left) gem_exec_fence (syncobj-stationary-timeline-chain-engines)
[438.095323] Starting subtest: syncobj-stationary-timeline-chain-engines
[438.223589] Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.130s)
[438.427171] [120/142] (583s left) syncobj_wait (wait-all-delayed-signal)
[438.634829] Starting subtest: wait-all-delayed-signal
[438.737990] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[438.795102] [121/142] (583s left) perf_pmu (busy)
[439.006300] Starting subtest: busy
[439.007795] Starting dynamic subtest: rcs0
[439.556639] Dynamic subtest rcs0: SUCCESS (0.549s)
[439.583064] Starting dynamic subtest: bcs0
[440.103336] Dynamic subtest bcs0: SUCCESS (0.543s)
[440.128796] Starting dynamic subtest: vcs0
[440.649338] Dynamic subtest vcs0: SUCCESS (0.543s)
[440.675382] Starting dynamic subtest: vecs0
[441.189573] Dynamic subtest vecs0: SUCCESS (0.537s)
[441.190949] Subtest busy: SUCCESS (2.185s)
[441.259689] [122/142] (580s left) gem_eio (in-flight-internal-1us)
[441.496196] Starting subtest: in-flight-internal-1us
[441.599764] Subtest in-flight-internal-1us: SUCCESS (0.105s)
[441.760454] [123/142] (580s left) kms_vblank (pipe-a-query-idle-hang)
[442.067677] Starting subtest: pipe-A-query-idle-hang
[444.348367] Subtest pipe-A-query-idle-hang: SUCCESS (2.284s)
[444.645410] [124/142] (577s left) gem_ringfill (legacy-basic)
[445.118347] Starting subtest: legacy-basic
[445.119741] Starting dynamic subtest: default
[445.138894] Dynamic subtest default: SUCCESS (0.019s)
[445.168331] Starting dynamic subtest: render
[445.169735] Dynamic subtest render: SUCCESS (0.021s)
[445.195378] Starting dynamic subtest: bsd
[445.196747] Dynamic subtest bsd: SUCCESS (0.018s)
[445.221690] Starting dynamic subtest: bsd1
[445.223031] Dynamic subtest bsd1: SUCCESS (0.015s)
[445.247835] Starting dynamic subtest: blt
[445.249458] Dynamic subtest blt: SUCCESS (0.022s)
[445.274037] Starting dynamic subtest: vebox
[445.275438] Dynamic subtest vebox: SUCCESS (0.019s)
[445.276781] Subtest legacy-basic: SUCCESS (0.132s)
[445.310076] [125/142] (576s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[445.612312] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[445.613642] Starting dynamic subtest: pipe-A-valid-mode
[445.985781] Dynamic subtest pipe-A-valid-mode: SKIP (0.370s)
[445.988497] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SKIP (0.374s)
[446.323158] [126/142] (575s left) kms_cursor_legacy (torture-bo)
[446.633525] Starting subtest: torture-bo
[446.634938] Starting dynamic subtest: pipe-A
[452.246237] Dynamic subtest pipe-A: SUCCESS (5.615s)
[452.278541] Starting dynamic subtest: pipe-B
[458.086088] Dynamic subtest pipe-B: SUCCESS (5.828s)
[458.113067] Starting dynamic subtest: pipe-C
[463.747186] Dynamic subtest pipe-C: SUCCESS (5.657s)
[463.770300] Starting dynamic subtest: all-pipes
[469.377637] Dynamic subtest all-pipes: SUCCESS (5.629s)
[469.379273] Subtest torture-bo: SUCCESS (22.746s)
[469.437801] [127/142] (552s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[469.842491] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[469.846183] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[469.895750] [128/142] (552s left) gem_exec_params (rel-constants-invalid)
[470.127914] Starting subtest: rel-constants-invalid
[470.130449] Subtest rel-constants-invalid: SUCCESS (0.001s)
[470.204342] [129/142] (552s left) kms_big_fb (linear-32bpp-rotate-90)
[470.530598] Starting subtest: linear-32bpp-rotate-90
[470.617957] Subtest linear-32bpp-rotate-90: SKIP (0.085s)
[470.700746] [130/142] (551s left) kms_flip (2x-modeset-vs-vblank-race)
[470.975519] Starting subtest: 2x-modeset-vs-vblank-race
[470.984161] Subtest 2x-modeset-vs-vblank-race: SKIP (0.004s)
[471.068370] [131/142] (551s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[471.360977] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[471.366644] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.003s)
[471.435245] [132/142] (551s left) gem_eio (in-flight-10ms)
[471.660362] Starting subtest: in-flight-10ms
[472.615584] Subtest in-flight-10ms: SUCCESS (0.953s)
[472.802350] [133/142] (549s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[473.117318] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[473.122232] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.003s)
[473.184671] [134/142] (549s left) kms_plane_lowres (tiling-x)
[473.486732] Starting subtest: tiling-x
[473.488215] Starting dynamic subtest: pipe-A-DP-1
[479.873035] Dynamic subtest pipe-A-DP-1: SUCCESS (6.385s)
[479.996074] Starting dynamic subtest: pipe-B-DP-1
[486.254938] Dynamic subtest pipe-B-DP-1: SUCCESS (6.280s)
[486.396213] Starting dynamic subtest: pipe-C-DP-1
[490.651602] Dynamic subtest pipe-C-DP-1: SUCCESS (4.280s)
[490.765399] Subtest tiling-x: SUCCESS (17.282s)
[491.201804] [135/142] (531s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[491.502918] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[491.504310] Starting dynamic subtest: pipe-A-valid-mode
[491.618639] Dynamic subtest pipe-A-valid-mode: SKIP (0.113s)
[491.622381] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.118s)
[491.933571] [136/142] (530s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-pwrite)
[492.248844] Starting subtest: psr-1p-offscren-pri-shrfb-draw-pwrite
[492.251734] Subtest psr-1p-offscren-pri-shrfb-draw-pwrite: SKIP (0.000s)
[492.328715] [137/142] (530s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_mtl_rc_ccs)
[492.926877] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs
[492.932191] Subtest pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs: SKIP (0.002s)
[493.307734] [138/142] (529s left) perf (blocking)
[493.570496] Starting subtest: blocking
[493.571938] Starting dynamic subtest: 0-rcs0
[503.645479] Dynamic subtest 0-rcs0: SUCCESS (10.073s)
[503.646803] Subtest blocking: SUCCESS (10.076s)
[503.708043] [139/142] (519s left) kms_sequence (get-forked)
[504.015187] Starting subtest: get-forked
[504.016551] Starting dynamic subtest: DP-1-pipe-A
[506.375971] Dynamic subtest DP-1-pipe-A: SUCCESS (2.358s)
[506.403557] Starting dynamic subtest: DP-1-pipe-B
[509.045360] Dynamic subtest DP-1-pipe-B: SUCCESS (2.667s)
[509.073803] Starting dynamic subtest: DP-1-pipe-C
[511.840920] Dynamic subtest DP-1-pipe-C: SUCCESS (2.789s)
[511.842271] Subtest get-forked: SUCCESS (7.829s)
[512.136516] [140/142] (510s left) kms_plane (plane-position-hole-dpms)
[512.434062] Starting subtest: plane-position-hole-dpms
[512.435686] Starting dynamic subtest: pipe-A-planes
[514.620397] Dynamic subtest pipe-A-planes: SUCCESS (2.187s)
[514.647735] Starting dynamic subtest: pipe-B-planes
[517.035336] Dynamic subtest pipe-B-planes: SUCCESS (2.410s)
[517.036922] Subtest plane-position-hole-dpms: SUCCESS (4.607s)
[517.358807] [141/142] (505s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-25)
[517.670383] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-25
[517.671760] Starting dynamic subtest: pipe-A-DP-1
[517.709246] Dynamic subtest pipe-A-DP-1: SKIP (0.035s)
[517.753129] Starting dynamic subtest: pipe-B-DP-1
[517.800506] Dynamic subtest pipe-B-DP-1: SKIP (0.085s)
[517.828653] Starting dynamic subtest: pipe-C-DP-1
[517.886659] Dynamic subtest pipe-C-DP-1: SKIP (0.080s)
[517.889285] Subtest planes-upscale-factor-0-25-downscale-factor-0-25: SKIP (0.218s)
[517.953387] [142/142] (504s left) gem_busy (parallel)
[518.314661] Starting subtest: parallel
[518.316143] Starting dynamic subtest: rcs0
[518.362890] Dynamic subtest rcs0: SUCCESS (0.049s)
[518.401308] Starting dynamic subtest: bcs0
[518.410773] Dynamic subtest bcs0: SUCCESS (0.045s)
[518.448933] Starting dynamic subtest: vcs0
[518.453452] Dynamic subtest vcs0: SUCCESS (0.038s)
[518.487371] Starting dynamic subtest: vecs0
[518.491998] Dynamic subtest vecs0: SUCCESS (0.035s)
[518.493549] Subtest parallel: SUCCESS (0.178s)
[518.698743] Closing watchdogs
Done.