[52.267148] Initializing watchdogs
[52.267409]   /dev/watchdog0
[52.283375] [001/137] (960s left) gem_pipe_control_store_loop (fresh-buffer)
[52.508345] Starting subtest: fresh-buffer
[54.685913] Subtest fresh-buffer: SUCCESS (2.176s)
[54.784074] [002/137] (958s left) sysfs_preempt_timeout (idempotent)
[54.981074] Starting subtest: idempotent
[54.982497] Starting dynamic subtest: vecs0
[54.983832] Dynamic subtest vecs0: SUCCESS (0.002s)
[54.998276] Starting dynamic subtest: vcs0
[54.999901] Dynamic subtest vcs0: SUCCESS (0.002s)
[55.012366] Starting dynamic subtest: bcs0
[55.013695] Dynamic subtest bcs0: SUCCESS (0.003s)
[55.026790] Starting dynamic subtest: rcs0
[55.028156] Dynamic subtest rcs0: SUCCESS (0.001s)
[55.029439] Subtest idempotent: SUCCESS (0.010s)
[55.062635] [003/137] (957s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_ccs)
[55.771799] Subtest pipe-D-bad-pixel-format-y_tiled_ccs: SKIP (0.000s)
[56.080427] [004/137] (956s left) kms_vblank (pipe-a-wait-forked-busy)
[56.369124] Starting subtest: pipe-A-wait-forked-busy
[58.835821] Subtest pipe-A-wait-forked-busy: SUCCESS (2.469s)
[59.162925] [005/137] (953s left) gem_workarounds (suspend-resume)
[59.383549] Starting subtest: suspend-resume
[76.454386] Subtest suspend-resume: SUCCESS (1.358s)
[86.581129] [006/137] (952s left) kms_atomic (plane-primary-legacy)
[87.228741] Starting subtest: plane-primary-legacy
[87.417695] Subtest plane-primary-legacy: SUCCESS (0.188s)
[87.948388] [007/137] (950s left) vc4/vc4_perfmon (destroy-invalid-perfmon)
[88.285705] Subtest destroy-invalid-perfmon: SKIP (0.000s)
[88.350044] [008/137] (950s left) sw_sync (alloc_timeline)
[88.527428] Starting subtest: alloc_timeline
[88.529095] Subtest alloc_timeline: SUCCESS (0.000s)
[88.585906] [009/137] (950s left) perf_pmu (init-wait)
[88.778707] Starting subtest: init-wait
[88.780694] Starting dynamic subtest: rcs0
[88.782063] Dynamic subtest rcs0: SUCCESS (0.003s)
[88.802817] Starting dynamic subtest: bcs0
[88.804096] Dynamic subtest bcs0: SUCCESS (0.003s)
[88.820095] Starting dynamic subtest: vcs0
[88.821426] Dynamic subtest vcs0: SUCCESS (0.001s)
[88.837337] Starting dynamic subtest: vecs0
[88.838642] Dynamic subtest vecs0: SUCCESS (0.001s)
[88.839889] Subtest init-wait: SUCCESS (0.009s)
[88.866819] [010/137] (949s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[89.150622] Starting subtest: cursor-vs-flip-legacy
[126.134565] Subtest cursor-vs-flip-legacy: SUCCESS (36.982s)
[126.449503] [011/137] (912s left) gem_exec_reloc (basic-write-wc)
[126.641726] Starting subtest: basic-write-wc
[126.660416] Subtest basic-write-wc: SUCCESS (0.019s)
[126.846830] [012/137] (912s left) gem_basic (multigpu-create-close)
[127.026664] Starting subtest: multigpu-create-close
[127.029445] Subtest multigpu-create-close: SKIP (0.000s)
[127.113774] [013/137] (911s left) v3d/v3d_submit_csd (bad-flag)
[127.331994] Subtest bad-flag: SKIP (0.000s)
[127.396898] [014/137] (911s left) gem_exec_balancer (bonded-true-hang)
[131.327403] Starting subtest: bonded-true-hang
[134.839036] Subtest bonded-true-hang: SUCCESS (3.512s)
[134.930000] [015/137] (904s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[135.226565] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[135.230613] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[135.314078] [016/137] (903s left) api_intel_bb (blit-noreloc-keep-cache)
[135.495926] Starting subtest: blit-noreloc-keep-cache
[135.518107] Subtest blit-noreloc-keep-cache: SUCCESS (0.024s)
[135.613759] [017/137] (903s left) gem_mmap_gtt (basic-write)
[135.792196] Starting subtest: basic-write
[136.217179] Subtest basic-write: SUCCESS (0.424s)
[136.343516] [018/137] (902s left) gem_exec_balancer (persistence)
[140.283508] Starting subtest: persistence
[143.939801] Subtest persistence: SUCCESS (3.655s)
[144.013243] [019/137] (895s left) i915_pm_rpm (legacy-planes)
[145.782878] Starting subtest: legacy-planes
[145.785556] Starting dynamic subtest: plane-39
[148.612078] Dynamic subtest plane-39: SUCCESS (2.828s)
[148.631260] Starting dynamic subtest: plane-47
[151.426494] Dynamic subtest plane-47: SUCCESS (2.814s)
[151.429232] Subtest legacy-planes: SUCCESS (5.643s)
[151.867805] [020/137] (887s left) gem_exec_whisper (basic-queues-forked)
[152.074846] Starting subtest: basic-queues-forked
[165.361269] Subtest basic-queues-forked: SUCCESS (13.285s)
[165.551954] [021/137] (873s left) gem_mmap_gtt (cpuset-basic-small-copy)
[165.729626] Starting subtest: cpuset-basic-small-copy
[172.103012] Subtest cpuset-basic-small-copy: SUCCESS (6.371s)
[172.298991] [022/137] (866s left) gem_ctx_create (basic-files)
[172.653325] Starting subtest: basic-files
[174.684345] Subtest basic-files: SUCCESS (2.031s)
[174.918953] [023/137] (864s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen)
[175.213804] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen
[175.216296] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[175.303214] [024/137] (863s left) gem_exec_reloc (basic-cpu-read-noreloc)
[175.495651] Starting subtest: basic-cpu-read-noreloc
[175.508214] Subtest basic-cpu-read-noreloc: SUCCESS (0.012s)
[175.687415] [025/137] (863s left) kms_frontbuffer_tracking (psr-2p-rte)
[175.974838] Starting subtest: psr-2p-rte
[175.977400] Subtest psr-2p-rte: SKIP (0.000s)
[176.069795] [026/137] (863s left) kms_flip (2x-modeset-vs-vblank-race-interruptible)
[176.343329] Starting subtest: 2x-modeset-vs-vblank-race-interruptible
[176.347454] Subtest 2x-modeset-vs-vblank-race-interruptible: SKIP (0.003s)
[176.436582] [027/137] (862s left) gem_lmem_swapping (massive)
[176.645906] Subtest massive: SKIP (0.000s)
[176.689085] [028/137] (862s left) syncobj_timeline (etime-multi-wait-for-submit-available-unsubmitted)
[176.867536] Starting subtest: etime-multi-wait-for-submit-available-unsubmitted
[176.970969] Subtest etime-multi-wait-for-submit-available-unsubmitted: SUCCESS (0.103s)
[177.052878] [029/137] (862s left) gem_pwrite (basic-random)
[177.230425] Starting subtest: basic-random
[182.867805] Subtest basic-random: SUCCESS (5.634s)
[182.991716] [030/137] (856s left) kms_vblank (pipe-a-ts-continuation-modeset-rpm)
[183.399158] Starting subtest: pipe-A-ts-continuation-modeset-rpm
[185.310177] Subtest pipe-A-ts-continuation-modeset-rpm: SUCCESS (1.914s)
[185.738632] [031/137] (853s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[186.027905] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[186.030529] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[186.121979] [032/137] (853s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[186.428758] Starting subtest: yf-tiled-32bpp-rotate-180
[191.434608] Subtest yf-tiled-32bpp-rotate-180: SUCCESS (5.005s)
[191.956422] [033/137] (847s left) gem_eio (in-flight-1us)
[192.162404] Starting subtest: in-flight-1us
[193.540571] Subtest in-flight-1us: SUCCESS (1.375s)
[193.725724] [034/137] (845s left) kms_frontbuffer_tracking (fbc-tiling-y)
[194.014067] Starting subtest: fbc-tiling-y
[196.236341] Subtest fbc-tiling-y: SUCCESS (2.222s)
[196.372434] [035/137] (843s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[196.557890] Starting subtest: invalid-single-wait-all-unsubmitted
[196.560187] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[196.639366] [036/137] (843s left) perf_pmu (gt-awake)
[196.830516] Starting subtest: gt-awake
[199.386660] Subtest gt-awake: SUCCESS (2.555s)
[199.488200] [037/137] (840s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[199.763048] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[199.764280] Starting dynamic subtest: pipe-A-valid-mode
[200.449217] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.682s)
[200.450699] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SUCCESS (0.685s)
[200.800666] [038/137] (838s left) kms_busy (extended-modeset-hang-oldfb)
[201.100647] Starting subtest: extended-modeset-hang-oldfb
[201.105066] Starting dynamic subtest: pipe-A
[203.010957] Dynamic subtest pipe-A: SUCCESS (1.902s)
[203.035798] Starting dynamic subtest: pipe-C
[205.037703] Dynamic subtest pipe-C: SUCCESS (2.023s)
[205.039151] Subtest extended-modeset-hang-oldfb: SUCCESS (3.938s)
[205.384611] [039/137] (834s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[205.670100] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[205.672705] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[205.763913] [040/137] (834s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc)
[206.053411] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc
[206.056123] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[206.147157] [041/137] (833s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs)
[206.836384] Subtest pipe-D-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[207.139030] [042/137] (832s left) kms_content_protection (lic)
[207.451377] Starting subtest: LIC
[207.483836] Starting dynamic subtest: pipe-A-DP-1
[328.109047] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[328.813870] Closing watchdogs
[328.822225] Initializing watchdogs
[328.822397]   /dev/watchdog0
[328.849366] [043/137] (711s left) prime_vgem (basic-write)
[329.060937] Starting subtest: basic-write
[329.122396] Subtest basic-write: SUCCESS (0.061s)
[329.206444] [044/137] (710s left) vc4/vc4_wait_bo (bad-bo)
[329.450163] Subtest bad-bo: SKIP (0.000s)
[329.523334] [045/137] (710s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[329.708049] Starting subtest: multi-wait-for-submit-available-signaled
[329.712014] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.002s)
[329.789906] [046/137] (710s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[329.971133] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[329.973141] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.001s)
[330.056362] [047/137] (710s left) gem_ctx_persistence (engines-persistence)
[330.244965] Starting subtest: engines-persistence
[330.246557] Starting dynamic subtest: rcs0
[331.034091] Dynamic subtest rcs0: SUCCESS (0.789s)
[331.058963] Starting dynamic subtest: bcs0
[331.811249] Dynamic subtest bcs0: SUCCESS (0.776s)
[331.834803] Starting dynamic subtest: vcs0
[332.573214] Dynamic subtest vcs0: SUCCESS (0.761s)
[332.598153] Starting dynamic subtest: vecs0
[333.346115] Dynamic subtest vecs0: SUCCESS (0.772s)
[333.347442] Subtest engines-persistence: SUCCESS (3.102s)
[333.439742] [048/137] (706s left) gem_exec_schedule (u-lateslice)
[333.652328] Starting subtest: u-lateslice
[333.653844] Starting dynamic subtest: rcs0
[333.692712] Dynamic subtest rcs0: SUCCESS (0.039s)
[333.718994] Starting dynamic subtest: bcs0
[333.729847] Dynamic subtest bcs0: SUCCESS (0.037s)
[333.756706] Starting dynamic subtest: vcs0
[333.762176] Dynamic subtest vcs0: SUCCESS (0.031s)
[333.787915] Starting dynamic subtest: vecs0
[333.791861] Dynamic subtest vecs0: SUCCESS (0.029s)
[333.793224] Subtest u-lateslice: SUCCESS (0.141s)
[334.140786] [049/137] (706s left) kms_plane_cursor (primary)
[334.427376] Starting subtest: primary
[334.430101] Starting dynamic subtest: pipe-A-DP-1-size-64
[336.865284] Dynamic subtest pipe-A-DP-1-size-64: SUCCESS (2.438s)
[336.926487] Starting dynamic subtest: pipe-A-DP-1-size-128
[339.314666] Dynamic subtest pipe-A-DP-1-size-128: SUCCESS (2.416s)
[339.374786] Starting dynamic subtest: pipe-A-DP-1-size-256
[341.764808] Dynamic subtest pipe-A-DP-1-size-256: SUCCESS (2.415s)
[341.906791] Starting dynamic subtest: pipe-B-DP-1-size-64
[344.517221] Dynamic subtest pipe-B-DP-1-size-64: SUCCESS (2.635s)
[344.575710] Starting dynamic subtest: pipe-B-DP-1-size-128
[346.968383] Dynamic subtest pipe-B-DP-1-size-128: SUCCESS (2.416s)
[347.023372] Starting dynamic subtest: pipe-B-DP-1-size-256
[349.434204] Dynamic subtest pipe-B-DP-1-size-256: SUCCESS (2.433s)
[349.580697] Starting dynamic subtest: pipe-C-DP-1-size-64
[352.200258] Dynamic subtest pipe-C-DP-1-size-64: SUCCESS (2.644s)
[352.256628] Starting dynamic subtest: pipe-C-DP-1-size-128
[354.650382] Dynamic subtest pipe-C-DP-1-size-128: SUCCESS (2.416s)
[354.712716] Starting dynamic subtest: pipe-C-DP-1-size-256
[357.100976] Dynamic subtest pipe-C-DP-1-size-256: SUCCESS (2.416s)
[357.216110] Subtest primary: SUCCESS (22.793s)
[357.529919] [050/137] (682s left) gem_exec_schedule (preempt-other)
[357.739864] Starting subtest: preempt-other
[357.741321] Starting dynamic subtest: rcs0
[357.814730] Dynamic subtest rcs0: SUCCESS (0.075s)
[357.853051] Starting dynamic subtest: bcs0
[357.895330] Dynamic subtest bcs0: SUCCESS (0.080s)
[357.928205] Starting dynamic subtest: vcs0
[357.976104] Dynamic subtest vcs0: SUCCESS (0.080s)
[358.007970] Starting dynamic subtest: vecs0
[358.059039] Dynamic subtest vecs0: SUCCESS (0.082s)
[358.060762] Subtest preempt-other: SUCCESS (0.321s)
[358.396690] [051/137] (681s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[358.668279] Starting subtest: cursorA-vs-flipB-legacy
[358.670926] Subtest cursorA-vs-flipB-legacy: SKIP (0.003s)
[358.764928] [052/137] (681s left) gem_exec_flush (basic-uc-set-default)
[358.965192] Starting subtest: basic-uc-set-default
[364.421542] Subtest basic-uc-set-default: SUCCESS (5.456s)
[364.629045] [053/137] (675s left) prime_self_import (basic-with_fd_dup)
[364.803882] Starting subtest: basic-with_fd_dup
[364.808931] Subtest basic-with_fd_dup: SUCCESS (0.005s)
[364.912038] [054/137] (675s left) perf (invalid-oa-format-id)
[365.232661] Starting subtest: invalid-oa-format-id
[365.291364] Subtest invalid-oa-format-id: SUCCESS (0.060s)
[365.347322] [055/137] (675s left) gem_render_copy (yf-tiled)
[365.543433] Starting subtest: yf-tiled
[365.545044] Starting dynamic subtest: smem
[365.925201] Dynamic subtest smem: SUCCESS (0.380s)
[365.926590] Subtest yf-tiled: SUCCESS (0.381s)
[366.131955] [056/137] (674s left) gem_userptr_blits (sync-unmap-cycles)
[366.342934] Starting subtest: sync-unmap-cycles
[371.727208] Subtest sync-unmap-cycles: SUCCESS (5.383s)
[371.864724] [057/137] (668s left) kms_chamelium_color (ctm-negative)
[372.145853] Subtest ctm-negative: SKIP (0.000s)
[372.199784] [058/137] (668s left) drm_read (short-buffer-block)
[372.591768] Starting subtest: short-buffer-block
[372.593113] Subtest short-buffer-block: SUCCESS (0.000s)
[372.714962] [059/137] (667s left) gem_ctx_bad_destroy (invalid-default-ctx)
[372.890516] Starting subtest: invalid-default-ctx
[372.892141] Subtest invalid-default-ctx: SUCCESS (0.000s)
[372.979996] [060/137] (667s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs)
[373.657937] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs
[373.663136] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs: SKIP (0.004s)
[373.988994] [061/137] (666s left) kms_cursor_crc (cursor-random-max-size)
[374.283324] Starting subtest: cursor-random-max-size
[374.287240] Subtest cursor-random-max-size: SKIP (0.000s)
[374.388243] [062/137] (666s left) kms_frontbuffer_tracking (psr-1p-rte)
[374.676768] Starting subtest: psr-1p-rte
[374.679375] Subtest psr-1p-rte: SKIP (0.000s)
[374.771817] [063/137] (666s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[375.058888] Starting subtest: FB-8BPC-Vs-Panel-6BPC
[375.060126] Starting dynamic subtest: pipe-A-DP-1
[375.480644] Dynamic subtest pipe-A-DP-1: SUCCESS (0.424s)
[375.482340] Subtest FB-8BPC-Vs-Panel-6BPC: SUCCESS (0.426s)
[375.827272] [064/137] (664s left) drm_fdinfo (virtual-busy)
[376.015529] Starting subtest: virtual-busy
[378.170403] Subtest virtual-busy: SUCCESS (2.156s)
[378.259967] [065/137] (662s left) kms_psr (psr2_cursor_plane_onoff)
[378.438615] Subtest psr2_cursor_plane_onoff: SKIP (0.000s)
[378.495509] [066/137] (662s left) kms_ccs (pipe-a-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc)
[378.932758] Starting subtest: pipe-A-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc
[378.935625] Subtest pipe-A-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[379.522586] [067/137] (661s left) kms_ccs (pipe-b-bad-pixel-format-4_tiled_dg2_mc_ccs)
[379.998254] Starting subtest: pipe-B-bad-pixel-format-4_tiled_dg2_mc_ccs
[380.000856] Subtest pipe-B-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[380.552367] [068/137] (660s left) gem_exec_reloc (basic-wc-gtt-active)
[380.745096] Starting subtest: basic-wc-gtt-active
[380.888174] Subtest basic-wc-gtt-active: SUCCESS (0.142s)
[381.085408] [069/137] (659s left) fbdev (nullptr)
[381.279691] Starting subtest: nullptr
[381.281078] Subtest nullptr: SUCCESS (0.000s)
[381.352044] [070/137] (659s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[381.634973] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[381.637518] Subtest fbcpsr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[381.735879] [071/137] (659s left) kms_lease (possible-crtcs-filtering)
[382.016621] Starting subtest: possible-crtcs-filtering
[382.025258] Subtest possible-crtcs-filtering: SUCCESS (0.010s)
[382.118794] [072/137] (659s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[382.417100] Starting subtest: fbcpsr-slowdraw
[382.419711] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[382.519121] [073/137] (658s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_gen12_mc_ccs)
[383.212068] Subtest pipe-D-random-ccs-data-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[383.527136] [074/137] (657s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[383.801395] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[383.802712] Starting dynamic subtest: pipe-A-valid-mode
[384.181952] Dynamic subtest pipe-A-valid-mode: SKIP (0.376s)
[384.184497] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SKIP (0.379s)
[384.555740] [075/137] (656s left) gem_userptr_blits (sd-probe)
[384.749229] Starting subtest: sd-probe
[384.750688] Subtest sd-probe: SUCCESS (0.001s)
[384.906132] [076/137] (656s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_dg2_rc_ccs_cc)
[385.479082] Starting subtest: pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs_cc
[385.481936] Subtest pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[385.882089] [077/137] (655s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[386.168368] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[386.170974] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[386.264969] [078/137] (655s left) gem_ctx_param (invalid-get-no-zeromap)
[386.443974] Starting subtest: invalid-get-no-zeromap
[386.445841] Subtest invalid-get-no-zeromap: SUCCESS (0.000s)
[386.531609] [079/137] (654s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[386.825288] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[386.828211] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[386.932076] [080/137] (654s left) kms_flip (2x-flip-vs-fences-interruptible)
[387.204273] Starting subtest: 2x-flip-vs-fences-interruptible
[387.206825] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.003s)
[387.299053] [081/137] (654s left) gem_partial_pwrite_pread (writes-after-reads-display)
[387.490077] Starting subtest: writes-after-reads-display
[407.614702] Subtest writes-after-reads-display: SUCCESS (20.125s)
[407.731181] [082/137] (633s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[408.021973] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[408.024746] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[408.132781] [083/137] (633s left) kms_concurrent (pipe-a)
[408.409683] Starting subtest: pipe-A
[408.412175] Starting dynamic subtest: DP-1
[409.231881] Dynamic subtest DP-1: SUCCESS (0.818s)
[409.233191] Subtest pipe-A: SUCCESS (0.823s)
[409.547731] [084/137] (632s left) i915_pm_freq_mult (media-freq)
[409.730128] Starting subtest: media-freq
[409.732174] Starting dynamic subtest: gt0
[409.734859] Dynamic subtest gt0: SKIP (0.000s)
[409.737383] Subtest media-freq: SKIP (0.002s)
[409.813373] [085/137] (631s left) kms_psr (cursor_mmap_gtt)
[409.994653] Subtest cursor_mmap_gtt: SKIP (0.000s)
[410.049181] [086/137] (631s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[410.353278] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[410.356224] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[410.464298] [087/137] (631s left) syncobj_timeline (reset-unsignaled)
[410.647090] Starting subtest: reset-unsignaled
[410.650377] Subtest reset-unsignaled: SUCCESS (0.000s)
[410.746429] [088/137] (631s left) kms_properties (crtc-properties-legacy)
[411.023768] Starting subtest: crtc-properties-legacy
[412.312236] Subtest crtc-properties-legacy: SUCCESS (1.290s)
[412.649880] [089/137] (629s left) kms_plane_scaling (plane-downscale-with-pixel-format-factor-0-5)
[412.933906] Starting subtest: plane-downscale-with-pixel-format-factor-0-5
[412.935356] Starting dynamic subtest: pipe-A-DP-1
[415.499611] Dynamic subtest pipe-A-DP-1: SUCCESS (2.559s)
[415.523735] Starting dynamic subtest: pipe-B-DP-1
[418.101821] Dynamic subtest pipe-B-DP-1: SUCCESS (2.604s)
[418.138214] Starting dynamic subtest: pipe-C-DP-1
[418.481265] Dynamic subtest pipe-C-DP-1: SUCCESS (0.375s)
[418.482857] Subtest plane-downscale-with-pixel-format-factor-0-5: SUCCESS (5.553s)
[418.892840] [090/137] (622s left) gem_userptr_blits (unsync-overlap)
[419.087057] Subtest unsync-overlap: SKIP (0.000s)
[419.247375] [091/137] (622s left) kms_invalid_mode (zero-clock)
[419.534761] Starting subtest: zero-clock
[419.537360] Starting dynamic subtest: DP-1-pipe-A
[419.620821] Dynamic subtest DP-1-pipe-A: SUCCESS (0.090s)
[419.652155] Starting dynamic subtest: DP-1-pipe-B
[419.654016] Dynamic subtest DP-1-pipe-B: SUCCESS (0.014s)
[419.678637] Starting dynamic subtest: DP-1-pipe-C
[419.680370] Dynamic subtest DP-1-pipe-C: SUCCESS (0.015s)
[419.682067] Subtest zero-clock: SUCCESS (0.132s)
[419.983724] [092/137] (621s left) kms_vblank (pipe-d-wait-idle)
[420.265090] Subtest pipe-D-wait-idle: SKIP (0.000s)
[420.349278] [093/137] (621s left) gem_exec_fence (parallel)
[420.672809] Starting subtest: parallel
[420.674282] Starting dynamic subtest: rcs0
[422.834092] Dynamic subtest rcs0: SUCCESS (2.157s)
[422.864402] Starting dynamic subtest: bcs0
[424.991954] Dynamic subtest bcs0: SUCCESS (2.158s)
[425.016820] Starting dynamic subtest: vcs0
[427.150959] Dynamic subtest vcs0: SUCCESS (2.157s)
[427.176164] Starting dynamic subtest: vecs0
[429.302698] Dynamic subtest vecs0: SUCCESS (2.152s)
[429.304276] Subtest parallel: SUCCESS (8.629s)
[429.949113] [094/137] (612s left) gem_eio (in-flight-immediate)
[430.146921] Starting subtest: in-flight-immediate
[431.064448] Subtest in-flight-immediate: SUCCESS (0.917s)
[431.234939] [095/137] (610s left) kms_vblank (pipe-d-query-busy-hang)
[431.511789] Subtest pipe-D-query-busy-hang: SKIP (0.000s)
[431.600780] [096/137] (610s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[431.791082] Starting subtest: basic-wc-cpu-noreloc
[431.801596] Subtest basic-wc-cpu-noreloc: SUCCESS (0.011s)
[432.000886] [097/137] (610s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted-submitted-sign
[432.185193] Starting subtest: invalid-multi-wait-all-available-unsubmitted-submitted-signaled
[432.186647] Subtest invalid-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[432.282892] [098/137] (609s left) kms_plane_scaling (plane-scaler-with-rotation-unity-scaling)
[432.562588] Starting subtest: plane-scaler-with-rotation-unity-scaling
[432.567349] Starting dynamic subtest: pipe-A-DP-1
[432.641640] Dynamic subtest pipe-A-DP-1: SKIP (0.074s)
[432.671356] Starting dynamic subtest: pipe-B-DP-1
[433.013131] Dynamic subtest pipe-B-DP-1: SKIP (0.367s)
[433.037533] Starting dynamic subtest: pipe-C-DP-1
[433.391982] Dynamic subtest pipe-C-DP-1: SKIP (0.373s)
[433.394398] Subtest plane-scaler-with-rotation-unity-scaling: SKIP (0.827s)
[433.785683] [099/137] (608s left) gem_wait (wait)
[433.997825] Starting subtest: wait
[433.999384] Starting dynamic subtest: all-engines
[435.030217] Dynamic subtest all-engines: SUCCESS (1.031s)
[435.058434] Starting dynamic subtest: rcs0
[436.061052] Dynamic subtest rcs0: SUCCESS (1.030s)
[436.085994] Starting dynamic subtest: bcs0
[437.088452] Dynamic subtest bcs0: SUCCESS (1.024s)
[437.114613] Starting dynamic subtest: vcs0
[438.113172] Dynamic subtest vcs0: SUCCESS (1.023s)
[438.138254] Starting dynamic subtest: vecs0
[439.146111] Dynamic subtest vecs0: SUCCESS (1.031s)
[439.147810] Subtest wait: SUCCESS (5.148s)
[439.385856] [100/137] (602s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[439.676621] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[439.679648] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[439.785345] [101/137] (602s left) kms_flip (2x-flip-vs-panning-vs-hang)
[440.055998] Starting subtest: 2x-flip-vs-panning-vs-hang
[440.058634] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.003s)
[440.168695] [102/137] (602s left) gem_ctx_shared (q-smoketest)
[440.371901] Starting subtest: Q-smoketest
[440.373427] Starting dynamic subtest: rcs0
[445.822121] Dynamic subtest rcs0: SUCCESS (5.444s)
[445.846942] Starting dynamic subtest: bcs0
[451.274693] Dynamic subtest bcs0: SUCCESS (5.449s)
[451.324083] Starting dynamic subtest: vcs0
[456.724112] Dynamic subtest vcs0: SUCCESS (5.449s)
[456.749958] Starting dynamic subtest: vecs0
[462.170110] Dynamic subtest vecs0: SUCCESS (5.444s)
[462.171456] Subtest Q-smoketest: SUCCESS (21.793s)
[462.367395] [103/137] (580s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[462.680239] Starting subtest: x-tiled-64bpp-rotate-0
[470.292076] Subtest x-tiled-64bpp-rotate-0: SUCCESS (7.613s)
[471.218395] [104/137] (571s left) gem_mmap (short-mmap)
[471.392926] Starting subtest: short-mmap
[471.394315] Subtest short-mmap: SUCCESS (0.000s)
[471.601337] [105/137] (570s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[471.899733] Starting subtest: x-tiled-32bpp-rotate-270
[471.977134] Subtest x-tiled-32bpp-rotate-270: SKIP (0.076s)
[472.202120] [106/137] (570s left) perf (enable-disable)
[472.460890] Starting subtest: enable-disable
[472.462371] Starting dynamic subtest: 0-rcs0
[475.390144] Dynamic subtest 0-rcs0: SUCCESS (2.927s)
[475.391550] Subtest enable-disable: SUCCESS (2.929s)
[475.456172] [107/137] (567s left) gem_ppgtt (shrink-vs-evict-any)
[475.645349] Starting subtest: shrink-vs-evict-any
[480.728391] Subtest shrink-vs-evict-any: SUCCESS (5.081s)
[480.822392] [108/137] (561s left) i915_pm_backlight (basic-brightness)
[481.107095] Subtest basic-brightness: SKIP (0.000s)
[481.170876] [109/137] (561s left) syncobj_wait (multi-wait-all-submitted)
[481.351265] Starting subtest: multi-wait-all-submitted
[481.458299] Subtest multi-wait-all-submitted: SUCCESS (0.107s)
[481.550333] [110/137] (561s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[481.839816] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[481.842517] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[481.935676] [111/137] (560s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[482.239822] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[482.242415] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[482.352035] [112/137] (560s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[482.726190] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[483.019863] [113/137] (559s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc)
[483.589605] Starting subtest: pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc
[483.596866] Subtest pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.006s)
[484.061429] [114/137] (558s left) kms_plane_scaling (planes-upscale-factor-0-25)
[484.336324] Starting subtest: planes-upscale-factor-0-25
[484.337667] Starting dynamic subtest: pipe-A-DP-1
[484.399895] Dynamic subtest pipe-A-DP-1: SUCCESS (0.063s)
[484.455256] Starting dynamic subtest: pipe-B-DP-1
[484.755055] Dynamic subtest pipe-B-DP-1: SUCCESS (0.351s)
[484.782762] Starting dynamic subtest: pipe-C-DP-1
[484.847524] Dynamic subtest pipe-C-DP-1: SKIP (0.083s)
[484.848885] Subtest planes-upscale-factor-0-25: SUCCESS (0.512s)
[485.262544] [115/137] (557s left) kms_color (ctm-0-75)
[485.537246] Starting subtest: ctm-0-75
[485.538615] Starting dynamic subtest: pipe-A-DP-1
[488.008636] Dynamic subtest pipe-A-DP-1: SUCCESS (2.468s)
[488.044361] Starting dynamic subtest: pipe-B-DP-1
[490.780165] Dynamic subtest pipe-B-DP-1: SUCCESS (2.767s)
[490.816419] Starting dynamic subtest: pipe-C-DP-1
[493.647618] Dynamic subtest pipe-C-DP-1: SUCCESS (2.862s)
[493.648912] Subtest ctm-0-75: SUCCESS (8.115s)
[493.992853] [116/137] (548s left) v3d/v3d_perfmon (create-perfmon-exceed)
[494.233691] Subtest create-perfmon-exceed: SKIP (0.000s)
[494.309072] [117/137] (548s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[494.600037] Starting subtest: cursor-vs-flip-toggle
[534.947267] Subtest cursor-vs-flip-toggle: SUCCESS (40.349s)
[535.288121] [118/137] (507s left) i915_hangman (engine-engine-error)
[535.494852] Starting subtest: engine-engine-error
[535.499423] Starting dynamic subtest: rcs0
[535.581308] Dynamic subtest rcs0: SUCCESS (0.084s)
[535.607873] Starting dynamic subtest: bcs0
[535.610836] Dynamic subtest bcs0: SKIP (0.002s)
[535.641313] Starting dynamic subtest: vcs0
[535.667246] Dynamic subtest vcs0: SUCCESS (0.080s)
[535.696588] Starting dynamic subtest: vecs0
[535.746313] Dynamic subtest vecs0: SUCCESS (0.078s)
[535.747865] Subtest engine-engine-error: SUCCESS (0.252s)
[535.868309] [119/137] (507s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[536.157442] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[536.159901] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[536.269722] [120/137] (506s left) gem_exec_fair (basic-pace-share)
[536.477421] Starting subtest: basic-pace-share
[536.478984] Starting dynamic subtest: rcs0
[541.156628] Dynamic subtest rcs0: SUCCESS (4.679s)
[541.157996] Subtest basic-pace-share: SUCCESS (4.681s)
[541.351368] [121/137] (501s left) kms_scaling_modes (scaling-mode-full)
[541.631867] Starting subtest: scaling-mode-full
[541.634478] Subtest scaling-mode-full: SKIP (0.001s)
[541.718403] [122/137] (501s left) perf (disabled-read-error)
[541.993095] Starting subtest: disabled-read-error
[542.079074] Subtest disabled-read-error: SUCCESS (0.086s)
[542.134237] [123/137] (501s left) kms_atomic (atomic_plane_damage)
[542.539071] Starting subtest: atomic_plane_damage
[542.542039] Subtest atomic_plane_damage: SKIP (0.000s)
[542.975530] [124/137] (500s left) api_intel_bb (purge-bb)
[543.158346] Starting subtest: purge-bb
[543.165340] Subtest purge-bb: SUCCESS (0.009s)
[543.256985] [125/137] (500s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_dg2_rc_ccs)
[543.847269] Starting subtest: pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs
[543.849922] Subtest pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[544.263792] [126/137] (499s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[544.448437] Starting subtest: invalid-single-wait-all-unsubmitted
[544.450255] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[544.546424] [127/137] (498s left) i915_pm_rps (waitboost)
[544.734220] Starting subtest: waitboost
[547.298916] Subtest waitboost: SUCCESS (2.565s)
[547.396379] [128/137] (496s left) api_intel_allocator (alloc-simple)
[547.577810] Starting subtest: alloc-simple
[547.581778] Subtest alloc-simple: SUCCESS (0.004s)
[547.695381] [129/137] (495s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[547.989894] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[548.021161] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.030s)
[548.146978] [130/137] (495s left) gem_wait (invalid-buf)
[548.333952] Starting subtest: invalid-buf
[548.335315] Subtest invalid-buf: SUCCESS (0.000s)
[548.580385] [131/137] (494s left) kms_ccs (pipe-a-ccs-on-another-bo-yf_tiled_ccs)
[549.015537] Starting subtest: pipe-A-ccs-on-another-bo-yf_tiled_ccs
[549.017112] Subtest pipe-A-ccs-on-another-bo-yf_tiled_ccs: SUCCESS (0.001s)
[549.601350] [132/137] (494s left) kms_cursor_edge_walk (256x256-top-edge)
[549.878795] Starting subtest: 256x256-top-edge
[549.880089] Starting dynamic subtest: pipe-A-DP-1
[553.522228] Dynamic subtest pipe-A-DP-1: SUCCESS (3.639s)
[553.550782] Starting dynamic subtest: pipe-C-DP-1
[557.007588] Dynamic subtest pipe-C-DP-1: SUCCESS (3.480s)
[557.009578] Subtest 256x256-top-edge: SUCCESS (7.129s)
[557.335933] [133/137] (486s left) vc4/vc4_create_bo (create-bo-0)
[557.570751] Subtest create-bo-0: SKIP (0.000s)
[557.646353] [134/137] (486s left) kms_frontbuffer_tracking (fbc-1p-rte)
[557.932825] Starting subtest: fbc-1p-rte
[561.601262] Subtest fbc-1p-rte: SUCCESS (3.671s)
[561.763904] [135/137] (481s left) gem_softpin (allocator-evict)
[561.953716] Starting subtest: allocator-evict
[561.955271] Starting dynamic subtest: rcs0
[597.372487] Dynamic subtest rcs0: SUCCESS (35.417s)
[597.398421] Starting dynamic subtest: bcs0
[633.688492] Dynamic subtest bcs0: SUCCESS (36.315s)
[633.714235] Starting dynamic subtest: vcs0
[670.640518] Dynamic subtest vcs0: SUCCESS (36.951s)
[670.666489] Starting dynamic subtest: vecs0
[707.511772] Dynamic subtest vecs0: SUCCESS (36.870s)
[707.513625] Subtest allocator-evict: SUCCESS (145.557s)
[707.617699] [136/137] (336s left) gem_exec_schedule (fairslice-all)
[707.958346] Starting subtest: fairslice-all
[710.056823] Subtest fairslice-all: SUCCESS (2.098s)
[710.462395] [137/137] (333s left) kms_rotation_crc (cursor-rotation-180)
[710.753743] Starting subtest: cursor-rotation-180
[711.558531] Subtest cursor-rotation-180: SUCCESS (0.807s)
[711.961451] Closing watchdogs
Done.