[61.009409] Initializing watchdogs
[61.009460]   /dev/watchdog0
[61.031106] [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]
[61.036071] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.038293] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.040108] [001/133] (960s left) gem_exec_params (dr4-dirt)
[61.098564] Starting subtest: DR4-dirt
[61.099560] Subtest DR4-dirt: SUCCESS (0.000s)
[61.205699] [002/133] (960s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[61.311170] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[61.312576] Starting dynamic subtest: pipe-A
[61.552474] Dynamic subtest pipe-A: SUCCESS (0.241s)
[61.565982] Starting dynamic subtest: pipe-B
[62.863948] Dynamic subtest pipe-B: SUCCESS (1.308s)
[62.871924] Starting dynamic subtest: pipe-C
[64.206022] Dynamic subtest pipe-C: SUCCESS (1.339s)
[64.213283] Starting dynamic subtest: pipe-D
[65.539179] Dynamic subtest pipe-D: SUCCESS (1.331s)
[65.540246] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.220s)
[66.714180] [003/133] (954s left) kms_psr (psr2-cursor-blt)
[66.813485] Starting subtest: psr2-cursor-blt
[66.814513] Starting dynamic subtest: eDP-1
[68.508030] Dynamic subtest eDP-1: SUCCESS (1.692s)
[68.509268] Subtest psr2-cursor-blt: SUCCESS (1.692s)
[69.380941] [004/133] (952s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[69.466240] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[69.493056] Starting dynamic subtest: pipe-A-plane-0
[69.915572] Dynamic subtest pipe-A-plane-0: SUCCESS (0.422s)
[69.922680] Starting dynamic subtest: pipe-A-plane-3
[70.171262] Dynamic subtest pipe-A-plane-3: SUCCESS (0.254s)
[71.279042] Starting dynamic subtest: pipe-B-plane-0
[71.705923] Dynamic subtest pipe-B-plane-0: SUCCESS (0.433s)
[71.712908] Starting dynamic subtest: pipe-B-plane-3
[71.961528] Dynamic subtest pipe-B-plane-3: SUCCESS (0.255s)
[72.271633] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.804s)
[73.148198] [005/133] (948s left) kms_atomic (plane-primary-legacy)
[73.231580] Starting subtest: plane-primary-legacy
[73.232561] Starting dynamic subtest: pipe-A-eDP-1
[73.709586] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.477s)
[73.710415] Subtest plane-primary-legacy: SUCCESS (0.478s)
[74.566337] [006/133] (947s left) syncobj_wait (signal)
[74.620551] Starting subtest: signal
[74.621480] Subtest signal: SUCCESS (0.000s)
[74.700599] [007/133] (947s left) gem_create (busy-create)
[74.742311] Starting subtest: busy-create
[74.743222] Starting dynamic subtest: smem0
[107.934268] Dynamic subtest smem0: SUCCESS (33.173s)
[107.935468] Subtest busy-create: SUCCESS (33.174s)
[108.105996] [008/133] (913s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[108.197174] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[108.198074] Starting dynamic subtest: pipe-A
[108.547894] Dynamic subtest pipe-A: SUCCESS (0.351s)
[108.553798] Starting dynamic subtest: pipe-B
[109.991308] Dynamic subtest pipe-B: SUCCESS (1.443s)
[109.998762] Starting dynamic subtest: pipe-C
[111.463517] Dynamic subtest pipe-C: SUCCESS (1.472s)
[111.469472] Starting dynamic subtest: pipe-D
[112.863553] Dynamic subtest pipe-D: SUCCESS (1.399s)
[112.864788] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.666s)
[114.017066] [009/133] (907s left) kms_flip (bo-too-big)
[114.112723] Starting subtest: bo-too-big
[114.114516] Starting dynamic subtest: A-eDP1
[115.487270] Dynamic subtest A-eDP1: SUCCESS (1.371s)
[115.496327] Starting dynamic subtest: B-eDP1
[116.812978] Dynamic subtest B-eDP1: SUCCESS (1.327s)
[116.822834] Starting dynamic subtest: C-eDP1
[118.148946] Dynamic subtest C-eDP1: SUCCESS (1.334s)
[118.157808] Starting dynamic subtest: D-eDP1
[119.486403] Dynamic subtest D-eDP1: SUCCESS (1.335s)
[119.487762] Subtest bo-too-big: SUCCESS (5.375s)
[120.696960] [010/133] (901s left) kms_invalid_mode (overflow-vrefresh)
[120.803400] Starting subtest: overflow-vrefresh
[120.804330] Starting dynamic subtest: pipe-A-eDP-1
[121.082594] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[121.091128] Starting dynamic subtest: pipe-B-eDP-1
[121.092213] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[121.097963] Starting dynamic subtest: pipe-C-eDP-1
[121.098880] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[121.104498] Starting dynamic subtest: pipe-D-eDP-1
[121.105487] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[121.106459] Subtest overflow-vrefresh: SUCCESS (0.293s)
[121.939478] [011/133] (899s left) sysfs_timeslice_duration (timeout)
[121.991885] Starting subtest: timeout
[121.992726] Starting dynamic subtest: vecs0
[131.929403] Dynamic subtest vecs0: SUCCESS (9.927s)
[131.938695] Starting dynamic subtest: ccs0
[133.228249] Dynamic subtest ccs0: SUCCESS (1.307s)
[133.237250] Starting dynamic subtest: vcs0
[142.936589] Dynamic subtest vcs0: SUCCESS (9.697s)
[142.945868] Starting dynamic subtest: bcs0
[143.574534] Dynamic subtest bcs0: SUCCESS (0.645s)
[143.582987] Starting dynamic subtest: rcs0
[144.869114] Dynamic subtest rcs0: SUCCESS (1.293s)
[144.877311] Starting dynamic subtest: vcs1
[154.909658] Dynamic subtest vcs1: SUCCESS (10.029s)
[154.910827] Subtest timeout: SUCCESS (32.908s)
[154.966856] [012/133] (866s left) kms_psr (psr-sprite-blt)
[155.063313] Starting subtest: psr-sprite-blt
[155.064230] Starting dynamic subtest: eDP-1
[156.849842] Dynamic subtest eDP-1: SUCCESS (1.786s)
[156.850944] Subtest psr-sprite-blt: SUCCESS (1.787s)
[157.694587] [013/133] (864s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[157.770303] Starting subtest: stress-mm-invalidate-close-overlap
[159.963356] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[160.007752] [014/133] (861s left) kms_prop_blob (blob-prop-lifetime)
[160.055951] Starting subtest: blob-prop-lifetime
[160.056847] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[160.151544] [015/133] (861s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[160.241580] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[167.877397] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.636s)
[168.991103] [016/133] (853s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[169.087021] Starting subtest: extended-modeset-hang-oldfb-with-reset
[169.087921] Starting dynamic subtest: pipe-A
[180.659420] Dynamic subtest pipe-A: SUCCESS (11.571s)
[180.667594] Starting dynamic subtest: pipe-D
[192.924305] Dynamic subtest pipe-D: SUCCESS (12.262s)
[192.925184] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (23.837s)
[193.702860] [017/133] (828s left) gem_ctx_shared (single-timeline)
[193.763681] Starting subtest: single-timeline
[193.777938] Subtest single-timeline: SUCCESS (0.014s)
[194.008309] [018/133] (828s left) perf_pmu (busy-check-all)
[194.049397] Starting subtest: busy-check-all
[194.050433] Starting dynamic subtest: rcs0
[194.598959] Dynamic subtest rcs0: SUCCESS (0.549s)
[194.608734] Starting dynamic subtest: bcs0
[195.149212] Dynamic subtest bcs0: SUCCESS (0.549s)
[195.160297] Starting dynamic subtest: vcs0
[196.893805] Dynamic subtest vcs0: SUCCESS (1.734s)
[196.903812] Starting dynamic subtest: vcs1
[198.941968] Dynamic subtest vcs1: SUCCESS (2.046s)
[198.951721] Starting dynamic subtest: vecs0
[200.926051] Dynamic subtest vecs0: SUCCESS (1.982s)
[200.935227] Starting dynamic subtest: ccs0
[201.472863] Dynamic subtest ccs0: SUCCESS (0.554s)
[201.473936] Subtest busy-check-all: SUCCESS (7.424s)
[201.564992] [019/133] (820s left) gem_userptr_blits (probe)
[201.637470] Starting subtest: probe
[201.717834] Subtest probe: SUCCESS (0.080s)
[201.821385] [020/133] (820s left) i915_drm_fdinfo (most-busy-idle-check-all)
[201.860062] Starting subtest: most-busy-idle-check-all
[201.861046] Starting dynamic subtest: rcs0
[201.864927] Dynamic subtest rcs0: SKIP (0.004s)
[201.871944] Starting dynamic subtest: bcs0
[201.873657] Dynamic subtest bcs0: SKIP (0.000s)
[201.880243] Starting dynamic subtest: vcs0
[201.881915] Dynamic subtest vcs0: SKIP (0.000s)
[201.888259] Starting dynamic subtest: vcs1
[201.889893] Dynamic subtest vcs1: SKIP (0.000s)
[201.896331] Starting dynamic subtest: vecs0
[201.898060] Dynamic subtest vecs0: SKIP (0.000s)
[201.904445] Starting dynamic subtest: ccs0
[201.906197] Dynamic subtest ccs0: SKIP (0.000s)
[201.907831] Subtest most-busy-idle-check-all: SKIP (0.008s)
[201.934384] [021/133] (820s left) kms_big_fb (linear-16bpp-rotate-0)
[202.045842] Starting subtest: linear-16bpp-rotate-0
[203.434037] Subtest linear-16bpp-rotate-0: SUCCESS (1.389s)
[203.549835] [022/133] (818s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[203.652332] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[203.654142] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[203.741929] [023/133] (818s left) gem_readwrite (new-obj)
[203.786193] Subtest new-obj: SKIP (0.000s)
[203.838395] [024/133] (818s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[203.938209] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[203.939927] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[204.021749] [025/133] (818s left) kms_flip (flip-vs-dpms-off-vs-modeset)
[204.110054] Starting subtest: flip-vs-dpms-off-vs-modeset
[204.110972] Starting dynamic subtest: A-eDP1
[207.739334] Dynamic subtest A-eDP1: SUCCESS (3.628s)
[207.747427] Starting dynamic subtest: B-eDP1
[210.964245] Dynamic subtest B-eDP1: SUCCESS (3.224s)
[210.971369] Starting dynamic subtest: C-eDP1
[214.084617] Dynamic subtest C-eDP1: SUCCESS (3.120s)
[214.091774] Starting dynamic subtest: D-eDP1
[217.203513] Dynamic subtest D-eDP1: SUCCESS (3.118s)
[217.205466] Subtest flip-vs-dpms-off-vs-modeset: SUCCESS (13.096s)
[217.990079] [026/133] (804s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[218.090134] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[218.092766] Starting dynamic subtest: pipe-A-eDP-1
[218.586264] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.484s)
[218.593189] Starting dynamic subtest: pipe-B-eDP-1
[220.124906] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.538s)
[220.133302] Starting dynamic subtest: pipe-C-eDP-1
[221.562339] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.436s)
[221.569211] Starting dynamic subtest: pipe-D-eDP-1
[222.996716] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.424s)
[222.997582] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.887s)
[224.139841] [027/133] (798s left) syncobj_wait (wait-any-interrupted)
[224.188499] Starting subtest: wait-any-interrupted
[224.292423] Subtest wait-any-interrupted: SUCCESS (0.103s)
[224.374312] [028/133] (797s left) kms_selftest (drm_plane_helper)
[224.413194] Starting subtest: drm_plane_helper
[224.458501] Starting dynamic subtest: drm_test_check_plane_state
[224.549400] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.092s)
[224.556342] Starting dynamic subtest: drm_test_check_invalid_plane_state
[224.648053] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.096s)
[224.648891] Subtest drm_plane_helper: SUCCESS (0.234s)
[224.812331] [029/133] (797s left) kms_lease (lease-uevent)
[224.898095] Starting subtest: lease-uevent
[225.916628] Subtest lease-uevent: SUCCESS (1.013s)
[225.983386] [030/133] (796s left) kms_content_protection (dp-mst-type-1-suspend-resume)
[226.126106] Starting subtest: dp-mst-type-1-suspend-resume
[226.128798] Subtest dp-mst-type-1-suspend-resume: SKIP (0.000s)
[226.236944] [031/133] (796s left) syncobj_wait (wait-all-delayed-signal)
[226.282800] Starting subtest: wait-all-delayed-signal
[226.385548] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[226.454501] [032/133] (796s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[226.499038] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[226.552899] [033/133] (795s left) syncobj_wait (wait-all-for-submit-complex)
[226.603133] Starting subtest: wait-all-for-submit-complex
[226.824604] Subtest wait-all-for-submit-complex: SUCCESS (0.213s)
[226.883489] [034/133] (795s left) core_setmaster (master-drop-set-root)
[226.961624] Starting subtest: master-drop-set-root
[226.975241] Subtest master-drop-set-root: SUCCESS (0.014s)
[227.014432] [035/133] (795s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[227.102725] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[227.104642] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[228.189038] [036/133] (794s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[228.248735] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[228.250591] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[228.423685] [037/133] (794s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[228.527171] Starting subtest: 4-tiled-32bpp-rotate-180
[230.041296] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.514s)
[230.187115] [038/133] (792s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[230.281749] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[237.009742] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (6.728s)
[237.112552] [039/133] (785s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[237.196615] Starting subtest: bad-rotation-90-yf-tiled-ccs
[237.197493] Starting dynamic subtest: pipe-A-eDP-1
[237.206041] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[237.215564] Starting dynamic subtest: pipe-B-eDP-1
[237.224255] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[237.231537] Starting dynamic subtest: pipe-C-eDP-1
[237.239865] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[237.246367] Starting dynamic subtest: pipe-D-eDP-1
[237.254777] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[237.256430] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.005s)
[239.487711] [040/133] (783s left) gem_mmap_wc (coherency)
[239.544678] Subtest coherency: SKIP (0.000s)
[239.611621] [041/133] (783s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[239.658261] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[239.659475] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[239.741377] [042/133] (783s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[239.838299] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[239.840520] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[239.928515] [043/133] (782s left) kms_plane_alpha_blend (constant-alpha-mid)
[240.028498] Starting subtest: constant-alpha-mid
[240.323016] Starting dynamic subtest: pipe-A-eDP-1
[241.209991] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.887s)
[241.225906] Starting dynamic subtest: pipe-D-eDP-1
[242.705897] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.495s)
[242.706936] Subtest constant-alpha-mid: SUCCESS (2.679s)
[243.551471] [044/133] (779s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[243.644479] Starting subtest: psr2-overlay-plane-update-continuous-sf
[243.645452] Starting dynamic subtest: pipe-A-eDP-1
[244.774436] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.128s)
[244.781151] Starting dynamic subtest: pipe-B-eDP-1
[246.503040] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.727s)
[246.504121] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (2.858s)
[247.316755] [045/133] (775s left) gem_exec_whisper (basic-contexts-priority)
[247.378644] Starting subtest: basic-contexts-priority
[272.382615] Subtest basic-contexts-priority: SUCCESS (24.957s)
[272.513378] [046/133] (750s left) kms_feature_discovery (chamelium)
[272.609381] Starting subtest: chamelium
[272.611241] Subtest chamelium: SKIP (0.000s)
[272.674074] [047/133] (750s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[272.797238] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[272.931446] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.136s)
[273.034407] [048/133] (749s left) gem_exec_balancer (persistence)
[273.304209] Starting subtest: persistence
[274.315509] Subtest persistence: SUCCESS (1.008s)
[274.416094] [049/133] (748s left) gem_render_copy (linear)
[274.464271] Starting subtest: linear
[274.465758] Starting dynamic subtest: smem
[274.506439] Dynamic subtest smem: SUCCESS (0.042s)
[274.507712] Subtest linear: SUCCESS (0.042s)
[274.696308] [050/133] (748s left) i915_pm_rps (thresholds)
[274.749770] Starting subtest: thresholds
[274.751738] Subtest thresholds: SKIP (0.001s)
[274.856271] [051/133] (748s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[274.944196] Starting subtest: yf-tiled-32bpp-rotate-0
[274.946813] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[275.031149] [052/133] (748s left) api_intel_bb (object-reloc-purge-cache)
[275.074186] Starting subtest: object-reloc-purge-cache
[275.077799] Subtest object-reloc-purge-cache: SKIP (0.003s)
[275.192987] [053/133] (747s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[275.280694] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[275.282415] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[275.371717] [054/133] (747s left) kms_chamelium_hpd (hdmi-hpd-storm)
[275.763520] Subtest hdmi-hpd-storm: SKIP (0.000s)
[276.523234] [055/133] (746s left) gem_caching (writes)
[276.584752] Subtest writes: SKIP (0.000s)
[276.645632] [056/133] (746s left) kms_cursor_legacy (forked-bo)
[276.764622] Starting subtest: forked-bo
[276.765587] Starting dynamic subtest: pipe-A
[282.267923] Dynamic subtest pipe-A: SUCCESS (5.456s)
[282.278071] Starting dynamic subtest: pipe-B
[287.749489] Dynamic subtest pipe-B: SUCCESS (5.478s)
[287.762119] Starting dynamic subtest: pipe-C
[293.225420] Dynamic subtest pipe-C: SUCCESS (5.473s)
[293.240241] Starting dynamic subtest: pipe-D
[298.695733] Dynamic subtest pipe-D: SUCCESS (5.468s)
[298.711305] Starting dynamic subtest: all-pipes
[304.201698] Dynamic subtest all-pipes: SUCCESS (5.504s)
[304.203043] Subtest forked-bo: SUCCESS (27.391s)
[304.299343] [057/133] (719s left) kms_async_flips (crc)
[304.381682] Starting subtest: crc
[304.771467] Starting dynamic subtest: pipe-A-eDP-1
[307.676013] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.904s)
[308.052639] Starting dynamic subtest: pipe-B-eDP-1
[310.987375] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.941s)
[311.362371] Starting dynamic subtest: pipe-C-eDP-1
[314.213460] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.858s)
[314.573909] Starting dynamic subtest: pipe-D-eDP-1
[317.439071] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.871s)
[317.440355] Subtest crc: SUCCESS (13.058s)
[318.729284] [058/133] (704s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[318.839275] Starting subtest: yf-tiled-8bpp-rotate-90
[318.841372] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[318.928066] [059/133] (704s left) gem_ctx_bad_destroy (invalid-pad)
[318.975551] Starting subtest: invalid-pad
[318.976478] Subtest invalid-pad: SUCCESS (0.000s)
[319.035635] [060/133] (704s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[319.123488] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[319.124441] Starting dynamic subtest: pipe-A-eDP-1
[319.133353] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[319.140991] Starting dynamic subtest: pipe-B-eDP-1
[319.149995] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[319.159063] Starting dynamic subtest: pipe-C-eDP-1
[319.167697] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[319.174897] Starting dynamic subtest: pipe-D-eDP-1
[319.183491] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[319.185366] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[321.377714] [061/133] (702s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[321.472273] Starting subtest: y-tiled-16bpp-rotate-180
[321.473873] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[321.579808] [062/133] (701s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-blt)
[321.678417] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-blt
[321.680354] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[321.768096] [063/133] (701s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[321.871099] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[321.872760] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[321.964799] [064/133] (701s left) gem_exec_parallel (engines)
[322.022320] Starting subtest: engines
[322.023178] Starting dynamic subtest: basic
[323.501549] Dynamic subtest basic: SUCCESS (1.479s)
[323.512635] Starting dynamic subtest: contexts
[327.470602] Dynamic subtest contexts: SUCCESS (3.957s)
[327.482329] Starting dynamic subtest: fds
[331.157386] Dynamic subtest fds: SUCCESS (3.685s)
[331.167074] Starting dynamic subtest: userptr
[332.907412] Dynamic subtest userptr: SUCCESS (1.748s)
[332.908365] Subtest engines: SUCCESS (10.876s)
[333.081625] [065/133] (690s left) kms_cursor_crc (cursor-onscreen-256x85)
[333.341133] Starting subtest: cursor-onscreen-256x85
[333.388069] Subtest cursor-onscreen-256x85: SKIP (0.045s)
[333.564396] [066/133] (690s left) gem_exec_flush (basic-uc-prw-default)
[333.630605] Starting subtest: basic-uc-prw-default
[339.103244] Subtest basic-uc-prw-default: SUCCESS (5.472s)
[339.285743] [067/133] (684s left) kms_pm_rpm (modeset-lpsp)
[341.019072] Starting subtest: modeset-lpsp
[342.487064] Subtest modeset-lpsp: SUCCESS (1.469s)
[342.567852] [068/133] (681s left) i915_hangman (gt-error-state-capture)
[342.639991] Starting subtest: gt-error-state-capture
[342.640875] Starting dynamic subtest: rcs0
[352.894466] Dynamic subtest rcs0: SUCCESS (10.243s)
[352.904600] Starting dynamic subtest: bcs0
[363.901989] Dynamic subtest bcs0: SUCCESS (11.004s)
[363.909617] Starting dynamic subtest: vcs0
[375.805752] Dynamic subtest vcs0: SUCCESS (11.900s)
[375.816225] Starting dynamic subtest: vcs1
[385.853499] Dynamic subtest vcs1: SUCCESS (10.045s)
[385.864296] Starting dynamic subtest: vecs0
[397.821656] Dynamic subtest vecs0: SUCCESS (11.965s)
[397.831924] Starting dynamic subtest: ccs0
[408.895095] Dynamic subtest ccs0: SUCCESS (11.070s)
[408.896519] Subtest gt-error-state-capture: SUCCESS (66.245s)
[409.002801] [069/133] (614s left) kms_psr (psr2-primary-page-flip)
[409.098040] Starting subtest: psr2-primary-page-flip
[409.098904] Starting dynamic subtest: eDP-1
[410.830403] Dynamic subtest eDP-1: SUCCESS (1.732s)
[410.831293] Subtest psr2-primary-page-flip: SUCCESS (1.733s)
[411.701724] [070/133] (612s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[411.796031] Starting subtest: x-tiled-64bpp-rotate-0
[413.518472] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.723s)
[413.677787] [071/133] (610s left) i915_pm_rps (min-max-config-loaded)
[413.743731] Starting subtest: min-max-config-loaded
[413.745681] Subtest min-max-config-loaded: SKIP (0.000s)
[413.850494] [072/133] (610s left) perf_pmu (busy-no-semaphores)
[413.887639] Starting subtest: busy-no-semaphores
[413.888626] Starting dynamic subtest: rcs0
[414.451545] Dynamic subtest rcs0: SUCCESS (0.552s)
[414.460524] Starting dynamic subtest: bcs0
[415.000985] Dynamic subtest bcs0: SUCCESS (0.546s)
[415.011205] Starting dynamic subtest: vcs0
[415.551889] Dynamic subtest vcs0: SUCCESS (0.548s)
[415.561577] Starting dynamic subtest: vcs1
[417.445049] Dynamic subtest vcs1: SUCCESS (1.890s)
[417.455227] Starting dynamic subtest: vecs0
[419.428039] Dynamic subtest vecs0: SUCCESS (1.980s)
[419.438493] Starting dynamic subtest: ccs0
[421.411395] Dynamic subtest ccs0: SUCCESS (1.979s)
[421.412545] Subtest busy-no-semaphores: SUCCESS (7.515s)
[421.515089] [073/133] (602s left) kms_flip (blocking-wf_vblank)
[421.611274] Starting subtest: blocking-wf_vblank
[421.613563] Starting dynamic subtest: A-eDP1
[430.572782] Dynamic subtest A-eDP1: SUCCESS (8.934s)
[430.582054] Starting dynamic subtest: B-eDP1
[439.511788] Dynamic subtest B-eDP1: SUCCESS (8.935s)
[439.525384] Starting dynamic subtest: C-eDP1
[448.439233] Dynamic subtest C-eDP1: SUCCESS (8.915s)
[448.452824] Starting dynamic subtest: D-eDP1
[457.374020] Dynamic subtest D-eDP1: SUCCESS (8.931s)
[457.375169] Subtest blocking-wf_vblank: SUCCESS (35.730s)
[458.569654] [074/133] (565s left) gem_mmap_wc (read-write)
[458.622872] Subtest read-write: SKIP (0.000s)
[458.670548] [075/133] (565s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[458.782992] Starting subtest: basic-flip-after-cursor-varying-size
[459.710471] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.928s)
[460.492557] [076/133] (563s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[460.552838] Starting subtest: etime-multi-wait-all-for-submit-submitted
[460.658469] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.105s)
[460.735917] [077/133] (563s left) kms_properties (plane-properties-legacy)
[460.825752] Starting subtest: plane-properties-legacy
[460.826705] Starting dynamic subtest: pipe-A-eDP-1
[461.369994] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.543s)
[461.377147] Starting dynamic subtest: pipe-B-eDP-1
[462.680551] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.309s)
[462.690094] Starting dynamic subtest: pipe-C-eDP-1
[463.910716] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.229s)
[463.918555] Starting dynamic subtest: pipe-D-eDP-1
[465.148331] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.236s)
[465.149146] Subtest plane-properties-legacy: SUCCESS (4.323s)
[465.944655] [078/133] (558s left) gem_exec_fence (syncobj-invalid-flags)
[466.433825] Starting subtest: syncobj-invalid-flags
[466.435350] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[467.992758] [FACT gem_exec_fence (syncobj-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[467.994816] [079/133] (556s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[468.076523] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[468.078182] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[468.145161] [080/133] (555s left) gem_mmap_gtt (basic-small-bo-tiledy)
[468.191320] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[468.253714] [081/133] (555s left) syncobj_basic (bad-destroy)
[468.306433] Starting subtest: bad-destroy
[468.307397] Subtest bad-destroy: SUCCESS (0.000s)
[468.412356] [082/133] (555s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[468.499868] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[475.513978] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (7.014s)
[475.636053] [083/133] (548s left) gem_exec_balancer (hang)
[475.903547] Starting subtest: hang
[477.920524] Subtest hang: SUCCESS (2.017s)
[478.024184] [084/133] (546s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[478.109374] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[478.110263] Starting dynamic subtest: pipe-A-eDP-1
[479.044543] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.933s)
[479.052267] Starting dynamic subtest: pipe-B-eDP-1
[480.700436] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.654s)
[480.708052] Starting dynamic subtest: pipe-C-eDP-1
[482.239229] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.537s)
[482.246594] Starting dynamic subtest: pipe-D-eDP-1
[483.729514] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.489s)
[483.730287] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.618s)
[484.893396] [085/133] (539s left) prime_vgem (basic-blt)
[484.957860] Starting subtest: basic-blt
[484.984015] Subtest basic-blt: SUCCESS (0.026s)
[485.104722] [086/133] (539s left) gem_exec_schedule (implicit-write-read)
[485.167077] Starting subtest: implicit-write-read
[485.167953] Starting dynamic subtest: rcs0
[485.285653] Dynamic subtest rcs0: SUCCESS (0.117s)
[485.296464] Starting dynamic subtest: vcs0
[485.380869] Dynamic subtest vcs0: SUCCESS (0.096s)
[485.391293] Starting dynamic subtest: vcs1
[485.461131] Dynamic subtest vcs1: SUCCESS (0.078s)
[485.472078] Starting dynamic subtest: vecs0
[485.540359] Dynamic subtest vecs0: SUCCESS (0.079s)
[485.551822] Starting dynamic subtest: ccs0
[485.645293] Dynamic subtest ccs0: SUCCESS (0.105s)
[485.646487] Subtest implicit-write-read: SUCCESS (0.480s)
[487.035590] [087/133] (537s left) gem_userptr_blits (coherency-unsync)
[487.092147] Subtest coherency-unsync: SKIP (0.000s)
[487.225629] [088/133] (537s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[487.323865] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[487.325632] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[487.389980] [089/133] (537s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[487.477709] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[487.489716] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[487.560885] [090/133] (536s left) gem_softpin (evict-prime)
[487.618072] Starting subtest: evict-prime
[487.619288] Starting dynamic subtest: rcs0
[488.764697] Dynamic subtest rcs0: SUCCESS (1.146s)
[488.777351] Starting dynamic subtest: bcs0
[489.886383] Dynamic subtest bcs0: SUCCESS (1.120s)
[489.897513] Starting dynamic subtest: vcs0
[491.881800] Dynamic subtest vcs0: SUCCESS (1.982s)
[491.894401] Starting dynamic subtest: vcs1
[493.868511] Dynamic subtest vcs1: SUCCESS (1.984s)
[493.880565] Starting dynamic subtest: vecs0
[495.902344] Dynamic subtest vecs0: SUCCESS (2.031s)
[495.913159] Starting dynamic subtest: ccs0
[497.021557] Dynamic subtest ccs0: SUCCESS (1.127s)
[497.032100] Starting dynamic subtest: all
[498.887247] Dynamic subtest all: SUCCESS (1.853s)
[498.888483] Subtest evict-prime: SUCCESS (11.260s)
[498.989239] [091/133] (525s left) gem_ctx_param (invalid-get-engines)
[499.029727] Starting subtest: invalid-get-engines
[499.032401] Subtest invalid-get-engines: SUCCESS (0.001s)
[499.122605] [092/133] (525s left) gem_exec_schedule (timeslicing)
[499.175136] Starting subtest: timeslicing
[499.176492] Starting dynamic subtest: rcs0
[499.203786] Dynamic subtest rcs0: SUCCESS (0.028s)
[499.219494] Starting dynamic subtest: bcs0
[499.232167] Dynamic subtest bcs0: SUCCESS (0.026s)
[499.242900] Starting dynamic subtest: vcs0
[499.254691] Dynamic subtest vcs0: SUCCESS (0.022s)
[499.267383] Starting dynamic subtest: vcs1
[499.279603] Dynamic subtest vcs1: SUCCESS (0.024s)
[499.289894] Starting dynamic subtest: vecs0
[499.303266] Dynamic subtest vecs0: SUCCESS (0.023s)
[499.311062] Starting dynamic subtest: ccs0
[499.328352] Dynamic subtest ccs0: SUCCESS (0.024s)
[499.329365] Subtest timeslicing: SUCCESS (0.153s)
[500.925629] [093/133] (523s left) kms_color_pipeline (plane-ctm3x4)
[501.019143] Starting subtest: plane-ctm3x4
[501.167602] Starting dynamic subtest: pipe-A-plane-0
[501.379080] Dynamic subtest pipe-A-plane-0: FAIL (0.150s)
[501.394495] Starting dynamic subtest: pipe-A-plane-1
[501.545357] Dynamic subtest pipe-A-plane-1: FAIL (0.152s)
[501.553956] Starting dynamic subtest: pipe-A-plane-2
[501.694221] Dynamic subtest pipe-A-plane-2: FAIL (0.149s)
[502.815840] Starting dynamic subtest: pipe-B-plane-0
[503.032449] Dynamic subtest pipe-B-plane-0: FAIL (0.130s)
[503.040923] Starting dynamic subtest: pipe-B-plane-1
[503.183783] Dynamic subtest pipe-B-plane-1: FAIL (0.144s)
[503.191593] Starting dynamic subtest: pipe-B-plane-2
[503.356841] Dynamic subtest pipe-B-plane-2: FAIL (0.166s)
[504.449318] Starting dynamic subtest: pipe-C-plane-0
[504.684735] Dynamic subtest pipe-C-plane-0: FAIL (0.107s)
[504.692714] Starting dynamic subtest: pipe-C-plane-1
[504.829135] Dynamic subtest pipe-C-plane-1: FAIL (0.132s)
[504.837231] Starting dynamic subtest: pipe-C-plane-2
[504.986043] Dynamic subtest pipe-C-plane-2: FAIL (0.132s)
[505.965957] Starting dynamic subtest: pipe-D-plane-0
[506.260915] Dynamic subtest pipe-D-plane-0: FAIL (0.140s)
[506.278253] Starting dynamic subtest: pipe-D-plane-1
[506.455476] Dynamic subtest pipe-D-plane-1: FAIL (0.133s)
[506.473649] Starting dynamic subtest: pipe-D-plane-2
[506.674704] Dynamic subtest pipe-D-plane-2: FAIL (0.335s)
[506.874909] Subtest plane-ctm3x4: FAIL (5.855s)
[507.738669] [094/133] (516s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[507.861717] Starting subtest: cursorA-vs-flipA-legacy
[518.981334] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.119s)
[519.793584] [095/133] (504s left) kms_cursor_crc (cursor-random-32x32)
[519.931557] Starting subtest: cursor-random-32x32
[519.977508] Subtest cursor-random-32x32: SKIP (0.044s)
[520.264050] [096/133] (504s left) sw_sync (alloc_merge_fence)
[520.295752] Starting subtest: alloc_merge_fence
[520.296818] Subtest alloc_merge_fence: SUCCESS (0.000s)
[520.353229] [097/133] (504s left) syncobj_wait (multi-wait-all-signaled)
[520.411281] Starting subtest: multi-wait-all-signaled
[520.412607] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[520.505590] [098/133] (504s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[520.551956] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[520.552907] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[520.651144] [099/133] (504s left) gem_render_tiled_blits (basic)
[520.712301] Subtest basic: SKIP (0.000s)
[520.781378] [100/133] (504s left) kms_pm_backlight (fade-with-suspend)
[520.876425] Starting subtest: fade-with-suspend
[536.954043] Starting dynamic subtest: eDP-1
[539.439373] Dynamic subtest eDP-1: SUCCESS (2.487s)
[539.440510] Subtest fade-with-suspend: SUCCESS (4.963s)
[543.324953] [101/133] (484s left) kms_cursor_crc (cursor-suspend)
[543.449079] Starting subtest: cursor-suspend
[543.451164] Starting dynamic subtest: pipe-A-eDP-1
[577.434857] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.877s)
[577.445089] Starting dynamic subtest: pipe-D-eDP-1
[612.426092] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.468s)
[612.427370] Subtest cursor-suspend: SUCCESS (8.348s)
[618.335826] [102/133] (414s left) gem_lmem_swapping (parallel-random-engines)
[618.379733] Subtest parallel-random-engines: SKIP (0.000s)
[618.421258] [103/133] (414s left) syncobj_timeline (invalid-query-bad-pad)
[618.474306] Starting subtest: invalid-query-bad-pad
[618.475198] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[618.574471] [104/133] (414s left) api_intel_bb (blit-noreloc-purge-cache)
[618.630377] Starting subtest: blit-noreloc-purge-cache
[618.646841] Subtest blit-noreloc-purge-cache: SUCCESS (0.017s)
[618.772874] [105/133] (414s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[618.860797] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[618.862542] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[618.975339] [106/133] (414s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[619.037371] Starting subtest: mixed-tiled-to-y-tiled-ccs
[619.039087] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[619.233334] [107/133] (413s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[619.328062] Starting subtest: psr-1p-primscrn-cur-indfb-move
[626.749793] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.354s)
[626.870170] [108/133] (406s left) kms_psr (fbc-psr-no-drrs)
[626.958134] Starting subtest: fbc-psr-no-drrs
[626.958958] Starting dynamic subtest: eDP-1
[626.960760] Dynamic subtest eDP-1: SKIP (0.000s)
[626.962455] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[627.027749] [109/133] (406s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[627.121301] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[627.123021] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[627.214421] [110/133] (406s left) kms_rotation_crc (bad-pixel-format)
[627.291087] Starting subtest: bad-pixel-format
[627.292800] Subtest bad-pixel-format: SKIP (0.001s)
[627.376887] [111/133] (405s left) kms_psr (fbc-pr-sprite-render)
[627.464693] Starting subtest: fbc-pr-sprite-render
[627.466428] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[627.533817] [112/133] (405s left) syncobj_timeline (wait-all-delayed-signal)
[627.577942] Starting subtest: wait-all-delayed-signal
[627.682188] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[627.771368] [113/133] (405s left) gem_ctx_persistence (legacy-engines-hang)
[627.818735] Starting subtest: legacy-engines-hang
[627.819678] Starting dynamic subtest: render
[627.928433] Dynamic subtest render: SUCCESS (0.108s)
[627.940937] Starting dynamic subtest: bsd1
[628.020531] Dynamic subtest bsd1: SUCCESS (0.088s)
[628.032150] Starting dynamic subtest: bsd2
[628.117561] Dynamic subtest bsd2: SUCCESS (0.093s)
[628.129131] Starting dynamic subtest: blt
[628.210680] Dynamic subtest blt: SUCCESS (0.090s)
[628.223291] Starting dynamic subtest: vebox
[628.300516] Dynamic subtest vebox: SUCCESS (0.087s)
[628.302262] Subtest legacy-engines-hang: SUCCESS (0.480s)
[628.377138] [114/133] (405s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[628.436584] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[628.542242] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.105s)
[628.614425] [115/133] (404s left) gem_exec_suspend (basic-s4-devices)
[628.679421] Starting subtest: basic-S4-devices
[628.680341] Starting dynamic subtest: smem
[639.963210] Dynamic subtest smem: SUCCESS (11.246s)
[639.964623] Subtest basic-S4-devices: SUCCESS (11.248s)
[645.193820] [116/133] (393s left) gem_busy (extended)
[645.392570] Starting subtest: extended
[645.393933] Starting dynamic subtest: rcs0
[645.479189] Dynamic subtest rcs0: SUCCESS (0.089s)
[645.491378] Starting dynamic subtest: bcs0
[645.523877] Dynamic subtest bcs0: SUCCESS (0.043s)
[645.536140] Starting dynamic subtest: vcs0
[647.002008] Dynamic subtest vcs0: SUCCESS (1.467s)
[647.017985] Starting dynamic subtest: vcs1
[648.986659] Dynamic subtest vcs1: SUCCESS (1.983s)
[648.996856] Starting dynamic subtest: vecs0
[651.035196] Dynamic subtest vecs0: SUCCESS (2.045s)
[651.044202] Starting dynamic subtest: ccs0
[651.072567] Dynamic subtest ccs0: SUCCESS (0.044s)
[651.074269] Subtest extended: SUCCESS (5.683s)
[651.282632] [117/133] (387s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[651.376112] Starting subtest: fbcpsr-tiling-4
[658.454219] Subtest fbcpsr-tiling-4: SUCCESS (7.076s)
[658.583847] [118/133] (380s left) kms_vblank (ts-continuation-modeset-hang)
[658.669267] Starting subtest: ts-continuation-modeset-hang
[658.670144] Starting dynamic subtest: pipe-A-eDP-1
[684.754374] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.079s)
[684.765403] Starting dynamic subtest: pipe-D-eDP-1
[699.864316] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.106s)
[699.865111] Subtest ts-continuation-modeset-hang: SUCCESS (41.190s)
[700.723691] [119/133] (337s left) gen9_exec_parse (secure-batches)
[700.780261] Subtest secure-batches: SKIP (0.000s)
[700.843507] [120/133] (337s left) kms_chamelium_edid (dp-edid-read)
[701.224290] Subtest dp-edid-read: SKIP (0.000s)
[702.029443] [121/133] (336s left) gem_userptr_blits (forbidden-operations)
[702.089723] Starting subtest: forbidden-operations
[702.091580] Subtest forbidden-operations: SKIP (0.000s)
[702.208270] [122/133] (336s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[702.294210] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[702.296044] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[702.404034] [123/133] (336s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[702.503796] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[702.505619] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[702.607774] [124/133] (336s left) gem_mmap_gtt (isolation)
[702.660113] Subtest isolation: SKIP (0.000s)
[702.712427] [125/133] (336s left) gem_mmap_gtt (cpuset-big-copy-xy)
[702.756537] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[702.811491] [126/133] (336s left) vgem_basic (setversion)
[702.848666] Starting subtest: setversion
[702.850560] Subtest setversion: SUCCESS (0.000s)
[702.902347] [127/133] (336s left) kms_plane_multiple (tiling-4)
[702.978886] Starting subtest: tiling-4
[702.979689] Starting dynamic subtest: pipe-A-eDP-1
[706.713891] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.734s)
[706.724554] Starting dynamic subtest: pipe-B-eDP-1
[711.084413] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.368s)
[711.096558] Starting dynamic subtest: pipe-C-eDP-1
[715.442896] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.356s)
[715.452268] Starting dynamic subtest: pipe-D-eDP-1
[719.766207] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.321s)
[719.767065] Subtest tiling-4: SUCCESS (16.784s)
[720.558032] [128/133] (318s left) kms_atomic_interruptible (legacy-pageflip)
[720.659344] Starting subtest: legacy-pageflip
[720.660280] Starting dynamic subtest: pipe-A-eDP-1
[728.091166] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.429s)
[728.092172] Subtest legacy-pageflip: SUCCESS (7.430s)
[728.954430] [129/133] (310s left) gem_ppgtt (blt-vs-render-ctxn)
[729.027191] Starting subtest: blt-vs-render-ctxN
[762.286105] Subtest blt-vs-render-ctxN: SUCCESS (33.237s)
[762.395557] [130/133] (276s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[762.539105] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[771.777018] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (9.235s)
[771.898315] [131/133] (267s left) gem_eio (in-flight-contexts-immediate)
[772.150388] Starting subtest: in-flight-contexts-immediate
[785.963010] Subtest in-flight-contexts-immediate: SUCCESS (13.808s)
[786.128550] [132/133] (253s left) perf (oa-formats)
[786.270151] Starting subtest: oa-formats
[786.270949] Starting dynamic subtest: 0-rcs0
[786.285073] Dynamic subtest 0-rcs0: SUCCESS (0.016s)
[786.295732] Starting dynamic subtest: 1-vcs1
[786.307454] Dynamic subtest 1-vcs1: SUCCESS (0.021s)
[786.308363] Subtest oa-formats: SUCCESS (0.039s)
[786.358367] [133/133] (252s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[786.407682] Starting subtest: multi-wait-all-for-submit-submitted
[786.509872] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.102s)
[786.579506] 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.