[56.911370] Initializing watchdogs
[56.911417]   /dev/watchdog0
[56.926249] [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.930621] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.932846] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.936182] [001/133] (960s left) gem_create (create-ext-placement-all)
[57.128997] Starting subtest: create-ext-placement-all
[57.131434] Subtest create-ext-placement-all: SUCCESS (0.000s)
[57.246202] [002/133] (960s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[57.322945] Starting subtest: map-fixed-invalidate-overlap
[57.327799] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[57.444235] [003/133] (960s left) gem_pipe_control_store_loop (fresh-buffer)
[57.493627] Starting subtest: fresh-buffer
[59.642293] Subtest fresh-buffer: SUCCESS (2.155s)
[59.775087] [004/133] (957s left) gem_mmap_offset (mmap-boundaries)
[59.838754] Starting subtest: mmap-boundaries
[59.841119] Starting dynamic subtest: smem0
[60.127035] Dynamic subtest smem0: SUCCESS (0.290s)
[60.129420] Subtest mmap-boundaries: SUCCESS (0.290s)
[60.295082] [005/133] (957s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[60.406473] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[68.093930] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.706s)
[68.276205] [006/133] (949s left) kms_atomic (atomic-plane-damage)
[68.376528] Starting subtest: atomic-plane-damage
[68.449955] Starting dynamic subtest: pipe-A-eDP-1
[68.982277] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.530s)
[68.984852] Subtest atomic-plane-damage: SUCCESS (0.606s)
[69.797202] [007/133] (947s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[69.898270] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[69.900296] Starting dynamic subtest: pipe-A-eDP-1
[69.958692] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.062s)
[69.967671] Starting dynamic subtest: pipe-B-eDP-1
[70.022606] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.063s)
[70.031392] Starting dynamic subtest: pipe-C-eDP-1
[70.087728] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.064s)
[70.096863] Starting dynamic subtest: pipe-D-eDP-1
[70.151611] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.063s)
[70.154108] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.256s)
[72.363187] [008/133] (945s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[72.472273] Starting subtest: psr-rgb101010-draw-mmap-wc
[79.513901] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (7.048s)
[79.648244] [009/133] (938s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[79.718421] Starting subtest: yf-tiled-to-vebox-x-tiled
[79.722791] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[79.921455] [010/133] (937s left) syncobj_timeline (wait-all-complex)
[79.980873] Starting subtest: wait-all-complex
[80.194860] Subtest wait-all-complex: SUCCESS (0.213s)
[80.290356] [011/133] (937s left) gem_pxp (create-valid-protected-context)
[80.422653] Starting subtest: create-valid-protected-context
[80.425169] Subtest create-valid-protected-context: SUCCESS (0.002s)
[80.576108] [012/133] (937s left) gem_exec_flush (basic-wb-rw-default)
[80.639678] Starting subtest: basic-wb-rw-default
[86.088904] Subtest basic-wb-rw-default: SUCCESS (5.449s)
[86.299362] [013/133] (931s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[86.398142] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[86.400885] Starting dynamic subtest: pipe-A-eDP-1
[86.422333] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[86.432359] Starting dynamic subtest: pipe-B-eDP-1
[86.451831] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[86.459074] Starting dynamic subtest: pipe-C-eDP-1
[86.478306] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[86.485687] Starting dynamic subtest: pipe-D-eDP-1
[86.505893] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[86.507930] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[88.718686] [014/133] (929s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[88.813416] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[92.930078] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.119s)
[94.127345] [015/133] (923s left) kms_cursor_edge_walk (256x256-top-bottom)
[94.220031] Starting subtest: 256x256-top-bottom
[94.222303] Starting dynamic subtest: pipe-A-eDP-1
[97.658066] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.438s)
[97.666870] Starting dynamic subtest: pipe-D-eDP-1
[100.782382] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.125s)
[100.784637] Subtest 256x256-top-bottom: SUCCESS (6.564s)
[101.601804] [016/133] (916s left) kms_force_connector_basic (force-load-detect)
[101.688948] Starting subtest: force-load-detect
[101.693629] Subtest force-load-detect: SKIP (0.000s)
[101.826767] [017/133] (916s left) syncobj_wait (invalid-signal-zero-handles)
[101.883539] Starting subtest: invalid-signal-zero-handles
[101.885626] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[102.005712] [018/133] (916s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[106.983595] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[106.985898] Starting dynamic subtest: pipe-A-eDP-1
[107.781932] Dynamic subtest pipe-A-eDP-1: SKIP (0.794s)
[107.791242] Starting dynamic subtest: pipe-B-eDP-1
[108.915068] Dynamic subtest pipe-B-eDP-1: SKIP (1.132s)
[108.919420] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.929s)
[110.126426] [019/133] (908s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[110.220715] Starting subtest: fbcpsr-rgb565-draw-render
[117.218362] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.000s)
[117.408425] [020/133] (900s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[117.508041] Starting subtest: primary-4-tiled-reflect-x-0
[124.544237] Subtest primary-4-tiled-reflect-x-0: SUCCESS (7.037s)
[125.804427] [021/133] (892s left) kms_setmode (clone-exclusive-crtc)
[125.867941] Starting subtest: clone-exclusive-crtc
[125.874881] Subtest clone-exclusive-crtc: SKIP (0.000s)
[126.006905] [022/133] (892s left) kms_addfb_basic (too-high)
[126.106795] Starting subtest: too-high
[126.108635] Subtest too-high: SUCCESS (0.000s)
[126.242541] [023/133] (892s left) perf_pmu (busy-accuracy-2)
[126.278754] Starting subtest: busy-accuracy-2
[126.280632] Starting dynamic subtest: rcs0
[129.320663] Dynamic subtest rcs0: SUCCESS (3.041s)
[129.330096] Starting dynamic subtest: bcs0
[132.380489] Dynamic subtest bcs0: SUCCESS (3.058s)
[132.390114] Starting dynamic subtest: vcs0
[135.425792] Dynamic subtest vcs0: SUCCESS (3.044s)
[135.436349] Starting dynamic subtest: vcs1
[138.476098] Dynamic subtest vcs1: SUCCESS (3.048s)
[138.484622] Starting dynamic subtest: vecs0
[141.524211] Dynamic subtest vecs0: SUCCESS (3.046s)
[141.534646] Starting dynamic subtest: ccs0
[144.580971] Dynamic subtest ccs0: SUCCESS (3.056s)
[144.583449] Subtest busy-accuracy-2: SUCCESS (18.302s)
[144.736560] [024/133] (873s left) kms_psr (pr-primary-mmap-gtt)
[144.841722] Starting subtest: pr-primary-mmap-gtt
[144.846111] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[144.950905] [025/133] (873s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[145.053955] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[145.058117] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[145.189138] [026/133] (873s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[145.294480] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[145.298866] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[145.460420] [027/133] (873s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[145.554777] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[145.557043] Starting dynamic subtest: pipe-A-default-mode
[148.358078] Dynamic subtest pipe-A-default-mode: SUCCESS (2.803s)
[148.360352] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.804s)
[149.205731] [028/133] (869s left) i915_drm_fdinfo (idle)
[149.245882] Starting subtest: idle
[149.247847] Starting dynamic subtest: rcs0
[149.824602] Dynamic subtest rcs0: SUCCESS (0.580s)
[149.835835] Starting dynamic subtest: bcs0
[150.332478] Dynamic subtest bcs0: SUCCESS (0.504s)
[150.343637] Starting dynamic subtest: vcs0
[150.838424] Dynamic subtest vcs0: SUCCESS (0.503s)
[150.849790] Starting dynamic subtest: vcs1
[151.343819] Dynamic subtest vcs1: SUCCESS (0.504s)
[151.354839] Starting dynamic subtest: vecs0
[151.849087] Dynamic subtest vecs0: SUCCESS (0.503s)
[151.860712] Starting dynamic subtest: ccs0
[152.354684] Dynamic subtest ccs0: SUCCESS (0.504s)
[152.357488] Subtest idle: SUCCESS (3.109s)
[152.456069] [029/133] (866s left) core_setmaster (master-drop-set-shared-fd)
[152.548391] Starting subtest: master-drop-set-shared-fd
[152.573309] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[152.612271] [030/133] (865s left) kms_chamelium_color (ctm-negative)
[152.709800] Subtest ctm-negative: SKIP (0.000s)
[152.753447] [031/133] (865s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[152.823171] Starting subtest: multi-wait-for-submit-available-submitted
[152.825575] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.000s)
[152.955886] [032/133] (865s left) syncobj_timeline (invalid-signal-illegal-point)
[153.015266] Starting subtest: invalid-signal-illegal-point
[153.017176] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[153.130816] [033/133] (865s left) gem_mmap (bad-offset)
[153.205259] Subtest bad-offset: SKIP (0.000s)
[153.271603] [034/133] (865s left) i915_hangman (gt-error-state-capture)
[153.346471] Starting subtest: gt-error-state-capture
[153.348727] Starting dynamic subtest: rcs0
[163.892207] Dynamic subtest rcs0: SUCCESS (10.544s)
[163.903922] Starting dynamic subtest: bcs0
[174.835839] Dynamic subtest bcs0: SUCCESS (10.941s)
[174.847796] Starting dynamic subtest: vcs0
[186.869540] Dynamic subtest vcs0: SUCCESS (12.031s)
[186.880661] Starting dynamic subtest: vcs1
[197.875539] Dynamic subtest vcs1: SUCCESS (11.004s)
[197.887270] Starting dynamic subtest: vecs0
[209.907832] Dynamic subtest vecs0: SUCCESS (12.030s)
[209.920081] Starting dynamic subtest: ccs0
[220.852059] Dynamic subtest ccs0: SUCCESS (10.941s)
[220.854951] Subtest gt-error-state-capture: SUCCESS (67.503s)
[220.959587] [035/133] (797s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[221.058372] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[221.060726] Starting dynamic subtest: pipe-A-eDP-1
[221.069964] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[221.079759] Starting dynamic subtest: pipe-B-eDP-1
[221.089757] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[221.098166] Starting dynamic subtest: pipe-C-eDP-1
[221.106834] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[221.115139] Starting dynamic subtest: pipe-D-eDP-1
[221.123817] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[221.128181] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[223.376779] [036/133] (795s left) gem_exec_whisper (basic-fds)
[223.456486] Starting subtest: basic-fds
[230.971994] Subtest basic-fds: SUCCESS (7.510s)
[231.173868] [037/133] (787s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[231.298096] Starting subtest: psr-1p-primscrn-cur-indfb-move
[238.640193] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.347s)
[238.824631] [038/133] (780s left) gem_exec_schedule (preempt-user)
[238.905386] Starting subtest: preempt-user
[238.907631] Starting dynamic subtest: rcs0
[238.921758] Dynamic subtest rcs0: SUCCESS (0.025s)
[238.931278] Starting dynamic subtest: bcs0
[238.940067] Dynamic subtest bcs0: SUCCESS (0.018s)
[238.950109] Starting dynamic subtest: vcs0
[238.960398] Dynamic subtest vcs0: SUCCESS (0.020s)
[238.970826] Starting dynamic subtest: vcs1
[238.979619] Dynamic subtest vcs1: SUCCESS (0.019s)
[238.988228] Starting dynamic subtest: vecs0
[238.999112] Dynamic subtest vecs0: SUCCESS (0.019s)
[239.007736] Starting dynamic subtest: ccs0
[239.020157] Dynamic subtest ccs0: SUCCESS (0.020s)
[239.022431] Subtest preempt-user: SUCCESS (0.125s)
[241.060587] [039/133] (777s left) kms_psr (psr2-primary-mmap-cpu)
[241.153083] Starting subtest: psr2-primary-mmap-cpu
[241.155309] Starting dynamic subtest: eDP-1
[242.863546] Dynamic subtest eDP-1: SUCCESS (1.710s)
[242.865824] Subtest psr2-primary-mmap-cpu: SUCCESS (1.710s)
[243.768553] [040/133] (775s left) gem_ctx_param (invalid-size-set)
[243.823666] Starting subtest: invalid-size-set
[243.825875] Subtest invalid-size-set: SUCCESS (0.001s)
[243.944369] [041/133] (775s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[244.035754] Starting subtest: bad-aux-stride-y-tiled-ccs
[244.037994] Starting dynamic subtest: pipe-A-eDP-1
[244.060085] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[244.071072] Starting dynamic subtest: pipe-B-eDP-1
[244.092357] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[244.100884] Starting dynamic subtest: pipe-C-eDP-1
[244.122273] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[244.130740] Starting dynamic subtest: pipe-D-eDP-1
[244.152606] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[244.156705] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.007s)
[246.330790] [042/133] (772s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[246.427900] Starting subtest: blocking-absolute-wf_vblank-interruptible
[246.430248] Starting dynamic subtest: A-eDP1
[255.165965] Dynamic subtest A-eDP1: SUCCESS (8.735s)
[255.176831] Starting dynamic subtest: B-eDP1
[263.888605] Dynamic subtest B-eDP1: SUCCESS (8.721s)
[263.897507] Starting dynamic subtest: C-eDP1
[272.608593] Dynamic subtest C-eDP1: SUCCESS (8.717s)
[272.620190] Starting dynamic subtest: D-eDP1
[281.339050] Dynamic subtest D-eDP1: SUCCESS (8.729s)
[281.344195] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.914s)
[282.581423] [043/133] (736s left) kms_psr2_su (page_flip-nv12)
[282.639939] Subtest page_flip-NV12: SKIP (0.000s)
[282.696093] [044/133] (736s left) gem_exec_balancer (sliced)
[282.765656] Starting subtest: sliced
[282.770155] Subtest sliced: SKIP (0.000s)
[283.097616] [045/133] (736s left) kms_plane_lowres (tiling-x)
[283.186733] Starting subtest: tiling-x
[283.189127] Starting dynamic subtest: pipe-A-eDP-1
[283.193765] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[283.482923] Starting dynamic subtest: pipe-B-eDP-1
[283.487703] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[283.496333] Starting dynamic subtest: pipe-C-eDP-1
[283.500662] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[283.509101] Starting dynamic subtest: pipe-D-eDP-1
[283.513423] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[283.517771] Subtest tiling-x: SKIP (0.295s)
[284.312972] [046/133] (734s left) kms_vblank (query-busy-hang)
[284.422230] Starting subtest: query-busy-hang
[284.424087] Starting dynamic subtest: pipe-A-eDP-1
[310.674547] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.251s)
[310.689276] Starting dynamic subtest: pipe-D-eDP-1
[335.694863] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.013s)
[335.697082] Subtest query-busy-hang: SUCCESS (51.269s)
[336.572272] [047/133] (682s left) gem_exec_balancer (hang)
[336.833265] Starting subtest: hang
[338.919965] Subtest hang: SUCCESS (2.089s)
[339.095779] [048/133] (680s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[339.198728] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[339.202907] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[339.341037] [049/133] (680s left) kms_flip (bo-too-big)
[339.434851] Starting subtest: bo-too-big
[339.437133] Starting dynamic subtest: A-eDP1
[340.706402] Dynamic subtest A-eDP1: SUCCESS (1.273s)
[340.716573] Starting dynamic subtest: B-eDP1
[342.041561] Dynamic subtest B-eDP1: SUCCESS (1.334s)
[342.051252] Starting dynamic subtest: C-eDP1
[343.362698] Dynamic subtest C-eDP1: SUCCESS (1.320s)
[343.372797] Starting dynamic subtest: D-eDP1
[344.690965] Dynamic subtest D-eDP1: SUCCESS (1.327s)
[344.693555] Subtest bo-too-big: SUCCESS (5.262s)
[345.918350] [050/133] (673s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[345.983445] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[345.987848] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[346.193035] [051/133] (673s left) kms_cursor_legacy (single-bo)
[346.322756] Starting subtest: single-bo
[346.325074] Starting dynamic subtest: pipe-A
[351.705470] Dynamic subtest pipe-A: SUCCESS (5.379s)
[351.717075] Starting dynamic subtest: pipe-B
[357.087268] Dynamic subtest pipe-B: SUCCESS (5.379s)
[357.098557] Starting dynamic subtest: pipe-C
[362.469410] Dynamic subtest pipe-C: SUCCESS (5.379s)
[362.480859] Starting dynamic subtest: pipe-D
[367.852310] Dynamic subtest pipe-D: SUCCESS (5.379s)
[367.863476] Starting dynamic subtest: all-pipes
[373.240840] Dynamic subtest all-pipes: SUCCESS (5.386s)
[373.243541] Subtest single-bo: SUCCESS (26.916s)
[373.406604] [052/133] (646s left) kms_pm_rpm (basic-pci-d3-state)
[375.045782] Starting subtest: basic-pci-d3-state
[376.628047] Subtest basic-pci-d3-state: SUCCESS (1.583s)
[376.730278] [053/133] (642s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[376.833248] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[381.199014] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.370s)
[382.404709] [054/133] (637s left) kms_pm_rpm (legacy-planes)
[384.035229] Starting subtest: legacy-planes
[384.038035] Starting dynamic subtest: plane-63
[392.647414] Dynamic subtest plane-63: SUCCESS (8.610s)
[392.658939] Starting dynamic subtest: plane-93
[400.934995] Dynamic subtest plane-93: SUCCESS (8.285s)
[400.946799] Starting dynamic subtest: plane-123
[409.265653] Dynamic subtest plane-123: SUCCESS (8.329s)
[409.277498] Starting dynamic subtest: plane-133
[417.952762] Dynamic subtest plane-133: SUCCESS (8.684s)
[417.955246] Subtest legacy-planes: SUCCESS (33.919s)
[418.350546] [055/133] (601s left) prime_self_import (basic-with_one_bo)
[418.384350] Starting subtest: basic-with_one_bo
[418.410010] Subtest basic-with_one_bo: SUCCESS (0.029s)
[418.557102] [056/133] (601s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[418.655906] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[418.660286] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[418.789117] [057/133] (600s left) kms_flip (modeset-vs-vblank-race)
[418.891145] Starting subtest: modeset-vs-vblank-race
[418.893405] Starting dynamic subtest: A-eDP1
[423.666021] Dynamic subtest A-eDP1: SUCCESS (4.773s)
[423.677366] Starting dynamic subtest: B-eDP1
[428.386727] Dynamic subtest B-eDP1: SUCCESS (4.719s)
[428.398158] Starting dynamic subtest: C-eDP1
[433.018263] Dynamic subtest C-eDP1: SUCCESS (4.629s)
[433.029709] Starting dynamic subtest: D-eDP1
[437.658409] Dynamic subtest D-eDP1: SUCCESS (4.637s)
[437.660791] Subtest modeset-vs-vblank-race: SUCCESS (18.770s)
[438.860275] [058/133] (580s left) gem_lmem_swapping (heavy-verify-random-ccs)
[438.914655] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[438.973470] [059/133] (580s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[439.071431] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[439.075444] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[439.214182] [060/133] (580s left) kms_chamelium_color (gamma)
[439.310701] Subtest gamma: SKIP (0.000s)
[439.352974] [061/133] (580s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[439.477114] Starting subtest: cursorB-vs-flipA-varying-size
[439.481623] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[439.647958] [062/133] (580s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[439.755207] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[444.123548] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.371s)
[445.356889] [063/133] (574s left) dumb_buffer (map-valid)
[445.422695] Starting subtest: map-valid
[445.424917] Subtest map-valid: SUCCESS (0.000s)
[445.555267] [064/133] (574s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[445.657772] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[445.659966] Starting dynamic subtest: pipe-A-default-mode
[445.963296] Dynamic subtest pipe-A-default-mode: SKIP (0.304s)
[445.967645] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.306s)
[446.772689] [065/133] (573s left) perf (i915-ref-count)
[446.853435] Starting subtest: i915-ref-count
[446.924613] Subtest i915-ref-count: SUCCESS (0.072s)
[446.980694] [066/133] (573s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[447.086373] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[447.090256] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[447.232165] [067/133] (572s left) gem_softpin (noreloc-interruptible)
[447.294528] Starting subtest: noreloc-interruptible
[452.689983] Subtest noreloc-interruptible: SUCCESS (5.396s)
[452.854619] [068/133] (567s left) kms_psr (fbc-psr2-primary-render)
[452.957537] Starting subtest: fbc-psr2-primary-render
[452.959774] Starting dynamic subtest: eDP-1
[452.963817] Dynamic subtest eDP-1: SKIP (0.000s)
[452.967912] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[453.050902] [069/133] (567s left) kms_lease (atomic-implicit-crtc)
[453.144869] Starting subtest: atomic-implicit-crtc
[453.147093] Starting dynamic subtest: pipe-A-eDP-1
[453.157792] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[453.169672] Starting dynamic subtest: pipe-B-eDP-1
[453.180403] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[453.189174] Starting dynamic subtest: pipe-C-eDP-1
[453.199301] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s)
[453.207898] Starting dynamic subtest: pipe-D-eDP-1
[453.218314] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.010s)
[453.220473] Subtest atomic-implicit-crtc: SUCCESS (0.036s)
[453.314087] [070/133] (566s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[453.401825] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[453.775302] Starting dynamic subtest: pipe-A-eDP-1
[456.518926] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.742s)
[456.890952] Starting dynamic subtest: pipe-B-eDP-1
[459.633791] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.754s)
[460.003390] Starting dynamic subtest: pipe-C-eDP-1
[462.767318] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.770s)
[463.133919] Starting dynamic subtest: pipe-D-eDP-1
[465.893561] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.765s)
[465.896243] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (12.493s)
[467.113453] [071/133] (553s left) gem_exec_reloc (basic-wc-read-active)
[467.189042] Subtest basic-wc-read-active: SKIP (0.000s)
[467.282691] [072/133] (553s left) gem_readwrite (new-obj)
[467.332452] Subtest new-obj: SKIP (0.000s)
[467.391192] [073/133] (552s left) gem_mmap_gtt (basic-short)
[467.454721] Subtest basic-short: SKIP (0.000s)
[467.525333] [074/133] (552s left) kms_plane_multiple (tiling-yf)
[467.614060] Starting subtest: tiling-yf
[467.618108] Subtest tiling-yf: SKIP (0.000s)
[467.713858] [075/133] (552s left) kms_content_protection (dp-mst-type-0-hdcp14)
[467.850252] Starting subtest: dp-mst-type-0-hdcp14
[467.854748] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[468.022749] [076/133] (552s left) gem_ctx_shared (q-in-order)
[468.082777] Starting subtest: Q-in-order
[468.084855] Starting dynamic subtest: rcs0
[468.272851] Dynamic subtest rcs0: SUCCESS (0.188s)
[468.289894] Starting dynamic subtest: vcs0
[468.351917] Dynamic subtest vcs0: SUCCESS (0.078s)
[468.365838] Starting dynamic subtest: vcs1
[468.430400] Dynamic subtest vcs1: SUCCESS (0.077s)
[468.446389] Starting dynamic subtest: vecs0
[468.510464] Dynamic subtest vecs0: SUCCESS (0.079s)
[468.527865] Starting dynamic subtest: ccs0
[468.603395] Dynamic subtest ccs0: SUCCESS (0.092s)
[468.605899] Subtest Q-in-order: SUCCESS (0.520s)
[470.049753] [FACT gem_ctx_shared (q-in-order)] new: kernel.kmod_is_loaded.vgem: true
[470.052885] [077/133] (550s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[470.117496] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[470.225824] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.108s)
[470.328458] [078/133] (550s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[470.427812] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[477.091835] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.666s)
[477.265628] [079/133] (543s left) kms_big_fb (linear-8bpp-rotate-270)
[477.378310] Starting subtest: linear-8bpp-rotate-270
[477.404059] Subtest linear-8bpp-rotate-270: SKIP (0.025s)
[477.590985] [080/133] (543s left) api_intel_allocator (standalone)
[477.652339] Starting subtest: standalone
[477.666066] Subtest standalone: SUCCESS (0.016s)
[477.834941] [081/133] (542s left) core_auth (getclient-master-drop)
[477.872399] Starting subtest: getclient-master-drop
[477.906467] Subtest getclient-master-drop: SUCCESS (0.035s)
[478.035897] [082/133] (542s left) kms_psr (fbc-psr2-basic)
[478.143119] Starting subtest: fbc-psr2-basic
[478.145059] Starting dynamic subtest: eDP-1
[478.148417] Dynamic subtest eDP-1: SKIP (0.000s)
[478.151799] Subtest fbc-psr2-basic: SKIP (0.001s)
[478.243592] [083/133] (542s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[478.396892] Starting subtest: cursor-rapid-movement-32x32
[478.444260] Subtest cursor-rapid-movement-32x32: SKIP (0.044s)
[478.794791] [084/133] (542s left) gem_exec_schedule (noreorder-priority)
[478.975557] Starting subtest: noreorder-priority
[478.977755] Starting dynamic subtest: rcs0
[480.139023] Dynamic subtest rcs0: SUCCESS (1.163s)
[480.150842] Starting dynamic subtest: vcs0
[481.862455] Dynamic subtest vcs0: SUCCESS (1.720s)
[481.874308] Starting dynamic subtest: vcs1
[483.955746] Dynamic subtest vcs1: SUCCESS (2.091s)
[483.968154] Starting dynamic subtest: vecs0
[485.893401] Dynamic subtest vecs0: SUCCESS (1.934s)
[485.902811] Starting dynamic subtest: ccs0
[487.061081] Dynamic subtest ccs0: SUCCESS (1.166s)
[487.063972] Subtest noreorder-priority: SUCCESS (8.087s)
[487.279485] [085/133] (533s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[487.377873] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[487.380219] Starting dynamic subtest: pipe-A-eDP-1
[487.404801] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[487.415761] Starting dynamic subtest: pipe-B-eDP-1
[487.439080] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[487.448376] Starting dynamic subtest: pipe-C-eDP-1
[487.471976] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[487.480643] Starting dynamic subtest: pipe-D-eDP-1
[487.504402] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[487.508430] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[489.609382] [086/133] (531s left) core_auth (many-magics)
[489.674453] Starting subtest: many-magics
[490.591414] Subtest many-magics: SUCCESS (0.920s)
[490.837429] [087/133] (530s left) i915_suspend (fence-restore-tiled2untiled)
[490.901884] Starting subtest: fence-restore-tiled2untiled
[490.906265] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[491.020811] [088/133] (529s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[491.122395] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[491.124813] Starting dynamic subtest: pipe-A
[491.146437] Dynamic subtest pipe-A: SKIP (0.019s)
[491.155804] Starting dynamic subtest: pipe-B
[491.178601] Dynamic subtest pipe-B: SKIP (0.032s)
[491.187594] Starting dynamic subtest: pipe-C
[491.215262] Dynamic subtest pipe-C: SKIP (0.035s)
[491.224222] Starting dynamic subtest: pipe-D
[491.246865] Dynamic subtest pipe-D: SKIP (0.033s)
[491.250626] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.122s)
[491.396114] [089/133] (529s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[491.499909] Starting subtest: y-tiled-64bpp-rotate-0
[491.504226] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[491.628336] [090/133] (529s left) gem_ctx_persistence (saturated-hostile)
[491.702643] Starting subtest: saturated-hostile
[491.705256] Starting dynamic subtest: rcs0
[492.527814] Dynamic subtest rcs0: SUCCESS (0.824s)
[492.540285] Starting dynamic subtest: bcs0
[493.323829] Dynamic subtest bcs0: SUCCESS (0.794s)
[493.336330] Starting dynamic subtest: vcs0
[494.135740] Dynamic subtest vcs0: SUCCESS (0.807s)
[494.146689] Starting dynamic subtest: vcs1
[494.954275] Dynamic subtest vcs1: SUCCESS (0.819s)
[494.965945] Starting dynamic subtest: vecs0
[495.748621] Dynamic subtest vecs0: SUCCESS (0.792s)
[495.760455] Starting dynamic subtest: ccs0
[496.611218] Dynamic subtest ccs0: SUCCESS (0.861s)
[496.613837] Subtest saturated-hostile: SUCCESS (4.910s)
[496.715424] [091/133] (524s left) kms_prop_blob (invalid-get-prop)
[496.771953] Starting subtest: invalid-get-prop
[496.774312] Subtest invalid-get-prop: SUCCESS (0.000s)
[496.888818] [092/133] (524s left) gem_mmap_gtt (basic-write)
[496.951766] Subtest basic-write: SKIP (0.000s)
[497.024434] [093/133] (524s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[497.153448] Starting subtest: cursorA-vs-flipA-varying-size
[508.283543] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.128s)
[509.133548] [094/133] (512s left) gem_linear_blits (interruptible)
[509.528725] Starting subtest: interruptible
[514.101443] Subtest interruptible: SUCCESS (4.573s)
[514.273786] [095/133] (507s left) gem_exec_params (sol-reset-invalid)
[514.362776] Starting subtest: sol-reset-invalid
[514.369469] Subtest sol-reset-invalid: SUCCESS (0.003s)
[516.009557] [096/133] (505s left) kms_color_pipeline (plane-lut1d)
[516.121420] Starting subtest: plane-lut1d
[516.137423] Starting dynamic subtest: pipe-A-eDP-1
[516.902369] Dynamic subtest pipe-A-eDP-1: FAIL (0.376s)
[517.600347] Starting dynamic subtest: pipe-B-eDP-1
[518.425689] Dynamic subtest pipe-B-eDP-1: FAIL (0.302s)
[518.933097] Starting dynamic subtest: pipe-C-eDP-1
[519.792401] Dynamic subtest pipe-C-eDP-1: FAIL (0.269s)
[520.266192] Starting dynamic subtest: pipe-D-eDP-1
[521.121091] Dynamic subtest pipe-D-eDP-1: FAIL (0.268s)
[521.123445] Subtest plane-lut1d: FAIL (4.699s)
[521.662900] [097/133] (499s left) gem_wait (busy)
[521.733516] Starting subtest: busy
[521.735840] Starting dynamic subtest: all-engines
[522.901682] Dynamic subtest all-engines: SUCCESS (1.166s)
[522.913862] Starting dynamic subtest: rcs0
[523.442848] Dynamic subtest rcs0: SUCCESS (0.540s)
[523.454843] Starting dynamic subtest: bcs0
[523.981624] Dynamic subtest bcs0: SUCCESS (0.538s)
[523.993259] Starting dynamic subtest: vcs0
[525.909672] Dynamic subtest vcs0: SUCCESS (1.925s)
[525.922118] Starting dynamic subtest: vcs1
[527.892887] Dynamic subtest vcs1: SUCCESS (1.981s)
[527.905065] Starting dynamic subtest: vecs0
[529.877561] Dynamic subtest vecs0: SUCCESS (1.982s)
[529.890121] Starting dynamic subtest: ccs0
[530.420325] Dynamic subtest ccs0: SUCCESS (0.541s)
[530.422754] Subtest busy: SUCCESS (8.687s)
[530.640993] [098/133] (490s left) sriov_basic (enable-vfs-autoprobe-on)
[530.704475] Starting subtest: enable-vfs-autoprobe-on
[530.706845] Starting dynamic subtest: numvfs-1
[530.781654] Dynamic subtest numvfs-1: FAIL (0.006s)
[530.791552] Starting dynamic subtest: numvfs-2
[530.864676] Dynamic subtest numvfs-2: FAIL (0.007s)
[530.875670] Starting dynamic subtest: numvfs-3
[530.949469] Dynamic subtest numvfs-3: FAIL (0.004s)
[530.960508] Starting dynamic subtest: numvfs-4
[531.032558] Dynamic subtest numvfs-4: FAIL (0.004s)
[531.043835] Starting dynamic subtest: numvfs-5
[531.117644] Dynamic subtest numvfs-5: FAIL (0.004s)
[531.128255] Starting dynamic subtest: numvfs-6
[531.200630] Dynamic subtest numvfs-6: FAIL (0.006s)
[531.211247] Starting dynamic subtest: numvfs-7
[531.284633] Dynamic subtest numvfs-7: FAIL (0.004s)
[531.296123] Starting dynamic subtest: numvfs-random
[531.367442] Dynamic subtest numvfs-random: FAIL (0.007s)
[531.377775] Starting dynamic subtest: numvfs-all
[531.450968] Dynamic subtest numvfs-all: FAIL (0.516s)
[531.453317] Subtest enable-vfs-autoprobe-on: FAIL (0.567s)
[531.493499] [099/133] (489s left) kms_chamelium_frames (vga-frame-dump)
[531.883405] Subtest vga-frame-dump: SKIP (0.000s)
[532.653373] [100/133] (488s left) gem_eio (throttle)
[532.845290] Starting subtest: throttle
[533.115076] Subtest throttle: SUCCESS (0.269s)
[533.273743] [101/133] (488s left) kms_frontbuffer_tracking (psr-abgr161616f-draw-mmap-wc)
[533.370104] Starting subtest: psr-abgr161616f-draw-mmap-wc
[533.374345] Subtest psr-abgr161616f-draw-mmap-wc: SKIP (0.000s)
[533.503196] [102/133] (488s left) gem_softpin (allocator-nopin)
[533.570443] Starting subtest: allocator-nopin
[533.679402] Subtest allocator-nopin: SUCCESS (0.110s)
[533.856438] [103/133] (487s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[533.967275] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[534.669099] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.703s)
[534.880919] [104/133] (486s left) kms_flip (busy-flip)
[534.975018] Starting subtest: busy-flip
[534.977333] Starting dynamic subtest: A-eDP1
[536.688214] Dynamic subtest A-eDP1: SUCCESS (1.712s)
[536.699896] Starting dynamic subtest: B-eDP1
[538.338189] Dynamic subtest B-eDP1: SUCCESS (1.648s)
[538.350045] Starting dynamic subtest: C-eDP1
[540.058396] Dynamic subtest C-eDP1: SUCCESS (1.719s)
[540.068228] Starting dynamic subtest: D-eDP1
[541.703313] Dynamic subtest D-eDP1: SUCCESS (1.643s)
[541.708760] Subtest busy-flip: SUCCESS (6.734s)
[542.983030] [105/133] (478s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[543.075336] Starting subtest: x-tiled-16bpp-rotate-270
[543.095048] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[543.256701] [106/133] (478s left) kms_frontbuffer_tracking (fbcpsr-abgr161616f-draw-render)
[543.360938] Starting subtest: fbcpsr-abgr161616f-draw-render
[543.365059] Subtest fbcpsr-abgr161616f-draw-render: SKIP (0.000s)
[543.484957] [107/133] (478s left) kms_getfb (getfb2-accept-nv12)
[543.580502] Starting subtest: getfb2-accept-nv12
[543.582838] Subtest getfb2-accept-nv12: SUCCESS (0.001s)
[543.719468] [108/133] (478s left) gem_ctx_exec (basic-nohangcheck)
[543.786059] Starting subtest: basic-nohangcheck
[544.047251] Subtest basic-nohangcheck: SUCCESS (0.262s)
[544.210233] [109/133] (477s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[544.345392] Starting subtest: short-flip-before-cursor-atomic-transitions
[545.332310] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.985s)
[546.229637] [110/133] (475s left) kms_cursor_crc (cursor-offscreen-256x256)
[546.476839] Starting subtest: cursor-offscreen-256x256
[546.482060] Starting dynamic subtest: pipe-A-eDP-1
[548.410020] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.929s)
[548.440671] Starting dynamic subtest: pipe-D-eDP-1
[551.005797] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.573s)
[551.008522] Subtest cursor-offscreen-256x256: SUCCESS (4.528s)
[551.895426] [111/133] (470s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[552.005998] Starting subtest: 4-tiled-8bpp-rotate-0
[553.230797] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.224s)
[553.402436] [112/133] (468s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[553.468230] Subtest writes-after-reads: SKIP (0.000s)
[553.556792] [113/133] (468s left) kms_frontbuffer_tracking (psr-slowdraw)
[553.658943] Starting subtest: psr-slowdraw
[578.912654] Subtest psr-slowdraw: SUCCESS (25.253s)
[579.084945] [114/133] (443s left) i915_pm_rc6_residency (rc6-idle)
[579.142447] Starting subtest: rc6-idle
[579.167322] Starting dynamic subtest: gt0-rcs0
[603.587702] Dynamic subtest gt0-rcs0: SUCCESS (24.420s)
[603.599487] Starting dynamic subtest: gt0-bcs0
[628.024822] Dynamic subtest gt0-bcs0: SUCCESS (24.433s)
[628.034462] Starting dynamic subtest: gt0-ccs0
[652.491040] Dynamic subtest gt0-ccs0: SUCCESS (24.464s)
[652.502716] Starting dynamic subtest: gt1-vcs0
[658.666504] Dynamic subtest gt1-vcs0: SUCCESS (6.170s)
[658.678478] Starting dynamic subtest: gt1-vecs0
[664.837471] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[664.840480] Subtest rc6-idle: SUCCESS (85.696s)
[664.942770] [115/133] (357s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[665.065097] Starting subtest: fbc-modesetfrombusy
[668.862908] Subtest fbc-modesetfrombusy: SUCCESS (3.802s)
[670.053591] [116/133] (352s left) kms_psr (psr-cursor-render)
[670.162156] Starting subtest: psr-cursor-render
[670.164442] Starting dynamic subtest: eDP-1
[671.909021] Dynamic subtest eDP-1: SUCCESS (1.745s)
[671.911373] Subtest psr-cursor-render: SUCCESS (1.746s)
[672.798783] [117/133] (349s left) gem_exec_parallel (engines)
[672.867731] Starting subtest: engines
[672.870211] Starting dynamic subtest: basic
[674.902209] Dynamic subtest basic: SUCCESS (2.032s)
[674.914695] Starting dynamic subtest: contexts
[679.345965] Dynamic subtest contexts: SUCCESS (4.441s)
[679.357825] Starting dynamic subtest: fds
[683.155683] Dynamic subtest fds: SUCCESS (3.807s)
[683.167913] Starting dynamic subtest: userptr
[684.936351] Dynamic subtest userptr: SUCCESS (1.780s)
[684.939272] Subtest engines: SUCCESS (12.068s)
[685.162610] [118/133] (337s left) prime_busy (after)
[685.233088] Starting subtest: after
[685.235404] Starting dynamic subtest: rcs0
[685.286544] Dynamic subtest rcs0: SUCCESS (0.051s)
[685.296976] Starting dynamic subtest: bcs0
[685.328698] Dynamic subtest bcs0: SUCCESS (0.042s)
[685.337968] Starting dynamic subtest: vcs0
[685.369202] Dynamic subtest vcs0: SUCCESS (0.041s)
[685.378795] Starting dynamic subtest: vcs1
[685.411029] Dynamic subtest vcs1: SUCCESS (0.041s)
[685.426435] Starting dynamic subtest: vecs0
[685.453828] Dynamic subtest vecs0: SUCCESS (0.042s)
[685.464620] Starting dynamic subtest: ccs0
[685.494465] Dynamic subtest ccs0: SUCCESS (0.040s)
[685.603226] Subtest after: SUCCESS (0.371s)
[685.705353] [119/133] (336s left) gem_partial_pwrite_pread (write-display)
[685.768081] Subtest write-display: SKIP (0.000s)
[685.850109] [120/133] (336s left) gem_tiled_blits (basic)
[685.908154] Subtest basic: SKIP (0.000s)
[686.002223] [121/133] (336s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[686.093349] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[694.767254] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.676s)
[694.964289] [122/133] (327s left) sysfs_defaults (readonly)
[695.034387] Starting subtest: readonly
[695.036700] Starting dynamic subtest: vecs0
[695.039059] Dynamic subtest vecs0: SUCCESS (0.000s)
[695.050467] Starting dynamic subtest: ccs0
[695.052350] Dynamic subtest ccs0: SUCCESS (0.000s)
[695.061230] Starting dynamic subtest: vcs0
[695.063092] Dynamic subtest vcs0: SUCCESS (0.000s)
[695.072554] Starting dynamic subtest: bcs0
[695.074727] Dynamic subtest bcs0: SUCCESS (0.000s)
[695.084125] Starting dynamic subtest: rcs0
[695.085987] Dynamic subtest rcs0: SUCCESS (0.000s)
[695.095033] Starting dynamic subtest: vcs1
[695.096945] Dynamic subtest vcs1: SUCCESS (0.000s)
[695.098851] Subtest readonly: SUCCESS (0.006s)
[695.174370] [123/133] (327s left) kms_dsc (dsc-with-formats)
[695.262120] Starting subtest: dsc-with-formats
[695.302272] Subtest dsc-with-formats: SKIP (0.001s)
[695.367290] [124/133] (327s left) prime_mmap (test_reprime)
[695.423534] Starting subtest: test_reprime
[695.425508] Starting dynamic subtest: test_reprime-smem
[695.451834] Dynamic subtest test_reprime-smem: SUCCESS (0.031s)
[695.454313] Subtest test_reprime: SUCCESS (0.031s)
[695.631175] [125/133] (327s left) gem_exec_params (rsvd2-dirt)
[695.694346] Starting subtest: rsvd2-dirt
[695.697949] Subtest rsvd2-dirt: SKIP (0.000s)
[695.826912] [126/133] (326s left) kms_lease (lessee-list)
[695.924332] Starting subtest: lessee-list
[695.926088] Starting dynamic subtest: pipe-A-eDP-1
[695.927918] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[695.937553] Starting dynamic subtest: pipe-B-eDP-1
[695.939256] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[695.948501] Starting dynamic subtest: pipe-C-eDP-1
[695.950069] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[695.958967] Starting dynamic subtest: pipe-D-eDP-1
[695.960560] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[695.962117] Subtest lessee-list: SUCCESS (0.008s)
[696.053105] [127/133] (326s left) kms_vrr (flip-dpms)
[696.148095] Starting subtest: flip-dpms
[696.153569] Subtest flip-dpms: SKIP (0.000s)
[696.245387] [128/133] (326s left) kms_frontbuffer_tracking (fbc-1p-rte)
[696.341192] Starting subtest: fbc-1p-rte
[701.883926] Subtest fbc-1p-rte: SUCCESS (5.545s)
[703.109502] [129/133] (319s left) gen9_exec_parse (batch-zero-length)
[703.169273] Subtest batch-zero-length: SKIP (0.000s)
[703.244088] [130/133] (319s left) prime_mmap (test_aperture_limit)
[703.304527] Starting subtest: test_aperture_limit
[703.306753] Starting dynamic subtest: test_aperture_limit-smem
[703.314091] Dynamic subtest test_aperture_limit-smem: SKIP (0.008s)
[703.318227] Subtest test_aperture_limit: SKIP (0.009s)
[703.451219] [131/133] (319s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[703.555200] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[703.559566] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[703.662436] [132/133] (319s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[703.753335] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[703.757379] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[703.881769] [133/133] (319s left) gem_exec_fence (long-history)
[704.337486] Starting subtest: long-history
[711.731059] Subtest long-history: SUCCESS (7.394s)
[712.257354] 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.