[57.129805] Initializing watchdogs
[57.129852]   /dev/watchdog0
[57.143935] [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]
[57.148008] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.150180] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.153665] [001/133] (960s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[57.280774] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[57.283811] Starting dynamic subtest: pipe-A-eDP-1
[57.550335] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.237s)
[57.562368] Starting dynamic subtest: pipe-B-eDP-1
[58.833500] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.284s)
[58.840255] Starting dynamic subtest: pipe-C-eDP-1
[60.034405] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.200s)
[60.043647] Starting dynamic subtest: pipe-D-eDP-1
[61.260136] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.226s)
[61.262507] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.950s)
[62.511396] [002/133] (955s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[62.569271] Starting subtest: yf-tiled-ccs-to-y-tiled
[62.573869] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[62.761391] [003/133] (954s left) gem_eio (in-flight-1us)
[62.897985] Starting subtest: in-flight-1us
[70.690437] Subtest in-flight-1us: SUCCESS (7.792s)
[70.815548] [FACT gem_eio (in-flight-1us)] new: kernel.kmod_is_loaded.vgem: true
[70.818601] [004/133] (946s left) kms_cursor_crc (cursor-size-change)
[70.915178] Starting subtest: cursor-size-change
[70.917062] Starting dynamic subtest: pipe-A-eDP-1
[71.755246] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.838s)
[71.763911] Starting dynamic subtest: pipe-D-eDP-1
[73.263845] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.509s)
[73.266250] Subtest cursor-size-change: SUCCESS (2.350s)
[74.076144] [005/133] (943s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[74.187996] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[74.199638] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s)
[74.335197] [006/133] (943s left) gem_ctx_persistence (heartbeat-close)
[74.387943] Starting subtest: heartbeat-close
[74.392517] Subtest heartbeat-close: SKIP (0.000s)
[74.507665] [007/133] (943s left) core_setmaster (master-drop-set-shared-fd)
[74.608543] Starting subtest: master-drop-set-shared-fd
[74.629379] Subtest master-drop-set-shared-fd: SUCCESS (0.021s)
[74.667627] [008/133] (943s left) gem_exec_schedule (wide)
[74.849177] Starting subtest: wide
[74.854844] Starting dynamic subtest: rcs0
[84.640144] Dynamic subtest rcs0: SUCCESS (9.791s)
[84.649583] Starting dynamic subtest: vcs0
[94.549350] Dynamic subtest vcs0: SUCCESS (9.907s)
[94.559964] Starting dynamic subtest: vcs1
[105.379552] Dynamic subtest vcs1: SUCCESS (10.830s)
[105.390132] Starting dynamic subtest: vecs0
[115.683381] Dynamic subtest vecs0: SUCCESS (10.303s)
[115.697806] Starting dynamic subtest: ccs0
[127.450559] Dynamic subtest ccs0: SUCCESS (11.715s)
[127.453184] Subtest wide: SUCCESS (52.548s)
[130.194694] [009/133] (887s left) gem_exec_fence (syncobj-timeline-signal)
[130.817345] Starting subtest: syncobj-timeline-signal
[130.828587] Subtest syncobj-timeline-signal: SUCCESS (0.013s)
[132.053980] [010/133] (885s left) kms_lease (setcrtc-implicit-plane)
[132.156390] Starting subtest: setcrtc-implicit-plane
[132.158771] Starting dynamic subtest: pipe-A-eDP-1
[132.630661] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.472s)
[132.639676] Starting dynamic subtest: pipe-B-eDP-1
[133.789790] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.158s)
[133.798044] Starting dynamic subtest: pipe-C-eDP-1
[134.917843] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.129s)
[134.925776] Starting dynamic subtest: pipe-D-eDP-1
[136.052196] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.133s)
[136.054496] Subtest setcrtc-implicit-plane: SUCCESS (3.895s)
[136.907707] [011/133] (881s left) kms_vrr (lobf)
[137.008948] Starting subtest: lobf
[137.013346] Subtest lobf: SKIP (0.000s)
[137.093015] [012/133] (880s left) gem_exec_reloc (basic-write-cpu-active)
[137.156586] Subtest basic-write-cpu-active: SKIP (0.000s)
[137.244057] [013/133] (880s left) syncobj_timeline (wait-any-interrupted)
[137.298821] Starting subtest: wait-any-interrupted
[137.401668] Subtest wait-any-interrupted: SUCCESS (0.101s)
[137.498209] [014/133] (880s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[137.591769] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[138.659035] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.067s)
[138.808874] [015/133] (879s left) kms_content_protection (type1)
[138.935108] Starting subtest: type1
[138.939058] Subtest type1: SKIP (0.000s)
[139.097988] [016/133] (879s left) gem_softpin (allocator-nopin-reserve)
[139.169832] Starting subtest: allocator-nopin-reserve
[139.285234] Subtest allocator-nopin-reserve: SUCCESS (0.113s)
[139.440253] [017/133] (878s left) gem_ctx_shared (q-smoketest)
[139.508079] Starting subtest: Q-smoketest
[139.510109] Starting dynamic subtest: rcs0
[145.107185] Dynamic subtest rcs0: SUCCESS (5.497s)
[145.116355] Starting dynamic subtest: bcs0
[150.596850] Dynamic subtest bcs0: SUCCESS (5.491s)
[150.605838] Starting dynamic subtest: vcs0
[156.084273] Dynamic subtest vcs0: SUCCESS (5.488s)
[156.093887] Starting dynamic subtest: vcs1
[161.559775] Dynamic subtest vcs1: SUCCESS (5.466s)
[161.582663] Starting dynamic subtest: vecs0
[167.020387] Dynamic subtest vecs0: SUCCESS (5.466s)
[167.029525] Starting dynamic subtest: ccs0
[172.522064] Dynamic subtest ccs0: SUCCESS (5.499s)
[172.524503] Subtest Q-smoketest: SUCCESS (32.912s)
[172.694394] [018/133] (845s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[172.793176] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[172.795681] Starting dynamic subtest: pipe-A-default-mode
[175.523337] Dynamic subtest pipe-A-default-mode: SUCCESS (2.730s)
[175.525765] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.731s)
[176.402605] [019/133] (841s left) syncobj_wait (multi-wait-for-submit-signaled)
[176.463853] Starting subtest: multi-wait-for-submit-signaled
[176.466113] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[176.590190] [020/133] (841s left) gem_ctx_param (invalid-set-ringsize)
[176.655854] Starting subtest: invalid-set-ringsize
[176.658192] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[176.743092] [021/133] (841s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[176.844514] Starting subtest: psr-rgb565-draw-render
[183.792480] Subtest psr-rgb565-draw-render: SUCCESS (6.951s)
[183.950096] [022/133] (834s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[184.040053] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[184.066190] Starting dynamic subtest: pipe-A-plane-0
[184.071029] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[184.083473] Starting dynamic subtest: pipe-A-plane-3
[184.088229] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[184.099399] Starting dynamic subtest: pipe-A-plane-5
[184.104153] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[185.213114] Starting dynamic subtest: pipe-B-plane-0
[185.217524] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[185.227789] Starting dynamic subtest: pipe-B-plane-3
[185.232169] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[185.239684] Starting dynamic subtest: pipe-B-plane-5
[185.244110] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[185.519487] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (1.476s)
[186.395059] [023/133] (832s left) i915_pciid
[186.549917] [024/133] (832s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[186.647277] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[186.649632] Starting dynamic subtest: pipe-A
[186.667475] Dynamic subtest pipe-A: SKIP (0.017s)
[186.676347] Starting dynamic subtest: pipe-B
[186.698653] Dynamic subtest pipe-B: SKIP (0.030s)
[186.707451] Starting dynamic subtest: pipe-C
[186.727171] Dynamic subtest pipe-C: SKIP (0.029s)
[186.734945] Starting dynamic subtest: pipe-D
[186.758595] Dynamic subtest pipe-D: SKIP (0.030s)
[186.762605] Subtest planes-downscale-factor-0-5-unity-scaling: SKIP (0.110s)
[186.877265] [025/133] (831s left) kms_universal_plane (universal-plane-functional)
[186.976694] Starting subtest: universal-plane-functional
[186.978633] Starting dynamic subtest: pipe-A-eDP-1
[190.877842] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.901s)
[190.893720] Starting dynamic subtest: pipe-B-eDP-1
[195.332763] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.454s)
[195.342686] Starting dynamic subtest: pipe-C-eDP-1
[199.695330] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.360s)
[199.704188] Starting dynamic subtest: pipe-D-eDP-1
[204.064237] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.367s)
[204.066428] Subtest universal-plane-functional: SUCCESS (17.088s)
[204.912449] [026/133] (813s left) i915_suspend (forcewake)
[204.973517] Starting subtest: forcewake
[237.515358] Subtest forcewake: SUCCESS (3.316s)
[237.628373] [027/133] (781s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[237.736061] Starting subtest: fbc-pr-cursor-mmap-cpu
[237.740426] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[237.820890] [028/133] (780s left) gem_mmap_gtt (cpuset-basic-small-copy)
[237.894745] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[237.963396] [029/133] (780s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[238.060254] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[238.064480] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[238.196068] [030/133] (780s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[238.301201] Starting subtest: crc-primary-basic-yf-tiled-ccs
[238.303443] Starting dynamic subtest: pipe-A-eDP-1
[238.325444] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[238.336226] Starting dynamic subtest: pipe-B-eDP-1
[238.356826] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[238.364586] Starting dynamic subtest: pipe-C-eDP-1
[238.385532] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[238.393370] Starting dynamic subtest: pipe-D-eDP-1
[238.414234] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[238.418609] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.005s)
[240.645404] [031/133] (778s left) kms_psr (pr-cursor-blt)
[240.753004] Starting subtest: pr-cursor-blt
[240.757343] Subtest pr-cursor-blt: SKIP (0.000s)
[240.838499] [032/133] (778s left) syncobj_eventfd (timeline-wait-before-signal)
[240.901635] Starting subtest: timeline-wait-before-signal
[240.931156] Subtest timeline-wait-before-signal: SUCCESS (0.031s)
[241.022311] [033/133] (777s left) prime_busy (hang-wait)
[241.096191] Starting subtest: hang-wait
[241.098377] Starting dynamic subtest: rcs0
[267.612556] Dynamic subtest rcs0: SUCCESS (26.494s)
[267.620612] Starting dynamic subtest: bcs0
[274.759465] Dynamic subtest bcs0: SUCCESS (7.151s)
[274.770213] Starting dynamic subtest: vcs0
[281.734985] Dynamic subtest vcs0: SUCCESS (6.972s)
[281.745032] Starting dynamic subtest: vcs1
[288.708955] Dynamic subtest vcs1: SUCCESS (6.969s)
[288.719402] Starting dynamic subtest: vecs0
[295.744405] Dynamic subtest vecs0: SUCCESS (7.032s)
[295.752839] Starting dynamic subtest: ccs0
[309.583400] Dynamic subtest ccs0: SUCCESS (13.834s)
[309.585930] Subtest hang-wait: SUCCESS (68.469s)
[309.738862] [034/133] (709s left) gem_mmap_gtt (basic-copy)
[309.798726] Subtest basic-copy: SKIP (0.000s)
[309.882073] [035/133] (709s left) kms_chamelium_frames (dp-frame-dump)
[310.270620] Subtest dp-frame-dump: SKIP (0.000s)
[311.037145] [036/133] (708s left) gem_mmap_gtt (big-copy-xy)
[311.103418] Subtest big-copy-XY: SKIP (0.000s)
[311.181393] [037/133] (707s left) kms_feature_discovery (display-3x)
[311.275953] Starting subtest: display-3x
[311.280379] Subtest display-3x: SKIP (0.000s)
[311.364699] [038/133] (707s left) gem_exec_schedule (u-semaphore-user)
[311.436608] Starting subtest: u-semaphore-user
[311.488469] Subtest u-semaphore-user: SUCCESS (0.057s)
[313.193840] [039/133] (706s left) gem_tiled_blits (normal)
[313.263164] Subtest normal: SKIP (0.000s)
[313.357119] [040/133] (705s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[313.455905] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[313.460222] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[313.594727] [041/133] (705s left) gen7_exec_parse (chained-batch)
[313.664567] Subtest chained-batch: SKIP (0.000s)
[313.756808] [042/133] (705s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[313.864139] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[313.866081] Starting dynamic subtest: pipe-A
[314.200459] Dynamic subtest pipe-A: SUCCESS (0.339s)
[314.209468] Starting dynamic subtest: pipe-B
[315.652679] Dynamic subtest pipe-B: SUCCESS (1.451s)
[315.661798] Starting dynamic subtest: pipe-C
[317.083657] Dynamic subtest pipe-C: SUCCESS (1.430s)
[317.094554] Starting dynamic subtest: pipe-D
[318.524512] Dynamic subtest pipe-D: SUCCESS (1.440s)
[318.526771] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.663s)
[319.704496] [043/133] (699s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[319.804720] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[319.809130] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[319.937621] [044/133] (699s left) kms_async_flips (invalid-async-flip)
[320.054197] Starting subtest: invalid-async-flip
[320.425809] Starting dynamic subtest: pipe-A-eDP-1
[321.211983] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.786s)
[321.573561] Starting dynamic subtest: pipe-B-eDP-1
[322.351307] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.785s)
[322.719270] Starting dynamic subtest: pipe-C-eDP-1
[323.472212] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.758s)
[323.835179] Starting dynamic subtest: pipe-D-eDP-1
[324.615843] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.785s)
[324.618238] Subtest invalid-async-flip: SUCCESS (4.561s)
[325.798237] [045/133] (693s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[325.892425] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[325.896346] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.000s)
[325.979318] [046/133] (693s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0-hflip)
[326.081518] Starting subtest: linear-max-hw-stride-32bpp-rotate-0-hflip
[326.085429] Subtest linear-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[326.222811] [047/133] (693s left) gem_basic (create-fd-close)
[326.278767] Starting subtest: create-fd-close
[326.283111] Subtest create-fd-close: SUCCESS (0.000s)
[326.388049] [048/133] (693s left) gem_exec_reloc (basic-cpu-wc)
[326.452795] Subtest basic-cpu-wc: SKIP (0.000s)
[326.528732] [049/133] (693s left) kms_pm_rpm (legacy-planes)
[328.258939] Starting subtest: legacy-planes
[328.261298] Starting dynamic subtest: plane-64
[336.562887] Dynamic subtest plane-64: SUCCESS (8.307s)
[336.573493] Starting dynamic subtest: plane-94
[344.942404] Dynamic subtest plane-94: SUCCESS (8.380s)
[344.953362] Starting dynamic subtest: plane-124
[353.254588] Dynamic subtest plane-124: SUCCESS (8.311s)
[353.266434] Starting dynamic subtest: plane-134
[361.540925] Dynamic subtest plane-134: SUCCESS (8.284s)
[361.543641] Subtest legacy-planes: SUCCESS (33.293s)
[361.925693] [050/133] (657s left) core_auth (getclient-master-drop)
[361.966003] Starting subtest: getclient-master-drop
[361.987529] Subtest getclient-master-drop: SUCCESS (0.021s)
[362.121017] [051/133] (657s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[362.177212] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[362.179594] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[362.312977] [052/133] (657s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[362.408938] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[362.413340] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[362.530689] [053/133] (657s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[362.602648] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[362.605059] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[362.708565] [054/133] (657s left) kms_cursor_edge_walk (64x64-right-edge)
[362.811291] Starting subtest: 64x64-right-edge
[362.813514] Starting dynamic subtest: pipe-A-eDP-1
[366.252664] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.441s)
[366.261980] Starting dynamic subtest: pipe-D-eDP-1
[369.393881] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.139s)
[369.395766] Subtest 64x64-right-edge: SUCCESS (6.583s)
[370.220469] [055/133] (649s left) prime_vgem (basic-gtt)
[370.321254] Starting subtest: basic-gtt
[370.325839] Subtest basic-gtt: SKIP (0.000s)
[370.442062] [056/133] (649s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[370.539257] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[370.541500] Starting dynamic subtest: pipe-A-eDP-1
[370.564041] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[370.573193] Starting dynamic subtest: pipe-B-eDP-1
[370.594369] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[370.602581] Starting dynamic subtest: pipe-C-eDP-1
[370.624028] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[370.632479] Starting dynamic subtest: pipe-D-eDP-1
[370.653937] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[370.658283] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[372.790859] [057/133] (647s left) gem_madvise (dontneed-before-exec)
[372.831217] Starting subtest: dontneed-before-exec
[372.858192] Subtest dontneed-before-exec: SKIP (0.025s)
[372.932273] [058/133] (647s left) kms_pwrite_crc
[374.534706] [059/133] (645s left) gem_exec_schedule (in-order)
[374.620494] Starting subtest: in-order
[374.622813] Starting dynamic subtest: rcs0
[374.711445] Dynamic subtest rcs0: SUCCESS (0.098s)
[374.724231] Starting dynamic subtest: vcs0
[374.799344] Dynamic subtest vcs0: SUCCESS (0.087s)
[374.811439] Starting dynamic subtest: vcs1
[374.872162] Dynamic subtest vcs1: SUCCESS (0.073s)
[374.888392] Starting dynamic subtest: vecs0
[374.942015] Dynamic subtest vecs0: SUCCESS (0.069s)
[374.955359] Starting dynamic subtest: ccs0
[375.020656] Dynamic subtest ccs0: SUCCESS (0.078s)
[375.023207] Subtest in-order: SUCCESS (0.409s)
[376.238254] [060/133] (643s left) gem_ccs (block-copy-uncompressed)
[376.286517] Starting subtest: block-copy-uncompressed
[376.288825] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[376.306460] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.020s)
[376.317523] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[376.323691] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[376.332353] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[376.341657] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[376.350753] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[376.360723] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[376.363077] Subtest block-copy-uncompressed: SUCCESS (0.075s)
[376.528732] [061/133] (643s left) i915_drm_fdinfo (memory-info-resident)
[376.570281] Starting subtest: memory-info-resident
[376.572208] Starting dynamic subtest: smem0
[379.598983] Dynamic subtest smem0: SUCCESS (3.031s)
[379.601002] Subtest memory-info-resident: SUCCESS (3.032s)
[379.682682] [062/133] (640s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[379.789114] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[379.801054] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.010s)
[379.946220] [063/133] (640s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[380.053441] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[387.323446] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.272s)
[387.483063] [064/133] (632s left) kms_psr (psr-sprite-mmap-gtt)
[387.579474] Starting subtest: psr-sprite-mmap-gtt
[387.581736] Starting dynamic subtest: eDP-1
[389.071952] Dynamic subtest eDP-1: SKIP (1.489s)
[389.076962] Subtest psr-sprite-mmap-gtt: SKIP (1.492s)
[389.268667] [065/133] (631s left) kms_cursor_legacy (single-bo)
[389.393454] Starting subtest: single-bo
[389.395558] Starting dynamic subtest: pipe-A
[394.825790] Dynamic subtest pipe-A: SUCCESS (5.378s)
[394.836741] Starting dynamic subtest: pipe-B
[400.209505] Dynamic subtest pipe-B: SUCCESS (5.379s)
[400.220906] Starting dynamic subtest: pipe-C
[405.590804] Dynamic subtest pipe-C: SUCCESS (5.378s)
[405.602174] Starting dynamic subtest: pipe-D
[410.972814] Dynamic subtest pipe-D: SUCCESS (5.379s)
[410.983590] Starting dynamic subtest: all-pipes
[416.362265] Dynamic subtest all-pipes: SUCCESS (5.386s)
[416.364214] Subtest single-bo: SUCCESS (26.915s)
[416.479669] [066/133] (603s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[416.597965] Starting subtest: psr-rgb565-draw-mmap-gtt
[416.602256] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[416.740890] [067/133] (603s left) i915_pm_rps (basic-api)
[416.810017] Starting subtest: basic-api
[416.814772] Subtest basic-api: SKIP (0.001s)
[416.948724] [068/133] (603s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[417.051902] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[417.054232] Starting dynamic subtest: pipe-A-eDP-1
[417.157894] Dynamic subtest pipe-A-eDP-1: SKIP (0.110s)
[417.169338] Starting dynamic subtest: pipe-B-eDP-1
[418.262643] Dynamic subtest pipe-B-eDP-1: SKIP (1.101s)
[418.267187] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.214s)
[419.475825] [069/133] (601s left) intel_hwmon (hwmon-write)
[419.545071] Subtest hwmon-write: SKIP (0.000s)
[419.607557] [070/133] (600s left) gem_lmem_swapping (heavy-multi)
[419.646081] Subtest heavy-multi: SKIP (0.000s)
[419.683322] [071/133] (600s left) perf_pmu (init-busy)
[419.724278] Starting subtest: init-busy
[419.726604] Starting dynamic subtest: rcs0
[419.728840] Dynamic subtest rcs0: SUCCESS (0.001s)
[419.738246] Starting dynamic subtest: bcs0
[419.740380] Dynamic subtest bcs0: SUCCESS (0.000s)
[419.748663] Starting dynamic subtest: vcs0
[419.750850] Dynamic subtest vcs0: SUCCESS (0.001s)
[419.759298] Starting dynamic subtest: vcs1
[419.761450] Dynamic subtest vcs1: SUCCESS (0.000s)
[419.770096] Starting dynamic subtest: vecs0
[419.772303] Dynamic subtest vecs0: SUCCESS (0.001s)
[419.780382] Starting dynamic subtest: ccs0
[419.782612] Dynamic subtest ccs0: SUCCESS (0.000s)
[419.784885] Subtest init-busy: SUCCESS (0.007s)
[419.848555] [072/133] (600s left) gem_pread (self)
[419.901856] Subtest self: SKIP (0.000s)
[419.959341] [073/133] (600s left) gem_exec_flush (basic-uc-rw-default)
[420.020502] Starting subtest: basic-uc-rw-default
[425.510908] Subtest basic-uc-rw-default: SUCCESS (5.452s)
[425.670236] [074/133] (595s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[425.792323] Starting subtest: cursorB-vs-flipA-legacy
[425.796929] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[425.961703] [075/133] (594s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[426.061606] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[426.065792] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[426.188275] [076/133] (594s left) kms_addfb_basic (basic)
[426.246467] Starting subtest: basic
[426.248715] Subtest basic: SUCCESS (0.000s)
[426.411473] [077/133] (594s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[426.513621] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[426.517132] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[426.610390] [078/133] (594s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[426.702550] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[430.618521] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (3.916s)
[431.786952] [079/133] (589s left) syncobj_wait (wait-any-snapshot)
[431.841637] Starting subtest: wait-any-snapshot
[431.921472] Subtest wait-any-snapshot: SUCCESS (0.081s)
[432.021529] [080/133] (588s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[432.114087] Starting subtest: crc-primary-suspend-y-tiled-ccs
[432.116302] Starting dynamic subtest: pipe-A-eDP-1
[432.124763] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[432.133943] Starting dynamic subtest: pipe-B-eDP-1
[432.142427] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[432.150981] Starting dynamic subtest: pipe-C-eDP-1
[432.159353] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[432.167806] Starting dynamic subtest: pipe-D-eDP-1
[432.176041] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[432.180274] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.005s)
[434.440269] [081/133] (586s left) kms_prop_blob (blob-prop-validate)
[434.488047] Starting subtest: blob-prop-validate
[434.490413] Subtest blob-prop-validate: SUCCESS (0.000s)
[434.622317] [082/133] (586s left) kms_psr (psr2-primary-mmap-gtt)
[434.725195] Starting subtest: psr2-primary-mmap-gtt
[434.727551] Starting dynamic subtest: eDP-1
[436.128735] Dynamic subtest eDP-1: SKIP (1.400s)
[436.133269] Subtest psr2-primary-mmap-gtt: SKIP (1.402s)
[436.282475] [083/133] (584s left) kms_psr (fbc-pr-suspend)
[436.368291] Starting subtest: fbc-pr-suspend
[436.372835] Subtest fbc-pr-suspend: SKIP (0.000s)
[436.452434] [084/133] (584s left) kms_pipe_crc_basic (nonblocking-crc)
[436.538889] Starting subtest: nonblocking-crc
[436.540761] Starting dynamic subtest: pipe-A-eDP-1
[437.332014] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.792s)
[437.340995] Starting dynamic subtest: pipe-B-eDP-1
[438.763853] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.430s)
[438.773107] Starting dynamic subtest: pipe-C-eDP-1
[440.172268] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.407s)
[440.181915] Starting dynamic subtest: pipe-D-eDP-1
[441.505768] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[441.508204] Subtest nonblocking-crc: SUCCESS (4.967s)
[442.319991] [085/133] (578s left) prime_mmap (test_correct_cpu_write)
[442.382830] Starting subtest: test_correct_cpu_write
[442.385206] Starting dynamic subtest: test_correct_cpu_write-smem
[442.387423] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[442.389666] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[442.521396] [086/133] (578s left) syncobj_basic (bad-create-flags)
[442.577841] Starting subtest: bad-create-flags
[442.580137] Subtest bad-create-flags: SUCCESS (0.000s)
[442.696716] [087/133] (578s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[442.793454] Starting subtest: psr-2p-primscrn-cur-indfb-move
[442.797810] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[442.914882] [088/133] (578s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[443.019200] Starting subtest: y-tiled-32bpp-rotate-0
[443.023686] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[443.153608] [089/133] (578s left) kms_vblank (wait-forked-busy-hang)
[443.256529] Starting subtest: wait-forked-busy-hang
[443.258320] Starting dynamic subtest: pipe-A-eDP-1
[469.878570] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.619s)
[469.892704] Starting dynamic subtest: pipe-D-eDP-1
[494.899890] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.019s)
[494.902329] Subtest wait-forked-busy-hang: SUCCESS (51.643s)
[495.768861] [090/133] (525s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[495.869321] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[495.871527] Starting dynamic subtest: pipe-A-eDP-1
[497.102346] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.235s)
[497.111939] Starting dynamic subtest: pipe-B-eDP-1
[499.349671] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.246s)
[499.359437] Starting dynamic subtest: pipe-C-eDP-1
[501.589589] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.239s)
[501.600075] Starting dynamic subtest: pipe-D-eDP-1
[503.840604] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.250s)
[503.842977] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (7.974s)
[505.022254] [091/133] (516s left) syncobj_timeline (etime-multi-wait-submitted)
[505.087775] Starting subtest: etime-multi-wait-submitted
[505.191332] Subtest etime-multi-wait-submitted: SUCCESS (0.103s)
[505.278421] [092/133] (516s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[505.391759] Starting subtest: flip-vs-cursor-legacy
[512.450849] Subtest flip-vs-cursor-legacy: SUCCESS (7.059s)
[513.343452] [093/133] (508s left) gem_create (create-ext-placement-all)
[513.518405] Starting subtest: create-ext-placement-all
[513.520969] Subtest create-ext-placement-all: SUCCESS (0.000s)
[513.634808] [094/133] (507s left) gem_exec_balancer (individual)
[513.705823] Starting subtest: individual
[515.140396] Subtest individual: SUCCESS (1.433s)
[515.475960] [095/133] (506s left) api_intel_allocator (reloc-allocator)
[515.541601] Starting subtest: reloc-allocator
[515.543932] Starting dynamic subtest: basic
[515.546295] Dynamic subtest basic: SUCCESS (0.003s)
[515.557435] Starting dynamic subtest: parallel-one
[515.559683] Dynamic subtest parallel-one: SUCCESS (0.008s)
[515.568648] Starting dynamic subtest: print
[515.570537] Dynamic subtest print: SUCCESS (0.000s)
[515.578824] Starting dynamic subtest: reuse
[515.580737] Dynamic subtest reuse: SUCCESS (0.000s)
[515.589004] Starting dynamic subtest: fork-reopen-allocator
[515.590873] Dynamic subtest fork-reopen-allocator: SUCCESS (0.010s)
[515.592755] Subtest reloc-allocator: SUCCESS (0.025s)
[515.700651] [096/133] (505s left) kms_addfb_basic (bad-pitch-128)
[515.764855] Starting subtest: bad-pitch-128
[515.767235] Subtest bad-pitch-128: SUCCESS (0.000s)
[515.908930] [097/133] (505s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[515.999021] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[516.003449] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[516.137064] [098/133] (505s left) gen3_render_mixed_blits
[516.267362] [099/133] (505s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[516.375794] Starting subtest: yf-tiled-64bpp-rotate-180
[516.379322] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[516.485156] [100/133] (505s left) gem_mmap_offset (mmap-unmap)
[516.541932] Starting subtest: mmap-unmap
[516.543604] Starting dynamic subtest: smem0
[538.831960] Dynamic subtest smem0: SUCCESS (22.238s)
[538.834592] Subtest mmap-unmap: SUCCESS (22.239s)
[538.892611] [101/133] (482s left) gem_mmap_gtt (coherency)
[538.951251] Subtest coherency: SKIP (0.000s)
[539.030483] [102/133] (482s left) kms_big_fb (linear-32bpp-rotate-0)
[539.132992] Starting subtest: linear-32bpp-rotate-0
[540.645020] Subtest linear-32bpp-rotate-0: SUCCESS (1.511s)
[540.836407] [103/133] (481s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[540.903658] Starting subtest: x-tiled-to-vebox-y-tiled
[540.907406] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[541.111629] [104/133] (480s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[541.165575] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[541.168092] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[541.288642] [105/133] (480s left) template (a)
[541.351936] Starting subtest: A
[541.354136] Subtest A: SUCCESS (0.000s)
[541.483083] [106/133] (480s left) kms_hdr (invalid-hdr)
[541.578989] Starting subtest: invalid-hdr
[543.650345] Starting dynamic subtest: pipe-A-eDP-1
[546.596730] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.946s)
[546.599382] Subtest invalid-hdr: SUCCESS (5.021s)
[546.739235] [107/133] (475s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt)
[546.845840] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt
[546.850221] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[546.983719] [108/133] (475s left) kms_cursor_crc (cursor-rapid-movement-64x64)
[547.165421] Starting subtest: cursor-rapid-movement-64x64
[547.183708] Starting dynamic subtest: pipe-A-eDP-1
[548.786415] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.601s)
[548.816838] Starting dynamic subtest: pipe-D-eDP-1
[550.017848] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.206s)
[550.020201] Subtest cursor-rapid-movement-64x64: SUCCESS (2.849s)
[550.863303] [109/133] (471s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[550.971207] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[555.194728] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.224s)
[556.350710] [110/133] (465s left) kms_psr (fbc-psr-sprite-blt)
[556.447585] Starting subtest: fbc-psr-sprite-blt
[556.449829] Starting dynamic subtest: eDP-1
[556.453864] Dynamic subtest eDP-1: SKIP (0.000s)
[556.457941] Subtest fbc-psr-sprite-blt: SKIP (0.002s)
[556.548160] [111/133] (465s left) kms_flip (plain-flip-interruptible)
[556.642093] Starting subtest: plain-flip-interruptible
[556.644285] Starting dynamic subtest: A-eDP1
[558.336859] Dynamic subtest A-eDP1: SUCCESS (1.694s)
[558.346292] Starting dynamic subtest: B-eDP1
[559.953389] Dynamic subtest B-eDP1: SUCCESS (1.615s)
[559.963719] Starting dynamic subtest: C-eDP1
[561.560401] Dynamic subtest C-eDP1: SUCCESS (1.605s)
[561.570897] Starting dynamic subtest: D-eDP1
[563.201891] Dynamic subtest D-eDP1: SUCCESS (1.640s)
[563.207848] Subtest plain-flip-interruptible: SUCCESS (6.565s)
[564.478870] [112/133] (457s left) kms_cursor_crc (cursor-offscreen-32x32)
[564.631730] Starting subtest: cursor-offscreen-32x32
[564.680107] Subtest cursor-offscreen-32x32: SKIP (0.042s)
[564.977327] [113/133] (457s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[565.070619] Starting subtest: 4-tiled-addfb-size-offset-overflow
[565.072522] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[565.192328] [114/133] (457s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[565.288621] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[565.292741] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[565.418815] [115/133] (456s left) syncobj_timeline (invalid-signal-illegal-point)
[565.475822] Starting subtest: invalid-signal-illegal-point
[565.477782] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[565.614553] [116/133] (456s left) gem_exec_create (madvise)
[565.682226] Starting subtest: madvise
[565.684206] Starting dynamic subtest: smem
[587.192884] Dynamic subtest smem: SUCCESS (21.506s)
[587.195642] Subtest madvise: SUCCESS (21.509s)
[588.190630] [117/133] (434s left) kms_flip (flip-vs-expired-vblank-interruptible)
[588.321707] Starting subtest: flip-vs-expired-vblank-interruptible
[588.326706] Starting dynamic subtest: A-eDP1
[597.100370] Dynamic subtest A-eDP1: SUCCESS (8.739s)
[597.112748] Starting dynamic subtest: B-eDP1
[605.723795] Dynamic subtest B-eDP1: SUCCESS (8.621s)
[605.735443] Starting dynamic subtest: C-eDP1
[614.358816] Dynamic subtest C-eDP1: SUCCESS (8.621s)
[614.370545] Starting dynamic subtest: D-eDP1
[622.996740] Dynamic subtest D-eDP1: SUCCESS (8.634s)
[622.998848] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.629s)
[624.136257] [118/133] (398s left) kms_plane_multiple (2x-tiling-y)
[624.226388] Starting subtest: 2x-tiling-y
[624.230885] Subtest 2x-tiling-y: SKIP (0.000s)
[624.317296] [119/133] (398s left) gem_sync (basic-store-all)
[624.396613] Starting subtest: basic-store-all
[627.345062] Subtest basic-store-all: SUCCESS (2.957s)
[627.546577] [120/133] (395s left) kms_color (ctm-0-75)
[627.644402] Starting subtest: ctm-0-75
[627.646382] Starting dynamic subtest: pipe-A-eDP-1
[632.568424] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.923s)
[632.578057] Starting dynamic subtest: pipe-B-eDP-1
[638.215234] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.646s)
[638.227893] Starting dynamic subtest: pipe-C-eDP-1
[643.619651] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.405s)
[643.629973] Starting dynamic subtest: pipe-D-eDP-1
[648.940805] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.318s)
[648.943119] Subtest ctm-0-75: SUCCESS (21.295s)
[649.777585] [121/133] (372s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[649.889821] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[649.894063] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[650.007242] [122/133] (372s left) kms_pm_rpm (dpms-non-lpsp)
[652.355729] Starting subtest: dpms-non-lpsp
[652.360931] Subtest dpms-non-lpsp: SKIP (0.001s)
[652.753624] [123/133] (369s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[653.338966] Starting subtest: syncobj-timeline-invalid-wait
[653.341944] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.003s)
[655.277947] [124/133] (367s left) kms_invalid_mode (bad-htotal)
[655.388856] Starting subtest: bad-htotal
[655.391222] Starting dynamic subtest: pipe-A-eDP-1
[655.679059] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[655.688825] Starting dynamic subtest: pipe-B-eDP-1
[655.690690] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[655.698998] Starting dynamic subtest: pipe-C-eDP-1
[655.701241] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[655.709673] Starting dynamic subtest: pipe-D-eDP-1
[655.711790] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[655.713945] Subtest bad-htotal: SUCCESS (0.295s)
[656.546317] [125/133] (366s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[656.650732] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[663.169586] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.520s)
[663.296341] [126/133] (359s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[663.400676] Starting subtest: fbcpsr-tiling-4
[670.481586] Subtest fbcpsr-tiling-4: SUCCESS (7.083s)
[670.641230] [127/133] (352s left) gem_exec_big (single)
[670.691170] Starting subtest: single
[673.130392] Subtest single: FAIL (2.377s)
[673.173895] [128/133] (349s left) kms_chamelium_hpd (common-hpd-after-suspend)
[673.630176] Subtest common-hpd-after-suspend: SKIP (0.000s)
[674.415936] [129/133] (348s left) gem_render_linear_blits (basic)
[674.476943] Starting subtest: basic
[674.499286] Subtest basic: SUCCESS (0.022s)
[674.586013] [130/133] (348s left) gem_wait (await)
[674.652233] Starting subtest: await
[674.654527] Starting dynamic subtest: all-engines
[675.757182] Dynamic subtest all-engines: SUCCESS (1.104s)
[675.778177] Starting dynamic subtest: rcs0
[676.812700] Dynamic subtest rcs0: SUCCESS (1.053s)
[676.829841] Starting dynamic subtest: bcs0
[677.868527] Dynamic subtest bcs0: SUCCESS (1.053s)
[677.885306] Starting dynamic subtest: vcs0
[678.921418] Dynamic subtest vcs0: SUCCESS (1.051s)
[678.938259] Starting dynamic subtest: vcs1
[679.973826] Dynamic subtest vcs1: SUCCESS (1.050s)
[679.990749] Starting dynamic subtest: vecs0
[681.026217] Dynamic subtest vecs0: SUCCESS (1.050s)
[681.043846] Starting dynamic subtest: ccs0
[682.083266] Dynamic subtest ccs0: SUCCESS (1.055s)
[682.085741] Subtest await: SUCCESS (7.431s)
[682.306107] [131/133] (340s left) gem_eio (in-flight-contexts-10ms)
[682.525748] Starting subtest: in-flight-contexts-10ms
[696.146090] Subtest in-flight-contexts-10ms: SUCCESS (13.620s)
[696.345943] [132/133] (326s left) vgem_basic (busy-fence)
[696.396296] Starting subtest: busy-fence
[696.398634] Subtest busy-fence: SUCCESS (0.000s)
[696.464027] [133/133] (326s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[696.565976] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[703.280230] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (6.714s)
[703.413512] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: gem_render_copy
results: parsing output: 2/ for test: gem_eio
results: parsing output: 3/ for test: kms_cursor_crc
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: gem_ctx_persistence
results: parsing output: 6/ for test: core_setmaster
results: parsing output: 7/ for test: gem_exec_schedule
results: parsing output: 8/ for test: gem_exec_fence
results: parsing output: 9/ for test: kms_lease
results: parsing output: 10/ for test: kms_vrr
results: parsing output: 11/ for test: gem_exec_reloc
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: kms_content_protection
results: parsing output: 15/ for test: gem_softpin
results: parsing output: 16/ for test: gem_ctx_shared
results: parsing output: 17/ for test: kms_flip_scaled_crc
results: parsing output: 18/ for test: syncobj_wait
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_plane
results: parsing output: 22/ for test: i915_pciid
results: parsing output: 23/ for test: kms_plane_scaling
results: parsing output: 24/ for test: kms_universal_plane
results: parsing output: 25/ for test: i915_suspend
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: gem_mmap_gtt
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_ccs
results: parsing output: 30/ for test: kms_psr
results: parsing output: 31/ for test: syncobj_eventfd
results: parsing output: 32/ for test: prime_busy
results: parsing output: 33/ for test: gem_mmap_gtt
results: parsing output: 34/ for test: kms_chamelium_frames
results: parsing output: 35/ for test: gem_mmap_gtt
results: parsing output: 36/ for test: kms_feature_discovery
results: parsing output: 37/ for test: gem_exec_schedule
results: parsing output: 38/ for test: gem_tiled_blits
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: gen7_exec_parse
results: parsing output: 41/ for test: kms_plane_scaling
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_async_flips
results: parsing output: 44/ for test: kms_flip_scaled_crc
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: gem_basic
results: parsing output: 47/ for test: gem_exec_reloc
results: parsing output: 48/ for test: kms_pm_rpm
results: parsing output: 49/ for test: core_auth
results: parsing output: 50/ for test: syncobj_wait
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: syncobj_wait
results: parsing output: 53/ for test: kms_cursor_edge_walk
results: parsing output: 54/ for test: prime_vgem
results: parsing output: 55/ for test: kms_ccs
results: parsing output: 56/ for test: gem_madvise
results: parsing output: 57/ for test: kms_pwrite_crc
results: parsing output: 58/ for test: gem_exec_schedule
results: parsing output: 59/ for test: gem_ccs
results: parsing output: 60/ for test: i915_drm_fdinfo
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_cursor_legacy
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: i915_pm_rps
results: parsing output: 67/ for test: kms_psr2_sf
results: parsing output: 68/ for test: intel_hwmon
results: parsing output: 69/ for test: gem_lmem_swapping
results: parsing output: 70/ for test: perf_pmu
results: parsing output: 71/ for test: gem_pread
results: parsing output: 72/ for test: gem_exec_flush
results: parsing output: 73/ for test: kms_cursor_legacy
results: parsing output: 74/ for test: kms_big_fb
results: parsing output: 75/ for test: kms_addfb_basic
results: parsing output: 76/ for test: kms_flip_scaled_crc
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: syncobj_wait
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: kms_prop_blob
results: parsing output: 81/ for test: kms_psr
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: kms_pipe_crc_basic
results: parsing output: 84/ for test: prime_mmap
results: parsing output: 85/ for test: syncobj_basic
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: kms_vblank
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: gem_create
results: parsing output: 93/ for test: gem_exec_balancer
results: parsing output: 94/ for test: api_intel_allocator
results: parsing output: 95/ for test: kms_addfb_basic
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: gen3_render_mixed_blits
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: gem_mmap_offset
results: parsing output: 100/ for test: gem_mmap_gtt
results: parsing output: 101/ for test: kms_big_fb
results: parsing output: 102/ for test: gem_render_copy
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: template
results: parsing output: 105/ for test: kms_hdr
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_cursor_crc
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: kms_flip
results: parsing output: 111/ for test: kms_cursor_crc
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: gem_exec_create
results: parsing output: 116/ for test: kms_flip
results: parsing output: 117/ for test: kms_plane_multiple
results: parsing output: 118/ for test: gem_sync
results: parsing output: 119/ for test: kms_color
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_pm_rpm
results: parsing output: 122/ for test: gem_exec_fence
results: parsing output: 123/ for test: kms_invalid_mode
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_exec_big
results: parsing output: 127/ for test: kms_chamelium_hpd
results: parsing output: 128/ for test: gem_render_linear_blits
results: parsing output: 129/ for test: gem_wait
results: parsing output: 130/ for test: gem_eio
results: parsing output: 131/ for test: vgem_basic
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.