[60.127040] Initializing watchdogs
[60.127228]   /dev/watchdog0
[60.143336] [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.147782] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.150718] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.154201] [001/156] (960s left) kms_psr (psr2-cursor-plane-move)
[60.272741] Starting subtest: psr2-cursor-plane-move
[60.275091] Starting dynamic subtest: eDP-1
[62.034315] Dynamic subtest eDP-1: SUCCESS (1.779s)
[62.036207] Subtest psr2-cursor-plane-move: SUCCESS (1.780s)
[62.910039] [002/156] (957s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[63.002241] Starting subtest: primary-yf-tiled-reflect-x-270
[63.006561] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[63.082267] [003/156] (957s left) sw_sync (sync_random_merge)
[63.115457] Starting subtest: sync_random_merge
[63.161651] Subtest sync_random_merge: SUCCESS (0.049s)
[63.221669] [004/156] (957s left) kms_joiner (basic-force-ultra-joiner)
[63.313497] Starting subtest: basic-force-ultra-joiner
[63.317726] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[63.376599] [005/156] (957s left) gem_exec_fence (syncobj-signal)
[63.825568] Starting subtest: syncobj-signal
[63.830127] Subtest syncobj-signal: SUCCESS (0.006s)
[65.364812] [FACT gem_exec_fence (syncobj-signal)] new: kernel.kmod_is_loaded.vgem: true
[65.368701] [006/156] (955s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-pwrite)
[65.470881] Starting subtest: psrhdr-rgb101010-draw-pwrite
[65.475292] Subtest psrhdr-rgb101010-draw-pwrite: SKIP (0.000s)
[65.588776] [007/156] (955s left) kms_chamelium_edid (dp-edid-resolution-list)
[65.981929] Subtest dp-edid-resolution-list: SKIP (0.000s)
[66.798848] [008/156] (954s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-cpu)
[66.909387] Starting subtest: fbchdr-rgb565-draw-mmap-cpu
[66.913690] Subtest fbchdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[67.025359] [009/156] (953s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[67.131275] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[68.193934] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.068s)
[68.330870] [010/156] (952s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-plflip-blt)
[68.441899] Starting subtest: psrhdr-1p-primscrn-shrfb-plflip-blt
[68.445493] Subtest psrhdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[68.540956] [011/156] (952s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[68.635496] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[68.638948] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[68.744748] [012/156] (952s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[68.847471] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[75.669403] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.843s)
[75.782686] [013/156] (945s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-move)
[75.884110] Starting subtest: psrhdr-2p-primscrn-spr-indfb-move
[75.888721] Subtest psrhdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[75.977475] [014/156] (945s left) i915_pm_rpm (gem-idle)
[77.499855] Starting subtest: gem-idle
[83.717973] Subtest gem-idle: SUCCESS (6.227s)
[84.065664] [015/156] (937s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-msflip-blt)
[85.341899] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-msflip-blt
[85.346605] Subtest fbcpsrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[85.443993] [016/156] (935s left) api_intel_allocator (fork-simple-stress)
[85.507810] Starting subtest: fork-simple-stress
[90.908581] Subtest fork-simple-stress: SUCCESS (5.400s)
[91.047526] [017/156] (930s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[91.154766] Starting subtest: fbc-rgb565-draw-mmap-cpu
[95.297944] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.148s)
[96.468843] [018/156] (924s left) gem_ctx_persistence (engines-hostile-preempt)
[96.526027] Starting subtest: engines-hostile-preempt
[96.528373] Starting dynamic subtest: rcs0
[96.614286] Dynamic subtest rcs0: SUCCESS (0.087s)
[96.624819] Starting dynamic subtest: bcs0
[96.663911] Dynamic subtest bcs0: SUCCESS (0.049s)
[96.673401] Starting dynamic subtest: vcs0
[98.305122] Dynamic subtest vcs0: SUCCESS (1.638s)
[98.314880] Starting dynamic subtest: vcs1
[100.287492] Dynamic subtest vcs1: SUCCESS (1.982s)
[100.297078] Starting dynamic subtest: vecs0
[102.280095] Dynamic subtest vecs0: SUCCESS (1.992s)
[102.290680] Starting dynamic subtest: ccs0
[102.311537] Dynamic subtest ccs0: SUCCESS (0.032s)
[102.313888] Subtest engines-hostile-preempt: SUCCESS (5.785s)
[102.419436] [019/156] (918s left) kms_big_fb (yf-tiled-addfb)
[102.528092] Starting subtest: yf-tiled-addfb
[102.532508] Subtest yf-tiled-addfb: SKIP (0.000s)
[102.626552] [020/156] (918s left) kms_scaling_modes (scaling-mode-none)
[102.724460] Starting subtest: scaling-mode-none
[102.726856] Starting dynamic subtest: pipe-A-eDP-1
[102.824031] Dynamic subtest pipe-A-eDP-1: SKIP (0.097s)
[102.834861] Starting dynamic subtest: pipe-B-eDP-1
[102.858724] Dynamic subtest pipe-B-eDP-1: SKIP (0.033s)
[102.869094] Starting dynamic subtest: pipe-C-eDP-1
[102.893688] Dynamic subtest pipe-C-eDP-1: SKIP (0.034s)
[102.903890] Starting dynamic subtest: pipe-D-eDP-1
[102.927628] Dynamic subtest pipe-D-eDP-1: SKIP (0.034s)
[102.932049] Subtest scaling-mode-none: SKIP (0.202s)
[103.081820] [021/156] (918s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-indfb-msflip-blt)
[103.180985] Starting subtest: fbcpsrhdr-2p-scndscrn-indfb-msflip-blt
[103.185062] Subtest fbcpsrhdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[103.269470] [022/156] (918s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[103.363076] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[103.366812] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[103.477364] [023/156] (918s left) gem_lmem_swapping (verify)
[103.519172] Subtest verify: SKIP (0.000s)
[103.563372] [024/156] (918s left) gem_flink_basic (basic)
[103.615307] Starting subtest: basic
[103.617071] Subtest basic: SUCCESS (0.000s)
[103.701071] [025/156] (917s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[103.791424] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[110.604728] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.816s)
[110.739282] [026/156] (910s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[110.834313] Starting subtest: 2x-plain-flip-ts-check-interruptible
[110.846544] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.011s)
[110.921258] [027/156] (910s left) gem_exec_reloc (basic-wc-read-active)
[110.995649] Subtest basic-wc-read-active: SKIP (0.000s)
[111.066022] [028/156] (910s left) gem_userptr_blits (map-fixed-invalidate)
[111.138765] Starting subtest: map-fixed-invalidate
[111.143589] Subtest map-fixed-invalidate: SKIP (0.000s)
[111.269785] [029/156] (910s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[111.367163] Starting subtest: psr-rgb565-draw-blt
[118.551257] Subtest psr-rgb565-draw-blt: SUCCESS (7.188s)
[118.686869] [030/156] (903s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[118.748081] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[118.857578] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.108s)
[118.932293] [031/156] (902s left) kms_chamelium_color (ctm-green-to-red)
[119.021918] Subtest ctm-green-to-red: SKIP (0.000s)
[119.065397] [032/156] (902s left) api_intel_bb (misplaced-blitter)
[119.125065] Starting subtest: misplaced-blitter
[119.130419] Subtest misplaced-blitter: SUCCESS (0.007s)
[119.256999] [033/156] (902s left) kms_pm_rpm (dpms-non-lpsp)
[121.398493] Starting subtest: dpms-non-lpsp
[121.403961] Subtest dpms-non-lpsp: SKIP (0.001s)
[121.777926] [034/156] (900s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[121.873881] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[121.878177] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[121.975362] [035/156] (900s left) kms_vblank (wait-forked-hang)
[122.068399] Starting subtest: wait-forked-hang
[122.070621] Starting dynamic subtest: pipe-A-eDP-1
[148.117478] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.048s)
[148.133377] Starting dynamic subtest: pipe-D-eDP-1
[163.078151] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.960s)
[163.080677] Subtest wait-forked-hang: SUCCESS (41.012s)
[163.962145] [036/156] (858s left) gem_exec_reloc (basic-wc-active)
[164.017236] Subtest basic-wc-active: SKIP (0.000s)
[164.097580] [037/156] (858s left) kms_psr (fbc-pr-primary-mmap-cpu)
[164.199192] Starting subtest: fbc-pr-primary-mmap-cpu
[164.203666] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[164.283419] [038/156] (857s left) perf (oa-formats)
[164.395105] Starting subtest: oa-formats
[164.397471] Starting dynamic subtest: 0-rcs0
[164.411831] Dynamic subtest 0-rcs0: SUCCESS (0.017s)
[164.420288] Starting dynamic subtest: 1-vcs1
[164.434862] Dynamic subtest 1-vcs1: SUCCESS (0.023s)
[164.437065] Subtest oa-formats: SUCCESS (0.041s)
[164.483629] [039/156] (857s left) sysfs_timeslice_duration (idempotent)
[164.538922] Starting subtest: idempotent
[164.541659] Starting dynamic subtest: vecs0
[164.544248] Dynamic subtest vecs0: SUCCESS (0.000s)
[164.554144] Starting dynamic subtest: ccs0
[164.556585] Dynamic subtest ccs0: SUCCESS (0.000s)
[164.566388] Starting dynamic subtest: vcs0
[164.568585] Dynamic subtest vcs0: SUCCESS (0.000s)
[164.576711] Starting dynamic subtest: bcs0
[164.578840] Dynamic subtest bcs0: SUCCESS (0.000s)
[164.588327] Starting dynamic subtest: rcs0
[164.590265] Dynamic subtest rcs0: SUCCESS (0.000s)
[164.598064] Starting dynamic subtest: vcs1
[164.600195] Dynamic subtest vcs1: SUCCESS (0.000s)
[164.602351] Subtest idempotent: SUCCESS (0.005s)
[164.641430] [040/156] (857s left) perf_pmu (all-busy-idle-check-all)
[164.677856] Starting subtest: all-busy-idle-check-all
[166.280623] Subtest all-busy-idle-check-all: SUCCESS (1.602s)
[166.365982] [041/156] (855s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-shrfb-msflip-blt)
[166.461589] Starting subtest: fbchdr-2p-scndscrn-shrfb-msflip-blt
[166.465936] Subtest fbchdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[166.558256] [042/156] (855s left) kms_fbcon_fbt (fbc)
[166.622326] Starting subtest: fbc
[172.965060] Subtest fbc: SUCCESS (6.341s)
[174.054729] [043/156] (848s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[174.144307] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[174.146641] Starting dynamic subtest: pipe-A
[175.337371] Dynamic subtest pipe-A: SUCCESS (1.196s)
[175.346083] Starting dynamic subtest: pipe-B
[177.566203] Dynamic subtest pipe-B: SUCCESS (2.227s)
[177.575358] Starting dynamic subtest: pipe-C
[178.779111] Dynamic subtest pipe-C: SUCCESS (1.212s)
[178.788659] Starting dynamic subtest: pipe-D
[180.006811] Dynamic subtest pipe-D: SUCCESS (1.226s)
[180.009308] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.866s)
[181.216031] [044/156] (841s left) gem_exec_whisper (basic-queues-all)
[181.278917] Starting subtest: basic-queues-all
[193.259779] Subtest basic-queues-all: SUCCESS (11.976s)
[193.441187] [045/156] (829s left) gem_pread (bench)
[193.500297] Subtest bench: SKIP (0.000s)
[193.569163] [046/156] (828s left) gem_exec_schedule (out-order)
[193.641035] Starting subtest: out-order
[193.643372] Starting dynamic subtest: rcs0
[193.801474] Dynamic subtest rcs0: SUCCESS (0.168s)
[193.813488] Starting dynamic subtest: vcs0
[193.911467] Dynamic subtest vcs0: SUCCESS (0.110s)
[193.923725] Starting dynamic subtest: vcs1
[194.019717] Dynamic subtest vcs1: SUCCESS (0.108s)
[194.031937] Starting dynamic subtest: vecs0
[194.137953] Dynamic subtest vecs0: SUCCESS (0.117s)
[194.149908] Starting dynamic subtest: ccs0
[194.262969] Dynamic subtest ccs0: SUCCESS (0.124s)
[194.265805] Subtest out-order: SUCCESS (0.630s)
[195.374116] [047/156] (827s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[195.473570] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[195.477808] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[195.556711] [048/156] (827s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[195.655085] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[195.659372] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[195.744897] [049/156] (826s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[195.839802] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[202.455755] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.619s)
[202.596253] [050/156] (820s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[202.716569] Starting subtest: cursor-vs-flip-varying-size
[234.118071] Subtest cursor-vs-flip-varying-size: SUCCESS (31.399s)
[234.959371] [051/156] (787s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[235.063579] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[235.067781] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[235.131251] [052/156] (787s left) i915_drm_fdinfo (memory-info-purgeable)
[235.167654] Starting subtest: memory-info-purgeable
[235.170077] Starting dynamic subtest: smem0
[237.883509] Dynamic subtest smem0: SUCCESS (2.716s)
[237.886112] Subtest memory-info-purgeable: SUCCESS (2.717s)
[237.958439] [053/156] (784s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[238.078215] Starting subtest: cursorB-vs-flipA-varying-size
[238.082053] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[238.222394] [054/156] (784s left) gem_ctx_param (get-priority-new-ctx)
[238.268646] Starting subtest: get-priority-new-ctx
[238.270737] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[238.381735] [055/156] (784s left) kms_plane_scaling (planes-upscale-factor-0-25)
[238.464933] Starting subtest: planes-upscale-factor-0-25
[238.466815] Starting dynamic subtest: pipe-A
[238.525620] Dynamic subtest pipe-A: SUCCESS (0.063s)
[238.535196] Starting dynamic subtest: pipe-B
[239.686041] Dynamic subtest pipe-B: SUCCESS (1.159s)
[239.696973] Starting dynamic subtest: pipe-C
[240.801682] Dynamic subtest pipe-C: SUCCESS (1.115s)
[240.812768] Starting dynamic subtest: pipe-D
[241.926034] Dynamic subtest pipe-D: SUCCESS (1.124s)
[241.928414] Subtest planes-upscale-factor-0-25: SUCCESS (3.463s)
[243.085138] [056/156] (779s left) gen9_exec_parse (basic-rejected-ctx-param)
[243.159582] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[243.237975] [057/156] (779s left) core_getversion (basic)
[243.274071] Starting subtest: basic
[243.278558] Subtest basic: SUCCESS (0.000s)
[243.339261] [058/156] (779s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[243.435610] Starting subtest: fbcpsr-shrfb-scaledprimary
[249.188661] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.757s)
[249.303048] [059/156] (773s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[249.399498] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[256.949883] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.555s)
[257.086821] [060/156] (766s left) kms_lease (lease-invalid-plane)
[257.190366] Starting subtest: lease-invalid-plane
[257.192659] Starting dynamic subtest: pipe-A-eDP-1
[257.194850] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[257.204165] Starting dynamic subtest: pipe-B-eDP-1
[257.206476] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[257.215773] Starting dynamic subtest: pipe-C-eDP-1
[257.218136] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[257.226740] Starting dynamic subtest: pipe-D-eDP-1
[257.228585] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[257.230501] Subtest lease-invalid-plane: SUCCESS (0.005s)
[257.267712] [061/156] (765s left) gem_exec_params (secure-non-root)
[257.320926] Starting subtest: secure-non-root
[257.325225] Subtest secure-non-root: SKIP (0.000s)
[257.418448] [062/156] (765s left) kms_cursor_crc (cursor-random-64x21)
[257.607234] Starting subtest: cursor-random-64x21
[257.654639] Subtest cursor-random-64x21: SKIP (0.043s)
[257.883164] [063/156] (765s left) kms_plane_alpha_blend (alpha-basic)
[257.971387] Starting subtest: alpha-basic
[258.268073] Starting dynamic subtest: pipe-A-eDP-1
[260.193632] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.924s)
[260.223556] Starting dynamic subtest: pipe-D-eDP-1
[262.919067] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.703s)
[262.921333] Subtest alpha-basic: SUCCESS (4.947s)
[263.808094] [064/156] (759s left) sw_sync (sync_multi_timeline_wait)
[263.844239] Starting subtest: sync_multi_timeline_wait
[263.846553] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[263.893536] [065/156] (759s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[263.994817] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc
[263.999052] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[264.094727] [066/156] (759s left) prime_mmap_coherency (read)
[264.156973] Starting subtest: read
[269.531754] Subtest read: SUCCESS (5.375s)
[269.674814] [067/156] (753s left) syncobj_timeline (multi-wait-all-available-signaled)
[269.746514] Starting subtest: multi-wait-all-available-signaled
[269.748801] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s)
[269.865876] [068/156] (753s left) gem_ctx_param (non-root-set)
[269.928966] Starting subtest: non-root-set
[269.953878] Subtest non-root-set: SUCCESS (0.027s)
[270.051286] [069/156] (753s left) prime_busy (after-wait)
[270.113824] Starting subtest: after-wait
[270.118524] Starting dynamic subtest: rcs0
[270.152406] Dynamic subtest rcs0: SUCCESS (0.034s)
[270.162048] Starting dynamic subtest: bcs0
[270.194523] Dynamic subtest bcs0: SUCCESS (0.042s)
[270.203894] Starting dynamic subtest: vcs0
[270.236129] Dynamic subtest vcs0: SUCCESS (0.041s)
[270.245385] Starting dynamic subtest: vcs1
[270.279235] Dynamic subtest vcs1: SUCCESS (0.042s)
[270.288405] Starting dynamic subtest: vecs0
[270.320310] Dynamic subtest vecs0: SUCCESS (0.040s)
[270.329385] Starting dynamic subtest: ccs0
[270.363394] Dynamic subtest ccs0: SUCCESS (0.042s)
[270.470235] Subtest after-wait: SUCCESS (0.355s)
[270.557860] [070/156] (752s left) gem_eio (in-flight-1us)
[270.805868] Starting subtest: in-flight-1us
[279.295790] Subtest in-flight-1us: SUCCESS (8.490s)
[279.418726] [071/156] (744s left) gem_ppgtt (blt-vs-render-ctx0)
[279.478949] Starting subtest: blt-vs-render-ctx0
[312.399735] Subtest blt-vs-render-ctx0: SUCCESS (32.916s)
[312.556354] [072/156] (711s left) kms_psr (psr2-suspend)
[312.694449] Starting subtest: psr2-suspend
[312.696784] Starting dynamic subtest: eDP-1
[330.673933] Dynamic subtest eDP-1: SUCCESS (3.042s)
[330.676293] Subtest psr2-suspend: SUCCESS (3.043s)
[331.531400] [073/156] (692s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[331.648963] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[331.653469] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[331.745175] [074/156] (691s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[331.858470] Starting subtest: fbc-psr2-cursor-plane-update-sf
[331.860962] Starting dynamic subtest: pipe-A-eDP-1
[331.949862] Dynamic subtest pipe-A-eDP-1: SKIP (0.101s)
[331.961123] Starting dynamic subtest: pipe-B-eDP-1
[333.071196] Dynamic subtest pipe-B-eDP-1: SKIP (1.145s)
[333.076021] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.249s)
[334.323864] [075/156] (689s left) syncobj_timeline (multi-wait-submitted-signaled)
[334.382259] Starting subtest: multi-wait-submitted-signaled
[334.384163] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[334.453970] [076/156] (689s left) tools_test (sysfs_l3_parity)
[334.504970] Starting subtest: sysfs_l3_parity
[334.568264] Subtest sysfs_l3_parity: SKIP (0.075s)
[334.623227] [077/156] (689s left) gem_exec_suspend (basic-s4-devices)
[334.677351] Starting subtest: basic-S4-devices
[334.679570] Starting dynamic subtest: smem
[346.210234] Dynamic subtest smem: SUCCESS (11.636s)
[346.215012] Subtest basic-S4-devices: SUCCESS (11.639s)
[352.414553] [078/156] (677s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[352.550747] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[352.554952] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[352.661976] [079/156] (677s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[352.756773] Starting subtest: fbcpsr-stridechange
[359.010095] Subtest fbcpsr-stridechange: SUCCESS (6.260s)
[359.158754] [080/156] (670s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[359.273597] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[365.662048] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.396s)
[366.803757] [081/156] (663s left) gem_exec_schedule (u-semaphore-codependency)
[366.893823] Starting subtest: u-semaphore-codependency
[366.901289] Subtest u-semaphore-codependency: SUCCESS (0.013s)
[367.257665] [082/156] (662s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[367.343843] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[367.346012] Starting dynamic subtest: pipe-A-eDP-1
[367.890499] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.519s)
[367.903840] Starting dynamic subtest: pipe-B-eDP-1
[369.372632] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.479s)
[369.386587] Starting dynamic subtest: pipe-C-eDP-1
[370.827416] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.456s)
[370.839414] Starting dynamic subtest: pipe-D-eDP-1
[372.244453] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.419s)
[372.246720] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.877s)
[373.416929] [083/156] (656s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-mc-ccs)
[373.519079] Starting subtest: crc-primary-suspend-4-tiled-mtl-mc-ccs
[373.521227] Starting dynamic subtest: pipe-A-eDP-1
[406.482596] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.967s)
[406.496477] Starting dynamic subtest: pipe-B-eDP-1
[439.411328] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.760s)
[439.424555] Starting dynamic subtest: pipe-C-eDP-1
[472.389737] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.795s)
[472.405424] Starting dynamic subtest: pipe-D-eDP-1
[505.399303] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.731s)
[505.401630] Subtest crc-primary-suspend-4-tiled-mtl-mc-ccs: SUCCESS (14.258s)
[506.598373] [084/156] (523s left) kms_cursor_crc (cursor-offscreen-512x512)
[506.879243] Starting subtest: cursor-offscreen-512x512
[506.883622] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[507.035163] [085/156] (523s left) kms_cursor_legacy (forked-bo)
[507.146842] Starting subtest: forked-bo
[507.148678] Starting dynamic subtest: pipe-A
[512.527614] Dynamic subtest pipe-A: SUCCESS (5.465s)
[512.546992] Starting dynamic subtest: pipe-B
[517.971584] Dynamic subtest pipe-B: SUCCESS (5.485s)
[517.995334] Starting dynamic subtest: pipe-C
[523.418025] Dynamic subtest pipe-C: SUCCESS (5.467s)
[523.443093] Starting dynamic subtest: pipe-D
[528.881712] Dynamic subtest pipe-D: SUCCESS (5.472s)
[528.905870] Starting dynamic subtest: all-pipes
[534.379513] Dynamic subtest all-pipes: SUCCESS (5.504s)
[534.382069] Subtest forked-bo: SUCCESS (27.404s)
[534.531264] [086/156] (495s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[534.627494] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[534.631568] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[534.732465] [087/156] (495s left) gem_create (create-invalid-size)
[534.785480] Starting subtest: create-invalid-size
[534.787687] Subtest create-invalid-size: SUCCESS (0.000s)
[534.972741] [088/156] (495s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[535.067762] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[535.069940] Starting dynamic subtest: pipe-A-eDP-1
[535.216953] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.139s)
[535.231412] Starting dynamic subtest: pipe-B-eDP-1
[535.251019] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[535.263269] Starting dynamic subtest: pipe-C-eDP-1
[535.282492] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[535.294460] Starting dynamic subtest: pipe-D-eDP-1
[535.313431] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[535.315558] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.148s)
[537.474990] [089/156] (492s left) i915_drm_fdinfo (virtual-busy-idle-all)
[537.520415] Starting subtest: virtual-busy-idle-all
[537.524733] Subtest virtual-busy-idle-all: SKIP (0.000s)
[537.609225] [090/156] (492s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[537.718722] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[538.509687] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.793s)
[538.696772] [091/156] (491s left) gem_partial_pwrite_pread (write-snoop)
[538.760831] Subtest write-snoop: SKIP (0.000s)
[538.832305] [092/156] (491s left) perf_pmu (module-unload)