[59.831553] Initializing watchdogs
[59.831621]   /dev/watchdog0
[59.848643] [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]
[59.853284] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.855778] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.858925] [001/156] (960s left) kms_psr (psr2-cursor-plane-move)
[59.987695] Starting subtest: psr2-cursor-plane-move
[59.990206] Starting dynamic subtest: eDP-1
[61.681528] Dynamic subtest eDP-1: SUCCESS (1.701s)
[61.683798] Subtest psr2-cursor-plane-move: SUCCESS (1.702s)
[62.574973] [002/156] (957s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[62.673690] Starting subtest: primary-yf-tiled-reflect-x-270
[62.677676] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[62.744959] [003/156] (957s left) sw_sync (sync_random_merge)
[62.782903] Starting subtest: sync_random_merge
[62.827073] Subtest sync_random_merge: SUCCESS (0.044s)
[62.887015] [004/156] (957s left) kms_joiner (basic-force-ultra-joiner)
[62.989628] Starting subtest: basic-force-ultra-joiner
[62.993756] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[63.053744] [005/156] (957s left) gem_exec_fence (syncobj-signal)
[63.484800] Starting subtest: syncobj-signal
[63.490188] Subtest syncobj-signal: SUCCESS (0.006s)
[65.334179] [FACT gem_exec_fence (syncobj-signal)] new: kernel.kmod_is_loaded.vgem: true
[65.337210] [006/156] (955s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-pwrite)
[65.442731] Starting subtest: psrhdr-rgb101010-draw-pwrite
[65.446681] Subtest psrhdr-rgb101010-draw-pwrite: SKIP (0.000s)
[65.552185] [007/156] (955s left) kms_chamelium_edid (dp-edid-resolution-list)
[65.924241] Subtest dp-edid-resolution-list: SKIP (0.000s)
[66.764822] [008/156] (953s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-cpu)
[66.868768] Starting subtest: fbchdr-rgb565-draw-mmap-cpu
[66.873283] Subtest fbchdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[66.977876] [009/156] (953s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[67.066954] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[68.078439] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.014s)
[68.220200] [010/156] (952s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-plflip-blt)
[68.322839] Starting subtest: psrhdr-1p-primscrn-shrfb-plflip-blt
[68.326427] Subtest psrhdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[68.431898] [011/156] (952s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[68.521588] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[68.524899] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[68.613296] [012/156] (952s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[68.699889] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[75.271383] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.581s)
[75.417943] [013/156] (945s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-move)
[75.511697] Starting subtest: psrhdr-2p-primscrn-spr-indfb-move
[75.516075] Subtest psrhdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[75.602854] [014/156] (945s left) i915_pm_rpm (gem-idle)
[77.523869] Starting subtest: gem-idle
[83.739834] Subtest gem-idle: SUCCESS (6.212s)
[84.075510] [015/156] (936s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-msflip-blt)
[85.270878] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-msflip-blt
[85.275079] Subtest fbcpsrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[85.366077] [016/156] (935s left) api_intel_allocator (fork-simple-stress)
[85.420918] Starting subtest: fork-simple-stress
[90.819182] Subtest fork-simple-stress: SUCCESS (5.397s)
[90.941496] [017/156] (930s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[91.052813] Starting subtest: fbc-rgb565-draw-mmap-cpu
[95.183454] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.137s)
[96.385918] [018/156] (924s left) gem_ctx_persistence (engines-hostile-preempt)
[96.440988] Starting subtest: engines-hostile-preempt
[96.443468] Starting dynamic subtest: rcs0
[96.516590] Dynamic subtest rcs0: SUCCESS (0.075s)
[96.526836] Starting dynamic subtest: bcs0
[96.569988] Dynamic subtest bcs0: SUCCESS (0.052s)
[96.579357] Starting dynamic subtest: vcs0
[98.300771] Dynamic subtest vcs0: SUCCESS (1.728s)
[98.311648] Starting dynamic subtest: vcs1
[100.291603] Dynamic subtest vcs1: SUCCESS (1.990s)
[100.301175] Starting dynamic subtest: vecs0
[102.269975] Dynamic subtest vecs0: SUCCESS (1.977s)
[102.280101] Starting dynamic subtest: ccs0
[102.339052] Dynamic subtest ccs0: SUCCESS (0.069s)
[102.341749] Subtest engines-hostile-preempt: SUCCESS (5.898s)
[102.432205] [019/156] (918s left) kms_big_fb (yf-tiled-addfb)
[102.530116] Starting subtest: yf-tiled-addfb
[102.534531] Subtest yf-tiled-addfb: SKIP (0.000s)
[102.624357] [020/156] (918s left) kms_scaling_modes (scaling-mode-none)
[102.719350] Starting subtest: scaling-mode-none
[102.721740] Starting dynamic subtest: pipe-A-eDP-1
[102.817233] Dynamic subtest pipe-A-eDP-1: SKIP (0.097s)
[102.829474] Starting dynamic subtest: pipe-B-eDP-1
[102.856017] Dynamic subtest pipe-B-eDP-1: SKIP (0.037s)
[102.866202] Starting dynamic subtest: pipe-C-eDP-1
[102.887727] Dynamic subtest pipe-C-eDP-1: SKIP (0.031s)
[102.896231] Starting dynamic subtest: pipe-D-eDP-1
[102.922745] Dynamic subtest pipe-D-eDP-1: SKIP (0.034s)
[102.926760] Subtest scaling-mode-none: SKIP (0.204s)
[103.040613] [021/156] (918s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-indfb-msflip-blt)
[103.141443] Starting subtest: fbcpsrhdr-2p-scndscrn-indfb-msflip-blt
[103.145805] Subtest fbcpsrhdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[103.244507] [022/156] (917s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[103.337106] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[103.340882] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[103.439642] [023/156] (917s left) gem_lmem_swapping (verify)
[103.479250] Subtest verify: SKIP (0.000s)
[103.521302] [024/156] (917s left) gem_flink_basic (basic)
[103.571681] Starting subtest: basic
[103.573576] Subtest basic: SUCCESS (0.000s)
[103.687300] [025/156] (917s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[103.782201] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[110.513456] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.735s)
[110.661775] [026/156] (910s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[110.759401] Starting subtest: 2x-plain-flip-ts-check-interruptible
[110.770900] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.010s)
[110.850687] [027/156] (910s left) gem_exec_reloc (basic-wc-read-active)
[110.916746] Subtest basic-wc-read-active: SKIP (0.000s)
[110.992348] [028/156] (910s left) gem_userptr_blits (map-fixed-invalidate)
[111.076208] Starting subtest: map-fixed-invalidate
[111.080676] Subtest map-fixed-invalidate: SKIP (0.000s)
[111.207494] [029/156] (910s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[111.313060] Starting subtest: psr-rgb565-draw-blt
[118.273114] Subtest psr-rgb565-draw-blt: SUCCESS (6.965s)
[118.400385] [030/156] (903s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[118.444931] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[118.552923] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.106s)
[118.625660] [031/156] (902s left) kms_chamelium_color (ctm-green-to-red)
[118.719581] Subtest ctm-green-to-red: SKIP (0.000s)
[118.764741] [032/156] (902s left) api_intel_bb (misplaced-blitter)
[118.812065] Starting subtest: misplaced-blitter
[118.817246] Subtest misplaced-blitter: SUCCESS (0.008s)
[118.929793] [033/156] (902s left) kms_pm_rpm (dpms-non-lpsp)
[120.444329] Starting subtest: dpms-non-lpsp
[120.449191] Subtest dpms-non-lpsp: SKIP (0.001s)
[121.179944] [034/156] (900s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[121.286020] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[121.290279] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[121.395681] [035/156] (900s left) kms_vblank (wait-forked-hang)
[121.495355] Starting subtest: wait-forked-hang
[121.497531] Starting dynamic subtest: pipe-A-eDP-1
[149.067775] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.572s)
[149.082212] Starting dynamic subtest: pipe-D-eDP-1
[164.045980] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.974s)
[164.048174] Subtest wait-forked-hang: SUCCESS (42.550s)
[164.853398] [036/156] (856s left) gem_exec_reloc (basic-wc-active)
[164.921184] Subtest basic-wc-active: SKIP (0.000s)
[164.986781] [037/156] (856s left) kms_psr (fbc-pr-primary-mmap-cpu)
[165.075101] Starting subtest: fbc-pr-primary-mmap-cpu
[165.079478] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[165.164266] [038/156] (856s left) perf (oa-formats)
[165.294387] Starting subtest: oa-formats
[165.296235] Starting dynamic subtest: 0-rcs0
[165.309682] Dynamic subtest 0-rcs0: SUCCESS (0.016s)
[165.317956] Starting dynamic subtest: 1-vecs0
[165.331292] Dynamic subtest 1-vecs0: SUCCESS (0.021s)
[165.333610] Subtest oa-formats: SUCCESS (0.038s)
[165.381507] [039/156] (856s left) sysfs_timeslice_duration (idempotent)
[165.437822] Starting subtest: idempotent
[165.440278] Starting dynamic subtest: vecs0
[165.442779] Dynamic subtest vecs0: SUCCESS (0.000s)
[165.452190] Starting dynamic subtest: ccs0
[165.454194] Dynamic subtest ccs0: SUCCESS (0.000s)
[165.461794] Starting dynamic subtest: vcs0
[165.463696] Dynamic subtest vcs0: SUCCESS (0.000s)
[165.471145] Starting dynamic subtest: bcs0
[165.473053] Dynamic subtest bcs0: SUCCESS (0.000s)
[165.480533] Starting dynamic subtest: rcs0
[165.482444] Dynamic subtest rcs0: SUCCESS (0.000s)
[165.491681] Starting dynamic subtest: vcs1
[165.493985] Dynamic subtest vcs1: SUCCESS (0.001s)
[165.496145] Subtest idempotent: SUCCESS (0.007s)
[165.562398] [040/156] (856s left) perf_pmu (all-busy-idle-check-all)
[165.601503] Starting subtest: all-busy-idle-check-all
[167.300822] Subtest all-busy-idle-check-all: SUCCESS (1.697s)
[167.409977] [041/156] (854s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-shrfb-msflip-blt)
[167.502910] Starting subtest: fbchdr-2p-scndscrn-shrfb-msflip-blt
[167.507444] Subtest fbchdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[167.605612] [042/156] (854s left) kms_fbcon_fbt (fbc)
[167.658496] Starting subtest: fbc
[174.088085] Subtest fbc: SUCCESS (6.429s)
[175.269560] [043/156] (846s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[175.368743] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[175.371075] Starting dynamic subtest: pipe-A
[176.511568] Dynamic subtest pipe-A: SUCCESS (1.145s)
[176.521673] Starting dynamic subtest: pipe-B
[178.782180] Dynamic subtest pipe-B: SUCCESS (2.269s)
[178.791171] Starting dynamic subtest: pipe-C
[179.980972] Dynamic subtest pipe-C: SUCCESS (1.197s)
[179.991121] Starting dynamic subtest: pipe-D
[181.206656] Dynamic subtest pipe-D: SUCCESS (1.225s)
[181.208984] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.840s)
[182.389704] [044/156] (839s left) gem_exec_whisper (basic-queues-all)
[182.459679] Starting subtest: basic-queues-all
[194.279205] Subtest basic-queues-all: SUCCESS (11.817s)
[194.473453] [045/156] (827s left) gem_pread (bench)
[194.527272] Subtest bench: SKIP (0.000s)
[194.582826] [046/156] (827s left) gem_exec_schedule (out-order)
[194.647674] Starting subtest: out-order
[194.649614] Starting dynamic subtest: rcs0
[194.802015] Dynamic subtest rcs0: SUCCESS (0.159s)
[194.811365] Starting dynamic subtest: vcs0
[194.908904] Dynamic subtest vcs0: SUCCESS (0.105s)
[194.920894] Starting dynamic subtest: vcs1
[195.015076] Dynamic subtest vcs1: SUCCESS (0.106s)
[195.027304] Starting dynamic subtest: vecs0
[195.121556] Dynamic subtest vecs0: SUCCESS (0.105s)
[195.134266] Starting dynamic subtest: ccs0
[195.245819] Dynamic subtest ccs0: SUCCESS (0.124s)
[195.248656] Subtest out-order: SUCCESS (0.603s)
[196.394800] [047/156] (825s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[196.490487] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[196.494577] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[196.600265] [048/156] (825s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[196.710032] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[196.714198] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[196.818295] [049/156] (825s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[196.916787] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[203.486211] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.573s)
[203.610655] [050/156] (818s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[203.721144] Starting subtest: cursor-vs-flip-varying-size
[234.997537] Subtest cursor-vs-flip-varying-size: SUCCESS (31.275s)
[235.842566] [051/156] (786s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[235.948396] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[235.952960] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[236.017673] [052/156] (786s left) i915_drm_fdinfo (memory-info-purgeable)
[236.059073] Starting subtest: memory-info-purgeable
[236.060928] Starting dynamic subtest: smem0
[238.712419] Dynamic subtest smem0: SUCCESS (2.655s)
[238.714546] Subtest memory-info-purgeable: SUCCESS (2.657s)
[238.802525] [053/156] (783s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[238.937612] Starting subtest: cursorB-vs-flipA-varying-size
[238.943385] Subtest cursorB-vs-flipA-varying-size: SKIP (0.002s)
[239.078166] [054/156] (783s left) gem_ctx_param (get-priority-new-ctx)
[239.134590] Starting subtest: get-priority-new-ctx
[239.136858] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[239.232863] [055/156] (783s left) kms_plane_scaling (planes-upscale-factor-0-25)
[239.316444] Starting subtest: planes-upscale-factor-0-25
[239.318480] Starting dynamic subtest: pipe-A
[239.377344] Dynamic subtest pipe-A: SUCCESS (0.062s)
[239.386089] Starting dynamic subtest: pipe-B
[240.570840] Dynamic subtest pipe-B: SUCCESS (1.192s)
[240.581455] Starting dynamic subtest: pipe-C
[241.678798] Dynamic subtest pipe-C: SUCCESS (1.107s)
[241.689834] Starting dynamic subtest: pipe-D
[242.830652] Dynamic subtest pipe-D: SUCCESS (1.151s)
[242.833082] Subtest planes-upscale-factor-0-25: SUCCESS (3.516s)
[243.976469] [056/156] (778s left) gen9_exec_parse (basic-rejected-ctx-param)
[244.032471] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[244.099163] [057/156] (778s left) core_getversion (basic)
[244.137603] Starting subtest: basic
[244.142075] Subtest basic: SUCCESS (0.000s)
[244.196201] [058/156] (778s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[244.295097] Starting subtest: fbcpsr-shrfb-scaledprimary
[250.080789] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.791s)
[250.205318] [059/156] (772s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[250.308091] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[257.682517] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.380s)
[257.811554] [060/156] (764s left) kms_lease (lease-invalid-plane)
[257.910442] Starting subtest: lease-invalid-plane
[257.912636] Starting dynamic subtest: pipe-A-eDP-1
[257.914943] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[257.923791] Starting dynamic subtest: pipe-B-eDP-1
[257.926078] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[257.934766] Starting dynamic subtest: pipe-C-eDP-1
[257.937097] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[257.945721] Starting dynamic subtest: pipe-D-eDP-1
[257.947994] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[257.950228] Subtest lease-invalid-plane: SUCCESS (0.004s)
[257.991931] [061/156] (764s left) gem_exec_params (secure-non-root)
[258.051835] Starting subtest: secure-non-root
[258.056559] Subtest secure-non-root: SKIP (0.000s)
[258.161428] [062/156] (764s left) kms_cursor_crc (cursor-random-64x21)
[258.363529] Starting subtest: cursor-random-64x21
[258.412719] Subtest cursor-random-64x21: SKIP (0.046s)
[258.641378] [063/156] (764s left) kms_plane_alpha_blend (alpha-basic)
[258.724802] Starting subtest: alpha-basic
[259.011784] Starting dynamic subtest: pipe-A-eDP-1
[260.938332] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.925s)
[260.970282] Starting dynamic subtest: pipe-D-eDP-1
[263.633214] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.675s)
[263.635829] Subtest alpha-basic: SUCCESS (4.910s)
[264.506854] [064/156] (758s left) sw_sync (sync_multi_timeline_wait)
[264.542141] Starting subtest: sync_multi_timeline_wait
[264.544562] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[264.594909] [065/156] (758s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[264.697662] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc
[264.701946] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[264.804471] [066/156] (758s left) prime_mmap_coherency (read)
[264.856769] Starting subtest: read
[270.287355] Subtest read: SUCCESS (5.379s)
[270.366059] [067/156] (752s left) syncobj_timeline (multi-wait-all-available-signaled)
[270.416724] Starting subtest: multi-wait-all-available-signaled
[270.419124] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s)
[270.535486] [068/156] (752s left) gem_ctx_param (non-root-set)
[270.587582] Starting subtest: non-root-set
[270.613164] Subtest non-root-set: SUCCESS (0.025s)
[270.711883] [069/156] (752s left) prime_busy (after-wait)
[270.773967] Starting subtest: after-wait
[270.778832] Starting dynamic subtest: rcs0
[270.813567] Dynamic subtest rcs0: SUCCESS (0.035s)
[270.822505] Starting dynamic subtest: bcs0
[270.870860] Dynamic subtest bcs0: SUCCESS (0.056s)
[270.881895] Starting dynamic subtest: vcs0
[270.910239] Dynamic subtest vcs0: SUCCESS (0.039s)
[270.919359] Starting dynamic subtest: vcs1
[270.952593] Dynamic subtest vcs1: SUCCESS (0.042s)
[270.961619] Starting dynamic subtest: vecs0
[270.995488] Dynamic subtest vecs0: SUCCESS (0.042s)
[271.005094] Starting dynamic subtest: ccs0
[271.039821] Dynamic subtest ccs0: SUCCESS (0.044s)
[271.147698] Subtest after-wait: SUCCESS (0.372s)
[271.223468] [070/156] (751s left) gem_eio (in-flight-1us)
[271.455206] Starting subtest: in-flight-1us
[280.242108] Subtest in-flight-1us: SUCCESS (8.787s)
[280.371439] [071/156] (742s left) gem_ppgtt (blt-vs-render-ctx0)
[280.422131] Starting subtest: blt-vs-render-ctx0
[313.288263] Subtest blt-vs-render-ctx0: SUCCESS (32.863s)
[313.426135] [072/156] (709s left) kms_psr (psr2-suspend)
[313.572041] Starting subtest: psr2-suspend
[313.574377] Starting dynamic subtest: eDP-1
[331.566235] Dynamic subtest eDP-1: SUCCESS (3.028s)
[331.568629] Subtest psr2-suspend: SUCCESS (3.029s)
[332.461285] [073/156] (690s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[332.572268] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[332.576448] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[332.676922] [074/156] (690s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[332.783524] Starting subtest: fbc-psr2-cursor-plane-update-sf
[332.785752] Starting dynamic subtest: pipe-A-eDP-1
[332.856018] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[332.868774] Starting dynamic subtest: pipe-B-eDP-1
[333.999592] Dynamic subtest pipe-B-eDP-1: SKIP (1.149s)
[334.004330] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.228s)
[335.195176] [075/156] (688s left) syncobj_timeline (multi-wait-submitted-signaled)
[335.258228] Starting subtest: multi-wait-submitted-signaled
[335.260441] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[335.358679] [076/156] (688s left) tools_test (sysfs_l3_parity)
[335.414549] Starting subtest: sysfs_l3_parity
[335.478569] Subtest sysfs_l3_parity: SKIP (0.078s)
[335.547365] [077/156] (687s left) gem_exec_suspend (basic-s4-devices)
[335.613475] Starting subtest: basic-S4-devices
[335.615770] Starting dynamic subtest: smem
[347.259773] Dynamic subtest smem: SUCCESS (11.678s)
[347.264648] Subtest basic-S4-devices: SUCCESS (11.680s)
[353.471822] [078/156] (676s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[353.602701] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[353.606799] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[353.697279] [079/156] (675s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[353.790786] Starting subtest: fbcpsr-stridechange
[359.964373] Subtest fbcpsr-stridechange: SUCCESS (6.181s)
[360.118842] [080/156] (669s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[360.233542] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[366.608157] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.383s)
[367.768856] [081/156] (661s left) gem_exec_schedule (u-semaphore-codependency)
[367.853813] Starting subtest: u-semaphore-codependency
[367.864184] Subtest u-semaphore-codependency: SUCCESS (0.013s)
[368.237661] [082/156] (661s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[368.337198] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[368.339451] Starting dynamic subtest: pipe-A-eDP-1
[368.783644] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.417s)
[368.797568] Starting dynamic subtest: pipe-B-eDP-1
[370.247373] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.464s)
[370.261419] Starting dynamic subtest: pipe-C-eDP-1
[371.663647] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.414s)
[371.676359] Starting dynamic subtest: pipe-D-eDP-1
[372.992609] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[372.994963] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.631s)
[374.106060] [083/156] (655s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-mc-ccs)
[374.203340] Starting subtest: crc-primary-suspend-4-tiled-mtl-mc-ccs
[374.205480] Starting dynamic subtest: pipe-A-eDP-1
[406.393713] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.926s)
[406.408115] Starting dynamic subtest: pipe-B-eDP-1
[439.339757] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.710s)
[439.351841] Starting dynamic subtest: pipe-C-eDP-1
[472.302320] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.734s)
[472.320494] Starting dynamic subtest: pipe-D-eDP-1
[505.284957] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.637s)
[505.287748] Subtest crc-primary-suspend-4-tiled-mtl-mc-ccs: SUCCESS (14.013s)
[506.532875] [084/156] (523s left) kms_cursor_crc (cursor-offscreen-512x512)
[506.825042] Starting subtest: cursor-offscreen-512x512
[506.830072] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[506.982137] [085/156] (522s left) kms_cursor_legacy (forked-bo)
[507.099454] Starting subtest: forked-bo
[507.101932] Starting dynamic subtest: pipe-A
[512.587622] Dynamic subtest pipe-A: SUCCESS (5.457s)
[512.600761] Starting dynamic subtest: pipe-B
[518.052537] Dynamic subtest pipe-B: SUCCESS (5.479s)
[518.065195] Starting dynamic subtest: pipe-C
[523.516332] Dynamic subtest pipe-C: SUCCESS (5.470s)
[523.535717] Starting dynamic subtest: pipe-D
[529.009418] Dynamic subtest pipe-D: SUCCESS (5.495s)
[529.026486] Starting dynamic subtest: all-pipes
[534.512352] Dynamic subtest all-pipes: SUCCESS (5.505s)
[534.515231] Subtest forked-bo: SUCCESS (27.418s)
[534.615993] [086/156] (495s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[534.721740] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[534.726803] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[534.805980] [087/156] (495s left) gem_create (create-invalid-size)
[534.858598] Starting subtest: create-invalid-size
[534.860830] Subtest create-invalid-size: SUCCESS (0.000s)
[535.048631] [088/156] (494s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[535.139446] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[535.141918] Starting dynamic subtest: pipe-A-eDP-1
[535.288066] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.138s)
[535.299748] Starting dynamic subtest: pipe-B-eDP-1
[535.321224] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[535.332229] Starting dynamic subtest: pipe-C-eDP-1
[535.352815] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[535.364843] Starting dynamic subtest: pipe-D-eDP-1
[535.387534] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[535.389807] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.147s)
[537.623809] [089/156] (492s left) i915_drm_fdinfo (virtual-busy-idle-all)
[537.670066] Starting subtest: virtual-busy-idle-all
[537.675353] Subtest virtual-busy-idle-all: SKIP (0.000s)
[537.745445] [090/156] (492s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[537.849912] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[538.662649] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.815s)
[538.813550] [091/156] (491s left) gem_partial_pwrite_pread (write-snoop)
[538.879056] Subtest write-snoop: SKIP (0.000s)
[538.963642] [092/156] (491s left) perf_pmu (module-unload)