[51.958917] Initializing watchdogs
[51.959173]   /dev/watchdog0
[51.974824] [001/138] (960s left) gem_eio (in-flight-immediate)
[52.183777] Starting subtest: in-flight-immediate
[53.223969] Subtest in-flight-immediate: SUCCESS (1.042s)
[53.430134] [002/138] (959s left) kms_ccs (pipe-d-bad-rotation-90-yf-tiled-ccs)
[53.929223] Subtest pipe-D-bad-rotation-90-yf-tiled-ccs: SKIP (0.000s)
[54.246165] [003/138] (958s left) gem_mmap_gtt (flink-race)
[54.389119] Starting subtest: flink-race
[74.496918] Subtest flink-race: SUCCESS (20.108s)
[74.596494] [004/138] (937s left) perf (mi-rpc)
[74.880962] Starting subtest: mi-rpc
[74.944205] Subtest mi-rpc: SUCCESS (0.062s)
[74.995199] [005/138] (937s left) kms_ccs (pipe-a-crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[75.337371] Starting subtest: pipe-A-crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[75.340144] Subtest pipe-A-crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.001s)
[75.788069] [006/138] (936s left) i915_pm_rps (thresholds-idle)
[75.941328] Starting subtest: thresholds-idle
[75.942993] Starting dynamic subtest: gt0
[86.313994] Dynamic subtest gt0: SUCCESS (10.371s)
[86.315430] Subtest thresholds-idle: SUCCESS (10.373s)
[86.404201] [007/138] (926s left) kms_psr (basic)
[86.548951] Subtest basic: SKIP (0.000s)
[86.589272] [008/138] (926s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[86.829907] Starting subtest: 2x-cursor-vs-flip-legacy
[86.834500] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.004s)
[86.920999] [009/138] (925s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[87.393540] Subtest pipe-D-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[87.710139] [010/138] (925s left) gem_exec_endless (dispatch)
[87.898479] Starting subtest: dispatch
[87.900410] Starting dynamic subtest: rcs0
[87.913670] Dynamic subtest rcs0: SUCCESS (0.014s)
[87.934903] Starting dynamic subtest: bcs0
[87.939500] Dynamic subtest bcs0: SKIP (0.002s)
[87.955148] Starting dynamic subtest: vcs0
[87.957773] Dynamic subtest vcs0: SUCCESS (0.010s)
[87.973051] Starting dynamic subtest: vecs0
[87.975603] Dynamic subtest vecs0: SUCCESS (0.010s)
[87.976885] Subtest dispatch: SUCCESS (0.038s)
[88.056303] [011/138] (924s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[88.301915] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[88.303829] Starting dynamic subtest: pipe-A-valid-mode
[89.041668] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.740s)
[89.043190] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SUCCESS (0.742s)
[89.396695] [012/138] (923s left) kms_flip (flip-vs-suspend)
[89.628706] Starting subtest: flip-vs-suspend
[89.631870] Starting dynamic subtest: A-DP1
[143.339206] Dynamic subtest A-DP1: SUCCESS (5.543s)
[143.367904] Starting dynamic subtest: B-DP1
[195.143946] Dynamic subtest B-DP1: SUCCESS (4.785s)
[195.163453] Starting dynamic subtest: C-DP1
[247.187840] Dynamic subtest C-DP1: SUCCESS (4.791s)
[247.189453] Subtest flip-vs-suspend: SUCCESS (15.129s)
[252.665232] [013/138] (907s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[252.930289] Starting subtest: fbc-shrfb-scaledprimary
[256.159066] Subtest fbc-shrfb-scaledprimary: SUCCESS (3.232s)
[256.298864] [014/138] (904s left) kms_plane (plane-panning-top-left)
[256.533394] Starting subtest: plane-panning-top-left
[256.534760] Starting dynamic subtest: pipe-A
[257.350426] Dynamic subtest pipe-A: SUCCESS (0.816s)
[257.371214] Starting dynamic subtest: pipe-B
[258.356740] Dynamic subtest pipe-B: SUCCESS (1.000s)
[258.358142] Subtest plane-panning-top-left: SUCCESS (1.823s)
[258.711436] [015/138] (901s left) kms_lease (atomic-implicit-crtc)
[258.946788] Starting subtest: atomic-implicit-crtc
[258.948081] Starting dynamic subtest: pipe-A-DP-1
[258.955233] Dynamic subtest pipe-A-DP-1: SUCCESS (0.004s)
[258.975214] Starting dynamic subtest: pipe-B-DP-1
[258.976863] Dynamic subtest pipe-B-DP-1: SUCCESS (0.022s)
[259.000789] Starting dynamic subtest: pipe-C-DP-1
[259.002074] Dynamic subtest pipe-C-DP-1: SUCCESS (0.022s)
[259.003270] Subtest atomic-implicit-crtc: SUCCESS (0.056s)
[259.095467] [016/138] (901s left) v3d/v3d_perfmon (destroy-invalid-perfmon)
[259.290184] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[259.360914] [017/138] (901s left) kms_big_fb (linear-64bpp-rotate-90)
[259.622741] Starting subtest: linear-64bpp-rotate-90
[259.715285] Subtest linear-64bpp-rotate-90: SKIP (0.091s)
[259.845955] [018/138] (900s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[260.082016] Starting subtest: 2x-flip-vs-rmfb-interruptible
[260.086413] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.003s)
[260.177051] [019/138] (900s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[260.323995] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[260.427608] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.103s)
[260.510447] [020/138] (900s left) gem_spin_batch (engines)
[260.673211] Starting subtest: engines
[260.674670] Starting dynamic subtest: rcs0
[264.002209] Dynamic subtest rcs0: SUCCESS (3.326s)
[264.021853] Starting dynamic subtest: bcs0
[267.327857] Dynamic subtest bcs0: SUCCESS (3.324s)
[267.347005] Starting dynamic subtest: vcs0
[270.650922] Dynamic subtest vcs0: SUCCESS (3.322s)
[270.670033] Starting dynamic subtest: vecs0
[273.972407] Dynamic subtest vecs0: SUCCESS (3.321s)
[273.973950] Subtest engines: SUCCESS (13.297s)
[274.177653] [021/138] (886s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[274.431087] Starting subtest: fbcpsr-rgb101010-draw-render
[274.433708] Subtest fbcpsr-rgb101010-draw-render: SKIP (0.000s)
[274.527778] [022/138] (886s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-signaled)
[274.670148] Starting subtest: multi-wait-for-submit-available-unsubmitted-signaled
[274.671433] Subtest multi-wait-for-submit-available-unsubmitted-signaled: SUCCESS (0.001s)
[274.760545] [023/138] (886s left) fbdev (eof)
[274.909308] Starting subtest: eof
[274.910900] Subtest eof: SUCCESS (0.000s)
[274.976009] [024/138] (885s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[275.216599] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[275.221553] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.004s)
[275.311671] [025/138] (885s left) kms_cursor_crc (cursor-offscreen-max-size)
[275.584629] Starting subtest: cursor-offscreen-max-size
[275.588430] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[275.678139] [026/138] (885s left) kms_content_protection (dp-mst-type-0)
[275.954278] Starting subtest: dp-mst-type-0
[275.956944] Subtest dp-mst-type-0: SKIP (0.000s)
[276.060492] [027/138] (884s left) kms_ccs (pipe-b-crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[276.447688] Starting subtest: pipe-B-crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[276.450209] Subtest pipe-B-crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SKIP (0.001s)
[276.850995] [028/138] (884s left) kms_ccs (pipe-d-bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[277.316106] Subtest pipe-D-bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SKIP (0.000s)
[277.631283] [029/138] (883s left) gem_exec_flush (basic-wb-ro-default)
[277.799725] Starting subtest: basic-wb-ro-default
[283.272451] Subtest basic-wb-ro-default: SUCCESS (5.473s)
[283.483018] [030/138] (877s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[283.729202] Starting subtest: psr-2p-primscrn-cur-indfb-move
[283.731743] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[283.830883] [031/138] (877s left) syncobj_timeline (wait-any-interrupted)
[283.974383] Starting subtest: wait-any-interrupted
[284.083108] Subtest wait-any-interrupted: SUCCESS (0.107s)
[284.164704] [032/138] (876s left) api_intel_allocator (simple-allocator)
[284.309325] Starting subtest: simple-allocator
[284.310771] Starting dynamic subtest: basic
[284.314130] Dynamic subtest basic: SUCCESS (0.006s)
[284.336028] Starting dynamic subtest: parallel-one
[284.482255] Dynamic subtest parallel-one: SUCCESS (0.166s)
[284.502010] Starting dynamic subtest: print
[284.503288] Dynamic subtest print: SUCCESS (0.000s)
[284.524348] Starting dynamic subtest: reuse
[284.525771] Dynamic subtest reuse: SUCCESS (0.000s)
[284.547522] Starting dynamic subtest: reserve
[284.548823] Dynamic subtest reserve: SUCCESS (0.000s)
[284.568539] Starting dynamic subtest: fork-reopen-allocator
[284.569853] Dynamic subtest fork-reopen-allocator: SUCCESS (0.043s)
[284.571083] Subtest simple-allocator: SUCCESS (0.222s)
[284.616039] [033/138] (876s left) gen9_exec_parse (bb-start-cmd)
[284.784615] Starting subtest: bb-start-cmd
[284.789670] Subtest bb-start-cmd: SUCCESS (0.005s)
[285.015075] [034/138] (876s left) gem_ctx_persistence (smoketest)
[285.166267] Starting subtest: smoketest
[316.675123] Subtest smoketest: SUCCESS (31.508s)
[316.770322] [035/138] (844s left) gem_exec_basic (basic)
[316.929472] Starting subtest: basic
[316.930903] Starting dynamic subtest: rcs0-smem
[316.935184] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[316.955961] Starting dynamic subtest: bcs0-smem
[316.957412] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[316.976907] Starting dynamic subtest: vcs0-smem
[316.978182] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[316.997804] Starting dynamic subtest: vecs0-smem
[316.999110] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[317.000363] Subtest basic: SUCCESS (0.013s)
[317.167013] [036/138] (844s left) gem_ccs (block-copy-uncompressed)
[317.319380] Subtest block-copy-uncompressed: SKIP (0.000s)
[317.368000] [037/138] (843s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[317.611513] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[317.613083] Starting dynamic subtest: pipe-A-valid-mode
[317.737645] Dynamic subtest pipe-A-valid-mode: SKIP (0.122s)
[317.740256] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.126s)
[318.075209] [038/138] (843s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[318.326204] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[320.669113] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (2.344s)
[320.826876] [039/138] (840s left) gem_shrink (reclaim)
[320.976476] Starting subtest: reclaim
[323.283396] Subtest reclaim: SUCCESS (2.306s)
[323.377214] [040/138] (838s left) kms_cursor_edge_walk (64x64-top-bottom)
[323.622222] Starting subtest: 64x64-top-bottom
[323.623645] Starting dynamic subtest: pipe-A-DP-1
[327.242442] Dynamic subtest pipe-A-DP-1: SUCCESS (3.618s)
[327.264847] Starting dynamic subtest: pipe-C-DP-1
[330.724642] Dynamic subtest pipe-C-DP-1: SUCCESS (3.479s)
[330.726280] Subtest 64x64-top-bottom: SUCCESS (7.106s)
[331.049026] [041/138] (830s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[331.308740] Starting subtest: 4-tiled-16bpp-rotate-0
[331.311707] Subtest 4-tiled-16bpp-rotate-0: SKIP (0.000s)
[331.412823] [042/138] (830s left) gem_ctx_param (invalid-set-ringsize)
[331.556959] Starting subtest: invalid-set-ringsize
[331.558429] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[331.645901] [043/138] (829s left) gem_readwrite (write-bad-handle)
[331.789511] Starting subtest: write-bad-handle
[331.792325] Subtest write-bad-handle: SUCCESS (0.000s)
[331.896023] [044/138] (829s left) gem_softpin (allocator-basic-reserve)
[332.047631] Starting subtest: allocator-basic-reserve
[332.279744] Subtest allocator-basic-reserve: SUCCESS (0.232s)
[332.396905] [045/138] (829s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[332.649802] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[332.652614] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[332.761380] [046/138] (828s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y-tiled-ccs)
[333.239229] Starting subtest: pipe-C-crc-sprite-planes-basic-y-tiled-ccs
[333.244956] Subtest pipe-C-crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.004s)
[333.569724] [047/138] (828s left) kms_pipe_crc_basic (suspend-read-crc)
[333.808244] Starting subtest: suspend-read-crc
[333.809732] Starting dynamic subtest: pipe-A-DP-1
[352.418527] Dynamic subtest pipe-A-DP-1: SUCCESS (2.369s)
[352.441209] Starting dynamic subtest: pipe-B-DP-1
[370.404407] Dynamic subtest pipe-B-DP-1: SUCCESS (2.347s)
[370.428248] Starting dynamic subtest: pipe-C-DP-1