[41.153098] Initializing watchdogs
[41.153345]   /dev/watchdog0
[41.168107] [001/142] (960s left) gem_userptr_blits (sd-probe)
[41.408049] Starting subtest: sd-probe
[41.409885] Subtest sd-probe: SUCCESS (0.002s)
[41.580678] [002/142] (960s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[41.909814] Starting subtest: 4-tiled-32bpp-rotate-270
[41.912776] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.000s)
[42.009276] [003/142] (959s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_mtl_rc_ccs_cc)
[42.751257] Subtest pipe-D-bad-pixel-format-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[43.064795] [004/142] (958s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[43.381924] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[43.384953] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[43.464351] [005/142] (958s left) kms_fbcon_fbt (psr)
[43.675690] Starting subtest: psr
[43.678900] Subtest psr: SKIP (0.001s)
[43.776597] [006/142] (958s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs)
[44.550520] Subtest pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[44.860403] [007/142] (956s left) kms_flip (flip-vs-suspend)
[45.158125] Starting subtest: flip-vs-suspend
[45.159617] Starting dynamic subtest: A-DP1
[99.050390] Dynamic subtest A-DP1: SUCCESS (4.726s)
[99.065628] Starting dynamic subtest: B-DP1
[151.048954] Dynamic subtest B-DP1: SUCCESS (3.901s)
[151.077415] Starting dynamic subtest: C-DP1
[204.032066] Dynamic subtest C-DP1: SUCCESS (3.912s)
[204.035791] Subtest flip-vs-suspend: SUCCESS (12.548s)
[210.484105] [008/142] (943s left) gem_fence_thrash (bo-copy)
[210.692760] Starting subtest: bo-copy
[212.211323] Subtest bo-copy: SUCCESS (1.518s)
[212.314345] [009/142] (941s left) gem_mmap_offset (blt-coherency)
[212.506688] Starting subtest: blt-coherency
[212.550848] Subtest blt-coherency: SUCCESS (0.042s)
[212.663837] [010/142] (941s left) kms_flip (dpms-off-confusion-interruptible)
[212.956726] Starting subtest: dpms-off-confusion-interruptible
[212.960655] Starting dynamic subtest: A-DP1
[223.467387] Dynamic subtest A-DP1: SUCCESS (10.508s)
[223.488487] Starting dynamic subtest: B-DP1
[233.918370] Dynamic subtest B-DP1: SUCCESS (10.443s)
[233.936424] Starting dynamic subtest: C-DP1
[244.355505] Dynamic subtest C-DP1: SUCCESS (10.442s)
[244.360886] Subtest dpms-off-confusion-interruptible: SUCCESS (31.401s)
[244.739105] [011/142] (909s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[245.066213] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[246.380299] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (1.314s)
[246.719517] [012/142] (907s left) gem_exec_flush (basic-batch-kernel-default-uc)
[246.945233] Starting subtest: basic-batch-kernel-default-uc
[252.741513] Subtest basic-batch-kernel-default-uc: SUCCESS (5.794s)
[252.935736] [013/142] (901s left) syncobj_wait (single-wait-signaled)
[253.138326] Starting subtest: single-wait-signaled
[253.140059] Subtest single-wait-signaled: SUCCESS (0.001s)
[253.218462] [014/142] (901s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_mtl_rc_ccs)
[253.859240] Starting subtest: pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs
[253.861864] Subtest pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[254.295797] [015/142] (900s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[254.601141] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[254.603688] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[254.695359] [016/142] (899s left) gem_mmap_gtt (basic-write-read-distinct)
[254.896647] Starting subtest: basic-write-read-distinct
[254.929699] Subtest basic-write-read-distinct: SUCCESS (0.033s)
[255.044857] [017/142] (899s left) gem_ctx_create (basic)
[255.271683] Starting subtest: basic
[255.273082] Subtest basic: SUCCESS (0.000s)
[255.463615] [018/142] (899s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[255.778582] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[255.781339] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[255.879224] [019/142] (898s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[256.185356] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[256.187880] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[256.279183] [020/142] (898s left) kms_display_modes (mst-extended-mode-negative)
[256.575929] Starting subtest: mst-extended-mode-negative
[256.578970] Subtest mst-extended-mode-negative: SKIP (0.000s)
[256.627980] [021/142] (898s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[257.023447] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[257.304415] [022/142] (897s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_dg2_mc_ccs)
[257.816200] Starting subtest: pipe-B-bad-rotation-90-4_tiled_dg2_mc_ccs
[257.818782] Subtest pipe-B-bad-rotation-90-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[258.342239] [023/142] (896s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[258.649749] Starting subtest: fbc-rgb565-draw-render
[260.869825] Subtest fbc-rgb565-draw-render: SUCCESS (2.222s)
[261.009898] [024/142] (893s left) drm_fdinfo (virtual-busy-idle)
[261.217134] Starting subtest: virtual-busy-idle
[263.794988] Subtest virtual-busy-idle: SUCCESS (2.578s)
[263.875696] [025/142] (891s left) kms_busy (basic-hang)
[264.190180] Starting subtest: basic-hang
[264.194921] Starting dynamic subtest: flip-pipe-A
[264.817166] Dynamic subtest flip-pipe-A: SUCCESS (0.622s)
[264.840393] Starting dynamic subtest: modeset-pipe-A
[266.233175] Dynamic subtest modeset-pipe-A: SUCCESS (1.417s)
[266.260753] Starting dynamic subtest: flip-pipe-C
[267.155734] Dynamic subtest flip-pipe-C: SUCCESS (0.910s)
[267.174572] Starting dynamic subtest: modeset-pipe-C
[268.797646] Dynamic subtest modeset-pipe-C: SUCCESS (1.642s)
[268.801338] Subtest basic-hang: SUCCESS (4.610s)
[269.146803] [026/142] (885s left) i915_pm_dc (dc5-psr)
[270.459540] Starting subtest: dc5-psr
[270.464050] Subtest dc5-psr: SKIP (0.000s)
[270.627405] [027/142] (884s left) kms_plane_alpha_blend (coverage-7efc)
[270.935851] Starting subtest: coverage-7efc
[271.242597] Starting dynamic subtest: pipe-A-DP-1
[271.717418] Dynamic subtest pipe-A-DP-1: SUCCESS (0.474s)
[271.787603] Starting dynamic subtest: pipe-C-DP-1
[272.551509] Dynamic subtest pipe-C-DP-1: SUCCESS (0.777s)
[272.553280] Subtest coverage-7efc: SUCCESS (1.617s)
[272.902915] [028/142] (882s left) kms_cdclk (mode-transition)
[273.103830] Subtest mode-transition: SKIP (0.000s)
[273.152296] [029/142] (881s left) perf_pmu (idle)
[273.357110] Starting subtest: idle
[273.358692] Starting dynamic subtest: rcs0
[273.879149] Dynamic subtest rcs0: SUCCESS (0.519s)
[273.898520] Starting dynamic subtest: bcs0
[274.392113] Dynamic subtest bcs0: SUCCESS (0.514s)
[274.412899] Starting dynamic subtest: vcs0
[274.909082] Dynamic subtest vcs0: SUCCESS (0.515s)
[274.929586] Starting dynamic subtest: vecs0
[275.425188] Dynamic subtest vecs0: SUCCESS (0.514s)
[275.426886] Subtest idle: SUCCESS (2.068s)
[275.520455] [030/142] (879s left) feature_discovery (psr1)
[275.812399] Starting subtest: psr1
[275.814885] Subtest psr1: SKIP (0.000s)
[275.904007] [031/142] (879s left) kms_cursor_legacy (flip-vs-cursor-toggle)
[276.199619] Starting subtest: flip-vs-cursor-toggle
[295.112039] Subtest flip-vs-cursor-toggle: SUCCESS (18.913s)
[295.448760] [032/142] (859s left) gem_exec_reloc (basic-cpu-read-active)
[295.661502] Starting subtest: basic-cpu-read-active
[295.799858] Subtest basic-cpu-read-active: SUCCESS (0.138s)
[295.996971] [033/142] (859s left) gem_lmem_swapping (verify-random)
[296.203849] Subtest verify-random: SKIP (0.000s)
[296.262347] [034/142] (858s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[296.558422] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[296.559788] Starting dynamic subtest: pipe-A-DP-1
[296.637716] Dynamic subtest pipe-A-DP-1: SUCCESS (0.078s)
[296.667434] Starting dynamic subtest: pipe-B-DP-1
[297.008407] Dynamic subtest pipe-B-DP-1: SUCCESS (0.366s)
[297.036964] Starting dynamic subtest: pipe-C-DP-1
[297.103840] Dynamic subtest pipe-C-DP-1: SKIP (0.087s)
[297.105328] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (0.545s)
[297.501575] [035/142] (857s left) gem_exec_balancer (parallel-contexts)
[301.510520] Subtest parallel-contexts: SKIP (0.000s)
[301.602298] [036/142] (853s left) kms_ccs (pipe-d-crc-primary-basic-4_tiled_mtl_mc_ccs)
[302.329488] Subtest pipe-D-crc-primary-basic-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[302.627900] [037/142] (852s left) gem_exec_reloc (basic-wc-gtt-active)
[302.836724] Starting subtest: basic-wc-gtt-active
[302.979165] Subtest basic-wc-gtt-active: SUCCESS (0.141s)
[303.178188] [038/142] (852s left) kms_cursor_crc (cursor-onscreen-64x64)
[303.481690] Starting subtest: cursor-onscreen-64x64
[303.494443] Starting dynamic subtest: pipe-A-DP-1
[305.835642] Dynamic subtest pipe-A-DP-1: SUCCESS (2.342s)
[305.920193] Starting dynamic subtest: pipe-C-DP-1
[308.549351] Dynamic subtest pipe-C-DP-1: SUCCESS (2.648s)
[308.550600] Subtest cursor-onscreen-64x64: SUCCESS (5.068s)
[308.897095] [039/142] (846s left) kms_selftest (drm_damage)
[309.092408] Starting subtest: drm_damage
[309.097646] Subtest drm_damage: SKIP (0.004s)
[309.176731] [040/142] (846s left) gem_exec_whisper (basic-contexts-forked)
[309.402326] Starting subtest: basic-contexts-forked
[323.970527] Subtest basic-contexts-forked: SUCCESS (14.567s)
[324.180195] [041/142] (831s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[324.494726] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[324.497605] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[324.595231] [042/142] (830s left) kms_ccs (pipe-b-crc-primary-basic-yf_tiled_ccs)
[325.108428] Starting subtest: pipe-B-crc-primary-basic-yf_tiled_ccs
[325.763970] Subtest pipe-B-crc-primary-basic-yf_tiled_ccs: SUCCESS (0.654s)
[326.368871] [043/142] (829s left) gem_vm_create (isolation)
[326.577702] Starting subtest: isolation
[326.589532] Subtest isolation: SUCCESS (0.012s)
[326.701758] [044/142] (828s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[327.008662] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[327.011539] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[327.117939] [045/142] (828s left) kms_vblank (pipe-d-wait-idle-hang)
[327.423439] Subtest pipe-D-wait-idle-hang: SKIP (0.000s)
[327.517998] [046/142] (828s left) gem_ctx_isolation (preservation-reset)
[327.858476] Starting subtest: preservation-reset
[327.859908] Starting dynamic subtest: rcs0
[329.077279] Dynamic subtest rcs0: SUCCESS (1.218s)
[329.097584] Starting dynamic subtest: bcs0
[330.556771] Dynamic subtest bcs0: SUCCESS (1.479s)
[330.578825] Starting dynamic subtest: vcs0
[331.680495] Dynamic subtest vcs0: SUCCESS (1.122s)
[331.700524] Starting dynamic subtest: vecs0
[332.773338] Dynamic subtest vecs0: SUCCESS (1.091s)
[332.775062] Subtest preservation-reset: SUCCESS (4.919s)
[332.953671] [047/142] (822s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[333.160247] Starting subtest: wait-all-for-submit-delayed-submit
[333.265462] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.105s)
[333.350523] [048/142] (822s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[333.655865] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[333.658731] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[333.751385] [049/142] (822s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[336.473700] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s)
[336.558453] [050/142] (819s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[336.864786] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[336.867613] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[336.970379] [051/142] (818s left) kms_flip (2x-plain-flip)
[337.261130] Starting subtest: 2x-plain-flip
[337.263884] Subtest 2x-plain-flip: SKIP (0.003s)
[337.385207] [052/142] (818s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-submitted-signaled
[337.586112] Starting subtest: invalid-multi-wait-available-unsubmitted-submitted-signaled
[337.587956] Subtest invalid-multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[337.684784] [053/142] (818s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[337.888808] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[337.992135] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.101s)
[338.083579] [054/142] (817s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[338.392530] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[338.395132] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[338.484393] [055/142] (817s left) kms_atomic (atomic-invalid-params)
[338.919950] Starting subtest: atomic-invalid-params
[339.009390] Subtest atomic-invalid-params: SUCCESS (0.089s)
[339.444468] [056/142] (816s left) perf (mi-rpc)
[339.794860] Starting subtest: mi-rpc
[339.858219] Subtest mi-rpc: SUCCESS (0.062s)
[339.907169] [057/142] (816s left) kms_ccs (pipe-c-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc)
[340.598167] Starting subtest: pipe-C-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc
[340.600774] Subtest pipe-C-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[340.951700] [058/142] (815s left) v3d/v3d_perfmon (create-perfmon-exceed)
[341.193298] Subtest create-perfmon-exceed: SKIP (0.000s)
[341.267565] [059/142] (814s left) gem_ctx_sseu (engines)
[341.478113] Subtest engines: SKIP (0.000s)
[341.533855] [060/142] (814s left) i915_suspend (basic-s3-without-i915)
[341.735159] Starting subtest: basic-s3-without-i915
[362.146704] Subtest basic-s3-without-i915: SUCCESS (4.052s)
[367.306557] [061/142] (810s left) syncobj_timeline (single-wait-all-available-submitted)
[367.521790] Starting subtest: single-wait-all-available-submitted
[367.523307] Subtest single-wait-all-available-submitted: SUCCESS (0.001s)
[367.608453] [062/142] (810s left) gem_exec_params (readonly)
[367.828391] Starting subtest: readonly
[367.843103] Subtest readonly: SUCCESS (0.013s)
[367.961584] [063/142] (809s left) gem_exec_schedule (noreorder)
[368.330389] Starting subtest: noreorder
[368.331866] Starting dynamic subtest: rcs0
[368.406176] Dynamic subtest rcs0: SUCCESS (0.074s)
[368.430013] Starting dynamic subtest: bcs0
[368.467310] Dynamic subtest bcs0: SUCCESS (0.059s)
[368.499724] Starting dynamic subtest: vcs0
[368.531594] Dynamic subtest vcs0: SUCCESS (0.061s)
[368.563475] Starting dynamic subtest: vecs0
[368.589670] Dynamic subtest vecs0: SUCCESS (0.055s)
[368.591289] Subtest noreorder: SUCCESS (0.261s)
[368.793112] [064/142] (809s left) gen9_exec_parse (secure-batches)
[369.023407] Starting subtest: secure-batches
[369.024900] Subtest secure-batches: SUCCESS (0.000s)
[369.243529] [065/142] (808s left) syncobj_wait (wait-all-snapshot)
[369.447232] Starting subtest: wait-all-snapshot
[369.529691] Subtest wait-all-snapshot: SUCCESS (0.083s)
[369.630118] [066/142] (808s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[369.955160] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[369.958221] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[370.060392] [067/142] (807s left) gem_exec_reloc (basic-gtt-read-active)
[370.273359] Starting subtest: basic-gtt-read-active
[370.422311] Subtest basic-gtt-read-active: SUCCESS (0.148s)
[370.610486] [068/142] (807s left) kms_universal_plane (universal-plane-pageflip-windowed-pipe-c)
[370.914763] Starting subtest: universal-plane-pageflip-windowed-pipe-C
[371.873555] Subtest universal-plane-pageflip-windowed-pipe-C: SUCCESS (0.958s)
[372.196291] [069/142] (805s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[372.517180] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[372.520113] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[372.613135] [070/142] (805s left) kms_invalid_mode (bad-hsync-start)
[372.915907] Starting subtest: bad-hsync-start
[372.917242] Starting dynamic subtest: DP-1-pipe-A
[373.032684] Dynamic subtest DP-1-pipe-A: SUCCESS (0.115s)
[373.063852] Starting dynamic subtest: DP-1-pipe-B
[373.065688] Dynamic subtest DP-1-pipe-B: SUCCESS (0.015s)
[373.089403] Starting dynamic subtest: DP-1-pipe-C
[373.090688] Dynamic subtest DP-1-pipe-C: SUCCESS (0.016s)
[373.091916] Subtest bad-hsync-start: SUCCESS (0.164s)
[373.411274] [071/142] (804s left) vgem_basic (mmap)
[373.687639] Starting subtest: mmap
[373.713987] Subtest mmap: SUCCESS (0.027s)
[373.795415] [072/142] (804s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs)
[374.418419] Starting subtest: pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs
[374.425759] Subtest pipe-B-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs: SKIP (0.006s)
[374.887525] [073/142] (803s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[375.215223] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[375.220862] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.004s)
[375.320996] [074/142] (802s left) kms_color (ctm-blue-to-red)
[375.632956] Starting subtest: ctm-blue-to-red
[375.634352] Starting dynamic subtest: pipe-A
[376.142013] Dynamic subtest pipe-A: SUCCESS (0.510s)
[376.162961] Starting dynamic subtest: pipe-B
[376.927410] Dynamic subtest pipe-B: SUCCESS (0.782s)
[376.953302] Starting dynamic subtest: pipe-C
[377.842185] Dynamic subtest pipe-C: SUCCESS (0.914s)
[377.843503] Subtest ctm-blue-to-red: SUCCESS (2.213s)
[378.178404] [075/142] (800s left) kms_getfb (getfb2-handle-closed)
[378.385435] Starting subtest: getfb2-handle-closed
[378.387149] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[378.494453] [076/142] (799s left) kms_ccs (pipe-a-bad-rotation-90-4_tiled_mtl_rc_ccs)
[378.887986] Starting subtest: pipe-A-bad-rotation-90-4_tiled_mtl_rc_ccs
[378.890655] Subtest pipe-A-bad-rotation-90-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[379.505096] [077/142] (798s left) kms_psr (psr2_cursor_mmap_cpu)
[379.708188] Subtest psr2_cursor_mmap_cpu: SKIP (0.000s)
[379.771169] [078/142] (798s left) sysfs_timeslice_duration (idempotent)
[379.979533] Starting subtest: idempotent
[379.984337] Starting dynamic subtest: vecs0
[379.986148] Dynamic subtest vecs0: SUCCESS (0.002s)
[380.009554] Starting dynamic subtest: vcs0
[380.011043] Dynamic subtest vcs0: SUCCESS (0.002s)
[380.032043] Starting dynamic subtest: bcs0
[380.033366] Dynamic subtest bcs0: SUCCESS (0.002s)
[380.054080] Starting dynamic subtest: rcs0
[380.055470] Dynamic subtest rcs0: SUCCESS (0.001s)
[380.056892] Subtest idempotent: SUCCESS (0.018s)
[380.090317] [079/142] (798s left) kms_force_connector_basic (force-connector-state)
[380.296701] Subtest force-connector-state: SKIP (0.000s)
[380.354207] [080/142] (798s left) gem_exec_schedule (u-fairslice)
[380.579884] Starting subtest: u-fairslice
[380.584108] Starting dynamic subtest: rcs0
[382.617896] Dynamic subtest rcs0: SUCCESS (2.034s)
[382.641840] Starting dynamic subtest: bcs0
[384.654833] Dynamic subtest bcs0: SUCCESS (2.033s)
[384.677097] Starting dynamic subtest: vcs0
[386.684675] Dynamic subtest vcs0: SUCCESS (2.028s)
[386.707323] Starting dynamic subtest: vecs0
[388.718023] Dynamic subtest vecs0: SUCCESS (2.029s)
[388.719356] Subtest u-fairslice: SUCCESS (8.135s)
[389.055549] [081/142] (789s left) kms_ccs (pipe-c-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc)
[389.728097] Starting subtest: pipe-C-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc
[389.730899] Subtest pipe-C-missing-ccs-buffer-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[390.086400] [082/142] (788s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[390.384637] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[390.386016] Starting dynamic subtest: pipe-A-valid-mode
[390.501613] Dynamic subtest pipe-A-valid-mode: SKIP (0.116s)
[390.505190] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.123s)
[390.841076] [083/142] (787s left) perf (low-oa-exponent-permissions)
[391.147270] Starting subtest: low-oa-exponent-permissions
[391.304573] Subtest low-oa-exponent-permissions: SUCCESS (0.157s)
[391.359432] [084/142] (787s left) debugfs_test (basic-hwmon)
[391.566032] Starting subtest: basic-hwmon
[391.568959] Subtest basic-hwmon: SKIP (0.000s)
[391.757985] [085/142] (786s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[391.978295] Starting subtest: mixed-tiled-to-y-tiled-ccs
[391.979746] Starting dynamic subtest: smem
[392.282745] Dynamic subtest smem: SUCCESS (0.302s)
[392.284147] Subtest mixed-tiled-to-y-tiled-ccs: SUCCESS (0.305s)
[392.492502] [086/142] (786s left) kms_big_fb (linear-addfb)
[392.812150] Starting subtest: linear-addfb
[392.814141] Subtest linear-addfb: SUCCESS (0.001s)
[392.908175] [087/142] (785s left) kms_lease (master-vs-lease)
[393.205218] Starting subtest: master-vs-lease
[393.206714] Subtest master-vs-lease: SUCCESS (0.003s)
[393.292438] [088/142] (785s left) kms_setmode (invalid-clone-single-crtc)
[393.494770] Starting subtest: invalid-clone-single-crtc
[393.499942] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[393.590967] [089/142] (785s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[393.900015] Starting subtest: primary-x-tiled-reflect-x-180
[393.906354] Subtest primary-x-tiled-reflect-x-180: SKIP (0.003s)
[393.991409] [090/142] (784s left) gem_eio (create-ext)
[394.228164] Starting subtest: create-ext
[394.276060] Subtest create-ext: SUCCESS (0.045s)
[394.457939] [091/142] (784s left) gem_sync (basic-store-each)
[394.682844] Starting subtest: basic-store-each
[397.374869] Subtest basic-store-each: SUCCESS (2.691s)
[397.592724] [092/142] (781s left) kms_atomic_transition (plane-all-transition-nonblocking)
[397.891199] Starting subtest: plane-all-transition-nonblocking
[397.892542] Starting dynamic subtest: pipe-A-DP-1
[401.880479] Dynamic subtest pipe-A-DP-1: SUCCESS (3.988s)
[402.016575] Starting dynamic subtest: pipe-B-DP-1
[406.328620] Dynamic subtest pipe-B-DP-1: SUCCESS (4.342s)
[406.429640] Subtest plane-all-transition-nonblocking: SUCCESS (8.542s)
[406.774753] [093/142] (772s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[407.114207] Starting subtest: yf-tiled-16bpp-rotate-180
[410.761948] Subtest yf-tiled-16bpp-rotate-180: SUCCESS (3.650s)
[411.092464] [094/142] (767s left) gem_ctx_param (invalid-param-set)
[411.295094] Starting subtest: invalid-param-set
[411.296452] Subtest invalid-param-set: SUCCESS (0.000s)
[411.390418] [095/142] (767s left) gem_ctx_param (get-priority-new-ctx)
[411.596748] Starting subtest: get-priority-new-ctx
[411.598443] Subtest get-priority-new-ctx: SUCCESS (0.002s)
[411.690605] [096/142] (767s left) gem_fence_thrash (bo-write-verify-threaded-none)
[411.888231] Starting subtest: bo-write-verify-threaded-none
[414.438323] Subtest bo-write-verify-threaded-none: SUCCESS (2.550s)
[414.542697] [097/142] (764s left) gem_ctx_bad_destroy (double-destroy)
[414.748305] Starting subtest: double-destroy
[414.750045] Subtest double-destroy: SUCCESS (0.001s)
[414.840583] [098/142] (764s left) gem_userptr_blits (create-destroy-sync)
[415.079003] Starting subtest: create-destroy-sync
[420.167203] Subtest create-destroy-sync: SUCCESS (5.088s)
[420.308199] [099/142] (758s left) gem_basic (create-close)
[420.509546] Starting subtest: create-close
[420.512754] Subtest create-close: SUCCESS (0.000s)
[420.607234] [100/142] (758s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[420.911624] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[420.914187] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[421.024241] [101/142] (758s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[421.349739] Starting subtest: fbc-rgb101010-draw-pwrite
[423.995581] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (2.649s)
[424.124362] [102/142] (755s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[424.441751] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[424.444806] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[424.541228] [103/142] (754s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[424.868657] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[424.897870] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.028s)
[425.024340] [104/142] (754s left) kms_rotation_crc (bad-tiling)
[425.333286] Starting subtest: bad-tiling
[427.655246] Subtest bad-tiling: SUCCESS (2.325s)
[428.036249] [105/142] (751s left) gem_create (create-ext-cpu-access-sanity-check)
[428.370110] Starting subtest: create-ext-cpu-access-sanity-check
[428.374024] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[428.469979] [106/142] (751s left) i915_pm_rpm (system-suspend-modeset)
[430.359947] Starting subtest: system-suspend-modeset
[448.651290] Subtest system-suspend-modeset: SUCCESS (2.204s)
[454.337225] [107/142] (746s left) kms_properties (invalid-properties-legacy)
[454.640018] Starting subtest: invalid-properties-legacy
[454.768175] Subtest invalid-properties-legacy: SUCCESS (0.130s)
[454.864840] [108/142] (745s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[455.181372] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[455.184568] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[455.297250] [109/142] (745s left) kms_content_protection (srm)
[455.631301] Starting subtest: srm
[455.670272] Starting dynamic subtest: pipe-A-DP-1
[576.266633] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[576.971447] Closing watchdogs
[576.975429] Initializing watchdogs
[576.975601]   /dev/watchdog0
[577.003604] [110/142] (623s left) vc4/vc4_wait_bo (unused-bo-1ns)
[577.269007] Subtest unused-bo-1ns: SKIP (0.000s)
[577.345554] [111/142] (623s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[577.651249] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[579.733678] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (2.085s)
[579.879557] [112/142] (621s left) gem_eio (wait-wedge-10ms)
[580.100683] Starting subtest: wait-wedge-10ms
[580.206387] Subtest wait-wedge-10ms: SUCCESS (0.105s)
[580.378332] [113/142] (620s left) kms_cursor_crc (cursor-sliding-128x42)
[580.691139] Starting subtest: cursor-sliding-128x42
[580.702122] Starting dynamic subtest: pipe-A-DP-1
[584.371635] Dynamic subtest pipe-A-DP-1: SUCCESS (3.665s)
[584.465619] Starting dynamic subtest: pipe-C-DP-1
[588.435969] Dynamic subtest pipe-C-DP-1: SUCCESS (3.994s)
[588.437386] Subtest cursor-sliding-128x42: SUCCESS (7.745s)
[588.799003] [114/142] (612s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[589.000693] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[589.107050] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.106s)
[589.193723] [115/142] (611s left) gem_lmem_swapping (massive-random)
[589.401200] Subtest massive-random: SKIP (0.000s)
[589.459305] [116/142] (611s left) gem_exec_flush (basic-wb-rw-default)
[589.680116] Starting subtest: basic-wb-rw-default
[595.167861] Subtest basic-wb-rw-default: SUCCESS (5.487s)
[595.376501] [117/142] (605s left) vc4/vc4_purgeable_bo (mark-unpurgeable-purged)
[595.634022] Subtest mark-unpurgeable-purged: SKIP (0.000s)
[595.709149] [118/142] (605s left) gem_ctx_persistence (engines-mixed-process)
[595.917652] Starting subtest: engines-mixed-process
[595.919288] Starting dynamic subtest: rcs0
[596.284389] Dynamic subtest rcs0: SUCCESS (0.366s)
[596.312743] Starting dynamic subtest: bcs0
[596.642300] Dynamic subtest bcs0: SUCCESS (0.355s)
[596.670002] Starting dynamic subtest: vcs0
[596.997217] Dynamic subtest vcs0: SUCCESS (0.352s)
[597.023967] Starting dynamic subtest: vecs0
[597.341278] Dynamic subtest vecs0: SUCCESS (0.341s)
[597.342981] Subtest engines-mixed-process: SUCCESS (1.425s)
[597.444491] [119/142] (603s left) api_intel_bb (crc32)
[597.648533] Starting subtest: crc32
[597.651351] Subtest crc32: SKIP (0.000s)
[597.759068] [120/142] (603s left) gem_exec_fair (basic-deadline)
[597.993997] Starting subtest: basic-deadline
[600.441421] Subtest basic-deadline: FAIL (2.344s)
[600.561887] [121/142] (600s left) gem_exec_fence (syncobj-timeline-export)
[601.368556] Starting subtest: syncobj-timeline-export
[601.375817] Subtest syncobj-timeline-export: SUCCESS (0.008s)
[601.594018] [122/142] (599s left) kms_plane_scaling (plane-downscale-with-pixel-format-factor-0-25)
[601.894121] Starting subtest: plane-downscale-with-pixel-format-factor-0-25
[601.895590] Starting dynamic subtest: pipe-A-DP-1
[601.930333] Dynamic subtest pipe-A-DP-1: SKIP (0.035s)
[601.956391] Starting dynamic subtest: pipe-B-DP-1
[602.014731] Dynamic subtest pipe-B-DP-1: SKIP (0.079s)
[602.045154] Starting dynamic subtest: pipe-C-DP-1
[602.103596] Dynamic subtest pipe-C-DP-1: SKIP (0.082s)
[602.107086] Subtest plane-downscale-with-pixel-format-factor-0-25: SKIP (0.217s)
[602.227661] [123/142] (599s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[602.528445] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[602.530296] Starting dynamic subtest: pipe-A-valid-mode
[602.645593] Dynamic subtest pipe-A-valid-mode: SKIP (0.117s)
[602.648347] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.122s)
[602.965602] [124/142] (598s left) kms_chamelium_frames (hdmi-crc-fast)
[603.353642] Subtest hdmi-crc-fast: SKIP (0.000s)
[603.644739] [125/142] (597s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[603.846157] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[603.847568] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.002s)
[603.939150] [126/142] (597s left) v3d/v3d_submit_csd (job-perfmon)
[604.194934] Subtest job-perfmon: SKIP (0.000s)
[604.272478] [127/142] (597s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[604.575548] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[604.578489] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[604.689204] [128/142] (596s left) kms_ccs (pipe-a-crc-primary-rotation-180-4_tiled_mtl_rc_ccs)
[605.102446] Starting subtest: pipe-A-crc-primary-rotation-180-4_tiled_mtl_rc_ccs
[605.105337] Subtest pipe-A-crc-primary-rotation-180-4_tiled_mtl_rc_ccs: SKIP (0.001s)
[605.718594] [129/142] (595s left) v3d/v3d_wait_bo (used-bo)
[605.973346] Subtest used-bo: SKIP (0.000s)
[606.050591] [130/142] (595s left) i915_pm_rpm (fences)
[607.832624] Starting subtest: fences
[610.859091] Subtest fences: SUCCESS (3.026s)
[611.086048] [131/142] (590s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[611.420093] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip
[611.423507] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[611.534516] [132/142] (590s left) kms_prime (basic-crc-vgem)
[613.534381] Starting subtest: basic-crc-vgem
[613.536094] Starting dynamic subtest: second-to-first
[614.756889] Dynamic subtest second-to-first: SUCCESS (1.221s)
[614.758562] Subtest basic-crc-vgem: SUCCESS (1.224s)
[614.869606] [133/142] (587s left) prime_vgem (basic-blt)
[615.090162] Starting subtest: basic-blt
[615.145440] Subtest basic-blt: SUCCESS (0.055s)
[615.268198] [134/142] (586s left) api_intel_allocator (reloc-allocator)
[615.471965] Starting subtest: reloc-allocator
[615.473680] Starting dynamic subtest: basic
[615.476510] Dynamic subtest basic: SUCCESS (0.006s)
[615.504238] Starting dynamic subtest: parallel-one
[615.505572] Dynamic subtest parallel-one: SUCCESS (0.012s)
[615.529914] Starting dynamic subtest: print
[615.531162] Dynamic subtest print: SUCCESS (0.000s)
[615.555035] Starting dynamic subtest: reuse
[615.556368] Dynamic subtest reuse: SUCCESS (0.000s)
[615.579740] Starting dynamic subtest: fork-reopen-allocator
[615.580998] Dynamic subtest fork-reopen-allocator: SUCCESS (0.040s)
[615.582198] Subtest reloc-allocator: SUCCESS (0.070s)
[615.635533] [135/142] (586s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size)
[615.935299] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size
[657.843694] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (41.910s)
[658.184723] [136/142] (543s left) kms_vblank (pipe-b-query-busy-hang)
[658.484108] Starting subtest: pipe-B-query-busy-hang
[665.805490] Subtest pipe-B-query-busy-hang: SUCCESS (7.325s)
[666.155494] [137/142] (535s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[666.375597] Starting subtest: y-tiled-to-vebox-y-tiled
[666.378626] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[666.590177] [138/142] (535s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_dg2_rc_ccs_cc)
[667.031367] Starting subtest: pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs_cc
[667.035578] Subtest pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[667.641026] [139/142] (534s left) kms_vblank (pipe-b-wait-idle)
[667.944510] Starting subtest: pipe-B-wait-idle
[670.605615] Subtest pipe-B-wait-idle: SUCCESS (2.664s)
[670.945101] [140/142] (531s left) syncobj_basic (test-valid-cycle)
[671.150389] Starting subtest: test-valid-cycle
[671.151978] Subtest test-valid-cycle: SUCCESS (0.000s)
[671.245109] [141/142] (531s left) kms_vblank (pipe-a-wait-forked-busy-hang)
[671.540994] Starting subtest: pipe-A-wait-forked-busy-hang
[678.795700] Subtest pipe-A-wait-forked-busy-hang: SUCCESS (7.258s)
[679.148050] [142/142] (523s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_dg2_mc_ccs)
[679.695815] Starting subtest: pipe-B-random-ccs-data-4_tiled_dg2_mc_ccs
[679.700387] Subtest pipe-B-random-ccs-data-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[680.188984] Closing watchdogs
Done.