[56.523784] Initializing watchdogs
[56.523831]   /dev/watchdog0
[56.538079] [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]
[56.542543] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.545620] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.549136] [001/133] (960s left) gem_create (create-ext-placement-all)
[56.751036] Starting subtest: create-ext-placement-all
[56.753714] Subtest create-ext-placement-all: SUCCESS (0.000s)
[56.891431] [002/133] (960s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[56.960147] Starting subtest: map-fixed-invalidate-overlap
[56.964693] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[57.107616] [003/133] (960s left) gem_pipe_control_store_loop (fresh-buffer)
[57.175621] Starting subtest: fresh-buffer
[59.386891] Subtest fresh-buffer: SUCCESS (2.155s)
[59.473829] [004/133] (957s left) gem_mmap_offset (mmap-boundaries)
[59.526253] Starting subtest: mmap-boundaries
[59.528378] Starting dynamic subtest: smem0
[59.807856] Dynamic subtest smem0: SUCCESS (0.283s)
[59.810567] Subtest mmap-boundaries: SUCCESS (0.283s)
[59.963818] [005/133] (957s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[60.071732] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[67.676896] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.605s)
[67.930147] [006/133] (949s left) kms_atomic (atomic-plane-damage)
[68.079357] Starting subtest: atomic-plane-damage
[68.162268] Starting dynamic subtest: pipe-A-eDP-1
[68.689172] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.527s)
[68.691210] Subtest atomic-plane-damage: SUCCESS (0.608s)
[69.522942] [007/133] (947s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[69.621861] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[69.623975] Starting dynamic subtest: pipe-A-eDP-1
[69.683743] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.063s)
[69.691777] Starting dynamic subtest: pipe-B-eDP-1
[69.746688] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.063s)
[69.755371] Starting dynamic subtest: pipe-C-eDP-1
[69.809618] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.062s)
[69.818319] Starting dynamic subtest: pipe-D-eDP-1
[69.874102] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.063s)
[69.876520] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.254s)
[72.175842] [008/133] (945s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[72.287378] Starting subtest: psr-rgb101010-draw-mmap-wc
[79.272946] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (6.985s)
[79.409068] [009/133] (937s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[79.476466] Starting subtest: yf-tiled-to-vebox-x-tiled
[79.480722] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[79.677923] [010/133] (937s left) syncobj_timeline (wait-all-complex)
[79.732732] Starting subtest: wait-all-complex
[79.945249] Subtest wait-all-complex: SUCCESS (0.212s)
[80.037334] [011/133] (937s left) gem_pxp (create-valid-protected-context)
[80.152028] Starting subtest: create-valid-protected-context
[80.154401] Subtest create-valid-protected-context: SUCCESS (0.002s)
[80.299084] [012/133] (937s left) gem_exec_flush (basic-wb-rw-default)
[80.370350] Starting subtest: basic-wb-rw-default
[85.855229] Subtest basic-wb-rw-default: SUCCESS (5.451s)
[86.029497] [013/133] (931s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[86.130137] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[86.132500] Starting dynamic subtest: pipe-A-eDP-1
[86.152962] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[86.160992] Starting dynamic subtest: pipe-B-eDP-1
[86.180315] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[86.187750] Starting dynamic subtest: pipe-C-eDP-1
[86.207459] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[86.214813] Starting dynamic subtest: pipe-D-eDP-1
[86.234278] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[86.236467] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[88.309885] [014/133] (929s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[88.408597] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[92.587938] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.183s)
[93.721932] [015/133] (923s left) kms_cursor_edge_walk (256x256-top-bottom)
[93.814928] Starting subtest: 256x256-top-bottom
[93.817338] Starting dynamic subtest: pipe-A-eDP-1
[97.241594] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.428s)
[97.250386] Starting dynamic subtest: pipe-D-eDP-1
[100.368969] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.126s)
[100.371567] Subtest 256x256-top-bottom: SUCCESS (6.556s)
[101.202956] [016/133] (916s left) kms_force_connector_basic (force-load-detect)
[101.278349] Starting subtest: force-load-detect
[101.282887] Subtest force-load-detect: SKIP (0.000s)
[101.414941] [017/133] (916s left) syncobj_wait (invalid-signal-zero-handles)
[101.478868] Starting subtest: invalid-signal-zero-handles
[101.481219] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[101.611463] [018/133] (916s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[106.544703] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[106.547034] Starting dynamic subtest: pipe-A-eDP-1
[107.328029] Dynamic subtest pipe-A-eDP-1: SKIP (0.781s)
[107.337378] Starting dynamic subtest: pipe-B-eDP-1
[108.451945] Dynamic subtest pipe-B-eDP-1: SKIP (1.122s)
[108.456570] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.906s)
[109.686683] [019/133] (908s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[109.804296] Starting subtest: fbcpsr-rgb565-draw-render
[116.816576] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.014s)
[116.997630] [020/133] (900s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[117.094735] Starting subtest: primary-4-tiled-reflect-x-0
[124.099972] Subtest primary-4-tiled-reflect-x-0: SUCCESS (7.002s)
[125.361124] [021/133] (892s left) kms_setmode (clone-exclusive-crtc)
[125.427211] Starting subtest: clone-exclusive-crtc
[125.433928] Subtest clone-exclusive-crtc: SKIP (0.000s)
[125.556791] [022/133] (892s left) kms_addfb_basic (too-high)
[125.652631] Starting subtest: too-high
[125.654819] Subtest too-high: SUCCESS (0.000s)
[125.780471] [023/133] (892s left) perf_pmu (busy-accuracy-2)
[125.823134] Starting subtest: busy-accuracy-2
[125.825552] Starting dynamic subtest: rcs0
[128.864276] Dynamic subtest rcs0: SUCCESS (3.040s)
[128.873702] Starting dynamic subtest: bcs0
[131.973893] Dynamic subtest bcs0: SUCCESS (3.057s)
[131.984539] Starting dynamic subtest: vcs0
[135.021460] Dynamic subtest vcs0: SUCCESS (3.046s)
[135.030227] Starting dynamic subtest: vcs1
[138.070824] Dynamic subtest vcs1: SUCCESS (3.046s)
[138.079899] Starting dynamic subtest: vecs0
[141.117580] Dynamic subtest vecs0: SUCCESS (3.045s)
[141.126469] Starting dynamic subtest: ccs0
[144.177201] Dynamic subtest ccs0: SUCCESS (3.059s)
[144.179355] Subtest busy-accuracy-2: SUCCESS (18.304s)
[144.267366] [024/133] (873s left) kms_psr (pr-primary-mmap-gtt)
[144.370094] Starting subtest: pr-primary-mmap-gtt
[144.374610] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[144.463113] [025/133] (873s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[144.569384] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[144.573616] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[144.693430] [026/133] (873s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[144.820122] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[144.824372] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[144.961278] [027/133] (873s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[145.053938] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[145.056193] Starting dynamic subtest: pipe-A-default-mode
[147.799549] Dynamic subtest pipe-A-default-mode: SUCCESS (2.746s)
[147.801725] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.748s)
[148.660970] [028/133] (869s left) i915_drm_fdinfo (idle)
[148.704642] Starting subtest: idle
[148.706916] Starting dynamic subtest: rcs0
[149.297035] Dynamic subtest rcs0: SUCCESS (0.593s)
[149.308598] Starting dynamic subtest: bcs0
[149.804249] Dynamic subtest bcs0: SUCCESS (0.504s)
[149.815084] Starting dynamic subtest: vcs0
[150.309598] Dynamic subtest vcs0: SUCCESS (0.503s)
[150.320071] Starting dynamic subtest: vcs1
[150.814855] Dynamic subtest vcs1: SUCCESS (0.503s)
[150.825235] Starting dynamic subtest: vecs0
[151.320574] Dynamic subtest vecs0: SUCCESS (0.503s)
[151.329819] Starting dynamic subtest: ccs0
[151.826934] Dynamic subtest ccs0: SUCCESS (0.504s)
[151.829706] Subtest idle: SUCCESS (3.123s)
[151.924821] [029/133] (866s left) core_setmaster (master-drop-set-shared-fd)
[152.023813] Starting subtest: master-drop-set-shared-fd
[152.031052] Subtest master-drop-set-shared-fd: SUCCESS (0.011s)
[152.067083] [030/133] (866s left) kms_chamelium_color (ctm-negative)
[152.150997] Subtest ctm-negative: SKIP (0.000s)
[152.196693] [031/133] (866s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[152.264162] Starting subtest: multi-wait-for-submit-available-submitted
[152.266440] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.000s)
[152.391269] [032/133] (865s left) syncobj_timeline (invalid-signal-illegal-point)
[152.453298] Starting subtest: invalid-signal-illegal-point
[152.455533] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[152.567596] [033/133] (865s left) gem_mmap (bad-offset)
[152.634511] Subtest bad-offset: SKIP (0.000s)
[152.695783] [034/133] (865s left) i915_hangman (gt-error-state-capture)
[152.769937] Starting subtest: gt-error-state-capture
[152.772294] Starting dynamic subtest: rcs0
[163.892476] Dynamic subtest rcs0: SUCCESS (11.121s)
[163.904958] Starting dynamic subtest: bcs0
[175.860359] Dynamic subtest bcs0: SUCCESS (11.965s)
[175.871947] Starting dynamic subtest: vcs0
[187.828493] Dynamic subtest vcs0: SUCCESS (11.966s)
[187.839823] Starting dynamic subtest: vcs1
[198.835974] Dynamic subtest vcs1: SUCCESS (11.005s)
[198.847207] Starting dynamic subtest: vecs0
[209.843520] Dynamic subtest vecs0: SUCCESS (11.005s)
[209.856289] Starting dynamic subtest: ccs0
[221.875779] Dynamic subtest ccs0: SUCCESS (12.029s)
[221.878470] Subtest gt-error-state-capture: SUCCESS (69.104s)
[221.980769] [035/133] (796s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[222.084186] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[222.086576] Starting dynamic subtest: pipe-A-eDP-1
[222.096017] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[222.108362] Starting dynamic subtest: pipe-B-eDP-1
[222.117959] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[222.128077] Starting dynamic subtest: pipe-C-eDP-1
[222.137049] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[222.145111] Starting dynamic subtest: pipe-D-eDP-1
[222.153874] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[222.158274] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[224.421312] [036/133] (793s left) gem_exec_whisper (basic-fds)
[224.497324] Starting subtest: basic-fds
[231.433536] Subtest basic-fds: SUCCESS (6.933s)
[231.642936] [037/133] (786s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[231.764062] Starting subtest: psr-1p-primscrn-cur-indfb-move
[239.202528] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.441s)
[239.371495] [038/133] (779s left) gem_exec_schedule (preempt-user)
[239.437721] Starting subtest: preempt-user
[239.439591] Starting dynamic subtest: rcs0
[239.457235] Dynamic subtest rcs0: SUCCESS (0.026s)
[239.468435] Starting dynamic subtest: bcs0
[239.476672] Dynamic subtest bcs0: SUCCESS (0.019s)
[239.485229] Starting dynamic subtest: vcs0
[239.497848] Dynamic subtest vcs0: SUCCESS (0.021s)
[239.506260] Starting dynamic subtest: vcs1
[239.519586] Dynamic subtest vcs1: SUCCESS (0.021s)
[239.528067] Starting dynamic subtest: vecs0
[239.540990] Dynamic subtest vecs0: SUCCESS (0.021s)
[239.549757] Starting dynamic subtest: ccs0
[239.562642] Dynamic subtest ccs0: SUCCESS (0.021s)
[239.565019] Subtest preempt-user: SUCCESS (0.132s)
[241.040916] [039/133] (777s left) kms_psr (psr2-primary-mmap-cpu)
[241.141134] Starting subtest: psr2-primary-mmap-cpu
[241.143448] Starting dynamic subtest: eDP-1
[242.802131] Dynamic subtest eDP-1: SUCCESS (1.661s)
[242.804452] Subtest psr2-primary-mmap-cpu: SUCCESS (1.661s)
[243.704269] [040/133] (774s left) gem_ctx_param (invalid-size-set)
[243.767945] Starting subtest: invalid-size-set
[243.770327] Subtest invalid-size-set: SUCCESS (0.001s)
[243.903302] [041/133] (774s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[244.001190] Starting subtest: bad-aux-stride-y-tiled-ccs
[244.003413] Starting dynamic subtest: pipe-A-eDP-1
[244.027147] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[244.038304] Starting dynamic subtest: pipe-B-eDP-1
[244.060121] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[244.068474] Starting dynamic subtest: pipe-C-eDP-1
[244.089471] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[244.097906] Starting dynamic subtest: pipe-D-eDP-1
[244.119600] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[244.123594] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s)
[246.183765] [042/133] (772s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[246.279591] Starting subtest: blocking-absolute-wf_vblank-interruptible
[246.281781] Starting dynamic subtest: A-eDP1
[255.044453] Dynamic subtest A-eDP1: SUCCESS (8.729s)
[255.055691] Starting dynamic subtest: B-eDP1
[263.782401] Dynamic subtest B-eDP1: SUCCESS (8.735s)
[263.793826] Starting dynamic subtest: C-eDP1
[272.517826] Dynamic subtest C-eDP1: SUCCESS (8.724s)
[272.529126] Starting dynamic subtest: D-eDP1
[281.211025] Dynamic subtest D-eDP1: SUCCESS (8.735s)
[281.213731] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.933s)
[282.463922] [043/133] (736s left) kms_psr2_su (page_flip-nv12)
[282.518547] Subtest page_flip-NV12: SKIP (0.000s)
[282.588050] [044/133] (736s left) gem_exec_balancer (sliced)
[282.657351] Starting subtest: sliced
[282.661447] Subtest sliced: SKIP (0.000s)
[282.987225] [045/133] (735s left) kms_plane_lowres (tiling-x)
[283.082848] Starting subtest: tiling-x
[283.085079] Starting dynamic subtest: pipe-A-eDP-1
[283.089292] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[283.386706] Starting dynamic subtest: pipe-B-eDP-1
[283.391077] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[283.401768] Starting dynamic subtest: pipe-C-eDP-1
[283.406192] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[283.414440] Starting dynamic subtest: pipe-D-eDP-1
[283.418773] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[283.423060] Subtest tiling-x: SKIP (0.305s)
[284.188948] [046/133] (734s left) kms_vblank (query-busy-hang)
[284.297398] Starting subtest: query-busy-hang
[284.299301] Starting dynamic subtest: pipe-A-eDP-1
[310.684039] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.386s)
[310.699088] Starting dynamic subtest: pipe-D-eDP-1
[335.628546] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.940s)
[335.630896] Subtest query-busy-hang: SUCCESS (51.331s)
[336.450594] [047/133] (682s left) gem_exec_balancer (hang)
[336.718319] Starting subtest: hang
[338.381300] Subtest hang: SUCCESS (1.665s)
[338.547258] [048/133] (680s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[338.648640] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[338.652951] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[338.769905] [049/133] (680s left) kms_flip (bo-too-big)
[338.863997] Starting subtest: bo-too-big
[338.866274] Starting dynamic subtest: A-eDP1
[340.188488] Dynamic subtest A-eDP1: SUCCESS (1.323s)
[340.198659] Starting dynamic subtest: B-eDP1
[341.511951] Dynamic subtest B-eDP1: SUCCESS (1.322s)
[341.522440] Starting dynamic subtest: C-eDP1
[342.846114] Dynamic subtest C-eDP1: SUCCESS (1.333s)
[342.856516] Starting dynamic subtest: D-eDP1
[344.179121] Dynamic subtest D-eDP1: SUCCESS (1.332s)
[344.181544] Subtest bo-too-big: SUCCESS (5.318s)
[345.407301] [050/133] (673s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[345.474906] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[345.479705] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[345.679934] [051/133] (673s left) kms_cursor_legacy (single-bo)
[345.799832] Starting subtest: single-bo
[345.802150] Starting dynamic subtest: pipe-A
[351.235289] Dynamic subtest pipe-A: SUCCESS (5.379s)
[351.246301] Starting dynamic subtest: pipe-B
[356.616067] Dynamic subtest pipe-B: SUCCESS (5.379s)
[356.626795] Starting dynamic subtest: pipe-C
[361.997886] Dynamic subtest pipe-C: SUCCESS (5.379s)
[362.008830] Starting dynamic subtest: pipe-D
[367.379336] Dynamic subtest pipe-D: SUCCESS (5.378s)
[367.389884] Starting dynamic subtest: all-pipes
[372.767272] Dynamic subtest all-pipes: SUCCESS (5.386s)
[372.769750] Subtest single-bo: SUCCESS (26.915s)
[372.884506] [052/133] (646s left) kms_pm_rpm (basic-pci-d3-state)
[375.134494] Starting subtest: basic-pci-d3-state
[376.606280] Subtest basic-pci-d3-state: SUCCESS (1.472s)
[376.719680] [053/133] (642s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[376.820883] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[381.302370] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.484s)
[382.500887] [054/133] (636s left) kms_pm_rpm (legacy-planes)
[384.120639] Starting subtest: legacy-planes
[384.122743] Starting dynamic subtest: plane-63
[392.676967] Dynamic subtest plane-63: SUCCESS (8.559s)
[392.688830] Starting dynamic subtest: plane-93
[401.061987] Dynamic subtest plane-93: SUCCESS (8.382s)
[401.072319] Starting dynamic subtest: plane-123
[409.461777] Dynamic subtest plane-123: SUCCESS (8.397s)
[409.473783] Starting dynamic subtest: plane-133
[418.062814] Dynamic subtest plane-133: SUCCESS (8.599s)
[418.065234] Subtest legacy-planes: SUCCESS (33.947s)
[418.461256] [055/133] (600s left) prime_self_import (basic-with_one_bo)
[418.497705] Starting subtest: basic-with_one_bo
[418.515938] Subtest basic-with_one_bo: SUCCESS (0.021s)
[418.649092] [056/133] (600s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[418.768379] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[418.772912] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[418.904218] [057/133] (600s left) kms_flip (modeset-vs-vblank-race)
[419.009639] Starting subtest: modeset-vs-vblank-race
[419.012070] Starting dynamic subtest: A-eDP1
[423.744548] Dynamic subtest A-eDP1: SUCCESS (4.734s)
[423.754995] Starting dynamic subtest: B-eDP1
[428.485554] Dynamic subtest B-eDP1: SUCCESS (4.739s)
[428.497130] Starting dynamic subtest: C-eDP1
[433.169166] Dynamic subtest C-eDP1: SUCCESS (4.681s)
[433.179815] Starting dynamic subtest: D-eDP1
[437.715635] Dynamic subtest D-eDP1: SUCCESS (4.545s)
[437.720888] Subtest modeset-vs-vblank-race: SUCCESS (18.712s)
[438.996643] [058/133] (580s left) gem_lmem_swapping (heavy-verify-random-ccs)
[439.042123] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[439.079721] [059/133] (580s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[439.178303] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[439.182599] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[439.320596] [060/133] (580s left) kms_chamelium_color (gamma)
[439.424562] Subtest gamma: SKIP (0.000s)
[439.471255] [061/133] (579s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[439.583344] Starting subtest: cursorB-vs-flipA-varying-size
[439.587784] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[439.749280] [062/133] (579s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[439.854051] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[444.340788] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.490s)
[445.499172] [063/133] (573s left) dumb_buffer (map-valid)
[445.563909] Starting subtest: map-valid
[445.566395] Subtest map-valid: SUCCESS (0.000s)
[445.699388] [064/133] (573s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[445.803992] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[445.806320] Starting dynamic subtest: pipe-A-default-mode
[446.110779] Dynamic subtest pipe-A-default-mode: SKIP (0.306s)
[446.115422] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.307s)
[446.915248] [065/133] (572s left) perf (i915-ref-count)
[446.994978] Starting subtest: i915-ref-count
[447.068644] Subtest i915-ref-count: SUCCESS (0.071s)
[447.124600] [066/133] (572s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[447.229933] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[447.234324] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[447.367646] [067/133] (572s left) gem_softpin (noreloc-interruptible)
[447.429761] Starting subtest: noreloc-interruptible
[452.890081] Subtest noreloc-interruptible: SUCCESS (5.406s)
[453.016504] [068/133] (566s left) kms_psr (fbc-psr2-primary-render)
[453.115743] Starting subtest: fbc-psr2-primary-render
[453.117909] Starting dynamic subtest: eDP-1
[453.121807] Dynamic subtest eDP-1: SKIP (0.000s)
[453.125703] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[453.214321] [069/133] (566s left) kms_lease (atomic-implicit-crtc)
[453.314121] Starting subtest: atomic-implicit-crtc
[453.316349] Starting dynamic subtest: pipe-A-eDP-1
[453.327819] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[453.338597] Starting dynamic subtest: pipe-B-eDP-1
[453.350196] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[453.359083] Starting dynamic subtest: pipe-C-eDP-1
[453.369682] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.011s)
[453.377826] Starting dynamic subtest: pipe-D-eDP-1
[453.388636] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.010s)
[453.390566] Subtest atomic-implicit-crtc: SUCCESS (0.037s)
[453.493388] [070/133] (566s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[453.595919] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[453.977240] Starting dynamic subtest: pipe-A-eDP-1
[456.769763] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.791s)
[457.138196] Starting dynamic subtest: pipe-B-eDP-1
[459.952076] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.767s)
[460.270970] Starting dynamic subtest: pipe-C-eDP-1
[463.023675] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.759s)
[463.387100] Starting dynamic subtest: pipe-D-eDP-1
[466.152306] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.771s)
[466.154774] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (12.558s)
[467.366485] [071/133] (552s left) gem_exec_reloc (basic-wc-read-active)
[467.435933] Subtest basic-wc-read-active: SKIP (0.000s)
[467.521131] [072/133] (552s left) gem_readwrite (new-obj)
[467.578636] Subtest new-obj: SKIP (0.000s)
[467.642473] [073/133] (552s left) gem_mmap_gtt (basic-short)
[467.705085] Subtest basic-short: SKIP (0.000s)
[467.779822] [074/133] (552s left) kms_plane_multiple (tiling-yf)
[467.869477] Starting subtest: tiling-yf
[467.873558] Subtest tiling-yf: SKIP (0.000s)
[467.981427] [075/133] (551s left) kms_content_protection (dp-mst-type-0-hdcp14)
[468.126036] Starting subtest: dp-mst-type-0-hdcp14
[468.130815] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[468.268853] [076/133] (551s left) gem_ctx_shared (q-in-order)
[468.335520] Starting subtest: Q-in-order
[468.337110] Starting dynamic subtest: rcs0
[468.494490] Dynamic subtest rcs0: SUCCESS (0.161s)
[468.513192] Starting dynamic subtest: vcs0
[468.575677] Dynamic subtest vcs0: SUCCESS (0.079s)
[468.592117] Starting dynamic subtest: vcs1
[468.650205] Dynamic subtest vcs1: SUCCESS (0.074s)
[468.667419] Starting dynamic subtest: vecs0
[468.725501] Dynamic subtest vecs0: SUCCESS (0.075s)
[468.741266] Starting dynamic subtest: ccs0
[468.814988] Dynamic subtest ccs0: SUCCESS (0.089s)
[468.817115] Subtest Q-in-order: SUCCESS (0.482s)
[470.036411] [FACT gem_ctx_shared (q-in-order)] new: kernel.kmod_is_loaded.vgem: true
[470.039925] [077/133] (550s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[470.106019] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[470.208363] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[470.309218] [078/133] (549s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[470.405918] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[477.156823] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.754s)
[477.317220] [079/133] (542s left) kms_big_fb (linear-8bpp-rotate-270)
[477.416935] Starting subtest: linear-8bpp-rotate-270
[477.437866] Subtest linear-8bpp-rotate-270: SKIP (0.020s)
[477.588295] [080/133] (542s left) api_intel_allocator (standalone)
[477.657463] Starting subtest: standalone
[477.674167] Subtest standalone: SUCCESS (0.018s)
[477.830071] [081/133] (542s left) core_auth (getclient-master-drop)
[477.866433] Starting subtest: getclient-master-drop
[477.896133] Subtest getclient-master-drop: SUCCESS (0.031s)
[478.040022] [082/133] (542s left) kms_psr (fbc-psr2-basic)
[478.144987] Starting subtest: fbc-psr2-basic
[478.147289] Starting dynamic subtest: eDP-1
[478.151284] Dynamic subtest eDP-1: SKIP (0.000s)
[478.155307] Subtest fbc-psr2-basic: SKIP (0.001s)
[478.247674] [083/133] (542s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[478.399050] Starting subtest: cursor-rapid-movement-32x32
[478.446667] Subtest cursor-rapid-movement-32x32: SKIP (0.044s)
[478.787450] [084/133] (541s left) gem_exec_schedule (noreorder-priority)
[478.969578] Starting subtest: noreorder-priority
[478.972179] Starting dynamic subtest: rcs0
[480.117366] Dynamic subtest rcs0: SUCCESS (1.147s)
[480.130887] Starting dynamic subtest: vcs0
[481.956357] Dynamic subtest vcs0: SUCCESS (1.836s)
[481.974271] Starting dynamic subtest: vcs1
[483.940274] Dynamic subtest vcs1: SUCCESS (1.981s)
[483.952372] Starting dynamic subtest: vecs0
[485.924291] Dynamic subtest vecs0: SUCCESS (1.981s)
[485.935939] Starting dynamic subtest: ccs0
[487.101371] Dynamic subtest ccs0: SUCCESS (1.176s)
[487.104190] Subtest noreorder-priority: SUCCESS (8.133s)
[487.313273] [085/133] (533s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[487.415721] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[487.418025] Starting dynamic subtest: pipe-A-eDP-1
[487.442334] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[487.451829] Starting dynamic subtest: pipe-B-eDP-1
[487.475079] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[487.483810] Starting dynamic subtest: pipe-C-eDP-1
[487.507463] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[487.517052] Starting dynamic subtest: pipe-D-eDP-1
[487.539768] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[487.543631] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[489.763863] [086/133] (530s left) core_auth (many-magics)
[489.835183] Starting subtest: many-magics
[490.749241] Subtest many-magics: SUCCESS (0.917s)
[490.992164] [087/133] (529s left) i915_suspend (fence-restore-tiled2untiled)
[491.068484] Starting subtest: fence-restore-tiled2untiled
[491.073068] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[491.213153] [088/133] (529s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[491.323244] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[491.325537] Starting dynamic subtest: pipe-A
[491.346179] Dynamic subtest pipe-A: SKIP (0.019s)
[491.355546] Starting dynamic subtest: pipe-B
[491.381330] Dynamic subtest pipe-B: SKIP (0.032s)
[491.390563] Starting dynamic subtest: pipe-C
[491.413281] Dynamic subtest pipe-C: SKIP (0.032s)
[491.422276] Starting dynamic subtest: pipe-D
[491.444971] Dynamic subtest pipe-D: SKIP (0.032s)
[491.449184] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.118s)
[491.583421] [089/133] (528s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[491.678578] Starting subtest: y-tiled-64bpp-rotate-0
[491.683043] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[491.815546] [090/133] (528s left) gem_ctx_persistence (saturated-hostile)
[491.880583] Starting subtest: saturated-hostile
[491.882579] Starting dynamic subtest: rcs0
[492.704176] Dynamic subtest rcs0: SUCCESS (0.826s)
[492.716876] Starting dynamic subtest: bcs0
[493.520076] Dynamic subtest bcs0: SUCCESS (0.815s)
[493.530928] Starting dynamic subtest: vcs0
[494.336888] Dynamic subtest vcs0: SUCCESS (0.814s)
[494.349064] Starting dynamic subtest: vcs1
[495.154337] Dynamic subtest vcs1: SUCCESS (0.815s)
[495.166634] Starting dynamic subtest: vecs0
[495.964219] Dynamic subtest vecs0: SUCCESS (0.808s)
[495.976006] Starting dynamic subtest: ccs0
[496.825551] Dynamic subtest ccs0: SUCCESS (0.859s)
[496.828105] Subtest saturated-hostile: SUCCESS (4.949s)
[496.931052] [091/133] (523s left) kms_prop_blob (invalid-get-prop)
[497.006827] Starting subtest: invalid-get-prop
[497.009408] Subtest invalid-get-prop: SUCCESS (0.000s)
[497.162864] [092/133] (523s left) gem_mmap_gtt (basic-write)
[497.248432] Subtest basic-write: SKIP (0.000s)
[497.322342] [093/133] (523s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[497.444502] Starting subtest: cursorA-vs-flipA-varying-size
[508.579461] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.135s)
[509.406354] [094/133] (511s left) gem_linear_blits (interruptible)
[509.884889] Starting subtest: interruptible
[514.373858] Subtest interruptible: SUCCESS (4.489s)
[514.556415] [095/133] (506s left) gem_exec_params (sol-reset-invalid)
[514.644788] Starting subtest: sol-reset-invalid
[514.650619] Subtest sol-reset-invalid: SUCCESS (0.004s)
[516.010850] [096/133] (504s left) kms_color_pipeline (plane-lut1d)
[516.120056] Starting subtest: plane-lut1d
[516.133467] Starting dynamic subtest: pipe-A-eDP-1
[516.884481] Dynamic subtest pipe-A-eDP-1: FAIL (0.366s)
[517.560359] Starting dynamic subtest: pipe-B-eDP-1
[518.380801] Dynamic subtest pipe-B-eDP-1: FAIL (0.313s)
[518.993235] Starting dynamic subtest: pipe-C-eDP-1
[519.815096] Dynamic subtest pipe-C-eDP-1: FAIL (0.235s)
[520.319291] Starting dynamic subtest: pipe-D-eDP-1
[521.145309] Dynamic subtest pipe-D-eDP-1: FAIL (0.246s)
[521.147673] Subtest plane-lut1d: FAIL (4.729s)
[521.720338] [097/133] (499s left) gem_wait (busy)
[521.797125] Starting subtest: busy
[521.799599] Starting dynamic subtest: all-engines
[523.925461] Dynamic subtest all-engines: SUCCESS (2.126s)
[523.938054] Starting dynamic subtest: rcs0
[524.469103] Dynamic subtest rcs0: SUCCESS (0.542s)
[524.480979] Starting dynamic subtest: bcs0
[525.009039] Dynamic subtest bcs0: SUCCESS (0.539s)
[525.020483] Starting dynamic subtest: vcs0
[526.932620] Dynamic subtest vcs0: SUCCESS (1.921s)
[526.944056] Starting dynamic subtest: vcs1
[528.915984] Dynamic subtest vcs1: SUCCESS (1.980s)
[528.928344] Starting dynamic subtest: vecs0
[530.899957] Dynamic subtest vecs0: SUCCESS (1.981s)
[530.912553] Starting dynamic subtest: ccs0
[531.441259] Dynamic subtest ccs0: SUCCESS (0.540s)
[531.443830] Subtest busy: SUCCESS (9.644s)
[531.670526] [098/133] (489s left) sriov_basic (enable-vfs-autoprobe-on)
[531.751705] Starting subtest: enable-vfs-autoprobe-on
[531.754119] Starting dynamic subtest: numvfs-1
[531.827852] Dynamic subtest numvfs-1: FAIL (0.007s)
[531.839235] Starting dynamic subtest: numvfs-2
[531.911820] Dynamic subtest numvfs-2: FAIL (0.004s)
[531.922796] Starting dynamic subtest: numvfs-3
[531.994782] Dynamic subtest numvfs-3: FAIL (0.003s)
[532.004673] Starting dynamic subtest: numvfs-4
[532.073461] Dynamic subtest numvfs-4: FAIL (0.004s)
[532.083075] Starting dynamic subtest: numvfs-5
[532.152487] Dynamic subtest numvfs-5: FAIL (0.004s)
[532.162497] Starting dynamic subtest: numvfs-6
[532.236242] Dynamic subtest numvfs-6: FAIL (0.004s)
[532.246841] Starting dynamic subtest: numvfs-7
[532.317446] Dynamic subtest numvfs-7: FAIL (0.003s)
[532.326848] Starting dynamic subtest: numvfs-random
[532.396191] Dynamic subtest numvfs-random: FAIL (0.003s)
[532.406194] Starting dynamic subtest: numvfs-all
[532.475689] Dynamic subtest numvfs-all: FAIL (0.504s)
[532.478056] Subtest enable-vfs-autoprobe-on: FAIL (0.544s)
[532.516361] [099/133] (488s left) kms_chamelium_frames (vga-frame-dump)
[532.900381] Subtest vga-frame-dump: SKIP (0.000s)
[533.698061] [100/133] (487s left) gem_eio (throttle)
[533.905938] Starting subtest: throttle
[534.183809] Subtest throttle: SUCCESS (0.278s)
[534.355856] [101/133] (486s left) kms_frontbuffer_tracking (psr-abgr161616f-draw-mmap-wc)
[534.464559] Starting subtest: psr-abgr161616f-draw-mmap-wc
[534.468595] Subtest psr-abgr161616f-draw-mmap-wc: SKIP (0.000s)
[534.606954] [102/133] (486s left) gem_softpin (allocator-nopin)
[534.672491] Starting subtest: allocator-nopin
[534.787035] Subtest allocator-nopin: SUCCESS (0.113s)
[534.950662] [103/133] (486s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[535.061310] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[535.752365] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.692s)
[535.963121] [104/133] (485s left) kms_flip (busy-flip)
[536.068273] Starting subtest: busy-flip
[536.070408] Starting dynamic subtest: A-eDP1
[537.731147] Dynamic subtest A-eDP1: SUCCESS (1.664s)
[537.742771] Starting dynamic subtest: B-eDP1
[539.376877] Dynamic subtest B-eDP1: SUCCESS (1.644s)
[539.388894] Starting dynamic subtest: C-eDP1
[541.087866] Dynamic subtest C-eDP1: SUCCESS (1.709s)
[541.101926] Starting dynamic subtest: D-eDP1
[542.725297] Dynamic subtest D-eDP1: SUCCESS (1.636s)
[542.727905] Subtest busy-flip: SUCCESS (6.663s)
[544.009278] [105/133] (477s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[544.115497] Starting subtest: x-tiled-16bpp-rotate-270
[544.134764] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[544.300488] [106/133] (477s left) kms_frontbuffer_tracking (fbcpsr-abgr161616f-draw-render)
[544.398234] Starting subtest: fbcpsr-abgr161616f-draw-render
[544.402556] Subtest fbcpsr-abgr161616f-draw-render: SKIP (0.000s)
[544.530482] [107/133] (476s left) kms_getfb (getfb2-accept-nv12)
[544.626059] Starting subtest: getfb2-accept-nv12
[544.628214] Subtest getfb2-accept-nv12: SUCCESS (0.001s)
[544.750860] [108/133] (476s left) gem_ctx_exec (basic-nohangcheck)
[544.813713] Starting subtest: basic-nohangcheck
[545.065533] Subtest basic-nohangcheck: SUCCESS (0.254s)
[545.223754] [109/133] (476s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[545.349702] Starting subtest: short-flip-before-cursor-atomic-transitions
[546.302662] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.953s)
[547.143688] [110/133] (474s left) kms_cursor_crc (cursor-offscreen-256x256)
[547.379403] Starting subtest: cursor-offscreen-256x256
[547.385683] Starting dynamic subtest: pipe-A-eDP-1
[549.347314] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.962s)
[549.377392] Starting dynamic subtest: pipe-D-eDP-1
[551.921243] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.552s)
[551.923687] Subtest cursor-offscreen-256x256: SUCCESS (4.541s)
[552.814968] [111/133] (468s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[552.920310] Starting subtest: 4-tiled-8bpp-rotate-0
[554.177549] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.257s)
[554.347683] [112/133] (467s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[554.411099] Subtest writes-after-reads: SKIP (0.000s)
[554.480626] [113/133] (467s left) kms_frontbuffer_tracking (psr-slowdraw)
[554.577207] Starting subtest: psr-slowdraw
[579.826893] Subtest psr-slowdraw: SUCCESS (25.250s)
[580.019968] [114/133] (441s left) i915_pm_rc6_residency (rc6-idle)
[580.083976] Starting subtest: rc6-idle
[580.122738] Starting dynamic subtest: gt0-rcs0
[604.460173] Dynamic subtest gt0-rcs0: SUCCESS (24.336s)
[604.471738] Starting dynamic subtest: gt0-bcs0
[628.808074] Dynamic subtest gt0-bcs0: SUCCESS (24.345s)
[628.817812] Starting dynamic subtest: gt0-ccs0
[653.160318] Dynamic subtest gt0-ccs0: SUCCESS (24.350s)
[653.171988] Starting dynamic subtest: gt1-vcs0
[659.389559] Dynamic subtest gt1-vcs0: SUCCESS (6.170s)
[659.402980] Starting dynamic subtest: gt1-vecs0
[665.557635] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[665.560237] Subtest rc6-idle: SUCCESS (85.423s)
[665.611976] [115/133] (356s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[665.720619] Starting subtest: fbc-modesetfrombusy
[669.555274] Subtest fbc-modesetfrombusy: SUCCESS (3.838s)
[670.760526] [116/133] (350s left) kms_psr (psr-cursor-render)
[670.872508] Starting subtest: psr-cursor-render
[670.874877] Starting dynamic subtest: eDP-1
[672.609886] Dynamic subtest eDP-1: SUCCESS (1.739s)
[672.612218] Subtest psr-cursor-render: SUCCESS (1.741s)
[673.497931] [117/133] (348s left) gem_exec_parallel (engines)
[673.569777] Starting subtest: engines
[673.571905] Starting dynamic subtest: basic
[676.885067] Dynamic subtest basic: SUCCESS (3.316s)
[676.897145] Starting dynamic subtest: contexts
[681.317086] Dynamic subtest contexts: SUCCESS (4.430s)
[681.326546] Starting dynamic subtest: fds
[685.213761] Dynamic subtest fds: SUCCESS (3.895s)
[685.223169] Starting dynamic subtest: userptr
[686.906900] Dynamic subtest userptr: SUCCESS (1.691s)
[686.909107] Subtest engines: SUCCESS (13.340s)
[687.123731] [118/133] (334s left) prime_busy (after)
[687.191487] Starting subtest: after
[687.193895] Starting dynamic subtest: rcs0
[687.248408] Dynamic subtest rcs0: SUCCESS (0.056s)
[687.260295] Starting dynamic subtest: bcs0
[687.328056] Dynamic subtest bcs0: SUCCESS (0.078s)
[687.338706] Starting dynamic subtest: vcs0
[687.370148] Dynamic subtest vcs0: SUCCESS (0.041s)
[687.381078] Starting dynamic subtest: vcs1
[687.411486] Dynamic subtest vcs1: SUCCESS (0.041s)
[687.421464] Starting dynamic subtest: vecs0
[687.453842] Dynamic subtest vecs0: SUCCESS (0.041s)
[687.464830] Starting dynamic subtest: ccs0
[687.495981] Dynamic subtest ccs0: SUCCESS (0.042s)
[687.604445] Subtest after: SUCCESS (0.414s)
[687.707680] [119/133] (334s left) gem_partial_pwrite_pread (write-display)
[687.785182] Subtest write-display: SKIP (0.000s)
[687.881323] [120/133] (334s left) gem_tiled_blits (basic)
[687.940493] Subtest basic: SKIP (0.000s)
[688.007370] [121/133] (333s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[688.100455] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[696.955374] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.857s)
[697.104416] [122/133] (324s left) sysfs_defaults (readonly)
[697.165703] Starting subtest: readonly
[697.168251] Starting dynamic subtest: vecs0
[697.170664] Dynamic subtest vecs0: SUCCESS (0.000s)
[697.182189] Starting dynamic subtest: ccs0
[697.184060] Dynamic subtest ccs0: SUCCESS (0.000s)
[697.193024] Starting dynamic subtest: vcs0
[697.194925] Dynamic subtest vcs0: SUCCESS (0.000s)
[697.203603] Starting dynamic subtest: bcs0
[697.205537] Dynamic subtest bcs0: SUCCESS (0.000s)
[697.214964] Starting dynamic subtest: rcs0
[697.216922] Dynamic subtest rcs0: SUCCESS (0.000s)
[697.225583] Starting dynamic subtest: vcs1
[697.227522] Dynamic subtest vcs1: SUCCESS (0.000s)
[697.229442] Subtest readonly: SUCCESS (0.006s)
[697.314252] [123/133] (324s left) kms_dsc (dsc-with-formats)
[697.401142] Starting subtest: dsc-with-formats
[697.441299] Subtest dsc-with-formats: SKIP (0.001s)
[697.501499] [124/133] (324s left) prime_mmap (test_reprime)
[697.563154] Starting subtest: test_reprime
[697.565075] Starting dynamic subtest: test_reprime-smem
[697.591463] Dynamic subtest test_reprime-smem: SUCCESS (0.030s)
[697.593984] Subtest test_reprime: SUCCESS (0.031s)
[697.729892] [125/133] (324s left) gem_exec_params (rsvd2-dirt)
[697.788736] Starting subtest: rsvd2-dirt
[697.792857] Subtest rsvd2-dirt: SKIP (0.000s)
[697.905676] [126/133] (324s left) kms_lease (lessee-list)
[697.996852] Starting subtest: lessee-list
[697.998899] Starting dynamic subtest: pipe-A-eDP-1
[698.000776] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[698.010843] Starting dynamic subtest: pipe-B-eDP-1
[698.012608] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[698.021583] Starting dynamic subtest: pipe-C-eDP-1
[698.023340] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[698.032155] Starting dynamic subtest: pipe-D-eDP-1
[698.033934] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[698.035795] Subtest lessee-list: SUCCESS (0.009s)
[698.134145] [127/133] (324s left) kms_vrr (flip-dpms)
[698.227598] Starting subtest: flip-dpms
[698.233207] Subtest flip-dpms: SKIP (0.000s)
[698.330350] [128/133] (323s left) kms_frontbuffer_tracking (fbc-1p-rte)
[698.432656] Starting subtest: fbc-1p-rte
[703.877858] Subtest fbc-1p-rte: SUCCESS (5.448s)
[705.056654] [129/133] (317s left) gen9_exec_parse (batch-zero-length)
[705.126424] Subtest batch-zero-length: SKIP (0.000s)
[705.209163] [130/133] (317s left) prime_mmap (test_aperture_limit)
[705.269500] Starting subtest: test_aperture_limit
[705.271437] Starting dynamic subtest: test_aperture_limit-smem
[705.278304] Dynamic subtest test_aperture_limit-smem: SKIP (0.008s)
[705.282434] Subtest test_aperture_limit: SKIP (0.009s)
[705.376542] [131/133] (317s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[705.485642] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[705.490278] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[705.606303] [132/133] (316s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[705.698867] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[705.703047] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[705.822498] [133/133] (316s left) gem_exec_fence (long-history)
[706.283489] Starting subtest: long-history
[713.648090] Subtest long-history: SUCCESS (7.365s)
[714.208278] 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.