[61.330688] Initializing watchdogs
[61.330733]   /dev/watchdog0
[61.344107] [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.348209] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.350299] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.353486] [001/133] (960s left) gem_create (create-ext-placement-all)
[61.545319] Starting subtest: create-ext-placement-all
[61.547798] Subtest create-ext-placement-all: SUCCESS (0.000s)
[61.673651] [002/133] (960s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[61.746575] Starting subtest: map-fixed-invalidate-overlap
[61.750696] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[61.898991] [003/133] (960s left) gem_pipe_control_store_loop (fresh-buffer)
[61.961617] Starting subtest: fresh-buffer
[64.113673] Subtest fresh-buffer: SUCCESS (2.154s)
[64.273786] [004/133] (957s left) gem_mmap_offset (mmap-boundaries)
[64.331933] Starting subtest: mmap-boundaries
[64.334332] Starting dynamic subtest: smem0
[64.611935] Dynamic subtest smem0: SUCCESS (0.280s)
[64.614458] Subtest mmap-boundaries: SUCCESS (0.280s)
[64.763992] [005/133] (957s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[64.875247] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[72.439738] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.574s)
[72.616013] [006/133] (949s left) kms_atomic (atomic-plane-damage)
[72.721090] Starting subtest: atomic-plane-damage
[72.795962] Starting dynamic subtest: pipe-A-eDP-1
[73.325945] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.529s)
[73.328765] Subtest atomic-plane-damage: SUCCESS (0.605s)
[74.171196] [007/133] (947s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[74.279778] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[74.281960] Starting dynamic subtest: pipe-A-eDP-1
[74.342154] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.063s)
[74.349858] Starting dynamic subtest: pipe-B-eDP-1
[74.404042] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.062s)
[74.412160] Starting dynamic subtest: pipe-C-eDP-1
[74.463061] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.058s)
[74.470759] Starting dynamic subtest: pipe-D-eDP-1
[74.523763] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.059s)
[74.526112] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.246s)
[76.829751] [008/133] (945s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[76.932362] Starting subtest: psr-rgb101010-draw-mmap-wc
[83.925989] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (6.996s)
[84.072780] [009/133] (938s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[84.142756] Starting subtest: yf-tiled-to-vebox-x-tiled
[84.147671] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[84.349977] [010/133] (937s left) syncobj_timeline (wait-all-complex)
[84.407790] Starting subtest: wait-all-complex
[84.619641] Subtest wait-all-complex: SUCCESS (0.212s)
[84.713361] [011/133] (937s left) gem_pxp (create-valid-protected-context)
[84.844825] Starting subtest: create-valid-protected-context
[84.847401] Subtest create-valid-protected-context: SUCCESS (0.002s)
[84.990712] [012/133] (937s left) gem_exec_flush (basic-wb-rw-default)
[85.072746] Starting subtest: basic-wb-rw-default
[90.516997] Subtest basic-wb-rw-default: SUCCESS (5.445s)
[90.725518] [013/133] (931s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[90.828062] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[90.830197] Starting dynamic subtest: pipe-A-eDP-1
[90.849875] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[90.857754] Starting dynamic subtest: pipe-B-eDP-1
[90.876593] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[90.884752] Starting dynamic subtest: pipe-C-eDP-1
[90.903274] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[90.910251] Starting dynamic subtest: pipe-D-eDP-1
[90.928954] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[90.930855] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[93.120251] [014/133] (929s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[93.223149] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[97.322678] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.104s)
[98.519528] [015/133] (923s left) kms_cursor_edge_walk (256x256-top-bottom)
[98.613125] Starting subtest: 256x256-top-bottom
[98.615494] Starting dynamic subtest: pipe-A-eDP-1
[102.039526] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.427s)
[102.048252] Starting dynamic subtest: pipe-D-eDP-1
[105.170562] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.130s)
[105.172903] Subtest 256x256-top-bottom: SUCCESS (6.558s)
[106.007369] [016/133] (916s left) kms_force_connector_basic (force-load-detect)
[106.077289] Starting subtest: force-load-detect
[106.081856] Subtest force-load-detect: SKIP (0.000s)
[106.232428] [017/133] (916s left) syncobj_wait (invalid-signal-zero-handles)
[106.299222] Starting subtest: invalid-signal-zero-handles
[106.301622] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[106.437936] [018/133] (916s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[111.380989] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[111.383365] Starting dynamic subtest: pipe-A-eDP-1
[112.165356] Dynamic subtest pipe-A-eDP-1: SKIP (0.781s)
[112.173061] Starting dynamic subtest: pipe-B-eDP-1
[113.297179] Dynamic subtest pipe-B-eDP-1: SKIP (1.130s)
[113.301572] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.914s)
[114.497916] [019/133] (908s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[114.615193] Starting subtest: fbcpsr-rgb565-draw-render
[122.644914] Subtest fbcpsr-rgb565-draw-render: SUCCESS (8.031s)
[122.813307] [020/133] (899s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[122.910180] Starting subtest: primary-4-tiled-reflect-x-0
[129.979228] Subtest primary-4-tiled-reflect-x-0: SUCCESS (7.069s)
[131.213075] [021/133] (891s left) kms_setmode (clone-exclusive-crtc)
[131.269360] Starting subtest: clone-exclusive-crtc
[131.276170] Subtest clone-exclusive-crtc: SKIP (0.000s)
[131.417780] [022/133] (891s left) kms_addfb_basic (too-high)
[131.506488] Starting subtest: too-high
[131.508367] Subtest too-high: SUCCESS (0.001s)
[131.641250] [023/133] (891s left) perf_pmu (busy-accuracy-2)
[131.685389] Starting subtest: busy-accuracy-2
[131.687691] Starting dynamic subtest: rcs0
[134.722913] Dynamic subtest rcs0: SUCCESS (3.035s)
[134.733019] Starting dynamic subtest: bcs0
[137.779860] Dynamic subtest bcs0: SUCCESS (3.055s)
[137.790301] Starting dynamic subtest: vcs0
[140.823452] Dynamic subtest vcs0: SUCCESS (3.042s)
[140.834839] Starting dynamic subtest: vcs1
[143.869334] Dynamic subtest vcs1: SUCCESS (3.044s)
[143.880528] Starting dynamic subtest: vecs0
[146.915468] Dynamic subtest vecs0: SUCCESS (3.044s)
[146.925521] Starting dynamic subtest: ccs0
[149.970920] Dynamic subtest ccs0: SUCCESS (3.054s)
[149.973404] Subtest busy-accuracy-2: SUCCESS (18.284s)
[150.110894] [024/133] (872s left) kms_psr (pr-primary-mmap-gtt)
[150.217902] Starting subtest: pr-primary-mmap-gtt
[150.222298] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[150.320032] [025/133] (872s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[150.423058] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[150.427535] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[150.574517] [026/133] (872s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[150.686878] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[150.690695] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[150.799139] [027/133] (872s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[150.898325] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[150.900464] Starting dynamic subtest: pipe-A-default-mode
[153.667661] Dynamic subtest pipe-A-default-mode: SUCCESS (2.768s)
[153.669784] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.769s)
[154.513934] [028/133] (868s left) i915_drm_fdinfo (idle)
[154.559753] Starting subtest: idle
[154.561965] Starting dynamic subtest: rcs0
[155.152235] Dynamic subtest rcs0: SUCCESS (0.593s)
[155.161862] Starting dynamic subtest: bcs0
[155.658956] Dynamic subtest bcs0: SUCCESS (0.504s)
[155.669626] Starting dynamic subtest: vcs0
[156.164223] Dynamic subtest vcs0: SUCCESS (0.503s)
[156.174779] Starting dynamic subtest: vcs1
[156.669615] Dynamic subtest vcs1: SUCCESS (0.503s)
[156.678956] Starting dynamic subtest: vecs0
[157.174801] Dynamic subtest vecs0: SUCCESS (0.503s)
[157.185508] Starting dynamic subtest: ccs0
[157.680218] Dynamic subtest ccs0: SUCCESS (0.503s)
[157.683065] Subtest idle: SUCCESS (3.121s)
[157.776632] [029/133] (865s left) core_setmaster (master-drop-set-shared-fd)
[157.877321] Starting subtest: master-drop-set-shared-fd
[157.895615] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[157.933525] [030/133] (865s left) kms_chamelium_color (ctm-negative)
[158.036843] Subtest ctm-negative: SKIP (0.000s)
[158.076922] [031/133] (864s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[158.132217] Starting subtest: multi-wait-for-submit-available-submitted
[158.134569] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.000s)
[158.260969] [032/133] (864s left) syncobj_timeline (invalid-signal-illegal-point)
[158.313381] Starting subtest: invalid-signal-illegal-point
[158.315349] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[158.450139] [033/133] (864s left) gem_mmap (bad-offset)
[158.512225] Subtest bad-offset: SKIP (0.000s)
[158.577859] [034/133] (864s left) i915_hangman (gt-error-state-capture)
[158.640950] Starting subtest: gt-error-state-capture
[158.643307] Starting dynamic subtest: rcs0
[169.844533] Dynamic subtest rcs0: SUCCESS (11.202s)
[169.856825] Starting dynamic subtest: bcs0
[180.850319] Dynamic subtest bcs0: SUCCESS (11.003s)
[180.861460] Starting dynamic subtest: vcs0
[192.818667] Dynamic subtest vcs0: SUCCESS (11.966s)
[192.830033] Starting dynamic subtest: vcs1
[203.828074] Dynamic subtest vcs1: SUCCESS (11.007s)
[203.839199] Starting dynamic subtest: vecs0
[214.835090] Dynamic subtest vecs0: SUCCESS (11.005s)
[214.847086] Starting dynamic subtest: ccs0
[226.867752] Dynamic subtest ccs0: SUCCESS (12.030s)
[226.870503] Subtest gt-error-state-capture: SUCCESS (68.225s)
[226.968358] [035/133] (796s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[227.072876] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[227.075183] Starting dynamic subtest: pipe-A-eDP-1
[227.084153] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[227.096062] Starting dynamic subtest: pipe-B-eDP-1
[227.105265] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[227.117069] Starting dynamic subtest: pipe-C-eDP-1
[227.125726] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[227.135361] Starting dynamic subtest: pipe-D-eDP-1
[227.144068] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[227.148182] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[229.270184] [036/133] (793s left) gem_exec_whisper (basic-fds)
[229.358764] Starting subtest: basic-fds
[236.361946] Subtest basic-fds: SUCCESS (7.000s)
[236.569200] [037/133] (786s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[236.666056] Starting subtest: psr-1p-primscrn-cur-indfb-move
[244.009287] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.344s)
[244.201763] [038/133] (779s left) gem_exec_schedule (preempt-user)
[244.286266] Starting subtest: preempt-user
[244.288176] Starting dynamic subtest: rcs0
[244.301699] Dynamic subtest rcs0: SUCCESS (0.023s)
[244.313043] Starting dynamic subtest: bcs0
[244.319960] Dynamic subtest bcs0: SUCCESS (0.018s)
[244.328398] Starting dynamic subtest: vcs0
[244.338488] Dynamic subtest vcs0: SUCCESS (0.018s)
[244.346739] Starting dynamic subtest: vcs1
[244.357842] Dynamic subtest vcs1: SUCCESS (0.019s)
[244.366135] Starting dynamic subtest: vecs0
[244.378909] Dynamic subtest vecs0: SUCCESS (0.020s)
[244.387201] Starting dynamic subtest: ccs0
[244.400703] Dynamic subtest ccs0: SUCCESS (0.021s)
[244.402937] Subtest preempt-user: SUCCESS (0.122s)
[246.042091] [039/133] (777s left) kms_psr (psr2-primary-mmap-cpu)
[246.140544] Starting subtest: psr2-primary-mmap-cpu
[246.142683] Starting dynamic subtest: eDP-1
[247.853125] Dynamic subtest eDP-1: SUCCESS (1.711s)
[247.855359] Subtest psr2-primary-mmap-cpu: SUCCESS (1.712s)
[248.758870] [040/133] (774s left) gem_ctx_param (invalid-size-set)
[248.824602] Starting subtest: invalid-size-set
[248.826968] Subtest invalid-size-set: SUCCESS (0.001s)
[248.949169] [041/133] (774s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[249.052531] Starting subtest: bad-aux-stride-y-tiled-ccs
[249.054759] Starting dynamic subtest: pipe-A-eDP-1
[249.077060] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[249.086312] Starting dynamic subtest: pipe-B-eDP-1
[249.107504] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[249.115439] Starting dynamic subtest: pipe-C-eDP-1
[249.137322] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[249.145307] Starting dynamic subtest: pipe-D-eDP-1
[249.167456] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[249.171574] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s)
[251.228526] [042/133] (772s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[251.323614] Starting subtest: blocking-absolute-wf_vblank-interruptible
[251.325906] Starting dynamic subtest: A-eDP1
[260.069308] Dynamic subtest A-eDP1: SUCCESS (8.744s)
[260.080577] Starting dynamic subtest: B-eDP1
[268.795484] Dynamic subtest B-eDP1: SUCCESS (8.724s)
[268.805740] Starting dynamic subtest: C-eDP1
[277.513417] Dynamic subtest C-eDP1: SUCCESS (8.716s)
[277.522617] Starting dynamic subtest: D-eDP1
[286.239061] Dynamic subtest D-eDP1: SUCCESS (8.723s)
[286.241287] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.917s)
[287.466736] [043/133] (736s left) kms_psr2_su (page_flip-nv12)
[287.526193] Subtest page_flip-NV12: SKIP (0.000s)
[287.595066] [044/133] (735s left) gem_exec_balancer (sliced)
[287.650852] Starting subtest: sliced
[287.655542] Subtest sliced: SKIP (0.000s)
[287.980909] [045/133] (735s left) kms_plane_lowres (tiling-x)
[288.069635] Starting subtest: tiling-x
[288.071829] Starting dynamic subtest: pipe-A-eDP-1
[288.076155] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[288.383188] Starting dynamic subtest: pipe-B-eDP-1
[288.387639] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[288.396114] Starting dynamic subtest: pipe-C-eDP-1
[288.400939] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[288.411322] Starting dynamic subtest: pipe-D-eDP-1
[288.415683] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[288.420062] Subtest tiling-x: SKIP (0.311s)
[289.191046] [046/133] (734s left) kms_vblank (query-busy-hang)
[289.273749] Starting subtest: query-busy-hang
[289.275861] Starting dynamic subtest: pipe-A-eDP-1
[315.655048] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.380s)
[315.671943] Starting dynamic subtest: pipe-D-eDP-1
[340.687848] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.029s)
[340.690256] Subtest query-busy-hang: SUCCESS (51.414s)
[341.550129] [047/133] (682s left) gem_exec_balancer (hang)
[341.820303] Starting subtest: hang
[343.874322] Subtest hang: SUCCESS (2.054s)
[344.024144] [048/133] (679s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[344.132848] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[344.136710] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[344.274051] [049/133] (679s left) kms_flip (bo-too-big)
[344.379244] Starting subtest: bo-too-big
[344.381303] Starting dynamic subtest: A-eDP1
[345.672968] Dynamic subtest A-eDP1: SUCCESS (1.295s)
[345.689688] Starting dynamic subtest: B-eDP1
[347.010492] Dynamic subtest B-eDP1: SUCCESS (1.335s)
[347.021362] Starting dynamic subtest: C-eDP1
[348.343511] Dynamic subtest C-eDP1: SUCCESS (1.332s)
[348.360071] Starting dynamic subtest: D-eDP1
[349.688109] Dynamic subtest D-eDP1: SUCCESS (1.342s)
[349.690215] Subtest bo-too-big: SUCCESS (5.314s)
[350.925127] [050/133] (672s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[350.985977] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[350.990138] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[351.190146] [051/133] (672s left) kms_cursor_legacy (single-bo)
[351.304363] Starting subtest: single-bo
[351.306519] Starting dynamic subtest: pipe-A
[356.687763] Dynamic subtest pipe-A: SUCCESS (5.379s)
[356.698762] Starting dynamic subtest: pipe-B
[362.069387] Dynamic subtest pipe-B: SUCCESS (5.379s)
[362.079483] Starting dynamic subtest: pipe-C
[367.450985] Dynamic subtest pipe-C: SUCCESS (5.378s)
[367.461857] Starting dynamic subtest: pipe-D
[372.832789] Dynamic subtest pipe-D: SUCCESS (5.379s)
[372.843690] Starting dynamic subtest: all-pipes
[378.220924] Dynamic subtest all-pipes: SUCCESS (5.386s)
[378.223491] Subtest single-bo: SUCCESS (26.914s)
[378.387010] [052/133] (645s left) kms_pm_rpm (basic-pci-d3-state)
[380.119202] Starting subtest: basic-pci-d3-state
[381.579816] Subtest basic-pci-d3-state: SUCCESS (1.460s)
[381.691274] [053/133] (642s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[381.793582] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[386.153208] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.363s)
[387.361471] [054/133] (636s left) kms_pm_rpm (legacy-planes)
[389.096034] Starting subtest: legacy-planes
[389.098641] Starting dynamic subtest: plane-63
[397.698223] Dynamic subtest plane-63: SUCCESS (8.605s)
[397.715717] Starting dynamic subtest: plane-93
[406.039789] Dynamic subtest plane-93: SUCCESS (8.339s)
[406.050679] Starting dynamic subtest: plane-123
[414.643231] Dynamic subtest plane-123: SUCCESS (8.600s)
[414.654623] Starting dynamic subtest: plane-133
[422.919261] Dynamic subtest plane-133: SUCCESS (8.273s)
[422.922237] Subtest legacy-planes: SUCCESS (33.828s)
[423.320072] [055/133] (600s left) prime_self_import (basic-with_one_bo)
[423.357330] Starting subtest: basic-with_one_bo
[423.376921] Subtest basic-with_one_bo: SUCCESS (0.022s)
[423.511235] [056/133] (600s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[423.611383] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[423.615716] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[423.735211] [057/133] (600s left) kms_flip (modeset-vs-vblank-race)
[423.826419] Starting subtest: modeset-vs-vblank-race
[423.828783] Starting dynamic subtest: A-eDP1
[428.497816] Dynamic subtest A-eDP1: SUCCESS (4.671s)
[428.510454] Starting dynamic subtest: B-eDP1
[433.216924] Dynamic subtest B-eDP1: SUCCESS (4.717s)
[433.228207] Starting dynamic subtest: C-eDP1
[437.841197] Dynamic subtest C-eDP1: SUCCESS (4.622s)
[437.853939] Starting dynamic subtest: D-eDP1
[442.407721] Dynamic subtest D-eDP1: SUCCESS (4.562s)
[442.413458] Subtest modeset-vs-vblank-race: SUCCESS (18.588s)
[443.681280] [058/133] (580s left) gem_lmem_swapping (heavy-verify-random-ccs)
[443.722923] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[443.756431] [059/133] (580s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[443.854071] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[443.857941] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[443.983350] [060/133] (580s left) kms_chamelium_color (gamma)
[444.077950] Subtest gamma: SKIP (0.000s)
[444.120280] [061/133] (580s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[444.248627] Starting subtest: cursorB-vs-flipA-varying-size
[444.253166] Subtest cursorB-vs-flipA-varying-size: SKIP (0.002s)
[444.428607] [062/133] (579s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[444.533304] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[448.967282] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.437s)
[450.179324] [063/133] (574s left) dumb_buffer (map-valid)
[450.240046] Starting subtest: map-valid
[450.242308] Subtest map-valid: SUCCESS (0.000s)
[450.368535] [064/133] (573s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[450.459468] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[450.461793] Starting dynamic subtest: pipe-A-default-mode
[450.780027] Dynamic subtest pipe-A-default-mode: SKIP (0.318s)
[450.784568] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.320s)
[451.608956] [065/133] (572s left) perf (i915-ref-count)
[451.702055] Starting subtest: i915-ref-count
[451.775678] Subtest i915-ref-count: SUCCESS (0.071s)
[451.832613] [066/133] (572s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[451.933392] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[451.937656] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[452.037779] [067/133] (572s left) gem_softpin (noreloc-interruptible)
[452.099044] Starting subtest: noreloc-interruptible
[457.516298] Subtest noreloc-interruptible: SUCCESS (5.415s)
[457.687205] [068/133] (566s left) kms_psr (fbc-psr2-primary-render)
[457.794123] Starting subtest: fbc-psr2-primary-render
[457.796342] Starting dynamic subtest: eDP-1
[457.800442] Dynamic subtest eDP-1: SKIP (0.000s)
[457.804553] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[457.887181] [069/133] (566s left) kms_lease (atomic-implicit-crtc)
[457.990681] Starting subtest: atomic-implicit-crtc
[457.993192] Starting dynamic subtest: pipe-A-eDP-1
[458.004319] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[458.014392] Starting dynamic subtest: pipe-B-eDP-1
[458.025084] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.009s)
[458.033151] Starting dynamic subtest: pipe-C-eDP-1
[458.043137] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s)
[458.051116] Starting dynamic subtest: pipe-D-eDP-1
[458.061066] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.009s)
[458.062842] Subtest atomic-implicit-crtc: SUCCESS (0.035s)
[458.167959] [070/133] (566s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[458.262069] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[458.632924] Starting dynamic subtest: pipe-A-eDP-1
[461.358210] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.724s)
[461.724944] Starting dynamic subtest: pipe-B-eDP-1
[464.484013] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.765s)
[464.860126] Starting dynamic subtest: pipe-C-eDP-1
[467.610079] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.755s)
[467.976175] Starting dynamic subtest: pipe-D-eDP-1
[470.732807] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.762s)
[470.735431] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (12.472s)
[471.975474] [071/133] (552s left) gem_exec_reloc (basic-wc-read-active)
[472.054027] Subtest basic-wc-read-active: SKIP (0.000s)
[472.120446] [072/133] (552s left) gem_readwrite (new-obj)
[472.181236] Subtest new-obj: SKIP (0.000s)
[472.245921] [073/133] (552s left) gem_mmap_gtt (basic-short)
[472.309504] Subtest basic-short: SKIP (0.000s)
[472.372564] [074/133] (552s left) kms_plane_multiple (tiling-yf)
[472.459205] Starting subtest: tiling-yf
[472.463304] Subtest tiling-yf: SKIP (0.000s)
[472.549627] [075/133] (552s left) kms_content_protection (dp-mst-type-0-hdcp14)
[472.700554] Starting subtest: dp-mst-type-0-hdcp14
[472.705132] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[472.863106] [076/133] (551s left) gem_ctx_shared (q-in-order)
[472.937819] Starting subtest: Q-in-order
[472.939573] Starting dynamic subtest: rcs0
[473.080871] Dynamic subtest rcs0: SUCCESS (0.144s)
[473.096305] Starting dynamic subtest: vcs0
[473.158882] Dynamic subtest vcs0: SUCCESS (0.077s)
[473.174689] Starting dynamic subtest: vcs1
[473.231279] Dynamic subtest vcs1: SUCCESS (0.071s)
[473.246870] Starting dynamic subtest: vecs0
[473.306845] Dynamic subtest vecs0: SUCCESS (0.075s)
[473.322882] Starting dynamic subtest: ccs0
[473.396440] Dynamic subtest ccs0: SUCCESS (0.088s)
[473.399287] Subtest Q-in-order: SUCCESS (0.460s)
[475.037884] [FACT gem_ctx_shared (q-in-order)] new: kernel.kmod_is_loaded.vgem: true
[475.041527] [077/133] (549s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[475.100778] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[475.207076] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.106s)
[475.308580] [078/133] (549s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[475.414594] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[482.003222] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.590s)
[482.163017] [079/133] (542s left) kms_big_fb (linear-8bpp-rotate-270)
[482.262237] Starting subtest: linear-8bpp-rotate-270
[482.282628] Subtest linear-8bpp-rotate-270: SKIP (0.020s)
[482.453232] [080/133] (542s left) api_intel_allocator (standalone)
[482.513755] Starting subtest: standalone
[482.530058] Subtest standalone: SUCCESS (0.019s)
[482.687148] [081/133] (542s left) core_auth (getclient-master-drop)
[482.726848] Starting subtest: getclient-master-drop
[482.734725] Subtest getclient-master-drop: SUCCESS (0.014s)
[482.885691] [082/133] (542s left) kms_psr (fbc-psr2-basic)
[482.986298] Starting subtest: fbc-psr2-basic
[482.988632] Starting dynamic subtest: eDP-1
[482.993121] Dynamic subtest eDP-1: SKIP (0.000s)
[482.997628] Subtest fbc-psr2-basic: SKIP (0.001s)
[483.100370] [083/133] (542s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[483.240725] Starting subtest: cursor-rapid-movement-32x32
[483.288030] Subtest cursor-rapid-movement-32x32: SKIP (0.042s)
[483.615618] [084/133] (541s left) gem_exec_schedule (noreorder-priority)
[483.801279] Starting subtest: noreorder-priority
[483.803880] Starting dynamic subtest: rcs0
[484.948236] Dynamic subtest rcs0: SUCCESS (1.147s)
[484.961535] Starting dynamic subtest: vcs0
[486.951374] Dynamic subtest vcs0: SUCCESS (2.000s)
[486.967430] Starting dynamic subtest: vcs1
[488.898696] Dynamic subtest vcs1: SUCCESS (1.945s)
[488.915847] Starting dynamic subtest: vecs0
[490.935871] Dynamic subtest vecs0: SUCCESS (2.034s)
[490.947979] Starting dynamic subtest: ccs0
[492.083453] Dynamic subtest ccs0: SUCCESS (1.147s)
[492.086375] Subtest noreorder-priority: SUCCESS (8.284s)
[492.309949] [085/133] (532s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[492.417032] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[492.419358] Starting dynamic subtest: pipe-A-eDP-1
[492.444684] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[492.454745] Starting dynamic subtest: pipe-B-eDP-1
[492.478622] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[492.486872] Starting dynamic subtest: pipe-C-eDP-1
[492.510369] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[492.518636] Starting dynamic subtest: pipe-D-eDP-1
[492.542445] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[492.546550] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[494.715507] [086/133] (530s left) core_auth (many-magics)
[494.772595] Starting subtest: many-magics
[495.631439] Subtest many-magics: SUCCESS (0.862s)
[495.896875] [087/133] (529s left) i915_suspend (fence-restore-tiled2untiled)
[495.961860] Starting subtest: fence-restore-tiled2untiled
[495.965818] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[496.096801] [088/133] (529s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[496.196709] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[496.198959] Starting dynamic subtest: pipe-A
[496.217714] Dynamic subtest pipe-A: SKIP (0.017s)
[496.226972] Starting dynamic subtest: pipe-B
[496.248381] Dynamic subtest pipe-B: SKIP (0.030s)
[496.256864] Starting dynamic subtest: pipe-C
[496.278114] Dynamic subtest pipe-C: SKIP (0.030s)
[496.286835] Starting dynamic subtest: pipe-D
[496.310184] Dynamic subtest pipe-D: SKIP (0.030s)
[496.313779] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.111s)
[496.451056] [089/133] (528s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[496.550687] Starting subtest: y-tiled-64bpp-rotate-0
[496.555080] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[496.678552] [090/133] (528s left) gem_ctx_persistence (saturated-hostile)
[496.737158] Starting subtest: saturated-hostile
[496.739396] Starting dynamic subtest: rcs0
[497.564635] Dynamic subtest rcs0: SUCCESS (0.827s)
[497.577075] Starting dynamic subtest: bcs0
[498.380601] Dynamic subtest bcs0: SUCCESS (0.814s)
[498.391381] Starting dynamic subtest: vcs0
[499.200522] Dynamic subtest vcs0: SUCCESS (0.818s)
[499.211626] Starting dynamic subtest: vcs1
[500.025475] Dynamic subtest vcs1: SUCCESS (0.823s)
[500.037089] Starting dynamic subtest: vecs0
[500.852053] Dynamic subtest vecs0: SUCCESS (0.825s)
[500.863183] Starting dynamic subtest: ccs0
[501.672450] Dynamic subtest ccs0: SUCCESS (0.819s)
[501.675090] Subtest saturated-hostile: SUCCESS (4.936s)
[501.775102] [091/133] (523s left) kms_prop_blob (invalid-get-prop)
[501.830806] Starting subtest: invalid-get-prop
[501.833169] Subtest invalid-get-prop: SUCCESS (0.000s)
[501.976250] [092/133] (523s left) gem_mmap_gtt (basic-write)
[502.037584] Subtest basic-write: SKIP (0.000s)
[502.110530] [093/133] (523s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[502.243445] Starting subtest: cursorA-vs-flipA-varying-size
[513.380717] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.138s)
[514.253809] [094/133] (511s left) gem_linear_blits (interruptible)
[514.628687] Starting subtest: interruptible
[518.940039] Subtest interruptible: SUCCESS (4.312s)
[519.142295] [095/133] (506s left) gem_exec_params (sol-reset-invalid)
[519.243457] Starting subtest: sol-reset-invalid
[519.245895] Subtest sol-reset-invalid: SUCCESS (0.003s)
[521.036578] [096/133] (504s left) kms_color_pipeline (plane-lut1d)
[521.147755] Starting subtest: plane-lut1d
[521.160754] Starting dynamic subtest: pipe-A-eDP-1
[521.856195] Dynamic subtest pipe-A-eDP-1: FAIL (0.310s)
[522.514227] Starting dynamic subtest: pipe-B-eDP-1
[523.305029] Dynamic subtest pipe-B-eDP-1: FAIL (0.279s)
[523.841708] Starting dynamic subtest: pipe-C-eDP-1
[524.682188] Dynamic subtest pipe-C-eDP-1: FAIL (0.257s)
[525.169440] Starting dynamic subtest: pipe-D-eDP-1
[526.010745] Dynamic subtest pipe-D-eDP-1: FAIL (0.257s)
[526.012781] Subtest plane-lut1d: FAIL (4.562s)
[526.598822] [097/133] (499s left) gem_wait (busy)
[526.670576] Starting subtest: busy
[526.672506] Starting dynamic subtest: all-engines
[527.892063] Dynamic subtest all-engines: SUCCESS (1.223s)
[527.904214] Starting dynamic subtest: rcs0
[528.433698] Dynamic subtest rcs0: SUCCESS (0.540s)
[528.444740] Starting dynamic subtest: bcs0
[528.972755] Dynamic subtest bcs0: SUCCESS (0.538s)
[528.984145] Starting dynamic subtest: vcs0
[530.900308] Dynamic subtest vcs0: SUCCESS (1.925s)
[530.911705] Starting dynamic subtest: vcs1
[532.884567] Dynamic subtest vcs1: SUCCESS (1.981s)
[532.896417] Starting dynamic subtest: vecs0
[534.932781] Dynamic subtest vecs0: SUCCESS (2.045s)
[534.944495] Starting dynamic subtest: ccs0
[535.475048] Dynamic subtest ccs0: SUCCESS (0.541s)
[535.477486] Subtest busy: SUCCESS (8.807s)
[535.701718] [098/133] (490s left) sriov_basic (enable-vfs-autoprobe-on)
[535.774698] Starting subtest: enable-vfs-autoprobe-on
[535.777053] Starting dynamic subtest: numvfs-1
[535.851261] Dynamic subtest numvfs-1: FAIL (0.005s)
[535.861841] Starting dynamic subtest: numvfs-2
[535.938160] Dynamic subtest numvfs-2: FAIL (0.004s)
[535.948818] Starting dynamic subtest: numvfs-3
[536.021527] Dynamic subtest numvfs-3: FAIL (0.004s)
[536.031544] Starting dynamic subtest: numvfs-4
[536.106297] Dynamic subtest numvfs-4: FAIL (0.003s)
[536.116628] Starting dynamic subtest: numvfs-5
[536.190642] Dynamic subtest numvfs-5: FAIL (0.004s)
[536.201401] Starting dynamic subtest: numvfs-6
[536.274329] Dynamic subtest numvfs-6: FAIL (0.004s)
[536.283791] Starting dynamic subtest: numvfs-7
[536.356776] Dynamic subtest numvfs-7: FAIL (0.004s)
[536.367675] Starting dynamic subtest: numvfs-random
[536.438870] Dynamic subtest numvfs-random: FAIL (0.004s)
[536.449031] Starting dynamic subtest: numvfs-all
[536.521410] Dynamic subtest numvfs-all: FAIL (0.519s)
[536.523724] Subtest enable-vfs-autoprobe-on: FAIL (0.558s)
[536.561808] [099/133] (489s left) kms_chamelium_frames (vga-frame-dump)
[536.957461] Subtest vga-frame-dump: SKIP (0.000s)
[537.757986] [100/133] (488s left) gem_eio (throttle)
[537.970927] Starting subtest: throttle
[538.241420] Subtest throttle: SUCCESS (0.267s)
[538.419418] [101/133] (487s left) kms_frontbuffer_tracking (psr-abgr161616f-draw-mmap-wc)
[538.526095] Starting subtest: psr-abgr161616f-draw-mmap-wc
[538.530257] Subtest psr-abgr161616f-draw-mmap-wc: SKIP (0.000s)
[538.652311] [102/133] (487s left) gem_softpin (allocator-nopin)
[538.711534] Starting subtest: allocator-nopin
[538.799077] Subtest allocator-nopin: SUCCESS (0.087s)
[538.957067] [103/133] (487s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[539.049865] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[539.729940] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.681s)
[539.936785] [104/133] (486s left) kms_flip (busy-flip)
[540.041389] Starting subtest: busy-flip
[540.043254] Starting dynamic subtest: A-eDP1
[541.716244] Dynamic subtest A-eDP1: SUCCESS (1.676s)
[541.727705] Starting dynamic subtest: B-eDP1
[543.358519] Dynamic subtest B-eDP1: SUCCESS (1.639s)
[543.371222] Starting dynamic subtest: C-eDP1
[544.983792] Dynamic subtest C-eDP1: SUCCESS (1.624s)
[544.994177] Starting dynamic subtest: D-eDP1
[546.623774] Dynamic subtest D-eDP1: SUCCESS (1.639s)
[546.626055] Subtest busy-flip: SUCCESS (6.588s)
[547.823499] [105/133] (478s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[547.917174] Starting subtest: x-tiled-16bpp-rotate-270
[547.935857] Subtest x-tiled-16bpp-rotate-270: SKIP (0.018s)
[548.073588] [106/133] (478s left) kms_frontbuffer_tracking (fbcpsr-abgr161616f-draw-render)
[548.170479] Starting subtest: fbcpsr-abgr161616f-draw-render
[548.174633] Subtest fbcpsr-abgr161616f-draw-render: SKIP (0.000s)
[548.296571] [107/133] (477s left) kms_getfb (getfb2-accept-nv12)
[548.386843] Starting subtest: getfb2-accept-nv12
[548.389170] Subtest getfb2-accept-nv12: SUCCESS (0.001s)
[548.517450] [108/133] (477s left) gem_ctx_exec (basic-nohangcheck)
[548.575418] Starting subtest: basic-nohangcheck
[548.821825] Subtest basic-nohangcheck: SUCCESS (0.248s)
[548.984338] [109/133] (477s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[549.110607] Starting subtest: short-flip-before-cursor-atomic-transitions
[550.058799] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.948s)
[550.966680] [110/133] (475s left) kms_cursor_crc (cursor-offscreen-256x256)
[551.200720] Starting subtest: cursor-offscreen-256x256
[551.206095] Starting dynamic subtest: pipe-A-eDP-1
[553.132265] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.926s)
[553.158593] Starting dynamic subtest: pipe-D-eDP-1
[555.752822] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.600s)
[555.755207] Subtest cursor-offscreen-256x256: SUCCESS (4.551s)
[556.627468] [111/133] (469s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[556.732340] Starting subtest: 4-tiled-8bpp-rotate-0
[557.945920] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.214s)
[558.105868] [112/133] (468s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[558.177151] Subtest writes-after-reads: SKIP (0.000s)
[558.263187] [113/133] (468s left) kms_frontbuffer_tracking (psr-slowdraw)
[558.363928] Starting subtest: psr-slowdraw
[583.519412] Subtest psr-slowdraw: SUCCESS (25.155s)
[583.692734] [114/133] (442s left) i915_pm_rc6_residency (rc6-idle)
[583.762302] Starting subtest: rc6-idle
[583.794232] Starting dynamic subtest: gt0-rcs0
[608.069636] Dynamic subtest gt0-rcs0: SUCCESS (24.274s)
[608.081182] Starting dynamic subtest: gt0-bcs0
[632.423705] Dynamic subtest gt0-bcs0: SUCCESS (24.351s)
[632.432672] Starting dynamic subtest: gt0-ccs0
[656.692048] Dynamic subtest gt0-ccs0: SUCCESS (24.265s)
[656.703936] Starting dynamic subtest: gt1-vcs0
[662.866092] Dynamic subtest gt1-vcs0: SUCCESS (6.169s)
[662.878456] Starting dynamic subtest: gt1-vecs0
[669.037085] Dynamic subtest gt1-vecs0: SUCCESS (6.168s)
[669.039956] Subtest rc6-idle: SUCCESS (85.274s)
[669.141286] [115/133] (357s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[669.241580] Starting subtest: fbc-modesetfrombusy
[673.134861] Subtest fbc-modesetfrombusy: SUCCESS (3.897s)
[674.330199] [116/133] (352s left) kms_psr (psr-cursor-render)
[674.422339] Starting subtest: psr-cursor-render
[674.424492] Starting dynamic subtest: eDP-1
[676.188143] Dynamic subtest eDP-1: SUCCESS (1.766s)
[676.190566] Subtest psr-cursor-render: SUCCESS (1.767s)
[677.083333] [117/133] (349s left) gem_exec_parallel (engines)
[677.143330] Starting subtest: engines
[677.145649] Starting dynamic subtest: basic
[678.868206] Dynamic subtest basic: SUCCESS (1.723s)
[678.880345] Starting dynamic subtest: contexts
[683.363321] Dynamic subtest contexts: SUCCESS (4.493s)
[683.374120] Starting dynamic subtest: fds
[687.213224] Dynamic subtest fds: SUCCESS (3.847s)
[687.222674] Starting dynamic subtest: userptr
[688.917111] Dynamic subtest userptr: SUCCESS (1.702s)
[688.919764] Subtest engines: SUCCESS (11.773s)
[689.131294] [118/133] (337s left) prime_busy (after)
[689.201827] Starting subtest: after
[689.207440] Starting dynamic subtest: rcs0
[689.252201] Dynamic subtest rcs0: SUCCESS (0.046s)
[689.263730] Starting dynamic subtest: bcs0
[689.329405] Dynamic subtest bcs0: SUCCESS (0.075s)
[689.340863] Starting dynamic subtest: vcs0
[689.371232] Dynamic subtest vcs0: SUCCESS (0.041s)
[689.380813] Starting dynamic subtest: vcs1
[689.413211] Dynamic subtest vcs1: SUCCESS (0.041s)
[689.424078] Starting dynamic subtest: vecs0
[689.454145] Dynamic subtest vecs0: SUCCESS (0.040s)
[689.468303] Starting dynamic subtest: ccs0
[689.497066] Dynamic subtest ccs0: SUCCESS (0.040s)
[689.605218] Subtest after: SUCCESS (0.401s)
[689.704996] [119/133] (336s left) gem_partial_pwrite_pread (write-display)
[689.776691] Subtest write-display: SKIP (0.000s)
[689.872607] [120/133] (336s left) gem_tiled_blits (basic)
[689.934204] Subtest basic: SKIP (0.000s)
[690.013301] [121/133] (336s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[690.133638] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[698.688299] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.568s)
[698.814145] [122/133] (327s left) sysfs_defaults (readonly)
[698.875176] Starting subtest: readonly
[698.877403] Starting dynamic subtest: vecs0
[698.879682] Dynamic subtest vecs0: SUCCESS (0.000s)
[698.888594] Starting dynamic subtest: ccs0
[698.890497] Dynamic subtest ccs0: SUCCESS (0.000s)
[698.898751] Starting dynamic subtest: vcs0
[698.900670] Dynamic subtest vcs0: SUCCESS (0.000s)
[698.908964] Starting dynamic subtest: bcs0
[698.910884] Dynamic subtest bcs0: SUCCESS (0.000s)
[698.919632] Starting dynamic subtest: rcs0
[698.921874] Dynamic subtest rcs0: SUCCESS (0.000s)
[698.930761] Starting dynamic subtest: vcs1
[698.932670] Dynamic subtest vcs1: SUCCESS (0.000s)
[698.934565] Subtest readonly: SUCCESS (0.005s)
[698.996354] [123/133] (327s left) kms_dsc (dsc-with-formats)
[699.091867] Starting subtest: dsc-with-formats
[699.130843] Subtest dsc-with-formats: SKIP (0.001s)
[699.185166] [124/133] (327s left) prime_mmap (test_reprime)
[699.247180] Starting subtest: test_reprime
[699.249088] Starting dynamic subtest: test_reprime-smem
[699.273433] Dynamic subtest test_reprime-smem: SUCCESS (0.029s)
[699.275813] Subtest test_reprime: SUCCESS (0.029s)
[699.404129] [125/133] (327s left) gem_exec_params (rsvd2-dirt)
[699.460273] Starting subtest: rsvd2-dirt
[699.464545] Subtest rsvd2-dirt: SKIP (0.000s)
[699.592730] [126/133] (327s left) kms_lease (lessee-list)
[699.684350] Starting subtest: lessee-list
[699.686638] Starting dynamic subtest: pipe-A-eDP-1
[699.688872] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[699.700299] Starting dynamic subtest: pipe-B-eDP-1
[699.701812] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[699.710492] Starting dynamic subtest: pipe-C-eDP-1
[699.712086] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[699.720089] Starting dynamic subtest: pipe-D-eDP-1
[699.721693] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[699.723215] Subtest lessee-list: SUCCESS (0.008s)
[699.819123] [127/133] (327s left) kms_vrr (flip-dpms)
[699.912055] Starting subtest: flip-dpms
[699.917159] Subtest flip-dpms: SKIP (0.000s)
[700.009418] [128/133] (327s left) kms_frontbuffer_tracking (fbc-1p-rte)
[700.102370] Starting subtest: fbc-1p-rte
[705.560966] Subtest fbc-1p-rte: SUCCESS (5.461s)
[706.788299] [129/133] (320s left) gen9_exec_parse (batch-zero-length)
[706.854115] Subtest batch-zero-length: SKIP (0.000s)
[706.933782] [130/133] (320s left) prime_mmap (test_aperture_limit)
[706.997363] Starting subtest: test_aperture_limit
[706.999262] Starting dynamic subtest: test_aperture_limit-smem
[707.004263] Dynamic subtest test_aperture_limit-smem: SKIP (0.007s)
[707.008613] Subtest test_aperture_limit: SKIP (0.008s)
[707.140630] [131/133] (320s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[707.247399] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[707.251609] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[707.374402] [132/133] (319s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[707.470200] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[707.474185] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[707.604275] [133/133] (319s left) gem_exec_fence (long-history)
[708.078954] Starting subtest: long-history
[715.639275] Subtest long-history: SUCCESS (7.562s)
[716.107983] Closing watchdogs
results: parsing output: 0/ for test: gem_create
results: parsing output: 1/ for test: gem_userptr_blits
results: parsing output: 2/ for test: gem_pipe_control_store_loop
results: parsing output: 3/ for test: gem_mmap_offset
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_atomic
results: parsing output: 6/ for test: kms_ccs
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: gem_render_copy
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: gem_pxp
results: parsing output: 11/ for test: gem_exec_flush
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_cursor_edge_walk
results: parsing output: 15/ for test: kms_force_connector_basic
results: parsing output: 16/ for test: syncobj_wait
results: parsing output: 17/ for test: kms_psr2_sf
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_rotation_crc
results: parsing output: 20/ for test: kms_setmode
results: parsing output: 21/ for test: kms_addfb_basic
results: parsing output: 22/ for test: perf_pmu
results: parsing output: 23/ for test: kms_psr
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_flip_scaled_crc
results: parsing output: 27/ for test: i915_drm_fdinfo
results: parsing output: 28/ for test: core_setmaster
results: parsing output: 29/ for test: kms_chamelium_color
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: syncobj_timeline
results: parsing output: 32/ for test: gem_mmap
results: parsing output: 33/ for test: i915_hangman
results: parsing output: 34/ for test: kms_ccs
results: parsing output: 35/ for test: gem_exec_whisper
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_exec_schedule
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: gem_ctx_param
results: parsing output: 40/ for test: kms_ccs
results: parsing output: 41/ for test: kms_flip
results: parsing output: 42/ for test: kms_psr2_su
results: parsing output: 43/ for test: gem_exec_balancer
results: parsing output: 44/ for test: kms_plane_lowres
results: parsing output: 45/ for test: kms_vblank
results: parsing output: 46/ for test: gem_exec_balancer
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_flip
results: parsing output: 49/ for test: gem_render_copy
results: parsing output: 50/ for test: kms_cursor_legacy
results: parsing output: 51/ for test: kms_pm_rpm
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_pm_rpm
results: parsing output: 54/ for test: prime_self_import
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_flip
results: parsing output: 57/ for test: gem_lmem_swapping
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_chamelium_color
results: parsing output: 60/ for test: kms_cursor_legacy
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: dumb_buffer
results: parsing output: 63/ for test: kms_flip_scaled_crc
results: parsing output: 64/ for test: perf
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_softpin
results: parsing output: 67/ for test: kms_psr
results: parsing output: 68/ for test: kms_lease
results: parsing output: 69/ for test: kms_async_flips
results: parsing output: 70/ for test: gem_exec_reloc
results: parsing output: 71/ for test: gem_readwrite
results: parsing output: 72/ for test: gem_mmap_gtt
results: parsing output: 73/ for test: kms_plane_multiple
results: parsing output: 74/ for test: kms_content_protection
results: parsing output: 75/ for test: gem_ctx_shared
results: parsing output: 76/ for test: syncobj_timeline
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: api_intel_allocator
results: parsing output: 80/ for test: core_auth
results: parsing output: 81/ for test: kms_psr
results: parsing output: 82/ for test: kms_cursor_crc
results: parsing output: 83/ for test: gem_exec_schedule
results: parsing output: 84/ for test: kms_ccs
results: parsing output: 85/ for test: core_auth
results: parsing output: 86/ for test: i915_suspend
results: parsing output: 87/ for test: kms_plane_scaling
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: gem_ctx_persistence
results: parsing output: 90/ for test: kms_prop_blob
results: parsing output: 91/ for test: gem_mmap_gtt
results: parsing output: 92/ for test: kms_cursor_legacy
results: parsing output: 93/ for test: gem_linear_blits
results: parsing output: 94/ for test: gem_exec_params
results: parsing output: 95/ for test: kms_color_pipeline
results: parsing output: 96/ for test: gem_wait
results: parsing output: 97/ for test: sriov_basic
results: parsing output: 98/ for test: kms_chamelium_frames
results: parsing output: 99/ for test: gem_eio
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: gem_softpin
results: parsing output: 102/ for test: kms_big_fb
results: parsing output: 103/ for test: kms_flip
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_getfb
results: parsing output: 107/ for test: gem_ctx_exec
results: parsing output: 108/ for test: kms_cursor_legacy
results: parsing output: 109/ for test: kms_cursor_crc
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: i915_pm_rc6_residency
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_psr
results: parsing output: 116/ for test: gem_exec_parallel
results: parsing output: 117/ for test: prime_busy
results: parsing output: 118/ for test: gem_partial_pwrite_pread
results: parsing output: 119/ for test: gem_tiled_blits
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: sysfs_defaults
results: parsing output: 122/ for test: kms_dsc
results: parsing output: 123/ for test: prime_mmap
results: parsing output: 124/ for test: gem_exec_params
results: parsing output: 125/ for test: kms_lease
results: parsing output: 126/ for test: kms_vrr
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: gen9_exec_parse
results: parsing output: 129/ for test: prime_mmap
results: parsing output: 130/ for test: kms_big_fb
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: gem_exec_fence
Done.