[57.035445] Initializing watchdogs
[57.035494]   /dev/watchdog0
[57.050395] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[57.054518] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.056529] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.059987] [001/133] (960s left) kms_big_fb (linear-8bpp-rotate-90)
[57.176986] Starting subtest: linear-8bpp-rotate-90
[57.213209] Subtest linear-8bpp-rotate-90: SKIP (0.036s)
[57.320467] [002/133] (960s left) gem_ctx_param (non-root-set)
[57.374688] Starting subtest: non-root-set
[57.395943] Subtest non-root-set: SUCCESS (0.021s)
[57.477579] [003/133] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[57.563982] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[57.568346] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[57.674153] [004/133] (959s left) gem_pxp (create-protected-buffer)
[57.807637] Starting subtest: create-protected-buffer
[57.810044] Subtest create-protected-buffer: SUCCESS (0.001s)
[57.903856] [005/133] (959s left) kms_invalid_mode (bad-htotal)
[57.976777] Starting subtest: bad-htotal
[57.978864] Starting dynamic subtest: pipe-A-eDP-1
[58.274914] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.298s)
[58.283613] Starting dynamic subtest: pipe-B-eDP-1
[58.285841] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[58.293037] Starting dynamic subtest: pipe-C-eDP-1
[58.295409] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[58.304779] Starting dynamic subtest: pipe-D-eDP-1
[58.306755] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[58.308724] Subtest bad-htotal: SUCCESS (0.302s)
[59.109241] [006/133] (958s left) kms_vblank (invalid)
[59.171831] Starting subtest: invalid
[59.173719] Starting dynamic subtest: pipe-A-eDP-1
[59.576494] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.404s)
[59.578815] Subtest invalid: SUCCESS (0.405s)
[60.434876] [007/133] (957s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[60.530182] Starting subtest: fbc-2p-pri-indfb-multidraw
[60.534233] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[60.618506] [008/133] (957s left) gem_softpin (overlap)
[60.678281] Starting subtest: overlap
[61.550144] Subtest overlap: SUCCESS (0.871s)
[61.654822] [009/133] (956s left) kms_psr (fbc-psr-basic)
[61.729466] Starting subtest: fbc-psr-basic
[61.731832] Starting dynamic subtest: eDP-1
[61.736251] Dynamic subtest eDP-1: SKIP (0.000s)
[61.740951] Subtest fbc-psr-basic: SKIP (0.000s)
[61.809482] [010/133] (956s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[61.877634] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[61.879770] Starting dynamic subtest: pipe-A-eDP-1
[62.347165] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.440s)
[62.357507] Starting dynamic subtest: pipe-B-eDP-1
[63.729491] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.382s)
[63.739123] Starting dynamic subtest: pipe-C-eDP-1
[65.147433] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.420s)
[65.157557] Starting dynamic subtest: pipe-D-eDP-1
[66.465287] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.317s)
[66.467532] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.562s)
[67.671532] [011/133] (950s left) gem_vm_create (invalid-destroy)
[67.761099] Starting subtest: invalid-destroy
[67.763980] Subtest invalid-destroy: SUCCESS (0.002s)
[67.872027] [012/133] (950s left) i915_pm_rpm (debugfs-forcewake-user)
[70.020054] Starting subtest: debugfs-forcewake-user
[81.411571] Subtest debugfs-forcewake-user: SUCCESS (11.397s)
[81.800685] [013/133] (936s left) core_setmaster_vs_auth
[81.980151] [014/133] (936s left) gem_softpin (noreloc)
[82.046698] Starting subtest: noreloc
[87.444764] Subtest noreloc: SUCCESS (5.397s)
[87.576141] [015/133] (930s left) kms_flip (flip-vs-expired-vblank-interruptible)
[87.662892] Starting subtest: flip-vs-expired-vblank-interruptible
[87.665175] Starting dynamic subtest: A-eDP1
[96.310120] Dynamic subtest A-eDP1: SUCCESS (8.645s)
[96.319541] Starting dynamic subtest: B-eDP1
[104.949085] Dynamic subtest B-eDP1: SUCCESS (8.640s)
[104.959064] Starting dynamic subtest: C-eDP1
[113.559392] Dynamic subtest C-eDP1: SUCCESS (8.608s)
[113.566953] Starting dynamic subtest: D-eDP1
[122.194713] Dynamic subtest D-eDP1: SUCCESS (8.633s)
[122.199505] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.534s)
[123.359801] [016/133] (894s left) gem_mmap_gtt (isolation)
[123.417364] Subtest isolation: SKIP (0.000s)
[123.476431] [017/133] (894s left) kms_psr2_sf (pr-cursor-plane-move-continuous-sf)
[123.553068] Starting subtest: pr-cursor-plane-move-continuous-sf
[123.557398] Subtest pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[123.616618] [018/133] (894s left) gem_exec_params (larger-than-life-batch)
[123.676594] Starting subtest: larger-than-life-batch
[125.941583] Subtest larger-than-life-batch: SUCCESS (2.265s)
[126.919863] [019/133] (891s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[127.050391] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[127.054841] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[127.144091] [020/133] (891s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[127.503961] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[128.276070] [021/133] (890s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[128.351936] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[128.355981] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[128.422964] [022/133] (889s left) kms_dsc (dsc-basic)
[128.484845] Starting subtest: dsc-basic
[128.499876] Subtest dsc-basic: SKIP (0.000s)
[128.558421] [023/133] (889s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[128.616517] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[128.725518] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.106s)
[128.796851] [024/133] (889s left) kms_atomic_transition (plane-all-transition-nonblocking)
[128.876057] Starting subtest: plane-all-transition-nonblocking
[128.878819] Starting dynamic subtest: pipe-A-eDP-1
[131.376537] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.499s)
[131.683606] Starting dynamic subtest: pipe-B-eDP-1
[134.920735] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.248s)
[135.218511] Subtest plane-all-transition-nonblocking: SUCCESS (6.343s)
[136.043210] [025/133] (882s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[136.178054] Starting subtest: dp-mst-lic-type-0-hdcp14
[136.182870] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[136.291242] [026/133] (882s left) kms_color (ctm-max)
[136.371625] Starting subtest: ctm-max
[136.373579] Starting dynamic subtest: pipe-A-eDP-1
[137.087370] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.714s)
[137.096412] Starting dynamic subtest: pipe-B-eDP-1
[138.471455] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.384s)
[138.483405] Starting dynamic subtest: pipe-C-eDP-1
[139.838475] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.368s)
[139.847244] Starting dynamic subtest: pipe-D-eDP-1
[141.171101] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.332s)
[141.173462] Subtest ctm-max: SUCCESS (4.800s)
[142.020034] [027/133] (876s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[142.097335] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[142.101439] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[142.221880] [028/133] (876s left) kms_async_flips (crc)
[142.293546] Starting subtest: crc
[142.670446] Starting dynamic subtest: pipe-A-eDP-1
[145.569516] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.898s)
[145.954228] Starting dynamic subtest: pipe-B-eDP-1
[148.826823] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.878s)
[149.199720] Starting dynamic subtest: pipe-C-eDP-1
[152.140891] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.950s)
[152.519633] Starting dynamic subtest: pipe-D-eDP-1
[155.361022] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.849s)
[155.363284] Subtest crc: SUCCESS (13.068s)
[156.546975] [029/133] (862s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[156.616926] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[156.619523] Starting dynamic subtest: pipe-A-eDP-1
[156.628589] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[156.638321] Starting dynamic subtest: pipe-B-eDP-1
[156.647302] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[156.655055] Starting dynamic subtest: pipe-C-eDP-1
[156.663542] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[156.670889] Starting dynamic subtest: pipe-D-eDP-1
[156.679410] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[156.683500] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[158.903265] [030/133] (859s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[158.984681] Starting subtest: fbc-1p-pri-indfb-multidraw
[171.910797] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (12.932s)
[173.355054] [031/133] (845s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[173.445403] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[173.449538] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[173.514420] [032/133] (845s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[173.594186] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[173.598482] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[173.679547] [033/133] (845s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[173.757807] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[173.768584] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[173.845926] [034/133] (845s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[173.909703] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[173.914172] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.000s)
[173.977997] [035/133] (844s left) kms_dp_link_training (uhbr-mst)
[174.065627] Starting subtest: uhbr-mst
[174.073591] Subtest uhbr-mst: SKIP (0.000s)
[174.134522] [036/133] (844s left) kms_plane_multiple (2x-tiling-x)
[174.212673] Starting subtest: 2x-tiling-x
[174.217087] Subtest 2x-tiling-x: SKIP (0.000s)
[174.282925] [037/133] (844s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[174.365084] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[174.368649] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[174.474652] [038/133] (844s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[174.559341] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[174.563121] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[174.659544] [039/133] (844s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[174.746919] Starting subtest: 4-tiled-32bpp-rotate-0
[176.182270] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.436s)
[176.300014] [040/133] (842s left) gem_ctx_persistence (engines-mixed-process)
[176.371060] Starting subtest: engines-mixed-process
[176.373083] Starting dynamic subtest: rcs0
[177.809248] Dynamic subtest rcs0: SUCCESS (1.437s)
[177.819961] Starting dynamic subtest: bcs0
[179.784691] Dynamic subtest bcs0: SUCCESS (1.975s)
[179.793794] Starting dynamic subtest: vcs0
[181.861382] Dynamic subtest vcs0: SUCCESS (2.076s)
[181.872361] Starting dynamic subtest: vcs1
[183.828895] Dynamic subtest vcs1: SUCCESS (1.966s)
[183.839244] Starting dynamic subtest: vecs0
[185.895728] Dynamic subtest vecs0: SUCCESS (2.066s)
[185.906843] Starting dynamic subtest: ccs0
[187.868922] Dynamic subtest ccs0: SUCCESS (1.972s)
[187.871405] Subtest engines-mixed-process: SUCCESS (11.498s)
[187.943945] [041/133] (831s left) i915_drm_fdinfo (virtual-busy-hang-all)
[187.995104] Starting subtest: virtual-busy-hang-all
[187.999915] Subtest virtual-busy-hang-all: SKIP (0.000s)
[188.055768] [042/133] (831s left) kms_flip (2x-blocking-wf_vblank)
[188.138989] Starting subtest: 2x-blocking-wf_vblank
[188.146098] Subtest 2x-blocking-wf_vblank: SKIP (0.007s)
[188.222915] [043/133] (831s left) kms_chamelium_color (gamma)
[188.296738] Subtest gamma: SKIP (0.000s)
[188.341973] [044/133] (830s left) sw_sync (sync_multi_timeline_wait)
[188.377687] Starting subtest: sync_multi_timeline_wait
[188.379622] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[188.437307] [045/133] (830s left) prime_self_import (basic-with_fd_dup)
[188.469806] Starting subtest: basic-with_fd_dup
[188.490459] Subtest basic-with_fd_dup: SUCCESS (0.021s)
[188.588221] [046/133] (830s left) i915_drm_fdinfo (virtual-busy)
[188.630746] Starting subtest: virtual-busy
[188.635325] Subtest virtual-busy: SKIP (0.000s)
[188.717919] [047/133] (830s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[188.794224] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[188.798520] Starting dynamic subtest: pipe-A
[188.814154] Dynamic subtest pipe-A: SKIP (0.018s)
[188.828260] Starting dynamic subtest: pipe-B
[188.850282] Dynamic subtest pipe-B: SKIP (0.035s)
[188.858845] Starting dynamic subtest: pipe-C
[188.878605] Dynamic subtest pipe-C: SKIP (0.031s)
[188.888513] Starting dynamic subtest: pipe-D
[188.912719] Dynamic subtest pipe-D: SKIP (0.032s)
[188.916164] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.118s)
[189.011206] [048/133] (830s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[189.100217] Starting subtest: fbc-rgb565-draw-pwrite
[193.639382] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.541s)
[194.843238] [049/133] (824s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180-hflip)
[194.939201] Starting subtest: linear-max-hw-stride-64bpp-rotate-180-hflip
[194.943701] Subtest linear-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[195.053802] [050/133] (824s left) kms_fbcon_fbt (psr)
[195.113340] Starting subtest: psr
[202.475127] Subtest psr: SUCCESS (7.359s)
[202.548118] [051/133] (817s left) kms_pm_backlight (fade-with-suspend)
[202.635064] Starting subtest: fade-with-suspend
[219.114205] Starting dynamic subtest: eDP-1
[221.592680] Dynamic subtest eDP-1: SUCCESS (2.487s)
[221.595065] Subtest fade-with-suspend: SUCCESS (3.853s)
[222.423750] [052/133] (797s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[222.493254] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[222.555949] [053/133] (797s left) prime_vgem (basic-blt)
[222.699637] Starting subtest: basic-blt
[222.720925] Subtest basic-blt: SUCCESS (0.024s)
[222.835437] [FACT prime_vgem (basic-blt)] new: kernel.kmod_is_loaded.vgem: true
[222.838833] [054/133] (796s left) gem_softpin (allocator-basic-reserve)
[222.894879] Starting subtest: allocator-basic-reserve
[222.970218] Subtest allocator-basic-reserve: SUCCESS (0.075s)
[223.094673] [055/133] (796s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[223.172364] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[229.856117] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.699s)
[229.956567] [056/133] (789s left) gem_exec_schedule (u-fairslice)
[230.031908] Starting subtest: u-fairslice
[230.033830] Starting dynamic subtest: rcs0
[232.045413] Dynamic subtest rcs0: SUCCESS (2.023s)
[232.058914] Starting dynamic subtest: bcs0
[234.063143] Dynamic subtest bcs0: SUCCESS (2.018s)
[234.075063] Starting dynamic subtest: vcs0
[236.082675] Dynamic subtest vcs0: SUCCESS (2.022s)
[236.092342] Starting dynamic subtest: vcs1
[238.102166] Dynamic subtest vcs1: SUCCESS (2.019s)
[238.111235] Starting dynamic subtest: vecs0
[240.119488] Dynamic subtest vecs0: SUCCESS (2.018s)
[240.130261] Starting dynamic subtest: ccs0
[242.149651] Dynamic subtest ccs0: SUCCESS (2.031s)
[242.152038] Subtest u-fairslice: SUCCESS (12.137s)
[242.463033] [057/133] (777s left) gem_lmem_evict (dontneed-evict-race)
[242.514734] Subtest dontneed-evict-race: SKIP (0.000s)
[242.567841] [058/133] (777s left) gem_exec_reloc (basic-gtt-cpu-active)
[242.629335] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[242.699731] [059/133] (777s left) kms_pipe_crc_basic (suspend-read-crc)
[242.787756] Starting subtest: suspend-read-crc
[242.790137] Starting dynamic subtest: pipe-A-eDP-1
[261.035940] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.600s)
[261.046337] Starting dynamic subtest: pipe-B-eDP-1
[279.023917] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.141s)
[279.036790] Starting dynamic subtest: pipe-C-eDP-1
[296.938444] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.018s)
[296.952440] Starting dynamic subtest: pipe-D-eDP-1
[314.950781] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.124s)
[314.953432] Subtest suspend-read-crc: SUCCESS (11.889s)
[315.782672] [060/133] (704s left) gem_mmap_gtt (basic)
[315.861315] Subtest basic: SKIP (0.000s)
[315.929041] [061/133] (704s left) syncobj_wait (invalid-signal-illegal-handle)
[315.978409] Starting subtest: invalid-signal-illegal-handle
[315.980405] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[316.071803] [062/133] (703s left) perf_pmu (busy-idle-check-all)
[316.118667] Starting subtest: busy-idle-check-all
[316.120568] Starting dynamic subtest: rcs0
[316.775354] Dynamic subtest rcs0: SUCCESS (0.657s)
[316.788693] Starting dynamic subtest: bcs0
[317.430278] Dynamic subtest bcs0: SUCCESS (0.653s)
[317.443722] Starting dynamic subtest: vcs0
[318.148626] Dynamic subtest vcs0: FAIL (0.643s)
[318.159346] Starting dynamic subtest: vcs1
[319.255536] Dynamic subtest vcs1: SUCCESS (1.178s)
[319.268683] Starting dynamic subtest: vecs0
[321.240757] Dynamic subtest vecs0: SUCCESS (1.983s)
[321.254492] Starting dynamic subtest: ccs0
[321.891801] Dynamic subtest ccs0: SUCCESS (0.650s)
[321.894536] Subtest busy-idle-check-all: FAIL (5.778s)
[322.008383] [063/133] (698s left) kms_hdr (bpc-switch-suspend)
[322.085125] Starting subtest: bpc-switch-suspend
[323.158845] Starting dynamic subtest: pipe-A-eDP-1
[342.246222] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.538s)
[342.248526] Subtest bpc-switch-suspend: SUCCESS (5.615s)
[342.358944] [064/133] (677s left) gem_ctx_persistence (legacy-engines-hang)
[342.423858] Starting subtest: legacy-engines-hang
[342.425764] Starting dynamic subtest: render
[342.540580] Dynamic subtest render: SUCCESS (0.119s)
[342.555290] Starting dynamic subtest: bsd1
[342.627252] Dynamic subtest bsd1: SUCCESS (0.084s)
[342.643546] Starting dynamic subtest: bsd2
[342.718211] Dynamic subtest bsd2: SUCCESS (0.088s)
[342.733811] Starting dynamic subtest: blt
[342.765855] Dynamic subtest blt: SUCCESS (0.044s)
[342.778053] Starting dynamic subtest: vebox
[342.858610] Dynamic subtest vebox: SUCCESS (0.091s)
[342.861136] Subtest legacy-engines-hang: SUCCESS (0.440s)
[342.944371] [065/133] (677s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[343.030945] Starting subtest: yf-tiled-32bpp-rotate-270
[343.035455] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[343.131325] [066/133] (677s left) kms_cursor_crc (cursor-offscreen-128x42)
[343.323996] Starting subtest: cursor-offscreen-128x42
[343.372406] Subtest cursor-offscreen-128x42: SKIP (0.043s)
[343.549021] [067/133] (676s left) gem_create (create-valid-nonaligned)
[343.600403] Starting subtest: create-valid-nonaligned
[343.602043] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[343.801405] [068/133] (676s left) kms_rotation_crc (primary-rotation-270)
[343.870402] Starting subtest: primary-rotation-270
[343.874497] Subtest primary-rotation-270: SKIP (0.001s)
[343.955099] [069/133] (676s left) kms_plane (pixel-format-y-tiled-ccs-modifier-source-clamping)
[344.022953] Starting subtest: pixel-format-y-tiled-ccs-modifier-source-clamping
[344.027321] Subtest pixel-format-y-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[344.093290] [070/133] (676s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[344.192850] Starting subtest: verify-pxp-stale-buf-optout-execution
[345.252156] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.061s)
[345.381165] [071/133] (675s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[345.481362] Starting subtest: basic-flip-before-cursor-legacy
[346.395768] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.915s)
[347.223235] [072/133] (673s left) kms_async_flips (test-cursor)
[347.289799] Starting subtest: test-cursor
[347.666984] Starting dynamic subtest: pipe-A-eDP-1
[348.418475] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.753s)
[348.800641] Starting dynamic subtest: pipe-B-eDP-1
[349.539978] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.749s)
[349.922424] Starting dynamic subtest: pipe-C-eDP-1
[350.669333] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.756s)
[351.035564] Starting dynamic subtest: pipe-D-eDP-1
[351.799169] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.774s)
[351.801495] Subtest test-cursor: SUCCESS (4.518s)
[352.972352] [073/133] (667s left) core_setmaster (master-drop-set-shared-fd)
[353.064614] Starting subtest: master-drop-set-shared-fd
[353.082659] Subtest master-drop-set-shared-fd: SUCCESS (0.021s)
[353.122789] [074/133] (667s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[353.180183] Starting subtest: multi-wait-available-unsubmitted-signaled
[353.182454] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.001s)
[353.273602] [075/133] (667s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[353.365265] Starting subtest: cursor-tearing-framebuffer-change
[353.367107] Starting dynamic subtest: pipe-A-eDP-1
[353.924248] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.560s)
[353.940233] Starting dynamic subtest: pipe-D-eDP-1
[355.207687] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.283s)
[355.210095] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.845s)
[356.044399] [076/133] (664s left) gem_eio (in-flight-external)
[356.269699] Starting subtest: in-flight-external
[357.872477] Subtest in-flight-external: SUCCESS (1.604s)
[357.999674] [077/133] (662s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[358.076239] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[358.080704] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[358.168841] [078/133] (662s left) kms_pm_rpm (i2c)
[359.977449] Starting subtest: i2c
[364.990470] Subtest i2c: SUCCESS (5.019s)
[365.080849] [079/133] (655s left) kms_vblank (query-forked-hang)
[365.160278] Starting subtest: query-forked-hang
[365.162119] Starting dynamic subtest: pipe-A-eDP-1
[367.055597] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.894s)
[367.071743] Starting dynamic subtest: pipe-D-eDP-1
[369.548624] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.494s)
[369.551093] Subtest query-forked-hang: SUCCESS (4.393s)
[370.392702] [080/133] (650s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[370.492059] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[370.496129] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[370.585421] [081/133] (650s left) kms_pipe_crc_basic (bad-source)
[370.671243] Starting subtest: bad-source
[370.673506] Subtest bad-source: SUCCESS (0.000s)
[370.745626] [082/133] (650s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[370.804451] Starting subtest: single-wait-for-submit-available-signaled
[370.806860] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[370.915703] [083/133] (650s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[370.976924] Starting subtest: yf-tiled-to-vebox-yf-tiled
[370.981747] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[371.157403] [084/133] (649s left) api_intel_bb (crc32)
[371.220495] Starting subtest: crc32
[371.222841] Starting dynamic subtest: rcs0-smem0
[371.544561] Dynamic subtest rcs0-smem0: SUCCESS (0.324s)
[371.557274] Starting dynamic subtest: bcs0-smem0
[371.863202] Dynamic subtest bcs0-smem0: SUCCESS (0.315s)
[371.876440] Starting dynamic subtest: vcs0-smem0
[372.181867] Dynamic subtest vcs0-smem0: SUCCESS (0.315s)
[372.196713] Starting dynamic subtest: vcs1-smem0
[372.531122] Dynamic subtest vcs1-smem0: SUCCESS (0.346s)
[372.542934] Starting dynamic subtest: vecs0-smem0
[372.828043] Dynamic subtest vecs0-smem0: SUCCESS (0.294s)
[372.840984] Starting dynamic subtest: ccs0-smem0
[373.156596] Dynamic subtest ccs0-smem0: SUCCESS (0.326s)
[373.159528] Subtest crc32: SUCCESS (1.939s)
[373.286238] [085/133] (647s left) template (a)
[373.341968] Starting subtest: A
[373.344367] Subtest A: SUCCESS (0.000s)
[373.459770] [086/133] (647s left) gem_exec_fence (parallel)
[373.647725] Starting subtest: parallel
[373.650170] Starting dynamic subtest: rcs0
[375.801483] Dynamic subtest rcs0: SUCCESS (2.154s)
[375.813132] Starting dynamic subtest: bcs0
[377.958724] Dynamic subtest bcs0: SUCCESS (2.155s)
[377.969842] Starting dynamic subtest: vcs0
[380.108365] Dynamic subtest vcs0: SUCCESS (2.148s)
[380.119241] Starting dynamic subtest: vcs1
[382.262916] Dynamic subtest vcs1: SUCCESS (2.152s)
[382.274518] Starting dynamic subtest: vecs0
[384.413272] Dynamic subtest vecs0: SUCCESS (2.149s)
[384.424813] Starting dynamic subtest: ccs0
[386.561832] Dynamic subtest ccs0: SUCCESS (2.148s)
[386.563808] Subtest parallel: SUCCESS (12.912s)
[388.862968] [087/133] (632s left) kms_psr (psr-sprite-blt)
[388.937831] Starting subtest: psr-sprite-blt
[388.940114] Starting dynamic subtest: eDP-1
[390.725428] Dynamic subtest eDP-1: SUCCESS (1.786s)
[390.727771] Subtest psr-sprite-blt: SUCCESS (1.787s)
[391.550273] [088/133] (629s left) gem_mmap (bad-object)
[391.621319] Subtest bad-object: SKIP (0.000s)
[391.686682] [089/133] (629s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[391.744052] Starting subtest: etime-single-wait-available-unsubmitted
[391.852812] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.108s)
[391.943308] [090/133] (629s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[392.016959] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[392.019324] Starting dynamic subtest: pipe-A-eDP-1
[392.042670] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[392.054460] Starting dynamic subtest: pipe-B-eDP-1
[392.075062] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[392.084609] Starting dynamic subtest: pipe-C-eDP-1
[392.105635] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[392.115034] Starting dynamic subtest: pipe-D-eDP-1
[392.136235] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[392.140554] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[394.229295] [091/133] (627s left) kms_psr (fbc-pr-basic)
[394.306887] Starting subtest: fbc-pr-basic
[394.311194] Subtest fbc-pr-basic: SKIP (0.000s)
[394.381136] [092/133] (626s left) fbdev (unaligned-read)
[394.431804] Starting subtest: unaligned-read
[394.446346] Subtest unaligned-read: SUCCESS (0.015s)
[394.503000] [093/133] (626s left) gem_ctx_param (set-priority-not-supported)
[394.569263] Starting subtest: set-priority-not-supported
[394.573781] Subtest set-priority-not-supported: SKIP (0.000s)
[394.648727] [094/133] (626s left) gem_exec_schedule (preempt)
[394.718326] Starting subtest: preempt
[394.720295] Starting dynamic subtest: rcs0
[394.741759] Dynamic subtest rcs0: SUCCESS (0.028s)
[394.755088] Starting dynamic subtest: bcs0
[394.762037] Dynamic subtest bcs0: SUCCESS (0.019s)
[394.773484] Starting dynamic subtest: vcs0
[394.783458] Dynamic subtest vcs0: SUCCESS (0.021s)
[394.793533] Starting dynamic subtest: vcs1
[394.805445] Dynamic subtest vcs1: SUCCESS (0.021s)
[394.815586] Starting dynamic subtest: vecs0
[394.826818] Dynamic subtest vecs0: SUCCESS (0.021s)
[394.837608] Starting dynamic subtest: ccs0
[394.849867] Dynamic subtest ccs0: SUCCESS (0.022s)
[394.852073] Subtest preempt: SUCCESS (0.137s)
[396.855770] [095/133] (624s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[396.938836] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[404.005873] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.072s)
[404.135720] [096/133] (617s left) sw_sync (sync_multi_consumer)
[404.180046] Starting subtest: sync_multi_consumer
[404.599623] Subtest sync_multi_consumer: SUCCESS (0.417s)
[404.664133] [097/133] (616s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[404.744522] Starting subtest: y-tiled-addfb-size-offset-overflow
[404.748773] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[404.841472] [098/133] (616s left) perf_pmu (all-busy-idle-check-all)
[404.883574] Starting subtest: all-busy-idle-check-all
[406.768388] Subtest all-busy-idle-check-all: SUCCESS (1.887s)
[406.885501] [099/133] (614s left) gem_eio (wait-wedge-10ms)
[407.104267] Starting subtest: wait-wedge-10ms
[408.899608] Subtest wait-wedge-10ms: SUCCESS (1.797s)
[409.052336] [100/133] (612s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[409.138290] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[413.175223] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (4.041s)
[414.370774] [101/133] (607s left) kms_psr (pr-sprite-mmap-cpu)
[414.451143] Starting subtest: pr-sprite-mmap-cpu
[414.455656] Subtest pr-sprite-mmap-cpu: SKIP (0.000s)
[414.527495] [102/133] (607s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[414.596681] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[414.679267] [103/133] (607s left) syncobj_timeline (invalid-query-illegal-handle)
[414.732835] Starting subtest: invalid-query-illegal-handle
[414.734697] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[414.839081] [104/133] (607s left) kms_cursor_crc (cursor-random-32x10)
[414.976736] Starting subtest: cursor-random-32x10
[415.024220] Subtest cursor-random-32x10: SKIP (0.042s)
[415.318673] [105/133] (606s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[415.391082] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[415.392641] Starting dynamic subtest: pipe-A-eDP-1
[417.459936] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.069s)
[417.470181] Starting dynamic subtest: pipe-B-eDP-1
[419.607391] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.148s)
[419.617916] Starting dynamic subtest: pipe-C-eDP-1
[421.657111] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.048s)
[421.667223] Starting dynamic subtest: pipe-D-eDP-1
[423.682971] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.024s)
[423.685237] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.294s)
[424.920688] [106/133] (597s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[425.012207] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[425.016168] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[425.092920] [107/133] (596s left) gem_pread (display)
[425.144978] Subtest display: SKIP (0.000s)
[425.201574] [108/133] (596s left) gem_exec_basic (basic)
[425.266077] Starting subtest: basic
[425.268363] Starting dynamic subtest: rcs0-smem
[425.270745] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[425.282880] Starting dynamic subtest: bcs0-smem
[425.284777] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[425.294989] Starting dynamic subtest: vcs0-smem
[425.296885] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[425.306184] Starting dynamic subtest: vcs1-smem
[425.308051] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[425.317556] Starting dynamic subtest: vecs0-smem
[425.319447] Dynamic subtest vecs0-smem: SUCCESS (0.001s)
[425.328769] Starting dynamic subtest: ccs0-smem
[425.330642] Dynamic subtest ccs0-smem: SUCCESS (0.002s)
[425.332537] Subtest basic: SUCCESS (0.015s)
[426.840407] [109/133] (595s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[426.927948] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[426.932253] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[427.025706] [110/133] (595s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[427.103596] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[427.105815] Starting dynamic subtest: pipe-A
[427.443499] Dynamic subtest pipe-A: SUCCESS (0.342s)
[427.453459] Starting dynamic subtest: pipe-B
[428.904180] Dynamic subtest pipe-B: SUCCESS (1.461s)
[428.914718] Starting dynamic subtest: pipe-C
[430.326032] Dynamic subtest pipe-C: SUCCESS (1.421s)
[430.339014] Starting dynamic subtest: pipe-D
[431.775312] Dynamic subtest pipe-D: SUCCESS (1.449s)
[431.777521] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.676s)
[432.966021] [111/133] (589s left) gem_exec_balancer (bonded-pair)
[433.028251] Starting subtest: bonded-pair
[433.032923] Subtest bonded-pair: SKIP (0.000s)
[433.332225] [112/133] (588s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[433.419870] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[440.678928] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.264s)
[440.793482] [113/133] (581s left) kms_legacy_colorkey (invalid-plane)
[440.860092] Starting subtest: invalid-plane
[440.862278] Starting dynamic subtest: zero-id
[440.864103] Dynamic subtest zero-id: SUCCESS (0.000s)
[440.875072] Starting dynamic subtest: outrange-id
[440.877136] Dynamic subtest outrange-id: SUCCESS (0.000s)
[440.879191] Subtest invalid-plane: SUCCESS (0.001s)
[440.940361] [114/133] (581s left) api_intel_bb (purge-bb)
[440.999645] Starting subtest: purge-bb
[441.001954] Subtest purge-bb: SUCCESS (0.003s)
[441.123301] [115/133] (581s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[441.201481] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[447.845466] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.647s)
[447.982585] [116/133] (574s left) perf_pmu (rc6)
[448.023331] Starting subtest: rc6
[448.025513] Starting dynamic subtest: gt0
[454.385776] Dynamic subtest gt0: SUCCESS (6.360s)
[454.398998] Starting dynamic subtest: runtime-pm-gt0
[462.170338] Dynamic subtest runtime-pm-gt0: SUCCESS (7.781s)
[462.181974] Starting dynamic subtest: runtime-pm-long-gt0
[474.608042] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.435s)
[474.621599] Starting dynamic subtest: other-idle-gt0
[480.951077] Dynamic subtest other-idle-gt0: SUCCESS (6.341s)
[480.964857] Starting dynamic subtest: gt1
[487.291113] Dynamic subtest gt1: SUCCESS (6.338s)
[487.303987] Starting dynamic subtest: runtime-pm-gt1
[494.791501] Dynamic subtest runtime-pm-gt1: SUCCESS (7.499s)
[494.807937] Starting dynamic subtest: runtime-pm-long-gt1
[507.215239] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.423s)
[507.228904] Starting dynamic subtest: other-idle-gt1
[513.557361] Dynamic subtest other-idle-gt1: SUCCESS (6.341s)
[513.560361] Subtest rc6: SUCCESS (65.536s)
[514.715899] [117/133] (507s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[514.792511] Starting subtest: plane-primary-toggle-with-vblank-wait
[514.794892] Starting dynamic subtest: pipe-A-eDP-1
[514.965807] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.174s)
[515.275692] Starting dynamic subtest: pipe-B-eDP-1
[516.136090] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.870s)
[516.440939] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.647s)
[517.278395] [118/133] (505s left) gem_cs_tlb (engines)
[517.335590] Starting subtest: engines
[517.338062] Starting dynamic subtest: rcs0
[519.490968] Dynamic subtest rcs0: SUCCESS (2.153s)
[519.502211] Starting dynamic subtest: bcs0
[521.643519] Dynamic subtest bcs0: SUCCESS (2.152s)
[521.655097] Starting dynamic subtest: vcs0
[523.797232] Dynamic subtest vcs0: SUCCESS (2.153s)
[523.811922] Starting dynamic subtest: vcs1
[525.952325] Dynamic subtest vcs1: SUCCESS (2.153s)
[525.963611] Starting dynamic subtest: vecs0
[528.104983] Dynamic subtest vecs0: SUCCESS (2.153s)
[528.115869] Starting dynamic subtest: ccs0
[530.260489] Dynamic subtest ccs0: SUCCESS (2.155s)
[530.262949] Subtest engines: SUCCESS (12.926s)
[530.343473] [119/133] (492s left) kms_cdclk (mode-transition-all-outputs)
[530.416197] Starting subtest: mode-transition-all-outputs
[530.722424] Subtest mode-transition-all-outputs: SKIP (0.304s)
[531.504456] [120/133] (491s left) kms_psr (fbc-pr-sprite-plane-onoff)
[531.590351] Starting subtest: fbc-pr-sprite-plane-onoff
[531.594486] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[531.644964] [121/133] (490s left) gem_exec_whisper (basic-normal-all)
[531.707744] Starting subtest: basic-normal-all
[533.906077] Subtest basic-normal-all: SUCCESS (2.199s)
[534.072579] [122/133] (488s left) kms_flip (2x-flip-vs-panning-interruptible)
[534.154313] Starting subtest: 2x-flip-vs-panning-interruptible
[534.162738] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.008s)
[534.219501] [123/133] (488s left) kms_addfb_basic (unused-pitches)
[534.275640] Starting subtest: unused-pitches
[534.277862] Subtest unused-pitches: SUCCESS (0.000s)
[534.371931] [124/133] (488s left) gem_ctx_shared (q-out-order)
[534.434620] Starting subtest: Q-out-order
[534.436996] Starting dynamic subtest: rcs0
[534.548209] Dynamic subtest rcs0: SUCCESS (0.111s)
[534.568590] Starting dynamic subtest: vcs0
[534.642975] Dynamic subtest vcs0: SUCCESS (0.095s)
[534.662284] Starting dynamic subtest: vcs1
[534.724631] Dynamic subtest vcs1: SUCCESS (0.080s)
[534.741961] Starting dynamic subtest: vecs0
[534.809044] Dynamic subtest vecs0: SUCCESS (0.084s)
[534.827936] Starting dynamic subtest: ccs0
[534.944095] Dynamic subtest ccs0: SUCCESS (0.133s)
[534.946817] Subtest Q-out-order: SUCCESS (0.510s)
[535.859440] [125/133] (486s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[535.945664] Starting subtest: psr-rgb565-draw-mmap-wc
[542.686342] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.744s)
[542.765666] [126/133] (480s left) gem_media_fill (media-fill)
[542.822440] Subtest media-fill: SKIP (0.000s)
[542.898362] [127/133] (479s left) kms_vrr (seamless-rr-switch-virtual)
[542.976290] Starting subtest: seamless-rr-switch-virtual
[542.982717] Subtest seamless-rr-switch-virtual: SKIP (0.000s)
[543.025285] [128/133] (479s left) gem_exec_flush (basic-uc-set-default)
[543.087975] Starting subtest: basic-uc-set-default
[548.551192] Subtest basic-uc-set-default: SUCCESS (5.465s)
[548.722871] [129/133] (474s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[548.806865] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[548.811224] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[548.869385] [130/133] (474s left) gen9_exec_parse (bb-large)
[548.921521] Subtest bb-large: SKIP (0.000s)
[548.991903] [131/133] (474s left) vgem_basic (bad-flag)
[549.032804] Starting subtest: bad-flag
[549.035089] Subtest bad-flag: SUCCESS (0.000s)
[549.075704] [132/133] (473s left) i915_suspend (forcewake)
[549.121080] Starting subtest: forcewake
[581.108486] Subtest forcewake: SUCCESS (2.255s)
[582.163518] [133/133] (441s left) kms_psr (psr2-primary-blt)
[582.251138] Starting subtest: psr2-primary-blt
[582.253434] Starting dynamic subtest: eDP-1
[583.939209] Dynamic subtest eDP-1: SUCCESS (1.691s)
[583.941621] Subtest psr2-primary-blt: SUCCESS (1.693s)
[584.783643] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: gem_ctx_param
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: gem_pxp
results: parsing output: 4/ for test: kms_invalid_mode
results: parsing output: 5/ for test: kms_vblank
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_softpin
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: gem_vm_create
results: parsing output: 11/ for test: i915_pm_rpm
results: parsing output: 12/ for test: core_setmaster_vs_auth
results: parsing output: 13/ for test: gem_softpin
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: gem_mmap_gtt
results: parsing output: 16/ for test: kms_psr2_sf
results: parsing output: 17/ for test: gem_exec_params
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_chamelium_hpd
results: parsing output: 20/ for test: kms_flip_scaled_crc
results: parsing output: 21/ for test: kms_dsc
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: kms_atomic_transition
results: parsing output: 24/ for test: kms_content_protection
results: parsing output: 25/ for test: kms_color
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_async_flips
results: parsing output: 28/ for test: kms_ccs
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_psr2_sf
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_flip_scaled_crc
results: parsing output: 34/ for test: kms_dp_link_training
results: parsing output: 35/ for test: kms_plane_multiple
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: gem_ctx_persistence
results: parsing output: 40/ for test: i915_drm_fdinfo
results: parsing output: 41/ for test: kms_flip
results: parsing output: 42/ for test: kms_chamelium_color
results: parsing output: 43/ for test: sw_sync
results: parsing output: 44/ for test: prime_self_import
results: parsing output: 45/ for test: i915_drm_fdinfo
results: parsing output: 46/ for test: kms_plane_scaling
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: kms_fbcon_fbt
results: parsing output: 50/ for test: kms_pm_backlight
results: parsing output: 51/ for test: kms_pipe_b_c_ivb
results: parsing output: 52/ for test: prime_vgem
results: parsing output: 53/ for test: gem_softpin
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: gem_exec_schedule
results: parsing output: 56/ for test: gem_lmem_evict
results: parsing output: 57/ for test: gem_exec_reloc
results: parsing output: 58/ for test: kms_pipe_crc_basic
results: parsing output: 59/ for test: gem_mmap_gtt
results: parsing output: 60/ for test: syncobj_wait
results: parsing output: 61/ for test: perf_pmu
results: parsing output: 62/ for test: kms_hdr
results: parsing output: 63/ for test: gem_ctx_persistence
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: kms_cursor_crc
results: parsing output: 66/ for test: gem_create
results: parsing output: 67/ for test: kms_rotation_crc
results: parsing output: 68/ for test: kms_plane
results: parsing output: 69/ for test: gem_pxp
results: parsing output: 70/ for test: kms_cursor_legacy
results: parsing output: 71/ for test: kms_async_flips
results: parsing output: 72/ for test: core_setmaster
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_cursor_crc
results: parsing output: 75/ for test: gem_eio
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_pm_rpm
results: parsing output: 78/ for test: kms_vblank
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_pipe_crc_basic
results: parsing output: 81/ for test: syncobj_timeline
results: parsing output: 82/ for test: gem_render_copy
results: parsing output: 83/ for test: api_intel_bb
results: parsing output: 84/ for test: template
results: parsing output: 85/ for test: gem_exec_fence
results: parsing output: 86/ for test: kms_psr
results: parsing output: 87/ for test: gem_mmap
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: fbdev
results: parsing output: 92/ for test: gem_ctx_param
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: sw_sync
results: parsing output: 96/ for test: kms_big_fb
results: parsing output: 97/ for test: perf_pmu
results: parsing output: 98/ for test: gem_eio
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: gem_exec_reloc
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: kms_cursor_crc
results: parsing output: 104/ for test: kms_ccs
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: gem_pread
results: parsing output: 107/ for test: gem_exec_basic
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_plane_scaling
results: parsing output: 110/ for test: gem_exec_balancer
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_legacy_colorkey
results: parsing output: 113/ for test: api_intel_bb
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: perf_pmu
results: parsing output: 116/ for test: kms_atomic_transition
results: parsing output: 117/ for test: gem_cs_tlb
results: parsing output: 118/ for test: kms_cdclk
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: gem_exec_whisper
results: parsing output: 121/ for test: kms_flip
results: parsing output: 122/ for test: kms_addfb_basic
results: parsing output: 123/ for test: gem_ctx_shared
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_media_fill
results: parsing output: 126/ for test: kms_vrr
results: parsing output: 127/ for test: gem_exec_flush
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gen9_exec_parse
results: parsing output: 130/ for test: vgem_basic
results: parsing output: 131/ for test: i915_suspend
results: parsing output: 132/ for test: kms_psr
Done.