[57.610606] Initializing watchdogs
[57.610651]   /dev/watchdog0
[57.624592] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.628781] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.630973] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.634628] [001/133] (960s left) kms_feature_discovery (display-1x)
[57.751632] Starting subtest: display-1x
[57.753830] Subtest display-1x: SUCCESS (0.000s)
[57.818903] [002/133] (960s left) kms_universal_plane (universal-plane-functional)
[57.913068] Starting subtest: universal-plane-functional
[57.914937] Starting dynamic subtest: pipe-A-eDP-1
[61.786884] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.884s)
[61.795355] Starting dynamic subtest: pipe-B-eDP-1
[66.262636] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.484s)
[66.272216] Starting dynamic subtest: pipe-C-eDP-1
[70.609417] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.349s)
[70.617789] Starting dynamic subtest: pipe-D-eDP-1
[74.909264] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.301s)
[74.911603] Subtest universal-plane-functional: SUCCESS (17.021s)
[75.781953] [003/133] (942s left) kms_vblank (query-forked-busy)
[75.878191] Starting subtest: query-forked-busy
[75.880137] Starting dynamic subtest: pipe-A-eDP-1
[77.757720] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.879s)
[77.774548] Starting dynamic subtest: pipe-D-eDP-1
[80.350556] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.593s)
[80.352853] Subtest query-forked-busy: SUCCESS (4.476s)
[81.154727] [004/133] (937s left) kms_color_pipeline (plane-lut1d-pre-ctm3x4)
[81.256903] Starting subtest: plane-lut1d-pre-ctm3x4
[81.428460] Starting dynamic subtest: pipe-A-plane-0
[81.753592] Dynamic subtest pipe-A-plane-0: FAIL (0.153s)
[81.760172] Starting dynamic subtest: pipe-A-plane-1
[81.974061] Dynamic subtest pipe-A-plane-1: FAIL (0.166s)
[81.982595] Starting dynamic subtest: pipe-A-plane-2
[82.211076] Dynamic subtest pipe-A-plane-2: FAIL (0.166s)
[83.180597] Starting dynamic subtest: pipe-B-plane-0
[83.603279] Dynamic subtest pipe-B-plane-0: FAIL (0.152s)
[83.611803] Starting dynamic subtest: pipe-B-plane-1
[83.925906] Dynamic subtest pipe-B-plane-1: FAIL (0.155s)
[83.934160] Starting dynamic subtest: pipe-B-plane-2
[84.232225] Dynamic subtest pipe-B-plane-2: FAIL (0.166s)
[84.869966] Starting dynamic subtest: pipe-C-plane-0
[85.377563] Dynamic subtest pipe-C-plane-0: FAIL (0.140s)
[85.386524] Starting dynamic subtest: pipe-C-plane-1
[85.792217] Dynamic subtest pipe-C-plane-1: FAIL (0.612s)
[85.801329] Starting dynamic subtest: pipe-C-plane-2
[86.165348] Dynamic subtest pipe-C-plane-2: FAIL (0.160s)
[87.002989] Starting dynamic subtest: pipe-D-plane-0
[87.597948] Dynamic subtest pipe-D-plane-0: FAIL (0.129s)
[87.607065] Starting dynamic subtest: pipe-D-plane-1
[88.065236] Dynamic subtest pipe-D-plane-1: FAIL (0.608s)
[88.072023] Starting dynamic subtest: pipe-D-plane-2
[88.503081] Dynamic subtest pipe-D-plane-2: FAIL (0.534s)
[88.568828] Subtest plane-lut1d-pre-ctm3x4: FAIL (7.312s)
[89.374954] [005/133] (928s left) kms_lease (lease-unleased-crtc)
[89.477659] Starting subtest: lease-unleased-crtc
[89.479900] Starting dynamic subtest: pipe-A-eDP-1
[89.766679] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[89.774654] Starting dynamic subtest: pipe-B-eDP-1
[90.534875] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.767s)
[90.543999] Starting dynamic subtest: pipe-C-eDP-1
[90.546397] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[90.553129] Starting dynamic subtest: pipe-D-eDP-1
[90.555043] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[90.557033] Subtest lease-unleased-crtc: SUCCESS (1.069s)
[90.901309] [006/133] (927s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[91.002157] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[91.006180] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[91.089104] [007/133] (927s left) kms_flip (flip-vs-blocking-wf-vblank)
[91.185623] Starting subtest: flip-vs-blocking-wf-vblank
[91.187519] Starting dynamic subtest: A-eDP1
[100.148223] Dynamic subtest A-eDP1: SUCCESS (8.963s)
[100.158075] Starting dynamic subtest: B-eDP1
[109.087223] Dynamic subtest B-eDP1: SUCCESS (8.937s)
[109.097708] Starting dynamic subtest: C-eDP1
[118.015738] Dynamic subtest C-eDP1: SUCCESS (8.926s)
[118.024241] Starting dynamic subtest: D-eDP1
[126.952898] Dynamic subtest D-eDP1: SUCCESS (8.936s)
[126.957695] Subtest flip-vs-blocking-wf-vblank: SUCCESS (35.775s)
[128.184681] [008/133] (890s left) kms_cursor_legacy (single-move)
[128.305504] Starting subtest: single-move
[128.307744] Starting dynamic subtest: pipe-A
[133.686632] Dynamic subtest pipe-A: SUCCESS (5.378s)
[133.694654] Starting dynamic subtest: pipe-B
[139.068901] Dynamic subtest pipe-B: SUCCESS (5.380s)
[139.080273] Starting dynamic subtest: pipe-C
[144.450859] Dynamic subtest pipe-C: SUCCESS (5.379s)
[144.462951] Starting dynamic subtest: pipe-D
[149.833042] Dynamic subtest pipe-D: SUCCESS (5.380s)
[149.842700] Starting dynamic subtest: all-pipes
[155.222729] Dynamic subtest all-pipes: SUCCESS (5.387s)
[155.225232] Subtest single-move: SUCCESS (26.914s)
[155.364951] [009/133] (863s left) kms_hdr (invalid-hdr)
[155.471364] Starting subtest: invalid-hdr
[157.598132] Starting dynamic subtest: pipe-A-eDP-1
[160.465682] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.868s)
[160.468076] Subtest invalid-hdr: SUCCESS (4.997s)
[160.582722] [010/133] (857s left) sw_sync (sync_merge_same)
[160.617496] Starting subtest: sync_merge_same
[160.619705] Subtest sync_merge_same: SUCCESS (0.000s)
[160.679424] [011/133] (857s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-move)
[160.775662] Starting subtest: fbc-2p-scndscrn-spr-indfb-move
[160.780149] Subtest fbc-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[160.876039] [012/133] (857s left) kms_psr (fbc-psr2-sprite-blt)
[160.976399] Starting subtest: fbc-psr2-sprite-blt
[160.978568] Starting dynamic subtest: eDP-1
[160.982510] Dynamic subtest eDP-1: SKIP (0.000s)
[160.986509] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[161.044995] [013/133] (857s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[161.253859] Starting subtest: cursor-rapid-movement-128x128
[161.270301] Starting dynamic subtest: pipe-A-eDP-1
[162.870133] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.597s)
[162.894399] Starting dynamic subtest: pipe-D-eDP-1
[164.071932] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.181s)
[164.074276] Subtest cursor-rapid-movement-128x128: SUCCESS (2.816s)
[164.926407] [014/133] (853s left) gem_mmap (basic-small-bo)
[164.985054] Subtest basic-small-bo: SKIP (0.000s)
[165.039159] [015/133] (853s left) kms_async_flips (invalid-async-flip-atomic)
[165.127890] Starting subtest: invalid-async-flip-atomic
[165.501056] Starting dynamic subtest: pipe-A-eDP-1
[166.345689] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.846s)
[166.701753] Starting dynamic subtest: pipe-B-eDP-1
[167.481462] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.785s)
[167.837510] Starting dynamic subtest: pipe-C-eDP-1
[168.605187] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.774s)
[168.959126] Starting dynamic subtest: pipe-D-eDP-1
[169.731399] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.779s)
[169.733601] Subtest invalid-async-flip-atomic: SUCCESS (4.605s)
[170.882332] [016/133] (847s left) kms_cursor_crc (cursor-sliding-512x512)
[171.155863] Starting subtest: cursor-sliding-512x512
[171.160362] Subtest cursor-sliding-512x512: SKIP (0.001s)
[171.316403] [017/133] (847s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[171.375265] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[171.433412] [018/133] (847s left) gem_flink_basic (double-flink)
[171.490014] Starting subtest: double-flink
[171.491921] Subtest double-flink: SUCCESS (0.000s)
[171.587627] [019/133] (847s left) core_auth (getclient-master-drop)
[171.623786] Starting subtest: getclient-master-drop
[171.645981] Subtest getclient-master-drop: SUCCESS (0.026s)
[171.745663] [020/133] (847s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[171.834332] Starting subtest: crc-primary-rotation-180-4-tiled-lnl-ccs
[171.838171] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[172.909072] [021/133] (845s left) i915_module_load (reload-no-display)
[172.944085] Starting subtest: reload-no-display
[177.298347] Subtest reload-no-display: SUCCESS (4.354s)
[177.326057] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[177.329254] [022/133] (841s left) gem_exec_flush (basic-uc-pro-default)
[178.846752] Starting subtest: basic-uc-pro-default
[184.327238] Subtest basic-uc-pro-default: SUCCESS (5.480s)
[184.486116] [FACT gem_exec_flush (basic-uc-pro-default)] new: kernel.kmod_is_loaded.i915: true
[184.489977] [023/133] (834s left) sysfs_preempt_timeout (invalid)
[184.565086] Starting subtest: invalid
[184.567362] Starting dynamic subtest: vecs0
[184.569699] Dynamic subtest vecs0: SUCCESS (0.000s)
[184.578322] Starting dynamic subtest: ccs0
[184.580173] Dynamic subtest ccs0: SUCCESS (0.000s)
[184.586604] Starting dynamic subtest: vcs0
[184.588838] Dynamic subtest vcs0: SUCCESS (0.000s)
[184.595622] Starting dynamic subtest: bcs0
[184.597705] Dynamic subtest bcs0: SUCCESS (0.000s)
[184.604222] Starting dynamic subtest: rcs0
[184.606053] Dynamic subtest rcs0: SUCCESS (0.000s)
[184.612394] Starting dynamic subtest: vcs1
[184.614253] Dynamic subtest vcs1: SUCCESS (0.000s)
[184.616074] Subtest invalid: SUCCESS (0.008s)
[184.651376] [024/133] (834s left) kms_psr (psr2-cursor-blt)
[184.751976] Starting subtest: psr2-cursor-blt
[184.754304] Starting dynamic subtest: eDP-1
[186.484636] Dynamic subtest eDP-1: SUCCESS (1.733s)
[186.487072] Subtest psr2-cursor-blt: SUCCESS (1.734s)
[187.279742] [025/133] (831s left) kms_chamelium_frames (dp-crc-multiple)
[187.657178] Subtest dp-crc-multiple: SKIP (0.000s)
[188.480975] [026/133] (830s left) kms_flip (basic-flip-vs-modeset)
[188.579209] Starting subtest: basic-flip-vs-modeset
[188.581364] Starting dynamic subtest: A-eDP1
[191.129380] Dynamic subtest A-eDP1: SUCCESS (2.549s)
[191.141328] Starting dynamic subtest: B-eDP1
[193.275046] Dynamic subtest B-eDP1: SUCCESS (2.146s)
[193.283768] Starting dynamic subtest: C-eDP1
[195.450327] Dynamic subtest C-eDP1: SUCCESS (2.175s)
[195.458948] Starting dynamic subtest: D-eDP1
[197.611720] Dynamic subtest D-eDP1: SUCCESS (2.159s)
[197.613986] Subtest basic-flip-vs-modeset: SUCCESS (9.036s)
[198.413125] [027/133] (820s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[198.514721] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[198.518835] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[198.607045] [028/133] (820s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[198.702633] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[198.707057] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[198.802955] [029/133] (820s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[198.909630] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[198.913774] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[198.979081] [030/133] (820s left) gem_exec_fence (parallel)
[199.160461] Starting subtest: parallel
[199.162970] Starting dynamic subtest: rcs0
[201.312123] Dynamic subtest rcs0: SUCCESS (2.152s)
[201.321856] Starting dynamic subtest: bcs0
[203.466872] Dynamic subtest bcs0: SUCCESS (2.154s)
[203.474161] Starting dynamic subtest: vcs0
[205.616679] Dynamic subtest vcs0: SUCCESS (2.149s)
[205.625469] Starting dynamic subtest: vcs1
[207.768075] Dynamic subtest vcs1: SUCCESS (2.151s)
[207.775494] Starting dynamic subtest: vecs0
[209.921552] Dynamic subtest vecs0: SUCCESS (2.153s)
[209.932308] Starting dynamic subtest: ccs0
[212.074332] Dynamic subtest ccs0: SUCCESS (2.152s)
[212.076432] Subtest parallel: SUCCESS (12.915s)
[212.965109] [FACT gem_exec_fence (parallel)] new: kernel.kmod_is_loaded.vgem: true
[212.970599] [031/133] (806s left) api_intel_bb (blit-reloc-purge-cache)
[213.018096] Starting subtest: blit-reloc-purge-cache
[213.022625] Subtest blit-reloc-purge-cache: SKIP (0.001s)
[213.128357] [032/133] (806s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[213.187323] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[213.191813] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[213.369216] [033/133] (805s left) i915_pm_rpm (gem-execbuf-stress-pc8)
[215.094079] Starting subtest: gem-execbuf-stress-pc8
[215.145900] Subtest gem-execbuf-stress-pc8: SKIP (0.050s)
[215.635896] [034/133] (803s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs)
[215.729275] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs
[215.731548] Starting dynamic subtest: pipe-A-eDP-1
[215.753897] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[215.763743] Starting dynamic subtest: pipe-B-eDP-1
[215.784823] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[215.792628] Starting dynamic subtest: pipe-C-eDP-1
[215.814510] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[215.823048] Starting dynamic subtest: pipe-D-eDP-1
[215.845083] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[215.849348] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[217.979583] [035/133] (801s left) gen3_render_tiledy_blits
[218.101262] [036/133] (801s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[218.197053] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[218.201297] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.000s)
[218.263228] [037/133] (801s left) perf_pmu (pmu-read)
[218.305633] Starting subtest: pmu-read
[218.307816] Subtest pmu-read: SUCCESS (0.002s)
[218.417371] [038/133] (801s left) kms_psr (fbc-psr-primary-mmap-gtt)
[218.509205] Starting subtest: fbc-psr-primary-mmap-gtt
[218.511357] Starting dynamic subtest: eDP-1
[218.515424] Dynamic subtest eDP-1: SKIP (0.000s)
[218.520273] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[218.577645] [039/133] (800s left) kms_flip (2x-plain-flip-interruptible)
[218.670476] Starting subtest: 2x-plain-flip-interruptible
[218.682474] Subtest 2x-plain-flip-interruptible: SKIP (0.011s)
[218.759130] [040/133] (800s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[218.861028] Starting subtest: primary-x-tiled-reflect-x-180
[225.848248] Subtest primary-x-tiled-reflect-x-180: SUCCESS (6.990s)
[227.046421] [041/133] (792s left) gem_userptr_blits (create-destroy-sync)
[227.122674] Starting subtest: create-destroy-sync
[232.132228] Subtest create-destroy-sync: SUCCESS (5.008s)
[232.244690] [042/133] (787s left) syncobj_timeline (multi-wait-for-submit-signaled)
[232.302053] Starting subtest: multi-wait-for-submit-signaled
[232.304529] Subtest multi-wait-for-submit-signaled: SUCCESS (0.001s)
[232.413989] [043/133] (787s left) gem_spin_batch (legacy-resubmit)
[232.483434] Starting subtest: legacy-resubmit
[232.485699] Starting dynamic subtest: default
[232.490834] Dynamic subtest default: SUCCESS (0.009s)
[232.499802] Starting dynamic subtest: render
[232.502328] Dynamic subtest render: SUCCESS (0.001s)
[232.516811] Starting dynamic subtest: bsd
[232.518824] Dynamic subtest bsd: SUCCESS (0.005s)
[232.526500] Starting dynamic subtest: bsd1
[232.528413] Dynamic subtest bsd1: SUCCESS (0.001s)
[232.535953] Starting dynamic subtest: bsd2
[232.537895] Dynamic subtest bsd2: SUCCESS (0.004s)
[232.545327] Starting dynamic subtest: blt
[232.547221] Dynamic subtest blt: SUCCESS (0.004s)
[232.554681] Starting dynamic subtest: vebox
[232.556605] Dynamic subtest vebox: SUCCESS (0.004s)
[232.558494] Subtest legacy-resubmit: SUCCESS (0.035s)
[232.763609] [044/133] (787s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[232.878151] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[240.174914] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.298s)
[240.300931] [045/133] (779s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[240.389409] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[240.391542] Starting dynamic subtest: pipe-A
[241.437230] Dynamic subtest pipe-A: SUCCESS (1.050s)
[241.450361] Starting dynamic subtest: pipe-B
[243.567125] Dynamic subtest pipe-B: SUCCESS (2.128s)
[243.574664] Starting dynamic subtest: pipe-C
[244.761771] Dynamic subtest pipe-C: SUCCESS (1.193s)
[244.774716] Starting dynamic subtest: pipe-D
[245.996472] Dynamic subtest pipe-D: SUCCESS (1.234s)
[245.998695] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.610s)
[247.191607] [046/133] (772s left) fbdev (unaligned-write)
[247.242064] Starting subtest: unaligned-write
[247.341060] Subtest unaligned-write: SUCCESS (0.098s)
[247.402058] [047/133] (772s left) gem_bad_reloc (negative-reloc-bltcopy)
[247.462886] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[247.542065] [048/133] (772s left) syncobj_wait (wait-all-snapshot)
[247.592929] Starting subtest: wait-all-snapshot
[247.674831] Subtest wait-all-snapshot: SUCCESS (0.082s)
[247.753851] [049/133] (772s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[247.846840] Starting subtest: fbc-rgb101010-draw-render
[252.492756] Subtest fbc-rgb101010-draw-render: SUCCESS (4.650s)
[253.686567] [050/133] (766s left) kms_force_connector_basic (force-load-detect)
[253.763971] Starting subtest: force-load-detect
[253.768530] Subtest force-load-detect: SKIP (0.000s)
[253.875838] [051/133] (766s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[253.982571] Starting subtest: psr-indfb-scaledprimary
[259.805220] Subtest psr-indfb-scaledprimary: SUCCESS (5.826s)
[259.926076] [052/133] (760s left) gem_exec_schedule (preempt-self)
[259.991954] Starting subtest: preempt-self
[259.993786] Starting dynamic subtest: rcs0
[260.007689] Dynamic subtest rcs0: SUCCESS (0.022s)
[260.019082] Starting dynamic subtest: bcs0
[260.030841] Dynamic subtest bcs0: SUCCESS (0.024s)
[260.042728] Starting dynamic subtest: vcs0
[260.051565] Dynamic subtest vcs0: SUCCESS (0.020s)
[260.060166] Starting dynamic subtest: vcs1
[260.070636] Dynamic subtest vcs1: SUCCESS (0.018s)
[260.083182] Starting dynamic subtest: vecs0
[260.094682] Dynamic subtest vecs0: SUCCESS (0.023s)
[260.105040] Starting dynamic subtest: ccs0
[260.114615] Dynamic subtest ccs0: SUCCESS (0.019s)
[260.116891] Subtest preempt-self: SUCCESS (0.131s)
[261.988568] [053/133] (758s left) gem_lmem_swapping (heavy-random)
[262.049058] Subtest heavy-random: SKIP (0.000s)
[262.090539] [054/133] (758s left) syncobj_wait (reset-during-wait-for-submit)
[262.150085] Starting subtest: reset-during-wait-for-submit
[262.252108] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[262.330292] [055/133] (757s left) kms_rotation_crc (primary-rotation-90)
[262.442116] Starting subtest: primary-rotation-90
[262.446570] Subtest primary-rotation-90: SKIP (0.001s)
[262.527363] [056/133] (757s left) gem_mmap_gtt (basic-read)
[262.585350] Subtest basic-read: SKIP (0.000s)
[262.652786] [057/133] (757s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[262.733849] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[262.736116] Starting dynamic subtest: pipe-A-eDP-1
[262.760294] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[262.770181] Starting dynamic subtest: pipe-B-eDP-1
[262.792589] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[262.799976] Starting dynamic subtest: pipe-C-eDP-1
[262.822956] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[262.831783] Starting dynamic subtest: pipe-D-eDP-1
[262.855450] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[262.859540] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[264.996067] [058/133] (755s left) kms_plane_multiple (tiling-none)
[265.086815] Starting subtest: tiling-none
[265.089048] Starting dynamic subtest: pipe-A-eDP-1
[268.740925] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.655s)
[268.749868] Starting dynamic subtest: pipe-B-eDP-1
[273.172886] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.430s)
[273.181909] Starting dynamic subtest: pipe-C-eDP-1
[277.482799] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.306s)
[277.493134] Starting dynamic subtest: pipe-D-eDP-1
[281.839269] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.356s)
[281.841572] Subtest tiling-none: SUCCESS (16.752s)
[282.636629] [059/133] (737s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[282.744289] Starting subtest: yf-tiled-addfb-size-offset-overflow
[282.748741] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[282.849458] [060/133] (737s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[282.938762] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[282.943069] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[283.025298] [061/133] (737s left) gem_cs_tlb (engines)
[283.097940] Starting subtest: engines
[283.100572] Starting dynamic subtest: rcs0
[285.254260] Dynamic subtest rcs0: SUCCESS (2.153s)
[285.265341] Starting dynamic subtest: bcs0
[287.406787] Dynamic subtest bcs0: SUCCESS (2.151s)
[287.417219] Starting dynamic subtest: vcs0
[289.559961] Dynamic subtest vcs0: SUCCESS (2.151s)
[289.570895] Starting dynamic subtest: vcs1
[291.712556] Dynamic subtest vcs1: SUCCESS (2.152s)
[291.720484] Starting dynamic subtest: vecs0
[293.868770] Dynamic subtest vecs0: SUCCESS (2.153s)
[293.879608] Starting dynamic subtest: ccs0
[296.022570] Dynamic subtest ccs0: SUCCESS (2.152s)
[296.024993] Subtest engines: SUCCESS (12.921s)
[296.138790] [062/133] (724s left) kms_flip (basic-plain-flip)
[296.233075] Starting subtest: basic-plain-flip
[296.235220] Starting dynamic subtest: A-eDP1
[297.939638] Dynamic subtest A-eDP1: SUCCESS (1.708s)
[297.950088] Starting dynamic subtest: B-eDP1
[299.574783] Dynamic subtest B-eDP1: SUCCESS (1.632s)
[299.585329] Starting dynamic subtest: C-eDP1
[301.193573] Dynamic subtest C-eDP1: SUCCESS (1.617s)
[301.205253] Starting dynamic subtest: D-eDP1
[302.834180] Dynamic subtest D-eDP1: SUCCESS (1.638s)
[302.836536] Subtest basic-plain-flip: SUCCESS (6.606s)
[304.000593] [063/133] (716s left) kms_chamelium_hpd (hdmi-hpd-after-suspend)
[304.382170] Subtest hdmi-hpd-after-suspend: SKIP (0.000s)
[305.207301] [064/133] (715s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[305.304035] Starting subtest: yf-tiled-32bpp-rotate-180
[305.308406] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[305.388804] [065/133] (715s left) i915_drm_fdinfo (most-busy-check-all)
[305.436955] Starting subtest: most-busy-check-all
[305.439216] Starting dynamic subtest: rcs0
[305.443346] Dynamic subtest rcs0: SKIP (0.003s)
[305.451475] Starting dynamic subtest: bcs0
[305.455496] Dynamic subtest bcs0: SKIP (0.000s)
[305.463426] Starting dynamic subtest: vcs0
[305.467730] Dynamic subtest vcs0: SKIP (0.000s)
[305.475269] Starting dynamic subtest: vcs1
[305.479281] Dynamic subtest vcs1: SKIP (0.000s)
[305.486719] Starting dynamic subtest: vecs0
[305.490856] Dynamic subtest vecs0: SKIP (0.000s)
[305.498313] Starting dynamic subtest: ccs0
[305.504430] Dynamic subtest ccs0: SKIP (0.000s)
[305.508450] Subtest most-busy-check-all: SKIP (0.008s)
[305.543720] [066/133] (715s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[305.645491] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[306.680210] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.034s)
[306.787398] [067/133] (713s left) gem_exec_capture (capture)
[306.852588] Starting subtest: capture
[306.854201] Starting dynamic subtest: rcs0-smem
[307.686752] Dynamic subtest rcs0-smem: SUCCESS (0.833s)
[307.694248] Starting dynamic subtest: bcs0-smem
[308.511886] Dynamic subtest bcs0-smem: SUCCESS (0.823s)
[308.519607] Starting dynamic subtest: vcs0-smem
[309.329038] Dynamic subtest vcs0-smem: SUCCESS (0.816s)
[309.336768] Starting dynamic subtest: vcs1-smem
[310.179081] Dynamic subtest vcs1-smem: SUCCESS (0.854s)
[310.186938] Starting dynamic subtest: vecs0-smem
[311.064167] Dynamic subtest vecs0-smem: FAIL (0.814s)
[311.073217] Starting dynamic subtest: ccs0-smem
[312.319763] Dynamic subtest ccs0-smem: SUCCESS (1.323s)
[312.322070] Subtest capture: FAIL (5.467s)
[312.444816] [068/133] (708s left) gen7_exec_parse (basic-allowed)
[312.500538] Subtest basic-allowed: SKIP (0.000s)
[312.577464] [069/133] (708s left) kms_invalid_mode (overflow-vrefresh)
[312.680176] Starting subtest: overflow-vrefresh
[312.682401] Starting dynamic subtest: pipe-A-eDP-1
[312.962066] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[312.970593] Starting dynamic subtest: pipe-B-eDP-1
[312.972805] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[312.980762] Starting dynamic subtest: pipe-C-eDP-1
[312.983069] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[312.994574] Starting dynamic subtest: pipe-D-eDP-1
[312.996770] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[312.998899] Subtest overflow-vrefresh: SUCCESS (0.289s)
[313.775933] [070/133] (707s left) i915_query (query-regions-garbage-items)
[313.841850] Starting subtest: query-regions-garbage-items
[313.844163] Subtest query-regions-garbage-items: SUCCESS (0.000s)
[313.962082] [071/133] (706s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[314.036036] Starting subtest: stress-mm-invalidate-close-overlap
[316.183687] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[316.302505] [072/133] (704s left) syncobj_wait (multi-wait-for-submit-submitted-signaled)
[316.363616] Starting subtest: multi-wait-for-submit-submitted-signaled
[316.366225] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[316.461885] [073/133] (704s left) gen7_exec_parse (chained-batch)
[316.519911] Subtest chained-batch: SKIP (0.000s)
[316.577376] [074/133] (704s left) gem_exec_store (basic)
[316.633790] Starting subtest: basic
[316.754576] Subtest basic: SUCCESS (0.121s)
[316.938045] [075/133] (704s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[317.001524] Starting subtest: yf-tiled-ccs-to-x-tiled
[317.005868] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[317.184031] [076/133] (703s left) gem_softpin (noreloc-interruptible)
[317.237808] Starting subtest: noreloc-interruptible
[322.674228] Subtest noreloc-interruptible: SUCCESS (5.435s)
[322.793131] [077/133] (698s left) syncobj_eventfd (timeline-wait)
[322.850621] Starting subtest: timeline-wait
[322.858967] Subtest timeline-wait: SUCCESS (0.010s)
[322.933580] [078/133] (698s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[323.035368] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[323.148995] [079/133] (698s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[323.238944] Starting subtest: addfb25-y-tiled-legacy
[323.240848] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[323.347778] [080/133] (697s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[323.450312] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[323.454336] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[323.569970] [081/133] (697s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[323.664610] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[323.666704] Starting dynamic subtest: pipe-A-eDP-1
[323.688088] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[323.697550] Starting dynamic subtest: pipe-B-eDP-1
[323.714470] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[323.721711] Starting dynamic subtest: pipe-C-eDP-1
[323.738670] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[323.745827] Starting dynamic subtest: pipe-D-eDP-1
[323.763485] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[323.766796] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[325.847788] [082/133] (695s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[325.942052] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[325.946221] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[326.049962] [083/133] (695s left) perf_pmu (busy)
[326.087839] Starting subtest: busy
[326.090223] Starting dynamic subtest: rcs0
[326.677251] Dynamic subtest rcs0: SUCCESS (0.589s)
[326.686937] Starting dynamic subtest: bcs0
[327.249083] Dynamic subtest bcs0: SUCCESS (0.569s)
[327.259964] Starting dynamic subtest: vcs0
[328.918048] Dynamic subtest vcs0: SUCCESS (1.665s)
[328.929605] Starting dynamic subtest: vcs1
[330.901419] Dynamic subtest vcs1: SUCCESS (1.980s)
[330.912879] Starting dynamic subtest: vecs0
[332.885670] Dynamic subtest vecs0: SUCCESS (1.981s)
[332.897274] Starting dynamic subtest: ccs0
[333.452482] Dynamic subtest ccs0: SUCCESS (0.566s)
[333.455235] Subtest busy: SUCCESS (7.366s)
[333.552858] [084/133] (687s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[333.612251] Starting subtest: invalid-bsd2-flag-on-vebox
[333.614611] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[333.711254] [085/133] (687s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[333.805966] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[333.810082] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[333.884698] [086/133] (687s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[333.976687] Starting subtest: x-tiled-32bpp-rotate-0
[335.356434] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.380s)
[335.491155] [087/133] (686s left) kms_big_fb (linear-16bpp-rotate-90)
[335.581835] Starting subtest: linear-16bpp-rotate-90
[335.602022] Subtest linear-16bpp-rotate-90: SKIP (0.020s)
[335.703043] [088/133] (685s left) gem_exec_schedule (pi-userfault)
[335.891980] Starting subtest: pi-userfault
[335.894225] Starting dynamic subtest: rcs0
[335.902330] Dynamic subtest rcs0: SUCCESS (0.012s)
[335.912451] Starting dynamic subtest: bcs0
[335.914322] Dynamic subtest bcs0: SUCCESS (0.007s)
[335.922790] Starting dynamic subtest: vcs0
[335.924951] Dynamic subtest vcs0: SUCCESS (0.010s)
[335.933186] Starting dynamic subtest: vcs1
[335.935030] Dynamic subtest vcs1: SUCCESS (0.007s)
[335.944088] Starting dynamic subtest: vecs0
[335.946293] Dynamic subtest vecs0: SUCCESS (0.008s)
[335.954204] Starting dynamic subtest: ccs0
[335.956083] Dynamic subtest ccs0: SUCCESS (0.007s)
[335.957912] Subtest pi-userfault: SUCCESS (0.055s)
[336.130209] [089/133] (685s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[336.233203] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[336.237138] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[336.299520] [090/133] (685s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[336.399185] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[336.403260] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[336.490968] [091/133] (685s left) kms_psr (psr-cursor-plane-move)
[336.588714] Starting subtest: psr-cursor-plane-move
[336.590949] Starting dynamic subtest: eDP-1
[338.347730] Dynamic subtest eDP-1: SUCCESS (1.758s)
[338.350368] Subtest psr-cursor-plane-move: SUCCESS (1.758s)
[339.204870] [092/133] (682s left) gem_exec_balancer (indices)
[339.272835] Starting subtest: indices
[341.811046] Subtest indices: SUCCESS (2.538s)
[342.158422] [093/133] (679s left) syncobj_wait (multi-wait-for-submit-signaled)
[342.220959] Starting subtest: multi-wait-for-submit-signaled
[342.223156] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[342.308239] [094/133] (679s left) dumb_buffer (create-clear)
[342.370067] Starting subtest: create-clear
[376.599901] Subtest create-clear: SUCCESS (34.226s)
[376.704482] [095/133] (645s left) gem_softpin (softpin)
[376.770604] Starting subtest: softpin
[408.993736] Subtest softpin: SUCCESS (32.218s)
[409.113647] [096/133] (612s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-upscaling)
[409.213848] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-upscaling
[409.216080] Starting dynamic subtest: pipe-A-default-mode
[411.913801] Dynamic subtest pipe-A-default-mode: SUCCESS (2.701s)
[411.916299] Subtest flip-32bpp-xtile-to-64bpp-xtile-upscaling: SUCCESS (2.702s)
[412.785459] [097/133] (609s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[412.875268] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[412.879196] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[412.972447] [098/133] (609s left) vgem_basic (second-client)
[413.024502] Starting subtest: second-client
[413.046700] Subtest second-client: SUCCESS (0.022s)
[413.106393] [099/133] (608s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[413.187768] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[413.190049] Starting dynamic subtest: pipe-A
[413.206834] Dynamic subtest pipe-A: SKIP (0.016s)
[413.215523] Starting dynamic subtest: pipe-B
[413.239395] Dynamic subtest pipe-B: SKIP (0.030s)
[413.248285] Starting dynamic subtest: pipe-C
[413.269259] Dynamic subtest pipe-C: SKIP (0.029s)
[413.277930] Starting dynamic subtest: pipe-D
[413.297825] Dynamic subtest pipe-D: SKIP (0.029s)
[413.301580] Subtest plane-downscale-factor-0-5-with-pixel-format: SKIP (0.107s)
[413.400363] [100/133] (608s left) syncobj_timeline (invalid-wait-illegal-handle)
[413.459189] Starting subtest: invalid-wait-illegal-handle
[413.461433] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[413.549666] [101/133] (608s left) gem_ctx_shared (disjoint-timelines)
[413.612140] Starting subtest: disjoint-timelines
[413.640690] Subtest disjoint-timelines: SUCCESS (0.028s)
[413.894373] [102/133] (608s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[413.990055] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[413.992466] Starting dynamic subtest: pipe-A-eDP-1
[414.015653] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[414.026101] Starting dynamic subtest: pipe-B-eDP-1
[414.048202] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[414.055800] Starting dynamic subtest: pipe-C-eDP-1
[414.077604] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[414.085545] Starting dynamic subtest: pipe-D-eDP-1
[414.107466] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[414.111843] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[416.254381] [103/133] (605s left) kms_busy (extended-modeset-hang-oldfb)
[416.343968] Starting subtest: extended-modeset-hang-oldfb
[416.346302] Starting dynamic subtest: pipe-A
[427.943981] Dynamic subtest pipe-A: SUCCESS (11.598s)
[427.953769] Starting dynamic subtest: pipe-D
[440.218851] Dynamic subtest pipe-D: SUCCESS (12.273s)
[440.221041] Subtest extended-modeset-hang-oldfb: SUCCESS (23.875s)
[441.012930] [104/133] (581s left) kms_content_protection (atomic-hdcp14)
[441.158057] Starting subtest: atomic-hdcp14
[441.162390] Subtest atomic-hdcp14: SKIP (0.000s)
[441.271331] [105/133] (581s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[441.329060] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[441.433886] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[441.512266] [106/133] (580s left) kms_pm_rpm (i2c)
[443.032601] Starting subtest: i2c
[448.736074] Subtest i2c: SUCCESS (5.705s)
[448.875749] [107/133] (573s left) kms_plane_alpha_blend (constant-alpha-min)
[448.977132] Starting subtest: constant-alpha-min
[449.261350] Starting dynamic subtest: pipe-A-eDP-1
[449.974233] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.710s)
[449.983745] Starting dynamic subtest: pipe-D-eDP-1
[451.521343] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.548s)
[451.523715] Subtest constant-alpha-min: SUCCESS (2.546s)
[452.373163] [108/133] (570s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[452.468712] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[452.472930] Subtest flip-64bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.000s)
[452.569305] [109/133] (570s left) kms_psr (fbc-pr-basic)
[452.651248] Starting subtest: fbc-pr-basic
[452.655240] Subtest fbc-pr-basic: SKIP (0.000s)
[452.725557] [110/133] (569s left) gem_mmap_gtt (zero-extend)
[452.794591] Subtest zero-extend: SKIP (0.000s)
[452.853644] [111/133] (569s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt)
[452.944876] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt
[460.192936] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.251s)
[460.331804] [112/133] (562s left) kms_cursor_crc (cursor-offscreen-64x64)
[460.511555] Starting subtest: cursor-offscreen-64x64
[460.513705] Starting dynamic subtest: pipe-A-eDP-1
[462.445888] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.930s)
[462.473577] Starting dynamic subtest: pipe-D-eDP-1
[465.090703] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.624s)
[465.093089] Subtest cursor-offscreen-64x64: SUCCESS (4.580s)
[465.960068] [113/133] (556s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[466.069325] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[472.899956] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.835s)
[473.038582] [114/133] (549s left) gem_ctx_persistence (legacy-engines-mixed-process)
[473.102280] Starting subtest: legacy-engines-mixed-process
[473.104571] Starting dynamic subtest: render
[474.911213] Dynamic subtest render: SUCCESS (1.808s)
[474.922182] Starting dynamic subtest: bsd1
[476.889643] Dynamic subtest bsd1: SUCCESS (1.977s)
[476.899160] Starting dynamic subtest: bsd2
[478.881783] Dynamic subtest bsd2: SUCCESS (1.990s)
[478.892347] Starting dynamic subtest: blt
[480.848420] Dynamic subtest blt: SUCCESS (1.965s)
[480.858060] Starting dynamic subtest: vebox
[482.921829] Dynamic subtest vebox: SUCCESS (2.072s)
[482.924240] Subtest legacy-engines-mixed-process: SUCCESS (9.821s)
[483.001150] [115/133] (539s left) gem_exec_params (mmapped)
[483.066602] Starting subtest: mmapped
[483.071737] Subtest mmapped: SUCCESS (0.004s)
[483.189018] [116/133] (539s left) gem_pwrite (basic-random)
[483.236531] Subtest basic-random: SKIP (0.000s)
[483.299850] [117/133] (539s left) perf (short-reads)
[483.421637] Starting subtest: short-reads
[483.436950] Subtest short-reads: SUCCESS (0.018s)
[483.480139] [118/133] (539s left) gem_exec_reloc (basic-write-gtt-noreloc)
[483.540402] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[483.609765] [119/133] (539s left) syncobj_basic (bad-flags-handle-to-fd)
[483.660739] Starting subtest: bad-flags-handle-to-fd
[483.662669] Subtest bad-flags-handle-to-fd: SUCCESS (0.000s)
[483.753306] [120/133] (539s left) gem_render_tiled_blits (basic)
[483.804930] Subtest basic: SKIP (0.000s)
[483.869900] [121/133] (539s left) kms_cursor_legacy (flip-vs-cursor-toggle)
[483.984879] Starting subtest: flip-vs-cursor-toggle
[492.602812] Subtest flip-vs-cursor-toggle: SUCCESS (8.617s)
[493.429699] [122/133] (529s left) gem_basic (create-fd-close)
[493.493164] Starting subtest: create-fd-close
[493.497233] Subtest create-fd-close: SUCCESS (0.000s)
[493.606917] [123/133] (529s left) sw_sync (alloc_fence_invalid_timeline)
[493.635380] Starting subtest: alloc_fence_invalid_timeline
[493.637646] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[493.693020] [124/133] (529s left) gem_ctx_persistence (engines-persistence)
[493.750165] Starting subtest: engines-persistence
[493.752369] Starting dynamic subtest: rcs0
[494.547236] Dynamic subtest rcs0: SUCCESS (0.796s)
[494.558417] Starting dynamic subtest: bcs0
[495.307862] Dynamic subtest bcs0: SUCCESS (0.759s)
[495.318773] Starting dynamic subtest: vcs0
[496.081830] Dynamic subtest vcs0: SUCCESS (0.772s)
[496.094822] Starting dynamic subtest: vcs1
[496.851930] Dynamic subtest vcs1: SUCCESS (0.768s)
[496.863693] Starting dynamic subtest: vecs0
[497.616718] Dynamic subtest vecs0: SUCCESS (0.763s)
[497.627206] Starting dynamic subtest: ccs0
[498.385020] Dynamic subtest ccs0: SUCCESS (0.766s)
[498.387714] Subtest engines-persistence: SUCCESS (4.636s)
[498.476865] [125/133] (524s left) kms_psr (psr-sprite-mmap-gtt)
[498.562917] Starting subtest: psr-sprite-mmap-gtt
[498.565231] Starting dynamic subtest: eDP-1
[500.057071] Dynamic subtest eDP-1: SKIP (1.490s)
[500.062298] Subtest psr-sprite-mmap-gtt: SKIP (1.492s)
[500.214463] [126/133] (523s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[500.283117] Subtest writes-after-reads-snoop: SKIP (0.000s)
[500.364628] [127/133] (522s left) perf (polling-small-buf)
[500.469674] Starting subtest: polling-small-buf
[500.561953] Subtest polling-small-buf: SUCCESS (0.089s)
[500.609086] [128/133] (522s left) prime_self_import (basic-llseek-size)
[500.645768] Starting subtest: basic-llseek-size
[500.668076] Subtest basic-llseek-size: SUCCESS (0.022s)
[500.756776] [129/133] (522s left) syncobj_timeline (single-wait-available-submitted)
[500.806597] Starting subtest: single-wait-available-submitted
[500.808492] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[500.898410] [130/133] (522s left) kms_pm_rpm (basic-rte)
[500.932338] Starting subtest: basic-rte
[504.583069] Subtest basic-rte: SUCCESS (3.651s)
[506.083805] [131/133] (517s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[506.177630] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[506.182216] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[506.277493] [132/133] (517s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[506.371440] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[506.375874] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[506.478148] [133/133] (517s left) gem_exec_reloc (basic-gtt-cpu)
[506.531569] Subtest basic-gtt-cpu: SKIP (0.000s)
[506.602253] Closing watchdogs
results: parsing output: 0/ for test: kms_feature_discovery
results: parsing output: 1/ for test: kms_universal_plane
results: parsing output: 2/ for test: kms_vblank
results: parsing output: 3/ for test: kms_color_pipeline
results: parsing output: 4/ for test: kms_lease
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_flip
results: parsing output: 7/ for test: kms_cursor_legacy
results: parsing output: 8/ for test: kms_hdr
results: parsing output: 9/ for test: sw_sync
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_psr
results: parsing output: 12/ for test: kms_cursor_crc
results: parsing output: 13/ for test: gem_mmap
results: parsing output: 14/ for test: kms_async_flips
results: parsing output: 15/ for test: kms_cursor_crc
results: parsing output: 16/ for test: gem_mmap_gtt
results: parsing output: 17/ for test: gem_flink_basic
results: parsing output: 18/ for test: core_auth
results: parsing output: 19/ for test: kms_ccs
results: parsing output: 20/ for test: i915_module_load
results: parsing output: 21/ for test: gem_exec_flush
results: parsing output: 22/ for test: sysfs_preempt_timeout
results: parsing output: 23/ for test: kms_psr
results: parsing output: 24/ for test: kms_chamelium_frames
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_flip_scaled_crc
results: parsing output: 29/ for test: gem_exec_fence
results: parsing output: 30/ for test: api_intel_bb
results: parsing output: 31/ for test: gem_render_copy
results: parsing output: 32/ for test: i915_pm_rpm
results: parsing output: 33/ for test: kms_ccs
results: parsing output: 34/ for test: gen3_render_tiledy_blits
results: parsing output: 35/ for test: kms_psr2_sf
results: parsing output: 36/ for test: perf_pmu
results: parsing output: 37/ for test: kms_psr
results: parsing output: 38/ for test: kms_flip
results: parsing output: 39/ for test: kms_rotation_crc
results: parsing output: 40/ for test: gem_userptr_blits
results: parsing output: 41/ for test: syncobj_timeline
results: parsing output: 42/ for test: gem_spin_batch
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_plane_scaling
results: parsing output: 45/ for test: fbdev
results: parsing output: 46/ for test: gem_bad_reloc
results: parsing output: 47/ for test: syncobj_wait
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_force_connector_basic
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_exec_schedule
results: parsing output: 52/ for test: gem_lmem_swapping
results: parsing output: 53/ for test: syncobj_wait
results: parsing output: 54/ for test: kms_rotation_crc
results: parsing output: 55/ for test: gem_mmap_gtt
results: parsing output: 56/ for test: kms_ccs
results: parsing output: 57/ for test: kms_plane_multiple
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_cs_tlb
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: kms_chamelium_hpd
results: parsing output: 63/ for test: kms_big_fb
results: parsing output: 64/ for test: i915_drm_fdinfo
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: gem_exec_capture
results: parsing output: 67/ for test: gen7_exec_parse
results: parsing output: 68/ for test: kms_invalid_mode
results: parsing output: 69/ for test: i915_query
results: parsing output: 70/ for test: gem_userptr_blits
results: parsing output: 71/ for test: syncobj_wait
results: parsing output: 72/ for test: gen7_exec_parse
results: parsing output: 73/ for test: gem_exec_store
results: parsing output: 74/ for test: gem_render_copy
results: parsing output: 75/ for test: gem_softpin
results: parsing output: 76/ for test: syncobj_eventfd
results: parsing output: 77/ for test: kms_addfb_basic
results: parsing output: 78/ for test: kms_addfb_basic
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_ccs
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: perf_pmu
results: parsing output: 83/ for test: gem_exec_params
results: parsing output: 84/ for test: kms_plane
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_big_fb
results: parsing output: 87/ for test: gem_exec_schedule
results: parsing output: 88/ for test: kms_psr2_sf
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: gem_exec_balancer
results: parsing output: 92/ for test: syncobj_wait
results: parsing output: 93/ for test: dumb_buffer
results: parsing output: 94/ for test: gem_softpin
results: parsing output: 95/ for test: kms_flip_scaled_crc
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: vgem_basic
results: parsing output: 98/ for test: kms_plane_scaling
results: parsing output: 99/ for test: syncobj_timeline
results: parsing output: 100/ for test: gem_ctx_shared
results: parsing output: 101/ for test: kms_ccs
results: parsing output: 102/ for test: kms_busy
results: parsing output: 103/ for test: kms_content_protection
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: kms_pm_rpm
results: parsing output: 106/ for test: kms_plane_alpha_blend
results: parsing output: 107/ for test: kms_flip_scaled_crc
results: parsing output: 108/ for test: kms_psr
results: parsing output: 109/ for test: gem_mmap_gtt
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_cursor_crc
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_ctx_persistence
results: parsing output: 114/ for test: gem_exec_params
results: parsing output: 115/ for test: gem_pwrite
results: parsing output: 116/ for test: perf
results: parsing output: 117/ for test: gem_exec_reloc
results: parsing output: 118/ for test: syncobj_basic
results: parsing output: 119/ for test: gem_render_tiled_blits
results: parsing output: 120/ for test: kms_cursor_legacy
results: parsing output: 121/ for test: gem_basic
results: parsing output: 122/ for test: sw_sync
results: parsing output: 123/ for test: gem_ctx_persistence
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: gem_partial_pwrite_pread
results: parsing output: 126/ for test: perf
results: parsing output: 127/ for test: prime_self_import
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: kms_pm_rpm
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: gem_exec_reloc
Done.