[58.301248] Initializing watchdogs
[58.301387]   /dev/watchdog0
[58.317644] [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]
[58.322498] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.325244] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.328665] [001/133] (960s left) gem_tiled_pread_pwrite
[58.477399] [002/133] (960s left) gem_mmap_gtt (cpuset-medium-copy)
[58.533067] Subtest cpuset-medium-copy: SKIP (0.000s)
[58.589499] [003/133] (960s left) kms_debugfs (display-on-read-all)
[58.688402] Starting subtest: display-on-read-all
[58.872407] Subtest display-on-read-all: SUCCESS (0.185s)
[58.982965] [004/133] (959s left) kms_flip (2x-flip-vs-modeset)
[59.073703] Starting subtest: 2x-flip-vs-modeset
[59.083211] Subtest 2x-flip-vs-modeset: SKIP (0.011s)
[59.184411] [005/133] (959s left) gem_ctx_param (invalid-ctx-set)
[59.241709] Starting subtest: invalid-ctx-set
[59.244157] Subtest invalid-ctx-set: SUCCESS (0.001s)
[59.327788] [006/133] (959s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[59.425261] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[59.429345] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[59.549663] [007/133] (959s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[59.611315] Starting subtest: single-wait-for-submit-available-submitted
[59.613382] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[59.704839] [008/133] (959s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[59.804808] Starting subtest: 4-tiled-64bpp-rotate-90
[59.824765] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.019s)
[59.951138] [009/133] (959s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[60.009476] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[60.011519] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[60.100152] [010/133] (959s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[60.147059] Starting subtest: multi-wait-available-unsubmitted-signaled
[60.148960] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[60.236200] [011/133] (958s left) kms_sequence (get-idle)
[60.323804] Starting subtest: get-idle
[60.325530] Starting dynamic subtest: pipe-A-eDP-1
[62.133148] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.814s)
[62.142400] Starting dynamic subtest: pipe-B-eDP-1
[64.617550] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.488s)
[64.626892] Starting dynamic subtest: pipe-C-eDP-1
[67.112598] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.495s)
[67.126525] Starting dynamic subtest: pipe-D-eDP-1
[69.540616] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.431s)
[69.542981] Subtest get-idle: SUCCESS (9.232s)
[70.351190] [012/133] (948s left) api_intel_bb (destroy-bb)
[70.403725] Starting subtest: destroy-bb
[70.411703] Subtest destroy-bb: SUCCESS (0.008s)
[70.527214] [013/133] (948s left) kms_flip (2x-flip-vs-rmfb)
[70.612042] Starting subtest: 2x-flip-vs-rmfb
[70.624847] Subtest 2x-flip-vs-rmfb: SKIP (0.012s)
[70.696248] [014/133] (948s left) gen9_exec_parse (cmd-crossing-page)
[70.761769] Subtest cmd-crossing-page: SKIP (0.000s)
[70.830991] [015/133] (948s left) kms_chamelium_hpd (vga-hpd-after-suspend)
[71.215707] Subtest vga-hpd-after-suspend: SKIP (0.000s)
[72.057861] [016/133] (947s left) kms_cursor_edge_walk (256x256-top-edge)
[72.148589] Starting subtest: 256x256-top-edge
[72.150907] Starting dynamic subtest: pipe-A-eDP-1
[75.645348] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.498s)
[75.654442] Starting dynamic subtest: pipe-D-eDP-1
[78.791563] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.146s)
[78.793818] Subtest 256x256-top-edge: SUCCESS (6.646s)
[79.581842] [017/133] (939s left) gem_exec_nop (basic-parallel)
[79.666932] Starting subtest: basic-parallel
[95.899896] Subtest basic-parallel: SUCCESS (16.244s)
[96.119849] [018/133] (923s left) kms_lease (lease-uevent)
[96.222721] Starting subtest: lease-uevent
[97.239537] Subtest lease-uevent: SUCCESS (1.017s)
[97.342517] [019/133] (922s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[97.442470] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[97.444711] Starting dynamic subtest: pipe-A-eDP-1
[99.634660] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.194s)
[99.643595] Starting dynamic subtest: pipe-B-eDP-1
[102.085200] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.449s)
[102.093458] Starting dynamic subtest: pipe-C-eDP-1
[104.240187] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.155s)
[104.249070] Starting dynamic subtest: pipe-D-eDP-1
[106.393985] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.153s)
[106.396385] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.954s)
[107.604385] [020/133] (911s left) gem_pxp (regular-baseline-src-copy-readible)
[107.730468] Starting subtest: regular-baseline-src-copy-readible
[107.789720] Subtest regular-baseline-src-copy-readible: SUCCESS (0.061s)
[107.901858] [021/133] (911s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[107.989073] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[107.993189] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[108.085156] [022/133] (911s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[108.179224] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[108.190951] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[108.275684] [023/133] (911s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[108.374644] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[115.192726] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.824s)
[115.318077] [024/133] (904s left) perf_pmu (busy-idle-check-all)
[115.366307] Starting subtest: busy-idle-check-all
[115.368582] Starting dynamic subtest: rcs0
[116.017602] Dynamic subtest rcs0: SUCCESS (0.652s)
[116.028883] Starting dynamic subtest: bcs0
[116.668726] Dynamic subtest bcs0: SUCCESS (0.650s)
[116.679664] Starting dynamic subtest: vcs0
[117.910570] Dynamic subtest vcs0: SUCCESS (1.239s)
[117.922134] Starting dynamic subtest: vcs1
[119.896227] Dynamic subtest vcs1: SUCCESS (1.983s)
[119.906896] Starting dynamic subtest: vecs0
[121.880509] Dynamic subtest vecs0: SUCCESS (1.982s)
[121.891779] Starting dynamic subtest: ccs0
[122.537171] Dynamic subtest ccs0: SUCCESS (0.657s)
[122.539753] Subtest busy-idle-check-all: SUCCESS (7.173s)
[122.651455] [025/133] (897s left) kms_flip (flip-vs-panning-interruptible)
[122.750805] Starting subtest: flip-vs-panning-interruptible
[122.753190] Starting dynamic subtest: A-eDP1
[131.455771] Dynamic subtest A-eDP1: SUCCESS (8.703s)
[131.465818] Starting dynamic subtest: B-eDP1
[140.170076] Dynamic subtest B-eDP1: SUCCESS (8.712s)
[140.179846] Starting dynamic subtest: C-eDP1
[148.903452] Dynamic subtest C-eDP1: SUCCESS (8.732s)
[148.916796] Starting dynamic subtest: D-eDP1
[157.631925] Dynamic subtest D-eDP1: SUCCESS (8.726s)
[157.636113] Subtest flip-vs-panning-interruptible: SUCCESS (34.883s)
[158.790942] [026/133] (861s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[158.880039] Starting subtest: primary-yf-tiled-reflect-x-0
[158.940079] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.059s)
[159.040240] [027/133] (860s left) kms_pm_rpm (fences-dpms)
[161.068488] Starting subtest: fences-dpms
[161.073821] Subtest fences-dpms: SKIP (0.000s)
[161.439042] [028/133] (858s left) syncobj_wait (invalid-signal-one-illegal-handle)
[161.489667] Starting subtest: invalid-signal-one-illegal-handle
[161.492091] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[161.605168] [029/133] (858s left) kms_psr (fbc-pr-primary-page-flip)
[161.702133] Starting subtest: fbc-pr-primary-page-flip
[161.706638] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[161.772394] [030/133] (858s left) gem_ctx_persistence (engines-mixed-process)
[161.827905] Starting subtest: engines-mixed-process
[161.830479] Starting dynamic subtest: rcs0
[163.942778] Dynamic subtest rcs0: SUCCESS (2.115s)
[163.953668] Starting dynamic subtest: bcs0
[165.897510] Dynamic subtest bcs0: SUCCESS (1.951s)
[165.906827] Starting dynamic subtest: vcs0
[167.916383] Dynamic subtest vcs0: SUCCESS (2.016s)
[167.928659] Starting dynamic subtest: vcs1
[169.929235] Dynamic subtest vcs1: SUCCESS (2.012s)
[169.938278] Starting dynamic subtest: vecs0
[171.875740] Dynamic subtest vecs0: SUCCESS (1.948s)
[171.886309] Starting dynamic subtest: ccs0
[173.916710] Dynamic subtest ccs0: SUCCESS (2.040s)
[173.919078] Subtest engines-mixed-process: SUCCESS (12.090s)
[174.005078] [031/133] (846s left) syncobj_wait (invalid-wait-illegal-handle)
[174.067559] Starting subtest: invalid-wait-illegal-handle
[174.069913] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[174.172431] [032/133] (845s left) perf_pmu (semaphore-wait-idle)
[174.210290] Starting subtest: semaphore-wait-idle
[174.212222] Starting dynamic subtest: rcs0
[174.726208] Dynamic subtest rcs0: SUCCESS (0.514s)
[174.738033] Starting dynamic subtest: bcs0
[175.241541] Dynamic subtest bcs0: SUCCESS (0.513s)
[175.253294] Starting dynamic subtest: vcs0
[175.757098] Dynamic subtest vcs0: SUCCESS (0.512s)
[175.768751] Starting dynamic subtest: vcs1
[176.272060] Dynamic subtest vcs1: SUCCESS (0.512s)
[176.283998] Starting dynamic subtest: vecs0
[176.789395] Dynamic subtest vecs0: SUCCESS (0.514s)
[176.801080] Starting dynamic subtest: ccs0
[177.307727] Dynamic subtest ccs0: SUCCESS (0.515s)
[177.310777] Subtest semaphore-wait-idle: SUCCESS (3.098s)
[177.431393] [033/133] (842s left) gem_mmap_wc (read)
[177.490594] Subtest read: SKIP (0.000s)
[177.545714] [034/133] (842s left) gem_exec_schedule (pi-ringfull)
[177.724548] Starting subtest: pi-ringfull
[177.727137] Starting dynamic subtest: rcs0
[177.788085] Dynamic subtest rcs0: SUCCESS (0.067s)
[177.801980] Starting dynamic subtest: bcs0
[177.819651] Dynamic subtest bcs0: SUCCESS (0.031s)
[177.832525] Starting dynamic subtest: vcs0
[177.851224] Dynamic subtest vcs0: SUCCESS (0.031s)
[177.863798] Starting dynamic subtest: vcs1
[177.881990] Dynamic subtest vcs1: SUCCESS (0.030s)
[177.890945] Starting dynamic subtest: vecs0
[177.913639] Dynamic subtest vecs0: SUCCESS (0.031s)
[177.922519] Starting dynamic subtest: ccs0
[177.945319] Dynamic subtest ccs0: SUCCESS (0.031s)
[177.947667] Subtest pi-ringfull: SUCCESS (0.225s)
[178.178734] [FACT gem_exec_schedule (pi-ringfull)] new: kernel.kmod_is_loaded.vgem: true
[178.182555] [035/133] (841s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[178.277683] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[178.280030] Starting dynamic subtest: pipe-A-eDP-1
[178.302655] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[178.320298] Starting dynamic subtest: pipe-B-eDP-1
[178.341811] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[178.358801] Starting dynamic subtest: pipe-C-eDP-1
[178.381100] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[178.398347] Starting dynamic subtest: pipe-D-eDP-1
[178.421554] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[178.425742] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.005s)
[180.591319] [036/133] (839s left) kms_content_protection (dp-mst-lic-type-0)
[180.747070] Starting subtest: dp-mst-lic-type-0
[180.751615] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[180.863527] [037/133] (839s left) gem_pxp (fail-invalid-protected-context)
[180.961225] Starting subtest: fail-invalid-protected-context
[180.963461] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[181.070670] [038/133] (839s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[181.123930] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[181.126279] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[181.218069] [039/133] (839s left) kms_invalid_mode (zero-clock)
[181.313613] Starting subtest: zero-clock
[181.315919] Starting dynamic subtest: pipe-A-eDP-1
[181.600713] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[181.609701] Starting dynamic subtest: pipe-B-eDP-1
[181.611908] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[181.620099] Starting dynamic subtest: pipe-C-eDP-1
[181.622405] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[181.630575] Starting dynamic subtest: pipe-D-eDP-1
[181.632882] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[181.635241] Subtest zero-clock: SUCCESS (0.293s)
[182.428902] [040/133] (837s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[182.529650] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[182.534152] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[182.624627] [041/133] (837s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[182.737771] Starting subtest: x-tiled-32bpp-rotate-90
[182.757750] Subtest x-tiled-32bpp-rotate-90: SKIP (0.019s)
[182.862941] [042/133] (837s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[182.950228] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[182.954491] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[183.043808] [043/133] (837s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[183.142060] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[183.146086] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[183.216859] [044/133] (837s left) perf (gen12-group-exclusive-stream-sample-oa)
[183.341770] Starting subtest: gen12-group-exclusive-stream-sample-oa
[183.360264] Subtest gen12-group-exclusive-stream-sample-oa: SUCCESS (0.020s)
[183.399931] [045/133] (837s left) gem_exec_schedule (thriceslice)
[183.475482] Starting subtest: thriceslice
[183.477204] Starting dynamic subtest: rcs0
[183.500574] Dynamic subtest rcs0: SUCCESS (0.029s)
[183.510617] Starting dynamic subtest: bcs0
[183.530277] Dynamic subtest bcs0: SUCCESS (0.029s)
[183.538474] Starting dynamic subtest: vcs0
[183.560238] Dynamic subtest vcs0: SUCCESS (0.029s)
[183.568998] Starting dynamic subtest: vcs1
[183.589211] Dynamic subtest vcs1: SUCCESS (0.028s)
[183.597403] Starting dynamic subtest: vecs0
[183.639899] Dynamic subtest vecs0: SUCCESS (0.049s)
[183.651887] Starting dynamic subtest: ccs0
[183.671523] Dynamic subtest ccs0: SUCCESS (0.030s)
[183.673484] Subtest thriceslice: SUCCESS (0.201s)
[183.974734] [046/133] (836s left) gem_ctx_create (basic)
[184.037068] Starting subtest: basic
[184.039247] Subtest basic: SUCCESS (0.000s)
[184.217809] [047/133] (836s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier)
[184.325677] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier
[184.329868] Subtest pixel-format-4-tiled-lnl-ccs-modifier: SKIP (0.000s)
[184.400652] [048/133] (836s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[184.512717] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[184.516752] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[184.613311] [049/133] (836s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[184.707777] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[184.709926] Starting dynamic subtest: pipe-A
[184.730105] Dynamic subtest pipe-A: SKIP (0.017s)
[184.743471] Starting dynamic subtest: pipe-B
[184.761592] Dynamic subtest pipe-B: SKIP (0.031s)
[184.775685] Starting dynamic subtest: pipe-C
[184.792972] Dynamic subtest pipe-C: SKIP (0.031s)
[184.805365] Starting dynamic subtest: pipe-D
[184.824427] Dynamic subtest pipe-D: SKIP (0.030s)
[184.828659] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.113s)
[184.931691] [050/133] (835s left) kms_psr2_su (page_flip-nv12)
[184.996820] Subtest page_flip-NV12: SKIP (0.000s)
[185.065248] [051/133] (835s left) kms_psr (fbc-psr-basic)
[185.152892] Starting subtest: fbc-psr-basic
[185.155170] Starting dynamic subtest: eDP-1
[185.159429] Dynamic subtest eDP-1: SKIP (0.000s)
[185.164023] Subtest fbc-psr-basic: SKIP (0.001s)
[185.240516] [052/133] (835s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[185.345427] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[185.349063] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[185.440037] [053/133] (835s left) kms_vblank (wait-forked-busy-hang)
[185.537245] Starting subtest: wait-forked-busy-hang
[185.539139] Starting dynamic subtest: pipe-A-eDP-1
[211.740675] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.201s)
[211.754832] Starting dynamic subtest: pipe-D-eDP-1
[236.699531] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.958s)
[236.701884] Subtest wait-forked-busy-hang: SUCCESS (51.163s)
[237.559741] [054/133] (783s left) sw_sync (timeline_closed_signaled)
[237.601696] Starting subtest: timeline_closed_signaled
[237.603711] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[237.660417] [055/133] (783s left) perf (per-context-mode-unprivileged)
[237.749646] Starting subtest: per-context-mode-unprivileged
[237.754378] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[237.792703] [056/133] (783s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[237.885271] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[237.887354] Starting dynamic subtest: pipe-A-default-mode
[239.026016] Dynamic subtest pipe-A-default-mode: SKIP (1.139s)
[239.030255] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.140s)
[239.183182] [057/133] (781s left) syncobj_timeline (device-signal-unordered)
[239.251307] Starting subtest: device-signal-unordered
[239.253508] Subtest device-signal-unordered: SUCCESS (0.001s)
[239.358960] [058/133] (781s left) kms_async_flips (alternate-sync-async-flip)
[239.447155] Starting subtest: alternate-sync-async-flip
[239.835958] Starting dynamic subtest: pipe-A-eDP-1
[242.584847] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.749s)
[242.968511] Starting dynamic subtest: pipe-B-eDP-1
[245.715630] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.754s)
[246.103252] Starting dynamic subtest: pipe-C-eDP-1
[248.852371] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.757s)
[249.217915] Starting dynamic subtest: pipe-D-eDP-1
[251.980957] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.771s)
[251.983057] Subtest alternate-sync-async-flip: SUCCESS (12.537s)
[253.152635] [059/133] (768s left) gem_exec_balancer (bonded-dual)
[253.219908] Starting subtest: bonded-dual
[253.224225] Subtest bonded-dual: SKIP (0.000s)
[253.505583] [060/133] (767s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[253.768103] Starting subtest: cursor-rapid-movement-256x85
[253.817604] Subtest cursor-rapid-movement-256x85: SKIP (0.046s)
[253.982895] [061/133] (767s left) kms_frontbuffer_tracking (fbc-suspend)
[254.083221] Starting subtest: fbc-suspend
[292.635431] Subtest fbc-suspend: SUCCESS (8.342s)
[293.814791] [062/133] (727s left) prime_vgem (basic-fence-read)
[293.895265] Starting subtest: basic-fence-read
[293.903409] Subtest basic-fence-read: SKIP (0.007s)
[293.998651] [063/133] (727s left) kms_fence_pin_leak
[294.182318] [064/133] (727s left) gem_exec_fence (basic-busy)
[294.366190] Starting subtest: basic-busy
[294.368540] Starting dynamic subtest: rcs0
[294.424280] Dynamic subtest rcs0: SUCCESS (0.062s)
[294.436214] Starting dynamic subtest: bcs0
[294.479451] Dynamic subtest bcs0: SUCCESS (0.053s)
[294.490820] Starting dynamic subtest: vcs0
[294.537104] Dynamic subtest vcs0: SUCCESS (0.056s)
[294.548349] Starting dynamic subtest: vcs1
[294.589000] Dynamic subtest vcs1: SUCCESS (0.050s)
[294.599628] Starting dynamic subtest: vecs0
[294.637896] Dynamic subtest vecs0: SUCCESS (0.047s)
[294.648748] Starting dynamic subtest: ccs0
[294.686091] Dynamic subtest ccs0: SUCCESS (0.046s)
[294.688406] Subtest basic-busy: SUCCESS (0.327s)
[296.278101] [065/133] (725s left) kms_chamelium_frames (hdmi-frame-dump)
[296.658268] Subtest hdmi-frame-dump: SKIP (0.000s)
[297.476061] [066/133] (723s left) kms_atomic (crtc-invalid-params-fence)
[297.571251] Starting subtest: crtc-invalid-params-fence
[297.573683] Starting dynamic subtest: pipe-A-eDP-1
[297.988529] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.419s)
[297.990542] Subtest crtc-invalid-params-fence: SUCCESS (0.421s)
[298.818069] [067/133] (722s left) gem_exec_capture (userptr)
[298.878179] Starting subtest: userptr
[299.700796] Subtest userptr: SUCCESS (0.827s)
[299.826622] [068/133] (721s left) gem_workarounds (reset)
[299.904161] Starting subtest: reset
[300.068819] Subtest reset: SUCCESS (0.163s)
[300.186029] [069/133] (721s left) kms_atomic_interruptible (legacy-dpms)
[300.287241] Starting subtest: legacy-dpms
[300.289524] Starting dynamic subtest: pipe-A-eDP-1
[307.758357] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.491s)
[307.760634] Subtest legacy-dpms: SUCCESS (7.493s)
[308.567888] [070/133] (713s left) gem_wait (await)
[308.639503] Starting subtest: await
[308.641577] Starting dynamic subtest: all-engines
[309.738092] Dynamic subtest all-engines: SUCCESS (1.102s)
[309.754892] Starting dynamic subtest: rcs0
[310.792648] Dynamic subtest rcs0: SUCCESS (1.054s)
[310.809646] Starting dynamic subtest: bcs0
[311.850310] Dynamic subtest bcs0: SUCCESS (1.057s)
[311.865016] Starting dynamic subtest: vcs0
[312.904566] Dynamic subtest vcs0: SUCCESS (1.054s)
[312.921440] Starting dynamic subtest: vcs1
[313.957158] Dynamic subtest vcs1: SUCCESS (1.052s)
[313.974126] Starting dynamic subtest: vecs0
[315.011132] Dynamic subtest vecs0: SUCCESS (1.053s)
[315.027921] Starting dynamic subtest: ccs0
[316.069573] Dynamic subtest ccs0: SUCCESS (1.057s)
[316.072454] Subtest await: SUCCESS (7.443s)
[316.268526] [071/133] (705s left) i915_power (sanity)
[316.329032] Starting subtest: sanity
[316.333231] Subtest sanity: SKIP (0.000s)
[316.421023] [072/133] (705s left) gem_ctx_shared (q-independent)
[316.482919] Starting subtest: Q-independent
[316.485187] Starting dynamic subtest: rcs0
[316.621777] Dynamic subtest rcs0: SUCCESS (0.138s)
[316.634682] Starting dynamic subtest: vcs0
[316.696524] Dynamic subtest vcs0: SUCCESS (0.073s)
[316.708881] Starting dynamic subtest: vcs1
[316.787584] Dynamic subtest vcs1: SUCCESS (0.093s)
[316.797596] Starting dynamic subtest: vecs0
[316.854446] Dynamic subtest vecs0: SUCCESS (0.065s)
[316.866659] Starting dynamic subtest: ccs0
[316.951465] Dynamic subtest ccs0: SUCCESS (0.096s)
[316.954422] Subtest Q-independent: SUCCESS (0.469s)
[318.240738] [073/133] (703s left) gem_busy (extended)
[318.424093] Starting subtest: extended
[318.426465] Starting dynamic subtest: rcs0
[318.516064] Dynamic subtest rcs0: SUCCESS (0.091s)
[318.528413] Starting dynamic subtest: bcs0
[318.558712] Dynamic subtest bcs0: SUCCESS (0.041s)
[318.569283] Starting dynamic subtest: vcs0
[320.200252] Dynamic subtest vcs0: SUCCESS (1.639s)
[320.212242] Starting dynamic subtest: vcs1
[322.184725] Dynamic subtest vcs1: SUCCESS (1.981s)
[322.196941] Starting dynamic subtest: vecs0
[324.168081] Dynamic subtest vecs0: SUCCESS (1.980s)
[324.180627] Starting dynamic subtest: ccs0
[324.214773] Dynamic subtest ccs0: SUCCESS (0.045s)
[324.217795] Subtest extended: SUCCESS (5.793s)
[324.418295] [074/133] (697s left) perf (unprivileged-single-ctx-counters)
[324.513806] Starting subtest: unprivileged-single-ctx-counters
[324.518435] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[324.563108] [075/133] (697s left) kms_draw_crc (draw-method-blt)
[324.658754] Starting subtest: draw-method-blt
[324.660988] Starting dynamic subtest: xrgb8888-untiled
[324.923895] Dynamic subtest xrgb8888-untiled: SUCCESS (0.266s)
[324.936495] Starting dynamic subtest: rgb565-untiled
[325.168404] Dynamic subtest rgb565-untiled: SUCCESS (0.243s)
[325.178250] Starting dynamic subtest: xrgb2101010-untiled
[325.390005] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[325.406280] Starting dynamic subtest: xbgr16161616f-untiled
[325.612647] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[325.622220] Starting dynamic subtest: xrgb8888-xtiled
[325.734701] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.120s)
[325.746470] Starting dynamic subtest: rgb565-xtiled
[325.849234] Dynamic subtest rgb565-xtiled: SUCCESS (0.109s)
[325.858650] Starting dynamic subtest: xrgb2101010-xtiled
[325.956982] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.109s)
[325.966785] Starting dynamic subtest: xbgr16161616f-xtiled
[326.068399] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.110s)
[326.080305] Starting dynamic subtest: xrgb8888-4tiled
[326.190195] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.120s)
[326.202150] Starting dynamic subtest: rgb565-4tiled
[326.312478] Dynamic subtest rgb565-4tiled: SUCCESS (0.120s)
[326.324256] Starting dynamic subtest: xrgb2101010-4tiled
[326.423760] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.109s)
[326.435547] Starting dynamic subtest: xbgr16161616f-4tiled
[326.545793] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.120s)
[326.547646] Subtest draw-method-blt: SUCCESS (1.889s)
[326.666235] [076/133] (695s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[326.770716] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[326.774271] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[326.866356] [077/133] (695s left) kms_plane_alpha_blend (alpha-7efc)
[326.964995] Starting subtest: alpha-7efc
[327.263210] Starting dynamic subtest: pipe-A-eDP-1
[327.889413] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.625s)
[327.917377] Starting dynamic subtest: pipe-D-eDP-1
[329.254331] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.343s)
[329.256321] Subtest alpha-7efc: SUCCESS (2.288s)
[330.104056] [078/133] (691s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[330.196147] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[334.191568] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (3.998s)
[335.310504] [079/133] (686s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[337.350418] Starting subtest: gem-execbuf-stress-pc8
[337.387982] Subtest gem-execbuf-stress-pc8: SKIP (0.041s)
[337.727492] [080/133] (684s left) gem_exec_store (pages)
[337.784998] Starting subtest: pages
[337.786886] Starting dynamic subtest: rcs0
[337.859253] Dynamic subtest rcs0: SUCCESS (0.075s)
[337.871491] Starting dynamic subtest: bcs0
[337.933826] Dynamic subtest bcs0: SUCCESS (0.074s)
[337.945693] Starting dynamic subtest: vcs0
[338.000018] Dynamic subtest vcs0: SUCCESS (0.065s)
[338.011679] Starting dynamic subtest: vcs1
[338.067354] Dynamic subtest vcs1: SUCCESS (0.067s)
[338.079226] Starting dynamic subtest: vecs0
[338.130772] Dynamic subtest vecs0: SUCCESS (0.062s)
[338.142951] Starting dynamic subtest: ccs0
[338.207098] Dynamic subtest ccs0: SUCCESS (0.075s)
[338.209630] Subtest pages: SUCCESS (0.424s)
[338.396354] [081/133] (683s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions)
[338.512311] Starting subtest: cursorA-vs-flipA-atomic-transitions
[349.682079] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.171s)
[350.572277] [082/133] (671s left) gem_ctx_bad_destroy (invalid-pad)
[350.635668] Starting subtest: invalid-pad
[350.637889] Subtest invalid-pad: SUCCESS (0.000s)
[350.724292] [083/133] (671s left) api_intel_bb (blit-reloc-keep-cache)
[350.787325] Starting subtest: blit-reloc-keep-cache
[350.792049] Subtest blit-reloc-keep-cache: SKIP (0.001s)
[350.920197] [084/133] (671s left) kms_psr (pr-basic)
[351.012033] Starting subtest: pr-basic
[351.016132] Subtest pr-basic: SKIP (0.000s)
[351.087097] [085/133] (671s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[351.196960] Starting subtest: y-tiled-16bpp-rotate-90
[351.200691] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[351.285221] [086/133] (671s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[351.386973] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[351.391404] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[351.487078] [087/133] (670s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[351.592148] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[351.595617] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[351.678012] [088/133] (670s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[351.766951] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[351.768802] Starting dynamic subtest: pipe-A-eDP-1
[351.786920] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[351.797124] Starting dynamic subtest: pipe-B-eDP-1
[351.814235] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[351.823414] Starting dynamic subtest: pipe-C-eDP-1
[351.840504] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[351.849145] Starting dynamic subtest: pipe-D-eDP-1
[351.866563] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[351.869958] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[354.025111] [089/133] (668s left) gem_exec_fence (nb-await)
[354.195979] Starting subtest: nb-await
[354.198373] Starting dynamic subtest: rcs0
[354.264997] Dynamic subtest rcs0: SUCCESS (0.068s)
[354.277597] Starting dynamic subtest: bcs0
[354.323251] Dynamic subtest bcs0: SUCCESS (0.056s)
[354.335719] Starting dynamic subtest: vcs0
[354.383008] Dynamic subtest vcs0: SUCCESS (0.058s)
[354.394517] Starting dynamic subtest: vcs1
[354.442070] Dynamic subtest vcs1: SUCCESS (0.056s)
[354.453783] Starting dynamic subtest: vecs0
[354.500357] Dynamic subtest vecs0: SUCCESS (0.057s)
[354.511682] Starting dynamic subtest: ccs0
[354.558950] Dynamic subtest ccs0: SUCCESS (0.056s)
[354.561449] Subtest nb-await: SUCCESS (0.364s)
[356.264324] [090/133] (666s left) kms_color (gamma)
[356.360192] Starting subtest: gamma
[356.362158] Starting dynamic subtest: pipe-A-eDP-1
[357.070941] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.711s)
[357.081623] Starting dynamic subtest: pipe-B-eDP-1
[358.459271] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.388s)
[358.469658] Starting dynamic subtest: pipe-C-eDP-1
[359.823324] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.365s)
[359.834159] Starting dynamic subtest: pipe-D-eDP-1
[361.150855] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.326s)
[361.153170] Subtest gamma: SUCCESS (4.793s)
[361.953175] [091/133] (660s left) gem_exec_reloc (basic-gtt-read)
[362.033533] Subtest basic-gtt-read: SKIP (0.000s)
[362.111713] [092/133] (660s left) gem_spin_batch (spin-each)
[362.188682] Starting subtest: spin-each
[365.459589] Subtest spin-each: SUCCESS (3.264s)
[366.245471] [093/133] (656s left) kms_psr (psr2-sprite-plane-onoff)
[366.346835] Starting subtest: psr2-sprite-plane-onoff
[366.349168] Starting dynamic subtest: eDP-1
[368.041367] Dynamic subtest eDP-1: SUCCESS (1.694s)
[368.043574] Subtest psr2-sprite-plane-onoff: SUCCESS (1.695s)
[368.929312] [094/133] (653s left) kms_plane_cursor (overlay)
[369.028795] Starting subtest: overlay
[369.033063] Starting dynamic subtest: pipe-A-eDP-1-size-64
[371.633333] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.607s)
[371.695547] Starting dynamic subtest: pipe-A-eDP-1-size-128
[374.212192] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.527s)
[374.273825] Starting dynamic subtest: pipe-A-eDP-1-size-256
[376.867263] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.604s)
[377.233677] Starting dynamic subtest: pipe-B-eDP-1-size-64
[380.470556] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.248s)
[380.531015] Starting dynamic subtest: pipe-B-eDP-1-size-128
[383.082558] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.560s)
[383.144351] Starting dynamic subtest: pipe-B-eDP-1-size-256
[385.660123] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.527s)
[386.017182] Starting dynamic subtest: pipe-C-eDP-1-size-64
[389.040292] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.032s)
[389.081159] Starting dynamic subtest: pipe-C-eDP-1-size-128
[391.274205] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.203s)
[391.313314] Starting dynamic subtest: pipe-C-eDP-1-size-256
[393.463821] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.159s)
[393.796594] Starting dynamic subtest: pipe-D-eDP-1-size-64
[396.661964] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.876s)
[396.700511] Starting dynamic subtest: pipe-D-eDP-1-size-128
[398.884538] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.192s)
[398.923710] Starting dynamic subtest: pipe-D-eDP-1-size-256
[401.074266] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.159s)
[401.394080] Subtest overlay: SUCCESS (32.364s)
[402.262963] [095/133] (620s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[402.378286] Starting subtest: yf-tiled-16bpp-rotate-180
[402.382803] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[402.473292] [096/133] (620s left) kms_psr (psr-primary-render)
[402.568663] Starting subtest: psr-primary-render
[402.570805] Starting dynamic subtest: eDP-1
[404.350791] Dynamic subtest eDP-1: SUCCESS (1.781s)
[404.353166] Subtest psr-primary-render: SUCCESS (1.782s)
[405.209254] [097/133] (617s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[405.319991] Starting subtest: fbcpsr-2p-rte
[405.324865] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[405.414710] [098/133] (617s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs-cc)
[405.508034] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs-cc
[405.510317] Starting dynamic subtest: pipe-A-eDP-1
[405.519242] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[405.529805] Starting dynamic subtest: pipe-B-eDP-1
[405.538415] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[405.548372] Starting dynamic subtest: pipe-C-eDP-1
[405.557326] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[405.569502] Starting dynamic subtest: pipe-D-eDP-1
[405.578018] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[405.582165] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs-cc: SKIP (0.011s)
[407.685585] [099/133] (615s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[407.781468] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[407.783785] Starting dynamic subtest: pipe-A-eDP-1
[407.807522] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[407.819670] Starting dynamic subtest: pipe-B-eDP-1
[407.842341] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[407.851906] Starting dynamic subtest: pipe-C-eDP-1
[407.874500] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[407.883825] Starting dynamic subtest: pipe-D-eDP-1
[407.907658] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[407.911849] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[410.038984] [100/133] (612s left) kms_frontbuffer_tracking (fbc-1p-rte)
[410.145988] Starting subtest: fbc-1p-rte
[415.607469] Subtest fbc-1p-rte: SUCCESS (5.465s)
[416.743500] [101/133] (606s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[416.839220] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[416.843488] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[416.934607] [102/133] (606s left) kms_getfb (getfb2-handle-protection)
[417.031558] Starting subtest: getfb2-handle-protection
[417.057220] Subtest getfb2-handle-protection: SUCCESS (0.026s)
[417.179495] [103/133] (605s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[417.274544] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[417.277261] Starting dynamic subtest: pipe-A
[417.336338] Dynamic subtest pipe-A: SUCCESS (0.064s)
[417.350161] Starting dynamic subtest: pipe-B
[418.455536] Dynamic subtest pipe-B: SUCCESS (1.118s)
[418.467650] Starting dynamic subtest: pipe-C
[419.575001] Dynamic subtest pipe-C: SUCCESS (1.118s)
[419.585432] Starting dynamic subtest: pipe-D
[420.705778] Dynamic subtest pipe-D: SUCCESS (1.129s)
[420.707979] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (3.434s)
[421.866234] [104/133] (601s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[421.973453] Starting subtest: psr-rgb101010-draw-mmap-cpu
[429.032846] Subtest psr-rgb101010-draw-mmap-cpu: SUCCESS (7.064s)
[429.150059] [105/133] (594s left) core_getversion (basic)
[429.191884] Starting subtest: basic
[429.195965] Subtest basic: SUCCESS (0.000s)
[429.256357] [106/133] (593s left) i915_getparams_basic (basic-subslice-total)
[429.302101] Starting subtest: basic-subslice-total
[429.306512] Subtest basic-subslice-total: SUCCESS (0.000s)
[429.390999] [107/133] (593s left) kms_flip (wf_vblank-ts-check)
[429.490081] Starting subtest: wf_vblank-ts-check
[429.492379] Starting dynamic subtest: A-eDP1
[438.445145] Dynamic subtest A-eDP1: SUCCESS (8.957s)
[438.455133] Starting dynamic subtest: B-eDP1
[447.369563] Dynamic subtest B-eDP1: SUCCESS (8.923s)
[447.382592] Starting dynamic subtest: C-eDP1
[456.301212] Dynamic subtest C-eDP1: SUCCESS (8.930s)
[456.313405] Starting dynamic subtest: D-eDP1
[465.233904] Dynamic subtest D-eDP1: SUCCESS (8.931s)
[465.238685] Subtest wf_vblank-ts-check: SUCCESS (35.756s)
[466.472036] [108/133] (556s left) gem_softpin (evict-snoop)
[466.543557] Starting subtest: evict-snoop
[466.548363] Subtest evict-snoop: SKIP (0.000s)
[466.702460] [109/133] (556s left) kms_flip (dpms-vs-vblank-race)
[466.801961] Starting subtest: dpms-vs-vblank-race
[466.803865] Starting dynamic subtest: A-eDP1
[471.776437] Dynamic subtest A-eDP1: SUCCESS (4.975s)
[471.786335] Starting dynamic subtest: B-eDP1
[476.402792] Dynamic subtest B-eDP1: SUCCESS (4.626s)
[476.413177] Starting dynamic subtest: C-eDP1
[481.031947] Dynamic subtest C-eDP1: SUCCESS (4.628s)
[481.044340] Starting dynamic subtest: D-eDP1
[484.557130] Dynamic subtest D-eDP1: SUCCESS (3.525s)
[484.559517] Subtest dpms-vs-vblank-race: SUCCESS (17.763s)
[485.424109] [110/133] (537s left) sysfs_timeslice_duration (idempotent)
[485.494343] Starting subtest: idempotent
[485.496586] Starting dynamic subtest: vecs0
[485.498942] Dynamic subtest vecs0: SUCCESS (0.000s)
[485.510104] Starting dynamic subtest: ccs0
[485.511942] Dynamic subtest ccs0: SUCCESS (0.000s)
[485.521230] Starting dynamic subtest: vcs0
[485.523126] Dynamic subtest vcs0: SUCCESS (0.000s)
[485.532360] Starting dynamic subtest: bcs0
[485.534231] Dynamic subtest bcs0: SUCCESS (0.000s)
[485.543504] Starting dynamic subtest: rcs0
[485.545415] Dynamic subtest rcs0: SUCCESS (0.000s)
[485.555119] Starting dynamic subtest: vcs1
[485.557058] Dynamic subtest vcs1: SUCCESS (0.000s)
[485.558924] Subtest idempotent: SUCCESS (0.006s)
[485.623590] [111/133] (537s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[485.713260] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[494.510163] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.801s)
[494.647656] [112/133] (528s left) gem_create (create-clear)
[494.710221] Starting subtest: create-clear
[494.712454] Starting dynamic subtest: smem0
[527.452612] Dynamic subtest smem0: SUCCESS (32.728s)
[527.454535] Subtest create-clear: SUCCESS (32.729s)
[527.660479] [113/133] (495s left) gen9_exec_parse (shadow-peek)
[527.723025] Subtest shadow-peek: SKIP (0.000s)
[527.795676] [114/133] (495s left) perf_pmu (all-busy-check-all)
[527.836138] Starting subtest: all-busy-check-all
[529.175908] Subtest all-busy-check-all: SUCCESS (1.322s)
[529.252515] [115/133] (494s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[529.353008] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[529.357187] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[529.453769] [116/133] (494s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[529.560275] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[529.564355] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[529.663156] [117/133] (494s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[529.760727] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[529.765433] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[529.887910] [118/133] (493s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-render)
[529.992098] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-render
[536.834980] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.841s)
[536.971563] [119/133] (486s left) kms_big_fb (linear-8bpp-rotate-270)
[537.089512] Starting subtest: linear-8bpp-rotate-270
[537.109511] Subtest linear-8bpp-rotate-270: SKIP (0.021s)
[537.225923] [120/133] (486s left) gem_exec_whisper (basic-sync)
[537.288361] Starting subtest: basic-sync
[545.695189] Subtest basic-sync: SUCCESS (8.402s)
[545.885474] [121/133] (477s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[545.997994] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[553.272045] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.274s)
[553.403107] [122/133] (470s left) gem_exec_fence (syncobj-wait)
[553.886276] Starting subtest: syncobj-wait
[555.167402] Subtest syncobj-wait: SUCCESS (1.278s)
[555.498084] [123/133] (468s left) kms_cursor_crc (cursor-onscreen-128x42)
[555.758950] Starting subtest: cursor-onscreen-128x42
[555.804888] Subtest cursor-onscreen-128x42: SKIP (0.044s)
[556.013150] [124/133] (467s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[556.112108] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[562.745810] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.633s)
[562.903480] [125/133] (461s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[563.015557] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[563.019683] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.000s)
[563.099194] [126/133] (460s left) gem_tiling_max_stride
[563.206206] [127/133] (460s left) gem_ctx_persistence (hostile)
[563.258060] Starting subtest: hostile
[573.407349] Subtest hostile: SUCCESS (10.141s)
[573.533012] [128/133] (450s left) gem_mmap_offset (open-flood)
[573.613449] Starting subtest: open-flood
[595.104729] Subtest open-flood: SUCCESS (21.492s)
[595.222488] [129/133] (428s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[595.285305] Starting subtest: invalid-signal-one-illegal-handle
[595.287369] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[595.393905] [130/133] (428s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[595.454300] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[595.458205] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[595.655881] [131/133] (428s left) kms_properties (plane-properties-legacy)
[595.752828] Starting subtest: plane-properties-legacy
[595.755076] Starting dynamic subtest: pipe-A-eDP-1
[596.309829] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.556s)
[596.320644] Starting dynamic subtest: pipe-B-eDP-1
[597.597372] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.288s)
[597.607598] Starting dynamic subtest: pipe-C-eDP-1
[598.914873] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.315s)
[598.925448] Starting dynamic subtest: pipe-D-eDP-1
[600.142479] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.227s)
[600.144691] Subtest plane-properties-legacy: SUCCESS (4.390s)
[600.940290] [132/133] (423s left) kms_pm_lpsp (kms-lpsp)
[602.044347] Starting subtest: kms-lpsp
[602.046491] Starting dynamic subtest: pipe-A-eDP-1
[602.188760] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.148s)
[602.486884] Subtest kms-lpsp: SUCCESS (0.446s)
[603.357822] [133/133] (421s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[603.459731] Starting subtest: psr2-cursor-plane-move-continuous-sf
[603.462157] Starting dynamic subtest: pipe-A-eDP-1
[613.797728] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.344s)
[613.808733] Starting dynamic subtest: pipe-B-eDP-1
[624.832286] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.033s)
[624.834698] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.380s)
[625.636656] Closing watchdogs
results: parsing output: 0/ for test: gem_tiled_pread_pwrite
results: parsing output: 1/ for test: gem_mmap_gtt
results: parsing output: 2/ for test: kms_debugfs
results: parsing output: 3/ for test: kms_flip
results: parsing output: 4/ for test: gem_ctx_param
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: syncobj_wait
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: kms_sequence
results: parsing output: 11/ for test: api_intel_bb
results: parsing output: 12/ for test: kms_flip
results: parsing output: 13/ for test: gen9_exec_parse
results: parsing output: 14/ for test: kms_chamelium_hpd
results: parsing output: 15/ for test: kms_cursor_edge_walk
results: parsing output: 16/ for test: gem_exec_nop
results: parsing output: 17/ for test: kms_lease
results: parsing output: 18/ for test: kms_ccs
results: parsing output: 19/ for test: gem_pxp
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: perf_pmu
results: parsing output: 24/ for test: kms_flip
results: parsing output: 25/ for test: kms_rotation_crc
results: parsing output: 26/ for test: kms_pm_rpm
results: parsing output: 27/ for test: syncobj_wait
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: gem_ctx_persistence
results: parsing output: 30/ for test: syncobj_wait
results: parsing output: 31/ for test: perf_pmu
results: parsing output: 32/ for test: gem_mmap_wc
results: parsing output: 33/ for test: gem_exec_schedule
results: parsing output: 34/ for test: kms_ccs
results: parsing output: 35/ for test: kms_content_protection
results: parsing output: 36/ for test: gem_pxp
results: parsing output: 37/ for test: syncobj_timeline
results: parsing output: 38/ for test: kms_invalid_mode
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_flip_scaled_crc
results: parsing output: 43/ for test: perf
results: parsing output: 44/ for test: gem_exec_schedule
results: parsing output: 45/ for test: gem_ctx_create
results: parsing output: 46/ for test: kms_plane
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_plane_scaling
results: parsing output: 49/ for test: kms_psr2_su
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: kms_big_fb
results: parsing output: 52/ for test: kms_vblank
results: parsing output: 53/ for test: sw_sync
results: parsing output: 54/ for test: perf
results: parsing output: 55/ for test: kms_flip_scaled_crc
results: parsing output: 56/ for test: syncobj_timeline
results: parsing output: 57/ for test: kms_async_flips
results: parsing output: 58/ for test: gem_exec_balancer
results: parsing output: 59/ for test: kms_cursor_crc
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: prime_vgem
results: parsing output: 62/ for test: kms_fence_pin_leak
results: parsing output: 63/ for test: gem_exec_fence
results: parsing output: 64/ for test: kms_chamelium_frames
results: parsing output: 65/ for test: kms_atomic
results: parsing output: 66/ for test: gem_exec_capture
results: parsing output: 67/ for test: gem_workarounds
results: parsing output: 68/ for test: kms_atomic_interruptible
results: parsing output: 69/ for test: gem_wait
results: parsing output: 70/ for test: i915_power
results: parsing output: 71/ for test: gem_ctx_shared
results: parsing output: 72/ for test: gem_busy
results: parsing output: 73/ for test: perf
results: parsing output: 74/ for test: kms_draw_crc
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_plane_alpha_blend
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: i915_pm_rpm
results: parsing output: 79/ for test: gem_exec_store
results: parsing output: 80/ for test: kms_cursor_legacy
results: parsing output: 81/ for test: gem_ctx_bad_destroy
results: parsing output: 82/ for test: api_intel_bb
results: parsing output: 83/ for test: kms_psr
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_ccs
results: parsing output: 88/ for test: gem_exec_fence
results: parsing output: 89/ for test: kms_color
results: parsing output: 90/ for test: gem_exec_reloc
results: parsing output: 91/ for test: gem_spin_batch
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: kms_plane_cursor
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: kms_psr
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: kms_ccs
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_getfb
results: parsing output: 102/ for test: kms_plane_scaling
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: core_getversion
results: parsing output: 105/ for test: i915_getparams_basic
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: gem_softpin
results: parsing output: 108/ for test: kms_flip
results: parsing output: 109/ for test: sysfs_timeslice_duration
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gem_create
results: parsing output: 112/ for test: gen9_exec_parse
results: parsing output: 113/ for test: perf_pmu
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: gem_exec_whisper
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: gem_exec_fence
results: parsing output: 122/ for test: kms_cursor_crc
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_psr2_sf
results: parsing output: 125/ for test: gem_tiling_max_stride
results: parsing output: 126/ for test: gem_ctx_persistence
results: parsing output: 127/ for test: gem_mmap_offset
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: gem_render_copy
results: parsing output: 130/ for test: kms_properties
results: parsing output: 131/ for test: kms_pm_lpsp
results: parsing output: 132/ for test: kms_psr2_sf
Done.