[55.307050] Initializing watchdogs
[55.307296]   /dev/watchdog0
[55.342621] [001/141] (960s left) kms_vblank (pipe-c-query-forked-hang)
[55.745293] Starting subtest: pipe-C-query-forked-hang
[63.098478] Subtest pipe-C-query-forked-hang: SUCCESS (7.311s)
[63.433024] [002/141] (952s left) kms_universal_plane (universal-plane-pipe-c-functional)
[63.815480] Starting subtest: universal-plane-pipe-C-functional
[66.884289] Subtest universal-plane-pipe-C-functional: SUCCESS (3.076s)
[67.221972] [003/141] (948s left) gem_ctx_isolation (clean)
[67.562392] Starting subtest: clean
[67.583694] Starting dynamic subtest: rcs0
[67.750926] Dynamic subtest rcs0: SUCCESS (0.205s)
[67.781602] Starting dynamic subtest: bcs0
[68.059371] Dynamic subtest bcs0: SUCCESS (0.312s)
[68.091405] Starting dynamic subtest: vcs0
[68.291331] Dynamic subtest vcs0: SUCCESS (0.227s)
[68.323122] Starting dynamic subtest: vecs0
[68.537149] Dynamic subtest vecs0: SUCCESS (0.250s)
[68.555525] Subtest clean: SUCCESS (0.996s)
[68.782146] [004/141] (947s left) kms_atomic_transition (plane-all-modeset-transition-fencing)
[69.146751] Starting subtest: plane-all-modeset-transition-fencing
[69.165736] Starting dynamic subtest: pipe-A-DP-1
[125.808303] Dynamic subtest pipe-A-DP-1: SUCCESS (56.657s)
[125.943742] Starting dynamic subtest: pipe-B-DP-1
[182.183333] Dynamic subtest pipe-B-DP-1: SUCCESS (56.274s)
[182.301825] Subtest plane-all-modeset-transition-fencing: SUCCESS (113.138s)
[182.652849] [005/141] (833s left) sw_sync (alloc_timeline)
[182.957578] Starting subtest: alloc_timeline
[182.978841] Subtest alloc_timeline: SUCCESS (0.000s)
[183.083084] [006/141] (833s left) kms_flip (2x-modeset-vs-vblank-race)
[183.449922] Starting subtest: 2x-modeset-vs-vblank-race
[183.493915] Subtest 2x-modeset-vs-vblank-race: SKIP (0.003s)
[183.604259] [007/141] (832s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc)
[184.439149] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[184.752140] [008/141] (831s left) gem_mmap_wc (close)
[185.056625] Starting subtest: close
[185.111792] Subtest close: SUCCESS (0.088s)
[185.250620] [009/141] (831s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[185.664232] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[185.708143] Subtest fbcpsr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[185.817991] [010/141] (830s left) i915_pm_rps (min-max-config-idle)
[186.121087] Starting subtest: min-max-config-idle
[186.497171] Subtest min-max-config-idle: SUCCESS (0.394s)
[186.617514] [011/141] (830s left) perf_pmu (most-busy-idle-check-all)
[186.921154] Starting subtest: most-busy-idle-check-all
[186.940563] Starting dynamic subtest: rcs0
[187.529140] Dynamic subtest rcs0: SUCCESS (0.625s)
[187.566629] Starting dynamic subtest: bcs0
[188.156106] Dynamic subtest bcs0: SUCCESS (0.622s)
[188.190935] Starting dynamic subtest: vcs0
[188.775283] Dynamic subtest vcs0: SUCCESS (0.623s)
[188.813437] Starting dynamic subtest: vecs0
[189.403598] Dynamic subtest vecs0: SUCCESS (0.623s)
[189.423265] Subtest most-busy-idle-check-all: SUCCESS (2.498s)
[189.551356] [012/141] (827s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[189.846899] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[189.868668] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[189.978437] [013/141] (827s left) kms_vblank (pipe-a-wait-idle-hang)
[190.340974] Starting subtest: pipe-A-wait-idle-hang
[197.124960] Subtest pipe-A-wait-idle-hang: SUCCESS (6.670s)
[197.371394] [014/141] (819s left) gem_ctx_shared (create-shared-gtt)
[197.665642] Starting subtest: create-shared-gtt
[199.985125] Subtest create-shared-gtt: SUCCESS (2.151s)
[200.088947] [015/141] (817s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[200.512834] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[200.549828] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[200.658118] [016/141] (816s left) kms_frontbuffer_tracking (fbc-badstride)
[201.099110] Starting subtest: fbc-badstride
[209.033324] Subtest fbc-badstride: SUCCESS (7.911s)
[209.139929] [017/141] (808s left) gem_exec_schedule (promotion)
[209.647411] Starting subtest: promotion
[209.673277] Starting dynamic subtest: rcs0
[209.700343] Dynamic subtest rcs0: SUCCESS (0.145s)
[209.744775] Starting dynamic subtest: bcs0
[209.768834] Dynamic subtest bcs0: SUCCESS (0.148s)
[209.811526] Starting dynamic subtest: vcs0
[209.877128] Dynamic subtest vcs0: SUCCESS (0.137s)
[209.921596] Starting dynamic subtest: vecs0
[210.011221] Dynamic subtest vecs0: SUCCESS (0.134s)
[210.036041] Subtest promotion: SUCCESS (0.570s)
[210.423399] [018/141] (807s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling)
[210.812862] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling
[210.832588] Starting dynamic subtest: pipe-A-valid-mode
[211.468840] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.650s)
[211.487495] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SUCCESS (0.654s)
[211.816765] [019/141] (805s left) i915_pm_rpm (drm-resources-equal)
[213.866235] Starting subtest: drm-resources-equal
[215.457248] Subtest drm-resources-equal: SUCCESS (1.573s)
[215.698607] [020/141] (802s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[216.118375] Starting subtest: fbcpsr-stridechange
[216.160998] Subtest fbcpsr-stridechange: SKIP (0.000s)
[216.269864] [021/141] (801s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size)
[216.635149] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size
[258.628877] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (41.999s)
[258.989799] [022/141] (759s left) gem_pipe_control_store_loop (fresh-buffer)
[259.290558] Starting subtest: fresh-buffer
[261.592573] Subtest fresh-buffer: SUCCESS (2.156s)
[261.683060] [023/141] (756s left) v3d/v3d_submit_csd (bad-bo)
[262.035780] Subtest bad-bo: SKIP (0.000s)
[262.124705] [024/141] (756s left) perf_pmu (busy)
[262.397152] Starting subtest: busy
[262.418522] Starting dynamic subtest: rcs0
[262.924951] Dynamic subtest rcs0: SUCCESS (0.547s)
[262.958813] Starting dynamic subtest: bcs0
[263.477884] Dynamic subtest bcs0: SUCCESS (0.544s)
[263.514311] Starting dynamic subtest: vcs0
[264.017313] Dynamic subtest vcs0: SUCCESS (0.545s)
[264.051290] Starting dynamic subtest: vecs0
[264.567024] Dynamic subtest vecs0: SUCCESS (0.543s)
[264.587993] Subtest busy: SUCCESS (2.185s)
[264.694133] [025/141] (753s left) kms_mmap_write_crc (main)
[265.065315] Starting subtest: main
[265.086663] Starting dynamic subtest: pipe-A-DP-1
[268.458614] Dynamic subtest pipe-A-DP-1: SUCCESS (3.395s)
[268.481448] Subtest main: SUCCESS (3.398s)
[268.825965] [026/141] (749s left) kms_psr (primary_mmap_cpu)
[269.145532] Subtest primary_mmap_cpu: SKIP (0.000s)
[269.254076] [027/141] (749s left) prime_vgem (basic-fence-flip)
[269.664274] Starting subtest: basic-fence-flip
[270.189606] Subtest basic-fence-flip: SUCCESS (0.568s)
[270.357358] [028/141] (748s left) i915_module_load (reload)
[270.628274] Starting subtest: reload
[273.378929] Subtest reload: SUCCESS (2.757s)
[273.548667] [029/141] (745s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[274.048719] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[274.091232] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[274.195574] [030/141] (744s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[274.557622] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[274.579900] Starting dynamic subtest: pipe-A-valid-mode
[275.300509] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.723s)
[275.321774] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (0.726s)
[275.657387] [031/141] (743s left) kms_plane_scaling (plane-downscale-with-modifiers-factor-0-25)
[276.026590] Starting subtest: plane-downscale-with-modifiers-factor-0-25
[276.047344] Starting dynamic subtest: pipe-A-DP-1
[276.084851] Dynamic subtest pipe-A-DP-1: SKIP (0.043s)
[276.124294] Starting dynamic subtest: pipe-B-DP-1
[276.201751] Dynamic subtest pipe-B-DP-1: SKIP (0.092s)
[276.243320] Starting dynamic subtest: pipe-C-DP-1
[276.282607] Dynamic subtest pipe-C-DP-1: SKIP (0.093s)
[276.319928] Subtest plane-downscale-with-modifiers-factor-0-25: SKIP (0.241s)
[276.438167] [032/141] (742s left) gem_ctx_persistence (heartbeat-hostile)
[276.740418] Starting subtest: heartbeat-hostile
[277.942455] Subtest heartbeat-hostile: SUCCESS (1.206s)
[278.078902] [033/141] (741s left) v3d/v3d_mmap (mmap-bo)
[278.427069] Subtest mmap-bo: SKIP (0.000s)
[278.532096] [034/141] (740s left) gem_fence_thrash (bo-write-verify-y)
[278.812110] Starting subtest: bo-write-verify-y
[280.066344] Subtest bo-write-verify-y: SUCCESS (1.273s)
[280.206829] [035/141] (739s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_ccs)
[280.880174] Starting subtest: pipe-C-bad-rotation-90-y_tiled_ccs
[280.918144] Subtest pipe-C-bad-rotation-90-y_tiled_ccs: SKIP (0.000s)
[281.305668] [036/141] (738s left) kms_ccs (pipe-a-crc-primary-rotation-180-y_tiled_gen12_mc_ccs)
[281.793740] Starting subtest: pipe-A-crc-primary-rotation-180-y_tiled_gen12_mc_ccs
[281.833116] Subtest pipe-A-crc-primary-rotation-180-y_tiled_gen12_mc_ccs: SKIP (0.001s)
[282.437405] [037/141] (737s left) kms_universal_plane (universal-plane-pipe-a-sanity)
[282.826989] Starting subtest: universal-plane-pipe-A-sanity
[283.225861] Subtest universal-plane-pipe-A-sanity: SUCCESS (0.418s)
[283.570439] [038/141] (736s left) gen7_exec_parse (oacontrol-tracking)
[283.885945] Subtest oacontrol-tracking: SKIP (0.000s)
[283.990745] [039/141] (735s left) api_intel_bb (blit-noreloc-keep-cache)
[284.291418] Starting subtest: blit-noreloc-keep-cache
[284.310173] Subtest blit-noreloc-keep-cache: SUCCESS (0.018s)
[284.440374] [040/141] (735s left) gem_lmem_swapping (parallel-random-verify)
[284.755961] Subtest parallel-random-verify: SKIP (0.000s)
[284.863183] [041/141] (735s left) kms_ccs (pipe-d-ccs-on-another-bo-y_tiled_gen12_rc_ccs)
[285.675386] Subtest pipe-D-ccs-on-another-bo-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[286.000726] [042/141] (734s left) kms_ccs (pipe-c-missing-ccs-buffer-y_tiled_ccs)
[286.726379] Starting subtest: pipe-C-missing-ccs-buffer-y_tiled_ccs
[286.768432] Subtest pipe-C-missing-ccs-buffer-y_tiled_ccs: SKIP (0.000s)
[287.122528] [043/141] (733s left) perf (mi-rpc)
[287.560789] Starting subtest: mi-rpc
[287.624942] Subtest mi-rpc: SUCCESS (0.061s)
[287.754314] [044/141] (732s left) gem_exec_params (invalid-fence-in-submit)
[288.049503] Starting subtest: invalid-fence-in-submit
[288.070881] Subtest invalid-fence-in-submit: SUCCESS (0.006s)
[288.185411] [045/141] (732s left) prime_vgem (basic-fence-read)
[288.497655] Starting subtest: basic-fence-read
[288.578632] Subtest basic-fence-read: SUCCESS (0.106s)
[288.723971] [046/141] (731s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_dg2_mc_ccs)
[289.525882] Subtest pipe-D-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[289.864778] [047/141] (730s left) vc4/vc4_wait_bo (bad-pad)
[290.243631] Subtest bad-pad: SKIP (0.000s)
[290.377670] [048/141] (730s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[290.701907] Starting subtest: y-tiled-to-vebox-x-tiled
[290.741131] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[290.921724] [049/141] (729s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc)
[291.571315] Starting subtest: pipe-B-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc
[291.613869] Subtest pipe-B-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.005s)
[292.042623] [050/141] (728s left) gem_exec_big (single)
[292.348572] Starting subtest: single
[332.791093] Subtest single: SUCCESS (40.291s)
[335.545373] [051/141] (685s left) kms_ccs (pipe-c-bad-pixel-format-4_tiled_dg2_rc_ccs)
[336.431182] Starting subtest: pipe-C-bad-pixel-format-4_tiled_dg2_rc_ccs
[336.473721] Subtest pipe-C-bad-pixel-format-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[336.887768] [052/141] (684s left) v3d/v3d_submit_cl (bad-multisync-extension)
[337.279377] Subtest bad-multisync-extension: SKIP (0.000s)
[337.388105] [053/141] (683s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc)
[337.841856] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc
[339.654124] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (1.889s)
[339.797859] [054/141] (681s left) kms_psr (psr2_primary_mmap_gtt)
[340.120738] Subtest psr2_primary_mmap_gtt: SKIP (0.000s)
[340.209559] [055/141] (681s left) kms_ccs (pipe-d-random-ccs-data-4_tiled_mtl_rc_ccs)
[341.003562] Subtest pipe-D-random-ccs-data-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[341.332324] [056/141] (680s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[341.768201] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[341.805435] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[341.920582] [057/141] (679s left) kms_frontbuffer_tracking (psr-slowdraw)
[342.341843] Starting subtest: psr-slowdraw
[342.385040] Subtest psr-slowdraw: SKIP (0.000s)
[342.498420] [058/141] (679s left) prime_self_import (reimport-vs-gem_close-race)
[342.776741] Starting subtest: reimport-vs-gem_close-race
[347.992211] Subtest reimport-vs-gem_close-race: SUCCESS (5.020s)
[348.084834] [059/141] (673s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[348.469975] Starting subtest: yf-tiled-16bpp-rotate-180
[352.287875] Subtest yf-tiled-16bpp-rotate-180: SUCCESS (3.840s)
[352.656664] [060/141] (669s left) kms_dp_tiled_display (basic-test-pattern-with-chamelium)
[353.017893] Starting subtest: basic-test-pattern-with-chamelium
[353.055616] Subtest basic-test-pattern-with-chamelium: SKIP (0.001s)
[353.173322] [061/141] (668s left) vc4/vc4_label_bo (set-label)
[353.520155] Subtest set-label: SKIP (0.000s)
[353.607899] [062/141] (668s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_mc_ccs)
[354.168762] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_mc_ccs
[354.213511] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_mc_ccs: SKIP (0.001s)
[354.733074] [063/141] (667s left) kms_color (ctm-0-75)
[355.084715] Starting subtest: ctm-0-75
[355.106257] Starting dynamic subtest: pipe-A
[357.573709] Dynamic subtest pipe-A: SUCCESS (2.480s)
[357.627658] Starting dynamic subtest: pipe-B
[360.319716] Dynamic subtest pipe-B: SUCCESS (2.737s)
[360.367756] Starting dynamic subtest: pipe-C
[363.192444] Dynamic subtest pipe-C: SUCCESS (2.876s)
[363.213807] Subtest ctm-0-75: SUCCESS (8.097s)
[363.555144] [064/141] (658s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[363.956405] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[364.033947] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.032s)
[364.153109] [065/141] (658s left) gem_softpin (evict-active-interruptible)
[364.453568] Starting subtest: evict-active-interruptible
[364.474305] Subtest evict-active-interruptible: SUCCESS (0.015s)
[364.605943] [066/141] (658s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[365.050074] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[365.089097] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[365.203099] [067/141] (657s left) kms_ccs (pipe-a-bad-aux-stride-yf_tiled_ccs)
[365.730310] Starting subtest: pipe-A-bad-aux-stride-yf_tiled_ccs
[365.749195] Subtest pipe-A-bad-aux-stride-yf_tiled_ccs: SUCCESS (0.001s)
[366.344972] [068/141] (656s left) dmabuf_sync_file (import-multiple-read-write)
[366.648854] Starting subtest: import-multiple-read-write
[366.667709] Subtest import-multiple-read-write: SUCCESS (0.014s)
[366.776796] [069/141] (656s left) gem_mmap_gtt (bad-object)
[367.075939] Starting subtest: bad-object
[367.096308] Subtest bad-object: SUCCESS (0.000s)
[367.211141] [070/141] (655s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[367.503839] Starting subtest: no-spare-fences-busy-interruptible
[369.695095] Subtest no-spare-fences-busy-interruptible: SUCCESS (2.183s)
[370.082724] [071/141] (653s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[370.467290] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[370.548627] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.023s)
[370.667290] [072/141] (652s left) prime_vgem (fence-read-hang)
[371.009731] Subtest fence-read-hang: SKIP (0.000s)
[371.122714] [073/141] (652s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[371.545150] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[371.588052] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[371.699491] [074/141] (651s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[372.000098] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[372.075343] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.107s)
[372.211650] [075/141] (651s left) gem_mmap_gtt (medium-copy)
[372.506471] Starting subtest: medium-copy
[377.789477] Subtest medium-copy: SUCCESS (5.315s)
[377.914948] [076/141] (645s left) kms_getfb (getfb-repeated-different-handles)
[378.343099] Starting subtest: getfb-repeated-different-handles
[378.361934] Subtest getfb-repeated-different-handles: SUCCESS (0.000s)
[378.454949] [077/141] (645s left) gen9_exec_parse (batch-zero-length)
[378.751169] Starting subtest: batch-zero-length
[378.772916] Subtest batch-zero-length: SUCCESS (0.002s)
[378.998728] [078/141] (644s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted)
[379.330863] Starting subtest: invalid-multi-wait-available-unsubmitted
[379.349877] Subtest invalid-multi-wait-available-unsubmitted: SUCCESS (0.000s)
[379.450258] [079/141] (644s left) gem_exec_whisper (basic-queues-forked-all)
[379.786242] Starting subtest: basic-queues-forked-all
[402.514075] Subtest basic-queues-forked-all: SUCCESS (22.733s)
[402.739589] [080/141] (621s left) kms_sequence (queue-busy)
[403.155650] Starting subtest: queue-busy
[403.175179] Starting dynamic subtest: DP-1-pipe-A
[405.662463] Dynamic subtest DP-1-pipe-A: SUCCESS (2.492s)
[405.706875] Starting dynamic subtest: DP-1-pipe-B
[408.396427] Dynamic subtest DP-1-pipe-B: SUCCESS (2.732s)
[408.442361] Starting dynamic subtest: DP-1-pipe-C
[411.282835] Dynamic subtest DP-1-pipe-C: SUCCESS (2.874s)
[411.305226] Subtest queue-busy: SUCCESS (8.112s)
[411.625691] [081/141] (612s left) gem_mmap (short-mmap)
[411.930147] Starting subtest: short-mmap
[411.949370] Subtest short-mmap: SUCCESS (0.000s)
[412.066063] [082/141] (612s left) kms_big_fb (linear-64bpp-rotate-270)
[412.484816] Starting subtest: linear-64bpp-rotate-270
[412.563815] Subtest linear-64bpp-rotate-270: SKIP (0.075s)
[412.683568] [083/141] (611s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[413.043627] Starting subtest: map-fixed-invalidate-overlap
[413.062735] Starting dynamic subtest: gtt
[413.340457] Dynamic subtest gtt: SUCCESS (0.368s)
[413.381750] Starting dynamic subtest: wb
[413.620517] Dynamic subtest wb: SUCCESS (0.277s)
[413.661897] Starting dynamic subtest: wc
[413.892589] Dynamic subtest wc: SUCCESS (0.273s)
[413.931426] Starting dynamic subtest: uc
[414.173553] Dynamic subtest uc: SUCCESS (0.277s)
[414.194785] Subtest map-fixed-invalidate-overlap: SUCCESS (1.201s)
[414.334857] [084/141] (610s left) kms_lease (lease_get)
[414.702375] Starting subtest: lease_get
[414.723886] Starting dynamic subtest: pipe-A-DP-1
[414.745765] Dynamic subtest pipe-A-DP-1: SUCCESS (0.003s)
[414.784875] Starting dynamic subtest: pipe-B-DP-1
[414.805551] Dynamic subtest pipe-B-DP-1: SUCCESS (0.002s)
[414.844280] Starting dynamic subtest: pipe-C-DP-1
[414.863145] Dynamic subtest pipe-C-DP-1: SUCCESS (0.001s)
[414.881805] Subtest lease_get: SUCCESS (0.017s)
[414.998321] [085/141] (609s left) gem_reg_read (timestamp-monotonic)
[415.290150] Starting subtest: timestamp-monotonic
[420.040593] Subtest timestamp-monotonic: SUCCESS (4.623s)
[420.131086] [086/141] (604s left) kms_flip (plain-flip-interruptible)
[420.492752] Starting subtest: plain-flip-interruptible
[420.512101] Starting dynamic subtest: A-DP1
[421.872111] Dynamic subtest A-DP1: SUCCESS (1.368s)
[421.912453] Starting dynamic subtest: B-DP1
[422.980053] Dynamic subtest B-DP1: SUCCESS (1.108s)
[423.018418] Starting dynamic subtest: C-DP1
[424.067714] Dynamic subtest C-DP1: SUCCESS (1.087s)
[424.088060] Subtest plain-flip-interruptible: SUCCESS (3.574s)
[424.541053] [087/141] (600s left) kms_ccs (pipe-a-bad-pixel-format-y_tiled_ccs)
[424.913507] Starting subtest: pipe-A-bad-pixel-format-y_tiled_ccs
[424.934868] Subtest pipe-A-bad-pixel-format-y_tiled_ccs: SUCCESS (0.001s)
[425.672970] [088/141] (599s left) kms_chamelium_frames (dp-crc-fast)
[426.159991] Subtest dp-crc-fast: SKIP (0.000s)
[426.488686] [089/141] (598s left) api_intel_bb (reset-bb)
[426.790974] Starting subtest: reset-bb
[426.810716] Subtest reset-bb: SUCCESS (0.006s)
[426.923349] [090/141] (598s left) kms_concurrent (pipe-d)
[427.344468] Subtest pipe-D: SKIP (0.000s)
[427.534346] [091/141] (597s left) kms_vblank (pipe-a-query-busy-hang)
[427.901995] Starting subtest: pipe-A-query-busy-hang
[435.131434] Subtest pipe-A-query-busy-hang: SUCCESS (7.100s)
[435.390741] [092/141] (589s left) kms_vblank (pipe-b-accuracy-idle)
[435.752882] Starting subtest: pipe-B-accuracy-idle
[438.430557] Subtest pipe-B-accuracy-idle: SUCCESS (2.663s)
[438.788935] [093/141] (586s left) gem_eio (in-flight-contexts-1us)
[439.128354] Starting subtest: in-flight-contexts-1us
[450.255315] Subtest in-flight-contexts-1us: SUCCESS (11.167s)
[450.506166] [094/141] (575s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[450.960810] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[451.003768] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[451.117131] [095/141] (574s left) gem_exec_params (rel-constants-invalid-ring)
[451.418489] Starting subtest: rel-constants-invalid-ring
[451.437494] Subtest rel-constants-invalid-ring: SUCCESS (0.001s)
[451.533738] [096/141] (574s left) kms_async_flips (crc)
[451.925148] Starting subtest: crc
[452.342284] Starting dynamic subtest: pipe-A-DP-1
[454.966887] Dynamic subtest pipe-A-DP-1: SUCCESS (2.426s)
[455.281092] Starting dynamic subtest: pipe-B-DP-1
[457.769294] Dynamic subtest pipe-B-DP-1: SUCCESS (2.341s)
[457.986567] Starting dynamic subtest: pipe-C-DP-1
[460.444763] Dynamic subtest pipe-C-DP-1: SUCCESS (2.331s)
[460.472487] Subtest crc: SUCCESS (8.348s)
[460.858837] [097/141] (564s left) syncobj_timeline (signal-point-0)
[461.177417] Starting subtest: signal-point-0
[461.199929] Subtest signal-point-0: SUCCESS (0.001s)
[461.337621] [098/141] (564s left) fbdev (unaligned-read)
[461.626425] Starting subtest: unaligned-read
[461.647997] Subtest unaligned-read: SUCCESS (0.012s)
[461.744617] [099/141] (564s left) kms_ccs (pipe-c-crc-primary-rotation-180-y_tiled_ccs)
[462.435253] Starting subtest: pipe-C-crc-primary-rotation-180-y_tiled_ccs
[462.476302] Subtest pipe-C-crc-primary-rotation-180-y_tiled_ccs: SKIP (0.001s)
[462.852777] [100/141] (563s left) kms_cursor_crc (cursor-random-512x170)
[463.241783] Starting subtest: cursor-random-512x170
[463.280259] Subtest cursor-random-512x170: SKIP (0.003s)
[463.396570] [101/141] (562s left) kms_content_protection (lic)
[463.805439] Starting subtest: LIC
[463.850905] Starting dynamic subtest: pipe-A-DP-1
[584.829551] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[592.133209] Closing watchdogs
[592.142327] Initializing watchdogs
[592.142516]   /dev/watchdog0
[592.206543] [102/141] (434s left) gem_eio (throttle)
[592.548110] Starting subtest: throttle
[592.569282] Subtest throttle: SUCCESS (0.038s)
[592.753678] [103/141] (433s left) gem_userptr_blits (input-checking)
[593.105525] Starting subtest: input-checking
[593.124863] Subtest input-checking: SUCCESS (0.000s)
[593.257147] [104/141] (433s left) i915_pm_rpm (pm-caching)
[595.111476] Starting subtest: pm-caching
[596.732408] Subtest pm-caching: SUCCESS (1.618s)
[613.759959] [105/141] (412s left) gem_ctx_param (invalid-ctx-set)
[614.080128] Starting subtest: invalid-ctx-set
[614.101546] Subtest invalid-ctx-set: SUCCESS (0.000s)
[614.196526] [106/141] (412s left) kms_ccs (pipe-b-bad-aux-stride-4_tiled_mtl_mc_ccs)
[614.831512] Starting subtest: pipe-B-bad-aux-stride-4_tiled_mtl_mc_ccs
[614.874520] Subtest pipe-B-bad-aux-stride-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[615.324401] [107/141] (411s left) gem_create (create-ext-set-pat)
[615.737073] Starting subtest: create-ext-set-pat
[615.774831] Subtest create-ext-set-pat: SKIP (0.000s)
[615.891851] [108/141] (411s left) perf_pmu (semaphore-busy)
[616.188710] Starting subtest: semaphore-busy
[616.209963] Starting dynamic subtest: rcs0
[617.755101] Dynamic subtest rcs0: SUCCESS (1.568s)
[617.796076] Starting dynamic subtest: bcs0
[619.313723] Dynamic subtest bcs0: SUCCESS (1.555s)
[619.354530] Starting dynamic subtest: vcs0
[620.870457] Dynamic subtest vcs0: SUCCESS (1.555s)
[620.911832] Starting dynamic subtest: vecs0
[622.427108] Dynamic subtest vecs0: SUCCESS (1.554s)
[622.448587] Subtest semaphore-busy: SUCCESS (6.241s)
[622.560278] [109/141] (404s left) kms_chamelium_edid (dp-edid-read)
[623.054315] Subtest dp-edid-read: SKIP (0.000s)
[623.374830] [110/141] (403s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs)
[623.925170] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs
[623.968449] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs: SKIP (0.005s)
[624.515706] [111/141] (402s left) gem_exec_balancer (hang)
[628.782152] Starting subtest: hang
[632.324824] Subtest hang: SUCCESS (3.550s)
[632.425512] [112/141] (395s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[632.844301] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[635.171254] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (2.385s)
[635.335873] [113/141] (392s left) kms_cursor_legacy (single-bo)
[635.699185] Starting subtest: single-bo
[635.717900] Starting dynamic subtest: pipe-A
[657.225044] Dynamic subtest pipe-A: SUCCESS (21.513s)
[657.264232] Starting dynamic subtest: pipe-B
[678.932345] Dynamic subtest pipe-B: SUCCESS (21.511s)
[678.972293] Starting dynamic subtest: pipe-C
[700.435519] Dynamic subtest pipe-C: SUCCESS (21.510s)
[700.475908] Starting dynamic subtest: all-pipes
[721.964198] Dynamic subtest all-pipes: SUCCESS (21.515s)
[721.985043] Subtest single-bo: SUCCESS (86.054s)
[722.075627] [114/141] (305s left) kms_prime (basic-crc-vgem)
[724.206561] Starting subtest: basic-crc-vgem
[724.225419] Starting dynamic subtest: second-to-first
[725.379926] Dynamic subtest second-to-first: SUCCESS (1.204s)
[725.399639] Subtest basic-crc-vgem: SUCCESS (1.205s)
[725.542132] [115/141] (302s left) kms_plane_lowres (tiling-4)
[725.909423] Starting subtest: tiling-4
[725.952377] Subtest tiling-4: SKIP (0.000s)
[726.066737] [116/141] (301s left) gem_render_copy (linear-to-vebox-yf-tiled)
[726.386620] Starting subtest: linear-to-vebox-yf-tiled
[726.424141] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[726.564128] [117/141] (301s left) gem_exec_schedule (semaphore-power)
[727.001360] Starting subtest: semaphore-power
[727.882459] Subtest semaphore-power: SUCCESS (0.871s)
[728.091082] [118/141] (299s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[728.489455] Starting subtest: 4-tiled-32bpp-rotate-0
[728.529902] Subtest 4-tiled-32bpp-rotate-0: SKIP (0.000s)
[728.643890] [119/141] (299s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt)
[729.070263] Starting subtest: psr-2p-primscrn-indfb-plflip-blt
[729.107439] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[729.228663] [120/141] (299s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[729.607867] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[729.645277] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[729.765411] [121/141] (298s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[730.181932] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[730.232500] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[730.382554] [122/141] (298s left) kms_cursor_crc (cursor-sliding-128x128)
[730.741900] Starting subtest: cursor-sliding-128x128
[730.760879] Starting dynamic subtest: pipe-A-DP-1
[734.439515] Dynamic subtest pipe-A-DP-1: SUCCESS (3.680s)
[734.529467] Starting dynamic subtest: pipe-C-DP-1
[738.486926] Dynamic subtest pipe-C-DP-1: SUCCESS (3.973s)
[738.506055] Subtest cursor-sliding-128x128: SUCCESS (7.728s)
[738.870457] [123/141] (289s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[739.292457] Starting subtest: fbcpsr-tiling-4
[739.334888] Subtest fbcpsr-tiling-4: SKIP (0.000s)
[739.448371] [124/141] (289s left) gem_mmap_offset (basic-uaf)
[739.751292] Starting subtest: basic-uaf
[739.770594] Subtest basic-uaf: SUCCESS (0.004s)
[739.884934] [125/141] (288s left) kms_vblank (pipe-d-wait-forked-busy)
[740.269490] Subtest pipe-D-wait-forked-busy: SKIP (0.000s)
[740.465499] [126/141] (288s left) drm_read (empty-block)
[740.943698] Starting subtest: empty-block
[741.927353] Subtest empty-block: SUCCESS (1.001s)
[742.090864] [127/141] (286s left) sysfs_heartbeat_interval (nopreempt)
[742.389745] Starting subtest: nopreempt
[742.410550] Starting dynamic subtest: vecs0
[746.063532] Dynamic subtest vecs0: SUCCESS (3.515s)
[746.102264] Starting dynamic subtest: vcs0
[749.557716] Dynamic subtest vcs0: SUCCESS (3.486s)
[749.598325] Starting dynamic subtest: bcs0
[753.052858] Dynamic subtest bcs0: SUCCESS (3.501s)
[753.097826] Starting dynamic subtest: rcs0
[756.600913] Dynamic subtest rcs0: SUCCESS (3.492s)
[756.629701] Subtest nopreempt: SUCCESS (14.001s)
[756.766178] [128/141] (272s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[757.092284] Starting subtest: write-cpu-read-wc-unflushed
[757.308430] Subtest write-cpu-read-wc-unflushed: SUCCESS (0.262s)
[757.422254] [129/141] (271s left) gem_reg_read (bad-register)
[757.741905] Starting subtest: bad-register
[757.764858] Subtest bad-register: SUCCESS (0.000s)
[757.878858] [130/141] (271s left) gem_userptr_blits (vma-merge)
[758.284794] Starting subtest: vma-merge
[779.189723] Subtest vma-merge: FAIL (20.693s)
[779.282515] [131/141] (250s left) gem_pwrite (basic-random)
[779.586668] Starting subtest: basic-random
[785.815148] Subtest basic-random: SUCCESS (6.240s)
[785.938900] [132/141] (243s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[786.574641] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[786.614917] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[786.730564] [133/141] (243s left) syncobj_wait (reset-during-wait-for-submit)
[787.062469] Starting subtest: reset-during-wait-for-submit
[787.167263] Subtest reset-during-wait-for-submit: SUCCESS (0.105s)
[787.282986] [134/141] (242s left) gem_exec_fence (keep-in-fence)
[787.712288] Starting subtest: keep-in-fence
[787.733303] Starting dynamic subtest: rcs0
[787.752516] Dynamic subtest rcs0: SUCCESS (0.036s)
[787.793830] Starting dynamic subtest: bcs0
[787.900406] Dynamic subtest bcs0: SUCCESS (0.146s)
[787.943496] Starting dynamic subtest: vcs0
[787.964668] Dynamic subtest vcs0: SUCCESS (0.043s)
[788.006178] Starting dynamic subtest: vecs0
[788.025232] Dynamic subtest vecs0: SUCCESS (0.049s)
[788.043885] Subtest keep-in-fence: SUCCESS (0.280s)
[788.693590] [135/141] (241s left) kms_flip (2x-flip-vs-panning-interruptible)
[789.084182] Starting subtest: 2x-flip-vs-panning-interruptible
[789.126038] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.003s)
[789.234812] [136/141] (240s left) gem_exec_fence (syncobj-timeline-unused-fence)
[790.096167] Starting subtest: syncobj-timeline-unused-fence
[790.115046] Subtest syncobj-timeline-unused-fence: SUCCESS (0.007s)
[790.364168] [137/141] (239s left) gem_ctx_param (basic-default)
[790.683608] Starting subtest: basic-default
[790.702370] Subtest basic-default: SUCCESS (0.000s)
[790.792652] [138/141] (239s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[791.220147] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[791.257714] Subtest psr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[791.375683] [139/141] (238s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[791.672495] Starting subtest: multi-wait-all-for-submit-available-submitted
[791.694641] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.002s)
[791.792065] [140/141] (238s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[792.236684] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[794.274294] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (2.116s)
[794.444157] [141/141] (236s left) kms_plane_scaling (plane-upscale-with-rotation-factor-0-25)
[794.792446] Starting subtest: plane-upscale-with-rotation-factor-0-25
[794.811351] Starting dynamic subtest: pipe-A-DP-1
[794.924228] Dynamic subtest pipe-A-DP-1: SKIP (0.105s)
[794.964756] Starting dynamic subtest: pipe-B-DP-1
[795.305103] Dynamic subtest pipe-B-DP-1: SKIP (0.356s)
[795.348513] Starting dynamic subtest: pipe-C-DP-1
[795.661450] Dynamic subtest pipe-C-DP-1: SKIP (0.360s)
[795.706482] Subtest plane-upscale-with-rotation-factor-0-25: SKIP (0.833s)
[796.008446] Closing watchdogs
Done.