[57.939489] Initializing watchdogs
[57.939539]   /dev/watchdog0
[57.953801] [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.958060] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.960542] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.964029] [001/131] (960s left) fbdev (unaligned-read)
[58.033910] Starting subtest: unaligned-read
[58.049224] Subtest unaligned-read: SUCCESS (0.015s)
[58.127295] [002/131] (960s left) gem_ctx_isolation (dirty-switch)
[58.195089] Starting subtest: dirty-switch
[58.197532] Starting dynamic subtest: rcs0
[58.454726] Dynamic subtest rcs0: SUCCESS (0.259s)
[58.465260] Starting dynamic subtest: bcs0
[58.744097] Dynamic subtest bcs0: SUCCESS (0.288s)
[58.754652] Starting dynamic subtest: vcs0
[59.015385] Dynamic subtest vcs0: SUCCESS (0.272s)
[59.024452] Starting dynamic subtest: vcs1
[60.133705] Dynamic subtest vcs1: SUCCESS (1.117s)
[60.143466] Starting dynamic subtest: vecs0
[62.138946] Dynamic subtest vecs0: SUCCESS (2.003s)
[62.150816] Starting dynamic subtest: ccs0
[64.113088] Dynamic subtest ccs0: SUCCESS (1.973s)
[64.115490] Subtest dirty-switch: SUCCESS (5.914s)
[64.389152] [003/131] (954s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[64.500299] Starting subtest: psr-modesetfrombusy
[70.660565] Subtest psr-modesetfrombusy: SUCCESS (6.162s)
[70.857654] [004/131] (947s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[70.962033] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[70.963991] Starting dynamic subtest: pipe-A-default-mode
[73.670666] Dynamic subtest pipe-A-default-mode: SUCCESS (2.708s)
[73.672937] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.709s)
[74.554330] [005/131] (944s left) gem_exec_params (sol-reset-invalid)
[74.617770] Starting subtest: sol-reset-invalid
[74.620181] Subtest sol-reset-invalid: SUCCESS (0.002s)
[76.041113] [006/131] (942s left) gem_spin_batch (resubmit-new)
[76.110915] Starting subtest: resubmit-new
[76.113725] Starting dynamic subtest: rcs0
[76.119190] Dynamic subtest rcs0: SUCCESS (0.007s)
[76.129519] Starting dynamic subtest: bcs0
[76.131935] Dynamic subtest bcs0: SUCCESS (0.004s)
[76.143298] Starting dynamic subtest: vcs0
[76.145274] Dynamic subtest vcs0: SUCCESS (0.004s)
[76.152284] Starting dynamic subtest: vcs1
[76.154205] Dynamic subtest vcs1: SUCCESS (0.004s)
[76.161217] Starting dynamic subtest: vecs0
[76.163143] Dynamic subtest vecs0: SUCCESS (0.005s)
[76.169953] Starting dynamic subtest: ccs0
[76.171947] Dynamic subtest ccs0: SUCCESS (0.004s)
[76.173905] Subtest resubmit-new: SUCCESS (0.030s)
[77.971583] [007/131] (940s left) gem_exec_schedule (preempt-user)
[78.049891] Starting subtest: preempt-user
[78.051964] Starting dynamic subtest: rcs0
[78.079438] Dynamic subtest rcs0: SUCCESS (0.037s)
[78.089177] Starting dynamic subtest: bcs0
[78.107966] Dynamic subtest bcs0: SUCCESS (0.028s)
[78.115778] Starting dynamic subtest: vcs0
[78.132939] Dynamic subtest vcs0: SUCCESS (0.025s)
[78.142215] Starting dynamic subtest: vcs1
[78.159456] Dynamic subtest vcs1: SUCCESS (0.026s)
[78.167595] Starting dynamic subtest: vecs0
[78.186295] Dynamic subtest vecs0: SUCCESS (0.025s)
[78.193872] Starting dynamic subtest: ccs0
[78.216229] Dynamic subtest ccs0: SUCCESS (0.031s)
[78.218498] Subtest preempt-user: SUCCESS (0.174s)
[80.023189] [008/131] (938s left) sysfs_heartbeat_interval (nopreempt)
[80.086394] Starting subtest: nopreempt
[80.088612] Starting dynamic subtest: vecs0
[89.968089] Dynamic subtest vecs0: SUCCESS (9.879s)
[89.977591] Starting dynamic subtest: ccs0
[94.047537] Dynamic subtest ccs0: SUCCESS (4.081s)
[94.058885] Starting dynamic subtest: vcs0
[102.963183] Dynamic subtest vcs0: SUCCESS (8.912s)
[102.972290] Starting dynamic subtest: bcs0
[107.022999] Dynamic subtest bcs0: SUCCESS (4.061s)
[107.032798] Starting dynamic subtest: rcs0
[111.092963] Dynamic subtest rcs0: SUCCESS (4.070s)
[111.103678] Starting dynamic subtest: vcs1
[120.922967] Dynamic subtest vcs1: SUCCESS (9.827s)
[120.925462] Subtest nopreempt: SUCCESS (40.837s)
[121.018741] [009/131] (897s left) kms_invalid_mode (zero-hdisplay)
[121.122060] Starting subtest: zero-hdisplay
[121.124348] Starting dynamic subtest: pipe-A-eDP-1
[121.404761] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[121.412709] Starting dynamic subtest: pipe-B-eDP-1
[121.414682] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[121.422057] Starting dynamic subtest: pipe-C-eDP-1
[121.423972] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[121.431715] Starting dynamic subtest: pipe-D-eDP-1
[121.433962] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[121.436258] Subtest zero-hdisplay: SUCCESS (0.288s)
[122.311737] [010/131] (896s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[122.414411] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[122.418894] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[122.542198] [011/131] (896s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[122.640416] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[123.509043] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.870s)
[123.725200] [012/131] (895s left) kms_getfb (getfb-handle-zero)
[123.827060] Starting subtest: getfb-handle-zero
[123.828943] Subtest getfb-handle-zero: SUCCESS (0.000s)
[123.964579] [013/131] (894s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[124.024574] Starting subtest: yf-tiled-ccs-to-yf-tiled
[124.028854] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[124.232071] [014/131] (894s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[124.337709] Starting subtest: multiplane-rotation-cropping-bottom
[131.598949] Subtest multiplane-rotation-cropping-bottom: SUCCESS (7.263s)
[131.744216] [015/131] (887s left) gem_eio (wait-1us)
[131.976869] Starting subtest: wait-1us
[132.325855] Subtest wait-1us: SUCCESS (0.350s)
[132.509416] [016/131] (886s left) syncobj_wait (multi-wait-for-submit-signaled)
[132.562562] Starting subtest: multi-wait-for-submit-signaled
[132.564567] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[132.692623] [017/131] (886s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[132.784970] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[132.786798] Starting dynamic subtest: pipe-A-default-mode
[133.075708] Dynamic subtest pipe-A-default-mode: SKIP (0.288s)
[133.080323] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.289s)
[133.896960] [018/131] (885s left) kms_plane_alpha_blend (alpha-7efc)
[134.001461] Starting subtest: alpha-7efc
[134.287397] Starting dynamic subtest: pipe-A-eDP-1
[134.887021] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.597s)
[134.916822] Starting dynamic subtest: pipe-D-eDP-1
[136.197212] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.284s)
[136.199431] Subtest alpha-7efc: SUCCESS (2.194s)
[137.043094] [019/131] (882s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[137.140244] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[137.144215] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[137.279565] [020/131] (881s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[137.375637] Starting subtest: fbc-psr2-primary-mmap-cpu
[137.377838] Starting dynamic subtest: eDP-1
[137.382050] Dynamic subtest eDP-1: SKIP (0.000s)
[137.386150] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[137.467874] [021/131] (881s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[137.558688] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[137.568261] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[137.713872] [022/131] (881s left) gem_exec_whisper (basic-normal-all)
[137.794460] Starting subtest: basic-normal-all
[140.276861] Subtest basic-normal-all: SUCCESS (2.480s)
[140.479719] [023/131] (878s left) kms_big_fb (linear-16bpp-rotate-180)
[140.584364] Starting subtest: linear-16bpp-rotate-180
[141.907986] Subtest linear-16bpp-rotate-180: SUCCESS (1.323s)
[142.072398] [024/131] (877s left) kms_frontbuffer_tracking (psr-slowdraw)
[142.184279] Starting subtest: psr-slowdraw
[167.473191] Subtest psr-slowdraw: SUCCESS (25.288s)
[167.633110] [025/131] (851s left) vgem_basic (dmabuf-fence)
[167.727660] Starting subtest: dmabuf-fence
[167.729995] Subtest dmabuf-fence: SUCCESS (0.002s)
[167.806726] [FACT vgem_basic (dmabuf-fence)] new: kernel.kmod_is_loaded.vgem: true
[167.809990] [026/131] (851s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[167.869853] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[167.871924] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[168.011151] [027/131] (851s left) kms_psr (pr-primary-render)
[168.112695] Starting subtest: pr-primary-render
[168.117100] Subtest pr-primary-render: SKIP (0.000s)
[168.211494] [028/131] (851s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[168.315269] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[168.317137] Starting dynamic subtest: pipe-A-eDP-1
[168.336308] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[168.346476] Starting dynamic subtest: pipe-B-eDP-1
[168.364425] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[168.372574] Starting dynamic subtest: pipe-C-eDP-1
[168.390466] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[168.398544] Starting dynamic subtest: pipe-D-eDP-1
[168.417068] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[168.420980] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[170.622631] [029/131] (848s left) core_auth (getclient-master-drop)
[170.661882] Starting subtest: getclient-master-drop
[170.689605] Subtest getclient-master-drop: SUCCESS (0.028s)
[170.809209] [030/131] (848s left) kms_plane_scaling (planes-downscale-factor-0-5)
[170.902559] Starting subtest: planes-downscale-factor-0-5
[170.904814] Starting dynamic subtest: pipe-A
[170.924487] Dynamic subtest pipe-A: SKIP (0.018s)
[170.933626] Starting dynamic subtest: pipe-B
[170.960782] Dynamic subtest pipe-B: SKIP (0.036s)
[170.969382] Starting dynamic subtest: pipe-C
[170.998503] Dynamic subtest pipe-C: SKIP (0.036s)
[171.006928] Starting dynamic subtest: pipe-D
[171.031514] Dynamic subtest pipe-D: SKIP (0.032s)
[171.035851] Subtest planes-downscale-factor-0-5: SKIP (0.124s)
[171.158704] [031/131] (848s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[171.264990] Starting subtest: fb-8bpc-vs-panel-8bpc
[171.267360] Starting dynamic subtest: pipe-A-eDP-1
[172.688968] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.421s)
[172.690878] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.423s)
[173.580629] [032/131] (846s left) gem_partial_pwrite_pread (reads-snoop)
[173.647698] Subtest reads-snoop: SKIP (0.000s)
[173.731924] [033/131] (846s left) gem_eio (in-flight-contexts-immediate)
[173.970756] Starting subtest: in-flight-contexts-immediate
[187.952512] Subtest in-flight-contexts-immediate: SUCCESS (13.981s)
[188.164957] [034/131] (831s left) gen3_render_mixed_blits
[188.297966] [035/131] (831s left) kms_pm_rpm (pm-tiling)
[190.021747] Starting subtest: pm-tiling
[190.026507] Subtest pm-tiling: SKIP (0.000s)
[190.625099] [036/131] (829s left) i915_module_load (reload-no-display)
[190.659532] Starting subtest: reload-no-display
[198.105467] Subtest reload-no-display: SUCCESS (7.446s)
[198.133764] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[198.134496] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[198.137330] [037/131] (821s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[199.765924] Starting subtest: plane-upscale-20x20-with-rotation
[199.768248] Starting dynamic subtest: pipe-A
[200.010887] Dynamic subtest pipe-A: SUCCESS (0.247s)
[200.018925] Starting dynamic subtest: pipe-B
[201.331535] Dynamic subtest pipe-B: SUCCESS (1.320s)
[201.339545] Starting dynamic subtest: pipe-C
[202.666476] Dynamic subtest pipe-C: SUCCESS (1.334s)
[202.676165] Starting dynamic subtest: pipe-D
[203.999054] Dynamic subtest pipe-D: SUCCESS (1.332s)
[204.001463] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.236s)
[205.156263] [FACT kms_plane_scaling (plane-upscale-20x20-with-rotation)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[205.157571] [FACT kms_plane_scaling (plane-upscale-20x20-with-rotation)] new: kernel.kmod_is_loaded.i915: true
[205.161283] [038/131] (814s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[205.278303] Starting subtest: 4-tiled-8bpp-rotate-180
[206.433388] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.154s)
[206.613977] [039/131] (813s left) kms_rotation_crc (primary-rotation-90)
[206.708084] Starting subtest: primary-rotation-90
[206.711470] Subtest primary-rotation-90: SKIP (0.001s)
[206.805112] [040/131] (813s left) kms_cursor_crc (cursor-onscreen-32x10)
[206.970106] Starting subtest: cursor-onscreen-32x10
[207.017514] Subtest cursor-onscreen-32x10: SKIP (0.043s)
[207.321632] [041/131] (812s left) kms_vblank (wait-idle)
[207.436102] Starting subtest: wait-idle
[207.437821] Starting dynamic subtest: pipe-A-eDP-1
[209.220191] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.783s)
[209.234028] Starting dynamic subtest: pipe-D-eDP-1
[211.627325] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.403s)
[211.629520] Subtest wait-idle: SUCCESS (4.190s)
[212.463964] [042/131] (807s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[212.566684] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[212.571106] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[212.691444] [043/131] (807s left) kms_flip (2x-flip-vs-dpms)
[212.791903] Starting subtest: 2x-flip-vs-dpms
[212.802054] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[212.904921] [044/131] (807s left) gem_exec_fence (syncobj-channel)
[213.329790] Starting subtest: syncobj-channel
[214.347964] Subtest syncobj-channel: SUCCESS (1.016s)
[215.059427] [045/131] (805s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[215.160857] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[215.163195] Starting dynamic subtest: pipe-A-eDP-1
[215.172367] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[215.181292] Starting dynamic subtest: pipe-B-eDP-1
[215.190058] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[215.197853] Starting dynamic subtest: pipe-C-eDP-1
[215.206023] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[215.213262] Starting dynamic subtest: pipe-D-eDP-1
[215.221303] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[215.225110] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[217.417216] [046/131] (802s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[217.510401] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[224.905348] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.396s)
[225.053580] [047/131] (795s left) kms_flip (busy-flip)
[225.156304] Starting subtest: busy-flip
[225.158574] Starting dynamic subtest: A-eDP1
[226.864448] Dynamic subtest A-eDP1: SUCCESS (1.710s)
[226.876656] Starting dynamic subtest: B-eDP1
[228.598701] Dynamic subtest B-eDP1: SUCCESS (1.733s)
[228.608904] Starting dynamic subtest: C-eDP1
[230.228479] Dynamic subtest C-eDP1: SUCCESS (1.629s)
[230.237958] Starting dynamic subtest: D-eDP1
[231.861987] Dynamic subtest D-eDP1: SUCCESS (1.632s)
[231.864323] Subtest busy-flip: SUCCESS (6.711s)
[233.139897] [048/131] (787s left) prime_mmap (test_aperture_limit)
[233.201126] Starting subtest: test_aperture_limit
[233.203026] Starting dynamic subtest: test_aperture_limit-smem
[233.210070] Dynamic subtest test_aperture_limit-smem: SKIP (0.008s)
[233.214372] Subtest test_aperture_limit: SKIP (0.008s)
[233.342321] [049/131] (787s left) i915_pm_rpm (sysfs-read)
[235.074244] Starting subtest: sysfs-read
[236.242925] Subtest sysfs-read: SUCCESS (1.166s)
[236.647256] [050/131] (783s left) kms_big_fb (yf-tiled-addfb)
[236.756716] Starting subtest: yf-tiled-addfb
[236.761309] Subtest yf-tiled-addfb: SKIP (0.000s)
[236.886332] [051/131] (783s left) sriov_basic (enable-vfs-autoprobe-on)
[236.939953] Starting subtest: enable-vfs-autoprobe-on
[236.942361] Starting dynamic subtest: numvfs-1
[237.022578] Dynamic subtest numvfs-1: FAIL (0.034s)
[237.030607] Starting dynamic subtest: numvfs-2
[237.103663] Dynamic subtest numvfs-2: FAIL (0.004s)
[237.113574] Starting dynamic subtest: numvfs-3
[237.186627] Dynamic subtest numvfs-3: FAIL (0.004s)
[237.195767] Starting dynamic subtest: numvfs-4
[237.269296] Dynamic subtest numvfs-4: FAIL (0.004s)
[237.279277] Starting dynamic subtest: numvfs-5
[237.352215] Dynamic subtest numvfs-5: FAIL (0.004s)
[237.361793] Starting dynamic subtest: numvfs-6
[237.435282] Dynamic subtest numvfs-6: FAIL (0.004s)
[237.445204] Starting dynamic subtest: numvfs-7
[237.518643] Dynamic subtest numvfs-7: FAIL (0.004s)
[237.526774] Starting dynamic subtest: numvfs-random
[237.589843] Dynamic subtest numvfs-random: FAIL (0.004s)
[237.598915] Starting dynamic subtest: numvfs-all
[237.658448] Dynamic subtest numvfs-all: FAIL (0.004s)
[237.660183] Subtest enable-vfs-autoprobe-on: FAIL (0.071s)
[237.696720] [052/131] (782s left) gem_exec_whisper (basic-queues)
[237.772219] Starting subtest: basic-queues
[243.955397] Subtest basic-queues: SUCCESS (6.181s)
[244.161268] [053/131] (776s left) kms_flip (2x-dpms-vs-vblank-race)
[244.256548] Starting subtest: 2x-dpms-vs-vblank-race
[244.268145] Subtest 2x-dpms-vs-vblank-race: SKIP (0.011s)
[244.370945] [054/131] (776s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[244.461743] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[244.834311] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[247.574698] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.740s)
[247.586073] Starting dynamic subtest: pipe-A-eDP-1-4
[249.760212] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.185s)
[249.771618] Starting dynamic subtest: pipe-A-eDP-1-4
[251.770541] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[251.781992] Starting dynamic subtest: pipe-A-eDP-1-x
[253.982550] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.211s)
[254.356495] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[257.100362] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.749s)
[257.112223] Starting dynamic subtest: pipe-B-eDP-1-4
[259.286840] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.186s)
[259.298216] Starting dynamic subtest: pipe-B-eDP-1-4
[261.296750] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[261.308628] Starting dynamic subtest: pipe-B-eDP-1-x
[263.487093] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.189s)
[263.854111] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[266.628042] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.781s)
[266.640686] Starting dynamic subtest: pipe-C-eDP-1-4
[268.869531] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.241s)
[268.880774] Starting dynamic subtest: pipe-C-eDP-1-4
[270.879321] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[270.890532] Starting dynamic subtest: pipe-C-eDP-1-x
[273.058514] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.178s)
[273.423495] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[276.160518] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.742s)
[276.171967] Starting dynamic subtest: pipe-D-eDP-1-4
[278.334980] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s)
[278.349455] Starting dynamic subtest: pipe-D-eDP-1-4
[280.345585] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.008s)
[280.356351] Starting dynamic subtest: pipe-D-eDP-1-x
[282.524790] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[282.527652] Subtest async-flip-with-page-flip-events-tiled-atomic: SUCCESS (38.062s)
[283.771060] [055/131] (736s left) gem_mmap_gtt (basic-write-read-distinct)
[283.840806] Subtest basic-write-read-distinct: SKIP (0.000s)
[283.910097] [056/131] (736s left) gem_mmap_offset (oob-read)
[283.973057] Starting subtest: oob-read
[283.975336] Subtest oob-read: SUCCESS (0.000s)
[284.124231] [057/131] (736s left) kms_atomic (atomic-plane-damage)
[284.220935] Starting subtest: atomic-plane-damage
[284.270521] Starting dynamic subtest: pipe-A-eDP-1
[284.787783] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.517s)
[284.789752] Subtest atomic-plane-damage: SUCCESS (0.568s)
[285.605913] [058/131] (735s left) kms_atomic_transition (plane-all-transition-nonblocking)
[285.705796] Starting subtest: plane-all-transition-nonblocking
[285.708058] Starting dynamic subtest: pipe-A-eDP-1
[288.200305] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[288.507375] Starting dynamic subtest: pipe-B-eDP-1
[291.715879] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.218s)
[292.032111] Subtest plane-all-transition-nonblocking: SUCCESS (6.327s)
[292.876926] [059/131] (727s left) gem_exec_parallel (contexts)
[292.931471] Starting subtest: contexts
[292.933906] Starting dynamic subtest: rcs0
[295.524383] Dynamic subtest rcs0: SUCCESS (2.591s)
[295.534542] Starting dynamic subtest: bcs0
[299.064422] Dynamic subtest bcs0: SUCCESS (3.537s)
[299.076259] Starting dynamic subtest: vcs0
[303.295969] Dynamic subtest vcs0: SUCCESS (4.231s)
[303.306280] Starting dynamic subtest: vcs1
[307.236782] Dynamic subtest vcs1: SUCCESS (3.939s)
[307.246301] Starting dynamic subtest: vecs0
[311.280422] Dynamic subtest vecs0: SUCCESS (4.040s)
[311.289609] Starting dynamic subtest: ccs0
[314.764086] Dynamic subtest ccs0: SUCCESS (3.483s)
[314.766484] Subtest contexts: SUCCESS (21.831s)
[314.982297] [060/131] (705s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[315.082272] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[321.860222] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.779s)
[322.033300] [061/131] (698s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[322.135917] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[322.140129] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[322.280311] [062/131] (698s left) kms_atomic_interruptible (legacy-pageflip)
[322.375078] Starting subtest: legacy-pageflip
[322.377345] Starting dynamic subtest: pipe-A-eDP-1
[329.816248] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.437s)
[329.818503] Subtest legacy-pageflip: SUCCESS (7.439s)
[330.648373] [063/131] (690s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[330.750946] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[330.755377] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[330.878902] [064/131] (690s left) kms_psr (psr2-sprite-mmap-cpu)
[330.978175] Starting subtest: psr2-sprite-mmap-cpu
[330.980478] Starting dynamic subtest: eDP-1
[332.719200] Dynamic subtest eDP-1: SUCCESS (1.739s)
[332.721340] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.740s)
[333.597614] [065/131] (687s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[333.691182] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[340.820297] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.131s)
[341.005079] [066/131] (680s left) gem_exec_schedule (fairslice-all)
[341.084955] Starting subtest: fairslice-all
[343.143148] Subtest fairslice-all: SUCCESS (2.061s)
[345.037741] [067/131] (676s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf)
[350.064270] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf
[350.066541] Starting dynamic subtest: pipe-A-eDP-1
[350.862944] Dynamic subtest pipe-A-eDP-1: SKIP (0.795s)
[350.878199] Starting dynamic subtest: pipe-B-eDP-1
[351.989077] Dynamic subtest pipe-B-eDP-1: SKIP (1.122s)
[351.993629] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.921s)
[353.224808] [068/131] (667s left) api_intel_allocator (two-level-inception)
[353.285385] Starting subtest: two-level-inception
[358.682720] Subtest two-level-inception: SUCCESS (5.398s)
[358.847461] [069/131] (662s left) i915_suspend (debugfs-reader)
[358.908642] Starting subtest: debugfs-reader
[377.111806] Subtest debugfs-reader: SUCCESS (7.861s)
[377.195040] [070/131] (644s left) kms_flip (plain-flip-fb-recreate-interruptible)
[377.314967] Starting subtest: plain-flip-fb-recreate-interruptible
[377.317703] Starting dynamic subtest: A-eDP1
[386.214114] Dynamic subtest A-eDP1: SUCCESS (8.854s)
[386.228470] Starting dynamic subtest: B-eDP1
[395.071534] Dynamic subtest B-eDP1: SUCCESS (8.839s)
[395.082328] Starting dynamic subtest: C-eDP1
[403.906158] Dynamic subtest C-eDP1: SUCCESS (8.826s)
[403.916602] Starting dynamic subtest: D-eDP1
[412.742221] Dynamic subtest D-eDP1: SUCCESS (8.828s)
[412.744313] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.356s)
[413.953127] [071/131] (607s left) gem_vm_create (invalid-destroy)
[414.022757] Starting subtest: invalid-destroy
[414.025627] Subtest invalid-destroy: SUCCESS (0.002s)
[414.179332] [072/131] (607s left) gem_ccs (ctrl-surf-copy-new-ctx)
[414.247603] Starting subtest: ctrl-surf-copy-new-ctx
[414.252980] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[414.383399] [073/131] (607s left) syncobj_basic (bad-destroy-pad)
[414.448331] Starting subtest: bad-destroy-pad
[414.451086] Subtest bad-destroy-pad: SUCCESS (0.000s)
[414.581691] [074/131] (606s left) kms_plane_multiple (2x-tiling-y)
[414.683559] Starting subtest: 2x-tiling-y
[414.688455] Subtest 2x-tiling-y: SKIP (0.000s)
[414.780336] [075/131] (606s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[414.884172] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[414.886600] Starting dynamic subtest: pipe-A-eDP-1
[414.912238] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[414.934786] Starting dynamic subtest: pipe-B-eDP-1
[414.958598] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[414.970564] Starting dynamic subtest: pipe-C-eDP-1
[414.994208] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[415.004449] Starting dynamic subtest: pipe-D-eDP-1
[415.027968] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[415.032514] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[417.199042] [076/131] (604s left) kms_pm_rpm (modeset-lpsp)
[419.442117] Starting subtest: modeset-lpsp
[420.907511] Subtest modeset-lpsp: SUCCESS (1.469s)
[421.034761] [077/131] (600s left) kms_setmode (basic)
[421.095353] Starting subtest: basic
[421.420758] Starting dynamic subtest: pipe-A-eDP-1
[423.490110] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.064s)
[423.831894] Starting dynamic subtest: pipe-B-eDP-1
[425.926790] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.098s)
[426.236441] Subtest basic: SUCCESS (5.137s)
[427.095719] [078/131] (594s left) kms_psr (fbc-psr-suspend)
[427.198486] Starting subtest: fbc-psr-suspend
[427.201059] Starting dynamic subtest: eDP-1
[427.205684] Dynamic subtest eDP-1: SKIP (0.000s)
[427.210807] Subtest fbc-psr-suspend: SKIP (0.001s)
[427.300268] [079/131] (594s left) gem_ringfill (engines-basic)
[433.274716] Starting subtest: engines-basic
[433.277594] Starting dynamic subtest: rcs0
[433.359943] Dynamic subtest rcs0: SUCCESS (0.085s)
[433.373062] Starting dynamic subtest: bcs0
[433.406417] Dynamic subtest bcs0: SUCCESS (0.044s)
[433.418540] Starting dynamic subtest: vcs0
[433.450565] Dynamic subtest vcs0: SUCCESS (0.043s)
[433.463053] Starting dynamic subtest: vcs1
[433.493516] Dynamic subtest vcs1: SUCCESS (0.042s)
[433.504233] Starting dynamic subtest: vecs0
[433.537271] Dynamic subtest vecs0: SUCCESS (0.042s)
[433.548974] Starting dynamic subtest: ccs0
[433.583160] Dynamic subtest ccs0: SUCCESS (0.044s)
[433.585942] Subtest engines-basic: SUCCESS (0.310s)
[433.687908] [080/131] (588s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[433.762469] Starting subtest: invalid-bsd2-flag-on-vebox
[433.764960] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[433.903174] [081/131] (587s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[434.000186] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[434.002531] Starting dynamic subtest: pipe-A-eDP-1
[434.024852] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.021s)
[434.035207] Starting dynamic subtest: pipe-B-eDP-1
[434.056789] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.020s)
[434.067077] Starting dynamic subtest: pipe-C-eDP-1
[434.090536] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.020s)
[434.100814] Starting dynamic subtest: pipe-D-eDP-1
[434.122586] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.020s)
[434.124782] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.086s)
[436.358341] [082/131] (585s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[436.457405] Starting subtest: extended-pageflip-modeset-hang-oldfb
[436.460033] Starting dynamic subtest: pipe-A
[444.357645] Dynamic subtest pipe-A: SUCCESS (7.894s)
[444.373098] Starting dynamic subtest: pipe-D
[452.941431] Dynamic subtest pipe-D: SUCCESS (8.577s)
[452.943806] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.477s)
[453.821140] [083/131] (568s left) gem_ppgtt (shrink-vs-evict-any)
[453.884458] Starting subtest: shrink-vs-evict-any
[459.228598] Subtest shrink-vs-evict-any: SUCCESS (5.340s)
[459.357303] [084/131] (562s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[459.423446] Starting subtest: etime-single-wait-for-submit-unsubmitted
[459.522329] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.101s)
[459.637955] [085/131] (562s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[459.702538] Starting subtest: multi-wait-all-for-submit-signaled
[459.704918] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[459.842429] [086/131] (562s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[459.904264] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[460.012040] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.108s)
[460.123122] [087/131] (561s left) gem_exec_schedule (implicit-write-read)
[460.203230] Starting subtest: implicit-write-read
[460.205543] Starting dynamic subtest: rcs0
[460.385830] Dynamic subtest rcs0: SUCCESS (0.196s)
[460.399935] Starting dynamic subtest: vcs0
[460.527414] Dynamic subtest vcs0: SUCCESS (0.141s)
[460.541580] Starting dynamic subtest: vcs1
[460.642674] Dynamic subtest vcs1: SUCCESS (0.115s)
[460.653443] Starting dynamic subtest: vecs0
[460.765218] Dynamic subtest vecs0: SUCCESS (0.122s)
[460.781423] Starting dynamic subtest: ccs0
[460.896604] Dynamic subtest ccs0: SUCCESS (0.131s)
[460.899444] Subtest implicit-write-read: SUCCESS (0.708s)
[462.340321] [088/131] (559s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[462.444288] Starting subtest: fbcpsr-rgb565-draw-blt
[469.510079] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (7.064s)
[469.673796] [089/131] (552s left) gem_ctx_persistence (processes)
[469.735458] Starting subtest: processes
[471.246240] Subtest processes: SUCCESS (1.509s)
[471.350459] [090/131] (550s left) kms_cursor_crc (cursor-sliding-32x10)
[471.585136] Starting subtest: cursor-sliding-32x10
[471.638616] Subtest cursor-sliding-32x10: SKIP (0.051s)
[471.994320] [091/131] (550s left) kms_psr (fbc-pr-primary-render)
[472.090795] Starting subtest: fbc-pr-primary-render
[472.095798] Subtest fbc-pr-primary-render: SKIP (0.000s)
[472.191031] [092/131] (550s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[472.258527] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[472.342969] [093/131] (549s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[472.390172] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[472.392202] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[472.502038] [094/131] (549s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[472.597882] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[472.602542] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[472.719120] [095/131] (549s left) gem_exec_async (concurrent-writes)
[472.790040] Starting subtest: concurrent-writes
[472.792448] Starting dynamic subtest: rcs0
[472.806775] Dynamic subtest rcs0: SUCCESS (0.016s)
[472.819114] Starting dynamic subtest: bcs0
[472.821457] Dynamic subtest bcs0: SUCCESS (0.005s)
[472.831573] Starting dynamic subtest: vcs0
[472.833785] Dynamic subtest vcs0: SUCCESS (0.006s)
[472.844814] Starting dynamic subtest: vcs1
[472.847077] Dynamic subtest vcs1: SUCCESS (0.006s)
[472.858796] Starting dynamic subtest: vecs0
[472.860934] Dynamic subtest vecs0: SUCCESS (0.006s)
[472.870801] Starting dynamic subtest: ccs0
[472.872790] Dynamic subtest ccs0: SUCCESS (0.006s)
[472.874616] Subtest concurrent-writes: SUCCESS (0.048s)
[473.093180] [096/131] (549s left) gem_mmap_gtt (basic-small-bo-tiledx)
[473.146389] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[473.216913] [097/131] (549s left) dmabuf_sync_file (import-basic)
[473.260625] Starting subtest: import-basic
[473.263003] Subtest import-basic: SUCCESS (0.001s)
[473.340563] [098/131] (549s left) kms_feature_discovery (display)
[473.442912] Starting subtest: display
[473.444816] Subtest display: SUCCESS (0.000s)
[473.545036] [099/131] (549s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[473.645559] Starting subtest: x-tiled-64bpp-rotate-90
[473.665611] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[473.811887] [100/131] (548s left) perf_pmu (busy-no-semaphores)
[473.846808] Starting subtest: busy-no-semaphores
[473.849102] Starting dynamic subtest: rcs0
[474.430147] Dynamic subtest rcs0: SUCCESS (0.582s)
[474.444439] Starting dynamic subtest: bcs0
[474.981794] Dynamic subtest bcs0: SUCCESS (0.550s)
[474.995572] Starting dynamic subtest: vcs0
[475.532896] Dynamic subtest vcs0: SUCCESS (0.548s)
[475.547636] Starting dynamic subtest: vcs1
[476.792573] Dynamic subtest vcs1: SUCCESS (1.257s)
[476.805610] Starting dynamic subtest: vecs0
[478.779738] Dynamic subtest vecs0: SUCCESS (1.987s)
[478.796670] Starting dynamic subtest: ccs0
[480.764395] Dynamic subtest ccs0: SUCCESS (1.984s)
[480.767413] Subtest busy-no-semaphores: SUCCESS (6.924s)
[480.910484] [101/131] (541s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt)
[481.015787] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt
[481.020785] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[481.136477] [102/131] (541s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[481.256549] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[481.261427] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[481.429482] [103/131] (541s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[481.818711] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[482.632503] [104/131] (540s left) kms_getfb (getfb-reject-nv12)
[482.741103] Starting subtest: getfb-reject-nv12
[482.743602] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[482.876283] [105/131] (539s left) i915_drm_fdinfo (context-close-stress)
[482.925196] Starting subtest: context-close-stress
[493.666819] Subtest context-close-stress: SUCCESS (10.745s)
[493.773015] [106/131] (529s left) perf_pmu (module-unload)
[494.007770] Starting subtest: module-unload
[500.762780] Subtest module-unload: FAIL (6.627s)
[500.795364] [FACT perf_pmu (module-unload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[500.796104] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true
[500.799498] [107/131] (522s left) kms_chamelium_edid (dp-edid-read)
[503.932350] Subtest dp-edid-read: SKIP (0.000s)
[504.751582] [FACT kms_chamelium_edid (dp-edid-read)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[504.752991] [FACT kms_chamelium_edid (dp-edid-read)] new: kernel.kmod_is_loaded.i915: true
[504.757002] [108/131] (518s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[509.743757] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[509.746484] Starting dynamic subtest: pipe-A-eDP-1
[510.539533] Dynamic subtest pipe-A-eDP-1: SKIP (0.791s)
[510.550832] Starting dynamic subtest: pipe-B-eDP-1
[511.673584] Dynamic subtest pipe-B-eDP-1: SKIP (1.132s)
[511.678415] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.926s)
[512.889539] [109/131] (510s left) kms_vblank (query-busy)
[512.987444] Starting subtest: query-busy
[512.989905] Starting dynamic subtest: pipe-A-eDP-1
[514.865753] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.877s)
[514.880625] Starting dynamic subtest: pipe-D-eDP-1
[517.404251] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.538s)
[517.406805] Subtest query-busy: SUCCESS (4.419s)
[518.236141] [110/131] (504s left) syncobj_timeline (wait-for-submit-complex)
[518.295678] Starting subtest: wait-for-submit-complex
[518.356505] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[518.460991] [111/131] (504s left) perf (invalid-create-userspace-config)
[518.602827] Starting subtest: invalid-create-userspace-config
[518.605515] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[518.642601] [112/131] (504s left) gem_exec_basic (basic)
[518.707848] Starting subtest: basic
[518.710663] Starting dynamic subtest: rcs0-smem
[518.713494] Dynamic subtest rcs0-smem: SUCCESS (0.004s)
[518.723529] Starting dynamic subtest: bcs0-smem
[518.726156] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[518.734805] Starting dynamic subtest: vcs0-smem
[518.737518] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[518.746676] Starting dynamic subtest: vcs1-smem
[518.749498] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[518.758480] Starting dynamic subtest: vecs0-smem
[518.761331] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[518.770284] Starting dynamic subtest: ccs0-smem
[518.772790] Dynamic subtest ccs0-smem: SUCCESS (0.003s)
[518.775576] Subtest basic: SUCCESS (0.017s)
[520.326649] [113/131] (502s left) kms_chamelium_color (ctm-0-25)
[520.428668] Subtest ctm-0-25: SKIP (0.000s)
[520.484846] [114/131] (502s left) gem_userptr_blits (process-exit-busy)
[520.561782] Starting subtest: process-exit-busy
[520.570233] Subtest process-exit-busy: SUCCESS (0.012s)
[520.759487] [115/131] (502s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[520.824770] Starting subtest: y-tiled-ccs-to-y-tiled
[520.829819] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[521.040618] [116/131] (502s left) gen7_exec_parse (bitmasks)
[521.105806] Subtest bitmasks: SKIP (0.000s)
[521.190806] [117/131] (502s left) gem_eio (wait-wedge-10ms)
[521.379413] Starting subtest: wait-wedge-10ms
[523.319166] Subtest wait-wedge-10ms: SUCCESS (1.937s)
[523.461804] [118/131] (500s left) kms_content_protection (atomic)
[523.612945] Starting subtest: atomic
[523.618585] Subtest atomic: SKIP (0.000s)
[523.779312] [119/131] (499s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[523.876093] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[523.878432] Starting dynamic subtest: pipe-A-default-mode
[524.172616] Dynamic subtest pipe-A-default-mode: SKIP (0.294s)
[524.178317] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.295s)
[525.072497] [120/131] (498s left) gem_exec_params (invalid-flag)
[525.135593] Starting subtest: invalid-flag
[525.138055] Subtest invalid-flag: SUCCESS (0.000s)
[525.274860] [121/131] (498s left) kms_getfb (getfb-addfb-different-handles)
[525.375038] Starting subtest: getfb-addfb-different-handles
[525.377654] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[525.499256] [122/131] (498s left) gem_exec_flush (basic-uc-ro-default)
[525.565778] Starting subtest: basic-uc-ro-default
[531.032307] Subtest basic-uc-ro-default: SUCCESS (5.468s)
[531.244078] [123/131] (492s left) gem_sync (basic-each)
[531.319116] Starting subtest: basic-each
[533.747520] Subtest basic-each: SUCCESS (2.438s)
[533.958290] [124/131] (489s left) gem_exec_balancer (full)
[534.026041] Starting subtest: full
[539.110800] Subtest full: SUCCESS (5.084s)
[539.495747] [125/131] (484s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[539.605330] Starting subtest: y-tiled-8bpp-rotate-180
[539.610195] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s)
[539.749241] [126/131] (484s left) sw_sync (sync_merge_invalid)
[539.786202] Starting subtest: sync_merge_invalid
[539.802023] Subtest sync_merge_invalid: SUCCESS (0.018s)
[539.894016] [127/131] (483s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[540.002450] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[544.411171] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.414s)
[545.589396] [128/131] (478s left) gem_lmem_swapping (parallel-random-verify-ccs)
[545.631314] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[545.678129] [129/131] (478s left) kms_lease (lease-unleased-connector)
[545.776606] Starting subtest: lease-unleased-connector
[545.779020] Starting dynamic subtest: pipe-A-eDP-1
[545.784187] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[545.794918] Starting dynamic subtest: pipe-B-eDP-1
[545.797230] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[545.807348] Starting dynamic subtest: pipe-C-eDP-1
[545.809666] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s)
[545.819646] Starting dynamic subtest: pipe-D-eDP-1
[545.822642] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[545.825613] Subtest lease-unleased-connector: SUCCESS (0.040s)
[545.953862] [130/131] (478s left) perf (enable-disable)
[546.061653] Starting subtest: enable-disable
[546.064391] Starting dynamic subtest: 0-rcs0
[548.329038] Dynamic subtest 0-rcs0: SUCCESS (2.265s)
[548.340846] Starting dynamic subtest: 1-vecs0
[552.806548] Dynamic subtest 1-vecs0: SUCCESS (4.476s)
[552.809063] Subtest enable-disable: SUCCESS (6.743s)
[552.855675] [131/131] (471s left) kms_cursor_crc (cursor-rapid-movement-32x32)
[553.026543] Starting subtest: cursor-rapid-movement-32x32
[553.069544] Subtest cursor-rapid-movement-32x32: SKIP (0.043s)
[553.404254] Closing watchdogs
results: parsing output: 0/ for test: fbdev
results: parsing output: 1/ for test: gem_ctx_isolation
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_flip_scaled_crc
results: parsing output: 4/ for test: gem_exec_params
results: parsing output: 5/ for test: gem_spin_batch
results: parsing output: 6/ for test: gem_exec_schedule
results: parsing output: 7/ for test: sysfs_heartbeat_interval
results: parsing output: 8/ for test: kms_invalid_mode
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: kms_getfb
results: parsing output: 12/ for test: gem_render_copy
results: parsing output: 13/ for test: kms_rotation_crc
results: parsing output: 14/ for test: gem_eio
results: parsing output: 15/ for test: syncobj_wait
results: parsing output: 16/ for test: kms_flip_scaled_crc
results: parsing output: 17/ for test: kms_plane_alpha_blend
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: kms_flip
results: parsing output: 21/ for test: gem_exec_whisper
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: vgem_basic
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: core_auth
results: parsing output: 29/ for test: kms_plane_scaling
results: parsing output: 30/ for test: kms_dither
results: parsing output: 31/ for test: gem_partial_pwrite_pread
results: parsing output: 32/ for test: gem_eio
results: parsing output: 33/ for test: gen3_render_mixed_blits
results: parsing output: 34/ for test: kms_pm_rpm
results: parsing output: 35/ for test: i915_module_load
results: parsing output: 36/ for test: kms_plane_scaling
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: kms_rotation_crc
results: parsing output: 39/ for test: kms_cursor_crc
results: parsing output: 40/ for test: kms_vblank
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_flip
results: parsing output: 43/ for test: gem_exec_fence
results: parsing output: 44/ for test: kms_ccs
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_flip
results: parsing output: 47/ for test: prime_mmap
results: parsing output: 48/ for test: i915_pm_rpm
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: sriov_basic
results: parsing output: 51/ for test: gem_exec_whisper
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: kms_async_flips
results: parsing output: 54/ for test: gem_mmap_gtt
results: parsing output: 55/ for test: gem_mmap_offset
results: parsing output: 56/ for test: kms_atomic
results: parsing output: 57/ for test: kms_atomic_transition
results: parsing output: 58/ for test: gem_exec_parallel
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_atomic_interruptible
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: gem_exec_schedule
results: parsing output: 66/ for test: kms_psr2_sf
results: parsing output: 67/ for test: api_intel_allocator
results: parsing output: 68/ for test: i915_suspend
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: gem_vm_create
results: parsing output: 71/ for test: gem_ccs
results: parsing output: 72/ for test: syncobj_basic
results: parsing output: 73/ for test: kms_plane_multiple
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: kms_pm_rpm
results: parsing output: 76/ for test: kms_setmode
results: parsing output: 77/ for test: kms_psr
results: parsing output: 78/ for test: gem_ringfill
results: parsing output: 79/ for test: gem_exec_params
results: parsing output: 80/ for test: kms_ccs
results: parsing output: 81/ for test: kms_busy
results: parsing output: 82/ for test: gem_ppgtt
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: syncobj_timeline
results: parsing output: 85/ for test: syncobj_timeline
results: parsing output: 86/ for test: gem_exec_schedule
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_ctx_persistence
results: parsing output: 89/ for test: kms_cursor_crc
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: gem_exec_reloc
results: parsing output: 92/ for test: syncobj_wait
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: gem_exec_async
results: parsing output: 95/ for test: gem_mmap_gtt
results: parsing output: 96/ for test: dmabuf_sync_file
results: parsing output: 97/ for test: kms_feature_discovery
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: perf_pmu
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_cursor_legacy
results: parsing output: 102/ for test: kms_chamelium_hpd
results: parsing output: 103/ for test: kms_getfb
results: parsing output: 104/ for test: i915_drm_fdinfo
results: parsing output: 105/ for test: perf_pmu
results: parsing output: 106/ for test: kms_chamelium_edid
results: parsing output: 107/ for test: kms_psr2_sf
results: parsing output: 108/ for test: kms_vblank
results: parsing output: 109/ for test: syncobj_timeline
results: parsing output: 110/ for test: perf
results: parsing output: 111/ for test: gem_exec_basic
results: parsing output: 112/ for test: kms_chamelium_color
results: parsing output: 113/ for test: gem_userptr_blits
results: parsing output: 114/ for test: gem_render_copy
results: parsing output: 115/ for test: gen7_exec_parse
results: parsing output: 116/ for test: gem_eio
results: parsing output: 117/ for test: kms_content_protection
results: parsing output: 118/ for test: kms_flip_scaled_crc
results: parsing output: 119/ for test: gem_exec_params
results: parsing output: 120/ for test: kms_getfb
results: parsing output: 121/ for test: gem_exec_flush
results: parsing output: 122/ for test: gem_sync
results: parsing output: 123/ for test: gem_exec_balancer
results: parsing output: 124/ for test: kms_big_fb
results: parsing output: 125/ for test: sw_sync
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: gem_lmem_swapping
results: parsing output: 128/ for test: kms_lease
results: parsing output: 129/ for test: perf
results: parsing output: 130/ for test: kms_cursor_crc
Done.