[63.397363] Initializing watchdogs
[63.397432]   /dev/watchdog0
[63.419525] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[63.430898] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[63.434778] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[63.437789] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[63.632726] Starting subtest: fbc-tiling-linear
[66.122383] Subtest fbc-tiling-linear: SUCCESS (2.489s)
[66.386302] [002/156] (957s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[66.458382] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[66.542476] [003/156] (957s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[66.730918] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[66.732695] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[66.930997] [004/156] (957s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[67.415126] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[67.416919] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[67.651386] [005/156] (956s left) kms_force_connector_basic (prune-stale-modes)
[67.795630] Starting subtest: prune-stale-modes
[67.819624] Subtest prune-stale-modes: SUCCESS (0.024s)
[68.120381] [006/156] (956s left) perf_pmu (most-busy-check-all)
[68.191588] Starting subtest: most-busy-check-all
[68.192507] Starting dynamic subtest: rcs0
[68.778451] Dynamic subtest rcs0: SUCCESS (0.586s)
[68.793160] Starting dynamic subtest: bcs0
[69.335593] Dynamic subtest bcs0: SUCCESS (0.557s)
[69.349148] Starting dynamic subtest: vcs0
[69.888533] Dynamic subtest vcs0: SUCCESS (0.552s)
[69.902246] Starting dynamic subtest: vcs1
[70.443657] Dynamic subtest vcs1: SUCCESS (0.555s)
[70.455456] Starting dynamic subtest: vecs0
[70.996048] Dynamic subtest vecs0: SUCCESS (0.552s)
[71.007271] Starting dynamic subtest: vecs1
[71.545563] Dynamic subtest vecs1: SUCCESS (0.549s)
[71.557063] Starting dynamic subtest: ccs0
[72.099562] Dynamic subtest ccs0: SUCCESS (0.554s)
[72.100512] Subtest most-busy-check-all: SUCCESS (3.908s)
[72.222713] [007/156] (952s left) gem_ccs (large-ctrl-surf-copy)
[72.300786] Starting subtest: large-ctrl-surf-copy
[72.954730] Subtest large-ctrl-surf-copy: SUCCESS (0.654s)
[73.115795] [008/156] (951s left) kms_plane_alpha_blend (coverage-7efc)
[73.297478] Starting subtest: coverage-7efc
[73.555616] Starting dynamic subtest: pipe-A-DP-3
[74.037064] Dynamic subtest pipe-A-DP-3: SUCCESS (0.480s)
[74.081259] Starting dynamic subtest: pipe-D-DP-3
[74.781424] Dynamic subtest pipe-D-DP-3: SUCCESS (0.709s)
[74.782345] Subtest coverage-7efc: SUCCESS (1.483s)
[75.078210] [009/156] (949s left) gem_mmap_gtt (fault-concurrent)
[75.155147] Subtest fault-concurrent: SKIP (0.000s)
[75.233180] [010/156] (949s left) api_intel_allocator (gem-pool)
[75.308980] Starting subtest: gem-pool
[75.343529] Subtest gem-pool: SUCCESS (0.035s)
[75.532865] [011/156] (949s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[75.719858] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[75.721502] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[75.850565] [012/156] (948s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[76.033165] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[76.062996] Starting dynamic subtest: pipe-A-plane-0
[76.595898] Dynamic subtest pipe-A-plane-0: SUCCESS (0.533s)
[76.608056] Starting dynamic subtest: pipe-A-plane-3
[76.929367] Dynamic subtest pipe-A-plane-3: SUCCESS (0.333s)
[76.942531] Starting dynamic subtest: pipe-A-plane-5
[76.944601] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[77.196860] Starting dynamic subtest: pipe-B-plane-0
[77.563185] Dynamic subtest pipe-B-plane-0: SUCCESS (0.400s)
[77.601291] Starting dynamic subtest: pipe-B-plane-3
[77.880877] Dynamic subtest pipe-B-plane-3: SUCCESS (0.317s)
[77.892813] Starting dynamic subtest: pipe-B-plane-5
[77.894535] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[77.955266] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SUCCESS (1.922s)
[78.273097] [013/156] (946s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[78.454970] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[78.455880] Starting dynamic subtest: pipe-A-DP-3
[78.464736] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[78.474925] Starting dynamic subtest: pipe-B-DP-3
[78.483764] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[78.493802] Starting dynamic subtest: pipe-C-DP-3
[78.502256] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[78.512398] Starting dynamic subtest: pipe-D-DP-3
[78.522697] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[78.524656] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.006s)
[78.946102] [014/156] (945s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[79.130522] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[79.131469] Starting dynamic subtest: pipe-A-DP-3
[79.139997] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[79.150357] Starting dynamic subtest: pipe-B-DP-3
[79.159151] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[79.169481] Starting dynamic subtest: pipe-C-DP-3
[79.177922] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[79.187810] Starting dynamic subtest: pipe-D-DP-3
[79.198953] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[79.200937] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[79.597171] [015/156] (945s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[79.786409] Starting subtest: hdr-rgb565-draw-blt
[80.451501] Subtest hdr-rgb565-draw-blt: SUCCESS (0.664s)
[80.691495] [016/156] (944s left) kms_pm_rpm (legacy-planes-dpms)
[83.051601] Starting subtest: legacy-planes-dpms
[83.053674] Starting dynamic subtest: plane-65
[85.879147] Dynamic subtest plane-65: SUCCESS (2.827s)
[85.922028] Starting dynamic subtest: plane-95
[88.553521] Dynamic subtest plane-95: SUCCESS (2.676s)
[88.579017] Starting dynamic subtest: plane-125
[91.361118] Dynamic subtest plane-125: SUCCESS (2.807s)
[91.387607] Starting dynamic subtest: plane-135
[94.255944] Dynamic subtest plane-135: SUCCESS (2.892s)
[94.257962] Subtest legacy-planes-dpms: SUCCESS (11.206s)
[94.704748] [017/156] (930s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[94.784529] Starting subtest: x-tiled-to-vebox-y-tiled
[94.786601] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[95.069495] [018/156] (930s left) sysfs_heartbeat_interval (invalid)
[95.147047] Starting subtest: invalid
[95.147950] Starting dynamic subtest: vecs0
[95.148915] Dynamic subtest vecs0: SUCCESS (0.000s)
[95.160235] Starting dynamic subtest: ccs0
[95.168208] Dynamic subtest ccs0: SUCCESS (0.000s)
[95.178861] Starting dynamic subtest: vcs0
[95.179912] Dynamic subtest vcs0: SUCCESS (0.000s)
[95.190573] Starting dynamic subtest: bcs0
[95.191789] Dynamic subtest bcs0: SUCCESS (0.000s)
[95.203151] Starting dynamic subtest: vecs1
[95.204355] Dynamic subtest vecs1: SUCCESS (0.000s)
[95.214627] Starting dynamic subtest: rcs0
[95.215756] Dynamic subtest rcs0: SUCCESS (0.000s)
[95.225950] Starting dynamic subtest: vcs1
[95.227198] Dynamic subtest vcs1: SUCCESS (0.001s)
[95.228454] Subtest invalid: SUCCESS (0.005s)
[95.272130] [019/156] (929s left) kms_frontbuffer_tracking (fbc-stridechange)
[95.460055] Starting subtest: fbc-stridechange
[97.696756] Subtest fbc-stridechange: SUCCESS (2.236s)
[97.912745] [020/156] (927s left) gem_ctx_param (basic-default)
[97.984388] Starting subtest: basic-default
[97.985270] Subtest basic-default: SUCCESS (0.000s)
[98.075443] [021/156] (927s left) kms_psr (fbc-pr-primary-mmap-gtt)
[98.259390] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[98.320581] [022/156] (927s left) i915_pm_freq_mult (media-freq)
[98.394009] Starting subtest: media-freq
[98.394955] Starting dynamic subtest: gt0
[98.889432] Dynamic subtest gt0: SUCCESS (0.496s)
[98.890534] Subtest media-freq: SUCCESS (0.497s)
[99.049998] [023/156] (926s left) prime_busy (before-wait)
[99.127190] Starting subtest: before-wait
[99.131817] Starting dynamic subtest: rcs0
[99.180198] Dynamic subtest rcs0: SUCCESS (0.048s)
[99.191642] Starting dynamic subtest: bcs0
[99.233871] Dynamic subtest bcs0: SUCCESS (0.053s)
[99.246660] Starting dynamic subtest: vcs0
[99.275095] Dynamic subtest vcs0: SUCCESS (0.041s)
[99.286655] Starting dynamic subtest: vcs1
[99.314866] Dynamic subtest vcs1: SUCCESS (0.039s)
[99.333486] Starting dynamic subtest: vecs0
[99.356414] Dynamic subtest vecs0: SUCCESS (0.041s)
[99.378324] Starting dynamic subtest: vecs1
[99.398413] Dynamic subtest vecs1: SUCCESS (0.041s)
[99.420576] Starting dynamic subtest: ccs0
[99.439315] Dynamic subtest ccs0: SUCCESS (0.040s)
[99.548449] Subtest before-wait: SUCCESS (0.420s)
[99.752372] [024/156] (925s left) gem_exec_gttfill (engines)
[99.832976] Starting subtest: engines
[99.833887] Starting dynamic subtest: rcs0
[125.927842] Dynamic subtest rcs0: SUCCESS (26.092s)
[125.939388] Starting dynamic subtest: bcs0
[152.174505] Dynamic subtest bcs0: SUCCESS (26.246s)
[152.186049] Starting dynamic subtest: vcs0
[178.360531] Dynamic subtest vcs0: SUCCESS (26.186s)
[178.372116] Starting dynamic subtest: vcs1
[204.592748] Dynamic subtest vcs1: SUCCESS (26.232s)
[204.605368] Starting dynamic subtest: vecs0
[230.768035] Dynamic subtest vecs0: SUCCESS (26.175s)
[230.779607] Starting dynamic subtest: vecs1
[256.898526] Dynamic subtest vecs1: SUCCESS (26.130s)
[256.910374] Starting dynamic subtest: ccs0
[283.078777] Dynamic subtest ccs0: SUCCESS (26.180s)
[283.079679] Subtest engines: SUCCESS (183.246s)
[283.332111] [025/156] (742s left) syncobj_timeline (etime-multi-wait-all-submitted)
[283.449527] Starting subtest: etime-multi-wait-all-submitted
[283.555146] Subtest etime-multi-wait-all-submitted: SUCCESS (0.105s)
[283.678890] [026/156] (741s left) gem_ctx_param (get-priority-new-ctx)
[283.763506] Starting subtest: get-priority-new-ctx
[283.765451] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[283.917410] [027/156] (741s left) kms_big_fb (linear-32bpp-rotate-90)
[284.119485] Starting subtest: linear-32bpp-rotate-90
[284.186323] Subtest linear-32bpp-rotate-90: SKIP (0.065s)
[284.392692] [028/156] (741s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[287.160525] Starting subtest: heavy-verify-multi-ccs
[287.161740] Starting dynamic subtest: lmem0
[295.353057] Dynamic subtest lmem0: SUCCESS (8.192s)
[295.354044] Subtest heavy-verify-multi-ccs: SUCCESS (8.193s)
[296.595715] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[296.597579] [029/156] (729s left) gem_ctx_shared (disjoint-timelines)
[298.212522] Starting subtest: disjoint-timelines
[298.289891] Subtest disjoint-timelines: SUCCESS (0.076s)
[298.571349] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.i915: true
[298.571957] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[298.573798] [030/156] (727s left) kms_psr (fbc-psr2-cursor-plane-move)
[298.785017] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[298.845673] [031/156] (727s left) gem_bad_reloc (negative-reloc-bltcopy)
[298.933259] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[299.044174] [032/156] (727s left) kms_chamelium_hpd (hdmi-hpd)
[299.328476] Subtest hdmi-hpd: SKIP (0.000s)
[299.500367] [033/156] (726s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[299.692346] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[299.756455] [034/156] (726s left) gem_eio (wait-10ms)
[300.003181] Starting subtest: wait-10ms
[300.561982] Subtest wait-10ms: SUCCESS (0.559s)
[300.824586] [035/156] (725s left) kms_content_protection (legacy-hdcp14)
[301.096062] Starting subtest: legacy-hdcp14
[301.363046] Starting dynamic subtest: pipe-A-DP-3
[424.509462] Dynamic subtest pipe-A-DP-3: FAIL (122.871s)
[424.510592] Subtest legacy-hdcp14: FAIL (123.250s)
[424.696820] [036/156] (601s left) syncobj_wait (reset-signaled)
[424.778455] Starting subtest: reset-signaled
[424.779407] Subtest reset-signaled: SUCCESS (0.000s)
[424.930114] [037/156] (601s left) gem_ctx_isolation (dirty-switch)
[425.020410] Starting subtest: dirty-switch
[425.021435] Starting dynamic subtest: rcs0
[425.663701] Dynamic subtest rcs0: SUCCESS (0.643s)
[425.676558] Starting dynamic subtest: bcs0
[426.321657] Dynamic subtest bcs0: SUCCESS (0.657s)
[426.334455] Starting dynamic subtest: vcs0
[426.996805] Dynamic subtest vcs0: SUCCESS (0.674s)
[427.010380] Starting dynamic subtest: vcs1
[427.650251] Dynamic subtest vcs1: SUCCESS (0.653s)
[427.662575] Starting dynamic subtest: vecs0
[428.306486] Dynamic subtest vecs0: SUCCESS (0.653s)
[428.326172] Starting dynamic subtest: vecs1
[428.966915] Dynamic subtest vecs1: SUCCESS (0.662s)
[428.979491] Starting dynamic subtest: ccs0
[429.630383] Dynamic subtest ccs0: SUCCESS (0.663s)
[429.631340] Subtest dirty-switch: SUCCESS (4.610s)
[429.894707] [038/156] (596s left) gem_flink_basic (bad-open)
[429.974162] Starting subtest: bad-open
[429.975061] Subtest bad-open: SUCCESS (0.000s)
[430.133115] [039/156] (596s left) gem_caching (writes)
[430.225106] Starting subtest: writes
[434.547569] Subtest writes: SUCCESS (4.323s)
[434.712508] [040/156] (591s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[434.910752] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[434.911635] Starting dynamic subtest: pipe-A
[435.487392] Dynamic subtest pipe-A: SUCCESS (0.576s)
[435.506753] Starting dynamic subtest: pipe-B
[436.134131] Dynamic subtest pipe-B: SUCCESS (0.646s)
[436.155940] Starting dynamic subtest: pipe-C
[436.797069] Dynamic subtest pipe-C: SUCCESS (0.662s)
[436.826067] Starting dynamic subtest: pipe-D
[437.460267] Dynamic subtest pipe-D: SUCCESS (0.662s)
[437.461561] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (2.550s)
[437.893719] [041/156] (588s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[437.975381] Starting subtest: single-wait-all-for-submit-available-submitted
[437.976322] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[438.108076] [042/156] (588s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[438.302929] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[438.304767] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[438.452426] [043/156] (588s left) kms_pm_rpm (system-suspend-idle)
[439.996258] Starting subtest: system-suspend-idle
[459.383088] Subtest system-suspend-idle: SUCCESS (5.607s)
[478.872305] [044/156] (567s left) kms_atomic_transition (modeset-transition)
[479.065730] Starting subtest: modeset-transition
[479.066655] Starting dynamic subtest: 1x-outputs
[481.841165] Dynamic subtest 1x-outputs: SUCCESS (2.773s)
[481.843298] Subtest modeset-transition: SUCCESS (2.776s)
[482.169642] [045/156] (563s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[482.368221] Starting subtest: pipe-fbc-rte
[482.373207] Subtest pipe-fbc-rte: SKIP (0.000s)
[482.555972] [046/156] (563s left) gem_spin_batch (resubmit-all)
[482.648198] Starting subtest: resubmit-all
[482.649204] Starting dynamic subtest: rcs0
[482.685752] Dynamic subtest rcs0: SUCCESS (0.037s)
[482.701884] Starting dynamic subtest: bcs0
[482.702808] Dynamic subtest bcs0: SUCCESS (0.003s)
[482.717738] Starting dynamic subtest: vcs0
[482.719043] Dynamic subtest vcs0: SUCCESS (0.003s)
[482.733324] Starting dynamic subtest: vcs1
[482.734481] Dynamic subtest vcs1: SUCCESS (0.002s)
[482.748583] Starting dynamic subtest: vecs0
[482.749723] Dynamic subtest vecs0: SUCCESS (0.003s)
[482.763763] Starting dynamic subtest: vecs1
[482.765016] Dynamic subtest vecs1: SUCCESS (0.003s)
[482.779071] Starting dynamic subtest: ccs0
[482.780138] Dynamic subtest ccs0: SUCCESS (0.003s)
[482.781165] Subtest resubmit-all: SUCCESS (0.060s)
[483.036330] [047/156] (563s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[483.233156] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[483.234795] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[483.401568] [048/156] (562s left) syncobj_basic (illegal-fd-to-handle)
[483.486667] Starting subtest: illegal-fd-to-handle
[483.487602] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[483.648878] [049/156] (562s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[483.848305] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[483.850059] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[484.052600] [050/156] (562s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[484.255874] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[485.577607] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.320s)
[485.807598] [051/156] (560s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[485.899850] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[486.002168] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.102s)
[486.154000] [052/156] (560s left) api_intel_bb (misplaced-blitter)
[486.236863] Starting subtest: misplaced-blitter
[486.268060] Subtest misplaced-blitter: SUCCESS (0.032s)
[486.404891] [053/156] (560s left) kms_atomic_interruptible (universal-setplane-cursor)
[486.601574] Starting subtest: universal-setplane-cursor
[486.602512] Starting dynamic subtest: pipe-A-DP-3
[492.922594] Dynamic subtest pipe-A-DP-3: SUCCESS (6.318s)
[492.923607] Subtest universal-setplane-cursor: SUCCESS (6.320s)
[493.206298] [054/156] (553s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[493.410749] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[493.412597] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[493.527187] [055/156] (553s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[493.724305] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[493.726075] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[493.876053] [056/156] (553s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[494.066132] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[494.067866] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[494.227308] [057/156] (552s left) gem_pxp (create-protected-buffer)
[496.839795] Starting subtest: create-protected-buffer
[496.841777] Subtest create-protected-buffer: SKIP (0.000s)
[496.963079] [058/156] (550s left) gem_fence_thrash (bo-write-verify-threaded-x)
[497.013668] Starting subtest: bo-write-verify-threaded-x
[497.044604] Subtest bo-write-verify-threaded-x: SKIP (0.030s)
[497.128626] [059/156] (550s left) gen9_exec_parse (allowed-single)
[497.219785] Subtest allowed-single: SKIP (0.000s)
[497.276657] [060/156] (550s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[497.474510] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[497.476278] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[497.638603] [061/156] (549s left) gem_exec_balancer (bonded-semaphore)
[497.739348] Starting subtest: bonded-semaphore
[497.741509] Subtest bonded-semaphore: SKIP (0.000s)
[498.132197] [062/156] (549s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[498.325823] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[498.327694] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[498.416697] [063/156] (549s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[498.613385] Starting subtest: fbc-rgb565-draw-mmap-wc
[498.615152] Subtest fbc-rgb565-draw-mmap-wc: SKIP (0.000s)
[498.762241] [064/156] (548s left) kms_pwrite_crc (basic)
[498.955779] Starting subtest: basic
[499.577107] Subtest basic: SUCCESS (0.620s)
[499.902678] [065/156] (547s left) gem_flink_basic (bad-flink)
[499.983743] Starting subtest: bad-flink
[499.984807] Subtest bad-flink: SUCCESS (0.000s)
[500.133690] [066/156] (547s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[500.338467] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[500.340208] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[500.521259] [067/156] (547s left) gem_ctx_persistence (legacy-engines-persistence)
[500.609952] Starting subtest: legacy-engines-persistence
[500.610854] Starting dynamic subtest: render
[501.464158] Dynamic subtest render: SUCCESS (0.853s)
[501.481980] Starting dynamic subtest: bsd1
[502.226853] Dynamic subtest bsd1: SUCCESS (0.761s)
[502.244166] Starting dynamic subtest: bsd2
[502.995814] Dynamic subtest bsd2: SUCCESS (0.767s)
[503.014951] Starting dynamic subtest: blt
[503.759914] Dynamic subtest blt: SUCCESS (0.761s)
[503.778442] Starting dynamic subtest: vebox
[504.522655] Dynamic subtest vebox: SUCCESS (0.758s)
[504.527550] Subtest legacy-engines-persistence: SUCCESS (3.914s)
[504.666634] [068/156] (543s left) gen7_exec_parse (basic-allowed)
[504.759253] Subtest basic-allowed: SKIP (0.000s)
[504.856061] [069/156] (543s left) gem_exec_schedule (reorder-wide)
[505.074994] Subtest reorder-wide: SKIP (0.000s)
[505.371259] [070/156] (542s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[505.573290] Starting subtest: psrhdr-slowdraw
[505.575017] Subtest psrhdr-slowdraw: SKIP (0.000s)
[505.726695] [071/156] (542s left) perf (non-zero-reason)
[506.130257] Starting subtest: non-zero-reason
[506.131153] Starting dynamic subtest: 0-rcs0
[509.187610] Dynamic subtest 0-rcs0: FAIL (2.732s)
[509.189304] Subtest non-zero-reason: FAIL (2.733s)
[509.274217] [072/156] (539s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[509.471544] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[509.473369] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[509.625717] [073/156] (538s left) i915_drm_fdinfo (all-busy-check-all)
[509.701352] Starting subtest: all-busy-check-all
[509.729797] Subtest all-busy-check-all: SKIP (0.028s)
[509.825447] [074/156] (538s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[510.051331] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[510.053142] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[510.238176] [075/156] (538s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[510.432792] Starting subtest: compare-crc-sanitycheck-nv12
[510.433720] Starting dynamic subtest: pipe-A-DP-3
[511.181787] Dynamic subtest pipe-A-DP-3: SUCCESS (0.748s)
[511.197639] Starting dynamic subtest: pipe-B-DP-3
[511.860561] Dynamic subtest pipe-B-DP-3: SUCCESS (0.677s)
[511.876757] Starting dynamic subtest: pipe-C-DP-3
[512.513590] Dynamic subtest pipe-C-DP-3: SUCCESS (0.651s)
[512.529514] Starting dynamic subtest: pipe-D-DP-3
[513.169524] Dynamic subtest pipe-D-DP-3: SUCCESS (0.655s)
[513.170466] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.739s)
[513.477581] [076/156] (535s left) gem_exec_reloc (basic-write-gtt)
[513.566321] Subtest basic-write-gtt: SKIP (0.000s)
[513.657785] [077/156] (535s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[514.181627] Starting subtest: cursor-rapid-movement-max-size
[514.184397] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[514.377227] [078/156] (534s left) kms_color_pipeline (plane-lut1d)
[514.573844] Starting subtest: plane-lut1d
[514.792422] Starting dynamic subtest: pipe-A-plane-0
[515.001711] Dynamic subtest pipe-A-plane-0: SUCCESS (0.214s)
[515.016889] Starting dynamic subtest: pipe-A-plane-1
[515.186427] Dynamic subtest pipe-A-plane-1: SUCCESS (0.183s)
[515.220146] Starting dynamic subtest: pipe-A-plane-2
[515.369817] Dynamic subtest pipe-A-plane-2: SUCCESS (0.179s)
[515.787137] Starting dynamic subtest: pipe-B-plane-0
[515.944506] Dynamic subtest pipe-B-plane-0: SUCCESS (0.198s)
[515.986884] Starting dynamic subtest: pipe-B-plane-1
[516.145157] Dynamic subtest pipe-B-plane-1: SUCCESS (0.197s)
[516.187438] Starting dynamic subtest: pipe-B-plane-2
[516.343425] Dynamic subtest pipe-B-plane-2: SUCCESS (0.195s)
[516.748976] Starting dynamic subtest: pipe-C-plane-0
[516.903228] Dynamic subtest pipe-C-plane-0: SUCCESS (0.199s)
[516.925450] Starting dynamic subtest: pipe-C-plane-1
[517.085352] Dynamic subtest pipe-C-plane-1: SUCCESS (0.178s)
[517.111920] Starting dynamic subtest: pipe-C-plane-2
[517.271140] Dynamic subtest pipe-C-plane-2: SUCCESS (0.182s)
[517.644495] Starting dynamic subtest: pipe-D-plane-0
[517.821898] Dynamic subtest pipe-D-plane-0: SUCCESS (0.215s)
[517.863110] Starting dynamic subtest: pipe-D-plane-1
[518.038514] Dynamic subtest pipe-D-plane-1: SUCCESS (0.212s)
[518.081163] Starting dynamic subtest: pipe-D-plane-2
[518.236774] Dynamic subtest pipe-D-plane-2: SUCCESS (0.194s)
[518.296375] Subtest plane-lut1d: SUCCESS (3.723s)
[518.622250] [079/156] (530s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[518.830279] Starting subtest: yf-tiled-16bpp-rotate-180
[518.832107] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[518.969182] [080/156] (529s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[519.173235] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[520.439287] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.253s)
[520.604716] [081/156] (528s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[520.800839] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[520.802825] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[520.939259] [082/156] (528s left) gem_exec_reloc (basic-wc-read)
[521.028877] Subtest basic-wc-read: SKIP (0.000s)
[521.132594] [083/156] (527s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[521.326861] Starting subtest: short-busy-flip-before-cursor-toggle
[521.328674] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[521.476254] [084/156] (527s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[521.679674] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[521.694878] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.014s)
[521.804098] [085/156] (527s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[522.003411] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[522.005233] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[522.163170] [086/156] (527s left) kms_draw_crc (draw-method-render)
[522.357719] Starting subtest: draw-method-render
[522.359204] Starting dynamic subtest: xrgb8888-untiled
[522.710142] Dynamic subtest xrgb8888-untiled: SUCCESS (0.351s)
[522.730038] Starting dynamic subtest: rgb565-untiled
[523.026904] Dynamic subtest rgb565-untiled: SUCCESS (0.312s)
[523.080960] Starting dynamic subtest: xrgb2101010-untiled
[523.375399] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.344s)
[523.395289] Starting dynamic subtest: xbgr16161616f-untiled
[523.709953] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.332s)
[523.759980] Starting dynamic subtest: xrgb8888-xtiled
[523.910071] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.195s)
[523.962384] Starting dynamic subtest: rgb565-xtiled
[524.110152] Dynamic subtest rgb565-xtiled: SUCCESS (0.195s)
[524.162375] Starting dynamic subtest: xrgb2101010-xtiled
[524.292113] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.178s)
[524.312934] Starting dynamic subtest: xbgr16161616f-xtiled
[524.460122] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.166s)
[524.509516] Starting dynamic subtest: xrgb8888-4tiled
[524.660066] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.195s)
[524.712005] Starting dynamic subtest: rgb565-4tiled
[524.860175] Dynamic subtest rgb565-4tiled: SUCCESS (0.195s)
[524.907597] Starting dynamic subtest: xrgb2101010-4tiled
[525.042125] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.176s)
[525.062403] Starting dynamic subtest: xbgr16161616f-4tiled
[525.209792] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.166s)
[525.216041] Subtest draw-method-render: SUCCESS (2.854s)
[525.437630] [087/156] (524s left) kms_cursor_crc (cursor-sliding-256x85)
[525.890764] Starting subtest: cursor-sliding-256x85
[525.896140] Starting dynamic subtest: pipe-A-DP-3
[530.319565] Dynamic subtest pipe-A-DP-3: SUCCESS (4.424s)
[530.369253] Starting dynamic subtest: pipe-D-DP-3
[534.799532] Dynamic subtest pipe-D-DP-3: SUCCESS (4.444s)
[534.800594] Subtest cursor-sliding-256x85: SUCCESS (8.908s)
[535.165323] [088/156] (514s left) kms_cursor_crc (cursor-onscreen-256x256)
[535.584868] Starting subtest: cursor-onscreen-256x256
[535.590100] Starting dynamic subtest: pipe-A-DP-3
[538.329336] Dynamic subtest pipe-A-DP-3: SUCCESS (2.738s)
[538.386840] Starting dynamic subtest: pipe-D-DP-3
[541.112697] Dynamic subtest pipe-D-DP-3: SUCCESS (2.739s)
[541.113661] Subtest cursor-onscreen-256x256: SUCCESS (5.527s)
[541.501458] [089/156] (508s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[541.698395] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[541.700196] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[541.858271] [090/156] (507s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[542.060603] Starting subtest: fbcpsr-slowdraw
[542.062351] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[542.195150] [091/156] (507s left) kms_lease (cursor-implicit-plane)
[542.387108] Starting subtest: cursor-implicit-plane
[542.389774] Starting dynamic subtest: pipe-A-DP-3
[542.747910] Dynamic subtest pipe-A-DP-3: SUCCESS (0.359s)
[542.775700] Starting dynamic subtest: pipe-B-DP-3
[543.100767] Dynamic subtest pipe-B-DP-3: SUCCESS (0.350s)
[543.122937] Starting dynamic subtest: pipe-C-DP-3
[543.430855] Dynamic subtest pipe-C-DP-3: SUCCESS (0.328s)
[543.453654] Starting dynamic subtest: pipe-D-DP-3
[543.765418] Dynamic subtest pipe-D-DP-3: SUCCESS (0.331s)
[543.766477] Subtest cursor-implicit-plane: SUCCESS (1.378s)
[544.060758] [092/156] (505s left) syncobj_wait (invalid-reset-zero-handles)
[544.146918] Starting subtest: invalid-reset-zero-handles
[544.147902] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[544.326335] [093/156] (505s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[544.524618] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[544.526363] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[544.743569] [094/156] (505s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[544.946297] Starting subtest: yf-tiled-64bpp-rotate-90
[544.948122] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[545.118356] [095/156] (505s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[545.315586] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[545.317343] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[545.467975] [096/156] (504s left) gem_exec_fence (long-history)
[545.986203] Starting subtest: long-history
[552.336633] Subtest long-history: SUCCESS (6.350s)
[553.338478] [097/156] (496s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[553.537384] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[553.539195] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[553.703897] [098/156] (496s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[553.893988] Starting subtest: bad-rotation-90-yf-tiled-ccs
[553.896392] Starting dynamic subtest: pipe-A-DP-3
[553.905330] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[553.920573] Starting dynamic subtest: pipe-B-DP-3
[553.929344] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[553.944090] Starting dynamic subtest: pipe-C-DP-3
[553.953304] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[553.968186] Starting dynamic subtest: pipe-D-DP-3
[553.979754] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[553.981720] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.007s)
[554.417813] [099/156] (496s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[554.610826] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[558.441827] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (3.831s)
[558.773411] [100/156] (491s left) kms_chamelium_audio (dp-audio-edid)
[559.038521] Subtest dp-audio-edid: SKIP (0.000s)
[559.229879] [101/156] (491s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[559.423853] Starting subtest: addfb25-yf-tiled-legacy
[559.424827] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[559.614030] [102/156] (491s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[559.808817] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[560.557587] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SUCCESS (0.749s)
[560.775315] [103/156] (490s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[560.974682] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[560.976441] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[561.117191] [104/156] (489s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[561.311987] Starting subtest: fbc-2p-pri-indfb-multidraw
[561.313656] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[561.449700] [105/156] (489s left) kms_sequence (queue-busy)
[561.641294] Starting subtest: queue-busy
[561.643812] Starting dynamic subtest: pipe-A-DP-3
[564.323630] Dynamic subtest pipe-A-DP-3: SUCCESS (2.680s)
[564.345777] Starting dynamic subtest: pipe-B-DP-3
[566.833415] Dynamic subtest pipe-B-DP-3: SUCCESS (2.508s)
[566.850878] Starting dynamic subtest: pipe-C-DP-3
[569.325602] Dynamic subtest pipe-C-DP-3: SUCCESS (2.490s)
[569.344624] Starting dynamic subtest: pipe-D-DP-3
[571.817590] Dynamic subtest pipe-D-DP-3: SUCCESS (2.490s)
[571.818726] Subtest queue-busy: SUCCESS (10.176s)
[572.107835] [106/156] (478s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[572.298410] Starting subtest: plane-upscale-20x20-with-rotation
[572.299273] Starting dynamic subtest: pipe-A
[572.678979] Dynamic subtest pipe-A: SUCCESS (0.380s)
[572.710465] Starting dynamic subtest: pipe-B
[573.175962] Dynamic subtest pipe-B: SUCCESS (0.495s)
[573.210759] Starting dynamic subtest: pipe-C
[573.673987] Dynamic subtest pipe-C: SUCCESS (0.496s)
[573.707818] Starting dynamic subtest: pipe-D
[574.188433] Dynamic subtest pipe-D: SUCCESS (0.512s)
[574.189509] Subtest plane-upscale-20x20-with-rotation: SUCCESS (1.891s)
[574.537132] [107/156] (476s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[574.734430] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[574.736263] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[574.877647] [108/156] (476s left) gem_exec_reloc (basic-write-wc)
[574.969616] Subtest basic-write-wc: SKIP (0.000s)
[575.058095] [109/156] (476s left) syncobj_timeline (reset-signaled)
[575.146913] Starting subtest: reset-signaled
[575.147825] Subtest reset-signaled: SUCCESS (0.000s)
[575.319882] [110/156] (476s left) kms_psr (pr-cursor-plane-move)
[575.512806] Subtest pr-cursor-plane-move: SKIP (0.000s)
[575.586356] [111/156] (475s left) syncobj_wait (invalid-wait-illegal-handle)
[575.671970] Starting subtest: invalid-wait-illegal-handle
[575.672883] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[575.884447] [112/156] (475s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[576.086695] Starting subtest: extended-pageflip-modeset-hang-oldfb
[576.087604] Starting dynamic subtest: pipe-A
[583.912189] Dynamic subtest pipe-A: SUCCESS (7.824s)
[583.929843] Starting dynamic subtest: pipe-D
[591.755972] Dynamic subtest pipe-D: SUCCESS (7.841s)
[591.756887] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (15.671s)
[592.067452] [113/156] (459s left) kms_psr (psr2-primary-blt)
[592.263568] Subtest psr2-primary-blt: SKIP (0.000s)
[592.323200] [114/156] (459s left) gem_softpin (evict-snoop-interruptible)
[592.416800] Starting subtest: evict-snoop-interruptible
[592.418963] Subtest evict-snoop-interruptible: SKIP (0.000s)
[592.526872] [115/156] (459s left) kms_force_connector_basic (force-load-detect)
[592.643750] Starting subtest: force-load-detect
[592.645537] Subtest force-load-detect: SKIP (0.000s)
[592.877516] [116/156] (459s left) kms_chamelium_edid (hdmi-mode-timings)
[593.150467] Subtest hdmi-mode-timings: SKIP (0.000s)
[593.338602] [117/156] (458s left) syncobj_timeline (invalid-signal-zero-handles)
[593.424009] Starting subtest: invalid-signal-zero-handles
[593.424942] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[593.558480] [118/156] (458s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[593.758379] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[593.767722] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[593.923767] [119/156] (458s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[594.121606] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[594.123399] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[594.256305] [120/156] (457s left) gem_mmap_gtt (fault-concurrent-x)
[594.334671] Subtest fault-concurrent-X: SKIP (0.000s)
[594.418940] [121/156] (457s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[594.506967] Starting subtest: y-tiled-to-vebox-y-tiled
[594.508976] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[594.760243] [122/156] (457s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[594.960897] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[594.962611] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[595.158254] [123/156] (457s left) vgem_basic (debugfs)
[595.229828] Starting subtest: debugfs
[595.230821] Subtest debugfs: SUCCESS (0.000s)
[595.321831] [124/156] (457s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[595.515772] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[595.516709] Starting dynamic subtest: pipe-A-valid-mode
[598.044606] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.528s)
[598.045563] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (2.530s)
[598.332017] [125/156] (454s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[598.530759] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[598.532601] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[598.697256] [126/156] (453s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[598.896707] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[598.898435] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[599.026800] [127/156] (453s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[599.223959] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[599.225688] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[599.399785] [128/156] (453s left) kms_vblank (wait-forked-hang)
[599.589837] Starting subtest: wait-forked-hang
[599.590779] Starting dynamic subtest: pipe-A-DP-3
[626.317851] Dynamic subtest pipe-A-DP-3: SUCCESS (26.725s)
[626.335516] Starting dynamic subtest: pipe-D-DP-3
[651.326782] Dynamic subtest pipe-D-DP-3: SUCCESS (25.006s)
[651.327719] Subtest wait-forked-hang: SUCCESS (51.737s)
[651.577809] [129/156] (401s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[651.770707] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[651.771647] Starting dynamic subtest: pipe-A-valid-mode
[656.614435] Dynamic subtest pipe-A-valid-mode: SUCCESS (4.839s)
[656.616038] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (4.843s)
[656.909963] [130/156] (396s left) gem_ctx_exec (basic-invalid-context)
[656.995911] Starting subtest: basic-invalid-context
[657.002834] Subtest basic-invalid-context: SUCCESS (0.007s)
[657.149017] [131/156] (395s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[657.345591] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[657.347372] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[657.562353] [132/156] (395s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[657.762806] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[657.837513] [133/156] (395s left) gem_ppgtt (blt-vs-render-ctxn)
[657.930330] Starting subtest: blt-vs-render-ctxN
[690.755668] Subtest blt-vs-render-ctxN: SUCCESS (32.824s)
[691.011589] [134/156] (362s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[691.153816] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[691.251408] [135/156] (362s left) gem_softpin (invalid)
[691.341589] Starting subtest: invalid
[691.352966] Subtest invalid: SUCCESS (0.011s)
[691.574292] [136/156] (361s left) gem_exec_capture (capture)
[691.665383] Starting subtest: capture
[691.666378] Starting dynamic subtest: rcs0-smem
[692.619842] Dynamic subtest rcs0-smem: SUCCESS (0.953s)
[692.636742] Starting dynamic subtest: rcs0-lmem0
[693.548838] Dynamic subtest rcs0-lmem0: SUCCESS (0.928s)
[693.564682] Starting dynamic subtest: bcs0-smem
[694.961927] Dynamic subtest bcs0-smem: SUCCESS (1.412s)
[694.977967] Starting dynamic subtest: bcs0-lmem0
[695.991858] Dynamic subtest bcs0-lmem0: SUCCESS (1.029s)
[696.007850] Starting dynamic subtest: vcs0-smem
[696.891897] Dynamic subtest vcs0-smem: SUCCESS (0.898s)
[696.907796] Starting dynamic subtest: vcs0-lmem0
[698.036809] Dynamic subtest vcs0-lmem0: SUCCESS (1.144s)
[698.052538] Starting dynamic subtest: vcs1-smem
[698.937920] Dynamic subtest vcs1-smem: SUCCESS (0.900s)
[698.953805] Starting dynamic subtest: vcs1-lmem0
[700.020794] Dynamic subtest vcs1-lmem0: SUCCESS (1.082s)
[700.036498] Starting dynamic subtest: vecs0-smem
[700.957168] Dynamic subtest vecs0-smem: FAIL (0.916s)
[700.972676] Starting dynamic subtest: vecs0-lmem0
[701.954427] Dynamic subtest vecs0-lmem0: FAIL (0.994s)
[701.969493] Starting dynamic subtest: vecs1-smem
[703.361048] Dynamic subtest vecs1-smem: FAIL (1.403s)
[703.376033] Starting dynamic subtest: vecs1-lmem0
[704.372324] Dynamic subtest vecs1-lmem0: FAIL (1.011s)
[704.387445] Starting dynamic subtest: ccs0-smem
[705.762911] Dynamic subtest ccs0-smem: SUCCESS (1.412s)
[705.778556] Starting dynamic subtest: ccs0-lmem0
[706.678814] Dynamic subtest ccs0-lmem0: SUCCESS (0.914s)
[706.679900] Subtest capture: FAIL (15.013s)
[706.816688] [137/156] (346s left) gem_tiled_blits (basic)
[706.903307] Subtest basic: SKIP (0.000s)
[706.989280] [138/156] (346s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[707.192722] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[707.194534] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[707.355913] [139/156] (346s left) gem_mmap_offset (partial-remap)
[707.639523] Starting subtest: partial-remap
[707.640754] Starting dynamic subtest: lmem0
[707.646426] Dynamic subtest lmem0: SUCCESS (0.006s)
[707.664576] Starting dynamic subtest: smem0
[707.666790] Dynamic subtest smem0: SUCCESS (0.006s)
[707.667910] Subtest partial-remap: SUCCESS (0.015s)
[707.978448] [140/156] (345s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[708.275542] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[708.963130] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (0.688s)
[709.222917] [141/156] (344s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[709.421663] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[709.423415] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[709.543756] [142/156] (344s left) i915_power (sanity)
[709.624033] Starting subtest: sanity
[713.685811] Subtest sanity: SUCCESS (4.059s)
[713.842309] [143/156] (340s left) gem_exec_schedule (preempt-queue)
[713.952191] Subtest preempt-queue: SKIP (0.000s)
[714.330110] [144/156] (339s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[714.524698] Starting subtest: cursorB-vs-flipA-legacy
[714.526539] Subtest cursorB-vs-flipA-legacy: SKIP (0.002s)
[714.628736] [145/156] (339s left) i915_pm_rps (reset)
[714.723812] Starting subtest: reset
[719.525764] Subtest reset: SUCCESS (4.803s)
[719.659877] [146/156] (334s left) kms_psr (fbc-psr-cursor-plane-onoff)
[719.852793] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[719.920400] [147/156] (334s left) kms_setmode (clone-exclusive-crtc)
[720.009943] Starting subtest: clone-exclusive-crtc
[720.012837] Subtest clone-exclusive-crtc: SKIP (0.000s)
[720.177967] [148/156] (334s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[720.373632] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[720.375442] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[720.516835] [149/156] (333s left) kms_flip (flip-vs-rmfb)
[720.708647] Starting subtest: flip-vs-rmfb
[720.713444] Starting dynamic subtest: A-DP3
[728.612692] Dynamic subtest A-DP3: SUCCESS (7.898s)
[728.666663] Starting dynamic subtest: B-DP3
[736.415590] Dynamic subtest B-DP3: SUCCESS (7.796s)
[736.463555] Starting dynamic subtest: C-DP3
[744.222504] Dynamic subtest C-DP3: SUCCESS (7.800s)
[744.278332] Starting dynamic subtest: D-DP3
[752.029485] Dynamic subtest D-DP3: SUCCESS (7.801s)
[752.038008] Subtest flip-vs-rmfb: SUCCESS (31.328s)
[752.435162] [150/156] (302s left) gem_exec_whisper (basic-contexts-all)
[752.532835] Starting subtest: basic-contexts-all
[774.535877] Subtest basic-contexts-all: SUCCESS (22.001s)
[774.793581] [151/156] (279s left) dmabuf_sync_file (export-basic)
[774.860267] Starting subtest: export-basic
[774.861212] Subtest export-basic: SUCCESS (0.001s)
[774.955183] [152/156] (279s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[775.147429] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[775.149156] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[775.348678] [153/156] (279s left) gem_sync (basic-each)
[775.440444] Starting subtest: basic-each
[777.975973] Subtest basic-each: SUCCESS (2.535s)
[778.246312] [154/156] (276s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[778.439794] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[778.440716] Starting dynamic subtest: pipe-A-DP-3
[779.182273] Dynamic subtest pipe-A-DP-3: SUCCESS (0.738s)
[779.198350] Starting dynamic subtest: pipe-B-DP-3
[779.877873] Dynamic subtest pipe-B-DP-3: SUCCESS (0.694s)
[779.894015] Starting dynamic subtest: pipe-C-DP-3
[780.576302] Dynamic subtest pipe-C-DP-3: SUCCESS (0.696s)
[780.592596] Starting dynamic subtest: pipe-D-DP-3
[781.257900] Dynamic subtest pipe-D-DP-3: SUCCESS (0.679s)
[781.258971] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SUCCESS (2.816s)
[781.591560] [155/156] (273s left) syncobj_wait (multi-wait-for-submit-submitted)
[781.674879] Starting subtest: multi-wait-for-submit-submitted
[781.778002] Subtest multi-wait-for-submit-submitted: SUCCESS (0.103s)
[781.929065] [156/156] (273s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[782.127811] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[782.129648] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[782.267485] 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.