[38.709152] Initializing watchdogs
[38.709355]   /dev/watchdog0
[38.726176] [001/142] (960s left) kms_lease (lease_unleased_crtc)
[38.987468] Starting subtest: lease_unleased_crtc
[38.988810] Starting dynamic subtest: pipe-A-DP-1
[39.168809] Dynamic subtest pipe-A-DP-1: SUCCESS (0.181s)
[39.186465] Starting dynamic subtest: pipe-B-DP-1
[39.272163] Dynamic subtest pipe-B-DP-1: SUCCESS (0.097s)
[39.284847] Starting dynamic subtest: pipe-C-DP-1
[39.364558] Dynamic subtest pipe-C-DP-1: SUCCESS (0.092s)
[39.366191] Subtest lease_unleased_crtc: SUCCESS (0.378s)
[39.675482] [002/142] (959s left) kms_dsc (dsc-with-output-formats-with-bpc)
[39.922505] Subtest dsc-with-output-formats-with-bpc: SKIP (0.000s)
[39.973477] [003/142] (959s left) api_intel_allocator (reserve-simple)
[40.115717] Starting subtest: reserve-simple
[40.136248] Subtest reserve-simple: SUCCESS (0.017s)
[40.237453] [004/142] (959s left) vgem_basic (bad-handle)
[40.473381] Starting subtest: bad-handle
[40.474816] Subtest bad-handle: SUCCESS (0.000s)
[40.537411] [005/142] (958s left) feature_discovery (display-4x)
[40.775807] Starting subtest: display-4x
[40.778550] Subtest display-4x: SKIP (0.000s)
[40.855576] [006/142] (958s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[41.114666] Starting subtest: x-tiled-16bpp-rotate-0
[44.973482] Subtest x-tiled-16bpp-rotate-0: SUCCESS (3.857s)
[45.306856] [007/142] (954s left) gem_render_copy (yf-tiled-to-vebox-linear)
[45.469055] Starting subtest: yf-tiled-to-vebox-linear
[45.471761] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[45.655361] [008/142] (953s left) gem_userptr_blits (coherency-sync)
[45.851273] Starting subtest: coherency-sync
[47.009301] Subtest coherency-sync: SKIP (1.145s)
[47.153778] [009/142] (952s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[47.542868] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[47.545448] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[47.642131] [010/142] (951s left) gem_exec_whisper (basic-forked)
[47.817990] Starting subtest: basic-forked
[58.596154] Subtest basic-forked: SUCCESS (10.777s)
[58.792253] [011/142] (940s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-blt)
[59.038908] Starting subtest: fbc-1p-offscren-pri-indfb-draw-blt
[60.976134] Subtest fbc-1p-offscren-pri-indfb-draw-blt: SUCCESS (1.940s)
[61.111105] [012/142] (938s left) kms_ccs (pipe-a-crc-primary-rotation-180-y_tiled_gen12_rc_ccs)
[61.465389] Starting subtest: pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs
[61.467951] Subtest pipe-A-crc-primary-rotation-180-y_tiled_gen12_rc_ccs: SKIP (0.001s)
[61.911638] [013/142] (937s left) kms_vblank (pipe-a-query-busy)
[62.155381] Starting subtest: pipe-A-query-busy
[64.647520] Subtest pipe-A-query-busy: SUCCESS (2.493s)
[64.995747] [014/142] (934s left) kms_psr (psr2_primary_mmap_cpu)
[65.153611] Subtest psr2_primary_mmap_cpu: SKIP (0.000s)
[65.217190] [015/142] (934s left) i915_pm_freq_api (freq-reset-multiple)
[65.366995] Subtest freq-reset-multiple: SKIP (0.000s)
[65.412312] [016/142] (934s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[65.649442] Starting subtest: compare-crc-sanitycheck-xr24
[65.651036] Starting dynamic subtest: pipe-A-DP-1
[66.107257] Dynamic subtest pipe-A-DP-1: SUCCESS (0.455s)
[66.124425] Starting dynamic subtest: pipe-B-DP-1
[66.732754] Dynamic subtest pipe-B-DP-1: SUCCESS (0.624s)
[66.753363] Starting dynamic subtest: pipe-C-DP-1
[67.366882] Dynamic subtest pipe-C-DP-1: SUCCESS (0.630s)
[67.368273] Subtest compare-crc-sanitycheck-xr24: SUCCESS (1.719s)
[67.705092] [017/142] (932s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[67.945100] Starting subtest: plane-all-transition-nonblocking-fencing
[67.950075] Starting dynamic subtest: pipe-A-DP-1
[71.880038] Dynamic subtest pipe-A-DP-1: SUCCESS (3.932s)
[71.998992] Starting dynamic subtest: pipe-B-DP-1
[76.457429] Dynamic subtest pipe-B-DP-1: SUCCESS (4.479s)
[76.553185] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (8.608s)
[76.876461] [018/142] (922s left) kms_ccs (pipe-d-crc-primary-basic-y_tiled_gen12_mc_ccs)
[77.361045] Subtest pipe-D-crc-primary-basic-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[77.668290] [019/142] (922s left) kms_chamelium_color (ctm-negative)
[77.913088] Subtest ctm-negative: SKIP (0.000s)
[77.969936] [020/142] (921s left) i915_pm_rps (basic-api)
[78.118388] Starting subtest: basic-api
[78.128219] Subtest basic-api: SUCCESS (0.007s)
[78.235482] [021/142] (921s left) kms_ccs (pipe-a-random-ccs-data-yf_tiled_ccs)
[78.581074] Starting subtest: pipe-A-random-ccs-data-yf_tiled_ccs
[79.035816] Subtest pipe-A-random-ccs-data-yf_tiled_ccs: SUCCESS (0.454s)
[79.601505] [022/142] (920s left) gem_tiling_max_stride
[79.899056] [023/142] (920s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[80.142160] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[81.507281] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (1.367s)
[81.849903] [024/142] (918s left) vc4/vc4_perfmon (create-perfmon-0)
[82.063300] Subtest create-perfmon-0: SKIP (0.000s)
[82.130123] [025/142] (917s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[82.367838] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[82.369200] Starting dynamic subtest: pipe-A-valid-mode
[82.455897] Dynamic subtest pipe-A-valid-mode: SKIP (0.085s)
[82.460404] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.088s)
[82.757965] [026/142] (917s left) vgem_basic (second-client)
[82.907108] Starting subtest: second-client
[82.939088] Subtest second-client: SUCCESS (0.032s)
[83.007826] [027/142] (917s left) sw_sync (alloc_merge_fence)
[83.143736] Starting subtest: alloc_merge_fence
[83.145391] Subtest alloc_merge_fence: SUCCESS (0.001s)
[83.211851] [028/142] (916s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[83.462183] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[83.464929] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[83.558315] [029/142] (916s left) gem_partial_pwrite_pread (writes-after-reads-display)
[83.708056] Starting subtest: writes-after-reads-display
[104.192208] Subtest writes-after-reads-display: SUCCESS (20.484s)
[104.313263] [030/142] (895s left) kms_chamelium_audio (hdmi-audio)
[104.653247] Subtest hdmi-audio: SKIP (0.000s)
[104.940962] [031/142] (895s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[105.187797] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[105.190681] Subtest psr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[105.284494] [032/142] (895s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[105.424420] Starting subtest: basic-write-cpu-read-gtt
[105.427149] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[105.502100] [033/142] (894s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move)
[105.743489] Starting subtest: psr-1p-primscrn-spr-indfb-move
[105.746149] Subtest psr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[105.850746] [034/142] (894s left) syncobj_wait (invalid-reset-illegal-handle)
[105.993931] Starting subtest: invalid-reset-illegal-handle
[105.995747] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[106.083864] [035/142] (894s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[106.315324] Starting subtest: cursorA-vs-flipA-toggle
[117.395758] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.077s)
[117.724795] [036/142] (882s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[117.988223] Starting subtest: cursor-rapid-movement-max-size
[117.992653] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[118.070542] [037/142] (882s left) prime_vgem (basic-fence-mmap)
[118.235181] Starting subtest: basic-fence-mmap
[118.320096] Subtest basic-fence-mmap: SUCCESS (0.085s)
[118.406517] [038/142] (882s left) kms_vblank (pipe-b-wait-forked-busy-hang)
[118.641212] Starting subtest: pipe-B-wait-forked-busy-hang
[126.007922] Subtest pipe-B-wait-forked-busy-hang: SUCCESS (7.366s)
[126.350226] [039/142] (874s left) i915_pm_rpm (dpms-mode-unset-non-lpsp)
[128.201522] Starting subtest: dpms-mode-unset-non-lpsp
[129.525205] Subtest dpms-mode-unset-non-lpsp: SUCCESS (1.323s)
[129.972093] [040/142] (870s left) gem_ctx_persistence (userptr)
[130.128037] Starting subtest: userptr
[130.256132] Subtest userptr: SUCCESS (0.127s)
[130.337395] [041/142] (870s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[130.582278] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[130.585408] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[130.685998] [042/142] (870s left) syncobj_wait (wait-any-snapshot)
[130.826152] Starting subtest: wait-any-snapshot
[130.908831] Subtest wait-any-snapshot: SUCCESS (0.082s)
[131.006636] [043/142] (869s left) kms_plane_scaling (plane-downscale-with-modifiers-factor-0-25)
[131.241993] Starting subtest: plane-downscale-with-modifiers-factor-0-25
[131.243459] Starting dynamic subtest: pipe-A-DP-1
[131.298813] Dynamic subtest pipe-A-DP-1: SKIP (0.048s)
[131.318638] Starting dynamic subtest: pipe-B-DP-1
[131.395898] Dynamic subtest pipe-B-DP-1: SKIP (0.100s)
[131.418456] Starting dynamic subtest: pipe-C-DP-1
[131.502676] Dynamic subtest pipe-C-DP-1: SKIP (0.099s)
[131.505656] Subtest plane-downscale-with-modifiers-factor-0-25: SKIP (0.261s)
[131.604536] [044/142] (869s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-onoff)
[131.853999] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-onoff
[131.856586] Subtest fbcpsr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[131.954999] [045/142] (868s left) prime_busy (after)
[132.105808] Starting subtest: after
[132.123896] Starting dynamic subtest: rcs0
[132.134493] Dynamic subtest rcs0: SUCCESS (0.010s)
[132.158448] Starting dynamic subtest: bcs0
[132.159686] Dynamic subtest bcs0: SUCCESS (0.016s)
[132.178259] Starting dynamic subtest: vcs0
[132.179580] Dynamic subtest vcs0: SUCCESS (0.023s)
[132.200030] Starting dynamic subtest: vecs0
[132.201303] Dynamic subtest vecs0: SUCCESS (0.016s)
[132.316304] Subtest after: SUCCESS (0.208s)
[132.405468] [046/142] (868s left) gem_exec_fence (syncobj-timeline-wait)
[133.163845] Starting subtest: syncobj-timeline-wait
[133.209091] Subtest syncobj-timeline-wait: SUCCESS (0.043s)
[133.423240] [047/142] (867s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[133.565186] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[133.566898] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[133.654300] [048/142] (867s left) i915_suspend (sysfs-reader)
[133.794205] Starting subtest: sysfs-reader
[152.136662] Subtest sysfs-reader: SUCCESS (3.581s)
[157.296232] [049/142] (863s left) kms_hdr (invalid-metadata-sizes)
[157.536372] Starting subtest: invalid-metadata-sizes
[157.539161] Subtest invalid-metadata-sizes: SKIP (0.000s)
[157.628745] [050/142] (863s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[157.882128] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[157.884950] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[157.994859] [051/142] (863s left) gem_mmap_gtt (basic-small-copy-xy)
[158.136456] Starting subtest: basic-small-copy-XY
[161.692482] Subtest basic-small-copy-XY: SUCCESS (3.556s)
[161.780177] [052/142] (859s left) prime_vgem (coherency-blt)
[162.076669] Starting subtest: coherency-blt
[165.525383] Subtest coherency-blt: SUCCESS (3.448s)
[165.643891] [053/142] (855s left) gen9_exec_parse (bb-large)
[165.811926] Starting subtest: bb-large
[249.390724] Subtest bb-large: SUCCESS (83.578s)
[249.654924] [054/142] (771s left) kms_ccs (pipe-c-bad-rotation-90-y_tiled_gen12_rc_ccs_cc)
[250.220828] Starting subtest: pipe-C-bad-rotation-90-y_tiled_gen12_rc_ccs_cc
[250.223397] Subtest pipe-C-bad-rotation-90-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[250.594367] [055/142] (770s left) drm_fdinfo (all-busy-check-all)
[250.747191] Starting subtest: all-busy-check-all
[251.276567] Subtest all-busy-check-all: SUCCESS (0.529s)
[251.360284] [056/142] (769s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[251.601331] Starting subtest: primary-y-tiled-reflect-x-180
[251.604406] Subtest primary-y-tiled-reflect-x-180: SKIP (0.003s)
[251.694260] [057/142] (769s left) kms_rotation_crc (cursor-rotation-180)
[251.933197] Starting subtest: cursor-rotation-180
[252.739447] Subtest cursor-rotation-180: SUCCESS (0.807s)
[253.169335] [058/142] (768s left) perf (buffer-fill)
[253.460326] Starting subtest: buffer-fill
[253.462067] Starting dynamic subtest: 0-rcs0
[256.375608] Dynamic subtest 0-rcs0: SUCCESS (2.913s)
[256.377055] Subtest buffer-fill: SUCCESS (2.915s)
[256.436216] [059/142] (764s left) gem_madvise (dontneed-before-exec)
[256.574554] Starting subtest: dontneed-before-exec
[256.584341] Subtest dontneed-before-exec: SUCCESS (0.008s)
[256.699058] [060/142] (764s left) kms_draw_crc (draw-method-mmap-wc)
[256.941713] Starting subtest: draw-method-mmap-wc
[256.943171] Starting dynamic subtest: xrgb8888-untiled
[257.255147] Dynamic subtest xrgb8888-untiled: SUCCESS (0.314s)
[257.281690] Starting dynamic subtest: rgb565-untiled
[257.538111] Dynamic subtest rgb565-untiled: SUCCESS (0.281s)
[257.563576] Starting dynamic subtest: xrgb2101010-untiled
[257.855004] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.315s)
[257.880039] Starting dynamic subtest: xrgb8888-xtiled
[258.072883] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.215s)
[258.096535] Starting dynamic subtest: rgb565-xtiled
[258.272636] Dynamic subtest rgb565-xtiled: SUCCESS (0.199s)
[258.297267] Starting dynamic subtest: xrgb2101010-xtiled
[258.488345] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.214s)
[258.513239] Starting dynamic subtest: xrgb8888-ytiled
[258.705146] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.215s)
[258.729133] Starting dynamic subtest: rgb565-ytiled
[258.905296] Dynamic subtest rgb565-ytiled: SUCCESS (0.198s)
[258.929531] Starting dynamic subtest: xrgb2101010-ytiled
[259.121472] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.215s)
[259.123999] Subtest draw-method-mmap-wc: SUCCESS (2.183s)
[259.248980] [061/142] (762s left) v3d/v3d_submit_cl (job-perfmon)
[259.441139] Subtest job-perfmon: SKIP (0.000s)
[259.516256] [062/142] (761s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[259.657190] Starting subtest: write-cpu-read-wc-unflushed
[259.938743] Subtest write-cpu-read-wc-unflushed: SUCCESS (0.280s)
[260.033115] [063/142] (761s left) kms_cursor_crc (cursor-random-64x64)
[260.283301] Starting subtest: cursor-random-64x64
[260.293474] Starting dynamic subtest: pipe-A-DP-1
[264.336711] Dynamic subtest pipe-A-DP-1: SUCCESS (4.042s)
[264.439776] Starting dynamic subtest: pipe-C-DP-1
[268.328336] Dynamic subtest pipe-C-DP-1: SUCCESS (3.915s)
[268.329773] Subtest cursor-random-64x64: SUCCESS (8.046s)
[268.701889] [064/142] (752s left) kms_vblank (pipe-b-ts-continuation-modeset)
[268.939927] Starting subtest: pipe-B-ts-continuation-modeset
[269.860199] Subtest pipe-B-ts-continuation-modeset: SUCCESS (0.916s)
[270.205993] [065/142] (751s left) gem_exec_flush (basic-wb-prw-default)
[270.378193] Starting subtest: basic-wb-prw-default
[275.853559] Subtest basic-wb-prw-default: SUCCESS (5.475s)
[276.071071] [066/142] (745s left) kms_pm_backlight (fade-with-dpms)
[276.309368] Subtest fade-with-dpms: SKIP (0.000s)
[276.371951] [067/142] (745s left) gem_exec_params (invalid-ring2)
[276.520386] Starting subtest: invalid-ring2
[276.522067] Subtest invalid-ring2: SUCCESS (0.001s)
[276.623987] [068/142] (745s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[276.766239] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[276.768334] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.001s)
[276.854771] [069/142] (744s left) gem_mmap_wc (bad-offset)
[276.995872] Starting subtest: bad-offset
[276.997236] Subtest bad-offset: SUCCESS (0.001s)
[277.105029] [070/142] (744s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs)
[277.565275] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs
[277.570544] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_dg2_mc_ccs: SKIP (0.004s)
[277.884328] [071/142] (744s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[278.030003] Starting subtest: invalid-single-wait-all-unsubmitted
[278.031914] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.001s)
[278.119800] [072/142] (743s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[278.389750] Starting subtest: y-tiled-8bpp-rotate-270
[278.482003] Subtest y-tiled-8bpp-rotate-270: SKIP (0.090s)
[278.598676] [073/142] (743s left) kms_async_flips (alternate-sync-async-flip)
[278.837183] Starting subtest: alternate-sync-async-flip
[279.016757] Starting dynamic subtest: pipe-A-DP-1
[281.409561] Dynamic subtest pipe-A-DP-1: SUCCESS (2.391s)
[281.639942] Starting dynamic subtest: pipe-B-DP-1
[283.882808] Dynamic subtest pipe-B-DP-1: SUCCESS (2.279s)
[284.102823] Starting dynamic subtest: pipe-C-DP-1
[286.347130] Dynamic subtest pipe-C-DP-1: SUCCESS (2.270s)
[286.349407] Subtest alternate-sync-async-flip: SUCCESS (7.509s)
[286.844314] [074/142] (735s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_rc_ccs_cc)
[287.188968] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc
[287.191491] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.001s)
[287.670953] [075/142] (734s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[287.909390] Starting subtest: cursorB-vs-flipB-varying-size
[287.911942] Subtest cursorB-vs-flipB-varying-size: SKIP (0.002s)
[288.004130] [076/142] (734s left) kms_vblank (pipe-d-ts-continuation-idle)
[288.243722] Subtest pipe-D-ts-continuation-idle: SKIP (0.000s)
[288.337057] [077/142] (733s left) gem_pxp (reject-modify-context-protection-off-3)
[291.003831] Subtest reject-modify-context-protection-off-3: SKIP (0.000s)
[291.085344] [078/142] (731s left) vc4/vc4_dmabuf_poll (poll-read-waits-until-write-done)
[291.278895] Subtest poll-read-waits-until-write-done: SKIP (0.000s)
[291.353139] [079/142] (730s left) kms_flip (2x-flip-vs-blocking-wf-vblank)
[291.587415] Starting subtest: 2x-flip-vs-blocking-wf-vblank
[291.590267] Subtest 2x-flip-vs-blocking-wf-vblank: SKIP (0.003s)
[291.702901] [080/142] (730s left) kms_busy (basic-hang)
[291.956796] Starting subtest: basic-hang
[291.958170] Starting dynamic subtest: flip-pipe-A
[292.570288] Dynamic subtest flip-pipe-A: SUCCESS (0.612s)
[292.598039] Starting dynamic subtest: modeset-pipe-A
[294.052146] Dynamic subtest modeset-pipe-A: SUCCESS (1.479s)
[294.083176] Starting dynamic subtest: flip-pipe-C
[294.786734] Dynamic subtest flip-pipe-C: SUCCESS (0.725s)
[294.812682] Starting dynamic subtest: modeset-pipe-C
[296.229666] Dynamic subtest modeset-pipe-C: SUCCESS (1.439s)
[296.231194] Subtest basic-hang: SUCCESS (4.274s)
[296.595461] [081/142] (725s left) kms_vblank (pipe-a-query-busy-hang)
[296.840207] Starting subtest: pipe-A-query-busy-hang
[303.778501] Subtest pipe-A-query-busy-hang: SUCCESS (6.936s)
[304.117832] [082/142] (718s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[304.362176] Starting subtest: modeset-transition-nonblocking-fencing
[304.363479] Starting dynamic subtest: 1x-outputs
[309.310085] Dynamic subtest 1x-outputs: SUCCESS (4.947s)
[309.426721] Subtest modeset-transition-nonblocking-fencing: SUCCESS (5.062s)
[309.796276] [083/142] (712s left) gem_set_tiling_vs_pwrite
[310.059335] [084/142] (712s left) gem_exec_schedule (promotion)
[310.239787] Starting subtest: promotion
[310.241297] Starting dynamic subtest: rcs0
[310.392300] Dynamic subtest rcs0: SUCCESS (0.150s)
[310.424010] Starting dynamic subtest: bcs0
[310.549038] Dynamic subtest bcs0: SUCCESS (0.153s)
[310.578541] Starting dynamic subtest: vcs0
[310.691704] Dynamic subtest vcs0: SUCCESS (0.135s)
[310.716695] Starting dynamic subtest: vecs0
[310.829255] Dynamic subtest vecs0: SUCCESS (0.137s)
[310.832060] Subtest promotion: SUCCESS (0.591s)
[311.193813] [085/142] (711s left) perf_pmu (busy-accuracy-98)
[311.345159] Starting subtest: busy-accuracy-98
[311.346913] Starting dynamic subtest: rcs0
[314.414341] Dynamic subtest rcs0: SUCCESS (3.065s)
[314.438207] Starting dynamic subtest: bcs0
[317.475821] Dynamic subtest bcs0: SUCCESS (3.060s)
[317.500191] Starting dynamic subtest: vcs0
[320.537812] Dynamic subtest vcs0: SUCCESS (3.057s)
[320.562683] Starting dynamic subtest: vecs0
[323.597411] Dynamic subtest vecs0: SUCCESS (3.058s)
[323.599171] Subtest busy-accuracy-98: SUCCESS (12.252s)
[323.694806] [086/142] (698s left) gem_ccs (block-copy-uncompressed)
[323.848157] Subtest block-copy-uncompressed: SKIP (0.000s)
[323.910732] [087/142] (698s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[324.168257] Starting subtest: yf-tiled-8bpp-rotate-0
[324.170874] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[324.278249] [088/142] (698s left) kms_ccs (pipe-c-bad-aux-stride-4_tiled_mtl_mc_ccs)
[324.769973] Starting subtest: pipe-C-bad-aux-stride-4_tiled_mtl_mc_ccs
[324.772690] Subtest pipe-C-bad-aux-stride-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[325.101680] [089/142] (697s left) kms_addfb_basic (framebuffer-vs-set-tiling)
[325.246236] Starting subtest: framebuffer-vs-set-tiling
[325.247607] Subtest framebuffer-vs-set-tiling: SUCCESS (0.000s)
[325.435719] [090/142] (697s left) kms_chamelium_hpd (dp-hpd-enable-disable-mode)
[325.754378] Subtest dp-hpd-enable-disable-mode: SKIP (0.000s)
[326.052588] [091/142] (696s left) syncobj_timeline (reset-unsignaled)
[326.196755] Starting subtest: reset-unsignaled
[326.198060] Subtest reset-unsignaled: SUCCESS (0.000s)
[326.295928] [092/142] (696s left) gem_ctx_engines (execute-oneforall)
[326.462096] Starting subtest: execute-oneforall
[327.153831] Subtest execute-oneforall: SUCCESS (0.690s)
[327.380580] [093/142] (695s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[327.617679] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[327.621722] Starting dynamic subtest: pipe-A-DP-1
[327.939837] Dynamic subtest pipe-A-DP-1: SUCCESS (0.319s)
[327.981305] Starting dynamic subtest: pipe-B-DP-1
[328.327395] Dynamic subtest pipe-B-DP-1: SUCCESS (0.383s)
[328.369509] Starting dynamic subtest: pipe-C-DP-1
[328.427828] Dynamic subtest pipe-C-DP-1: SKIP (0.093s)
[328.429308] Subtest planes-downscale-factor-0-5-upscale-20x20: SUCCESS (0.811s)
[328.835357] [094/142] (694s left) kms_ccs (pipe-a-bad-aux-stride-y_tiled_gen12_rc_ccs_cc)
[329.208199] Starting subtest: pipe-A-bad-aux-stride-y_tiled_gen12_rc_ccs_cc
[329.210966] Subtest pipe-A-bad-aux-stride-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[329.651258] [095/142] (693s left) gem_exec_balancer (hang)
[333.660176] Starting subtest: hang
[337.325454] Subtest hang: SUCCESS (3.665s)
[337.434840] [096/142] (685s left) gen7_exec_parse (cmd-crossing-page)
[337.587397] Subtest cmd-crossing-page: SKIP (0.000s)
[337.652624] [097/142] (685s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[337.888246] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[337.892824] Starting dynamic subtest: pipe-A-valid-mode
[338.276975] Dynamic subtest pipe-A-valid-mode: SKIP (0.385s)
[338.279679] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SKIP (0.389s)
[338.667446] [098/142] (684s left) gem_ctx_param (root-set)
[338.812477] Starting subtest: root-set
[338.813850] Subtest root-set: SUCCESS (0.000s)
[338.900995] [099/142] (684s left) syncobj_wait (multi-wait-for-submit-submitted)
[339.048811] Starting subtest: multi-wait-for-submit-submitted
[339.155217] Subtest multi-wait-for-submit-submitted: SUCCESS (0.108s)
[339.251944] [100/142] (683s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[339.394115] Starting subtest: multi-wait-for-submit-available-submitted
[339.398333] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.002s)
[339.483282] [101/142] (683s left) syncobj_timeline (invalid-single-wait-all-available-unsubmitted)
[339.627279] Starting subtest: invalid-single-wait-all-available-unsubmitted
[339.629213] Subtest invalid-single-wait-all-available-unsubmitted: SUCCESS (0.000s)
[339.716497] [102/142] (683s left) kms_ccs (pipe-d-bad-aux-stride-y_tiled_gen12_mc_ccs)
[340.185232] Subtest pipe-D-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s)
[340.498495] [103/142] (682s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-blt)
[340.746630] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-blt
[342.674394] Subtest fbc-1p-offscren-pri-shrfb-draw-blt: SUCCESS (1.930s)
[342.832407] [104/142] (680s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[343.082576] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[343.085533] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[343.197595] [105/142] (680s left) vgem_basic (mmap)
[343.355401] Starting subtest: mmap
[343.382562] Subtest mmap: SUCCESS (0.027s)
[343.464749] [106/142] (680s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[343.708668] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[343.711867] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[343.815917] [107/142] (679s left) sysfs_timeslice_duration (invalid)
[343.966612] Starting subtest: invalid
[343.967995] Starting dynamic subtest: vecs0
[343.969231] Dynamic subtest vecs0: SUCCESS (0.001s)
[343.996772] Starting dynamic subtest: vcs0
[343.998104] Dynamic subtest vcs0: SUCCESS (0.001s)
[344.022135] Starting dynamic subtest: bcs0
[344.023491] Dynamic subtest bcs0: SUCCESS (0.001s)
[344.046817] Starting dynamic subtest: rcs0
[344.048094] Dynamic subtest rcs0: SUCCESS (0.001s)
[344.049327] Subtest invalid: SUCCESS (0.016s)
[344.082794] [108/142] (679s left) v3d/v3d_perfmon (get-values-invalid-pad)
[344.288182] Subtest get-values-invalid-pad: SKIP (0.000s)
[344.367034] [109/142] (679s left) gem_exec_balancer (nop)
[344.534551] Starting subtest: nop
[383.539598] Subtest nop: SUCCESS (39.004s)
[387.481466] [110/142] (636s left) kms_psr (dpms)
[387.623577] Subtest dpms: SKIP (0.000s)
[387.682037] [111/142] (636s left) kms_frontbuffer_tracking (psr-suspend)
[387.926379] Starting subtest: psr-suspend
[387.929061] Subtest psr-suspend: SKIP (0.000s)
[388.031076] [112/142] (635s left) kms_psr (psr2_sprite_blt)
[388.173729] Subtest psr2_sprite_blt: SKIP (0.000s)
[388.234343] [113/142] (635s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[388.484477] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[388.487574] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[388.580929] [114/142] (635s left) kms_pm_dc (dc9-dpms)
[389.834621] Starting subtest: dc9-dpms
[393.232625] Subtest dc9-dpms: SUCCESS (3.399s)
[393.408365] [115/142] (630s left) kms_vblank (pipe-b-ts-continuation-idle)
[393.646688] Starting subtest: pipe-B-ts-continuation-idle
[394.208084] Subtest pipe-B-ts-continuation-idle: SUCCESS (0.562s)
[394.550175] [116/142] (629s left) gem_lmem_swapping (parallel-random)
[394.703870] Subtest parallel-random: SKIP (0.000s)
[394.767725] [117/142] (629s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[395.030029] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[396.154265] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (1.123s)
[396.485861] [118/142] (627s left) gen3_render_linear_blits
[396.684393] [119/142] (627s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[396.826456] Starting subtest: single-wait-for-submit-unsubmitted
[396.930800] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.104s)
[397.031965] [120/142] (627s left) kms_rotation_crc (sprite-rotation-270)
[397.267194] Starting subtest: sprite-rotation-270
[403.504318] Subtest sprite-rotation-270: SUCCESS (6.239s)
[403.935943] [121/142] (620s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[404.194051] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[404.196963] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[404.282942] [122/142] (619s left) kms_universal_plane (universal-plane-pageflip-windowed-pipe-a)
[404.528226] Starting subtest: universal-plane-pageflip-windowed-pipe-A
[404.933344] Subtest universal-plane-pageflip-windowed-pipe-A: SUCCESS (0.407s)
[405.264614] [123/142] (619s left) gem_exec_schedule (semaphore-codependency)
[405.438762] Starting subtest: semaphore-codependency
[405.460270] Subtest semaphore-codependency: SUCCESS (0.021s)
[405.831279] [124/142] (618s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_mtl_mc_ccs)
[406.237469] Starting subtest: pipe-B-random-ccs-data-4_tiled_mtl_mc_ccs
[406.241373] Subtest pipe-B-random-ccs-data-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[406.642312] [125/142] (617s left) gem_tiled_partial_pwrite_pread (reads)
[406.794959] Starting subtest: reads
[408.204735] Subtest reads: SUCCESS (1.410s)
[408.324584] [126/142] (616s left) kms_cursor_crc (cursor-onscreen-128x42)
[408.575137] Starting subtest: cursor-onscreen-128x42
[408.585581] Starting dynamic subtest: pipe-A-DP-1
[410.925549] Dynamic subtest pipe-A-DP-1: SUCCESS (2.338s)
[411.016400] Starting dynamic subtest: pipe-C-DP-1
[413.438160] Dynamic subtest pipe-C-DP-1: SUCCESS (2.444s)
[413.439667] Subtest cursor-onscreen-128x42: SUCCESS (4.863s)
[413.799451] [127/142] (610s left) kms_ccs (pipe-c-missing-ccs-buffer-4_tiled_mtl_mc_ccs)
[414.257743] Starting subtest: pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs
[414.260392] Subtest pipe-C-missing-ccs-buffer-4_tiled_mtl_mc_ccs: SKIP (0.000s)
[414.612153] [128/142] (609s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_ccs)
[414.995740] Starting subtest: pipe-B-bad-pixel-format-y_tiled_ccs
[414.997128] Subtest pipe-B-bad-pixel-format-y_tiled_ccs: SUCCESS (0.001s)
[415.427395] [129/142] (609s left) gem_workarounds (suspend-resume)
[415.603848] Starting subtest: suspend-resume
[433.109349] Subtest suspend-resume: SUCCESS (1.474s)
[439.272997] [130/142] (607s left) gem_exec_fence (long-history)
[439.883229] Starting subtest: long-history
[445.650065] Subtest long-history: SUCCESS (5.764s)
[446.370938] [131/142] (600s left) gem_spin_batch (legacy)
[446.540842] Starting subtest: legacy
[446.542424] Starting dynamic subtest: default
[449.868139] Dynamic subtest default: SUCCESS (3.324s)
[449.894495] Starting dynamic subtest: render
[453.194526] Dynamic subtest render: SUCCESS (3.324s)
[453.218538] Starting dynamic subtest: bsd
[456.518173] Dynamic subtest bsd: SUCCESS (3.321s)
[456.541701] Starting dynamic subtest: bsd1
[459.842544] Dynamic subtest bsd1: SUCCESS (3.320s)
[459.866200] Starting dynamic subtest: blt
[463.167825] Dynamic subtest blt: SUCCESS (3.323s)
[463.191792] Starting dynamic subtest: vebox
[466.490907] Dynamic subtest vebox: SUCCESS (3.321s)
[466.493061] Subtest legacy: SUCCESS (19.951s)
[466.704519] [132/142] (580s left) gem_ctx_persistence (heartbeat-hostile)
[466.858113] Starting subtest: heartbeat-hostile
[468.046599] Subtest heartbeat-hostile: SUCCESS (1.189s)
[468.138452] [133/142] (578s left) kms_ccs (pipe-b-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc)
[468.552436] Starting subtest: pipe-B-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc
[468.555099] Subtest pipe-B-ccs-on-another-bo-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s)
[468.962534] [134/142] (577s left) gem_lmem_swapping (verify-random-ccs)
[469.115944] Subtest verify-random-ccs: SKIP (0.000s)
[469.176574] [135/142] (577s left) vgem_basic (debugfs)
[469.330909] Starting subtest: debugfs
[469.333150] Subtest debugfs: SUCCESS (0.002s)
[469.407011] [136/142] (577s left) kms_color (ctm-0-50)
[469.647344] Starting subtest: ctm-0-50
[469.648774] Starting dynamic subtest: pipe-A
[471.471599] Dynamic subtest pipe-A: SUCCESS (1.825s)
[471.504833] Starting dynamic subtest: pipe-B
[473.488248] Dynamic subtest pipe-B: SUCCESS (2.010s)
[473.514948] Starting dynamic subtest: pipe-C
[475.536793] Dynamic subtest pipe-C: SUCCESS (2.050s)
[475.539454] Subtest ctm-0-50: SUCCESS (5.893s)
[475.891353] [137/142] (571s left) core_auth (getclient-master-drop)
[476.030802] Starting subtest: getclient-master-drop
[476.036385] Subtest getclient-master-drop: SUCCESS (0.005s)
[476.125717] [138/142] (570s left) gem_softpin (allocator-basic-reserve)
[476.279157] Starting subtest: allocator-basic-reserve
[476.526054] Subtest allocator-basic-reserve: SUCCESS (0.246s)
[476.658519] [139/142] (570s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[476.906455] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[476.909473] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[477.008974] [140/142] (570s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_ccs)
[477.494021] Subtest pipe-D-missing-ccs-buffer-y_tiled_ccs: SKIP (0.000s)
[477.819087] [141/142] (569s left) gem_exec_schedule (deep)
[478.129532] Starting subtest: deep
[478.130933] Starting dynamic subtest: rcs0
[492.084791] Dynamic subtest rcs0: SUCCESS (13.947s)
[492.113129] Starting dynamic subtest: bcs0
[507.096068] Dynamic subtest bcs0: SUCCESS (15.008s)
[507.123874] Starting dynamic subtest: vcs0
[521.161120] Dynamic subtest vcs0: SUCCESS (14.061s)
[521.192986] Starting dynamic subtest: vecs0
[535.369991] Dynamic subtest vecs0: SUCCESS (14.198s)
[535.371393] Subtest deep: SUCCESS (57.239s)
[536.438367] [142/142] (510s left) gem_exec_fence (basic-wait-all)
[536.605077] Starting subtest: basic-wait-all
[536.638982] Subtest basic-wait-all: SUCCESS (0.034s)
[537.467914] Closing watchdogs
Done.