[59.575804] Initializing watchdogs
[59.575852]   /dev/watchdog0
[59.590521] [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.594869] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.597160] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.600423] [001/156] (960s left) gem_flink_basic (basic)
[59.668710] Starting subtest: basic
[59.671127] Subtest basic: SUCCESS (0.000s)
[59.764703] [002/156] (960s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-render)
[59.865610] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-render
[59.869954] Subtest hdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[59.956656] [003/156] (960s left) gem_exec_suspend (basic-s4-devices)
[60.015793] Starting subtest: basic-S4-devices
[60.017966] Starting dynamic subtest: smem
[71.236708] Dynamic subtest smem: SUCCESS (11.297s)
[71.239912] Subtest basic-S4-devices: SUCCESS (11.298s)
[78.433264] [004/156] (948s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-pwrite)
[78.542330] Starting subtest: psr-1p-offscreen-pri-indfb-draw-pwrite
[85.743965] Subtest psr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.208s)
[85.871207] [005/156] (941s left) kms_frontbuffer_tracking (psrhdr-suspend)
[85.968315] Starting subtest: psrhdr-suspend
[85.972330] Subtest psrhdr-suspend: SKIP (0.000s)
[86.051523] [006/156] (941s left) gem_userptr_blits (create-destroy-unsync)
[86.113333] Subtest create-destroy-unsync: SKIP (0.000s)
[86.218361] [007/156] (941s left) kms_psr2_su (page_flip-xrgb8888)
[86.272737] Subtest page_flip-XRGB8888: SKIP (0.000s)
[86.329904] [008/156] (941s left) gem_exec_reloc (basic-gtt-read-noreloc)
[86.387166] Subtest basic-gtt-read-noreloc: SKIP (0.000s)
[86.450247] [009/156] (940s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[86.540501] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[86.552145] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s)
[86.628350] [010/156] (940s left) gem_render_tiled_blits (basic)
[86.684421] Subtest basic: SKIP (0.000s)
[86.737714] [011/156] (940s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[86.824643] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[86.826657] Starting dynamic subtest: pipe-A-eDP-1
[87.373824] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.524s)
[87.384669] Starting dynamic subtest: pipe-B-eDP-1
[88.820981] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.440s)
[88.831657] Starting dynamic subtest: pipe-C-eDP-1
[90.169861] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.350s)
[90.181333] Starting dynamic subtest: pipe-D-eDP-1
[91.528624] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.357s)
[91.530510] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.674s)
[92.684199] [012/156] (934s left) kms_frontbuffer_tracking (hdr-indfb-scaledprimary)
[92.798510] Starting subtest: hdr-indfb-scaledprimary
[92.802703] Subtest hdr-indfb-scaledprimary: SKIP (0.000s)
[92.901177] [013/156] (934s left) perf_pmu (most-busy-check-all)
[92.947294] Starting subtest: most-busy-check-all
[92.949770] Starting dynamic subtest: rcs0
[94.276750] Dynamic subtest rcs0: SUCCESS (1.328s)
[94.289823] Starting dynamic subtest: bcs0
[96.324115] Dynamic subtest bcs0: SUCCESS (2.046s)
[96.334560] Starting dynamic subtest: vcs0
[98.309814] Dynamic subtest vcs0: SUCCESS (1.984s)
[98.320506] Starting dynamic subtest: vcs1
[100.293020] Dynamic subtest vcs1: SUCCESS (1.982s)
[100.304994] Starting dynamic subtest: vecs0
[102.276307] Dynamic subtest vecs0: SUCCESS (1.981s)
[102.287077] Starting dynamic subtest: ccs0
[104.327070] Dynamic subtest ccs0: SUCCESS (2.049s)
[104.330074] Subtest most-busy-check-all: SUCCESS (11.380s)
[104.434035] [014/156] (923s left) dumb_buffer (invalid-bpp)
[104.491774] Starting subtest: invalid-bpp
[104.494174] Subtest invalid-bpp: SUCCESS (0.000s)
[104.601209] [015/156] (923s left) gem_flink_basic (bad-flink)
[104.657713] Starting subtest: bad-flink
[104.660059] Subtest bad-flink: SUCCESS (0.000s)
[104.766887] [016/156] (922s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[104.879310] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[104.883820] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[104.985124] [017/156] (922s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[105.073164] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[105.075527] Starting dynamic subtest: pipe-A
[105.099508] Dynamic subtest pipe-A: SKIP (0.022s)
[105.110232] Starting dynamic subtest: pipe-B
[105.129209] Dynamic subtest pipe-B: SKIP (0.029s)
[105.138663] Starting dynamic subtest: pipe-C
[105.159946] Dynamic subtest pipe-C: SKIP (0.029s)
[105.169175] Starting dynamic subtest: pipe-D
[105.188488] Dynamic subtest pipe-D: SKIP (0.029s)
[105.192167] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.109s)
[105.287182] [018/156] (922s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[105.396978] Starting subtest: 2x-long-flip-vs-cursor-atomic
[105.400911] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[105.537771] [019/156] (922s left) gem_exec_reloc (basic-cpu-wc-active)
[105.594504] Subtest basic-cpu-wc-active: SKIP (0.000s)
[105.652666] [020/156] (922s left) gen9_exec_parse (bb-chained)
[105.701386] Subtest bb-chained: SKIP (0.000s)
[105.770478] [021/156] (922s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-blt)
[105.878171] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-blt
[105.881767] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[105.978193] [022/156] (921s left) prime_vgem (basic-gtt)
[106.114450] Starting subtest: basic-gtt
[106.118673] Subtest basic-gtt: SKIP (0.000s)
[106.238520] [FACT prime_vgem (basic-gtt)] new: kernel.kmod_is_loaded.vgem: true
[106.241129] [023/156] (921s left) kms_chamelium_frames (hdmi-frame-dump)
[106.621890] Subtest hdmi-frame-dump: SKIP (0.000s)
[107.409936] [024/156] (920s left) kms_psr (psr2-cursor-mmap-cpu)
[107.503999] Starting subtest: psr2-cursor-mmap-cpu
[107.506497] Starting dynamic subtest: eDP-1
[109.208847] Dynamic subtest eDP-1: SUCCESS (1.705s)
[109.211162] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.706s)
[110.089503] [025/156] (917s left) kms_vrr (flip-basic)
[110.187094] Starting subtest: flip-basic
[110.193560] Subtest flip-basic: SKIP (0.000s)
[110.257692] [026/156] (917s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-pgflip-blt)
[110.355294] Starting subtest: psrhdr-1p-primscrn-shrfb-pgflip-blt
[110.359831] Subtest psrhdr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[110.465714] [027/156] (917s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[110.563803] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[110.568705] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[110.662943] [028/156] (917s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[110.713778] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[110.816065] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.101s)
[110.900907] [029/156] (917s left) gem_sync (basic-store-each)
[110.967733] Starting subtest: basic-store-each
[113.603424] Subtest basic-store-each: SUCCESS (2.646s)
[113.788326] [030/156] (914s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[113.890215] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[113.892647] Starting dynamic subtest: pipe-A-eDP-1
[113.916748] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[113.927170] Starting dynamic subtest: pipe-B-eDP-1
[113.948793] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[113.957590] Starting dynamic subtest: pipe-C-eDP-1
[113.979236] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[113.987639] Starting dynamic subtest: pipe-D-eDP-1
[114.009150] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[114.013316] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[116.149945] [031/156] (912s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-4)
[116.238950] Starting subtest: fbcpsrhdr-tiling-4
[116.243292] Subtest fbcpsrhdr-tiling-4: SKIP (0.000s)
[116.347309] [032/156] (911s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[116.437787] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[116.441889] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[116.529877] [033/156] (911s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[116.635437] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[116.639877] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[116.729851] [034/156] (911s left) gem_exec_endless (dispatch)
[116.798566] Starting subtest: dispatch
[116.801037] Starting dynamic subtest: rcs0
[116.805399] Dynamic subtest rcs0: SUCCESS (0.006s)
[116.819304] Starting dynamic subtest: bcs0
[116.824077] Dynamic subtest bcs0: SUCCESS (0.005s)
[116.834790] Starting dynamic subtest: vcs0
[116.839202] Dynamic subtest vcs0: SUCCESS (0.004s)
[116.847752] Starting dynamic subtest: vcs1
[116.852341] Dynamic subtest vcs1: SUCCESS (0.004s)
[116.861059] Starting dynamic subtest: vecs0
[116.865409] Dynamic subtest vecs0: SUCCESS (0.004s)
[116.873868] Starting dynamic subtest: ccs0
[116.878220] Dynamic subtest ccs0: SUCCESS (0.005s)
[116.880465] Subtest dispatch: SUCCESS (0.031s)
[118.331411] [035/156] (910s left) kms_big_fb (linear-64bpp-rotate-0)
[118.434714] Starting subtest: linear-64bpp-rotate-0
[120.001512] Subtest linear-64bpp-rotate-0: SUCCESS (1.567s)
[120.170381] [036/156] (908s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[120.269724] Starting subtest: plane-lut1d-post-ctm3x4
[120.404066] Starting dynamic subtest: pipe-A-plane-0
[120.548999] Dynamic subtest pipe-A-plane-0: SUCCESS (0.144s)
[120.559236] Starting dynamic subtest: pipe-A-plane-1
[120.682073] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[120.692627] Starting dynamic subtest: pipe-A-plane-2
[120.815205] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[122.094333] Starting dynamic subtest: pipe-B-plane-0
[122.231483] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[122.242744] Starting dynamic subtest: pipe-B-plane-1
[122.408926] Dynamic subtest pipe-B-plane-1: SUCCESS (0.177s)
[122.421676] Starting dynamic subtest: pipe-B-plane-2
[122.542526] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[123.792603] Starting dynamic subtest: pipe-C-plane-0
[123.939134] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[123.952735] Starting dynamic subtest: pipe-C-plane-1
[124.083677] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[124.093637] Starting dynamic subtest: pipe-C-plane-2
[124.239056] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[125.424072] Starting dynamic subtest: pipe-D-plane-0
[125.561489] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[125.571589] Starting dynamic subtest: pipe-D-plane-1
[125.727687] Dynamic subtest pipe-D-plane-1: SUCCESS (0.166s)
[125.737480] Starting dynamic subtest: pipe-D-plane-2
[125.871875] Dynamic subtest pipe-D-plane-2: SUCCESS (0.142s)
[126.161918] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.891s)
[126.995514] [037/156] (901s left) gem_ctx_persistence (userptr)
[127.055514] Starting subtest: userptr
[127.256094] Subtest userptr: SUCCESS (0.200s)
[127.332090] [038/156] (901s left) gem_lmem_swapping (verify)
[127.371265] Subtest verify: SKIP (0.000s)
[127.409946] [039/156] (901s left) kms_psr (psr2-suspend)
[127.494904] Starting subtest: psr2-suspend
[127.497199] Starting dynamic subtest: eDP-1
[145.455729] Dynamic subtest eDP-1: SUCCESS (2.857s)
[145.458407] Subtest psr2-suspend: SUCCESS (2.858s)
[146.318555] [040/156] (882s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-render)
[146.425105] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-render
[146.429813] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s)
[146.523383] [041/156] (882s left) kms_psr (psr-suspend)
[146.610781] Starting subtest: psr-suspend
[146.613209] Starting dynamic subtest: eDP-1
[164.453541] Dynamic subtest eDP-1: SUCCESS (2.900s)
[164.455907] Subtest psr-suspend: SUCCESS (2.900s)
[165.284535] [042/156] (863s left) kms_psr (fbc-pr-sprite-render)
[165.396302] Starting subtest: fbc-pr-sprite-render
[165.400140] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[165.478807] [043/156] (863s left) kms_sequence (get-busy)
[165.569762] Starting subtest: get-busy
[165.572041] Starting dynamic subtest: pipe-A-eDP-1
[167.457297] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.936s)
[167.466832] Starting dynamic subtest: pipe-B-eDP-1
[169.999405] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.587s)
[170.010530] Starting dynamic subtest: pipe-C-eDP-1
[172.591030] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.623s)
[172.600842] Starting dynamic subtest: pipe-D-eDP-1
[175.175727] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.609s)
[175.178072] Subtest get-busy: SUCCESS (9.759s)
[176.069968] [044/156] (852s left) perf (unprivileged-single-ctx-counters)
[176.209310] Starting subtest: unprivileged-single-ctx-counters
[176.213750] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[176.256595] [045/156] (852s left) kms_cursor_crc (cursor-sliding-128x128)
[176.479726] Starting subtest: cursor-sliding-128x128
[176.484636] Starting dynamic subtest: pipe-A-eDP-1
[179.714881] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.247s)
[179.741872] Starting dynamic subtest: pipe-D-eDP-1
[183.644580] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.925s)
[183.647011] Subtest cursor-sliding-128x128: SUCCESS (7.196s)
[184.499866] [046/156] (844s left) kms_plane (pixel-format-4-tiled-modifier)
[184.599729] Starting subtest: pixel-format-4-tiled-modifier
[184.626448] Starting dynamic subtest: pipe-A-plane-0
[185.036236] Dynamic subtest pipe-A-plane-0: SUCCESS (0.411s)
[185.047031] Starting dynamic subtest: pipe-A-plane-3
[185.357922] Dynamic subtest pipe-A-plane-3: SUCCESS (0.322s)
[185.367497] Starting dynamic subtest: pipe-A-plane-5
[185.371790] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[186.473357] Starting dynamic subtest: pipe-B-plane-0
[186.853144] Dynamic subtest pipe-B-plane-0: SUCCESS (0.389s)
[186.865271] Starting dynamic subtest: pipe-B-plane-3
[187.186631] Dynamic subtest pipe-B-plane-3: SUCCESS (0.333s)
[187.199400] Starting dynamic subtest: pipe-B-plane-5
[187.203933] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[187.507893] Subtest pixel-format-4-tiled-modifier: SUCCESS (2.914s)
[188.349908] [047/156] (840s left) gem_exec_alignment (pi-isolated)
[188.409915] Starting subtest: pi-isolated
[239.016629] Subtest pi-isolated: SUCCESS (50.607s)
[239.919090] [048/156] (789s left) core_auth (getclient-master-drop)
[240.006383] Starting subtest: getclient-master-drop
[240.021314] Subtest getclient-master-drop: SUCCESS (0.020s)
[240.128948] [049/156] (788s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[240.229318] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[244.982781] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (4.760s)
[245.110033] [050/156] (783s left) gem_vm_create (isolation)
[245.180763] Starting subtest: isolation
[245.189366] Subtest isolation: SUCCESS (0.009s)
[245.317548] [051/156] (783s left) gem_ctx_exec (basic)
[245.386244] Starting subtest: basic
[245.395793] Subtest basic: SUCCESS (0.011s)
[245.526895] [052/156] (783s left) sw_sync (alloc_merge_fence)
[245.575687] Starting subtest: alloc_merge_fence
[245.577880] Subtest alloc_merge_fence: SUCCESS (0.000s)
[245.636684] [053/156] (783s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[245.737410] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[245.741869] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[245.841612] [054/156] (783s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[245.936187] Starting subtest: psr-rgb101010-draw-blt
[253.451972] Subtest psr-rgb101010-draw-blt: SUCCESS (7.523s)
[253.583137] [055/156] (775s left) api_intel_allocator (reopen)
[253.653390] Starting subtest: reopen
[253.659314] Subtest reopen: SUCCESS (0.005s)
[253.793654] [056/156] (775s left) gem_exec_reloc (basic-range-active)
[253.859460] Subtest basic-range-active: SKIP (0.000s)
[253.930599] [057/156] (775s left) perf (mi-rpc)
[254.034744] Starting subtest: mi-rpc
[254.039377] Subtest mi-rpc: SKIP (0.000s)
[254.086645] [058/156] (775s left) kms_cursor_legacy (single-move)
[254.200138] Starting subtest: single-move
[254.202251] Starting dynamic subtest: pipe-A
[259.634852] Dynamic subtest pipe-A: SUCCESS (5.380s)
[259.646569] Starting dynamic subtest: pipe-B
[265.019642] Dynamic subtest pipe-B: SUCCESS (5.379s)
[265.031838] Starting dynamic subtest: pipe-C
[270.403417] Dynamic subtest pipe-C: SUCCESS (5.379s)
[270.415781] Starting dynamic subtest: pipe-D
[275.736102] Dynamic subtest pipe-D: SUCCESS (5.379s)
[275.746651] Starting dynamic subtest: all-pipes
[281.181290] Dynamic subtest all-pipes: SUCCESS (5.389s)
[281.183555] Subtest single-move: SUCCESS (26.920s)
[281.269817] [059/156] (748s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[281.316875] Starting subtest: multi-wait-available-submitted-signaled
[281.319102] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[281.410211] [060/156] (748s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[281.518475] Starting subtest: 4-tiled-64bpp-rotate-0
[282.975680] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (1.457s)
[283.156645] [061/156] (746s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[283.250691] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[283.254816] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[283.333421] [062/156] (746s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-msflip-blt)
[283.428720] Starting subtest: psrhdr-2p-scndscrn-shrfb-msflip-blt
[283.432165] Subtest psrhdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[283.520489] [063/156] (746s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[283.612295] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[283.639088] Starting dynamic subtest: pipe-A-plane-0
[283.938921] Dynamic subtest pipe-A-plane-0: SUCCESS (0.300s)
[283.951705] Starting dynamic subtest: pipe-A-plane-3
[284.172571] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[284.184132] Starting dynamic subtest: pipe-A-plane-5
[284.188329] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[285.298985] Starting dynamic subtest: pipe-B-plane-0
[285.601197] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[285.611868] Starting dynamic subtest: pipe-B-plane-3
[285.845757] Dynamic subtest pipe-B-plane-3: SUCCESS (0.242s)
[285.856982] Starting dynamic subtest: pipe-B-plane-5
[285.861622] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[286.158297] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.547s)
[286.972685] [064/156] (742s left) kms_frontbuffer_tracking (fbcpsrhdr-modesetfrombusy)
[287.075890] Starting subtest: fbcpsrhdr-modesetfrombusy
[287.080725] Subtest fbcpsrhdr-modesetfrombusy: SKIP (0.000s)
[287.171206] [065/156] (742s left) kms_lease (possible-crtcs-filtering)
[287.264605] Starting subtest: possible-crtcs-filtering
[287.269729] Subtest possible-crtcs-filtering: SUCCESS (0.006s)
[287.396600] [066/156] (742s left) i915_pm_rpm (debugfs-forcewake-user)
[289.643322] Starting subtest: debugfs-forcewake-user
[301.033591] Subtest debugfs-forcewake-user: SUCCESS (11.392s)
[301.438810] [067/156] (728s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[301.546081] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[305.231309] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.691s)
[306.436964] [068/156] (723s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[306.535184] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[307.147871] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.615s)
[307.311460] [069/156] (722s left) gem_ccs (ctrl-surf-copy-new-ctx)
[307.371312] Starting subtest: ctrl-surf-copy-new-ctx
[307.376153] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[307.474696] [070/156] (722s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[307.563506] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[307.565841] Starting dynamic subtest: A-eDP1
[316.467628] Dynamic subtest A-eDP1: SUCCESS (8.903s)
[316.482676] Starting dynamic subtest: B-eDP1
[325.398710] Dynamic subtest B-eDP1: SUCCESS (8.930s)
[325.409776] Starting dynamic subtest: C-eDP1
[334.365304] Dynamic subtest C-eDP1: SUCCESS (8.920s)
[334.381327] Starting dynamic subtest: D-eDP1
[343.253430] Dynamic subtest D-eDP1: SUCCESS (8.933s)
[343.258751] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.698s)
[344.499221] [071/156] (685s left) syncobj_wait (single-wait-all-signaled)
[344.560159] Starting subtest: single-wait-all-signaled
[344.562349] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[344.677271] [072/156] (685s left) vgem_basic (bad-fence)
[344.713966] Starting subtest: bad-fence
[344.715849] Subtest bad-fence: SUCCESS (0.000s)
[344.769437] [073/156] (685s left) kms_chamelium_color (ctm-limited-range)
[344.867092] Subtest ctm-limited-range: SKIP (0.000s)
[344.926082] [074/156] (685s left) gem_madvise (dontneed-before-exec)
[344.959312] Starting subtest: dontneed-before-exec
[344.983394] Subtest dontneed-before-exec: SKIP (0.019s)
[345.042934] [075/156] (685s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[345.143732] Starting subtest: y-tiled-16bpp-rotate-180
[345.148120] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[345.240572] [076/156] (684s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[345.340046] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[345.344389] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[345.457772] [077/156] (684s left) gem_exec_fence (syncobj-timeline-repeat)
[346.048987] Starting subtest: syncobj-timeline-repeat
[346.287868] Subtest syncobj-timeline-repeat: SUCCESS (0.239s)
[347.533466] [078/156] (682s left) kms_plane (plane-position-hole)
[347.633113] Starting subtest: plane-position-hole
[348.125551] Starting dynamic subtest: pipe-A-plane-1
[349.429079] Dynamic subtest pipe-A-plane-1: SUCCESS (1.303s)
[349.440738] Starting dynamic subtest: pipe-A-plane-2
[350.664211] Dynamic subtest pipe-A-plane-2: SUCCESS (1.237s)
[350.677596] Starting dynamic subtest: pipe-A-plane-3
[351.887385] Dynamic subtest pipe-A-plane-3: SUCCESS (1.223s)
[351.898830] Starting dynamic subtest: pipe-A-plane-4
[353.136089] Dynamic subtest pipe-A-plane-4: SUCCESS (1.247s)
[353.148291] Starting dynamic subtest: pipe-A-plane-5
[353.162344] Dynamic subtest pipe-A-plane-5: SUCCESS (0.025s)
[354.323890] Starting dynamic subtest: pipe-B-plane-1
[355.598877] Dynamic subtest pipe-B-plane-1: SUCCESS (1.285s)
[355.610071] Starting dynamic subtest: pipe-B-plane-2
[356.812728] Dynamic subtest pipe-B-plane-2: SUCCESS (1.212s)
[356.823502] Starting dynamic subtest: pipe-B-plane-3
[358.077933] Dynamic subtest pipe-B-plane-3: SUCCESS (1.267s)
[358.089550] Starting dynamic subtest: pipe-B-plane-4
[359.291971] Dynamic subtest pipe-B-plane-4: SUCCESS (1.213s)
[359.304529] Starting dynamic subtest: pipe-B-plane-5
[359.320368] Dynamic subtest pipe-B-plane-5: SUCCESS (0.026s)
[359.322708] Subtest plane-position-hole: SUCCESS (11.690s)
[360.126592] [079/156] (670s left) gem_exec_balancer (individual)
[360.187731] Starting subtest: individual
[361.431358] Subtest individual: SUCCESS (1.243s)
[361.768572] [080/156] (668s left) gen3_render_tiledy_blits
[361.868698] [081/156] (668s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[361.961269] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[361.963428] Starting dynamic subtest: pipe-A-eDP-1
[361.985406] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[361.996886] Starting dynamic subtest: pipe-B-eDP-1
[362.018711] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[362.028701] Starting dynamic subtest: pipe-C-eDP-1
[362.050632] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[362.061283] Starting dynamic subtest: pipe-D-eDP-1
[362.083467] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[362.088284] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[364.285237] [082/156] (666s left) gem_render_copy_redux (interruptible)
[364.340472] Starting subtest: interruptible
[364.619038] Subtest interruptible: SUCCESS (0.279s)
[364.751757] [083/156] (665s left) sriov_basic (bind-unbind-vf)
[364.810863] Starting subtest: bind-unbind-vf
[364.813177] Starting dynamic subtest: vf-1
[364.817492] Dynamic subtest vf-1: SKIP (0.000s)
[364.834730] Starting dynamic subtest: vf-2
[364.838678] Dynamic subtest vf-2: SKIP (0.000s)
[364.848699] Starting dynamic subtest: vf-3
[364.852781] Dynamic subtest vf-3: SKIP (0.000s)
[364.862783] Starting dynamic subtest: vf-4
[364.866942] Dynamic subtest vf-4: SKIP (0.000s)
[364.877028] Starting dynamic subtest: vf-5
[364.881260] Dynamic subtest vf-5: SKIP (0.000s)
[364.891125] Starting dynamic subtest: vf-6
[364.895298] Dynamic subtest vf-6: SKIP (0.000s)
[364.905195] Starting dynamic subtest: vf-7
[364.909350] Dynamic subtest vf-7: SKIP (0.000s)
[364.919061] Starting dynamic subtest: vf-random
[364.923295] Dynamic subtest vf-random: SKIP (0.000s)
[364.933259] Starting dynamic subtest: vf-last
[364.937283] Dynamic subtest vf-last: SKIP (0.000s)
[364.941388] Subtest bind-unbind-vf: SKIP (0.011s)
[364.978641] [084/156] (665s left) vgem_basic (dmabuf-fence-before)
[365.020592] Starting subtest: dmabuf-fence-before
[365.022557] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[365.076990] [085/156] (665s left) gem_tiled_pread_basic (basic)
[365.135896] Subtest basic: SKIP (0.000s)
[365.190950] [086/156] (665s left) syncobj_wait (multi-wait-submitted-signaled)
[365.249828] Starting subtest: multi-wait-submitted-signaled
[365.251531] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[365.358452] [087/156] (665s left) kms_prime (d3hot)
[365.439394] Subtest D3hot: SKIP (0.000s)
[365.542039] [088/156] (665s left) kms_flip (flip-vs-dpms-on-nop)
[365.638097] Starting subtest: flip-vs-dpms-on-nop
[365.640161] Starting dynamic subtest: A-eDP1
[367.494693] Dynamic subtest A-eDP1: SUCCESS (1.857s)
[367.509053] Starting dynamic subtest: B-eDP1
[369.350225] Dynamic subtest B-eDP1: SUCCESS (1.854s)
[369.360737] Starting dynamic subtest: C-eDP1
[371.190619] Dynamic subtest C-eDP1: SUCCESS (1.839s)
[371.206187] Starting dynamic subtest: D-eDP1
[373.032390] Dynamic subtest D-eDP1: SUCCESS (1.839s)
[373.034848] Subtest flip-vs-dpms-on-nop: SUCCESS (7.400s)
[374.200593] [089/156] (656s left) i915_drm_fdinfo (virtual-busy)
[374.240500] Starting subtest: virtual-busy
[374.244934] Subtest virtual-busy: SKIP (0.000s)
[374.306416] [090/156] (656s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[374.414732] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[379.740466] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.331s)
[380.896441] [091/156] (649s left) gem_exec_fence (basic-wait-all)
[380.961505] Starting subtest: basic-wait-all
[381.056549] Subtest basic-wait-all: SUCCESS (0.095s)
[382.533649] [092/156] (648s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-msflip-blt)
[382.626141] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-msflip-blt
[382.630373] Subtest fbcpsrhdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[382.735794] [093/156] (648s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-blt)
[382.828290] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-blt
[382.832389] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[382.931004] [094/156] (647s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[383.033767] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[383.038075] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[383.116724] [095/156] (647s left) gem_ctx_persistence (engines-hang)
[383.169982] Starting subtest: engines-hang
[383.172264] Starting dynamic subtest: rcs0
[383.288008] Dynamic subtest rcs0: SUCCESS (0.117s)
[383.300538] Starting dynamic subtest: bcs0
[383.390817] Dynamic subtest bcs0: SUCCESS (0.101s)
[383.403365] Starting dynamic subtest: vcs0
[383.495464] Dynamic subtest vcs0: SUCCESS (0.103s)
[383.508422] Starting dynamic subtest: vcs1
[383.603258] Dynamic subtest vcs1: SUCCESS (0.105s)
[383.618426] Starting dynamic subtest: vecs0
[383.693371] Dynamic subtest vecs0: SUCCESS (0.087s)
[383.705324] Starting dynamic subtest: ccs0
[383.765387] Dynamic subtest ccs0: SUCCESS (0.070s)
[383.767618] Subtest engines-hang: SUCCESS (0.597s)
[383.890527] [096/156] (647s left) gem_linear_blits (interruptible)
[384.077620] Starting subtest: interruptible
[388.381148] Subtest interruptible: SUCCESS (4.303s)
[388.549917] [097/156] (642s left) gem_tiled_wc
[388.732918] [098/156] (642s left) kms_atomic_interruptible (legacy-setmode)
[388.850262] Starting subtest: legacy-setmode
[388.852409] Starting dynamic subtest: pipe-A-eDP-1
[395.751829] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.897s)
[395.754249] Subtest legacy-setmode: SUCCESS (6.899s)
[396.563838] [099/156] (634s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[396.621404] Starting subtest: y-tiled-to-vebox-x-tiled
[396.625908] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[396.818857] [100/156] (634s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[396.917386] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[419.638138] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (22.722s)
[419.782560] [101/156] (611s left) kms_frontbuffer_tracking (fbchdr-tiling-4)
[419.890093] Starting subtest: fbchdr-tiling-4
[419.893355] Subtest fbchdr-tiling-4: SKIP (0.000s)
[420.000886] [102/156] (611s left) gem_create (create-clear)
[420.048179] Starting subtest: create-clear
[420.050044] Starting dynamic subtest: smem0
[452.782613] Dynamic subtest smem0: SUCCESS (32.724s)
[452.784764] Subtest create-clear: SUCCESS (32.725s)
[452.974238] [103/156] (578s left) perf_pmu (busy-accuracy-50)
[453.021714] Starting subtest: busy-accuracy-50
[453.023839] Starting dynamic subtest: rcs0
[454.564139] Dynamic subtest rcs0: SUCCESS (1.540s)
[454.577324] Starting dynamic subtest: bcs0
[456.110472] Dynamic subtest bcs0: SUCCESS (1.545s)
[456.123722] Starting dynamic subtest: vcs0
[457.655252] Dynamic subtest vcs0: SUCCESS (1.542s)
[457.668045] Starting dynamic subtest: vcs1
[459.197708] Dynamic subtest vcs1: SUCCESS (1.540s)
[459.209025] Starting dynamic subtest: vecs0
[460.740261] Dynamic subtest vecs0: SUCCESS (1.539s)
[460.753078] Starting dynamic subtest: ccs0
[462.287104] Dynamic subtest ccs0: SUCCESS (1.546s)
[462.289584] Subtest busy-accuracy-50: SUCCESS (9.264s)
[462.400724] [104/156] (568s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[462.453706] Starting subtest: invalid-single-wait-unsubmitted
[462.456010] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[462.548491] [105/156] (568s left) syncobj_timeline (wait-all-interrupted)
[462.604468] Starting subtest: wait-all-interrupted
[462.712431] Subtest wait-all-interrupted: SUCCESS (0.107s)
[462.792617] [106/156] (568s left) gem_mmap_offset (perf)
[462.857227] Starting subtest: perf
[462.859263] Starting dynamic subtest: smem0
[463.308742] Dynamic subtest smem0: SUCCESS (0.450s)
[463.311227] Subtest perf: SUCCESS (0.451s)
[463.440971] [107/156] (568s left) i915_hangman (hangcheck-unterminated)
[463.512969] Starting subtest: hangcheck-unterminated
[475.404041] Subtest hangcheck-unterminated: SUCCESS (11.889s)
[475.540348] [108/156] (555s left) kms_invalid_mode (clock-too-high)
[475.641880] Starting subtest: clock-too-high
[475.644223] Starting dynamic subtest: pipe-A-eDP-1
[475.932629] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.292s)
[475.943720] Starting dynamic subtest: pipe-B-eDP-1
[475.948112] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[475.958782] Starting dynamic subtest: pipe-C-eDP-1
[475.963480] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[475.974014] Starting dynamic subtest: pipe-D-eDP-1
[475.978689] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[475.981052] Subtest clock-too-high: SUCCESS (0.298s)
[476.752096] [109/156] (554s left) gem_mmap_gtt (fault-concurrent)
[476.809281] Subtest fault-concurrent: SKIP (0.000s)
[476.874176] [110/156] (554s left) kms_dsc (dsc-with-formats)
[476.960390] Starting subtest: dsc-with-formats
[477.000152] Subtest dsc-with-formats: SKIP (0.001s)
[477.040326] [111/156] (554s left) kms_pipe_crc_basic (suspend-read-crc)
[477.135717] Starting subtest: suspend-read-crc
[477.138053] Starting dynamic subtest: pipe-A-eDP-1
[494.913195] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.484s)
[494.928559] Starting dynamic subtest: pipe-B-eDP-1
[512.915661] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.194s)
[512.926966] Starting dynamic subtest: pipe-C-eDP-1
[530.892787] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.114s)
[530.904043] Starting dynamic subtest: pipe-D-eDP-1
[548.892459] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.076s)
[548.894863] Subtest suspend-read-crc: SUCCESS (11.872s)
[549.696429] [112/156] (481s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[549.805947] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[549.810098] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[549.911171] [113/156] (481s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-indfb-pgflip-blt)
[550.005980] Starting subtest: psrhdr-2p-scndscrn-indfb-pgflip-blt
[550.010184] Subtest psrhdr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[550.086257] [114/156] (481s left) kms_cursor_edge_walk (256x256-top-edge)
[550.178415] Starting subtest: 256x256-top-edge
[550.180741] Starting dynamic subtest: pipe-A-eDP-1
[553.569241] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.457s)
[553.580463] Starting dynamic subtest: pipe-D-eDP-1
[556.671693] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.144s)
[556.673978] Subtest 256x256-top-edge: SUCCESS (6.604s)
[557.481089] [115/156] (474s left) gem_exec_schedule (u-independent)
[557.556099] Starting subtest: u-independent
[557.558448] Starting dynamic subtest: rcs0
[559.582693] Dynamic subtest rcs0: SUCCESS (2.052s)
[559.597916] Starting dynamic subtest: vcs0
[561.577604] Dynamic subtest vcs0: SUCCESS (2.005s)
[561.590110] Starting dynamic subtest: vcs1
[563.554656] Dynamic subtest vcs1: SUCCESS (1.985s)
[563.568774] Starting dynamic subtest: vecs0
[565.609090] Dynamic subtest vecs0: SUCCESS (2.061s)
[565.622784] Starting dynamic subtest: ccs0
[567.580600] Dynamic subtest ccs0: SUCCESS (1.975s)
[567.583592] Subtest u-independent: SUCCESS (10.089s)
[567.885280] [116/156] (463s left) kms_pm_dc (dc9-dpms)
[568.993719] Starting subtest: dc9-dpms
[570.422135] Subtest dc9-dpms: SUCCESS (1.434s)
[570.495993] [117/156] (461s left) gem_pxp (reject-modify-context-protection-off-1)
[570.625697] Starting subtest: reject-modify-context-protection-off-1
[570.628443] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[570.761292] [118/156] (461s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-render)
[570.853241] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-render
[570.857668] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[570.954951] [119/156] (461s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-render)
[571.050280] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-render
[571.054702] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s)
[571.161865] [120/156] (460s left) kms_flip (flip-vs-wf_vblank-interruptible)
[571.255849] Starting subtest: flip-vs-wf_vblank-interruptible
[571.258122] Starting dynamic subtest: A-eDP1
[573.206001] Dynamic subtest A-eDP1: SUCCESS (1.952s)
[573.218204] Starting dynamic subtest: B-eDP1
[575.130927] Dynamic subtest B-eDP1: SUCCESS (1.926s)
[575.145023] Starting dynamic subtest: C-eDP1
[577.047106] Dynamic subtest C-eDP1: SUCCESS (1.917s)
[577.059931] Starting dynamic subtest: D-eDP1
[578.973751] Dynamic subtest D-eDP1: SUCCESS (1.927s)
[578.975987] Subtest flip-vs-wf_vblank-interruptible: SUCCESS (7.731s)
[580.212532] [121/156] (451s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[580.317751] Starting subtest: x-tiled-16bpp-rotate-0
[581.642937] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.324s)
[581.780262] [122/156] (450s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[581.871264] Starting subtest: psr2-cursor-plane-update-sf
[581.873173] Starting dynamic subtest: pipe-A-eDP-1
[582.435759] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.566s)
[582.446944] Starting dynamic subtest: pipe-B-eDP-1
[583.705525] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.267s)
[583.707912] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.836s)
[584.571309] [123/156] (447s left) gem_exec_whisper (basic-fds-priority)
[584.622426] Starting subtest: basic-fds-priority
[608.863897] Subtest basic-fds-priority: SUCCESS (24.212s)
[609.031712] [124/156] (423s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[609.146008] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[609.150085] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[609.239282] [125/156] (423s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[609.329326] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[609.333758] Subtest hdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[609.423205] [126/156] (422s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[609.514591] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[609.519068] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.000s)
[609.585362] [127/156] (422s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[609.697784] Starting subtest: yf-tiled-64bpp-rotate-0
[609.702217] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[609.797334] [128/156] (422s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[609.886982] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[609.890565] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.000s)
[609.959038] [129/156] (422s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[610.013396] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[610.120126] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.106s)
[610.197534] [130/156] (422s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[610.283897] Starting subtest: fbc-pr-primary-plane-update-sf-dmg-area
[610.288369] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[610.352086] [131/156] (422s left) kms_chamelium_hpd (vga-hpd-fast)
[610.726183] Subtest vga-hpd-fast: SKIP (0.000s)
[611.586258] [132/156] (420s left) gem_pread (bench)
[611.644888] Subtest bench: SKIP (0.000s)
[611.706175] [133/156] (420s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[611.804381] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[618.329426] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.532s)
[618.440991] [134/156] (414s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[618.543437] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[625.862382] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.325s)
[626.001319] [135/156] (406s left) kms_atomic (crtc-invalid-params)
[626.100967] Starting subtest: crtc-invalid-params
[626.103318] Starting dynamic subtest: pipe-A-eDP-1
[626.456632] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.355s)
[626.458652] Subtest crtc-invalid-params: SUCCESS (0.356s)
[627.283086] [136/156] (405s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[627.382665] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[627.384980] Starting dynamic subtest: pipe-A-eDP-1
[627.394146] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[627.406853] Starting dynamic subtest: pipe-B-eDP-1
[627.415132] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[627.426060] Starting dynamic subtest: pipe-C-eDP-1
[627.434321] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[627.444679] Starting dynamic subtest: pipe-D-eDP-1
[627.452957] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[627.456938] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[629.639909] [137/156] (403s left) i915_suspend (basic-s2idle-without-i915)
[629.677087] Starting subtest: basic-s2idle-without-i915
[650.544107] Subtest basic-s2idle-without-i915: SUCCESS (5.769s)
[650.682819] [138/156] (382s left) gem_ctx_param (invalid-ctx-get)
[650.740296] Starting subtest: invalid-ctx-get
[650.742749] Subtest invalid-ctx-get: SUCCESS (0.000s)
[650.827354] [139/156] (382s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[650.929929] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc
[650.934329] Subtest fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[651.025190] [140/156] (381s left) gem_partial_pwrite_pread (write)
[651.083784] Subtest write: SKIP (0.000s)
[651.149916] [141/156] (381s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[651.242967] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[658.546047] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.433s)
[658.681143] [142/156] (374s left) gem_exec_reloc (basic-scanout)
[658.747469] Subtest basic-scanout: SKIP (0.000s)
[658.831084] [143/156] (374s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[658.961628] Starting subtest: cursorB-vs-flipB-atomic-transitions
[658.966143] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[659.120466] [144/156] (373s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[659.218909] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[659.223218] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[659.319397] [145/156] (373s left) prime_mmap (test_map_unmap)
[659.384023] Starting subtest: test_map_unmap
[659.386267] Starting dynamic subtest: test_map_unmap-smem
[659.415989] Dynamic subtest test_map_unmap-smem: SUCCESS (0.033s)
[659.418413] Subtest test_map_unmap: SUCCESS (0.033s)
[659.541731] [146/156] (373s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[659.626671] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[666.166830] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.608s)
[666.313897] [147/156] (366s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[666.414552] Starting subtest: plane-primary-toggle-with-vblank-wait
[666.416852] Starting dynamic subtest: pipe-A-eDP-1
[666.585727] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.173s)
[666.904559] Starting dynamic subtest: pipe-B-eDP-1
[667.772653] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.884s)
[668.079415] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.675s)
[668.932618] [148/156] (364s left) kms_content_protection (dp-mst-lic-type-1)
[669.081481] Starting subtest: dp-mst-lic-type-1
[669.087110] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[669.221943] [149/156] (364s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[669.304694] Starting subtest: primary-y-tiled-reflect-x-0
[669.364916] Subtest primary-y-tiled-reflect-x-0: SKIP (0.059s)
[669.467327] [150/156] (363s left) syncobj_wait (wait-all-complex)
[669.525808] Starting subtest: wait-all-complex
[669.740632] Subtest wait-all-complex: SUCCESS (0.215s)
[669.831723] [151/156] (363s left) kms_addfb_basic (basic-y-tiled-legacy)
[669.889261] Starting subtest: basic-y-tiled-legacy
[669.893540] Subtest basic-y-tiled-legacy: SKIP (0.000s)
[670.020417] [152/156] (363s left) kms_cursor_crc (cursor-offscreen-512x170)
[670.288546] Starting subtest: cursor-offscreen-512x170
[670.293417] Subtest cursor-offscreen-512x170: SKIP (0.001s)
[670.440589] [153/156] (362s left) kms_psr (fbc-psr-sprite-render)
[670.526123] Starting subtest: fbc-psr-sprite-render
[670.528441] Starting dynamic subtest: eDP-1
[670.532458] Dynamic subtest eDP-1: SKIP (0.000s)
[670.536505] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[670.618327] [154/156] (362s left) gem_lmem_swapping (parallel-random-engines)
[670.659026] Subtest parallel-random-engines: SKIP (0.000s)
[670.708234] [155/156] (362s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[670.811225] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[670.815118] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[670.921867] [156/156] (362s left) gem_exec_schedule (preempt-queue-chain)
[670.984766] Subtest preempt-queue-chain: SKIP (0.000s)
[671.284971] Closing watchdogs
results: parsing output: 0/ for test: gem_flink_basic
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_exec_suspend
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: gem_userptr_blits
results: parsing output: 6/ for test: kms_psr2_su
results: parsing output: 7/ for test: gem_exec_reloc
results: parsing output: 8/ for test: kms_flip
results: parsing output: 9/ for test: gem_render_tiled_blits
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: perf_pmu
results: parsing output: 13/ for test: dumb_buffer
results: parsing output: 14/ for test: gem_flink_basic
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_plane_scaling
results: parsing output: 17/ for test: kms_cursor_legacy
results: parsing output: 18/ for test: gem_exec_reloc
results: parsing output: 19/ for test: gen9_exec_parse
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: prime_vgem
results: parsing output: 22/ for test: kms_chamelium_frames
results: parsing output: 23/ for test: kms_psr
results: parsing output: 24/ for test: kms_vrr
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: syncobj_timeline
results: parsing output: 28/ for test: gem_sync
results: parsing output: 29/ for test: kms_ccs
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: gem_exec_endless
results: parsing output: 34/ for test: kms_big_fb
results: parsing output: 35/ for test: kms_color_pipeline
results: parsing output: 36/ for test: gem_ctx_persistence
results: parsing output: 37/ for test: gem_lmem_swapping
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_psr
results: parsing output: 41/ for test: kms_psr
results: parsing output: 42/ for test: kms_sequence
results: parsing output: 43/ for test: perf
results: parsing output: 44/ for test: kms_cursor_crc
results: parsing output: 45/ for test: kms_plane
results: parsing output: 46/ for test: gem_exec_alignment
results: parsing output: 47/ for test: core_auth
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_vm_create
results: parsing output: 50/ for test: gem_ctx_exec
results: parsing output: 51/ for test: sw_sync
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: api_intel_allocator
results: parsing output: 55/ for test: gem_exec_reloc
results: parsing output: 56/ for test: perf
results: parsing output: 57/ for test: kms_cursor_legacy
results: parsing output: 58/ for test: syncobj_timeline
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_plane
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_lease
results: parsing output: 65/ for test: i915_pm_rpm
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: gem_ccs
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: syncobj_wait
results: parsing output: 71/ for test: vgem_basic
results: parsing output: 72/ for test: kms_chamelium_color
results: parsing output: 73/ for test: gem_madvise
results: parsing output: 74/ for test: kms_big_fb
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_exec_fence
results: parsing output: 77/ for test: kms_plane
results: parsing output: 78/ for test: gem_exec_balancer
results: parsing output: 79/ for test: gen3_render_tiledy_blits
results: parsing output: 80/ for test: kms_ccs
results: parsing output: 81/ for test: gem_render_copy_redux
results: parsing output: 82/ for test: sriov_basic
results: parsing output: 83/ for test: vgem_basic
results: parsing output: 84/ for test: gem_tiled_pread_basic
results: parsing output: 85/ for test: syncobj_wait
results: parsing output: 86/ for test: kms_prime
results: parsing output: 87/ for test: kms_flip
results: parsing output: 88/ for test: i915_drm_fdinfo
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: gem_exec_fence
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: gem_ctx_persistence
results: parsing output: 95/ for test: gem_linear_blits
results: parsing output: 96/ for test: gem_tiled_wc
results: parsing output: 97/ for test: kms_atomic_interruptible
results: parsing output: 98/ for test: gem_render_copy
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: gem_create
results: parsing output: 102/ for test: perf_pmu
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: gem_mmap_offset
results: parsing output: 106/ for test: i915_hangman
results: parsing output: 107/ for test: kms_invalid_mode
results: parsing output: 108/ for test: gem_mmap_gtt
results: parsing output: 109/ for test: kms_dsc
results: parsing output: 110/ for test: kms_pipe_crc_basic
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_cursor_edge_walk
results: parsing output: 114/ for test: gem_exec_schedule
results: parsing output: 115/ for test: kms_pm_dc
results: parsing output: 116/ for test: gem_pxp
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_flip
results: parsing output: 120/ for test: kms_big_fb
results: parsing output: 121/ for test: kms_psr2_sf
results: parsing output: 122/ for test: gem_exec_whisper
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_flip_scaled_crc
results: parsing output: 126/ for test: kms_big_fb
results: parsing output: 127/ for test: kms_flip_scaled_crc
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: kms_psr2_sf
results: parsing output: 130/ for test: kms_chamelium_hpd
results: parsing output: 131/ for test: gem_pread
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_atomic
results: parsing output: 135/ for test: kms_ccs
results: parsing output: 136/ for test: i915_suspend
results: parsing output: 137/ for test: gem_ctx_param
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: gem_partial_pwrite_pread
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: gem_exec_reloc
results: parsing output: 142/ for test: kms_cursor_legacy
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: prime_mmap
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_atomic_transition
results: parsing output: 147/ for test: kms_content_protection
results: parsing output: 148/ for test: kms_rotation_crc
results: parsing output: 149/ for test: syncobj_wait
results: parsing output: 150/ for test: kms_addfb_basic
results: parsing output: 151/ for test: kms_cursor_crc
results: parsing output: 152/ for test: kms_psr
results: parsing output: 153/ for test: gem_lmem_swapping
results: parsing output: 154/ for test: kms_big_fb
results: parsing output: 155/ for test: gem_exec_schedule
Done.