[63.048490] Initializing watchdogs
[63.048540]   /dev/watchdog0
[63.063863] [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]
[63.068533] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[63.070812] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[63.072582] [001/154] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[63.177089] Starting subtest: fbc-tiling-linear
[66.951040] Subtest fbc-tiling-linear: SUCCESS (3.775s)
[68.098992] [002/154] (955s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[68.150651] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[68.206942] [003/154] (955s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[68.297329] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[68.298940] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[68.404679] [004/154] (955s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[68.500873] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[68.502678] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[68.597930] [005/154] (955s left) kms_force_connector_basic (prune-stale-modes)
[68.668734] Starting subtest: prune-stale-modes
[69.137164] Subtest prune-stale-modes: SUCCESS (0.468s)
[69.262577] [006/154] (954s left) perf_pmu (most-busy-check-all)
[69.302402] Starting subtest: most-busy-check-all
[69.303363] Starting dynamic subtest: rcs0
[71.336941] Dynamic subtest rcs0: SUCCESS (2.025s)
[71.344411] Starting dynamic subtest: bcs0
[73.318949] Dynamic subtest bcs0: SUCCESS (1.980s)
[73.326565] Starting dynamic subtest: vcs0
[75.302331] Dynamic subtest vcs0: SUCCESS (1.982s)
[75.311769] Starting dynamic subtest: vcs1
[77.350774] Dynamic subtest vcs1: SUCCESS (2.047s)
[77.360080] Starting dynamic subtest: vecs0
[79.335452] Dynamic subtest vecs0: SUCCESS (1.983s)
[79.345356] Starting dynamic subtest: ccs0
[81.319651] Dynamic subtest ccs0: SUCCESS (1.983s)
[81.321246] Subtest most-busy-check-all: SUCCESS (12.008s)
[81.419442] [007/154] (942s left) gem_ccs (large-ctrl-surf-copy)
[81.472079] Starting subtest: large-ctrl-surf-copy
[81.473835] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[81.561616] [008/154] (942s left) kms_plane_alpha_blend (coverage-7efc)
[81.650991] Starting subtest: coverage-7efc
[81.945492] Starting dynamic subtest: pipe-A-eDP-1
[82.517827] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.572s)
[82.549720] Starting dynamic subtest: pipe-D-eDP-1
[83.915924] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.373s)
[83.916863] Subtest coverage-7efc: SUCCESS (2.265s)
[84.803964] [009/154] (939s left) gem_mmap_gtt (fault-concurrent)
[84.852726] Subtest fault-concurrent: SKIP (0.000s)
[84.905358] [010/154] (938s left) api_intel_allocator (gem-pool)
[84.951726] Starting subtest: gem-pool
[84.959194] Subtest gem-pool: SUCCESS (0.009s)
[85.078936] [011/154] (938s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[85.165313] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[85.167097] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[85.248861] [012/154] (938s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[85.333105] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[85.334832] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[85.395972] [013/154] (938s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[85.494549] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[85.495718] Starting dynamic subtest: pipe-A-eDP-1
[85.505029] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[85.513620] Starting dynamic subtest: pipe-B-eDP-1
[85.522788] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[85.530476] Starting dynamic subtest: pipe-C-eDP-1
[85.538829] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[85.546298] Starting dynamic subtest: pipe-D-eDP-1
[85.557709] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[85.559438] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.004s)
[87.702941] [014/154] (936s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[87.782571] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[87.783557] Starting dynamic subtest: pipe-A-eDP-1
[87.801173] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[87.810136] Starting dynamic subtest: pipe-B-eDP-1
[87.818390] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[87.825471] Starting dynamic subtest: pipe-C-eDP-1
[87.833557] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[87.840517] Starting dynamic subtest: pipe-D-eDP-1
[87.848506] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[87.850048] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[89.997392] [015/154] (934s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[90.085885] Starting subtest: hdr-rgb565-draw-blt
[90.088074] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[90.167602] [016/154] (933s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[90.218812] Starting subtest: x-tiled-to-vebox-y-tiled
[90.220817] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[90.391456] [017/154] (933s left) sysfs_heartbeat_interval (invalid)
[90.434712] Starting subtest: invalid
[90.435962] Starting dynamic subtest: vecs0
[90.437148] Dynamic subtest vecs0: SUCCESS (0.000s)
[90.446366] Starting dynamic subtest: ccs0
[90.447265] Dynamic subtest ccs0: SUCCESS (0.000s)
[90.454296] Starting dynamic subtest: vcs0
[90.455123] Dynamic subtest vcs0: SUCCESS (0.000s)
[90.462066] Starting dynamic subtest: bcs0
[90.462911] Dynamic subtest bcs0: SUCCESS (0.000s)
[90.469991] Starting dynamic subtest: rcs0
[90.470906] Dynamic subtest rcs0: SUCCESS (0.000s)
[90.477877] Starting dynamic subtest: vcs1
[90.478930] Dynamic subtest vcs1: SUCCESS (0.000s)
[90.479864] Subtest invalid: SUCCESS (0.003s)
[90.533269] [018/154] (933s left) kms_frontbuffer_tracking (fbc-stridechange)
[90.632541] Starting subtest: fbc-stridechange
[93.905313] Subtest fbc-stridechange: SUCCESS (3.273s)
[95.120760] [019/154] (929s left) gem_ctx_param (basic-default)
[95.167376] Starting subtest: basic-default
[95.168552] Subtest basic-default: SUCCESS (0.000s)
[95.233236] [020/154] (928s left) kms_psr (fbc-pr-primary-mmap-gtt)
[95.327291] Starting subtest: fbc-pr-primary-mmap-gtt
[95.329118] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[95.390540] [021/154] (928s left) i915_pm_freq_mult (media-freq)
[95.444197] Starting subtest: media-freq
[95.445133] Starting dynamic subtest: gt0
[95.447446] Dynamic subtest gt0: SKIP (0.000s)
[95.456813] Starting dynamic subtest: gt1
[95.458620] Dynamic subtest gt1: SKIP (0.000s)
[95.460768] Subtest media-freq: SKIP (0.001s)
[95.542342] [022/154] (928s left) prime_busy (before-wait)
[95.592935] Starting subtest: before-wait
[95.595108] Starting dynamic subtest: rcs0
[95.628472] Dynamic subtest rcs0: SUCCESS (0.033s)
[95.638312] Starting dynamic subtest: bcs0
[95.670601] Dynamic subtest bcs0: SUCCESS (0.042s)
[95.678999] Starting dynamic subtest: vcs0
[95.712186] Dynamic subtest vcs0: SUCCESS (0.041s)
[95.720312] Starting dynamic subtest: vcs1
[95.754087] Dynamic subtest vcs1: SUCCESS (0.042s)
[95.762313] Starting dynamic subtest: vecs0
[95.794556] Dynamic subtest vecs0: SUCCESS (0.040s)
[95.802792] Starting dynamic subtest: ccs0
[95.835766] Dynamic subtest ccs0: SUCCESS (0.041s)
[95.942485] Subtest before-wait: SUCCESS (0.350s)
[96.021435] [023/154] (928s left) gem_exec_gttfill (engines)
[96.078988] Starting subtest: engines
[96.080135] Starting dynamic subtest: rcs0
[118.881364] Dynamic subtest rcs0: SUCCESS (22.802s)
[118.891614] Starting dynamic subtest: bcs0
[142.188561] Dynamic subtest bcs0: SUCCESS (23.306s)
[142.198765] Starting dynamic subtest: vcs0
[165.479381] Dynamic subtest vcs0: SUCCESS (23.287s)
[165.490364] Starting dynamic subtest: vcs1
[188.783854] Dynamic subtest vcs1: SUCCESS (23.303s)
[188.794427] Starting dynamic subtest: vecs0
[212.053360] Dynamic subtest vecs0: SUCCESS (23.268s)
[212.062403] Starting dynamic subtest: ccs0
[235.447767] Dynamic subtest ccs0: SUCCESS (23.393s)
[235.448663] Subtest engines: SUCCESS (139.364s)
[235.645341] [024/154] (788s left) syncobj_timeline (etime-multi-wait-all-submitted)
[235.741060] Starting subtest: etime-multi-wait-all-submitted
[235.850837] Subtest etime-multi-wait-all-submitted: SUCCESS (0.109s)
[235.934971] [025/154] (788s left) gem_ctx_param (get-priority-new-ctx)
[235.977268] Starting subtest: get-priority-new-ctx
[235.978157] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[236.097184] [026/154] (788s left) kms_big_fb (linear-32bpp-rotate-90)
[236.196214] Starting subtest: linear-32bpp-rotate-90
[236.215901] Subtest linear-32bpp-rotate-90: SKIP (0.019s)
[236.322592] [027/154] (788s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[236.362214] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[236.397801] [028/154] (788s left) gem_ctx_shared (disjoint-timelines)
[236.452293] Starting subtest: disjoint-timelines
[236.527521] Subtest disjoint-timelines: SUCCESS (0.075s)
[236.743969] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[236.746632] [029/154] (787s left) kms_psr (fbc-psr2-cursor-plane-move)
[236.839968] Starting subtest: fbc-psr2-cursor-plane-move
[236.841781] Starting dynamic subtest: eDP-1
[236.843519] Dynamic subtest eDP-1: SKIP (0.000s)
[236.845236] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[236.906904] [030/154] (787s left) gem_bad_reloc (negative-reloc-bltcopy)
[236.956397] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[237.025562] [031/154] (787s left) kms_chamelium_hpd (hdmi-hpd)
[237.393757] Subtest hdmi-hpd: SKIP (0.000s)
[238.171407] [032/154] (786s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[238.267043] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[238.268681] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[238.333472] [033/154] (786s left) gem_eio (wait-10ms)
[238.567170] Starting subtest: wait-10ms
[238.934894] Subtest wait-10ms: SUCCESS (0.368s)
[239.082148] [034/154] (785s left) kms_content_protection (legacy-hdcp14)
[239.216999] Starting subtest: legacy-hdcp14
[239.219358] Subtest legacy-hdcp14: SKIP (0.000s)
[239.342779] [035/154] (785s left) syncobj_wait (reset-signaled)
[239.387201] Starting subtest: reset-signaled
[239.388140] Subtest reset-signaled: SUCCESS (0.000s)
[239.480022] [036/154] (785s left) gem_ctx_isolation (dirty-switch)
[239.530631] Starting subtest: dirty-switch
[239.531751] Starting dynamic subtest: rcs0
[239.733529] Dynamic subtest rcs0: SUCCESS (0.203s)
[239.743300] Starting dynamic subtest: bcs0
[239.961934] Dynamic subtest bcs0: SUCCESS (0.228s)
[239.971605] Starting dynamic subtest: vcs0
[240.195028] Dynamic subtest vcs0: SUCCESS (0.233s)
[240.204625] Starting dynamic subtest: vcs1
[241.493592] Dynamic subtest vcs1: SUCCESS (1.298s)
[241.502387] Starting dynamic subtest: vecs0
[243.566520] Dynamic subtest vecs0: SUCCESS (2.073s)
[243.577243] Starting dynamic subtest: ccs0
[245.559410] Dynamic subtest ccs0: SUCCESS (1.992s)
[245.560604] Subtest dirty-switch: SUCCESS (6.029s)
[245.814300] [037/154] (778s left) gem_flink_basic (bad-open)
[245.865819] Starting subtest: bad-open
[245.866679] Subtest bad-open: SUCCESS (0.000s)
[245.978624] [038/154] (778s left) gem_caching (writes)
[246.023053] Subtest writes: SKIP (0.000s)
[246.097875] [039/154] (778s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[246.201401] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[246.202458] Starting dynamic subtest: pipe-A
[246.550826] Dynamic subtest pipe-A: SUCCESS (0.350s)
[246.562068] Starting dynamic subtest: pipe-B
[247.957683] Dynamic subtest pipe-B: SUCCESS (1.406s)
[247.967745] Starting dynamic subtest: pipe-C
[249.349313] Dynamic subtest pipe-C: SUCCESS (1.391s)
[249.360428] Starting dynamic subtest: pipe-D
[250.767359] Dynamic subtest pipe-D: SUCCESS (1.417s)
[250.768231] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.567s)
[251.988447] [040/154] (772s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[252.039333] Starting subtest: single-wait-all-for-submit-available-submitted
[252.040387] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[252.125484] [041/154] (772s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[252.210442] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[252.212125] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[252.315395] [042/154] (772s left) kms_pm_rpm (system-suspend-idle)
[254.463396] Starting subtest: system-suspend-idle
[275.082673] Subtest system-suspend-idle: SUCCESS (5.172s)
[280.544645] [043/154] (749s left) kms_atomic_transition (modeset-transition)
[280.640466] Starting subtest: modeset-transition
[280.641379] Starting dynamic subtest: 1x-outputs
[291.556058] Dynamic subtest 1x-outputs: SUCCESS (10.907s)
[291.559032] Subtest modeset-transition: SUCCESS (10.909s)
[292.389156] [044/154] (737s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[292.492050] Starting subtest: pipe-fbc-rte
[292.494697] Starting dynamic subtest: pipe-B-eDP-1
[297.915088] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.423s)
[297.918710] Subtest pipe-fbc-rte: SUCCESS (5.425s)
[300.190103] [045/154] (729s left) gem_spin_batch (resubmit-all)
[300.247099] Starting subtest: resubmit-all
[300.248223] Starting dynamic subtest: rcs0
[300.258292] Dynamic subtest rcs0: SUCCESS (0.011s)
[300.270486] Starting dynamic subtest: bcs0
[300.271308] Dynamic subtest bcs0: SUCCESS (0.002s)
[300.280584] Starting dynamic subtest: vcs0
[300.281525] Dynamic subtest vcs0: SUCCESS (0.003s)
[300.290604] Starting dynamic subtest: vcs1
[300.291544] Dynamic subtest vcs1: SUCCESS (0.002s)
[300.300552] Starting dynamic subtest: vecs0
[300.301479] Dynamic subtest vecs0: SUCCESS (0.002s)
[300.310576] Starting dynamic subtest: ccs0
[300.311494] Dynamic subtest ccs0: SUCCESS (0.002s)
[300.312462] Subtest resubmit-all: SUCCESS (0.025s)
[301.788873] [046/154] (728s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[301.871499] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[301.873124] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[301.960187] [047/154] (728s left) syncobj_basic (illegal-fd-to-handle)
[301.999141] Starting subtest: illegal-fd-to-handle
[302.000044] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[302.088886] [048/154] (728s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[302.176447] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[302.178350] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[302.291532] [049/154] (727s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[302.402203] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[303.513800] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.112s)
[303.644838] [050/154] (726s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[303.699635] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[303.804540] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[303.891294] [051/154] (726s left) api_intel_bb (misplaced-blitter)
[303.944892] Starting subtest: misplaced-blitter
[303.951362] Subtest misplaced-blitter: SUCCESS (0.008s)
[304.068402] [052/154] (726s left) kms_atomic_interruptible (universal-setplane-cursor)
[304.154001] Starting subtest: universal-setplane-cursor
[304.154805] Starting dynamic subtest: pipe-A-eDP-1
[311.580259] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.426s)
[311.581013] Subtest universal-setplane-cursor: SUCCESS (7.427s)
[312.382840] [053/154] (717s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[312.478682] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[312.480504] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[312.570752] [054/154] (717s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[312.663886] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[312.665729] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[312.766512] [055/154] (717s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[312.855643] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[312.857680] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[312.928286] [056/154] (717s left) gem_pxp (create-protected-buffer)
[313.037566] Starting subtest: create-protected-buffer
[313.038669] Subtest create-protected-buffer: SUCCESS (0.000s)
[313.152896] [057/154] (717s left) gen9_exec_parse (allowed-single)
[313.200812] Subtest allowed-single: SKIP (0.000s)
[313.251122] [058/154] (717s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[313.340466] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[313.342045] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[313.433531] [059/154] (717s left) gem_exec_balancer (bonded-semaphore)
[313.485277] Starting subtest: bonded-semaphore
[313.486958] Subtest bonded-semaphore: SKIP (0.000s)
[313.790345] [060/154] (716s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[313.882298] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[313.883916] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[313.964052] [061/154] (716s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[314.060507] Starting subtest: fbc-rgb565-draw-mmap-wc
[318.101825] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.042s)
[319.288787] [062/154] (711s left) kms_pwrite_crc (basic)
[319.383165] Starting subtest: basic
[320.093585] Subtest basic: SUCCESS (0.711s)
[320.887117] [063/154] (709s left) gem_flink_basic (bad-flink)
[320.943937] Starting subtest: bad-flink
[320.944923] Subtest bad-flink: SUCCESS (0.000s)
[321.059664] [064/154] (709s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[321.164406] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[321.166195] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[321.265074] [065/154] (709s left) gem_ctx_persistence (legacy-engines-persistence)
[321.321471] Starting subtest: legacy-engines-persistence
[321.322525] Starting dynamic subtest: render
[322.115967] Dynamic subtest render: SUCCESS (0.793s)
[322.129631] Starting dynamic subtest: bsd1
[322.874820] Dynamic subtest bsd1: SUCCESS (0.756s)
[322.887662] Starting dynamic subtest: bsd2
[323.638577] Dynamic subtest bsd2: SUCCESS (0.761s)
[323.652037] Starting dynamic subtest: blt
[324.409588] Dynamic subtest blt: SUCCESS (0.767s)
[324.423461] Starting dynamic subtest: vebox
[325.179141] Dynamic subtest vebox: SUCCESS (0.767s)
[325.180614] Subtest legacy-engines-persistence: SUCCESS (3.858s)
[325.269507] [066/154] (705s left) gen7_exec_parse (basic-allowed)
[325.325427] Subtest basic-allowed: SKIP (0.000s)
[325.401296] [067/154] (705s left) gem_exec_schedule (reorder-wide)
[325.581624] Subtest reorder-wide: SKIP (0.000s)
[325.753453] [068/154] (705s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[325.848693] Starting subtest: psrhdr-slowdraw
[325.850357] Subtest psrhdr-slowdraw: SKIP (0.000s)
[325.937762] [069/154] (704s left) perf (non-zero-reason)
[326.066525] Starting subtest: non-zero-reason
[326.067488] Starting dynamic subtest: 0-rcs0
[331.732546] Dynamic subtest 0-rcs0: SUCCESS (5.665s)
[331.745576] Starting dynamic subtest: 1-vcs0
[335.773143] Dynamic subtest 1-vcs0: SUCCESS (4.030s)
[335.774711] Subtest non-zero-reason: SUCCESS (9.696s)
[335.809474] [070/154] (695s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[335.900757] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[335.902394] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[336.001713] [071/154] (694s left) i915_drm_fdinfo (all-busy-check-all)
[336.043605] Starting subtest: all-busy-check-all
[336.048462] Subtest all-busy-check-all: SKIP (0.003s)
[336.124862] [072/154] (694s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[336.237848] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[336.239602] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[336.346388] [073/154] (694s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[336.430889] Starting subtest: compare-crc-sanitycheck-nv12
[336.431955] Starting dynamic subtest: pipe-A-eDP-1
[337.145177] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.714s)
[337.155261] Starting dynamic subtest: pipe-B-eDP-1
[338.462446] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.316s)
[338.472204] Starting dynamic subtest: pipe-C-eDP-1
[339.736352] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.272s)
[339.746539] Starting dynamic subtest: pipe-D-eDP-1
[340.991503] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.255s)
[340.992371] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.561s)
[341.811475] [074/154] (689s left) gem_exec_reloc (basic-write-gtt)
[341.858159] Subtest basic-write-gtt: SKIP (0.000s)
[341.920713] [075/154] (689s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[342.206794] Starting subtest: cursor-rapid-movement-max-size
[342.209753] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[342.336620] [076/154] (688s left) kms_color_pipeline (plane-lut1d)
[342.430050] Starting subtest: plane-lut1d
[342.578473] Starting dynamic subtest: pipe-A-plane-0
[342.745001] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s)
[342.755314] Starting dynamic subtest: pipe-A-plane-1
[342.901450] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[342.911195] Starting dynamic subtest: pipe-A-plane-2
[343.056198] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[344.292849] Starting dynamic subtest: pipe-B-plane-0
[344.450217] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[344.460429] Starting dynamic subtest: pipe-B-plane-1
[344.606004] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[344.616516] Starting dynamic subtest: pipe-B-plane-2
[344.772642] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[345.986637] Starting dynamic subtest: pipe-C-plane-0
[346.132195] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[346.145050] Starting dynamic subtest: pipe-C-plane-1
[346.276786] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[346.287484] Starting dynamic subtest: pipe-C-plane-2
[346.443629] Dynamic subtest pipe-C-plane-2: SUCCESS (0.166s)
[347.661904] Starting dynamic subtest: pipe-D-plane-0
[347.797384] Dynamic subtest pipe-D-plane-0: SUCCESS (0.146s)
[347.807546] Starting dynamic subtest: pipe-D-plane-1
[347.949652] Dynamic subtest pipe-D-plane-1: SUCCESS (0.152s)
[347.962290] Starting dynamic subtest: pipe-D-plane-2
[348.116230] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[348.402444] Subtest plane-lut1d: SUCCESS (5.971s)
[349.291832] [077/154] (681s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[349.383305] Starting subtest: yf-tiled-16bpp-rotate-180
[349.384939] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[349.493906] [078/154] (681s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[349.591265] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[350.694476] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.103s)
[350.800476] [079/154] (680s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[350.899031] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[350.900994] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[351.011983] [080/154] (680s left) gem_exec_reloc (basic-wc-read)
[351.074801] Subtest basic-wc-read: SKIP (0.000s)
[351.138974] [081/154] (680s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[351.252016] Starting subtest: short-busy-flip-before-cursor-toggle
[351.253876] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[351.393035] [082/154] (679s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[351.486471] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[351.498210] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[351.584769] [083/154] (679s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[351.670135] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[351.671821] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[351.767954] [084/154] (679s left) kms_draw_crc (draw-method-render)
[351.856745] Starting subtest: draw-method-render
[351.857825] Starting dynamic subtest: xrgb8888-untiled
[352.101218] Dynamic subtest xrgb8888-untiled: SUCCESS (0.245s)
[352.111240] Starting dynamic subtest: rgb565-untiled
[352.335353] Dynamic subtest rgb565-untiled: SUCCESS (0.233s)
[352.346117] Starting dynamic subtest: xrgb2101010-untiled
[352.568174] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.233s)
[352.577920] Starting dynamic subtest: xbgr16161616f-untiled
[352.812571] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.244s)
[352.824411] Starting dynamic subtest: xrgb8888-xtiled
[352.946640] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.132s)
[352.964079] Starting dynamic subtest: rgb565-xtiled
[353.068038] Dynamic subtest rgb565-xtiled: SUCCESS (0.120s)
[353.080839] Starting dynamic subtest: xrgb2101010-xtiled
[353.190340] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[353.200263] Starting dynamic subtest: xbgr16161616f-xtiled
[353.323950] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s)
[353.336704] Starting dynamic subtest: xrgb8888-4tiled
[353.446158] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.122s)
[353.457965] Starting dynamic subtest: rgb565-4tiled
[353.557309] Dynamic subtest rgb565-4tiled: SUCCESS (0.109s)
[353.567151] Starting dynamic subtest: xrgb2101010-4tiled
[353.679274] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.121s)
[353.689244] Starting dynamic subtest: xbgr16161616f-4tiled
[353.812610] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.132s)
[353.813555] Subtest draw-method-render: SUCCESS (1.957s)
[353.917350] [085/154] (677s left) kms_cursor_crc (cursor-sliding-256x85)
[354.171195] Starting subtest: cursor-sliding-256x85
[354.216059] Subtest cursor-sliding-256x85: SKIP (0.043s)
[354.403773] [086/154] (677s left) kms_cursor_crc (cursor-onscreen-256x256)
[354.629307] Starting subtest: cursor-onscreen-256x256
[354.631304] Starting dynamic subtest: pipe-A-eDP-1
[356.740904] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.107s)
[356.774182] Starting dynamic subtest: pipe-D-eDP-1
[359.546689] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.782s)
[359.547596] Subtest cursor-onscreen-256x256: SUCCESS (4.918s)
[360.401267] [087/154] (671s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[360.497742] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[360.499610] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[360.608329] [088/154] (671s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[360.702428] Starting subtest: fbcpsr-slowdraw
[385.987290] Subtest fbcpsr-slowdraw: SUCCESS (25.286s)
[386.116287] [089/154] (645s left) kms_lease (cursor-implicit-plane)
[386.205646] Starting subtest: cursor-implicit-plane
[386.206535] Starting dynamic subtest: pipe-A-eDP-1
[386.653731] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.447s)
[386.663800] Starting dynamic subtest: pipe-B-eDP-1
[387.776324] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.122s)
[387.787587] Starting dynamic subtest: pipe-C-eDP-1
[388.861873] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.083s)
[388.872589] Starting dynamic subtest: pipe-D-eDP-1
[389.961503] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.097s)
[389.962686] Subtest cursor-implicit-plane: SUCCESS (3.756s)
[390.780208] [090/154] (640s left) syncobj_wait (invalid-reset-zero-handles)
[390.826460] Starting subtest: invalid-reset-zero-handles
[390.827237] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[390.947322] [091/154] (640s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[391.029980] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[391.031918] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[391.135395] [092/154] (640s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[391.224173] Starting subtest: yf-tiled-64bpp-rotate-90
[391.225954] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[391.317673] [093/154] (640s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[391.425688] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[391.427353] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[391.523218] [094/154] (640s left) gem_exec_fence (long-history)
[391.962507] Starting subtest: long-history
[399.550668] Subtest long-history: SUCCESS (7.582s)
[400.034547] [095/154] (631s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[400.132377] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[407.643806] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.513s)
[407.774488] [096/154] (624s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[407.864598] Starting subtest: bad-rotation-90-yf-tiled-ccs
[407.865719] Starting dynamic subtest: pipe-A-eDP-1
[407.874584] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[407.887288] Starting dynamic subtest: pipe-B-eDP-1
[407.896838] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[407.908612] Starting dynamic subtest: pipe-C-eDP-1
[407.916932] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[407.926290] Starting dynamic subtest: pipe-D-eDP-1
[407.934743] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[407.936190] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[410.081174] [097/154] (621s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[410.202378] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[428.899355] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (18.685s)
[429.270087] [098/154] (602s left) kms_chamelium_audio (dp-audio-edid)
[429.657733] Subtest dp-audio-edid: SKIP (0.000s)
[430.454299] [099/154] (601s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[430.546939] Starting subtest: addfb25-yf-tiled-legacy
[430.547849] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[430.639403] [100/154] (601s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[430.725670] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[430.727401] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[430.824662] [101/154] (601s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[430.917889] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[430.919987] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[431.012955] [102/154] (601s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[431.108383] Starting subtest: fbc-2p-pri-indfb-multidraw
[431.110054] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[431.197847] [103/154] (600s left) kms_sequence (queue-busy)
[431.286242] Starting subtest: queue-busy
[431.287005] Starting dynamic subtest: pipe-A-eDP-1
[433.222620] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.935s)
[433.234508] Starting dynamic subtest: pipe-B-eDP-1
[435.848245] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.624s)
[435.858784] Starting dynamic subtest: pipe-C-eDP-1
[438.432639] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.583s)
[438.442932] Starting dynamic subtest: pipe-D-eDP-1
[441.033618] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.599s)
[441.034684] Subtest queue-busy: SUCCESS (9.746s)
[441.882481] [104/154] (590s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[441.970724] Starting subtest: plane-upscale-20x20-with-rotation
[441.971664] Starting dynamic subtest: pipe-A
[442.196195] Dynamic subtest pipe-A: SUCCESS (0.226s)
[442.206274] Starting dynamic subtest: pipe-B
[443.527196] Dynamic subtest pipe-B: SUCCESS (1.330s)
[443.537306] Starting dynamic subtest: pipe-C
[444.848073] Dynamic subtest pipe-C: SUCCESS (1.318s)
[444.858025] Starting dynamic subtest: pipe-D
[446.132038] Dynamic subtest pipe-D: SUCCESS (1.285s)
[446.133175] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.162s)
[447.270513] [105/154] (584s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[447.357126] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[447.359168] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[447.442715] [106/154] (584s left) gem_exec_reloc (basic-write-wc)
[447.502381] Subtest basic-write-wc: SKIP (0.000s)
[447.562856] [107/154] (584s left) syncobj_timeline (reset-signaled)
[447.610388] Starting subtest: reset-signaled
[447.611262] Subtest reset-signaled: SUCCESS (0.000s)
[447.712614] [108/154] (584s left) kms_psr (pr-cursor-plane-move)
[447.793455] Starting subtest: pr-cursor-plane-move
[447.795252] Subtest pr-cursor-plane-move: SKIP (0.000s)
[447.862319] [109/154] (584s left) syncobj_wait (invalid-wait-illegal-handle)
[447.917816] Starting subtest: invalid-wait-illegal-handle
[447.918946] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[448.030012] [110/154] (584s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[448.121277] Starting subtest: extended-pageflip-modeset-hang-oldfb
[448.122071] Starting dynamic subtest: pipe-A
[456.015500] Dynamic subtest pipe-A: SUCCESS (7.892s)
[456.029456] Starting dynamic subtest: pipe-D
[464.679489] Dynamic subtest pipe-D: SUCCESS (8.659s)
[464.680540] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.556s)
[465.491190] [111/154] (566s left) kms_psr (psr2-primary-blt)
[465.578437] Starting subtest: psr2-primary-blt
[465.579192] Starting dynamic subtest: eDP-1
[467.330087] Dynamic subtest eDP-1: SUCCESS (1.752s)
[467.331069] Subtest psr2-primary-blt: SUCCESS (1.753s)
[468.176476] [112/154] (564s left) gem_softpin (evict-snoop-interruptible)
[468.245225] Starting subtest: evict-snoop-interruptible
[468.247348] Subtest evict-snoop-interruptible: SKIP (0.000s)
[468.349589] [113/154] (564s left) kms_force_connector_basic (force-load-detect)
[468.417434] Starting subtest: force-load-detect
[468.419165] Subtest force-load-detect: SKIP (0.000s)
[468.530312] [114/154] (564s left) kms_chamelium_edid (hdmi-mode-timings)
[468.913378] Subtest hdmi-mode-timings: SKIP (0.000s)
[469.755232] [115/154] (562s left) syncobj_timeline (invalid-signal-zero-handles)
[469.802084] Starting subtest: invalid-signal-zero-handles
[469.803151] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[469.901077] [116/154] (562s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[469.987338] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[469.989102] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[470.087406] [117/154] (562s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[470.192747] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[470.194531] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[470.290861] [118/154] (562s left) gem_mmap_gtt (fault-concurrent-x)
[470.349609] Subtest fault-concurrent-X: SKIP (0.000s)
[470.413960] [119/154] (562s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[470.464654] Starting subtest: y-tiled-to-vebox-y-tiled
[470.466850] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[470.645412] [120/154] (562s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[470.730311] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[470.731976] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[470.834091] [121/154] (561s left) vgem_basic (debugfs)
[470.877353] Starting subtest: debugfs
[470.878242] Subtest debugfs: SUCCESS (0.000s)
[470.946944] [122/154] (561s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[471.050216] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[471.051001] Starting dynamic subtest: pipe-A-default-mode
[472.213217] Dynamic subtest pipe-A-default-mode: SKIP (1.159s)
[472.215197] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.161s)
[472.379279] [123/154] (560s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[472.470803] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[472.473309] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[472.572659] [124/154] (560s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[472.659688] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[472.661423] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[472.754100] [125/154] (560s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[472.840788] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[472.842519] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[472.929075] [126/154] (560s left) kms_vblank (wait-forked-hang)
[473.015448] Starting subtest: wait-forked-hang
[473.016201] Starting dynamic subtest: pipe-A-eDP-1
[499.579313] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.560s)
[499.591368] Starting dynamic subtest: pipe-D-eDP-1
[524.546579] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.966s)
[524.547432] Subtest wait-forked-hang: SUCCESS (51.530s)
[525.384477] [127/154] (507s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[525.469892] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[525.470867] Starting dynamic subtest: pipe-A-default-mode
[526.608552] Dynamic subtest pipe-A-default-mode: SKIP (1.135s)
[526.610092] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.136s)
[526.761128] [128/154] (506s left) gem_ctx_exec (basic-invalid-context)
[526.821037] Starting subtest: basic-invalid-context
[526.825428] Subtest basic-invalid-context: SUCCESS (0.004s)
[526.945232] [129/154] (506s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[527.038382] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[527.040028] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[527.134125] [130/154] (505s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[527.230025] Starting subtest: fbc-psr2-cursor-plane-update-sf
[527.231080] Starting dynamic subtest: pipe-A-eDP-1
[527.311930] Dynamic subtest pipe-A-eDP-1: SKIP (0.081s)
[527.322911] Starting dynamic subtest: pipe-B-eDP-1
[528.428154] Dynamic subtest pipe-B-eDP-1: SKIP (1.116s)
[528.430014] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.199s)
[529.681045] [131/154] (503s left) gem_ppgtt (blt-vs-render-ctxn)
[529.740819] Starting subtest: blt-vs-render-ctxN
[562.571462] Subtest blt-vs-render-ctxN: SUCCESS (32.820s)
[562.694393] [132/154] (470s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[562.789177] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[562.852162] [133/154] (470s left) gem_softpin (invalid)
[562.904645] Starting subtest: invalid
[562.912238] Subtest invalid: SUCCESS (0.008s)
[563.027806] [134/154] (470s left) gem_exec_capture (capture)
[563.087979] Starting subtest: capture
[563.089153] Starting dynamic subtest: rcs0-smem
[563.944434] Dynamic subtest rcs0-smem: SUCCESS (0.854s)
[563.954579] Starting dynamic subtest: bcs0-smem
[564.777765] Dynamic subtest bcs0-smem: SUCCESS (0.834s)
[564.787917] Starting dynamic subtest: vcs0-smem
[565.614388] Dynamic subtest vcs0-smem: SUCCESS (0.835s)
[565.624093] Starting dynamic subtest: vcs1-smem
[566.446203] Dynamic subtest vcs1-smem: SUCCESS (0.831s)
[566.456020] Starting dynamic subtest: vecs0-smem
[567.801459] Dynamic subtest vecs0-smem: FAIL (1.331s)
[567.813153] Starting dynamic subtest: ccs0-smem
[569.098706] Dynamic subtest ccs0-smem: SUCCESS (1.319s)
[569.100543] Subtest capture: FAIL (6.010s)
[569.201091] [135/154] (464s left) gem_tiled_blits (basic)
[569.264803] Subtest basic: SKIP (0.000s)
[569.333158] [136/154] (464s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[569.439493] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[576.918329] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.487s)
[577.066936] [137/154] (456s left) gem_mmap_offset (partial-remap)
[577.117611] Starting subtest: partial-remap
[577.118605] Starting dynamic subtest: smem0
[577.123335] Dynamic subtest smem0: SUCCESS (0.004s)
[577.124186] Subtest partial-remap: SUCCESS (0.004s)
[577.229610] [138/154] (456s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[577.332713] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[577.334495] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[577.426615] [139/154] (456s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[577.526839] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[577.528649] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[577.629500] [140/154] (455s left) i915_power (sanity)
[577.689749] Starting subtest: sanity
[581.741448] Subtest sanity: SUCCESS (4.040s)
[583.821223] [141/154] (449s left) gem_exec_schedule (preempt-queue)
[583.893250] Subtest preempt-queue: SKIP (0.000s)
[584.188323] [142/154] (449s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[584.319268] Starting subtest: cursorB-vs-flipA-legacy
[584.322287] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[584.477579] [143/154] (449s left) i915_pm_rps (reset)
[584.539161] Starting subtest: reset
[592.973174] Subtest reset: SUCCESS (8.426s)
[593.073016] [144/154] (440s left) kms_psr (fbc-psr-cursor-plane-onoff)
[593.166358] Starting subtest: fbc-psr-cursor-plane-onoff
[593.167224] Starting dynamic subtest: eDP-1
[593.169377] Dynamic subtest eDP-1: SKIP (0.000s)
[593.171186] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[593.240267] [145/154] (440s left) kms_setmode (clone-exclusive-crtc)
[593.283925] Starting subtest: clone-exclusive-crtc
[593.286584] Subtest clone-exclusive-crtc: SKIP (0.000s)
[593.377040] [146/154] (440s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[593.475609] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[600.174851] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.688s)
[600.287198] [147/154] (433s left) kms_flip (flip-vs-rmfb)
[600.375981] Starting subtest: flip-vs-rmfb
[600.378201] Starting dynamic subtest: A-eDP1
[609.130915] Dynamic subtest A-eDP1: SUCCESS (8.752s)
[609.143737] Starting dynamic subtest: B-eDP1
[617.829436] Dynamic subtest B-eDP1: SUCCESS (8.697s)
[617.844302] Starting dynamic subtest: C-eDP1
[626.520595] Dynamic subtest C-eDP1: SUCCESS (8.689s)
[626.534045] Starting dynamic subtest: D-eDP1
[635.223527] Dynamic subtest D-eDP1: SUCCESS (8.699s)
[635.226285] Subtest flip-vs-rmfb: SUCCESS (34.849s)
[636.468074] [148/154] (397s left) gem_exec_whisper (basic-contexts-all)
[636.527749] Starting subtest: basic-contexts-all
[647.759902] Subtest basic-contexts-all: SUCCESS (11.220s)
[647.949722] [149/154] (385s left) dmabuf_sync_file (export-basic)
[647.987879] Starting subtest: export-basic
[647.988827] Subtest export-basic: SUCCESS (0.001s)
[648.063511] [150/154] (385s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[648.157733] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[648.159415] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[648.257244] [151/154] (385s left) gem_sync (basic-each)
[648.314561] Starting subtest: basic-each
[650.668138] Subtest basic-each: SUCCESS (2.354s)
[650.855375] [152/154] (383s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[650.946374] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[650.947262] Starting dynamic subtest: pipe-A-eDP-1
[650.957100] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[650.967593] Starting dynamic subtest: pipe-B-eDP-1
[650.977515] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[650.988580] Starting dynamic subtest: pipe-C-eDP-1
[650.997701] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[651.007643] Starting dynamic subtest: pipe-D-eDP-1
[651.016825] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[651.019073] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s)
[653.167383] [153/154] (380s left) syncobj_wait (multi-wait-for-submit-submitted)
[653.211333] Starting subtest: multi-wait-for-submit-submitted
[653.315580] Subtest multi-wait-for-submit-submitted: SUCCESS (0.104s)
[653.388473] [154/154] (380s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[653.480244] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[653.482281] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[653.575059] 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: gem_render_copy
results: parsing output: 16/ for test: sysfs_heartbeat_interval
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_ctx_param
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: i915_pm_freq_mult
results: parsing output: 21/ for test: prime_busy
results: parsing output: 22/ for test: gem_exec_gttfill
results: parsing output: 23/ for test: syncobj_timeline
results: parsing output: 24/ for test: gem_ctx_param
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: gem_lmem_swapping
results: parsing output: 27/ for test: gem_ctx_shared
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: gem_bad_reloc
results: parsing output: 30/ for test: kms_chamelium_hpd
results: parsing output: 31/ for test: kms_psr2_sf
results: parsing output: 32/ for test: gem_eio
results: parsing output: 33/ for test: kms_content_protection
results: parsing output: 34/ for test: syncobj_wait
results: parsing output: 35/ for test: gem_ctx_isolation
results: parsing output: 36/ for test: gem_flink_basic
results: parsing output: 37/ for test: gem_caching
results: parsing output: 38/ for test: kms_plane_scaling
results: parsing output: 39/ for test: syncobj_timeline
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_pm_rpm
results: parsing output: 42/ for test: kms_atomic_transition
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gem_spin_batch
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: syncobj_basic
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: syncobj_timeline
results: parsing output: 50/ for test: api_intel_bb
results: parsing output: 51/ for test: kms_atomic_interruptible
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_flip_scaled_crc
results: parsing output: 55/ for test: gem_pxp
results: parsing output: 56/ for test: gen9_exec_parse
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: gem_exec_balancer
results: parsing output: 59/ for test: kms_plane
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_pwrite_crc
results: parsing output: 62/ for test: gem_flink_basic
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_ctx_persistence
results: parsing output: 65/ for test: gen7_exec_parse
results: parsing output: 66/ for test: gem_exec_schedule
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: perf
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: i915_drm_fdinfo
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_pipe_crc_basic
results: parsing output: 73/ for test: gem_exec_reloc
results: parsing output: 74/ for test: kms_cursor_crc
results: parsing output: 75/ for test: kms_color_pipeline
results: parsing output: 76/ for test: kms_big_fb
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_exec_reloc
results: parsing output: 80/ for test: kms_cursor_legacy
results: parsing output: 81/ for test: kms_flip
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_draw_crc
results: parsing output: 84/ for test: kms_cursor_crc
results: parsing output: 85/ for test: kms_cursor_crc
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_lease
results: parsing output: 89/ for test: syncobj_wait
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: gem_exec_fence
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_ccs
results: parsing output: 96/ for test: kms_cursor_legacy
results: parsing output: 97/ for test: kms_chamelium_audio
results: parsing output: 98/ for test: kms_addfb_basic
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_sequence
results: parsing output: 103/ for test: kms_plane_scaling
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_exec_reloc
results: parsing output: 106/ for test: syncobj_timeline
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: syncobj_wait
results: parsing output: 109/ for test: kms_busy
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: gem_softpin
results: parsing output: 112/ for test: kms_force_connector_basic
results: parsing output: 113/ for test: kms_chamelium_edid
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: gem_mmap_gtt
results: parsing output: 118/ for test: gem_render_copy
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: vgem_basic
results: parsing output: 121/ for test: kms_flip_scaled_crc
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_vblank
results: parsing output: 126/ for test: kms_flip_scaled_crc
results: parsing output: 127/ for test: gem_ctx_exec
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_psr2_sf
results: parsing output: 130/ for test: gem_ppgtt
results: parsing output: 131/ for test: gem_exec_reloc
results: parsing output: 132/ for test: gem_softpin
results: parsing output: 133/ for test: gem_exec_capture
results: parsing output: 134/ for test: gem_tiled_blits
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: gem_mmap_offset
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: i915_power
results: parsing output: 140/ for test: gem_exec_schedule
results: parsing output: 141/ for test: kms_cursor_legacy
results: parsing output: 142/ for test: i915_pm_rps
results: parsing output: 143/ for test: kms_psr
results: parsing output: 144/ for test: kms_setmode
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_flip
results: parsing output: 147/ for test: gem_exec_whisper
results: parsing output: 148/ for test: dmabuf_sync_file
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: gem_sync
results: parsing output: 151/ for test: kms_ccs
results: parsing output: 152/ for test: syncobj_wait
results: parsing output: 153/ for test: kms_frontbuffer_tracking
Done.