[64.142553] Initializing watchdogs
[64.142614]   /dev/watchdog0
[64.160857] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:9a49 Intel Tigerlake (Gen12) TigerLake-LP GT2 [Iris Xe Graphics]
[64.166413] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[64.169121] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[64.170950] [001/133] (960s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[64.262533] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[64.263947] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (0.000s)
[64.319320] [002/133] (960s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[64.404300] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[64.405744] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (0.000s)
[64.462306] [003/133] (960s left) kms_vblank (query-busy)
[64.546740] Starting subtest: query-busy
[64.547443] Starting dynamic subtest: pipe-A-HDMI-A-1
[65.832267] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.284s)
[65.839930] Starting dynamic subtest: pipe-D-HDMI-A-1
[67.102395] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.266s)
[67.103195] Subtest query-busy: SUCCESS (2.555s)
[67.379734] [004/133] (957s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[67.521685] Starting subtest: 4-tiled-32bpp-rotate-270
[67.523404] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.000s)
[67.762238] [005/133] (957s left) gem_mmap_gtt (big-copy-xy)
[67.810893] Starting subtest: big-copy-XY
[78.268332] Subtest big-copy-XY: SUCCESS (10.457s)
[78.333404] [006/133] (946s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt)
[78.469478] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt
[78.470858] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[78.548596] [007/133] (946s left) gen3_render_mixed_blits
[78.619424] [008/133] (946s left) gem_eio (execbuf)
[78.664449] Starting subtest: execbuf
[78.700822] Subtest execbuf: SUCCESS (0.036s)
[78.809199] [009/133] (946s left) kms_async_flips (async-flip-suspend-resume)
[78.893263] Starting subtest: async-flip-suspend-resume
[79.022722] Starting dynamic subtest: pipe-A-HDMI-A-1
[96.403773] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.410s)
[96.529350] Starting dynamic subtest: pipe-B-HDMI-A-1
[114.372710] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.276s)
[114.504677] Starting dynamic subtest: pipe-C-HDMI-A-1
[132.372208] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.257s)
[132.488252] Starting dynamic subtest: pipe-D-HDMI-A-1
[150.382795] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (2.300s)
[150.383500] Subtest async-flip-suspend-resume: SUCCESS (9.727s)
[159.639088] [010/133] (874s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[159.690905] Starting subtest: invalid-single-wait-unsubmitted
[159.691653] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[159.754403] [011/133] (874s left) kms_plane (plane-panning-top-left)
[159.844543] Starting subtest: plane-panning-top-left
[159.845268] Starting dynamic subtest: pipe-A
[160.225492] Dynamic subtest pipe-A: SUCCESS (0.380s)
[160.234007] Starting dynamic subtest: pipe-B
[160.585763] Dynamic subtest pipe-B: SUCCESS (0.358s)
[160.586522] Subtest plane-panning-top-left: SUCCESS (0.742s)
[160.710971] [012/133] (873s left) drm_read (invalid-buffer)
[160.838354] Starting subtest: invalid-buffer
[160.839116] Subtest invalid-buffer: SUCCESS (0.000s)
[160.942651] [013/133] (873s left) kms_pm_rpm (modeset-pc8-residency-stress)
[162.332965] Starting subtest: modeset-pc8-residency-stress
[162.335324] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[162.518433] [014/133] (871s left) kms_flip (2x-dpms-vs-vblank-race-interruptible)
[162.609375] Starting subtest: 2x-dpms-vs-vblank-race-interruptible
[162.618902] Subtest 2x-dpms-vs-vblank-race-interruptible: SKIP (0.009s)
[162.676879] [015/133] (871s left) i915_drm_fdinfo (most-busy-idle-check-all)
[162.712489] Starting subtest: most-busy-idle-check-all
[162.713237] Starting dynamic subtest: rcs0
[163.353475] Dynamic subtest rcs0: SUCCESS (0.640s)
[163.363025] Starting dynamic subtest: bcs0
[164.001237] Dynamic subtest bcs0: SUCCESS (0.647s)
[164.012090] Starting dynamic subtest: vcs0
[164.634762] Dynamic subtest vcs0: SUCCESS (0.632s)
[164.644997] Starting dynamic subtest: vcs1
[165.270241] Dynamic subtest vcs1: SUCCESS (0.632s)
[165.280658] Starting dynamic subtest: vecs0
[165.894484] Dynamic subtest vecs0: SUCCESS (0.626s)
[165.895416] Subtest most-busy-idle-check-all: SUCCESS (3.181s)
[165.960915] [016/133] (868s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[166.051698] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[166.053079] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[166.137591] [017/133] (868s left) gem_exec_schedule (deep)
[166.305918] Starting subtest: deep
[166.306874] Starting dynamic subtest: rcs0
[174.397760] Dynamic subtest rcs0: SUCCESS (8.082s)
[174.407685] Starting dynamic subtest: bcs0
[182.507399] Dynamic subtest bcs0: SUCCESS (8.108s)
[182.516990] Starting dynamic subtest: vcs0
[190.770007] Dynamic subtest vcs0: SUCCESS (8.261s)
[190.780179] Starting dynamic subtest: vcs1
[198.924103] Dynamic subtest vcs1: SUCCESS (8.153s)
[198.934049] Starting dynamic subtest: vecs0
[207.174412] Dynamic subtest vecs0: SUCCESS (8.249s)
[207.175243] Subtest deep: SUCCESS (40.858s)
[207.728288] [FACT gem_exec_schedule (deep)] new: kernel.kmod_is_loaded.vgem: true
[207.730560] [018/133] (826s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[207.828789] Starting subtest: x-tiled-32bpp-rotate-0
[209.139142] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.310s)
[209.296850] [019/133] (825s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[209.385625] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s)
[209.432866] [020/133] (825s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0)
[209.527411] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0
[210.122658] Subtest x-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (0.595s)
[210.231592] [021/133] (824s left) gem_exec_nop (basic-sequential)
[210.273956] Starting subtest: basic-sequential
[213.600061] Subtest basic-sequential: SUCCESS (3.325s)
[213.813519] [022/133] (820s left) kms_cursor_crc (cursor-sliding-128x42)
[213.904024] Starting subtest: cursor-sliding-128x42
[213.908830] Starting dynamic subtest: pipe-A-HDMI-A-1
[215.143886] Dynamic subtest pipe-A-HDMI-A-1: FAIL (1.063s)
[215.152624] Starting dynamic subtest: pipe-D-HDMI-A-1
[216.918025] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.909s)
[216.918733] Subtest cursor-sliding-128x42: FAIL (3.014s)
[217.085252] [023/133] (817s left) gem_exec_reloc (basic-write-gtt)
[217.127466] Starting subtest: basic-write-gtt
[217.133859] Subtest basic-write-gtt: SUCCESS (0.006s)
[217.248911] [024/133] (817s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[217.339254] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[217.340723] Starting dynamic subtest: pipe-A-HDMI-A-1
[217.343366] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[217.350751] Starting dynamic subtest: pipe-B-HDMI-A-1
[217.353380] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[217.360834] Starting dynamic subtest: pipe-C-HDMI-A-1
[217.363559] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[217.371024] Starting dynamic subtest: pipe-D-HDMI-A-1
[217.373703] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[217.375071] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SKIP (0.009s)
[217.574414] [025/133] (817s left) gem_create (create-size-update)
[217.608610] Starting subtest: create-size-update
[217.609350] Subtest create-size-update: SUCCESS (0.000s)
[217.811169] [026/133] (816s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[217.901637] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[217.910453] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.009s)
[217.977713] [027/133] (816s left) gem_exec_reloc (basic-wc-read)
[218.014399] Starting subtest: basic-wc-read
[218.019669] Subtest basic-wc-read: SUCCESS (0.005s)
[218.134232] [028/133] (816s left) i915_hangman (gt-engine-hang)
[218.175603] Starting subtest: gt-engine-hang
[218.176296] Starting dynamic subtest: rcs0
[218.764959] Dynamic subtest rcs0: SUCCESS (0.589s)
[218.776441] Starting dynamic subtest: bcs0
[219.348937] Dynamic subtest bcs0: SUCCESS (0.583s)
[219.360228] Starting dynamic subtest: vcs0
[219.920010] Dynamic subtest vcs0: SUCCESS (0.570s)
[219.929976] Starting dynamic subtest: vcs1
[220.491903] Dynamic subtest vcs1: SUCCESS (0.571s)
[220.502873] Starting dynamic subtest: vecs0
[221.079033] Dynamic subtest vecs0: SUCCESS (0.586s)
[221.080101] Subtest gt-engine-hang: SUCCESS (2.903s)
[221.192274] [029/133] (813s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[221.287496] Starting subtest: psr-rgb565-draw-mmap-cpu
[221.288823] Subtest psr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[221.376007] [030/133] (813s left) kms_cursor_crc (cursor-onscreen-512x170)
[221.464713] Starting subtest: cursor-onscreen-512x170
[221.466061] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[221.560597] [031/133] (813s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[221.593970] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[221.698743] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[221.769525] [032/133] (813s left) gem_mmap_offset (bad-object)
[221.802259] Starting subtest: bad-object
[221.804070] Subtest bad-object: SUCCESS (0.001s)
[221.891256] [033/133] (813s left) gem_ctx_persistence (heartbeat-close)
[221.928462] Starting subtest: heartbeat-close
[222.145759] Subtest heartbeat-close: SUCCESS (0.217s)
[222.219079] [034/133] (812s left) gem_exec_balancer (bonded-sync)
[222.261060] Starting subtest: bonded-sync
[237.037091] Subtest bonded-sync: SUCCESS (14.767s)
[237.229621] [035/133] (797s left) api_intel_allocator (reopen-fork)
[237.262168] Starting subtest: reopen-fork
[240.728844] Subtest reopen-fork: SUCCESS (3.433s)
[240.764028] [036/133] (794s left) gem_pwrite_snooped
[240.923747] [037/133] (794s left) gem_exec_schedule (noreorder)
[241.087817] Starting subtest: noreorder
[241.088878] Starting dynamic subtest: rcs0
[241.152791] Dynamic subtest rcs0: SUCCESS (0.064s)
[241.173303] Starting dynamic subtest: bcs0
[241.214890] Dynamic subtest bcs0: SUCCESS (0.061s)
[241.231299] Starting dynamic subtest: vcs0
[241.264657] Dynamic subtest vcs0: SUCCESS (0.048s)
[241.283053] Starting dynamic subtest: vcs1
[241.319678] Dynamic subtest vcs1: SUCCESS (0.054s)
[241.336010] Starting dynamic subtest: vecs0
[241.379617] Dynamic subtest vecs0: SUCCESS (0.059s)
[241.380730] Subtest noreorder: SUCCESS (0.292s)
[241.568132] [038/133] (793s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[241.710634] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[241.814841] [039/133] (793s left) gem_exec_reloc (basic-cpu-wc)
[241.855643] Starting subtest: basic-cpu-wc
[241.859910] Subtest basic-cpu-wc: SUCCESS (0.005s)
[241.975981] [040/133] (793s left) kms_atomic_transition (plane-all-modeset-transition-fencing)
[242.061906] Starting subtest: plane-all-modeset-transition-fencing
[242.062616] Starting dynamic subtest: pipe-A-HDMI-A-1
[253.309549] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (11.246s)
[253.353293] Starting dynamic subtest: pipe-B-HDMI-A-1
[262.852922] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (9.506s)
[262.879837] Subtest plane-all-modeset-transition-fencing: SUCCESS (20.817s)
[263.020092] [041/133] (772s left) sysfs_heartbeat_interval (precise)
[263.058452] Starting subtest: precise
[263.059212] Starting dynamic subtest: vecs0
[264.579571] Dynamic subtest vecs0: SUCCESS (1.520s)
[264.591757] Starting dynamic subtest: vcs0
[266.092771] Dynamic subtest vcs0: SUCCESS (1.512s)
[266.102517] Starting dynamic subtest: bcs0
[267.611873] Dynamic subtest bcs0: SUCCESS (1.518s)
[267.622052] Starting dynamic subtest: rcs0
[269.107802] Dynamic subtest rcs0: SUCCESS (1.494s)
[269.118471] Starting dynamic subtest: vcs1
[270.615352] Dynamic subtest vcs1: SUCCESS (1.506s)
[270.616270] Subtest precise: SUCCESS (7.557s)
[270.684256] [042/133] (764s left) kms_properties (get_properties-sanity-non-atomic)
[270.772875] Starting subtest: get_properties-sanity-non-atomic
[270.775022] Subtest get_properties-sanity-non-atomic: SUCCESS (0.003s)
[270.836570] [043/133] (764s left) kms_getfb (getfb2-handle-closed)
[270.922585] Starting subtest: getfb2-handle-closed
[270.923295] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[271.022656] [044/133] (764s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[271.108740] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[271.109448] Starting dynamic subtest: pipe-A
[271.235588] Dynamic subtest pipe-A: SUCCESS (0.127s)
[271.244449] Starting dynamic subtest: pipe-B
[271.361387] Dynamic subtest pipe-B: SUCCESS (0.125s)
[271.370050] Starting dynamic subtest: pipe-C
[271.496153] Dynamic subtest pipe-C: SUCCESS (0.134s)
[271.518662] Starting dynamic subtest: pipe-D
[271.638910] Dynamic subtest pipe-D: SUCCESS (0.142s)
[271.639851] Subtest planes-upscale-20x20-downscale-factor-0-75: SUCCESS (0.531s)
[271.827130] [045/133] (763s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[271.916397] Starting subtest: cursor-vs-flip-legacy
[301.554955] Subtest cursor-vs-flip-legacy: SUCCESS (29.638s)
[301.705156] [046/133] (733s left) syncobj_basic (bad-destroy)
[301.741474] Starting subtest: bad-destroy
[301.742208] Subtest bad-destroy: SUCCESS (0.000s)
[301.808653] [047/133] (733s left) kms_psr (fbc-psr-primary-mmap-gtt)
[301.896335] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.000s)
[301.941194] [048/133] (733s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[301.979607] Starting subtest: yf-tiled-ccs-to-x-tiled
[301.980317] Starting dynamic subtest: smem
[302.077491] Dynamic subtest smem: SUCCESS (0.097s)
[302.078234] Subtest yf-tiled-ccs-to-x-tiled: SUCCESS (0.098s)
[302.257220] [049/133] (733s left) gem_mmap_gtt (basic-wc)
[302.291142] Starting subtest: basic-wc
[302.929460] Subtest basic-wc: SUCCESS (0.630s)
[303.019772] [050/133] (732s left) gem_mmap_gtt (cpuset-big-copy)
[303.051717] Starting subtest: cpuset-big-copy
[315.354484] Subtest cpuset-big-copy: SUCCESS (12.302s)
[315.419755] [051/133] (720s left) gem_exec_suspend (basic-s4-devices)
[315.497435] Starting subtest: basic-S4-devices
[315.498185] Starting dynamic subtest: smem
[323.369422] Dynamic subtest smem: SUCCESS (7.871s)
[323.370259] Subtest basic-S4-devices: SUCCESS (7.872s)
[331.577000] [052/133] (712s left) gem_eio (create)
[331.624259] Starting subtest: create
[331.652262] Subtest create: SUCCESS (0.028s)
[331.768572] [053/133] (712s left) kms_psr2_sf (pr-overlay-plane-move-continuous-sf)
[331.854510] Subtest pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[331.906688] [054/133] (711s left) kms_psr (psr2-no-drrs)
[331.995015] Subtest psr2-no-drrs: SKIP (0.000s)
[332.039567] [055/133] (711s left) gem_exec_balancer (semaphore)
[332.081430] Starting subtest: semaphore
[333.367493] Subtest semaphore: SUCCESS (1.286s)
[333.568501] [056/133] (710s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[333.660698] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[333.669570] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.009s)
[333.737741] [057/133] (710s left) gem_lmem_swapping (heavy-verify-random)
[333.780067] Subtest heavy-verify-random: SKIP (0.000s)
[333.822975] [058/133] (710s left) gem_exec_schedule (submit-golden-slice)
[333.869037] Starting subtest: submit-golden-slice
[333.869839] Starting dynamic subtest: rcs0
[333.899257] Dynamic subtest rcs0: SUCCESS (0.030s)
[333.912093] Starting dynamic subtest: bcs0
[333.929356] Dynamic subtest bcs0: SUCCESS (0.029s)
[333.939925] Starting dynamic subtest: vcs0
[333.962245] Dynamic subtest vcs0: SUCCESS (0.032s)
[333.972717] Starting dynamic subtest: vcs1
[333.992064] Dynamic subtest vcs1: SUCCESS (0.029s)
[334.002412] Starting dynamic subtest: vecs0
[334.023363] Dynamic subtest vecs0: SUCCESS (0.030s)
[334.024148] Subtest submit-golden-slice: SUCCESS (0.155s)
[334.319119] [059/133] (709s left) gem_spin_batch (spin-each)
[334.360258] Starting subtest: spin-each
[337.719787] Subtest spin-each: SUCCESS (3.341s)
[337.894244] [060/133] (706s left) kms_pm_rpm (dpms-lpsp)
[339.314465] Starting subtest: dpms-lpsp
[339.408099] Subtest dpms-lpsp: SUCCESS (0.096s)
[339.523996] [061/133] (704s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[339.626598] Starting subtest: yf-tiled-64bpp-rotate-180
[339.628037] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[339.697249] [062/133] (704s left) i915_query (query-regions-garbage-items)
[339.730841] Starting subtest: query-regions-garbage-items
[339.731528] Subtest query-regions-garbage-items: SUCCESS (0.000s)
[339.788948] [063/133] (704s left) kms_psr (psr-primary-blt)
[339.874051] Subtest psr-primary-blt: SKIP (0.000s)
[339.918244] [064/133] (704s left) syncobj_timeline (multi-wait-submitted-signaled)
[339.951891] Starting subtest: multi-wait-submitted-signaled
[339.952601] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[340.022996] [065/133] (704s left) gem_exec_whisper (basic-queues)
[340.065404] Starting subtest: basic-queues
[341.725381] Subtest basic-queues: SUCCESS (1.659s)
[341.909907] [066/133] (702s left) perf (create-destroy-userspace-config)
[341.999751] Starting subtest: create-destroy-userspace-config
[342.018104] Subtest create-destroy-userspace-config: SUCCESS (0.019s)
[342.062990] [067/133] (702s left) api_intel_bb (intel-bb-blit-x)
[342.096784] Starting subtest: intel-bb-blit-x
[345.244225] Subtest intel-bb-blit-x: SUCCESS (3.114s)
[345.303566] [068/133] (699s left) gem_sync (basic-many-each)
[345.345403] Starting subtest: basic-many-each
[347.573009] Subtest basic-many-each: SUCCESS (2.227s)
[347.779203] [069/133] (696s left) kms_vblank (ts-continuation-idle-hang)
[347.865907] Starting subtest: ts-continuation-idle-hang
[347.867569] Starting dynamic subtest: pipe-A-HDMI-A-1
[361.422664] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (13.555s)
[361.435224] Starting dynamic subtest: pipe-D-HDMI-A-1
[375.238072] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (13.812s)
[375.238865] Subtest ts-continuation-idle-hang: SUCCESS (27.373s)
[375.406241] [070/133] (669s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[375.503768] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[375.505124] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[375.611369] [071/133] (668s left) perf_pmu (multi-client)
[375.647033] Starting subtest: multi-client
[375.647769] Starting dynamic subtest: rcs0
[376.678520] Dynamic subtest rcs0: SUCCESS (1.022s)
[376.689937] Starting dynamic subtest: bcs0
[377.707433] Dynamic subtest bcs0: SUCCESS (1.027s)
[377.719572] Starting dynamic subtest: vcs0
[378.730369] Dynamic subtest vcs0: SUCCESS (1.021s)
[378.741648] Starting dynamic subtest: vcs1
[379.757399] Dynamic subtest vcs1: SUCCESS (1.026s)
[379.768891] Starting dynamic subtest: vecs0
[380.785033] Dynamic subtest vecs0: SUCCESS (1.026s)
[380.785989] Subtest multi-client: SUCCESS (5.130s)
[380.872529] [072/133] (663s left) gem_softpin (32b-excludes-last-page)
[380.909719] Starting subtest: 32b-excludes-last-page
[380.910400] Subtest 32b-excludes-last-page: SUCCESS (0.001s)
[381.008775] [073/133] (663s left) kms_psr (pr-sprite-plane-onoff)
[381.096958] Subtest pr-sprite-plane-onoff: SKIP (0.000s)
[381.150090] [074/133] (663s left) gem_userptr_blits (sync-unmap-cycles)
[381.199594] Starting subtest: sync-unmap-cycles
[386.618938] Subtest sync-unmap-cycles: SUCCESS (5.385s)
[386.692802] [075/133] (657s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[386.788453] Starting subtest: y-tiled-32bpp-rotate-270
[388.021308] Subtest y-tiled-32bpp-rotate-270: SUCCESS (1.232s)
[388.140702] [076/133] (656s left) kms_atomic (plane-immutable-zpos)
[388.227862] Starting subtest: plane-immutable-zpos
[388.228546] Starting dynamic subtest: pipe-A-HDMI-A-1
[388.529275] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.300s)
[388.530013] Subtest plane-immutable-zpos: SUCCESS (0.302s)
[388.654823] [077/133] (656s left) kms_frontbuffer_tracking (fbc-1p-rte)
[388.762994] Starting subtest: fbc-1p-rte
[391.526924] Subtest fbc-1p-rte: SUCCESS (2.766s)
[391.673757] [078/133] (653s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs)
[391.767003] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs
[391.768699] Starting dynamic subtest: pipe-A-HDMI-A-1
[391.909319] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.138s)
[391.918111] Starting dynamic subtest: pipe-B-HDMI-A-1
[392.003679] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.093s)
[392.012224] Starting dynamic subtest: pipe-C-HDMI-A-1
[392.098119] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.093s)
[392.106964] Starting dynamic subtest: pipe-D-HDMI-A-1
[392.192736] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.093s)
[392.193413] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs: SUCCESS (0.423s)
[392.462731] [079/133] (652s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc)
[392.553844] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc
[392.555225] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[392.658755] [080/133] (652s left) gem_ctx_param (set-priority-not-supported)
[392.691993] Starting subtest: set-priority-not-supported
[392.693402] Subtest set-priority-not-supported: SKIP (0.000s)
[392.752301] [081/133] (652s left) kms_hdmi_inject (inject-audio)
[392.842529] Starting subtest: inject-audio
[392.993190] Subtest inject-audio: SUCCESS (0.149s)
[393.138712] [082/133] (651s left) kms_lease (page-flip-implicit-plane)
[393.225206] Starting subtest: page-flip-implicit-plane
[393.225981] Starting dynamic subtest: pipe-A-HDMI-A-1
[393.424893] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.198s)
[393.435375] Starting dynamic subtest: pipe-B-HDMI-A-1
[393.604362] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.177s)
[393.615558] Starting dynamic subtest: pipe-C-HDMI-A-1
[393.776068] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.170s)
[393.789026] Starting dynamic subtest: pipe-D-HDMI-A-1
[393.943585] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.165s)
[393.944304] Subtest page-flip-implicit-plane: SUCCESS (0.719s)
[394.063369] [083/133] (650s left) gem_exec_fence (syncobj-backward-timeline-chain-engines)
[394.531543] Starting subtest: syncobj-backward-timeline-chain-engines
[394.595571] Subtest syncobj-backward-timeline-chain-engines: SUCCESS (0.069s)
[394.780793] [084/133] (650s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[394.871965] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[394.873382] Subtest fbcpsr-1p-pri-indfb-multidraw: SKIP (0.000s)
[394.982999] [085/133] (650s left) kms_content_protection (suspend-resume)
[395.070988] Starting subtest: suspend-resume
[395.076350] Subtest suspend-resume: SKIP (0.003s)
[395.174197] [086/133] (649s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping)
[395.258995] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping
[395.275876] Starting dynamic subtest: pipe-A-plane-0
[395.483910] Dynamic subtest pipe-A-plane-0: SUCCESS (0.208s)
[395.498564] Starting dynamic subtest: pipe-A-plane-3
[395.650707] Dynamic subtest pipe-A-plane-3: SUCCESS (0.165s)
[395.670908] Starting dynamic subtest: pipe-A-plane-5
[395.817384] Dynamic subtest pipe-A-plane-5: SUCCESS (0.163s)
[395.971911] Starting dynamic subtest: pipe-B-plane-0
[396.145297] Dynamic subtest pipe-B-plane-0: SUCCESS (0.183s)
[396.158937] Starting dynamic subtest: pipe-B-plane-3
[396.312017] Dynamic subtest pipe-B-plane-3: SUCCESS (0.164s)
[396.328921] Starting dynamic subtest: pipe-B-plane-5
[396.478696] Dynamic subtest pipe-B-plane-5: SUCCESS (0.164s)
[396.561498] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping: SUCCESS (1.302s)
[396.709553] [087/133] (648s left) i915_pm_freq_mult (media-freq)
[396.746002] Starting subtest: media-freq
[396.746718] Starting dynamic subtest: gt0
[396.748099] Dynamic subtest gt0: SKIP (0.000s)
[396.749467] Subtest media-freq: SKIP (0.001s)
[396.808126] [088/133] (648s left) gen9_exec_parse (bb-oversize)
[396.845275] Subtest bb-oversize: SKIP (0.000s)
[396.892840] [089/133] (648s left) perf (low-oa-exponent-permissions)
[396.966452] Starting subtest: low-oa-exponent-permissions
[397.000505] Subtest low-oa-exponent-permissions: SUCCESS (0.034s)
[397.049175] [090/133] (648s left) kms_addfb_basic (too-high)
[397.136167] Starting subtest: too-high
[397.136904] Subtest too-high: SUCCESS (0.001s)
[397.231720] [091/133] (648s left) gem_ctx_bad_destroy (invalid-pad)
[397.265120] Starting subtest: invalid-pad
[397.265825] Subtest invalid-pad: SUCCESS (0.000s)
[397.328836] [092/133] (648s left) gem_fenced_exec_thrash (no-spare-fences)
[397.365080] Starting subtest: no-spare-fences
[399.519940] Subtest no-spare-fences: SUCCESS (2.154s)
[399.624218] [093/133] (645s left) kms_pipe_crc_basic (bad-source)
[399.711094] Starting subtest: bad-source
[399.711851] Subtest bad-source: SUCCESS (0.000s)
[399.772331] [094/133] (645s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[399.856267] Starting subtest: bad-pixel-format-y-tiled-ccs
[399.857016] Starting dynamic subtest: pipe-A-HDMI-A-1
[399.863891] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[399.872875] Starting dynamic subtest: pipe-B-HDMI-A-1
[399.879643] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[399.888272] Starting dynamic subtest: pipe-C-HDMI-A-1
[399.895068] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[399.903622] Starting dynamic subtest: pipe-D-HDMI-A-1
[399.910535] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[399.911930] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.008s)
[400.102745] [095/133] (645s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[400.192913] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[401.265865] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (1.073s)
[401.413302] [096/133] (644s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted)
[401.446486] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted
[401.554810] Subtest etime-multi-wait-all-for-submit-unsubmitted: SUCCESS (0.108s)
[401.638698] [097/133] (643s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[401.728899] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[401.730298] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[401.807114] [098/133] (643s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[401.841231] Starting subtest: invalid-single-wait-all-unsubmitted
[401.841972] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[401.901498] [099/133] (643s left) kms_plane_lowres (tiling-x)
[401.987703] Starting subtest: tiling-x
[401.989987] Starting dynamic subtest: pipe-A-HDMI-A-1
[408.949152] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.960s)
[409.015127] Starting dynamic subtest: pipe-B-HDMI-A-1
[415.884385] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (6.877s)
[415.988352] Starting dynamic subtest: pipe-C-HDMI-A-1
[422.816869] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (6.839s)
[422.887017] Starting dynamic subtest: pipe-D-HDMI-A-1
[429.617775] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (6.743s)
[429.694027] Subtest tiling-x: SUCCESS (27.706s)
[429.852320] [100/133] (615s left) gem_sync (basic-all)
[429.906340] Starting subtest: basic-all
[431.994347] Subtest basic-all: SUCCESS (2.088s)
[432.184389] [101/133] (613s left) core_setmaster (master-drop-set-user)
[432.256153] Starting subtest: master-drop-set-user
[432.267232] Subtest master-drop-set-user: SUCCESS (0.012s)
[432.304774] [102/133] (613s left) kms_cursor_edge_walk (256x256-right-edge)
[432.391451] Starting subtest: 256x256-right-edge
[432.393594] Starting dynamic subtest: pipe-A-HDMI-A-1
[434.136667] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.741s)
[434.145392] Starting dynamic subtest: pipe-D-HDMI-A-1
[435.822138] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.686s)
[435.822901] Subtest 256x256-right-edge: SUCCESS (3.431s)
[435.930928] [103/133] (609s left) gem_ctx_param (invalid-set-ringsize)
[435.968002] Starting subtest: invalid-set-ringsize
[435.968742] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[436.030073] [104/133] (609s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[436.125774] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[436.800523] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.674s)
[436.946006] [105/133] (609s left) kms_color (ctm-red-to-blue)
[437.031079] Starting subtest: ctm-red-to-blue
[437.031808] Starting dynamic subtest: pipe-A-HDMI-A-1
[437.342794] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.312s)
[437.352604] Starting dynamic subtest: pipe-B-HDMI-A-1
[437.646539] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.301s)
[437.658043] Starting dynamic subtest: pipe-C-HDMI-A-1
[437.942221] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.295s)
[437.953703] Starting dynamic subtest: pipe-D-HDMI-A-1
[438.248217] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.304s)
[438.249001] Subtest ctm-red-to-blue: SUCCESS (1.217s)
[438.403161] [106/133] (607s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[438.497972] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[438.499368] Subtest fbcpsr-rgb101010-draw-pwrite: SKIP (0.000s)
[438.592970] [107/133] (607s left) prime_self_import (reimport-vs-gem_close-race)
[438.625230] Starting subtest: reimport-vs-gem_close-race
[443.670040] Subtest reimport-vs-gem_close-race: SUCCESS (5.043s)
[443.759960] [108/133] (602s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[443.849447] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[443.851635] Starting dynamic subtest: pipe-A-HDMI-A-1
[443.858555] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[443.869509] Starting dynamic subtest: pipe-B-HDMI-A-1
[443.876369] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[443.887862] Starting dynamic subtest: pipe-C-HDMI-A-1
[443.894625] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[443.904844] Starting dynamic subtest: pipe-D-HDMI-A-1
[443.911865] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.000s)
[443.913270] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[444.088978] [109/133] (602s left) kms_feature_discovery (display-2x)
[444.177046] Starting subtest: display-2x
[444.178482] Subtest display-2x: SKIP (0.000s)
[444.239240] [110/133] (601s left) kms_chamelium_frames (hdmi-crc-multiple)
[444.377586] Subtest hdmi-crc-multiple: SKIP (0.000s)
[444.480931] [111/133] (601s left) prime_busy (hang)
[444.525573] Starting subtest: hang
[444.527798] Starting dynamic subtest: rcs0
[458.173861] Dynamic subtest rcs0: SUCCESS (13.612s)
[458.183970] Starting dynamic subtest: bcs0
[465.316456] Dynamic subtest bcs0: SUCCESS (7.167s)
[465.327234] Starting dynamic subtest: vcs0
[472.292229] Dynamic subtest vcs0: SUCCESS (6.975s)
[472.301450] Starting dynamic subtest: vcs1
[479.359170] Dynamic subtest vcs1: SUCCESS (7.039s)
[479.369171] Starting dynamic subtest: vecs0
[486.333934] Dynamic subtest vecs0: SUCCESS (6.974s)
[486.334830] Subtest hang: SUCCESS (41.773s)
[486.401372] [112/133] (559s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[486.489777] Starting subtest: cursorA-vs-flipB-atomic
[486.491215] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[486.551584] [113/133] (559s left) core_setmaster_vs_auth
[486.646419] [114/133] (559s left) kms_big_fb (linear-16bpp-rotate-90)
[486.740120] Starting subtest: linear-16bpp-rotate-90
[486.816265] Subtest linear-16bpp-rotate-90: SKIP (0.075s)
[486.914310] [115/133] (559s left) gem_userptr_blits (vma-merge)
[486.965837] Starting subtest: vma-merge
[487.018283] Subtest vma-merge: SUCCESS (0.052s)
[487.132096] [116/133] (559s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[487.220809] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[487.222157] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[487.320631] [117/133] (559s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[487.409972] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[488.849675] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (1.439s)
[488.988273] [118/133] (557s left) kms_invalid_mode (bad-hsync-start)
[489.074660] Starting subtest: bad-hsync-start
[489.076497] Starting dynamic subtest: pipe-A-HDMI-A-1
[489.123389] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.047s)
[489.135974] Starting dynamic subtest: pipe-B-HDMI-A-1
[489.136729] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.001s)
[489.145441] Starting dynamic subtest: pipe-C-HDMI-A-1
[489.146168] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.001s)
[489.154840] Starting dynamic subtest: pipe-D-HDMI-A-1
[489.155570] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.001s)
[489.156326] Subtest bad-hsync-start: SUCCESS (0.056s)
[489.262714] [119/133] (557s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[489.349985] Starting subtest: disable-crc-after-crtc
[489.350680] Starting dynamic subtest: pipe-A-HDMI-A-1
[489.696869] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.346s)
[489.706863] Starting dynamic subtest: pipe-B-HDMI-A-1
[490.009205] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.310s)
[490.022139] Starting dynamic subtest: pipe-C-HDMI-A-1
[490.347254] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.336s)
[490.360302] Starting dynamic subtest: pipe-D-HDMI-A-1
[490.664333] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.315s)
[490.665070] Subtest disable-crc-after-crtc: SUCCESS (1.316s)
[490.822548] [120/133] (555s left) gem_pipe_control_store_loop (fresh-buffer)
[490.860708] Starting subtest: fresh-buffer
[493.020102] Subtest fresh-buffer: SUCCESS (2.151s)
[493.127765] [121/133] (553s left) kms_properties (colorop-properties-atomic)
[493.215083] Starting subtest: colorop-properties-atomic
[493.215878] Starting dynamic subtest: pipe-A-HDMI-A-1
[493.285771] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.070s)
[493.298430] Starting dynamic subtest: pipe-B-HDMI-A-1
[493.428181] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.139s)
[493.439044] Starting dynamic subtest: pipe-C-HDMI-A-1
[493.560649] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.130s)
[493.570753] Starting dynamic subtest: pipe-D-HDMI-A-1
[493.678892] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.115s)
[493.679600] Subtest colorop-properties-atomic: SUCCESS (0.464s)
[493.821277] [122/133] (552s left) syncobj_timeline (transfer-timeline-point)
[493.859433] Starting subtest: transfer-timeline-point
[493.860784] Subtest transfer-timeline-point: SUCCESS (0.001s)
[493.931994] [123/133] (552s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[494.020918] Starting subtest: psr-shrfb-scaledprimary
[494.022273] Subtest psr-shrfb-scaledprimary: SKIP (0.000s)
[494.106188] [124/133] (552s left) kms_getfb (getfb-handle-zero)
[494.193676] Starting subtest: getfb-handle-zero
[494.194441] Subtest getfb-handle-zero: SUCCESS (0.000s)
[494.284753] [125/133] (552s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[494.370383] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.000s)
[494.411781] [126/133] (552s left) kms_chamelium_audio (dp-audio)
[494.555541] Subtest dp-audio: SKIP (0.000s)
[494.648158] [127/133] (552s left) gem_bad_reloc (negative-reloc-lut)
[494.689637] Starting subtest: negative-reloc-lut
[494.691889] Subtest negative-reloc-lut: SUCCESS (0.001s)
[494.801689] [128/133] (552s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[494.890921] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[494.892306] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[494.991885] [129/133] (551s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[495.080570] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[495.081933] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[495.176525] [130/133] (551s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-blt)
[495.263891] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-blt
[495.265265] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[495.370050] [131/133] (551s left) kms_dp_linktrain_fallback (dsc-fallback)
[495.454643] Starting subtest: dsc-fallback
[495.456814] Subtest dsc-fallback: SKIP (0.000s)
[495.516310] [132/133] (551s left) perf_pmu (busy-double-start)
[495.552085] Starting subtest: busy-double-start
[495.552825] Starting dynamic subtest: rcs0
[499.092780] Dynamic subtest rcs0: SUCCESS (3.540s)
[499.105782] Starting dynamic subtest: bcs0
[502.636680] Dynamic subtest bcs0: SUCCESS (3.541s)
[502.649345] Starting dynamic subtest: vcs0
[506.179651] Dynamic subtest vcs0: SUCCESS (3.540s)
[506.191706] Starting dynamic subtest: vcs1
[509.722745] Dynamic subtest vcs1: SUCCESS (3.540s)
[509.735193] Starting dynamic subtest: vecs0
[513.269732] Dynamic subtest vecs0: SUCCESS (3.544s)
[513.273986] Subtest busy-double-start: SUCCESS (17.718s)
[513.344273] [133/133] (533s left) gem_pread (self)
[513.377875] Starting subtest: self
[513.382866] Subtest self: SUCCESS (0.006s)
[513.465686] Closing watchdogs
results: parsing output: 0/ for test: kms_flip_scaled_crc
results: parsing output: 1/ for test: kms_flip_scaled_crc
results: parsing output: 2/ for test: kms_vblank
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: gem_mmap_gtt
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: gen3_render_mixed_blits
results: parsing output: 7/ for test: gem_eio
results: parsing output: 8/ for test: kms_async_flips
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: kms_plane
results: parsing output: 11/ for test: drm_read
results: parsing output: 12/ for test: kms_pm_rpm
results: parsing output: 13/ for test: kms_flip
results: parsing output: 14/ for test: i915_drm_fdinfo
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: gem_exec_schedule
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: kms_psr
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: gem_exec_nop
results: parsing output: 21/ for test: kms_cursor_crc
results: parsing output: 22/ for test: gem_exec_reloc
results: parsing output: 23/ for test: kms_ccs
results: parsing output: 24/ for test: gem_create
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: gem_exec_reloc
results: parsing output: 27/ for test: i915_hangman
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_cursor_crc
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: gem_mmap_offset
results: parsing output: 32/ for test: gem_ctx_persistence
results: parsing output: 33/ for test: gem_exec_balancer
results: parsing output: 34/ for test: api_intel_allocator
results: parsing output: 35/ for test: gem_pwrite_snooped
results: parsing output: 36/ for test: gem_exec_schedule
results: parsing output: 37/ for test: kms_chamelium_hpd
results: parsing output: 38/ for test: gem_exec_reloc
results: parsing output: 39/ for test: kms_atomic_transition
results: parsing output: 40/ for test: sysfs_heartbeat_interval
results: parsing output: 41/ for test: kms_properties
results: parsing output: 42/ for test: kms_getfb
results: parsing output: 43/ for test: kms_plane_scaling
results: parsing output: 44/ for test: kms_cursor_legacy
results: parsing output: 45/ for test: syncobj_basic
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: gem_render_copy
results: parsing output: 48/ for test: gem_mmap_gtt
results: parsing output: 49/ for test: gem_mmap_gtt
results: parsing output: 50/ for test: gem_exec_suspend
results: parsing output: 51/ for test: gem_eio
results: parsing output: 52/ for test: kms_psr2_sf
results: parsing output: 53/ for test: kms_psr
results: parsing output: 54/ for test: gem_exec_balancer
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: gem_lmem_swapping
results: parsing output: 57/ for test: gem_exec_schedule
results: parsing output: 58/ for test: gem_spin_batch
results: parsing output: 59/ for test: kms_pm_rpm
results: parsing output: 60/ for test: kms_big_fb
results: parsing output: 61/ for test: i915_query
results: parsing output: 62/ for test: kms_psr
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: gem_exec_whisper
results: parsing output: 65/ for test: perf
results: parsing output: 66/ for test: api_intel_bb
results: parsing output: 67/ for test: gem_sync
results: parsing output: 68/ for test: kms_vblank
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: perf_pmu
results: parsing output: 71/ for test: gem_softpin
results: parsing output: 72/ for test: kms_psr
results: parsing output: 73/ for test: gem_userptr_blits
results: parsing output: 74/ for test: kms_big_fb
results: parsing output: 75/ for test: kms_atomic
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_ctx_param
results: parsing output: 80/ for test: kms_hdmi_inject
results: parsing output: 81/ for test: kms_lease
results: parsing output: 82/ for test: gem_exec_fence
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_content_protection
results: parsing output: 85/ for test: kms_plane
results: parsing output: 86/ for test: i915_pm_freq_mult
results: parsing output: 87/ for test: gen9_exec_parse
results: parsing output: 88/ for test: perf
results: parsing output: 89/ for test: kms_addfb_basic
results: parsing output: 90/ for test: gem_ctx_bad_destroy
results: parsing output: 91/ for test: gem_fenced_exec_thrash
results: parsing output: 92/ for test: kms_pipe_crc_basic
results: parsing output: 93/ for test: kms_ccs
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: syncobj_timeline
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: syncobj_wait
results: parsing output: 98/ for test: kms_plane_lowres
results: parsing output: 99/ for test: gem_sync
results: parsing output: 100/ for test: core_setmaster
results: parsing output: 101/ for test: kms_cursor_edge_walk
results: parsing output: 102/ for test: gem_ctx_param
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: kms_color
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: prime_self_import
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: kms_feature_discovery
results: parsing output: 109/ for test: kms_chamelium_frames
results: parsing output: 110/ for test: prime_busy
results: parsing output: 111/ for test: kms_cursor_legacy
results: parsing output: 112/ for test: core_setmaster_vs_auth
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: gem_userptr_blits
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_invalid_mode
results: parsing output: 118/ for test: kms_pipe_crc_basic
results: parsing output: 119/ for test: gem_pipe_control_store_loop
results: parsing output: 120/ for test: kms_properties
results: parsing output: 121/ for test: syncobj_timeline
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_getfb
results: parsing output: 124/ for test: kms_psr2_sf
results: parsing output: 125/ for test: kms_chamelium_audio
results: parsing output: 126/ for test: gem_bad_reloc
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_dp_linktrain_fallback
results: parsing output: 131/ for test: perf_pmu
results: parsing output: 132/ for test: gem_pread
Done.