[52.229363] Initializing watchdogs
[52.229409]   /dev/watchdog0
[52.243577] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[52.247589] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[52.249815] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[52.253862] [001/133] (960s left) gen9_exec_parse (allowed-all)
[52.334315] Subtest allowed-all: SKIP (0.000s)
[52.406404] [002/133] (960s left) gem_exec_balancer (sequential)
[52.466824] Starting subtest: sequential
[55.529641] Subtest sequential: SUCCESS (3.063s)
[55.834983] [003/133] (956s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[55.927621] Starting subtest: 4-tiled-8bpp-rotate-0
[57.142464] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.215s)
[57.273978] [004/133] (955s left) gem_pwrite (basic-self)
[57.337641] Subtest basic-self: SKIP (0.000s)
[57.390478] [005/133] (955s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[57.454022] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[57.457743] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[58.547496] [006/133] (954s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[58.617994] Starting subtest: nonblocking-crc-frame-sequence
[58.620422] Starting dynamic subtest: pipe-A-eDP-1
[59.387493] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.768s)
[59.398900] Starting dynamic subtest: pipe-B-eDP-1
[60.807026] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.419s)
[60.816101] Starting dynamic subtest: pipe-C-eDP-1
[62.158230] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.352s)
[62.168778] Starting dynamic subtest: pipe-D-eDP-1
[63.496401] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.334s)
[63.498754] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.879s)
[64.293243] [007/133] (948s left) gem_mmap_gtt (fault-concurrent-x)
[64.349312] Subtest fault-concurrent-X: SKIP (0.000s)
[64.424180] [008/133] (948s left) gem_exec_whisper (basic-queues-forked)
[64.497029] Starting subtest: basic-queues-forked
[88.938909] Subtest basic-queues-forked: SUCCESS (24.437s)
[89.116957] [009/133] (923s left) kms_flip (dpms-off-confusion)
[89.197179] Starting subtest: dpms-off-confusion
[89.199489] Starting dynamic subtest: A-eDP1
[97.900286] Dynamic subtest A-eDP1: SUCCESS (8.704s)
[97.910651] Starting dynamic subtest: B-eDP1
[106.622976] Dynamic subtest B-eDP1: SUCCESS (8.721s)
[106.633057] Starting dynamic subtest: C-eDP1
[115.327198] Dynamic subtest C-eDP1: SUCCESS (8.702s)
[115.337399] Starting dynamic subtest: D-eDP1
[123.961413] Dynamic subtest D-eDP1: SUCCESS (8.634s)
[123.966105] Subtest dpms-off-confusion: SUCCESS (34.771s)
[125.145196] [010/133] (887s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[125.235353] Starting subtest: yf-tiled-16bpp-rotate-0
[125.239425] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[125.335237] [011/133] (887s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[125.421209] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[125.425588] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[125.500291] [012/133] (887s left) gem_exec_whisper (basic-contexts-forked-all)
[125.558703] Starting subtest: basic-contexts-forked-all
[147.610908] Subtest basic-contexts-forked-all: SUCCESS (22.048s)
[147.791897] [013/133] (865s left) gem_mmap_wc (coherency)
[147.845991] Subtest coherency: SKIP (0.000s)
[147.904565] [014/133] (865s left) gem_exec_fence (keep-in-fence)
[148.084577] Starting subtest: keep-in-fence
[148.087217] Starting dynamic subtest: rcs0
[148.145998] Dynamic subtest rcs0: SUCCESS (0.061s)
[148.156883] Starting dynamic subtest: bcs0
[148.200958] Dynamic subtest bcs0: SUCCESS (0.054s)
[148.211864] Starting dynamic subtest: vcs0
[149.851764] Dynamic subtest vcs0: SUCCESS (1.648s)
[149.863211] Starting dynamic subtest: vcs1
[151.834988] Dynamic subtest vcs1: SUCCESS (1.982s)
[151.846625] Starting dynamic subtest: vecs0
[153.883234] Dynamic subtest vecs0: SUCCESS (2.047s)
[153.894087] Starting dynamic subtest: ccs0
[154.122187] Dynamic subtest ccs0: SUCCESS (0.239s)
[154.125067] Subtest keep-in-fence: SUCCESS (6.037s)
[155.938171] [FACT gem_exec_fence (keep-in-fence)] new: kernel.kmod_is_loaded.vgem: true
[155.941192] [015/133] (857s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[156.023727] Starting subtest: plane-primary-overlay-mutable-zpos
[159.468681] Subtest plane-primary-overlay-mutable-zpos: SKIP (3.441s)
[160.643631] [016/133] (852s left) kms_chamelium_color (ctm-0-75)
[160.720166] Subtest ctm-0-75: SKIP (0.000s)
[160.761521] [017/133] (852s left) kms_addfb_basic (bad-pitch-65536)
[160.808652] Starting subtest: bad-pitch-65536
[160.810806] Subtest bad-pitch-65536: SUCCESS (0.000s)
[160.916795] [018/133] (852s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[160.985970] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[161.015636] Starting dynamic subtest: pipe-A-plane-0
[161.460859] Dynamic subtest pipe-A-plane-0: SUCCESS (0.444s)
[161.474666] Starting dynamic subtest: pipe-A-plane-3
[161.715305] Dynamic subtest pipe-A-plane-3: SUCCESS (0.254s)
[162.837959] Starting dynamic subtest: pipe-B-plane-0
[163.274680] Dynamic subtest pipe-B-plane-0: SUCCESS (0.444s)
[163.284589] Starting dynamic subtest: pipe-B-plane-3
[163.539371] Dynamic subtest pipe-B-plane-3: SUCCESS (0.266s)
[163.850675] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.863s)
[164.705908] [019/133] (848s left) gem_eio (in-flight-1us)
[164.934786] Starting subtest: in-flight-1us
[173.596074] Subtest in-flight-1us: SUCCESS (8.660s)
[173.730530] [020/133] (839s left) gem_exec_params (cliprects_ptr-dirt)
[173.779960] Starting subtest: cliprects_ptr-dirt
[173.782725] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[173.877369] [021/133] (839s left) i915_query (test-query-geometry-subslices)
[173.924433] Starting subtest: test-query-geometry-subslices
[173.926588] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[174.010673] [022/133] (839s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[174.387201] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[175.222333] [023/133] (838s left) kms_cursor_legacy (torture-move)
[175.330935] Starting subtest: torture-move
[175.333134] Starting dynamic subtest: pipe-A
[181.796338] Dynamic subtest pipe-A: SUCCESS (6.466s)
[181.810705] Starting dynamic subtest: pipe-B
[188.678886] Dynamic subtest pipe-B: SUCCESS (6.882s)
[188.689199] Starting dynamic subtest: pipe-C
[195.396887] Dynamic subtest pipe-C: SUCCESS (6.717s)
[195.408113] Starting dynamic subtest: pipe-D
[201.949897] Dynamic subtest pipe-D: SUCCESS (6.552s)
[201.961883] Starting dynamic subtest: all-pipes
[208.776701] Dynamic subtest all-pipes: SUCCESS (6.826s)
[208.779197] Subtest torture-move: SUCCESS (33.448s)
[208.933849] [024/133] (804s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[209.026650] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[209.030849] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[209.125691] [025/133] (804s left) gem_create (create-ext-cpu-access-sanity-check)
[209.299998] Starting subtest: create-ext-cpu-access-sanity-check
[209.305444] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[209.373433] [026/133] (804s left) gem_eio (in-flight-internal-immediate)
[209.594184] Starting subtest: in-flight-internal-immediate
[210.908116] Subtest in-flight-internal-immediate: SUCCESS (1.312s)
[211.059039] [027/133] (802s left) perf_pmu (frequency)
[211.096760] Starting subtest: frequency
[211.101287] Starting dynamic subtest: gt0
[213.278890] Dynamic subtest gt0: SUCCESS (2.174s)
[213.290395] Starting dynamic subtest: idle-gt0
[213.784047] Dynamic subtest idle-gt0: SUCCESS (0.505s)
[213.797829] Starting dynamic subtest: gt1
[218.839472] Dynamic subtest gt1: SUCCESS (5.051s)
[218.850554] Starting dynamic subtest: idle-gt1
[219.343247] Dynamic subtest idle-gt1: SUCCESS (0.505s)
[219.346242] Subtest frequency: SUCCESS (8.247s)
[219.480725] [028/133] (794s left) gem_exec_fence (basic-wait-all)
[219.542199] Starting subtest: basic-wait-all
[219.644398] Subtest basic-wait-all: SUCCESS (0.103s)
[221.926868] [029/133] (791s left) dmabuf_sync_file (export-multiwait)
[221.965800] Starting subtest: export-multiwait
[221.968444] Subtest export-multiwait: SUCCESS (0.001s)
[222.021582] [030/133] (791s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[222.080937] Starting subtest: single-wait-all-for-submit-available-signaled
[222.083407] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[222.184601] [031/133] (791s left) kms_psr (fbc-pr-cursor-plane-move)
[222.259071] Starting subtest: fbc-pr-cursor-plane-move
[222.263116] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[222.332069] [032/133] (791s left) kms_psr (psr2-suspend)
[222.401845] Starting subtest: psr2-suspend
[222.404109] Starting dynamic subtest: eDP-1
[239.862500] Dynamic subtest eDP-1: SUCCESS (3.042s)
[239.864958] Subtest psr2-suspend: SUCCESS (3.043s)
[240.757965] [033/133] (773s left) gem_exec_reloc (basic-write-cpu)
[240.838277] Subtest basic-write-cpu: SKIP (0.000s)
[240.901095] [034/133] (773s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[240.978943] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[240.981311] Starting dynamic subtest: pipe-A-eDP-1
[241.845742] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.859s)
[241.856041] Starting dynamic subtest: pipe-B-eDP-1
[243.415388] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.563s)
[243.426502] Starting dynamic subtest: pipe-C-eDP-1
[244.883115] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.470s)
[244.893121] Starting dynamic subtest: pipe-D-eDP-1
[246.426647] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.539s)
[246.428959] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.435s)
[247.587474] [035/133] (766s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[247.655855] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[247.658144] Starting dynamic subtest: pipe-A
[247.674472] Dynamic subtest pipe-A: SKIP (0.016s)
[247.684534] Starting dynamic subtest: pipe-B
[247.704328] Dynamic subtest pipe-B: SKIP (0.029s)
[247.714103] Starting dynamic subtest: pipe-C
[247.736487] Dynamic subtest pipe-C: SKIP (0.030s)
[247.747831] Starting dynamic subtest: pipe-D
[247.765312] Dynamic subtest pipe-D: SKIP (0.029s)
[247.769679] Subtest plane-downscale-factor-0-5-with-pixel-format: SKIP (0.106s)
[247.862729] [036/133] (766s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[247.947643] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[254.866151] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.921s)
[255.017267] [037/133] (759s left) gem_exec_schedule (smoketest)
[255.208555] Starting subtest: smoketest
[255.210862] Starting dynamic subtest: rcs0
[260.763875] Dynamic subtest rcs0: SUCCESS (5.504s)
[260.778719] Starting dynamic subtest: bcs0
[266.306476] Dynamic subtest bcs0: SUCCESS (5.541s)
[266.322789] Starting dynamic subtest: vcs0
[271.813573] Dynamic subtest vcs0: SUCCESS (5.505s)
[271.831473] Starting dynamic subtest: vcs1
[277.317112] Dynamic subtest vcs1: SUCCESS (5.501s)
[277.329466] Starting dynamic subtest: vecs0
[282.818052] Dynamic subtest vecs0: SUCCESS (5.501s)
[282.831398] Starting dynamic subtest: ccs0
[288.346307] Dynamic subtest ccs0: SUCCESS (5.531s)
[288.348662] Subtest smoketest: SUCCESS (33.088s)
[288.501369] [038/133] (725s left) kms_flip (plain-flip-interruptible)
[288.583580] Starting subtest: plain-flip-interruptible
[288.585843] Starting dynamic subtest: A-eDP1
[290.216868] Dynamic subtest A-eDP1: SUCCESS (1.631s)
[290.226967] Starting dynamic subtest: B-eDP1
[291.847904] Dynamic subtest B-eDP1: SUCCESS (1.629s)
[291.858517] Starting dynamic subtest: C-eDP1
[293.460934] Dynamic subtest C-eDP1: SUCCESS (1.610s)
[293.477424] Starting dynamic subtest: D-eDP1
[295.091911] Dynamic subtest D-eDP1: SUCCESS (1.629s)
[295.097109] Subtest plain-flip-interruptible: SUCCESS (6.512s)
[296.263986] [039/133] (718s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[296.357637] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[296.362094] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[296.473343] [040/133] (717s left) i915_drm_fdinfo (virtual-busy-hang)
[296.510978] Starting subtest: virtual-busy-hang
[296.515202] Subtest virtual-busy-hang: SKIP (0.000s)
[296.592113] [041/133] (717s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[296.669968] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[296.674335] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[296.766571] [042/133] (717s left) kms_lease (cursor-implicit-plane)
[296.844779] Starting subtest: cursor-implicit-plane
[296.846995] Starting dynamic subtest: pipe-A-eDP-1
[297.292685] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.447s)
[297.302610] Starting dynamic subtest: pipe-B-eDP-1
[298.385441] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.091s)
[298.395523] Starting dynamic subtest: pipe-C-eDP-1
[299.515398] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.127s)
[299.526136] Starting dynamic subtest: pipe-D-eDP-1
[300.645897] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.129s)
[300.648169] Subtest cursor-implicit-plane: SUCCESS (3.800s)
[301.506500] [043/133] (712s left) sw_sync (alloc_merge_fence)
[301.544531] Starting subtest: alloc_merge_fence
[301.546681] Subtest alloc_merge_fence: SUCCESS (0.000s)
[301.613851] [044/133] (712s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[301.693896] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[301.698044] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[301.789576] [045/133] (712s left) i915_query (query-topology-unsupported)
[301.839025] Starting subtest: query-topology-unsupported
[301.843365] Subtest query-topology-unsupported: SKIP (0.000s)
[301.955079] [046/133] (712s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[302.060668] Starting subtest: flip-vs-cursor-crc-atomic
[302.772329] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.710s)
[303.623940] [047/133] (711s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[303.696434] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[303.700594] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[303.799097] [048/133] (710s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[303.856484] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[303.858911] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[303.955089] [049/133] (710s left) kms_setmode (basic)
[304.008679] Starting subtest: basic
[304.331075] Starting dynamic subtest: pipe-A-eDP-1
[306.435915] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.100s)
[306.781646] Starting dynamic subtest: pipe-B-eDP-1
[308.864907] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.087s)
[309.181751] Subtest basic: SUCCESS (5.173s)
[310.011799] [050/133] (704s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[310.087950] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[310.092599] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[310.165647] [051/133] (704s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-render)
[310.250579] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-render
[310.254676] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[310.355587] [052/133] (704s left) syncobj_eventfd (binary-wait-before-signal)
[310.414471] Starting subtest: binary-wait-before-signal
[310.443712] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[310.523689] [053/133] (704s left) kms_big_fb (x-tiled-addfb)
[310.639696] Starting subtest: x-tiled-addfb
[310.642128] Subtest x-tiled-addfb: SUCCESS (0.001s)
[310.746410] [054/133] (704s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[310.828477] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[317.543102] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.716s)
[317.643568] [055/133] (697s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[317.716365] Starting subtest: addfb25-y-tiled-small-legacy
[317.720396] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[317.798016] [056/133] (697s left) gem_ctx_param (invalid-set-ringsize)
[317.849753] Starting subtest: invalid-set-ringsize
[317.852088] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[317.920637] [057/133] (697s left) kms_prime (basic-modeset-hybrid)
[317.990931] Subtest basic-modeset-hybrid: SKIP (0.000s)
[318.086997] [058/133] (696s left) kms_vblank (crtc-id)
[318.159914] Starting subtest: crtc-id
[318.162166] Starting dynamic subtest: pipe-A-eDP-1
[318.610270] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.449s)
[318.624418] Starting dynamic subtest: pipe-D-eDP-1
[319.765294] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.152s)
[319.767534] Subtest crtc-id: SUCCESS (1.605s)
[320.601572] [059/133] (694s left) gem_exec_balancer (noheartbeat)
[320.851301] Starting subtest: noheartbeat
[320.856293] Subtest noheartbeat: SKIP (0.000s)
[320.987775] [060/133] (694s left) prime_udl
[321.069181] [061/133] (694s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[321.146010] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[321.150417] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[321.241363] [062/133] (694s left) gem_busy (extended)
[321.418122] Starting subtest: extended
[321.420380] Starting dynamic subtest: rcs0
[321.497301] Dynamic subtest rcs0: SUCCESS (0.079s)
[321.507991] Starting dynamic subtest: bcs0
[321.542602] Dynamic subtest bcs0: SUCCESS (0.043s)
[321.553164] Starting dynamic subtest: vcs0
[323.243911] Dynamic subtest vcs0: SUCCESS (1.697s)
[323.266173] Starting dynamic subtest: vcs1
[325.293043] Dynamic subtest vcs1: SUCCESS (2.046s)
[325.305871] Starting dynamic subtest: vecs0
[327.275649] Dynamic subtest vecs0: SUCCESS (1.980s)
[327.288250] Starting dynamic subtest: ccs0
[327.322920] Dynamic subtest ccs0: SUCCESS (0.046s)
[327.325842] Subtest extended: SUCCESS (5.906s)
[327.580157] [063/133] (687s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[327.671364] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[327.675558] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[327.752909] [064/133] (687s left) gem_userptr_blits (sync-unmap)
[327.834959] Starting subtest: sync-unmap
[327.849367] Subtest sync-unmap: SUCCESS (0.015s)
[327.968079] [065/133] (687s left) kms_flip (blocking-wf_vblank)
[328.050854] Starting subtest: blocking-wf_vblank
[328.052827] Starting dynamic subtest: A-eDP1
[336.951165] Dynamic subtest A-eDP1: SUCCESS (8.900s)
[336.978906] Starting dynamic subtest: B-eDP1
[345.881132] Dynamic subtest B-eDP1: SUCCESS (8.926s)
[345.895691] Starting dynamic subtest: C-eDP1
[354.809890] Dynamic subtest C-eDP1: SUCCESS (8.923s)
[354.822649] Starting dynamic subtest: D-eDP1
[363.742841] Dynamic subtest D-eDP1: SUCCESS (8.932s)
[363.745294] Subtest blocking-wf_vblank: SUCCESS (35.691s)
[364.974493] [066/133] (650s left) gem_spin_batch (legacy-resubmit)
[365.045136] Starting subtest: legacy-resubmit
[365.047485] Starting dynamic subtest: default
[365.052612] Dynamic subtest default: SUCCESS (0.006s)
[365.063961] Starting dynamic subtest: render
[365.066216] Dynamic subtest render: SUCCESS (0.001s)
[365.075214] Starting dynamic subtest: bsd
[365.077104] Dynamic subtest bsd: SUCCESS (0.003s)
[365.085982] Starting dynamic subtest: bsd1
[365.087785] Dynamic subtest bsd1: SUCCESS (0.001s)
[365.096567] Starting dynamic subtest: bsd2
[365.098479] Dynamic subtest bsd2: SUCCESS (0.003s)
[365.107201] Starting dynamic subtest: blt
[365.109046] Dynamic subtest blt: SUCCESS (0.003s)
[365.117625] Starting dynamic subtest: vebox
[365.119522] Dynamic subtest vebox: SUCCESS (0.003s)
[365.121354] Subtest legacy-resubmit: SUCCESS (0.023s)
[365.291308] [067/133] (650s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[365.360836] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[365.363012] Starting dynamic subtest: pipe-A-eDP-1
[365.384646] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[365.396367] Starting dynamic subtest: pipe-B-eDP-1
[365.417049] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[365.426499] Starting dynamic subtest: pipe-C-eDP-1
[365.447177] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[365.456004] Starting dynamic subtest: pipe-D-eDP-1
[365.476702] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[365.480531] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.004s)
[367.532439] [068/133] (647s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[367.620979] Starting subtest: cursorA-vs-flipA-atomic
[378.749527] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.125s)
[379.558371] [069/133] (635s left) kms_setmode (clone-exclusive-crtc)
[379.608455] Starting subtest: clone-exclusive-crtc
[379.614770] Subtest clone-exclusive-crtc: SKIP (0.000s)
[379.691445] [070/133] (635s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[379.765069] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[387.110706] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.346s)
[387.238142] [071/133] (628s left) gem_exec_schedule (fifo)
[387.308488] Starting subtest: fifo
[387.310509] Starting dynamic subtest: rcs0
[387.397735] Dynamic subtest rcs0: SUCCESS (0.099s)
[387.410780] Starting dynamic subtest: vcs0
[387.476451] Dynamic subtest vcs0: SUCCESS (0.078s)
[387.488747] Starting dynamic subtest: vcs1
[387.553040] Dynamic subtest vcs1: SUCCESS (0.076s)
[387.564827] Starting dynamic subtest: vecs0
[387.626604] Dynamic subtest vecs0: SUCCESS (0.073s)
[387.637715] Starting dynamic subtest: ccs0
[387.706306] Dynamic subtest ccs0: SUCCESS (0.078s)
[387.708784] Subtest fifo: SUCCESS (0.410s)
[389.392809] [072/133] (626s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[389.475705] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[389.480148] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[389.579694] [073/133] (626s left) prime_mmap (test_reprime)
[389.638778] Starting subtest: test_reprime
[389.640672] Starting dynamic subtest: test_reprime-smem
[389.668149] Dynamic subtest test_reprime-smem: SUCCESS (0.031s)
[389.670550] Subtest test_reprime: SUCCESS (0.032s)
[389.763250] [074/133] (625s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[389.812451] Starting subtest: invalid-multi-wait-unsubmitted
[389.814652] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[389.902447] [075/133] (625s left) gem_exec_schedule (preempt-queue)
[389.976635] Subtest preempt-queue: SKIP (0.000s)
[390.260801] [076/133] (625s left) kms_addfb_basic (small-bo)
[390.334979] Starting subtest: small-bo
[390.337266] Subtest small-bo: SUCCESS (0.000s)
[390.429584] [077/133] (625s left) i915_hangman (engine-engine-error)
[390.486498] Starting subtest: engine-engine-error
[390.488331] Starting dynamic subtest: rcs0
[392.201397] Dynamic subtest rcs0: SUCCESS (1.714s)
[392.214356] Starting dynamic subtest: bcs0
[394.250367] Dynamic subtest bcs0: SUCCESS (2.046s)
[394.262481] Starting dynamic subtest: vcs0
[396.234092] Dynamic subtest vcs0: SUCCESS (1.980s)
[396.247472] Starting dynamic subtest: vcs1
[398.217636] Dynamic subtest vcs1: SUCCESS (1.981s)
[398.230488] Starting dynamic subtest: vecs0
[400.265405] Dynamic subtest vecs0: SUCCESS (2.045s)
[400.279106] Starting dynamic subtest: ccs0
[402.249470] Dynamic subtest ccs0: SUCCESS (1.981s)
[402.252254] Subtest engine-engine-error: SUCCESS (11.763s)
[402.332217] [078/133] (613s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[402.397278] Starting subtest: legacy-engines-hostile-preempt
[402.399587] Starting dynamic subtest: render
[402.452428] Dynamic subtest render: SUCCESS (0.055s)
[402.464645] Starting dynamic subtest: bsd1
[404.262166] Dynamic subtest bsd1: SUCCESS (1.806s)
[404.275634] Starting dynamic subtest: bsd2
[406.249292] Dynamic subtest bsd2: SUCCESS (1.984s)
[406.261942] Starting dynamic subtest: blt
[406.296912] Dynamic subtest blt: SUCCESS (0.046s)
[406.307267] Starting dynamic subtest: vebox
[408.294740] Dynamic subtest vebox: SUCCESS (1.994s)
[408.297459] Subtest legacy-engines-hostile-preempt: SUCCESS (5.896s)
[408.377723] [079/133] (607s left) gem_create (create-valid-nonaligned)
[408.429170] Starting subtest: create-valid-nonaligned
[408.431374] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[408.623706] [080/133] (607s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[408.694297] Starting subtest: psr-farfromfence-mmap-gtt
[408.698206] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[408.794455] [081/133] (607s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[408.873918] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[408.876477] Starting dynamic subtest: pipe-A
[409.921746] Dynamic subtest pipe-A: SUCCESS (1.050s)
[409.930986] Starting dynamic subtest: pipe-B
[412.073289] Dynamic subtest pipe-B: SUCCESS (2.150s)
[412.084289] Starting dynamic subtest: pipe-C
[413.274490] Dynamic subtest pipe-C: SUCCESS (1.200s)
[413.283915] Starting dynamic subtest: pipe-D
[414.424920] Dynamic subtest pipe-D: SUCCESS (1.148s)
[414.427226] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.553s)
[415.626648] [082/133] (600s left) kms_prop_blob (blob-multiple)
[415.682403] Starting subtest: blob-multiple
[415.684752] Subtest blob-multiple: SUCCESS (0.002s)
[415.803825] [083/133] (600s left) api_intel_bb (bb-with-allocator)
[415.861422] Starting subtest: bb-with-allocator
[415.865216] Subtest bb-with-allocator: SUCCESS (0.006s)
[415.983473] [084/133] (600s left) kms_psr (fbc-pr-sprite-plane-move)
[416.049947] Starting subtest: fbc-pr-sprite-plane-move
[416.054373] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[416.126398] [085/133] (600s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[416.227271] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[416.231702] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[416.326103] [086/133] (599s left) kms_atomic_interruptible (atomic-setmode)
[416.399967] Starting subtest: atomic-setmode
[416.402310] Starting dynamic subtest: pipe-A-eDP-1
[423.287773] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.885s)
[423.289992] Subtest atomic-setmode: SUCCESS (6.886s)
[424.083341] [087/133] (592s left) gem_ctx_shared (disjoint-timelines)
[424.150284] Starting subtest: disjoint-timelines
[424.180473] Subtest disjoint-timelines: SUCCESS (0.030s)
[424.421799] [088/133] (591s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[424.503371] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[424.507958] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[424.609042] [089/133] (591s left) kms_chamelium_hpd (dp-hpd-storm)
[424.973896] Subtest dp-hpd-storm: SKIP (0.000s)
[425.805572] [090/133] (590s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[425.866175] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[425.870559] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[426.060743] [091/133] (590s left) syncobj_wait (wait-all-interrupted)
[426.109115] Starting subtest: wait-all-interrupted
[426.209004] Subtest wait-all-interrupted: SUCCESS (0.101s)
[426.298637] [092/133] (590s left) gem_exec_create (madvise)
[426.374039] Starting subtest: madvise
[426.376384] Starting dynamic subtest: smem
[447.339453] Dynamic subtest smem: SUCCESS (20.961s)
[447.342046] Subtest madvise: SUCCESS (20.962s)
[448.213546] [093/133] (568s left) core_auth (getclient-simple)
[448.255688] Starting subtest: getclient-simple
[448.266303] Subtest getclient-simple: SUCCESS (0.015s)
[448.393148] [094/133] (568s left) kms_addfb_basic (tile-pitch-mismatch)
[448.457244] Starting subtest: tile-pitch-mismatch
[448.461734] Subtest tile-pitch-mismatch: SKIP (0.000s)
[448.582912] [095/133] (568s left) kms_color (invalid-degamma-lut-sizes)
[448.670232] Starting subtest: invalid-degamma-lut-sizes
[448.672504] Starting dynamic subtest: pipe-A
[448.957602] Dynamic subtest pipe-A: SUCCESS (0.287s)
[448.969013] Starting dynamic subtest: pipe-B
[448.971452] Dynamic subtest pipe-B: SUCCESS (0.000s)
[448.980965] Starting dynamic subtest: pipe-C
[448.983405] Dynamic subtest pipe-C: SUCCESS (0.000s)
[448.992806] Starting dynamic subtest: pipe-D
[448.994794] Dynamic subtest pipe-D: SUCCESS (0.000s)
[448.996751] Subtest invalid-degamma-lut-sizes: SUCCESS (0.290s)
[449.764872] [096/133] (566s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[449.846005] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[449.850447] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[449.959570] [097/133] (566s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[450.027940] Starting subtest: bad-pixel-format-y-tiled-ccs
[450.030150] Starting dynamic subtest: pipe-A-eDP-1
[450.052453] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[450.062641] Starting dynamic subtest: pipe-B-eDP-1
[450.084343] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[450.093895] Starting dynamic subtest: pipe-C-eDP-1
[450.116105] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[450.124955] Starting dynamic subtest: pipe-D-eDP-1
[450.147232] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[450.151266] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[452.237965] [098/133] (564s left) gem_mmap_gtt (hang)
[452.296866] Subtest hang: SKIP (0.000s)
[452.354026] [099/133] (564s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[452.433859] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[452.438283] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[452.508474] [100/133] (564s left) i915_pm_rpm (debugfs-read)
[454.408854] Starting subtest: debugfs-read
[455.560777] Subtest debugfs-read: SUCCESS (1.150s)
[455.926012] [101/133] (560s left) gem_pxp (reject-modify-context-protection-off-2)
[456.058139] Starting subtest: reject-modify-context-protection-off-2
[456.060960] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[456.173464] [102/133] (560s left) prime_busy (after-wait)
[456.241612] Starting subtest: after-wait
[456.246665] Starting dynamic subtest: rcs0
[456.272374] Dynamic subtest rcs0: SUCCESS (0.028s)
[456.282635] Starting dynamic subtest: bcs0
[456.313731] Dynamic subtest bcs0: SUCCESS (0.041s)
[456.323687] Starting dynamic subtest: vcs0
[456.354125] Dynamic subtest vcs0: SUCCESS (0.039s)
[456.364172] Starting dynamic subtest: vcs1
[456.394569] Dynamic subtest vcs1: SUCCESS (0.040s)
[456.403705] Starting dynamic subtest: vecs0
[456.434831] Dynamic subtest vecs0: SUCCESS (0.039s)
[456.444419] Starting dynamic subtest: ccs0
[456.474110] Dynamic subtest ccs0: SUCCESS (0.038s)
[456.583123] Subtest after-wait: SUCCESS (0.340s)
[456.675566] [103/133] (560s left) sysfs_heartbeat_interval (invalid)
[456.726998] Starting subtest: invalid
[456.729372] Starting dynamic subtest: vecs0
[456.731636] Dynamic subtest vecs0: SUCCESS (0.000s)
[456.741379] Starting dynamic subtest: ccs0
[456.743594] Dynamic subtest ccs0: SUCCESS (0.000s)
[456.752646] Starting dynamic subtest: vcs0
[456.754551] Dynamic subtest vcs0: SUCCESS (0.000s)
[456.763671] Starting dynamic subtest: bcs0
[456.765913] Dynamic subtest bcs0: SUCCESS (0.000s)
[456.774955] Starting dynamic subtest: rcs0
[456.777228] Dynamic subtest rcs0: SUCCESS (0.000s)
[456.786476] Starting dynamic subtest: vcs1
[456.788689] Dynamic subtest vcs1: SUCCESS (0.000s)
[456.790880] Subtest invalid: SUCCESS (0.005s)
[456.828640] [104/133] (560s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[457.098937] Starting subtest: cursor-rapid-movement-256x256
[457.103934] Starting dynamic subtest: pipe-A-eDP-1
[457.657479] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.553s)
[457.690471] Starting dynamic subtest: pipe-D-eDP-1
[458.845563] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.166s)
[458.847986] Subtest cursor-rapid-movement-256x256: SUCCESS (1.746s)
[459.693909] [105/133] (557s left) kms_content_protection (atomic-hdcp14)
[459.819984] Starting subtest: atomic-hdcp14
[459.824358] Subtest atomic-hdcp14: SKIP (0.000s)
[459.958199] [106/133] (557s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[460.045342] Starting subtest: yf-tiled-8bpp-rotate-0
[460.049362] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[460.141416] [107/133] (556s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[460.233687] Starting subtest: y-tiled-64bpp-rotate-270
[460.238250] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[460.323758] [108/133] (556s left) gem_tiled_partial_pwrite_pread (reads)
[460.388340] Subtest reads: SKIP (0.000s)
[460.453696] [109/133] (556s left) prime_mmap (test_forked_cpu_write)
[460.503817] Starting subtest: test_forked_cpu_write
[460.505889] Starting dynamic subtest: test_forked_cpu_write-smem
[460.512822] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.011s)
[460.515073] Subtest test_forked_cpu_write: SUCCESS (0.012s)
[460.612321] [110/133] (556s left) gem_wait (busy)
[460.679994] Starting subtest: busy
[460.681672] Starting dynamic subtest: all-engines
[462.252009] Dynamic subtest all-engines: SUCCESS (1.572s)
[462.266002] Starting dynamic subtest: rcs0
[462.794402] Dynamic subtest rcs0: SUCCESS (0.540s)
[462.807104] Starting dynamic subtest: bcs0
[463.334116] Dynamic subtest bcs0: SUCCESS (0.539s)
[463.346488] Starting dynamic subtest: vcs0
[465.259811] Dynamic subtest vcs0: SUCCESS (1.924s)
[465.273120] Starting dynamic subtest: vcs1
[467.243980] Dynamic subtest vcs1: SUCCESS (1.981s)
[467.256782] Starting dynamic subtest: vecs0
[469.291773] Dynamic subtest vecs0: SUCCESS (2.046s)
[469.304711] Starting dynamic subtest: ccs0
[469.834422] Dynamic subtest ccs0: SUCCESS (0.542s)
[469.837016] Subtest busy: SUCCESS (9.157s)
[470.034208] [111/133] (547s left) perf_pmu (rc6-suspend)
[470.070370] Starting subtest: rc6-suspend
[492.612723] Subtest rc6-suspend: SUCCESS (7.572s)
[493.599376] [112/133] (523s left) kms_pm_backlight (fade-with-dpms)
[493.673403] Starting subtest: fade-with-dpms
[495.784699] Starting dynamic subtest: eDP-1
[498.265320] Dynamic subtest eDP-1: SUCCESS (2.478s)
[498.267661] Subtest fade-with-dpms: SUCCESS (4.587s)
[499.064280] [113/133] (518s left) kms_flip (flip-vs-modeset-vs-hang)
[499.146783] Starting subtest: flip-vs-modeset-vs-hang
[499.149273] Starting dynamic subtest: A-eDP1
[538.748041] Dynamic subtest A-eDP1: SUCCESS (39.585s)
[538.761260] Starting dynamic subtest: D-eDP1
[587.818036] Dynamic subtest D-eDP1: SUCCESS (49.053s)
[587.820991] Subtest flip-vs-modeset-vs-hang: SUCCESS (88.645s)
[588.196477] [114/133] (429s left) gem_mmap_offset (open-flood)
[588.245042] Starting subtest: open-flood
[609.724081] Subtest open-flood: SUCCESS (21.490s)
[609.822479] [115/133] (407s left) kms_big_fb (linear-16bpp-rotate-180)
[609.912166] Starting subtest: linear-16bpp-rotate-180
[611.367819] Subtest linear-16bpp-rotate-180: SUCCESS (1.457s)
[611.493412] [116/133] (406s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[611.571352] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[611.574784] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[611.646177] [117/133] (405s left) kms_joiner (invalid-modeset-big-joiner)
[611.718732] Starting subtest: invalid-modeset-big-joiner
[611.723167] Subtest invalid-modeset-big-joiner: SKIP (0.000s)
[611.790548] [118/133] (405s left) gem_exec_params (invalid-ring2)
[611.847873] Starting subtest: invalid-ring2
[611.850391] Subtest invalid-ring2: SUCCESS (0.000s)
[611.954807] [119/133] (405s left) gem_mmap (short-mmap)
[612.015525] Subtest short-mmap: SKIP (0.000s)
[612.077261] [120/133] (405s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[612.146711] Starting subtest: fbc-psr-cursor-mmap-cpu
[612.149129] Starting dynamic subtest: eDP-1
[612.152864] Dynamic subtest eDP-1: SKIP (0.000s)
[612.157231] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[612.220318] [121/133] (405s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[612.273689] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[612.378905] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[612.468798] [122/133] (405s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[612.563413] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[613.552419] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.990s)
[614.402173] [123/133] (403s left) kms_psr (psr-primary-render)
[614.477540] Starting subtest: psr-primary-render
[614.480088] Starting dynamic subtest: eDP-1
[616.206197] Dynamic subtest eDP-1: SUCCESS (1.728s)
[616.208619] Subtest psr-primary-render: SUCCESS (1.728s)
[617.066300] [124/133] (400s left) gem_workarounds (suspend-resume)
[617.129685] Starting subtest: suspend-resume
[633.434917] Subtest suspend-resume: SUCCESS (1.213s)
[633.571728] [125/133] (384s left) kms_psr (fbc-psr-sprite-render)
[633.650153] Starting subtest: fbc-psr-sprite-render
[633.652446] Starting dynamic subtest: eDP-1
[633.656882] Dynamic subtest eDP-1: SKIP (0.000s)
[633.661327] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[633.728188] [126/133] (384s left) kms_sequence (get-busy)
[633.805743] Starting subtest: get-busy
[633.807667] Starting dynamic subtest: pipe-A-eDP-1
[635.746248] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.938s)
[635.756155] Starting dynamic subtest: pipe-B-eDP-1
[638.317412] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.565s)
[638.327894] Starting dynamic subtest: pipe-C-eDP-1
[640.935123] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.612s)
[640.946964] Starting dynamic subtest: pipe-D-eDP-1
[643.560853] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.624s)
[643.562941] Subtest get-busy: SUCCESS (9.743s)
[644.360706] [127/133] (373s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[644.448166] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[651.775464] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.325s)
[651.915446] [128/133] (366s left) perf (non-zero-reason)
[652.040338] Starting subtest: non-zero-reason
[652.042473] Starting dynamic subtest: 0-rcs0
[657.374385] Dynamic subtest 0-rcs0: SUCCESS (5.331s)
[657.388871] Starting dynamic subtest: 1-vecs0
[661.259447] Dynamic subtest 1-vecs0: SUCCESS (3.881s)
[661.262349] Subtest non-zero-reason: SUCCESS (9.215s)
[661.313506] [129/133] (356s left) kms_fb_coherency (memset-crc)
[661.387706] Starting subtest: memset-crc
[661.389999] Starting dynamic subtest: mmap-offset-wc
[661.776163] Dynamic subtest mmap-offset-wc: SUCCESS (0.388s)
[662.065743] Starting dynamic subtest: mmap-offset-uc
[668.116208] Dynamic subtest mmap-offset-uc: SUCCESS (6.059s)
[668.447400] Subtest memset-crc: SUCCESS (7.058s)
[669.243334] [130/133] (348s left) kms_cursor_crc (cursor-sliding-256x256)
[669.475054] Starting subtest: cursor-sliding-256x256
[669.477346] Starting dynamic subtest: pipe-A-eDP-1
[672.768770] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.292s)
[672.801145] Starting dynamic subtest: pipe-D-eDP-1
[676.641517] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.846s)
[676.643464] Subtest cursor-sliding-256x256: SUCCESS (7.166s)
[677.527588] [131/133] (340s left) kms_vblank (query-forked-hang)
[677.614494] Starting subtest: query-forked-hang
[677.616733] Starting dynamic subtest: pipe-A-eDP-1
[704.158552] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.536s)
[704.173713] Starting dynamic subtest: pipe-D-eDP-1
[719.169971] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.003s)
[719.172151] Subtest query-forked-hang: SUCCESS (41.544s)
[720.004957] [132/133] (298s left) kms_cursor_crc (cursor-offscreen-512x170)
[720.262644] Starting subtest: cursor-offscreen-512x170
[720.267257] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[720.404684] [133/133] (297s left) kms_plane_multiple (2x-tiling-4)
[720.483275] Starting subtest: 2x-tiling-4
[720.487789] Subtest 2x-tiling-4: SKIP (0.000s)
[720.557666] Closing watchdogs
results: parsing output: 0/ for test: gen9_exec_parse
results: parsing output: 1/ for test: gem_exec_balancer
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: gem_pwrite
results: parsing output: 4/ for test: kms_ccs
results: parsing output: 5/ for test: kms_pipe_crc_basic
results: parsing output: 6/ for test: gem_mmap_gtt
results: parsing output: 7/ for test: gem_exec_whisper
results: parsing output: 8/ for test: kms_flip
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: gem_exec_whisper
results: parsing output: 12/ for test: gem_mmap_wc
results: parsing output: 13/ for test: gem_exec_fence
results: parsing output: 14/ for test: kms_atomic
results: parsing output: 15/ for test: kms_chamelium_color
results: parsing output: 16/ for test: kms_addfb_basic
results: parsing output: 17/ for test: kms_plane
results: parsing output: 18/ for test: gem_eio
results: parsing output: 19/ for test: gem_exec_params
results: parsing output: 20/ for test: i915_query
results: parsing output: 21/ for test: kms_chamelium_edid
results: parsing output: 22/ for test: kms_cursor_legacy
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: gem_create
results: parsing output: 25/ for test: gem_eio
results: parsing output: 26/ for test: perf_pmu
results: parsing output: 27/ for test: gem_exec_fence
results: parsing output: 28/ for test: dmabuf_sync_file
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: kms_psr
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: gem_exec_reloc
results: parsing output: 33/ for test: kms_ccs
results: parsing output: 34/ for test: kms_plane_scaling
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_exec_schedule
results: parsing output: 37/ for test: kms_flip
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: i915_drm_fdinfo
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_lease
results: parsing output: 42/ for test: sw_sync
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: i915_query
results: parsing output: 45/ for test: kms_cursor_legacy
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: syncobj_timeline
results: parsing output: 48/ for test: kms_setmode
results: parsing output: 49/ for test: kms_flip_scaled_crc
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: syncobj_eventfd
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_addfb_basic
results: parsing output: 55/ for test: gem_ctx_param
results: parsing output: 56/ for test: kms_prime
results: parsing output: 57/ for test: kms_vblank
results: parsing output: 58/ for test: gem_exec_balancer
results: parsing output: 59/ for test: prime_udl
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: gem_busy
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: gem_userptr_blits
results: parsing output: 64/ for test: kms_flip
results: parsing output: 65/ for test: gem_spin_batch
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: kms_cursor_legacy
results: parsing output: 68/ for test: kms_setmode
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: gem_exec_schedule
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: prime_mmap
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: gem_exec_schedule
results: parsing output: 75/ for test: kms_addfb_basic
results: parsing output: 76/ for test: i915_hangman
results: parsing output: 77/ for test: gem_ctx_persistence
results: parsing output: 78/ for test: gem_create
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_plane_scaling
results: parsing output: 81/ for test: kms_prop_blob
results: parsing output: 82/ for test: api_intel_bb
results: parsing output: 83/ for test: kms_psr
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_atomic_interruptible
results: parsing output: 86/ for test: gem_ctx_shared
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_chamelium_hpd
results: parsing output: 89/ for test: gem_render_copy
results: parsing output: 90/ for test: syncobj_wait
results: parsing output: 91/ for test: gem_exec_create
results: parsing output: 92/ for test: core_auth
results: parsing output: 93/ for test: kms_addfb_basic
results: parsing output: 94/ for test: kms_color
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_ccs
results: parsing output: 97/ for test: gem_mmap_gtt
results: parsing output: 98/ for test: kms_flip_scaled_crc
results: parsing output: 99/ for test: i915_pm_rpm
results: parsing output: 100/ for test: gem_pxp
results: parsing output: 101/ for test: prime_busy
results: parsing output: 102/ for test: sysfs_heartbeat_interval
results: parsing output: 103/ for test: kms_cursor_crc
results: parsing output: 104/ for test: kms_content_protection
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 108/ for test: prime_mmap
results: parsing output: 109/ for test: gem_wait
results: parsing output: 110/ for test: perf_pmu
results: parsing output: 111/ for test: kms_pm_backlight
results: parsing output: 112/ for test: kms_flip
results: parsing output: 113/ for test: gem_mmap_offset
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: kms_flip_scaled_crc
results: parsing output: 116/ for test: kms_joiner
results: parsing output: 117/ for test: gem_exec_params
results: parsing output: 118/ for test: gem_mmap
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: gem_workarounds
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: kms_sequence
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: perf
results: parsing output: 128/ for test: kms_fb_coherency
results: parsing output: 129/ for test: kms_cursor_crc
results: parsing output: 130/ for test: kms_vblank
results: parsing output: 131/ for test: kms_cursor_crc
results: parsing output: 132/ for test: kms_plane_multiple
Done.