[68.518279] Initializing watchdogs
[68.518363]   /dev/watchdog0
[68.549292] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[68.564227] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[68.569310] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[68.572836] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[70.079043] Starting subtest: fbc-tiling-linear
[72.851971] Subtest fbc-tiling-linear: SUCCESS (2.779s)
[73.043292] [002/156] (956s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[73.092449] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[73.156311] [003/156] (956s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[74.654025] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[74.657291] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[74.825489] [004/156] (954s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[76.330225] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[76.334179] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[76.505984] [005/156] (952s left) kms_force_connector_basic (prune-stale-modes)
[76.640889] Starting subtest: prune-stale-modes
[76.663296] Subtest prune-stale-modes: SUCCESS (0.023s)
[78.309977] [006/156] (951s left) perf_pmu (most-busy-check-all)
[78.366706] Starting subtest: most-busy-check-all
[78.368024] Starting dynamic subtest: rcs0
[78.947167] Dynamic subtest rcs0: SUCCESS (0.580s)
[78.962160] Starting dynamic subtest: bcs0
[79.499510] Dynamic subtest bcs0: SUCCESS (0.552s)
[79.512119] Starting dynamic subtest: vcs0
[80.047543] Dynamic subtest vcs0: SUCCESS (0.548s)
[80.060250] Starting dynamic subtest: vcs1
[80.595587] Dynamic subtest vcs1: SUCCESS (0.548s)
[80.607854] Starting dynamic subtest: vecs0
[81.143584] Dynamic subtest vecs0: SUCCESS (0.548s)
[81.145197] Subtest most-busy-check-all: SUCCESS (2.778s)
[81.267036] [007/156] (948s left) gem_ccs (large-ctrl-surf-copy)
[81.323052] Starting subtest: large-ctrl-surf-copy
[81.325945] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[81.422098] [008/156] (948s left) kms_plane_alpha_blend (coverage-7efc)
[82.909667] Starting subtest: coverage-7efc
[83.190107] Starting dynamic subtest: pipe-A-HDMI-A-4
[83.700136] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.510s)
[83.729523] Starting dynamic subtest: pipe-D-HDMI-A-4
[84.292785] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.571s)
[84.294259] Subtest coverage-7efc: SUCCESS (1.383s)
[84.505403] [009/156] (945s left) gem_mmap_gtt (fault-concurrent)
[84.557953] Subtest fault-concurrent: SKIP (0.000s)
[84.637913] [010/156] (945s left) api_intel_allocator (gem-pool)
[84.685518] Starting subtest: gem-pool
[84.710300] Subtest gem-pool: SUCCESS (0.025s)
[84.902480] [011/156] (944s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[86.398221] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[86.401293] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[86.567643] [012/156] (943s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[88.061328] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[88.064551] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[88.179832] [013/156] (941s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[89.682208] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[89.683713] Starting dynamic subtest: pipe-A-HDMI-A-4
[89.698484] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.001s)
[89.709100] Starting dynamic subtest: pipe-B-HDMI-A-4
[89.724155] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.001s)
[89.734236] Starting dynamic subtest: pipe-C-HDMI-A-4
[89.749694] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.001s)
[89.759961] Starting dynamic subtest: pipe-D-HDMI-A-4
[89.776976] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.001s)
[89.780481] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.008s)
[89.989428] [014/156] (940s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[91.491444] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[91.493099] Starting dynamic subtest: pipe-A-HDMI-A-4
[91.511393] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[91.522054] Starting dynamic subtest: pipe-B-HDMI-A-4
[91.539153] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[91.549269] Starting dynamic subtest: pipe-C-HDMI-A-4
[91.566168] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[91.576639] Starting dynamic subtest: pipe-D-HDMI-A-4
[91.592647] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[91.595315] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[91.765510] [015/156] (938s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[93.272150] Starting subtest: hdr-rgb565-draw-blt
[93.275254] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[93.402078] [016/156] (936s left) kms_pm_rpm (legacy-planes-dpms)
[96.962766] Starting subtest: legacy-planes-dpms
[96.965653] Starting dynamic subtest: plane-65
[103.870224] Dynamic subtest plane-65: SUCCESS (6.908s)
[103.920421] Starting dynamic subtest: plane-95
[110.863509] Dynamic subtest plane-95: SUCCESS (6.992s)
[110.914124] Starting dynamic subtest: plane-125
[119.650803] Dynamic subtest plane-125: SUCCESS (8.786s)
[119.700758] Starting dynamic subtest: plane-135
[126.636721] Dynamic subtest plane-135: SUCCESS (6.984s)
[126.687608] Starting dynamic subtest: plane-145
[133.646715] Dynamic subtest plane-145: SUCCESS (7.008s)
[133.696550] Starting dynamic subtest: plane-155
[140.643999] Dynamic subtest plane-155: SUCCESS (6.995s)
[140.646883] Subtest legacy-planes-dpms: SUCCESS (43.687s)
[141.677348] [017/156] (888s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[141.737972] Starting subtest: x-tiled-to-vebox-y-tiled
[141.739437] Starting dynamic subtest: smem
[141.821914] Dynamic subtest smem: SUCCESS (0.084s)
[141.832649] Starting dynamic subtest: lmem0
[142.581431] Dynamic subtest lmem0: SUCCESS (0.755s)
[142.583045] Subtest x-tiled-to-vebox-y-tiled: SUCCESS (0.840s)
[142.828535] [018/156] (887s left) sysfs_heartbeat_interval (invalid)
[142.880063] Starting subtest: invalid
[142.881580] Starting dynamic subtest: vecs0
[142.883078] Dynamic subtest vecs0: SUCCESS (0.000s)
[142.894199] Starting dynamic subtest: vcs0
[142.895815] Dynamic subtest vcs0: SUCCESS (0.000s)
[142.906701] Starting dynamic subtest: bcs0
[142.908260] Dynamic subtest bcs0: SUCCESS (0.000s)
[142.918923] Starting dynamic subtest: rcs0
[142.920713] Dynamic subtest rcs0: SUCCESS (0.000s)
[142.932795] Starting dynamic subtest: vcs1
[142.934334] Dynamic subtest vcs1: SUCCESS (0.000s)
[142.935821] Subtest invalid: SUCCESS (0.005s)
[142.994435] [019/156] (887s left) kms_frontbuffer_tracking (fbc-stridechange)
[144.493108] Starting subtest: fbc-stridechange
[146.515402] Subtest fbc-stridechange: SUCCESS (2.025s)
[146.715791] [020/156] (883s left) gem_ctx_param (basic-default)
[146.763520] Starting subtest: basic-default
[146.765239] Subtest basic-default: SUCCESS (0.000s)
[146.885517] [021/156] (883s left) kms_psr (fbc-pr-primary-mmap-gtt)
[148.376808] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[148.453986] [022/156] (882s left) i915_pm_freq_mult (media-freq)
[148.502610] Starting subtest: media-freq
[148.504223] Starting dynamic subtest: gt0
[148.507929] Dynamic subtest gt0: SKIP (0.000s)
[148.511792] Subtest media-freq: SKIP (0.001s)
[148.622804] [023/156] (882s left) prime_busy (before-wait)
[148.677630] Starting subtest: before-wait
[148.682156] Starting dynamic subtest: rcs0
[148.725566] Dynamic subtest rcs0: SUCCESS (0.043s)
[148.737248] Starting dynamic subtest: bcs0
[148.778326] Dynamic subtest bcs0: SUCCESS (0.052s)
[148.792534] Starting dynamic subtest: vcs0
[148.819261] Dynamic subtest vcs0: SUCCESS (0.041s)
[148.835712] Starting dynamic subtest: vcs1
[148.864636] Dynamic subtest vcs1: SUCCESS (0.044s)
[148.889607] Starting dynamic subtest: vecs0
[148.908744] Dynamic subtest vecs0: SUCCESS (0.043s)
[149.020218] Subtest before-wait: SUCCESS (0.342s)
[149.170603] [024/156] (881s left) gem_exec_gttfill (engines)
[149.232202] Starting subtest: engines
[149.233612] Starting dynamic subtest: rcs0
[176.893162] Dynamic subtest rcs0: SUCCESS (27.657s)
[176.904924] Starting dynamic subtest: bcs0
[204.719380] Dynamic subtest bcs0: SUCCESS (27.826s)
[204.730956] Starting dynamic subtest: vcs0
[232.537679] Dynamic subtest vcs0: SUCCESS (27.818s)
[232.549366] Starting dynamic subtest: vcs1
[260.297608] Dynamic subtest vcs1: SUCCESS (27.760s)
[260.309500] Starting dynamic subtest: vecs0
[288.160805] Dynamic subtest vecs0: SUCCESS (27.863s)
[288.162447] Subtest engines: SUCCESS (138.928s)
[288.468832] [025/156] (742s left) syncobj_timeline (etime-multi-wait-all-submitted)
[288.578506] Starting subtest: etime-multi-wait-all-submitted
[288.685833] Subtest etime-multi-wait-all-submitted: SUCCESS (0.106s)
[288.795745] [026/156] (742s left) gem_ctx_param (get-priority-new-ctx)
[288.842204] Starting subtest: get-priority-new-ctx
[288.843956] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[288.960587] [027/156] (742s left) kms_big_fb (linear-32bpp-rotate-90)
[290.484049] Starting subtest: linear-32bpp-rotate-90
[290.586907] Subtest linear-32bpp-rotate-90: SKIP (0.101s)
[290.730140] [028/156] (740s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[295.045871] Starting subtest: heavy-verify-multi-ccs
[295.047515] Starting dynamic subtest: lmem0
[295.050739] Dynamic subtest lmem0: SKIP (0.000s)
[295.053743] Subtest heavy-verify-multi-ccs: SKIP (0.001s)
[298.867903] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[298.870891] [029/156] (732s left) gem_ctx_shared (disjoint-timelines)
[302.173465] Starting subtest: disjoint-timelines
[302.287189] Subtest disjoint-timelines: SUCCESS (0.078s)
[302.575534] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.i915: true
[302.576245] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[302.579286] [030/156] (728s left) kms_psr (fbc-psr2-cursor-plane-move)
[304.074767] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[304.160911] [031/156] (727s left) gem_bad_reloc (negative-reloc-bltcopy)
[304.228718] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[304.345861] [032/156] (727s left) kms_chamelium_hpd (hdmi-hpd)
[305.891790] Subtest hdmi-hpd: SKIP (0.000s)
[306.029678] [033/156] (725s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[307.524847] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[307.620308] [034/156] (724s left) gem_eio (wait-10ms)
[307.764944] Starting subtest: wait-10ms
[308.138696] Subtest wait-10ms: SUCCESS (0.375s)
[309.708596] [035/156] (721s left) kms_content_protection (legacy-hdcp14)
[311.283783] Starting subtest: legacy-hdcp14
[311.288516] Subtest legacy-hdcp14: SKIP (0.002s)
[311.469983] [036/156] (720s left) syncobj_wait (reset-signaled)
[311.519243] Starting subtest: reset-signaled
[311.520711] Subtest reset-signaled: SUCCESS (0.000s)
[311.645333] [037/156] (720s left) gem_ctx_isolation (dirty-switch)
[311.703839] Starting subtest: dirty-switch
[311.705392] Starting dynamic subtest: rcs0
[312.528766] Dynamic subtest rcs0: SUCCESS (0.824s)
[312.542330] Starting dynamic subtest: bcs0
[313.387336] Dynamic subtest bcs0: SUCCESS (0.858s)
[313.400749] Starting dynamic subtest: vcs0
[314.232810] Dynamic subtest vcs0: SUCCESS (0.845s)
[314.246503] Starting dynamic subtest: vcs1
[315.079352] Dynamic subtest vcs1: SUCCESS (0.846s)
[315.092666] Starting dynamic subtest: vecs0
[315.936705] Dynamic subtest vecs0: SUCCESS (0.856s)
[315.938248] Subtest dirty-switch: SUCCESS (4.233s)
[316.237231] [038/156] (715s left) gem_flink_basic (bad-open)
[316.285581] Starting subtest: bad-open
[316.287160] Subtest bad-open: SUCCESS (0.000s)
[316.423621] [039/156] (715s left) gem_caching (writes)
[316.477759] Starting subtest: writes
[321.743483] Subtest writes: SUCCESS (5.264s)
[321.919358] [040/156] (710s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[323.413241] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[323.414574] Starting dynamic subtest: pipe-A
[324.178547] Dynamic subtest pipe-A: SUCCESS (0.766s)
[324.210478] Starting dynamic subtest: pipe-B
[324.969268] Dynamic subtest pipe-B: SUCCESS (0.789s)
[324.990170] Starting dynamic subtest: pipe-C
[325.742436] Dynamic subtest pipe-C: SUCCESS (0.772s)
[325.756221] Starting dynamic subtest: pipe-D
[326.517017] Dynamic subtest pipe-D: SUCCESS (0.773s)
[326.519210] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (3.106s)
[326.744562] [041/156] (705s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[326.794616] Starting subtest: single-wait-all-for-submit-available-submitted
[326.796190] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[326.909394] [042/156] (705s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[328.410347] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[328.413939] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[328.589940] [043/156] (703s left) kms_pm_rpm (system-suspend-idle)
[333.053577] Starting subtest: system-suspend-idle
[352.542366] Subtest system-suspend-idle: SUCCESS (4.669s)
[356.617914] [044/156] (678s left) kms_atomic_transition (modeset-transition)
[358.092766] Starting subtest: modeset-transition
[358.094189] Starting dynamic subtest: 1x-outputs
[360.189517] Dynamic subtest 1x-outputs: SUCCESS (2.130s)
[360.191471] Subtest modeset-transition: SUCCESS (2.134s)
[360.393093] [045/156] (675s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[361.874006] Starting subtest: pipe-fbc-rte
[361.883138] Subtest pipe-fbc-rte: SKIP (0.000s)
[362.059644] [046/156] (673s left) gem_spin_batch (resubmit-all)
[362.119250] Starting subtest: resubmit-all
[362.120773] Starting dynamic subtest: rcs0
[362.141262] Dynamic subtest rcs0: SUCCESS (0.022s)
[362.156962] Starting dynamic subtest: bcs0
[362.158524] Dynamic subtest bcs0: SUCCESS (0.003s)
[362.173151] Starting dynamic subtest: vcs0
[362.174885] Dynamic subtest vcs0: SUCCESS (0.002s)
[362.188856] Starting dynamic subtest: vcs1
[362.190369] Dynamic subtest vcs1: SUCCESS (0.002s)
[362.204480] Starting dynamic subtest: vecs0
[362.206185] Dynamic subtest vecs0: SUCCESS (0.002s)
[362.207941] Subtest resubmit-all: SUCCESS (0.037s)
[362.504715] [047/156] (673s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[363.987442] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[363.990647] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[364.179596] [048/156] (671s left) syncobj_basic (illegal-fd-to-handle)
[364.228829] Starting subtest: illegal-fd-to-handle
[364.230436] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[364.341637] [049/156] (671s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[365.830310] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[365.833304] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[366.000601] [050/156] (670s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[367.501068] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[368.756003] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.260s)
[368.986528] [051/156] (667s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[369.036146] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[369.142443] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[369.270872] [052/156] (667s left) api_intel_bb (misplaced-blitter)
[369.320715] Starting subtest: misplaced-blitter
[369.340823] Subtest misplaced-blitter: SUCCESS (0.022s)
[369.568422] [053/156] (666s left) kms_atomic_interruptible (universal-setplane-cursor)
[371.055359] Starting subtest: universal-setplane-cursor
[371.056969] Starting dynamic subtest: pipe-A-HDMI-A-4
[377.273626] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (6.235s)
[377.275481] Subtest universal-setplane-cursor: SUCCESS (6.237s)
[377.461575] [054/156] (659s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[378.968487] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[378.971139] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[379.157580] [055/156] (657s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[380.655629] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[380.659221] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[380.833177] [056/156] (655s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[382.323166] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[382.324883] Starting dynamic subtest: pipe-A-valid-mode
[383.681024] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.357s)
[383.682691] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.359s)
[383.876164] [057/156] (652s left) gem_pxp (create-protected-buffer)
[386.460887] Starting subtest: create-protected-buffer
[386.463991] Subtest create-protected-buffer: SKIP (0.000s)
[386.577713] [058/156] (650s left) gem_fence_thrash (bo-write-verify-threaded-x)
[386.623837] Starting subtest: bo-write-verify-threaded-x
[386.629030] Subtest bo-write-verify-threaded-x: SKIP (0.002s)
[386.721762] [059/156] (650s left) gen9_exec_parse (allowed-single)
[386.776471] Subtest allowed-single: SKIP (0.000s)
[386.866592] [060/156] (650s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[388.365681] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[388.368627] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[388.550093] [061/156] (648s left) gem_exec_balancer (bonded-semaphore)
[388.609892] Starting subtest: bonded-semaphore
[388.613188] Subtest bonded-semaphore: SKIP (0.000s)
[389.102658] [062/156] (648s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[390.594973] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[390.632303] Starting dynamic subtest: pipe-A-plane-0
[391.166321] Dynamic subtest pipe-A-plane-0: SUCCESS (0.533s)
[391.217610] Starting dynamic subtest: pipe-A-plane-3
[391.481750] Dynamic subtest pipe-A-plane-3: SUCCESS (0.310s)
[391.528018] Starting dynamic subtest: pipe-A-plane-5
[391.798346] Dynamic subtest pipe-A-plane-5: SUCCESS (0.313s)
[391.995812] Starting dynamic subtest: pipe-B-plane-0
[392.383824] Dynamic subtest pipe-B-plane-0: SUCCESS (0.400s)
[392.436895] Starting dynamic subtest: pipe-B-plane-3
[392.698692] Dynamic subtest pipe-B-plane-3: SUCCESS (0.310s)
[392.717609] Starting dynamic subtest: pipe-B-plane-5
[393.049196] Dynamic subtest pipe-B-plane-5: SUCCESS (0.349s)
[393.138958] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SUCCESS (2.546s)
[393.355586] [063/156] (643s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[394.855198] Starting subtest: fbc-rgb565-draw-mmap-wc
[394.858442] Subtest fbc-rgb565-draw-mmap-wc: SKIP (0.000s)
[395.037060] [064/156] (642s left) kms_pwrite_crc (basic)
[396.530383] Starting subtest: basic
[397.195977] Subtest basic: SUCCESS (0.665s)
[397.427028] [065/156] (640s left) gem_flink_basic (bad-flink)
[397.480321] Starting subtest: bad-flink
[397.482370] Subtest bad-flink: SUCCESS (0.000s)
[397.616897] [066/156] (639s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[399.115921] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[399.119074] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[399.309172] [067/156] (638s left) gem_ctx_persistence (legacy-engines-persistence)
[399.365520] Starting subtest: legacy-engines-persistence
[399.367518] Starting dynamic subtest: render
[400.143366] Dynamic subtest render: SUCCESS (0.777s)
[400.164511] Starting dynamic subtest: bsd1
[400.885251] Dynamic subtest bsd1: SUCCESS (0.739s)
[400.901264] Starting dynamic subtest: bsd2
[401.654614] Dynamic subtest bsd2: SUCCESS (0.767s)
[401.673669] Starting dynamic subtest: blt
[402.435230] Dynamic subtest blt: SUCCESS (0.776s)
[402.453311] Starting dynamic subtest: vebox
[403.205019] Dynamic subtest vebox: SUCCESS (0.768s)
[403.206614] Subtest legacy-engines-persistence: SUCCESS (3.842s)
[403.369721] [068/156] (634s left) gen7_exec_parse (basic-allowed)
[403.443362] Subtest basic-allowed: SKIP (0.000s)
[403.528255] [069/156] (634s left) gem_exec_schedule (reorder-wide)
[403.709837] Subtest reorder-wide: SKIP (0.000s)
[403.978191] [070/156] (633s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[405.479608] Starting subtest: psrhdr-slowdraw
[405.482575] Subtest psrhdr-slowdraw: SKIP (0.000s)
[405.666542] [071/156] (632s left) perf (non-zero-reason)
[405.816704] Starting subtest: non-zero-reason
[405.818203] Starting dynamic subtest: 0-rcs0
[416.868231] Dynamic subtest 0-rcs0: SUCCESS (11.053s)
[416.870146] Subtest non-zero-reason: SUCCESS (11.054s)
[416.944658] [072/156] (621s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[418.444839] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[418.448175] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[418.623840] [073/156] (619s left) i915_drm_fdinfo (all-busy-check-all)
[418.679016] Starting subtest: all-busy-check-all
[418.697827] Subtest all-busy-check-all: SKIP (0.017s)
[418.810412] [074/156] (619s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[420.358716] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[420.361846] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[420.530012] [075/156] (617s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[422.022048] Starting subtest: compare-crc-sanitycheck-nv12
[422.026132] Starting dynamic subtest: pipe-A-HDMI-A-4
[422.680666] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.657s)
[422.700005] Starting dynamic subtest: pipe-B-HDMI-A-4
[423.216642] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.532s)
[423.235831] Starting dynamic subtest: pipe-C-HDMI-A-4
[423.734852] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.514s)
[423.750863] Starting dynamic subtest: pipe-D-HDMI-A-4
[424.247245] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.509s)
[424.248912] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.225s)
[424.462540] [076/156] (614s left) gem_exec_reloc (basic-write-gtt)
[424.519510] Subtest basic-write-gtt: SKIP (0.000s)
[424.635785] [077/156] (613s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[426.439519] Starting subtest: cursor-rapid-movement-max-size
[426.443755] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[426.653487] [078/156] (612s left) kms_color_pipeline (plane-lut1d)
[428.145290] Starting subtest: plane-lut1d
[428.347763] Starting dynamic subtest: pipe-A-plane-0
[428.579901] Dynamic subtest pipe-A-plane-0: SUCCESS (0.232s)
[428.606104] Starting dynamic subtest: pipe-A-plane-1
[428.766874] Dynamic subtest pipe-A-plane-1: SUCCESS (0.181s)
[428.810034] Starting dynamic subtest: pipe-A-plane-2
[428.969443] Dynamic subtest pipe-A-plane-2: SUCCESS (0.193s)
[429.292377] Starting dynamic subtest: pipe-B-plane-0
[429.451485] Dynamic subtest pipe-B-plane-0: SUCCESS (0.197s)
[429.491058] Starting dynamic subtest: pipe-B-plane-1
[429.665328] Dynamic subtest pipe-B-plane-1: SUCCESS (0.209s)
[429.687958] Starting dynamic subtest: pipe-B-plane-2
[429.852686] Dynamic subtest pipe-B-plane-2: SUCCESS (0.176s)
[430.183907] Starting dynamic subtest: pipe-C-plane-0
[430.361969] Dynamic subtest pipe-C-plane-0: SUCCESS (0.213s)
[430.397380] Starting dynamic subtest: pipe-C-plane-1
[430.565430] Dynamic subtest pipe-C-plane-1: SUCCESS (0.192s)
[430.592924] Starting dynamic subtest: pipe-C-plane-2
[430.761926] Dynamic subtest pipe-C-plane-2: SUCCESS (0.194s)
[431.090046] Starting dynamic subtest: pipe-D-plane-0
[431.269822] Dynamic subtest pipe-D-plane-0: SUCCESS (0.213s)
[431.294158] Starting dynamic subtest: pipe-D-plane-1
[431.468748] Dynamic subtest pipe-D-plane-1: SUCCESS (0.196s)
[431.509864] Starting dynamic subtest: pipe-D-plane-2
[431.683212] Dynamic subtest pipe-D-plane-2: SUCCESS (0.208s)
[431.706612] Subtest plane-lut1d: SUCCESS (3.562s)
[431.910809] [079/156] (606s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[433.424658] Starting subtest: yf-tiled-16bpp-rotate-180
[433.427865] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[433.601794] [080/156] (605s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[435.153071] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[435.156114] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[435.329125] [081/156] (603s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[436.825753] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[436.828812] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[437.001616] [082/156] (602s left) gem_exec_reloc (basic-wc-read)
[437.056020] Subtest basic-wc-read: SKIP (0.000s)
[437.185981] [083/156] (602s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[438.678372] Starting subtest: short-busy-flip-before-cursor-toggle
[438.681045] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[438.791492] [084/156] (600s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[440.290712] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[440.310204] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.017s)
[440.436748] [085/156] (598s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[441.938023] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[441.941030] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[442.114959] [086/156] (597s left) kms_draw_crc (draw-method-render)
[443.684319] Starting subtest: draw-method-render
[443.759007] Starting dynamic subtest: xrgb8888-untiled
[444.049532] Dynamic subtest xrgb8888-untiled: SUCCESS (0.439s)
[444.065426] Starting dynamic subtest: rgb565-untiled
[444.332771] Dynamic subtest rgb565-untiled: SUCCESS (0.282s)
[444.350259] Starting dynamic subtest: xrgb2101010-untiled
[444.617374] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.282s)
[444.670318] Starting dynamic subtest: xbgr16161616f-untiled
[445.000650] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.376s)
[445.047713] Starting dynamic subtest: xrgb8888-xtiled
[445.200707] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.192s)
[445.247394] Starting dynamic subtest: rgb565-xtiled
[445.400642] Dynamic subtest rgb565-xtiled: SUCCESS (0.192s)
[445.451288] Starting dynamic subtest: xrgb2101010-xtiled
[445.583977] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.176s)
[445.635827] Starting dynamic subtest: xbgr16161616f-xtiled
[445.783924] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.192s)
[445.835749] Starting dynamic subtest: xrgb8888-ytiled
[445.983829] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.192s)
[446.035193] Starting dynamic subtest: rgb565-ytiled
[446.183939] Dynamic subtest rgb565-ytiled: SUCCESS (0.192s)
[446.221037] Starting dynamic subtest: xrgb2101010-ytiled
[446.350062] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.159s)
[446.400804] Starting dynamic subtest: xbgr16161616f-ytiled
[446.548622] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.192s)
[446.550307] Subtest draw-method-render: SUCCESS (2.940s)
[446.751852] [087/156] (592s left) kms_cursor_crc (cursor-sliding-256x85)
[448.490392] Starting subtest: cursor-sliding-256x85
[448.497234] Starting dynamic subtest: pipe-A-HDMI-A-4
[452.939086] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.441s)
[452.976253] Starting dynamic subtest: pipe-D-HDMI-A-4
[457.117626] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (4.152s)
[457.121370] Subtest cursor-sliding-256x85: SUCCESS (8.623s)
[457.459891] [088/156] (582s left) kms_cursor_crc (cursor-onscreen-256x256)
[459.170715] Starting subtest: cursor-onscreen-256x256
[459.178552] Starting dynamic subtest: pipe-A-HDMI-A-4
[461.805134] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.628s)
[461.843560] Starting dynamic subtest: pipe-D-HDMI-A-4
[464.482075] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.651s)
[464.483714] Subtest cursor-onscreen-256x256: SUCCESS (5.308s)
[464.829427] [089/156] (574s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[466.328648] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[466.331633] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[466.526264] [090/156] (573s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[468.028131] Starting subtest: fbcpsr-slowdraw
[468.031590] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[468.187295] [091/156] (571s left) kms_lease (cursor-implicit-plane)
[469.681057] Starting subtest: cursor-implicit-plane
[469.682568] Starting dynamic subtest: pipe-A-HDMI-A-4
[470.075437] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.391s)
[470.092969] Starting dynamic subtest: pipe-B-HDMI-A-4
[470.302062] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.225s)
[470.322371] Starting dynamic subtest: pipe-C-HDMI-A-4
[470.534775] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.227s)
[470.552714] Starting dynamic subtest: pipe-D-HDMI-A-4
[470.769493] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.232s)
[470.770907] Subtest cursor-implicit-plane: SUCCESS (1.088s)
[470.989389] [092/156] (569s left) syncobj_wait (invalid-reset-zero-handles)
[471.041935] Starting subtest: invalid-reset-zero-handles
[471.043834] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[471.178246] [093/156] (568s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[472.679969] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[472.682704] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[472.843816] [094/156] (567s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[474.357100] Starting subtest: yf-tiled-64bpp-rotate-90
[474.360784] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[474.544951] [095/156] (565s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[476.063080] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[476.066407] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[476.238681] [096/156] (564s left) gem_exec_fence (long-history)
[476.672866] Starting subtest: long-history
[482.760372] Subtest long-history: SUCCESS (6.085s)
[483.141115] [097/156] (557s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[484.640202] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[484.643690] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[484.841591] [098/156] (555s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[486.332739] Starting subtest: bad-rotation-90-yf-tiled-ccs
[486.334806] Starting dynamic subtest: pipe-A-HDMI-A-4
[486.352251] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[486.367937] Starting dynamic subtest: pipe-B-HDMI-A-4
[486.382393] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[486.397428] Starting dynamic subtest: pipe-C-HDMI-A-4
[486.412234] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[486.427302] Starting dynamic subtest: pipe-D-HDMI-A-4
[486.444302] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[486.447424] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.010s)
[486.624762] [099/156] (554s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[488.116465] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[490.085651] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.971s)
[490.277982] [100/156] (550s left) kms_chamelium_audio (dp-audio-edid)
[491.813735] Subtest dp-audio-edid: SKIP (0.000s)
[491.947386] [101/156] (549s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[493.443722] Starting subtest: addfb25-yf-tiled-legacy
[493.445315] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[493.630125] [102/156] (547s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[495.126908] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[495.129717] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[495.309609] [103/156] (545s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[496.817304] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[496.820959] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[497.002958] [104/156] (544s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[498.503825] Starting subtest: fbc-2p-pri-indfb-multidraw
[498.506714] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[498.680325] [105/156] (542s left) kms_sequence (queue-busy)
[500.173236] Starting subtest: queue-busy
[500.174957] Starting dynamic subtest: pipe-A-HDMI-A-4
[502.764748] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.592s)
[502.785106] Starting dynamic subtest: pipe-B-HDMI-A-4
[505.173681] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (2.407s)
[505.190468] Starting dynamic subtest: pipe-C-HDMI-A-4
[507.601936] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (2.427s)
[507.618374] Starting dynamic subtest: pipe-D-HDMI-A-4
[510.029142] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.425s)
[510.030770] Subtest queue-busy: SUCCESS (9.860s)
[510.229926] [106/156] (531s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[511.722755] Starting subtest: plane-upscale-20x20-with-rotation
[511.724370] Starting dynamic subtest: pipe-A
[512.144728] Dynamic subtest pipe-A: SKIP (0.418s)
[512.160046] Starting dynamic subtest: pipe-B
[512.570856] Dynamic subtest pipe-B: SKIP (0.424s)
[512.586165] Starting dynamic subtest: pipe-C
[512.996544] Dynamic subtest pipe-C: SKIP (0.424s)
[513.012102] Starting dynamic subtest: pipe-D
[513.419040] Dynamic subtest pipe-D: SKIP (0.421s)
[513.422519] Subtest plane-upscale-20x20-with-rotation: SKIP (1.693s)
[513.623646] [107/156] (528s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[515.128509] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[515.132041] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[515.256856] [108/156] (526s left) gem_exec_reloc (basic-write-wc)
[515.320524] Subtest basic-write-wc: SKIP (0.000s)
[515.433896] [109/156] (526s left) syncobj_timeline (reset-signaled)
[515.483838] Starting subtest: reset-signaled
[515.485401] Subtest reset-signaled: SUCCESS (0.000s)
[515.611020] [110/156] (526s left) kms_psr (pr-cursor-plane-move)
[517.100317] Subtest pr-cursor-plane-move: SKIP (0.000s)
[517.181721] [111/156] (524s left) syncobj_wait (invalid-wait-illegal-handle)
[517.230551] Starting subtest: invalid-wait-illegal-handle
[517.231930] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[517.364969] [112/156] (524s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[518.861025] Starting subtest: extended-pageflip-modeset-hang-oldfb
[518.862912] Starting dynamic subtest: pipe-A
[526.600837] Dynamic subtest pipe-A: SUCCESS (7.741s)
[526.619796] Starting dynamic subtest: pipe-D
[534.330430] Dynamic subtest pipe-D: SUCCESS (7.726s)
[534.332024] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (15.475s)
[534.577823] [113/156] (507s left) kms_psr (psr2-primary-blt)
[536.069517] Subtest psr2-primary-blt: SKIP (0.000s)
[536.155283] [114/156] (506s left) gem_softpin (evict-snoop-interruptible)
[536.212905] Starting subtest: evict-snoop-interruptible
[536.216115] Subtest evict-snoop-interruptible: SKIP (0.000s)
[536.338880] [115/156] (505s left) kms_force_connector_basic (force-load-detect)
[536.472851] Starting subtest: force-load-detect
[536.476084] Subtest force-load-detect: SKIP (0.000s)
[537.401904] [116/156] (504s left) kms_chamelium_edid (hdmi-mode-timings)
[538.931782] Subtest hdmi-mode-timings: SKIP (0.000s)
[539.063656] [117/156] (503s left) syncobj_timeline (invalid-signal-zero-handles)
[539.112299] Starting subtest: invalid-signal-zero-handles
[539.113834] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[539.256992] [118/156] (503s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[540.754853] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[540.757896] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[540.948933] [119/156] (501s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[542.446889] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[542.449730] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[542.626029] [120/156] (500s left) gem_mmap_gtt (fault-concurrent-x)
[542.678526] Subtest fault-concurrent-X: SKIP (0.000s)
[542.760233] [121/156] (500s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[542.816622] Starting subtest: y-tiled-to-vebox-y-tiled
[542.818152] Starting dynamic subtest: smem
[542.900713] Dynamic subtest smem: SUCCESS (0.083s)
[542.919462] Starting dynamic subtest: lmem0
[543.653920] Dynamic subtest lmem0: SUCCESS (0.749s)
[543.655617] Subtest y-tiled-to-vebox-y-tiled: SUCCESS (0.835s)
[543.916625] [122/156] (498s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[545.414052] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[545.417146] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[545.591569] [123/156] (497s left) vgem_basic (debugfs)
[545.643166] Starting subtest: debugfs
[545.644797] Subtest debugfs: SUCCESS (0.000s)
[545.746433] [124/156] (497s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[547.235010] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[547.238875] Starting dynamic subtest: pipe-A-valid-mode
[548.588433] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.352s)
[548.589914] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.354s)
[548.798875] [125/156] (494s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[550.296728] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[550.299418] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[550.486180] [126/156] (492s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[551.997450] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[552.001312] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[552.169596] [127/156] (491s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[553.667113] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[553.669739] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[553.851193] [128/156] (489s left) kms_vblank (wait-forked-hang)
[555.341516] Starting subtest: wait-forked-hang
[555.343319] Starting dynamic subtest: pipe-A-HDMI-A-4
[582.285444] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (26.946s)
[582.304146] Starting dynamic subtest: pipe-D-HDMI-A-4
[607.279676] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (24.985s)
[607.281264] Subtest wait-forked-hang: SUCCESS (51.939s)
[607.489998] [129/156] (436s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[608.993556] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[608.994969] Starting dynamic subtest: pipe-A-valid-mode
[611.601777] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.608s)
[611.603251] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (2.610s)
[611.802601] [130/156] (431s left) gem_ctx_exec (basic-invalid-context)
[611.854111] Starting subtest: basic-invalid-context
[611.858227] Subtest basic-invalid-context: SUCCESS (0.005s)
[612.019307] [131/156] (431s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[613.518376] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[613.521257] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[613.676084] [132/156] (430s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[615.169192] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[615.258713] [133/156] (428s left) gem_ppgtt (blt-vs-render-ctxn)
[615.313114] Starting subtest: blt-vs-render-ctxN
[648.047822] Subtest blt-vs-render-ctxN: SUCCESS (32.731s)
[648.290686] [134/156] (395s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[648.405211] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[648.511167] [135/156] (395s left) gem_softpin (invalid)
[648.564967] Starting subtest: invalid
[648.577448] Subtest invalid: SUCCESS (0.012s)
[648.782174] [136/156] (395s left) gem_exec_capture (capture)
[648.840680] Starting subtest: capture
[648.842000] Starting dynamic subtest: rcs0-smem
[649.744780] Dynamic subtest rcs0-smem: SUCCESS (0.904s)
[649.761768] Starting dynamic subtest: rcs0-lmem0
[650.626745] Dynamic subtest rcs0-lmem0: SUCCESS (0.880s)
[650.642461] Starting dynamic subtest: bcs0-smem
[651.996964] Dynamic subtest bcs0-smem: SUCCESS (1.369s)
[652.012428] Starting dynamic subtest: bcs0-lmem0
[653.000965] Dynamic subtest bcs0-lmem0: SUCCESS (1.001s)
[653.016489] Starting dynamic subtest: vcs0-smem
[653.858928] Dynamic subtest vcs0-smem: SUCCESS (0.857s)
[653.874675] Starting dynamic subtest: vcs0-lmem0
[654.718463] Dynamic subtest vcs0-lmem0: SUCCESS (0.855s)
[654.733826] Starting dynamic subtest: vcs1-smem
[655.579188] Dynamic subtest vcs1-smem: SUCCESS (0.862s)
[655.594936] Starting dynamic subtest: vcs1-lmem0
[656.436071] Dynamic subtest vcs1-lmem0: SUCCESS (0.855s)
[656.451569] Starting dynamic subtest: vecs0-smem
[657.866592] Dynamic subtest vecs0-smem: FAIL (1.392s)
[657.881949] Starting dynamic subtest: vecs0-lmem0
[658.872054] Dynamic subtest vecs0-lmem0: FAIL (0.979s)
[658.873726] Subtest capture: FAIL (9.970s)
[658.981601] [137/156] (385s left) gem_tiled_blits (basic)
[659.040825] Subtest basic: SKIP (0.000s)
[659.126763] [138/156] (385s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[660.630328] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[660.633063] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[660.808337] [139/156] (383s left) gem_mmap_offset (partial-remap)
[660.857739] Starting subtest: partial-remap
[660.859312] Starting dynamic subtest: lmem0
[660.864200] Dynamic subtest lmem0: SUCCESS (0.005s)
[660.880067] Starting dynamic subtest: smem0
[660.883515] Dynamic subtest smem0: SUCCESS (0.005s)
[660.885174] Subtest partial-remap: SUCCESS (0.013s)
[661.037699] [140/156] (383s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[662.536422] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[662.539386] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[662.695166] [141/156] (382s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[664.194702] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[664.197374] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[664.373054] [142/156] (380s left) i915_power (sanity)
[664.420934] Starting subtest: sanity
[668.466351] Subtest sanity: SUCCESS (4.039s)
[668.684802] [143/156] (376s left) gem_exec_schedule (preempt-queue)
[668.761753] Subtest preempt-queue: SKIP (0.000s)
[669.131247] [144/156] (375s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[670.624813] Starting subtest: cursorB-vs-flipA-legacy
[670.627900] Subtest cursorB-vs-flipA-legacy: SKIP (0.002s)
[670.763974] [145/156] (374s left) i915_pm_rps (reset)
[670.833266] Starting subtest: reset
[675.590795] Subtest reset: SUCCESS (4.758s)
[675.748420] [146/156] (369s left) kms_psr (fbc-psr-cursor-plane-onoff)
[677.241250] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[677.323918] [147/156] (367s left) kms_setmode (clone-exclusive-crtc)
[677.375027] Starting subtest: clone-exclusive-crtc
[677.379635] Subtest clone-exclusive-crtc: SKIP (0.000s)
[677.506329] [148/156] (367s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[679.009507] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[679.013203] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[679.198990] [149/156] (366s left) kms_flip (flip-vs-rmfb)
[680.691218] Starting subtest: flip-vs-rmfb
[680.699393] Starting dynamic subtest: A-HDMI-A4
[688.484829] Dynamic subtest A-HDMI-A4: SUCCESS (7.786s)
[688.515405] Starting dynamic subtest: B-HDMI-A4
[696.246846] Dynamic subtest B-HDMI-A4: SUCCESS (7.760s)
[696.288212] Starting dynamic subtest: C-HDMI-A4
[703.990625] Dynamic subtest C-HDMI-A4: SUCCESS (7.740s)
[704.032639] Starting dynamic subtest: D-HDMI-A4
[711.734459] Dynamic subtest D-HDMI-A4: SUCCESS (7.740s)
[711.736749] Subtest flip-vs-rmfb: SUCCESS (31.050s)
[712.011975] [150/156] (333s left) gem_exec_whisper (basic-contexts-all)
[712.075228] Starting subtest: basic-contexts-all
[719.210585] Subtest basic-contexts-all: SUCCESS (7.132s)
[719.548065] [151/156] (326s left) dmabuf_sync_file (export-basic)
[719.610262] Starting subtest: export-basic
[719.612199] Subtest export-basic: SUCCESS (0.001s)
[719.725761] [152/156] (326s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[721.223094] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[721.226141] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[721.406538] [153/156] (324s left) gem_sync (basic-each)
[721.471381] Starting subtest: basic-each
[723.870155] Subtest basic-each: SUCCESS (2.402s)
[724.216842] [154/156] (321s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[725.708932] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[725.710444] Starting dynamic subtest: pipe-A-HDMI-A-4
[725.727107] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[725.743458] Starting dynamic subtest: pipe-B-HDMI-A-4
[725.760673] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[725.776464] Starting dynamic subtest: pipe-C-HDMI-A-4
[725.794638] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[725.810187] Starting dynamic subtest: pipe-D-HDMI-A-4
[725.828648] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[725.832258] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.010s)
[725.989082] [155/156] (320s left) syncobj_wait (multi-wait-for-submit-submitted)
[726.040127] Starting subtest: multi-wait-for-submit-submitted
[726.144882] Subtest multi-wait-for-submit-submitted: SUCCESS (0.101s)
[726.323355] [156/156] (319s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[727.819430] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[727.822396] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[727.994819] 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.