[58.188929] Initializing watchdogs
[58.189059]   /dev/watchdog0
[58.207079] [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]
[58.212039] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.214858] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.217189] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[58.328359] Starting subtest: fbc-tiling-linear
[62.104255] Subtest fbc-tiling-linear: SUCCESS (3.777s)
[63.258511] [002/156] (955s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[63.305999] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[63.379547] [003/156] (955s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[63.476405] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[63.478217] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[63.577579] [004/156] (955s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[63.670266] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[63.672055] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[63.760842] [005/156] (955s left) kms_force_connector_basic (prune-stale-modes)
[63.830578] Starting subtest: prune-stale-modes
[64.270097] Subtest prune-stale-modes: SUCCESS (0.440s)
[64.396827] [006/156] (954s left) perf_pmu (most-busy-check-all)
[64.436883] Starting subtest: most-busy-check-all
[64.438068] Starting dynamic subtest: rcs0
[66.347425] Dynamic subtest rcs0: SUCCESS (1.900s)
[66.356613] Starting dynamic subtest: bcs0
[68.328925] Dynamic subtest bcs0: SUCCESS (1.979s)
[68.337649] Starting dynamic subtest: vcs0
[70.311276] Dynamic subtest vcs0: SUCCESS (1.981s)
[70.320301] Starting dynamic subtest: vcs1
[72.358966] Dynamic subtest vcs1: SUCCESS (2.047s)
[72.367103] Starting dynamic subtest: vecs0
[74.343595] Dynamic subtest vecs0: SUCCESS (1.984s)
[74.352162] Starting dynamic subtest: ccs0
[76.329529] Dynamic subtest ccs0: SUCCESS (1.985s)
[76.330817] Subtest most-busy-check-all: SUCCESS (11.884s)
[76.433820] [007/156] (942s left) gem_ccs (large-ctrl-surf-copy)
[76.500028] Starting subtest: large-ctrl-surf-copy
[76.502116] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[76.590984] [008/156] (942s left) kms_plane_alpha_blend (coverage-7efc)
[76.686396] Starting subtest: coverage-7efc
[77.007559] Starting dynamic subtest: pipe-A-eDP-1
[77.587915] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.580s)
[77.617904] Starting dynamic subtest: pipe-D-eDP-1
[78.959465] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.347s)
[78.960332] Subtest coverage-7efc: SUCCESS (2.274s)
[79.772770] [009/156] (939s left) gem_mmap_gtt (fault-concurrent)
[79.828516] Subtest fault-concurrent: SKIP (0.000s)
[79.884147] [010/156] (939s left) api_intel_allocator (gem-pool)
[79.936122] Starting subtest: gem-pool
[79.943053] Subtest gem-pool: SUCCESS (0.008s)
[80.045687] [011/156] (938s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[80.145155] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[80.146860] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[80.241946] [012/156] (938s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[80.320936] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[80.322662] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[80.401776] [013/156] (938s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[80.479365] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[80.480411] Starting dynamic subtest: pipe-A-eDP-1
[80.489357] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[80.500229] Starting dynamic subtest: pipe-B-eDP-1
[80.508393] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[80.516440] Starting dynamic subtest: pipe-C-eDP-1
[80.524508] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[80.531123] Starting dynamic subtest: pipe-D-eDP-1
[80.539872] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[80.541524] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.004s)
[82.785658] [014/156] (936s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[82.878861] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[82.879885] Starting dynamic subtest: pipe-A-eDP-1
[82.890358] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[82.898835] Starting dynamic subtest: pipe-B-eDP-1
[82.907568] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[82.914688] Starting dynamic subtest: pipe-C-eDP-1
[82.922554] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[82.929476] Starting dynamic subtest: pipe-D-eDP-1
[82.937771] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[82.939706] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[85.174130] [015/156] (933s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[85.268100] Starting subtest: hdr-rgb565-draw-blt
[85.270037] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[85.372723] [016/156] (933s left) kms_pm_rpm (legacy-planes-dpms)
[87.427927] Starting subtest: legacy-planes-dpms
[87.429027] Starting dynamic subtest: plane-65
[95.758029] Dynamic subtest plane-65: SUCCESS (8.329s)
[95.768301] Starting dynamic subtest: plane-95
[104.060676] Dynamic subtest plane-95: SUCCESS (8.302s)
[104.069142] Starting dynamic subtest: plane-125
[112.697059] Dynamic subtest plane-125: SUCCESS (8.636s)
[112.707551] Starting dynamic subtest: plane-135
[121.403712] Dynamic subtest plane-135: SUCCESS (8.705s)
[121.405474] Subtest legacy-planes-dpms: SUCCESS (33.977s)
[121.797028] [017/156] (897s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[121.854238] Starting subtest: x-tiled-to-vebox-y-tiled
[121.856130] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[122.045041] [018/156] (897s left) sysfs_heartbeat_interval (invalid)
[122.098348] Starting subtest: invalid
[122.099419] Starting dynamic subtest: vecs0
[122.100440] Dynamic subtest vecs0: SUCCESS (0.000s)
[122.108625] Starting dynamic subtest: ccs0
[122.109475] Dynamic subtest ccs0: SUCCESS (0.000s)
[122.116520] Starting dynamic subtest: vcs0
[122.117443] Dynamic subtest vcs0: SUCCESS (0.000s)
[122.124136] Starting dynamic subtest: bcs0
[122.125019] Dynamic subtest bcs0: SUCCESS (0.000s)
[122.131557] Starting dynamic subtest: rcs0
[122.132489] Dynamic subtest rcs0: SUCCESS (0.000s)
[122.139077] Starting dynamic subtest: vcs1
[122.139935] Dynamic subtest vcs1: SUCCESS (0.000s)
[122.140835] Subtest invalid: SUCCESS (0.003s)
[122.207752] [019/156] (897s left) kms_frontbuffer_tracking (fbc-stridechange)
[122.303905] Starting subtest: fbc-stridechange
[125.671370] Subtest fbc-stridechange: SUCCESS (3.368s)
[126.876365] [020/156] (892s left) gem_ctx_param (basic-default)
[126.925619] Starting subtest: basic-default
[126.927085] Subtest basic-default: SUCCESS (0.000s)
[126.987342] [021/156] (892s left) kms_psr (fbc-pr-primary-mmap-gtt)
[127.076558] Starting subtest: fbc-pr-primary-mmap-gtt
[127.078180] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[127.151952] [022/156] (892s left) i915_pm_freq_mult (media-freq)
[127.202435] Starting subtest: media-freq
[127.203431] Starting dynamic subtest: gt0
[127.205331] Dynamic subtest gt0: SKIP (0.000s)
[127.213233] Starting dynamic subtest: gt1
[127.214919] Dynamic subtest gt1: SKIP (0.000s)
[127.216855] Subtest media-freq: SKIP (0.001s)
[127.297449] [023/156] (892s left) prime_busy (before-wait)
[127.349033] Starting subtest: before-wait
[127.349764] Starting dynamic subtest: rcs0
[127.377354] Dynamic subtest rcs0: SUCCESS (0.030s)
[127.385122] Starting dynamic subtest: bcs0
[127.417994] Dynamic subtest bcs0: SUCCESS (0.040s)
[127.425289] Starting dynamic subtest: vcs0
[127.459995] Dynamic subtest vcs0: SUCCESS (0.042s)
[127.467112] Starting dynamic subtest: vcs1
[127.499467] Dynamic subtest vcs1: SUCCESS (0.039s)
[127.506238] Starting dynamic subtest: vecs0
[127.540526] Dynamic subtest vecs0: SUCCESS (0.041s)
[127.547181] Starting dynamic subtest: ccs0
[127.583630] Dynamic subtest ccs0: SUCCESS (0.043s)
[127.704608] Subtest before-wait: SUCCESS (0.348s)
[127.770638] [024/156] (891s left) gem_exec_gttfill (engines)
[127.824869] Starting subtest: engines
[127.825814] Starting dynamic subtest: rcs0
[150.690686] Dynamic subtest rcs0: SUCCESS (22.863s)
[150.699930] Starting dynamic subtest: bcs0
[174.102259] Dynamic subtest bcs0: SUCCESS (23.400s)
[174.112378] Starting dynamic subtest: vcs0
[197.383110] Dynamic subtest vcs0: SUCCESS (23.287s)
[197.392101] Starting dynamic subtest: vcs1
[220.698905] Dynamic subtest vcs1: SUCCESS (23.314s)
[220.708752] Starting dynamic subtest: vecs0
[244.060398] Dynamic subtest vecs0: SUCCESS (23.359s)
[244.068440] Starting dynamic subtest: ccs0
[267.445389] Dynamic subtest ccs0: SUCCESS (23.383s)
[267.446420] Subtest engines: SUCCESS (139.614s)
[267.630930] [025/156] (751s left) syncobj_timeline (etime-multi-wait-all-submitted)
[267.741604] Starting subtest: etime-multi-wait-all-submitted
[267.850717] Subtest etime-multi-wait-all-submitted: SUCCESS (0.108s)
[267.924196] [026/156] (751s left) gem_ctx_param (get-priority-new-ctx)
[267.982379] Starting subtest: get-priority-new-ctx
[267.983349] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[268.103345] [027/156] (751s left) kms_big_fb (linear-32bpp-rotate-90)
[268.222239] Starting subtest: linear-32bpp-rotate-90
[268.242862] Subtest linear-32bpp-rotate-90: SKIP (0.020s)
[268.343240] [028/156] (751s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[268.379110] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[268.414670] [029/156] (751s left) gem_ctx_shared (disjoint-timelines)
[268.472466] Starting subtest: disjoint-timelines
[268.564079] Subtest disjoint-timelines: SUCCESS (0.091s)
[268.773857] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[268.775665] [030/156] (750s left) kms_psr (fbc-psr2-cursor-plane-move)
[268.878532] Starting subtest: fbc-psr2-cursor-plane-move
[268.879593] Starting dynamic subtest: eDP-1
[268.881532] Dynamic subtest eDP-1: SKIP (0.000s)
[268.883536] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[268.961826] [031/156] (750s left) gem_bad_reloc (negative-reloc-bltcopy)
[269.013464] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[269.085144] [032/156] (750s left) kms_chamelium_hpd (hdmi-hpd)
[269.463391] Subtest hdmi-hpd: SKIP (0.000s)
[270.247144] [033/156] (749s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[270.334937] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[270.336699] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[270.401678] [034/156] (749s left) gem_eio (wait-10ms)
[270.623026] Starting subtest: wait-10ms
[270.985655] Subtest wait-10ms: SUCCESS (0.363s)
[271.122814] [035/156] (748s left) kms_content_protection (legacy-hdcp14)
[271.265872] Starting subtest: legacy-hdcp14
[271.267898] Subtest legacy-hdcp14: SKIP (0.000s)
[271.369624] [036/156] (748s left) syncobj_wait (reset-signaled)
[271.423621] Starting subtest: reset-signaled
[271.424853] Subtest reset-signaled: SUCCESS (0.000s)
[271.514996] [037/156] (748s left) gem_ctx_isolation (dirty-switch)
[271.568255] Starting subtest: dirty-switch
[271.569122] Starting dynamic subtest: rcs0
[271.762158] Dynamic subtest rcs0: SUCCESS (0.195s)
[271.771286] Starting dynamic subtest: bcs0
[271.984753] Dynamic subtest bcs0: SUCCESS (0.222s)
[271.994988] Starting dynamic subtest: vcs0
[272.200377] Dynamic subtest vcs0: SUCCESS (0.215s)
[272.210454] Starting dynamic subtest: vcs1
[273.485737] Dynamic subtest vcs1: SUCCESS (1.285s)
[273.496966] Starting dynamic subtest: vecs0
[275.488381] Dynamic subtest vecs0: SUCCESS (2.002s)
[275.498736] Starting dynamic subtest: ccs0
[277.560470] Dynamic subtest ccs0: SUCCESS (2.071s)
[277.561409] Subtest dirty-switch: SUCCESS (5.993s)
[277.780697] [038/156] (742s left) gem_flink_basic (bad-open)
[277.824135] Starting subtest: bad-open
[277.825058] Subtest bad-open: SUCCESS (0.000s)
[277.912627] [039/156] (742s left) gem_caching (writes)
[277.965913] Subtest writes: SKIP (0.000s)
[278.024711] [040/156] (741s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[278.112848] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[278.113828] Starting dynamic subtest: pipe-A
[278.451922] Dynamic subtest pipe-A: SUCCESS (0.339s)
[278.461645] Starting dynamic subtest: pipe-B
[279.934828] Dynamic subtest pipe-B: SUCCESS (1.482s)
[279.944855] Starting dynamic subtest: pipe-C
[281.321222] Dynamic subtest pipe-C: SUCCESS (1.386s)
[281.329345] Starting dynamic subtest: pipe-D
[282.723771] Dynamic subtest pipe-D: SUCCESS (1.402s)
[282.724986] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.612s)
[283.865873] [041/156] (736s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[283.913210] Starting subtest: single-wait-all-for-submit-available-submitted
[283.914128] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[284.015812] [042/156] (736s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[284.107554] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[284.109232] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[284.203623] [043/156] (735s left) kms_pm_rpm (system-suspend-idle)
[286.463038] Starting subtest: system-suspend-idle
[306.658763] Subtest system-suspend-idle: SUCCESS (5.153s)
[310.104376] [044/156] (713s left) kms_atomic_transition (modeset-transition)
[310.196469] Starting subtest: modeset-transition
[310.197548] Starting dynamic subtest: 1x-outputs
[321.034121] Dynamic subtest 1x-outputs: SUCCESS (10.828s)
[321.035594] Subtest modeset-transition: SUCCESS (10.830s)
[321.862065] [045/156] (701s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[321.964443] Starting subtest: pipe-fbc-rte
[321.967521] Starting dynamic subtest: pipe-B-eDP-1
[327.378585] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.414s)
[327.383064] Subtest pipe-fbc-rte: SUCCESS (5.416s)
[329.660865] [046/156] (693s left) gem_spin_batch (resubmit-all)
[329.716513] Starting subtest: resubmit-all
[329.718546] Starting dynamic subtest: rcs0
[329.730073] Dynamic subtest rcs0: SUCCESS (0.014s)
[329.742120] Starting dynamic subtest: bcs0
[329.743452] Dynamic subtest bcs0: SUCCESS (0.002s)
[329.752389] Starting dynamic subtest: vcs0
[329.753403] Dynamic subtest vcs0: SUCCESS (0.002s)
[329.762076] Starting dynamic subtest: vcs1
[329.763045] Dynamic subtest vcs1: SUCCESS (0.002s)
[329.772111] Starting dynamic subtest: vecs0
[329.773223] Dynamic subtest vecs0: SUCCESS (0.002s)
[329.782547] Starting dynamic subtest: ccs0
[329.783816] Dynamic subtest ccs0: SUCCESS (0.002s)
[329.785279] Subtest resubmit-all: SUCCESS (0.026s)
[331.400008] [047/156] (691s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[331.496361] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[331.498476] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[331.586344] [048/156] (691s left) syncobj_basic (illegal-fd-to-handle)
[331.641351] Starting subtest: illegal-fd-to-handle
[331.642509] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[331.757460] [049/156] (691s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[331.851228] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[331.853068] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[331.958607] [050/156] (691s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[332.049430] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[333.161662] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.113s)
[333.286606] [051/156] (690s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[333.339065] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[333.456645] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.108s)
[333.529221] [052/156] (689s left) api_intel_bb (misplaced-blitter)
[333.575428] Starting subtest: misplaced-blitter
[333.582421] Subtest misplaced-blitter: SUCCESS (0.008s)
[333.688744] [053/156] (689s left) kms_atomic_interruptible (universal-setplane-cursor)
[333.788691] Starting subtest: universal-setplane-cursor
[333.790110] Starting dynamic subtest: pipe-A-eDP-1
[341.245314] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.454s)
[341.246351] Subtest universal-setplane-cursor: SUCCESS (7.455s)
[342.061054] [054/156] (681s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[342.166591] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[342.169165] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[342.255373] [055/156] (681s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[342.345176] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[342.347486] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[342.437569] [056/156] (681s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[342.520214] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[342.522416] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[342.586971] [057/156] (681s left) gem_pxp (create-protected-buffer)
[342.704944] Starting subtest: create-protected-buffer
[342.706515] Subtest create-protected-buffer: SUCCESS (0.000s)
[342.808002] [058/156] (680s left) gem_fence_thrash (bo-write-verify-threaded-x)
[342.837470] Starting subtest: bo-write-verify-threaded-x
[342.852581] Subtest bo-write-verify-threaded-x: SKIP (0.013s)
[342.914183] [059/156] (680s left) gen9_exec_parse (allowed-single)
[342.963016] Subtest allowed-single: SKIP (0.000s)
[343.028736] [060/156] (680s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[343.114537] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[343.116544] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[343.201911] [061/156] (680s left) gem_exec_balancer (bonded-semaphore)
[343.253696] Starting subtest: bonded-semaphore
[343.255749] Subtest bonded-semaphore: SKIP (0.000s)
[343.565896] [062/156] (680s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[343.650051] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[343.652334] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[343.717426] [063/156] (680s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[343.813274] Starting subtest: fbc-rgb565-draw-mmap-wc
[347.973788] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.161s)
[349.173016] [064/156] (674s left) kms_pwrite_crc (basic)
[349.259552] Starting subtest: basic
[349.953000] Subtest basic: SUCCESS (0.693s)
[350.764457] [065/156] (673s left) gem_flink_basic (bad-flink)
[350.811450] Starting subtest: bad-flink
[350.812605] Subtest bad-flink: SUCCESS (0.000s)
[350.900069] [066/156] (673s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[351.001124] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[351.003012] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[351.090555] [067/156] (672s left) gem_ctx_persistence (legacy-engines-persistence)
[351.145057] Starting subtest: legacy-engines-persistence
[351.146455] Starting dynamic subtest: render
[351.969071] Dynamic subtest render: SUCCESS (0.823s)
[351.982784] Starting dynamic subtest: bsd1
[352.736054] Dynamic subtest bsd1: SUCCESS (0.763s)
[352.748279] Starting dynamic subtest: bsd2
[353.493664] Dynamic subtest bsd2: SUCCESS (0.754s)
[353.506041] Starting dynamic subtest: blt
[354.269925] Dynamic subtest blt: SUCCESS (0.774s)
[354.284487] Starting dynamic subtest: vebox
[355.039672] Dynamic subtest vebox: SUCCESS (0.767s)
[355.041237] Subtest legacy-engines-persistence: SUCCESS (3.895s)
[355.115370] [068/156] (668s left) gen7_exec_parse (basic-allowed)
[355.169135] Subtest basic-allowed: SKIP (0.000s)
[355.228156] [069/156] (668s left) gem_exec_schedule (reorder-wide)
[355.394630] Subtest reorder-wide: SKIP (0.000s)
[355.573382] [070/156] (668s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[355.669195] Starting subtest: psrhdr-slowdraw
[355.671356] Subtest psrhdr-slowdraw: SKIP (0.000s)
[355.762932] [071/156] (668s left) perf (non-zero-reason)
[355.896499] Starting subtest: non-zero-reason
[355.897791] Starting dynamic subtest: 0-rcs0
[361.660080] Dynamic subtest 0-rcs0: SUCCESS (5.716s)
[361.672666] Starting dynamic subtest: 1-vcs1
[365.691486] Dynamic subtest 1-vcs1: SUCCESS (4.066s)
[365.693207] Subtest non-zero-reason: SUCCESS (9.783s)
[365.732021] [072/156] (658s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[365.827887] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[365.829591] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[365.923836] [073/156] (658s left) i915_drm_fdinfo (all-busy-check-all)
[365.958046] Starting subtest: all-busy-check-all
[365.963147] Subtest all-busy-check-all: SKIP (0.003s)
[366.028249] [074/156] (658s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[366.118295] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[366.120402] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[366.202303] [075/156] (658s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[366.299191] Starting subtest: compare-crc-sanitycheck-nv12
[366.299966] Starting dynamic subtest: pipe-A-eDP-1
[367.028412] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.727s)
[367.038825] Starting dynamic subtest: pipe-B-eDP-1
[368.358598] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.329s)
[368.368610] Starting dynamic subtest: pipe-C-eDP-1
[369.576068] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.217s)
[369.588788] Starting dynamic subtest: pipe-D-eDP-1
[370.874700] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.297s)
[370.876305] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.575s)
[371.771522] [076/156] (652s left) gem_exec_reloc (basic-write-gtt)
[371.826568] Subtest basic-write-gtt: SKIP (0.000s)
[371.902060] [077/156] (652s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[372.197208] Starting subtest: cursor-rapid-movement-max-size
[372.200574] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[372.305907] [078/156] (652s left) kms_color_pipeline (plane-lut1d)
[372.397452] Starting subtest: plane-lut1d
[372.521578] Starting dynamic subtest: pipe-A-plane-0
[372.732456] Dynamic subtest pipe-A-plane-0: SUCCESS (0.211s)
[372.742011] Starting dynamic subtest: pipe-A-plane-1
[372.899662] Dynamic subtest pipe-A-plane-1: SUCCESS (0.166s)
[372.909526] Starting dynamic subtest: pipe-A-plane-2
[373.077325] Dynamic subtest pipe-A-plane-2: SUCCESS (0.177s)
[374.328770] Starting dynamic subtest: pipe-B-plane-0
[374.519495] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s)
[374.529202] Starting dynamic subtest: pipe-B-plane-1
[374.686152] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[374.698589] Starting dynamic subtest: pipe-B-plane-2
[374.852759] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[376.060536] Starting dynamic subtest: pipe-C-plane-0
[376.196254] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[376.209781] Starting dynamic subtest: pipe-C-plane-1
[376.351359] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[376.361104] Starting dynamic subtest: pipe-C-plane-2
[376.507275] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[377.743150] Starting dynamic subtest: pipe-D-plane-0
[377.879625] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[377.891601] Starting dynamic subtest: pipe-D-plane-1
[378.023222] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[378.033132] Starting dynamic subtest: pipe-D-plane-2
[378.167441] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[378.465666] Subtest plane-lut1d: SUCCESS (6.069s)
[379.276915] [079/156] (645s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[379.384554] Starting subtest: yf-tiled-16bpp-rotate-180
[379.386933] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[379.481012] [080/156] (645s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[379.584755] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[380.590048] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.004s)
[380.704897] [081/156] (643s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[380.797981] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[380.799814] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[380.886242] [082/156] (643s left) gem_exec_reloc (basic-wc-read)
[380.937785] Subtest basic-wc-read: SKIP (0.000s)
[380.990644] [083/156] (643s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[381.102816] Starting subtest: short-busy-flip-before-cursor-toggle
[381.104910] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[381.256149] [084/156] (643s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[381.359581] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[381.372491] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[381.447019] [085/156] (643s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[381.535275] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[381.537055] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[381.627455] [086/156] (643s left) kms_draw_crc (draw-method-render)
[381.716982] Starting subtest: draw-method-render
[381.717981] Starting dynamic subtest: xrgb8888-untiled
[381.972555] Dynamic subtest xrgb8888-untiled: SUCCESS (0.256s)
[381.984284] Starting dynamic subtest: rgb565-untiled
[382.205381] Dynamic subtest rgb565-untiled: SUCCESS (0.232s)
[382.215320] Starting dynamic subtest: xrgb2101010-untiled
[382.428320] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s)
[382.440252] Starting dynamic subtest: xbgr16161616f-untiled
[382.683538] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.253s)
[382.697104] Starting dynamic subtest: xrgb8888-xtiled
[382.817311] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s)
[382.828256] Starting dynamic subtest: rgb565-xtiled
[382.939206] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[382.955381] Starting dynamic subtest: xrgb2101010-xtiled
[383.061549] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[383.071469] Starting dynamic subtest: xbgr16161616f-xtiled
[383.194504] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s)
[383.205059] Starting dynamic subtest: xrgb8888-4tiled
[383.328125] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[383.338769] Starting dynamic subtest: rgb565-4tiled
[383.439570] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[383.449387] Starting dynamic subtest: xrgb2101010-4tiled
[383.561074] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.121s)
[383.571384] Starting dynamic subtest: xbgr16161616f-4tiled
[383.695074] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[383.696556] Subtest draw-method-render: SUCCESS (1.979s)
[383.796320] [087/156] (640s left) kms_cursor_crc (cursor-sliding-256x85)
[384.048407] Starting subtest: cursor-sliding-256x85
[384.094174] Subtest cursor-sliding-256x85: SKIP (0.044s)
[384.260635] [088/156] (640s left) kms_cursor_crc (cursor-onscreen-256x256)
[384.502938] Starting subtest: cursor-onscreen-256x256
[384.506848] Starting dynamic subtest: pipe-A-eDP-1
[386.664342] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.157s)
[386.695013] Starting dynamic subtest: pipe-D-eDP-1
[389.423573] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.738s)
[389.424476] Subtest cursor-onscreen-256x256: SUCCESS (4.920s)
[390.271414] [089/156] (634s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[390.366261] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[390.368029] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[390.457646] [090/156] (634s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[390.554895] Starting subtest: fbcpsr-slowdraw
[415.778515] Subtest fbcpsr-slowdraw: SUCCESS (25.227s)
[415.910229] [091/156] (609s left) kms_lease (cursor-implicit-plane)
[415.993277] Starting subtest: cursor-implicit-plane
[415.994640] Starting dynamic subtest: pipe-A-eDP-1
[416.442456] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.448s)
[416.453804] Starting dynamic subtest: pipe-B-eDP-1
[417.549460] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.104s)
[417.559564] Starting dynamic subtest: pipe-C-eDP-1
[418.625372] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.074s)
[418.636559] Starting dynamic subtest: pipe-D-eDP-1
[419.736555] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.111s)
[419.737502] Subtest cursor-implicit-plane: SUCCESS (3.745s)
[420.563295] [092/156] (604s left) syncobj_wait (invalid-reset-zero-handles)
[420.614263] Starting subtest: invalid-reset-zero-handles
[420.615190] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[420.733384] [093/156] (604s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[420.834322] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[420.836534] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[420.926353] [094/156] (604s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[421.028407] Starting subtest: yf-tiled-64bpp-rotate-90
[421.031113] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[421.129085] [095/156] (603s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[421.227464] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[421.229706] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[421.320080] [096/156] (603s left) gem_exec_fence (long-history)
[421.757520] Starting subtest: long-history
[429.098823] Subtest long-history: SUCCESS (7.335s)
[429.681488] [097/156] (595s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[429.787520] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[437.265991] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.481s)
[437.387337] [098/156] (587s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[437.476138] Starting subtest: bad-rotation-90-yf-tiled-ccs
[437.477387] Starting dynamic subtest: pipe-A-eDP-1
[437.487023] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[437.497494] Starting dynamic subtest: pipe-B-eDP-1
[437.508453] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[437.518417] Starting dynamic subtest: pipe-C-eDP-1
[437.528683] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[437.538308] Starting dynamic subtest: pipe-D-eDP-1
[437.549971] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[437.551700] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.005s)
[439.752304] [099/156] (585s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[439.862551] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[457.797036] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (17.934s)
[458.653103] [100/156] (566s left) kms_chamelium_audio (dp-audio-edid)
[459.041334] Subtest dp-audio-edid: SKIP (0.000s)
[459.830747] [101/156] (565s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[459.918399] Starting subtest: addfb25-yf-tiled-legacy
[459.919646] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[460.031381] [102/156] (565s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[460.119284] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[460.121192] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[460.211486] [103/156] (565s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[460.304106] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[460.306381] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[460.401540] [104/156] (565s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[460.490045] Starting subtest: fbc-2p-pri-indfb-multidraw
[460.491911] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[460.582432] [105/156] (564s left) kms_sequence (queue-busy)
[460.666721] Starting subtest: queue-busy
[460.667707] Starting dynamic subtest: pipe-A-eDP-1
[462.596286] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.928s)
[462.606414] Starting dynamic subtest: pipe-B-eDP-1
[465.232567] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.635s)
[465.242624] Starting dynamic subtest: pipe-C-eDP-1
[467.806587] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.574s)
[467.816672] Starting dynamic subtest: pipe-D-eDP-1
[470.406068] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.597s)
[470.407403] Subtest queue-busy: SUCCESS (9.739s)
[471.255016] [106/156] (554s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[471.343351] Starting subtest: plane-upscale-20x20-with-rotation
[471.344288] Starting dynamic subtest: pipe-A
[471.569688] Dynamic subtest pipe-A: SUCCESS (0.227s)
[471.579380] Starting dynamic subtest: pipe-B
[472.910428] Dynamic subtest pipe-B: SUCCESS (1.340s)
[472.920631] Starting dynamic subtest: pipe-C
[474.211060] Dynamic subtest pipe-C: SUCCESS (1.299s)
[474.222113] Starting dynamic subtest: pipe-D
[475.508213] Dynamic subtest pipe-D: SUCCESS (1.296s)
[475.509425] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.166s)
[476.653066] [107/156] (548s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[476.748484] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[476.750589] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[476.846643] [108/156] (548s left) gem_exec_reloc (basic-write-wc)
[476.902565] Subtest basic-write-wc: SKIP (0.000s)
[476.967520] [109/156] (548s left) syncobj_timeline (reset-signaled)
[477.022662] Starting subtest: reset-signaled
[477.024217] Subtest reset-signaled: SUCCESS (0.000s)
[477.118125] [110/156] (548s left) kms_psr (pr-cursor-plane-move)
[477.199371] Starting subtest: pr-cursor-plane-move
[477.202167] Subtest pr-cursor-plane-move: SKIP (0.000s)
[477.282220] [111/156] (548s left) syncobj_wait (invalid-wait-illegal-handle)
[477.333665] Starting subtest: invalid-wait-illegal-handle
[477.335038] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[477.424510] [112/156] (548s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[477.517693] Starting subtest: extended-pageflip-modeset-hang-oldfb
[477.519072] Starting dynamic subtest: pipe-A
[485.410208] Dynamic subtest pipe-A: SUCCESS (7.892s)
[485.421534] Starting dynamic subtest: pipe-D
[494.035153] Dynamic subtest pipe-D: SUCCESS (8.620s)
[494.036523] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.516s)
[494.853874] [113/156] (530s left) kms_psr (psr2-primary-blt)
[494.943189] Starting subtest: psr2-primary-blt
[494.944448] Starting dynamic subtest: eDP-1
[496.725168] Dynamic subtest eDP-1: SUCCESS (1.782s)
[496.726788] Subtest psr2-primary-blt: SUCCESS (1.783s)
[497.551629] [114/156] (528s left) gem_softpin (evict-snoop-interruptible)
[497.606417] Starting subtest: evict-snoop-interruptible
[497.609359] Subtest evict-snoop-interruptible: SKIP (0.000s)
[497.722496] [115/156] (528s left) kms_force_connector_basic (force-load-detect)
[497.792403] Starting subtest: force-load-detect
[497.794657] Subtest force-load-detect: SKIP (0.000s)
[497.907317] [116/156] (527s left) kms_chamelium_edid (hdmi-mode-timings)
[498.278773] Subtest hdmi-mode-timings: SKIP (0.000s)
[499.138143] [117/156] (526s left) syncobj_timeline (invalid-signal-zero-handles)
[499.180176] Starting subtest: invalid-signal-zero-handles
[499.181126] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[499.274331] [118/156] (526s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[499.361339] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[499.363766] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[499.448500] [119/156] (526s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[499.533100] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[499.534755] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[499.634132] [120/156] (526s left) gem_mmap_gtt (fault-concurrent-x)
[499.689419] Subtest fault-concurrent-X: SKIP (0.000s)
[499.756467] [121/156] (526s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[499.809579] Starting subtest: y-tiled-to-vebox-y-tiled
[499.812297] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[499.986495] [122/156] (526s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[500.079516] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[500.082396] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[500.174485] [123/156] (525s left) vgem_basic (debugfs)
[500.217560] Starting subtest: debugfs
[500.219436] Subtest debugfs: SUCCESS (0.000s)
[500.271126] [124/156] (525s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[500.366374] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[500.367305] Starting dynamic subtest: pipe-A-default-mode
[501.493370] Dynamic subtest pipe-A-default-mode: SKIP (1.124s)
[501.495215] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.125s)
[501.624718] [125/156] (524s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[501.721931] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[501.724032] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[501.814170] [126/156] (524s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[501.904393] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[501.906277] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[501.998290] [127/156] (524s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[502.095321] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[502.098029] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[502.187418] [128/156] (524s left) kms_vblank (wait-forked-hang)
[502.271004] Starting subtest: wait-forked-hang
[502.272061] Starting dynamic subtest: pipe-A-eDP-1
[529.179546] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.904s)
[529.191611] Starting dynamic subtest: pipe-D-eDP-1
[544.176098] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.994s)
[544.177407] Subtest wait-forked-hang: SUCCESS (41.903s)
[545.050043] [129/156] (481s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[545.131755] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[545.133162] Starting dynamic subtest: pipe-A-default-mode
[546.281544] Dynamic subtest pipe-A-default-mode: SKIP (1.147s)
[546.283932] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.149s)
[546.448402] [130/156] (479s left) gem_ctx_exec (basic-invalid-context)
[546.493649] Starting subtest: basic-invalid-context
[546.498955] Subtest basic-invalid-context: SUCCESS (0.006s)
[546.615239] [131/156] (479s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[546.708358] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[546.710438] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[546.793109] [132/156] (479s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[546.887789] Starting subtest: fbc-psr2-cursor-plane-update-sf
[546.888894] Starting dynamic subtest: pipe-A-eDP-1
[546.971556] Dynamic subtest pipe-A-eDP-1: SKIP (0.082s)
[546.981891] Starting dynamic subtest: pipe-B-eDP-1
[548.094009] Dynamic subtest pipe-B-eDP-1: SKIP (1.121s)
[548.096151] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.206s)
[549.348601] [133/156] (477s left) gem_ppgtt (blt-vs-render-ctxn)
[549.401175] Starting subtest: blt-vs-render-ctxN
[582.205141] Subtest blt-vs-render-ctxN: SUCCESS (32.794s)
[582.336561] [134/156] (444s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[582.468428] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[582.536187] [135/156] (444s left) gem_softpin (invalid)
[582.591672] Starting subtest: invalid
[582.601458] Subtest invalid: SUCCESS (0.010s)
[582.721239] [136/156] (443s left) gem_exec_capture (capture)
[582.771207] Starting subtest: capture
[582.772629] Starting dynamic subtest: rcs0-smem
[583.620312] Dynamic subtest rcs0-smem: SUCCESS (0.847s)
[583.631143] Starting dynamic subtest: bcs0-smem
[584.640186] Dynamic subtest bcs0-smem: SUCCESS (1.019s)
[584.651137] Starting dynamic subtest: vcs0-smem
[585.486623] Dynamic subtest vcs0-smem: SUCCESS (0.846s)
[585.498229] Starting dynamic subtest: vcs1-smem
[586.327425] Dynamic subtest vcs1-smem: SUCCESS (0.841s)
[586.339060] Starting dynamic subtest: vecs0-smem
[587.689989] Dynamic subtest vecs0-smem: FAIL (1.333s)
[587.699750] Starting dynamic subtest: ccs0-smem
[588.980165] Dynamic subtest ccs0-smem: SUCCESS (1.318s)
[588.981365] Subtest capture: FAIL (6.209s)
[589.091450] [137/156] (437s left) gem_tiled_blits (basic)
[589.152434] Subtest basic: SKIP (0.000s)
[589.212827] [138/156] (437s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[589.322143] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[596.756032] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.436s)
[596.877545] [139/156] (429s left) gem_mmap_offset (partial-remap)
[596.939188] Starting subtest: partial-remap
[596.940585] Starting dynamic subtest: smem0
[596.946069] Dynamic subtest smem0: SUCCESS (0.004s)
[596.947333] Subtest partial-remap: SUCCESS (0.004s)
[597.055671] [140/156] (429s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[597.151664] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[597.153409] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[597.243933] [141/156] (429s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[597.331155] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[597.332986] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[597.446585] [142/156] (429s left) i915_power (sanity)
[597.494365] Starting subtest: sanity
[601.554753] Subtest sanity: SUCCESS (4.048s)
[603.367924] [143/156] (423s left) gem_exec_schedule (preempt-queue)
[603.446460] Subtest preempt-queue: SKIP (0.000s)
[603.757290] [144/156] (423s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[603.884371] Starting subtest: cursorB-vs-flipA-legacy
[603.886353] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[604.023331] [145/156] (422s left) i915_pm_rps (reset)
[604.089619] Starting subtest: reset
[612.541076] Subtest reset: SUCCESS (8.445s)
[612.636943] [146/156] (414s left) kms_psr (fbc-psr-cursor-plane-onoff)
[612.724062] Starting subtest: fbc-psr-cursor-plane-onoff
[612.725017] Starting dynamic subtest: eDP-1
[612.726753] Dynamic subtest eDP-1: SKIP (0.000s)
[612.728491] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[612.797860] [147/156] (414s left) kms_setmode (clone-exclusive-crtc)
[612.852414] Starting subtest: clone-exclusive-crtc
[612.856323] Subtest clone-exclusive-crtc: SKIP (0.000s)
[612.963215] [148/156] (414s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[613.055140] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[619.928771] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.875s)
[620.068315] [149/156] (407s left) kms_flip (flip-vs-rmfb)
[620.164803] Starting subtest: flip-vs-rmfb
[620.165912] Starting dynamic subtest: A-eDP1
[628.904287] Dynamic subtest A-eDP1: SUCCESS (8.738s)
[628.915425] Starting dynamic subtest: B-eDP1
[637.603837] Dynamic subtest B-eDP1: SUCCESS (8.698s)
[637.616075] Starting dynamic subtest: C-eDP1
[646.300329] Dynamic subtest C-eDP1: SUCCESS (8.694s)
[646.311112] Starting dynamic subtest: D-eDP1
[654.992684] Dynamic subtest D-eDP1: SUCCESS (8.690s)
[654.997300] Subtest flip-vs-rmfb: SUCCESS (34.831s)
[656.240558] [150/156] (370s left) gem_exec_whisper (basic-contexts-all)
[656.299471] Starting subtest: basic-contexts-all
[666.478560] Subtest basic-contexts-all: SUCCESS (10.166s)
[666.655858] [151/156] (360s left) dmabuf_sync_file (export-basic)
[666.696946] Starting subtest: export-basic
[666.698693] Subtest export-basic: SUCCESS (0.001s)
[666.751179] [152/156] (360s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[666.848064] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[666.849978] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[666.937499] [153/156] (360s left) gem_sync (basic-each)
[667.000055] Starting subtest: basic-each
[669.446022] Subtest basic-each: SUCCESS (2.448s)
[669.630907] [154/156] (357s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[669.731356] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[669.732342] Starting dynamic subtest: pipe-A-eDP-1
[669.743288] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[669.753649] Starting dynamic subtest: pipe-B-eDP-1
[669.765494] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[669.775876] Starting dynamic subtest: pipe-C-eDP-1
[669.785349] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[669.794980] Starting dynamic subtest: pipe-D-eDP-1
[669.804519] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[669.806211] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[671.968218] [155/156] (355s left) syncobj_wait (multi-wait-for-submit-submitted)
[672.020289] Starting subtest: multi-wait-for-submit-submitted
[672.126627] Subtest multi-wait-for-submit-submitted: SUCCESS (0.106s)
[672.214631] [156/156] (355s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[672.312511] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[672.314381] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[672.417886] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_pipe_b_c_ivb
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_force_connector_basic
results: parsing output: 5/ for test: perf_pmu
results: parsing output: 6/ for test: gem_ccs
results: parsing output: 7/ for test: kms_plane_alpha_blend
results: parsing output: 8/ for test: gem_mmap_gtt
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_plane
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_pm_rpm
results: parsing output: 16/ for test: gem_render_copy
results: parsing output: 17/ for test: sysfs_heartbeat_interval
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: i915_pm_freq_mult
results: parsing output: 22/ for test: prime_busy
results: parsing output: 23/ for test: gem_exec_gttfill
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: gem_ctx_param
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: gem_lmem_swapping
results: parsing output: 28/ for test: gem_ctx_shared
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: gem_bad_reloc
results: parsing output: 31/ for test: kms_chamelium_hpd
results: parsing output: 32/ for test: kms_psr2_sf
results: parsing output: 33/ for test: gem_eio
results: parsing output: 34/ for test: kms_content_protection
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: gem_ctx_isolation
results: parsing output: 37/ for test: gem_flink_basic
results: parsing output: 38/ for test: gem_caching
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_pm_rpm
results: parsing output: 43/ for test: kms_atomic_transition
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_spin_batch
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: syncobj_basic
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: api_intel_bb
results: parsing output: 52/ for test: kms_atomic_interruptible
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_flip_scaled_crc
results: parsing output: 56/ for test: gem_pxp
results: parsing output: 57/ for test: gem_fence_thrash
results: parsing output: 58/ for test: gen9_exec_parse
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_exec_balancer
results: parsing output: 61/ for test: kms_plane
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_pwrite_crc
results: parsing output: 64/ for test: gem_flink_basic
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_ctx_persistence
results: parsing output: 67/ for test: gen7_exec_parse
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: perf
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: i915_drm_fdinfo
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_pipe_crc_basic
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: kms_cursor_crc
results: parsing output: 77/ for test: kms_color_pipeline
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_exec_reloc
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_draw_crc
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_lease
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_chamelium_audio
results: parsing output: 100/ for test: kms_addfb_basic
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_sequence
results: parsing output: 105/ for test: kms_plane_scaling
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_busy
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: gem_softpin
results: parsing output: 114/ for test: kms_force_connector_basic
results: parsing output: 115/ for test: kms_chamelium_edid
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_mmap_gtt
results: parsing output: 120/ for test: gem_render_copy
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: vgem_basic
results: parsing output: 123/ for test: kms_flip_scaled_crc
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_vblank
results: parsing output: 128/ for test: kms_flip_scaled_crc
results: parsing output: 129/ for test: gem_ctx_exec
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_psr2_sf
results: parsing output: 132/ for test: gem_ppgtt
results: parsing output: 133/ for test: gem_exec_reloc
results: parsing output: 134/ for test: gem_softpin
results: parsing output: 135/ for test: gem_exec_capture
results: parsing output: 136/ for test: gem_tiled_blits
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_mmap_offset
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: i915_power
results: parsing output: 142/ for test: gem_exec_schedule
results: parsing output: 143/ for test: kms_cursor_legacy
results: parsing output: 144/ for test: i915_pm_rps
results: parsing output: 145/ for test: kms_psr
results: parsing output: 146/ for test: kms_setmode
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: kms_flip
results: parsing output: 149/ for test: gem_exec_whisper
results: parsing output: 150/ for test: dmabuf_sync_file
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: gem_sync
results: parsing output: 153/ for test: kms_ccs
results: parsing output: 154/ for test: syncobj_wait
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.