[60.127613] Initializing watchdogs
[60.127733]   /dev/watchdog0
[60.141937] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[60.146635] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.150222] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.155051] [001/133] (960s left) kms_feature_discovery (psr1)
[60.280232] Starting subtest: psr1
[60.282470] Subtest psr1: SUCCESS (0.000s)
[60.356306] [002/133] (960s left) gem_render_copy_redux (flink-interruptible)
[60.410771] Starting subtest: flink-interruptible
[61.121189] Subtest flink-interruptible: SUCCESS (0.715s)
[61.259100] [003/133] (959s left) i915_pm_sseu (full-enable)
[61.325916] Subtest full-enable: SKIP (0.000s)
[61.445621] [004/133] (959s left) gem_exec_fence (long-history)
[61.866329] Starting subtest: long-history
[68.667573] Subtest long-history: SUCCESS (6.828s)
[69.103821] [FACT gem_exec_fence (long-history)] new: kernel.kmod_is_loaded.vgem: true
[69.106946] [005/133] (951s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[69.186776] Starting subtest: y-tiled-to-vebox-yf-tiled
[69.190983] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[69.378643] [006/133] (951s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[69.481337] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[69.485379] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[69.590220] [007/133] (951s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[69.730345] Starting subtest: cursor-rapid-movement-32x32
[69.780389] Subtest cursor-rapid-movement-32x32: SKIP (0.046s)
[70.074603] [008/133] (950s left) kms_hdr (bpc-switch-suspend)
[70.165714] Starting subtest: bpc-switch-suspend
[71.201973] Starting dynamic subtest: pipe-A-eDP-1
[91.163164] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.708s)
[91.165518] Subtest bpc-switch-suspend: SUCCESS (5.746s)
[91.290989] [009/133] (929s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[91.404166] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[91.406365] Starting dynamic subtest: pipe-A-eDP-1
[91.427672] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[91.435883] Starting dynamic subtest: pipe-B-eDP-1
[91.455626] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[91.462502] Starting dynamic subtest: pipe-C-eDP-1
[91.482910] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[91.489873] Starting dynamic subtest: pipe-D-eDP-1
[91.510398] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[91.514572] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[93.616419] [010/133] (927s left) kms_psr (psr2-primary-blt)
[93.717843] Starting subtest: psr2-primary-blt
[93.720216] Starting dynamic subtest: eDP-1
[95.439567] Dynamic subtest eDP-1: SUCCESS (1.766s)
[95.441995] Subtest psr2-primary-blt: SUCCESS (1.766s)
[96.298699] [011/133] (924s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[96.415949] Starting subtest: cursorB-vs-flipA-legacy
[96.420608] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[96.566406] [012/133] (924s left) kms_plane_alpha_blend (alpha-transparent-fb)
[96.660848] Starting subtest: alpha-transparent-fb
[96.932444] Starting dynamic subtest: pipe-A-eDP-1
[97.525355] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.602s)
[97.535123] Starting dynamic subtest: pipe-D-eDP-1
[98.908354] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.402s)
[98.911033] Subtest alpha-transparent-fb: SUCCESS (2.284s)
[99.727200] [013/133] (921s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[99.843086] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[99.847004] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[99.956762] [014/133] (921s left) kms_addfb_basic (addfb25-bad-modifier)
[100.052483] Starting subtest: addfb25-bad-modifier
[100.054331] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[100.160953] [015/133] (921s left) i915_hangman (engine-engine-hang)
[100.224074] Starting subtest: engine-engine-hang
[100.226263] Starting dynamic subtest: rcs0
[102.190344] Dynamic subtest rcs0: SUCCESS (1.986s)
[102.201688] Starting dynamic subtest: bcs0
[104.172287] Dynamic subtest bcs0: SUCCESS (1.997s)
[104.183152] Starting dynamic subtest: vcs0
[106.154878] Dynamic subtest vcs0: SUCCESS (1.994s)
[106.166202] Starting dynamic subtest: vcs1
[108.139496] Dynamic subtest vcs1: SUCCESS (1.992s)
[108.151247] Starting dynamic subtest: vecs0
[110.191217] Dynamic subtest vecs0: SUCCESS (2.058s)
[110.202175] Starting dynamic subtest: ccs0
[112.172232] Dynamic subtest ccs0: SUCCESS (1.986s)
[112.174635] Subtest engine-engine-hang: SUCCESS (12.021s)
[112.279457] [016/133] (908s left) syncobj_timeline (device-submit-unordered)
[112.341713] Starting subtest: device-submit-unordered
[112.344080] Subtest device-submit-unordered: SUCCESS (0.000s)
[112.472787] [017/133] (908s left) api_intel_allocator (fork-simple-once)
[112.535414] Starting subtest: fork-simple-once
[112.549545] Subtest fork-simple-once: SUCCESS (0.016s)
[112.697699] [018/133] (908s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs-cc)
[112.811474] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs-cc
[112.813737] Starting dynamic subtest: pipe-A-eDP-1
[112.822571] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[112.834645] Starting dynamic subtest: pipe-B-eDP-1
[112.843520] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[112.853194] Starting dynamic subtest: pipe-C-eDP-1
[112.861185] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[112.868748] Starting dynamic subtest: pipe-D-eDP-1
[112.876977] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[112.880936] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[114.997467] [019/133] (906s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[115.098632] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[115.124238] Starting dynamic subtest: pipe-A-plane-0
[115.412870] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[115.423310] Starting dynamic subtest: pipe-A-plane-3
[115.645246] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[115.653114] Starting dynamic subtest: pipe-A-plane-5
[115.657259] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[116.779605] Starting dynamic subtest: pipe-B-plane-0
[117.072607] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[117.082499] Starting dynamic subtest: pipe-B-plane-3
[117.305664] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[117.315357] Starting dynamic subtest: pipe-B-plane-5
[117.319824] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[117.612546] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.519s)
[118.458581] [020/133] (902s left) kms_flip (absolute-wf_vblank-interruptible)
[118.551098] Starting subtest: absolute-wf_vblank-interruptible
[118.553397] Starting dynamic subtest: A-eDP1
[127.305363] Dynamic subtest A-eDP1: SUCCESS (8.753s)
[127.313740] Starting dynamic subtest: B-eDP1
[136.040007] Dynamic subtest B-eDP1: SUCCESS (8.732s)
[136.050285] Starting dynamic subtest: C-eDP1
[144.753268] Dynamic subtest C-eDP1: SUCCESS (8.712s)
[144.765115] Starting dynamic subtest: D-eDP1
[153.476719] Dynamic subtest D-eDP1: SUCCESS (8.724s)
[153.479126] Subtest absolute-wf_vblank-interruptible: SUCCESS (34.928s)
[154.703443] [021/133] (866s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[154.826953] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[159.295412] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.492s)
[160.495631] [022/133] (860s left) gem_eio (wait-immediate)
[160.718326] Starting subtest: wait-immediate
[161.042440] Subtest wait-immediate: SUCCESS (0.326s)
[161.202815] [023/133] (860s left) gem_exec_balancer (individual)
[161.270615] Starting subtest: individual
[163.195883] Subtest individual: SUCCESS (1.924s)
[163.529150] [024/133] (858s left) kms_invalid_mode (clock-too-high)
[163.618564] Starting subtest: clock-too-high
[163.620888] Starting dynamic subtest: pipe-A-eDP-1
[163.625311] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[163.634098] Starting dynamic subtest: pipe-B-eDP-1
[163.638478] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[163.647073] Starting dynamic subtest: pipe-C-eDP-1
[163.651032] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[163.658934] Starting dynamic subtest: pipe-D-eDP-1
[163.662999] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[163.667034] Subtest clock-too-high: SKIP (0.003s)
[163.712601] [025/133] (857s left) fbdev (unaligned-write)
[163.757083] Starting subtest: unaligned-write
[163.854762] Subtest unaligned-write: SUCCESS (0.099s)
[163.950522] [026/133] (857s left) gen9_exec_parse (bb-start-out)
[164.013240] Subtest bb-start-out: SKIP (0.000s)
[164.114807] [027/133] (857s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[164.172726] Starting subtest: single-wait-all-for-submit-signaled
[164.175121] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.001s)
[164.317953] [028/133] (857s left) kms_atomic (test-only)
[164.416208] Starting subtest: test-only
[164.487445] Starting dynamic subtest: pipe-A-eDP-1
[166.986329] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.499s)
[166.988592] Subtest test-only: SUCCESS (2.571s)
[167.866526] [029/133] (853s left) sriov_basic (enable-vfs-autoprobe-off)
[167.922986] Starting subtest: enable-vfs-autoprobe-off
[167.925470] Starting dynamic subtest: numvfs-1
[168.003362] Dynamic subtest numvfs-1: FAIL (0.033s)
[168.013431] Starting dynamic subtest: numvfs-2
[168.084217] Dynamic subtest numvfs-2: FAIL (0.004s)
[168.094489] Starting dynamic subtest: numvfs-3
[168.165998] Dynamic subtest numvfs-3: FAIL (0.003s)
[168.174923] Starting dynamic subtest: numvfs-4
[168.243538] Dynamic subtest numvfs-4: FAIL (0.003s)
[168.254299] Starting dynamic subtest: numvfs-5
[168.326604] Dynamic subtest numvfs-5: FAIL (0.003s)
[168.336840] Starting dynamic subtest: numvfs-6
[168.408946] Dynamic subtest numvfs-6: FAIL (0.003s)
[168.418827] Starting dynamic subtest: numvfs-7
[168.491142] Dynamic subtest numvfs-7: FAIL (0.003s)
[168.499437] Starting dynamic subtest: numvfs-random
[168.568984] Dynamic subtest numvfs-random: FAIL (0.004s)
[168.579781] Starting dynamic subtest: numvfs-all
[168.651080] Dynamic subtest numvfs-all: FAIL (0.501s)
[168.653370] Subtest enable-vfs-autoprobe-off: FAIL (0.564s)
[168.690809] [030/133] (853s left) syncobj_wait (single-wait-for-submit-unsubmitted)
[168.747921] Starting subtest: single-wait-for-submit-unsubmitted
[168.849142] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.102s)
[168.935566] [031/133] (852s left) prime_mmap (test_userptr)
[169.000919] Starting subtest: test_userptr
[169.003150] Starting dynamic subtest: test_userptr-smem
[169.005070] Dynamic subtest test_userptr-smem: SUCCESS (0.000s)
[169.006971] Subtest test_userptr: SUCCESS (0.001s)
[169.120910] [032/133] (852s left) gem_mmap_wc (pf-nonblock)
[169.182938] Subtest pf-nonblock: SKIP (0.000s)
[169.252242] [033/133] (852s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[169.347136] Starting subtest: flip-primary-invalidate-overlay
[180.912132] Subtest flip-primary-invalidate-overlay: SUCCESS (11.570s)
[181.091465] [034/133] (840s left) kms_addfb_basic (legacy-format)
[181.159015] Starting subtest: legacy-format
[181.177808] Subtest legacy-format: SUCCESS (0.017s)
[181.329819] [035/133] (840s left) kms_properties (colorop-properties-legacy)
[181.430337] Starting subtest: colorop-properties-legacy
[181.432096] Starting dynamic subtest: pipe-A-eDP-1
[181.788553] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.358s)
[181.799660] Starting dynamic subtest: pipe-B-eDP-1
[182.821958] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.031s)
[182.830738] Starting dynamic subtest: pipe-C-eDP-1
[183.859765] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.037s)
[183.870413] Starting dynamic subtest: pipe-D-eDP-1
[184.893351] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.033s)
[184.895567] Subtest colorop-properties-legacy: SUCCESS (3.463s)
[185.705534] [036/133] (836s left) kms_chamelium_edid (vga-edid-read)
[186.100900] Subtest vga-edid-read: SKIP (0.000s)
[186.907655] [037/133] (835s left) kms_frontbuffer_tracking (psr-slowdraw)
[187.017648] Starting subtest: psr-slowdraw
[212.119652] Subtest psr-slowdraw: SUCCESS (25.098s)
[212.265465] [038/133] (809s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[212.379922] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[213.438147] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.058s)
[213.608124] [039/133] (808s left) gem_lmem_swapping (verify-random-ccs)
[213.646880] Subtest verify-random-ccs: SKIP (0.000s)
[213.687567] [040/133] (808s left) gem_workarounds (suspend-resume)
[213.749772] Starting subtest: suspend-resume
[230.051128] Subtest suspend-resume: SUCCESS (1.210s)
[230.211022] [041/133] (792s left) kms_rotation_crc (bad-tiling)
[230.309279] Starting subtest: bad-tiling
[230.313287] Subtest bad-tiling: SKIP (0.001s)
[230.408124] [042/133] (791s left) gem_fence_thrash (bo-write-verify-threaded-x)
[230.444475] Starting subtest: bo-write-verify-threaded-x
[230.474855] Subtest bo-write-verify-threaded-x: SKIP (0.029s)
[230.543419] [043/133] (791s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[230.644519] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[230.646845] Starting dynamic subtest: pipe-A-eDP-1
[230.655530] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[230.665440] Starting dynamic subtest: pipe-B-eDP-1
[230.674473] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[230.684672] Starting dynamic subtest: pipe-C-eDP-1
[230.692243] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[230.701393] Starting dynamic subtest: pipe-D-eDP-1
[230.709481] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[230.713664] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.007s)
[232.903005] [044/133] (789s left) kms_psr (fbc-psr2-cursor-plane-move)
[232.985907] Starting subtest: fbc-psr2-cursor-plane-move
[232.988130] Starting dynamic subtest: eDP-1
[232.992452] Dynamic subtest eDP-1: SKIP (0.000s)
[232.996785] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[233.066072] [045/133] (789s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[233.172349] Starting subtest: fbcpsr-2p-rte
[233.176273] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[233.291702] [046/133] (789s left) syncobj_wait (multi-wait-signaled)
[233.346975] Starting subtest: multi-wait-signaled
[233.349173] Subtest multi-wait-signaled: SUCCESS (0.000s)
[233.471288] [047/133] (789s left) gem_mmap_wc (read)
[233.531621] Subtest read: SKIP (0.000s)
[233.601007] [048/133] (788s left) syncobj_timeline (reset-during-wait-for-submit)
[233.660953] Starting subtest: reset-during-wait-for-submit
[233.764116] Subtest reset-during-wait-for-submit: SUCCESS (0.105s)
[233.852866] [049/133] (788s left) kms_force_connector_basic (prune-stale-modes)
[233.920924] Starting subtest: prune-stale-modes
[234.357497] Subtest prune-stale-modes: SUCCESS (0.447s)
[234.508468] [050/133] (788s left) perf (gen12-group-concurrent-oa-buffer-read)
[234.636291] Starting subtest: gen12-group-concurrent-oa-buffer-read
[244.807378] Subtest gen12-group-concurrent-oa-buffer-read: FAIL (10.167s)
[244.851596] [051/133] (777s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[244.964037] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[251.416312] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.482s)
[251.552495] [052/133] (771s left) gem_exec_parallel (basic)
[251.630290] Starting subtest: basic
[251.632767] Starting dynamic subtest: rcs0
[253.082124] Dynamic subtest rcs0: SUCCESS (1.455s)
[253.099168] Starting dynamic subtest: bcs0
[254.544222] Dynamic subtest bcs0: SUCCESS (1.463s)
[254.556283] Starting dynamic subtest: vcs0
[256.256127] Dynamic subtest vcs0: SUCCESS (1.712s)
[256.271800] Starting dynamic subtest: vcs1
[258.304348] Dynamic subtest vcs1: SUCCESS (2.049s)
[258.316614] Starting dynamic subtest: vecs0
[260.288227] Dynamic subtest vecs0: SUCCESS (1.984s)
[260.300557] Starting dynamic subtest: ccs0
[261.759372] Dynamic subtest ccs0: SUCCESS (1.470s)
[261.761813] Subtest basic: SUCCESS (10.148s)
[261.979788] [053/133] (760s left) kms_cursor_legacy (nonblocking-modeset-vs-cursor-atomic)
[262.102339] Starting subtest: nonblocking-modeset-vs-cursor-atomic
[263.550222] Subtest nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.448s)
[264.405929] [054/133] (758s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[264.518557] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[264.522986] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[264.647159] [055/133] (758s left) kms_sequence (get-busy)
[264.740428] Starting subtest: get-busy
[264.742323] Starting dynamic subtest: pipe-A-eDP-1
[266.664130] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.925s)
[266.676238] Starting dynamic subtest: pipe-B-eDP-1
[269.241746] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.576s)
[269.253330] Starting dynamic subtest: pipe-C-eDP-1
[271.833968] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.591s)
[271.844636] Starting dynamic subtest: pipe-D-eDP-1
[274.434414] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.598s)
[274.436641] Subtest get-busy: SUCCESS (9.695s)
[275.297923] [056/133] (747s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[275.402916] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[275.407287] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[275.522457] [057/133] (747s left) gem_exec_whisper (basic-queues-priority)
[275.592965] Starting subtest: basic-queues-priority
[298.509480] Subtest basic-queues-priority: SUCCESS (22.920s)
[298.719793] [058/133] (724s left) sysfs_preempt_timeout (idempotent)
[298.780545] Starting subtest: idempotent
[298.782854] Starting dynamic subtest: vecs0
[298.785115] Dynamic subtest vecs0: SUCCESS (0.000s)
[298.796142] Starting dynamic subtest: ccs0
[298.798283] Dynamic subtest ccs0: SUCCESS (0.000s)
[298.807827] Starting dynamic subtest: vcs0
[298.810136] Dynamic subtest vcs0: SUCCESS (0.000s)
[298.819808] Starting dynamic subtest: bcs0
[298.822006] Dynamic subtest bcs0: SUCCESS (0.000s)
[298.831187] Starting dynamic subtest: rcs0
[298.833028] Dynamic subtest rcs0: SUCCESS (0.000s)
[298.842525] Starting dynamic subtest: vcs1
[298.844721] Dynamic subtest vcs1: SUCCESS (0.000s)
[298.846972] Subtest idempotent: SUCCESS (0.006s)
[298.906176] [059/133] (724s left) dmabuf_sync_file (import-multiple-read-write)
[298.948374] Starting subtest: import-multiple-read-write
[298.950706] Subtest import-multiple-read-write: SUCCESS (0.003s)
[299.017476] [060/133] (724s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[299.121551] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[303.579329] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.463s)
[304.706005] [061/133] (718s left) kms_flip (wf_vblank-ts-check-interruptible)
[304.793208] Starting subtest: wf_vblank-ts-check-interruptible
[304.795549] Starting dynamic subtest: A-eDP1
[313.679439] Dynamic subtest A-eDP1: SUCCESS (8.889s)
[313.692048] Starting dynamic subtest: B-eDP1
[322.580117] Dynamic subtest B-eDP1: SUCCESS (8.903s)
[322.591329] Starting dynamic subtest: C-eDP1
[331.465307] Dynamic subtest C-eDP1: SUCCESS (8.882s)
[331.477664] Starting dynamic subtest: D-eDP1
[340.377399] Dynamic subtest D-eDP1: SUCCESS (8.903s)
[340.382387] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.588s)
[341.582304] [062/133] (681s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[341.715647] Starting subtest: cursorA-vs-flipA-varying-size
[352.871812] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.151s)
[353.683306] [063/133] (669s left) kms_cursor_crc (cursor-sliding-32x10)
[353.853052] Starting subtest: cursor-sliding-32x10
[353.900208] Subtest cursor-sliding-32x10: SKIP (0.041s)
[354.187456] [064/133] (669s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[354.251630] Starting subtest: multi-wait-all-for-submit-unsubmitted
[354.357491] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[354.457675] [065/133] (668s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[354.547994] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[354.550224] Starting dynamic subtest: pipe-A
[354.611900] Dynamic subtest pipe-A: SUCCESS (0.065s)
[354.623335] Starting dynamic subtest: pipe-B
[355.763447] Dynamic subtest pipe-B: SUCCESS (1.150s)
[355.774597] Starting dynamic subtest: pipe-C
[356.854924] Dynamic subtest pipe-C: SUCCESS (1.090s)
[356.865337] Starting dynamic subtest: pipe-D
[357.946142] Dynamic subtest pipe-D: SUCCESS (1.090s)
[357.948439] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.399s)
[359.090570] [066/133] (664s left) i915_pm_rpm (system-suspend-devices)
[360.613918] Starting subtest: system-suspend-devices
[368.295806] Subtest system-suspend-devices: SUCCESS (7.685s)
[368.683214] [067/133] (654s left) gem_eio (in-flight-contexts-10ms)
[368.914204] Starting subtest: in-flight-contexts-10ms
[380.318047] Subtest in-flight-contexts-10ms: SUCCESS (11.405s)
[380.513105] [068/133] (642s left) kms_force_connector_basic (force-edid)
[380.590808] Starting subtest: force-edid
[380.997351] Subtest force-edid: SUCCESS (0.410s)
[381.153837] [069/133] (642s left) kms_pm_rpm (legacy-planes-dpms)
[383.477386] Starting subtest: legacy-planes-dpms
[383.479841] Starting dynamic subtest: plane-64
[391.769667] Dynamic subtest plane-64: SUCCESS (8.295s)
[391.782296] Starting dynamic subtest: plane-94
[400.413659] Dynamic subtest plane-94: SUCCESS (8.642s)
[400.426269] Starting dynamic subtest: plane-124
[409.019900] Dynamic subtest plane-124: SUCCESS (8.605s)
[409.035309] Starting dynamic subtest: plane-134
[417.420700] Dynamic subtest plane-134: SUCCESS (8.399s)
[417.423383] Subtest legacy-planes-dpms: SUCCESS (33.954s)
[417.830865] [070/133] (605s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[417.934885] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[417.939250] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[418.053103] [071/133] (605s left) gem_exec_params (rel-constants-invalid)
[418.128476] Starting subtest: rel-constants-invalid
[418.130843] Subtest rel-constants-invalid: SUCCESS (0.000s)
[418.236142] [072/133] (605s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[418.297555] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[418.299454] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[418.418361] [073/133] (605s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[418.523251] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[425.024457] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.504s)
[425.173427] [074/133] (598s left) i915_drm_fdinfo (memory-info-active)
[425.218707] Starting subtest: memory-info-active
[425.221113] Starting dynamic subtest: smem0
[427.589335] Dynamic subtest smem0: SUCCESS (2.372s)
[427.591441] Subtest memory-info-active: SUCCESS (2.374s)
[427.682892] [075/133] (595s left) gem_exec_params (batch-first)
[427.754337] Starting subtest: batch-first
[427.763384] Subtest batch-first: SUCCESS (0.009s)
[427.928416] [076/133] (595s left) kms_big_fb (linear-64bpp-rotate-90)
[428.037994] Starting subtest: linear-64bpp-rotate-90
[428.057472] Subtest linear-64bpp-rotate-90: SKIP (0.019s)
[428.204973] [077/133] (595s left) gem_softpin (evict-prime)
[428.280507] Starting subtest: evict-prime
[428.282356] Starting dynamic subtest: rcs0
[429.481182] Dynamic subtest rcs0: SUCCESS (1.202s)
[429.492402] Starting dynamic subtest: bcs0
[430.637357] Dynamic subtest bcs0: SUCCESS (1.154s)
[430.648141] Starting dynamic subtest: vcs0
[432.287870] Dynamic subtest vcs0: SUCCESS (1.647s)
[432.301086] Starting dynamic subtest: vcs1
[434.277525] Dynamic subtest vcs1: SUCCESS (1.988s)
[434.290959] Starting dynamic subtest: vecs0
[436.254512] Dynamic subtest vecs0: SUCCESS (1.975s)
[436.267532] Starting dynamic subtest: ccs0
[437.417363] Dynamic subtest ccs0: SUCCESS (1.161s)
[437.429934] Starting dynamic subtest: all
[439.246623] Dynamic subtest all: SUCCESS (1.826s)
[439.249587] Subtest evict-prime: SUCCESS (10.970s)
[439.359482] [078/133] (584s left) drm_read (short-buffer-block)
[439.508959] Starting subtest: short-buffer-block
[439.510870] Subtest short-buffer-block: SUCCESS (0.000s)
[439.678578] [079/133] (584s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[439.780354] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[439.782819] Starting dynamic subtest: pipe-A-eDP-1
[439.805292] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[439.821306] Starting dynamic subtest: pipe-B-eDP-1
[439.843140] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[439.854487] Starting dynamic subtest: pipe-C-eDP-1
[439.876121] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[439.886131] Starting dynamic subtest: pipe-D-eDP-1
[439.906733] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[439.910816] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[442.065849] [080/133] (581s left) kms_psr (fbc-psr-cursor-blt)
[442.173320] Starting subtest: fbc-psr-cursor-blt
[442.175937] Starting dynamic subtest: eDP-1
[442.180835] Dynamic subtest eDP-1: SKIP (0.000s)
[442.185395] Subtest fbc-psr-cursor-blt: SKIP (0.001s)
[442.268826] [081/133] (581s left) kms_vblank (query-forked-busy-hang)
[442.368987] Starting subtest: query-forked-busy-hang
[442.371135] Starting dynamic subtest: pipe-A-eDP-1
[469.056777] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.691s)
[469.073508] Starting dynamic subtest: pipe-D-eDP-1
[494.038293] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.986s)
[494.040694] Subtest query-forked-busy-hang: SUCCESS (51.680s)
[494.853412] [082/133] (529s left) gem_exec_schedule (deep)
[495.043115] Starting subtest: deep
[495.045537] Starting dynamic subtest: rcs0
[500.451806] Dynamic subtest rcs0: SKIP (5.407s)
[500.464247] Starting dynamic subtest: vcs0
[511.411575] Dynamic subtest vcs0: SUCCESS (10.963s)
[511.422339] Starting dynamic subtest: vcs1
[523.361961] Dynamic subtest vcs1: SUCCESS (11.952s)
[523.374585] Starting dynamic subtest: vecs0
[535.406508] Dynamic subtest vecs0: SUCCESS (12.046s)
[535.424267] Starting dynamic subtest: ccs0
[548.367484] Dynamic subtest ccs0: SUCCESS (12.963s)
[548.370648] Subtest deep: SUCCESS (53.335s)
[549.959391] [083/133] (474s left) gem_ctx_param (vm)
[550.015951] Starting subtest: vm
[550.087152] Subtest vm: SUCCESS (0.071s)
[550.244142] [084/133] (473s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[550.343717] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[550.347640] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[550.439793] [085/133] (473s left) gem_ctx_persistence (legacy-engines-cleanup)
[550.503634] Starting subtest: legacy-engines-cleanup
[550.506120] Starting dynamic subtest: render
[550.608299] Dynamic subtest render: SUCCESS (0.104s)
[550.629998] Starting dynamic subtest: bsd1
[550.674686] Dynamic subtest bsd1: SUCCESS (0.064s)
[550.689810] Starting dynamic subtest: bsd2
[550.707562] Dynamic subtest bsd2: SUCCESS (0.030s)
[550.719018] Starting dynamic subtest: blt
[550.775474] Dynamic subtest blt: SUCCESS (0.065s)
[550.786662] Starting dynamic subtest: vebox
[550.865020] Dynamic subtest vebox: SUCCESS (0.087s)
[550.867957] Subtest legacy-engines-cleanup: SUCCESS (0.363s)
[550.967218] [086/133] (473s left) gem_exec_params (rsvd2-dirt)
[551.026053] Starting subtest: rsvd2-dirt
[551.029795] Subtest rsvd2-dirt: SKIP (0.000s)
[551.140561] [087/133] (473s left) kms_psr (psr2-basic)
[551.241291] Starting subtest: psr2-basic
[551.243195] Starting dynamic subtest: eDP-1
[552.857663] Dynamic subtest eDP-1: SUCCESS (1.616s)
[552.859888] Subtest psr2-basic: SUCCESS (1.617s)
[553.733249] [088/133] (470s left) api_intel_bb (purge-bb)
[553.798368] Starting subtest: purge-bb
[553.800574] Subtest purge-bb: SUCCESS (0.004s)
[553.916525] [089/133] (470s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[554.016451] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[554.020786] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[554.149445] [090/133] (470s left) kms_color (invalid-ctm-matrix-sizes)
[554.236724] Starting subtest: invalid-ctm-matrix-sizes
[554.239055] Starting dynamic subtest: pipe-A
[554.241418] Dynamic subtest pipe-A: SUCCESS (0.002s)
[554.252250] Starting dynamic subtest: pipe-B
[554.254169] Dynamic subtest pipe-B: SUCCESS (0.003s)
[554.263583] Starting dynamic subtest: pipe-C
[554.265817] Dynamic subtest pipe-C: SUCCESS (0.003s)
[554.274871] Starting dynamic subtest: pipe-D
[554.276823] Dynamic subtest pipe-D: SUCCESS (0.004s)
[554.278806] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.014s)
[554.332496] [091/133] (469s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[554.431654] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[561.592882] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.165s)
[561.772869] [092/133] (462s left) template (a)
[561.834566] Starting subtest: A
[561.836918] Subtest A: SUCCESS (0.000s)
[561.972217] [093/133] (462s left) kms_plane (pixel-format-y-tiled-modifier)
[562.066154] Starting subtest: pixel-format-y-tiled-modifier
[562.070237] Subtest pixel-format-y-tiled-modifier: SKIP (0.000s)
[562.176718] [094/133] (462s left) gem_exec_schedule (u-lateslice)
[562.242330] Starting subtest: u-lateslice
[562.244721] Starting dynamic subtest: rcs0
[562.253734] Dynamic subtest rcs0: SUCCESS (0.021s)
[562.263763] Starting dynamic subtest: bcs0
[562.272755] Dynamic subtest bcs0: SUCCESS (0.018s)
[562.281861] Starting dynamic subtest: vcs0
[562.289871] Dynamic subtest vcs0: SUCCESS (0.016s)
[562.299229] Starting dynamic subtest: vcs1
[562.318661] Dynamic subtest vcs1: SUCCESS (0.027s)
[562.327818] Starting dynamic subtest: vecs0
[562.337385] Dynamic subtest vecs0: SUCCESS (0.018s)
[562.347135] Starting dynamic subtest: ccs0
[562.356268] Dynamic subtest ccs0: SUCCESS (0.018s)
[562.358522] Subtest u-lateslice: SUCCESS (0.124s)
[564.412074] [095/133] (460s left) gem_readwrite (write-bad-handle)
[564.472448] Subtest write-bad-handle: SKIP (0.000s)
[564.532961] [096/133] (460s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[564.639107] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[564.643121] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[564.748419] [097/133] (459s left) gem_exec_schedule (implicit-write-read)
[564.836282] Starting subtest: implicit-write-read
[564.838160] Starting dynamic subtest: rcs0
[564.935989] Dynamic subtest rcs0: SUCCESS (0.109s)
[564.947415] Starting dynamic subtest: vcs0
[565.014226] Dynamic subtest vcs0: SUCCESS (0.077s)
[565.026679] Starting dynamic subtest: vcs1
[565.088826] Dynamic subtest vcs1: SUCCESS (0.074s)
[565.101026] Starting dynamic subtest: vecs0
[565.164131] Dynamic subtest vecs0: SUCCESS (0.075s)
[565.177418] Starting dynamic subtest: ccs0
[565.255918] Dynamic subtest ccs0: SUCCESS (0.091s)
[565.258571] Subtest implicit-write-read: SUCCESS (0.430s)
[566.393499] [098/133] (458s left) gem_exec_reloc (basic-cpu-read-noreloc)
[566.461818] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[566.550446] [099/133] (458s left) kms_pm_dc (dc9-dpms)
[567.645594] Starting subtest: dc9-dpms
[569.048228] Subtest dc9-dpms: SUCCESS (1.402s)
[569.123881] [100/133] (455s left) gem_ctx_engines (execute-oneforall)
[569.192854] Starting subtest: execute-oneforall
[569.841494] Subtest execute-oneforall: SUCCESS (0.647s)
[571.402657] [101/133] (453s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[571.784079] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[572.597473] [102/133] (452s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[572.657129] Subtest writes-after-reads: SKIP (0.000s)
[572.736743] [103/133] (452s left) kms_content_protection (lic-type-1)
[572.876116] Starting subtest: lic-type-1
[572.880369] Subtest lic-type-1: SKIP (0.000s)
[573.027911] [104/133] (451s left) kms_plane_multiple (tiling-y)
[573.130038] Starting subtest: tiling-y
[573.134028] Subtest tiling-y: SKIP (0.000s)
[573.215694] [105/133] (451s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[573.316227] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[573.319888] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[573.446643] [106/133] (451s left) gem_mmap_offset (close-race)
[573.503502] Starting subtest: close-race
[593.607369] Subtest close-race: SUCCESS (20.109s)
[593.781872] [107/133] (431s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[593.891477] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[593.895987] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[594.013299] [108/133] (431s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[594.107082] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[594.109313] Starting dynamic subtest: pipe-A-eDP-1
[594.133162] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[594.145186] Starting dynamic subtest: pipe-B-eDP-1
[594.167547] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[594.177348] Starting dynamic subtest: pipe-C-eDP-1
[594.200634] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[594.210223] Starting dynamic subtest: pipe-D-eDP-1
[594.233164] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[594.236978] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[596.408437] [109/133] (428s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[596.509507] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[596.513799] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[596.589732] [110/133] (428s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[596.642323] Starting subtest: etime-single-wait-all-for-submit-submitted
[596.749629] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.107s)
[596.848946] [111/133] (428s left) gem_userptr_blits (set-cache-level)
[596.912046] Starting subtest: set-cache-level
[596.916418] Subtest set-cache-level: SKIP (0.000s)
[597.070271] [112/133] (428s left) gem_exec_balancer (parallel-ordering)
[597.335698] Starting subtest: parallel-ordering
[598.248146] Subtest parallel-ordering: SUCCESS (0.913s)
[598.424146] [113/133] (426s left) gem_userptr_blits (input-checking)
[598.496960] Starting subtest: input-checking
[598.499255] Subtest input-checking: SUCCESS (0.000s)
[598.646381] [114/133] (426s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[598.739849] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[598.742205] Starting dynamic subtest: pipe-A-eDP-1
[598.834885] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[598.846764] Starting dynamic subtest: pipe-B-eDP-1
[599.964511] Dynamic subtest pipe-B-eDP-1: SKIP (1.128s)
[599.969018] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.229s)
[601.211961] [115/133] (424s left) kms_dp_linktrain_fallback (dp-fallback)
[601.303165] Starting subtest: dp-fallback
[601.309715] Subtest dp-fallback: SKIP (0.000s)
[601.385207] [116/133] (424s left) gem_set_tiling_vs_pwrite
[601.514701] [117/133] (423s left) gem_exec_whisper (basic-fds-priority-all)
[601.565492] Starting subtest: basic-fds-priority-all
[625.374789] Subtest basic-fds-priority-all: SUCCESS (23.812s)
[625.567562] [118/133] (399s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[625.641894] Starting subtest: rel-constants-invalid-rel-gen5
[625.644268] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[625.790069] [119/133] (399s left) gem_ctx_shared (q-out-order)
[625.866529] Starting subtest: Q-out-order
[625.868727] Starting dynamic subtest: rcs0
[626.056237] Dynamic subtest rcs0: SUCCESS (0.191s)
[626.069935] Starting dynamic subtest: vcs0
[626.196600] Dynamic subtest vcs0: SUCCESS (0.139s)
[626.211602] Starting dynamic subtest: vcs1
[626.362135] Dynamic subtest vcs1: SUCCESS (0.163s)
[626.381248] Starting dynamic subtest: vecs0
[626.508296] Dynamic subtest vecs0: SUCCESS (0.145s)
[626.518818] Starting dynamic subtest: ccs0
[626.646468] Dynamic subtest ccs0: SUCCESS (0.136s)
[626.649359] Subtest Q-out-order: SUCCESS (0.783s)
[627.403643] [120/133] (398s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[627.512034] Starting subtest: yf-tiled-64bpp-rotate-0
[627.516454] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[627.654519] [121/133] (397s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[627.753934] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[627.756139] Starting dynamic subtest: pipe-A-default-mode
[630.451104] Dynamic subtest pipe-A-default-mode: SUCCESS (2.698s)
[630.453478] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.699s)
[631.308278] [122/133] (394s left) kms_atomic_transition (plane-all-transition-fencing)
[631.409880] Starting subtest: plane-all-transition-fencing
[631.412126] Starting dynamic subtest: pipe-A-eDP-1
[633.909305] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.501s)
[634.217107] Starting dynamic subtest: pipe-B-eDP-1
[637.487033] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.279s)
[637.784679] Subtest plane-all-transition-fencing: SUCCESS (6.375s)
[638.597282] [123/133] (387s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[638.714947] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[639.715723] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.002s)
[639.904987] [124/133] (385s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[640.010637] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[640.014047] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[640.150171] [125/133] (385s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[640.248258] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[640.251592] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[640.375035] [126/133] (385s left) kms_cursor_crc (cursor-offscreen-512x512)
[640.648609] Starting subtest: cursor-offscreen-512x512
[640.652178] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[640.819020] [127/133] (385s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[640.921379] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[640.924832] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[641.046206] [128/133] (384s left) perf_pmu (rc6-suspend)
[641.091917] Starting subtest: rc6-suspend
[664.218346] Subtest rc6-suspend: SUCCESS (7.638s)
[665.065172] [129/133] (360s left) kms_psr (fbc-pr-cursor-render)
[665.176790] Starting subtest: fbc-pr-cursor-render
[665.180817] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[665.258674] [130/133] (360s left) drm_read (fault-buffer)
[665.407327] Starting subtest: fault-buffer
[665.409335] Subtest fault-buffer: SUCCESS (0.001s)
[665.559390] [131/133] (360s left) gem_render_copy (linear-to-vebox-y-tiled)
[665.609608] Starting subtest: linear-to-vebox-y-tiled
[665.613278] Subtest linear-to-vebox-y-tiled: SKIP (0.000s)
[665.801304] [132/133] (360s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[665.898949] Starting subtest: 4-tiled-32bpp-rotate-180
[667.178291] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.301s)
[667.343182] [133/133] (358s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[667.449245] Starting subtest: psr-rgb565-draw-mmap-cpu
[674.253638] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.878s)
[674.374336] Closing watchdogs
results: parsing output: 0/ for test: kms_feature_discovery
results: parsing output: 1/ for test: gem_render_copy_redux
results: parsing output: 2/ for test: i915_pm_sseu
results: parsing output: 3/ for test: gem_exec_fence
results: parsing output: 4/ for test: gem_render_copy
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_cursor_crc
results: parsing output: 7/ for test: kms_hdr
results: parsing output: 8/ for test: kms_ccs
results: parsing output: 9/ for test: kms_psr
results: parsing output: 10/ for test: kms_cursor_legacy
results: parsing output: 11/ for test: kms_plane_alpha_blend
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_addfb_basic
results: parsing output: 14/ for test: i915_hangman
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: api_intel_allocator
results: parsing output: 17/ for test: kms_ccs
results: parsing output: 18/ for test: kms_plane
results: parsing output: 19/ for test: kms_flip
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: gem_eio
results: parsing output: 22/ for test: gem_exec_balancer
results: parsing output: 23/ for test: kms_invalid_mode
results: parsing output: 24/ for test: fbdev
results: parsing output: 25/ for test: gen9_exec_parse
results: parsing output: 26/ for test: syncobj_timeline
results: parsing output: 27/ for test: kms_atomic
results: parsing output: 28/ for test: sriov_basic
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: prime_mmap
results: parsing output: 31/ for test: gem_mmap_wc
results: parsing output: 32/ for test: kms_psr_stress_test
results: parsing output: 33/ for test: kms_addfb_basic
results: parsing output: 34/ for test: kms_properties
results: parsing output: 35/ for test: kms_chamelium_edid
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: gem_lmem_swapping
results: parsing output: 39/ for test: gem_workarounds
results: parsing output: 40/ for test: kms_rotation_crc
results: parsing output: 41/ for test: gem_fence_thrash
results: parsing output: 42/ for test: kms_ccs
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: syncobj_wait
results: parsing output: 46/ for test: gem_mmap_wc
results: parsing output: 47/ for test: syncobj_timeline
results: parsing output: 48/ for test: kms_force_connector_basic
results: parsing output: 49/ for test: perf
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_exec_parallel
results: parsing output: 52/ for test: kms_cursor_legacy
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_sequence
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: gem_exec_whisper
results: parsing output: 57/ for test: sysfs_preempt_timeout
results: parsing output: 58/ for test: dmabuf_sync_file
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: kms_cursor_legacy
results: parsing output: 62/ for test: kms_cursor_crc
results: parsing output: 63/ for test: syncobj_wait
results: parsing output: 64/ for test: kms_plane_scaling
results: parsing output: 65/ for test: i915_pm_rpm
results: parsing output: 66/ for test: gem_eio
results: parsing output: 67/ for test: kms_force_connector_basic
results: parsing output: 68/ for test: kms_pm_rpm
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: gem_exec_params
results: parsing output: 71/ for test: syncobj_timeline
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: i915_drm_fdinfo
results: parsing output: 74/ for test: gem_exec_params
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: gem_softpin
results: parsing output: 77/ for test: drm_read
results: parsing output: 78/ for test: kms_ccs
results: parsing output: 79/ for test: kms_psr
results: parsing output: 80/ for test: kms_vblank
results: parsing output: 81/ for test: gem_exec_schedule
results: parsing output: 82/ for test: gem_ctx_param
results: parsing output: 83/ for test: kms_flip_scaled_crc
results: parsing output: 84/ for test: gem_ctx_persistence
results: parsing output: 85/ for test: gem_exec_params
results: parsing output: 86/ for test: kms_psr
results: parsing output: 87/ for test: api_intel_bb
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_color
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: template
results: parsing output: 92/ for test: kms_plane
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: gem_readwrite
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_exec_schedule
results: parsing output: 97/ for test: gem_exec_reloc
results: parsing output: 98/ for test: kms_pm_dc
results: parsing output: 99/ for test: gem_ctx_engines
results: parsing output: 100/ for test: kms_chamelium_hpd
results: parsing output: 101/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 102/ for test: kms_content_protection
results: parsing output: 103/ for test: kms_plane_multiple
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_mmap_offset
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: kms_joiner
results: parsing output: 109/ for test: syncobj_timeline
results: parsing output: 110/ for test: gem_userptr_blits
results: parsing output: 111/ for test: gem_exec_balancer
results: parsing output: 112/ for test: gem_userptr_blits
results: parsing output: 113/ for test: kms_psr2_sf
results: parsing output: 114/ for test: kms_dp_linktrain_fallback
results: parsing output: 115/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 116/ for test: gem_exec_whisper
results: parsing output: 117/ for test: gem_exec_params
results: parsing output: 118/ for test: gem_ctx_shared
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_flip_scaled_crc
results: parsing output: 121/ for test: kms_atomic_transition
results: parsing output: 122/ for test: kms_big_fb
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_cursor_crc
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: perf_pmu
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: drm_read
results: parsing output: 130/ for test: gem_render_copy
results: parsing output: 131/ for test: kms_big_fb
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.