[58.992628] Initializing watchdogs
[58.992675]   /dev/watchdog0
[59.006935] [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.011466] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.013762] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.017215] [001/155] (960s left) syncobj_eventfd (timeline-wait-signaled)
[59.097769] Starting subtest: timeline-wait-signaled
[59.100073] Subtest timeline-wait-signaled: SUCCESS (0.000s)
[59.189679] [002/155] (960s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-render)
[59.303838] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-render
[59.308226] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[59.395757] [003/155] (960s left) kms_addfb_basic (master-rmfb)
[59.454714] Starting subtest: master-rmfb
[59.457068] Subtest master-rmfb: SUCCESS (0.001s)
[59.603798] [004/155] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[59.703078] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[59.707187] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[59.814813] [005/155] (959s left) kms_getfb (getfb-handle-valid)
[59.910223] Starting subtest: getfb-handle-valid
[59.912326] Subtest getfb-handle-valid: SUCCESS (0.000s)
[60.014574] [006/155] (959s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[60.114568] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[60.118269] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[60.204339] [007/155] (959s left) prime_mmap (test_refcounting)
[60.255815] Starting subtest: test_refcounting
[60.257702] Starting dynamic subtest: test_refcounting-smem
[60.287893] Dynamic subtest test_refcounting-smem: SUCCESS (0.032s)
[60.289936] Subtest test_refcounting: SUCCESS (0.033s)
[60.384198] [008/155] (959s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[60.484108] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[60.487695] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[60.598286] [009/155] (959s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[60.687828] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[60.691286] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[60.765681] [010/155] (959s left) kms_cursor_edge_walk (128x128-right-edge)
[60.857225] Starting subtest: 128x128-right-edge
[60.859184] Starting dynamic subtest: pipe-A-eDP-1
[64.380606] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.528s)
[64.391237] Starting dynamic subtest: pipe-D-eDP-1
[67.518530] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.139s)
[67.520445] Subtest 128x128-right-edge: SUCCESS (6.669s)
[68.338081] [011/155] (951s left) kms_plane_scaling (invalid-parameters)
[68.428960] Starting subtest: invalid-parameters
[68.431177] Starting dynamic subtest: less-than-1-height-src
[68.441129] Dynamic subtest less-than-1-height-src: SUCCESS (0.013s)
[68.448994] Starting dynamic subtest: less-than-1-width-src
[68.454062] Dynamic subtest less-than-1-width-src: SUCCESS (0.013s)
[68.456395] Subtest invalid-parameters: SUCCESS (0.026s)
[68.569595] [012/155] (951s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[68.661763] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[76.056455] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.406s)
[76.200407] [013/155] (943s left) dmabuf_sync_file (export-wait-after-attach)
[76.280054] Starting subtest: export-wait-after-attach
[76.282295] Subtest export-wait-after-attach: SUCCESS (0.001s)
[76.336415] [FACT dmabuf_sync_file (export-wait-after-attach)] new: kernel.kmod_is_loaded.vgem: true
[76.340022] [014/155] (943s left) kms_flip (flip-vs-panning)
[76.438204] Starting subtest: flip-vs-panning
[76.440520] Starting dynamic subtest: A-eDP1
[85.412492] Dynamic subtest A-eDP1: SUCCESS (8.975s)
[85.422326] Starting dynamic subtest: B-eDP1
[94.150123] Dynamic subtest B-eDP1: SUCCESS (8.735s)
[94.160460] Starting dynamic subtest: C-eDP1
[102.864779] Dynamic subtest C-eDP1: SUCCESS (8.714s)
[102.875726] Starting dynamic subtest: D-eDP1
[111.599855] Dynamic subtest D-eDP1: SUCCESS (8.735s)
[111.604615] Subtest flip-vs-panning: SUCCESS (35.169s)
[112.754657] [015/155] (907s left) prime_vgem (coherency-blt)
[112.840319] Starting subtest: coherency-blt
[113.918319] Subtest coherency-blt: SUCCESS (1.077s)
[114.053592] [016/155] (906s left) kms_plane (planar-pixel-format-settings)
[114.149446] Starting subtest: planar-pixel-format-settings
[114.218337] Subtest planar-pixel-format-settings: SUCCESS (0.070s)
[114.342945] [017/155] (905s left) kms_atomic (test-only)
[114.436310] Starting subtest: test-only
[114.481305] Starting dynamic subtest: pipe-A-eDP-1
[116.954540] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.471s)
[116.956792] Subtest test-only: SUCCESS (2.519s)
[117.770352] [018/155] (902s left) testdisplay
[118.075300] [019/155] (902s left) kms_frontbuffer_tracking (psr-suspend)
[118.183091] Starting subtest: psr-suspend
[156.595162] Subtest psr-suspend: SUCCESS (8.282s)
[156.718818] [020/155] (863s left) kms_ccs (bad-rotation-90-4-tiled-lnl-ccs)
[156.816697] Starting subtest: bad-rotation-90-4-tiled-lnl-ccs
[156.821175] Subtest bad-rotation-90-4-tiled-lnl-ccs: SKIP (0.000s)
[157.928105] [021/155] (862s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-sf)
[158.031194] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-sf
[158.035164] Subtest fbc-pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[158.097771] [022/155] (862s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[158.199956] Starting subtest: x-tiled-32bpp-rotate-0
[159.672208] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.479s)
[159.817964] [023/155] (860s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-gtt)
[159.917783] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-gtt
[159.921173] Subtest fbcpsrhdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[160.010430] [024/155] (860s left) i915_drm_fdinfo (virtual-busy-hang)
[160.053950] Starting subtest: virtual-busy-hang
[160.057475] Subtest virtual-busy-hang: SKIP (0.000s)
[160.116333] [025/155] (860s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[160.218228] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[160.221741] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[160.310458] [026/155] (860s left) perf_pmu (interrupts)
[160.347398] Starting subtest: interrupts
[161.431712] Subtest interrupts: SUCCESS (1.086s)
[161.534041] [027/155] (858s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[161.627644] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[161.629944] Starting dynamic subtest: pipe-A-default-mode
[162.771710] Dynamic subtest pipe-A-default-mode: SKIP (1.146s)
[162.777046] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.147s)
[162.936373] [028/155] (857s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[163.052518] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-wc
[163.056603] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[163.157457] [029/155] (857s left) kms_vblank (query-forked)
[163.246967] Starting subtest: query-forked
[163.249237] Starting dynamic subtest: pipe-A-eDP-1
[165.080057] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.838s)
[165.096050] Starting dynamic subtest: pipe-D-eDP-1
[167.650360] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.572s)
[167.652602] Subtest query-forked: SUCCESS (4.413s)
[168.494114] [030/155] (852s left) gem_ctx_param (basic)
[168.547000] Starting subtest: basic
[168.549359] Subtest basic: SUCCESS (0.001s)
[168.630565] [031/155] (852s left) gem_mmap_wc (read-write-distinct)
[168.678446] Subtest read-write-distinct: SKIP (0.000s)
[168.741427] [032/155] (851s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[168.834457] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[175.360309] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.539s)
[175.498784] [033/155] (845s left) gem_exec_reloc (basic-cpu)
[175.562305] Subtest basic-cpu: SKIP (0.000s)
[175.635696] [034/155] (845s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[175.737097] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[175.741159] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[175.827535] [035/155] (845s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[176.208238] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[177.045394] [036/155] (843s left) kms_psr (pr-primary-mmap-gtt)
[177.149381] Starting subtest: pr-primary-mmap-gtt
[177.153351] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[177.234547] [037/155] (843s left) gem_exercise_blt (fast-copy-emit)
[177.289328] Starting subtest: fast-copy-emit
[177.291658] Starting dynamic subtest: linear-smem-smem-emit
[177.311154] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.023s)
[177.320333] Starting dynamic subtest: xmajor-smem-smem-emit
[177.327481] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.016s)
[177.336411] Starting dynamic subtest: tile4-smem-smem-emit
[177.343484] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.016s)
[177.352310] Starting dynamic subtest: tile64-smem-smem-emit
[177.360232] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.016s)
[177.362548] Subtest fast-copy-emit: SUCCESS (0.072s)
[177.475885] [038/155] (843s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[177.525417] Starting subtest: multi-wait-available-unsubmitted-signaled
[177.527836] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[177.629263] [039/155] (843s left) gem_exec_balancer (parallel)
[177.892790] Starting subtest: parallel
[178.838358] Subtest parallel: SUCCESS (0.946s)
[179.493970] [040/155] (841s left) vgem_slow (nohang)
[179.534167] Starting subtest: nohang
[189.852952] Subtest nohang: SUCCESS (10.322s)
[189.926545] [041/155] (831s left) gen3_render_mixed_blits
[190.064797] [042/155] (831s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[190.173778] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc
[190.177848] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[190.285722] [043/155] (830s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[190.382307] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[190.386377] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[190.448794] [044/155] (830s left) i915_hangman (engine-engine-error)
[190.500231] Starting subtest: engine-engine-error
[190.502543] Starting dynamic subtest: rcs0
[192.417103] Dynamic subtest rcs0: SUCCESS (1.915s)
[192.431278] Starting dynamic subtest: bcs0
[194.399128] Dynamic subtest bcs0: SUCCESS (1.979s)
[194.412303] Starting dynamic subtest: vcs0
[196.382544] Dynamic subtest vcs0: SUCCESS (1.980s)
[196.394723] Starting dynamic subtest: vcs1
[198.429357] Dynamic subtest vcs1: SUCCESS (2.044s)
[198.442412] Starting dynamic subtest: vecs0
[200.414275] Dynamic subtest vecs0: SUCCESS (1.982s)
[200.427557] Starting dynamic subtest: ccs0
[202.397688] Dynamic subtest ccs0: SUCCESS (1.981s)
[202.400743] Subtest engine-engine-error: SUCCESS (11.896s)
[202.532553] [045/155] (818s left) syncobj_timeline (multi-wait-available-signaled)
[202.583743] Starting subtest: multi-wait-available-signaled
[202.586204] Subtest multi-wait-available-signaled: SUCCESS (0.000s)
[202.704994] [046/155] (818s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-mmap-gtt)
[202.817721] Starting subtest: psrhdr-rgb565-draw-mmap-gtt
[202.822231] Subtest psrhdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[202.904261] [047/155] (818s left) gem_ccs (suspend-resume)
[202.965909] Starting subtest: suspend-resume
[202.970507] Subtest suspend-resume: SKIP (0.000s)
[203.058805] [048/155] (818s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[203.156934] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[203.161118] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[203.260595] [049/155] (818s left) gem_mmap_gtt (cpuset-big-copy)
[203.322083] Subtest cpuset-big-copy: SKIP (0.000s)
[203.393387] [050/155] (818s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[203.455834] Starting subtest: yf-tiled-to-vebox-yf-tiled
[203.459931] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[203.655341] [051/155] (817s left) gen7_exec_parse (basic-offset)
[203.710474] Subtest basic-offset: SKIP (0.000s)
[203.770011] [052/155] (817s left) gem_busy (close-race)
[203.835136] Starting subtest: close-race
[229.375240] Subtest close-race: SUCCESS (25.546s)
[229.735449] [053/155] (791s left) kms_pm_dc (dc6-dpms)
[230.897670] Starting subtest: dc6-dpms
[233.031813] Subtest dc6-dpms: SUCCESS (2.133s)
[233.110263] [054/155] (788s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[233.216666] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[233.221064] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[233.294985] [055/155] (788s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[233.389561] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[233.393922] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[233.487919] [056/155] (788s left) kms_psr (pr-cursor-mmap-gtt)
[233.579119] Starting subtest: pr-cursor-mmap-gtt
[233.583238] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[233.656478] [057/155] (788s left) kms_psr (fbc-pr-sprite-render)
[233.744756] Starting subtest: fbc-pr-sprite-render
[233.749119] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[233.819207] [058/155] (787s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-blt)
[233.922221] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-blt
[233.926342] Subtest psrhdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[234.017195] [059/155] (787s left) gem_pxp (create-regular-context-2)
[234.141083] Starting subtest: create-regular-context-2
[234.143414] Subtest create-regular-context-2: SUCCESS (0.001s)
[234.257419] [060/155] (787s left) gem_ctx_persistence (legacy-engines-mixed-process)
[234.337258] Starting subtest: legacy-engines-mixed-process
[234.338870] Starting dynamic subtest: render
[236.429937] Dynamic subtest render: SUCCESS (2.095s)
[236.443731] Starting dynamic subtest: bsd1
[238.485249] Dynamic subtest bsd1: SUCCESS (2.054s)
[238.497107] Starting dynamic subtest: bsd2
[240.469419] Dynamic subtest bsd2: SUCCESS (1.979s)
[240.482086] Starting dynamic subtest: blt
[242.438097] Dynamic subtest blt: SUCCESS (1.971s)
[242.449194] Starting dynamic subtest: vebox
[244.433930] Dynamic subtest vebox: SUCCESS (1.995s)
[244.436437] Subtest legacy-engines-mixed-process: SUCCESS (10.103s)
[244.515260] [061/155] (777s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[244.610740] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[244.614871] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[244.712834] [062/155] (777s left) kms_cursor_crc (cursor-offscreen-32x10)
[244.886817] Starting subtest: cursor-offscreen-32x10
[244.934458] Subtest cursor-offscreen-32x10: SKIP (0.043s)
[245.210952] [063/155] (776s left) kms_pm_rpm (system-suspend-modeset)
[246.757853] Starting subtest: system-suspend-modeset
[266.272654] Subtest system-suspend-modeset: SUCCESS (4.364s)
[266.664795] [064/155] (755s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[266.758899] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[266.763425] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[266.849968] [065/155] (755s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-render)
[266.947564] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-render
[266.951998] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[267.052057] [066/155] (755s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-render)
[267.150241] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-render
[267.154717] Subtest psrhdr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[267.245033] [067/155] (754s left) gen9_exec_parse (bb-oversize)
[267.305280] Subtest bb-oversize: SKIP (0.000s)
[267.371410] [068/155] (754s left) sysfs_heartbeat_interval (precise)
[267.426436] Starting subtest: precise
[267.428310] Starting dynamic subtest: vecs0
[274.612251] Dynamic subtest vecs0: SUCCESS (7.207s)
[274.623623] Starting dynamic subtest: ccs0
[275.731915] Dynamic subtest ccs0: SUCCESS (1.119s)
[275.742032] Starting dynamic subtest: vcs0
[283.567728] Dynamic subtest vcs0: SUCCESS (7.848s)
[283.581384] Starting dynamic subtest: bcs0
[284.696930] Dynamic subtest bcs0: SUCCESS (1.127s)
[284.709024] Starting dynamic subtest: rcs0
[285.796172] Dynamic subtest rcs0: SUCCESS (1.100s)
[285.810337] Starting dynamic subtest: vcs1
[293.618763] Dynamic subtest vcs1: SUCCESS (7.825s)
[293.621316] Subtest precise: SUCCESS (26.234s)
[293.696811] [069/155] (728s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[293.817964] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[295.282280] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: FAIL (0.910s)
[295.321814] [070/155] (726s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[295.420899] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[296.191627] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.771s)
[296.353798] [071/155] (725s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[296.445324] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[296.449457] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[296.537557] [072/155] (725s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[296.624583] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[296.626785] Starting dynamic subtest: pipe-A-eDP-1
[296.646563] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[296.657477] Starting dynamic subtest: pipe-B-eDP-1
[296.676280] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[296.686852] Starting dynamic subtest: pipe-C-eDP-1
[296.705470] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[296.717412] Starting dynamic subtest: pipe-D-eDP-1
[296.736802] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[296.738993] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[298.867914] [073/155] (723s left) kms_chamelium_color (ctm-0-25)
[298.960749] Subtest ctm-0-25: SKIP (0.000s)
[299.006115] [074/155] (723s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[299.102519] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[299.106935] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[299.201188] [075/155] (723s left) api_intel_bb (crc32)
[299.255357] Starting subtest: crc32
[299.257732] Starting dynamic subtest: rcs0-smem0
[299.560343] Dynamic subtest rcs0-smem0: SUCCESS (0.304s)
[299.571851] Starting dynamic subtest: bcs0-smem0
[299.887113] Dynamic subtest bcs0-smem0: SUCCESS (0.323s)
[299.899262] Starting dynamic subtest: vcs0-smem0
[300.230799] Dynamic subtest vcs0-smem0: SUCCESS (0.341s)
[300.243016] Starting dynamic subtest: vcs1-smem0
[300.593158] Dynamic subtest vcs1-smem0: SUCCESS (0.360s)
[300.607394] Starting dynamic subtest: vecs0-smem0
[300.935413] Dynamic subtest vecs0-smem0: SUCCESS (0.339s)
[300.949252] Starting dynamic subtest: ccs0-smem0
[301.283730] Dynamic subtest ccs0-smem0: SUCCESS (0.346s)
[301.286665] Subtest crc32: SUCCESS (2.029s)
[301.420552] [076/155] (721s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[301.524415] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[301.528921] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[301.622485] [077/155] (720s left) kms_vblank (wait-idle)
[301.722396] Starting subtest: wait-idle
[301.724714] Starting dynamic subtest: pipe-A-eDP-1
[303.493215] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.769s)
[303.508607] Starting dynamic subtest: pipe-D-eDP-1
[305.912743] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.416s)
[305.915127] Subtest wait-idle: SUCCESS (4.188s)
[306.739636] [078/155] (715s left) kms_addfb_basic (unused-modifier)
[306.796519] Starting subtest: unused-modifier
[306.798802] Subtest unused-modifier: SUCCESS (0.000s)
[306.957390] [079/155] (715s left) gem_flink_basic (bad-open)
[307.027124] Starting subtest: bad-open
[307.029286] Subtest bad-open: SUCCESS (0.000s)
[307.132876] [080/155] (715s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[307.233731] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-mc-ccs
[307.236042] Starting dynamic subtest: pipe-A-eDP-1
[307.259367] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[307.270421] Starting dynamic subtest: pipe-B-eDP-1
[307.291404] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[307.301321] Starting dynamic subtest: pipe-C-eDP-1
[307.322821] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[307.332856] Starting dynamic subtest: pipe-D-eDP-1
[307.355088] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[307.359319] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.006s)
[309.503004] [081/155] (713s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[309.615182] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[309.619518] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[309.719244] [082/155] (713s left) gem_ctx_persistence (engines-hang)
[309.784509] Starting subtest: engines-hang
[309.786977] Starting dynamic subtest: rcs0
[309.914668] Dynamic subtest rcs0: SUCCESS (0.129s)
[309.928020] Starting dynamic subtest: bcs0
[310.025658] Dynamic subtest bcs0: SUCCESS (0.109s)
[310.039501] Starting dynamic subtest: vcs0
[310.099289] Dynamic subtest vcs0: SUCCESS (0.072s)
[310.111290] Starting dynamic subtest: vcs1
[310.217624] Dynamic subtest vcs1: SUCCESS (0.116s)
[310.230976] Starting dynamic subtest: vecs0
[310.310409] Dynamic subtest vecs0: SUCCESS (0.091s)
[310.324701] Starting dynamic subtest: ccs0
[310.399297] Dynamic subtest ccs0: SUCCESS (0.087s)
[310.401788] Subtest engines-hang: SUCCESS (0.616s)
[310.491059] [083/155] (712s left) i915_pm_rpm (system-suspend)
[312.823289] Starting subtest: system-suspend
[331.014197] Subtest system-suspend: SUCCESS (3.047s)
[331.408084] [084/155] (691s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-render)
[331.512011] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-render
[331.516355] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[331.617748] [085/155] (691s left) core_debugfs (read-all-entries)
[331.667481] Starting subtest: read-all-entries
[331.761689] Subtest read-all-entries: SUCCESS (0.095s)
[331.827033] [086/155] (691s left) kms_psr (psr2-sprite-plane-onoff)
[331.914282] Starting subtest: psr2-sprite-plane-onoff
[331.916645] Starting dynamic subtest: eDP-1
[333.673778] Dynamic subtest eDP-1: SUCCESS (1.765s)
[333.675773] Subtest psr2-sprite-plane-onoff: SUCCESS (1.766s)
[334.528346] [087/155] (688s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[334.623285] Starting subtest: psrhdr-slowdraw
[334.627487] Subtest psrhdr-slowdraw: SKIP (0.000s)
[334.726209] [088/155] (688s left) perf (mi-rpc)
[334.875155] Starting subtest: mi-rpc
[334.879312] Subtest mi-rpc: SKIP (0.000s)
[334.919276] [089/155] (688s left) kms_draw_crc (draw-method-pwrite)
[335.014530] Starting subtest: draw-method-pwrite
[335.016886] Starting dynamic subtest: xrgb8888-untiled
[335.600392] Dynamic subtest xrgb8888-untiled: SUCCESS (0.588s)
[335.611674] Starting dynamic subtest: rgb565-untiled
[336.068017] Dynamic subtest rgb565-untiled: SUCCESS (0.464s)
[336.079095] Starting dynamic subtest: xrgb2101010-untiled
[336.630766] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.566s)
[336.641832] Starting dynamic subtest: xbgr16161616f-untiled
[337.395650] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.766s)
[337.408545] Starting dynamic subtest: xrgb8888-xtiled
[339.258116] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.866s)
[339.268904] Starting dynamic subtest: rgb565-xtiled
[340.389023] Dynamic subtest rgb565-xtiled: SUCCESS (1.132s)
[340.400370] Starting dynamic subtest: xrgb2101010-xtiled
[342.263957] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.877s)
[342.274769] Starting dynamic subtest: xbgr16161616f-xtiled
[345.692432] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.431s)
[345.703512] Starting dynamic subtest: xrgb8888-4tiled
[346.869298] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.176s)
[346.882323] Starting dynamic subtest: rgb565-4tiled
[347.456954] Dynamic subtest rgb565-4tiled: SUCCESS (0.588s)
[347.468018] Starting dynamic subtest: xrgb2101010-4tiled
[348.600322] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.144s)
[348.610915] Starting dynamic subtest: xbgr16161616f-4tiled
[351.720705] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (3.122s)
[351.723099] Subtest draw-method-pwrite: SUCCESS (16.734s)
[351.841060] [090/155] (671s left) kms_lease (setcrtc-implicit-plane)
[351.935343] Starting subtest: setcrtc-implicit-plane
[351.937603] Starting dynamic subtest: pipe-A-eDP-1
[352.405937] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.472s)
[352.420252] Starting dynamic subtest: pipe-B-eDP-1
[353.526880] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.116s)
[353.538596] Starting dynamic subtest: pipe-C-eDP-1
[354.630538] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.102s)
[354.641846] Starting dynamic subtest: pipe-D-eDP-1
[355.772336] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.142s)
[355.774696] Subtest setcrtc-implicit-plane: SUCCESS (3.841s)
[356.616673] [091/155] (666s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[356.725590] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-mmap-wc
[356.729770] Subtest hdr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[356.834602] [092/155] (666s left) gem_exec_whisper (basic-contexts-priority-all)
[356.893509] Starting subtest: basic-contexts-priority-all
[379.853816] Subtest basic-contexts-priority-all: SUCCESS (22.952s)
[380.051509] [093/155] (643s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[380.126938] Starting subtest: single-wait-all-for-submit-signaled
[380.129242] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[380.226914] [094/155] (643s left) gem_exec_schedule (semaphore-resolve)
[380.303556] Starting subtest: semaphore-resolve
[380.363080] Subtest semaphore-resolve: SUCCESS (0.066s)
[380.697889] [095/155] (642s left) kms_content_protection (uevent-hdcp14)
[380.841765] Starting subtest: uevent-hdcp14
[380.846505] Subtest uevent-hdcp14: SKIP (0.000s)
[380.981697] [096/155] (642s left) gem_exec_balancer (waits)
[381.055576] Starting subtest: waits
[387.614511] Subtest waits: SUCCESS (6.554s)
[387.912027] [097/155] (635s left) i915_suspend (fence-restore-tiled2untiled)
[387.962332] Starting subtest: fence-restore-tiled2untiled
[387.966843] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[388.059906] [098/155] (635s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[388.362046] Starting subtest: cursor-rapid-movement-max-size
[388.368760] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[388.486195] [099/155] (635s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[388.583268] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[388.587726] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.000s)
[388.658747] [100/155] (634s left) gem_exec_reloc (basic-range)
[388.720061] Subtest basic-range: SKIP (0.000s)
[388.809816] [101/155] (634s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[388.909667] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[395.744506] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.839s)
[395.868558] [102/155] (627s left) gem_ctx_bad_destroy (double-destroy)
[395.928285] Starting subtest: double-destroy
[395.930540] Subtest double-destroy: SUCCESS (0.000s)
[396.018992] [103/155] (627s left) kms_rotation_crc (bad-pixel-format)
[396.105315] Starting subtest: bad-pixel-format
[396.109612] Subtest bad-pixel-format: SKIP (0.001s)
[396.181257] [104/155] (627s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-plflip-blt)
[396.294059] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-plflip-blt
[396.298257] Subtest fbcpsrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[396.386112] [105/155] (627s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[396.482812] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[396.487338] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[396.585673] [106/155] (627s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[396.679006] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-mmap-wc
[396.682350] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[396.775324] [107/155] (627s left) perf_pmu (semaphore-busy)
[396.820110] Starting subtest: semaphore-busy
[396.822091] Starting dynamic subtest: rcs0
[398.464675] Dynamic subtest rcs0: SUCCESS (1.641s)
[398.477137] Starting dynamic subtest: bcs0
[400.097214] Dynamic subtest bcs0: SUCCESS (1.630s)
[400.110649] Starting dynamic subtest: vcs0
[404.256382] Dynamic subtest vcs0: SUCCESS (4.157s)
[404.270493] Starting dynamic subtest: vcs1
[410.272216] Dynamic subtest vcs1: SUCCESS (6.015s)
[410.284673] Starting dynamic subtest: vecs0
[416.287909] Dynamic subtest vecs0: SUCCESS (6.015s)
[416.302328] Starting dynamic subtest: ccs0
[419.364440] Dynamic subtest ccs0: SUCCESS (3.075s)
[419.367335] Subtest semaphore-busy: SUCCESS (22.549s)
[419.492342] [108/155] (604s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-wc)
[419.598972] Starting subtest: hdr-rgb565-draw-mmap-wc
[419.603240] Subtest hdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[419.699630] [109/155] (604s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier-source-clamping)
[419.792193] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier-source-clamping
[419.796560] Subtest pixel-format-4-tiled-bmg-ccs-modifier-source-clamping: SKIP (0.000s)
[419.870014] [110/155] (604s left) syncobj_wait (multi-wait-submitted-signaled)
[419.926995] Starting subtest: multi-wait-submitted-signaled
[419.929401] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[420.016389] [111/155] (604s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-plflip-blt)
[420.113165] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-plflip-blt
[420.117370] Subtest fbcpsrhdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[420.236610] [112/155] (603s left) kms_invalid_mode (bad-vsync-start)
[420.329438] Starting subtest: bad-vsync-start
[420.331946] Starting dynamic subtest: pipe-A-eDP-1
[420.613249] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[420.624291] Starting dynamic subtest: pipe-B-eDP-1
[420.626372] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[420.636325] Starting dynamic subtest: pipe-C-eDP-1
[420.638964] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[420.650209] Starting dynamic subtest: pipe-D-eDP-1
[420.652731] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[420.655339] Subtest bad-vsync-start: SUCCESS (0.291s)
[421.474624] [113/155] (602s left) gem_exec_parallel (engines)
[421.532882] Starting subtest: engines
[421.535399] Starting dynamic subtest: basic
[423.719277] Dynamic subtest basic: SUCCESS (2.185s)
[423.733042] Starting dynamic subtest: contexts
[428.069539] Dynamic subtest contexts: SUCCESS (4.349s)
[428.085761] Starting dynamic subtest: fds
[431.845501] Dynamic subtest fds: SUCCESS (3.771s)
[431.859326] Starting dynamic subtest: userptr
[433.702177] Dynamic subtest userptr: SUCCESS (1.853s)
[433.705300] Subtest engines: SUCCESS (12.168s)
[433.898168] [114/155] (590s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[434.003956] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[434.008280] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[434.096111] [115/155] (590s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[434.192245] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[434.204229] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.010s)
[434.295954] [116/155] (589s left) prime_mmap (test_dup)
[434.346578] Starting subtest: test_dup
[434.348691] Starting dynamic subtest: test_dup-smem
[434.384656] Dynamic subtest test_dup-smem: SUCCESS (0.038s)
[434.387046] Subtest test_dup: SUCCESS (0.039s)
[434.473301] [117/155] (589s left) prime_mmap_coherency (write)
[434.517972] Starting subtest: write
[439.899254] Subtest write: SUCCESS (5.378s)
[440.051890] [118/155] (584s left) kms_rmfb (close-fd)
[440.157222] Starting subtest: close-fd
[440.159526] Starting dynamic subtest: pipe-A-eDP-1
[440.266062] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.108s)
[440.279405] Starting dynamic subtest: pipe-B-eDP-1
[441.434178] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.166s)
[441.446930] Starting dynamic subtest: pipe-C-eDP-1
[442.651632] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.216s)
[442.664334] Starting dynamic subtest: pipe-D-eDP-1
[443.790998] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.137s)
[443.793110] Subtest close-fd: SUCCESS (3.631s)
[445.005891] [119/155] (579s left) gem_mmap_offset (partial-mmap)
[445.071681] Starting subtest: partial-mmap
[445.074044] Starting dynamic subtest: smem0
[445.079089] Dynamic subtest smem0: SUCCESS (0.005s)
[445.081058] Subtest partial-mmap: SUCCESS (0.006s)
[445.213630] [120/155] (579s left) fbdev (read)
[445.256806] Starting subtest: read
[445.800284] Subtest read: SUCCESS (0.532s)
[445.865252] [121/155] (578s left) perf (gen8-unprivileged-single-ctx-counters)
[445.966684] Starting subtest: gen8-unprivileged-single-ctx-counters
[445.971560] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[446.017309] [122/155] (578s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[446.118319] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[446.122536] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[446.219039] [123/155] (578s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[446.283273] Starting subtest: x-tiled-to-vebox-yf-tiled
[446.287920] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[446.470444] [124/155] (578s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[446.570031] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[450.313025] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.748s)
[451.515264] [125/155] (573s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[451.603944] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[452.671759] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.070s)
[452.796599] [126/155] (571s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[452.925410] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[452.929273] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[453.070966] [127/155] (571s left) syncobj_timeline (etime-multi-wait-for-submit-submitted)
[453.123526] Starting subtest: etime-multi-wait-for-submit-submitted
[453.225387] Subtest etime-multi-wait-for-submit-submitted: SUCCESS (0.102s)
[453.315820] [128/155] (571s left) kms_psr (fbc-pr-primary-render)
[453.416432] Starting subtest: fbc-pr-primary-render
[453.420859] Subtest fbc-pr-primary-render: SKIP (0.000s)
[453.488673] [129/155] (571s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt)
[453.588749] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt
[460.624655] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (7.040s)
[460.764323] [130/155] (564s left) kms_frontbuffer_tracking (hdr-2p-primscrn-indfb-plflip-blt)
[460.859923] Starting subtest: hdr-2p-primscrn-indfb-plflip-blt
[460.864212] Subtest hdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[460.965647] [131/155] (563s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-render)
[461.075526] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-render
[461.079659] Subtest hdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[461.157493] [132/155] (563s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[461.261750] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[461.266127] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[461.363374] [133/155] (563s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[461.464174] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[466.504778] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (5.045s)
[466.606049] [134/155] (558s left) kms_flip (modeset-vs-vblank-race)
[466.701325] Starting subtest: modeset-vs-vblank-race
[466.703656] Starting dynamic subtest: A-eDP1
[471.437163] Dynamic subtest A-eDP1: SUCCESS (4.738s)
[471.453276] Starting dynamic subtest: B-eDP1
[476.274982] Dynamic subtest B-eDP1: SUCCESS (4.835s)
[476.292854] Starting dynamic subtest: C-eDP1
[480.995243] Dynamic subtest C-eDP1: SUCCESS (4.718s)
[481.010579] Starting dynamic subtest: D-eDP1
[485.635094] Dynamic subtest D-eDP1: SUCCESS (4.637s)
[485.641280] Subtest modeset-vs-vblank-race: SUCCESS (18.944s)
[486.802980] [135/155] (538s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[486.930070] Starting subtest: cursor-vs-flip-legacy
[516.682576] Subtest cursor-vs-flip-legacy: SUCCESS (29.757s)
[517.521718] [136/155] (507s left) gem_userptr_blits (sync-overlap)
[517.587193] Starting subtest: sync-overlap
[517.589530] Subtest sync-overlap: SUCCESS (0.000s)
[517.715282] [137/155] (507s left) gem_exec_gttfill (basic)
[517.781685] Starting subtest: basic
[520.188964] Subtest basic: SUCCESS (2.407s)
[520.393826] [138/155] (504s left) kms_atomic_interruptible (legacy-dpms)
[520.545508] Starting subtest: legacy-dpms
[520.547918] Starting dynamic subtest: pipe-A-eDP-1
[527.963726] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.419s)
[527.966272] Subtest legacy-dpms: SUCCESS (7.420s)
[528.782718] [139/155] (496s left) kms_addfb_basic (unused-offsets)
[528.844575] Starting subtest: unused-offsets
[528.846952] Subtest unused-offsets: SUCCESS (0.000s)
[528.985414] [140/155] (496s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[529.096528] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[529.098954] Starting dynamic subtest: pipe-A-eDP-1
[529.609720] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.484s)
[529.621065] Starting dynamic subtest: pipe-B-eDP-1
[531.012522] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.403s)
[531.025993] Starting dynamic subtest: pipe-C-eDP-1
[532.395534] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.382s)
[532.408323] Starting dynamic subtest: pipe-D-eDP-1
[533.719250] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.324s)
[533.721341] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.598s)
[534.910102] [141/155] (490s left) kms_pipe_stress (stress-xrgb8888-4tiled)
[535.016758] Starting subtest: stress-xrgb8888-4tiled
[541.056619] Subtest stress-xrgb8888-4tiled: SUCCESS (6.042s)
[541.106153] [142/155] (484s left) gem_exec_schedule (preempt)
[541.173540] Starting subtest: preempt
[541.175502] Starting dynamic subtest: rcs0
[541.193989] Dynamic subtest rcs0: SUCCESS (0.021s)
[541.205068] Starting dynamic subtest: bcs0
[541.211562] Dynamic subtest bcs0: SUCCESS (0.017s)
[541.222034] Starting dynamic subtest: vcs0
[541.231806] Dynamic subtest vcs0: SUCCESS (0.020s)
[541.242355] Starting dynamic subtest: vcs1
[541.252216] Dynamic subtest vcs1: SUCCESS (0.020s)
[541.264791] Starting dynamic subtest: vecs0
[541.271691] Dynamic subtest vecs0: SUCCESS (0.019s)
[541.282725] Starting dynamic subtest: ccs0
[541.294025] Dynamic subtest ccs0: SUCCESS (0.022s)
[541.295771] Subtest preempt: SUCCESS (0.122s)
[542.866209] [143/155] (482s left) gem_mmap_wc (write-gtt-read-wc)
[542.914804] Subtest write-gtt-read-wc: SKIP (0.000s)
[542.972957] [144/155] (482s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[543.067782] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[543.070116] Starting dynamic subtest: pipe-A
[543.131516] Dynamic subtest pipe-A: SUCCESS (0.065s)
[543.145704] Starting dynamic subtest: pipe-B
[544.337671] Dynamic subtest pipe-B: SUCCESS (1.205s)
[544.353122] Starting dynamic subtest: pipe-C
[545.449741] Dynamic subtest pipe-C: SUCCESS (1.111s)
[545.463646] Starting dynamic subtest: pipe-D
[546.578973] Dynamic subtest pipe-D: SUCCESS (1.129s)
[546.581366] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.514s)
[547.755136] [145/155] (477s left) syncobj_wait (invalid-signal-illegal-handle)
[547.811862] Starting subtest: invalid-signal-illegal-handle
[547.814205] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[547.911950] [146/155] (477s left) gem_softpin (allocator-fork)
[547.972030] Starting subtest: allocator-fork
[550.181138] Subtest allocator-fork: SUCCESS (2.211s)
[550.307047] [147/155] (475s left) kms_chamelium_frames (dp-crc-fast)
[550.681690] Subtest dp-crc-fast: SKIP (0.000s)
[551.560306] [148/155] (474s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[551.671275] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[551.675786] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[551.770906] [149/155] (473s left) kms_vrr (seamless-rr-switch-virtual)
[551.863197] Starting subtest: seamless-rr-switch-virtual
[551.869715] Subtest seamless-rr-switch-virtual: SKIP (0.000s)
[551.942116] [150/155] (473s left) gem_exec_fence (submit)
[552.112210] Starting subtest: submit
[552.116809] Subtest submit: SKIP (0.000s)
[553.858284] [151/155] (471s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[553.961510] Starting subtest: plane-lut1d-pre-ctm3x4
[554.108050] Starting dynamic subtest: pipe-A-plane-0
[554.307857] Dynamic subtest pipe-A-plane-0: SUCCESS (0.200s)
[554.320178] Starting dynamic subtest: pipe-A-plane-1
[554.441981] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[554.458748] Starting dynamic subtest: pipe-A-plane-2
[554.630080] Dynamic subtest pipe-A-plane-2: SUCCESS (0.186s)
[555.864898] Starting dynamic subtest: pipe-B-plane-0
[556.009596] Dynamic subtest pipe-B-plane-0: SUCCESS (0.155s)
[556.021739] Starting dynamic subtest: pipe-B-plane-1
[556.143069] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[556.154234] Starting dynamic subtest: pipe-B-plane-2
[556.289573] Dynamic subtest pipe-B-plane-2: SUCCESS (0.144s)
[557.501537] Starting dynamic subtest: pipe-C-plane-0
[557.647444] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[557.659752] Starting dynamic subtest: pipe-C-plane-1
[557.780766] Dynamic subtest pipe-C-plane-1: SUCCESS (0.132s)
[557.792447] Starting dynamic subtest: pipe-C-plane-2
[557.925297] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[559.148343] Starting dynamic subtest: pipe-D-plane-0
[559.279526] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[559.293643] Starting dynamic subtest: pipe-D-plane-1
[559.435006] Dynamic subtest pipe-D-plane-1: SUCCESS (0.155s)
[559.446419] Starting dynamic subtest: pipe-D-plane-2
[559.568669] Dynamic subtest pipe-D-plane-2: SUCCESS (0.133s)
[559.855310] Subtest plane-lut1d-pre-ctm3x4: SUCCESS (5.893s)
[560.712116] [152/155] (465s left) kms_pwrite_crc (basic)
[560.818526] Starting subtest: basic
[561.501507] Subtest basic: SUCCESS (0.683s)
[562.356220] [153/155] (463s left) prime_mmap_coherency (read)
[562.411558] Starting subtest: read
[567.786645] Subtest read: SUCCESS (5.375s)
[567.897005] [154/155] (458s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[567.995230] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[567.999331] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[568.113057] [155/155] (457s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[568.219743] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[575.169703] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.950s)
[575.316145] Closing watchdogs
results: parsing output: 0/ for test: syncobj_eventfd
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_addfb_basic
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_getfb
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: prime_mmap
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_cursor_edge_walk
results: parsing output: 10/ for test: kms_plane_scaling
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: dmabuf_sync_file
results: parsing output: 13/ for test: kms_flip
results: parsing output: 14/ for test: prime_vgem
results: parsing output: 15/ for test: kms_plane
results: parsing output: 16/ for test: kms_atomic
results: parsing output: 17/ for test: testdisplay
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_ccs
results: parsing output: 20/ for test: kms_psr2_sf
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: i915_drm_fdinfo
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: perf_pmu
results: parsing output: 26/ for test: kms_flip_scaled_crc
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_vblank
results: parsing output: 29/ for test: gem_ctx_param
results: parsing output: 30/ for test: gem_mmap_wc
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_exec_reloc
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_chamelium_hpd
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: gem_exercise_blt
results: parsing output: 37/ for test: syncobj_timeline
results: parsing output: 38/ for test: gem_exec_balancer
results: parsing output: 39/ for test: vgem_slow
results: parsing output: 40/ for test: gen3_render_mixed_blits
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_psr2_sf
results: parsing output: 43/ for test: i915_hangman
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: gem_ccs
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: gem_mmap_gtt
results: parsing output: 49/ for test: gem_render_copy
results: parsing output: 50/ for test: gen7_exec_parse
results: parsing output: 51/ for test: gem_busy
results: parsing output: 52/ for test: kms_pm_dc
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: kms_psr
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: gem_pxp
results: parsing output: 59/ for test: gem_ctx_persistence
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_cursor_crc
results: parsing output: 62/ for test: kms_pm_rpm
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gen9_exec_parse
results: parsing output: 67/ for test: sysfs_heartbeat_interval
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: kms_big_fb
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_ccs
results: parsing output: 72/ for test: kms_chamelium_color
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: api_intel_bb
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_vblank
results: parsing output: 77/ for test: kms_addfb_basic
results: parsing output: 78/ for test: gem_flink_basic
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_ctx_persistence
results: parsing output: 82/ for test: i915_pm_rpm
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: core_debugfs
results: parsing output: 85/ for test: kms_psr
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: perf
results: parsing output: 88/ for test: kms_draw_crc
results: parsing output: 89/ for test: kms_lease
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: gem_exec_whisper
results: parsing output: 92/ for test: syncobj_timeline
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: kms_content_protection
results: parsing output: 95/ for test: gem_exec_balancer
results: parsing output: 96/ for test: i915_suspend
results: parsing output: 97/ for test: kms_cursor_crc
results: parsing output: 98/ for test: kms_flip_scaled_crc
results: parsing output: 99/ for test: gem_exec_reloc
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: gem_ctx_bad_destroy
results: parsing output: 102/ for test: kms_rotation_crc
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: perf_pmu
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_plane
results: parsing output: 109/ for test: syncobj_wait
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_invalid_mode
results: parsing output: 112/ for test: gem_exec_parallel
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_flip
results: parsing output: 115/ for test: prime_mmap
results: parsing output: 116/ for test: prime_mmap_coherency
results: parsing output: 117/ for test: kms_rmfb
results: parsing output: 118/ for test: gem_mmap_offset
results: parsing output: 119/ for test: fbdev
results: parsing output: 120/ for test: perf
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: gem_render_copy
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_big_fb
results: parsing output: 125/ for test: kms_cursor_legacy
results: parsing output: 126/ for test: syncobj_timeline
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_big_fb
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_flip
results: parsing output: 134/ for test: kms_cursor_legacy
results: parsing output: 135/ for test: gem_userptr_blits
results: parsing output: 136/ for test: gem_exec_gttfill
results: parsing output: 137/ for test: kms_atomic_interruptible
results: parsing output: 138/ for test: kms_addfb_basic
results: parsing output: 139/ for test: kms_ccs
results: parsing output: 140/ for test: kms_pipe_stress
results: parsing output: 141/ for test: gem_exec_schedule
results: parsing output: 142/ for test: gem_mmap_wc
results: parsing output: 143/ for test: kms_plane_scaling
results: parsing output: 144/ for test: syncobj_wait
results: parsing output: 145/ for test: gem_softpin
results: parsing output: 146/ for test: kms_chamelium_frames
results: parsing output: 147/ for test: kms_big_fb
results: parsing output: 148/ for test: kms_vrr
results: parsing output: 149/ for test: gem_exec_fence
results: parsing output: 150/ for test: kms_color_pipeline
results: parsing output: 151/ for test: kms_pwrite_crc
results: parsing output: 152/ for test: prime_mmap_coherency
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_frontbuffer_tracking
Done.