[59.750349] Initializing watchdogs
[59.750392]   /dev/watchdog0
[59.766711] [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.773795] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.776225] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.779638] [001/133] (960s left) gem_create (create-ext-placement-all)
[59.975993] Starting subtest: create-ext-placement-all
[59.978032] Subtest create-ext-placement-all: SUCCESS (0.000s)
[60.103214] [002/133] (960s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[60.175691] Starting subtest: map-fixed-invalidate-overlap
[60.180066] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[60.337669] [003/133] (960s left) gem_pipe_control_store_loop (fresh-buffer)
[60.391471] Starting subtest: fresh-buffer
[62.551791] Subtest fresh-buffer: SUCCESS (2.153s)
[62.707692] [004/133] (957s left) gem_mmap_offset (mmap-boundaries)
[62.762851] Starting subtest: mmap-boundaries
[62.765289] Starting dynamic subtest: smem0
[63.041268] Dynamic subtest smem0: SUCCESS (0.278s)
[63.043788] Subtest mmap-boundaries: SUCCESS (0.279s)
[63.194126] [005/133] (957s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[63.307097] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[70.585916] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.269s)
[70.784811] [006/133] (949s left) kms_atomic (atomic-plane-damage)
[70.874833] Starting subtest: atomic-plane-damage
[70.949914] Starting dynamic subtest: pipe-A-eDP-1
[71.516742] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.567s)
[71.519166] Subtest atomic-plane-damage: SUCCESS (0.643s)
[72.399592] [007/133] (948s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[72.513416] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[72.515415] Starting dynamic subtest: pipe-A-eDP-1
[72.601023] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.089s)
[72.609632] Starting dynamic subtest: pipe-B-eDP-1
[72.669757] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.067s)
[72.681517] Starting dynamic subtest: pipe-C-eDP-1
[72.730159] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.059s)
[72.739113] Starting dynamic subtest: pipe-D-eDP-1
[72.789337] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.059s)
[72.791668] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.278s)
[74.974647] [008/133] (945s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[75.092364] Starting subtest: psr-rgb101010-draw-mmap-wc
[82.052890] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (6.960s)
[82.202693] [009/133] (938s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[82.257065] Starting subtest: yf-tiled-to-vebox-x-tiled
[82.261664] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[82.458215] [010/133] (938s left) syncobj_timeline (wait-all-complex)
[82.527594] Starting subtest: wait-all-complex
[82.740583] Subtest wait-all-complex: SUCCESS (0.213s)
[82.836958] [011/133] (937s left) gem_pxp (create-valid-protected-context)
[82.970798] Starting subtest: create-valid-protected-context
[82.973685] Subtest create-valid-protected-context: SUCCESS (0.002s)
[83.105454] [012/133] (937s left) gem_exec_flush (basic-wb-rw-default)
[83.161424] Starting subtest: basic-wb-rw-default
[88.605899] Subtest basic-wb-rw-default: SUCCESS (5.445s)
[88.812191] [013/133] (931s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[88.925189] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[88.927400] Starting dynamic subtest: pipe-A-eDP-1
[88.948027] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[88.957208] Starting dynamic subtest: pipe-B-eDP-1
[88.976269] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[88.983243] Starting dynamic subtest: pipe-C-eDP-1
[89.002221] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[89.009405] Starting dynamic subtest: pipe-D-eDP-1
[89.028438] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[89.030608] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[91.211964] [014/133] (929s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt)
[91.312781] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt
[95.417715] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.109s)
[96.608710] [015/133] (924s left) kms_cursor_edge_walk (256x256-top-bottom)
[96.693655] Starting subtest: 256x256-top-bottom
[96.695956] Starting dynamic subtest: pipe-A-eDP-1
[100.147073] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.453s)
[100.155887] Starting dynamic subtest: pipe-D-eDP-1
[103.260488] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.113s)
[103.262819] Subtest 256x256-top-bottom: SUCCESS (6.568s)
[104.104857] [016/133] (916s left) kms_force_connector_basic (force-load-detect)
[104.187622] Starting subtest: force-load-detect
[104.192078] Subtest force-load-detect: SKIP (0.000s)
[104.325376] [017/133] (916s left) syncobj_wait (invalid-signal-zero-handles)
[104.381027] Starting subtest: invalid-signal-zero-handles
[104.383032] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[104.535872] [018/133] (916s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[109.573699] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[109.575744] Starting dynamic subtest: pipe-A-eDP-1
[110.362157] Dynamic subtest pipe-A-eDP-1: SKIP (0.785s)
[110.371221] Starting dynamic subtest: pipe-B-eDP-1
[111.495646] Dynamic subtest pipe-B-eDP-1: SKIP (1.132s)
[111.499842] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.921s)
[112.704384] [019/133] (908s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[112.816912] Starting subtest: fbcpsr-rgb565-draw-render
[119.841400] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.027s)
[120.024899] [020/133] (901s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[120.125313] Starting subtest: primary-4-tiled-reflect-x-0
[127.170469] Subtest primary-4-tiled-reflect-x-0: SUCCESS (7.046s)
[128.392572] [021/133] (892s left) kms_setmode (clone-exclusive-crtc)
[128.467254] Starting subtest: clone-exclusive-crtc
[128.474237] Subtest clone-exclusive-crtc: SKIP (0.001s)
[128.593362] [022/133] (892s left) kms_addfb_basic (too-high)
[128.687901] Starting subtest: too-high
[128.690132] Subtest too-high: SUCCESS (0.000s)
[128.804684] [023/133] (892s left) perf_pmu (busy-accuracy-2)
[128.849747] Starting subtest: busy-accuracy-2
[128.852035] Starting dynamic subtest: rcs0
[131.894530] Dynamic subtest rcs0: SUCCESS (3.042s)
[131.904398] Starting dynamic subtest: bcs0
[134.955744] Dynamic subtest bcs0: SUCCESS (3.059s)
[134.965610] Starting dynamic subtest: vcs0
[138.002195] Dynamic subtest vcs0: SUCCESS (3.045s)
[138.012461] Starting dynamic subtest: vcs1
[141.052461] Dynamic subtest vcs1: SUCCESS (3.048s)
[141.062425] Starting dynamic subtest: vecs0
[144.099061] Dynamic subtest vecs0: SUCCESS (3.045s)
[144.109484] Starting dynamic subtest: ccs0
[147.157785] Dynamic subtest ccs0: SUCCESS (3.057s)
[147.160204] Subtest busy-accuracy-2: SUCCESS (18.305s)
[147.314517] [024/133] (873s left) kms_psr (pr-primary-mmap-gtt)
[147.433129] Starting subtest: pr-primary-mmap-gtt
[147.437513] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[147.531990] [025/133] (873s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[147.631756] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[147.635796] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[147.740427] [026/133] (873s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[147.853243] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[147.857519] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[147.972409] [027/133] (873s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[148.071671] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[148.073971] Starting dynamic subtest: pipe-A-default-mode
[150.837849] Dynamic subtest pipe-A-default-mode: SUCCESS (2.766s)
[150.840186] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.767s)
[151.703552] [028/133] (869s left) i915_drm_fdinfo (idle)
[151.747733] Starting subtest: idle
[151.749888] Starting dynamic subtest: rcs0
[152.355578] Dynamic subtest rcs0: SUCCESS (0.608s)
[152.364919] Starting dynamic subtest: bcs0
[152.862227] Dynamic subtest bcs0: SUCCESS (0.503s)
[152.871059] Starting dynamic subtest: vcs0
[153.368292] Dynamic subtest vcs0: SUCCESS (0.504s)
[153.376648] Starting dynamic subtest: vcs1
[153.875283] Dynamic subtest vcs1: SUCCESS (0.504s)
[153.884962] Starting dynamic subtest: vecs0
[154.381206] Dynamic subtest vecs0: SUCCESS (0.504s)
[154.389213] Starting dynamic subtest: ccs0
[154.885560] Dynamic subtest ccs0: SUCCESS (0.503s)
[154.888328] Subtest idle: SUCCESS (3.138s)
[154.984061] [029/133] (866s left) core_setmaster (master-drop-set-shared-fd)
[155.079297] Starting subtest: master-drop-set-shared-fd
[155.101373] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[155.138339] [030/133] (866s left) kms_chamelium_color (ctm-negative)
[155.244959] Subtest ctm-negative: SKIP (0.000s)
[155.287770] [031/133] (866s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[155.344081] Starting subtest: multi-wait-for-submit-available-submitted
[155.346460] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.000s)
[155.491656] [032/133] (866s left) syncobj_timeline (invalid-signal-illegal-point)
[155.560193] Starting subtest: invalid-signal-illegal-point
[155.562583] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[155.700154] [033/133] (865s left) gem_mmap (bad-offset)
[155.762856] Subtest bad-offset: SKIP (0.000s)
[155.825860] [034/133] (865s left) i915_hangman (gt-error-state-capture)
[155.899076] Starting subtest: gt-error-state-capture
[155.900846] Starting dynamic subtest: rcs0
[167.859744] Dynamic subtest rcs0: SUCCESS (11.959s)
[167.871585] Starting dynamic subtest: bcs0
[179.892389] Dynamic subtest bcs0: SUCCESS (12.030s)
[179.904053] Starting dynamic subtest: vcs0
[191.796574] Dynamic subtest vcs0: SUCCESS (11.902s)
[191.813747] Starting dynamic subtest: vcs1
[201.846235] Dynamic subtest vcs1: SUCCESS (10.048s)
[201.857467] Starting dynamic subtest: vecs0
[213.813223] Dynamic subtest vecs0: SUCCESS (11.964s)
[213.825422] Starting dynamic subtest: ccs0
[224.884913] Dynamic subtest ccs0: SUCCESS (11.069s)
[224.887420] Subtest gt-error-state-capture: SUCCESS (68.984s)
[225.063254] [035/133] (796s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[225.158308] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[225.160501] Starting dynamic subtest: pipe-A-eDP-1
[225.169137] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[225.178029] Starting dynamic subtest: pipe-B-eDP-1
[225.186376] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[225.194251] Starting dynamic subtest: pipe-C-eDP-1
[225.202251] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[225.209488] Starting dynamic subtest: pipe-D-eDP-1
[225.217663] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[225.221583] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[227.432866] [036/133] (794s left) gem_exec_whisper (basic-fds)
[227.508545] Starting subtest: basic-fds
[234.526640] Subtest basic-fds: SUCCESS (7.020s)
[234.737765] [037/133] (786s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[234.849231] Starting subtest: psr-1p-primscrn-cur-indfb-move
[242.147472] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.300s)
[242.327656] [038/133] (779s left) gem_exec_schedule (preempt-user)
[242.409548] Starting subtest: preempt-user
[242.411784] Starting dynamic subtest: rcs0
[242.427344] Dynamic subtest rcs0: SUCCESS (0.026s)
[242.435725] Starting dynamic subtest: bcs0
[242.463343] Dynamic subtest bcs0: SUCCESS (0.035s)
[242.472435] Starting dynamic subtest: vcs0
[242.484541] Dynamic subtest vcs0: SUCCESS (0.021s)
[242.492659] Starting dynamic subtest: vcs1
[242.504703] Dynamic subtest vcs1: SUCCESS (0.020s)
[242.512780] Starting dynamic subtest: vecs0
[242.526233] Dynamic subtest vecs0: SUCCESS (0.021s)
[242.534152] Starting dynamic subtest: ccs0
[242.546596] Dynamic subtest ccs0: SUCCESS (0.020s)
[242.548888] Subtest preempt-user: SUCCESS (0.146s)
[244.047209] [039/133] (777s left) kms_psr (psr2-primary-mmap-cpu)
[244.137200] Starting subtest: psr2-primary-mmap-cpu
[244.139524] Starting dynamic subtest: eDP-1
[245.804936] Dynamic subtest eDP-1: SUCCESS (1.668s)
[245.807441] Subtest psr2-primary-mmap-cpu: SUCCESS (1.670s)
[246.714245] [040/133] (775s left) gem_ctx_param (invalid-size-set)
[246.778719] Starting subtest: invalid-size-set
[246.781251] Subtest invalid-size-set: SUCCESS (0.001s)
[246.914747] [041/133] (774s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[247.000429] Starting subtest: bad-aux-stride-y-tiled-ccs
[247.002711] Starting dynamic subtest: pipe-A-eDP-1
[247.025276] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[247.034382] Starting dynamic subtest: pipe-B-eDP-1
[247.056063] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[247.063994] Starting dynamic subtest: pipe-C-eDP-1
[247.086052] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[247.093789] Starting dynamic subtest: pipe-D-eDP-1
[247.115731] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[247.120266] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s)
[249.198547] [042/133] (772s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[249.304506] Starting subtest: blocking-absolute-wf_vblank-interruptible
[249.306864] Starting dynamic subtest: A-eDP1
[258.039236] Dynamic subtest A-eDP1: SUCCESS (8.733s)
[258.056969] Starting dynamic subtest: B-eDP1
[266.757378] Dynamic subtest B-eDP1: SUCCESS (8.715s)
[266.767714] Starting dynamic subtest: C-eDP1
[275.481908] Dynamic subtest C-eDP1: SUCCESS (8.723s)
[275.494877] Starting dynamic subtest: D-eDP1
[284.216902] Dynamic subtest D-eDP1: SUCCESS (8.732s)
[284.219560] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.914s)
[285.437074] [043/133] (736s left) kms_psr2_su (page_flip-nv12)
[285.495069] Subtest page_flip-NV12: SKIP (0.000s)
[285.564616] [044/133] (736s left) gem_exec_balancer (sliced)
[285.624516] Starting subtest: sliced
[285.628792] Subtest sliced: SKIP (0.000s)
[285.954247] [045/133] (736s left) kms_plane_lowres (tiling-x)
[286.054628] Starting subtest: tiling-x
[286.056871] Starting dynamic subtest: pipe-A-eDP-1
[286.061450] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[286.349678] Starting dynamic subtest: pipe-B-eDP-1
[286.354404] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[286.362756] Starting dynamic subtest: pipe-C-eDP-1
[286.367371] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[286.375573] Starting dynamic subtest: pipe-D-eDP-1
[286.380062] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[286.384397] Subtest tiling-x: SKIP (0.294s)
[287.175589] [046/133] (734s left) kms_vblank (query-busy-hang)
[287.266898] Starting subtest: query-busy-hang
[287.269207] Starting dynamic subtest: pipe-A-eDP-1
[313.699378] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.430s)
[313.713125] Starting dynamic subtest: pipe-D-eDP-1
[328.743611] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.043s)
[328.746217] Subtest query-busy-hang: SUCCESS (41.478s)
[329.566892] [047/133] (692s left) gem_exec_balancer (hang)
[329.824877] Starting subtest: hang
[331.950230] Subtest hang: SUCCESS (2.125s)
[332.102953] [048/133] (690s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[332.203359] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[332.207123] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[332.343377] [049/133] (689s left) kms_flip (bo-too-big)
[332.439209] Starting subtest: bo-too-big
[332.441111] Starting dynamic subtest: A-eDP1
[333.798665] Dynamic subtest A-eDP1: SUCCESS (1.361s)
[333.810064] Starting dynamic subtest: B-eDP1
[335.142843] Dynamic subtest B-eDP1: SUCCESS (1.343s)
[335.156203] Starting dynamic subtest: C-eDP1
[336.485994] Dynamic subtest C-eDP1: SUCCESS (1.341s)
[336.506204] Starting dynamic subtest: D-eDP1
[337.826894] Dynamic subtest D-eDP1: SUCCESS (1.339s)
[337.829286] Subtest bo-too-big: SUCCESS (5.393s)
[339.055328] [050/133] (683s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[339.126688] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[339.131045] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[339.334079] [051/133] (682s left) kms_cursor_legacy (single-bo)
[339.460196] Starting subtest: single-bo
[339.462429] Starting dynamic subtest: pipe-A
[344.840063] Dynamic subtest pipe-A: SUCCESS (5.378s)
[344.851640] Starting dynamic subtest: pipe-B
[350.223644] Dynamic subtest pipe-B: SUCCESS (5.381s)
[350.234579] Starting dynamic subtest: pipe-C
[355.605533] Dynamic subtest pipe-C: SUCCESS (5.379s)
[355.616623] Starting dynamic subtest: pipe-D
[360.987729] Dynamic subtest pipe-D: SUCCESS (5.379s)
[360.998646] Starting dynamic subtest: all-pipes
[366.378087] Dynamic subtest all-pipes: SUCCESS (5.387s)
[366.380341] Subtest single-bo: SUCCESS (26.918s)
[366.542450] [052/133] (655s left) kms_pm_rpm (basic-pci-d3-state)
[368.068992] Starting subtest: basic-pci-d3-state
[369.538051] Subtest basic-pci-d3-state: SUCCESS (1.468s)
[369.638172] [053/133] (652s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[369.745783] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[374.071046] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.328s)
[375.214298] [054/133] (647s left) kms_pm_rpm (legacy-planes)
[377.037525] Starting subtest: legacy-planes
[377.040073] Starting dynamic subtest: plane-63
[385.623066] Dynamic subtest plane-63: SUCCESS (8.584s)
[385.634415] Starting dynamic subtest: plane-93
[394.313569] Dynamic subtest plane-93: SUCCESS (8.687s)
[394.325957] Starting dynamic subtest: plane-123
[402.892017] Dynamic subtest plane-123: SUCCESS (8.576s)
[402.903096] Starting dynamic subtest: plane-133
[411.190527] Dynamic subtest plane-133: SUCCESS (8.295s)
[411.193416] Subtest legacy-planes: SUCCESS (34.153s)
[411.586441] [055/133] (610s left) prime_self_import (basic-with_one_bo)
[411.618445] Starting subtest: basic-with_one_bo
[411.643753] Subtest basic-with_one_bo: SUCCESS (0.026s)
[411.763681] [056/133] (610s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[411.861443] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[411.865830] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[412.001946] [057/133] (610s left) kms_flip (modeset-vs-vblank-race)
[412.106350] Starting subtest: modeset-vs-vblank-race
[412.108599] Starting dynamic subtest: A-eDP1
[416.768471] Dynamic subtest A-eDP1: SUCCESS (4.664s)
[416.780102] Starting dynamic subtest: B-eDP1
[421.515021] Dynamic subtest B-eDP1: SUCCESS (4.744s)
[421.527096] Starting dynamic subtest: C-eDP1
[426.050551] Dynamic subtest C-eDP1: SUCCESS (4.533s)
[426.060723] Starting dynamic subtest: D-eDP1
[430.655600] Dynamic subtest D-eDP1: SUCCESS (4.604s)
[430.658076] Subtest modeset-vs-vblank-race: SUCCESS (18.555s)
[431.864031] [058/133] (590s left) gem_lmem_swapping (heavy-verify-random-ccs)
[431.915480] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[431.955849] [059/133] (590s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[432.057054] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[432.061181] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[432.187337] [060/133] (590s left) kms_chamelium_color (gamma)
[432.279282] Subtest gamma: SKIP (0.000s)
[432.322812] [061/133] (590s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[432.447266] Starting subtest: cursorB-vs-flipA-varying-size
[432.451577] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[432.617194] [062/133] (590s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[432.722791] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[437.214896] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.496s)
[438.355469] [063/133] (584s left) dumb_buffer (map-valid)
[438.418057] Starting subtest: map-valid
[438.420319] Subtest map-valid: SUCCESS (0.000s)
[438.550297] [064/133] (584s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[438.644679] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[438.646769] Starting dynamic subtest: pipe-A-default-mode
[438.960126] Dynamic subtest pipe-A-default-mode: SKIP (0.314s)
[438.964547] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.315s)
[439.781637] [065/133] (583s left) perf (i915-ref-count)
[439.870312] Starting subtest: i915-ref-count
[439.933902] Subtest i915-ref-count: SUCCESS (0.064s)
[440.001300] [066/133] (582s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[440.096472] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[440.100688] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[440.208622] [067/133] (582s left) gem_softpin (noreloc-interruptible)
[440.269742] Starting subtest: noreloc-interruptible
[445.684062] Subtest noreloc-interruptible: SUCCESS (5.412s)
[445.830321] [068/133] (577s left) kms_psr (fbc-psr2-primary-render)
[445.941987] Starting subtest: fbc-psr2-primary-render
[445.944250] Starting dynamic subtest: eDP-1
[445.948208] Dynamic subtest eDP-1: SKIP (0.000s)
[445.952229] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[446.036487] [069/133] (576s left) kms_lease (atomic-implicit-crtc)
[446.134908] Starting subtest: atomic-implicit-crtc
[446.137164] Starting dynamic subtest: pipe-A-eDP-1
[446.149220] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[446.160739] Starting dynamic subtest: pipe-B-eDP-1
[446.171984] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.009s)
[446.180377] Starting dynamic subtest: pipe-C-eDP-1
[446.194107] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.009s)
[446.203820] Starting dynamic subtest: pipe-D-eDP-1
[446.214629] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.009s)
[446.216800] Subtest atomic-implicit-crtc: SUCCESS (0.034s)
[446.303622] [070/133] (576s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[446.397406] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[446.780598] Starting dynamic subtest: pipe-A-eDP-1
[449.535177] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.755s)
[449.898424] Starting dynamic subtest: pipe-B-eDP-1
[452.665813] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.773s)
[453.037906] Starting dynamic subtest: pipe-C-eDP-1
[455.790715] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.759s)
[456.158981] Starting dynamic subtest: pipe-D-eDP-1
[458.918547] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.774s)
[458.920792] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (12.522s)
[460.126073] [071/133] (562s left) gem_exec_reloc (basic-wc-read-active)
[460.190408] Subtest basic-wc-read-active: SKIP (0.000s)
[460.266778] [072/133] (562s left) gem_readwrite (new-obj)
[460.311606] Subtest new-obj: SKIP (0.000s)
[460.372803] [073/133] (562s left) gem_mmap_gtt (basic-short)
[460.431683] Subtest basic-short: SKIP (0.000s)
[460.497357] [074/133] (562s left) kms_plane_multiple (tiling-yf)
[460.586589] Starting subtest: tiling-yf
[460.590784] Subtest tiling-yf: SKIP (0.000s)
[460.683850] [075/133] (562s left) kms_content_protection (dp-mst-type-0-hdcp14)
[460.824446] Starting subtest: dp-mst-type-0-hdcp14
[460.829093] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[460.988735] [076/133] (562s left) gem_ctx_shared (q-in-order)
[461.063293] Starting subtest: Q-in-order
[461.065218] Starting dynamic subtest: rcs0
[461.229579] Dynamic subtest rcs0: SUCCESS (0.165s)
[461.245582] Starting dynamic subtest: vcs0
[461.302566] Dynamic subtest vcs0: SUCCESS (0.072s)
[461.317960] Starting dynamic subtest: vcs1
[461.375188] Dynamic subtest vcs1: SUCCESS (0.072s)
[461.390360] Starting dynamic subtest: vecs0
[461.448123] Dynamic subtest vecs0: SUCCESS (0.072s)
[461.463495] Starting dynamic subtest: ccs0
[461.534514] Dynamic subtest ccs0: SUCCESS (0.086s)
[461.536593] Subtest Q-in-order: SUCCESS (0.471s)
[463.054960] [FACT gem_ctx_shared (q-in-order)] new: kernel.kmod_is_loaded.vgem: true
[463.058468] [077/133] (560s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[463.109746] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[463.217594] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.106s)
[463.318751] [078/133] (560s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[463.420806] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[469.981296] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.562s)
[470.143694] [079/133] (553s left) kms_big_fb (linear-8bpp-rotate-270)
[470.259407] Starting subtest: linear-8bpp-rotate-270
[470.280333] Subtest linear-8bpp-rotate-270: SKIP (0.020s)
[470.429457] [080/133] (552s left) api_intel_allocator (standalone)
[470.494561] Starting subtest: standalone
[470.509425] Subtest standalone: SUCCESS (0.017s)
[470.686798] [081/133] (552s left) core_auth (getclient-master-drop)
[470.729477] Starting subtest: getclient-master-drop
[470.746807] Subtest getclient-master-drop: SUCCESS (0.021s)
[470.908689] [082/133] (552s left) kms_psr (fbc-psr2-basic)
[471.020975] Starting subtest: fbc-psr2-basic
[471.022803] Starting dynamic subtest: eDP-1
[471.026163] Dynamic subtest eDP-1: SKIP (0.000s)
[471.029455] Subtest fbc-psr2-basic: SKIP (0.001s)
[471.115783] [083/133] (552s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[471.270336] Starting subtest: cursor-rapid-movement-32x32
[471.322428] Subtest cursor-rapid-movement-32x32: SKIP (0.050s)
[471.646841] [084/133] (551s left) gem_exec_schedule (noreorder-priority)
[471.839448] Starting subtest: noreorder-priority
[471.842319] Starting dynamic subtest: rcs0
[472.998267] Dynamic subtest rcs0: SUCCESS (1.159s)
[473.010367] Starting dynamic subtest: vcs0
[474.915282] Dynamic subtest vcs0: SUCCESS (1.913s)
[474.927676] Starting dynamic subtest: vcs1
[476.887628] Dynamic subtest vcs1: SUCCESS (1.969s)
[476.899783] Starting dynamic subtest: vecs0
[478.965651] Dynamic subtest vecs0: SUCCESS (2.075s)
[478.977796] Starting dynamic subtest: ccs0
[480.124795] Dynamic subtest ccs0: SUCCESS (1.158s)
[480.127518] Subtest noreorder-priority: SUCCESS (8.288s)
[480.337502] [085/133] (543s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[480.428823] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[480.431045] Starting dynamic subtest: pipe-A-eDP-1
[480.455165] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[480.465729] Starting dynamic subtest: pipe-B-eDP-1
[480.489293] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[480.498126] Starting dynamic subtest: pipe-C-eDP-1
[480.521873] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[480.530528] Starting dynamic subtest: pipe-D-eDP-1
[480.555851] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[480.559981] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.004s)
[482.714199] [086/133] (541s left) core_auth (many-magics)
[482.768977] Starting subtest: many-magics
[483.647621] Subtest many-magics: SUCCESS (0.881s)
[483.910642] [087/133] (539s left) i915_suspend (fence-restore-tiled2untiled)
[483.962071] Starting subtest: fence-restore-tiled2untiled
[483.966470] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[484.095196] [088/133] (539s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[484.199197] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[484.201484] Starting dynamic subtest: pipe-A
[484.220768] Dynamic subtest pipe-A: SKIP (0.018s)
[484.238105] Starting dynamic subtest: pipe-B
[484.252232] Dynamic subtest pipe-B: SKIP (0.030s)
[484.266236] Starting dynamic subtest: pipe-C
[484.302542] Dynamic subtest pipe-C: SKIP (0.049s)
[484.311439] Starting dynamic subtest: pipe-D
[484.333955] Dynamic subtest pipe-D: SKIP (0.031s)
[484.337866] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.133s)
[484.444986] [089/133] (539s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[484.538601] Starting subtest: y-tiled-64bpp-rotate-0
[484.542721] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[484.667677] [090/133] (539s left) gem_ctx_persistence (saturated-hostile)
[484.734595] Starting subtest: saturated-hostile
[484.736953] Starting dynamic subtest: rcs0
[485.584155] Dynamic subtest rcs0: SUCCESS (0.849s)
[485.595802] Starting dynamic subtest: bcs0
[486.405575] Dynamic subtest bcs0: SUCCESS (0.820s)
[486.416639] Starting dynamic subtest: vcs0
[487.218211] Dynamic subtest vcs0: SUCCESS (0.811s)
[487.229649] Starting dynamic subtest: vcs1
[488.019877] Dynamic subtest vcs1: SUCCESS (0.800s)
[488.031344] Starting dynamic subtest: vecs0
[488.822896] Dynamic subtest vecs0: SUCCESS (0.801s)
[488.834119] Starting dynamic subtest: ccs0
[489.671790] Dynamic subtest ccs0: SUCCESS (0.847s)
[489.674256] Subtest saturated-hostile: SUCCESS (4.937s)
[489.776121] [091/133] (534s left) kms_prop_blob (invalid-get-prop)
[489.832565] Starting subtest: invalid-get-prop
[489.834494] Subtest invalid-get-prop: SUCCESS (0.000s)
[489.974187] [092/133] (533s left) gem_mmap_gtt (basic-write)
[490.039035] Subtest basic-write: SKIP (0.000s)
[490.105542] [093/133] (533s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[490.223282] Starting subtest: cursorA-vs-flipA-varying-size
[501.381862] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.158s)
[502.244209] [094/133] (521s left) gem_linear_blits (interruptible)
[502.652534] Starting subtest: interruptible
[506.874034] Subtest interruptible: SUCCESS (4.221s)
[507.055610] [095/133] (517s left) gem_exec_params (sol-reset-invalid)
[507.155926] Starting subtest: sol-reset-invalid
[507.161809] Subtest sol-reset-invalid: SUCCESS (0.005s)
[508.969185] [096/133] (515s left) kms_color_pipeline (plane-lut1d)
[509.082143] Starting subtest: plane-lut1d
[509.096187] Starting dynamic subtest: pipe-A-eDP-1
[509.799979] Dynamic subtest pipe-A-eDP-1: FAIL (0.310s)
[510.497776] Starting dynamic subtest: pipe-B-eDP-1
[511.290501] Dynamic subtest pipe-B-eDP-1: FAIL (0.300s)
[511.838173] Starting dynamic subtest: pipe-C-eDP-1
[512.696948] Dynamic subtest pipe-C-eDP-1: FAIL (0.279s)
[513.158472] Starting dynamic subtest: pipe-D-eDP-1
[514.005280] Dynamic subtest pipe-D-eDP-1: FAIL (0.260s)
[514.007633] Subtest plane-lut1d: FAIL (4.622s)
[514.569424] [097/133] (509s left) gem_wait (busy)
[514.639896] Starting subtest: busy
[514.642175] Starting dynamic subtest: all-engines
[515.925228] Dynamic subtest all-engines: SUCCESS (1.284s)
[515.938032] Starting dynamic subtest: rcs0
[516.467466] Dynamic subtest rcs0: SUCCESS (0.541s)
[516.479459] Starting dynamic subtest: bcs0
[517.005490] Dynamic subtest bcs0: SUCCESS (0.537s)
[517.017043] Starting dynamic subtest: vcs0
[518.933554] Dynamic subtest vcs0: SUCCESS (1.925s)
[518.945478] Starting dynamic subtest: vcs1
[520.916906] Dynamic subtest vcs1: SUCCESS (1.981s)
[520.929073] Starting dynamic subtest: vecs0
[522.901311] Dynamic subtest vecs0: SUCCESS (1.982s)
[522.913985] Starting dynamic subtest: ccs0
[523.442631] Dynamic subtest ccs0: SUCCESS (0.540s)
[523.445112] Subtest busy: SUCCESS (8.803s)
[523.663311] [098/133] (500s left) sriov_basic (enable-vfs-autoprobe-on)
[523.720550] Starting subtest: enable-vfs-autoprobe-on
[523.722897] Starting dynamic subtest: numvfs-1
[523.795909] Dynamic subtest numvfs-1: FAIL (0.005s)
[523.805810] Starting dynamic subtest: numvfs-2
[523.879278] Dynamic subtest numvfs-2: FAIL (0.003s)
[523.889945] Starting dynamic subtest: numvfs-3
[523.963611] Dynamic subtest numvfs-3: FAIL (0.003s)
[523.974232] Starting dynamic subtest: numvfs-4
[524.046073] Dynamic subtest numvfs-4: FAIL (0.003s)
[524.056629] Starting dynamic subtest: numvfs-5
[524.130095] Dynamic subtest numvfs-5: FAIL (0.003s)
[524.140833] Starting dynamic subtest: numvfs-6
[524.214378] Dynamic subtest numvfs-6: FAIL (0.004s)
[524.224759] Starting dynamic subtest: numvfs-7
[524.296399] Dynamic subtest numvfs-7: FAIL (0.003s)
[524.305238] Starting dynamic subtest: numvfs-random
[524.377762] Dynamic subtest numvfs-random: FAIL (0.003s)
[524.387541] Starting dynamic subtest: numvfs-all
[524.460945] Dynamic subtest numvfs-all: FAIL (0.514s)
[524.463381] Subtest enable-vfs-autoprobe-on: FAIL (0.550s)
[524.502141] [099/133] (499s left) kms_chamelium_frames (vga-frame-dump)
[524.890121] Subtest vga-frame-dump: SKIP (0.000s)
[525.664894] [100/133] (498s left) gem_eio (throttle)
[525.865713] Starting subtest: throttle
[526.122823] Subtest throttle: SUCCESS (0.257s)
[526.303349] [101/133] (498s left) kms_frontbuffer_tracking (psr-abgr161616f-draw-mmap-wc)
[526.400356] Starting subtest: psr-abgr161616f-draw-mmap-wc
[526.404741] Subtest psr-abgr161616f-draw-mmap-wc: SKIP (0.000s)
[526.515631] [102/133] (497s left) gem_softpin (allocator-nopin)
[526.576967] Starting subtest: allocator-nopin
[526.686176] Subtest allocator-nopin: SUCCESS (0.108s)
[526.838586] [103/133] (497s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[526.945284] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[527.635901] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.692s)
[527.856360] [104/133] (496s left) kms_flip (busy-flip)
[527.944597] Starting subtest: busy-flip
[527.946865] Starting dynamic subtest: A-eDP1
[529.606754] Dynamic subtest A-eDP1: SUCCESS (1.660s)
[529.618264] Starting dynamic subtest: B-eDP1
[531.249415] Dynamic subtest B-eDP1: SUCCESS (1.641s)
[531.261091] Starting dynamic subtest: C-eDP1
[532.871422] Dynamic subtest C-eDP1: SUCCESS (1.620s)
[532.882190] Starting dynamic subtest: D-eDP1
[534.501945] Dynamic subtest D-eDP1: SUCCESS (1.629s)
[534.507847] Subtest busy-flip: SUCCESS (6.561s)
[535.781275] [105/133] (488s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[535.881626] Starting subtest: x-tiled-16bpp-rotate-270
[535.900945] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[536.061810] [106/133] (488s left) kms_frontbuffer_tracking (fbcpsr-abgr161616f-draw-render)
[536.161458] Starting subtest: fbcpsr-abgr161616f-draw-render
[536.165852] Subtest fbcpsr-abgr161616f-draw-render: SKIP (0.000s)
[536.299608] [107/133] (488s left) kms_getfb (getfb2-accept-nv12)
[536.393086] Starting subtest: getfb2-accept-nv12
[536.395331] Subtest getfb2-accept-nv12: SUCCESS (0.001s)
[536.530586] [108/133] (488s left) gem_ctx_exec (basic-nohangcheck)
[536.584868] Starting subtest: basic-nohangcheck
[536.816416] Subtest basic-nohangcheck: SUCCESS (0.233s)
[536.965405] [109/133] (487s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[537.083746] Starting subtest: short-flip-before-cursor-atomic-transitions
[538.019780] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.935s)
[538.906020] [110/133] (485s left) kms_cursor_crc (cursor-offscreen-256x256)
[539.137687] Starting subtest: cursor-offscreen-256x256
[539.143117] Starting dynamic subtest: pipe-A-eDP-1
[541.061440] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.920s)
[541.088557] Starting dynamic subtest: pipe-D-eDP-1
[543.671558] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.587s)
[543.673696] Subtest cursor-offscreen-256x256: SUCCESS (4.531s)
[544.581484] [111/133] (480s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[544.686115] Starting subtest: 4-tiled-8bpp-rotate-0
[545.843521] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.159s)
[545.980421] [112/133] (478s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[546.050322] Subtest writes-after-reads: SKIP (0.000s)
[546.130054] [113/133] (478s left) kms_frontbuffer_tracking (psr-slowdraw)
[546.227232] Starting subtest: psr-slowdraw
[571.356199] Subtest psr-slowdraw: SUCCESS (25.130s)
[571.504282] [114/133] (453s left) i915_pm_rc6_residency (rc6-idle)
[571.558941] Starting subtest: rc6-idle
[571.602198] Starting dynamic subtest: gt0-rcs0
[595.964529] Dynamic subtest gt0-rcs0: SUCCESS (24.362s)
[595.973472] Starting dynamic subtest: gt0-bcs0
[620.318571] Dynamic subtest gt0-bcs0: SUCCESS (24.351s)
[620.327406] Starting dynamic subtest: gt0-ccs0
[644.686513] Dynamic subtest gt0-ccs0: SUCCESS (24.365s)
[644.695496] Starting dynamic subtest: gt1-vcs0
[650.860867] Dynamic subtest gt1-vcs0: SUCCESS (6.170s)
[650.872547] Starting dynamic subtest: gt1-vecs0
[657.032309] Dynamic subtest gt1-vecs0: SUCCESS (6.168s)
[657.034949] Subtest rc6-idle: SUCCESS (85.474s)
[657.144443] [115/133] (367s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[657.256986] Starting subtest: fbc-modesetfrombusy
[661.107295] Subtest fbc-modesetfrombusy: SUCCESS (3.854s)
[662.355142] [116/133] (362s left) kms_psr (psr-cursor-render)
[662.463305] Starting subtest: psr-cursor-render
[662.465602] Starting dynamic subtest: eDP-1
[664.162974] Dynamic subtest eDP-1: SUCCESS (1.699s)
[664.165310] Subtest psr-cursor-render: SUCCESS (1.700s)
[665.057331] [117/133] (359s left) gem_exec_parallel (engines)
[665.118666] Starting subtest: engines
[665.121182] Starting dynamic subtest: basic
[666.901175] Dynamic subtest basic: SUCCESS (1.781s)
[666.913135] Starting dynamic subtest: contexts
[671.378392] Dynamic subtest contexts: SUCCESS (4.475s)
[671.390758] Starting dynamic subtest: fds
[676.946281] Dynamic subtest fds: SUCCESS (5.565s)
[676.958571] Starting dynamic subtest: userptr
[678.870286] Dynamic subtest userptr: SUCCESS (1.921s)
[678.872884] Subtest engines: SUCCESS (13.751s)
[679.082669] [118/133] (345s left) prime_busy (after)
[679.138963] Starting subtest: after
[679.141338] Starting dynamic subtest: rcs0
[679.171250] Dynamic subtest rcs0: SUCCESS (0.031s)
[679.182785] Starting dynamic subtest: bcs0
[679.222312] Dynamic subtest bcs0: SUCCESS (0.050s)
[679.232075] Starting dynamic subtest: vcs0
[679.265518] Dynamic subtest vcs0: SUCCESS (0.042s)
[679.275106] Starting dynamic subtest: vcs1
[679.307535] Dynamic subtest vcs1: SUCCESS (0.041s)
[679.316890] Starting dynamic subtest: vecs0
[679.349432] Dynamic subtest vecs0: SUCCESS (0.041s)
[679.358533] Starting dynamic subtest: ccs0
[679.392548] Dynamic subtest ccs0: SUCCESS (0.042s)
[679.499795] Subtest after: SUCCESS (0.362s)
[679.600195] [119/133] (345s left) gem_partial_pwrite_pread (write-display)
[679.674271] Subtest write-display: SKIP (0.000s)
[679.757854] [120/133] (345s left) gem_tiled_blits (basic)
[679.829358] Subtest basic: SKIP (0.000s)
[679.901379] [121/133] (345s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[680.004568] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[688.532657] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.529s)
[688.673082] [122/133] (336s left) sysfs_defaults (readonly)
[688.740493] Starting subtest: readonly
[688.742843] Starting dynamic subtest: vecs0
[688.745209] Dynamic subtest vecs0: SUCCESS (0.000s)
[688.756054] Starting dynamic subtest: ccs0
[688.758229] Dynamic subtest ccs0: SUCCESS (0.000s)
[688.767057] Starting dynamic subtest: vcs0
[688.769170] Dynamic subtest vcs0: SUCCESS (0.000s)
[688.777843] Starting dynamic subtest: bcs0
[688.779994] Dynamic subtest bcs0: SUCCESS (0.000s)
[688.788941] Starting dynamic subtest: rcs0
[688.791175] Dynamic subtest rcs0: SUCCESS (0.000s)
[688.800124] Starting dynamic subtest: vcs1
[688.802059] Dynamic subtest vcs1: SUCCESS (0.000s)
[688.804369] Subtest readonly: SUCCESS (0.005s)
[688.879848] [123/133] (336s left) kms_dsc (dsc-with-formats)
[688.987115] Starting subtest: dsc-with-formats
[689.025747] Subtest dsc-with-formats: SKIP (0.001s)
[689.084417] [124/133] (336s left) prime_mmap (test_reprime)
[689.141727] Starting subtest: test_reprime
[689.144045] Starting dynamic subtest: test_reprime-smem
[689.168593] Dynamic subtest test_reprime-smem: SUCCESS (0.029s)
[689.171053] Subtest test_reprime: SUCCESS (0.029s)
[689.312540] [125/133] (336s left) gem_exec_params (rsvd2-dirt)
[689.368807] Starting subtest: rsvd2-dirt
[689.373043] Subtest rsvd2-dirt: SKIP (0.000s)
[689.502609] [126/133] (335s left) kms_lease (lessee-list)
[689.593301] Starting subtest: lessee-list
[689.595195] Starting dynamic subtest: pipe-A-eDP-1
[689.596936] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[689.606621] Starting dynamic subtest: pipe-B-eDP-1
[689.608174] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[689.616719] Starting dynamic subtest: pipe-C-eDP-1
[689.618385] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[689.626644] Starting dynamic subtest: pipe-D-eDP-1
[689.628137] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[689.629636] Subtest lessee-list: SUCCESS (0.012s)
[689.776430] [127/133] (335s left) kms_vrr (flip-dpms)
[689.878197] Starting subtest: flip-dpms
[689.883684] Subtest flip-dpms: SKIP (0.000s)
[689.974483] [128/133] (335s left) kms_frontbuffer_tracking (fbc-1p-rte)
[690.081208] Starting subtest: fbc-1p-rte
[695.533684] Subtest fbc-1p-rte: SUCCESS (5.455s)
[696.764281] [129/133] (328s left) gen9_exec_parse (batch-zero-length)
[696.830508] Subtest batch-zero-length: SKIP (0.000s)
[696.903484] [130/133] (328s left) prime_mmap (test_aperture_limit)
[696.969543] Starting subtest: test_aperture_limit
[696.971778] Starting dynamic subtest: test_aperture_limit-smem
[696.978598] Dynamic subtest test_aperture_limit-smem: SKIP (0.007s)
[696.982579] Subtest test_aperture_limit: SKIP (0.008s)
[697.109394] [131/133] (328s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[697.208710] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[697.213191] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[697.337235] [132/133] (328s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[697.432258] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[697.436691] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[697.569670] [133/133] (328s left) gem_exec_fence (long-history)
[698.039107] Starting subtest: long-history
[705.667296] Subtest long-history: SUCCESS (7.630s)
[706.569395] 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.