[53.869854] Initializing watchdogs
[53.870272]   /dev/watchdog0
[53.886218] [001/168] (960s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[54.277641] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[55.982665] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (1.704s)
[56.355092] [002/168] (958s left) kms_vblank (pipe-b-ts-continuation-idle-hang)
[56.681757] Starting subtest: pipe-B-ts-continuation-idle-hang
[63.557044] Subtest pipe-B-ts-continuation-idle-hang: SUCCESS (6.876s)
[63.892177] [003/168] (950s left) gem_exec_schedule (preempt-contexts)
[64.159672] Starting subtest: preempt-contexts
[64.161545] Starting dynamic subtest: rcs0
[64.307920] Dynamic subtest rcs0: SUCCESS (0.142s)
[64.326510] Starting dynamic subtest: bcs0
[64.477636] Dynamic subtest bcs0: SUCCESS (0.168s)
[64.493202] Starting dynamic subtest: vcs0
[64.606230] Dynamic subtest vcs0: SUCCESS (0.133s)
[64.622188] Starting dynamic subtest: vecs0
[64.746415] Dynamic subtest vecs0: SUCCESS (0.140s)
[64.748111] Subtest preempt-contexts: SUCCESS (0.585s)
[65.092448] [004/168] (949s left) kms_vblank (pipe-a-wait-forked-busy-hang)
[65.413443] Starting subtest: pipe-A-wait-forked-busy-hang
[71.968158] Subtest pipe-A-wait-forked-busy-hang: SUCCESS (6.560s)
[72.301329] [005/168] (942s left) kms_ccs (pipe-c-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc)
[72.801121] Starting subtest: pipe-C-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc
[72.806375] Subtest pipe-C-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.005s)
[73.112651] [006/168] (941s left) gem_ctx_persistence (engines-persistence)
[73.334988] Starting subtest: engines-persistence
[73.336836] Starting dynamic subtest: rcs0
[74.110059] Dynamic subtest rcs0: SUCCESS (0.775s)
[74.126520] Starting dynamic subtest: bcs0
[74.878703] Dynamic subtest bcs0: SUCCESS (0.769s)
[74.894428] Starting dynamic subtest: vcs0
[75.647760] Dynamic subtest vcs0: SUCCESS (0.769s)
[75.662564] Starting dynamic subtest: vecs0
[76.415260] Dynamic subtest vecs0: SUCCESS (0.767s)
[76.417333] Subtest engines-persistence: SUCCESS (3.080s)
[76.494812] [007/168] (938s left) gem_basic (bad-close)
[76.701447] Starting subtest: bad-close
[76.704507] Subtest bad-close: SUCCESS (0.000s)
[76.778856] [008/168] (937s left) kms_psr (psr2_sprite_mmap_cpu)
[76.990575] Subtest psr2_sprite_mmap_cpu: SKIP (0.000s)
[77.044223] [009/168] (937s left) syncobj_wait (single-wait-submitted)
[77.251926] Starting subtest: single-wait-submitted
[77.357092] Subtest single-wait-submitted: SUCCESS (0.103s)
[77.444653] [010/168] (937s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[77.765290] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[80.062528] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (2.302s)
[80.196080] [011/168] (934s left) gem_userptr_blits (forbidden-operations)
[80.423368] Starting subtest: forbidden-operations
[80.425071] Subtest forbidden-operations: SUCCESS (0.002s)
[80.579099] [012/168] (934s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[80.889476] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[80.890994] Starting dynamic subtest: pipe-A-DP-1
[83.260747] Dynamic subtest pipe-A-DP-1: SUCCESS (2.375s)
[83.276234] Starting dynamic subtest: pipe-B-DP-1
[85.912876] Dynamic subtest pipe-B-DP-1: SUCCESS (2.652s)
[85.928041] Starting dynamic subtest: pipe-C-DP-1
[86.318496] Dynamic subtest pipe-C-DP-1: SUCCESS (0.404s)
[86.320431] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (5.432s)
[86.729984] [013/168] (928s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_dg2_rc_ccs)
[87.132075] Starting subtest: pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs
[87.136513] Subtest pipe-A-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[87.503642] [014/168] (927s left) xe/xe_exec_fault_mode (twice-userptr-prefetch)
[87.787103] Subtest twice-userptr-prefetch: SKIP (0.000s)
[87.853254] [015/168] (926s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-signaled)
[88.062704] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[88.065156] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.001s)
[88.137670] [016/168] (926s left) kms_ccs (pipe-b-crc-primary-rotation-180-4_tiled_dg2_rc_ccs)
[88.580957] Starting subtest: pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs
[88.585545] Subtest pipe-B-crc-primary-rotation-180-4_tiled_dg2_rc_ccs: SKIP (0.002s)
[88.922728] [017/168] (925s left) xe/xe_exec_basic (no-exec-rebind)
[89.172522] Subtest no-exec-rebind: SKIP (0.000s)
[89.238922] [018/168] (925s left) perf_pmu (busy-accuracy-2)
[89.453817] Starting subtest: busy-accuracy-2
[89.455397] Starting dynamic subtest: rcs0
[92.511610] Dynamic subtest rcs0: SUCCESS (3.057s)
[92.528304] Starting dynamic subtest: bcs0
[95.581861] Dynamic subtest bcs0: SUCCESS (3.070s)
[95.596754] Starting dynamic subtest: vcs0
[98.652472] Dynamic subtest vcs0: SUCCESS (3.071s)
[98.668500] Starting dynamic subtest: vecs0
[101.718890] Dynamic subtest vecs0: SUCCESS (3.065s)
[101.720697] Subtest busy-accuracy-2: SUCCESS (12.263s)
[101.807665] [019/168] (913s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[102.203291] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[102.488106] [020/168] (912s left) gem_ctx_engines (execute-one)
[102.720778] Starting subtest: execute-one
[106.101862] Subtest execute-one: SUCCESS (3.379s)
[106.316182] [021/168] (908s left) v3d/v3d_submit_cl (valid-multisync-submission)
[106.582997] Subtest valid-multisync-submission: SKIP (0.000s)
[106.649567] [022/168] (908s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt)
[106.959786] Starting subtest: fbcpsr-farfromfence-mmap-gtt
[106.962525] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s)
[107.050690] [023/168] (908s left) gem_pxp (reject-modify-context-protection-off-1)
[109.786761] Subtest reject-modify-context-protection-off-1: SKIP (0.000s)
[109.865645] [024/168] (905s left) vc4/vc4_perfmon (get-values-invalid-perfmon)
[110.133685] Subtest get-values-invalid-perfmon: SKIP (0.000s)
[110.199485] [025/168] (904s left) xe/xe_exec_balancer (many-engines-cm-virtual-userptr-rebind)
[110.467955] Subtest many-engines-cm-virtual-userptr-rebind: SKIP (0.000s)
[110.532368] [026/168] (904s left) kms_chamelium_color (ctm-blue-to-red)
[110.844367] Subtest ctm-blue-to-red: SKIP (0.000s)
[110.899360] [027/168] (904s left) gem_mmap_wc (fault-concurrent)
[111.106229] Starting subtest: fault-concurrent
[113.322728] Subtest fault-concurrent: SUCCESS (2.215s)
[113.399409] [028/168] (901s left) gem_exec_fence (syncobj-invalid-wait)
[114.170554] Starting subtest: syncobj-invalid-wait
[114.172201] Subtest syncobj-invalid-wait: SUCCESS (0.002s)
[114.516882] [029/168] (900s left) gem_flink_basic (basic)
[114.726439] Starting subtest: basic
[114.728248] Subtest basic: SUCCESS (0.000s)
[114.833117] [030/168] (900s left) gem_mmap (basic-small-bo)
[115.041430] Starting subtest: basic-small-bo
[117.492919] Subtest basic-small-bo: SUCCESS (2.449s)
[117.617456] [031/168] (897s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[118.066451] Starting subtest: coverage-vs-premult-vs-constant
[118.422729] Starting dynamic subtest: pipe-A-DP-1
[118.845173] Dynamic subtest pipe-A-DP-1: SUCCESS (0.422s)
[118.866596] Starting dynamic subtest: pipe-C-DP-1
[119.518194] Dynamic subtest pipe-C-DP-1: SUCCESS (0.667s)
[119.519704] Subtest coverage-vs-premult-vs-constant: SUCCESS (1.450s)
[119.893990] [032/168] (895s left) vc4/vc4_label_bo (set-bad-handle)
[120.206917] Subtest set-bad-handle: SKIP (0.000s)
[120.276383] [033/168] (895s left) xe/xe_exec_threads (threads-hang-shared-vm-userptr-invalidate)
[120.540572] Subtest threads-hang-shared-vm-userptr-invalidate: SKIP (0.000s)
[120.609404] [034/168] (894s left) syncobj_timeline (wait-all-complex)
[120.816850] Starting subtest: wait-all-complex
[121.035052] Subtest wait-all-complex: SUCCESS (0.217s)
[121.126397] [035/168] (894s left) kms_vblank (pipe-a-ts-continuation-idle)
[121.430803] Starting subtest: pipe-A-ts-continuation-idle
[121.671333] Subtest pipe-A-ts-continuation-idle: SUCCESS (0.240s)
[121.987558] [036/168] (893s left) gem_eio (execbuf)
[122.233993] Starting subtest: execbuf
[122.279825] Subtest execbuf: SUCCESS (0.046s)
[122.469673] [037/168] (893s left) gem_reg_read (timestamp-moving)
[122.677631] Starting subtest: timestamp-moving
[123.679042] Subtest timestamp-moving: SUCCESS (1.001s)
[123.754556] [038/168] (891s left) xe/xe_exec_basic (many-engines-many-vm-basic)
[124.017511] Subtest many-engines-many-vm-basic: SKIP (0.000s)
[124.089194] [039/168] (891s left) xe/xe_vm (mixed-binds-3145728)
[124.350963] Subtest mixed-binds-3145728: SKIP (0.000s)
[124.421107] [040/168] (891s left) feature_discovery (display-3x)
[124.726226] Starting subtest: display-3x
[124.729104] Subtest display-3x: SKIP (0.000s)
[124.804446] [041/168] (890s left) xe/xe_dma_buf_sync (export-dma-buf-many)
[125.067723] Subtest export-dma-buf-many: SKIP (0.000s)
[125.138488] [042/168] (890s left) gem_eio (create-ext)
[125.368154] Starting subtest: create-ext
[125.427880] Subtest create-ext: SUCCESS (0.057s)
[125.603965] [043/168] (890s left) xe/xe_pm (d3cold-basic)
[125.867513] Subtest d3cold-basic: SKIP (0.000s)
[125.938652] [044/168] (889s left) gem_exec_capture (capture-recoverable)
[126.162716] Starting subtest: capture-recoverable
[126.165724] Subtest capture-recoverable: SKIP (0.000s)
[126.253592] [045/168] (889s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-gtt)
[126.574536] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-gtt
[128.536318] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-gtt: SUCCESS (1.966s)
[128.670331] [046/168] (887s left) perf_pmu (most-busy-check-all)
[128.892486] Starting subtest: most-busy-check-all
[128.894162] Starting dynamic subtest: rcs0
[129.420398] Dynamic subtest rcs0: SUCCESS (0.527s)
[129.442775] Starting dynamic subtest: bcs0
[129.950087] Dynamic subtest bcs0: SUCCESS (0.530s)
[129.970978] Starting dynamic subtest: vcs0
[130.481933] Dynamic subtest vcs0: SUCCESS (0.532s)
[130.499460] Starting dynamic subtest: vecs0
[131.012228] Dynamic subtest vecs0: SUCCESS (0.530s)
[131.013892] Subtest most-busy-check-all: SUCCESS (2.119s)
[131.103400] [047/168] (884s left) xe/xe_exec_basic (many-basic-defer-bind)
[131.367924] Subtest many-basic-defer-bind: SKIP (0.000s)
[131.440215] [048/168] (884s left) xe/xe_exec_fault_mode (once-userptr-rebind-imm)
[131.701390] Subtest once-userptr-rebind-imm: SKIP (0.000s)
[131.769639] [049/168] (884s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[132.084473] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[132.087165] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[132.188319] [050/168] (883s left) kms_fence_pin_leak
[136.353634] [051/168] (879s left) sysfs_preempt_timeout (idempotent)
[136.572282] Starting subtest: idempotent
[136.575500] Starting dynamic subtest: vecs0
[136.578984] Dynamic subtest vecs0: SUCCESS (0.005s)
[136.598468] Starting dynamic subtest: vcs0
[136.599861] Dynamic subtest vcs0: SUCCESS (0.002s)
[136.617271] Starting dynamic subtest: bcs0
[136.618605] Dynamic subtest bcs0: SUCCESS (0.003s)
[136.635882] Starting dynamic subtest: rcs0
[136.637396] Dynamic subtest rcs0: SUCCESS (0.002s)
[136.638759] Subtest idempotent: SUCCESS (0.017s)
[136.687637] [052/168] (879s left) syncobj_timeline (invalid-multi-wait-available-unsubmitted-submitted)
[136.896863] Starting subtest: invalid-multi-wait-available-unsubmitted-submitted
[136.898931] Subtest invalid-multi-wait-available-unsubmitted-submitted: SUCCESS (0.002s)
[136.986654] [053/168] (879s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[137.236047] Starting subtest: stress-mm-invalidate-close-overlap
[139.385929] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.149s)
[139.519720] [054/168] (876s left) kms_addfb_basic (no-handle)
[139.729242] Starting subtest: no-handle
[139.730880] Subtest no-handle: SUCCESS (0.000s)
[140.120553] [055/168] (876s left) xe/xe_exec_threads (threads-userptr-invalidate)
[140.399941] Subtest threads-userptr-invalidate: SKIP (0.000s)
[140.470745] [056/168] (875s left) kms_vrr (negative-basic)
[140.779510] Starting subtest: negative-basic
[140.782478] Subtest negative-basic: SKIP (0.000s)
[140.837175] [057/168] (875s left) gem_softpin (evict-snoop-interruptible)
[141.053475] Starting subtest: evict-snoop-interruptible
[141.056722] Subtest evict-snoop-interruptible: SKIP (0.000s)
[141.137562] [058/168] (875s left) kms_busy (extended-modeset-hang-oldfb)
[141.464700] Starting subtest: extended-modeset-hang-oldfb
[141.466428] Starting dynamic subtest: pipe-A
[143.610345] Dynamic subtest pipe-A: SUCCESS (2.144s)
[143.628384] Starting dynamic subtest: pipe-C
[145.685778] Dynamic subtest pipe-C: SUCCESS (2.073s)
[145.687364] Subtest extended-modeset-hang-oldfb: SUCCESS (4.219s)
[146.035537] [059/168] (870s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[146.342620] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[146.348489] Starting dynamic subtest: A-DP1
[157.158559] Dynamic subtest A-DP1: SUCCESS (10.810s)
[157.176214] Starting dynamic subtest: B-DP1
[167.888823] Dynamic subtest B-DP1: SUCCESS (10.729s)
[167.907572] Starting dynamic subtest: C-DP1
[178.612895] Dynamic subtest C-DP1: SUCCESS (10.722s)
[178.615323] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (32.272s)
[179.041166] [060/168] (837s left) gem_ctx_persistence (process)
[179.264244] Starting subtest: process
[179.381795] Subtest process: SUCCESS (0.116s)
[179.472708] [061/168] (836s left) gem_exec_flush (basic-batch-kernel-default-wb)
[179.711945] Starting subtest: basic-batch-kernel-default-wb
[185.684547] Subtest basic-batch-kernel-default-wb: SUCCESS (5.972s)
[185.888497] [062/168] (830s left) gem_exec_params (cliprects-invalid)
[186.116548] Starting subtest: cliprects-invalid
[186.118631] Subtest cliprects-invalid: SUCCESS (0.000s)
[186.221579] [063/168] (830s left) kms_content_protection (uevent)
[186.578474] Starting subtest: uevent
[186.616891] Starting dynamic subtest: pipe-A-DP-1
[221.275058] Dynamic subtest pipe-A-DP-1: FAIL (34.561s)
[221.331367] Subtest uevent: FAIL (34.752s)
[221.671267] [064/168] (794s left) sw_sync (sync_busy)
[221.879232] Starting subtest: sync_busy
[221.889342] Subtest sync_busy: SUCCESS (0.010s)
[221.967280] [065/168] (794s left) xe/xe_vm (munmap-style-unbind-userptr-inval-many-front)
[222.229505] Subtest munmap-style-unbind-userptr-inval-many-front: SKIP (0.000s)
[222.302419] [066/168] (794s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_dg2_mc_ccs)
[222.759733] Starting subtest: pipe-C-bad-rotation-90-4_tiled_dg2_mc_ccs
[222.762519] Subtest pipe-C-bad-rotation-90-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[223.092108] [067/168] (793s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[223.394981] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[223.397861] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.006s)
[223.510453] [068/168] (793s left) kms_vblank (pipe-b-wait-busy)
[223.817833] Starting subtest: pipe-B-wait-busy
[226.551131] Subtest pipe-B-wait-busy: SUCCESS (2.737s)
[226.872306] [069/168] (789s left) kms_cursor_crc (cursor-sliding-512x512)
[227.197932] Starting subtest: cursor-sliding-512x512
[227.200864] Subtest cursor-sliding-512x512: SKIP (0.000s)
[227.305584] [070/168] (789s left) xe/xe_exec_fault_mode (many-rebind-prefetch)
[227.567512] Subtest many-rebind-prefetch: SKIP (0.000s)
[227.638715] [071/168] (789s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[227.953195] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[227.955979] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[228.056265] [072/168] (788s left) gem_exec_suspend (basic-s3)
[228.287524] Starting subtest: basic-S3
[228.289373] Starting dynamic subtest: smem
[249.662138] Dynamic subtest smem: SUCCESS (6.443s)
[249.663593] Subtest basic-S3: SUCCESS (6.444s)
[251.863754] [073/168] (781s left) gem_render_copy (linear-to-vebox-yf-tiled)
[252.095343] Starting subtest: linear-to-vebox-yf-tiled
[252.098040] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[252.289588] [074/168] (781s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[252.609834] Starting subtest: cursorA-vs-flipB-toggle
[252.614365] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s)
[252.690655] [075/168] (781s left) core_setmaster (master-drop-set-shared-fd)
[254.963039] Starting subtest: master-drop-set-shared-fd
[255.012726] Subtest master-drop-set-shared-fd: SUCCESS (0.051s)
[255.053859] [076/168] (778s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt)
[255.380657] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt
[255.383482] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[255.491670] [077/168] (778s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[255.803379] Starting subtest: short-busy-flip-before-cursor-toggle
[255.806133] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[255.891948] [078/168] (778s left) prime_mmap (test_refcounting)
[256.106043] Starting subtest: test_refcounting
[256.107526] Starting dynamic subtest: test_refcounting-smem
[256.113677] Dynamic subtest test_refcounting-smem: SUCCESS (0.008s)
[256.115285] Subtest test_refcounting: SUCCESS (0.010s)
[256.223768] [079/168] (777s left) gem_exec_schedule (u-semaphore-noskip)
[256.467545] Starting subtest: u-semaphore-noskip
[256.717953] Subtest u-semaphore-noskip: SUCCESS (0.249s)
[257.072825] [080/168] (777s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[257.398412] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[257.401448] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[257.507890] [081/168] (776s left) kms_prop_blob (blob-prop-lifetime)
[257.719992] Starting subtest: blob-prop-lifetime
[257.721896] Subtest blob-prop-lifetime: SUCCESS (0.002s)
[257.807800] [082/168] (776s left) syncobj_wait (invalid-wait-zero-handles)
[258.019804] Starting subtest: invalid-wait-zero-handles
[258.021552] Subtest invalid-wait-zero-handles: SUCCESS (0.000s)
[258.107918] [083/168] (776s left) kms_flip (absolute-wf_vblank)
[258.401254] Starting subtest: absolute-wf_vblank
[258.402842] Starting dynamic subtest: A-DP1
[268.943757] Dynamic subtest A-DP1: SUCCESS (10.544s)
[268.966518] Starting dynamic subtest: B-DP1
[279.406641] Dynamic subtest B-DP1: SUCCESS (10.460s)
[279.429878] Starting dynamic subtest: C-DP1
[289.868423] Dynamic subtest C-DP1: SUCCESS (10.462s)
[289.870105] Subtest absolute-wf_vblank: SUCCESS (31.475s)
[290.273920] [084/168] (744s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[290.614708] Starting subtest: yf-tiled-32bpp-rotate-180
[296.312462] Subtest yf-tiled-32bpp-rotate-180: SUCCESS (5.695s)
[296.955004] [085/168] (737s left) api_intel_bb (blit-reloc-purge-cache)
[297.169088] Starting subtest: blit-reloc-purge-cache
[297.191931] Subtest blit-reloc-purge-cache: SUCCESS (0.024s)
[297.403083] [086/168] (736s left) gem_softpin (allocator-fork)
[297.618241] Starting subtest: allocator-fork
[299.990030] Subtest allocator-fork: SUCCESS (2.369s)
[300.105470] [087/168] (734s left) xe/xe_evict (evict-cm-threads-small)
[300.380070] Subtest evict-cm-threads-small: SKIP (0.000s)
[300.454411] [088/168] (733s left) xe/xe_exec_compute_mode (twice-userptr-invalidate-race)
[300.713271] Subtest twice-userptr-invalidate-race: SKIP (0.000s)
[300.792716] [089/168] (733s left) kms_psr2_su (frontbuffer-xrgb8888)
[301.007828] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[301.072757] [090/168] (733s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_dg2_mc_ccs)
[301.505172] Starting subtest: pipe-B-crc-primary-basic-4_tiled_dg2_mc_ccs
[301.508174] Subtest pipe-B-crc-primary-basic-4_tiled_dg2_mc_ccs: SKIP (0.002s)
[301.854835] [091/168] (732s left) kms_cursor_crc (cursor-random-128x128)
[302.177022] Starting subtest: cursor-random-128x128
[302.186748] Starting dynamic subtest: pipe-A-DP-1
[306.007987] Dynamic subtest pipe-A-DP-1: SUCCESS (3.822s)
[306.099889] Starting dynamic subtest: pipe-B-DP-1
[309.970733] Dynamic subtest pipe-B-DP-1: SUCCESS (3.883s)
[310.043000] Starting dynamic subtest: pipe-C-DP-1
[313.943818] Dynamic subtest pipe-C-DP-1: SUCCESS (3.924s)
[313.945323] Subtest cursor-random-128x128: SUCCESS (11.766s)
[314.317819] [092/168] (720s left) xe/xe_exec_balancer (once-cm-virtual-basic)
[314.586853] Subtest once-cm-virtual-basic: SKIP (0.000s)
[314.662241] [093/168] (719s left) gem_create (create-ext-cpu-access-big)
[314.998318] Starting subtest: create-ext-cpu-access-big
[315.001397] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[315.096348] [094/168] (719s left) kms_psr (cursor_blt)
[315.308671] Subtest cursor_blt: SKIP (0.000s)
[315.360400] [095/168] (719s left) xe/xe_exec_balancer (no-exec-parallel-rebind)
[315.620784] Subtest no-exec-parallel-rebind: SKIP (0.000s)
[315.694463] [096/168] (719s left) gem_partial_pwrite_pread (reads-snoop)
[315.914957] Starting subtest: reads-snoop
[323.285970] Subtest reads-snoop: SUCCESS (7.370s)
[323.411938] [097/168] (711s left) syncobj_timeline (signal-array)
[323.625709] Starting subtest: signal-array
[323.627512] Subtest signal-array: SUCCESS (0.001s)
[323.716927] [098/168] (711s left) kms_ccs (pipe-d-bad-pixel-format-4_tiled_dg2_rc_ccs)
[324.190327] Subtest pipe-D-bad-pixel-format-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[324.492774] [099/168] (710s left) xe/xe_exec_compute_mode (many-bindengine-userptr)
[324.766137] Subtest many-bindengine-userptr: SKIP (0.000s)
[324.842308] [100/168] (710s left) api_intel_allocator (fork-simple-once)
[325.049464] Starting subtest: fork-simple-once
[325.093312] Subtest fork-simple-once: SUCCESS (0.044s)
[325.193290] [101/168] (709s left) gem_mmap_gtt (zero-extend)
[325.402844] Starting subtest: zero-extend
[325.404668] Subtest zero-extend: SUCCESS (0.000s)
[325.508557] [102/168] (709s left) gem_exec_balancer (parallel-contexts)
[330.447334] Subtest parallel-contexts: SKIP (0.000s)
[330.541947] [103/168] (704s left) xe/xe_exec_fault_mode (many-engines-basic-prefetch)
[330.817851] Subtest many-engines-basic-prefetch: SKIP (0.000s)
[330.891384] [104/168] (704s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[331.227889] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[331.232761] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.001s)
[331.342430] [105/168] (703s left) gem_lmem_swapping (random)
[331.564363] Subtest random: SKIP (0.000s)
[331.626045] [106/168] (703s left) i915_pm_dc (dc9-dpms)
[332.946664] Starting subtest: dc9-dpms
[336.280370] Subtest dc9-dpms: SUCCESS (3.334s)
[336.448513] [107/168] (698s left) kms_addfb_basic (basic-y-tiled-legacy)
[336.936081] Starting subtest: basic-y-tiled-legacy
[336.937961] Subtest basic-y-tiled-legacy: SUCCESS (0.001s)
[337.032910] [108/168] (698s left) xe/xe_exec_balancer (many-cm-virtual-userptr-invalidate)
[337.304960] Subtest many-cm-virtual-userptr-invalidate: SKIP (0.000s)
[337.381520] [109/168] (697s left) kms_vblank (pipe-c-query-forked-busy)
[337.689527] Starting subtest: pipe-C-query-forked-busy
[340.444575] Subtest pipe-C-query-forked-busy: SUCCESS (2.756s)
[340.768841] [110/168] (694s left) syncobj_wait (invalid-signal-illegal-handle)
[340.980828] Starting subtest: invalid-signal-illegal-handle
[340.982644] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[341.067430] [111/168] (694s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[341.373670] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[341.375212] Starting dynamic subtest: pipe-A-valid-mode
[341.474472] Dynamic subtest pipe-A-valid-mode: SKIP (0.099s)
[341.478614] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.100s)
[341.788936] [112/168] (693s left) kms_chamelium_edid (dp-mode-timings)
[342.195098] Subtest dp-mode-timings: SKIP (0.000s)
[342.484824] [113/168] (693s left) xe/xe_exec_threads (threads-bal-basic)
[342.753056] Subtest threads-bal-basic: SKIP (0.000s)
[342.830617] [114/168] (692s left) xe/xe_exec_fault_mode (many-engines-bindengine-imm)
[343.086615] Subtest many-engines-bindengine-imm: SKIP (0.000s)
[343.162800] [115/168] (692s left) xe/xe_exec_reset (parallel-cancel)
[343.436098] Subtest parallel-cancel: SKIP (0.000s)
[343.513251] [116/168] (692s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[343.839768] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[343.842826] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[343.946829] [117/168] (691s left) xe/xe_exec_compute_mode (many-preempt-fence-early)
[344.220107] Subtest many-preempt-fence-early: SKIP (0.000s)
[344.296486] [118/168] (691s left) kms_ccs (pipe-c-ccs-on-another-bo-y_tiled_gen12_mc_ccs)
[344.748046] Starting subtest: pipe-C-ccs-on-another-bo-y_tiled_gen12_mc_ccs
[344.750982] Subtest pipe-C-ccs-on-another-bo-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[345.074452] [119/168] (690s left) v3d/v3d_submit_cl (bad-flag)
[345.347335] Subtest bad-flag: SKIP (0.000s)
[345.423817] [120/168] (690s left) xe/xe_vm (large-binds-4194304)
[345.697516] Subtest large-binds-4194304: SKIP (0.000s)
[345.773844] [121/168] (690s left) gen9_exec_parse (bb-start-cmd)
[346.007375] Starting subtest: bb-start-cmd
[346.015256] Subtest bb-start-cmd: SUCCESS (0.007s)
[346.241342] [122/168] (689s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[346.453115] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[346.559049] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.104s)
[346.657266] [123/168] (689s left) gem_media_fill (media-fill)
[346.885569] Starting subtest: media-fill
[346.887212] Starting dynamic subtest: smem
[346.905522] Dynamic subtest smem: SUCCESS (0.018s)
[346.907556] Subtest media-fill: SUCCESS (0.019s)
[347.124525] [124/168] (688s left) core_auth (basic-auth)
[347.334221] Starting subtest: basic-auth
[347.335750] Subtest basic-auth: SUCCESS (0.001s)
[347.423766] [125/168] (688s left) kms_legacy_colorkey (invalid-plane)
[347.736080] Starting subtest: invalid-plane
[347.737503] Starting dynamic subtest: zero-id
[347.738880] Dynamic subtest zero-id: SUCCESS (0.000s)
[347.761275] Starting dynamic subtest: outrange-id
[347.762757] Dynamic subtest outrange-id: SUCCESS (0.000s)
[347.764080] Subtest invalid-plane: SUCCESS (0.002s)
[347.824337] [126/168] (688s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[348.139203] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[348.142136] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[348.240996] [127/168] (687s left) gem_softpin (reverse)
[348.459651] Starting subtest: reverse
[348.483388] Subtest reverse: SUCCESS (0.024s)
[348.590569] [128/168] (687s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[348.929497] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[348.932381] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[349.040834] [129/168] (687s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[349.361845] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[349.364724] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[349.457919] [130/168] (686s left) i915_module_load (reload)
[349.662013] Starting subtest: reload
[353.371246] Subtest reload: SUCCESS (3.708s)
[353.424300] [131/168] (682s left) kms_content_protection (atomic)
[353.904862] Starting subtest: atomic
[353.972825] Starting dynamic subtest: pipe-A-DP-1
[474.994027] Per-test timeout exceeded. Killing the current test with SIGQUIT.
[475.793225] Closing watchdogs
[475.799001] Initializing watchdogs
[475.799236]   /dev/watchdog0
[475.824679] [132/168] (560s left) dmabuf_sync_file (export-wait-after-attach)
[476.037659] Starting subtest: export-wait-after-attach
[476.042341] Subtest export-wait-after-attach: SUCCESS (0.002s)
[476.113577] [133/168] (560s left) gem_exec_whisper (basic-normal-all)
[476.362824] Starting subtest: basic-normal-all
[488.402507] Subtest basic-normal-all: SUCCESS (12.039s)
[488.615947] [134/168] (547s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[488.936370] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[488.939255] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[489.047929] [135/168] (547s left) i915_pm_rpm (legacy-planes)
[490.865989] Starting subtest: legacy-planes
[490.868863] Starting dynamic subtest: plane-39
[509.998425] Dynamic subtest plane-39: SUCCESS (19.131s)
[510.021002] Starting dynamic subtest: plane-47
[529.087989] Dynamic subtest plane-47: SUCCESS (19.089s)
[529.090838] Subtest legacy-planes: SUCCESS (38.222s)
[545.810413] [136/168] (490s left) gem_exec_reloc (basic-cpu-noreloc)
[546.040459] Starting subtest: basic-cpu-noreloc
[546.054328] Subtest basic-cpu-noreloc: SUCCESS (0.017s)
[546.243909] [137/168] (490s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[546.582240] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[546.586576] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.002s)
[546.676712] [138/168] (489s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[546.993075] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[546.995935] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[547.076593] [139/168] (489s left) xe/xe_vm (large-split-misaligned-binds-2147483648)
[547.350733] Subtest large-split-misaligned-binds-2147483648: SKIP (0.000s)
[547.426431] [140/168] (489s left) xe/xe_prime_self_import (basic-with_one_bo_two_files)
[547.683786] Subtest basic-with_one_bo_two_files: SKIP (0.000s)
[547.760540] [141/168] (488s left) xe/xe_exec_threads (threads-bal-shared-vm-basic)
[548.017071] Subtest threads-bal-shared-vm-basic: SKIP (0.000s)
[548.091477] [142/168] (488s left) gem_readwrite (write-bad-handle)
[548.298197] Starting subtest: write-bad-handle
[548.301120] Subtest write-bad-handle: SUCCESS (0.000s)
[548.409004] [143/168] (488s left) kms_hdr (static-toggle-dpms)
[548.715874] Starting subtest: static-toggle-dpms
[548.718738] Subtest static-toggle-dpms: SKIP (0.000s)
[548.809934] [144/168] (488s left) xe/xe_vm (large-split-misaligned-binds-536870912)
[549.067130] Subtest large-split-misaligned-binds-536870912: SKIP (0.000s)
[549.142880] [145/168] (487s left) gem_exec_params (sol-reset-not-gen7)
[549.357851] Starting subtest: sol-reset-not-gen7
[549.363649] Subtest sol-reset-not-gen7: SUCCESS (0.004s)
[549.492386] [146/168] (487s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-gtt)
[549.806896] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-gtt
[549.809624] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[549.910515] [147/168] (487s left) xe/xe_vm (mixed-userptr-misaligned-binds-1611661312)
[550.183677] Subtest mixed-userptr-misaligned-binds-1611661312: SKIP (0.000s)
[550.260104] [148/168] (486s left) xe/xe_exec_fault_mode (once-bindengine-rebind-prefetch)
[550.518108] Subtest once-bindengine-rebind-prefetch: SKIP (0.000s)
[550.592588] [149/168] (486s left) kms_force_connector_basic (force-load-detect)
[550.802601] Subtest force-load-detect: SKIP (0.000s)
[550.860416] [150/168] (486s left) kms_ccs (pipe-a-bad-rotation-90-yf_tiled_ccs)
[551.248888] Starting subtest: pipe-A-bad-rotation-90-yf_tiled_ccs
[551.764140] Subtest pipe-A-bad-rotation-90-yf_tiled_ccs: SUCCESS (0.502s)
[552.288597] [151/168] (484s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[552.617234] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[552.620196] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[552.732906] [152/168] (484s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[553.068689] Starting subtest: x-tiled-16bpp-rotate-180
[557.055313] Subtest x-tiled-16bpp-rotate-180: SUCCESS (3.985s)
[557.485373] [153/168] (479s left) syncobj_timeline (invalid-single-wait-all-available-unsubmitted)
[557.697911] Starting subtest: invalid-single-wait-all-available-unsubmitted
[557.700098] Subtest invalid-single-wait-all-available-unsubmitted: SUCCESS (0.000s)
[557.798980] [154/168] (479s left) kms_plane (plane-panning-bottom-right-suspend)
[558.109771] Starting subtest: plane-panning-bottom-right-suspend
[558.111556] Starting dynamic subtest: pipe-A-planes
[576.161875] Dynamic subtest pipe-A-planes: SUCCESS (2.442s)
[576.189290] Starting dynamic subtest: pipe-B-planes
[595.094967] Dynamic subtest pipe-B-planes: SUCCESS (2.566s)
[595.096553] Subtest plane-panning-bottom-right-suspend: SUCCESS (5.010s)
[598.575981] [155/168] (473s left) xe/xe_exec_threads (threads-hang-rebind)
[598.849456] Subtest threads-hang-rebind: SKIP (0.000s)
[598.930873] [156/168] (473s left) sw_sync (sync_expired_merge)
[599.130522] Starting subtest: sync_expired_merge
[600.206688] Subtest sync_expired_merge: SUCCESS (1.074s)
[600.276650] [157/168] (472s left) i915_pm_rps (fence-order)
[600.489885] Starting subtest: fence-order
[603.991245] Subtest fence-order: SUCCESS (3.498s)
[604.244371] [158/168] (468s left) kms_flip (flip-vs-suspend-interruptible)
[604.558431] Starting subtest: flip-vs-suspend-interruptible
[604.562080] Starting dynamic subtest: A-DP1
[657.914816] Dynamic subtest A-DP1: SUCCESS (5.138s)
[657.946249] Starting dynamic subtest: B-DP1
[711.903680] Dynamic subtest B-DP1: SUCCESS (5.008s)
[711.936596] Starting dynamic subtest: C-DP1
[764.930669] Dynamic subtest C-DP1: SUCCESS (5.048s)
[764.932299] Subtest flip-vs-suspend-interruptible: SUCCESS (15.219s)
[769.388832] [159/168] (452s left) gem_exec_schedule (deep)
[769.754884] Starting subtest: deep
[769.756789] Starting dynamic subtest: rcs0
[784.873630] Dynamic subtest rcs0: SUCCESS (15.116s)
[784.897848] Starting dynamic subtest: bcs0
[801.107885] Dynamic subtest bcs0: SUCCESS (16.229s)
[801.133355] Starting dynamic subtest: vcs0
[816.221161] Dynamic subtest vcs0: SUCCESS (15.112s)
[816.257349] Starting dynamic subtest: vecs0
[831.575805] Dynamic subtest vecs0: SUCCESS (15.351s)
[831.578594] Subtest deep: SUCCESS (61.818s)
[833.210191] [160/168] (388s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[833.516250] Starting subtest: psr-2p-primscrn-cur-indfb-move
[833.519171] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[833.621595] [161/168] (388s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc)
[833.934624] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc
[833.937663] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[834.039193] [162/168] (387s left) gem_wait (busy)
[834.284837] Starting subtest: busy
[834.289904] Starting dynamic subtest: all-engines
[834.822076] Dynamic subtest all-engines: SUCCESS (0.534s)
[834.848998] Starting dynamic subtest: rcs0
[835.342347] Dynamic subtest rcs0: SUCCESS (0.519s)
[835.366365] Starting dynamic subtest: bcs0
[835.863874] Dynamic subtest bcs0: SUCCESS (0.520s)
[835.888800] Starting dynamic subtest: vcs0
[836.382807] Dynamic subtest vcs0: SUCCESS (0.518s)
[836.406224] Starting dynamic subtest: vecs0
[836.900849] Dynamic subtest vecs0: SUCCESS (0.517s)
[836.902416] Subtest busy: SUCCESS (2.616s)
[837.156458] [163/168] (384s left) kms_vblank (pipe-d-query-idle-hang)
[837.462209] Subtest pipe-D-query-idle-hang: SKIP (0.000s)
[837.555511] [164/168] (384s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[837.862540] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[837.865656] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[837.971110] [165/168] (384s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[838.476449] Starting subtest: framebuffer-vs-set-tiling
[838.477922] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[838.588320] [166/168] (383s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_dg2_rc_ccs)
[839.046877] Subtest pipe-D-crc-primary-rotation-180-4_tiled_dg2_rc_ccs: SKIP (0.000s)
[839.357389] [167/168] (382s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[839.660847] Starting subtest: fbcpsr-rgb565-draw-blt
[839.663961] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s)
[839.773521] [168/168] (382s left) kms_atomic (plane-invalid-params)
[840.214979] Starting subtest: plane-invalid-params
[840.363189] Subtest plane-invalid-params: SUCCESS (0.147s)
[840.804324] Closing watchdogs
Done.