[47.649592] Initializing watchdogs
[47.649882]   /dev/watchdog0
[47.666930] [001/137] (960s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[47.989371] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[50.278185] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (2.298s)
[50.432654] [002/137] (957s left) prime_self_import (reimport-vs-gem_close-race)
[50.618265] Starting subtest: reimport-vs-gem_close-race
[55.646387] Subtest reimport-vs-gem_close-race: SUCCESS (5.027s)
[55.726366] [003/137] (952s left) gen3_render_tiledy_blits
[55.962255] [004/137] (952s left) gem_create (create-ext-placement-sanity-check)
[56.270860] Starting subtest: create-ext-placement-sanity-check
[56.278055] Subtest create-ext-placement-sanity-check: SUCCESS (0.007s)
[56.358598] [005/137] (951s left) kms_plane_scaling (plane-downscale-with-rotation-factor-0-25)
[56.653928] Starting subtest: plane-downscale-with-rotation-factor-0-25
[56.655929] Starting dynamic subtest: pipe-A-DP-1
[56.704265] Dynamic subtest pipe-A-DP-1: SKIP (0.048s)
[56.717621] Starting dynamic subtest: pipe-B-DP-1
[56.799553] Dynamic subtest pipe-B-DP-1: SKIP (0.094s)
[56.813096] Starting dynamic subtest: pipe-C-DP-1
[56.893064] Dynamic subtest pipe-C-DP-1: SKIP (0.089s)
[56.896813] Subtest plane-downscale-with-rotation-factor-0-25: SKIP (0.239s)
[56.993947] [006/137] (951s left) kms_ccs (pipe-a-bad-rotation-90-y_tiled_ccs)
[57.381226] Starting subtest: pipe-A-bad-rotation-90-y_tiled_ccs
[58.022059] Subtest pipe-A-bad-rotation-90-y_tiled_ccs: SUCCESS (0.635s)
[58.729452] [007/137] (949s left) gem_ctx_param (set-priority-not-supported)
[58.936874] Starting subtest: set-priority-not-supported
[58.941147] Subtest set-priority-not-supported: SKIP (0.000s)
[59.002817] [008/137] (949s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[59.299170] Starting subtest: fbcpsr-2p-rte
[59.303126] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[59.377431] [009/137] (949s left) gem_ctx_exec (basic-norecovery)
[59.564849] Starting subtest: basic-norecovery
[59.599853] Subtest basic-norecovery: SUCCESS (0.035s)
[59.680124] [010/137] (948s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[59.969177] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[59.972982] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[60.060712] [011/137] (948s left) gen9_exec_parse (basic-rejected)
[60.280162] Starting subtest: basic-rejected
[60.282305] Subtest basic-rejected: SUCCESS (0.003s)
[60.494367] [012/137] (948s left) vgem_basic (dmabuf-export)
[60.763057] Starting subtest: dmabuf-export
[60.778255] Subtest dmabuf-export: SUCCESS (0.014s)
[60.867635] [013/137] (947s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[61.160874] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[63.295762] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (2.141s)
[63.433997] [014/137] (945s left) api_intel_allocator (reopen)
[63.620924] Starting subtest: reopen
[63.641328] Subtest reopen: SUCCESS (0.019s)
[63.746639] [015/137] (944s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[64.040957] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[64.044917] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[64.127665] [016/137] (944s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[64.407342] Starting subtest: cursorA-vs-flipA-legacy
[75.411187] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.006s)
[75.741831] [017/137] (932s left) kms_chamelium_hpd (hdmi-hpd-storm-disable)
[76.107120] Subtest hdmi-hpd-storm-disable: SKIP (0.000s)
[76.385347] [018/137] (932s left) i915_query (query-topology-known-pci-ids)
[76.566632] Starting subtest: query-topology-known-pci-ids
[76.570662] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[76.643338] [019/137] (932s left) gem_partial_pwrite_pread (write-display)
[76.838069] Starting subtest: write-display
[90.642863] Subtest write-display: SUCCESS (13.807s)
[90.791383] [020/137] (918s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[91.166073] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[91.189041] Starting dynamic subtest: pipe-A-valid-mode
[91.804516] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.661s)
[91.806506] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SUCCESS (0.664s)
[92.110796] [021/137] (916s left) kms_rotation_crc (sprite-rotation-90)
[92.395745] Starting subtest: sprite-rotation-90
[98.520475] Subtest sprite-rotation-90: SUCCESS (6.128s)
[98.942813] [022/137] (909s left) kms_vblank (pipe-c-query-forked-busy)
[99.228796] Starting subtest: pipe-C-query-forked-busy
[102.175292] Subtest pipe-C-query-forked-busy: SUCCESS (2.949s)
[102.493615] [023/137] (906s left) kms_cursor_crc (cursor-onscreen-128x128)
[102.784931] Starting subtest: cursor-onscreen-128x128
[102.796156] Starting dynamic subtest: pipe-A-DP-1
[105.125146] Dynamic subtest pipe-A-DP-1: SUCCESS (2.327s)
[105.196713] Starting dynamic subtest: pipe-C-DP-1
[107.802962] Dynamic subtest pipe-C-DP-1: SUCCESS (2.621s)
[107.804881] Subtest cursor-onscreen-128x128: SUCCESS (5.018s)
[108.147086] [024/137] (900s left) gem_mmap_gtt (basic-wc)
[108.331171] Starting subtest: basic-wc
[108.962975] Subtest basic-wc: SUCCESS (0.631s)
[109.061529] [025/137] (899s left) gem_exec_schedule (smoketest-all)
[109.271394] Starting subtest: smoketest-all
[141.567714] Subtest smoketest-all: SUCCESS (32.288s)
[141.895947] [026/137] (867s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[142.095486] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[142.099298] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[142.277362] [027/137] (866s left) kms_vrr (flip-basic)
[142.559688] Starting subtest: flip-basic
[142.563815] Subtest flip-basic: SKIP (0.000s)
[142.613434] [028/137] (866s left) syncobj_wait (invalid-reset-zero-handles)
[142.795255] Starting subtest: invalid-reset-zero-handles
[142.797553] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[142.877922] [029/137] (866s left) i915_pm_rpm (debugfs-read)
[144.748823] Starting subtest: debugfs-read
[148.645263] Subtest debugfs-read: SUCCESS (3.894s)
[149.198135] [030/137] (860s left) gem_exec_reloc (basic-gtt-active)
[149.503303] Starting subtest: basic-gtt-active
[149.626207] Subtest basic-gtt-active: SUCCESS (0.142s)
[149.851927] [031/137] (859s left) kms_flip (absolute-wf_vblank-interruptible)
[150.173100] Starting subtest: absolute-wf_vblank-interruptible
[150.177744] Starting dynamic subtest: A-DP1
[160.855546] Dynamic subtest A-DP1: SUCCESS (10.679s)
[160.872296] Starting dynamic subtest: B-DP1
[171.287786] Dynamic subtest B-DP1: SUCCESS (10.430s)
[171.304317] Starting dynamic subtest: C-DP1
[181.729693] Dynamic subtest C-DP1: SUCCESS (10.440s)
[181.731812] Subtest absolute-wf_vblank-interruptible: SUCCESS (31.557s)
[182.151330] [032/137] (827s left) gem_exec_await (wide-all)
[182.524791] Starting subtest: wide-all
[205.355423] Subtest wide-all: SUCCESS (22.830s)
[205.583114] [033/137] (803s left) i915_module_load (reload)
[205.871615] Starting subtest: reload
[209.171293] Subtest reload: SUCCESS (3.300s)
[209.213239] [034/137] (800s left) kms_ccs (pipe-c-bad-aux-stride-y_tiled_gen12_mc_ccs)
[209.848215] Starting subtest: pipe-C-bad-aux-stride-y_tiled_gen12_mc_ccs
[209.852557] Subtest pipe-C-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[210.156691] [035/137] (799s left) kms_getfb (getfb2-handle-zero)
[210.340229] Starting subtest: getfb2-handle-zero
[210.342631] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[210.427123] [036/137] (799s left) kms_chamelium_edid (dp-edid-read)
[210.802758] Subtest dp-edid-read: SKIP (0.000s)
[211.083248] [037/137] (798s left) gem_workarounds (basic-read)
[211.314050] Starting subtest: basic-read
[211.342610] Subtest basic-read: SUCCESS (0.029s)
[211.458559] [038/137] (798s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[211.753311] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[214.422330] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (2.674s)
[214.542954] [039/137] (795s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[214.833971] Starting subtest: fbc-rgb565-draw-mmap-wc
[217.005759] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (2.176s)
[217.140370] [040/137] (792s left) kms_cursor_legacy (torture-bo)
[217.435079] Starting subtest: torture-bo
[217.437313] Starting dynamic subtest: pipe-A
[239.474314] Dynamic subtest pipe-A: SUCCESS (22.041s)
[239.493216] Starting dynamic subtest: pipe-B
[261.092561] Dynamic subtest pipe-B: SUCCESS (21.618s)
[261.110314] Starting dynamic subtest: pipe-C
[282.711033] Dynamic subtest pipe-C: SUCCESS (21.618s)
[282.730203] Starting dynamic subtest: all-pipes
[304.563168] Dynamic subtest all-pipes: SUCCESS (21.851s)
[304.565404] Subtest torture-bo: SUCCESS (87.132s)
[304.640981] [041/137] (705s left) gem_exec_schedule (preempt-user)
[304.850759] Starting subtest: preempt-user
[304.852982] Starting dynamic subtest: rcs0
[304.924514] Dynamic subtest rcs0: SUCCESS (0.072s)
[304.944763] Starting dynamic subtest: bcs0
[305.013919] Dynamic subtest bcs0: SUCCESS (0.088s)
[305.033874] Starting dynamic subtest: vcs0
[305.073388] Dynamic subtest vcs0: SUCCESS (0.060s)
[305.096924] Starting dynamic subtest: vecs0
[305.136719] Dynamic subtest vecs0: SUCCESS (0.063s)
[305.138884] Subtest preempt-user: SUCCESS (0.286s)
[305.491217] [042/137] (704s left) gem_mmap_wc (close)
[305.673795] Starting subtest: close
[305.752157] Subtest close: SUCCESS (0.080s)
[305.824777] [043/137] (703s left) gem_exec_balancer (bonded-semaphore)
[306.027730] Starting subtest: bonded-semaphore
[309.515863] Subtest bonded-semaphore: SUCCESS (3.488s)
[313.343617] [044/137] (696s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[313.526990] Starting subtest: single-wait-for-submit-available-signaled
[313.529389] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.001s)
[313.607296] [045/137] (696s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[313.908720] Starting subtest: cursor-rapid-movement-256x256
[313.920140] Starting dynamic subtest: pipe-A-DP-1
[314.588185] Dynamic subtest pipe-A-DP-1: SUCCESS (0.668s)
[314.667608] Starting dynamic subtest: pipe-C-DP-1
[315.476977] Dynamic subtest pipe-C-DP-1: SUCCESS (0.823s)
[315.478990] Subtest cursor-rapid-movement-256x256: SUCCESS (1.566s)
[315.808441] [046/137] (694s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[315.990631] Starting subtest: multi-wait-for-submit-available-submitted
[315.993109] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.002s)
[316.072505] [047/137] (693s left) v3d/v3d_create_bo (create-bo-0)
[316.321856] Subtest create-bo-0: SKIP (0.000s)
[316.388784] [048/137] (693s left) v3d/v3d_submit_csd (bad-bo)
[316.638121] Subtest bad-bo: SKIP (0.000s)
[316.705886] [049/137] (693s left) kms_psr (psr2_sprite_mmap_gtt)
[316.891968] Subtest psr2_sprite_mmap_gtt: SKIP (0.000s)
[316.941683] [050/137] (693s left) i915_pm_rpm (pc8-residency)
[318.742207] Starting subtest: pc8-residency
[318.789206] Subtest pc8-residency: SKIP (0.000s)
[319.261484] [051/137] (690s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[319.616420] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[319.620519] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[319.706343] [052/137] (690s left) gem_mmap_offset (pf-nonblock)
[319.888782] Starting subtest: pf-nonblock
[319.903409] Subtest pf-nonblock: SUCCESS (0.014s)
[320.006256] [053/137] (690s left) api_intel_allocator (open-vm)
[320.187809] Starting subtest: open-vm
[320.190414] Subtest open-vm: SUCCESS (0.004s)
[320.289205] [054/137] (689s left) gem_exec_reloc (basic-scanout)
[320.584397] Starting subtest: basic-scanout
[320.586730] Starting dynamic subtest: rcs0
[320.834968] Dynamic subtest rcs0: SUCCESS (0.251s)
[320.853497] Starting dynamic subtest: bcs0
[321.001888] Dynamic subtest bcs0: SUCCESS (0.166s)
[321.020494] Starting dynamic subtest: vcs0
[321.174183] Dynamic subtest vcs0: SUCCESS (0.166s)
[321.192411] Starting dynamic subtest: vecs0
[321.334955] Dynamic subtest vecs0: SUCCESS (0.165s)
[321.337538] Subtest basic-scanout: SUCCESS (0.752s)
[321.457361] [055/137] (688s left) drm_fdinfo (idle)
[321.649436] Starting subtest: idle
[321.652324] Starting dynamic subtest: rcs0
[322.156483] Dynamic subtest rcs0: SUCCESS (0.506s)
[322.174793] Starting dynamic subtest: bcs0
[322.661245] Dynamic subtest bcs0: SUCCESS (0.504s)
[322.679633] Starting dynamic subtest: vcs0
[323.167135] Dynamic subtest vcs0: SUCCESS (0.505s)
[323.185368] Starting dynamic subtest: vecs0
[323.675050] Dynamic subtest vecs0: SUCCESS (0.504s)
[323.677075] Subtest idle: SUCCESS (2.022s)
[323.754844] [056/137] (686s left) kms_cursor_crc (cursor-size-change)
[324.044179] Starting subtest: cursor-size-change
[324.046447] Starting dynamic subtest: pipe-A-DP-1
[324.752344] Dynamic subtest pipe-A-DP-1: SUCCESS (0.706s)
[324.783240] Starting dynamic subtest: pipe-C-DP-1
[325.777550] Dynamic subtest pipe-C-DP-1: SUCCESS (1.023s)
[325.779624] Subtest cursor-size-change: SUCCESS (1.738s)
[326.140491] [057/137] (684s left) gem_mmap_gtt (cpuset-big-copy)
[326.323428] Starting subtest: cpuset-big-copy
[347.448814] Subtest cpuset-big-copy: SUCCESS (21.126s)
[347.871763] [058/137] (662s left) gem_madvise (dontneed-after-mmap)
[348.163359] Starting subtest: dontneed-after-mmap
[348.177406] Subtest dontneed-after-mmap: SUCCESS (0.013s)
[348.269303] [059/137] (662s left) kms_psr (psr2_cursor_mmap_cpu)
[348.451576] Subtest psr2_cursor_mmap_cpu: SKIP (0.000s)
[348.506206] [060/137] (661s left) gem_pxp (create-regular-context-1)
[351.211187] Subtest create-regular-context-1: SKIP (0.000s)
[351.286123] [061/137] (659s left) dmabuf_sync_file (export-basic)
[351.467774] Starting subtest: export-basic
[351.473162] Subtest export-basic: SUCCESS (0.003s)
[351.537109] [062/137] (658s left) gem_render_copy (y-tiled-ccs-to-linear)
[351.735131] Starting subtest: y-tiled-ccs-to-linear
[351.737317] Starting dynamic subtest: smem
[352.002968] Dynamic subtest smem: SUCCESS (0.267s)
[352.005158] Subtest y-tiled-ccs-to-linear: SUCCESS (0.268s)
[352.205535] [063/137] (658s left) gem_spin_batch (spin-all-new)
[352.405708] Starting subtest: spin-all-new
[352.433182] Subtest spin-all-new: SUCCESS (0.027s)
[352.670837] [064/137] (657s left) gem_ctx_persistence (engines-hostile-preempt)
[352.861063] Starting subtest: engines-hostile-preempt
[352.863288] Starting dynamic subtest: rcs0
[352.957546] Dynamic subtest rcs0: SUCCESS (0.095s)
[352.976903] Starting dynamic subtest: bcs0
[353.047067] Dynamic subtest bcs0: SUCCESS (0.087s)
[353.065497] Starting dynamic subtest: vcs0
[353.135848] Dynamic subtest vcs0: SUCCESS (0.087s)
[353.154640] Starting dynamic subtest: vecs0
[353.223348] Dynamic subtest vecs0: SUCCESS (0.087s)
[353.245865] Subtest engines-hostile-preempt: SUCCESS (0.363s)
[353.304810] [065/137] (657s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_ccs)
[353.930815] Starting subtest: pipe-C-bad-rotation-90-y_tiled_ccs
[353.936469] Subtest pipe-C-bad-rotation-90-y_tiled_ccs: SKIP (0.000s)
[354.348148] [066/137] (656s left) kms_vblank (pipe-c-query-idle-hang)
[354.634043] Starting subtest: pipe-C-query-idle-hang
[362.555227] Subtest pipe-C-query-idle-hang: SUCCESS (7.922s)
[362.891634] [067/137] (647s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[363.189632] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[363.193635] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[363.291579] [068/137] (647s left) gem_exec_params (dr4-dirt)
[363.481579] Starting subtest: DR4-dirt
[363.483786] Subtest DR4-dirt: SUCCESS (0.001s)
[363.591577] [069/137] (647s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[363.901142] Starting subtest: x-tiled-16bpp-rotate-90
[363.995090] Subtest x-tiled-16bpp-rotate-90: SKIP (0.091s)
[364.108633] [070/137] (646s left) gem_exec_fence (submit67)
[364.436995] Starting subtest: submit67
[364.439356] Starting dynamic subtest: rcs0
[366.694116] Dynamic subtest rcs0: SUCCESS (2.256s)
[366.718384] Starting dynamic subtest: bcs0
[367.858178] Dynamic subtest bcs0: SUCCESS (1.163s)
[367.880082] Starting dynamic subtest: vcs0
[368.893944] Dynamic subtest vcs0: SUCCESS (1.035s)
[368.915129] Starting dynamic subtest: vecs0
[370.102750] Dynamic subtest vecs0: SUCCESS (1.208s)
[370.106386] Subtest submit67: SUCCESS (5.666s)
[370.741800] [071/137] (640s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[371.028021] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[371.032027] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[371.125516] [072/137] (639s left) gem_exec_reloc (basic-cpu)
[371.321855] Starting subtest: basic-cpu
[371.340776] Subtest basic-cpu: SUCCESS (0.018s)
[371.526738] [073/137] (639s left) kms_cursor_legacy (cursor-vs-flip-atomic)
[371.810450] Starting subtest: cursor-vs-flip-atomic
[408.902562] Subtest cursor-vs-flip-atomic: SUCCESS (37.097s)
[409.243705] [074/137] (601s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_dg2_mc_ccs)
[409.877347] Starting subtest: pipe-C-random-ccs-data-4_tiled_dg2_mc_ccs
[409.884947] Subtest pipe-C-random-ccs-data-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[410.255908] [075/137] (600s left) kms_color (deep-color)
[410.541385] Starting subtest: deep-color
[410.545419] Subtest deep-color: SKIP (0.000s)
[410.623326] [076/137] (600s left) kms_lease (setcrtc_implicit_plane)
[410.916090] Starting subtest: setcrtc_implicit_plane
[410.918398] Starting dynamic subtest: pipe-A-DP-1
[411.222154] Dynamic subtest pipe-A-DP-1: SUCCESS (0.307s)
[411.242310] Starting dynamic subtest: pipe-B-DP-1
[411.799993] Dynamic subtest pipe-B-DP-1: SUCCESS (0.572s)
[411.826356] Starting dynamic subtest: pipe-C-DP-1
[412.525641] Dynamic subtest pipe-C-DP-1: SUCCESS (0.717s)
[412.527832] Subtest setcrtc_implicit_plane: SUCCESS (1.611s)
[412.847668] [077/137] (598s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_dg2_mc_ccs)
[413.568872] Subtest pipe-D-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[413.945444] [078/137] (597s left) kms_flip (2x-flip-vs-suspend)
[414.309167] Starting subtest: 2x-flip-vs-suspend
[414.356405] Subtest 2x-flip-vs-suspend: SKIP (0.003s)
[414.469396] [079/137] (596s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[414.811052] Starting subtest: cursor-rapid-movement-64x21
[414.820699] Starting dynamic subtest: pipe-A-DP-1
[415.307584] Dynamic subtest pipe-A-DP-1: SUCCESS (0.488s)
[415.390757] Starting dynamic subtest: pipe-C-DP-1
[416.177476] Dynamic subtest pipe-C-DP-1: SUCCESS (0.807s)
[416.179498] Subtest cursor-rapid-movement-64x21: SUCCESS (1.367s)
[416.530367] [080/137] (594s left) kms_chamelium_color (ctm-0-75)
[416.821285] Subtest ctm-0-75: SKIP (0.000s)
[416.879148] [081/137] (594s left) gen9_exec_parse (bb-secure)
[417.085081] Starting subtest: bb-secure
[417.087278] Subtest bb-secure: SUCCESS (0.004s)
[417.310320] [082/137] (593s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[417.613867] Starting subtest: y-tiled-64bpp-rotate-0
[425.288233] Subtest y-tiled-64bpp-rotate-0: SUCCESS (7.673s)
[426.209489] [083/137] (585s left) gem_exec_flush (basic-wb-set-default)
[426.408037] Starting subtest: basic-wb-set-default
[431.869972] Subtest basic-wb-set-default: SUCCESS (5.462s)
[432.075548] [084/137] (579s left) gem_exec_fence (expired-history)
[432.691143] Starting subtest: expired-history
[438.484269] Subtest expired-history: SUCCESS (5.794s)
[439.009365] [085/137] (572s left) kms_pipe_crc_basic (hang-read-crc)
[439.289316] Starting subtest: hang-read-crc
[439.291487] Starting dynamic subtest: pipe-A-DP-1
[440.534592] Dynamic subtest pipe-A-DP-1: SUCCESS (1.244s)
[440.558952] Starting dynamic subtest: pipe-B-DP-1
[442.155617] Dynamic subtest pipe-B-DP-1: SUCCESS (1.616s)
[442.180363] Starting dynamic subtest: pipe-C-DP-1
[444.028630] Dynamic subtest pipe-C-DP-1: SUCCESS (1.864s)
[444.031348] Subtest hang-read-crc: SUCCESS (4.739s)
[444.329835] [086/137] (567s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-wc)
[444.637098] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-wc
[444.640962] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[444.745497] [087/137] (566s left) prime_vgem (wait)
[444.956520] Starting subtest: wait
[444.958742] Starting dynamic subtest: rcs0
[444.988807] Dynamic subtest rcs0: SUCCESS (0.029s)
[445.011113] Starting dynamic subtest: vcs0
[445.013303] Dynamic subtest vcs0: SUCCESS (0.016s)
[445.038139] Starting dynamic subtest: vecs0
[445.040168] Dynamic subtest vecs0: SUCCESS (0.020s)
[445.042306] Subtest wait: SUCCESS (0.069s)
[445.128702] [088/137] (566s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[445.421104] Starting subtest: fbcpsr-rgb565-draw-render
[445.425175] Subtest fbcpsr-rgb565-draw-render: SKIP (0.000s)
[445.512238] [089/137] (566s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_dg2_mc_ccs)
[445.998535] Starting subtest: pipe-B-bad-rotation-90-4_tiled_dg2_mc_ccs
[446.004909] Subtest pipe-B-bad-rotation-90-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[446.526239] [090/137] (565s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[446.838381] Starting subtest: 4-tiled-64bpp-rotate-0
[446.842577] Subtest 4-tiled-64bpp-rotate-0: SKIP (0.000s)
[446.955942] [091/137] (564s left) kms_plane_scaling (plane-upscale-with-pixel-format-20x20)
[447.242031] Starting subtest: plane-upscale-with-pixel-format-20x20
[447.244293] Starting dynamic subtest: pipe-A-DP-1
[447.748713] Dynamic subtest pipe-A-DP-1: SUCCESS (0.506s)
[447.776627] Starting dynamic subtest: pipe-B-DP-1
[448.580174] Dynamic subtest pipe-B-DP-1: SUCCESS (0.822s)
[448.601269] Starting dynamic subtest: pipe-C-DP-1
[448.941939] Dynamic subtest pipe-C-DP-1: SUCCESS (0.362s)
[448.944564] Subtest plane-upscale-with-pixel-format-20x20: SUCCESS (1.704s)
[449.339102] [092/137] (562s left) gem_exec_schedule (u-semaphore-resolve)
[449.564675] Starting subtest: u-semaphore-resolve
[449.612404] Subtest u-semaphore-resolve: SUCCESS (0.047s)
[449.955631] [093/137] (561s left) gem_flink_basic (bad-flink)
[450.136471] Starting subtest: bad-flink
[450.138818] Subtest bad-flink: SUCCESS (0.000s)
[450.221630] [094/137] (561s left) gem_pread (display)
[450.403744] Starting subtest: display
[454.899327] Subtest display: SUCCESS (4.492s)
[455.004833] [095/137] (556s left) kms_force_connector_basic (force-load-detect)
[455.189906] Subtest force-load-detect: SKIP (0.000s)
[455.240765] [096/137] (556s left) kms_ccs (pipe-b-bad-aux-stride-y_tiled_gen12_mc_ccs)
[455.803465] Starting subtest: pipe-B-bad-aux-stride-y_tiled_gen12_mc_ccs
[455.809990] Subtest pipe-B-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[456.273794] [097/137] (555s left) perf_pmu (busy-check-all)
[456.469232] Starting subtest: busy-check-all
[456.472087] Starting dynamic subtest: rcs0
[457.003571] Dynamic subtest rcs0: SUCCESS (0.533s)
[457.027494] Starting dynamic subtest: bcs0
[457.552965] Dynamic subtest bcs0: SUCCESS (0.530s)
[457.573681] Starting dynamic subtest: vcs0
[458.091590] Dynamic subtest vcs0: SUCCESS (0.527s)
[458.123421] Starting dynamic subtest: vecs0
[458.628404] Dynamic subtest vecs0: SUCCESS (0.529s)
[458.630477] Subtest busy-check-all: SUCCESS (2.124s)
[458.690743] [098/137] (553s left) prime_busy (hang)
[458.878557] Starting subtest: hang
[458.884737] Starting dynamic subtest: rcs0
[465.449192] Dynamic subtest rcs0: SUCCESS (6.565s)
[465.469802] Starting dynamic subtest: bcs0
[472.425120] Dynamic subtest bcs0: SUCCESS (6.973s)
[472.445716] Starting dynamic subtest: vcs0
[479.464467] Dynamic subtest vcs0: SUCCESS (7.038s)
[479.485699] Starting dynamic subtest: vecs0
[486.440919] Dynamic subtest vecs0: SUCCESS (6.975s)
[486.444168] Subtest hang: SUCCESS (27.558s)
[486.555596] [099/137] (525s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[486.751782] Starting subtest: y-tiled-ccs-to-yf-tiled
[486.754037] Starting dynamic subtest: smem
[487.148167] Dynamic subtest smem: SUCCESS (0.395s)
[487.150338] Subtest y-tiled-ccs-to-yf-tiled: SUCCESS (0.396s)
[487.340888] [100/137] (524s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[487.645748] Starting subtest: extended-pageflip-modeset-hang-oldfb
[487.652113] Starting dynamic subtest: pipe-A
[489.150117] Dynamic subtest pipe-A: SUCCESS (1.500s)
[489.175764] Starting dynamic subtest: pipe-C
[490.798523] Dynamic subtest pipe-C: SUCCESS (1.639s)
[490.800577] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (3.152s)
[491.143210] [101/137] (520s left) gem_userptr_blits (sync-overlap)
[491.356116] Starting subtest: sync-overlap
[491.358424] Subtest sync-overlap: SUCCESS (0.001s)
[491.510246] [102/137] (520s left) syncobj_timeline (invalid-single-wait-all-available-unsubmitted)
[491.694470] Starting subtest: invalid-single-wait-all-available-unsubmitted
[491.697123] Subtest invalid-single-wait-all-available-unsubmitted: SUCCESS (0.000s)
[491.774396] [103/137] (520s left) gem_lmem_swapping (parallel-multi)
[491.966549] Subtest parallel-multi: SKIP (0.000s)
[492.027333] [104/137] (520s left) v3d/v3d_job_submission (multiple-singlesync-to-multisync)
[492.269502] Subtest multiple-singlesync-to-multisync: SKIP (0.000s)
[492.339638] [105/137] (519s left) prime_vgem (coherency-gtt)
[492.548743] Starting subtest: coherency-gtt
[492.553446] Subtest coherency-gtt: SKIP (0.000s)
[492.657736] [106/137] (519s left) kms_frontbuffer_tracking (fbc-tiling-4)
[492.948475] Starting subtest: fbc-tiling-4
[492.952355] Subtest fbc-tiling-4: SKIP (0.000s)
[493.041416] [107/137] (519s left) vc4/vc4_wait_bo (unused-bo-1ns)
[493.286408] Subtest unused-bo-1ns: SKIP (0.000s)
[493.357598] [108/137] (518s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[493.647481] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[493.651449] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[493.741872] [109/137] (518s left) fbdev (read)
[493.935399] Starting subtest: read
[495.345823] Subtest read: SUCCESS (1.410s)
[495.423721] [110/137] (516s left) kms_ccs (pipe-b-missing-ccs-buffer-yf_tiled_ccs)
[495.960473] Starting subtest: pipe-B-missing-ccs-buffer-yf_tiled_ccs
[495.962982] Subtest pipe-B-missing-ccs-buffer-yf_tiled_ccs: SUCCESS (0.001s)
[496.438045] [111/137] (515s left) gem_basic (bad-close)
[496.623873] Starting subtest: bad-close
[496.628459] Subtest bad-close: SUCCESS (0.000s)
[496.703970] [112/137] (515s left) gem_reg_read (bad-register)
[496.885053] Starting subtest: bad-register
[496.887572] Subtest bad-register: SUCCESS (0.000s)
[496.970834] [113/137] (515s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[497.279678] Starting subtest: x-tiled-8bpp-rotate-180
[500.115348] Subtest x-tiled-8bpp-rotate-180: SUCCESS (2.833s)
[500.337484] [114/137] (512s left) kms_psr (primary_mmap_cpu)
[500.770938] Subtest primary_mmap_cpu: SKIP (0.000s)
[500.823467] [115/137] (511s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[501.104142] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[512.147100] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.043s)
[512.488552] [116/137] (500s left) gem_exec_whisper (basic-contexts-priority-all)
[512.708290] Starting subtest: basic-contexts-priority-all
[536.378747] Subtest basic-contexts-priority-all: SUCCESS (23.668s)
[536.569640] [117/137] (476s left) syncobj_wait (wait-any-snapshot)
[536.758283] Starting subtest: wait-any-snapshot
[536.841416] Subtest wait-any-snapshot: SUCCESS (0.082s)
[536.935904] [118/137] (475s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[537.247526] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[537.252055] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.001s)
[537.336927] [119/137] (475s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[537.624895] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[537.628885] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[537.719620] [120/137] (475s left) kms_sequence (get-idle)
[538.002966] Starting subtest: get-idle
[538.005267] Starting dynamic subtest: DP-1-pipe-A
[540.324803] Dynamic subtest DP-1-pipe-A: SUCCESS (2.321s)
[540.352403] Starting dynamic subtest: DP-1-pipe-B
[542.934920] Dynamic subtest DP-1-pipe-B: SUCCESS (2.603s)
[542.959214] Starting dynamic subtest: DP-1-pipe-C
[545.622073] Dynamic subtest DP-1-pipe-C: SUCCESS (2.685s)
[545.624138] Subtest get-idle: SUCCESS (7.623s)
[545.918597] [121/137] (466s left) syncobj_timeline (etime-single-wait-all-submitted)
[546.104581] Starting subtest: etime-single-wait-all-submitted
[546.212379] Subtest etime-single-wait-all-submitted: SUCCESS (0.108s)
[546.300665] [122/137] (466s left) gem_softpin (softpin)
[546.493023] Starting subtest: softpin
[578.807739] Subtest softpin: SUCCESS (32.311s)
[578.915687] [123/137] (433s left) core_auth (many-magics)
[579.097624] Starting subtest: many-magics
[580.279709] Subtest many-magics: SUCCESS (1.175s)
[580.384482] [124/137] (432s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[580.685362] Starting subtest: fbcpsr-indfb-scaledprimary
[580.689242] Subtest fbcpsr-indfb-scaledprimary: SKIP (0.000s)
[580.785323] [125/137] (432s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[581.075124] Starting subtest: fbc-1p-pri-indfb-multidraw
[596.210858] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (15.139s)
[596.333718] [126/137] (416s left) i915_suspend (fence-restore-tiled2untiled)
[596.523046] Starting subtest: fence-restore-tiled2untiled
[615.035078] Subtest fence-restore-tiled2untiled: SUCCESS (4.185s)
[620.175976] [127/137] (412s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[620.461042] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[620.463384] Starting dynamic subtest: pipe-A-DP-1
[620.786858] Dynamic subtest pipe-A-DP-1: SUCCESS (0.324s)
[620.813730] Starting dynamic subtest: pipe-B-DP-1
[621.145681] Dynamic subtest pipe-B-DP-1: SUCCESS (0.355s)
[621.172742] Starting dynamic subtest: pipe-C-DP-1
[621.235985] Dynamic subtest pipe-C-DP-1: SKIP (0.082s)
[621.238231] Subtest planes-downscale-factor-0-5-upscale-20x20: SUCCESS (0.775s)
[621.629307] [128/137] (410s left) gem_exec_balancer (parallel-balancer)
[625.532498] Subtest parallel-balancer: SKIP (0.000s)
[625.627950] [129/137] (406s left) gem_ctx_shared (disjoint-timelines)
[625.830807] Starting subtest: disjoint-timelines
[625.844478] Subtest disjoint-timelines: SUCCESS (0.012s)
[626.079579] [130/137] (406s left) kms_async_flips (async-flip-with-page-flip-events)
[626.365624] Starting subtest: async-flip-with-page-flip-events
[626.367683] Starting dynamic subtest: pipe-A-DP-1
[629.141206] Dynamic subtest pipe-A-DP-1: SUCCESS (2.777s)
[629.166391] Starting dynamic subtest: pipe-B-DP-1
[631.808222] Dynamic subtest pipe-B-DP-1: SUCCESS (2.663s)
[631.833543] Starting dynamic subtest: pipe-C-DP-1
[634.396209] Dynamic subtest pipe-C-DP-1: SUCCESS (2.581s)
[634.398409] Subtest async-flip-with-page-flip-events: SUCCESS (8.033s)
[634.945325] [131/137] (397s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[635.247425] Starting subtest: y-tiled-addfb-size-offset-overflow
[635.252088] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[635.362579] [132/137] (397s left) v3d/v3d_wait_bo (used-bo-1ns)
[635.603417] Subtest used-bo-1ns: SKIP (0.000s)
[635.678832] [133/137] (396s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc)
[636.368334] Subtest pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[636.684451] [134/137] (396s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[636.983336] Starting subtest: yf-tiled-32bpp-rotate-90
[642.057418] Subtest yf-tiled-32bpp-rotate-90: SUCCESS (5.072s)
[642.583525] [135/137] (390s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[642.854852] Starting subtest: short-flip-after-cursor-toggle
[643.925477] Subtest short-flip-after-cursor-toggle: SUCCESS (1.072s)
[644.262611] [136/137] (388s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[644.550524] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[644.554457] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[644.644903] [137/137] (388s left) vc4/vc4_tiling (get-after-free)
[644.885560] Subtest get-after-free: SKIP (0.000s)
[644.955725] Closing watchdogs
Done.