[60.071263] Initializing watchdogs
[60.071313]   /dev/watchdog0
[60.085364] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[60.089981] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.092716] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.096038] [001/133] (960s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[60.225225] Starting subtest: x-tiled-8bpp-rotate-180
[61.479083] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.263s)
[61.612736] [002/133] (959s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[61.714285] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[61.717825] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[61.778508] [003/133] (958s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[61.879480] Starting subtest: pr-cursor-plane-update-sf
[61.882985] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[61.942313] [004/133] (958s left) kms_frontbuffer_tracking (psr-slowdraw)
[62.052914] Starting subtest: psr-slowdraw
[87.422301] Subtest psr-slowdraw: SUCCESS (25.405s)
[87.564393] [005/133] (933s left) kms_flip (plain-flip-fb-recreate)
[87.660705] Starting subtest: plain-flip-fb-recreate
[87.663197] Starting dynamic subtest: A-eDP1
[96.515578] Dynamic subtest A-eDP1: SUCCESS (8.854s)
[96.527423] Starting dynamic subtest: B-eDP1
[105.348967] Dynamic subtest B-eDP1: SUCCESS (8.830s)
[105.356418] Starting dynamic subtest: C-eDP1
[114.187301] Dynamic subtest C-eDP1: SUCCESS (8.836s)
[114.195186] Starting dynamic subtest: D-eDP1
[123.012839] Dynamic subtest D-eDP1: SUCCESS (8.824s)
[123.017093] Subtest plain-flip-fb-recreate: SUCCESS (35.353s)
[124.188907] [006/133] (896s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[124.260516] Starting subtest: invalid-signal-one-illegal-handle
[124.262525] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[124.363881] [007/133] (896s left) api_intel_allocator (open-vm)
[124.425138] Starting subtest: open-vm
[124.427378] Subtest open-vm: SUCCESS (0.003s)
[124.544490] [008/133] (896s left) gem_exec_params (cliprects_ptr-dirt)
[124.601083] Starting subtest: cliprects_ptr-dirt
[124.603497] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[124.700193] [009/133] (896s left) gem_ctx_persistence (engines-hang)
[124.789537] Starting subtest: engines-hang
[124.791900] Starting dynamic subtest: rcs0
[124.848508] Dynamic subtest rcs0: SUCCESS (0.059s)
[124.859886] Starting dynamic subtest: bcs0
[124.938639] Dynamic subtest bcs0: SUCCESS (0.089s)
[124.951622] Starting dynamic subtest: vcs0
[125.030835] Dynamic subtest vcs0: SUCCESS (0.092s)
[125.043608] Starting dynamic subtest: vcs1
[125.123663] Dynamic subtest vcs1: SUCCESS (0.092s)
[125.133440] Starting dynamic subtest: vecs0
[125.219586] Dynamic subtest vecs0: SUCCESS (0.095s)
[125.229867] Starting dynamic subtest: ccs0
[125.307614] Dynamic subtest ccs0: SUCCESS (0.087s)
[125.309906] Subtest engines-hang: SUCCESS (0.519s)
[125.395454] [010/133] (895s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[125.493803] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[129.805232] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.314s)
[130.989882] [011/133] (889s left) kms_psr (fbc-psr2-primary-page-flip)
[131.088235] Starting subtest: fbc-psr2-primary-page-flip
[131.090667] Starting dynamic subtest: eDP-1
[131.095295] Dynamic subtest eDP-1: SKIP (0.000s)
[131.100123] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[131.158349] [012/133] (889s left) i915_drm_fdinfo (busy)
[131.203731] Starting subtest: busy
[131.205660] Starting dynamic subtest: rcs0
[131.228047] Dynamic subtest rcs0: SKIP (0.023s)
[131.237274] Starting dynamic subtest: bcs0
[131.244909] Dynamic subtest bcs0: SKIP (0.017s)
[131.252607] Starting dynamic subtest: vcs0
[131.256685] Dynamic subtest vcs0: SKIP (0.001s)
[131.264163] Starting dynamic subtest: vcs1
[131.268505] Dynamic subtest vcs1: SKIP (0.000s)
[131.275824] Starting dynamic subtest: vecs0
[131.279999] Dynamic subtest vecs0: SKIP (0.000s)
[131.287093] Starting dynamic subtest: ccs0
[131.291155] Dynamic subtest ccs0: SKIP (0.000s)
[131.295287] Subtest busy: SKIP (0.043s)
[131.329142] [013/133] (889s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[131.432047] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[131.436414] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[131.495298] [014/133] (889s left) kms_cursor_crc (cursor-offscreen-64x64)
[131.662586] Starting subtest: cursor-offscreen-64x64
[131.667383] Starting dynamic subtest: pipe-A-eDP-1
[133.598533] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.930s)
[133.625767] Starting dynamic subtest: pipe-D-eDP-1
[136.199526] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.578s)
[136.201747] Subtest cursor-offscreen-64x64: SUCCESS (4.534s)
[137.066774] [015/133] (884s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[137.163570] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[137.165814] Starting dynamic subtest: pipe-A-eDP-1
[137.186307] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[137.195033] Starting dynamic subtest: pipe-B-eDP-1
[137.215104] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[137.222656] Starting dynamic subtest: pipe-C-eDP-1
[137.242246] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[137.249774] Starting dynamic subtest: pipe-D-eDP-1
[137.269562] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[137.271814] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.004s)
[139.325971] [016/133] (881s left) syncobj_timeline (single-wait-available-signaled)
[139.377023] Starting subtest: single-wait-available-signaled
[139.379293] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[139.462119] [017/133] (881s left) gem_tiled_fence_blits (normal)
[139.516887] Subtest normal: SKIP (0.000s)
[139.578652] [018/133] (881s left) kms_flip (2x-flip-vs-modeset-vs-hang)
[139.667211] Starting subtest: 2x-flip-vs-modeset-vs-hang
[139.679054] Subtest 2x-flip-vs-modeset-vs-hang: SKIP (0.011s)
[139.771386] [019/133] (881s left) syncobj_timeline (multi-wait-available-signaled)
[139.824620] Starting subtest: multi-wait-available-signaled
[139.826822] Subtest multi-wait-available-signaled: SUCCESS (0.000s)
[139.915710] [020/133] (881s left) kms_content_protection (uevent-hdcp14)
[140.056063] Starting subtest: uevent-hdcp14
[140.060675] Subtest uevent-hdcp14: SKIP (0.000s)
[140.180284] [021/133] (881s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[140.292101] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[140.295699] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[140.371670] [022/133] (881s left) perf_pmu (semaphore-wait-idle)
[140.410816] Starting subtest: semaphore-wait-idle
[140.412913] Starting dynamic subtest: rcs0
[140.924836] Dynamic subtest rcs0: SUCCESS (0.513s)
[140.935873] Starting dynamic subtest: bcs0
[141.439416] Dynamic subtest bcs0: SUCCESS (0.511s)
[141.449632] Starting dynamic subtest: vcs0
[141.951340] Dynamic subtest vcs0: SUCCESS (0.511s)
[141.961801] Starting dynamic subtest: vcs1
[142.462490] Dynamic subtest vcs1: SUCCESS (0.510s)
[142.473041] Starting dynamic subtest: vecs0
[142.975285] Dynamic subtest vecs0: SUCCESS (0.510s)
[142.986140] Starting dynamic subtest: ccs0
[143.489969] Dynamic subtest ccs0: SUCCESS (0.513s)
[143.493287] Subtest semaphore-wait-idle: SUCCESS (3.079s)
[144.931077] [023/133] (876s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[145.044215] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[152.432826] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.393s)
[152.566842] [024/133] (868s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[152.664382] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[152.668757] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[152.752909] [025/133] (868s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[152.849255] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[152.853552] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[152.936317] [026/133] (868s left) gem_fenced_exec_thrash (no-spare-fences-interruptible)
[152.992225] Subtest no-spare-fences-interruptible: SKIP (0.000s)
[153.070118] [027/133] (868s left) kms_psr (pr-sprite-blt)
[153.169024] Starting subtest: pr-sprite-blt
[153.173581] Subtest pr-sprite-blt: SKIP (0.000s)
[153.237547] [028/133] (868s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[153.356065] Starting subtest: flip-vs-cursor-busy-crc-atomic
[154.171791] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.814s)
[154.999068] [029/133] (866s left) kms_psr (psr2-dpms)
[155.102275] Starting subtest: psr2-dpms
[155.104677] Starting dynamic subtest: eDP-1
[157.968012] Dynamic subtest eDP-1: SUCCESS (2.866s)
[157.970181] Subtest psr2-dpms: SUCCESS (2.866s)
[158.836310] [030/133] (862s left) syncobj_wait (single-wait-all-signaled)
[158.886710] Starting subtest: single-wait-all-signaled
[158.888906] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[158.980068] [031/133] (862s left) gem_render_copy_redux (interruptible)
[159.033972] Starting subtest: interruptible
[159.302788] Subtest interruptible: SUCCESS (0.269s)
[159.420870] [032/133] (862s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-wc)
[159.514724] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-wc
[159.518910] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[159.607855] [033/133] (862s left) i915_pm_freq_api (freq-basic-api)
[159.666783] Starting subtest: freq-basic-api
[159.669177] Starting dynamic subtest: gt0
[159.671705] Dynamic subtest gt0: SUCCESS (0.002s)
[159.680158] Starting dynamic subtest: gt1
[159.682381] Dynamic subtest gt1: SUCCESS (0.002s)
[159.684633] Subtest freq-basic-api: SUCCESS (0.006s)
[159.769839] [034/133] (862s left) gem_exercise_blt (fast-copy)
[159.826234] Starting subtest: fast-copy
[159.827771] Starting dynamic subtest: linear-smem-smem
[159.846829] Dynamic subtest linear-smem-smem: SUCCESS (0.023s)
[159.855536] Starting dynamic subtest: xmajor-smem-smem
[159.864628] Dynamic subtest xmajor-smem-smem: SUCCESS (0.017s)
[159.872065] Starting dynamic subtest: tile4-smem-smem
[159.881898] Dynamic subtest tile4-smem-smem: SUCCESS (0.017s)
[159.889299] Starting dynamic subtest: tile64-smem-smem
[159.899278] Dynamic subtest tile64-smem-smem: SUCCESS (0.017s)
[159.901099] Subtest fast-copy: SUCCESS (0.076s)
[160.018086] [035/133] (861s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[160.114292] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[160.117744] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[160.205458] [036/133] (861s left) gem_partial_pwrite_pread (reads-display)
[160.275210] Subtest reads-display: SKIP (0.000s)
[160.347099] [037/133] (861s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[160.437925] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[160.441433] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[160.532786] [038/133] (861s left) gem_ctx_param (basic-default)
[160.592199] Starting subtest: basic-default
[160.594253] Subtest basic-default: SUCCESS (0.000s)
[160.663511] [039/133] (861s left) gem_exec_balancer (parallel)
[160.927486] Starting subtest: parallel
[161.866644] Subtest parallel: SUCCESS (0.939s)
[162.943151] [040/133] (859s left) gem_create (create-ext-set-pat)
[163.117486] Starting subtest: create-ext-set-pat
[163.119692] Subtest create-ext-set-pat: SUCCESS (0.001s)
[163.209698] [041/133] (858s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[163.324855] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[163.327055] Starting dynamic subtest: pipe-A-default-mode
[164.498715] Dynamic subtest pipe-A-default-mode: SKIP (1.169s)
[164.502824] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.171s)
[164.659972] [042/133] (857s left) gem_exec_reloc (basic-gtt-cpu-active)
[164.728089] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[164.807881] [043/133] (857s left) perf (rc6-disable)
[164.940577] Starting subtest: rc6-disable
[165.118812] Subtest rc6-disable: SUCCESS (0.177s)
[165.163647] [044/133] (857s left) gem_mmap_offset (clear-via-pagefault)
[165.222903] Starting subtest: clear-via-pagefault
[165.225094] Starting dynamic subtest: smem0
[198.457527] Dynamic subtest smem0: SUCCESS (33.228s)
[198.459605] Subtest clear-via-pagefault: SUCCESS (33.230s)
[198.562138] [045/133] (823s left) kms_dirtyfb (psr-dirtyfb-ioctl)
[198.665899] Starting subtest: psr-dirtyfb-ioctl
[198.667888] Starting dynamic subtest: A-eDP-1
[202.582217] Dynamic subtest A-eDP-1: SUCCESS (3.915s)
[202.584583] Subtest psr-dirtyfb-ioctl: SUCCESS (3.916s)
[203.461654] [046/133] (818s left) kms_lease (lease-unleased-crtc)
[203.554246] Starting subtest: lease-unleased-crtc
[203.556623] Starting dynamic subtest: pipe-A-eDP-1
[203.853493] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.298s)
[203.862998] Starting dynamic subtest: pipe-B-eDP-1
[204.600123] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.746s)
[204.610481] Starting dynamic subtest: pipe-C-eDP-1
[204.612824] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[204.620905] Starting dynamic subtest: pipe-D-eDP-1
[204.623065] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[204.625184] Subtest lease-unleased-crtc: SUCCESS (1.058s)
[204.979911] [047/133] (817s left) gem_render_copy (yf-tiled)
[205.046752] Starting subtest: yf-tiled
[205.051103] Subtest yf-tiled: SKIP (0.000s)
[205.243264] [048/133] (817s left) i915_suspend (sysfs-reader)
[205.299382] Starting subtest: sysfs-reader
[223.356380] Subtest sysfs-reader: SUCCESS (3.267s)
[223.417259] [049/133] (799s left) gem_render_linear_blits (basic)
[223.480003] Starting subtest: basic
[223.501074] Subtest basic: SUCCESS (0.022s)
[223.583268] [050/133] (798s left) gem_ctx_bad_destroy (invalid-pad)
[223.637831] Starting subtest: invalid-pad
[223.639809] Subtest invalid-pad: SUCCESS (0.000s)
[223.709671] [051/133] (798s left) gem_render_copy (y-tiled-ccs-to-linear)
[223.770358] Starting subtest: y-tiled-ccs-to-linear
[223.774031] Subtest y-tiled-ccs-to-linear: SKIP (0.000s)
[223.962699] [052/133] (798s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[224.059858] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[230.857476] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.985s)
[231.006055] [053/133] (791s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[231.114013] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc
[237.647330] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (6.608s)
[237.770034] [054/133] (784s left) kms_cursor_edge_walk (128x128-right-edge)
[237.867271] Starting subtest: 128x128-right-edge
[237.869547] Starting dynamic subtest: pipe-A-eDP-1
[241.294976] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.447s)
[241.304523] Starting dynamic subtest: pipe-D-eDP-1
[244.414997] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.130s)
[244.417242] Subtest 128x128-right-edge: SUCCESS (6.579s)
[245.234713] [055/133] (777s left) kms_plane (plane-position-hole-dpms)
[245.328175] Starting subtest: plane-position-hole-dpms
[245.863986] Starting dynamic subtest: pipe-A-plane-1
[248.224339] Dynamic subtest pipe-A-plane-1: SUCCESS (2.364s)
[248.235516] Starting dynamic subtest: pipe-A-plane-2
[250.599971] Dynamic subtest pipe-A-plane-2: SUCCESS (2.379s)
[250.610299] Starting dynamic subtest: pipe-A-plane-3
[253.027466] Dynamic subtest pipe-A-plane-3: SUCCESS (2.431s)
[253.037667] Starting dynamic subtest: pipe-A-plane-4
[255.379466] Dynamic subtest pipe-A-plane-4: SUCCESS (2.350s)
[255.390022] Starting dynamic subtest: pipe-A-plane-5
[255.406853] Dynamic subtest pipe-A-plane-5: SUCCESS (0.025s)
[256.583515] Starting dynamic subtest: pipe-B-plane-1
[259.019978] Dynamic subtest pipe-B-plane-1: SUCCESS (2.445s)
[259.029712] Starting dynamic subtest: pipe-B-plane-2
[261.455569] Dynamic subtest pipe-B-plane-2: SUCCESS (2.431s)
[261.466172] Starting dynamic subtest: pipe-B-plane-3
[263.822101] Dynamic subtest pipe-B-plane-3: SUCCESS (2.364s)
[263.832600] Starting dynamic subtest: pipe-B-plane-4
[266.181465] Dynamic subtest pipe-B-plane-4: SUCCESS (2.360s)
[266.193489] Starting dynamic subtest: pipe-B-plane-5
[266.208566] Dynamic subtest pipe-B-plane-5: SUCCESS (0.024s)
[266.210779] Subtest plane-position-hole-dpms: SUCCESS (20.885s)
[266.996760] [056/133] (755s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[267.095766] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[274.493510] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.399s)
[274.607449] [057/133] (748s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[274.709889] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[274.713994] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[274.812396] [058/133] (748s left) gem_softpin (full)
[274.867022] Starting subtest: full
[274.875029] Subtest full: SUCCESS (0.008s)
[275.006329] [059/133] (747s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[275.384967] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[276.227009] [060/133] (746s left) kms_dsc (dsc-fractional-bpp)
[276.314389] Starting subtest: dsc-fractional-bpp
[276.327550] Subtest dsc-fractional-bpp: SKIP (0.000s)
[276.391896] [061/133] (746s left) gem_exec_parallel (userptr)
[276.448741] Starting subtest: userptr
[276.451140] Starting dynamic subtest: rcs0
[277.862346] Dynamic subtest rcs0: SUCCESS (1.413s)
[277.874497] Starting dynamic subtest: bcs0
[279.337988] Dynamic subtest bcs0: SUCCESS (1.475s)
[279.348198] Starting dynamic subtest: vcs0
[280.740283] Dynamic subtest vcs0: SUCCESS (1.400s)
[280.749935] Starting dynamic subtest: vcs1
[282.745500] Dynamic subtest vcs1: SUCCESS (2.002s)
[282.757479] Starting dynamic subtest: vecs0
[284.729549] Dynamic subtest vecs0: SUCCESS (1.980s)
[284.742251] Starting dynamic subtest: ccs0
[286.224224] Dynamic subtest ccs0: SUCCESS (1.494s)
[286.226625] Subtest userptr: SUCCESS (9.777s)
[286.419345] [062/133] (736s left) gem_ctx_exec (basic)
[286.485377] Starting subtest: basic
[286.494454] Subtest basic: SUCCESS (0.010s)
[286.618671] [063/133] (736s left) gem_exec_schedule (submit-late-slice)
[286.685343] Starting subtest: submit-late-slice
[286.687166] Starting dynamic subtest: rcs0
[286.737581] Dynamic subtest rcs0: SUCCESS (0.056s)
[286.746914] Starting dynamic subtest: bcs0
[286.779867] Dynamic subtest bcs0: SUCCESS (0.042s)
[286.790374] Starting dynamic subtest: vcs0
[286.828077] Dynamic subtest vcs0: SUCCESS (0.047s)
[286.838019] Starting dynamic subtest: vcs1
[286.873583] Dynamic subtest vcs1: SUCCESS (0.045s)
[286.883186] Starting dynamic subtest: vecs0
[286.918226] Dynamic subtest vecs0: SUCCESS (0.045s)
[286.929535] Starting dynamic subtest: ccs0
[286.972284] Dynamic subtest ccs0: SUCCESS (0.053s)
[286.974755] Subtest submit-late-slice: SUCCESS (0.293s)
[288.784802] [064/133] (734s left) gem_exec_schedule (preempt-contexts)
[288.852524] Starting subtest: preempt-contexts
[288.854540] Starting dynamic subtest: rcs0
[288.935018] Dynamic subtest rcs0: SUCCESS (0.090s)
[288.946317] Starting dynamic subtest: bcs0
[289.014192] Dynamic subtest bcs0: SUCCESS (0.078s)
[289.024819] Starting dynamic subtest: vcs0
[289.070225] Dynamic subtest vcs0: SUCCESS (0.056s)
[289.084184] Starting dynamic subtest: vcs1
[289.143333] Dynamic subtest vcs1: SUCCESS (0.072s)
[289.153661] Starting dynamic subtest: vecs0
[289.204539] Dynamic subtest vecs0: SUCCESS (0.060s)
[289.214761] Starting dynamic subtest: ccs0
[289.279552] Dynamic subtest ccs0: SUCCESS (0.074s)
[289.281694] Subtest preempt-contexts: SUCCESS (0.435s)
[290.838001] [065/133] (732s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[290.899428] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[290.967602] [066/133] (732s left) gem_exec_reloc (basic-write-read-noreloc)
[291.028069] Subtest basic-write-read-noreloc: SKIP (0.000s)
[291.099515] [067/133] (732s left) kms_addfb_basic (master-rmfb)
[291.158235] Starting subtest: master-rmfb
[291.160969] Subtest master-rmfb: SUCCESS (0.001s)
[291.297278] [068/133] (731s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[291.408376] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[292.199040] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.793s)
[292.381089] [069/133] (730s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[292.468532] Starting subtest: nonblocking-crc-frame-sequence
[292.470881] Starting dynamic subtest: pipe-A-eDP-1
[293.218708] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.749s)
[293.228687] Starting dynamic subtest: pipe-B-eDP-1
[294.679599] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.460s)
[294.690758] Starting dynamic subtest: pipe-C-eDP-1
[296.025148] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.347s)
[296.036615] Starting dynamic subtest: pipe-D-eDP-1
[297.354752] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.326s)
[297.357065] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.889s)
[298.165657] [070/133] (725s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[298.266080] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[298.270383] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[298.343617] [071/133] (724s left) kms_color (ctm-signed)
[298.428540] Starting subtest: ctm-signed
[298.430505] Starting dynamic subtest: pipe-A-eDP-1
[300.543497] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.117s)
[300.554364] Starting dynamic subtest: pipe-B-eDP-1
[303.337340] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.792s)
[303.349103] Starting dynamic subtest: pipe-C-eDP-1
[306.065010] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.727s)
[306.077979] Starting dynamic subtest: pipe-D-eDP-1
[308.751825] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.689s)
[308.754151] Subtest ctm-signed: SUCCESS (10.329s)
[309.564008] [072/133] (713s left) gem_exec_alignment (forked)
[309.625758] Starting subtest: forked
[347.921463] Subtest forked: SUCCESS (38.288s)
[348.624009] [073/133] (674s left) kms_big_fb (4-tiled-32bpp-rotate-90)
[348.759644] Starting subtest: 4-tiled-32bpp-rotate-90
[348.779539] Subtest 4-tiled-32bpp-rotate-90: SKIP (0.019s)
[348.899112] [074/133] (674s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[348.997313] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[349.001626] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[349.087108] [075/133] (674s left) syncobj_wait (invalid-wait-bad-flags)
[349.146450] Starting subtest: invalid-wait-bad-flags
[349.148665] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[349.220089] [076/133] (674s left) sw_sync (sync_multi_timeline_wait)
[349.259637] Starting subtest: sync_multi_timeline_wait
[349.261943] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[349.315922] [077/133] (674s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[349.409175] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[349.413491] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[349.501593] [078/133] (674s left) gem_exec_fence (basic-busy)
[349.691114] Starting subtest: basic-busy
[349.693731] Starting dynamic subtest: rcs0
[349.771292] Dynamic subtest rcs0: SUCCESS (0.079s)
[349.787878] Starting dynamic subtest: bcs0
[349.826516] Dynamic subtest bcs0: SUCCESS (0.053s)
[349.839305] Starting dynamic subtest: vcs0
[349.879864] Dynamic subtest vcs0: SUCCESS (0.051s)
[349.891341] Starting dynamic subtest: vcs1
[349.935118] Dynamic subtest vcs1: SUCCESS (0.053s)
[349.946519] Starting dynamic subtest: vecs0
[349.987987] Dynamic subtest vecs0: SUCCESS (0.051s)
[349.997762] Starting dynamic subtest: ccs0
[350.041266] Dynamic subtest ccs0: SUCCESS (0.052s)
[350.043801] Subtest basic-busy: SUCCESS (0.351s)
[351.813931] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[351.817862] [079/133] (671s left) kms_async_flips (invalid-async-flip)
[351.928123] Starting subtest: invalid-async-flip
[352.321586] Starting dynamic subtest: pipe-A-eDP-1
[353.104053] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.783s)
[353.465532] Starting dynamic subtest: pipe-B-eDP-1
[354.245171] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.789s)
[354.601645] Starting dynamic subtest: pipe-C-eDP-1
[355.372868] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.779s)
[355.734087] Starting dynamic subtest: pipe-D-eDP-1
[356.505853] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.782s)
[356.508375] Subtest invalid-async-flip: SUCCESS (4.581s)
[357.674932] [080/133] (666s left) kms_psr2_su (frontbuffer-xrgb8888)
[357.740230] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[357.807906] [081/133] (665s left) syncobj_timeline (etime-single-wait-submitted)
[357.868554] Starting subtest: etime-single-wait-submitted
[357.974787] Subtest etime-single-wait-submitted: SUCCESS (0.105s)
[358.051252] [082/133] (665s left) gem_exec_reloc (basic-cpu-wc-active)
[358.119819] Subtest basic-cpu-wc-active: SKIP (0.000s)
[358.204362] [083/133] (665s left) kms_flip (2x-flip-vs-panning-interruptible)
[358.304822] Starting subtest: 2x-flip-vs-panning-interruptible
[358.317159] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.011s)
[358.395047] [084/133] (665s left) i915_pm_freq_mult (media-freq)
[358.446009] Starting subtest: media-freq
[358.448384] Starting dynamic subtest: gt0
[358.452884] Dynamic subtest gt0: SKIP (0.000s)
[358.464756] Starting dynamic subtest: gt1
[358.469081] Dynamic subtest gt1: SKIP (0.000s)
[358.473264] Subtest media-freq: SKIP (0.001s)
[358.556840] [085/133] (665s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[358.683040] Starting subtest: cursorA-vs-flipB-varying-size
[358.687679] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[358.842674] [086/133] (665s left) kms_pm_rpm (modeset-lpsp)
[360.902908] Starting subtest: modeset-lpsp
[362.384517] Subtest modeset-lpsp: SUCCESS (1.481s)
[362.485392] [087/133] (661s left) gem_exec_whisper (basic-fds-all)
[362.562500] Starting subtest: basic-fds-all
[374.683494] Subtest basic-fds-all: SUCCESS (12.118s)
[374.866806] [088/133] (649s left) fbdev (nullptr)
[374.915831] Starting subtest: nullptr
[374.918109] Subtest nullptr: SUCCESS (0.000s)
[374.975377] [089/133] (649s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-pwrite)
[375.072196] Starting subtest: psr-1p-offscreen-pri-indfb-draw-pwrite
[382.274311] Subtest psr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.204s)
[382.413475] [090/133] (641s left) gem_userptr_blits (nohangcheck)
[382.495501] Starting subtest: nohangcheck
[383.719901] Subtest nohangcheck: SUCCESS (1.220s)
[383.849630] [091/133] (640s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[383.947110] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[383.949242] Starting dynamic subtest: pipe-A-eDP-1
[383.971053] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[383.983337] Starting dynamic subtest: pipe-B-eDP-1
[384.003595] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[384.013563] Starting dynamic subtest: pipe-C-eDP-1
[384.035037] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[384.046629] Starting dynamic subtest: pipe-D-eDP-1
[384.068603] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[384.072929] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[386.126803] [092/133] (638s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[386.512087] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[387.342865] [093/133] (636s left) perf (blocking)
[387.441211] Starting subtest: blocking
[387.443707] Starting dynamic subtest: 0-rcs0
[397.467414] Dynamic subtest 0-rcs0: SUCCESS (10.026s)
[397.479265] Starting dynamic subtest: 1-vecs0
[407.487851] Dynamic subtest 1-vecs0: SUCCESS (10.021s)
[407.490343] Subtest blocking: SUCCESS (20.049s)
[407.539613] [094/133] (616s left) kms_psr (psr-primary-mmap-gtt)
[407.632636] Starting subtest: psr-primary-mmap-gtt
[407.634855] Starting dynamic subtest: eDP-1
[409.058360] Dynamic subtest eDP-1: SKIP (1.422s)
[409.063510] Subtest psr-primary-mmap-gtt: SKIP (1.425s)
[409.217458] [095/133] (615s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[409.308016] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[409.310231] Starting dynamic subtest: pipe-A-eDP-1
[410.198053] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.883s)
[410.212100] Starting dynamic subtest: pipe-B-eDP-1
[411.790224] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.590s)
[411.800558] Starting dynamic subtest: pipe-C-eDP-1
[413.284501] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.493s)
[413.305767] Starting dynamic subtest: pipe-D-eDP-1
[414.748760] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.461s)
[414.750683] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.433s)
[415.907364] [096/133] (608s left) gem_exec_params (no-vebox)
[415.961158] Starting subtest: no-vebox
[415.963563] Subtest no-vebox: SUCCESS (0.002s)
[417.769401] [097/133] (606s left) kms_pm_dc (dc5-psr)
[418.883594] Starting subtest: dc5-psr
[421.863523] Subtest dc5-psr: SUCCESS (2.981s)
[422.725762] [098/133] (601s left) gem_pread (self)
[422.773708] Subtest self: SKIP (0.000s)
[422.833678] [099/133] (601s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[422.934176] Starting subtest: yf-tiled-32bpp-rotate-90
[422.938677] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[423.048094] [100/133] (601s left) syncobj_eventfd (binary-wait)
[423.092154] Starting subtest: binary-wait
[423.100031] Subtest binary-wait: SUCCESS (0.010s)
[423.177669] [101/133] (601s left) kms_cursor_crc (cursor-size-hints)
[423.308227] Starting subtest: cursor-size-hints
[423.310531] Starting dynamic subtest: pipe-A-eDP-1
[423.930694] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.622s)
[423.940732] Starting dynamic subtest: pipe-D-eDP-1
[425.182144] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.250s)
[425.184444] Subtest cursor-size-hints: SUCCESS (1.875s)
[425.970409] [102/133] (598s left) kms_atomic_interruptible (legacy-cursor)
[426.072449] Starting subtest: legacy-cursor
[426.074772] Starting dynamic subtest: pipe-A-eDP-1
[433.475908] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.404s)
[433.478334] Subtest legacy-cursor: SUCCESS (7.405s)
[434.335208] [103/133] (590s left) gem_render_copy (x-tiled)
[434.400671] Starting subtest: x-tiled
[434.402917] Starting dynamic subtest: smem
[434.444563] Dynamic subtest smem: SUCCESS (0.046s)
[434.446860] Subtest x-tiled: SUCCESS (0.047s)
[434.669636] [104/133] (590s left) gen9_exec_parse (basic-rejected)
[434.732525] Subtest basic-rejected: SKIP (0.000s)
[434.808670] [105/133] (590s left) core_sysfs (read-all-entries)
[434.862564] Starting subtest: read-all-entries
[434.868411] Subtest read-all-entries: SUCCESS (0.008s)
[434.965868] [106/133] (589s left) perf (gen12-unprivileged-single-ctx-counters)
[435.063935] Starting subtest: gen12-unprivileged-single-ctx-counters
[435.065869] Starting dynamic subtest: rcs0
[435.100413] Dynamic subtest rcs0: SUCCESS (0.039s)
[435.102803] Subtest gen12-unprivileged-single-ctx-counters: SUCCESS (0.040s)
[435.169251] [107/133] (589s left) kms_big_fb (linear-32bpp-rotate-90)
[435.283910] Starting subtest: linear-32bpp-rotate-90
[435.303517] Subtest linear-32bpp-rotate-90: SKIP (0.019s)
[435.430255] [108/133] (589s left) gem_ctx_persistence (legacy-engines-hostile)
[435.492744] Starting subtest: legacy-engines-hostile
[435.495004] Starting dynamic subtest: render
[435.608863] Dynamic subtest render: SUCCESS (0.116s)
[435.624053] Starting dynamic subtest: bsd1
[435.700890] Dynamic subtest bsd1: SUCCESS (0.089s)
[435.714658] Starting dynamic subtest: bsd2
[435.793984] Dynamic subtest bsd2: SUCCESS (0.090s)
[435.809980] Starting dynamic subtest: blt
[435.884696] Dynamic subtest blt: SUCCESS (0.088s)
[435.899526] Starting dynamic subtest: vebox
[435.985783] Dynamic subtest vebox: SUCCESS (0.098s)
[435.987967] Subtest legacy-engines-hostile: SUCCESS (0.495s)
[436.080505] [109/133] (588s left) perf_pmu (enable-race)
[436.122962] Starting subtest: enable-race
[436.124813] Starting dynamic subtest: rcs0
[448.890143] Dynamic subtest rcs0: SUCCESS (12.768s)
[448.900544] Starting dynamic subtest: bcs0
[461.634657] Dynamic subtest bcs0: SUCCESS (12.739s)
[461.646514] Starting dynamic subtest: vcs0
[476.729646] Dynamic subtest vcs0: SUCCESS (15.087s)
[476.741413] Starting dynamic subtest: vcs1
[492.729870] Dynamic subtest vcs1: SUCCESS (15.995s)
[492.742603] Starting dynamic subtest: vecs0
[508.728772] Dynamic subtest vecs0: SUCCESS (15.995s)
[508.741292] Starting dynamic subtest: ccs0
[521.474228] Dynamic subtest ccs0: SUCCESS (12.743s)
[521.476601] Subtest enable-race: SUCCESS (85.341s)
[521.599638] [110/133] (503s left) gem_softpin (32b-excludes-last-page)
[521.667423] Starting subtest: 32b-excludes-last-page
[521.672189] Subtest 32b-excludes-last-page: SUCCESS (0.005s)
[521.849890] [111/133] (503s left) api_intel_bb (purge-bb)
[521.904664] Starting subtest: purge-bb
[521.909579] Subtest purge-bb: SUCCESS (0.007s)
[522.021103] [112/133] (503s left) kms_sequence (queue-idle)
[522.136422] Starting subtest: queue-idle
[522.138736] Starting dynamic subtest: pipe-A-eDP-1
[523.948280] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.810s)
[523.963706] Starting dynamic subtest: pipe-B-eDP-1
[526.398400] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.451s)
[526.413095] Starting dynamic subtest: pipe-C-eDP-1
[528.902035] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.502s)
[528.913284] Starting dynamic subtest: pipe-D-eDP-1
[531.335693] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.430s)
[531.337530] Subtest queue-idle: SUCCESS (9.198s)
[532.151874] [113/133] (493s left) gem_exec_flush (basic-wb-rw-before-default)
[532.211967] Starting subtest: basic-wb-rw-before-default
[537.662572] Subtest basic-wb-rw-before-default: SUCCESS (5.451s)
[537.888335] [114/133] (487s left) syncobj_timeline (wait-for-submit-delayed-submit)
[537.932783] Starting subtest: wait-for-submit-delayed-submit
[538.034312] Subtest wait-for-submit-delayed-submit: SUCCESS (0.102s)
[538.114087] [115/133] (487s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[538.208535] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[538.212965] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[538.302359] [116/133] (487s left) kms_flip (flip-vs-panning-interruptible)
[538.406493] Starting subtest: flip-vs-panning-interruptible
[538.408750] Starting dynamic subtest: A-eDP1
[547.148545] Dynamic subtest A-eDP1: SUCCESS (8.742s)
[547.163054] Starting dynamic subtest: B-eDP1
[555.886842] Dynamic subtest B-eDP1: SUCCESS (8.737s)
[555.901023] Starting dynamic subtest: C-eDP1
[564.609690] Dynamic subtest C-eDP1: SUCCESS (8.721s)
[564.622999] Starting dynamic subtest: D-eDP1
[573.329314] Dynamic subtest D-eDP1: SUCCESS (8.717s)
[573.333966] Subtest flip-vs-panning-interruptible: SUCCESS (34.932s)
[574.511678] [117/133] (450s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[574.605152] Starting subtest: y-tiled-16bpp-rotate-90
[574.609214] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[574.718880] [118/133] (450s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[574.809607] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[574.813929] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[574.931884] [119/133] (450s left) kms_plane_multiple (2x-tiling-4)
[575.037919] Starting subtest: 2x-tiling-4
[575.042068] Subtest 2x-tiling-4: SKIP (0.000s)
[575.127643] [120/133] (450s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc)
[575.226098] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc
[575.228323] Starting dynamic subtest: pipe-A-eDP-1
[575.287671] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[575.303488] Starting dynamic subtest: pipe-B-eDP-1
[575.360023] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[575.371352] Starting dynamic subtest: pipe-C-eDP-1
[575.428400] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[575.439369] Starting dynamic subtest: pipe-D-eDP-1
[575.485043] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[575.488671] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.009s)
[577.387775] [121/133] (448s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[577.491511] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt
[577.495910] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[577.603867] [122/133] (447s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[577.700155] Starting subtest: fbcpsr-slowdraw
[602.938476] Subtest fbcpsr-slowdraw: SUCCESS (25.244s)
[603.078953] [123/133] (422s left) kms_invalid_mode (clock-too-high)
[603.177254] Starting subtest: clock-too-high
[603.179571] Starting dynamic subtest: pipe-A-eDP-1
[603.183753] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[603.194134] Starting dynamic subtest: pipe-B-eDP-1
[603.198176] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[603.208488] Starting dynamic subtest: pipe-C-eDP-1
[603.212673] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[603.222622] Starting dynamic subtest: pipe-D-eDP-1
[603.226906] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[603.231235] Subtest clock-too-high: SKIP (0.004s)
[603.269564] [124/133] (422s left) gem_exec_fence (submit)
[603.443162] Starting subtest: submit
[603.448065] Subtest submit: SKIP (0.000s)
[604.808389] [125/133] (420s left) kms_vblank (query-forked-busy-hang)
[604.906568] Starting subtest: query-forked-busy-hang
[604.908872] Starting dynamic subtest: pipe-A-eDP-1
[618.504599] Dynamic subtest pipe-A-eDP-1: SUCCESS (13.599s)
[618.522061] Starting dynamic subtest: pipe-D-eDP-1
[633.530808] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.025s)
[633.532963] Subtest query-forked-busy-hang: SUCCESS (28.629s)
[634.359764] [126/133] (391s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[634.458052] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[634.462517] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[634.586283] [127/133] (391s left) gem_userptr_blits (dmabuf-unsync)
[634.665067] Subtest dmabuf-unsync: SKIP (0.000s)
[634.799449] [128/133] (391s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[634.893284] Starting subtest: plane-upscale-20x20-with-pixel-format
[634.895560] Starting dynamic subtest: pipe-A
[635.938932] Dynamic subtest pipe-A: SUCCESS (1.050s)
[635.949394] Starting dynamic subtest: pipe-B
[638.060424] Dynamic subtest pipe-B: SUCCESS (2.120s)
[638.073515] Starting dynamic subtest: pipe-C
[639.241874] Dynamic subtest pipe-C: SUCCESS (1.179s)
[639.253190] Starting dynamic subtest: pipe-D
[640.393370] Dynamic subtest pipe-D: SUCCESS (1.150s)
[640.396025] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.506s)
[641.589041] [129/133] (384s left) kms_chamelium_color (ctm-max)
[641.684031] Subtest ctm-max: SKIP (0.000s)
[641.734012] [130/133] (384s left) gem_render_copy (y-tiled)
[641.791481] Starting subtest: y-tiled
[641.795833] Subtest y-tiled: SKIP (0.000s)
[641.979117] [131/133] (383s left) kms_psr (fbc-psr-primary-mmap-gtt)
[642.087575] Starting subtest: fbc-psr-primary-mmap-gtt
[642.089859] Starting dynamic subtest: eDP-1
[642.093989] Dynamic subtest eDP-1: SKIP (0.000s)
[642.098129] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[642.164624] [132/133] (383s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[642.256401] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[642.260697] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[642.350354] [133/133] (383s left) gem_exec_params (secure-non-master)
[642.403451] Starting subtest: secure-non-master
[642.407662] Subtest secure-non-master: SKIP (0.000s)
[642.493713] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: kms_plane
results: parsing output: 2/ for test: kms_psr2_sf
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: api_intel_allocator
results: parsing output: 7/ for test: gem_exec_params
results: parsing output: 8/ for test: gem_ctx_persistence
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_psr
results: parsing output: 11/ for test: i915_drm_fdinfo
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_cursor_crc
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: gem_tiled_fence_blits
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: syncobj_timeline
results: parsing output: 19/ for test: kms_content_protection
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: perf_pmu
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_flip_scaled_crc
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: gem_fenced_exec_thrash
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: gem_render_copy_redux
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: i915_pm_freq_api
results: parsing output: 33/ for test: gem_exercise_blt
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: gem_partial_pwrite_pread
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_ctx_param
results: parsing output: 38/ for test: gem_exec_balancer
results: parsing output: 39/ for test: gem_create
results: parsing output: 40/ for test: kms_flip_scaled_crc
results: parsing output: 41/ for test: gem_exec_reloc
results: parsing output: 42/ for test: perf
results: parsing output: 43/ for test: gem_mmap_offset
results: parsing output: 44/ for test: kms_dirtyfb
results: parsing output: 45/ for test: kms_lease
results: parsing output: 46/ for test: gem_render_copy
results: parsing output: 47/ for test: i915_suspend
results: parsing output: 48/ for test: gem_render_linear_blits
results: parsing output: 49/ for test: gem_ctx_bad_destroy
results: parsing output: 50/ for test: gem_render_copy
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_cursor_edge_walk
results: parsing output: 54/ for test: kms_plane
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_softpin
results: parsing output: 58/ for test: kms_chamelium_edid
results: parsing output: 59/ for test: kms_dsc
results: parsing output: 60/ for test: gem_exec_parallel
results: parsing output: 61/ for test: gem_ctx_exec
results: parsing output: 62/ for test: gem_exec_schedule
results: parsing output: 63/ for test: gem_exec_schedule
results: parsing output: 64/ for test: gem_mmap_gtt
results: parsing output: 65/ for test: gem_exec_reloc
results: parsing output: 66/ for test: kms_addfb_basic
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: kms_pipe_crc_basic
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_color
results: parsing output: 71/ for test: gem_exec_alignment
results: parsing output: 72/ for test: kms_big_fb
results: parsing output: 73/ for test: kms_big_fb
results: parsing output: 74/ for test: syncobj_wait
results: parsing output: 75/ for test: sw_sync
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: gem_exec_fence
results: parsing output: 78/ for test: kms_async_flips
results: parsing output: 79/ for test: kms_psr2_su
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: gem_exec_reloc
results: parsing output: 82/ for test: kms_flip
results: parsing output: 83/ for test: i915_pm_freq_mult
results: parsing output: 84/ for test: kms_cursor_legacy
results: parsing output: 85/ for test: kms_pm_rpm
results: parsing output: 86/ for test: gem_exec_whisper
results: parsing output: 87/ for test: fbdev
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: gem_userptr_blits
results: parsing output: 90/ for test: kms_ccs
results: parsing output: 91/ for test: kms_chamelium_hpd
results: parsing output: 92/ for test: perf
results: parsing output: 93/ for test: kms_psr
results: parsing output: 94/ for test: kms_ccs
results: parsing output: 95/ for test: gem_exec_params
results: parsing output: 96/ for test: kms_pm_dc
results: parsing output: 97/ for test: gem_pread
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: syncobj_eventfd
results: parsing output: 100/ for test: kms_cursor_crc
results: parsing output: 101/ for test: kms_atomic_interruptible
results: parsing output: 102/ for test: gem_render_copy
results: parsing output: 103/ for test: gen9_exec_parse
results: parsing output: 104/ for test: core_sysfs
results: parsing output: 105/ for test: perf
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: gem_ctx_persistence
results: parsing output: 108/ for test: perf_pmu
results: parsing output: 109/ for test: gem_softpin
results: parsing output: 110/ for test: api_intel_bb
results: parsing output: 111/ for test: kms_sequence
results: parsing output: 112/ for test: gem_exec_flush
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_plane_multiple
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_invalid_mode
results: parsing output: 123/ for test: gem_exec_fence
results: parsing output: 124/ for test: kms_vblank
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_userptr_blits
results: parsing output: 127/ for test: kms_plane_scaling
results: parsing output: 128/ for test: kms_chamelium_color
results: parsing output: 129/ for test: gem_render_copy
results: parsing output: 130/ for test: kms_psr
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: gem_exec_params
Done.