[39.723406] Initializing watchdogs
[39.723616]   /dev/watchdog0
[39.738602] [001/139] (960s left) gem_spin_batch (spin-each)
[39.936125] Starting subtest: spin-each
[43.348619] Subtest spin-each: SUCCESS (3.413s)
[43.586897] [002/139] (956s left) gen9_exec_parse (unaligned-jump)
[43.755694] Starting subtest: unaligned-jump
[44.015418] Subtest unaligned-jump: SUCCESS (0.259s)
[44.234709] [003/139] (956s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[44.486650] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[44.489400] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[44.568413] [004/139] (955s left) gem_exec_params (invalid-bsd1-flag-on-render)
[44.719195] Starting subtest: invalid-bsd1-flag-on-render
[44.720821] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.001s)
[44.821189] [005/139] (955s left) kms_invalid_mode (bad-vsync-start)
[45.056203] Starting subtest: bad-vsync-start
[45.058610] Starting dynamic subtest: pipe-A-DP-1
[45.151624] Dynamic subtest pipe-A-DP-1: SUCCESS (0.098s)
[45.164564] Starting dynamic subtest: pipe-B-DP-1
[45.165838] Dynamic subtest pipe-B-DP-1: SUCCESS (0.002s)
[45.179220] Starting dynamic subtest: pipe-C-DP-1
[45.180542] Dynamic subtest pipe-C-DP-1: SUCCESS (0.002s)
[45.181864] Subtest bad-vsync-start: SUCCESS (0.108s)
[45.479351] [006/139] (954s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-signaled)
[45.623051] Starting subtest: invalid-multi-wait-available-unsubmitted-signaled
[45.667432] Subtest invalid-multi-wait-available-unsubmitted-signaled: FAIL (0.034s)
[45.729441] [007/139] (954s left) gen3_render_linear_blits
[45.914442] [008/139] (954s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[46.173972] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[47.613053] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (1.439s)
[47.863974] [009/139] (952s left) prime_mmap (test_errors)
[48.005581] Starting subtest: test_errors
[48.006858] Starting dynamic subtest: test_errors-smem
[48.022425] Dynamic subtest test_errors-smem: SUCCESS (0.020s)
[48.024327] Subtest test_errors: SUCCESS (0.020s)
[48.112075] [010/139] (952s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[48.447129] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[48.736787] [011/139] (951s left) kms_ccs (pipe-d-bad-pixel-format-y-tiled-gen12-rc-ccs)
[49.205558] Subtest pipe-D-bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[49.515305] [012/139] (951s left) v3d/v3d_job_submission (threaded-job-submission)
[49.718530] Subtest threaded-job-submission: SKIP (0.000s)
[49.783036] [013/139] (950s left) kms_atomic_transition (modeset-transition-nonblocking)
[50.016741] Starting subtest: modeset-transition-nonblocking
[50.018049] Starting dynamic subtest: 1x-outputs
[54.846084] Dynamic subtest 1x-outputs: SUCCESS (4.833s)
[54.961206] Subtest modeset-transition-nonblocking: SUCCESS (4.948s)
[55.279586] [014/139] (945s left) kms_properties (connector-properties-legacy)
[55.519263] Starting subtest: connector-properties-legacy
[57.982295] Subtest connector-properties-legacy: SUCCESS (2.464s)
[58.306165] [015/139] (942s left) gem_workarounds (suspend-resume-fd)
[58.478186] Starting subtest: suspend-resume-fd
[74.969309] Subtest suspend-resume-fd: SUCCESS (1.336s)
[81.203862] [016/139] (940s left) kms_sequence (get-busy)
[81.439082] Starting subtest: get-busy
[81.440454] Starting dynamic subtest: pipe-A-DP-1
[83.932265] Dynamic subtest pipe-A-DP-1: SUCCESS (2.491s)
[83.951883] Starting dynamic subtest: pipe-B-DP-1
[86.605571] Dynamic subtest pipe-B-DP-1: SUCCESS (2.671s)
[86.622434] Starting dynamic subtest: pipe-C-DP-1
[89.281144] Dynamic subtest pipe-C-DP-1: SUCCESS (2.674s)
[89.282451] Subtest get-busy: SUCCESS (7.842s)
[89.600730] [017/139] (932s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[89.744599] Starting subtest: etime-multi-wait-all-for-submit-submitted
[89.849800] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.104s)
[89.930359] [018/139] (932s left) kms_plane_scaling (planes-scaler-unity-scaling)
[90.163431] Starting subtest: planes-scaler-unity-scaling
[90.173232] Starting dynamic subtest: pipe-A-DP-1
[90.279484] Dynamic subtest pipe-A-DP-1: SUCCESS (0.104s)
[90.311664] Starting dynamic subtest: pipe-B-DP-1
[90.695545] Dynamic subtest pipe-B-DP-1: SUCCESS (0.409s)
[90.703685] Subtest planes-scaler-unity-scaling: SUCCESS (0.541s)
[91.116719] [019/139] (930s left) drm_fdinfo (all-busy-check-all)
[91.262587] Starting subtest: all-busy-check-all
[91.796874] Subtest all-busy-check-all: SUCCESS (0.531s)
[91.883493] [020/139] (930s left) gem_lmem_swapping (parallel-random-engines)
[92.035211] Subtest parallel-random-engines: SKIP (0.000s)
[92.085322] [021/139] (930s left) kms_prop_blob (invalid-get-prop-any)
[92.222086] Starting subtest: invalid-get-prop-any
[92.223845] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[92.300917] [022/139] (929s left) gem_exec_schedule (preempt-hang)
[92.587871] Starting subtest: preempt-hang
[92.589376] Starting dynamic subtest: rcs0
[92.874820] Dynamic subtest rcs0: SUCCESS (0.287s)
[92.901159] Starting dynamic subtest: bcs0
[93.174369] Dynamic subtest bcs0: SUCCESS (0.300s)
[93.197426] Starting dynamic subtest: vcs0
[93.441402] Dynamic subtest vcs0: SUCCESS (0.266s)
[93.462704] Starting dynamic subtest: vecs0
[93.709314] Dynamic subtest vecs0: SUCCESS (0.263s)
[93.710787] Subtest preempt-hang: SUCCESS (1.119s)
[93.974319] [023/139] (928s left) kms_addfb_basic (addfb25-bad-modifier)
[94.116600] Starting subtest: addfb25-bad-modifier
[94.118086] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[94.300550] [024/139] (927s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[94.549895] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[94.552580] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[94.651016] [025/139] (927s left) kms_ccs (pipe-d-crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[95.120418] Subtest pipe-D-crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[95.433693] [026/139] (926s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[95.680772] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[95.683422] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[95.767276] [027/139] (926s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[96.240304] Starting subtest: pipe-C-crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[96.245143] Subtest pipe-C-crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SKIP (0.004s)
[96.564804] [028/139] (925s left) kms_content_protection (legacy)
[96.837998] Starting subtest: legacy
[97.030539] Starting dynamic subtest: pipe-A-DP-1
[217.281067] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[217.837352] Closing watchdogs
[217.845104] Initializing watchdogs
[217.845314]   /dev/watchdog0
[217.866668] [029/139] (804s left) gen7_exec_parse (bitmasks)
[218.016454] Subtest bitmasks: SKIP (0.000s)
[218.066304] [030/139] (804s left) gem_mmap_gtt (pf-nonblock)
[218.200752] Starting subtest: pf-nonblock
[218.207931] Subtest pf-nonblock: SUCCESS (0.008s)
[218.315499] [031/139] (804s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[218.560967] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[220.960860] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (2.403s)
[221.101262] [032/139] (801s left) prime_busy (hang)
[221.249425] Starting subtest: hang
[221.250977] Starting dynamic subtest: rcs0
[228.036177] Dynamic subtest rcs0: SUCCESS (6.784s)
[228.054317] Starting dynamic subtest: bcs0
[235.077461] Dynamic subtest bcs0: SUCCESS (7.038s)
[235.096617] Starting dynamic subtest: vcs0
[242.051898] Dynamic subtest vcs0: SUCCESS (6.975s)
[242.069714] Starting dynamic subtest: vecs0
[249.028651] Dynamic subtest vecs0: SUCCESS (6.975s)
[249.029989] Subtest hang: SUCCESS (27.778s)
[249.132545] [033/139] (773s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[249.372083] Starting subtest: cursor-rapid-movement-64x21
[249.384430] Starting dynamic subtest: pipe-A-DP-1
[249.892440] Dynamic subtest pipe-A-DP-1: SUCCESS (0.509s)
[249.975347] Starting dynamic subtest: pipe-C-DP-1
[250.570310] Dynamic subtest pipe-C-DP-1: SUCCESS (0.608s)
[250.572362] Subtest cursor-rapid-movement-64x21: SUCCESS (1.196s)
[250.926340] [034/139] (771s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[251.182248] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[251.187332] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.003s)
[251.290715] [035/139] (771s left) kms_psr (sprite_mmap_cpu)
[251.434568] Subtest sprite_mmap_cpu: SKIP (0.000s)
[251.489488] [036/139] (771s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[251.626673] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[251.736580] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.110s)
[251.826458] [037/139] (770s left) gem_create (create-massive)
[251.964283] Starting subtest: create-massive
[251.965648] Subtest create-massive: SUCCESS (0.000s)
[252.189868] [038/139] (770s left) drm_fdinfo (virtual-busy-idle-all)
[252.329430] Starting subtest: virtual-busy-idle-all
[252.332969] Subtest virtual-busy-idle-all: SUCCESS (0.002s)
[252.423654] [039/139] (770s left) gem_exec_fence (syncobj-signal)
[253.109326] Starting subtest: syncobj-signal
[253.115673] Subtest syncobj-signal: SUCCESS (0.008s)
[253.474431] [040/139] (769s left) kms_ccs (pipe-a-random-ccs-data-4-tiled-dg2-mc-ccs)
[253.817551] Starting subtest: pipe-A-random-ccs-data-4-tiled-dg2-mc-ccs
[253.821480] Subtest pipe-A-random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[254.254776] [041/139] (768s left) syncobj_basic (bad-create-flags)
[254.396274] Starting subtest: bad-create-flags
[254.397974] Subtest bad-create-flags: SUCCESS (0.000s)
[254.489821] [042/139] (768s left) gem_exec_fair (basic-none)
[254.660501] Starting subtest: basic-none
[254.662278] Starting dynamic subtest: rcs0
[260.340685] Dynamic subtest rcs0: SUCCESS (5.681s)
[260.362417] Starting dynamic subtest: bcs0
[260.364996] Dynamic subtest bcs0: SKIP (0.008s)
[260.384117] Starting dynamic subtest: vcs0
[266.511468] Dynamic subtest vcs0: SUCCESS (6.159s)
[266.531747] Starting dynamic subtest: vecs0
[272.602637] Dynamic subtest vecs0: SUCCESS (6.090s)
[272.604164] Subtest basic-none: SUCCESS (17.944s)
[272.803567] [043/139] (750s left) syncobj_wait (wait-all-snapshot)
[272.946831] Starting subtest: wait-all-snapshot
[273.031526] Subtest wait-all-snapshot: SUCCESS (0.085s)
[273.119404] [044/139] (749s left) kms_pm_rpm (legacy-planes)
[274.849917] Starting subtest: legacy-planes
[274.852755] Starting dynamic subtest: plane-39
[277.344906] Dynamic subtest plane-39: SUCCESS (2.494s)
[277.366650] Starting dynamic subtest: plane-47
[279.962231] Dynamic subtest plane-47: SUCCESS (2.616s)
[279.964501] Subtest legacy-planes: SUCCESS (5.114s)
[280.476055] [045/139] (742s left) kms_pipe_crc_basic (nonblocking-crc)
[280.714464] Starting subtest: nonblocking-crc
[280.715902] Starting dynamic subtest: pipe-A-DP-1
[281.311385] Dynamic subtest pipe-A-DP-1: SUCCESS (0.594s)
[281.332938] Starting dynamic subtest: pipe-B-DP-1
[282.061736] Dynamic subtest pipe-B-DP-1: SUCCESS (0.746s)
[282.084872] Starting dynamic subtest: pipe-C-DP-1
[282.846962] Dynamic subtest pipe-C-DP-1: SUCCESS (0.782s)
[282.848478] Subtest nonblocking-crc: SUCCESS (2.134s)
[283.172651] [046/139] (739s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[283.414601] Starting subtest: fbc-rgb101010-draw-blt
[286.066826] Subtest fbc-rgb101010-draw-blt: SUCCESS (2.653s)
[286.190734] [047/139] (736s left) gem_exec_schedule (semaphore-noskip)
[286.360899] Starting subtest: semaphore-noskip
[286.600177] Subtest semaphore-noskip: SUCCESS (0.239s)
[286.956985] [048/139] (736s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[287.200527] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[287.203280] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[287.306124] [049/139] (735s left) kms_ccs (pipe-a-ccs-on-another-bo-yf-tiled-ccs)
[287.659067] Starting subtest: pipe-A-ccs-on-another-bo-yf-tiled-ccs
[287.660826] Subtest pipe-A-ccs-on-another-bo-yf-tiled-ccs: SUCCESS (0.001s)
[288.091948] [050/139] (735s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[288.339789] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt
[288.342537] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SKIP (0.000s)
[288.441827] [051/139] (734s left) kms_plane_scaling (plane-downscale-factor-0-25-with-modifiers)
[288.677839] Starting subtest: plane-downscale-factor-0-25-with-modifiers
[288.691401] Starting dynamic subtest: pipe-A-DP-1
[288.747451] Dynamic subtest pipe-A-DP-1: SKIP (0.050s)
[288.771841] Starting dynamic subtest: pipe-B-DP-1
[288.861458] Dynamic subtest pipe-B-DP-1: SKIP (0.102s)
[288.887224] Starting dynamic subtest: pipe-C-DP-1
[288.967723] Dynamic subtest pipe-C-DP-1: SKIP (0.099s)
[288.973413] Subtest plane-downscale-factor-0-25-with-modifiers: SKIP (0.291s)
[289.076003] [052/139] (734s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[289.402878] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[289.693139] [053/139] (733s left) perf (global-sseu-config-invalid)
[289.967281] Starting subtest: global-sseu-config-invalid
[289.969345] Starting dynamic subtest: 0-rcs0
[290.008320] Dynamic subtest 0-rcs0: SUCCESS (0.040s)
[290.009891] Subtest global-sseu-config-invalid: SUCCESS (0.042s)
[290.054705] [054/139] (733s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[290.194994] Starting subtest: single-wait-for-submit-available-submitted
[290.196726] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.001s)
[290.286693] [055/139] (733s left) gem_ctx_shared (q-smoketest)
[290.451392] Starting subtest: Q-smoketest
[290.452803] Starting dynamic subtest: rcs0
[295.926282] Dynamic subtest rcs0: SUCCESS (5.468s)
[295.945443] Starting dynamic subtest: bcs0
[301.394837] Dynamic subtest bcs0: SUCCESS (5.465s)
[301.417389] Starting dynamic subtest: vcs0
[306.867103] Dynamic subtest vcs0: SUCCESS (5.468s)
[306.890363] Starting dynamic subtest: vecs0
[312.341335] Dynamic subtest vecs0: SUCCESS (5.474s)
[312.342565] Subtest Q-smoketest: SUCCESS (21.885s)
[312.537229] [056/139] (710s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[312.778527] Starting subtest: 2x-cursor-vs-flip-atomic
[312.783650] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.004s)
[312.870460] [057/139] (710s left) kms_flip (plain-flip-ts-check-interruptible)
[313.103882] Starting subtest: plain-flip-ts-check-interruptible
[313.109563] Starting dynamic subtest: A-DP1
[323.913734] Dynamic subtest A-DP1: SUCCESS (10.804s)
[323.936244] Starting dynamic subtest: B-DP1
[334.623620] Dynamic subtest B-DP1: SUCCESS (10.707s)
[334.646385] Starting dynamic subtest: C-DP1
[345.349962] Dynamic subtest C-DP1: SUCCESS (10.723s)
[345.354779] Subtest plain-flip-ts-check-interruptible: SUCCESS (32.248s)
[345.783529] [058/139] (677s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[346.017747] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[346.020408] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.002s)
[346.110925] [059/139] (677s left) kms_atomic_interruptible (legacy-cursor)
[346.352437] Starting subtest: legacy-cursor
[346.357347] Starting dynamic subtest: pipe-A-DP-1
[352.885309] Dynamic subtest pipe-A-DP-1: SUCCESS (6.529s)
[352.886818] Subtest legacy-cursor: SUCCESS (6.534s)
[353.230045] [060/139] (670s left) syncobj_wait (signal)
[353.373479] Starting subtest: signal
[353.374893] Subtest signal: SUCCESS (0.000s)
[353.459492] [061/139] (670s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[353.702101] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[355.920617] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (2.221s)
[356.061145] [062/139] (667s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[356.305929] Starting subtest: fbc-rgb101010-draw-render
[358.953063] Subtest fbc-rgb101010-draw-render: SUCCESS (2.649s)
[359.094708] [063/139] (664s left) debugfs_test (basic-hwmon)
[359.243504] Starting subtest: basic-hwmon
[359.246377] Subtest basic-hwmon: SKIP (0.000s)
[359.427294] [064/139] (664s left) gem_fence_thrash (bo-write-verify-threaded-none)
[359.563133] Starting subtest: bo-write-verify-threaded-none
[364.569135] Subtest bo-write-verify-threaded-none: SUCCESS (5.006s)
[364.662763] [065/139] (659s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[364.913383] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[364.916107] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[365.010542] [066/139] (658s left) kms_vblank (wait-busy-hang)
[365.251006] Starting subtest: wait-busy-hang
[365.252408] Starting dynamic subtest: pipe-A-DP-1
[372.116917] Dynamic subtest pipe-A-DP-1: SUCCESS (6.865s)
[372.145041] Starting dynamic subtest: pipe-C-DP-1
[379.163877] Dynamic subtest pipe-C-DP-1: SUCCESS (7.039s)
[379.165358] Subtest wait-busy-hang: SUCCESS (13.917s)
[379.521027] [067/139] (644s left) kms_pipe_crc_basic (suspend-read-crc)
[379.752353] Starting subtest: suspend-read-crc
[379.756318] Starting dynamic subtest: pipe-A-DP-1
[398.391297] Dynamic subtest pipe-A-DP-1: SUCCESS (2.350s)
[398.413094] Starting dynamic subtest: pipe-B-DP-1
[416.372994] Dynamic subtest pipe-B-DP-1: SUCCESS (2.442s)
[416.398588] Starting dynamic subtest: pipe-C-DP-1