[57.545162] Initializing watchdogs
[57.545209]   /dev/watchdog0
[57.558983] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.563638] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.566602] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.569831] [001/133] (960s left) gem_exec_params (dr4-dirt)
[57.648627] Starting subtest: DR4-dirt
[57.650980] Subtest DR4-dirt: SUCCESS (0.000s)
[57.760756] [002/133] (960s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[57.861360] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[57.863777] Starting dynamic subtest: pipe-A
[58.100457] Dynamic subtest pipe-A: SUCCESS (0.242s)
[58.109072] Starting dynamic subtest: pipe-B
[59.390463] Dynamic subtest pipe-B: SUCCESS (1.302s)
[59.399320] Starting dynamic subtest: pipe-C
[60.703340] Dynamic subtest pipe-C: SUCCESS (1.323s)
[60.711774] Starting dynamic subtest: pipe-D
[62.027499] Dynamic subtest pipe-D: SUCCESS (1.332s)
[62.029932] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.200s)
[63.430184] [003/133] (954s left) kms_psr (psr2-cursor-blt)
[63.514297] Starting subtest: psr2-cursor-blt
[63.516545] Starting dynamic subtest: eDP-1
[65.192487] Dynamic subtest eDP-1: SUCCESS (1.684s)
[65.194703] Subtest psr2-cursor-blt: SUCCESS (1.684s)
[66.013117] [004/133] (952s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[66.111258] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[66.137492] Starting dynamic subtest: pipe-A-plane-0
[66.558275] Dynamic subtest pipe-A-plane-0: SUCCESS (0.422s)
[66.569345] Starting dynamic subtest: pipe-A-plane-3
[66.824229] Dynamic subtest pipe-A-plane-3: SUCCESS (0.266s)
[67.883929] Starting dynamic subtest: pipe-B-plane-0
[68.353655] Dynamic subtest pipe-B-plane-0: SUCCESS (0.478s)
[68.363782] Starting dynamic subtest: pipe-B-plane-3
[68.630683] Dynamic subtest pipe-B-plane-3: SUCCESS (0.277s)
[68.954078] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.852s)
[69.776501] [005/133] (948s left) kms_atomic (plane-primary-legacy)
[69.864465] Starting subtest: plane-primary-legacy
[69.866859] Starting dynamic subtest: pipe-A-eDP-1
[70.346459] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.481s)
[70.348886] Subtest plane-primary-legacy: SUCCESS (0.482s)
[71.210871] [006/133] (947s left) syncobj_wait (signal)
[71.272026] Starting subtest: signal
[71.274428] Subtest signal: SUCCESS (0.000s)
[71.379791] [007/133] (946s left) gem_create (busy-create)
[71.441721] Starting subtest: busy-create
[71.443694] Starting dynamic subtest: smem0
[104.927885] Dynamic subtest smem0: SUCCESS (33.487s)
[104.930908] Subtest busy-create: SUCCESS (33.488s)
[105.110249] [008/133] (913s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[105.214209] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[105.216322] Starting dynamic subtest: pipe-A
[105.563215] Dynamic subtest pipe-A: SUCCESS (0.351s)
[105.577400] Starting dynamic subtest: pipe-B
[106.998356] Dynamic subtest pipe-B: SUCCESS (1.434s)
[107.006616] Starting dynamic subtest: pipe-C
[108.452514] Dynamic subtest pipe-C: SUCCESS (1.453s)
[108.462733] Starting dynamic subtest: pipe-D
[109.851677] Dynamic subtest pipe-D: SUCCESS (1.399s)
[109.854096] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.640s)
[111.013409] [009/133] (907s left) kms_flip (bo-too-big)
[111.112289] Starting subtest: bo-too-big
[111.114225] Starting dynamic subtest: A-eDP1
[112.465374] Dynamic subtest A-eDP1: SUCCESS (1.355s)
[112.474546] Starting dynamic subtest: B-eDP1
[113.791555] Dynamic subtest B-eDP1: SUCCESS (1.325s)
[113.801079] Starting dynamic subtest: C-eDP1
[115.133332] Dynamic subtest C-eDP1: SUCCESS (1.340s)
[115.142478] Starting dynamic subtest: D-eDP1
[116.459081] Dynamic subtest D-eDP1: SUCCESS (1.325s)
[116.464275] Subtest bo-too-big: SUCCESS (5.354s)
[117.690420] [010/133] (900s left) kms_invalid_mode (overflow-vrefresh)
[117.783026] Starting subtest: overflow-vrefresh
[117.785180] Starting dynamic subtest: pipe-A-eDP-1
[118.065182] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[118.073797] Starting dynamic subtest: pipe-B-eDP-1
[118.075760] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[118.082642] Starting dynamic subtest: pipe-C-eDP-1
[118.084552] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[118.091714] Starting dynamic subtest: pipe-D-eDP-1
[118.093872] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[118.096270] Subtest overflow-vrefresh: SUCCESS (0.289s)
[118.897206] [011/133] (899s left) sysfs_timeslice_duration (timeout)
[118.958672] Starting subtest: timeout
[118.960952] Starting dynamic subtest: vecs0
[128.912695] Dynamic subtest vecs0: SUCCESS (9.949s)
[128.922805] Starting dynamic subtest: ccs0
[130.218956] Dynamic subtest ccs0: SUCCESS (1.305s)
[130.229463] Starting dynamic subtest: vcs0
[139.925385] Dynamic subtest vcs0: SUCCESS (9.705s)
[139.935729] Starting dynamic subtest: bcs0
[140.576037] Dynamic subtest bcs0: SUCCESS (0.650s)
[140.585058] Starting dynamic subtest: rcs0
[141.848751] Dynamic subtest rcs0: SUCCESS (1.273s)
[141.856582] Starting dynamic subtest: vcs1
[151.890020] Dynamic subtest vcs1: SUCCESS (10.036s)
[151.892368] Subtest timeout: SUCCESS (32.925s)
[151.961996] [012/133] (866s left) kms_psr (psr-sprite-blt)
[152.058737] Starting subtest: psr-sprite-blt
[152.061018] Starting dynamic subtest: eDP-1
[153.818983] Dynamic subtest eDP-1: SUCCESS (1.760s)
[153.821371] Subtest psr-sprite-blt: SUCCESS (1.761s)
[154.659158] [013/133] (863s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[154.730379] Starting subtest: stress-mm-invalidate-close-overlap
[156.879986] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[156.993422] [014/133] (861s left) kms_prop_blob (blob-prop-lifetime)
[157.043054] Starting subtest: blob-prop-lifetime
[157.045361] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[157.140311] [015/133] (861s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[157.244958] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[164.418360] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.175s)
[165.575316] [016/133] (853s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[165.686242] Starting subtest: extended-modeset-hang-oldfb-with-reset
[165.688609] Starting dynamic subtest: pipe-A
[177.659381] Dynamic subtest pipe-A: SUCCESS (11.970s)
[177.676064] Starting dynamic subtest: pipe-D
[189.924891] Dynamic subtest pipe-D: SUCCESS (12.263s)
[189.927096] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.237s)
[190.722611] [017/133] (827s left) gem_ctx_shared (single-timeline)
[190.782515] Starting subtest: single-timeline
[190.802558] Subtest single-timeline: SUCCESS (0.013s)
[190.995759] [018/133] (827s left) perf_pmu (busy-check-all)
[191.047095] Starting subtest: busy-check-all
[191.049240] Starting dynamic subtest: rcs0
[191.598407] Dynamic subtest rcs0: SUCCESS (0.553s)
[191.611622] Starting dynamic subtest: bcs0
[192.148612] Dynamic subtest bcs0: SUCCESS (0.548s)
[192.160796] Starting dynamic subtest: vcs0
[193.944175] Dynamic subtest vcs0: SUCCESS (1.792s)
[193.954295] Starting dynamic subtest: vcs1
[195.927448] Dynamic subtest vcs1: SUCCESS (1.981s)
[195.937424] Starting dynamic subtest: vecs0
[197.911336] Dynamic subtest vecs0: SUCCESS (1.982s)
[197.922530] Starting dynamic subtest: ccs0
[198.467205] Dynamic subtest ccs0: SUCCESS (0.556s)
[198.469641] Subtest busy-check-all: SUCCESS (7.422s)
[198.588554] [019/133] (820s left) gem_userptr_blits (probe)
[198.667621] Starting subtest: probe
[198.745895] Subtest probe: SUCCESS (0.078s)
[198.864332] [020/133] (819s left) i915_drm_fdinfo (most-busy-idle-check-all)
[198.910172] Starting subtest: most-busy-idle-check-all
[198.912420] Starting dynamic subtest: rcs0
[198.916676] Dynamic subtest rcs0: SKIP (0.003s)
[198.925122] Starting dynamic subtest: bcs0
[198.929129] Dynamic subtest bcs0: SKIP (0.000s)
[198.937460] Starting dynamic subtest: vcs0
[198.941669] Dynamic subtest vcs0: SKIP (0.000s)
[198.949458] Starting dynamic subtest: vcs1
[198.953232] Dynamic subtest vcs1: SKIP (0.000s)
[198.960914] Starting dynamic subtest: vecs0
[198.964837] Dynamic subtest vecs0: SKIP (0.000s)
[198.974124] Starting dynamic subtest: ccs0
[198.978266] Dynamic subtest ccs0: SKIP (0.000s)
[198.982439] Subtest most-busy-idle-check-all: SKIP (0.007s)
[199.017302] [021/133] (819s left) kms_big_fb (linear-16bpp-rotate-0)
[199.124383] Starting subtest: linear-16bpp-rotate-0
[200.546354] Subtest linear-16bpp-rotate-0: SUCCESS (1.422s)
[200.661529] [022/133] (818s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[200.759568] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[200.763082] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[200.853788] [023/133] (818s left) gem_readwrite (new-obj)
[200.918561] Subtest new-obj: SKIP (0.000s)
[200.969263] [024/133] (817s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[201.078621] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[201.082353] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[201.174072] [025/133] (817s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[201.258779] Starting subtest: flip-vs-dpms-off-vs-modeset
[201.260642] Starting dynamic subtest: A-eDP1
[204.861788] Dynamic subtest A-eDP1: SUCCESS (3.599s)
[204.871984] Starting dynamic subtest: B-eDP1
[208.046394] Dynamic subtest B-eDP1: SUCCESS (3.184s)
[208.055364] Starting dynamic subtest: C-eDP1
[211.172194] Dynamic subtest C-eDP1: SUCCESS (3.124s)
[211.183017] Starting dynamic subtest: D-eDP1
[214.284310] Dynamic subtest D-eDP1: SUCCESS (3.111s)
[214.286716] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.025s)
[215.082516] [026/133] (803s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[215.182843] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[215.184969] Starting dynamic subtest: pipe-A-eDP-1
[215.727786] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.516s)
[215.738837] Starting dynamic subtest: pipe-B-eDP-1
[217.178573] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.451s)
[217.188855] Starting dynamic subtest: pipe-C-eDP-1
[218.633634] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.452s)
[218.644133] Starting dynamic subtest: pipe-D-eDP-1
[220.047877] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.415s)
[220.050121] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.839s)
[221.209633] [027/133] (797s left) syncobj_wait (wait-any-interrupted)
[221.266226] Starting subtest: wait-any-interrupted
[221.366900] Subtest wait-any-interrupted: SUCCESS (0.101s)
[221.440896] [028/133] (797s left) kms_selftest (drm_plane_helper)
[221.480785] Starting subtest: drm_plane_helper
[221.528803] Starting dynamic subtest: drm_test_check_plane_state
[221.652330] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.123s)
[221.661062] Starting dynamic subtest: drm_test_check_invalid_plane_state
[221.756051] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.103s)
[221.758077] Subtest drm_plane_helper: SUCCESS (0.274s)
[221.925612] [029/133] (797s left) kms_lease (lease-uevent)
[222.020599] Starting subtest: lease-uevent
[223.031961] Subtest lease-uevent: SUCCESS (1.011s)
[223.112055] [030/133] (796s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[223.261947] Starting subtest: dp-mst-type-1-suspend-resume
[223.266587] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[223.386613] [031/133] (795s left) syncobj_wait (wait-all-delayed-signal)
[223.437637] Starting subtest: wait-all-delayed-signal
[223.541892] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[223.618822] [032/133] (795s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[223.670313] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[223.728225] [033/133] (795s left) syncobj_wait (wait-all-for-submit-complex)
[223.773834] Starting subtest: wait-all-for-submit-complex
[223.989066] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[224.063018] [034/133] (795s left) core_setmaster (master-drop-set-root)
[224.138460] Starting subtest: master-drop-set-root
[224.158044] Subtest master-drop-set-root: SUCCESS (0.022s)
[224.205156] [035/133] (795s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[224.307256] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[224.311649] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[225.439090] [036/133] (793s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[225.501481] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[225.505818] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[225.681746] [037/133] (793s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[225.781825] Starting subtest: 4-tiled-32bpp-rotate-180
[227.260729] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.479s)
[227.390704] [038/133] (792s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[227.486708] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[234.176826] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (6.692s)
[234.300212] [039/133] (785s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[234.400376] Starting subtest: bad-rotation-90-yf-tiled-ccs
[234.402726] Starting dynamic subtest: pipe-A-eDP-1
[234.426427] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[234.439289] Starting dynamic subtest: pipe-B-eDP-1
[234.461315] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[234.469499] Starting dynamic subtest: pipe-C-eDP-1
[234.491235] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[234.499381] Starting dynamic subtest: pipe-D-eDP-1
[234.522336] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[234.526738] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[236.643885] [040/133] (782s left) gem_mmap_wc (coherency)
[236.704118] Subtest coherency: SKIP (0.000s)
[236.770269] [041/133] (782s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[236.819812] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[236.822081] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[236.912410] [042/133] (782s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[237.014755] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[237.019091] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[237.118812] [043/133] (782s left) kms_plane_alpha_blend (constant-alpha-mid)
[237.204907] Starting subtest: constant-alpha-mid
[237.487804] Starting dynamic subtest: pipe-A-eDP-1
[238.399324] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.908s)
[238.408552] Starting dynamic subtest: pipe-D-eDP-1
[239.993209] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.595s)
[239.995610] Subtest constant-alpha-mid: SUCCESS (2.790s)
[240.803791] [044/133] (778s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[240.903647] Starting subtest: psr2-overlay-plane-update-continuous-sf
[240.906073] Starting dynamic subtest: pipe-A-eDP-1
[242.018863] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.121s)
[242.028326] Starting dynamic subtest: pipe-B-eDP-1
[243.769480] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.749s)
[243.771871] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (2.873s)
[244.573074] [045/133] (775s left) gem_exec_whisper (basic-contexts-priority)
[244.634717] Starting subtest: basic-contexts-priority
[269.455894] Subtest basic-contexts-priority: SUCCESS (24.815s)
[269.643678] [046/133] (750s left) kms_feature_discovery (chamelium)
[269.741697] Starting subtest: chamelium
[269.746181] Subtest chamelium: SKIP (0.000s)
[269.826794] [047/133] (750s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[269.966405] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[270.098588] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.135s)
[270.216674] [048/133] (749s left) gem_exec_balancer (persistence)
[270.484130] Starting subtest: persistence
[271.415293] Subtest persistence: SUCCESS (0.931s)
[271.526765] [049/133] (748s left) gem_render_copy (linear)
[271.594459] Starting subtest: linear
[271.596883] Starting dynamic subtest: smem
[271.661307] Dynamic subtest smem: SUCCESS (0.066s)
[271.663624] Subtest linear: SUCCESS (0.068s)
[271.870659] [050/133] (748s left) i915_pm_rps (thresholds)
[271.939661] Starting subtest: thresholds
[271.944262] Subtest thresholds: SKIP (0.001s)
[272.075558] [051/133] (747s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[272.172521] Starting subtest: yf-tiled-32bpp-rotate-0
[272.176703] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[272.261196] [052/133] (747s left) api_intel_bb (object-reloc-purge-cache)
[272.309150] Starting subtest: object-reloc-purge-cache
[272.313571] Subtest object-reloc-purge-cache: SKIP (0.002s)
[272.438625] [053/133] (747s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[272.536278] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[272.540388] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[272.645433] [054/133] (747s left) kms_chamelium_hpd (hdmi-hpd-storm)
[273.044993] Subtest hdmi-hpd-storm: SKIP (0.000s)
[273.912230] [055/133] (746s left) gem_caching (writes)
[273.962171] Subtest writes: SKIP (0.000s)
[274.028321] [056/133] (746s left) kms_cursor_legacy (forked-bo)
[274.140384] Starting subtest: forked-bo
[274.142500] Starting dynamic subtest: pipe-A
[279.605264] Dynamic subtest pipe-A: SUCCESS (5.462s)
[279.617068] Starting dynamic subtest: pipe-B
[285.090368] Dynamic subtest pipe-B: SUCCESS (5.483s)
[285.103287] Starting dynamic subtest: pipe-C
[290.572919] Dynamic subtest pipe-C: SUCCESS (5.480s)
[290.584332] Starting dynamic subtest: pipe-D
[296.057922] Dynamic subtest pipe-D: SUCCESS (5.483s)
[296.069341] Starting dynamic subtest: all-pipes
[301.592172] Dynamic subtest all-pipes: SUCCESS (5.532s)
[301.594460] Subtest forked-bo: SUCCESS (27.451s)
[301.733676] [057/133] (718s left) kms_async_flips (crc)
[301.824289] Starting subtest: crc
[302.206893] Starting dynamic subtest: pipe-A-eDP-1
[305.121754] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.914s)
[305.496735] Starting dynamic subtest: pipe-B-eDP-1
[308.454886] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.964s)
[308.826062] Starting dynamic subtest: pipe-C-eDP-1
[311.753253] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.935s)
[312.115602] Starting dynamic subtest: pipe-D-eDP-1
[314.972548] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.866s)
[314.974921] Subtest crc: SUCCESS (13.150s)
[316.154603] [058/133] (704s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[316.261538] Starting subtest: yf-tiled-8bpp-rotate-90
[316.265784] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[316.359115] [059/133] (704s left) gem_ctx_bad_destroy (invalid-pad)
[316.414982] Starting subtest: invalid-pad
[316.417470] Subtest invalid-pad: SUCCESS (0.000s)
[316.500430] [060/133] (703s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[316.600070] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[316.602349] Starting dynamic subtest: pipe-A-eDP-1
[316.624948] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[316.635651] Starting dynamic subtest: pipe-B-eDP-1
[316.657799] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[316.667992] Starting dynamic subtest: pipe-C-eDP-1
[316.689106] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[316.697594] Starting dynamic subtest: pipe-D-eDP-1
[316.718980] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[316.723282] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[318.919234] [061/133] (701s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[319.028237] Starting subtest: y-tiled-16bpp-rotate-180
[319.032774] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[319.145404] [062/133] (701s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-blt)
[319.237521] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-blt
[319.241618] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[319.318797] [063/133] (701s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[319.422664] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[319.427283] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[319.528790] [064/133] (701s left) gem_exec_parallel (engines)
[319.598673] Starting subtest: engines
[319.601061] Starting dynamic subtest: basic
[322.904652] Dynamic subtest basic: SUCCESS (3.304s)
[322.917186] Starting dynamic subtest: contexts
[327.302430] Dynamic subtest contexts: SUCCESS (4.395s)
[327.313210] Starting dynamic subtest: fds
[331.191987] Dynamic subtest fds: SUCCESS (3.886s)
[331.203053] Starting dynamic subtest: userptr
[332.887034] Dynamic subtest userptr: SUCCESS (1.693s)
[332.889555] Subtest engines: SUCCESS (13.289s)
[333.075924] [065/133] (687s left) kms_cursor_crc (cursor-onscreen-256x85)
[333.340889] Starting subtest: cursor-onscreen-256x85
[333.390824] Subtest cursor-onscreen-256x85: SKIP (0.044s)
[333.555999] [066/133] (687s left) gem_exec_flush (basic-uc-prw-default)
[333.635578] Starting subtest: basic-uc-prw-default
[339.098307] Subtest basic-uc-prw-default: SUCCESS (5.463s)
[339.283831] [067/133] (681s left) kms_pm_rpm (modeset-lpsp)
[341.033057] Starting subtest: modeset-lpsp
[342.499016] Subtest modeset-lpsp: SUCCESS (1.465s)
[342.621297] [068/133] (678s left) i915_hangman (gt-error-state-capture)
[342.683802] Starting subtest: gt-error-state-capture
[342.686126] Starting dynamic subtest: rcs0
[353.845447] Dynamic subtest rcs0: SUCCESS (11.161s)
[353.858384] Starting dynamic subtest: bcs0
[365.878119] Dynamic subtest bcs0: SUCCESS (12.029s)
[365.890084] Starting dynamic subtest: vcs0
[377.846994] Dynamic subtest vcs0: SUCCESS (11.965s)
[377.865252] Starting dynamic subtest: vcs1
[389.814296] Dynamic subtest vcs1: SUCCESS (11.964s)
[389.826993] Starting dynamic subtest: vecs0
[399.861775] Dynamic subtest vecs0: SUCCESS (10.044s)
[399.873087] Starting dynamic subtest: ccs0
[411.894213] Dynamic subtest ccs0: SUCCESS (12.029s)
[411.897082] Subtest gt-error-state-capture: SUCCESS (69.209s)
[412.011106] [069/133] (608s left) kms_psr (psr2-primary-page-flip)
[412.105914] Starting subtest: psr2-primary-page-flip
[412.108277] Starting dynamic subtest: eDP-1
[413.832427] Dynamic subtest eDP-1: SUCCESS (1.726s)
[413.834332] Subtest psr2-primary-page-flip: SUCCESS (1.727s)
[414.713160] [070/133] (606s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[414.814443] Starting subtest: x-tiled-64bpp-rotate-0
[416.426166] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.612s)
[416.629166] [071/133] (604s left) i915_pm_rps (min-max-config-loaded)
[416.695100] Starting subtest: min-max-config-loaded
[416.699664] Subtest min-max-config-loaded: SKIP (0.000s)
[416.799991] [072/133] (604s left) perf_pmu (busy-no-semaphores)
[416.845465] Starting subtest: busy-no-semaphores
[416.847846] Starting dynamic subtest: rcs0
[417.410312] Dynamic subtest rcs0: SUCCESS (0.563s)
[417.421879] Starting dynamic subtest: bcs0
[417.961432] Dynamic subtest bcs0: SUCCESS (0.549s)
[417.973300] Starting dynamic subtest: vcs0
[418.512317] Dynamic subtest vcs0: SUCCESS (0.548s)
[418.523983] Starting dynamic subtest: vcs1
[420.441600] Dynamic subtest vcs1: SUCCESS (1.926s)
[420.453498] Starting dynamic subtest: vecs0
[422.428369] Dynamic subtest vecs0: SUCCESS (1.984s)
[422.438311] Starting dynamic subtest: ccs0
[424.411442] Dynamic subtest ccs0: SUCCESS (1.980s)
[424.414248] Subtest busy-no-semaphores: SUCCESS (7.566s)
[424.524300] [073/133] (596s left) kms_flip (blocking-wf_vblank)
[424.629642] Starting subtest: blocking-wf_vblank
[424.632161] Starting dynamic subtest: A-eDP1
[433.554650] Dynamic subtest A-eDP1: SUCCESS (8.924s)
[433.563595] Starting dynamic subtest: B-eDP1
[442.490510] Dynamic subtest B-eDP1: SUCCESS (8.934s)
[442.499807] Starting dynamic subtest: C-eDP1
[451.419587] Dynamic subtest C-eDP1: SUCCESS (8.927s)
[451.428604] Starting dynamic subtest: D-eDP1
[460.355354] Dynamic subtest D-eDP1: SUCCESS (8.933s)
[460.360373] Subtest blocking-wf_vblank: SUCCESS (35.729s)
[461.600106] [074/133] (559s left) gem_mmap_wc (read-write)
[461.661356] Subtest read-write: SKIP (0.000s)
[461.720619] [075/133] (559s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[461.832488] Starting subtest: basic-flip-after-cursor-varying-size
[462.767643] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.936s)
[463.607648] [076/133] (557s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[463.661635] Starting subtest: etime-multi-wait-all-for-submit-submitted
[463.763069] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.102s)
[463.865082] [077/133] (557s left) kms_properties (plane-properties-legacy)
[463.954029] Starting subtest: plane-properties-legacy
[463.956346] Starting dynamic subtest: pipe-A-eDP-1
[464.507326] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.554s)
[464.516515] Starting dynamic subtest: pipe-B-eDP-1
[465.773797] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.265s)
[465.783531] Starting dynamic subtest: pipe-C-eDP-1
[467.011428] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.236s)
[467.020388] Starting dynamic subtest: pipe-D-eDP-1
[468.250124] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.237s)
[468.252492] Subtest plane-properties-legacy: SUCCESS (4.298s)
[469.042000] [078/133] (552s left) gem_exec_fence (syncobj-invalid-flags)
[469.559977] Starting subtest: syncobj-invalid-flags
[469.562791] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[471.007488] [FACT gem_exec_fence (syncobj-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[471.011276] [079/133] (550s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[471.117569] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[471.121759] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[471.188967] [080/133] (550s left) gem_mmap_gtt (basic-small-bo-tiledy)
[471.251181] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[471.320255] [081/133] (550s left) syncobj_basic (bad-destroy)
[471.368118] Starting subtest: bad-destroy
[471.370291] Subtest bad-destroy: SUCCESS (0.000s)
[471.457718] [082/133] (549s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[471.544076] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[478.447231] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.905s)
[478.561965] [083/133] (542s left) gem_exec_balancer (hang)
[478.815068] Starting subtest: hang
[480.921885] Subtest hang: SUCCESS (2.110s)
[481.073708] [084/133] (540s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[481.172130] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[481.174276] Starting dynamic subtest: pipe-A-eDP-1
[482.054078] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.874s)
[482.064035] Starting dynamic subtest: pipe-B-eDP-1
[483.661053] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.603s)
[483.671601] Starting dynamic subtest: pipe-C-eDP-1
[485.147929] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.487s)
[485.159696] Starting dynamic subtest: pipe-D-eDP-1
[486.683829] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.533s)
[486.685646] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.502s)
[487.861203] [085/133] (533s left) prime_vgem (basic-blt)
[487.938644] Starting subtest: basic-blt
[487.964606] Subtest basic-blt: SUCCESS (0.026s)
[488.101514] [086/133] (533s left) gem_exec_schedule (implicit-write-read)
[488.175372] Starting subtest: implicit-write-read
[488.177668] Starting dynamic subtest: rcs0
[488.269580] Dynamic subtest rcs0: SUCCESS (0.106s)
[488.282427] Starting dynamic subtest: vcs0
[488.361192] Dynamic subtest vcs0: SUCCESS (0.090s)
[488.378103] Starting dynamic subtest: vcs1
[488.449356] Dynamic subtest vcs1: SUCCESS (0.088s)
[488.461049] Starting dynamic subtest: vecs0
[488.528893] Dynamic subtest vecs0: SUCCESS (0.079s)
[488.539425] Starting dynamic subtest: ccs0
[488.621023] Dynamic subtest ccs0: SUCCESS (0.091s)
[488.623778] Subtest implicit-write-read: SUCCESS (0.458s)
[490.035052] [087/133] (531s left) gem_userptr_blits (coherency-unsync)
[490.108778] Subtest coherency-unsync: SKIP (0.000s)
[490.246157] [088/133] (531s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[490.351465] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[490.355670] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[490.420465] [089/133] (531s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[490.535960] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[490.546979] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[490.626172] [090/133] (531s left) gem_softpin (evict-prime)
[490.695179] Starting subtest: evict-prime
[490.697410] Starting dynamic subtest: rcs0
[491.838789] Dynamic subtest rcs0: SUCCESS (1.143s)
[491.848862] Starting dynamic subtest: bcs0
[492.972077] Dynamic subtest bcs0: SUCCESS (1.131s)
[492.982103] Starting dynamic subtest: vcs0
[494.879908] Dynamic subtest vcs0: SUCCESS (1.904s)
[494.891961] Starting dynamic subtest: vcs1
[496.856304] Dynamic subtest vcs1: SUCCESS (1.974s)
[496.868116] Starting dynamic subtest: vecs0
[498.900581] Dynamic subtest vecs0: SUCCESS (2.042s)
[498.912585] Starting dynamic subtest: ccs0
[500.019830] Dynamic subtest ccs0: SUCCESS (1.120s)
[500.030455] Starting dynamic subtest: all
[501.822444] Dynamic subtest all: SUCCESS (1.798s)
[501.825263] Subtest evict-prime: SUCCESS (11.127s)
[501.942454] [091/133] (519s left) gem_ctx_param (invalid-get-engines)
[501.994822] Starting subtest: invalid-get-engines
[501.997049] Subtest invalid-get-engines: SUCCESS (0.001s)
[502.132697] [092/133] (519s left) gem_exec_schedule (timeslicing)
[502.196745] Starting subtest: timeslicing
[502.199072] Starting dynamic subtest: rcs0
[502.212188] Dynamic subtest rcs0: SUCCESS (0.025s)
[502.223526] Starting dynamic subtest: bcs0
[502.235496] Dynamic subtest bcs0: SUCCESS (0.023s)
[502.244701] Starting dynamic subtest: vcs0
[502.259834] Dynamic subtest vcs0: SUCCESS (0.023s)
[502.268775] Starting dynamic subtest: vcs1
[502.282699] Dynamic subtest vcs1: SUCCESS (0.021s)
[502.291698] Starting dynamic subtest: vecs0
[502.305969] Dynamic subtest vecs0: SUCCESS (0.022s)
[502.315035] Starting dynamic subtest: ccs0
[502.329637] Dynamic subtest ccs0: SUCCESS (0.023s)
[502.332058] Subtest timeslicing: SUCCESS (0.143s)
[503.938597] [093/133] (517s left) kms_color_pipeline (plane-ctm3x4)
[504.026569] Starting subtest: plane-ctm3x4
[504.198053] Starting dynamic subtest: pipe-A-plane-0
[504.524843] Dynamic subtest pipe-A-plane-0: FAIL (0.165s)
[504.536127] Starting dynamic subtest: pipe-A-plane-1
[504.749297] Dynamic subtest pipe-A-plane-1: FAIL (0.143s)
[504.760286] Starting dynamic subtest: pipe-A-plane-2
[504.985411] Dynamic subtest pipe-A-plane-2: FAIL (0.154s)
[505.868205] Starting dynamic subtest: pipe-B-plane-0
[506.265906] Dynamic subtest pipe-B-plane-0: FAIL (0.142s)
[506.277176] Starting dynamic subtest: pipe-B-plane-1
[506.574731] Dynamic subtest pipe-B-plane-1: FAIL (0.154s)
[506.584298] Starting dynamic subtest: pipe-B-plane-2
[506.879334] Dynamic subtest pipe-B-plane-2: FAIL (0.155s)
[507.513299] Starting dynamic subtest: pipe-C-plane-0
[507.975207] Dynamic subtest pipe-C-plane-0: FAIL (0.107s)
[507.986535] Starting dynamic subtest: pipe-C-plane-1
[508.373728] Dynamic subtest pipe-C-plane-1: FAIL (0.601s)
[508.383201] Starting dynamic subtest: pipe-C-plane-2
[508.734558] Dynamic subtest pipe-C-plane-2: FAIL (0.146s)
[509.551717] Starting dynamic subtest: pipe-D-plane-0
[510.112489] Dynamic subtest pipe-D-plane-0: FAIL (0.140s)
[510.122688] Starting dynamic subtest: pipe-D-plane-1
[510.552810] Dynamic subtest pipe-D-plane-1: FAIL (0.589s)
[510.563766] Starting dynamic subtest: pipe-D-plane-2
[510.976261] Dynamic subtest pipe-D-plane-2: FAIL (0.534s)
[511.115066] Subtest plane-ctm3x4: FAIL (7.089s)
[511.997458] [094/133] (509s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[512.127042] Starting subtest: cursorA-vs-flipA-legacy
[523.274550] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.149s)
[524.128493] [095/133] (497s left) kms_cursor_crc (cursor-random-32x32)
[524.271990] Starting subtest: cursor-random-32x32
[524.330183] Subtest cursor-random-32x32: SKIP (0.052s)
[524.619437] [096/133] (497s left) sw_sync (alloc_merge_fence)
[524.657985] Starting subtest: alloc_merge_fence
[524.660033] Subtest alloc_merge_fence: SUCCESS (0.000s)
[524.719944] [097/133] (497s left) syncobj_wait (multi-wait-all-signaled)
[524.781585] Starting subtest: multi-wait-all-signaled
[524.783576] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[524.875364] [098/133] (497s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[524.925039] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[524.927343] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[525.022738] [099/133] (497s left) gem_render_tiled_blits (basic)
[525.083082] Subtest basic: SKIP (0.000s)
[525.158138] [100/133] (497s left) kms_pm_backlight (fade-with-suspend)
[525.255626] Starting subtest: fade-with-suspend
[541.321142] Starting dynamic subtest: eDP-1
[543.804546] Dynamic subtest eDP-1: SUCCESS (2.574s)
[543.806707] Subtest fade-with-suspend: SUCCESS (4.621s)
[544.597097] [101/133] (477s left) kms_cursor_crc (cursor-suspend)
[544.737425] Starting subtest: cursor-suspend
[544.739301] Starting dynamic subtest: pipe-A-eDP-1
[577.762194] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.127s)
[577.772439] Starting dynamic subtest: pipe-D-eDP-1
[611.756753] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.769s)
[611.759033] Subtest cursor-suspend: SUCCESS (6.899s)
[612.556057] [102/133] (409s left) gem_lmem_swapping (parallel-random-engines)
[612.603084] Subtest parallel-random-engines: SKIP (0.000s)
[612.646890] [103/133] (409s left) syncobj_timeline (invalid-query-bad-pad)
[612.699656] Starting subtest: invalid-query-bad-pad
[612.701874] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[612.809537] [104/133] (409s left) api_intel_bb (blit-noreloc-purge-cache)
[612.866619] Starting subtest: blit-noreloc-purge-cache
[612.874265] Subtest blit-noreloc-purge-cache: SUCCESS (0.010s)
[612.992482] [105/133] (409s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[613.092776] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[613.096850] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[613.186964] [106/133] (409s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[613.239988] Starting subtest: mixed-tiled-to-y-tiled-ccs
[613.243991] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[613.425370] [107/133] (409s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[613.517981] Starting subtest: psr-1p-primscrn-cur-indfb-move
[620.753968] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.404s)
[620.877119] [108/133] (401s left) kms_psr (fbc-psr-no-drrs)
[620.976308] Starting subtest: fbc-psr-no-drrs
[620.978545] Starting dynamic subtest: eDP-1
[620.982670] Dynamic subtest eDP-1: SKIP (0.000s)
[620.986780] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[621.051529] [109/133] (401s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[621.148266] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[621.152640] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[621.241988] [110/133] (401s left) kms_rotation_crc (bad-pixel-format)
[621.340763] Starting subtest: bad-pixel-format
[621.345145] Subtest bad-pixel-format: SKIP (0.001s)
[621.425862] [111/133] (401s left) kms_psr (fbc-pr-sprite-render)
[621.518784] Starting subtest: fbc-pr-sprite-render
[621.523121] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[621.589894] [112/133] (401s left) syncobj_timeline (wait-all-delayed-signal)
[621.648671] Starting subtest: wait-all-delayed-signal
[621.751318] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[621.826559] [113/133] (400s left) gem_ctx_persistence (legacy-engines-hang)
[621.878249] Starting subtest: legacy-engines-hang
[621.880427] Starting dynamic subtest: render
[621.988456] Dynamic subtest render: SUCCESS (0.111s)
[622.003364] Starting dynamic subtest: bsd1
[622.059190] Dynamic subtest bsd1: SUCCESS (0.069s)
[622.072304] Starting dynamic subtest: bsd2
[622.168594] Dynamic subtest bsd2: SUCCESS (0.108s)
[622.179896] Starting dynamic subtest: blt
[622.260347] Dynamic subtest blt: SUCCESS (0.090s)
[622.275317] Starting dynamic subtest: vebox
[622.349409] Dynamic subtest vebox: SUCCESS (0.087s)
[622.351965] Subtest legacy-engines-hang: SUCCESS (0.477s)
[622.442762] [114/133] (400s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[622.502931] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[622.604613] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.104s)
[622.683640] [115/133] (400s left) gem_exec_suspend (basic-s4-devices)
[622.741702] Starting subtest: basic-S4-devices
[622.744355] Starting dynamic subtest: smem
[634.259364] Dynamic subtest smem: SUCCESS (11.579s)
[634.262027] Subtest basic-S4-devices: SUCCESS (11.582s)
[641.459613] [116/133] (388s left) gem_busy (extended)
[641.653375] Starting subtest: extended
[641.655534] Starting dynamic subtest: rcs0
[641.741063] Dynamic subtest rcs0: SUCCESS (0.090s)
[641.754242] Starting dynamic subtest: bcs0
[641.785128] Dynamic subtest bcs0: SUCCESS (0.043s)
[641.799139] Starting dynamic subtest: vcs0
[643.218111] Dynamic subtest vcs0: SUCCESS (1.428s)
[643.236284] Starting dynamic subtest: vcs1
[645.266428] Dynamic subtest vcs1: SUCCESS (2.044s)
[645.281084] Starting dynamic subtest: vecs0
[647.251314] Dynamic subtest vecs0: SUCCESS (1.981s)
[647.264897] Starting dynamic subtest: ccs0
[647.295361] Dynamic subtest ccs0: SUCCESS (0.044s)
[647.297745] Subtest extended: SUCCESS (5.645s)
[647.547133] [117/133] (382s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[647.653986] Starting subtest: fbcpsr-tiling-4
[654.728514] Subtest fbcpsr-tiling-4: SUCCESS (7.077s)
[654.852351] [118/133] (375s left) kms_vblank (ts-continuation-modeset-hang)
[654.946905] Starting subtest: ts-continuation-modeset-hang
[654.948771] Starting dynamic subtest: pipe-A-eDP-1
[681.114527] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.167s)
[681.130774] Starting dynamic subtest: pipe-D-eDP-1
[696.076306] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.963s)
[696.078668] Subtest ts-continuation-modeset-hang: SUCCESS (41.136s)
[696.945909] [119/133] (333s left) gen9_exec_parse (secure-batches)
[697.007752] Subtest secure-batches: SKIP (0.000s)
[697.087334] [120/133] (332s left) kms_chamelium_edid (dp-edid-read)
[697.476972] Subtest dp-edid-read: SKIP (0.000s)
[698.261113] [121/133] (331s left) gem_userptr_blits (forbidden-operations)
[698.318937] Starting subtest: forbidden-operations
[698.323500] Subtest forbidden-operations: SKIP (0.000s)
[698.443335] [122/133] (331s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[698.535372] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[698.539520] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[698.635169] [123/133] (331s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[698.750201] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[698.754673] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[698.853652] [124/133] (331s left) gem_mmap_gtt (isolation)
[698.911416] Subtest isolation: SKIP (0.000s)
[698.974769] [125/133] (331s left) gem_mmap_gtt (cpuset-big-copy-xy)
[699.030032] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[699.094348] [126/133] (331s left) vgem_basic (setversion)
[699.133174] Starting subtest: setversion
[699.137742] Subtest setversion: SUCCESS (0.000s)
[699.193679] [127/133] (331s left) kms_plane_multiple (tiling-4)
[699.290544] Starting subtest: tiling-4
[699.292207] Starting dynamic subtest: pipe-A-eDP-1
[703.022549] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.734s)
[703.034127] Starting dynamic subtest: pipe-B-eDP-1
[707.493231] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.469s)
[707.505165] Starting dynamic subtest: pipe-C-eDP-1
[711.884989] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.391s)
[711.896087] Starting dynamic subtest: pipe-D-eDP-1
[716.219683] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.337s)
[716.222430] Subtest tiling-4: SUCCESS (16.936s)
[717.019182] [128/133] (313s left) kms_atomic_interruptible (legacy-pageflip)
[717.120422] Starting subtest: legacy-pageflip
[717.122541] Starting dynamic subtest: pipe-A-eDP-1
[724.545183] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.427s)
[724.547605] Subtest legacy-pageflip: SUCCESS (7.429s)
[725.372173] [129/133] (305s left) gem_ppgtt (blt-vs-render-ctxn)
[725.430381] Starting subtest: blt-vs-render-ctxN
[758.390768] Subtest blt-vs-render-ctxN: SUCCESS (32.972s)
[758.533374] [130/133] (271s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[758.675141] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[767.301139] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.633s)
[767.419672] [131/133] (263s left) gem_eio (in-flight-contexts-immediate)
[767.671437] Starting subtest: in-flight-contexts-immediate
[779.318518] Subtest in-flight-contexts-immediate: SUCCESS (11.631s)
[779.484043] [132/133] (251s left) perf (oa-formats)
[779.642532] Starting subtest: oa-formats
[779.644784] Starting dynamic subtest: 0-rcs0
[779.656162] Dynamic subtest 0-rcs0: SUCCESS (0.015s)
[779.667750] Starting dynamic subtest: 1-vcs0
[779.678961] Dynamic subtest 1-vcs0: SUCCESS (0.022s)
[779.681200] Subtest oa-formats: SUCCESS (0.038s)
[779.732191] [133/133] (250s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[779.795134] Starting subtest: multi-wait-all-for-submit-submitted
[779.902851] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.108s)
[779.978694] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_params
results: parsing output: 1/ for test: kms_plane_scaling
results: parsing output: 2/ for test: kms_psr
results: parsing output: 3/ for test: kms_plane
results: parsing output: 4/ for test: kms_atomic
results: parsing output: 5/ for test: syncobj_wait
results: parsing output: 6/ for test: gem_create
results: parsing output: 7/ for test: kms_plane_scaling
results: parsing output: 8/ for test: kms_flip
results: parsing output: 9/ for test: kms_invalid_mode
results: parsing output: 10/ for test: sysfs_timeslice_duration
results: parsing output: 11/ for test: kms_psr
results: parsing output: 12/ for test: gem_userptr_blits
results: parsing output: 13/ for test: kms_prop_blob
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_busy
results: parsing output: 16/ for test: gem_ctx_shared
results: parsing output: 17/ for test: perf_pmu
results: parsing output: 18/ for test: gem_userptr_blits
results: parsing output: 19/ for test: i915_drm_fdinfo
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_readwrite
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_flip
results: parsing output: 25/ for test: kms_ccs
results: parsing output: 26/ for test: syncobj_wait
results: parsing output: 27/ for test: kms_selftest
results: parsing output: 28/ for test: kms_lease
results: parsing output: 29/ for test: kms_content_protection
results: parsing output: 30/ for test: syncobj_wait
results: parsing output: 31/ for test: kms_pipe_b_c_ivb
results: parsing output: 32/ for test: syncobj_wait
results: parsing output: 33/ for test: core_setmaster
results: parsing output: 34/ for test: kms_ccs
results: parsing output: 35/ for test: gem_render_copy
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: gem_mmap_wc
results: parsing output: 40/ for test: syncobj_wait
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_plane_alpha_blend
results: parsing output: 43/ for test: kms_psr2_sf
results: parsing output: 44/ for test: gem_exec_whisper
results: parsing output: 45/ for test: kms_feature_discovery
results: parsing output: 46/ for test: gem_pxp
results: parsing output: 47/ for test: gem_exec_balancer
results: parsing output: 48/ for test: gem_render_copy
results: parsing output: 49/ for test: i915_pm_rps
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: api_intel_bb
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_chamelium_hpd
results: parsing output: 54/ for test: gem_caching
results: parsing output: 55/ for test: kms_cursor_legacy
results: parsing output: 56/ for test: kms_async_flips
results: parsing output: 57/ for test: kms_big_fb
results: parsing output: 58/ for test: gem_ctx_bad_destroy
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_big_fb
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_exec_parallel
results: parsing output: 64/ for test: kms_cursor_crc
results: parsing output: 65/ for test: gem_exec_flush
results: parsing output: 66/ for test: kms_pm_rpm
results: parsing output: 67/ for test: i915_hangman
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_big_fb
results: parsing output: 70/ for test: i915_pm_rps
results: parsing output: 71/ for test: perf_pmu
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: gem_mmap_wc
results: parsing output: 74/ for test: kms_cursor_legacy
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: kms_properties
results: parsing output: 77/ for test: gem_exec_fence
results: parsing output: 78/ for test: kms_flip_scaled_crc
results: parsing output: 79/ for test: gem_mmap_gtt
results: parsing output: 80/ for test: syncobj_basic
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: gem_exec_balancer
results: parsing output: 83/ for test: kms_ccs
results: parsing output: 84/ for test: prime_vgem
results: parsing output: 85/ for test: gem_exec_schedule
results: parsing output: 86/ for test: gem_userptr_blits
results: parsing output: 87/ for test: kms_psr2_sf
results: parsing output: 88/ for test: kms_flip
results: parsing output: 89/ for test: gem_softpin
results: parsing output: 90/ for test: gem_ctx_param
results: parsing output: 91/ for test: gem_exec_schedule
results: parsing output: 92/ for test: kms_color_pipeline
results: parsing output: 93/ for test: kms_cursor_legacy
results: parsing output: 94/ for test: kms_cursor_crc
results: parsing output: 95/ for test: sw_sync
results: parsing output: 96/ for test: syncobj_wait
results: parsing output: 97/ for test: syncobj_timeline
results: parsing output: 98/ for test: gem_render_tiled_blits
results: parsing output: 99/ for test: kms_pm_backlight
results: parsing output: 100/ for test: kms_cursor_crc
results: parsing output: 101/ for test: gem_lmem_swapping
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: api_intel_bb
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_render_copy
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_rotation_crc
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: syncobj_timeline
results: parsing output: 112/ for test: gem_ctx_persistence
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: gem_exec_suspend
results: parsing output: 115/ for test: gem_busy
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_vblank
results: parsing output: 118/ for test: gen9_exec_parse
results: parsing output: 119/ for test: kms_chamelium_edid
results: parsing output: 120/ for test: gem_userptr_blits
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: gem_mmap_gtt
results: parsing output: 124/ for test: gem_mmap_gtt
results: parsing output: 125/ for test: vgem_basic
results: parsing output: 126/ for test: kms_plane_multiple
results: parsing output: 127/ for test: kms_atomic_interruptible
results: parsing output: 128/ for test: gem_ppgtt
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_eio
results: parsing output: 131/ for test: perf
results: parsing output: 132/ for test: syncobj_wait
Done.