[42.129630] Initializing watchdogs
[42.129942]   /dev/watchdog0
[42.146105] [001/142] (960s left) gem_mmap_gtt (cpuset-big-copy-odd)
[42.376724] Starting subtest: cpuset-big-copy-odd
[66.252610] Subtest cpuset-big-copy-odd: SUCCESS (23.878s)
[66.688753] [002/142] (935s left) gem_partial_pwrite_pread (write)
[67.043151] Starting subtest: write
[81.803548] Subtest write: SUCCESS (14.762s)
[81.917997] [003/142] (920s left) vc4/vc4_purgeable_bo (free-purged-bo)
[82.202687] Subtest free-purged-bo: SKIP (0.000s)
[82.268314] [004/142] (920s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[82.605787] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[82.608356] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.001s)
[82.702950] [005/142] (920s left) kms_psr (psr2_primary_page_flip)
[82.913221] Subtest psr2_primary_page_flip: SKIP (0.000s)
[82.968414] [006/142] (919s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[83.296684] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[83.299665] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[83.388509] [007/142] (919s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[83.689286] Starting subtest: addfb25-y-tiled-legacy
[83.690502] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[83.787431] [008/142] (919s left) drm_fdinfo (virtual-busy-hang)
[83.996880] Starting subtest: virtual-busy-hang
[88.236939] Subtest virtual-busy-hang: SUCCESS (4.239s)
[88.318539] [009/142] (914s left) kms_psr (primary_render)
[88.522362] Subtest primary_render: SKIP (0.000s)
[88.569088] [010/142] (914s left) kms_cdclk (mode-transition)
[88.769895] Subtest mode-transition: SKIP (0.000s)
[88.818941] [011/142] (914s left) syncobj_wait (multi-wait-submitted)
[89.024455] Starting subtest: multi-wait-submitted
[89.127220] Subtest multi-wait-submitted: SUCCESS (0.102s)
[89.201863] [012/142] (913s left) gen9_exec_parse (basic-rejected)
[89.431297] Starting subtest: basic-rejected
[89.434395] Subtest basic-rejected: SUCCESS (0.003s)
[89.652902] [013/142] (913s left) gem_ctx_shared (q-out-order)
[89.881860] Starting subtest: Q-out-order
[89.883454] Starting dynamic subtest: rcs0
[90.056047] Dynamic subtest rcs0: SUCCESS (0.173s)
[90.078507] Starting dynamic subtest: bcs0
[90.173429] Dynamic subtest bcs0: SUCCESS (0.116s)
[90.195408] Starting dynamic subtest: vcs0
[90.264407] Dynamic subtest vcs0: SUCCESS (0.090s)
[90.285831] Starting dynamic subtest: vecs0
[90.353401] Dynamic subtest vecs0: SUCCESS (0.089s)
[90.355359] Subtest Q-out-order: SUCCESS (0.471s)
[90.600151] [014/142] (912s left) gem_exec_balancer (full-pulse)
[90.832439] Starting subtest: full-pulse
[94.882147] Subtest full-pulse: SUCCESS (4.049s)
[98.821649] [015/142] (904s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[99.127642] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[99.129074] Starting dynamic subtest: pipe-A-valid-mode
[99.893115] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.762s)
[99.894467] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (0.764s)
[100.250083] [016/142] (902s left) tools_test (tools_test)
[100.461610] Starting subtest: tools_test
[100.885480] Subtest tools_test: SUCCESS (0.431s)
[100.949657] [017/142] (902s left) gem_ctx_persistence (engines-cleanup)
[101.165276] Starting subtest: engines-cleanup
[101.166757] Starting dynamic subtest: rcs0
[101.204866] Dynamic subtest rcs0: SUCCESS (0.040s)
[101.223493] Starting dynamic subtest: bcs0
[101.244895] Dynamic subtest bcs0: SUCCESS (0.040s)
[101.266467] Starting dynamic subtest: vcs0
[101.274479] Dynamic subtest vcs0: SUCCESS (0.030s)
[101.294730] Starting dynamic subtest: vecs0
[101.308580] Dynamic subtest vecs0: SUCCESS (0.034s)
[101.309996] Subtest engines-cleanup: SUCCESS (0.144s)
[101.399976] [018/142] (901s left) gem_userptr_blits (userfault)
[101.615260] Starting subtest: userfault
[101.625892] Subtest userfault: SUCCESS (0.011s)
[101.800748] [019/142] (901s left) kms_flip (flip-vs-expired-vblank)
[102.091134] Starting subtest: flip-vs-expired-vblank
[102.092730] Starting dynamic subtest: A-DP1
[112.873301] Dynamic subtest A-DP1: SUCCESS (10.780s)
[112.891716] Starting dynamic subtest: B-DP1
[123.345203] Dynamic subtest B-DP1: SUCCESS (10.471s)
[123.364939] Starting dynamic subtest: C-DP1
[133.818694] Dynamic subtest C-DP1: SUCCESS (10.473s)
[133.822099] Subtest flip-vs-expired-vblank: SUCCESS (31.733s)
[134.241493] [020/142] (869s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[134.547878] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[134.550690] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.002s)
[134.639505] [021/142] (868s left) kms_cursor_crc (cursor-random-64x64)
[134.952870] Starting subtest: cursor-random-64x64
[134.963346] Starting dynamic subtest: pipe-A-DP-1
[138.766995] Dynamic subtest pipe-A-DP-1: SUCCESS (3.803s)
[138.844454] Starting dynamic subtest: pipe-C-DP-1
[142.967464] Dynamic subtest pipe-C-DP-1: SUCCESS (4.140s)
[142.968818] Subtest cursor-random-64x64: SUCCESS (8.016s)
[143.326446] [022/142] (860s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[143.655507] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[144.932953] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (1.277s)
[145.278274] [023/142] (858s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[145.581489] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[145.583381] Starting dynamic subtest: pipe-A-valid-mode
[145.666934] Dynamic subtest pipe-A-valid-mode: SKIP (0.086s)
[145.670957] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (0.088s)
[145.987950] [024/142] (857s left) kms_cursor_legacy (basic-flip-after-cursor-legacy)
[146.290649] Starting subtest: basic-flip-after-cursor-legacy
[147.382513] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.093s)
[147.719086] [025/142] (855s left) gem_lmem_swapping (parallel-random-verify-ccs)
[147.932823] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[147.982725] [026/142] (855s left) kms_vblank (pipe-c-wait-busy-hang)
[148.285800] Starting subtest: pipe-C-wait-busy-hang
[156.021355] Subtest pipe-C-wait-busy-hang: SUCCESS (7.738s)
[156.363553] [027/142] (847s left) syncobj_timeline (signal-point-0)
[156.572344] Starting subtest: signal-point-0
[156.574589] Subtest signal-point-0: SUCCESS (0.001s)
[156.663697] [028/142] (846s left) i915_pm_rps (reset)
[156.877796] Starting subtest: reset
[160.456289] Subtest reset: SUCCESS (3.578s)
[160.547963] [029/142] (843s left) kms_atomic_transition (modeset-transition-fencing)
[160.851232] Starting subtest: modeset-transition-fencing
[160.852517] Starting dynamic subtest: 1x-outputs
[165.698237] Dynamic subtest 1x-outputs: SUCCESS (4.850s)
[165.819605] Subtest modeset-transition-fencing: SUCCESS (4.972s)
[166.158691] [030/142] (837s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_mtl_mc_ccs)
[166.578824] Starting subtest: pipe-A-crc-primary-basic-4_tiled_mtl_mc_ccs
[166.581864] Subtest pipe-A-crc-primary-basic-4_tiled_mtl_mc_ccs: SKIP (0.001s)
[167.152760] [031/142] (836s left) gem_exec_balancer (parallel-keep-in-fence)
[171.237652] Subtest parallel-keep-in-fence: SKIP (0.000s)
[171.337848] [032/142] (832s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[171.670445] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[171.676388] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.003s)
[171.769913] [033/142] (832s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs)
[172.231261] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs
[172.237740] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs: SKIP (0.005s)
[172.745297] [034/142] (831s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[172.961335] Starting subtest: tiled-to-untiled
[173.075175] Subtest tiled-to-untiled: SUCCESS (0.112s)
[173.179139] [035/142] (830s left) prime_self_import (reimport-vs-gem_close-race)
[173.377660] Starting subtest: reimport-vs-gem_close-race
[178.403649] Subtest reimport-vs-gem_close-race: SUCCESS (5.026s)
[178.495821] [036/142] (825s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[178.815570] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[181.088495] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (2.278s)
[181.228075] [037/142] (822s left) kms_flip (plain-flip-fb-recreate-interruptible)
[181.530024] Starting subtest: plain-flip-fb-recreate-interruptible
[181.534261] Starting dynamic subtest: A-DP1
[192.341126] Dynamic subtest A-DP1: SUCCESS (10.807s)
[192.362628] Starting dynamic subtest: B-DP1
[203.073068] Dynamic subtest B-DP1: SUCCESS (10.730s)
[203.095224] Starting dynamic subtest: C-DP1
[213.789893] Dynamic subtest C-DP1: SUCCESS (10.715s)
[213.793779] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (32.262s)
[214.200552] [038/142] (789s left) perf_pmu (busy-idle)
[214.415253] Starting subtest: busy-idle
[214.416745] Starting dynamic subtest: rcs0
[215.059438] Dynamic subtest rcs0: SUCCESS (0.643s)
[215.078786] Starting dynamic subtest: bcs0
[215.698088] Dynamic subtest bcs0: SUCCESS (0.637s)
[215.717916] Starting dynamic subtest: vcs0
[216.334644] Dynamic subtest vcs0: SUCCESS (0.636s)
[216.353574] Starting dynamic subtest: vecs0
[216.978377] Dynamic subtest vecs0: SUCCESS (0.642s)
[216.979970] Subtest busy-idle: SUCCESS (2.564s)
[217.063105] [039/142] (787s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[217.385455] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[217.388070] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[217.481164] [040/142] (786s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[217.799237] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[220.241492] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (2.445s)
[220.380390] [041/142] (783s left) gem_exec_gttfill (engines)
[220.613809] Starting subtest: engines
[220.615191] Starting dynamic subtest: rcs0
[256.942987] Dynamic subtest rcs0: SUCCESS (36.327s)
[256.965306] Starting dynamic subtest: bcs0
[294.997733] Dynamic subtest bcs0: SUCCESS (38.053s)
[295.016307] Starting dynamic subtest: vcs0
[331.603741] Dynamic subtest vcs0: SUCCESS (36.604s)
[331.622935] Starting dynamic subtest: vecs0
[368.094752] Dynamic subtest vecs0: SUCCESS (36.491s)
[368.096054] Subtest engines: SUCCESS (147.480s)
[368.301558] [042/142] (635s left) gem_exec_fence (syncobj-import)
[369.110197] Starting subtest: syncobj-import
[369.116276] Subtest syncobj-import: SUCCESS (0.006s)
[369.481171] [043/142] (634s left) gem_userptr_blits (access-control)
[369.752180] Starting subtest: access-control
[369.755746] Subtest access-control: SKIP (0.000s)
[369.864417] [044/142] (634s left) gem_exec_reloc (basic-wc-cpu)
[370.077684] Starting subtest: basic-wc-cpu
[370.097918] Subtest basic-wc-cpu: SUCCESS (0.020s)
[370.298469] [045/142] (634s left) core_setmaster (master-drop-set-shared-fd)
[372.332402] Starting subtest: master-drop-set-shared-fd
[372.373520] Subtest master-drop-set-shared-fd: SUCCESS (0.042s)
[372.423448] [046/142] (631s left) gem_eio (throttle)
[372.655780] Starting subtest: throttle
[372.700189] Subtest throttle: SUCCESS (0.045s)
[372.915370] [047/142] (631s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[373.231479] Starting subtest: fbcpsr-rgb101010-draw-render
[373.234274] Subtest fbcpsr-rgb101010-draw-render: SKIP (0.000s)
[373.331865] [048/142] (631s left) kms_vblank (pipe-a-wait-idle)
[373.638632] Starting subtest: pipe-A-wait-idle
[375.923680] Subtest pipe-A-wait-idle: SUCCESS (2.287s)
[376.263308] [049/142] (628s left) gem_ringfill (engines-basic)
[376.746735] Starting subtest: engines-basic
[376.748242] Starting dynamic subtest: rcs0
[376.775724] Dynamic subtest rcs0: SUCCESS (0.030s)
[376.803634] Starting dynamic subtest: bcs0
[376.807770] Dynamic subtest bcs0: SUCCESS (0.030s)
[376.828709] Starting dynamic subtest: vcs0
[376.830038] Dynamic subtest vcs0: SUCCESS (0.020s)
[376.849973] Starting dynamic subtest: vecs0
[376.851272] Dynamic subtest vecs0: SUCCESS (0.014s)
[376.852660] Subtest engines-basic: SUCCESS (0.099s)
[376.929399] [050/142] (627s left) kms_cursor_legacy (flip-vs-cursor-varying-size)
[377.232174] Starting subtest: flip-vs-cursor-varying-size
[396.936239] Subtest flip-vs-cursor-varying-size: SUCCESS (19.705s)
[397.281719] [051/142] (607s left) syncobj_wait (single-wait-signaled)
[397.492702] Starting subtest: single-wait-signaled
[397.494301] Subtest single-wait-signaled: SUCCESS (0.001s)
[397.579171] [052/142] (607s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[397.889289] Starting subtest: 2x-long-cursor-vs-flip-legacy
[397.894284] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.005s)
[397.979441] [053/142] (606s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-mmap-cpu)
[398.293312] Starting subtest: psr-1p-offscren-pri-indfb-draw-mmap-cpu
[398.295863] Subtest psr-1p-offscren-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[398.413524] [054/142] (606s left) api_intel_allocator (default-alignment)
[398.615720] Starting subtest: default-alignment
[398.619636] Subtest default-alignment: SUCCESS (0.005s)
[398.729263] [055/142] (605s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_gen12_rc_ccs)
[399.241445] Starting subtest: pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs
[399.245587] Subtest pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[399.715115] [056/142] (605s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[399.921703] Starting subtest: invalid-signal-one-illegal-handle
[399.923328] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[400.015327] [057/142] (604s left) kms_ccs (pipe-a-ccs-on-another-bo-4_tiled_mtl_rc_ccs)
[400.462395] Starting subtest: pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs
[400.464939] Subtest pipe-A-ccs-on-another-bo-4_tiled_mtl_rc_ccs: SKIP (0.000s)
[401.002494] [058/142] (603s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_mtl_rc_ccs)
[401.606407] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs
[401.609386] Subtest pipe-C-crc-primary-rotation-180-4_tiled_mtl_rc_ccs: SKIP (0.001s)
[401.984946] [059/142] (602s left) kms_flip (nonblocking-read)
[402.276843] Starting subtest: nonblocking-read
[402.278321] Subtest nonblocking-read: SUCCESS (0.000s)
[402.385948] [060/142] (602s left) kms_cursor_crc (cursor-offscreen-128x42)
[402.702787] Starting subtest: cursor-offscreen-128x42
[402.714866] Starting dynamic subtest: pipe-A-DP-1
[405.211712] Dynamic subtest pipe-A-DP-1: SUCCESS (2.496s)
[405.292894] Starting dynamic subtest: pipe-C-DP-1
[408.089403] Dynamic subtest pipe-C-DP-1: SUCCESS (2.813s)
[408.090709] Subtest cursor-offscreen-128x42: SUCCESS (5.387s)
[408.440619] [061/142] (596s left) gem_exec_store (cachelines)
[408.668500] Starting subtest: cachelines
[408.669846] Starting dynamic subtest: rcs0
[408.712273] Dynamic subtest rcs0: SUCCESS (0.043s)
[408.736417] Starting dynamic subtest: bcs0
[408.741216] Dynamic subtest bcs0: SUCCESS (0.027s)
[408.761184] Starting dynamic subtest: vcs0
[408.775884] Dynamic subtest vcs0: SUCCESS (0.033s)
[408.796171] Starting dynamic subtest: vecs0
[408.800036] Dynamic subtest vecs0: SUCCESS (0.022s)
[408.801432] Subtest cachelines: SUCCESS (0.132s)
[409.003433] [062/142] (595s left) gem_exec_fair (basic-deadline)
[409.236268] Starting subtest: basic-deadline
[411.636825] Subtest basic-deadline: FAIL (2.302s)
[411.770605] [063/142] (593s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[412.161366] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[412.436189] [064/142] (592s left) kms_lease (possible-crtcs-filtering)
[412.734029] Starting subtest: possible-crtcs-filtering
[412.737709] Subtest possible-crtcs-filtering: SUCCESS (0.007s)
[412.832161] [065/142] (592s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_mtl_rc_ccs_cc)
[413.254800] Starting subtest: pipe-A-random-ccs-data-4_tiled_mtl_rc_ccs_cc
[413.258629] Subtest pipe-A-random-ccs-data-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s)
[413.822950] [066/142] (591s left) kms_3d
[414.090092] [067/142] (591s left) gem_madvise (dontneed-before-exec)
[414.288950] Starting subtest: dontneed-before-exec
[414.298706] Subtest dontneed-before-exec: SUCCESS (0.008s)
[414.408162] [068/142] (590s left) i915_pm_rpm (system-suspend)
[416.230511] Starting subtest: system-suspend
[432.718736] Subtest system-suspend: SUCCESS (1.352s)
[439.347086] [069/142] (586s left) api_intel_bb (blit-noreloc-purge-cache)
[439.555618] Starting subtest: blit-noreloc-purge-cache
[439.572774] Subtest blit-noreloc-purge-cache: SUCCESS (0.018s)
[439.685278] [070/142] (586s left) prime_self_import (basic-llseek-bad)
[439.883339] Starting subtest: basic-llseek-bad
[439.889759] Subtest basic-llseek-bad: SUCCESS (0.004s)
[439.986169] [071/142] (586s left) gem_exec_flush (basic-wb-set-default)
[440.215753] Starting subtest: basic-wb-set-default
[445.701257] Subtest basic-wb-set-default: SUCCESS (5.485s)
[445.919984] [072/142] (580s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[446.241944] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[446.245087] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.001s)
[446.339912] [073/142] (580s left) kms_invalid_mode (bad-hsync-end)
[446.648188] Starting subtest: bad-hsync-end
[446.650269] Starting dynamic subtest: DP-1-pipe-A
[446.748717] Dynamic subtest DP-1-pipe-A: SUCCESS (0.100s)
[446.781303] Starting dynamic subtest: DP-1-pipe-B
[446.783178] Dynamic subtest DP-1-pipe-B: SUCCESS (0.014s)
[446.807413] Starting dynamic subtest: DP-1-pipe-C
[446.808759] Dynamic subtest DP-1-pipe-C: SUCCESS (0.015s)
[446.810073] Subtest bad-hsync-end: SUCCESS (0.140s)
[447.144128] [074/142] (579s left) gem_basic (create-fd-close)
[447.346562] Starting subtest: create-fd-close
[447.349407] Subtest create-fd-close: SUCCESS (0.000s)
[447.443835] [075/142] (579s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[447.760631] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[447.763642] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[447.861048] [076/142] (578s left) sysfs_heartbeat_interval (mixed)
[448.076653] Starting subtest: mixed
[448.081071] Starting dynamic subtest: vecs0
[453.330896] Dynamic subtest vecs0: SUCCESS (5.252s)
[453.357658] Starting dynamic subtest: vcs0
[458.610939] Dynamic subtest vcs0: SUCCESS (5.278s)
[458.638419] Starting dynamic subtest: bcs0
[463.889509] Dynamic subtest bcs0: SUCCESS (5.276s)
[463.916374] Starting dynamic subtest: rcs0
[469.135668] Dynamic subtest rcs0: SUCCESS (5.244s)
[469.136996] Subtest mixed: SUCCESS (21.060s)
[469.227588] [077/142] (557s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[469.538071] Starting subtest: fbc-rgb101010-draw-pwrite
[472.181648] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (2.646s)
[472.335198] [078/142] (554s left) kms_prime (d3hot)
[472.679573] Subtest D3hot: SKIP (0.000s)
[472.796120] [079/142] (553s left) kms_busy (extended-pageflip-hang-oldfb)
[473.110872] Starting subtest: extended-pageflip-hang-oldfb
[473.112174] Starting dynamic subtest: pipe-A
[473.544770] Dynamic subtest pipe-A: SUCCESS (0.433s)
[473.574732] Starting dynamic subtest: pipe-C
[474.168831] Dynamic subtest pipe-C: SUCCESS (0.615s)
[474.170186] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.061s)
[474.557745] [080/142] (552s left) gem_exec_schedule (preempt-contexts)
[474.802661] Starting subtest: preempt-contexts
[474.804584] Starting dynamic subtest: rcs0
[474.946606] Dynamic subtest rcs0: SUCCESS (0.142s)
[474.980309] Starting dynamic subtest: bcs0
[475.101303] Dynamic subtest bcs0: SUCCESS (0.150s)
[475.133827] Starting dynamic subtest: vcs0
[475.224944] Dynamic subtest vcs0: SUCCESS (0.123s)
[475.256303] Starting dynamic subtest: vecs0
[475.357107] Dynamic subtest vecs0: SUCCESS (0.130s)
[475.359487] Subtest preempt-contexts: SUCCESS (0.557s)
[475.738912] [081/142] (551s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[476.055364] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[476.057846] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[476.171922] [082/142] (550s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[476.470904] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[476.472255] Starting dynamic subtest: pipe-A-DP-1
[476.624690] Dynamic subtest pipe-A-DP-1: SUCCESS (0.153s)
[476.757306] Starting dynamic subtest: pipe-B-DP-1
[477.310352] Dynamic subtest pipe-B-DP-1: SUCCESS (0.577s)
[477.410645] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (0.940s)
[477.770969] [083/142] (549s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_rc_ccs_cc)
[478.445306] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[478.769031] [084/142] (548s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[478.974099] Starting subtest: invalid-multi-wait-all-unsubmitted
[478.975948] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[479.068511] [085/142] (547s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-signaled)
[479.277592] Starting subtest: multi-wait-for-submit-available-unsubmitted-signaled
[479.279295] Subtest multi-wait-for-submit-available-unsubmitted-signaled: SUCCESS (0.002s)
[479.369057] [086/142] (547s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[479.676321] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[479.677767] Starting dynamic subtest: pipe-A-DP-1
[479.771591] Dynamic subtest pipe-A-DP-1: SUCCESS (0.095s)
[479.801643] Starting dynamic subtest: pipe-B-DP-1
[480.188710] Dynamic subtest pipe-B-DP-1: SUCCESS (0.410s)
[480.216544] Starting dynamic subtest: pipe-C-DP-1
[480.584442] Dynamic subtest pipe-C-DP-1: SUCCESS (0.391s)
[480.585701] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (0.912s)
[481.004182] [087/142] (546s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[481.320792] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[481.323425] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[481.437573] [088/142] (545s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[481.756125] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[481.759412] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[481.854339] [089/142] (545s left) kms_chamelium_hpd (hdmi-hpd-after-suspend)
[482.258767] Subtest hdmi-hpd-after-suspend: SKIP (0.000s)
[482.554470] [090/142] (544s left) kms_ccs (pipe-c-crc-primary-rotation-180-yf_tiled_ccs)
[483.164384] Starting subtest: pipe-C-crc-primary-rotation-180-yf_tiled_ccs
[483.167405] Subtest pipe-C-crc-primary-rotation-180-yf_tiled_ccs: SKIP (0.001s)
[483.554831] [091/142] (543s left) kms_cursor_legacy (torture-move)
[483.870128] Starting subtest: torture-move
[483.871639] Starting dynamic subtest: pipe-A
[489.465482] Dynamic subtest pipe-A: SUCCESS (5.592s)
[489.495280] Starting dynamic subtest: pipe-B
[495.015400] Dynamic subtest pipe-B: SUCCESS (5.550s)
[495.040239] Starting dynamic subtest: pipe-C
[500.904048] Dynamic subtest pipe-C: SUCCESS (5.886s)
[500.931332] Starting dynamic subtest: all-pipes
[506.561087] Dynamic subtest all-pipes: SUCCESS (5.655s)
[506.562841] Subtest torture-move: SUCCESS (22.694s)
[506.656995] [092/142] (520s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[506.971915] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[506.974621] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[507.071914] [093/142] (520s left) kms_frontbuffer_tracking (fbc-badstride)
[507.378883] Starting subtest: fbc-badstride
[515.290671] Subtest fbc-badstride: SUCCESS (7.913s)
[515.437993] [094/142] (511s left) prime_busy (before-wait)
[515.652027] Starting subtest: before-wait
[515.669556] Starting dynamic subtest: rcs0
[515.679179] Dynamic subtest rcs0: SUCCESS (0.010s)
[515.707137] Starting dynamic subtest: bcs0
[515.708466] Dynamic subtest bcs0: SUCCESS (0.016s)
[515.733372] Starting dynamic subtest: vcs0
[515.735400] Dynamic subtest vcs0: SUCCESS (0.016s)
[515.759630] Starting dynamic subtest: vecs0
[515.760939] Dynamic subtest vecs0: SUCCESS (0.020s)
[515.855973] Subtest before-wait: SUCCESS (0.203s)
[515.954394] [095/142] (511s left) gem_mmap_wc (pf-nonblock)
[516.161713] Starting subtest: pf-nonblock
[516.168166] Subtest pf-nonblock: SUCCESS (0.007s)
[516.270970] [096/142] (511s left) fbdev (pan)
[516.469914] Starting subtest: pan
[516.525551] Subtest pan: SUCCESS (0.055s)
[516.621788] [097/142] (510s left) v3d/v3d_submit_cl (bad-flag)
[516.877915] Subtest bad-flag: SKIP (0.000s)
[516.954408] [098/142] (510s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[517.265516] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[517.268625] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[517.372722] [099/142] (510s left) i915_pm_rc6_residency (rc6-idle)
[517.580020] Starting subtest: rc6-idle
[517.590331] Starting dynamic subtest: rcs0
[529.866577] Dynamic subtest rcs0: SUCCESS (12.277s)
[529.894060] Starting dynamic subtest: bcs0
[542.143616] Dynamic subtest bcs0: SUCCESS (12.273s)
[542.170615] Starting dynamic subtest: vcs0
[554.419595] Dynamic subtest vcs0: SUCCESS (12.273s)
[554.446858] Starting dynamic subtest: vecs0
[566.696179] Dynamic subtest vecs0: SUCCESS (12.274s)
[566.698084] Subtest rc6-idle: SUCCESS (49.119s)
[566.991104] [100/142] (460s left) v3d/v3d_submit_csd (bad-extension)
[567.260358] Subtest bad-extension: SKIP (0.000s)
[567.338141] [101/142] (460s left) perf (invalid-oa-metric-set-id)
[567.681409] Starting subtest: invalid-oa-metric-set-id
[567.736693] Subtest invalid-oa-metric-set-id: SUCCESS (0.057s)
[567.786617] [102/142] (459s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[568.122286] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[569.591703] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.469s)
[569.740285] [103/142] (458s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[570.070867] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[570.074173] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[570.172799] [104/142] (457s left) kms_cursor_edge_walk (128x128-left-edge)
[570.477488] Starting subtest: 128x128-left-edge
[570.478806] Starting dynamic subtest: pipe-A-DP-1
[574.136053] Dynamic subtest pipe-A-DP-1: SUCCESS (3.657s)
[574.163498] Starting dynamic subtest: pipe-C-DP-1
[577.629006] Dynamic subtest pipe-C-DP-1: SUCCESS (3.487s)
[577.630406] Subtest 128x128-left-edge: SUCCESS (7.155s)
[577.965960] [105/142] (449s left) i915_pm_dc (dc5-dpms)
[579.282034] Starting subtest: dc5-dpms
[580.702163] Subtest dc5-dpms: SUCCESS (1.425s)
[580.908667] [106/142] (446s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[581.226459] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[581.229103] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[581.343007] [107/142] (446s left) syncobj_timeline (etime-single-wait-all-submitted)
[581.548442] Starting subtest: etime-single-wait-all-submitted
[581.655503] Subtest etime-single-wait-all-submitted: SUCCESS (0.106s)
[581.758712] [108/142] (446s left) kms_color (ctm-signed)
[582.062784] Starting subtest: ctm-signed
[582.064029] Starting dynamic subtest: pipe-A
[583.244428] Dynamic subtest pipe-A: SUCCESS (1.182s)
[583.280028] Starting dynamic subtest: pipe-B
[584.710076] Dynamic subtest pipe-B: SUCCESS (1.463s)
[584.751077] Starting dynamic subtest: pipe-C
[586.298162] Dynamic subtest pipe-C: SUCCESS (1.586s)
[586.299480] Subtest ctm-signed: SUCCESS (4.238s)
[586.644163] [109/142] (441s left) v3d/v3d_wait_bo (used-bo)
[586.914389] Subtest used-bo: SKIP (0.000s)
[586.993522] [110/142] (441s left) kms_draw_crc (fill-fb)
[587.295720] Starting subtest: fill-fb
[587.728628] Subtest fill-fb: SUCCESS (0.434s)
[587.843689] [111/142] (440s left) gen9_exec_parse (bb-large)
[588.073752] Starting subtest: bb-large
[670.795335] Subtest bb-large: SUCCESS (82.721s)
[671.055031] [112/142] (357s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[671.516245] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[671.519170] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[671.628542] [113/142] (356s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[671.864540] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[671.866055] Starting dynamic subtest: smem
[672.322747] Dynamic subtest smem: SUCCESS (0.456s)
[672.324073] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SUCCESS (0.459s)
[672.529040] [114/142] (355s left) gem_ctx_param (invalid-get-ringsize)
[672.732498] Starting subtest: invalid-get-ringsize
[672.734342] Subtest invalid-get-ringsize: SUCCESS (0.000s)
[672.826618] [115/142] (355s left) kms_scaling_modes (scaling-mode-center)
[673.133012] Starting subtest: scaling-mode-center
[673.135790] Subtest scaling-mode-center: SKIP (0.001s)
[673.227321] [116/142] (355s left) kms_vblank (pipe-d-ts-continuation-modeset)
[673.538175] Subtest pipe-D-ts-continuation-modeset: SKIP (0.000s)
[673.627200] [117/142] (354s left) gem_mmap (big-bo)
[673.831762] Starting subtest: big-bo
[675.512866] Subtest big-bo: SUCCESS (1.681s)
[675.635275] [118/142] (352s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc)
[676.080683] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc
[676.083277] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[676.195607] [119/142] (352s left) kms_addfb_basic (bad-pitch-63)
[676.402587] Starting subtest: bad-pitch-63
[676.404114] Subtest bad-pitch-63: SUCCESS (0.000s)
[676.611939] [120/142] (351s left) gem_eio (in-flight-external)
[676.839941] Starting subtest: in-flight-external
[676.930868] Subtest in-flight-external: SUCCESS (0.087s)
[677.144180] [121/142] (351s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[677.460100] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[677.463339] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[677.577519] [122/142] (351s left) gem_blits (basic)
[677.789194] Starting subtest: basic
[691.506269] Subtest basic: SUCCESS (13.716s)
[691.626326] [123/142] (337s left) gem_exec_reloc (basic-wc-noreloc)
[691.842629] Starting subtest: basic-wc-noreloc
[691.853425] Subtest basic-wc-noreloc: SUCCESS (0.010s)
[692.043874] [124/142] (336s left) gem_exec_schedule (thriceslice)
[692.276750] Starting subtest: thriceslice
[692.280868] Starting dynamic subtest: rcs0
[692.293335] Dynamic subtest rcs0: SUCCESS (0.014s)
[692.327597] Starting dynamic subtest: bcs0
[692.328897] Dynamic subtest bcs0: SUCCESS (0.013s)
[692.357654] Starting dynamic subtest: vcs0
[692.358980] Dynamic subtest vcs0: SUCCESS (0.024s)
[692.383036] Starting dynamic subtest: vecs0
[692.384417] Dynamic subtest vecs0: SUCCESS (0.013s)
[692.385647] Subtest thriceslice: SUCCESS (0.078s)
[692.727167] [125/142] (336s left) vc4/vc4_wait_bo (unused-bo-0ns)
[692.999844] Subtest unused-bo-0ns: SKIP (0.000s)
[693.076691] [126/142] (335s left) perf_pmu (semaphore-busy)
[693.287066] Starting subtest: semaphore-busy
[693.288866] Starting dynamic subtest: rcs0
[694.862178] Dynamic subtest rcs0: SUCCESS (1.572s)
[694.887486] Starting dynamic subtest: bcs0
[696.440961] Dynamic subtest bcs0: SUCCESS (1.575s)
[696.466733] Starting dynamic subtest: vcs0
[697.999813] Dynamic subtest vcs0: SUCCESS (1.554s)
[698.025849] Starting dynamic subtest: vecs0
[699.558111] Dynamic subtest vecs0: SUCCESS (1.555s)
[699.559432] Subtest semaphore-busy: SUCCESS (6.272s)
[699.676765] [127/142] (329s left) gem_exec_await (wide-all)
[700.076073] Starting subtest: wide-all
[721.915506] Subtest wide-all: SUCCESS (21.841s)
[722.151535] [128/142] (306s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_mtl_mc_ccs)
[722.976812] Subtest pipe-D-bad-pixel-format-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[723.313366] [129/142] (305s left) kms_ccs (pipe-d-crc-primary-rotation-180-y_tiled_gen12_rc_ccs)
[723.987515] Subtest pipe-D-crc-primary-rotation-180-y_tiled_gen12_rc_ccs: SKIP (0.000s)
[724.314660] [130/142] (304s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[724.623181] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[727.665378] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SUCCESS (3.044s)
[727.815627] [131/142] (301s left) kms_ccs (pipe-b-bad-rotation-90-y_tiled_ccs)
[728.307152] Starting subtest: pipe-B-bad-rotation-90-y_tiled_ccs
[729.019388] Subtest pipe-B-bad-rotation-90-y_tiled_ccs: SUCCESS (0.709s)
[729.653080] [132/142] (299s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_dg2_rc_ccs_cc)
[730.258084] Starting subtest: pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs_cc
[730.261133] Subtest pipe-C-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[730.676898] [133/142] (298s left) gem_sync (basic-all)
[730.910453] Starting subtest: basic-all
[733.782514] Subtest basic-all: SUCCESS (2.871s)
[733.994912] [134/142] (295s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[734.312691] Starting subtest: bo-too-small-due-to-tiling
[734.314619] Subtest bo-too-small-due-to-tiling: SUCCESS (0.001s)
[734.426709] [135/142] (294s left) gem_softpin (noreloc-s3)
[734.642811] Starting subtest: noreloc-S3
[755.929162] Subtest noreloc-S3: SUCCESS (5.466s)
[758.805558] [136/142] (289s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y_tiled_ccs)
[759.383927] Starting subtest: pipe-B-crc-sprite-planes-basic-y_tiled_ccs
[759.910439] Subtest pipe-B-crc-sprite-planes-basic-y_tiled_ccs: SUCCESS (0.526s)
[760.431115] [137/142] (287s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[760.638574] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[760.694273] [138/142] (287s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[761.000859] Starting subtest: read-crc-frame-sequence
[761.002343] Starting dynamic subtest: pipe-A-DP-1
[761.527972] Dynamic subtest pipe-A-DP-1: SUCCESS (0.525s)
[761.556419] Starting dynamic subtest: pipe-B-DP-1
[762.363081] Dynamic subtest pipe-B-DP-1: SUCCESS (0.829s)
[762.396529] Starting dynamic subtest: pipe-C-DP-1
[763.371989] Dynamic subtest pipe-C-DP-1: SUCCESS (1.001s)
[763.373566] Subtest read-crc-frame-sequence: SUCCESS (2.372s)
[763.700437] [139/142] (284s left) kms_plane_scaling (plane-downscale-with-pixel-format-factor-0-5)
[764.010116] Starting subtest: plane-downscale-with-pixel-format-factor-0-5
[764.011542] Starting dynamic subtest: pipe-A-DP-1
[766.678438] Dynamic subtest pipe-A-DP-1: SUCCESS (2.667s)
[766.720310] Starting dynamic subtest: pipe-B-DP-1
[769.320516] Dynamic subtest pipe-B-DP-1: SUCCESS (2.636s)
[769.350080] Starting dynamic subtest: pipe-C-DP-1
[769.710126] Dynamic subtest pipe-C-DP-1: SUCCESS (0.384s)
[769.711457] Subtest plane-downscale-with-pixel-format-factor-0-5: SUCCESS (5.705s)
[770.131364] [140/142] (277s left) gem_pwrite (basic-exhaustion)
[770.341079] Starting subtest: basic-exhaustion
[791.672641] Subtest basic-exhaustion: SUCCESS (21.333s)
[791.796211] [141/142] (256s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs)
[792.472087] Subtest pipe-D-crc-sprite-planes-basic-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[792.799131] [142/142] (255s left) kms_vblank (invalid)
[793.115033] Starting subtest: invalid
[793.383600] Subtest invalid: SUCCESS (0.268s)
[793.713972] Closing watchdogs
Done.