[37.342385] Initializing watchdogs
[37.342468]   /dev/watchdog0
[37.348896] [001/137] (960s left) gen9_exec_parse (bb-oversize)
[37.431476] Subtest bb-oversize: SKIP (0.000s)
[37.463871] [002/137] (960s left) gem_ppgtt (blt-vs-render-ctx0)
[37.542599] Starting subtest: blt-vs-render-ctx0
[70.268140] Subtest blt-vs-render-ctx0: SUCCESS (32.725s)
[70.380803] [003/137] (927s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[72.498683] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[72.500900] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[72.571525] [004/137] (925s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[74.586917] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[74.589096] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[74.669872] [005/137] (923s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[76.680552] Starting subtest: yf-tiled-8bpp-rotate-90
[76.682879] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[76.770172] [006/137] (921s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-onoff)
[78.784117] Starting subtest: fbc-2p-primscrn-cur-indfb-onoff
[78.786095] Subtest fbc-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[78.868439] [007/137] (919s left) kms_chamelium_audio (dp-audio-edid)
[80.913084] Subtest dp-audio-edid: SKIP (0.000s)
[80.967945] [008/137] (916s left) api_intel_bb (reset-flags)
[81.038313] Starting subtest: reset-flags
[81.059965] Subtest reset-flags: SUCCESS (0.022s)
[81.156590] [009/137] (916s left) kms_vblank (pipe-d-wait-forked-hang)
[83.132666] Starting subtest: pipe-D-wait-forked-hang
[97.900805] Subtest pipe-D-wait-forked-hang: SUCCESS (14.767s)
[98.003424] [010/137] (899s left) kms_psr (cursor_mmap_cpu)
[98.074247] Subtest cursor_mmap_cpu: SKIP (0.000s)
[98.094693] [011/137] (899s left) syncobj_wait (multi-wait-for-submit-submitted)
[98.168420] Starting subtest: multi-wait-for-submit-submitted
[98.273527] Subtest multi-wait-for-submit-submitted: SUCCESS (0.103s)
[98.364146] [012/137] (899s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions)
[100.353241] Starting subtest: flip-vs-cursor-atomic-transitions
[109.683946] Subtest flip-vs-cursor-atomic-transitions: SUCCESS (9.330s)
[109.799705] [013/137] (888s left) gem_render_copy (yf-tiled-ccs-to-linear)
[109.877191] Starting subtest: yf-tiled-ccs-to-linear
[109.878135] Starting dynamic subtest: smem
[109.957742] Dynamic subtest smem: SUCCESS (0.080s)
[109.963151] Starting dynamic subtest: lmem0
[110.280035] Dynamic subtest lmem0: SUCCESS (0.319s)
[110.280898] Subtest yf-tiled-ccs-to-linear: SUCCESS (0.400s)
[110.514255] [014/137] (887s left) gem_ctx_param (invalid-size-get)
[110.590887] Starting subtest: invalid-size-get
[110.591758] Subtest invalid-size-get: SUCCESS (0.001s)
[110.648280] [015/137] (887s left) kms_universal_plane (universal-plane-pipe-b-sanity)
[112.626592] Starting subtest: universal-plane-pipe-B-sanity
[113.499219] Subtest universal-plane-pipe-B-sanity: SUCCESS (0.867s)
[113.625436] [016/137] (884s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[115.607356] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[115.609412] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[115.675706] [017/137] (882s left) gem_eio (in-flight-external)
[115.945802] Starting subtest: in-flight-external
[117.239596] Subtest in-flight-external: SUCCESS (1.294s)
[119.283392] [018/137] (878s left) gem_ctx_persistence (legacy-engines-persistence)
[119.354910] Starting subtest: legacy-engines-persistence
[119.355776] Starting dynamic subtest: render
[120.140168] Dynamic subtest render: SUCCESS (0.785s)
[120.147784] Starting dynamic subtest: bsd1
[120.874056] Dynamic subtest bsd1: SUCCESS (0.734s)
[120.879824] Starting dynamic subtest: bsd2
[121.643053] Dynamic subtest bsd2: SUCCESS (0.769s)
[121.648755] Starting dynamic subtest: blt
[122.411178] Dynamic subtest blt: SUCCESS (0.768s)
[122.416833] Starting dynamic subtest: vebox
[123.179063] Dynamic subtest vebox: SUCCESS (0.768s)
[123.179853] Subtest legacy-engines-persistence: SUCCESS (3.824s)
[123.281726] [019/137] (874s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[125.262115] Starting subtest: fbcpsr-tiling-y
[125.264182] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[125.358713] [020/137] (872s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[127.367150] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[127.369251] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[127.445635] [021/137] (870s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[129.509421] Starting subtest: fbc-2p-pri-indfb-multidraw
[129.511119] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[129.591059] [022/137] (868s left) prime_vgem (basic-fence-read)
[129.747727] Starting subtest: basic-fence-read
[129.754627] Subtest basic-fence-read: SKIP (0.006s)
[129.864748] [023/137] (868s left) kms_psr (psr2_cursor_plane_move)
[129.968833] Subtest psr2_cursor_plane_move: SKIP (0.000s)
[129.999507] [024/137] (868s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_dg2_rc_ccs_cc)
[132.305361] Starting subtest: pipe-D-bad-rotation-90-4_tiled_dg2_rc_ccs_cc
[132.307139] Subtest pipe-D-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[132.480538] [025/137] (865s left) kms_flip (2x-flip-vs-suspend-interruptible)
[133.508906] Starting subtest: 2x-flip-vs-suspend-interruptible
[133.535821] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.026s)
[133.597871] [026/137] (864s left) gem_exec_balancer (bonded-semaphore)
[133.697719] Starting subtest: bonded-semaphore
[133.699564] Subtest bonded-semaphore: SKIP (0.000s)
[134.047094] [027/137] (864s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[134.116366] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[134.227590] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.107s)
[134.289490] [028/137] (864s left) gem_mmap_wc (fault-concurrent)
[134.359191] Subtest fault-concurrent: SKIP (0.000s)
[134.388694] [029/137] (863s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-blt)
[136.373329] Starting subtest: psr-1p-offscren-pri-shrfb-draw-blt
[136.375070] Subtest psr-1p-offscren-pri-shrfb-draw-blt: SKIP (0.000s)
[136.446559] [030/137] (861s left) kms_frontbuffer_tracking (psr-slowdraw)
[138.441045] Starting subtest: psr-slowdraw
[138.443215] Subtest psr-slowdraw: SKIP (0.000s)
[138.513377] [031/137] (859s left) kms_atomic (plane-primary-legacy)
[140.723140] Starting subtest: plane-primary-legacy
[141.054281] Subtest plane-primary-legacy: SUCCESS (0.330s)
[141.236191] [032/137] (857s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[143.209191] Starting subtest: cursorB-vs-flipB-toggle
[143.212926] Subtest cursorB-vs-flipB-toggle: SKIP (0.002s)
[143.268812] [033/137] (855s left) syncobj_timeline (invalid-signal-illegal-point)
[143.340437] Starting subtest: invalid-signal-illegal-point
[143.341243] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[143.409474] [034/137] (854s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[144.458104] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[144.483574] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.025s)
[144.552362] [035/137] (853s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc)
[146.920028] Starting subtest: pipe-D-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc
[146.922068] Subtest pipe-D-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s)
[147.071944] [036/137] (851s left) vc4/vc4_wait_bo (used-bo-0ns)
[147.174020] Subtest used-bo-0ns: SKIP (0.000s)
[147.221872] [037/137] (851s left) gem_pxp (reject-modify-context-protection-on)
[149.810051] Subtest reject-modify-context-protection-on: SKIP (0.000s)
[149.885237] [038/137] (848s left) feature_discovery (display)
[151.861271] Starting subtest: display
[151.862374] Subtest display: SUCCESS (0.000s)
[151.921770] [039/137] (846s left) gem_ccs (ctrl-surf-copy)
[152.030139] Starting subtest: ctrl-surf-copy
[152.034558] Subtest ctrl-surf-copy: SKIP (0.000s)
[152.105407] [040/137] (846s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[154.115681] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[154.117416] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[154.204382] [041/137] (844s left) kms_mmap_write_crc (main)
[156.205126] Starting subtest: main
[156.207083] Starting dynamic subtest: pipe-A-HDMI-A-4
[160.145495] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (3.938s)
[160.146445] Subtest main: SUCCESS (3.940s)
[160.275897] [042/137] (838s left) gem_exec_flush (basic-batch-kernel-default-wb)
[160.348315] Subtest basic-batch-kernel-default-wb: SKIP (0.000s)
[160.382478] [043/137] (838s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[162.365548] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[162.367725] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[162.440504] [044/137] (836s left) perf_pmu (render-node-busy)
[162.528125] Starting subtest: render-node-busy
[162.529094] Starting dynamic subtest: rcs0
[163.092365] Dynamic subtest rcs0: SUCCESS (0.564s)
[163.100814] Starting dynamic subtest: bcs0
[163.636938] Dynamic subtest bcs0: SUCCESS (0.544s)
[163.645165] Starting dynamic subtest: vcs0
[164.180953] Dynamic subtest vcs0: SUCCESS (0.544s)
[164.189111] Starting dynamic subtest: vcs1
[164.724949] Dynamic subtest vcs1: SUCCESS (0.544s)
[164.732945] Starting dynamic subtest: vecs0
[165.268948] Dynamic subtest vecs0: SUCCESS (0.544s)
[165.269961] Subtest render-node-busy: SUCCESS (2.741s)
[165.351940] [045/137] (833s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[167.478699] Starting subtest: cursor-rapid-movement-256x256
[167.485689] Starting dynamic subtest: pipe-A-HDMI-A-4
[167.916503] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.428s)
[167.944978] Starting dynamic subtest: pipe-D-HDMI-A-4
[169.320503] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (1.384s)
[169.322248] Subtest cursor-rapid-movement-256x256: SUCCESS (1.839s)
[169.581319] [046/137] (829s left) kms_vblank (pipe-a-wait-forked-busy-hang)
[171.556446] Starting subtest: pipe-A-wait-forked-busy-hang
[185.914580] Subtest pipe-A-wait-forked-busy-hang: SUCCESS (14.357s)
[186.030421] [047/137] (812s left) kms_vblank (pipe-b-ts-continuation-modeset-rpm)
[188.002432] Starting subtest: pipe-B-ts-continuation-modeset-rpm
[190.824609] Subtest pipe-B-ts-continuation-modeset-rpm: SUCCESS (2.816s)
[191.999457] [048/137] (806s left) vgem_basic (setversion)
[192.083259] Starting subtest: setversion
[192.085286] Subtest setversion: SUCCESS (0.000s)
[192.133038] [049/137] (806s left) kms_plane_lowres (tiling-y)
[194.105474] Starting subtest: tiling-y
[194.108845] Starting dynamic subtest: pipe-A-HDMI-A-4
[202.399349] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (8.293s)
[202.443916] Starting dynamic subtest: pipe-B-HDMI-A-4
[210.596281] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (8.154s)
[210.641729] Starting dynamic subtest: pipe-C-HDMI-A-4
[218.773246] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (8.139s)
[218.819685] Starting dynamic subtest: pipe-D-HDMI-A-4
[227.010451] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (8.197s)
[227.048265] Subtest tiling-y: SUCCESS (32.943s)
[227.161783] [050/137] (771s left) kms_getfb (getfb-repeated-different-handles)
[227.231470] Starting subtest: getfb-repeated-different-handles
[227.232429] Subtest getfb-repeated-different-handles: SUCCESS (0.000s)
[227.311169] [051/137] (771s left) gem_mmap (big-bo)
[227.381758] Subtest big-bo: SKIP (0.000s)
[227.405478] [052/137] (771s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[229.385610] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[229.387741] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[229.463171] [053/137] (769s left) gem_wait (invalid-buf)
[229.536267] Starting subtest: invalid-buf
[229.537297] Subtest invalid-buf: SUCCESS (0.000s)
[229.841974] [054/137] (768s left) gen3_render_linear_blits
[229.969420] [055/137] (768s left) vc4/vc4_purgeable_bo (access-purged-bo-mem)
[230.061429] Subtest access-purged-bo-mem: SKIP (0.000s)
[230.111222] [056/137] (768s left) gen7_exec_parse (oacontrol-tracking)
[230.187599] Subtest oacontrol-tracking: SKIP (0.000s)
[230.219737] [057/137] (768s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[232.200199] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[232.201768] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[232.277265] [058/137] (766s left) kms_cursor_edge_walk (128x128-left-edge)
[234.266117] Starting subtest: 128x128-left-edge
[234.269124] Starting dynamic subtest: pipe-A-HDMI-A-4
[237.594227] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (3.326s)
[237.604961] Starting dynamic subtest: pipe-D-HDMI-A-4
[240.816321] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (3.219s)
[240.817327] Subtest 128x128-left-edge: SUCCESS (6.550s)
[240.943363] [059/137] (757s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[242.930793] Starting subtest: y-tiled-64bpp-rotate-180
[245.411918] Subtest y-tiled-64bpp-rotate-180: SUCCESS (2.478s)
[245.565309] [060/137] (753s left) gem_close_race (basic-process)
[245.656474] Starting subtest: basic-process
[245.703864] Subtest basic-process: SUCCESS (0.046s)
[245.938595] [061/137] (752s left) gem_userptr_blits (sync-unmap)
[246.053454] Starting subtest: sync-unmap
[246.074286] Subtest sync-unmap: SUCCESS (0.020s)
[246.213479] [062/137] (752s left) v3d/v3d_job_submission (threaded-job-submission)
[246.343204] Subtest threaded-job-submission: SKIP (0.000s)
[246.392551] [063/137] (752s left) gem_exec_reloc (basic-gtt-read)
[246.465779] Subtest basic-gtt-read: SKIP (0.000s)
[246.518360] [064/137] (752s left) kms_chamelium_edid (hdmi-edid-stress-resolution-4k)
[248.534108] Subtest hdmi-edid-stress-resolution-4k: SKIP (0.000s)
[248.592111] [065/137] (750s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[250.572233] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[250.574345] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[250.665334] [066/137] (748s left) kms_ccs (pipe-a-random-ccs-data-4_tiled_dg2_mc_ccs)
[252.758871] Starting subtest: pipe-A-random-ccs-data-4_tiled_dg2_mc_ccs
[252.763140] Subtest pipe-A-random-ccs-data-4_tiled_dg2_mc_ccs: SKIP (0.000s)
[253.205279] [067/137] (745s left) kms_vblank (pipe-b-query-busy)
[255.183051] Starting subtest: pipe-B-query-busy
[257.996855] Subtest pipe-B-query-busy: SUCCESS (2.814s)
[258.110251] [068/137] (740s left) kms_scaling_modes (scaling-mode-full)
[260.083721] Starting subtest: scaling-mode-full
[260.087828] Subtest scaling-mode-full: SKIP (0.003s)
[260.144140] [069/137] (738s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[262.129227] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[262.130254] Starting dynamic subtest: pipe-A-HDMI-A-4
[283.573184] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (21.444s)
[283.581521] Starting dynamic subtest: pipe-B-HDMI-A-4
[304.926745] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (21.351s)
[304.934955] Starting dynamic subtest: pipe-C-HDMI-A-4
[305.216460] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.287s)
[305.224488] Starting dynamic subtest: pipe-D-HDMI-A-4
[305.505438] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.287s)
[305.506392] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (43.378s)
[305.646727] [070/137] (693s left) api_intel_allocator (execbuf-with-allocator)
[305.719239] Starting subtest: execbuf-with-allocator
[305.734452] Subtest execbuf-with-allocator: SUCCESS (0.017s)
[305.826710] [071/137] (693s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[307.804784] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[307.808074] Starting dynamic subtest: pipe-A-HDMI-A-4
[307.892420] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.086s)
[307.943839] Starting dynamic subtest: pipe-B-HDMI-A-4
[308.085644] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.147s)
[308.107991] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (0.303s)
[308.222112] [072/137] (690s left) gem_lmem_swapping (parallel-random-verify)
[312.247646] Starting subtest: parallel-random-verify
[312.248553] Starting dynamic subtest: lmem0
[321.881662] Dynamic subtest lmem0: SUCCESS (9.630s)
[321.882771] Subtest parallel-random-verify: SUCCESS (9.631s)
[324.250282] [073/137] (674s left) kms_content_protection (dp-mst-lic-type-1)
[330.220495] Starting subtest: dp-mst-lic-type-1
[330.222121] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[330.298163] [074/137] (668s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[332.316180] Starting subtest: x-tiled-16bpp-rotate-90
[332.394329] Subtest x-tiled-16bpp-rotate-90: SKIP (0.077s)
[332.480349] [075/137] (666s left) gem_mmap_offset (oob-read)
[332.550317] Starting subtest: oob-read
[332.551206] Subtest oob-read: SUCCESS (0.000s)
[332.629935] [076/137] (666s left) kms_legacy_colorkey (basic)
[334.601814] Starting subtest: basic
[334.602895] Starting dynamic subtest: pipe-A
[334.696439] Dynamic subtest pipe-A: SUCCESS (0.094s)
[334.703884] Starting dynamic subtest: pipe-B
[334.705173] Dynamic subtest pipe-B: SUCCESS (0.000s)
[334.712825] Starting dynamic subtest: pipe-C
[334.713995] Dynamic subtest pipe-C: SUCCESS (0.001s)
[334.722511] Starting dynamic subtest: pipe-D
[334.723866] Dynamic subtest pipe-D: SUCCESS (0.001s)
[334.725248] Subtest basic: SUCCESS (0.099s)
[334.796372] [077/137] (664s left) kms_vblank (pipe-c-wait-busy)
[336.820191] Starting subtest: pipe-C-wait-busy
[340.044776] Subtest pipe-C-wait-busy: SUCCESS (3.225s)
[340.161103] [078/137] (659s left) gem_exec_schedule (deep)
[340.364525] Starting subtest: deep
[340.365433] Starting dynamic subtest: rcs0
[353.755312] Dynamic subtest rcs0: SUCCESS (13.393s)
[353.762370] Starting dynamic subtest: bcs0
[367.351027] Dynamic subtest bcs0: SUCCESS (13.595s)
[367.357652] Starting dynamic subtest: vcs0
[380.773365] Dynamic subtest vcs0: SUCCESS (13.422s)
[380.780247] Starting dynamic subtest: vcs1
[394.274465] Dynamic subtest vcs1: SUCCESS (13.501s)
[394.281052] Starting dynamic subtest: vecs0
[407.670229] Dynamic subtest vecs0: SUCCESS (13.395s)
[407.671127] Subtest deep: SUCCESS (67.308s)
[408.299072] [079/137] (590s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[410.280033] Starting subtest: fbc-shrfb-scaledprimary
[413.766584] Subtest fbc-shrfb-scaledprimary: SUCCESS (3.488s)
[413.901344] [080/137] (585s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[413.973000] Starting subtest: wait-all-for-submit-delayed-submit
[414.082143] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.105s)
[414.162122] [081/137] (585s left) v3d/v3d_submit_csd (single-in-sync)
[414.249413] Subtest single-in-sync: SKIP (0.000s)
[414.298839] [082/137] (584s left) gem_ppgtt (flink-and-close-vma-leak)
[414.370157] Starting subtest: flink-and-close-vma-leak
[414.410261] Subtest flink-and-close-vma-leak: SUCCESS (0.040s)
[414.531707] [083/137] (584s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled)
[414.636647] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled
[414.637521] Starting dynamic subtest: smem
[414.725965] Dynamic subtest smem: SUCCESS (0.089s)
[414.732507] Starting dynamic subtest: lmem0
[415.664135] Dynamic subtest lmem0: SUCCESS (0.934s)
[415.665049] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SUCCESS (1.024s)
[415.901686] [084/137] (583s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs)
[418.106332] Starting subtest: pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs
[418.110612] Subtest pipe-B-crc-sprite-planes-basic-4_tiled_dg2_rc_ccs: SKIP (0.003s)
[418.430133] [085/137] (580s left) kms_ccs (pipe-b-ccs-on-another-bo-yf_tiled_ccs)
[420.610045] Starting subtest: pipe-B-ccs-on-another-bo-yf_tiled_ccs
[420.612101] Subtest pipe-B-ccs-on-another-bo-yf_tiled_ccs: SKIP (0.000s)
[420.943189] [086/137] (578s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[422.930929] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[424.110060] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.178s)
[424.211702] [087/137] (575s left) syncobj_timeline (multi-wait-signaled)
[424.284981] Starting subtest: multi-wait-signaled
[424.285838] Subtest multi-wait-signaled: SUCCESS (0.001s)
[424.349157] [088/137] (575s left) gem_exec_big (single)
[424.439373] Starting subtest: single
[431.295057] Subtest single: SUCCESS (6.854s)
[432.370022] [089/137] (567s left) gem_ctx_param (invalid-ctx-set)
[432.482327] Starting subtest: invalid-ctx-set
[432.483187] Subtest invalid-ctx-set: SUCCESS (0.000s)
[432.541678] [090/137] (566s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[434.523940] Starting subtest: short-flip-after-cursor-atomic-transitions
[435.640746] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (1.114s)
[435.812777] [091/137] (563s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[437.789482] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[437.791519] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[437.862900] [092/137] (561s left) gem_userptr_blits (vma-merge)
[437.972423] Starting subtest: vma-merge
[458.107338] Subtest vma-merge: FAIL (20.114s)
[458.211097] [093/137] (541s left) i915_suspend (basic-s2idle-without-i915)
[458.277688] Starting subtest: basic-s2idle-without-i915
[478.869481] Subtest basic-s2idle-without-i915: SUCCESS (5.226s)
[480.887190] [094/137] (534s left) kms_flip (flip-vs-rmfb)
[481.922797] Starting subtest: flip-vs-rmfb
[481.932812] Starting dynamic subtest: A-HDMI-A4
[489.662495] Dynamic subtest A-HDMI-A4: SUCCESS (7.728s)
[489.672932] Starting dynamic subtest: B-HDMI-A4
[497.373263] Dynamic subtest B-HDMI-A4: SUCCESS (7.708s)
[497.380995] Starting dynamic subtest: C-HDMI-A4
[505.098238] Dynamic subtest C-HDMI-A4: SUCCESS (7.724s)
[505.105697] Starting dynamic subtest: D-HDMI-A4
[512.798798] Dynamic subtest D-HDMI-A4: SUCCESS (7.700s)
[512.799653] Subtest flip-vs-rmfb: SUCCESS (30.878s)
[512.959791] [095/137] (502s left) gem_exec_schedule (preempt-other-chain)
[513.045689] Starting subtest: preempt-other-chain
[513.046601] Starting dynamic subtest: rcs0
[513.191019] Dynamic subtest rcs0: SUCCESS (0.142s)
[513.198052] Starting dynamic subtest: bcs0
[513.317744] Dynamic subtest bcs0: SUCCESS (0.126s)
[513.324690] Starting dynamic subtest: vcs0
[513.447620] Dynamic subtest vcs0: SUCCESS (0.129s)
[513.454497] Starting dynamic subtest: vcs1
[513.574811] Dynamic subtest vcs1: SUCCESS (0.127s)
[513.581850] Starting dynamic subtest: vecs0
[513.703991] Dynamic subtest vecs0: SUCCESS (0.128s)
[513.704907] Subtest preempt-other-chain: SUCCESS (0.655s)
[514.077869] [096/137] (501s left) kms_plane_scaling (2x-scaler-multi-pipe)
[516.057797] Starting subtest: 2x-scaler-multi-pipe
[516.061720] Subtest 2x-scaler-multi-pipe: SKIP (0.004s)
[516.162418] [097/137] (499s left) v3d/v3d_submit_cl (single-in-sync)
[516.308789] Subtest single-in-sync: SKIP (0.000s)
[516.367129] [098/137] (499s left) kms_ccs (pipe-c-random-ccs-data-y_tiled_gen12_rc_ccs_cc)
[518.687959] Starting subtest: pipe-C-random-ccs-data-y_tiled_gen12_rc_ccs_cc
[518.800408] Subtest pipe-C-random-ccs-data-y_tiled_gen12_rc_ccs_cc: SUCCESS (0.112s)
[519.076268] [099/137] (496s left) api_intel_bb (intel-bb-blit-x)
[519.147633] Starting subtest: intel-bb-blit-x
[519.861742] Subtest intel-bb-blit-x: SUCCESS (0.715s)
[519.947246] [100/137] (496s left) gem_exec_whisper (basic-normal)
[520.027119] Starting subtest: basic-normal
[521.803208] Subtest basic-normal: SUCCESS (1.775s)
[521.978028] [101/137] (493s left) prime_vgem (basic-userptr)
[522.064104] Starting subtest: basic-userptr
[522.071673] Subtest basic-userptr: SKIP (0.007s)
[522.187164] [102/137] (493s left) gem_exec_schedule (submit-early-slice)
[522.277365] Starting subtest: submit-early-slice
[522.278212] Starting dynamic subtest: rcs0
[522.350048] Dynamic subtest rcs0: SUCCESS (0.072s)
[522.358041] Starting dynamic subtest: bcs0
[522.388225] Dynamic subtest bcs0: SUCCESS (0.038s)
[522.395525] Starting dynamic subtest: vcs0
[522.437417] Dynamic subtest vcs0: SUCCESS (0.048s)
[522.446267] Starting dynamic subtest: vcs1
[522.475932] Dynamic subtest vcs1: SUCCESS (0.038s)
[522.484029] Starting dynamic subtest: vecs0
[522.514880] Dynamic subtest vecs0: SUCCESS (0.038s)
[522.516090] Subtest submit-early-slice: SUCCESS (0.237s)
[522.863869] [103/137] (493s left) gem_workarounds (reset)
[522.955450] Starting subtest: reset
[523.125114] Subtest reset: SUCCESS (0.169s)
[523.267594] [104/137] (492s left) kms_sequence (get-forked-busy)
[525.278594] Starting subtest: get-forked-busy
[525.279709] Starting dynamic subtest: HDMI-A-4-pipe-A
[527.847968] Dynamic subtest HDMI-A-4-pipe-A: SUCCESS (2.566s)
[527.868670] Starting dynamic subtest: HDMI-A-4-pipe-B
[530.671740] Dynamic subtest HDMI-A-4-pipe-B: SUCCESS (2.819s)
[530.686360] Starting dynamic subtest: HDMI-A-4-pipe-C
[533.949130] Dynamic subtest HDMI-A-4-pipe-C: SUCCESS (3.271s)
[533.972989] Starting dynamic subtest: HDMI-A-4-pipe-D
[537.632084] Dynamic subtest HDMI-A-4-pipe-D: SUCCESS (3.674s)
[537.633807] Subtest get-forked-busy: SUCCESS (12.353s)
[537.759465] [105/137] (478s left) gem_mmap_gtt (cpuset-medium-copy-odd)
[537.832634] Subtest cpuset-medium-copy-odd: SKIP (0.000s)
[537.867323] [106/137] (478s left) i915_pm_dc (dc5-dpms)
[540.850429] Starting subtest: dc5-dpms
[542.122183] Subtest dc5-dpms: SUCCESS (1.269s)
[542.423082] [107/137] (473s left) kms_busy (extended-pageflip-hang-newfb)
[544.408033] Starting subtest: extended-pageflip-hang-newfb
[544.412910] Starting dynamic subtest: pipe-A
[545.910019] Dynamic subtest pipe-A: SUCCESS (1.500s)
[545.929836] Starting dynamic subtest: pipe-D
[547.304724] Dynamic subtest pipe-D: SUCCESS (1.383s)
[547.306288] Subtest extended-pageflip-hang-newfb: SUCCESS (2.895s)
[547.444814] [108/137] (468s left) gem_exec_fence (syncobj-signal)
[547.803666] Starting subtest: syncobj-signal
[547.823593] Subtest syncobj-signal: SUCCESS (0.020s)
[548.180684] [109/137] (467s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[549.214202] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[549.240034] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.025s)
[549.311920] [110/137] (466s left) i915_pm_rpm (i2c)
[553.842406] Starting subtest: i2c
[583.821026] Subtest i2c: SUCCESS (29.979s)
[584.874512] [111/137] (431s left) kms_hdr (bpc-switch)
[586.898245] Starting subtest: bpc-switch
[586.900318] Subtest bpc-switch: SKIP (0.000s)
[587.010593] [112/137] (429s left) perf_pmu (all-busy-check-all)
[587.142714] Starting subtest: all-busy-check-all
[587.705949] Subtest all-busy-check-all: SUCCESS (0.563s)
[587.795820] [113/137] (428s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[589.784703] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[589.785721] Starting dynamic subtest: pipe-A-valid-mode
[589.827989] Dynamic subtest pipe-A-valid-mode: SKIP (0.041s)
[589.829986] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.043s)
[589.918156] [114/137] (426s left) drm_fdinfo (basics)
[589.991677] Subtest basics: SKIP (0.000s)
[590.026888] [115/137] (426s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[592.043724] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[592.102648] [116/137] (424s left) sysfs_defaults (readonly)
[592.175943] Starting subtest: readonly
[592.176914] Starting dynamic subtest: vecs0
[592.177904] Dynamic subtest vecs0: SUCCESS (0.000s)
[592.186007] Starting dynamic subtest: vcs0
[592.186981] Dynamic subtest vcs0: SUCCESS (0.000s)
[592.194757] Starting dynamic subtest: bcs0
[592.195760] Dynamic subtest bcs0: SUCCESS (0.000s)
[592.203520] Starting dynamic subtest: rcs0
[592.204524] Dynamic subtest rcs0: SUCCESS (0.000s)
[592.212284] Starting dynamic subtest: vcs1
[592.213272] Dynamic subtest vcs1: SUCCESS (0.000s)
[592.214237] Subtest readonly: SUCCESS (0.005s)
[592.241348] [117/137] (424s left) gem_exec_fair (basic-none-share)
[592.318942] Subtest basic-none-share: SKIP (0.000s)
[592.367072] [118/137] (424s left) i915_pm_rpm (dpms-mode-unset-non-lpsp)
[596.838099] Starting subtest: dpms-mode-unset-non-lpsp
[603.072465] Subtest dpms-mode-unset-non-lpsp: SUCCESS (6.235s)
[604.228411] [119/137] (412s left) gem_exec_flush (basic-uc-pro-default)
[604.302241] Subtest basic-uc-pro-default: SKIP (0.000s)
[604.353748] [120/137] (412s left) gem_tiled_fence_blits (basic)
[604.427422] Subtest basic: SKIP (0.000s)
[604.456297] [121/137] (412s left) kms_cursor_crc (cursor-onscreen-256x256)
[606.652643] Starting subtest: cursor-onscreen-256x256
[606.660263] Starting dynamic subtest: pipe-A-HDMI-A-4
[609.415251] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.756s)
[609.444564] Starting dynamic subtest: pipe-D-HDMI-A-4
[612.768298] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (3.333s)
[612.769234] Subtest cursor-onscreen-256x256: SUCCESS (6.115s)
[613.039170] [122/137] (403s left) kms_ccs (pipe-a-bad-rotation-90-y_tiled_gen12_mc_ccs)
[615.062510] Starting subtest: pipe-A-bad-rotation-90-y_tiled_gen12_mc_ccs
[615.064418] Subtest pipe-A-bad-rotation-90-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[615.540551] [123/137] (400s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[617.515913] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[617.516783] Starting dynamic subtest: pipe-A-HDMI-A-4
[617.643434] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.127s)
[617.652898] Starting dynamic subtest: pipe-B-HDMI-A-4
[617.766298] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.121s)
[617.775413] Starting dynamic subtest: pipe-C-HDMI-A-4
[617.889336] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.121s)
[617.898509] Starting dynamic subtest: pipe-D-HDMI-A-4
[618.011503] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.120s)
[618.012440] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SUCCESS (0.497s)
[618.136091] [124/137] (398s left) gem_fence_thrash (bo-copy)
[618.203042] Starting subtest: bo-copy
[618.206272] Subtest bo-copy: SKIP (0.002s)
[618.227935] [125/137] (398s left) kms_force_connector_basic (force-connector-state)
[618.355026] Starting subtest: force-connector-state
[622.086015] Subtest force-connector-state: SUCCESS (3.732s)
[624.319317] [126/137] (392s left) kms_async_flips (alternate-sync-async-flip)
[626.298053] Starting subtest: alternate-sync-async-flip
[626.298943] Starting dynamic subtest: pipe-A-HDMI-A-4
[630.334931] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.037s)
[630.343999] Starting dynamic subtest: pipe-B-HDMI-A-4
[634.180628] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (3.841s)
[634.188712] Starting dynamic subtest: pipe-C-HDMI-A-4
[637.611804] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (3.429s)
[637.619985] Starting dynamic subtest: pipe-D-HDMI-A-4
[640.649362] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (3.036s)
[640.650298] Subtest alternate-sync-async-flip: SUCCESS (14.350s)
[641.349144] [127/137] (375s left) kms_display_modes (extended-mode-basic)
[643.329258] Starting subtest: extended-mode-basic
[643.331749] Subtest extended-mode-basic: SKIP (0.001s)
[643.357613] [128/137] (373s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[645.340142] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[645.342190] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[645.415828] [129/137] (371s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[647.410894] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[647.412791] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[647.498718] [130/137] (369s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[647.597156] Starting subtest: y-tiled-ccs-to-y-tiled
[647.598102] Starting dynamic subtest: smem
[647.679509] Dynamic subtest smem: SUCCESS (0.082s)
[647.687814] Starting dynamic subtest: lmem0
[648.610363] Dynamic subtest lmem0: SUCCESS (0.927s)
[648.611316] Subtest y-tiled-ccs-to-y-tiled: SUCCESS (1.010s)
[648.855323] [131/137] (367s left) perf_pmu (busy)
[648.933869] Starting subtest: busy
[648.934788] Starting dynamic subtest: rcs0
[649.494506] Dynamic subtest rcs0: SUCCESS (0.560s)
[649.504483] Starting dynamic subtest: bcs0
[650.040499] Dynamic subtest bcs0: SUCCESS (0.545s)
[650.050509] Starting dynamic subtest: vcs0
[650.586424] Dynamic subtest vcs0: SUCCESS (0.545s)
[650.596490] Starting dynamic subtest: vcs1
[651.132503] Dynamic subtest vcs1: SUCCESS (0.545s)
[651.142653] Starting dynamic subtest: vecs0
[651.678501] Dynamic subtest vecs0: SUCCESS (0.545s)
[651.679582] Subtest busy: SUCCESS (2.744s)
[651.754178] [132/137] (364s left) gem_ctx_param (invalid-set-ringsize)
[651.823133] Starting subtest: invalid-set-ringsize
[651.824107] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[651.884736] [133/137] (364s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[653.869254] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[654.363694] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.493s)
[654.499172] [134/137] (362s left) kms_ccs (pipe-c-crc-primary-basic-y_tiled_ccs)
[656.741938] Starting subtest: pipe-C-crc-primary-basic-y_tiled_ccs
[656.743834] Subtest pipe-C-crc-primary-basic-y_tiled_ccs: SKIP (0.000s)
[657.015836] [135/137] (359s left) gem_ctx_exec (basic-invalid-context)
[657.087086] Starting subtest: basic-invalid-context
[657.090836] Subtest basic-invalid-context: SUCCESS (0.004s)
[657.191128] [136/137] (359s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[659.164233] Starting subtest: 2x-cursor-vs-flip-atomic
[659.167128] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.004s)
[659.232443] [137/137] (357s left) kms_ccs (pipe-b-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc)
[661.418749] Starting subtest: pipe-B-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc
[661.419805] Subtest pipe-B-missing-ccs-buffer-y_tiled_gen12_rc_ccs_cc: SUCCESS (0.000s)
[661.753681] Closing watchdogs
Done.