[58.247838] Initializing watchdogs
[58.247882]   /dev/watchdog0
[58.263157] [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]
[58.267808] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.270457] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.275543] [001/156] (960s left) kms_psr (psr2-cursor-plane-move)
[58.410884] Starting subtest: psr2-cursor-plane-move
[58.413159] Starting dynamic subtest: eDP-1
[60.116279] Dynamic subtest eDP-1: SUCCESS (1.725s)
[60.118567] Subtest psr2-cursor-plane-move: SUCCESS (1.725s)
[60.993337] [002/156] (957s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[61.090191] Starting subtest: primary-yf-tiled-reflect-x-270
[61.094659] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[61.159616] [003/156] (957s left) sw_sync (sync_random_merge)
[61.194280] Starting subtest: sync_random_merge
[61.240393] Subtest sync_random_merge: SUCCESS (0.047s)
[61.305014] [004/156] (957s left) kms_joiner (basic-force-ultra-joiner)
[61.415130] Starting subtest: basic-force-ultra-joiner
[61.419421] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[61.476540] [005/156] (957s left) gem_exec_fence (syncobj-signal)
[61.919798] Starting subtest: syncobj-signal
[61.922367] Subtest syncobj-signal: SUCCESS (0.005s)
[63.374152] [FACT gem_exec_fence (syncobj-signal)] new: kernel.kmod_is_loaded.vgem: true
[63.377421] [006/156] (955s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-pwrite)
[63.475776] Starting subtest: psrhdr-rgb101010-draw-pwrite
[63.480229] Subtest psrhdr-rgb101010-draw-pwrite: SKIP (0.000s)
[63.590994] [007/156] (955s left) kms_chamelium_edid (dp-edid-resolution-list)
[63.976299] Subtest dp-edid-resolution-list: SKIP (0.000s)
[64.785724] [008/156] (954s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-cpu)
[64.892555] Starting subtest: fbchdr-rgb565-draw-mmap-cpu
[64.896845] Subtest fbchdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[65.001172] [009/156] (954s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[65.107893] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[66.183085] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.082s)
[66.325035] [010/156] (952s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-plflip-blt)
[66.437837] Starting subtest: psrhdr-1p-primscrn-shrfb-plflip-blt
[66.441441] Subtest psrhdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[66.546158] [011/156] (952s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[66.640376] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[66.643978] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[66.735197] [012/156] (952s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[66.827018] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[73.484631] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.681s)
[73.610825] [013/156] (945s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-move)
[73.716890] Starting subtest: psrhdr-2p-primscrn-spr-indfb-move
[73.721069] Subtest psrhdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[73.833106] [014/156] (945s left) i915_pm_rpm (gem-idle)
[75.467066] Starting subtest: gem-idle
[81.665824] Subtest gem-idle: SUCCESS (6.203s)
[82.009302] [015/156] (937s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-msflip-blt)
[83.289288] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-msflip-blt
[83.293515] Subtest fbcpsrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[83.378869] [016/156] (936s left) api_intel_allocator (fork-simple-stress)
[83.435812] Starting subtest: fork-simple-stress
[88.834315] Subtest fork-simple-stress: SUCCESS (5.398s)
[88.960899] [017/156] (930s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[89.072839] Starting subtest: fbc-rgb565-draw-mmap-cpu
[93.261206] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.194s)
[94.415687] [018/156] (925s left) gem_ctx_persistence (engines-hostile-preempt)
[94.474061] Starting subtest: engines-hostile-preempt
[94.476344] Starting dynamic subtest: rcs0
[94.555721] Dynamic subtest rcs0: SUCCESS (0.081s)
[94.567990] Starting dynamic subtest: bcs0
[94.607577] Dynamic subtest bcs0: SUCCESS (0.051s)
[94.620281] Starting dynamic subtest: vcs0
[96.338565] Dynamic subtest vcs0: SUCCESS (1.728s)
[96.350614] Starting dynamic subtest: vcs1
[98.307158] Dynamic subtest vcs1: SUCCESS (1.967s)
[98.317675] Starting dynamic subtest: vecs0
[100.287206] Dynamic subtest vecs0: SUCCESS (1.979s)
[100.296918] Starting dynamic subtest: ccs0
[100.330328] Dynamic subtest ccs0: SUCCESS (0.044s)
[100.332777] Subtest engines-hostile-preempt: SUCCESS (5.856s)
[100.408918] [019/156] (919s left) kms_big_fb (yf-tiled-addfb)
[100.527441] Starting subtest: yf-tiled-addfb
[100.531720] Subtest yf-tiled-addfb: SKIP (0.000s)
[100.627570] [020/156] (918s left) kms_scaling_modes (scaling-mode-none)
[100.720138] Starting subtest: scaling-mode-none
[100.722389] Starting dynamic subtest: pipe-A-eDP-1
[100.816057] Dynamic subtest pipe-A-eDP-1: SKIP (0.095s)
[100.826452] Starting dynamic subtest: pipe-B-eDP-1
[100.850319] Dynamic subtest pipe-B-eDP-1: SKIP (0.033s)
[100.858717] Starting dynamic subtest: pipe-C-eDP-1
[100.884217] Dynamic subtest pipe-C-eDP-1: SKIP (0.033s)
[100.894667] Starting dynamic subtest: pipe-D-eDP-1
[100.917127] Dynamic subtest pipe-D-eDP-1: SKIP (0.032s)
[100.921521] Subtest scaling-mode-none: SKIP (0.197s)
[101.040927] [021/156] (918s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-indfb-msflip-blt)
[101.153413] Starting subtest: fbcpsrhdr-2p-scndscrn-indfb-msflip-blt
[101.157379] Subtest fbcpsrhdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[101.250328] [022/156] (918s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[101.344812] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[101.348662] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[101.439329] [023/156] (918s left) gem_lmem_swapping (verify)
[101.476096] Subtest verify: SKIP (0.000s)
[101.519988] [024/156] (918s left) gem_flink_basic (basic)
[101.573283] Starting subtest: basic
[101.575207] Subtest basic: SUCCESS (0.000s)
[101.690612] [025/156] (918s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[101.782943] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[108.596065] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.817s)
[108.742246] [026/156] (911s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[108.854161] Starting subtest: 2x-plain-flip-ts-check-interruptible
[108.866174] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.011s)
[108.940986] [027/156] (910s left) gem_exec_reloc (basic-wc-read-active)
[108.994686] Subtest basic-wc-read-active: SKIP (0.000s)
[109.057145] [028/156] (910s left) gem_userptr_blits (map-fixed-invalidate)
[109.134373] Starting subtest: map-fixed-invalidate
[109.138768] Subtest map-fixed-invalidate: SKIP (0.000s)
[109.266271] [029/156] (910s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[109.364789] Starting subtest: psr-rgb565-draw-blt
[116.452078] Subtest psr-rgb565-draw-blt: SUCCESS (7.093s)
[116.575722] [030/156] (903s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[116.633446] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[116.737987] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.107s)
[116.814592] [031/156] (903s left) kms_chamelium_color (ctm-green-to-red)
[116.918284] Subtest ctm-green-to-red: SKIP (0.000s)
[116.967030] [032/156] (903s left) api_intel_bb (misplaced-blitter)
[117.031752] Starting subtest: misplaced-blitter
[117.039582] Subtest misplaced-blitter: SUCCESS (0.010s)
[117.172454] [033/156] (902s left) kms_pm_rpm (dpms-non-lpsp)
[119.500888] Starting subtest: dpms-non-lpsp
[119.506302] Subtest dpms-non-lpsp: SKIP (0.001s)
[119.908981] [034/156] (900s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[120.003234] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[120.007424] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[120.095015] [035/156] (900s left) kms_vblank (wait-forked-hang)
[120.178374] Starting subtest: wait-forked-hang
[120.180766] Starting dynamic subtest: pipe-A-eDP-1
[148.047382] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.866s)
[148.060612] Starting dynamic subtest: pipe-D-eDP-1
[163.148865] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.098s)
[163.151752] Subtest wait-forked-hang: SUCCESS (42.968s)
[163.956559] [036/156] (856s left) gem_exec_reloc (basic-wc-active)
[164.023991] Subtest basic-wc-active: SKIP (0.000s)
[164.089332] [037/156] (856s left) kms_psr (fbc-pr-primary-mmap-cpu)
[164.191176] Starting subtest: fbc-pr-primary-mmap-cpu
[164.195698] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[164.261857] [038/156] (856s left) perf (oa-formats)
[164.375031] Starting subtest: oa-formats
[164.377457] Starting dynamic subtest: 0-rcs0
[164.390406] Dynamic subtest 0-rcs0: SUCCESS (0.016s)
[164.399685] Starting dynamic subtest: 1-vcs1
[164.413766] Dynamic subtest 1-vcs1: SUCCESS (0.023s)
[164.415992] Subtest oa-formats: SUCCESS (0.040s)
[164.465247] [039/156] (855s left) sysfs_timeslice_duration (idempotent)
[164.530070] Starting subtest: idempotent
[164.532383] Starting dynamic subtest: vecs0
[164.534596] Dynamic subtest vecs0: SUCCESS (0.000s)
[164.542975] Starting dynamic subtest: ccs0
[164.545224] Dynamic subtest ccs0: SUCCESS (0.000s)
[164.553293] Starting dynamic subtest: vcs0
[164.555525] Dynamic subtest vcs0: SUCCESS (0.000s)
[164.563544] Starting dynamic subtest: bcs0
[164.565806] Dynamic subtest bcs0: SUCCESS (0.000s)
[164.574036] Starting dynamic subtest: rcs0
[164.576295] Dynamic subtest rcs0: SUCCESS (0.000s)
[164.583985] Starting dynamic subtest: vcs1
[164.586537] Dynamic subtest vcs1: SUCCESS (0.000s)
[164.588853] Subtest idempotent: SUCCESS (0.005s)
[164.646966] [040/156] (855s left) perf_pmu (all-busy-idle-check-all)
[164.686966] Starting subtest: all-busy-idle-check-all
[166.278870] Subtest all-busy-idle-check-all: SUCCESS (1.592s)
[166.403353] [041/156] (854s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-shrfb-msflip-blt)
[166.498771] Starting subtest: fbchdr-2p-scndscrn-shrfb-msflip-blt
[166.502785] Subtest fbchdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[166.650597] [042/156] (853s left) kms_fbcon_fbt (fbc)
[166.711804] Starting subtest: fbc
[173.162893] Subtest fbc: SUCCESS (6.449s)
[174.336049] [043/156] (846s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[174.432047] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[174.434281] Starting dynamic subtest: pipe-A
[175.614749] Dynamic subtest pipe-A: SUCCESS (1.185s)
[175.623651] Starting dynamic subtest: pipe-B
[177.855342] Dynamic subtest pipe-B: SUCCESS (2.240s)
[177.865222] Starting dynamic subtest: pipe-C
[179.037991] Dynamic subtest pipe-C: SUCCESS (1.182s)
[179.047713] Starting dynamic subtest: pipe-D
[180.272976] Dynamic subtest pipe-D: SUCCESS (1.234s)
[180.275386] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (5.843s)
[181.458884] [044/156] (839s left) gem_exec_whisper (basic-queues-all)
[181.528281] Starting subtest: basic-queues-all
[193.256975] Subtest basic-queues-all: SUCCESS (11.724s)
[193.438238] [045/156] (827s left) gem_pread (bench)
[193.496084] Subtest bench: SKIP (0.000s)
[193.564929] [046/156] (827s left) gem_exec_schedule (out-order)
[193.637342] Starting subtest: out-order
[193.639542] Starting dynamic subtest: rcs0
[193.807640] Dynamic subtest rcs0: SUCCESS (0.175s)
[193.819109] Starting dynamic subtest: vcs0
[193.915229] Dynamic subtest vcs0: SUCCESS (0.108s)
[193.927896] Starting dynamic subtest: vcs1
[194.021151] Dynamic subtest vcs1: SUCCESS (0.105s)
[194.033310] Starting dynamic subtest: vecs0
[194.136789] Dynamic subtest vecs0: SUCCESS (0.116s)
[194.150349] Starting dynamic subtest: ccs0
[194.264021] Dynamic subtest ccs0: SUCCESS (0.127s)
[194.266777] Subtest out-order: SUCCESS (0.633s)
[195.450166] [047/156] (825s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[195.556577] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[195.561062] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[195.660966] [048/156] (825s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[195.757126] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[195.761232] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[195.851186] [049/156] (824s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[195.949954] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[202.644659] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.699s)
[202.774448] [050/156] (818s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[202.901598] Starting subtest: cursor-vs-flip-varying-size
[234.310874] Subtest cursor-vs-flip-varying-size: SUCCESS (31.408s)
[235.129217] [051/156] (785s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[235.238969] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[235.243158] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[235.307942] [052/156] (785s left) i915_drm_fdinfo (memory-info-purgeable)
[235.351693] Starting subtest: memory-info-purgeable
[235.354046] Starting dynamic subtest: smem0
[238.161634] Dynamic subtest smem0: SUCCESS (2.809s)
[238.164273] Subtest memory-info-purgeable: SUCCESS (2.810s)
[238.238977] [053/156] (782s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[238.354584] Starting subtest: cursorB-vs-flipA-varying-size
[238.358359] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[238.499800] [054/156] (782s left) gem_ctx_param (get-priority-new-ctx)
[238.551416] Starting subtest: get-priority-new-ctx
[238.553498] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[238.662762] [055/156] (782s left) kms_plane_scaling (planes-upscale-factor-0-25)
[238.766636] Starting subtest: planes-upscale-factor-0-25
[238.768476] Starting dynamic subtest: pipe-A
[238.826735] Dynamic subtest pipe-A: SUCCESS (0.062s)
[238.837348] Starting dynamic subtest: pipe-B
[239.955735] Dynamic subtest pipe-B: SUCCESS (1.128s)
[239.967506] Starting dynamic subtest: pipe-C
[241.078015] Dynamic subtest pipe-C: SUCCESS (1.121s)
[241.087730] Starting dynamic subtest: pipe-D
[242.206068] Dynamic subtest pipe-D: SUCCESS (1.127s)
[242.208383] Subtest planes-upscale-factor-0-25: SUCCESS (3.441s)
[243.358218] [056/156] (777s left) gen9_exec_parse (basic-rejected-ctx-param)
[243.416589] Subtest basic-rejected-ctx-param: SKIP (0.000s)
[243.498077] [057/156] (777s left) core_getversion (basic)
[243.537771] Starting subtest: basic
[243.542168] Subtest basic: SUCCESS (0.000s)
[243.608463] [058/156] (777s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[243.714592] Starting subtest: fbcpsr-shrfb-scaledprimary
[249.554266] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.844s)
[249.702206] [059/156] (771s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[249.813305] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[257.159430] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.351s)
[257.283551] [060/156] (763s left) kms_lease (lease-invalid-plane)
[257.385402] Starting subtest: lease-invalid-plane
[257.387693] Starting dynamic subtest: pipe-A-eDP-1
[257.390053] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[257.399077] Starting dynamic subtest: pipe-B-eDP-1
[257.401400] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[257.410094] Starting dynamic subtest: pipe-C-eDP-1
[257.412072] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[257.420946] Starting dynamic subtest: pipe-D-eDP-1
[257.423215] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[257.425380] Subtest lease-invalid-plane: SUCCESS (0.004s)
[257.473233] [061/156] (763s left) gem_exec_params (secure-non-root)
[257.535680] Starting subtest: secure-non-root
[257.540320] Subtest secure-non-root: SKIP (0.000s)
[257.640973] [062/156] (763s left) kms_cursor_crc (cursor-random-64x21)
[257.808542] Starting subtest: cursor-random-64x21
[257.857923] Subtest cursor-random-64x21: SKIP (0.045s)
[258.107397] [063/156] (763s left) kms_plane_alpha_blend (alpha-basic)
[258.196532] Starting subtest: alpha-basic
[258.480374] Starting dynamic subtest: pipe-A-eDP-1
[260.415737] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.933s)
[260.443912] Starting dynamic subtest: pipe-D-eDP-1
[263.107189] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.670s)
[263.109485] Subtest alpha-basic: SUCCESS (4.910s)
[263.976186] [064/156] (757s left) sw_sync (sync_multi_timeline_wait)
[264.013187] Starting subtest: sync_multi_timeline_wait
[264.015563] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[264.064739] [065/156] (757s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[264.167936] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc
[264.172312] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[264.263387] [066/156] (757s left) prime_mmap_coherency (read)
[264.326483] Starting subtest: read
[269.700303] Subtest read: SUCCESS (5.373s)
[269.819622] [067/156] (751s left) syncobj_timeline (multi-wait-all-available-signaled)
[269.865835] Starting subtest: multi-wait-all-available-signaled
[269.868160] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s)
[269.988062] [068/156] (751s left) gem_ctx_param (non-root-set)
[270.029755] Starting subtest: non-root-set
[270.047603] Subtest non-root-set: SUCCESS (0.018s)
[270.146243] [069/156] (751s left) prime_busy (after-wait)
[270.203227] Starting subtest: after-wait
[270.209100] Starting dynamic subtest: rcs0
[270.244615] Dynamic subtest rcs0: SUCCESS (0.037s)
[270.255712] Starting dynamic subtest: bcs0
[270.297781] Dynamic subtest bcs0: SUCCESS (0.052s)
[270.307891] Starting dynamic subtest: vcs0
[270.342791] Dynamic subtest vcs0: SUCCESS (0.044s)
[270.360268] Starting dynamic subtest: vcs1
[270.383442] Dynamic subtest vcs1: SUCCESS (0.040s)
[270.393597] Starting dynamic subtest: vecs0
[270.426060] Dynamic subtest vecs0: SUCCESS (0.042s)
[270.435863] Starting dynamic subtest: ccs0
[270.470095] Dynamic subtest ccs0: SUCCESS (0.042s)
[270.578605] Subtest after-wait: SUCCESS (0.374s)
[270.655780] [070/156] (750s left) gem_eio (in-flight-1us)
[270.919447] Starting subtest: in-flight-1us
[279.238681] Subtest in-flight-1us: SUCCESS (8.318s)
[279.360414] [071/156] (742s left) gem_ppgtt (blt-vs-render-ctx0)
[279.414285] Starting subtest: blt-vs-render-ctx0
[312.338205] Subtest blt-vs-render-ctx0: SUCCESS (32.919s)
[312.465657] [072/156] (709s left) kms_psr (psr2-suspend)
[312.602887] Starting subtest: psr2-suspend
[312.605096] Starting dynamic subtest: eDP-1
[330.591583] Dynamic subtest eDP-1: SUCCESS (3.069s)
[330.593840] Subtest psr2-suspend: SUCCESS (3.070s)
[331.460493] [073/156] (690s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[331.571691] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[331.575706] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[331.677180] [074/156] (690s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[331.784843] Starting subtest: fbc-psr2-cursor-plane-update-sf
[331.787077] Starting dynamic subtest: pipe-A-eDP-1
[331.867727] Dynamic subtest pipe-A-eDP-1: SKIP (0.088s)
[331.879733] Starting dynamic subtest: pipe-B-eDP-1
[332.999628] Dynamic subtest pipe-B-eDP-1: SKIP (1.154s)
[333.004155] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.245s)
[334.165014] [075/156] (687s left) syncobj_timeline (multi-wait-submitted-signaled)
[334.227712] Starting subtest: multi-wait-submitted-signaled
[334.230112] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[334.345498] [076/156] (687s left) tools_test (sysfs_l3_parity)
[334.396843] Starting subtest: sysfs_l3_parity
[334.455097] Subtest sysfs_l3_parity: SKIP (0.073s)
[334.510748] [077/156] (687s left) gem_exec_suspend (basic-s4-devices)
[334.585937] Starting subtest: basic-S4-devices
[334.588174] Starting dynamic subtest: smem
[346.191430] Dynamic subtest smem: SUCCESS (11.701s)
[346.195497] Subtest basic-S4-devices: SUCCESS (11.703s)
[351.390702] [078/156] (675s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[351.506186] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[351.510188] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[351.609177] [079/156] (675s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[351.708502] Starting subtest: fbcpsr-stridechange
[357.908203] Subtest fbcpsr-stridechange: SUCCESS (6.207s)
[358.071492] [080/156] (669s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[358.167060] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[364.574378] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.416s)
[365.759377] [081/156] (661s left) gem_exec_schedule (u-semaphore-codependency)
[365.843561] Starting subtest: u-semaphore-codependency
[365.854609] Subtest u-semaphore-codependency: SUCCESS (0.015s)
[366.205425] [082/156] (660s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[366.297136] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[366.299428] Starting dynamic subtest: pipe-A-eDP-1
[366.762779] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.439s)
[366.776551] Starting dynamic subtest: pipe-B-eDP-1
[368.227619] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.464s)
[368.241715] Starting dynamic subtest: pipe-C-eDP-1
[369.666612] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.438s)
[369.679666] Starting dynamic subtest: pipe-D-eDP-1
[371.022427] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.356s)
[371.024270] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.703s)
[372.197898] [083/156] (655s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-mc-ccs)
[372.309415] Starting subtest: crc-primary-suspend-4-tiled-mtl-mc-ccs
[372.311757] Starting dynamic subtest: pipe-A-eDP-1
[405.416107] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.892s)
[405.432116] Starting dynamic subtest: pipe-B-eDP-1
[438.332667] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.749s)
[438.344455] Starting dynamic subtest: pipe-C-eDP-1
[471.318547] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.760s)
[471.330309] Starting dynamic subtest: pipe-D-eDP-1
[504.325107] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.694s)
[504.327342] Subtest crc-primary-suspend-4-tiled-mtl-mc-ccs: SUCCESS (14.101s)
[505.537217] [084/156] (521s left) kms_cursor_crc (cursor-offscreen-512x512)
[505.814730] Starting subtest: cursor-offscreen-512x512
[505.819138] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[505.979879] [085/156] (521s left) kms_cursor_legacy (forked-bo)
[506.099957] Starting subtest: forked-bo
[506.102698] Starting dynamic subtest: pipe-A
[511.505552] Dynamic subtest pipe-A: SUCCESS (5.481s)
[511.520542] Starting dynamic subtest: pipe-B
[516.939869] Dynamic subtest pipe-B: SUCCESS (5.471s)
[516.955121] Starting dynamic subtest: pipe-C
[522.407855] Dynamic subtest pipe-C: SUCCESS (5.485s)
[522.429960] Starting dynamic subtest: pipe-D
[527.887809] Dynamic subtest pipe-D: SUCCESS (5.488s)
[527.902650] Starting dynamic subtest: all-pipes
[533.389848] Dynamic subtest all-pipes: SUCCESS (5.508s)
[533.392251] Subtest forked-bo: SUCCESS (27.444s)
[533.543873] [086/156] (493s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[533.650122] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[533.654260] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[533.752458] [087/156] (493s left) gem_create (create-invalid-size)
[533.812752] Starting subtest: create-invalid-size
[533.815082] Subtest create-invalid-size: SUCCESS (0.000s)
[534.002334] [088/156] (493s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[534.098214] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[534.100432] Starting dynamic subtest: pipe-A-eDP-1
[534.243267] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.136s)
[534.256602] Starting dynamic subtest: pipe-B-eDP-1
[534.276398] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[534.287621] Starting dynamic subtest: pipe-C-eDP-1
[534.307113] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[534.317983] Starting dynamic subtest: pipe-D-eDP-1
[534.337103] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[534.339007] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.146s)
[536.557510] [089/156] (490s left) i915_drm_fdinfo (virtual-busy-idle-all)
[536.603199] Starting subtest: virtual-busy-idle-all
[536.607248] Subtest virtual-busy-idle-all: SKIP (0.000s)
[536.680776] [090/156] (490s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[536.781873] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[537.571901] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.792s)
[537.763997] [091/156] (489s left) gem_partial_pwrite_pread (write-snoop)
[537.825172] Subtest write-snoop: SKIP (0.000s)
[537.908295] [092/156] (489s left) perf_pmu (module-unload)