[42.300762] Initializing watchdogs
[42.301039]   /dev/watchdog0
[42.333696] [001/141] (960s left) kms_psr (psr2_cursor_mmap_cpu)
[42.688448] Subtest psr2_cursor_mmap_cpu: SKIP (0.000s)
[42.768432] [002/141] (960s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[43.151881] Starting subtest: cursor-vs-flip-legacy
[81.776538] Subtest cursor-vs-flip-legacy: SUCCESS (38.557s)
[82.046253] [003/141] (920s left) gem_mmap_gtt (big-bo)
[82.366136] Starting subtest: big-bo
[84.016713] Subtest big-bo: SUCCESS (1.682s)
[84.252444] [004/141] (918s left) perf (invalid-oa-metric-set-id)
[84.866493] Starting subtest: invalid-oa-metric-set-id
[84.934145] Subtest invalid-oa-metric-set-id: SUCCESS (0.053s)
[85.038364] [005/141] (918s left) gem_ctx_persistence (heartbeat-hostile)
[85.336216] Starting subtest: heartbeat-hostile
[86.550168] Subtest heartbeat-hostile: SUCCESS (1.230s)
[86.679872] [006/141] (916s left) kms_big_fb (linear-addfb)
[87.160654] Starting subtest: linear-addfb
[87.182154] Subtest linear-addfb: SUCCESS (0.000s)
[87.284706] [007/141] (916s left) kms_plane_scaling (plane-downscale-with-rotation-factor-0-75)
[87.646449] Starting subtest: plane-downscale-with-rotation-factor-0-75
[87.668772] Starting dynamic subtest: pipe-A-DP-1
[87.794599] Dynamic subtest pipe-A-DP-1: SKIP (0.123s)
[87.828641] Starting dynamic subtest: pipe-B-DP-1
[88.171900] Dynamic subtest pipe-B-DP-1: SKIP (0.378s)
[88.207172] Starting dynamic subtest: pipe-C-DP-1
[88.558575] Dynamic subtest pipe-C-DP-1: SKIP (0.379s)
[88.597482] Subtest plane-downscale-with-rotation-factor-0-75: SKIP (0.890s)
[88.971763] [008/141] (914s left) kms_atomic_transition (plane-all-transition)
[89.381269] Starting subtest: plane-all-transition
[89.402317] Starting dynamic subtest: pipe-A-DP-1
[93.245030] Dynamic subtest pipe-A-DP-1: SUCCESS (3.882s)
[93.374939] Starting dynamic subtest: pipe-B-DP-1
[97.672223] Dynamic subtest pipe-B-DP-1: SUCCESS (4.329s)
[97.780843] Subtest plane-all-transition: SUCCESS (8.413s)
[98.131261] [009/141] (905s left) kms_ccs (pipe-d-bad-aux-stride-y_tiled_gen12_rc_ccs)
[98.946232] Subtest pipe-D-bad-aux-stride-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[99.249828] [010/141] (904s left) kms_hdr (static-toggle)
[99.609830] Starting subtest: static-toggle
[99.647645] Subtest static-toggle: SKIP (0.000s)
[99.755320] [011/141] (903s left) kms_chamelium_frames (dp-crc-fast)
[100.251418] Subtest dp-crc-fast: SKIP (0.000s)
[100.563573] [012/141] (903s left) i915_pm_rpm (dpms-mode-unset-lpsp)
[102.738233] Starting subtest: dpms-mode-unset-lpsp
[103.173862] Subtest dpms-mode-unset-lpsp: SKIP (0.416s)
[103.669155] [013/141] (900s left) gem_exec_store (pages)
[104.024192] Starting subtest: pages
[104.044339] Starting dynamic subtest: rcs0
[104.063420] Dynamic subtest rcs0: SUCCESS (0.100s)
[104.096139] Starting dynamic subtest: bcs0
[104.161815] Dynamic subtest bcs0: SUCCESS (0.107s)
[104.196515] Starting dynamic subtest: vcs0
[104.256616] Dynamic subtest vcs0: SUCCESS (0.088s)
[104.289407] Starting dynamic subtest: vecs0
[104.351717] Dynamic subtest vecs0: SUCCESS (0.091s)
[104.372177] Subtest pages: SUCCESS (0.389s)
[104.575800] [014/141] (899s left) kms_psr (primary_blt)
[104.892809] Subtest primary_blt: SKIP (0.000s)
[104.998403] [015/141] (899s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[105.440998] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[105.483327] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[105.585867] [016/141] (898s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[105.949195] Starting subtest: 2x-long-flip-vs-cursor-legacy
[105.989569] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.005s)
[106.103887] [017/141] (898s left) kms_ccs (pipe-d-bad-rotation-90-y_tiled_gen12_mc_ccs)
[106.897415] Subtest pipe-D-bad-rotation-90-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[107.211751] [018/141] (897s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[107.648677] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[107.685535] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[107.794618] [019/141] (896s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt)
[108.223691] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt
[108.265760] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[108.367819] [020/141] (896s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[108.686329] Starting subtest: etime-single-wait-all-for-submit-submitted
[108.764114] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.109s)
[108.900300] [021/141] (895s left) vc4/vc4_label_bo (set-bad-name)
[109.235838] Subtest set-bad-name: SKIP (0.000s)
[109.335318] [022/141] (895s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[109.710977] Starting subtest: cursor-rapid-movement-128x128
[109.733047] Starting dynamic subtest: pipe-A-DP-1
[110.251157] Dynamic subtest pipe-A-DP-1: SUCCESS (0.520s)
[110.331809] Starting dynamic subtest: pipe-C-DP-1
[111.158491] Dynamic subtest pipe-C-DP-1: SUCCESS (0.832s)
[111.182925] Subtest cursor-rapid-movement-128x128: SUCCESS (1.437s)
[111.573732] [023/141] (893s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[111.897464] Starting subtest: y-tiled-to-vebox-x-tiled
[111.938921] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[112.130729] [024/141] (892s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[112.422756] Starting subtest: multi-wait-all-for-submit-submitted
[112.503080] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.107s)
[112.624128] [025/141] (892s left) syncobj_wait (wait-all-complex)
[112.915095] Starting subtest: wait-all-complex
[113.100649] Subtest wait-all-complex: SUCCESS (0.215s)
[113.228740] [026/141] (892s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[113.605505] Starting subtest: 2x-long-flip-vs-cursor-atomic
[113.646375] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.005s)
[113.751955] [027/141] (891s left) gem_create (busy-create)
[114.051413] Starting subtest: busy-create
[114.072974] Starting dynamic subtest: smem0
[146.305673] Dynamic subtest smem0: SUCCESS (32.258s)
[146.324277] Subtest busy-create: SUCCESS (32.259s)
[146.531778] [028/141] (858s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[146.842355] Starting subtest: x-tiled-to-vebox-y-tiled
[146.881566] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[147.065468] [029/141] (858s left) kms_color (invalid-ctm-matrix-sizes)
[147.411137] Starting subtest: invalid-ctm-matrix-sizes
[147.430257] Starting dynamic subtest: pipe-A
[147.448886] Dynamic subtest pipe-A: SUCCESS (0.007s)
[147.481869] Starting dynamic subtest: pipe-B
[147.500405] Dynamic subtest pipe-B: SUCCESS (0.010s)
[147.535817] Starting dynamic subtest: pipe-C
[147.557135] Dynamic subtest pipe-C: SUCCESS (0.013s)
[147.577598] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.032s)
[147.683183] [030/141] (857s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[148.633265] Starting subtest: syncobj-backward-timeline-chain-engines
[148.772620] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.136s)
[149.041481] [031/141] (856s left) kms_cursor_legacy (torture-move)
[149.425391] Starting subtest: torture-move
[149.445948] Starting dynamic subtest: pipe-A
[172.000073] Dynamic subtest pipe-A: SUCCESS (22.576s)
[172.035100] Starting dynamic subtest: pipe-B
[193.702816] Dynamic subtest pipe-B: SUCCESS (21.701s)
[193.738202] Starting dynamic subtest: pipe-C
[216.440269] Dynamic subtest pipe-C: SUCCESS (21.662s)
[217.425041] Starting dynamic subtest: all-pipes
[237.206574] Dynamic subtest all-pipes: SUCCESS (21.666s)
[237.225332] Subtest torture-move: SUCCESS (87.609s)
[237.307717] [032/141] (768s left) gen7_exec_parse (basic-allocation)
[237.615693] Subtest basic-allocation: SKIP (0.000s)
[237.704395] [033/141] (768s left) gem_eio (in-flight-internal-immediate)
[238.035732] Starting subtest: in-flight-internal-immediate
[238.079562] Subtest in-flight-internal-immediate: SUCCESS (0.081s)
[238.295076] [034/141] (767s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc)
[238.729936] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc
[240.462660] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (1.816s)
[240.610420] [035/141] (765s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_gen12_mc_ccs)
[241.282929] Starting subtest: pipe-C-bad-rotation-90-y_tiled_gen12_mc_ccs
[241.328601] Subtest pipe-C-bad-rotation-90-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[241.727088] [036/141] (764s left) kms_ccs (pipe-c-bad-aux-stride-y_tiled_gen12_rc_ccs)
[242.507211] Starting subtest: pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs
[242.548712] Subtest pipe-C-bad-aux-stride-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[242.876292] [037/141] (763s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[243.158803] Starting subtest: single-wait-all-for-submit-available-signaled
[243.180587] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.001s)
[243.284362] [038/141] (763s left) syncobj_timeline (single-wait-signaled)
[243.575313] Starting subtest: single-wait-signaled
[243.593989] Subtest single-wait-signaled: SUCCESS (0.001s)
[243.699989] [039/141] (762s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[244.133105] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[246.114624] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (2.048s)
[246.281520] [040/141] (760s left) gem_ctx_create (basic-files)
[246.607923] Starting subtest: basic-files
[248.624829] Subtest basic-files: SUCCESS (2.037s)
[248.889226] [041/141] (757s left) gem_eio (wait-immediate)
[249.221210] Starting subtest: wait-immediate
[249.241184] Subtest wait-immediate: SUCCESS (0.073s)
[249.465206] [042/141] (757s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[249.884547] Starting subtest: x-tiled-8bpp-rotate-180
[252.722738] Subtest x-tiled-8bpp-rotate-180: SUCCESS (2.884s)
[252.987879] [043/141] (753s left) gem_exec_reloc (basic-cpu-read-noreloc)
[253.279676] Starting subtest: basic-cpu-read-noreloc
[253.298766] Subtest basic-cpu-read-noreloc: SUCCESS (0.012s)
[253.496282] [044/141] (753s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[253.929548] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[253.971649] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[254.076760] [045/141] (752s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[254.514811] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[254.552045] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[254.662226] [046/141] (752s left) kms_ccs (pipe-b-bad-aux-stride-4_tiled_mtl_rc_ccs)
[255.298106] Starting subtest: pipe-B-bad-aux-stride-4_tiled_mtl_rc_ccs
[255.340227] Subtest pipe-B-bad-aux-stride-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[255.760306] [047/141] (751s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[256.049621] Starting subtest: invalid-signal-one-illegal-handle
[256.069248] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[256.172360] [048/141] (751s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc)
[256.706535] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc
[256.747519] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc: SKIP (0.005s)
[257.284175] [049/141] (750s left) gem_exec_reloc (basic-write-read)
[257.568458] Starting subtest: basic-write-read
[257.589688] Subtest basic-write-read: SUCCESS (0.016s)
[257.800501] [050/141] (749s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[258.148829] Starting subtest: cursorA-vs-flipB-toggle
[258.187683] Subtest cursorA-vs-flipB-toggle: SKIP (0.003s)
[258.297604] [051/141] (749s left) sw_sync (sync_merge_same)
[258.568198] Starting subtest: sync_merge_same
[258.587142] Subtest sync_merge_same: SUCCESS (0.000s)
[258.697687] [052/141] (749s left) sw_sync (sync_busy_fork_unixsocket)
[258.963580] Starting subtest: sync_busy_fork_unixsocket
[258.985140] Subtest sync_busy_fork_unixsocket: SUCCESS (0.035s)
[259.092911] [053/141] (748s left) api_intel_bb (blit-noreloc-purge-cache)
[259.389281] Starting subtest: blit-noreloc-purge-cache
[259.409233] Subtest blit-noreloc-purge-cache: SUCCESS (0.017s)
[259.512712] [054/141] (748s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs)
[260.275817] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs
[260.313995] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs: SKIP (0.004s)
[260.644265] [055/141] (747s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[261.063622] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[261.106177] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[261.211924] [056/141] (746s left) gem_exec_schedule (u-submit-early-slice)
[261.666287] Starting subtest: u-submit-early-slice
[261.686113] Starting dynamic subtest: rcs0
[261.705322] Dynamic subtest rcs0: SUCCESS (0.069s)
[261.746378] Starting dynamic subtest: bcs0
[261.765163] Dynamic subtest bcs0: SUCCESS (0.059s)
[261.802611] Starting dynamic subtest: vcs0
[261.824085] Dynamic subtest vcs0: SUCCESS (0.053s)
[261.861024] Starting dynamic subtest: vecs0
[261.881768] Dynamic subtest vecs0: SUCCESS (0.057s)
[261.900292] Subtest u-submit-early-slice: SUCCESS (0.242s)
[262.146578] [057/141] (746s left) dumb_buffer (map-invalid-size)
[262.435433] Starting subtest: map-invalid-size
[262.456086] Subtest map-invalid-size: SUCCESS (0.000s)
[262.541882] [058/141] (745s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[262.897758] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[262.916437] Starting dynamic subtest: pipe-A-valid-mode
[263.661781] Dynamic subtest pipe-A-valid-mode: SKIP (0.715s)
[263.713233] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (0.718s)
[264.053094] [059/141] (744s left) kms_fbcon_fbt (fbc-suspend)
[264.406558] Starting subtest: fbc-suspend
[312.143079] Subtest fbc-suspend: SUCCESS (17.107s)
[313.236305] [060/141] (727s left) perf_pmu (all-busy-idle-check-all)
[313.533112] Starting subtest: all-busy-idle-check-all
[314.140570] Subtest all-busy-idle-check-all: SUCCESS (0.628s)
[314.262387] [061/141] (726s left) i915_hangman (engine-engine-hang)
[314.557258] Starting subtest: engine-engine-hang
[314.578874] Starting dynamic subtest: rcs0
[315.145634] Dynamic subtest rcs0: SUCCESS (0.601s)
[315.193555] Starting dynamic subtest: bcs0
[315.750231] Dynamic subtest bcs0: SUCCESS (0.588s)
[315.803779] Starting dynamic subtest: vcs0
[316.343531] Dynamic subtest vcs0: SUCCESS (0.595s)
[316.389085] Starting dynamic subtest: vecs0
[316.929886] Dynamic subtest vecs0: SUCCESS (0.584s)
[316.951032] Subtest engine-engine-hang: SUCCESS (2.374s)
[317.047576] [062/141] (723s left) gem_ctx_param (invalid-set-no-zeromap)
[317.326160] Starting subtest: invalid-set-no-zeromap
[317.347323] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[317.437706] [063/141] (723s left) v3d/v3d_submit_cl (valid-multisync-submission)
[317.782094] Subtest valid-multisync-submission: SKIP (0.000s)
[317.877165] [064/141] (722s left) gem_exec_capture (capture-invisible)
[318.166297] Starting subtest: capture-invisible
[318.186484] Starting dynamic subtest: smem0
[318.228595] Dynamic subtest smem0: SKIP (0.000s)
[318.269282] Subtest capture-invisible: SKIP (0.001s)
[318.358982] [065/141] (722s left) drm_fdinfo (busy)
[318.651190] Starting subtest: busy
[318.670508] Starting dynamic subtest: rcs0
[319.171245] Dynamic subtest rcs0: SUCCESS (0.529s)
[319.212100] Starting dynamic subtest: bcs0
[319.696855] Dynamic subtest bcs0: SUCCESS (0.529s)
[319.735567] Starting dynamic subtest: vcs0
[320.228401] Dynamic subtest vcs0: SUCCESS (0.529s)
[320.269195] Starting dynamic subtest: vecs0
[320.755138] Dynamic subtest vecs0: SUCCESS (0.532s)
[320.774129] Subtest busy: SUCCESS (2.124s)
[320.866840] [066/141] (719s left) kms_vblank (pipe-a-ts-continuation-idle)
[321.223563] Starting subtest: pipe-A-ts-continuation-idle
[321.484041] Subtest pipe-A-ts-continuation-idle: SUCCESS (0.253s)
[321.850716] [067/141] (719s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[322.206982] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[322.226289] Starting dynamic subtest: pipe-A-valid-mode
[322.914408] Dynamic subtest pipe-A-valid-mode: SKIP (0.689s)
[322.953168] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.692s)
[323.327675] [068/141] (717s left) kms_plane_multiple (tiling-x)
[323.686219] Starting subtest: tiling-x
[323.705363] Starting dynamic subtest: pipe-A-DP-1
[325.161119] Dynamic subtest pipe-A-DP-1: SUCCESS (1.457s)
[325.212167] Starting dynamic subtest: pipe-B-DP-1
[326.802654] Dynamic subtest pipe-B-DP-1: SUCCESS (1.637s)
[326.844608] Starting dynamic subtest: pipe-C-DP-1
[328.461556] Dynamic subtest pipe-C-DP-1: SUCCESS (1.667s)
[328.480060] Subtest tiling-x: SUCCESS (4.773s)
[328.830838] [069/141] (712s left) kms_frontbuffer_tracking (psr-rgb101010-draw-render)
[329.268052] Starting subtest: psr-rgb101010-draw-render
[329.321501] Subtest psr-rgb101010-draw-render: SKIP (0.000s)
[329.459398] [070/141] (711s left) i915_pm_backlight (bad-brightness)
[329.852980] Subtest bad-brightness: SKIP (0.000s)
[329.968046] [071/141] (711s left) kms_sequence (queue-idle)
[330.322103] Starting subtest: queue-idle
[330.342521] Starting dynamic subtest: DP-1-pipe-A
[332.624716] Dynamic subtest DP-1-pipe-A: SUCCESS (2.278s)
[332.665629] Starting dynamic subtest: DP-1-pipe-B
[335.175561] Dynamic subtest DP-1-pipe-B: SUCCESS (2.546s)
[335.223867] Starting dynamic subtest: DP-1-pipe-C
[337.862044] Dynamic subtest DP-1-pipe-C: SUCCESS (2.699s)
[337.884517] Subtest queue-idle: SUCCESS (7.535s)
[338.220261] [072/141] (703s left) i915_query (query-regions-sanity-check)
[338.514799] Starting subtest: query-regions-sanity-check
[338.533833] Subtest query-regions-sanity-check: SUCCESS (0.038s)
[338.674016] [073/141] (702s left) api_intel_bb (reset-bb)
[338.961851] Starting subtest: reset-bb
[338.983276] Subtest reset-bb: SUCCESS (0.005s)
[339.096377] [074/141] (702s left) i915_pm_rpm (legacy-planes)
[340.942823] Starting subtest: legacy-planes
[340.963255] Starting dynamic subtest: plane-39
[343.885027] Dynamic subtest plane-39: SUCCESS (2.938s)
[343.929329] Starting dynamic subtest: plane-47
[346.695022] Dynamic subtest plane-47: SUCCESS (2.812s)
[346.715478] Subtest legacy-planes: SUCCESS (5.752s)
[347.187771] [075/141] (694s left) gem_mmap_gtt (hang)
[347.483627] Starting subtest: hang
[352.982385] Subtest hang: SUCCESS (5.522s)
[353.128715] [076/141] (688s left) gem_ctx_shared (exec-single-timeline)
[353.436302] Starting subtest: exec-single-timeline
[353.455554] Starting dynamic subtest: rcs0
[354.491602] Dynamic subtest rcs0: SUCCESS (1.053s)
[354.544188] Starting dynamic subtest: bcs0
[355.588382] Dynamic subtest bcs0: SUCCESS (1.085s)
[355.639983] Starting dynamic subtest: vcs0
[356.669709] Dynamic subtest vcs0: SUCCESS (1.086s)
[356.722364] Starting dynamic subtest: vecs0
[357.757681] Dynamic subtest vecs0: SUCCESS (1.087s)
[357.778732] Subtest exec-single-timeline: SUCCESS (4.319s)
[358.025281] [077/141] (683s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[358.411675] Starting subtest: addfb25-yf-tiled-legacy
[358.432744] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[358.546129] [078/141] (683s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-render)
[358.946279] Starting subtest: psr-1p-offscren-pri-indfb-draw-render
[358.988487] Subtest psr-1p-offscren-pri-indfb-draw-render: SKIP (0.000s)
[359.101967] [079/141] (683s left) gem_exec_reloc (basic-concurrent0)
[359.393624] Starting subtest: basic-concurrent0
[364.890175] Subtest basic-concurrent0: SUCCESS (5.463s)
[365.113189] [080/141] (677s left) gem_pwrite (basic-random)
[365.406950] Starting subtest: basic-random
[371.280176] Subtest basic-random: SUCCESS (5.872s)
[371.418250] [081/141] (670s left) kms_pipe_crc_basic (bad-source)
[371.971352] Starting subtest: bad-source
[371.990409] Subtest bad-source: SUCCESS (0.001s)
[372.115910] [082/141] (670s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-blt)
[372.539517] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-blt
[372.582847] Subtest fbcpsr-1p-offscren-pri-indfb-draw-blt: SKIP (0.000s)
[372.695796] [083/141] (669s left) v3d/v3d_job_submission (multiple-singlesync-to-multisync)
[373.072273] Subtest multiple-singlesync-to-multisync: SKIP (0.000s)
[373.165651] [084/141] (669s left) kms_busy (extended-modeset-hang-oldfb)
[373.578838] Starting subtest: extended-modeset-hang-oldfb
[373.598042] Starting dynamic subtest: pipe-A
[375.799258] Dynamic subtest pipe-A: SUCCESS (2.232s)
[375.845718] Starting dynamic subtest: pipe-C
[377.850865] Dynamic subtest pipe-C: SUCCESS (2.049s)
[377.872404] Subtest extended-modeset-hang-oldfb: SUCCESS (4.293s)
[378.237155] [085/141] (664s left) kms_content_protection (atomic-dpms)
[378.645274] Starting subtest: atomic-dpms
[378.664134] Starting dynamic subtest: pipe-A-DP-1
[498.761311] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[506.339701] Closing watchdogs
[506.347453] Initializing watchdogs
[506.347628]   /dev/watchdog0
[506.414495] [086/141] (536s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[506.787145] Starting subtest: basic-flip-before-cursor-varying-size
[507.893281] Subtest basic-flip-before-cursor-varying-size: SUCCESS (1.102s)
[508.258658] [087/141] (534s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[508.616070] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[508.653883] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.003s)
[508.775217] [088/141] (534s left) vc4/vc4_purgeable_bo (mark-unpurgeable-purged)
[509.125049] Subtest mark-unpurgeable-purged: SKIP (0.000s)
[509.237130] [089/141] (534s left) gem_lmem_swapping (heavy-random)
[509.553731] Subtest heavy-random: SKIP (0.000s)
[509.668778] [090/141] (533s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[510.065629] Starting subtest: 4-tiled-32bpp-rotate-90
[510.102896] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.000s)
[510.223660] [091/141] (533s left) gem_exec_create (basic)
[510.516180] Starting subtest: basic
[510.538025] Starting dynamic subtest: smem
[512.583099] Dynamic subtest smem: SUCCESS (2.065s)
[512.601899] Subtest basic: SUCCESS (2.066s)
[512.801355] [092/141] (530s left) kms_invalid_mode (zero-clock)
[513.177556] Starting subtest: zero-clock
[513.196611] Starting dynamic subtest: DP-1-pipe-A
[513.266050] Dynamic subtest DP-1-pipe-A: SUCCESS (0.094s)
[513.310688] Starting dynamic subtest: DP-1-pipe-B
[513.331400] Dynamic subtest DP-1-pipe-B: SUCCESS (0.013s)
[513.375198] Starting dynamic subtest: DP-1-pipe-C
[513.394159] Dynamic subtest DP-1-pipe-C: SUCCESS (0.013s)
[513.413109] Subtest zero-clock: SUCCESS (0.133s)
[513.660967] [093/141] (530s left) kms_vblank (pipe-d-ts-continuation-suspend)
[514.038953] Subtest pipe-D-ts-continuation-suspend: SKIP (0.000s)
[514.235651] [094/141] (529s left) kms_properties (invalid-properties-atomic)
[514.597852] Starting subtest: invalid-properties-atomic
[514.845292] Subtest invalid-properties-atomic: SUCCESS (0.250s)
[514.985800] [095/141] (528s left) kms_vblank (pipe-a-wait-busy)
[515.342582] Starting subtest: pipe-A-wait-busy
[517.809540] Subtest pipe-A-wait-busy: SUCCESS (2.454s)
[518.174643] [096/141] (525s left) gem_exec_fence (basic-await)
[518.588264] Starting subtest: basic-await
[518.607159] Starting dynamic subtest: rcs0
[518.721249] Dynamic subtest rcs0: SUCCESS (0.127s)
[518.768969] Starting dynamic subtest: bcs0
[518.844969] Dynamic subtest bcs0: SUCCESS (0.125s)
[518.891215] Starting dynamic subtest: vcs0
[518.975625] Dynamic subtest vcs0: SUCCESS (0.129s)
[519.025129] Starting dynamic subtest: vecs0
[519.105169] Dynamic subtest vecs0: SUCCESS (0.126s)
[519.125869] Subtest basic-await: SUCCESS (0.514s)
[519.800475] [097/141] (524s left) gem_mmap_offset (bad-extensions)
[520.093488] Starting subtest: bad-extensions
[520.112234] Subtest bad-extensions: SUCCESS (0.000s)
[520.202828] [098/141] (524s left) api_intel_allocator (open-vm)
[520.496497] Starting subtest: open-vm
[520.515309] Subtest open-vm: SUCCESS (0.004s)
[520.617368] [099/141] (523s left) v3d/v3d_submit_cl (simple-flush-cache)
[520.953486] Subtest simple-flush-cache: SKIP (0.000s)
[521.067226] [100/141] (523s left) gem_vm_create (create-ext)
[521.392455] Starting subtest: create-ext
[521.415452] Subtest create-ext: SUCCESS (0.005s)
[521.528989] [101/141] (523s left) gem_softpin (invalid)
[521.851709] Starting subtest: invalid
[521.874508] Subtest invalid: SUCCESS (0.013s)
[521.989601] [102/141] (522s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[522.385235] Starting subtest: 2x-long-cursor-vs-flip-atomic
[522.433997] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.005s)
[522.576777] [103/141] (522s left) kms_cursor_crc (cursor-random-512x170)
[522.954932] Starting subtest: cursor-random-512x170
[523.006798] Subtest cursor-random-512x170: SKIP (0.003s)
[523.138834] [104/141] (521s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[523.589805] Starting subtest: yf-tiled-16bpp-rotate-270
[523.692611] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.071s)
[523.837767] [105/141] (521s left) kms_ccs (pipe-a-ccs-on-another-bo-yf_tiled_ccs)
[524.363395] Starting subtest: pipe-A-ccs-on-another-bo-yf_tiled_ccs
[524.384578] Subtest pipe-A-ccs-on-another-bo-yf_tiled_ccs: SUCCESS (0.001s)
[524.977141] [106/141] (520s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs)
[525.630637] Starting subtest: pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs
[525.671863] Subtest pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs: SKIP (0.005s)
[526.098345] [107/141] (519s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[526.540977] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[526.578524] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[526.693671] [108/141] (518s left) kms_vblank (pipe-a-wait-forked-hang)
[527.049387] Starting subtest: pipe-A-wait-forked-hang
[533.784784] Subtest pipe-A-wait-forked-hang: SUCCESS (6.631s)
[534.035878] [109/141] (511s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[534.450271] Starting subtest: fbcpsr-modesetfrombusy
[534.493339] Subtest fbcpsr-modesetfrombusy: SKIP (0.000s)
[534.608398] [110/141] (510s left) kms_setmode (invalid-clone-single-crtc-stealing)
[534.904701] Starting subtest: invalid-clone-single-crtc-stealing
[534.961081] Subtest invalid-clone-single-crtc-stealing: SKIP (0.000s)
[535.079914] [111/141] (510s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[535.490186] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[535.533466] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[535.650375] [112/141] (510s left) gem_exec_schedule (preempt-hang)
[536.103897] Starting subtest: preempt-hang
[536.123190] Starting dynamic subtest: rcs0
[536.345973] Dynamic subtest rcs0: SUCCESS (0.291s)
[536.391597] Starting dynamic subtest: bcs0
[536.645537] Dynamic subtest bcs0: SUCCESS (0.295s)
[536.686579] Starting dynamic subtest: vcs0
[536.916153] Dynamic subtest vcs0: SUCCESS (0.273s)
[536.961503] Starting dynamic subtest: vecs0
[537.190022] Dynamic subtest vecs0: SUCCESS (0.270s)
[537.209183] Subtest preempt-hang: SUCCESS (1.135s)
[537.450019] [113/141] (508s left) perf_pmu (most-busy-check-all)
[537.741951] Starting subtest: most-busy-check-all
[537.760812] Starting dynamic subtest: rcs0
[538.260012] Dynamic subtest rcs0: SUCCESS (0.529s)
[538.307618] Starting dynamic subtest: bcs0
[538.782250] Dynamic subtest bcs0: SUCCESS (0.524s)
[538.826477] Starting dynamic subtest: vcs0
[539.311881] Dynamic subtest vcs0: SUCCESS (0.530s)
[539.357331] Starting dynamic subtest: vecs0
[539.840035] Dynamic subtest vecs0: SUCCESS (0.526s)
[539.858982] Subtest most-busy-check-all: SUCCESS (2.113s)
[539.979000] [114/141] (506s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[540.322781] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[540.343454] Starting dynamic subtest: pipe-A-DP-1
[540.670201] Dynamic subtest pipe-A-DP-1: SUCCESS (0.336s)
[540.726664] Starting dynamic subtest: pipe-B-DP-1
[541.056357] Dynamic subtest pipe-B-DP-1: SUCCESS (0.385s)
[541.100102] Starting dynamic subtest: pipe-C-DP-1
[541.448523] Dynamic subtest pipe-C-DP-1: SUCCESS (0.383s)
[541.467812] Subtest planes-unity-scaling-downscale-factor-0-5: SUCCESS (1.117s)
[541.853588] [115/141] (504s left) gem_mmap_gtt (cpuset-big-copy)
[542.150512] Starting subtest: cpuset-big-copy
[563.163331] Subtest cpuset-big-copy: SUCCESS (21.046s)
[563.629247] [116/141] (482s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[564.302578] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[564.637713] [117/141] (481s left) sw_sync (timeline_closed_signaled)
[564.917338] Starting subtest: timeline_closed_signaled
[564.940011] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[565.053489] [118/141] (481s left) kms_flip (2x-absolute-wf_vblank)
[565.406903] Starting subtest: 2x-absolute-wf_vblank
[565.447925] Subtest 2x-absolute-wf_vblank: SKIP (0.003s)
[565.567993] [119/141] (480s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs)
[566.377128] Subtest pipe-D-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[566.739612] [120/141] (479s left) gem_close (basic)
[567.074816] Starting subtest: basic
[567.097594] Subtest basic: SUCCESS (0.000s)
[567.362051] [121/141] (479s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[567.796002] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[567.838895] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[567.956737] [122/141] (478s left) gem_ctx_param (set-priority-not-supported)
[568.254740] Starting subtest: set-priority-not-supported
[568.292598] Subtest set-priority-not-supported: SKIP (0.000s)
[568.408633] [123/141] (478s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[568.767778] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[568.808251] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.003s)
[568.924949] [124/141] (478s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[569.354986] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[569.432575] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.004s)
[569.549340] [125/141] (477s left) perf (sysctl-defaults)
[569.898893] Starting subtest: sysctl-defaults
[569.919863] Subtest sysctl-defaults: SUCCESS (0.000s)
[570.072565] [126/141] (477s left) perf (non-zero-reason)
[570.453787] Starting subtest: non-zero-reason
[570.474503] Starting dynamic subtest: 0-rcs0
[602.810915] Dynamic subtest 0-rcs0: SUCCESS (32.385s)
[602.832487] Subtest non-zero-reason: SUCCESS (32.386s)
[602.949589] [127/141] (444s left) kms_ccs (pipe-c-crc-primary-rotation-180-y_tiled_gen12_rc_ccs)
[603.622409] Starting subtest: pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs
[603.661089] Subtest pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs: SKIP (0.001s)
[604.040092] [128/141] (443s left) syncobj_timeline (device-signal-unordered)
[604.335065] Starting subtest: device-signal-unordered
[604.354332] Subtest device-signal-unordered: SUCCESS (0.002s)
[604.467733] [129/141] (443s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[604.863257] Starting subtest: y-tiled-8bpp-rotate-180
[608.162307] Subtest y-tiled-8bpp-rotate-180: SUCCESS (3.321s)
[608.418672] [130/141] (439s left) kms_ccs (pipe-b-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[609.035933] Starting subtest: pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[609.074063] Subtest pipe-B-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[609.543625] [131/141] (438s left) kms_psr2_sf (overlay-plane-update-continuous-sf)
[609.860081] Subtest overlay-plane-update-continuous-sf: SKIP (0.000s)
[609.977220] [132/141] (438s left) kms_big_fb (linear-16bpp-rotate-270)
[610.349663] Starting subtest: linear-16bpp-rotate-270
[610.462170] Subtest linear-16bpp-rotate-270: SKIP (0.080s)
[610.573097] [133/141] (437s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[611.009946] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[613.181636] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (2.252s)
[613.357335] [134/141] (434s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[613.624187] Starting subtest: no-spare-fences-busy-interruptible
[615.834272] Subtest no-spare-fences-busy-interruptible: SUCCESS (2.176s)
[616.201105] [135/141] (432s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-submitted-signaled
[616.491757] Starting subtest: invalid-multi-wait-available-unsubmitted-submitted-signaled
[616.510864] Subtest invalid-multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[616.610574] [136/141] (431s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[617.046947] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[617.088523] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[617.204644] [137/141] (431s left) kms_ccs (pipe-a-crc-primary-basic-y_tiled_ccs)
[617.663279] Starting subtest: pipe-A-crc-primary-basic-y_tiled_ccs
[618.303649] Subtest pipe-A-crc-primary-basic-y_tiled_ccs: SUCCESS (0.649s)
[619.080062] [138/141] (429s left) gem_exec_balancer (full-late-pulse)
[619.367203] Starting subtest: full-late-pulse
[623.467795] Subtest full-late-pulse: SUCCESS (3.901s)
[627.539245] [139/141] (421s left) kms_plane_alpha_blend (constant-alpha-max)
[627.918806] Starting subtest: constant-alpha-max
[628.222488] Starting dynamic subtest: pipe-A-DP-1
[633.778217] Dynamic subtest pipe-A-DP-1: FAIL (0.532s)
[633.819526] Starting dynamic subtest: pipe-C-DP-1
[639.444720] Dynamic subtest pipe-C-DP-1: FAIL (8.350s)
[639.473352] Subtest constant-alpha-max: FAIL (9.197s)
[639.613308] [140/141] (409s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[640.081939] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[640.124134] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[640.245022] [141/141] (408s left) kms_ccs (pipe-d-random-ccs-data-4_tiled_mtl_rc_ccs_cc)
[641.041766] Subtest pipe-D-random-ccs-data-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[641.332321] Closing watchdogs
Done.