[56.795047] Initializing watchdogs
[56.795094]   /dev/watchdog0
[56.809417] [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]
[56.813726] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.816262] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.819720] [001/156] (960s left) kms_ccs (bad-rotation-90-y-tiled-ccs)
[56.935537] Starting subtest: bad-rotation-90-y-tiled-ccs
[56.937769] Starting dynamic subtest: pipe-A-eDP-1
[56.959808] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[56.968880] Starting dynamic subtest: pipe-B-eDP-1
[56.989294] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[56.996566] Starting dynamic subtest: pipe-C-eDP-1
[57.018142] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[57.025041] Starting dynamic subtest: pipe-D-eDP-1
[57.046581] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[57.050929] Subtest bad-rotation-90-y-tiled-ccs: SKIP (0.003s)
[59.267328] [002/156] (958s left) kms_addfb_basic (invalid-get-prop)
[59.325679] Starting subtest: invalid-get-prop
[59.328190] Subtest invalid-get-prop: SUCCESS (0.000s)
[59.477071] [003/156] (957s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[59.583523] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[59.588065] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.002s)
[59.689682] [004/156] (957s left) i915_hangman (error-state-basic)
[59.752101] Starting subtest: error-state-basic
[59.816788] Subtest error-state-basic: SUCCESS (0.064s)
[59.893213] [005/156] (957s left) gem_exec_params (batch-first)
[59.958123] Starting subtest: batch-first
[59.965468] Subtest batch-first: SUCCESS (0.007s)
[60.099496] [006/156] (957s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[60.203831] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[60.207562] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[60.301025] [007/156] (957s left) kms_frontbuffer_tracking (psrhdr-indfb-scaledprimary)
[60.396318] Starting subtest: psrhdr-indfb-scaledprimary
[60.399835] Subtest psrhdr-indfb-scaledprimary: SKIP (0.000s)
[60.487730] [008/156] (957s left) gem_fenced_exec_thrash (no-spare-fences-interruptible)
[60.548230] Subtest no-spare-fences-interruptible: SKIP (0.000s)
[60.610650] [009/156] (956s left) kms_psr (psr2-primary-mmap-gtt)
[60.700990] Starting subtest: psr2-primary-mmap-gtt
[60.702963] Starting dynamic subtest: eDP-1
[62.175243] Dynamic subtest eDP-1: SKIP (1.466s)
[62.180050] Subtest psr2-primary-mmap-gtt: SKIP (1.467s)
[62.315189] [010/156] (955s left) gem_ctx_persistence (legacy-engines-persistence)
[62.371511] Starting subtest: legacy-engines-persistence
[62.373768] Starting dynamic subtest: render
[63.166446] Dynamic subtest render: SUCCESS (0.791s)
[63.177075] Starting dynamic subtest: bsd1
[63.929377] Dynamic subtest bsd1: SUCCESS (0.760s)
[63.939114] Starting dynamic subtest: bsd2
[64.715401] Dynamic subtest bsd2: SUCCESS (0.783s)
[64.725885] Starting dynamic subtest: blt
[65.470262] Dynamic subtest blt: SUCCESS (0.752s)
[65.480530] Starting dynamic subtest: vebox
[66.215101] Dynamic subtest vebox: SUCCESS (0.742s)
[66.217765] Subtest legacy-engines-persistence: SUCCESS (3.835s)
[66.352602] [011/156] (951s left) kms_chamelium_hpd (vga-hpd)
[66.742973] Subtest vga-hpd: SKIP (0.000s)
[67.526273] [012/156] (950s left) kms_invalid_mode (zero-clock)
[67.618913] Starting subtest: zero-clock
[67.621242] Starting dynamic subtest: pipe-A-eDP-1
[67.902699] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[67.912619] Starting dynamic subtest: pipe-B-eDP-1
[67.914961] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[67.922461] Starting dynamic subtest: pipe-C-eDP-1
[67.924807] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[67.932204] Starting dynamic subtest: pipe-D-eDP-1
[67.934370] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[67.936515] Subtest zero-clock: SUCCESS (0.290s)
[68.768760] [013/156] (948s left) kms_pm_dc (dc5-retention-flops)
[69.868813] Starting subtest: dc5-retention-flops
[69.873447] Subtest dc5-retention-flops: SKIP (0.000s)
[69.935018] [014/156] (947s left) prime_vgem (basic-fence-flip)
[70.035048] Starting subtest: basic-fence-flip
[70.325373] Subtest basic-fence-flip: SUCCESS (0.290s)
[70.427469] [FACT prime_vgem (basic-fence-flip)] new: kernel.kmod_is_loaded.vgem: true
[70.431131] [015/156] (947s left) kms_frontbuffer_tracking (psrhdr-2p-pri-indfb-multidraw)
[70.532085] Starting subtest: psrhdr-2p-pri-indfb-multidraw
[70.536293] Subtest psrhdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[70.616776] [016/156] (947s left) gem_pxp (protected-raw-src-copy-not-readible)
[70.732977] Starting subtest: protected-raw-src-copy-not-readible
[70.790102] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.060s)
[70.912584] [017/156] (947s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[71.041167] Starting subtest: modeset-atomic-cursor-hotspot
[71.045777] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[71.186459] [018/156] (946s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[71.284330] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[71.288380] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[71.388374] [019/156] (946s left) kms_psr (psr2-dpms)
[71.477137] Starting subtest: psr2-dpms
[71.479167] Starting dynamic subtest: eDP-1
[74.498957] Dynamic subtest eDP-1: SUCCESS (3.020s)
[74.501495] Subtest psr2-dpms: SUCCESS (3.021s)
[75.379237] [020/156] (942s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[75.474504] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[75.479743] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[76.600655] [021/156] (941s left) gem_exec_schedule (u-submit-golden-slice)
[76.673670] Starting subtest: u-submit-golden-slice
[76.676075] Starting dynamic subtest: rcs0
[76.698810] Dynamic subtest rcs0: SUCCESS (0.034s)
[76.710055] Starting dynamic subtest: bcs0
[76.724893] Dynamic subtest bcs0: SUCCESS (0.026s)
[76.733102] Starting dynamic subtest: vcs0
[76.760078] Dynamic subtest vcs0: SUCCESS (0.035s)
[76.769675] Starting dynamic subtest: vcs1
[76.790938] Dynamic subtest vcs1: SUCCESS (0.031s)
[76.799141] Starting dynamic subtest: vecs0
[76.819534] Dynamic subtest vecs0: SUCCESS (0.028s)
[76.827898] Starting dynamic subtest: ccs0
[76.851736] Dynamic subtest ccs0: SUCCESS (0.032s)
[76.854096] Subtest u-submit-golden-slice: SUCCESS (0.187s)
[78.364530] [022/156] (939s left) gem_userptr_blits (dmabuf-sync)
[78.433329] Starting subtest: dmabuf-sync
[78.437856] Subtest dmabuf-sync: SKIP (0.002s)
[78.604774] [023/156] (939s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-blt)
[78.697629] Starting subtest: fbchdr-rgb565-draw-blt
[78.702060] Subtest fbchdr-rgb565-draw-blt: SKIP (0.000s)
[78.804566] [024/156] (939s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[79.333576] Starting subtest: syncobj-timeline-invalid-flags
[79.336645] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[80.378462] [025/156] (937s left) kms_vblank (ts-continuation-idle-hang)
[80.478444] Starting subtest: ts-continuation-idle-hang
[80.480555] Starting dynamic subtest: pipe-A-eDP-1
[108.031749] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.550s)
[108.045112] Starting dynamic subtest: pipe-D-eDP-1
[123.084257] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.047s)
[123.086507] Subtest ts-continuation-idle-hang: SUCCESS (42.600s)
[123.957231] [026/156] (894s left) syncobj_wait (single-wait-signaled)
[124.027655] Starting subtest: single-wait-signaled
[124.030043] Subtest single-wait-signaled: SUCCESS (0.000s)
[124.128343] [027/156] (894s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[124.227295] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[124.253473] Starting dynamic subtest: pipe-A-plane-0
[124.642436] Dynamic subtest pipe-A-plane-0: SUCCESS (0.389s)
[124.651807] Starting dynamic subtest: pipe-A-plane-3
[124.975106] Dynamic subtest pipe-A-plane-3: SUCCESS (0.333s)
[126.036841] Starting dynamic subtest: pipe-B-plane-0
[126.417723] Dynamic subtest pipe-B-plane-0: SUCCESS (0.389s)
[126.427391] Starting dynamic subtest: pipe-B-plane-3
[126.750719] Dynamic subtest pipe-B-plane-3: SUCCESS (0.333s)
[127.055286] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (2.828s)
[127.909365] [028/156] (890s left) gem_ctx_shared (q-independent)
[127.974803] Starting subtest: Q-independent
[127.977079] Starting dynamic subtest: rcs0
[128.041199] Dynamic subtest rcs0: SUCCESS (0.068s)
[128.052633] Starting dynamic subtest: vcs0
[128.085406] Dynamic subtest vcs0: SUCCESS (0.045s)
[128.093710] Starting dynamic subtest: vcs1
[128.127377] Dynamic subtest vcs1: SUCCESS (0.042s)
[128.136129] Starting dynamic subtest: vecs0
[128.167849] Dynamic subtest vecs0: SUCCESS (0.040s)
[128.177790] Starting dynamic subtest: ccs0
[128.210610] Dynamic subtest ccs0: SUCCESS (0.042s)
[128.212988] Subtest Q-independent: SUCCESS (0.238s)
[129.384161] [029/156] (889s left) perf_pmu (invalid-open)
[129.423464] Starting subtest: invalid-open
[129.425768] Subtest invalid-open: SUCCESS (0.000s)
[129.548041] [030/156] (888s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[129.654882] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[129.667605] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[129.749202] [031/156] (888s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[129.845367] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[129.873125] Starting dynamic subtest: pipe-A-plane-0
[130.272751] Dynamic subtest pipe-A-plane-0: SUCCESS (0.400s)
[130.281507] Starting dynamic subtest: pipe-A-plane-3
[130.606142] Dynamic subtest pipe-A-plane-3: SUCCESS (0.333s)
[131.704164] Starting dynamic subtest: pipe-B-plane-0
[132.085719] Dynamic subtest pipe-B-plane-0: SUCCESS (0.389s)
[132.094043] Starting dynamic subtest: pipe-B-plane-3
[132.419162] Dynamic subtest pipe-B-plane-3: SUCCESS (0.333s)
[132.733646] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (2.888s)
[133.573261] [032/156] (884s left) perf_pmu (busy-no-semaphores)
[133.610778] Starting subtest: busy-no-semaphores
[133.612864] Starting dynamic subtest: rcs0
[134.181556] Dynamic subtest rcs0: SUCCESS (0.568s)
[134.191996] Starting dynamic subtest: bcs0
[134.732775] Dynamic subtest bcs0: SUCCESS (0.549s)
[134.743394] Starting dynamic subtest: vcs0
[135.282902] Dynamic subtest vcs0: SUCCESS (0.548s)
[135.293920] Starting dynamic subtest: vcs1
[136.839735] Dynamic subtest vcs1: SUCCESS (1.555s)
[136.849671] Starting dynamic subtest: vecs0
[138.823065] Dynamic subtest vecs0: SUCCESS (1.981s)
[138.835267] Starting dynamic subtest: ccs0
[140.804380] Dynamic subtest ccs0: SUCCESS (1.980s)
[140.807415] Subtest busy-no-semaphores: SUCCESS (7.192s)
[140.924274] [033/156] (877s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-blt)
[141.037559] Starting subtest: fbcpsrhdr-rgb101010-draw-blt
[141.041703] Subtest fbcpsrhdr-rgb101010-draw-blt: SKIP (0.000s)
[141.152831] [034/156] (877s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[141.254264] Starting subtest: primary-4-tiled-reflect-x-0
[148.210484] Subtest primary-4-tiled-reflect-x-0: SUCCESS (6.957s)
[149.423304] [035/156] (869s left) gem_ctx_engines (invalid-engines)
[149.481134] Starting subtest: invalid-engines
[149.489123] Subtest invalid-engines: SUCCESS (0.008s)
[149.713221] [036/156] (869s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[149.803179] Starting subtest: pr-plane-move-sf-dmg-area
[149.807851] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[149.884862] [037/156] (868s left) kms_addfb_basic (unused-offsets)
[149.945255] Starting subtest: unused-offsets
[149.947224] Subtest unused-offsets: SUCCESS (0.000s)
[150.086770] [038/156] (868s left) gem_ctx_param (invalid-set-no-zeromap)
[150.137695] Starting subtest: invalid-set-no-zeromap
[150.140175] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[150.211394] [039/156] (868s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[150.315420] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[150.319326] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[150.397507] [040/156] (868s left) kms_rotation_crc (primary-rotation-90)
[150.483421] Starting subtest: primary-rotation-90
[150.488380] Subtest primary-rotation-90: SKIP (0.001s)
[150.567624] [041/156] (868s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled)
[150.627825] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled
[150.631464] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[150.817607] [042/156] (868s left) kms_pm_rpm (pm-caching)
[152.466233] Starting subtest: pm-caching
[152.471292] Subtest pm-caching: SKIP (0.000s)
[153.159112] [043/156] (865s left) kms_legacy_colorkey (basic)
[153.247176] Starting subtest: basic
[153.249066] Starting dynamic subtest: pipe-A
[153.290544] Dynamic subtest pipe-A: SUCCESS (0.045s)
[153.302086] Starting dynamic subtest: pipe-B
[153.304535] Dynamic subtest pipe-B: SUCCESS (0.001s)
[153.312724] Starting dynamic subtest: pipe-C
[153.315067] Dynamic subtest pipe-C: SUCCESS (0.000s)
[153.323490] Starting dynamic subtest: pipe-D
[153.325622] Dynamic subtest pipe-D: SUCCESS (0.001s)
[153.327728] Subtest basic: SUCCESS (0.050s)
[153.364874] [044/156] (865s left) kms_psr (fbc-psr-no-drrs)
[153.455857] Starting subtest: fbc-psr-no-drrs
[153.458297] Starting dynamic subtest: eDP-1
[153.462576] Dynamic subtest eDP-1: SKIP (0.000s)
[153.466816] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[153.528371] [045/156] (865s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-blt)
[153.628277] Starting subtest: hdr-rgb101010-draw-blt
[153.632546] Subtest hdr-rgb101010-draw-blt: SKIP (0.000s)
[153.721418] [046/156] (865s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[153.822907] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[153.827249] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[153.911649] [047/156] (865s left) prime_mmap (test_forked_cpu_write)
[153.972779] Starting subtest: test_forked_cpu_write
[153.975080] Starting dynamic subtest: test_forked_cpu_write-smem
[153.979992] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.008s)
[153.982404] Subtest test_forked_cpu_write: SUCCESS (0.009s)
[154.091803] [048/156] (865s left) kms_content_protection (legacy-hdcp14)
[154.216692] Starting subtest: legacy-hdcp14
[154.220869] Subtest legacy-hdcp14: SKIP (0.000s)
[154.339685] [049/156] (864s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[154.437906] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[154.441661] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[154.529625] [050/156] (864s left) gem_spin_batch (user-each)
[154.600312] Starting subtest: user-each
[157.872736] Subtest user-each: SUCCESS (3.266s)
[158.352704] [051/156] (860s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[158.450680] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[158.455281] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[158.532820] [052/156] (860s left) kms_vblank (accuracy-idle)
[158.624956] Starting subtest: accuracy-idle
[158.626809] Starting dynamic subtest: pipe-A-eDP-1
[160.379035] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.755s)
[160.396697] Starting dynamic subtest: pipe-D-eDP-1
[162.790087] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.407s)
[162.792249] Subtest accuracy-idle: SUCCESS (4.165s)
[163.610352] [053/156] (855s left) gen7_exec_parse (basic-allocation)
[163.669225] Subtest basic-allocation: SKIP (0.000s)
[163.726933] [054/156] (855s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[163.838224] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-gtt
[163.842334] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[163.927798] [055/156] (855s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[164.018709] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[164.023092] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[164.094227] [056/156] (855s left) kms_chamelium_audio (dp-audio-edid)
[164.479859] Subtest dp-audio-edid: SKIP (0.000s)
[165.317705] [057/156] (854s left) gem_eio (wait-immediate)
[165.506687] Starting subtest: wait-immediate
[165.822596] Subtest wait-immediate: SUCCESS (0.317s)
[165.949083] [058/156] (853s left) gem_lmem_swapping (parallel-random)
[165.988782] Subtest parallel-random: SKIP (0.000s)
[166.031460] [059/156] (853s left) gem_exec_reloc (basic-wc-cpu)
[166.086115] Subtest basic-wc-cpu: SKIP (0.000s)
[166.154391] [060/156] (853s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[166.250652] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[166.941765] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.692s)
[167.104756] [061/156] (852s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[167.201869] Starting subtest: primary-yf-tiled-reflect-x-270
[167.206188] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[167.290840] [062/156] (852s left) kms_flip (flip-vs-expired-vblank-interruptible)
[167.383935] Starting subtest: flip-vs-expired-vblank-interruptible
[167.386224] Starting dynamic subtest: A-eDP1
[176.071841] Dynamic subtest A-eDP1: SUCCESS (8.651s)
[176.084058] Starting dynamic subtest: B-eDP1
[184.702529] Dynamic subtest B-eDP1: SUCCESS (8.630s)
[184.714553] Starting dynamic subtest: C-eDP1
[193.334539] Dynamic subtest C-eDP1: SUCCESS (8.620s)
[193.346560] Starting dynamic subtest: D-eDP1
[201.953707] Dynamic subtest D-eDP1: SUCCESS (8.625s)
[201.957933] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.533s)
[203.193238] [063/156] (816s left) gem_partial_pwrite_pread (reads)
[203.252437] Subtest reads: SKIP (0.000s)
[203.316732] [064/156] (816s left) i915_drm_fdinfo (most-busy-check-all)
[203.353414] Starting subtest: most-busy-check-all
[203.355647] Starting dynamic subtest: rcs0
[203.360754] Dynamic subtest rcs0: SKIP (0.004s)
[203.372035] Starting dynamic subtest: bcs0
[203.375978] Dynamic subtest bcs0: SKIP (0.000s)
[203.387956] Starting dynamic subtest: vcs0
[203.392577] Dynamic subtest vcs0: SKIP (0.000s)
[203.401795] Starting dynamic subtest: vcs1
[203.406175] Dynamic subtest vcs1: SKIP (0.000s)
[203.414870] Starting dynamic subtest: vecs0
[203.419598] Dynamic subtest vecs0: SKIP (0.000s)
[203.428057] Starting dynamic subtest: ccs0
[203.432472] Dynamic subtest ccs0: SKIP (0.000s)
[203.436810] Subtest most-busy-check-all: SKIP (0.007s)
[203.473472] [065/156] (816s left) gem_mmap_wc (write-gtt-read-wc)
[203.564609] Subtest write-gtt-read-wc: SKIP (0.000s)
[203.630341] [066/156] (816s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[203.729065] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[203.733645] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[203.852321] [067/156] (816s left) gem_readwrite (read-bad-handle)
[203.914078] Subtest read-bad-handle: SKIP (0.000s)
[203.975304] [068/156] (816s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[204.074610] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[204.078725] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[204.164737] [069/156] (815s left) kms_plane (plane-position-hole-dpms)
[204.248582] Starting subtest: plane-position-hole-dpms
[204.751569] Starting dynamic subtest: pipe-A-plane-1
[207.096450] Dynamic subtest pipe-A-plane-1: SUCCESS (2.345s)
[207.106636] Starting dynamic subtest: pipe-A-plane-2
[209.427495] Dynamic subtest pipe-A-plane-2: SUCCESS (2.328s)
[209.436521] Starting dynamic subtest: pipe-A-plane-3
[211.797475] Dynamic subtest pipe-A-plane-3: SUCCESS (2.369s)
[211.806826] Starting dynamic subtest: pipe-A-plane-4
[214.155798] Dynamic subtest pipe-A-plane-4: SUCCESS (2.360s)
[214.166533] Starting dynamic subtest: pipe-A-plane-5
[214.183446] Dynamic subtest pipe-A-plane-5: SUCCESS (0.025s)
[215.330626] Starting dynamic subtest: pipe-B-plane-1
[217.670514] Dynamic subtest pipe-B-plane-1: SUCCESS (2.349s)
[217.682493] Starting dynamic subtest: pipe-B-plane-2
[220.034066] Dynamic subtest pipe-B-plane-2: SUCCESS (2.361s)
[220.045106] Starting dynamic subtest: pipe-B-plane-3
[222.312508] Dynamic subtest pipe-B-plane-3: SUCCESS (2.277s)
[222.322069] Starting dynamic subtest: pipe-B-plane-4
[224.547291] Dynamic subtest pipe-B-plane-4: SUCCESS (2.236s)
[224.558265] Starting dynamic subtest: pipe-B-plane-5
[224.579346] Dynamic subtest pipe-B-plane-5: SUCCESS (0.030s)
[224.581661] Subtest plane-position-hole-dpms: SUCCESS (20.330s)
[225.378802] [070/156] (794s left) i915_pm_rc6_residency (rc6-accuracy)
[225.436145] Starting subtest: rc6-accuracy
[225.438288] Starting dynamic subtest: gt0
[228.607873] Dynamic subtest gt0: SUCCESS (3.169s)
[228.619096] Starting dynamic subtest: gt1
[231.831772] Dynamic subtest gt1: SUCCESS (3.169s)
[231.834245] Subtest rc6-accuracy: SUCCESS (6.344s)
[231.874391] [071/156] (788s left) syncobj_timeline (etime-multi-wait-submitted)
[231.924080] Starting subtest: etime-multi-wait-submitted
[232.027867] Subtest etime-multi-wait-submitted: SUCCESS (0.103s)
[232.121479] [072/156] (788s left) kms_hdr (bpc-switch-dpms)
[232.211515] Starting subtest: bpc-switch-dpms
[232.213848] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[237.781604] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (5.572s)
[237.792538] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[243.351676] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (5.569s)
[243.354139] Subtest bpc-switch-dpms: SUCCESS (11.143s)
[243.449603] [073/156] (776s left) vgem_basic (busy-fence)
[243.494103] Starting subtest: busy-fence
[243.496186] Subtest busy-fence: SUCCESS (0.000s)
[243.551936] [074/156] (776s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[243.619640] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[243.723611] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.104s)
[243.804071] [075/156] (776s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[243.888321] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[243.890284] Starting dynamic subtest: pipe-A-eDP-1
[243.910343] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.020s)
[243.921861] Starting dynamic subtest: pipe-B-eDP-1
[243.941420] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.020s)
[243.951082] Starting dynamic subtest: pipe-C-eDP-1
[243.970446] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.020s)
[243.980579] Starting dynamic subtest: pipe-D-eDP-1
[244.000998] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.019s)
[244.003173] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.084s)
[246.279896] [076/156] (774s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[246.383080] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[247.506832] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.124s)
[247.628045] [077/156] (772s left) kms_psr (fbc-pr-sprite-plane-move)
[247.713390] Starting subtest: fbc-pr-sprite-plane-move
[247.716819] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[247.783034] [078/156] (772s left) kms_draw_crc (draw-method-mmap-wc)
[247.867259] Starting subtest: draw-method-mmap-wc
[247.868909] Starting dynamic subtest: xrgb8888-untiled
[248.098712] Dynamic subtest xrgb8888-untiled: SUCCESS (0.233s)
[248.109401] Starting dynamic subtest: rgb565-untiled
[248.298553] Dynamic subtest rgb565-untiled: SUCCESS (0.199s)
[248.307334] Starting dynamic subtest: xrgb2101010-untiled
[248.520684] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.220s)
[248.529967] Starting dynamic subtest: xbgr16161616f-untiled
[248.720855] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.199s)
[248.729963] Starting dynamic subtest: xrgb8888-xtiled
[248.842931] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[248.851651] Starting dynamic subtest: rgb565-xtiled
[248.965177] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[248.973713] Starting dynamic subtest: xrgb2101010-xtiled
[249.076161] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.109s)
[249.084557] Starting dynamic subtest: xbgr16161616f-xtiled
[249.187276] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.110s)
[249.195844] Starting dynamic subtest: xrgb8888-4tiled
[249.342960] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.155s)
[249.353891] Starting dynamic subtest: rgb565-4tiled
[249.487463] Dynamic subtest rgb565-4tiled: SUCCESS (0.142s)
[249.499102] Starting dynamic subtest: xrgb2101010-4tiled
[249.642900] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.154s)
[249.652362] Starting dynamic subtest: xbgr16161616f-4tiled
[249.809853] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.166s)
[249.811853] Subtest draw-method-mmap-wc: SUCCESS (1.945s)
[249.907081] [079/156] (770s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[249.996364] Starting subtest: plane-upscale-20x20-with-modifiers
[249.998266] Starting dynamic subtest: pipe-A
[250.333591] Dynamic subtest pipe-A: SUCCESS (0.338s)
[250.348264] Starting dynamic subtest: pipe-B
[251.779605] Dynamic subtest pipe-B: SUCCESS (1.444s)
[251.789926] Starting dynamic subtest: pipe-C
[253.216767] Dynamic subtest pipe-C: SUCCESS (1.436s)
[253.232724] Starting dynamic subtest: pipe-D
[254.658733] Dynamic subtest pipe-D: SUCCESS (1.440s)
[254.661062] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (4.664s)
[255.824260] [080/156] (764s left) gem_exec_params (secure-non-root)
[255.887521] Starting subtest: secure-non-root
[255.892400] Subtest secure-non-root: SKIP (0.000s)
[256.009212] [081/156] (764s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-pwrite)
[256.104721] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-pwrite
[256.109127] Subtest hdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[256.196068] [082/156] (764s left) gem_userptr_blits (unsync-unmap-cycles)
[256.259578] Subtest unsync-unmap-cycles: SKIP (0.000s)
[256.372370] [083/156] (764s left) gem_exec_balancer (bonded-true-hang)
[256.627071] Starting subtest: bonded-true-hang
[256.632265] Subtest bonded-true-hang: SKIP (0.000s)
[256.754504] [084/156] (763s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-render)
[256.852685] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-render
[256.856645] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[256.957572] [085/156] (763s left) syncobj_wait (invalid-signal-zero-handles)
[257.002904] Starting subtest: invalid-signal-zero-handles
[257.005265] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[257.108970] [086/156] (763s left) kms_flip (plain-flip-fb-recreate-interruptible)
[257.206722] Starting subtest: plain-flip-fb-recreate-interruptible
[257.211830] Starting dynamic subtest: A-eDP1
[266.103228] Dynamic subtest A-eDP1: SUCCESS (8.894s)
[266.113619] Starting dynamic subtest: B-eDP1
[274.938285] Dynamic subtest B-eDP1: SUCCESS (8.833s)
[274.953516] Starting dynamic subtest: C-eDP1
[283.764702] Dynamic subtest C-eDP1: SUCCESS (8.824s)
[283.777518] Starting dynamic subtest: D-eDP1
[292.603662] Dynamic subtest D-eDP1: SUCCESS (8.835s)
[292.607885] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.399s)
[293.787687] [087/156] (727s left) gem_sync (basic-many-each)
[293.854536] Starting subtest: basic-many-each
[296.162060] Subtest basic-many-each: SUCCESS (2.320s)
[296.345450] [088/156] (724s left) gem_exec_params (larger-than-life-batch)
[296.414281] Starting subtest: larger-than-life-batch
[298.720726] Subtest larger-than-life-batch: SUCCESS (2.307s)
[299.318868] [089/156] (721s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[299.469974] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[299.474241] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[299.586484] [090/156] (721s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-render)
[299.696035] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-render
[299.700184] Subtest hdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.001s)
[299.823033] [091/156] (721s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-blt)
[299.916587] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-blt
[299.920587] Subtest hdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[300.005683] [092/156] (721s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[300.062976] Starting subtest: single-wait-for-submit-available-submitted
[300.065441] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[300.138398] [093/156] (720s left) kms_cursor_crc (cursor-offscreen-256x256)
[300.379478] Starting subtest: cursor-offscreen-256x256
[300.383629] Starting dynamic subtest: pipe-A-eDP-1
[302.330822] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.947s)
[302.360664] Starting dynamic subtest: pipe-D-eDP-1
[304.988757] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.637s)
[304.991219] Subtest cursor-offscreen-256x256: SUCCESS (4.609s)
[305.851624] [094/156] (715s left) kms_async_flips (async-flip-dpms)
[305.953909] Starting subtest: async-flip-dpms
[306.344853] Starting dynamic subtest: pipe-A-eDP-1
[310.272334] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.928s)
[310.663026] Starting dynamic subtest: pipe-B-eDP-1
[313.442640] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.791s)
[313.827896] Starting dynamic subtest: pipe-C-eDP-1
[316.700769] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.882s)
[317.077784] Starting dynamic subtest: pipe-D-eDP-1
[319.874889] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.806s)
[319.877277] Subtest async-flip-dpms: SUCCESS (13.922s)
[321.057815] [095/156] (700s left) gem_busy (extended)
[321.247246] Starting subtest: extended
[321.249583] Starting dynamic subtest: rcs0
[321.338342] Dynamic subtest rcs0: SUCCESS (0.091s)
[321.350608] Starting dynamic subtest: bcs0
[321.380342] Dynamic subtest bcs0: SUCCESS (0.040s)
[321.390954] Starting dynamic subtest: vcs0
[323.328559] Dynamic subtest vcs0: SUCCESS (1.944s)
[323.339281] Starting dynamic subtest: vcs1
[325.311639] Dynamic subtest vcs1: SUCCESS (1.980s)
[325.327623] Starting dynamic subtest: vecs0
[327.296040] Dynamic subtest vecs0: SUCCESS (1.981s)
[327.310884] Starting dynamic subtest: ccs0
[327.340204] Dynamic subtest ccs0: SUCCESS (0.044s)
[327.342686] Subtest extended: SUCCESS (6.094s)
[327.576450] [096/156] (693s left) syncobj_wait (single-wait-all-for-submit-signaled)
[327.631399] Starting subtest: single-wait-all-for-submit-signaled
[327.633792] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[327.744478] [097/156] (693s left) kms_pipe_stress (stress-xrgb8888-ytiled)
[327.843763] Starting subtest: stress-xrgb8888-ytiled
[327.848248] Subtest stress-xrgb8888-ytiled: SKIP (0.000s)
[327.914139] [098/156] (693s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-blt)
[328.019221] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-blt
[328.023323] Subtest psrhdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[328.104124] [099/156] (693s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render)
[328.197585] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render
[328.202047] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[328.295484] [100/156] (693s left) gem_mmap_gtt (cpuset-big-copy-xy)
[328.349741] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[328.410155] [101/156] (692s left) gem_linear_blits (normal)
[328.561333] Starting subtest: normal
[332.271261] Subtest normal: SUCCESS (3.708s)
[332.478126] [102/156] (688s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-mc-ccs)
[332.616206] Starting subtest: crc-primary-suspend-y-tiled-gen12-mc-ccs
[332.618487] Starting dynamic subtest: pipe-A-eDP-1
[332.627356] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[332.638895] Starting dynamic subtest: pipe-B-eDP-1
[332.647637] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[332.656456] Starting dynamic subtest: pipe-C-eDP-1
[332.664540] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[332.673412] Starting dynamic subtest: pipe-D-eDP-1
[332.681786] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[332.685832] Subtest crc-primary-suspend-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[334.936626] [103/156] (686s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[335.054302] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[335.058978] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[335.163983] [104/156] (686s left) kms_psr2_su (page_flip-xrgb8888)
[335.215313] Subtest page_flip-XRGB8888: SKIP (0.000s)
[335.272648] [105/156] (686s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[335.370659] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[335.372960] Starting dynamic subtest: pipe-A-eDP-1
[335.466920] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[335.477803] Starting dynamic subtest: pipe-B-eDP-1
[336.617433] Dynamic subtest pipe-B-eDP-1: SKIP (1.147s)
[336.622525] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.249s)
[337.800593] [106/156] (683s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[337.898682] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[337.902829] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[338.005998] [107/156] (683s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[338.100391] Starting subtest: fbcpsr-rgb101010-draw-render
[343.086729] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (4.990s)
[343.179539] [108/156] (678s left) api_intel_bb (blit-noreloc-keep-cache)
[343.241871] Starting subtest: blit-noreloc-keep-cache
[343.251137] Subtest blit-noreloc-keep-cache: SUCCESS (0.011s)
[343.395248] [109/156] (678s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[343.495926] Starting subtest: 4-tiled-16bpp-rotate-90
[343.515152] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.019s)
[343.642670] [110/156] (678s left) syncobj_basic (bad-pad-fd-to-handle)
[343.698497] Starting subtest: bad-pad-fd-to-handle
[343.700911] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[343.811654] [111/156] (678s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-indfb-pgflip-blt)
[343.914982] Starting subtest: psrhdr-2p-scndscrn-indfb-pgflip-blt
[343.919112] Subtest psrhdr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[344.006541] [112/156] (677s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[344.131412] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[344.135343] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[344.273812] [113/156] (677s left) gem_mmap_wc (read)
[344.333562] Subtest read: SKIP (0.000s)
[344.394951] [114/156] (677s left) gem_mmap_wc (write-prefaulted)
[344.448138] Subtest write-prefaulted: SKIP (0.000s)
[344.513111] [115/156] (677s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[344.604110] Starting subtest: y-tiled-16bpp-rotate-90
[344.607795] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[344.710173] [116/156] (677s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[344.804229] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc
[344.807744] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[344.903735] [117/156] (677s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-pgflip-blt)
[345.008281] Starting subtest: fbchdr-2p-primscrn-indfb-pgflip-blt
[345.011743] Subtest fbchdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[345.090720] [118/156] (676s left) gem_userptr_blits (create-destroy-unsync)
[345.155241] Subtest create-destroy-unsync: SKIP (0.000s)
[345.292084] [119/156] (676s left) kms_atomic_transition (modeset-transition)
[345.382978] Starting subtest: modeset-transition
[345.384689] Starting dynamic subtest: 1x-outputs
[356.512266] Dynamic subtest 1x-outputs: SUCCESS (11.127s)
[356.515046] Subtest modeset-transition: SUCCESS (11.128s)
[357.387788] [120/156] (664s left) syncobj_timeline (multi-wait-signaled)
[357.457644] Starting subtest: multi-wait-signaled
[357.459958] Subtest multi-wait-signaled: SUCCESS (0.001s)
[357.533582] [121/156] (664s left) gem_flink_basic (basic)
[357.584177] Starting subtest: basic
[357.586049] Subtest basic: SUCCESS (0.000s)
[357.686344] [122/156] (664s left) kms_chamelium_edid (dp-edid-read)
[358.076388] Subtest dp-edid-read: SKIP (0.000s)
[358.902408] [123/156] (663s left) gem_exec_schedule (noreorder)
[359.080959] Starting subtest: noreorder
[359.083470] Starting dynamic subtest: rcs0
[359.144712] Dynamic subtest rcs0: SUCCESS (0.061s)
[359.156229] Starting dynamic subtest: vcs0
[360.294917] Dynamic subtest vcs0: SUCCESS (1.148s)
[360.305536] Starting dynamic subtest: vcs1
[362.304741] Dynamic subtest vcs1: SUCCESS (2.007s)
[362.317102] Starting dynamic subtest: vecs0
[364.298292] Dynamic subtest vecs0: SUCCESS (1.988s)
[364.309540] Starting dynamic subtest: ccs0
[364.377213] Dynamic subtest ccs0: SUCCESS (0.081s)
[364.379950] Subtest noreorder: SUCCESS (5.297s)
[364.565234] [124/156] (657s left) core_sysfs (read-all-entries)
[364.614913] Starting subtest: read-all-entries
[364.617250] Subtest read-all-entries: SUCCESS (0.003s)
[364.721537] [125/156] (657s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt)
[364.815619] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt
[364.820314] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[364.922269] [126/156] (657s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[365.017781] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[368.729215] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.718s)
[369.914197] [127/156] (652s left) syncobj_basic (bad-pad-handle-to-fd)
[369.968930] Starting subtest: bad-pad-handle-to-fd
[369.971311] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[370.053463] [128/156] (652s left) gem_exec_balancer (semaphore)
[370.120877] Starting subtest: semaphore
[371.258499] Subtest semaphore: SUCCESS (1.136s)
[371.577495] [129/156] (650s left) gem_exec_flush (basic-batch-kernel-default-wb)
[371.627631] Starting subtest: basic-batch-kernel-default-wb
[377.865759] Subtest basic-batch-kernel-default-wb: SUCCESS (6.238s)
[378.057662] [130/156] (644s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[378.163228] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[378.986900] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.825s)
[379.176259] [131/156] (643s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[379.228984] Starting subtest: invalid-single-wait-all-unsubmitted
[379.231369] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[379.376832] [132/156] (643s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[379.476222] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[379.480386] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[379.604102] [133/156] (643s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-move)
[379.706671] Starting subtest: psrhdr-2p-primscrn-spr-indfb-move
[379.710933] Subtest psrhdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[379.801065] [134/156] (643s left) gem_exec_reloc (basic-gtt-wc-active)
[379.856815] Subtest basic-gtt-wc-active: SKIP (0.000s)
[379.932231] [135/156] (642s left) kms_cursor_crc (cursor-rapid-movement-64x64)
[380.109109] Starting subtest: cursor-rapid-movement-64x64
[380.113331] Starting dynamic subtest: pipe-A-eDP-1
[380.655703] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.543s)
[380.684030] Starting dynamic subtest: pipe-D-eDP-1
[381.900840] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.223s)
[381.903209] Subtest cursor-rapid-movement-64x64: SUCCESS (1.790s)
[382.765313] [136/156] (640s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-pwrite)
[382.855184] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-pwrite
[382.859362] Subtest fbchdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[382.947256] [137/156] (640s left) gen9_exec_parse (bb-secure)
[383.008592] Subtest bb-secure: SKIP (0.000s)
[383.076477] [138/156] (639s left) perf (blocking)
[383.205518] Starting subtest: blocking
[383.207377] Starting dynamic subtest: 0-rcs0
[393.226821] Dynamic subtest 0-rcs0: SUCCESS (10.019s)
[393.238681] Starting dynamic subtest: 1-vcs0
[403.299020] Dynamic subtest 1-vcs0: SUCCESS (10.020s)
[403.301832] Subtest blocking: SUCCESS (20.042s)
[403.345393] [139/156] (619s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[403.453066] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[403.457263] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[403.563998] [140/156] (619s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[403.654867] Starting subtest: plane-lut1d-post-ctm3x4
[403.778795] Starting dynamic subtest: pipe-A-plane-0
[403.955884] Dynamic subtest pipe-A-plane-0: SUCCESS (0.177s)
[403.966428] Starting dynamic subtest: pipe-A-plane-1
[404.089522] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[404.102631] Starting dynamic subtest: pipe-A-plane-2
[404.235041] Dynamic subtest pipe-A-plane-2: SUCCESS (0.143s)
[405.446615] Starting dynamic subtest: pipe-B-plane-0
[405.582771] Dynamic subtest pipe-B-plane-0: SUCCESS (0.144s)
[405.595240] Starting dynamic subtest: pipe-B-plane-1
[405.738361] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[405.748377] Starting dynamic subtest: pipe-B-plane-2
[405.927306] Dynamic subtest pipe-B-plane-2: SUCCESS (0.188s)
[407.178548] Starting dynamic subtest: pipe-C-plane-0
[407.302890] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[407.313216] Starting dynamic subtest: pipe-C-plane-1
[407.447407] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[407.457619] Starting dynamic subtest: pipe-C-plane-2
[407.591696] Dynamic subtest pipe-C-plane-2: SUCCESS (0.143s)
[408.801546] Starting dynamic subtest: pipe-D-plane-0
[408.925251] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[408.935619] Starting dynamic subtest: pipe-D-plane-1
[409.058098] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[409.069301] Starting dynamic subtest: pipe-D-plane-2
[409.203565] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[409.498716] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.845s)
[410.392225] [141/156] (612s left) api_intel_bb (intel-bb-blit-y)
[410.446566] Starting subtest: intel-bb-blit-y
[414.057821] Subtest intel-bb-blit-y: SUCCESS (3.610s)
[414.168498] [142/156] (609s left) gem_exec_whisper (basic-fds-priority-all)
[414.238659] Starting subtest: basic-fds-priority-all
[437.461917] Subtest basic-fds-priority-all: SUCCESS (23.218s)
[437.659872] [143/156] (585s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[437.771064] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[460.269446] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (22.504s)
[460.407290] [144/156] (562s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[460.511466] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[467.281104] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.772s)
[467.410187] [145/156] (555s left) gem_render_copy_redux (normal)
[467.473843] Starting subtest: normal
[467.762993] Subtest normal: SUCCESS (0.289s)
[467.900426] [146/156] (555s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[468.003673] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[468.007706] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[468.115773] [147/156] (555s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[468.204841] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[468.209370] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[468.317290] [148/156] (555s left) i915_query (engine-info)
[468.374482] Starting subtest: engine-info
[468.376452] Subtest engine-info: SUCCESS (0.004s)
[468.500075] [149/156] (554s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-msflip-blt)
[468.609101] Starting subtest: fbchdr-1p-primscrn-indfb-msflip-blt
[468.612517] Subtest fbchdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[468.705733] [150/156] (554s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-render)
[468.806449] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-render
[468.809881] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[468.897261] [151/156] (554s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[468.985090] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[476.004165] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (7.023s)
[476.108396] [152/156] (547s left) kms_cursor_edge_walk (128x128-top-edge)
[476.208976] Starting subtest: 128x128-top-edge
[476.211208] Starting dynamic subtest: pipe-A-eDP-1
[479.673281] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.462s)
[479.684327] Starting dynamic subtest: pipe-D-eDP-1
[482.820359] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.146s)
[482.822301] Subtest 128x128-top-edge: SUCCESS (6.611s)
[483.637953] [153/156] (540s left) prime_busy (hang-wait)
[483.694333] Starting subtest: hang-wait
[483.696537] Starting dynamic subtest: rcs0
[510.755959] Dynamic subtest rcs0: SUCCESS (27.047s)
[510.768429] Starting dynamic subtest: bcs0
[517.903198] Dynamic subtest bcs0: SUCCESS (7.155s)
[517.913069] Starting dynamic subtest: vcs0
[524.878938] Dynamic subtest vcs0: SUCCESS (6.973s)
[524.888224] Starting dynamic subtest: vcs1
[531.979485] Dynamic subtest vcs1: SUCCESS (7.099s)
[531.988738] Starting dynamic subtest: vecs0
[538.892361] Dynamic subtest vecs0: SUCCESS (6.909s)
[538.903640] Starting dynamic subtest: ccs0
[552.740072] Dynamic subtest ccs0: SUCCESS (13.837s)
[552.742229] Subtest hang-wait: SUCCESS (69.033s)
[552.847360] [154/156] (470s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-pwrite)
[552.956967] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-pwrite
[552.961022] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[553.071268] [155/156] (470s left) gem_mmap_offset (partial-remap)
[553.132523] Starting subtest: partial-remap
[553.134587] Starting dynamic subtest: smem0
[553.143483] Dynamic subtest smem0: SUCCESS (0.005s)
[553.145779] Subtest partial-remap: SUCCESS (0.006s)
[553.260883] [156/156] (470s left) gem_ctx_bad_destroy (invalid-ctx)
[553.316808] Starting subtest: invalid-ctx
[553.319066] Subtest invalid-ctx: SUCCESS (0.000s)
[553.389858] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: kms_addfb_basic
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: i915_hangman
results: parsing output: 4/ for test: gem_exec_params
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_fenced_exec_thrash
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: gem_ctx_persistence
results: parsing output: 10/ for test: kms_chamelium_hpd
results: parsing output: 11/ for test: kms_invalid_mode
results: parsing output: 12/ for test: kms_pm_dc
results: parsing output: 13/ for test: prime_vgem
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_pxp
results: parsing output: 16/ for test: kms_cursor_legacy
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: kms_psr
results: parsing output: 19/ for test: kms_ccs
results: parsing output: 20/ for test: gem_exec_schedule
results: parsing output: 21/ for test: gem_userptr_blits
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: gem_exec_fence
results: parsing output: 24/ for test: kms_vblank
results: parsing output: 25/ for test: syncobj_wait
results: parsing output: 26/ for test: kms_plane
results: parsing output: 27/ for test: gem_ctx_shared
results: parsing output: 28/ for test: perf_pmu
results: parsing output: 29/ for test: kms_flip
results: parsing output: 30/ for test: kms_plane
results: parsing output: 31/ for test: perf_pmu
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_rotation_crc
results: parsing output: 34/ for test: gem_ctx_engines
results: parsing output: 35/ for test: kms_psr2_sf
results: parsing output: 36/ for test: kms_addfb_basic
results: parsing output: 37/ for test: gem_ctx_param
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_rotation_crc
results: parsing output: 40/ for test: gem_render_copy
results: parsing output: 41/ for test: kms_pm_rpm
results: parsing output: 42/ for test: kms_legacy_colorkey
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: prime_mmap
results: parsing output: 47/ for test: kms_content_protection
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_spin_batch
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_vblank
results: parsing output: 52/ for test: gen7_exec_parse
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_flip_scaled_crc
results: parsing output: 55/ for test: kms_chamelium_audio
results: parsing output: 56/ for test: gem_eio
results: parsing output: 57/ for test: gem_lmem_swapping
results: parsing output: 58/ for test: gem_exec_reloc
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: kms_rotation_crc
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: gem_partial_pwrite_pread
results: parsing output: 63/ for test: i915_drm_fdinfo
results: parsing output: 64/ for test: gem_mmap_wc
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_readwrite
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_plane
results: parsing output: 69/ for test: i915_pm_rc6_residency
results: parsing output: 70/ for test: syncobj_timeline
results: parsing output: 71/ for test: kms_hdr
results: parsing output: 72/ for test: vgem_basic
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_psr
results: parsing output: 77/ for test: kms_draw_crc
results: parsing output: 78/ for test: kms_plane_scaling
results: parsing output: 79/ for test: gem_exec_params
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_userptr_blits
results: parsing output: 82/ for test: gem_exec_balancer
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: syncobj_wait
results: parsing output: 85/ for test: kms_flip
results: parsing output: 86/ for test: gem_sync
results: parsing output: 87/ for test: gem_exec_params
results: parsing output: 88/ for test: kms_flip_scaled_crc
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: syncobj_timeline
results: parsing output: 92/ for test: kms_cursor_crc
results: parsing output: 93/ for test: kms_async_flips
results: parsing output: 94/ for test: gem_busy
results: parsing output: 95/ for test: syncobj_wait
results: parsing output: 96/ for test: kms_pipe_stress
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: gem_mmap_gtt
results: parsing output: 100/ for test: gem_linear_blits
results: parsing output: 101/ for test: kms_ccs
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_psr2_su
results: parsing output: 104/ for test: kms_psr2_sf
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: api_intel_bb
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: syncobj_basic
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_cursor_legacy
results: parsing output: 112/ for test: gem_mmap_wc
results: parsing output: 113/ for test: gem_mmap_wc
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: gem_userptr_blits
results: parsing output: 118/ for test: kms_atomic_transition
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: gem_flink_basic
results: parsing output: 121/ for test: kms_chamelium_edid
results: parsing output: 122/ for test: gem_exec_schedule
results: parsing output: 123/ for test: core_sysfs
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: syncobj_basic
results: parsing output: 127/ for test: gem_exec_balancer
results: parsing output: 128/ for test: gem_exec_flush
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: syncobj_timeline
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: gem_exec_reloc
results: parsing output: 134/ for test: kms_cursor_crc
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: gen9_exec_parse
results: parsing output: 137/ for test: perf
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: kms_color_pipeline
results: parsing output: 140/ for test: api_intel_bb
results: parsing output: 141/ for test: gem_exec_whisper
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: gem_render_copy_redux
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: i915_query
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: kms_cursor_edge_walk
results: parsing output: 152/ for test: prime_busy
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: gem_mmap_offset
results: parsing output: 155/ for test: gem_ctx_bad_destroy
Done.