[50.255773] Initializing watchdogs
[50.256221]   /dev/watchdog0
[50.274231] [001/137] (960s left) gem_exec_reloc (basic-scanout)
[50.616257] Starting subtest: basic-scanout
[50.617796] Starting dynamic subtest: rcs0
[51.092165] Dynamic subtest rcs0: SUCCESS (0.476s)
[51.105892] Starting dynamic subtest: bcs0
[51.257941] Dynamic subtest bcs0: SUCCESS (0.166s)
[51.271685] Starting dynamic subtest: vcs0
[51.425190] Dynamic subtest vcs0: SUCCESS (0.167s)
[51.440049] Starting dynamic subtest: vecs0
[51.613206] Dynamic subtest vecs0: SUCCESS (0.185s)
[51.614784] Subtest basic-scanout: SUCCESS (0.995s)
[51.726993] [002/137] (959s left) kms_sequence (get-busy)
[52.005296] Starting subtest: get-busy
[52.006627] Starting dynamic subtest: DP-1-pipe-A
[54.473710] Dynamic subtest DP-1-pipe-A: SUCCESS (2.469s)
[54.486435] Starting dynamic subtest: DP-1-pipe-B
[57.204868] Dynamic subtest DP-1-pipe-B: SUCCESS (2.730s)
[57.218111] Starting dynamic subtest: DP-1-pipe-C
[60.096652] Dynamic subtest DP-1-pipe-C: SUCCESS (2.890s)
[60.098821] Subtest get-busy: SUCCESS (8.093s)
[60.401515] [003/137] (950s left) gem_tiled_blits (basic)
[61.733232] Starting subtest: basic
[61.782383] Subtest basic: SUCCESS (0.050s)
[61.907655] [004/137] (948s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[62.318726] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[62.321313] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[62.402146] [005/137] (948s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[62.680579] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[62.682979] Starting dynamic subtest: pipe-A-valid-mode
[63.294817] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.615s)
[63.298153] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SUCCESS (0.617s)
[63.628626] [006/137] (947s left) gem_mmap_gtt (big-bo-tiledy)
[63.807760] Starting subtest: big-bo-tiledY
[64.540484] Subtest big-bo-tiledY: SUCCESS (0.733s)
[64.789039] [007/137] (946s left) kms_flip (2x-plain-flip-interruptible)
[65.184271] Starting subtest: 2x-plain-flip-interruptible
[65.188760] Subtest 2x-plain-flip-interruptible: SKIP (0.003s)
[65.279599] [008/137] (945s left) gem_pread (display)
[65.455997] Starting subtest: display
[69.976182] Subtest display: SUCCESS (4.520s)
[70.063592] [009/137] (940s left) syncobj_wait (wait-any-interrupted)
[70.256194] Starting subtest: wait-any-interrupted
[70.363248] Subtest wait-any-interrupted: SUCCESS (0.106s)
[70.443658] [010/137] (940s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[70.750725] Starting subtest: yf-tiled-8bpp-rotate-0
[70.753669] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[70.845309] [011/137] (940s left) kms_cursor_crc (cursor-random-64x21)
[71.123862] Starting subtest: cursor-random-64x21
[71.134107] Starting dynamic subtest: pipe-A-DP-1
[75.094763] Dynamic subtest pipe-A-DP-1: SUCCESS (3.961s)
[75.166057] Starting dynamic subtest: pipe-B-DP-1
[79.166683] Dynamic subtest pipe-B-DP-1: SUCCESS (4.014s)
[79.243743] Starting dynamic subtest: pipe-C-DP-1
[83.415450] Dynamic subtest pipe-C-DP-1: SUCCESS (4.188s)
[83.416779] Subtest cursor-random-64x21: SUCCESS (12.291s)
[83.766516] [012/137] (927s left) vc4/vc4_perfmon (get-values-invalid-pointer)
[83.998715] Subtest get-values-invalid-pointer: SKIP (0.000s)
[84.062507] [013/137] (927s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt)
[84.343155] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt
[84.345576] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[84.429240] [014/137] (926s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[84.726896] Starting subtest: x-tiled-32bpp-rotate-90
[84.795351] Subtest x-tiled-32bpp-rotate-90: SKIP (0.067s)
[84.912700] [015/137] (926s left) gem_exec_balancer (full-late-pulse)
[85.111284] Starting subtest: full-late-pulse
[89.025136] Subtest full-late-pulse: SUCCESS (3.913s)
[92.863257] [016/137] (918s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[93.141232] Starting subtest: fbcpsr-stridechange
[93.143802] Subtest fbcpsr-stridechange: SKIP (0.000s)
[93.230586] [017/137] (918s left) kms_ccs (pipe-a-crc-sprite-planes-basic-yf_tiled_ccs)
[93.660200] Starting subtest: pipe-A-crc-sprite-planes-basic-yf_tiled_ccs
[94.193858] Subtest pipe-A-crc-sprite-planes-basic-yf_tiled_ccs: SUCCESS (0.534s)
[94.781911] [018/137] (916s left) kms_busy (extended-modeset-hang-newfb)
[95.069840] Starting subtest: extended-modeset-hang-newfb
[95.072747] Starting dynamic subtest: pipe-A
[98.022055] Dynamic subtest pipe-A: SUCCESS (2.945s)
[98.039924] Starting dynamic subtest: pipe-C
[101.280990] Dynamic subtest pipe-C: SUCCESS (3.258s)
[101.282730] Subtest extended-modeset-hang-newfb: SUCCESS (6.211s)
[101.603477] [019/137] (909s left) vc4/vc4_tiling (get-bad-modifier)
[101.818419] Subtest get-bad-modifier: SKIP (0.000s)
[101.883281] [020/137] (909s left) tools_test (tools_test)
[102.060773] Starting subtest: tools_test
[102.430770] Subtest tools_test: SUCCESS (0.379s)
[102.500263] [021/137] (908s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[102.777641] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[102.780173] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[102.866996] [022/137] (908s left) i915_pm_rpm (pm-caching)
[104.737420] Starting subtest: pm-caching
[106.372067] Subtest pm-caching: SUCCESS (1.634s)
[106.832315] [023/137] (904s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[107.105746] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[107.106992] Starting dynamic subtest: pipe-A-valid-mode
[107.472505] Dynamic subtest pipe-A-valid-mode: SKIP (0.365s)
[107.475164] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SKIP (0.367s)
[107.862419] [024/137] (903s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_dg2_mc_ccs)
[108.309738] Starting subtest: pipe-B-bad-rotation-90-4_tiled_dg2_mc_ccs
[108.312833] Subtest pipe-B-bad-rotation-90-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[108.800383] [025/137] (902s left) kms_plane (plane-panning-bottom-right-suspend)
[109.073618] Starting subtest: plane-panning-bottom-right-suspend
[109.075097] Starting dynamic subtest: pipe-A-planes
[126.590734] Dynamic subtest pipe-A-planes: SUCCESS (2.403s)
[126.611459] Starting dynamic subtest: pipe-B-planes
[145.547691] Dynamic subtest pipe-B-planes: SUCCESS (2.323s)
[145.549080] Subtest plane-panning-bottom-right-suspend: SUCCESS (4.732s)
[150.890176] [026/137] (897s left) kms_cursor_legacy (single-bo)
[151.173773] Starting subtest: single-bo
[151.175097] Starting dynamic subtest: pipe-A
[172.678471] Dynamic subtest pipe-A: SUCCESS (21.506s)
[172.699234] Starting dynamic subtest: pipe-B
[194.182254] Dynamic subtest pipe-B: SUCCESS (21.503s)
[194.201810] Starting dynamic subtest: pipe-C
[215.686467] Dynamic subtest pipe-C: SUCCESS (21.503s)
[215.708969] Starting dynamic subtest: all-pipes
[237.195275] Dynamic subtest all-pipes: SUCCESS (21.508s)
[237.197101] Subtest single-bo: SUCCESS (86.023s)
[237.280827] [027/137] (811s left) prime_busy (hang)
[237.460072] Starting subtest: hang
[237.462405] Starting dynamic subtest: rcs0
[244.201525] Dynamic subtest rcs0: SUCCESS (6.740s)
[244.223270] Starting dynamic subtest: bcs0
[251.176176] Dynamic subtest bcs0: SUCCESS (6.971s)
[251.198430] Starting dynamic subtest: vcs0
[258.215116] Dynamic subtest vcs0: SUCCESS (7.039s)
[258.235255] Starting dynamic subtest: vecs0
[265.190790] Dynamic subtest vecs0: SUCCESS (6.975s)
[265.192271] Subtest hang: SUCCESS (27.729s)
[265.297995] [028/137] (783s left) gem_mmap_gtt (basic-write-read)
[265.469480] Starting subtest: basic-write-read
[265.498829] Subtest basic-write-read: SUCCESS (0.031s)
[265.632686] [029/137] (782s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[265.932931] Starting subtest: 4-tiled-8bpp-rotate-90
[265.935880] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.000s)
[266.033111] [030/137] (782s left) gem_exec_reloc (basic-wc-read)
[266.218527] Starting subtest: basic-wc-read
[266.237830] Subtest basic-wc-read: SUCCESS (0.019s)
[266.416646] [031/137] (782s left) gem_partial_pwrite_pread (write-uncached)
[266.604276] Starting subtest: write-uncached
[280.522666] Subtest write-uncached: SUCCESS (13.919s)
[280.632876] [032/137] (767s left) gem_exec_fence (syncobj-timeline-unused-fence)
[281.441439] Starting subtest: syncobj-timeline-unused-fence
[281.446633] Subtest syncobj-timeline-unused-fence: SUCCESS (0.006s)
[281.669670] [033/137] (766s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[281.959493] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[281.961942] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[282.051129] [034/137] (766s left) gem_mmap_wc (bad-size)
[282.230798] Starting subtest: bad-size
[282.232682] Subtest bad-size: SUCCESS (0.002s)
[282.333935] [035/137] (766s left) gem_ctx_param (get-priority-new-ctx)
[282.508075] Starting subtest: get-priority-new-ctx
[282.509670] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[282.601535] [036/137] (766s left) gem_ctx_shared (exec-single-timeline)
[282.798731] Starting subtest: exec-single-timeline
[282.800352] Starting dynamic subtest: rcs0
[283.878100] Dynamic subtest rcs0: SUCCESS (1.077s)
[283.906336] Starting dynamic subtest: bcs0
[284.965581] Dynamic subtest bcs0: SUCCESS (1.086s)
[284.995947] Starting dynamic subtest: vcs0
[286.052690] Dynamic subtest vcs0: SUCCESS (1.087s)
[286.082990] Starting dynamic subtest: vecs0
[287.141233] Dynamic subtest vecs0: SUCCESS (1.087s)
[287.142676] Subtest exec-single-timeline: SUCCESS (4.342s)
[287.367531] [037/137] (761s left) kms_frontbuffer_tracking (fbc-suspend)
[287.647799] Starting subtest: fbc-suspend
[325.647333] Subtest fbc-suspend: SUCCESS (6.345s)
[327.022735] [038/137] (754s left) api_intel_bb (blit-reloc-purge-cache)
[327.201605] Starting subtest: blit-reloc-purge-cache
[327.222950] Subtest blit-reloc-purge-cache: SUCCESS (0.022s)
[327.336644] [039/137] (754s left) i915_hangman (error-state-basic)
[327.529924] Starting subtest: error-state-basic
[327.550877] Subtest error-state-basic: SUCCESS (0.021s)
[327.636372] [040/137] (753s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_rc_ccs_cc)
[328.084259] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc
[328.086927] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.001s)
[328.567432] [041/137] (753s left) api_intel_bb (reset-bb)
[328.746165] Starting subtest: reset-bb
[328.752947] Subtest reset-bb: SUCCESS (0.009s)
[328.865910] [042/137] (752s left) i915_query (query-regions-garbage-items)
[329.040448] Starting subtest: query-regions-garbage-items
[329.042273] Subtest query-regions-garbage-items: SUCCESS (0.001s)
[329.138664] [043/137] (752s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[329.344753] Starting subtest: single-wait-for-submit-available-signaled
[329.346181] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.001s)
[329.436385] [044/137] (752s left) gem_media_vme
[329.672075] [045/137] (752s left) syncobj_timeline (invalid-signal-illegal-handle)
[329.849468] Starting subtest: invalid-signal-illegal-handle
[329.850995] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[329.934118] [046/137] (751s left) gem_tiled_partial_pwrite_pread (writes)
[330.116514] Starting subtest: writes
[332.181601] Subtest writes: SUCCESS (2.065s)
[332.283114] [047/137] (749s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-render)
[332.566894] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-render
[334.461954] Subtest fbc-1p-offscren-pri-shrfb-draw-render: SUCCESS (1.898s)
[334.598532] [048/137] (747s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[334.960841] Starting subtest: addfb25-yf-tiled-legacy
[334.962166] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[335.152394] [049/137] (746s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[335.430289] Starting subtest: addfb25-framebuffer-vs-set-tiling
[335.431493] Subtest addfb25-framebuffer-vs-set-tiling: SUCCESS (0.000s)
[335.720717] [050/137] (746s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_rc_ccs)
[336.084765] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs
[336.089663] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs: SKIP (0.002s)
[336.657477] [051/137] (745s left) api_intel_allocator (gem-pool)
[336.831784] Starting subtest: gem-pool
[336.850221] Subtest gem-pool: SUCCESS (0.017s)
[336.940733] [052/137] (745s left) gem_exec_schedule (u-fairslice-all)
[337.142367] Starting subtest: u-fairslice-all
[339.249296] Subtest u-fairslice-all: SUCCESS (2.106s)
[339.591911] [053/137] (742s left) kms_plane_scaling (plane-upscale-with-pixel-format-factor-0-25)
[339.869979] Starting subtest: plane-upscale-with-pixel-format-factor-0-25
[339.871675] Starting dynamic subtest: pipe-A-DP-1
[340.499278] Dynamic subtest pipe-A-DP-1: SUCCESS (0.630s)
[340.523948] Starting dynamic subtest: pipe-B-DP-1
[341.407054] Dynamic subtest pipe-B-DP-1: SUCCESS (0.904s)
[341.430916] Starting dynamic subtest: pipe-C-DP-1
[341.762152] Dynamic subtest pipe-C-DP-1: SUCCESS (0.352s)
[341.763581] Subtest plane-upscale-with-pixel-format-factor-0-25: SUCCESS (1.896s)
[342.165196] [054/137] (740s left) gem_userptr_blits (sd-probe)
[342.350619] Starting subtest: sd-probe
[342.351846] Subtest sd-probe: SUCCESS (0.001s)
[342.499091] [055/137] (739s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[342.785283] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[342.788306] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[342.881455] [056/137] (739s left) gem_exec_create (legacy)
[343.074537] Starting subtest: legacy
[343.075808] Starting dynamic subtest: smem
[345.128991] Dynamic subtest smem: SUCCESS (2.056s)
[345.130356] Subtest legacy: SUCCESS (2.056s)
[345.331950] [057/137] (736s left) kms_ccs (pipe-c-random-ccs-data-y_tiled_ccs)
[345.894510] Starting subtest: pipe-C-random-ccs-data-y_tiled_ccs
[345.898819] Subtest pipe-C-random-ccs-data-y_tiled_ccs: SKIP (0.000s)
[346.266695] [058/137] (736s left) gem_close_race (multigpu-basic-threads)
[346.466286] Starting subtest: multigpu-basic-threads
[346.468877] Subtest multigpu-basic-threads: SKIP (0.000s)
[346.664941] [059/137] (735s left) kms_ccs (pipe-d-bad-rotation-90-y_tiled_ccs)
[347.277318] Subtest pipe-D-bad-rotation-90-y_tiled_ccs: SKIP (0.000s)
[347.575775] [060/137] (734s left) gem_ppgtt (blt-vs-render-ctx0)
[347.759572] Starting subtest: blt-vs-render-ctx0
[380.692867] Subtest blt-vs-render-ctx0: SUCCESS (32.935s)
[380.812219] [061/137] (701s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_gen12_rc_ccs)
[381.433481] Starting subtest: pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs
[381.437415] Subtest pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[381.905771] [062/137] (700s left) gem_exec_reloc (basic-write-gtt-active)
[382.093632] Starting subtest: basic-write-gtt-active
[382.235765] Subtest basic-write-gtt-active: SUCCESS (0.141s)
[382.423942] [063/137] (700s left) gem_exec_reloc (basic-gtt-read)
[382.611179] Starting subtest: basic-gtt-read
[382.632592] Subtest basic-gtt-read: SUCCESS (0.021s)
[382.822523] [064/137] (699s left) kms_psr (psr2_suspend)
[382.998109] Subtest psr2_suspend: SKIP (0.000s)
[383.058624] [065/137] (699s left) kms_ccs (pipe-d-random-ccs-data-yf_tiled_ccs)
[383.702832] Subtest pipe-D-random-ccs-data-yf_tiled_ccs: SKIP (0.000s)
[384.014739] [066/137] (698s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[384.288626] Starting subtest: bo-too-small-due-to-tiling
[384.289853] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[384.598277] [067/137] (698s left) kms_invalid_mode (bad-hsync-start)
[384.874952] Starting subtest: bad-hsync-start
[384.876684] Starting dynamic subtest: DP-1-pipe-A
[384.965503] Dynamic subtest DP-1-pipe-A: SUCCESS (0.090s)
[384.993551] Starting dynamic subtest: DP-1-pipe-B
[384.995177] Dynamic subtest DP-1-pipe-B: SUCCESS (0.014s)
[385.018372] Starting dynamic subtest: DP-1-pipe-C
[385.019609] Dynamic subtest DP-1-pipe-C: SUCCESS (0.014s)
[385.020913] Subtest bad-hsync-start: SUCCESS (0.126s)
[385.340350] [068/137] (697s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[385.641573] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[385.644146] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[385.757599] [069/137] (697s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[386.041497] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[386.044082] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[386.140627] [070/137] (696s left) kms_content_protection (lic)
[386.447688] Starting subtest: LIC
[386.485221] Starting dynamic subtest: pipe-A-DP-1
[507.132077] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[507.856713] Closing watchdogs
[507.863292] Initializing watchdogs
[507.863479]   /dev/watchdog0
[507.890622] [071/137] (574s left) v3d/v3d_mmap (mmap-bad-handle)
[508.150662] Subtest mmap-bad-handle: SKIP (0.000s)
[508.224347] [072/137] (574s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[508.582431] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[508.870944] [073/137] (574s left) i915_pm_rc6_residency (rc6-fence)
[509.044288] Starting subtest: rc6-fence
[524.290318] Subtest rc6-fence: SUCCESS (15.248s)
[524.543515] [074/137] (558s left) kms_flip (2x-plain-flip-ts-check)
[524.802740] Starting subtest: 2x-plain-flip-ts-check
[524.805173] Subtest 2x-plain-flip-ts-check: SKIP (0.003s)
[524.912163] [075/137] (558s left) gem_ctx_param (invalid-get-engines)
[525.085445] Starting subtest: invalid-get-engines
[525.087240] Subtest invalid-get-engines: SUCCESS (0.000s)
[525.178718] [076/137] (557s left) gem_exec_fence (basic-busy-all)
[525.374973] Starting subtest: basic-busy-all
[525.403547] Subtest basic-busy-all: SUCCESS (0.030s)
[526.178262] [077/137] (556s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[526.354256] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[526.460406] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[526.543807] [078/137] (556s left) drm_fdinfo (busy-idle-check-all)
[526.725632] Starting subtest: busy-idle-check-all
[526.727077] Starting dynamic subtest: rcs0
[527.356647] Dynamic subtest rcs0: SUCCESS (0.629s)
[527.381098] Starting dynamic subtest: bcs0
[527.988690] Dynamic subtest bcs0: SUCCESS (0.631s)
[528.011766] Starting dynamic subtest: vcs0
[528.622270] Dynamic subtest vcs0: SUCCESS (0.632s)
[528.646253] Starting dynamic subtest: vecs0
[529.258229] Dynamic subtest vecs0: SUCCESS (0.634s)
[529.259668] Subtest busy-idle-check-all: SUCCESS (2.533s)
[529.344517] [079/137] (553s left) perf_pmu (faulting-read)
[529.526918] Starting subtest: faulting-read
[529.528436] Starting dynamic subtest: gtt
[529.533050] Dynamic subtest gtt: SUCCESS (0.005s)
[529.557586] Starting dynamic subtest: wb
[529.558954] Dynamic subtest wb: SUCCESS (0.003s)
[529.581317] Starting dynamic subtest: wc
[529.582599] Dynamic subtest wc: SUCCESS (0.001s)
[529.604741] Starting dynamic subtest: uc
[529.606058] Dynamic subtest uc: SUCCESS (0.001s)
[529.607252] Subtest faulting-read: SUCCESS (0.016s)
[529.643920] [080/137] (553s left) gem_render_copy (yf-tiled-to-vebox-linear)
[529.833475] Starting subtest: yf-tiled-to-vebox-linear
[529.835951] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[530.027264] [081/137] (553s left) prime_mmap (test_map_unmap)
[530.201718] Starting subtest: test_map_unmap
[530.203093] Starting dynamic subtest: test_map_unmap-smem
[530.210087] Dynamic subtest test_map_unmap-smem: SUCCESS (0.007s)
[530.211557] Subtest test_map_unmap: SUCCESS (0.008s)
[530.310653] [082/137] (553s left) perf_pmu (invalid-init)
[530.490698] Starting subtest: invalid-init
[530.492159] Subtest invalid-init: SUCCESS (0.001s)
[530.577561] [083/137] (552s left) kms_ccs (pipe-d-crc-primary-basic-y_tiled_gen12_rc_ccs)
[531.188837] Subtest pipe-D-crc-primary-basic-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[531.505624] [084/137] (551s left) syncobj_timeline (multi-wait-submitted-signaled)
[531.682713] Starting subtest: multi-wait-submitted-signaled
[531.687101] Subtest multi-wait-submitted-signaled: SUCCESS (0.002s)
[531.772876] [085/137] (551s left) gen7_exec_parse (basic-rejected)
[531.952777] Subtest basic-rejected: SKIP (0.000s)
[532.007665] [086/137] (551s left) gem_lmem_swapping (heavy-multi)
[532.186353] Subtest heavy-multi: SKIP (0.000s)
[532.241687] [087/137] (551s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[532.426949] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[532.429647] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[532.622335] [088/137] (550s left) gem_exec_schedule (preempt-engines)
[532.824754] Starting subtest: preempt-engines
[532.826364] Starting dynamic subtest: rcs0
[533.113630] Dynamic subtest rcs0: SUCCESS (0.287s)
[533.139106] Starting dynamic subtest: bcs0
[533.506641] Dynamic subtest bcs0: SUCCESS (0.392s)
[533.537449] Starting dynamic subtest: vcs0
[533.758966] Dynamic subtest vcs0: SUCCESS (0.251s)
[533.790928] Starting dynamic subtest: vecs0
[534.023219] Dynamic subtest vecs0: SUCCESS (0.263s)
[534.024666] Subtest preempt-engines: SUCCESS (1.198s)
[534.372830] [089/137] (549s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[534.660419] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[534.662947] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[534.755518] [090/137] (548s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[535.043007] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[554.221851] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (19.175s)
[554.555328] [091/137] (529s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[554.838425] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[554.840993] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[554.936645] [092/137] (528s left) gem_exec_reloc (basic-wc-noreloc)
[555.120500] Starting subtest: basic-wc-noreloc
[555.131208] Subtest basic-wc-noreloc: SUCCESS (0.010s)
[555.319844] [093/137] (528s left) kms_properties (plane-properties-atomic)
[555.589678] Starting subtest: plane-properties-atomic
[556.630458] Subtest plane-properties-atomic: SUCCESS (1.044s)
[556.980457] [094/137] (526s left) fbdev (write)
[557.171000] Starting subtest: write
[557.899973] Subtest write: SUCCESS (0.729s)
[557.978195] [095/137] (525s left) kms_cursor_crc (cursor-sliding-512x512)
[558.272684] Starting subtest: cursor-sliding-512x512
[558.275609] Subtest cursor-sliding-512x512: SKIP (0.002s)
[558.379135] [096/137] (525s left) kms_content_protection (dp-mst-type-0)
[558.682349] Starting subtest: dp-mst-type-0
[558.684900] Subtest dp-mst-type-0: SKIP (0.001s)
[558.794614] [097/137] (525s left) kms_panel_fitting (atomic-fastset)
[559.070423] Starting subtest: atomic-fastset
[559.072943] Subtest atomic-fastset: SKIP (0.001s)
[559.161779] [098/137] (524s left) v3d/v3d_submit_csd (multiple-job-submission)
[559.404350] Subtest multiple-job-submission: SKIP (0.000s)
[559.478346] [099/137] (524s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[559.779999] Starting subtest: 4-tiled-64bpp-rotate-270
[559.782932] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.000s)
[559.879225] [100/137] (524s left) gem_pxp (regular-baseline-src-copy-readible)
[562.571882] Subtest regular-baseline-src-copy-readible: SKIP (0.000s)
[562.661462] [101/137] (521s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[562.836142] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[562.940108] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[563.028274] [102/137] (521s left) v3d/v3d_get_bo_offset (create-get-offsets)
[563.253961] Subtest create-get-offsets: SKIP (0.000s)
[563.328286] [103/137] (520s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[563.628584] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[564.718446] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (1.090s)
[565.031931] [104/137] (519s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[565.337858] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[565.344229] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.003s)
[565.428854] [105/137] (518s left) perf (gen12-group-concurrent-oa-buffer-read)
[565.707437] Subtest gen12-group-concurrent-oa-buffer-read: SKIP (0.000s)
[565.758870] [106/137] (518s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[566.039849] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[566.042738] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[566.145584] [107/137] (518s left) v3d/v3d_submit_cl (multi-and-single-sync)
[566.387692] Subtest multi-and-single-sync: SKIP (0.000s)
[566.461703] [108/137] (517s left) kms_psr2_sf (cursor-plane-move-continuous-exceed-sf)
[566.637544] Subtest cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[566.697574] [109/137] (517s left) prime_vgem (basic-blt)
[566.881483] Starting subtest: basic-blt
[566.934414] Subtest basic-blt: SUCCESS (0.053s)
[567.044956] [110/137] (517s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[567.344445] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[567.370715] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.025s)
[567.495378] [111/137] (516s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[567.775122] Starting subtest: plane-all-modeset-transition-internal-panels
[567.778073] Subtest plane-all-modeset-transition-internal-panels: SKIP (0.000s)
[567.862676] [112/137] (516s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move)
[568.150507] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move
[568.153155] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[568.262179] [113/137] (516s left) kms_vblank (pipe-c-wait-busy)
[568.534015] Starting subtest: pipe-C-wait-busy
[571.464498] Subtest pipe-C-wait-busy: SUCCESS (2.932s)
[571.802658] [114/137] (512s left) kms_vblank (pipe-b-ts-continuation-dpms-rpm)
[572.074788] Starting subtest: pipe-B-ts-continuation-dpms-rpm
[574.250979] Subtest pipe-B-ts-continuation-dpms-rpm: SUCCESS (2.173s)
[574.720343] [115/137] (509s left) kms_chamelium_frames (dp-crc-fast)
[575.077260] Subtest dp-crc-fast: SKIP (0.000s)
[575.354792] [116/137] (509s left) kms_ccs (pipe-c-crc-primary-rotation-180-y_tiled_ccs)
[575.915896] Starting subtest: pipe-C-crc-primary-rotation-180-y_tiled_ccs
[575.921575] Subtest pipe-C-crc-primary-rotation-180-y_tiled_ccs: SKIP (0.002s)
[576.302736] [117/137] (508s left) gem_userptr_blits (unsync-overlap)
[576.491234] Subtest unsync-overlap: SKIP (0.000s)
[576.636017] [118/137] (508s left) gem_ctx_persistence (legacy-engines-mixed)
[576.820362] Starting subtest: legacy-engines-mixed
[576.824994] Starting dynamic subtest: render
[576.865707] Dynamic subtest render: SUCCESS (0.043s)
[576.892592] Starting dynamic subtest: bsd
[576.918784] Dynamic subtest bsd: SUCCESS (0.051s)
[576.949329] Starting dynamic subtest: blt
[576.965874] Dynamic subtest blt: SUCCESS (0.045s)
[576.992098] Starting dynamic subtest: vebox
[577.015611] Dynamic subtest vebox: SUCCESS (0.048s)
[577.017705] Subtest legacy-engines-mixed: SUCCESS (0.196s)
[577.120526] [119/137] (507s left) kms_flip (wf_vblank-ts-check-interruptible)
[577.392905] Starting subtest: wf_vblank-ts-check-interruptible
[577.398599] Starting dynamic subtest: A-DP1
[588.213310] Dynamic subtest A-DP1: SUCCESS (10.814s)
[588.241408] Starting dynamic subtest: B-DP1
[598.945509] Dynamic subtest B-DP1: SUCCESS (10.729s)
[598.971751] Starting dynamic subtest: C-DP1
[609.659130] Dynamic subtest C-DP1: SUCCESS (10.710s)
[609.660522] Subtest wf_vblank-ts-check-interruptible: SUCCESS (32.265s)
[610.061560] [120/137] (474s left) kms_vblank (pipe-c-wait-idle-hang)
[610.341230] Starting subtest: pipe-C-wait-idle-hang
[617.962310] Subtest pipe-C-wait-idle-hang: SUCCESS (7.623s)
[618.305532] [121/137] (466s left) kms_plane_scaling (planes-downscale-factor-0-25-upscale-0-25)
[618.575443] Starting subtest: planes-downscale-factor-0-25-upscale-0-25
[618.576770] Starting dynamic subtest: pipe-A-DP-1
[618.714809] Dynamic subtest pipe-A-DP-1: SKIP (0.138s)
[618.738386] Starting dynamic subtest: pipe-B-DP-1
[618.826383] Dynamic subtest pipe-B-DP-1: SKIP (0.109s)
[618.853505] Starting dynamic subtest: pipe-C-DP-1
[618.940403] Dynamic subtest pipe-C-DP-1: SKIP (0.110s)
[618.942773] Subtest planes-downscale-factor-0-25-upscale-0-25: SKIP (0.367s)
[619.056958] [122/137] (465s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[619.336149] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[619.338665] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[619.437641] [123/137] (465s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[619.723792] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[619.726594] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[619.821343] [124/137] (465s left) gem_exec_flush (basic-wb-pro-default)
[620.015842] Starting subtest: basic-wb-pro-default
[625.484394] Subtest basic-wb-pro-default: SUCCESS (5.468s)
[625.688669] [125/137] (459s left) kms_lease (invalid-create-leases)
[625.961704] Starting subtest: invalid-create-leases
[625.973895] Subtest invalid-create-leases: SUCCESS (0.015s)
[626.087394] [126/137] (459s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc)
[626.372453] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc
[628.212797] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (1.843s)
[628.337420] [127/137] (456s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[628.513183] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[628.575320] [128/137] (456s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[628.851991] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[628.854858] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[628.954451] [129/137] (456s left) syncobj_wait (invalid-wait-zero-handles)
[629.128466] Starting subtest: invalid-wait-zero-handles
[629.129795] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[629.220916] [130/137] (456s left) sw_sync (alloc_fence_invalid_timeline)
[629.393278] Starting subtest: alloc_fence_invalid_timeline
[629.394839] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[629.469763] [131/137] (455s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[629.644732] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[629.646453] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[629.736231] [132/137] (455s left) kms_addfb_basic (bad-pitch-63)
[629.910908] Starting subtest: bad-pitch-63
[629.912261] Subtest bad-pitch-63: SUCCESS (0.000s)
[630.304889] [133/137] (455s left) i915_hangman (detector)
[630.502884] Starting subtest: detector
[630.504308] Starting dynamic subtest: rcs0
[640.215763] Dynamic subtest rcs0: SUCCESS (9.714s)
[640.243344] Starting dynamic subtest: bcs0
[650.292881] Dynamic subtest bcs0: SUCCESS (10.076s)
[650.320112] Starting dynamic subtest: vcs0
[660.276926] Dynamic subtest vcs0: SUCCESS (9.983s)
[660.301786] Starting dynamic subtest: vecs0
[670.221871] Dynamic subtest vecs0: SUCCESS (9.944s)
[670.223334] Subtest detector: SUCCESS (39.721s)
[670.341566] [134/137] (415s left) gem_exec_basic (basic)
[670.526968] Starting subtest: basic
[670.528607] Starting dynamic subtest: rcs0-smem
[670.530278] Dynamic subtest rcs0-smem: SUCCESS (0.002s)
[670.554348] Starting dynamic subtest: bcs0-smem
[670.555663] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[670.578106] Starting dynamic subtest: vcs0-smem
[670.579450] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[670.602191] Starting dynamic subtest: vecs0-smem
[670.603687] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[670.604987] Subtest basic: SUCCESS (0.012s)
[670.753515] [135/137] (414s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[671.023353] Starting subtest: disable-crc-after-crtc
[671.024630] Starting dynamic subtest: pipe-A-DP-1
[671.706422] Dynamic subtest pipe-A-DP-1: SUCCESS (0.683s)
[671.736279] Starting dynamic subtest: pipe-B-DP-1
[672.694888] Dynamic subtest pipe-B-DP-1: SUCCESS (0.984s)
[672.728361] Starting dynamic subtest: pipe-C-DP-1
[673.858842] Dynamic subtest pipe-C-DP-1: SUCCESS (1.158s)
[673.864394] Subtest disable-crc-after-crtc: SUCCESS (2.836s)
[674.191436] [136/137] (411s left) gem_exec_store (dword)
[674.384378] Starting subtest: dword
[674.389273] Starting dynamic subtest: rcs0
[674.429967] Dynamic subtest rcs0: SUCCESS (0.043s)
[674.461968] Starting dynamic subtest: bcs0
[674.463335] Dynamic subtest bcs0: SUCCESS (0.017s)
[674.491227] Starting dynamic subtest: vcs0
[674.492470] Dynamic subtest vcs0: SUCCESS (0.020s)
[674.515238] Starting dynamic subtest: vecs0
[674.516560] Dynamic subtest vecs0: SUCCESS (0.019s)
[674.517762] Subtest dword: SUCCESS (0.105s)
[674.691742] [137/137] (410s left) syncobj_wait (wait-all-snapshot)
[674.864662] Starting subtest: wait-all-snapshot
[674.946808] Subtest wait-all-snapshot: SUCCESS (0.082s)
[675.036881] Closing watchdogs
Done.