[50.044576] Initializing watchdogs
[50.044814]   /dev/watchdog0
[50.060217] [001/137] (960s left) gen9_exec_parse (bb-oversize)
[50.326223] Starting subtest: bb-oversize
[51.307754] Subtest bb-oversize: SKIP (0.962s)
[51.539877] [002/137] (959s left) gem_ppgtt (blt-vs-render-ctx0)
[51.865409] Starting subtest: blt-vs-render-ctx0
[84.455371] Subtest blt-vs-render-ctx0: SUCCESS (32.588s)
[84.564530] [003/137] (926s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[85.015192] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[85.018166] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[85.107903] [004/137] (925s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[85.424199] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[85.426874] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[85.525243] [005/137] (925s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[85.845541] Starting subtest: yf-tiled-8bpp-rotate-90
[85.848503] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[85.941713] [006/137] (924s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-onoff)
[86.247404] Starting subtest: fbc-2p-primscrn-cur-indfb-onoff
[86.250168] Subtest fbc-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[86.341834] [007/137] (924s left) kms_chamelium_audio (dp-audio-edid)
[86.726216] Subtest dp-audio-edid: SKIP (0.000s)
[87.010678] [008/137] (923s left) api_intel_bb (reset-flags)
[87.209157] Starting subtest: reset-flags
[87.234136] Subtest reset-flags: SUCCESS (0.023s)
[87.339373] [009/137] (923s left) kms_vblank (pipe-d-wait-forked-hang)
[87.653299] Subtest pipe-D-wait-forked-hang: SKIP (0.000s)
[87.741910] [010/137] (923s left) kms_psr (cursor_mmap_cpu)
[87.949315] Subtest cursor_mmap_cpu: SKIP (0.000s)
[88.005693] [011/137] (922s left) syncobj_wait (multi-wait-for-submit-submitted)
[88.231104] Starting subtest: multi-wait-for-submit-submitted
[88.338478] Subtest multi-wait-for-submit-submitted: SUCCESS (0.106s)
[88.425950] [012/137] (922s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions)
[88.732491] Starting subtest: flip-vs-cursor-atomic-transitions
[100.470472] Subtest flip-vs-cursor-atomic-transitions: FAIL (11.408s)
[100.493918] [013/137] (910s left) gem_render_copy (yf-tiled-ccs-to-linear)
[100.716219] Starting subtest: yf-tiled-ccs-to-linear
[100.717642] Starting dynamic subtest: smem
[101.007660] Dynamic subtest smem: SUCCESS (0.291s)
[101.009158] Subtest yf-tiled-ccs-to-linear: SUCCESS (0.291s)
[101.210615] [014/137] (909s left) gem_ctx_param (invalid-size-get)
[101.411757] Starting subtest: invalid-size-get
[101.413361] Subtest invalid-size-get: SUCCESS (0.001s)
[101.490534] [015/137] (909s left) kms_universal_plane (universal-plane-pipe-b-sanity)
[101.796445] Starting subtest: universal-plane-pipe-B-sanity
[102.615836] Subtest universal-plane-pipe-B-sanity: SUCCESS (0.822s)
[102.917227] [016/137] (908s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[103.229636] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[103.232402] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[103.334855] [017/137] (907s left) gem_eio (in-flight-external)
[103.564601] Starting subtest: in-flight-external
[103.649435] Subtest in-flight-external: SUCCESS (0.085s)
[103.818605] [018/137] (907s left) gem_ctx_persistence (legacy-engines-persistence)
[104.025439] Starting subtest: legacy-engines-persistence
[104.027229] Starting dynamic subtest: render
[104.822456] Dynamic subtest render: SUCCESS (0.795s)
[104.838318] Starting dynamic subtest: bsd
[105.577246] Dynamic subtest bsd: SUCCESS (0.754s)
[105.593502] Starting dynamic subtest: blt
[106.345223] Dynamic subtest blt: SUCCESS (0.767s)
[106.362257] Starting dynamic subtest: vebox
[107.118717] Dynamic subtest vebox: SUCCESS (0.772s)
[107.120161] Subtest legacy-engines-persistence: SUCCESS (3.093s)
[107.201649] [019/137] (903s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[107.512390] Starting subtest: fbcpsr-tiling-y
[107.515016] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[107.602152] [020/137] (903s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[107.909213] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[107.911952] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[108.001476] [021/137] (903s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[108.319384] Starting subtest: fbc-2p-pri-indfb-multidraw
[108.322291] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[108.401763] [022/137] (902s left) prime_vgem (basic-fence-read)
[108.721151] Starting subtest: basic-fence-read
[108.826837] Subtest basic-fence-read: SUCCESS (0.105s)
[108.919873] [023/137] (902s left) kms_psr (psr2_cursor_plane_move)
[109.125331] Subtest psr2_cursor_plane_move: SKIP (0.000s)
[109.170058] [024/137] (902s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_dg2_rc_ccs_cc)
[109.883124] Subtest pipe-D-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[110.181558] [025/137] (901s left) kms_flip (2x-flip-vs-suspend-interruptible)
[110.477878] Starting subtest: 2x-flip-vs-suspend-interruptible
[110.481158] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.003s)
[110.581224] [026/137] (900s left) gem_exec_balancer (bonded-semaphore)
[110.808986] Starting subtest: bonded-semaphore
[114.351222] Subtest bonded-semaphore: SUCCESS (3.541s)
[118.181858] [027/137] (893s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[118.385884] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[118.489288] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.102s)
[118.564718] [028/137] (892s left) gem_mmap_wc (fault-concurrent)
[118.762837] Starting subtest: fault-concurrent
[120.433268] Subtest fault-concurrent: SUCCESS (1.669s)
[120.513953] [029/137] (891s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-blt)
[120.820854] Starting subtest: psr-1p-offscren-pri-shrfb-draw-blt
[120.823465] Subtest psr-1p-offscren-pri-shrfb-draw-blt: SKIP (0.000s)
[120.913676] [030/137] (890s left) kms_frontbuffer_tracking (psr-slowdraw)
[121.223461] Starting subtest: psr-slowdraw
[121.226314] Subtest psr-slowdraw: SKIP (0.000s)
[121.314383] [031/137] (890s left) kms_atomic (plane-primary-legacy)
[121.736535] Starting subtest: plane-primary-legacy
[121.913441] Subtest plane-primary-legacy: SUCCESS (0.177s)
[122.340725] [032/137] (889s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[122.641766] Starting subtest: cursorB-vs-flipB-toggle
[122.644669] Subtest cursorB-vs-flipB-toggle: SKIP (0.003s)
[122.722211] [033/137] (888s left) syncobj_timeline (invalid-signal-illegal-point)
[122.925746] Starting subtest: invalid-signal-illegal-point
[122.927395] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[123.004906] [034/137] (888s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[123.296023] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[123.298681] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.003s)
[123.405590] [035/137] (888s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc)
[124.105682] Subtest pipe-D-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[124.417283] [036/137] (887s left) vc4/vc4_wait_bo (used-bo-0ns)
[124.682795] Subtest used-bo-0ns: SKIP (0.000s)
[124.750879] [037/137] (887s left) gem_pxp (reject-modify-context-protection-on)
[127.468729] Subtest reject-modify-context-protection-on: SKIP (0.000s)
[127.550041] [038/137] (884s left) feature_discovery (display)
[127.843734] Starting subtest: display
[127.845296] Subtest display: SUCCESS (0.000s)
[127.934180] [039/137] (883s left) gem_ccs (ctrl-surf-copy)
[128.143544] Subtest ctrl-surf-copy: SKIP (0.000s)
[128.200356] [040/137] (883s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[128.503472] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[128.506176] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[128.601920] [041/137] (883s left) kms_mmap_write_crc (main)
[128.922393] Starting subtest: main
[128.925460] Starting dynamic subtest: pipe-A-DP-1
[132.313245] Dynamic subtest pipe-A-DP-1: SUCCESS (3.387s)
[132.314772] Subtest main: SUCCESS (3.389s)
[132.649158] [042/137] (879s left) gem_exec_flush (basic-batch-kernel-default-wb)
[132.874494] Starting subtest: basic-batch-kernel-default-wb
[138.626296] Subtest basic-batch-kernel-default-wb: SUCCESS (5.749s)
[138.822203] [043/137] (873s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[139.127178] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[139.130027] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[139.221564] [044/137] (872s left) perf_pmu (render-node-busy)
[139.435535] Starting subtest: render-node-busy
[139.437346] Starting dynamic subtest: rcs0
[139.959860] Dynamic subtest rcs0: SUCCESS (0.524s)
[139.979295] Starting dynamic subtest: bcs0
[140.485673] Dynamic subtest bcs0: SUCCESS (0.526s)
[140.503169] Starting dynamic subtest: vcs0
[141.007671] Dynamic subtest vcs0: SUCCESS (0.522s)
[141.025149] Starting dynamic subtest: vecs0
[141.529642] Dynamic subtest vecs0: SUCCESS (0.522s)
[141.531084] Subtest render-node-busy: SUCCESS (2.094s)
[141.605661] [045/137] (870s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[141.909831] Starting subtest: cursor-rapid-movement-256x256
[141.921428] Starting dynamic subtest: pipe-A-DP-1
[142.418353] Dynamic subtest pipe-A-DP-1: SUCCESS (0.494s)
[142.499110] Starting dynamic subtest: pipe-C-DP-1
[143.297837] Dynamic subtest pipe-C-DP-1: SUCCESS (0.817s)
[143.299305] Subtest cursor-rapid-movement-256x256: SUCCESS (1.386s)
[143.643226] [046/137] (868s left) kms_vblank (pipe-a-wait-forked-busy-hang)
[143.950069] Starting subtest: pipe-A-wait-forked-busy-hang
[151.016875] Subtest pipe-A-wait-forked-busy-hang: SUCCESS (7.067s)
[151.346233] [047/137] (860s left) kms_vblank (pipe-b-ts-continuation-modeset-rpm)
[151.648909] Starting subtest: pipe-B-ts-continuation-modeset-rpm
[154.066497] Subtest pipe-B-ts-continuation-modeset-rpm: SUCCESS (2.419s)
[154.542704] [048/137] (857s left) vgem_basic (setversion)
[154.755182] Starting subtest: setversion
[154.757943] Subtest setversion: SUCCESS (0.000s)
[154.825864] [049/137] (857s left) kms_plane_lowres (tiling-y)
[155.123600] Starting subtest: tiling-y
[155.125391] Starting dynamic subtest: pipe-A-DP-1
[161.260042] Dynamic subtest pipe-A-DP-1: SUCCESS (6.133s)
[161.360131] Starting dynamic subtest: pipe-B-DP-1
[167.338687] Dynamic subtest pipe-B-DP-1: SUCCESS (5.994s)
[167.479943] Starting dynamic subtest: pipe-C-DP-1
[171.523416] Dynamic subtest pipe-C-DP-1: SUCCESS (4.060s)
[171.604399] Subtest tiling-y: SUCCESS (16.482s)
[172.058689] [050/137] (840s left) kms_getfb (getfb-repeated-different-handles)
[172.267745] Starting subtest: getfb-repeated-different-handles
[172.269380] Subtest getfb-repeated-different-handles: SUCCESS (0.000s)
[172.357967] [051/137] (839s left) gem_mmap (big-bo)
[172.558672] Starting subtest: big-bo
[174.468947] Subtest big-bo: SUCCESS (1.910s)
[174.582966] [052/137] (837s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[175.007745] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[175.010551] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[175.091453] [053/137] (837s left) gem_wait (invalid-buf)
[175.304101] Starting subtest: invalid-buf
[175.305661] Subtest invalid-buf: SUCCESS (0.000s)
[175.574907] [054/137] (836s left) gen3_render_linear_blits
[175.823331] [055/137] (836s left) vc4/vc4_purgeable_bo (access-purged-bo-mem)
[176.103781] Subtest access-purged-bo-mem: SKIP (0.000s)
[176.173957] [056/137] (836s left) gen7_exec_parse (oacontrol-tracking)
[176.382925] Subtest oacontrol-tracking: SKIP (0.000s)
[176.440340] [057/137] (836s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[176.755275] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[176.757877] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[176.857859] [058/137] (835s left) kms_cursor_edge_walk (128x128-left-edge)
[177.163087] Starting subtest: 128x128-left-edge
[177.164636] Starting dynamic subtest: pipe-A-DP-1
[180.982396] Dynamic subtest pipe-A-DP-1: SUCCESS (3.817s)
[181.004836] Starting dynamic subtest: pipe-C-DP-1
[184.464047] Dynamic subtest pipe-C-DP-1: SUCCESS (3.477s)
[184.465955] Subtest 128x128-left-edge: SUCCESS (7.303s)
[184.779263] [059/137] (827s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[185.111365] Starting subtest: y-tiled-64bpp-rotate-180
[193.322272] Subtest y-tiled-64bpp-rotate-180: SUCCESS (8.210s)
[194.312738] [060/137] (818s left) gem_close_race (basic-process)
[194.539295] Starting subtest: basic-process
[194.672315] Subtest basic-process: SUCCESS (0.133s)
[194.876930] [061/137] (817s left) gem_userptr_blits (sync-unmap)
[195.115795] Starting subtest: sync-unmap
[195.163531] Subtest sync-unmap: SUCCESS (0.047s)
[195.461861] [062/137] (817s left) v3d/v3d_job_submission (threaded-job-submission)
[195.706579] Subtest threaded-job-submission: SKIP (0.000s)
[195.776764] [063/137] (816s left) gem_exec_reloc (basic-gtt-read)
[195.978193] Starting subtest: basic-gtt-read
[196.000240] Subtest basic-gtt-read: SUCCESS (0.022s)
[196.195035] [064/137] (816s left) kms_chamelium_edid (hdmi-edid-stress-resolution-4k)
[196.590774] Subtest hdmi-edid-stress-resolution-4k: SKIP (0.000s)
[196.869205] [065/137] (815s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[197.177056] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[197.179731] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[197.280916] [066/137] (815s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_dg2_mc_ccs)
[197.712333] Starting subtest: pipe-A-random-ccs-data-4_tiled_dg2_mc_ccs
[197.716778] Subtest pipe-A-random-ccs-data-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[198.303365] [067/137] (814s left) kms_vblank (pipe-b-query-busy)
[198.614758] Starting subtest: pipe-B-query-busy
[201.450067] Subtest pipe-B-query-busy: SUCCESS (2.837s)
[201.781350] [068/137] (811s left) kms_scaling_modes (scaling-mode-full)
[202.083670] Starting subtest: scaling-mode-full
[202.086313] Subtest scaling-mode-full: SKIP (0.001s)
[202.163690] [069/137] (810s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[202.468162] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[202.469721] Starting dynamic subtest: pipe-A-DP-1
[204.757599] Dynamic subtest pipe-A-DP-1: SUCCESS (2.288s)
[204.779263] Starting dynamic subtest: pipe-B-DP-1
[207.332556] Dynamic subtest pipe-B-DP-1: SUCCESS (2.572s)
[207.356259] Starting dynamic subtest: pipe-C-DP-1
[207.706384] Dynamic subtest pipe-C-DP-1: SUCCESS (0.370s)
[207.707867] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (5.242s)
[208.095555] [070/137] (804s left) api_intel_allocator (execbuf-with-allocator)
[208.298263] Starting subtest: execbuf-with-allocator
[208.307364] Subtest execbuf-with-allocator: SUCCESS (0.010s)
[208.410283] [071/137] (804s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[208.716676] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[208.718236] Starting dynamic subtest: pipe-A-DP-1
[208.869677] Dynamic subtest pipe-A-DP-1: SUCCESS (0.153s)
[209.015423] Starting dynamic subtest: pipe-B-DP-1
[209.533710] Dynamic subtest pipe-B-DP-1: SUCCESS (0.545s)
[209.617711] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (0.905s)
[209.959892] [072/137] (803s left) gem_lmem_swapping (parallel-random-verify)
[210.172115] Subtest parallel-random-verify: SKIP (0.000s)
[210.225540] [073/137] (802s left) kms_content_protection (dp-mst-lic-type-1)
[210.561653] Starting subtest: dp-mst-lic-type-1
[210.564468] Subtest dp-mst-lic-type-1: SKIP (0.001s)
[210.676351] [074/137] (802s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[210.996567] Starting subtest: x-tiled-16bpp-rotate-90
[211.067989] Subtest x-tiled-16bpp-rotate-90: SKIP (0.072s)
[211.175869] [075/137] (802s left) gem_mmap_offset (oob-read)
[211.378510] Starting subtest: oob-read
[211.380138] Subtest oob-read: SUCCESS (0.002s)
[211.476646] [076/137] (801s left) kms_legacy_colorkey (basic)
[211.781349] Starting subtest: basic
[211.782734] Starting dynamic subtest: pipe-A
[211.802502] Dynamic subtest pipe-A: SUCCESS (0.025s)
[211.821533] Starting dynamic subtest: pipe-B
[211.822947] Dynamic subtest pipe-B: SUCCESS (0.001s)
[211.841164] Starting dynamic subtest: pipe-C
[211.842538] Dynamic subtest pipe-C: SUCCESS (0.000s)
[211.843849] Subtest basic: SUCCESS (0.028s)
[211.893503] [077/137] (801s left) kms_vblank (pipe-c-wait-busy)
[212.201885] Starting subtest: pipe-C-wait-busy
[215.150331] Subtest pipe-C-wait-busy: SUCCESS (2.949s)
[215.468861] [078/137] (797s left) gem_exec_schedule (deep)
[215.834948] Starting subtest: deep
[215.836374] Starting dynamic subtest: rcs0
[229.882442] Dynamic subtest rcs0: SUCCESS (14.048s)
[229.907660] Starting dynamic subtest: bcs0
[245.040336] Dynamic subtest bcs0: SUCCESS (15.154s)
[245.082040] Starting dynamic subtest: vcs0
[259.176385] Dynamic subtest vcs0: SUCCESS (14.132s)
[259.221135] Starting dynamic subtest: vecs0
[273.400754] Dynamic subtest vecs0: SUCCESS (14.225s)
[273.402306] Subtest deep: SUCCESS (57.568s)
[274.223453] [079/137] (739s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[274.532764] Starting subtest: fbc-shrfb-scaledprimary
[277.748200] Subtest fbc-shrfb-scaledprimary: SUCCESS (3.215s)
[277.885927] [080/137] (735s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[278.091281] Starting subtest: wait-all-for-submit-delayed-submit
[278.198274] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.105s)
[278.289190] [081/137] (735s left) v3d/v3d_submit_csd (single-in-sync)
[278.547720] Subtest single-in-sync: SKIP (0.000s)
[278.619694] [082/137] (734s left) gem_ppgtt (flink-and-close-vma-leak)
[278.833803] Starting subtest: flink-and-close-vma-leak
[278.840388] Subtest flink-and-close-vma-leak: SUCCESS (0.009s)
[278.952216] [083/137] (734s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[279.172221] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[279.174847] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[279.369974] [084/137] (734s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs)
[279.957969] Starting subtest: pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs
[279.964453] Subtest pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs: SKIP (0.005s)
[280.397678] [085/137] (733s left) kms_ccs (pipe-b-ccs-on-another-bo-yf_tiled_ccs)
[280.992915] Starting subtest: pipe-B-ccs-on-another-bo-yf_tiled_ccs
[280.994617] Subtest pipe-B-ccs-on-another-bo-yf_tiled_ccs: SUCCESS (0.001s)
[281.484312] [086/137] (732s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[281.817698] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[281.846552] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.025s)
[281.949325] [087/137] (731s left) syncobj_timeline (multi-wait-signaled)
[282.159040] Starting subtest: multi-wait-signaled
[282.160598] Subtest multi-wait-signaled: SUCCESS (0.002s)
[282.249232] [088/137] (731s left) gem_exec_big (single)
[282.464419] Starting subtest: single
[321.420554] Subtest single: SUCCESS (38.951s)
[324.189876] [089/137] (689s left) gem_ctx_param (invalid-ctx-set)
[324.505877] Starting subtest: invalid-ctx-set
[324.508323] Subtest invalid-ctx-set: SUCCESS (0.000s)
[324.599421] [090/137] (689s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[324.903558] Starting subtest: short-flip-after-cursor-atomic-transitions
[326.248314] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (1.346s)
[326.576363] [091/137] (687s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[326.904212] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[326.906985] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[327.074180] [092/137] (686s left) gem_userptr_blits (vma-merge)
[327.333093] Starting subtest: vma-merge
[347.814699] Subtest vma-merge: FAIL (20.463s)
[347.893850] [093/137] (665s left) i915_suspend (basic-s2idle-without-i915)
[348.094377] Starting subtest: basic-s2idle-without-i915
[367.645955] Subtest basic-s2idle-without-i915: SUCCESS (19.543s)
[370.819610] [094/137] (646s left) kms_flip (flip-vs-rmfb)
[371.118774] Starting subtest: flip-vs-rmfb
[371.122868] Starting dynamic subtest: A-DP1
[381.631929] Dynamic subtest A-DP1: SUCCESS (10.508s)
[381.652364] Starting dynamic subtest: B-DP1
[392.081412] Dynamic subtest B-DP1: SUCCESS (10.450s)
[392.102444] Starting dynamic subtest: C-DP1
[402.546648] Dynamic subtest C-DP1: SUCCESS (10.463s)
[402.548074] Subtest flip-vs-rmfb: SUCCESS (31.432s)
[402.956290] [095/137] (614s left) gem_exec_schedule (preempt-other-chain)
[403.210405] Starting subtest: preempt-other-chain
[403.211937] Starting dynamic subtest: rcs0
[403.348940] Dynamic subtest rcs0: SUCCESS (0.136s)
[403.378621] Starting dynamic subtest: bcs0
[403.462571] Dynamic subtest bcs0: SUCCESS (0.113s)
[403.489839] Starting dynamic subtest: vcs0
[403.567147] Dynamic subtest vcs0: SUCCESS (0.102s)
[403.592546] Starting dynamic subtest: vecs0
[403.670112] Dynamic subtest vecs0: SUCCESS (0.102s)
[403.671720] Subtest preempt-other-chain: SUCCESS (0.459s)
[404.020976] [096/137] (613s left) kms_plane_scaling (2x-scaler-multi-pipe)
[404.334409] Starting subtest: 2x-scaler-multi-pipe
[404.340518] Subtest 2x-scaler-multi-pipe: SKIP (0.005s)
[404.420406] [097/137] (612s left) v3d/v3d_submit_cl (single-in-sync)
[404.680069] Subtest single-in-sync: SKIP (0.000s)
[404.752948] [098/137] (612s left) kms_ccs (pipe-c-random-ccs-data-y_tiled_gen12_rc_ccs_cc)
[405.408658] Starting subtest: pipe-C-random-ccs-data-y_tiled_gen12_rc_ccs_cc
[405.413267] Subtest pipe-C-random-ccs-data-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[405.793418] [099/137] (611s left) api_intel_bb (intel-bb-blit-x)
[405.997737] Starting subtest: intel-bb-blit-x
[409.136462] Subtest intel-bb-blit-x: SUCCESS (3.138s)
[409.244214] [100/137] (607s left) gem_exec_whisper (basic-normal)
[409.473174] Starting subtest: basic-normal
[415.577429] Subtest basic-normal: SUCCESS (6.103s)
[415.777880] [101/137] (601s left) prime_vgem (basic-userptr)
[416.008508] Starting subtest: basic-userptr
[416.031640] Subtest basic-userptr: SKIP (0.022s)
[416.159963] [102/137] (601s left) gem_exec_schedule (submit-early-slice)
[416.394792] Starting subtest: submit-early-slice
[416.396777] Starting dynamic subtest: rcs0
[416.468687] Dynamic subtest rcs0: SUCCESS (0.070s)
[416.491853] Starting dynamic subtest: bcs0
[416.530446] Dynamic subtest bcs0: SUCCESS (0.061s)
[416.559288] Starting dynamic subtest: vcs0
[416.582635] Dynamic subtest vcs0: SUCCESS (0.051s)
[416.609864] Starting dynamic subtest: vecs0
[416.640452] Dynamic subtest vecs0: SUCCESS (0.056s)
[416.641748] Subtest submit-early-slice: SUCCESS (0.245s)
[416.995239] [103/137] (600s left) gem_workarounds (reset)
[417.234632] Starting subtest: reset
[417.261305] Subtest reset: SUCCESS (0.026s)
[417.377651] [104/137] (599s left) kms_sequence (get-forked-busy)
[417.682056] Starting subtest: get-forked-busy
[417.686754] Starting dynamic subtest: DP-1-pipe-A
[420.149329] Dynamic subtest DP-1-pipe-A: SUCCESS (2.464s)
[420.174819] Starting dynamic subtest: DP-1-pipe-B
[422.918073] Dynamic subtest DP-1-pipe-B: SUCCESS (2.765s)
[422.941710] Starting dynamic subtest: DP-1-pipe-C
[425.807608] Dynamic subtest DP-1-pipe-C: SUCCESS (2.885s)
[425.809360] Subtest get-forked-busy: SUCCESS (8.127s)
[426.128529] [105/137] (591s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[426.346126] Starting subtest: cpuset-medium-copy-odd
[438.306858] Subtest cpuset-medium-copy-odd: SUCCESS (11.962s)
[438.494794] [106/137] (578s left) i915_pm_dc (dc5-dpms)
[439.970052] Starting subtest: dc5-dpms
[441.397205] Subtest dc5-dpms: SUCCESS (1.426s)
[441.600287] [107/137] (575s left) kms_busy (extended-pageflip-hang-newfb)
[441.922302] Starting subtest: extended-pageflip-hang-newfb
[441.927150] Starting dynamic subtest: pipe-A
[443.756380] Dynamic subtest pipe-A: SUCCESS (1.826s)
[443.783375] Starting dynamic subtest: pipe-C
[445.499554] Dynamic subtest pipe-C: SUCCESS (1.735s)
[445.501026] Subtest extended-pageflip-hang-newfb: SUCCESS (3.578s)
[445.854896] [108/137] (571s left) gem_exec_fence (syncobj-signal)
[446.533048] Starting subtest: syncobj-signal
[446.536036] Subtest syncobj-signal: SUCCESS (0.006s)
[446.902166] [109/137] (570s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[447.185135] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[447.193124] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.003s)
[447.301512] [110/137] (570s left) i915_pm_rpm (i2c)
[449.106187] Starting subtest: i2c
[451.436110] Subtest i2c: SUCCESS (2.331s)
[451.627170] [111/137] (566s left) kms_hdr (bpc-switch)
[451.938457] Starting subtest: bpc-switch
[454.166208] Starting dynamic subtest: pipe-A-DP-1
[455.264226] Dynamic subtest pipe-A-DP-1: SUCCESS (1.096s)
[455.265684] Subtest bpc-switch: SUCCESS (3.327s)
[455.638894] [112/137] (562s left) perf_pmu (all-busy-check-all)
[455.848533] Starting subtest: all-busy-check-all
[456.376649] Subtest all-busy-check-all: SUCCESS (0.528s)
[456.470598] [113/137] (561s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[456.777590] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[456.779079] Starting dynamic subtest: pipe-A-valid-mode
[457.475622] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.696s)
[457.477239] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SUCCESS (0.700s)
[457.824655] [114/137] (559s left) drm_fdinfo (basics)
[458.037496] Starting subtest: basics
[458.038971] Subtest basics: SUCCESS (0.000s)
[458.124254] [115/137] (559s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[458.514838] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[458.782637] [116/137] (559s left) sysfs_defaults (readonly)
[458.989588] Starting subtest: readonly
[458.991139] Starting dynamic subtest: vecs0
[458.992554] Dynamic subtest vecs0: SUCCESS (0.001s)
[459.015510] Starting dynamic subtest: vcs0
[459.016856] Dynamic subtest vcs0: SUCCESS (0.001s)
[459.038258] Starting dynamic subtest: bcs0
[459.039668] Dynamic subtest bcs0: SUCCESS (0.001s)
[459.061176] Starting dynamic subtest: rcs0
[459.062458] Dynamic subtest rcs0: SUCCESS (0.001s)
[459.063817] Subtest readonly: SUCCESS (0.012s)
[459.094998] [117/137] (558s left) gem_exec_fair (basic-none-share)
[459.331914] Starting subtest: basic-none-share
[459.333657] Starting dynamic subtest: rcs0
[465.005615] Dynamic subtest rcs0: SUCCESS (5.670s)
[465.007114] Subtest basic-none-share: SUCCESS (5.672s)
[465.211602] [118/137] (552s left) i915_pm_rpm (dpms-mode-unset-non-lpsp)
[467.017914] Starting subtest: dpms-mode-unset-non-lpsp
[468.488152] Subtest dpms-mode-unset-non-lpsp: SUCCESS (1.471s)
[468.943585] [119/137] (549s left) gem_exec_flush (basic-uc-pro-default)
[469.172458] Starting subtest: basic-uc-pro-default
[474.658600] Subtest basic-uc-pro-default: SUCCESS (5.486s)
[474.859392] [120/137] (543s left) gem_tiled_fence_blits (basic)
[475.400301] Starting subtest: basic
[475.436144] Subtest basic: SUCCESS (0.038s)
[475.588221] [121/137] (542s left) kms_cursor_crc (cursor-onscreen-256x256)
[476.043829] Starting subtest: cursor-onscreen-256x256
[476.057383] Starting dynamic subtest: pipe-A-DP-1
[478.596174] Dynamic subtest pipe-A-DP-1: SUCCESS (2.537s)
[478.683056] Starting dynamic subtest: pipe-C-DP-1
[481.355543] Dynamic subtest pipe-C-DP-1: SUCCESS (2.696s)
[481.356848] Subtest cursor-onscreen-256x256: SUCCESS (5.312s)
[481.713976] [122/137] (536s left) kms_ccs (pipe-a-bad-rotation-90-y_tiled_gen12_mc_ccs)
[482.101775] Starting subtest: pipe-A-bad-rotation-90-y_tiled_gen12_mc_ccs
[482.105597] Subtest pipe-A-bad-rotation-90-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[482.743232] [123/137] (535s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[483.048183] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[483.049572] Starting dynamic subtest: pipe-A-DP-1
[483.365647] Dynamic subtest pipe-A-DP-1: SUCCESS (0.316s)
[483.397541] Starting dynamic subtest: pipe-B-DP-1
[483.757860] Dynamic subtest pipe-B-DP-1: SUCCESS (0.388s)
[483.786549] Starting dynamic subtest: pipe-C-DP-1
[483.852076] Dynamic subtest pipe-C-DP-1: SKIP (0.087s)
[483.853458] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SUCCESS (0.804s)
[484.232316] [124/137] (533s left) gem_fence_thrash (bo-copy)
[484.431425] Starting subtest: bo-copy
[485.856253] Subtest bo-copy: SUCCESS (1.423s)
[485.966979] [125/137] (532s left) kms_force_connector_basic (force-connector-state)
[486.168067] Subtest force-connector-state: SKIP (0.000s)
[486.232419] [126/137] (532s left) kms_async_flips (alternate-sync-async-flip)
[486.539622] Starting subtest: alternate-sync-async-flip
[486.540966] Starting dynamic subtest: pipe-A-DP-1
[489.334872] Dynamic subtest pipe-A-DP-1: SUCCESS (2.795s)
[489.356070] Starting dynamic subtest: pipe-B-DP-1
[492.060632] Dynamic subtest pipe-B-DP-1: SUCCESS (2.722s)
[492.083127] Starting dynamic subtest: pipe-C-DP-1
[494.703156] Dynamic subtest pipe-C-DP-1: SUCCESS (2.638s)
[494.704669] Subtest alternate-sync-async-flip: SUCCESS (8.166s)
[495.262227] [127/137] (523s left) kms_display_modes (extended-mode-basic)
[495.571959] Starting subtest: extended-mode-basic
[495.574646] Subtest extended-mode-basic: SKIP (0.000s)
[495.628485] [128/137] (522s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[495.938412] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[495.941247] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[496.062107] [129/137] (522s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[496.373222] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[496.375979] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[496.478718] [130/137] (521s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[496.699761] Starting subtest: y-tiled-ccs-to-y-tiled
[496.701378] Starting dynamic subtest: smem
[497.000993] Dynamic subtest smem: SUCCESS (0.297s)
[497.002512] Subtest y-tiled-ccs-to-y-tiled: SUCCESS (0.299s)
[497.198513] [131/137] (521s left) perf_pmu (busy)
[497.412820] Starting subtest: busy
[497.414440] Starting dynamic subtest: rcs0
[497.940455] Dynamic subtest rcs0: SUCCESS (0.526s)
[497.963634] Starting dynamic subtest: bcs0
[498.469377] Dynamic subtest bcs0: SUCCESS (0.527s)
[498.492646] Starting dynamic subtest: vcs0
[499.000666] Dynamic subtest vcs0: SUCCESS (0.531s)
[499.023269] Starting dynamic subtest: vecs0
[499.528345] Dynamic subtest vecs0: SUCCESS (0.526s)
[499.529938] Subtest busy: SUCCESS (2.116s)
[499.629308] [132/137] (518s left) gem_ctx_param (invalid-set-ringsize)
[499.833574] Starting subtest: invalid-set-ringsize
[499.835067] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[499.928944] [133/137] (518s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[500.257904] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[501.534655] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (1.275s)
[501.881462] [134/137] (516s left) kms_ccs (pipe-c-crc-primary-basic-y_tiled_ccs)
[502.499672] Starting subtest: pipe-C-crc-primary-basic-y_tiled_ccs
[502.502398] Subtest pipe-C-crc-primary-basic-y_tiled_ccs: SKIP (0.001s)
[502.909085] [135/137] (515s left) gem_ctx_exec (basic-invalid-context)
[503.114614] Starting subtest: basic-invalid-context
[503.120790] Subtest basic-invalid-context: SUCCESS (0.006s)
[503.241959] [136/137] (515s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[503.539454] Starting subtest: 2x-cursor-vs-flip-atomic
[503.547078] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.005s)
[503.642499] [137/137] (515s left) kms_ccs (pipe-b-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc)
[504.188095] Starting subtest: pipe-B-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc
[504.190732] Subtest pipe-B-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[504.666528] Closing watchdogs
Done.