[40.663354] Initializing watchdogs
[40.663392]   /dev/watchdog0
[40.669293] [001/139] (960s left) gem_spin_batch (spin-each)
[40.729584] Starting subtest: spin-each
[44.105164] Subtest spin-each: SUCCESS (3.376s)
[44.455319] [002/139] (956s left) gen9_exec_parse (unaligned-jump)
[44.526396] Subtest unaligned-jump: SKIP (0.000s)
[44.548893] [003/139] (956s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[44.648395] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[49.867078] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (5.223s)
[50.004756] [004/139] (951s left) gem_exec_params (invalid-bsd1-flag-on-render)
[50.059422] Starting subtest: invalid-bsd1-flag-on-render
[50.061886] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[50.141216] [005/139] (951s left) kms_invalid_mode (bad-vsync-start)
[50.229416] Starting subtest: bad-vsync-start
[50.231905] Starting dynamic subtest: pipe-A-eDP-1
[50.510908] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[50.522311] Starting dynamic subtest: pipe-B-eDP-1
[50.524669] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[50.536043] Starting dynamic subtest: pipe-C-eDP-1
[50.538583] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[50.550644] Starting dynamic subtest: pipe-D-eDP-1
[50.553053] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[50.555465] Subtest bad-vsync-start: SUCCESS (0.292s)
[51.421180] [006/139] (949s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-signaled)
[51.467949] Starting subtest: invalid-multi-wait-available-unsubmitted-signaled
[51.513398] Subtest invalid-multi-wait-available-unsubmitted-signaled: FAIL (0.019s)
[51.546743] [007/139] (949s left) gen3_render_linear_blits
[51.621448] [008/139] (949s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[51.704131] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[51.708709] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[51.775080] [009/139] (949s left) prime_mmap (test_errors)
[51.830746] Starting subtest: test_errors
[51.833431] Starting dynamic subtest: test_errors-smem
[51.903775] Dynamic subtest test_errors-smem: SUCCESS (0.077s)
[51.906442] Subtest test_errors: SUCCESS (0.077s)
[51.979611] [010/139] (949s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[52.322830] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[53.184533] [011/139] (948s left) kms_ccs (pipe-d-bad-pixel-format-y-tiled-gen12-rc-ccs)
[53.586563] Starting subtest: pipe-D-bad-pixel-format-y-tiled-gen12-rc-ccs
[53.591988] Subtest pipe-D-bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[54.428440] [012/139] (947s left) v3d/v3d_job_submission (threaded-job-submission)
[54.540235] Subtest threaded-job-submission: SKIP (0.000s)
[54.587103] [013/139] (946s left) kms_atomic_transition (modeset-transition-nonblocking)
[54.658243] Starting subtest: modeset-transition-nonblocking
[54.660770] Starting dynamic subtest: 1x-outputs
[66.875511] Dynamic subtest 1x-outputs: SUCCESS (12.217s)
[67.176020] Subtest modeset-transition-nonblocking: SUCCESS (12.517s)
[68.062554] [014/139] (933s left) kms_properties (connector-properties-legacy)
[68.137359] Starting subtest: connector-properties-legacy
[76.667061] Subtest connector-properties-legacy: SUCCESS (8.524s)
[77.537135] [015/139] (923s left) gem_workarounds (suspend-resume-fd)
[77.609507] Starting subtest: suspend-resume-fd
[94.751278] Subtest suspend-resume-fd: SUCCESS (1.143s)
[94.841474] [016/139] (922s left) kms_sequence (get-busy)
[94.897203] Starting subtest: get-busy
[94.899556] Starting dynamic subtest: pipe-A-eDP-1
[96.831890] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.935s)
[96.845348] Starting dynamic subtest: pipe-B-eDP-1
[99.558970] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.725s)
[99.574728] Starting dynamic subtest: pipe-C-eDP-1
[102.226025] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.665s)
[102.239971] Starting dynamic subtest: pipe-D-eDP-1
[104.902017] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.674s)
[104.904644] Subtest get-busy: SUCCESS (10.006s)
[105.821461] [017/139] (911s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[105.878219] Starting subtest: etime-multi-wait-all-for-submit-submitted
[105.978641] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.102s)
[106.046764] [018/139] (911s left) kms_plane_scaling (planes-scaler-unity-scaling)
[106.113137] Starting subtest: planes-scaler-unity-scaling
[106.115589] Starting dynamic subtest: pipe-A-eDP-1
[106.191901] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.080s)
[106.207102] Starting dynamic subtest: pipe-B-eDP-1
[107.425336] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.230s)
[107.439013] Starting dynamic subtest: pipe-C-eDP-1
[108.628589] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.200s)
[108.644369] Starting dynamic subtest: pipe-D-eDP-1
[109.846246] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.214s)
[109.849610] Subtest planes-scaler-unity-scaling: SUCCESS (3.735s)
[111.069038] [019/139] (906s left) drm_fdinfo (all-busy-check-all)
[111.116014] Starting subtest: all-busy-check-all
[111.122826] Subtest all-busy-check-all: SKIP (0.007s)
[111.187103] [020/139] (906s left) gem_lmem_swapping (parallel-random-engines)
[111.237454] Subtest parallel-random-engines: SKIP (0.000s)
[111.263454] [021/139] (906s left) kms_prop_blob (invalid-get-prop-any)
[111.321136] Starting subtest: invalid-get-prop-any
[111.323819] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[111.408807] [022/139] (906s left) gem_exec_schedule (preempt-hang)
[111.584401] Starting subtest: preempt-hang
[111.587366] Starting dynamic subtest: rcs0
[111.835104] Dynamic subtest rcs0: SUCCESS (0.249s)
[111.841849] Starting dynamic subtest: bcs0
[112.073732] Dynamic subtest bcs0: SUCCESS (0.238s)
[112.079852] Starting dynamic subtest: vcs0
[112.291659] Dynamic subtest vcs0: SUCCESS (0.218s)
[112.298344] Starting dynamic subtest: vcs1
[112.516092] Dynamic subtest vcs1: SUCCESS (0.225s)
[112.521523] Starting dynamic subtest: vecs0
[112.746082] Dynamic subtest vecs0: SUCCESS (0.229s)
[112.758531] Starting dynamic subtest: ccs0
[112.985800] Dynamic subtest ccs0: SUCCESS (0.238s)
[112.988424] Subtest preempt-hang: SUCCESS (1.400s)
[113.462565] [023/139] (904s left) kms_addfb_basic (addfb25-bad-modifier)
[113.514732] Starting subtest: addfb25-bad-modifier
[113.517097] Subtest addfb25-bad-modifier: SUCCESS (0.001s)
[113.624704] [024/139] (904s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[113.698571] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[113.703196] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[113.781021] [025/139] (903s left) kms_ccs (pipe-d-crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[114.202774] Starting subtest: pipe-D-crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[114.208176] Subtest pipe-D-crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[115.031884] [026/139] (902s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[115.104492] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[120.007763] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (4.907s)
[120.110442] [027/139] (897s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[120.517563] Starting subtest: pipe-C-crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[125.940883] Subtest pipe-C-crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (5.424s)
[127.135546] [028/139] (890s left) kms_content_protection (legacy)
[127.255652] Starting subtest: legacy
[131.267301] Subtest legacy: SKIP (4.005s)
[132.511851] [029/139] (885s left) gen7_exec_parse (bitmasks)
[132.585330] Subtest bitmasks: SKIP (0.000s)
[132.624159] [030/139] (885s left) gem_mmap_gtt (pf-nonblock)
[132.686932] Subtest pf-nonblock: SKIP (0.000s)
[132.712861] [031/139] (885s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[132.778364] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[136.059734] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (3.287s)
[136.192317] [032/139] (881s left) prime_busy (hang)
[136.244097] Starting subtest: hang
[136.246720] Starting dynamic subtest: rcs0
[149.958761] Dynamic subtest rcs0: SUCCESS (13.712s)
[149.971762] Starting dynamic subtest: bcs0
[157.118032] Dynamic subtest bcs0: SUCCESS (7.158s)
[157.131170] Starting dynamic subtest: vcs0
[164.090774] Dynamic subtest vcs0: SUCCESS (6.975s)
[164.104458] Starting dynamic subtest: vcs1
[171.071309] Dynamic subtest vcs1: SUCCESS (6.977s)
[171.077444] Starting dynamic subtest: vecs0
[178.114060] Dynamic subtest vecs0: SUCCESS (7.044s)
[178.119432] Starting dynamic subtest: ccs0
[191.944743] Dynamic subtest ccs0: SUCCESS (13.829s)
[191.947408] Subtest hang: SUCCESS (55.697s)
[192.045825] [033/139] (825s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[192.183274] Starting subtest: cursor-rapid-movement-64x21
[192.186058] Starting dynamic subtest: pipe-A-eDP-1
[192.751679] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.565s)
[192.787379] Starting dynamic subtest: pipe-D-eDP-1
[194.021194] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.245s)
[194.023810] Subtest cursor-rapid-movement-64x21: SUCCESS (1.837s)
[194.910468] [034/139] (823s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[194.993091] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[195.651259] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.660s)
[195.813065] [035/139] (822s left) kms_psr (sprite_mmap_cpu)
[195.878652] Starting subtest: sprite_mmap_cpu
[197.724905] Subtest sprite_mmap_cpu: SUCCESS (1.846s)
[198.622378] [036/139] (819s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[198.676034] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[198.779786] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.105s)
[198.848288] [037/139] (819s left) gem_create (create-massive)
[198.909672] Starting subtest: create-massive
[198.912185] Subtest create-massive: SUCCESS (0.000s)
[199.083638] [038/139] (819s left) drm_fdinfo (virtual-busy-idle-all)
[199.140259] Starting subtest: virtual-busy-idle-all
[199.144576] Subtest virtual-busy-idle-all: SKIP (0.000s)
[199.202401] [039/139] (818s left) gem_exec_fence (syncobj-signal)
[199.656344] Starting subtest: syncobj-signal
[199.663961] Subtest syncobj-signal: SUCCESS (0.009s)
[201.504165] [040/139] (816s left) kms_ccs (pipe-a-random-ccs-data-4-tiled-dg2-mc-ccs)
[201.885231] Starting subtest: pipe-A-random-ccs-data-4-tiled-dg2-mc-ccs
[201.893518] Subtest pipe-A-random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[202.784068] [041/139] (815s left) syncobj_basic (bad-create-flags)
[202.844209] Starting subtest: bad-create-flags
[202.846782] Subtest bad-create-flags: SUCCESS (0.000s)
[202.907039] [042/139] (815s left) gem_exec_fair (basic-none)
[202.981596] Subtest basic-none: SKIP (0.000s)
[203.004207] [043/139] (815s left) syncobj_wait (wait-all-snapshot)
[203.043919] Starting subtest: wait-all-snapshot
[203.121315] Subtest wait-all-snapshot: SUCCESS (0.082s)
[203.186357] [044/139] (815s left) kms_pm_rpm (legacy-planes)
[214.789098] Starting subtest: legacy-planes
[214.792913] Starting dynamic subtest: plane-40
[239.060345] Dynamic subtest plane-40: SUCCESS (24.270s)
[239.074968] Starting dynamic subtest: plane-49
[263.354595] Dynamic subtest plane-49: SUCCESS (24.292s)
[263.369641] Starting dynamic subtest: plane-58
[287.610791] Dynamic subtest plane-58: SUCCESS (24.254s)
[287.626241] Starting dynamic subtest: plane-67
[311.856267] Dynamic subtest plane-67: SUCCESS (24.244s)
[311.859361] Subtest legacy-planes: SUCCESS (97.069s)
[312.650220] [045/139] (705s left) kms_pipe_crc_basic (nonblocking-crc)
[312.739057] Starting subtest: nonblocking-crc
[312.741769] Starting dynamic subtest: pipe-A-eDP-1
[313.529221] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.790s)
[313.543386] Starting dynamic subtest: pipe-B-eDP-1
[315.097314] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.564s)
[315.112511] Starting dynamic subtest: pipe-C-eDP-1
[316.568589] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.467s)
[316.579778] Starting dynamic subtest: pipe-D-eDP-1
[318.049846] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.477s)
[318.052553] Subtest nonblocking-crc: SUCCESS (5.310s)
[318.937334] [046/139] (699s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[319.005116] Starting subtest: fbc-rgb101010-draw-blt
[322.645096] Subtest fbc-rgb101010-draw-blt: SUCCESS (3.646s)
[322.762719] [047/139] (695s left) gem_exec_schedule (semaphore-noskip)
[322.825531] Starting subtest: semaphore-noskip
[323.297634] Subtest semaphore-noskip: SUCCESS (0.487s)
[323.624887] [048/139] (694s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[323.710198] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[323.714903] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[323.777158] [049/139] (694s left) kms_ccs (pipe-a-ccs-on-another-bo-yf-tiled-ccs)
[324.139673] Starting subtest: pipe-A-ccs-on-another-bo-yf-tiled-ccs
[324.144852] Subtest pipe-A-ccs-on-another-bo-yf-tiled-ccs: SKIP (0.000s)
[325.022908] [050/139] (693s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[325.091065] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt
[329.768582] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.681s)
[329.900241] [051/139] (688s left) kms_plane_scaling (plane-downscale-factor-0-25-with-modifiers)
[329.967169] Starting subtest: plane-downscale-factor-0-25-with-modifiers
[329.969802] Starting dynamic subtest: pipe-A-eDP-1
[330.009729] Dynamic subtest pipe-A-eDP-1: SKIP (0.037s)
[330.016863] Starting dynamic subtest: pipe-B-eDP-1
[330.045212] Dynamic subtest pipe-B-eDP-1: SKIP (0.037s)
[330.051651] Starting dynamic subtest: pipe-C-eDP-1
[330.083938] Dynamic subtest pipe-C-eDP-1: SKIP (0.034s)
[330.093087] Starting dynamic subtest: pipe-D-eDP-1
[330.121870] Dynamic subtest pipe-D-eDP-1: SKIP (0.038s)
[330.125620] Subtest plane-downscale-factor-0-25-with-modifiers: SKIP (0.153s)
[330.213911] [052/139] (688s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[330.585064] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[331.460081] [053/139] (687s left) perf (global-sseu-config-invalid)
[331.605539] Subtest global-sseu-config-invalid: SKIP (0.000s)
[331.623717] [054/139] (686s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[331.676735] Starting subtest: single-wait-for-submit-available-submitted
[331.679076] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[331.743472] [055/139] (686s left) gem_ctx_shared (q-smoketest)
[331.803537] Starting subtest: Q-smoketest
[331.806182] Starting dynamic subtest: rcs0
[337.403243] Dynamic subtest rcs0: SUCCESS (5.545s)
[337.417428] Starting dynamic subtest: bcs0
[342.884564] Dynamic subtest bcs0: SUCCESS (5.479s)
[342.896270] Starting dynamic subtest: vcs0
[348.350558] Dynamic subtest vcs0: SUCCESS (5.467s)
[348.367553] Starting dynamic subtest: vcs1
[353.838657] Dynamic subtest vcs1: SUCCESS (5.487s)
[353.854123] Starting dynamic subtest: vecs0
[359.334842] Dynamic subtest vecs0: SUCCESS (5.496s)
[359.344206] Starting dynamic subtest: ccs0
[364.832162] Dynamic subtest ccs0: SUCCESS (5.497s)
[364.834576] Subtest Q-smoketest: SUCCESS (32.979s)
[364.959597] [056/139] (653s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[365.051263] Starting subtest: 2x-cursor-vs-flip-atomic
[365.056389] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.002s)
[365.142278] [057/139] (653s left) kms_flip (plain-flip-ts-check-interruptible)
[365.224069] Starting subtest: plain-flip-ts-check-interruptible
[365.229577] Starting dynamic subtest: A-eDP1
[374.164956] Dynamic subtest A-eDP1: SUCCESS (8.935s)
[374.180166] Starting dynamic subtest: B-eDP1
[383.122312] Dynamic subtest B-eDP1: SUCCESS (8.956s)
[383.137008] Starting dynamic subtest: C-eDP1
[392.007290] Dynamic subtest C-eDP1: SUCCESS (8.883s)
[392.023317] Starting dynamic subtest: D-eDP1
[400.904143] Dynamic subtest D-eDP1: SUCCESS (8.895s)
[400.914244] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.690s)
[402.144159] [058/139] (616s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[402.231763] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[402.236497] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.003s)
[402.378097] [059/139] (616s left) kms_atomic_interruptible (legacy-cursor)
[402.433528] Starting subtest: legacy-cursor
[402.436060] Starting dynamic subtest: pipe-A-eDP-1
[409.910391] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.476s)
[409.913172] Subtest legacy-cursor: SUCCESS (7.477s)
[410.848937] [060/139] (607s left) syncobj_wait (signal)
[410.892182] Starting subtest: signal
[410.894639] Subtest signal: SUCCESS (0.000s)
[410.935798] [061/139] (607s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[410.984042] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[414.012157] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (3.034s)
[414.125562] [062/139] (604s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[414.174462] Starting subtest: fbc-rgb101010-draw-render
[417.812124] Subtest fbc-rgb101010-draw-render: SUCCESS (3.643s)
[417.934078] [063/139] (600s left) debugfs_test (basic-hwmon)
[417.992457] Starting subtest: basic-hwmon
[417.998157] Subtest basic-hwmon: SKIP (0.000s)
[418.095114] [064/139] (600s left) gem_fence_thrash (bo-write-verify-threaded-none)
[418.149179] Starting subtest: bo-write-verify-threaded-none
[418.157145] Subtest bo-write-verify-threaded-none: SKIP (0.001s)
[418.189360] [065/139] (600s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[418.242824] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[418.246617] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[418.320896] [066/139] (600s left) kms_vblank (wait-busy-hang)
[418.381963] Starting subtest: wait-busy-hang
[418.384455] Starting dynamic subtest: pipe-A-eDP-1
[432.233053] Dynamic subtest pipe-A-eDP-1: SUCCESS (13.848s)
[432.249134] Starting dynamic subtest: pipe-D-eDP-1
[447.296258] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.058s)
[447.298731] Subtest wait-busy-hang: SUCCESS (28.914s)
[448.223376] [067/139] (570s left) kms_pipe_crc_basic (suspend-read-crc)
[448.292300] Starting subtest: suspend-read-crc
[448.294626] Starting dynamic subtest: pipe-A-eDP-1
[465.663776] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.479s)
[465.682498] Starting dynamic subtest: pipe-B-eDP-1
[484.741448] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.292s)
[484.760008] Starting dynamic subtest: pipe-C-eDP-1
[502.712680] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.154s)
[502.727647] Starting dynamic subtest: pipe-D-eDP-1
[521.712819] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.215s)
[521.715679] Subtest suspend-read-crc: SUCCESS (12.152s)
[522.613892] [068/139] (557s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[522.683833] Starting subtest: primary-y-tiled-reflect-x-180
[522.742822] Subtest primary-y-tiled-reflect-x-180: SKIP (0.059s)
[522.825817] [069/139] (557s left) kms_chamelium_color (ctm-max)
[522.881504] Subtest ctm-max: SKIP (0.000s)
[522.896958] [070/139] (557s left) kms_vblank (ts-continuation-idle-hang)
[522.972177] Starting subtest: ts-continuation-idle-hang
[522.974526] Starting dynamic subtest: pipe-A-eDP-1
[536.504272] Dynamic subtest pipe-A-eDP-1: SUCCESS (13.533s)
[536.521101] Starting dynamic subtest: pipe-D-eDP-1
[551.576706] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.064s)
[551.579328] Subtest ts-continuation-idle-hang: SUCCESS (28.606s)
[552.497030] [071/139] (527s left) gem_ctx_persistence (file)
[552.538663] Starting subtest: file
[552.624713] Subtest file: SUCCESS (0.086s)
[552.710028] [072/139] (527s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[552.768525] Starting subtest: x-tiled-32bpp-rotate-90
[552.784192] Subtest x-tiled-32bpp-rotate-90: SKIP (0.017s)
[552.890434] [073/139] (527s left) kms_ccs (pipe-a-bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[552.952939] Starting subtest: pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[552.960437] Subtest pipe-A-bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[554.167963] [074/139] (526s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-blt)
[554.235438] Starting subtest: psr-1p-offscren-pri-shrfb-draw-blt
[558.926831] Subtest psr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.693s)
[559.059767] [075/139] (521s left) v3d/v3d_wait_bo (used-bo-1ns)
[559.158354] Subtest used-bo-1ns: SKIP (0.000s)
[559.208135] [076/139] (521s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[559.280274] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[559.282810] Starting dynamic subtest: pipe-A-default-mode
[559.601406] Dynamic subtest pipe-A-default-mode: SKIP (0.318s)
[559.605846] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.320s)
[560.446122] [077/139] (519s left) kms_ccs (pipe-b-bad-rotation-90-4-tiled-dg2-mc-ccs)
[560.838331] Starting subtest: pipe-B-bad-rotation-90-4-tiled-dg2-mc-ccs
[560.843708] Subtest pipe-B-bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[561.716553] [078/139] (518s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt)
[561.781398] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt
[561.786141] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[561.870135] [079/139] (518s left) api_intel_bb (intel-bb-blit-x)
[561.929036] Starting subtest: intel-bb-blit-x
[565.765561] Subtest intel-bb-blit-x: SUCCESS (3.839s)
[565.865374] [080/139] (514s left) gem_exec_balancer (full-late-pulse)
[565.924267] Starting subtest: full-late-pulse
[570.279926] Subtest full-late-pulse: SUCCESS (4.355s)
[570.519661] [081/139] (509s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[570.602508] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[570.607103] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[570.683401] [082/139] (509s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[570.747655] Starting subtest: addfb25-y-tiled-legacy
[570.749936] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[570.819134] [083/139] (509s left) prime_mmap (test_correct)
[570.878930] Starting subtest: test_correct
[570.881586] Starting dynamic subtest: test_correct-smem
[570.900843] Dynamic subtest test_correct-smem: SUCCESS (0.026s)
[570.904098] Subtest test_correct: SUCCESS (0.026s)
[570.986042] [084/139] (509s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[571.077917] Starting subtest: psr-rgb101010-draw-blt
[576.176047] Subtest psr-rgb101010-draw-blt: SUCCESS (5.102s)
[576.282347] [085/139] (504s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[576.353938] Starting subtest: cursorB-vs-flipA-legacy
[576.358415] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[576.437811] [086/139] (504s left) gem_eio (wait-1us)
[576.658882] Starting subtest: wait-1us
[577.022051] Subtest wait-1us: SUCCESS (0.366s)
[577.134286] [087/139] (503s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[577.215386] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[577.218959] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[577.301909] [088/139] (503s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[577.381842] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[577.383934] Starting dynamic subtest: pipe-A-default-mode
[577.679225] Dynamic subtest pipe-A-default-mode: SKIP (0.295s)
[577.684395] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.296s)
[578.552568] [089/139] (502s left) gem_eio (wait-10ms)
[578.765717] Starting subtest: wait-10ms
[579.136901] Subtest wait-10ms: SUCCESS (0.371s)
[579.250249] [090/139] (501s left) kms_ccs (pipe-d-random-ccs-data-y-tiled-gen12-rc-ccs)
[579.670983] Starting subtest: pipe-D-random-ccs-data-y-tiled-gen12-rc-ccs
[579.678753] Subtest pipe-D-random-ccs-data-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[580.536362] [091/139] (500s left) i915_pm_rpm (system-suspend)
[592.107791] Starting subtest: system-suspend
[614.774860] Subtest system-suspend: SUCCESS (8.269s)
[615.585319] [092/139] (479s left) gem_eio (create)
[615.810892] Starting subtest: create
[616.101895] Subtest create: SUCCESS (0.291s)
[616.219309] [093/139] (478s left) gem_exec_reloc (basic-wc-cpu-active)
[616.281287] Subtest basic-wc-cpu-active: SKIP (0.000s)
[616.330303] [094/139] (478s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[616.374218] Starting subtest: multi-wait-available-submitted-signaled
[616.376143] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[616.425563] [095/139] (478s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-wc)
[616.504585] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-wc
[616.508282] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[616.592527] [096/139] (478s left) gem_caching (read-writes)
[616.644737] Subtest read-writes: SKIP (0.000s)
[616.661599] [097/139] (478s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[616.718203] Starting subtest: x-tiled-to-vebox-yf-tiled
[616.722035] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[616.894482] [098/139] (478s left) gem_ccs (block-multicopy-compressed)
[616.938650] Starting subtest: block-multicopy-compressed
[616.943161] Subtest block-multicopy-compressed: SKIP (0.000s)
[616.988981] [099/139] (478s left) kms_cursor_legacy (single-bo)
[617.063731] Starting subtest: single-bo
[617.065736] Starting dynamic subtest: pipe-A
[622.444088] Dynamic subtest pipe-A: SUCCESS (5.378s)
[622.461452] Starting dynamic subtest: pipe-B
[627.822277] Dynamic subtest pipe-B: SUCCESS (5.378s)
[627.839217] Starting dynamic subtest: pipe-C
[633.199002] Dynamic subtest pipe-C: SUCCESS (5.377s)
[633.216552] Starting dynamic subtest: pipe-D
[638.576179] Dynamic subtest pipe-D: SUCCESS (5.377s)
[638.592484] Starting dynamic subtest: all-pipes
[643.953994] Dynamic subtest all-pipes: SUCCESS (5.377s)
[643.956491] Subtest single-bo: SUCCESS (26.889s)
[644.055733] [100/139] (451s left) gem_tiled_blits (interruptible)
[644.101527] Subtest interruptible: SKIP (0.000s)
[644.142057] [101/139] (451s left) kms_addfb_basic (bad-pitch-63)
[644.177821] Starting subtest: bad-pitch-63
[644.180244] Subtest bad-pitch-63: SUCCESS (0.000s)
[644.276857] [102/139] (451s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[644.344637] Starting subtest: yf-tiled-to-vebox-x-tiled
[644.349256] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[644.524695] [103/139] (450s left) gem_exercise_blt (fast-copy-emit)
[644.582449] Starting subtest: fast-copy-emit
[644.584636] Starting dynamic subtest: linear-smem-smem-emit
[644.602932] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.023s)
[644.621530] Starting dynamic subtest: xmajor-smem-smem-emit
[644.624440] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.017s)
[644.637400] Starting dynamic subtest: tile4-smem-smem-emit
[644.639773] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.015s)
[644.649206] Starting dynamic subtest: tile64-smem-smem-emit
[644.651773] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.016s)
[644.653954] Subtest fast-copy-emit: SUCCESS (0.072s)
[644.749450] [104/139] (450s left) kms_dsc (dsc-with-bpc)
[644.824151] Subtest dsc-with-bpc: SKIP (0.000s)
[644.851791] [105/139] (450s left) i915_pm_rps (thresholds-park)
[644.922290] Starting subtest: thresholds-park
[644.924383] Starting dynamic subtest: gt0
[644.929445] Dynamic subtest gt0: SKIP (0.004s)
[644.946571] Starting dynamic subtest: gt1
[644.950988] Dynamic subtest gt1: SKIP (0.000s)
[644.955284] Subtest thresholds-park: SKIP (0.005s)
[644.997463] [106/139] (450s left) kms_psr (psr2_sprite_blt)
[645.055654] Starting subtest: psr2_sprite_blt
[646.909642] Subtest psr2_sprite_blt: SUCCESS (1.854s)
[647.797051] [107/139] (447s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[647.864135] Starting subtest: yf-tiled-addfb-size-offset-overflow
[647.869080] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[647.940196] [108/139] (447s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[648.034361] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[648.039002] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[648.114913] [109/139] (447s left) kms_ccs (pipe-b-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc)
[648.497357] Starting subtest: pipe-B-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc
[648.502450] Subtest pipe-B-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.001s)
[649.397175] [110/139] (446s left) gem_exec_store (basic)
[649.448969] Starting subtest: basic
[649.540265] Subtest basic: SUCCESS (0.100s)
[649.721930] [111/139] (445s left) kms_ccs (pipe-b-crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[650.089182] Starting subtest: pipe-B-crc-primary-basic-y-tiled-gen12-rc-ccs-cc
[650.093950] Subtest pipe-B-crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[650.938957] [112/139] (444s left) gem_exec_params (dr1-dirt)
[651.015047] Starting subtest: DR1-dirt
[651.017097] Subtest DR1-dirt: SUCCESS (0.000s)
[651.096301] [113/139] (444s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[651.179620] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[654.035617] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (2.861s)
[654.168353] [114/139] (441s left) kms_rotation_crc (primary-rotation-270)
[654.248453] Starting subtest: primary-rotation-270
[654.252574] Subtest primary-rotation-270: SKIP (0.001s)
[654.316283] [115/139] (441s left) syncobj_timeline (wait-all-for-submit-snapshot)
[654.381124] Starting subtest: wait-all-for-submit-snapshot
[654.463542] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[654.533936] [116/139] (441s left) kms_ccs (pipe-d-crc-primary-basic-y-tiled-gen12-mc-ccs)
[654.946780] Starting subtest: pipe-D-crc-primary-basic-y-tiled-gen12-mc-ccs
[654.953299] Subtest pipe-D-crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.000s)
[655.798863] [117/139] (440s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[655.852882] Starting subtest: invalid-single-wait-all-unsubmitted
[655.855483] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[655.922245] [118/139] (439s left) i915_pm_freq_api (freq-basic-api)
[655.972738] Starting subtest: freq-basic-api
[655.975417] Starting dynamic subtest: gt0
[655.977394] Dynamic subtest gt0: SUCCESS (0.005s)
[655.985220] Starting dynamic subtest: gt1
[655.987543] Dynamic subtest gt1: SUCCESS (0.003s)
[655.989421] Subtest freq-basic-api: SUCCESS (0.009s)
[656.070836] [119/139] (439s left) kms_ccs (pipe-c-random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[656.454836] Starting subtest: pipe-C-random-ccs-data-4-tiled-mtl-rc-ccs-cc
[657.255971] Subtest pipe-C-random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.799s)
[658.420431] [120/139] (437s left) kms_ccs (pipe-c-bad-pixel-format-y-tiled-gen12-rc-ccs)
[658.849944] Starting subtest: pipe-C-bad-pixel-format-y-tiled-gen12-rc-ccs
[658.856020] Subtest pipe-C-bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[659.698303] [121/139] (436s left) kms_pm_dc (dc5-psr)
[670.848718] Starting subtest: dc5-psr
[674.233887] Subtest dc5-psr: SUCCESS (3.386s)
[675.125604] [122/139] (420s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[675.199176] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[675.204556] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[675.281256] [123/139] (420s left) sw_sync (sync_random_merge)
[675.340603] Starting subtest: sync_random_merge
[675.358815] Subtest sync_random_merge: SUCCESS (0.020s)
[675.408054] [124/139] (420s left) gem_exec_params (readonly)
[675.444568] Starting subtest: readonly
[675.449231] Subtest readonly: SUCCESS (0.003s)
[675.550611] [125/139] (420s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[675.619248] Starting subtest: psr-2p-pri-indfb-multidraw
[675.623801] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[675.698345] [126/139] (420s left) kms_ccs (pipe-a-crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[676.046720] Starting subtest: pipe-A-crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[676.052765] Subtest pipe-A-crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[676.916328] [127/139] (419s left) vc4/vc4_wait_bo (bad-bo)
[676.975448] Subtest bad-bo: SKIP (0.000s)
[677.025338] [128/139] (419s left) gem_exec_reloc (basic-cpu-read-active)
[677.080143] Subtest basic-cpu-read-active: SKIP (0.000s)
[677.127738] [129/139] (419s left) gem_tiling_max_stride
[677.204296] [130/139] (418s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-plflip-blt)
[677.271193] Starting subtest: fbc-2p-scndscrn-shrfb-plflip-blt
[677.275922] Subtest fbc-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[677.366700] [131/139] (418s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[679.939666] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SKIP (0.000s)
[680.005497] [132/139] (416s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[680.078289] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[681.219808] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.145s)
[681.325989] [133/139] (414s left) gem_exec_flush (basic-uc-rw-default)
[681.403628] Starting subtest: basic-uc-rw-default
[686.872144] Subtest basic-uc-rw-default: SUCCESS (5.468s)
[687.057943] [134/139] (409s left) syncobj_wait (wait-any-complex)
[687.116835] Starting subtest: wait-any-complex
[687.178716] Subtest wait-any-complex: SUCCESS (0.062s)
[687.252327] [135/139] (409s left) kms_addfb_basic (bo-too-small)
[687.322814] Starting subtest: bo-too-small
[687.324710] Subtest bo-too-small: SUCCESS (0.000s)
[687.416171] [136/139] (408s left) kms_ccs (pipe-c-bad-aux-stride-4-tiled-mtl-mc-ccs)
[687.838114] Starting subtest: pipe-C-bad-aux-stride-4-tiled-mtl-mc-ccs
[687.915293] Subtest pipe-C-bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.077s)
[688.670707] [137/139] (407s left) v3d/v3d_perfmon (get-values-valid-perfmon)
[688.771254] Subtest get-values-valid-perfmon: SKIP (0.000s)
[688.821960] [138/139] (407s left) kms_cursor_crc (cursor-random-256x256)
[689.088891] Starting subtest: cursor-random-256x256
[689.094363] Starting dynamic subtest: pipe-A-eDP-1
[693.006010] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.910s)
[693.042865] Starting dynamic subtest: pipe-D-eDP-1
[697.684848] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.657s)
[697.687442] Subtest cursor-random-256x256: SUCCESS (8.595s)
[698.569902] [139/139] (397s left) vc4/vc4_perfmon (create-perfmon-exceed)
[698.645740] Subtest create-perfmon-exceed: SKIP (0.000s)
[698.691977] Closing watchdogs
Done.