[59.468579] Initializing watchdogs
[59.468623]   /dev/watchdog0
[59.482083] [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]
[59.486169] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.488349] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.491569] [001/133] (960s left) gem_create (create-ext-placement-all)
[59.680877] Starting subtest: create-ext-placement-all
[59.683832] Subtest create-ext-placement-all: SUCCESS (0.000s)
[59.814954] [002/133] (960s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[59.877986] Starting subtest: map-fixed-invalidate-overlap
[59.882214] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[60.037236] [003/133] (960s left) gem_pipe_control_store_loop (fresh-buffer)
[60.108553] Starting subtest: fresh-buffer
[62.252316] Subtest fresh-buffer: SUCCESS (2.155s)
[62.409365] [004/133] (957s left) gem_mmap_offset (mmap-boundaries)
[62.473513] Starting subtest: mmap-boundaries
[62.475863] Starting dynamic subtest: smem0
[62.752914] Dynamic subtest smem0: SUCCESS (0.283s)
[62.755563] Subtest mmap-boundaries: SUCCESS (0.283s)
[62.911848] [005/133] (957s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[63.010065] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[70.370884] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.389s)
[70.518756] [006/133] (949s left) kms_atomic (atomic-plane-damage)
[70.611461] Starting subtest: atomic-plane-damage
[70.691895] Starting dynamic subtest: pipe-A-eDP-1
[71.207157] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.516s)
[71.209100] Subtest atomic-plane-damage: SUCCESS (0.597s)
[72.079163] [007/133] (948s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[72.185078] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[72.187367] Starting dynamic subtest: pipe-A-eDP-1
[72.256912] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.073s)
[72.265965] Starting dynamic subtest: pipe-B-eDP-1
[72.316423] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.059s)
[72.324394] Starting dynamic subtest: pipe-C-eDP-1
[72.376630] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.059s)
[72.385086] Starting dynamic subtest: pipe-D-eDP-1
[72.436566] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.060s)
[72.438775] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.254s)
[74.646518] [008/133] (945s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[74.765050] Starting subtest: psr-rgb101010-draw-mmap-wc
[81.690143] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (6.939s)
[81.823218] [009/133] (938s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[81.893720] Starting subtest: yf-tiled-to-vebox-x-tiled
[81.898176] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[82.092810] [010/133] (938s left) syncobj_timeline (wait-all-complex)
[82.141703] Starting subtest: wait-all-complex
[82.355088] Subtest wait-all-complex: SUCCESS (0.212s)
[82.452623] [011/133] (937s left) gem_pxp (create-valid-protected-context)
[82.588240] Starting subtest: create-valid-protected-context
[82.591073] Subtest create-valid-protected-context: SUCCESS (0.002s)
[82.732716] [012/133] (937s left) gem_exec_flush (basic-wb-rw-default)
[82.799520] Starting subtest: basic-wb-rw-default
[88.248210] Subtest basic-wb-rw-default: SUCCESS (5.449s)
[88.451902] [013/133] (931s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[88.558562] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[88.560903] Starting dynamic subtest: pipe-A-eDP-1
[88.580154] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[88.589328] Starting dynamic subtest: pipe-B-eDP-1
[88.608767] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[88.615578] Starting dynamic subtest: pipe-C-eDP-1
[88.634762] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[88.641818] Starting dynamic subtest: pipe-D-eDP-1
[88.661179] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[88.663362] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[90.820116] [014/133] (929s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[90.914073] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[95.052907] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.142s)
[96.226996] [015/133] (924s left) kms_cursor_edge_walk (256x256-top-bottom)
[96.322129] Starting subtest: 256x256-top-bottom
[96.324432] Starting dynamic subtest: pipe-A-eDP-1
[99.750266] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.429s)
[99.761587] Starting dynamic subtest: pipe-D-eDP-1
[102.879109] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.125s)
[102.881411] Subtest 256x256-top-bottom: SUCCESS (6.557s)
[103.710933] [016/133] (916s left) kms_force_connector_basic (force-load-detect)
[103.795861] Starting subtest: force-load-detect
[103.800380] Subtest force-load-detect: SKIP (0.000s)
[103.926645] [017/133] (916s left) syncobj_wait (invalid-signal-zero-handles)
[103.982081] Starting subtest: invalid-signal-zero-handles
[103.984391] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[104.108460] [018/133] (916s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[109.043515] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[109.045915] Starting dynamic subtest: pipe-A-eDP-1
[109.852324] Dynamic subtest pipe-A-eDP-1: SKIP (0.808s)
[109.862747] Starting dynamic subtest: pipe-B-eDP-1
[110.987109] Dynamic subtest pipe-B-eDP-1: SKIP (1.132s)
[110.991533] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.944s)
[112.201206] [019/133] (908s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[112.308535] Starting subtest: fbcpsr-rgb565-draw-render
[119.315651] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.009s)
[119.481015] [020/133] (901s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[119.582534] Starting subtest: primary-4-tiled-reflect-x-0
[126.584488] Subtest primary-4-tiled-reflect-x-0: SUCCESS (7.001s)
[127.767924] [021/133] (892s left) kms_setmode (clone-exclusive-crtc)
[127.838548] Starting subtest: clone-exclusive-crtc
[127.845313] Subtest clone-exclusive-crtc: SKIP (0.000s)
[127.955155] [022/133] (892s left) kms_addfb_basic (too-high)
[128.044848] Starting subtest: too-high
[128.047093] Subtest too-high: SUCCESS (0.000s)
[128.154331] [023/133] (892s left) perf_pmu (busy-accuracy-2)
[128.194889] Starting subtest: busy-accuracy-2
[128.197197] Starting dynamic subtest: rcs0
[131.241416] Dynamic subtest rcs0: SUCCESS (3.043s)
[131.251156] Starting dynamic subtest: bcs0
[134.301064] Dynamic subtest bcs0: SUCCESS (3.058s)
[134.310928] Starting dynamic subtest: vcs0
[137.346545] Dynamic subtest vcs0: SUCCESS (3.043s)
[137.356665] Starting dynamic subtest: vcs1
[140.390969] Dynamic subtest vcs1: SUCCESS (3.042s)
[140.401029] Starting dynamic subtest: vecs0
[143.438514] Dynamic subtest vecs0: SUCCESS (3.045s)
[143.448853] Starting dynamic subtest: ccs0
[146.498303] Dynamic subtest ccs0: SUCCESS (3.057s)
[146.500794] Subtest busy-accuracy-2: SUCCESS (18.300s)
[146.639864] [024/133] (874s left) kms_psr (pr-primary-mmap-gtt)
[146.758174] Starting subtest: pr-primary-mmap-gtt
[146.762308] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[146.853812] [025/133] (874s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[146.960314] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[146.964465] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[147.099594] [026/133] (873s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[147.196018] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[147.200566] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[147.369315] [027/133] (873s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[147.463176] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[147.470387] Starting dynamic subtest: pipe-A-default-mode
[150.196076] Dynamic subtest pipe-A-default-mode: SUCCESS (2.734s)
[150.198736] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.735s)
[151.061525] [028/133] (869s left) i915_drm_fdinfo (idle)
[151.102947] Starting subtest: idle
[151.105523] Starting dynamic subtest: rcs0
[151.694050] Dynamic subtest rcs0: SUCCESS (0.590s)
[151.705265] Starting dynamic subtest: bcs0
[152.200332] Dynamic subtest bcs0: SUCCESS (0.503s)
[152.212097] Starting dynamic subtest: vcs0
[152.705128] Dynamic subtest vcs0: SUCCESS (0.503s)
[152.716241] Starting dynamic subtest: vcs1
[153.209467] Dynamic subtest vcs1: SUCCESS (0.503s)
[153.220192] Starting dynamic subtest: vecs0
[153.714435] Dynamic subtest vecs0: SUCCESS (0.503s)
[153.725822] Starting dynamic subtest: ccs0
[154.219386] Dynamic subtest ccs0: SUCCESS (0.503s)
[154.222114] Subtest idle: SUCCESS (3.116s)
[154.317512] [029/133] (866s left) core_setmaster (master-drop-set-shared-fd)
[154.423886] Starting subtest: master-drop-set-shared-fd
[154.434235] Subtest master-drop-set-shared-fd: SUCCESS (0.011s)
[154.469325] [030/133] (866s left) kms_chamelium_color (ctm-negative)
[154.582327] Subtest ctm-negative: SKIP (0.000s)
[154.627310] [031/133] (866s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[154.690420] Starting subtest: multi-wait-for-submit-available-submitted
[154.692666] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.000s)
[154.839192] [032/133] (866s left) syncobj_timeline (invalid-signal-illegal-point)
[154.903193] Starting subtest: invalid-signal-illegal-point
[154.905352] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[155.023050] [033/133] (866s left) gem_mmap (bad-offset)
[155.085387] Subtest bad-offset: SKIP (0.000s)
[155.150961] [034/133] (866s left) i915_hangman (gt-error-state-capture)
[155.221140] Starting subtest: gt-error-state-capture
[155.223419] Starting dynamic subtest: rcs0
[166.902253] Dynamic subtest rcs0: SUCCESS (11.680s)
[166.913522] Starting dynamic subtest: bcs0
[177.907420] Dynamic subtest bcs0: SUCCESS (11.003s)
[177.919075] Starting dynamic subtest: vcs0
[189.811984] Dynamic subtest vcs0: SUCCESS (11.902s)
[189.823763] Starting dynamic subtest: vcs1
[199.796955] Dynamic subtest vcs1: SUCCESS (9.981s)
[199.808527] Starting dynamic subtest: vecs0
[210.804748] Dynamic subtest vecs0: SUCCESS (11.005s)
[210.816343] Starting dynamic subtest: ccs0
[220.852417] Dynamic subtest ccs0: SUCCESS (10.045s)
[220.855409] Subtest gt-error-state-capture: SUCCESS (65.630s)
[221.030242] [035/133] (800s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[221.141961] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[221.144139] Starting dynamic subtest: pipe-A-eDP-1
[221.152722] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[221.162125] Starting dynamic subtest: pipe-B-eDP-1
[221.170784] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[221.179171] Starting dynamic subtest: pipe-C-eDP-1
[221.187618] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[221.195018] Starting dynamic subtest: pipe-D-eDP-1
[221.203098] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[221.207050] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[223.412946] [036/133] (797s left) gem_exec_whisper (basic-fds)
[223.488828] Starting subtest: basic-fds
[230.950021] Subtest basic-fds: SUCCESS (7.460s)
[231.153320] [037/133] (790s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[231.257424] Starting subtest: psr-1p-primscrn-cur-indfb-move
[238.641570] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.387s)
[238.822533] [038/133] (782s left) gem_exec_schedule (preempt-user)
[238.898241] Starting subtest: preempt-user
[238.900379] Starting dynamic subtest: rcs0
[238.912386] Dynamic subtest rcs0: SUCCESS (0.022s)
[238.921901] Starting dynamic subtest: bcs0
[238.930272] Dynamic subtest bcs0: SUCCESS (0.017s)
[238.938345] Starting dynamic subtest: vcs0
[238.950079] Dynamic subtest vcs0: SUCCESS (0.019s)
[238.959062] Starting dynamic subtest: vcs1
[238.970567] Dynamic subtest vcs1: SUCCESS (0.020s)
[238.979906] Starting dynamic subtest: vecs0
[238.990977] Dynamic subtest vecs0: SUCCESS (0.020s)
[238.999313] Starting dynamic subtest: ccs0
[239.012248] Dynamic subtest ccs0: SUCCESS (0.020s)
[239.014519] Subtest preempt-user: SUCCESS (0.122s)
[241.042301] [039/133] (780s left) kms_psr (psr2-primary-mmap-cpu)
[241.151086] Starting subtest: psr2-primary-mmap-cpu
[241.153390] Starting dynamic subtest: eDP-1
[242.908979] Dynamic subtest eDP-1: SUCCESS (1.757s)
[242.911242] Subtest psr2-primary-mmap-cpu: SUCCESS (1.759s)
[243.802788] [040/133] (777s left) gem_ctx_param (invalid-size-set)
[243.868649] Starting subtest: invalid-size-set
[243.871111] Subtest invalid-size-set: SUCCESS (0.001s)
[244.003110] [041/133] (777s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[244.104936] Starting subtest: bad-aux-stride-y-tiled-ccs
[244.107169] Starting dynamic subtest: pipe-A-eDP-1
[244.128889] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[244.139676] Starting dynamic subtest: pipe-B-eDP-1
[244.159969] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[244.167707] Starting dynamic subtest: pipe-C-eDP-1
[244.188304] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[244.196485] Starting dynamic subtest: pipe-D-eDP-1
[244.217320] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[244.221492] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s)
[246.286717] [042/133] (775s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[246.386305] Starting subtest: blocking-absolute-wf_vblank-interruptible
[246.390662] Starting dynamic subtest: A-eDP1
[255.120203] Dynamic subtest A-eDP1: SUCCESS (8.729s)
[255.130531] Starting dynamic subtest: B-eDP1
[263.851290] Dynamic subtest B-eDP1: SUCCESS (8.730s)
[263.863612] Starting dynamic subtest: C-eDP1
[272.570174] Dynamic subtest C-eDP1: SUCCESS (8.717s)
[272.580072] Starting dynamic subtest: D-eDP1
[281.299367] Dynamic subtest D-eDP1: SUCCESS (8.729s)
[281.304890] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.917s)
[282.537088] [043/133] (739s left) kms_psr2_su (page_flip-nv12)
[282.604535] Subtest page_flip-NV12: SKIP (0.000s)
[282.668674] [044/133] (738s left) gem_exec_balancer (sliced)
[282.742980] Starting subtest: sliced
[282.747359] Subtest sliced: SKIP (0.000s)
[283.051798] [045/133] (738s left) kms_plane_lowres (tiling-x)
[283.150039] Starting subtest: tiling-x
[283.152146] Starting dynamic subtest: pipe-A-eDP-1
[283.156454] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[283.445209] Starting dynamic subtest: pipe-B-eDP-1
[283.449833] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[283.460019] Starting dynamic subtest: pipe-C-eDP-1
[283.464309] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[283.472555] Starting dynamic subtest: pipe-D-eDP-1
[283.476753] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[283.481035] Subtest tiling-x: SKIP (0.292s)
[284.260097] [046/133] (737s left) kms_vblank (query-busy-hang)
[284.367066] Starting subtest: query-busy-hang
[284.369390] Starting dynamic subtest: pipe-A-eDP-1
[310.706031] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.338s)
[310.721455] Starting dynamic subtest: pipe-D-eDP-1
[335.627057] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.918s)
[335.629659] Subtest query-busy-hang: SUCCESS (51.262s)
[336.517912] [047/133] (685s left) gem_exec_balancer (hang)
[336.767696] Starting subtest: hang
[338.403765] Subtest hang: SUCCESS (1.637s)
[338.580366] [048/133] (683s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[338.696470] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[338.700789] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[338.835722] [049/133] (683s left) kms_flip (bo-too-big)
[338.925345] Starting subtest: bo-too-big
[338.927275] Starting dynamic subtest: A-eDP1
[340.249555] Dynamic subtest A-eDP1: SUCCESS (1.326s)
[340.259869] Starting dynamic subtest: B-eDP1
[341.575884] Dynamic subtest B-eDP1: SUCCESS (1.325s)
[341.587283] Starting dynamic subtest: C-eDP1
[342.907761] Dynamic subtest C-eDP1: SUCCESS (1.331s)
[342.919526] Starting dynamic subtest: D-eDP1
[344.237772] Dynamic subtest D-eDP1: SUCCESS (1.328s)
[344.240611] Subtest bo-too-big: SUCCESS (5.318s)
[345.476951] [050/133] (676s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[345.532340] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[345.537048] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[345.742566] [051/133] (676s left) kms_cursor_legacy (single-bo)
[345.859822] Starting subtest: single-bo
[345.861860] Starting dynamic subtest: pipe-A
[351.241680] Dynamic subtest pipe-A: SUCCESS (5.379s)
[351.252834] Starting dynamic subtest: pipe-B
[356.623243] Dynamic subtest pipe-B: SUCCESS (5.379s)
[356.633838] Starting dynamic subtest: pipe-C
[362.004775] Dynamic subtest pipe-C: SUCCESS (5.378s)
[362.015298] Starting dynamic subtest: pipe-D
[367.386760] Dynamic subtest pipe-D: SUCCESS (5.379s)
[367.397853] Starting dynamic subtest: all-pipes
[372.775877] Dynamic subtest all-pipes: SUCCESS (5.386s)
[372.778259] Subtest single-bo: SUCCESS (26.916s)
[372.941257] [052/133] (649s left) kms_pm_rpm (basic-pci-d3-state)
[375.065069] Starting subtest: basic-pci-d3-state
[376.540908] Subtest basic-pci-d3-state: SUCCESS (1.475s)
[376.650414] [053/133] (645s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[376.742849] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[381.070937] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.332s)
[382.232774] [054/133] (639s left) kms_pm_rpm (legacy-planes)
[384.067471] Starting subtest: legacy-planes
[384.069634] Starting dynamic subtest: plane-63
[392.356710] Dynamic subtest plane-63: SUCCESS (8.292s)
[392.368317] Starting dynamic subtest: plane-93
[400.652532] Dynamic subtest plane-93: SUCCESS (8.294s)
[400.662692] Starting dynamic subtest: plane-123
[409.253106] Dynamic subtest plane-123: SUCCESS (8.598s)
[409.264239] Starting dynamic subtest: plane-133
[417.526923] Dynamic subtest plane-133: SUCCESS (8.270s)
[417.530055] Subtest legacy-planes: SUCCESS (33.463s)
[417.928056] [055/133] (604s left) prime_self_import (basic-with_one_bo)
[417.963816] Starting subtest: basic-with_one_bo
[417.988998] Subtest basic-with_one_bo: SUCCESS (0.026s)
[418.129887] [056/133] (604s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[418.238274] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[418.242527] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[418.384009] [057/133] (603s left) kms_flip (modeset-vs-vblank-race)
[418.483244] Starting subtest: modeset-vs-vblank-race
[418.485523] Starting dynamic subtest: A-eDP1
[423.212199] Dynamic subtest A-eDP1: SUCCESS (4.731s)
[423.229908] Starting dynamic subtest: B-eDP1
[427.943915] Dynamic subtest B-eDP1: SUCCESS (4.729s)
[427.958362] Starting dynamic subtest: C-eDP1
[432.633541] Dynamic subtest C-eDP1: SUCCESS (4.687s)
[432.644458] Starting dynamic subtest: D-eDP1
[437.178391] Dynamic subtest D-eDP1: SUCCESS (4.544s)
[437.183709] Subtest modeset-vs-vblank-race: SUCCESS (18.702s)
[438.464689] [058/133] (583s left) gem_lmem_swapping (heavy-verify-random-ccs)
[438.505408] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[438.548795] [059/133] (583s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[438.641823] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[438.646269] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[438.772444] [060/133] (583s left) kms_chamelium_color (gamma)
[438.862776] Subtest gamma: SKIP (0.000s)
[438.905543] [061/133] (583s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[439.021913] Starting subtest: cursorB-vs-flipA-varying-size
[439.026503] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[439.186344] [062/133] (583s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[439.289294] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[443.702646] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.417s)
[444.868410] [063/133] (577s left) dumb_buffer (map-valid)
[444.932023] Starting subtest: map-valid
[444.934476] Subtest map-valid: SUCCESS (0.000s)
[445.059118] [064/133] (577s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[445.160892] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[445.163065] Starting dynamic subtest: pipe-A-default-mode
[445.467796] Dynamic subtest pipe-A-default-mode: SKIP (0.306s)
[445.472219] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.307s)
[446.287205] [065/133] (576s left) perf (i915-ref-count)
[446.380784] Starting subtest: i915-ref-count
[446.440069] Subtest i915-ref-count: SUCCESS (0.061s)
[446.532333] [066/133] (575s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[446.620587] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[446.624613] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[446.750904] [067/133] (575s left) gem_softpin (noreloc-interruptible)
[446.803749] Starting subtest: noreloc-interruptible
[452.191880] Subtest noreloc-interruptible: SUCCESS (5.387s)
[452.324511] [068/133] (570s left) kms_psr (fbc-psr2-primary-render)
[452.427792] Starting subtest: fbc-psr2-primary-render
[452.430239] Starting dynamic subtest: eDP-1
[452.434983] Dynamic subtest eDP-1: SKIP (0.000s)
[452.439250] Subtest fbc-psr2-primary-render: SKIP (0.003s)
[452.528996] [069/133] (570s left) kms_lease (atomic-implicit-crtc)
[452.629084] Starting subtest: atomic-implicit-crtc
[452.631470] Starting dynamic subtest: pipe-A-eDP-1
[452.642744] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[452.653077] Starting dynamic subtest: pipe-B-eDP-1
[452.664133] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.009s)
[452.672247] Starting dynamic subtest: pipe-C-eDP-1
[452.682405] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.009s)
[452.690609] Starting dynamic subtest: pipe-D-eDP-1
[452.701185] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.009s)
[452.703113] Subtest atomic-implicit-crtc: SUCCESS (0.035s)
[452.791859] [070/133] (569s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[452.878848] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[453.269074] Starting dynamic subtest: pipe-A-eDP-1
[456.035687] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.765s)
[456.398443] Starting dynamic subtest: pipe-B-eDP-1
[459.163071] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.770s)
[459.529681] Starting dynamic subtest: pipe-C-eDP-1
[462.290508] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.767s)
[462.651157] Starting dynamic subtest: pipe-D-eDP-1
[465.413529] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.770s)
[465.415981] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (12.535s)
[466.614493] [071/133] (556s left) gem_exec_reloc (basic-wc-read-active)
[466.687086] Subtest basic-wc-read-active: SKIP (0.000s)
[466.784449] [072/133] (555s left) gem_readwrite (new-obj)
[466.840973] Subtest new-obj: SKIP (0.000s)
[466.908082] [073/133] (555s left) gem_mmap_gtt (basic-short)
[466.979896] Subtest basic-short: SKIP (0.000s)
[467.040228] [074/133] (555s left) kms_plane_multiple (tiling-yf)
[467.137710] Starting subtest: tiling-yf
[467.142093] Subtest tiling-yf: SKIP (0.000s)
[467.234474] [075/133] (555s left) kms_content_protection (dp-mst-type-0-hdcp14)
[467.369663] Starting subtest: dp-mst-type-0-hdcp14
[467.374282] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[467.531875] [076/133] (555s left) gem_ctx_shared (q-in-order)
[467.593482] Starting subtest: Q-in-order
[467.595511] Starting dynamic subtest: rcs0
[467.754050] Dynamic subtest rcs0: SUCCESS (0.160s)
[467.771800] Starting dynamic subtest: vcs0
[467.839518] Dynamic subtest vcs0: SUCCESS (0.084s)
[467.854463] Starting dynamic subtest: vcs1
[467.913730] Dynamic subtest vcs1: SUCCESS (0.074s)
[467.930907] Starting dynamic subtest: vecs0
[467.988488] Dynamic subtest vecs0: SUCCESS (0.074s)
[468.005539] Starting dynamic subtest: ccs0
[468.080952] Dynamic subtest ccs0: SUCCESS (0.091s)
[468.083554] Subtest Q-in-order: SUCCESS (0.487s)
[469.019215] [FACT gem_ctx_shared (q-in-order)] new: kernel.kmod_is_loaded.vgem: true
[469.022560] [077/133] (553s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[469.078465] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[469.185934] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.107s)
[469.297099] [078/133] (553s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[469.395746] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[475.987345] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.594s)
[476.162298] [079/133] (546s left) kms_big_fb (linear-8bpp-rotate-270)
[476.281406] Starting subtest: linear-8bpp-rotate-270
[476.303146] Subtest linear-8bpp-rotate-270: SKIP (0.021s)
[476.459740] [080/133] (546s left) api_intel_allocator (standalone)
[476.523125] Starting subtest: standalone
[476.538119] Subtest standalone: SUCCESS (0.017s)
[476.696775] [081/133] (546s left) core_auth (getclient-master-drop)
[476.734237] Starting subtest: getclient-master-drop
[476.759214] Subtest getclient-master-drop: SUCCESS (0.027s)
[476.896087] [082/133] (546s left) kms_psr (fbc-psr2-basic)
[476.992710] Starting subtest: fbc-psr2-basic
[476.994595] Starting dynamic subtest: eDP-1
[476.998085] Dynamic subtest eDP-1: SKIP (0.000s)
[477.001420] Subtest fbc-psr2-basic: SKIP (0.001s)
[477.092348] [083/133] (546s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[477.234178] Starting subtest: cursor-rapid-movement-32x32
[477.278939] Subtest cursor-rapid-movement-32x32: SKIP (0.040s)
[477.609389] [084/133] (545s left) gem_exec_schedule (noreorder-priority)
[477.790786] Starting subtest: noreorder-priority
[477.793259] Starting dynamic subtest: rcs0
[478.901858] Dynamic subtest rcs0: SUCCESS (1.112s)
[478.918152] Starting dynamic subtest: vcs0
[480.901532] Dynamic subtest vcs0: SUCCESS (1.995s)
[480.911815] Starting dynamic subtest: vcs1
[482.884430] Dynamic subtest vcs1: SUCCESS (1.981s)
[482.896837] Starting dynamic subtest: vecs0
[484.869574] Dynamic subtest vecs0: SUCCESS (1.982s)
[484.881042] Starting dynamic subtest: ccs0
[486.045420] Dynamic subtest ccs0: SUCCESS (1.174s)
[486.048244] Subtest noreorder-priority: SUCCESS (8.256s)
[486.255537] [085/133] (537s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[486.363352] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[486.365606] Starting dynamic subtest: pipe-A-eDP-1
[486.390653] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[486.404891] Starting dynamic subtest: pipe-B-eDP-1
[486.428062] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[486.436618] Starting dynamic subtest: pipe-C-eDP-1
[486.459738] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[486.467913] Starting dynamic subtest: pipe-D-eDP-1
[486.493625] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[486.498272] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[488.699521] [086/133] (534s left) core_auth (many-magics)
[488.753590] Starting subtest: many-magics
[489.615096] Subtest many-magics: SUCCESS (0.864s)
[489.863473] [087/133] (533s left) i915_suspend (fence-restore-tiled2untiled)
[489.924584] Starting subtest: fence-restore-tiled2untiled
[489.929056] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[490.055386] [088/133] (533s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[490.147028] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[490.149305] Starting dynamic subtest: pipe-A
[490.169729] Dynamic subtest pipe-A: SKIP (0.018s)
[490.179663] Starting dynamic subtest: pipe-B
[490.202143] Dynamic subtest pipe-B: SKIP (0.032s)
[490.211066] Starting dynamic subtest: pipe-C
[490.233038] Dynamic subtest pipe-C: SKIP (0.029s)
[490.241742] Starting dynamic subtest: pipe-D
[490.261629] Dynamic subtest pipe-D: SKIP (0.029s)
[490.265898] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.112s)
[490.391353] [089/133] (533s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[490.491217] Starting subtest: y-tiled-64bpp-rotate-0
[490.495255] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[490.627502] [090/133] (532s left) gem_ctx_persistence (saturated-hostile)
[490.684851] Starting subtest: saturated-hostile
[490.687569] Starting dynamic subtest: rcs0
[491.511218] Dynamic subtest rcs0: SUCCESS (0.826s)
[491.522855] Starting dynamic subtest: bcs0
[492.299457] Dynamic subtest bcs0: SUCCESS (0.786s)
[492.310277] Starting dynamic subtest: vcs0
[493.085745] Dynamic subtest vcs0: SUCCESS (0.783s)
[493.097297] Starting dynamic subtest: vcs1
[493.900059] Dynamic subtest vcs1: SUCCESS (0.812s)
[493.911956] Starting dynamic subtest: vecs0
[494.714226] Dynamic subtest vecs0: SUCCESS (0.812s)
[494.725932] Starting dynamic subtest: ccs0
[495.558302] Dynamic subtest ccs0: SUCCESS (0.842s)
[495.560838] Subtest saturated-hostile: SUCCESS (4.875s)
[495.663105] [091/133] (527s left) kms_prop_blob (invalid-get-prop)
[495.728731] Starting subtest: invalid-get-prop
[495.730798] Subtest invalid-get-prop: SUCCESS (0.000s)
[495.874995] [092/133] (527s left) gem_mmap_gtt (basic-write)
[495.942826] Subtest basic-write: SKIP (0.000s)
[496.013793] [093/133] (527s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[496.141500] Starting subtest: cursorA-vs-flipA-varying-size
[507.305314] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.162s)
[508.133464] [094/133] (515s left) gem_linear_blits (interruptible)
[509.010705] Starting subtest: interruptible
[513.804526] Subtest interruptible: SUCCESS (4.796s)
[513.964855] [095/133] (509s left) gem_exec_params (sol-reset-invalid)
[514.068497] Starting subtest: sol-reset-invalid
[514.070649] Subtest sol-reset-invalid: SUCCESS (0.003s)
[516.050409] [096/133] (507s left) kms_color_pipeline (plane-lut1d)
[516.158902] Starting subtest: plane-lut1d
[516.171975] Starting dynamic subtest: pipe-A-eDP-1
[516.877306] Dynamic subtest pipe-A-eDP-1: FAIL (0.311s)
[517.595085] Starting dynamic subtest: pipe-B-eDP-1
[518.415306] Dynamic subtest pipe-B-eDP-1: FAIL (0.324s)
[519.024290] Starting dynamic subtest: pipe-C-eDP-1
[519.865688] Dynamic subtest pipe-C-eDP-1: FAIL (0.268s)
[520.354377] Starting dynamic subtest: pipe-D-eDP-1
[521.185064] Dynamic subtest pipe-D-eDP-1: FAIL (0.255s)
[521.187091] Subtest plane-lut1d: FAIL (4.748s)
[521.769400] [097/133] (502s left) gem_wait (busy)
[521.837684] Starting subtest: busy
[521.839974] Starting dynamic subtest: all-engines
[523.924814] Dynamic subtest all-engines: SUCCESS (2.085s)
[523.937736] Starting dynamic subtest: rcs0
[524.468049] Dynamic subtest rcs0: SUCCESS (0.542s)
[524.480066] Starting dynamic subtest: bcs0
[525.009126] Dynamic subtest bcs0: SUCCESS (0.540s)
[525.020559] Starting dynamic subtest: vcs0
[526.931052] Dynamic subtest vcs0: SUCCESS (1.921s)
[526.945903] Starting dynamic subtest: vcs1
[528.917651] Dynamic subtest vcs1: SUCCESS (1.982s)
[528.929845] Starting dynamic subtest: vecs0
[530.900731] Dynamic subtest vecs0: SUCCESS (1.980s)
[530.913104] Starting dynamic subtest: ccs0
[531.444495] Dynamic subtest ccs0: SUCCESS (0.542s)
[531.446967] Subtest busy: SUCCESS (9.607s)
[531.668943] [098/133] (492s left) sriov_basic (enable-vfs-autoprobe-on)
[531.732829] Starting subtest: enable-vfs-autoprobe-on
[531.735135] Starting dynamic subtest: numvfs-1
[531.807486] Dynamic subtest numvfs-1: FAIL (0.005s)
[531.817948] Starting dynamic subtest: numvfs-2
[531.889654] Dynamic subtest numvfs-2: FAIL (0.004s)
[531.900830] Starting dynamic subtest: numvfs-3
[531.974044] Dynamic subtest numvfs-3: FAIL (0.004s)
[531.984149] Starting dynamic subtest: numvfs-4
[532.057250] Dynamic subtest numvfs-4: FAIL (0.003s)
[532.067920] Starting dynamic subtest: numvfs-5
[532.140026] Dynamic subtest numvfs-5: FAIL (0.004s)
[532.150523] Starting dynamic subtest: numvfs-6
[532.223126] Dynamic subtest numvfs-6: FAIL (0.003s)
[532.233814] Starting dynamic subtest: numvfs-7
[532.306796] Dynamic subtest numvfs-7: FAIL (0.003s)
[532.316517] Starting dynamic subtest: numvfs-random
[532.386450] Dynamic subtest numvfs-random: FAIL (0.003s)
[532.396499] Starting dynamic subtest: numvfs-all
[532.469652] Dynamic subtest numvfs-all: FAIL (0.512s)
[532.471665] Subtest enable-vfs-autoprobe-on: FAIL (0.550s)
[532.510179] [099/133] (491s left) kms_chamelium_frames (vga-frame-dump)
[532.882062] Subtest vga-frame-dump: SKIP (0.000s)
[533.660262] [100/133] (490s left) gem_eio (throttle)
[533.864667] Starting subtest: throttle
[534.121017] Subtest throttle: SUCCESS (0.255s)
[534.304544] [101/133] (489s left) kms_frontbuffer_tracking (psr-abgr161616f-draw-mmap-wc)
[534.404139] Starting subtest: psr-abgr161616f-draw-mmap-wc
[534.408087] Subtest psr-abgr161616f-draw-mmap-wc: SKIP (0.000s)
[534.522470] [102/133] (489s left) gem_softpin (allocator-nopin)
[534.570566] Starting subtest: allocator-nopin
[534.667270] Subtest allocator-nopin: SUCCESS (0.096s)
[534.818130] [103/133] (489s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[534.919613] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[535.621918] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.703s)
[535.821962] [104/133] (488s left) kms_flip (busy-flip)
[535.920076] Starting subtest: busy-flip
[535.922252] Starting dynamic subtest: A-eDP1
[537.592518] Dynamic subtest A-eDP1: SUCCESS (1.674s)
[537.604272] Starting dynamic subtest: B-eDP1
[539.243374] Dynamic subtest B-eDP1: SUCCESS (1.649s)
[539.254581] Starting dynamic subtest: C-eDP1
[540.862653] Dynamic subtest C-eDP1: SUCCESS (1.618s)
[540.873811] Starting dynamic subtest: D-eDP1
[542.493481] Dynamic subtest D-eDP1: SUCCESS (1.628s)
[542.495786] Subtest busy-flip: SUCCESS (6.579s)
[543.774549] [105/133] (480s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[543.878603] Starting subtest: x-tiled-16bpp-rotate-270
[543.898271] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[544.058380] [106/133] (480s left) kms_frontbuffer_tracking (fbcpsr-abgr161616f-draw-render)
[544.166063] Starting subtest: fbcpsr-abgr161616f-draw-render
[544.169970] Subtest fbcpsr-abgr161616f-draw-render: SKIP (0.000s)
[544.316095] [107/133] (479s left) kms_getfb (getfb2-accept-nv12)
[544.412081] Starting subtest: getfb2-accept-nv12
[544.414358] Subtest getfb2-accept-nv12: SUCCESS (0.001s)
[544.551153] [108/133] (479s left) gem_ctx_exec (basic-nohangcheck)
[544.614474] Starting subtest: basic-nohangcheck
[544.876741] Subtest basic-nohangcheck: SUCCESS (0.262s)
[545.030708] [109/133] (479s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[545.150241] Starting subtest: short-flip-before-cursor-atomic-transitions
[546.100331] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.949s)
[546.909448] [110/133] (477s left) kms_cursor_crc (cursor-offscreen-256x256)
[547.147016] Starting subtest: cursor-offscreen-256x256
[547.152229] Starting dynamic subtest: pipe-A-eDP-1
[549.079442] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.930s)
[549.110591] Starting dynamic subtest: pipe-D-eDP-1
[551.685571] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.584s)
[551.687954] Subtest cursor-offscreen-256x256: SUCCESS (4.538s)
[552.569552] [111/133] (471s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[552.685081] Starting subtest: 4-tiled-8bpp-rotate-0
[553.888742] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.202s)
[554.024936] [112/133] (470s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[554.101083] Subtest writes-after-reads: SKIP (0.000s)
[554.178951] [113/133] (470s left) kms_frontbuffer_tracking (psr-slowdraw)
[554.281473] Starting subtest: psr-slowdraw
[579.367284] Subtest psr-slowdraw: SUCCESS (25.087s)
[579.529636] [114/133] (444s left) i915_pm_rc6_residency (rc6-idle)
[579.579454] Starting subtest: rc6-idle
[579.599673] Starting dynamic subtest: gt0-rcs0
[603.942965] Dynamic subtest gt0-rcs0: SUCCESS (24.342s)
[603.954234] Starting dynamic subtest: gt0-bcs0
[628.292191] Dynamic subtest gt0-bcs0: SUCCESS (24.346s)
[628.303950] Starting dynamic subtest: gt0-ccs0
[652.553333] Dynamic subtest gt0-ccs0: SUCCESS (24.258s)
[652.564693] Starting dynamic subtest: gt1-vcs0
[658.727990] Dynamic subtest gt1-vcs0: SUCCESS (6.170s)
[658.739533] Starting dynamic subtest: gt1-vecs0
[664.901820] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[664.904797] Subtest rc6-idle: SUCCESS (85.322s)
[665.011043] [115/133] (359s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[665.117803] Starting subtest: fbc-modesetfrombusy
[668.844129] Subtest fbc-modesetfrombusy: SUCCESS (3.732s)
[670.036374] [116/133] (354s left) kms_psr (psr-cursor-render)
[670.137135] Starting subtest: psr-cursor-render
[670.139336] Starting dynamic subtest: eDP-1
[671.901750] Dynamic subtest eDP-1: SUCCESS (1.763s)
[671.903743] Subtest psr-cursor-render: SUCCESS (1.764s)
[672.798579] [117/133] (351s left) gem_exec_parallel (engines)
[672.865731] Starting subtest: engines
[672.868089] Starting dynamic subtest: basic
[674.901695] Dynamic subtest basic: SUCCESS (2.034s)
[674.914204] Starting dynamic subtest: contexts
[679.315023] Dynamic subtest contexts: SUCCESS (4.411s)
[679.327658] Starting dynamic subtest: fds
[683.166614] Dynamic subtest fds: SUCCESS (3.849s)
[683.177107] Starting dynamic subtest: userptr
[684.915365] Dynamic subtest userptr: SUCCESS (1.748s)
[684.917763] Subtest engines: SUCCESS (12.049s)
[685.131622] [118/133] (339s left) prime_busy (after)
[685.191215] Starting subtest: after
[685.193526] Starting dynamic subtest: rcs0
[685.247751] Dynamic subtest rcs0: SUCCESS (0.055s)
[685.259191] Starting dynamic subtest: bcs0
[685.324030] Dynamic subtest bcs0: SUCCESS (0.076s)
[685.335252] Starting dynamic subtest: vcs0
[685.365533] Dynamic subtest vcs0: SUCCESS (0.041s)
[685.375121] Starting dynamic subtest: vcs1
[685.405623] Dynamic subtest vcs1: SUCCESS (0.040s)
[685.415090] Starting dynamic subtest: vecs0
[685.447135] Dynamic subtest vecs0: SUCCESS (0.041s)
[685.456752] Starting dynamic subtest: ccs0
[685.488830] Dynamic subtest ccs0: SUCCESS (0.041s)
[685.597778] Subtest after: SUCCESS (0.407s)
[685.695114] [119/133] (338s left) gem_partial_pwrite_pread (write-display)
[685.762921] Subtest write-display: SKIP (0.000s)
[685.841360] [120/133] (338s left) gem_tiled_blits (basic)
[685.909404] Subtest basic: SKIP (0.000s)
[685.973407] [121/133] (338s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[686.064952] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[694.913988] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.851s)
[695.063357] [122/133] (329s left) sysfs_defaults (readonly)
[695.134487] Starting subtest: readonly
[695.136719] Starting dynamic subtest: vecs0
[695.139061] Dynamic subtest vecs0: SUCCESS (0.000s)
[695.150609] Starting dynamic subtest: ccs0
[695.152534] Dynamic subtest ccs0: SUCCESS (0.000s)
[695.161706] Starting dynamic subtest: vcs0
[695.163943] Dynamic subtest vcs0: SUCCESS (0.000s)
[695.173141] Starting dynamic subtest: bcs0
[695.175083] Dynamic subtest bcs0: SUCCESS (0.000s)
[695.183768] Starting dynamic subtest: rcs0
[695.185744] Dynamic subtest rcs0: SUCCESS (0.000s)
[695.194512] Starting dynamic subtest: vcs1
[695.196489] Dynamic subtest vcs1: SUCCESS (0.000s)
[695.198450] Subtest readonly: SUCCESS (0.005s)
[695.234131] [123/133] (329s left) kms_dsc (dsc-with-formats)
[695.350105] Starting subtest: dsc-with-formats
[695.390397] Subtest dsc-with-formats: SKIP (0.001s)
[695.446134] [124/133] (329s left) prime_mmap (test_reprime)
[695.510037] Starting subtest: test_reprime
[695.512235] Starting dynamic subtest: test_reprime-smem
[695.536825] Dynamic subtest test_reprime-smem: SUCCESS (0.028s)
[695.539543] Subtest test_reprime: SUCCESS (0.029s)
[695.675936] [125/133] (329s left) gem_exec_params (rsvd2-dirt)
[695.740077] Starting subtest: rsvd2-dirt
[695.744465] Subtest rsvd2-dirt: SKIP (0.000s)
[695.864570] [126/133] (329s left) kms_lease (lessee-list)
[695.959984] Starting subtest: lessee-list
[695.961943] Starting dynamic subtest: pipe-A-eDP-1
[695.963729] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.003s)
[695.973296] Starting dynamic subtest: pipe-B-eDP-1
[695.975053] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[695.984482] Starting dynamic subtest: pipe-C-eDP-1
[695.986198] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[695.995146] Starting dynamic subtest: pipe-D-eDP-1
[695.996855] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[695.998568] Subtest lessee-list: SUCCESS (0.010s)
[696.094907] [127/133] (328s left) kms_vrr (flip-dpms)
[696.195475] Starting subtest: flip-dpms
[696.200856] Subtest flip-dpms: SKIP (0.000s)
[696.292376] [128/133] (328s left) kms_frontbuffer_tracking (fbc-1p-rte)
[696.400099] Starting subtest: fbc-1p-rte
[701.834599] Subtest fbc-1p-rte: SUCCESS (5.437s)
[703.079212] [129/133] (321s left) gen9_exec_parse (batch-zero-length)
[703.136777] Subtest batch-zero-length: SKIP (0.000s)
[703.212976] [130/133] (321s left) prime_mmap (test_aperture_limit)
[703.268078] Starting subtest: test_aperture_limit
[703.269982] Starting dynamic subtest: test_aperture_limit-smem
[703.277323] Dynamic subtest test_aperture_limit-smem: SKIP (0.007s)
[703.281692] Subtest test_aperture_limit: SKIP (0.008s)
[703.404516] [131/133] (321s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[703.496923] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[703.501391] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[703.619987] [132/133] (321s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[703.720827] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[703.725253] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[703.850671] [133/133] (321s left) gem_exec_fence (long-history)
[704.336651] Starting subtest: long-history
[711.629044] Subtest long-history: SUCCESS (7.296s)
[712.436493] 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.