[59.022415] Initializing watchdogs
[59.022462]   /dev/watchdog0
[59.037200] [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]
[59.042412] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.044871] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.048206] [001/133] (960s left) gem_busy (parallel)
[59.267984] Starting subtest: parallel
[59.270287] Starting dynamic subtest: rcs0
[60.886939] Dynamic subtest rcs0: SUCCESS (1.608s)
[60.897691] Starting dynamic subtest: bcs0
[62.933825] Dynamic subtest bcs0: SUCCESS (2.035s)
[62.943767] Starting dynamic subtest: vcs0
[64.921681] Dynamic subtest vcs0: SUCCESS (1.978s)
[64.932628] Starting dynamic subtest: vcs1
[66.900974] Dynamic subtest vcs1: SUCCESS (1.973s)
[66.908609] Starting dynamic subtest: vecs0
[68.886024] Dynamic subtest vecs0: SUCCESS (1.979s)
[68.896518] Starting dynamic subtest: ccs0
[70.871817] Dynamic subtest ccs0: SUCCESS (1.981s)
[70.874981] Subtest parallel: SUCCESS (11.560s)
[71.072807] [002/133] (948s left) syncobj_wait (multi-wait-submitted)
[71.138528] Starting subtest: multi-wait-submitted
[71.251414] Subtest multi-wait-submitted: SUCCESS (0.110s)
[71.322987] [003/133] (948s left) gem_exec_fence (syncobj-timeline-export)
[71.891626] Starting subtest: syncobj-timeline-export
[71.899084] Subtest syncobj-timeline-export: SUCCESS (0.012s)
[72.972991] [FACT gem_exec_fence (syncobj-timeline-export)] new: kernel.kmod_is_loaded.vgem: true
[72.976430] [004/133] (946s left) gem_exec_schedule (u-submit-late-slice)
[73.052989] Starting subtest: u-submit-late-slice
[73.055187] Starting dynamic subtest: rcs0
[73.104071] Dynamic subtest rcs0: SUCCESS (0.056s)
[73.114593] Starting dynamic subtest: bcs0
[73.150494] Dynamic subtest bcs0: SUCCESS (0.046s)
[73.160317] Starting dynamic subtest: vcs0
[73.196942] Dynamic subtest vcs0: SUCCESS (0.046s)
[73.204674] Starting dynamic subtest: vcs1
[73.243586] Dynamic subtest vcs1: SUCCESS (0.045s)
[73.256137] Starting dynamic subtest: vecs0
[73.296468] Dynamic subtest vecs0: SUCCESS (0.053s)
[73.306752] Starting dynamic subtest: ccs0
[73.346960] Dynamic subtest ccs0: SUCCESS (0.050s)
[73.349981] Subtest u-submit-late-slice: SUCCESS (0.300s)
[74.992574] [005/133] (944s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs)
[75.092854] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs
[75.095197] Starting dynamic subtest: pipe-A-eDP-1
[75.121829] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[75.132445] Starting dynamic subtest: pipe-B-eDP-1
[75.157057] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[75.163771] Starting dynamic subtest: pipe-C-eDP-1
[75.191592] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[75.198610] Starting dynamic subtest: pipe-D-eDP-1
[75.222234] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[75.226546] Subtest random-ccs-data-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[77.371038] [006/133] (942s left) gem_mmap_gtt (big-bo-tiledx)
[77.429730] Subtest big-bo-tiledX: SKIP (0.000s)
[77.482199] [007/133] (942s left) gem_exec_async (forked-writes)
[77.536348] Starting subtest: forked-writes
[77.538637] Starting dynamic subtest: rcs0
[77.564945] Dynamic subtest rcs0: SUCCESS (0.028s)
[77.572852] Starting dynamic subtest: bcs0
[77.585860] Dynamic subtest bcs0: SUCCESS (0.021s)
[77.597078] Starting dynamic subtest: vcs0
[77.622838] Dynamic subtest vcs0: SUCCESS (0.036s)
[77.630532] Starting dynamic subtest: vcs1
[77.647174] Dynamic subtest vcs1: SUCCESS (0.024s)
[77.655306] Starting dynamic subtest: vecs0
[77.667700] Dynamic subtest vecs0: SUCCESS (0.020s)
[77.675650] Starting dynamic subtest: ccs0
[77.689369] Dynamic subtest ccs0: SUCCESS (0.021s)
[77.691528] Subtest forked-writes: SUCCESS (0.153s)
[77.926985] [008/133] (941s left) gem_exec_balancer (persistence)
[78.192213] Starting subtest: persistence
[79.128621] Subtest persistence: SUCCESS (0.936s)
[79.234635] [009/133] (940s left) syncobj_timeline (device-submit-unordered)
[79.300561] Starting subtest: device-submit-unordered
[79.302881] Subtest device-submit-unordered: SUCCESS (0.001s)
[79.423198] [010/133] (940s left) gem_workarounds (basic-read)
[79.488887] Starting subtest: basic-read
[79.504122] Subtest basic-read: SUCCESS (0.016s)
[79.613105] [011/133] (940s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[79.709016] Starting subtest: async-flip-with-page-flip-events-linear
[80.097777] Starting dynamic subtest: pipe-A-eDP-1
[82.856322] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.755s)
[83.221181] Starting dynamic subtest: pipe-B-eDP-1
[85.981066] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.764s)
[86.354968] Starting dynamic subtest: pipe-C-eDP-1
[89.115887] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.764s)
[89.480024] Starting dynamic subtest: pipe-D-eDP-1
[92.253271] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.777s)
[92.255663] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.538s)
[93.450520] [012/133] (926s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[93.561226] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[101.031069] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.470s)
[101.177563] [013/133] (918s left) gem_partial_pwrite_pread (write-display)
[101.246493] Subtest write-display: SKIP (0.000s)
[101.309800] [014/133] (918s left) syncobj_wait (wait-any-interrupted)
[101.364626] Starting subtest: wait-any-interrupted
[101.467830] Subtest wait-any-interrupted: SUCCESS (0.102s)
[101.542617] [015/133] (918s left) gem_spin_batch (resubmit-new)
[101.603226] Starting subtest: resubmit-new
[101.605554] Starting dynamic subtest: rcs0
[101.611204] Dynamic subtest rcs0: SUCCESS (0.008s)
[101.622167] Starting dynamic subtest: bcs0
[101.624271] Dynamic subtest bcs0: SUCCESS (0.004s)
[101.632228] Starting dynamic subtest: vcs0
[101.634481] Dynamic subtest vcs0: SUCCESS (0.004s)
[101.641649] Starting dynamic subtest: vcs1
[101.643818] Dynamic subtest vcs1: SUCCESS (0.005s)
[101.650886] Starting dynamic subtest: vecs0
[101.653056] Dynamic subtest vecs0: SUCCESS (0.004s)
[101.660207] Starting dynamic subtest: ccs0
[101.662380] Dynamic subtest ccs0: SUCCESS (0.004s)
[101.664759] Subtest resubmit-new: SUCCESS (0.030s)
[102.968638] [016/133] (917s left) sysfs_heartbeat_interval (precise)
[103.019019] Starting subtest: precise
[103.021521] Starting dynamic subtest: vecs0
[110.930237] Dynamic subtest vecs0: SUCCESS (7.909s)
[110.941317] Starting dynamic subtest: ccs0
[112.073610] Dynamic subtest ccs0: SUCCESS (1.141s)
[112.085592] Starting dynamic subtest: vcs0
[119.888495] Dynamic subtest vcs0: SUCCESS (7.811s)
[119.897945] Starting dynamic subtest: bcs0
[121.001924] Dynamic subtest bcs0: SUCCESS (1.115s)
[121.009980] Starting dynamic subtest: rcs0
[122.082057] Dynamic subtest rcs0: SUCCESS (1.080s)
[122.089988] Starting dynamic subtest: vcs1
[129.873598] Dynamic subtest vcs1: SUCCESS (7.789s)
[129.876160] Subtest precise: SUCCESS (26.851s)
[129.956996] [017/133] (890s left) gem_exec_balancer (bonded-chain)
[130.035890] Starting subtest: bonded-chain
[137.055650] Subtest bonded-chain: SUCCESS (7.017s)
[137.376171] [018/133] (882s left) i915_pm_rpm (debugfs-read)
[139.030710] Starting subtest: debugfs-read
[140.189901] Subtest debugfs-read: SUCCESS (1.156s)
[140.564851] [019/133] (879s left) gem_pxp (reject-modify-context-protection-off-2)
[140.678638] Starting subtest: reject-modify-context-protection-off-2
[140.681118] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[140.810311] [020/133] (879s left) kms_prop_blob (blob-prop-lifetime)
[140.877884] Starting subtest: blob-prop-lifetime
[140.880256] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[140.992201] [021/133] (879s left) syncobj_basic (bad-fd-to-handle)
[141.048815] Starting subtest: bad-fd-to-handle
[141.051043] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[141.135871] [022/133] (879s left) syncobj_timeline (host-signal-points)
[141.197390] Starting subtest: host-signal-points
[141.202276] Subtest host-signal-points: SUCCESS (0.004s)
[141.301972] [023/133] (879s left) kms_flip (flip-vs-rmfb-interruptible)
[141.403988] Starting subtest: flip-vs-rmfb-interruptible
[141.406404] Starting dynamic subtest: A-eDP1
[150.042524] Dynamic subtest A-eDP1: SUCCESS (8.638s)
[150.054337] Starting dynamic subtest: B-eDP1
[158.674912] Dynamic subtest B-eDP1: SUCCESS (8.630s)
[158.682777] Starting dynamic subtest: C-eDP1
[167.304164] Dynamic subtest C-eDP1: SUCCESS (8.628s)
[167.312456] Starting dynamic subtest: D-eDP1
[175.928202] Dynamic subtest D-eDP1: SUCCESS (8.622s)
[175.932925] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.530s)
[177.102933] [024/133] (843s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[177.226844] Starting subtest: short-flip-before-cursor-toggle
[178.150316] Subtest short-flip-before-cursor-toggle: SUCCESS (0.921s)
[178.951958] [025/133] (841s left) i915_suspend (debugfs-reader)
[179.004728] Starting subtest: debugfs-reader
[197.269741] Subtest debugfs-reader: SUCCESS (3.251s)
[197.454235] [026/133] (823s left) gem_eio (banned)
[197.686756] Starting subtest: banned
[198.249233] Subtest banned: SUCCESS (0.543s)
[198.421063] [027/133] (822s left) gem_create (create-ext-cpu-access-big)
[198.578193] Starting subtest: create-ext-cpu-access-big
[198.583710] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[198.656869] [028/133] (822s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[198.750329] Starting subtest: primary-y-tiled-reflect-x-0
[198.822938] Subtest primary-y-tiled-reflect-x-0: SKIP (0.071s)
[198.972314] [029/133] (821s left) gem_exec_schedule (fairslice)
[199.052279] Starting subtest: fairslice
[199.054228] Starting dynamic subtest: rcs0
[201.086273] Dynamic subtest rcs0: SUCCESS (1.988s)
[201.098736] Starting dynamic subtest: bcs0
[203.108591] Dynamic subtest bcs0: SUCCESS (1.981s)
[203.118464] Starting dynamic subtest: vcs0
[205.128005] Dynamic subtest vcs0: SUCCESS (1.988s)
[205.137593] Starting dynamic subtest: vcs1
[207.147843] Dynamic subtest vcs1: SUCCESS (1.996s)
[207.157582] Starting dynamic subtest: vecs0
[209.179081] Dynamic subtest vecs0: SUCCESS (2.011s)
[209.188478] Starting dynamic subtest: ccs0
[211.204788] Dynamic subtest ccs0: SUCCESS (2.011s)
[211.206736] Subtest fairslice: SUCCESS (11.981s)
[211.518338] [030/133] (809s left) gem_userptr_blits (sd-probe)
[211.581386] Starting subtest: sd-probe
[211.583574] Subtest sd-probe: SUCCESS (0.000s)
[211.711293] [031/133] (809s left) gem_lmem_swapping (verify-random)
[211.761374] Subtest verify-random: SKIP (0.000s)
[211.813127] [032/133] (809s left) gem_partial_pwrite_pread (reads-display)
[211.870413] Subtest reads-display: SKIP (0.000s)
[211.937881] [033/133] (809s left) kms_flip (2x-flip-vs-suspend)
[212.026503] Starting subtest: 2x-flip-vs-suspend
[212.030825] Subtest 2x-flip-vs-suspend: SKIP (0.002s)
[212.141541] [034/133] (808s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[212.197605] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[212.199867] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[212.294215] [035/133] (808s left) kms_big_fb (linear-32bpp-rotate-0)
[212.384300] Starting subtest: linear-32bpp-rotate-0
[213.948411] Subtest linear-32bpp-rotate-0: SUCCESS (1.557s)
[214.080490] [036/133] (807s left) kms_cursor_edge_walk (256x256-top-edge)
[214.176310] Starting subtest: 256x256-top-edge
[214.178235] Starting dynamic subtest: pipe-A-eDP-1
[217.590309] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.402s)
[217.599709] Starting dynamic subtest: pipe-D-eDP-1
[220.731529] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.131s)
[220.733869] Subtest 256x256-top-edge: SUCCESS (6.535s)
[221.531931] [037/133] (799s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[221.633960] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[221.638030] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[221.729643] [038/133] (799s left) perf (missing-sample-flags)
[221.846698] Starting subtest: missing-sample-flags
[221.848500] Subtest missing-sample-flags: SUCCESS (0.000s)
[221.891868] [039/133] (799s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[221.984294] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[221.988786] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[222.090696] [040/133] (799s left) i915_hangman (hangcheck-unterminated)
[222.153333] Starting subtest: hangcheck-unterminated
[233.860449] Subtest hangcheck-unterminated: SUCCESS (11.698s)
[233.973292] [041/133] (787s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[234.068661] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[234.070932] Starting dynamic subtest: pipe-A-eDP-1
[234.618581] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.521s)
[234.629391] Starting dynamic subtest: pipe-B-eDP-1
[236.107354] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.488s)
[236.117557] Starting dynamic subtest: pipe-C-eDP-1
[237.504708] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.396s)
[237.514831] Starting dynamic subtest: pipe-D-eDP-1
[238.940182] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.433s)
[238.942366] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.846s)
[240.054368] [042/133] (781s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[240.161117] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[240.165435] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[240.261637] [043/133] (781s left) gem_mmap_gtt (big-bo-tiledy)
[240.313832] Subtest big-bo-tiledY: SKIP (0.000s)
[240.389165] [044/133] (781s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[240.492939] Starting subtest: yf-tiled-16bpp-rotate-180
[240.497077] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[240.580832] [045/133] (780s left) perf_pmu (busy-accuracy-50)
[240.619150] Starting subtest: busy-accuracy-50
[240.621500] Starting dynamic subtest: rcs0
[242.151747] Dynamic subtest rcs0: SUCCESS (1.533s)
[242.163129] Starting dynamic subtest: bcs0
[243.699114] Dynamic subtest bcs0: SUCCESS (1.544s)
[243.710528] Starting dynamic subtest: vcs0
[245.243372] Dynamic subtest vcs0: SUCCESS (1.541s)
[245.254819] Starting dynamic subtest: vcs1
[246.785375] Dynamic subtest vcs1: SUCCESS (1.539s)
[246.796840] Starting dynamic subtest: vecs0
[248.323794] Dynamic subtest vecs0: SUCCESS (1.535s)
[248.335256] Starting dynamic subtest: ccs0
[249.872494] Dynamic subtest ccs0: SUCCESS (1.546s)
[249.875743] Subtest busy-accuracy-50: SUCCESS (9.254s)
[249.993255] [046/133] (771s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[250.109670] Starting subtest: yf-tiled-8bpp-rotate-0
[250.114011] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[250.213009] [047/133] (771s left) kms_plane_multiple (tiling-x)
[250.307757] Starting subtest: tiling-x
[250.309941] Starting dynamic subtest: pipe-A-eDP-1
[254.095741] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.786s)
[254.107407] Starting dynamic subtest: pipe-B-eDP-1
[258.437984] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.341s)
[258.447117] Starting dynamic subtest: pipe-C-eDP-1
[262.786607] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.343s)
[262.797510] Starting dynamic subtest: pipe-D-eDP-1
[267.102347] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.315s)
[267.104568] Subtest tiling-x: SUCCESS (16.790s)
[267.911127] [048/133] (753s left) kms_prime (basic-modeset-hybrid)
[268.010956] Subtest basic-modeset-hybrid: SKIP (0.000s)
[268.111005] [049/133] (753s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[268.219757] Starting subtest: multiplane-rotation-cropping-bottom
[285.751571] Subtest multiplane-rotation-cropping-bottom: SUCCESS (17.527s)
[285.858713] [050/133] (735s left) gem_exec_reloc (basic-cpu-active)
[285.922294] Subtest basic-cpu-active: SKIP (0.000s)
[286.000428] [051/133] (735s left) gem_exec_fence (basic-wait-all)
[286.064951] Starting subtest: basic-wait-all
[286.160957] Subtest basic-wait-all: SUCCESS (0.096s)
[287.990576] [052/133] (733s left) sriov_basic (enable-vfs-autoprobe-on)
[288.044222] Starting subtest: enable-vfs-autoprobe-on
[288.046100] Starting dynamic subtest: numvfs-1
[288.119943] Dynamic subtest numvfs-1: FAIL (0.016s)
[288.133650] Starting dynamic subtest: numvfs-2
[288.209515] Dynamic subtest numvfs-2: FAIL (0.004s)
[288.220021] Starting dynamic subtest: numvfs-3
[288.293074] Dynamic subtest numvfs-3: FAIL (0.004s)
[288.303304] Starting dynamic subtest: numvfs-4
[288.374761] Dynamic subtest numvfs-4: FAIL (0.004s)
[288.385772] Starting dynamic subtest: numvfs-5
[288.458128] Dynamic subtest numvfs-5: FAIL (0.004s)
[288.468055] Starting dynamic subtest: numvfs-6
[288.539558] Dynamic subtest numvfs-6: FAIL (0.003s)
[288.550741] Starting dynamic subtest: numvfs-7
[288.622107] Dynamic subtest numvfs-7: FAIL (0.004s)
[288.632066] Starting dynamic subtest: numvfs-random
[288.702025] Dynamic subtest numvfs-random: FAIL (0.004s)
[288.711430] Starting dynamic subtest: numvfs-all
[288.784720] Dynamic subtest numvfs-all: FAIL (0.516s)
[288.786765] Subtest enable-vfs-autoprobe-on: FAIL (0.565s)
[288.826558] [053/133] (733s left) kms_psr (psr-cursor-mmap-cpu)
[288.914794] Starting subtest: psr-cursor-mmap-cpu
[288.917079] Starting dynamic subtest: eDP-1
[290.612994] Dynamic subtest eDP-1: SUCCESS (1.697s)
[290.615313] Subtest psr-cursor-mmap-cpu: SUCCESS (1.698s)
[291.497057] [054/133] (730s left) drm_read (short-buffer-wakeup)
[291.656475] Starting subtest: short-buffer-wakeup
[323.867298] Subtest short-buffer-wakeup: SUCCESS (32.217s)
[324.006353] [055/133] (697s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[324.104768] Starting subtest: pr-overlay-plane-update-continuous-sf
[324.108784] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[324.173304] [056/133] (697s left) gem_exec_flush (basic-uc-set-default)
[324.228722] Starting subtest: basic-uc-set-default
[329.679641] Subtest basic-uc-set-default: SUCCESS (5.451s)
[329.868612] [057/133] (692s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[329.923818] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[329.926094] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[330.038969] [058/133] (692s left) gem_ctx_isolation (preservation-s3)
[330.114172] Starting subtest: preservation-S3
[330.116059] Starting dynamic subtest: rcs0
[346.327321] Dynamic subtest rcs0: SUCCESS (1.365s)
[346.338149] Starting dynamic subtest: bcs0
[362.331020] Dynamic subtest bcs0: SUCCESS (1.337s)
[362.348678] Starting dynamic subtest: vcs0
[378.335057] Dynamic subtest vcs0: SUCCESS (3.069s)
[378.346504] Starting dynamic subtest: vcs1
[396.337948] Dynamic subtest vcs1: SUCCESS (4.865s)
[396.353468] Starting dynamic subtest: vecs0
[414.336826] Dynamic subtest vecs0: SUCCESS (4.844s)
[414.348963] Starting dynamic subtest: ccs0
[432.319255] Dynamic subtest ccs0: SUCCESS (3.103s)
[432.324299] Subtest preservation-S3: SUCCESS (18.589s)
[432.565604] [059/133] (589s left) syncobj_timeline (single-wait-all-available-signaled)
[432.631969] Starting subtest: single-wait-all-available-signaled
[432.634336] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[432.730194] [060/133] (589s left) kms_color_pipeline (plane-lut1d-lut1d)
[432.839635] Starting subtest: plane-lut1d-lut1d
[432.992766] Starting dynamic subtest: pipe-A-plane-0
[433.165997] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s)
[433.177447] Starting dynamic subtest: pipe-A-plane-1
[433.359030] Dynamic subtest pipe-A-plane-1: SUCCESS (0.188s)
[433.372861] Starting dynamic subtest: pipe-A-plane-2
[433.555051] Dynamic subtest pipe-A-plane-2: SUCCESS (0.188s)
[434.832419] Starting dynamic subtest: pipe-B-plane-0
[435.030165] Dynamic subtest pipe-B-plane-0: SUCCESS (0.199s)
[435.042544] Starting dynamic subtest: pipe-B-plane-1
[435.201273] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[435.212697] Starting dynamic subtest: pipe-B-plane-2
[435.373230] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[436.617959] Starting dynamic subtest: pipe-C-plane-0
[436.757945] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[436.768605] Starting dynamic subtest: pipe-C-plane-1
[436.916433] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[436.926837] Starting dynamic subtest: pipe-C-plane-2
[437.075144] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[438.289682] Starting dynamic subtest: pipe-D-plane-0
[438.454244] Dynamic subtest pipe-D-plane-0: SUCCESS (0.167s)
[438.464368] Starting dynamic subtest: pipe-D-plane-1
[438.633923] Dynamic subtest pipe-D-plane-1: SUCCESS (0.174s)
[438.645950] Starting dynamic subtest: pipe-D-plane-2
[438.780205] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[439.077366] Subtest plane-lut1d-lut1d: SUCCESS (6.082s)
[439.969283] [061/133] (582s left) kms_plane (pixel-format-y-tiled-modifier-source-clamping)
[440.059909] Starting subtest: pixel-format-y-tiled-modifier-source-clamping
[440.064936] Subtest pixel-format-y-tiled-modifier-source-clamping: SKIP (0.000s)
[440.139765] [062/133] (582s left) kms_explicit_fence (multiplane-atomic-fence-wait)
[440.278277] Starting subtest: multiplane-atomic-fence-wait
[440.601446] Subtest multiplane-atomic-fence-wait: SUCCESS (0.318s)
[441.722393] [063/133] (580s left) gem_ctx_param (invalid-size-get)
[441.779370] Starting subtest: invalid-size-get
[441.782077] Subtest invalid-size-get: SUCCESS (0.001s)
[441.878415] [064/133] (580s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[441.973439] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[441.975891] Starting dynamic subtest: pipe-A-eDP-1
[441.999204] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[442.009930] Starting dynamic subtest: pipe-B-eDP-1
[442.032905] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[442.041324] Starting dynamic subtest: pipe-C-eDP-1
[442.063561] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[442.072238] Starting dynamic subtest: pipe-D-eDP-1
[442.095267] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[442.099998] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[444.303935] [065/133] (578s left) kms_content_protection (atomic)
[444.451712] Starting subtest: atomic
[444.456404] Subtest atomic: SKIP (0.000s)
[444.565191] [066/133] (577s left) kms_psr (pr-suspend)
[444.650197] Starting subtest: pr-suspend
[444.655117] Subtest pr-suspend: SKIP (0.000s)
[444.722052] [067/133] (577s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[444.824028] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[445.889024] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.058s)
[446.011717] [068/133] (576s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[446.115224] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[453.113719] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.968s)
[453.263652] [069/133] (569s left) kms_cursor_crc (cursor-onscreen-512x512)
[453.557819] Starting subtest: cursor-onscreen-512x512
[453.562826] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[453.735838] [070/133] (568s left) kms_chamelium_edid (hdmi-mode-timings)
[454.137748] Subtest hdmi-mode-timings: SKIP (0.000s)
[455.004645] [071/133] (567s left) kms_addfb_basic (invalid-get-prop-any)
[455.051446] Starting subtest: invalid-get-prop-any
[455.053433] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[455.182418] [072/133] (567s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[455.286413] Starting subtest: x-tiled-32bpp-rotate-180
[456.759286] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.468s)
[456.899343] [073/133] (565s left) api_intel_bb (intel-bb-blit-x)
[456.958422] Starting subtest: intel-bb-blit-x
[460.834056] Subtest intel-bb-blit-x: SUCCESS (3.870s)
[460.960590] [074/133] (561s left) gem_exec_gttfill (basic)
[461.026323] Starting subtest: basic
[464.353202] Subtest basic: SUCCESS (3.328s)
[464.546693] [075/133] (558s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[464.704947] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[468.483461] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (3.785s)
[469.652790] [076/133] (553s left) kms_tiled_display (basic-test-pattern)
[469.767433] Subtest basic-test-pattern: SKIP (0.000s)
[469.815140] [077/133] (553s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[469.932118] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[474.371932] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.445s)
[475.547490] [078/133] (547s left) syncobj_wait (wait-for-submit-delayed-submit)
[475.611031] Starting subtest: wait-for-submit-delayed-submit
[475.715017] Subtest wait-for-submit-delayed-submit: SUCCESS (0.103s)
[475.805833] [079/133] (547s left) gem_mmap_gtt (medium-copy-xy)
[475.869678] Subtest medium-copy-XY: SKIP (0.000s)
[475.935298] [080/133] (547s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[476.030902] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[476.033568] Starting dynamic subtest: pipe-A-eDP-1
[476.058457] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[476.069961] Starting dynamic subtest: pipe-B-eDP-1
[476.094416] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[476.104089] Starting dynamic subtest: pipe-C-eDP-1
[476.129291] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[476.139017] Starting dynamic subtest: pipe-D-eDP-1
[476.164821] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[476.169871] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.007s)
[478.293453] [081/133] (544s left) perf_pmu (interrupts-sync)
[478.343851] Starting subtest: interrupts-sync
[479.432870] Subtest interrupts-sync: SUCCESS (1.090s)
[479.539855] [082/133] (543s left) kms_flip (2x-dpms-vs-vblank-race)
[479.636115] Starting subtest: 2x-dpms-vs-vblank-race
[479.648611] Subtest 2x-dpms-vs-vblank-race: SKIP (0.011s)
[479.731904] [083/133] (543s left) gem_exec_schedule (implicit-write-read)
[479.803319] Starting subtest: implicit-write-read
[479.806236] Starting dynamic subtest: rcs0
[479.909696] Dynamic subtest rcs0: SUCCESS (0.117s)
[479.921074] Starting dynamic subtest: vcs0
[480.000055] Dynamic subtest vcs0: SUCCESS (0.089s)
[480.013736] Starting dynamic subtest: vcs1
[480.080602] Dynamic subtest vcs1: SUCCESS (0.080s)
[480.091328] Starting dynamic subtest: vecs0
[480.159337] Dynamic subtest vecs0: SUCCESS (0.078s)
[480.170470] Starting dynamic subtest: ccs0
[480.247399] Dynamic subtest ccs0: SUCCESS (0.087s)
[480.250430] Subtest implicit-write-read: SUCCESS (0.455s)
[481.175898] [084/133] (542s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[481.285296] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[481.291026] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[481.384237] [085/133] (541s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping)
[481.493299] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping
[481.498707] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[481.576051] [086/133] (541s left) kms_hdr (static-toggle)
[481.670315] Starting subtest: static-toggle
[481.678258] Subtest static-toggle: SKIP (0.000s)
[481.742678] [087/133] (541s left) gem_render_copy (yf-tiled-to-vebox-linear)
[481.807254] Starting subtest: yf-tiled-to-vebox-linear
[481.812428] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[481.993310] [088/133] (541s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[482.092934] Starting subtest: fbcpsr-rgb565-draw-render
[489.118307] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.029s)
[489.270041] [089/133] (534s left) kms_mmap_write_crc (main)
[489.371970] Starting subtest: main
[489.374499] Starting dynamic subtest: pipe-A-eDP-1
[492.953326] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.581s)
[492.955918] Subtest main: SUCCESS (3.582s)
[493.754745] [090/133] (529s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[493.869620] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[493.874567] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[493.967171] [091/133] (529s left) kms_plane_scaling (planes-upscale-factor-0-25)
[494.058420] Starting subtest: planes-upscale-factor-0-25
[494.060904] Starting dynamic subtest: pipe-A
[494.119937] Dynamic subtest pipe-A: SUCCESS (0.063s)
[494.131933] Starting dynamic subtest: pipe-B
[495.283563] Dynamic subtest pipe-B: SUCCESS (1.162s)
[495.295415] Starting dynamic subtest: pipe-C
[496.406300] Dynamic subtest pipe-C: SUCCESS (1.120s)
[496.416244] Starting dynamic subtest: pipe-D
[497.520918] Dynamic subtest pipe-D: SUCCESS (1.113s)
[497.523739] Subtest planes-upscale-factor-0-25: SUCCESS (3.463s)
[498.686028] [092/133] (524s left) gem_mmap_gtt (hang-user)
[498.749281] Subtest hang-user: SKIP (0.000s)
[498.813698] [093/133] (524s left) gem_reg_read (timestamp-monotonic)
[498.866068] Starting subtest: timestamp-monotonic
[503.717762] Subtest timestamp-monotonic: SUCCESS (4.852s)
[503.795353] [094/133] (519s left) kms_getfb (getfb-handle-not-fb)
[503.884584] Starting subtest: getfb-handle-not-fb
[503.887003] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[503.992717] [095/133] (519s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[504.105263] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[504.109902] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[504.190064] [096/133] (519s left) perf_pmu (idle)
[504.234156] Starting subtest: idle
[504.236662] Starting dynamic subtest: rcs0
[504.765406] Dynamic subtest rcs0: SUCCESS (0.530s)
[504.779562] Starting dynamic subtest: bcs0
[505.285569] Dynamic subtest bcs0: SUCCESS (0.517s)
[505.299673] Starting dynamic subtest: vcs0
[505.802810] Dynamic subtest vcs0: SUCCESS (0.514s)
[505.815451] Starting dynamic subtest: vcs1
[506.327602] Dynamic subtest vcs1: SUCCESS (0.523s)
[506.341558] Starting dynamic subtest: vecs0
[506.845166] Dynamic subtest vecs0: SUCCESS (0.514s)
[506.858148] Starting dynamic subtest: ccs0
[507.361718] Dynamic subtest ccs0: SUCCESS (0.514s)
[507.364679] Subtest idle: SUCCESS (3.127s)
[507.484529] [097/133] (516s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[507.606098] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[507.608779] Starting dynamic subtest: pipe-A-default-mode
[510.342448] Dynamic subtest pipe-A-default-mode: SUCCESS (2.737s)
[510.345265] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.739s)
[511.175130] [098/133] (512s left) i915_drm_fdinfo (most-busy-check-all)
[511.217188] Starting subtest: most-busy-check-all
[511.220031] Starting dynamic subtest: rcs0
[511.225528] Dynamic subtest rcs0: SKIP (0.004s)
[511.237235] Starting dynamic subtest: bcs0
[511.242312] Dynamic subtest bcs0: SKIP (0.000s)
[511.252548] Starting dynamic subtest: vcs0
[511.257206] Dynamic subtest vcs0: SKIP (0.000s)
[511.267077] Starting dynamic subtest: vcs1
[511.272103] Dynamic subtest vcs1: SKIP (0.000s)
[511.281837] Starting dynamic subtest: vecs0
[511.287008] Dynamic subtest vecs0: SKIP (0.000s)
[511.296437] Starting dynamic subtest: ccs0
[511.301863] Dynamic subtest ccs0: SKIP (0.000s)
[511.307036] Subtest most-busy-check-all: SKIP (0.009s)
[511.347166] [099/133] (512s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[511.474039] Starting subtest: crc-primary-basic-yf-tiled-ccs
[511.476527] Starting dynamic subtest: pipe-A-eDP-1
[511.502180] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[511.514082] Starting dynamic subtest: pipe-B-eDP-1
[511.536418] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[511.545611] Starting dynamic subtest: pipe-C-eDP-1
[511.567834] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[511.576792] Starting dynamic subtest: pipe-D-eDP-1
[511.601288] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[511.606820] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.006s)
[513.727954] [100/133] (510s left) kms_flip (blocking-absolute-wf_vblank)
[513.814247] Starting subtest: blocking-absolute-wf_vblank
[513.816959] Starting dynamic subtest: A-eDP1
[522.595030] Dynamic subtest A-eDP1: SUCCESS (8.777s)
[522.606477] Starting dynamic subtest: B-eDP1
[531.327214] Dynamic subtest B-eDP1: SUCCESS (8.729s)
[531.342054] Starting dynamic subtest: C-eDP1
[540.039388] Dynamic subtest C-eDP1: SUCCESS (8.711s)
[540.053926] Starting dynamic subtest: D-eDP1
[548.763149] Dynamic subtest D-eDP1: SUCCESS (8.722s)
[548.769275] Subtest blocking-absolute-wf_vblank: SUCCESS (34.955s)
[549.977973] [101/133] (473s left) gem_exec_schedule (reorder-wide)
[550.173193] Subtest reorder-wide: SKIP (0.000s)
[550.355508] [102/133] (473s left) kms_flip (flip-vs-rmfb)
[550.446232] Starting subtest: flip-vs-rmfb
[550.449069] Starting dynamic subtest: A-eDP1
[559.066306] Dynamic subtest A-eDP1: SUCCESS (8.619s)
[559.077197] Starting dynamic subtest: B-eDP1
[567.691644] Dynamic subtest B-eDP1: SUCCESS (8.623s)
[567.705045] Starting dynamic subtest: C-eDP1
[576.311214] Dynamic subtest C-eDP1: SUCCESS (8.616s)
[576.324053] Starting dynamic subtest: D-eDP1
[584.939195] Dynamic subtest D-eDP1: SUCCESS (8.624s)
[584.944664] Subtest flip-vs-rmfb: SUCCESS (34.498s)
[586.128862] [103/133] (437s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[586.226182] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[590.488960] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.266s)
[591.628069] [104/133] (432s left) gen9_exec_parse (batch-zero-length)
[591.684166] Subtest batch-zero-length: SKIP (0.000s)
[591.759017] [105/133] (432s left) gem_mmap_wc (read-write)
[591.815006] Subtest read-write: SKIP (0.000s)
[591.875202] [106/133] (432s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[591.979028] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[591.983644] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[592.061036] [107/133] (432s left) kms_psr2_su (frontbuffer-xrgb8888)
[592.111309] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[592.175076] [108/133] (432s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[592.309215] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip
[592.314497] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[592.425883] [109/133] (431s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render)
[592.538285] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render
[592.543243] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[592.637865] [110/133] (431s left) gem_pxp (display-protected-crc)
[592.739404] Starting subtest: display-protected-crc
[594.470764] Subtest display-protected-crc: SUCCESS (1.733s)
[594.577462] [111/133] (429s left) api_intel_allocator (fork-simple-once)
[594.635813] Starting subtest: fork-simple-once
[594.646416] Subtest fork-simple-once: SUCCESS (0.014s)
[594.755627] [112/133] (429s left) gem_render_tiled_blits (basic)
[594.810278] Subtest basic: SKIP (0.000s)
[594.882744] [113/133] (429s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[594.978028] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[594.983129] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[595.068905] [114/133] (429s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[595.161559] Starting subtest: fbc-psr2-cursor-mmap-gtt
[595.164089] Starting dynamic subtest: eDP-1
[595.169017] Dynamic subtest eDP-1: SKIP (0.000s)
[595.174063] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[595.241163] [115/133] (429s left) sw_sync (sync_random_merge)
[595.282321] Starting subtest: sync_random_merge
[595.325419] Subtest sync_random_merge: SUCCESS (0.044s)
[595.394789] [116/133] (429s left) kms_async_flips (test-time-stamp-atomic)
[595.476002] Starting subtest: test-time-stamp-atomic
[595.858266] Starting dynamic subtest: pipe-A-eDP-1
[596.623215] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.762s)
[596.992161] Starting dynamic subtest: pipe-B-eDP-1
[597.750723] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.764s)
[598.139255] Starting dynamic subtest: pipe-C-eDP-1
[598.895201] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.760s)
[599.270269] Starting dynamic subtest: pipe-D-eDP-1
[600.021462] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.756s)
[600.024016] Subtest test-time-stamp-atomic: SUCCESS (4.543s)
[601.181887] [117/133] (423s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[601.237416] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[601.240254] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[601.359955] [118/133] (423s left) kms_properties (invalid-properties-legacy)
[601.458904] Starting subtest: invalid-properties-legacy
[601.561698] Subtest invalid-properties-legacy: SUCCESS (0.103s)
[601.651612] [119/133] (423s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[601.758516] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[601.762990] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[601.841232] [120/133] (422s left) kms_vblank (query-forked-busy)
[601.955101] Starting subtest: query-forked-busy
[601.957448] Starting dynamic subtest: pipe-A-eDP-1
[603.845325] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.887s)
[603.863063] Starting dynamic subtest: pipe-D-eDP-1
[606.446401] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.597s)
[606.449247] Subtest query-forked-busy: SUCCESS (4.490s)
[607.254114] [121/133] (417s left) gem_linear_blits (interruptible)
[607.481398] Starting subtest: interruptible
[611.782492] Subtest interruptible: SUCCESS (4.299s)
[611.908699] [122/133] (412s left) gem_ctx_persistence (heartbeat-hostile)
[612.000712] Starting subtest: heartbeat-hostile
[612.006295] Subtest heartbeat-hostile: SKIP (0.000s)
[612.114778] [123/133] (412s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[612.216356] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[619.114199] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.900s)
[619.233317] [124/133] (405s left) kms_pm_dc (dc5-retention-flops)
[620.340357] Starting subtest: dc5-retention-flops
[620.346321] Subtest dc5-retention-flops: SKIP (0.000s)
[620.416379] [125/133] (404s left) kms_dp_link_training (non-uhbr-sst)
[620.513535] Starting subtest: non-uhbr-sst
[620.523741] Subtest non-uhbr-sst: SKIP (0.000s)
[620.587074] [126/133] (404s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[620.678993] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[620.684246] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[620.794852] [127/133] (404s left) kms_cursor_crc (cursor-random-512x512)
[621.076302] Starting subtest: cursor-random-512x512
[621.081426] Subtest cursor-random-512x512: SKIP (0.001s)
[621.226350] [128/133] (403s left) gem_mmap (short-mmap)
[621.283992] Subtest short-mmap: SKIP (0.000s)
[621.338985] [129/133] (403s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[621.427030] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[621.431158] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[621.521082] [130/133] (403s left) kms_psr (fbc-psr-cursor-plane-onoff)
[621.614847] Starting subtest: fbc-psr-cursor-plane-onoff
[621.616781] Starting dynamic subtest: eDP-1
[621.620117] Dynamic subtest eDP-1: SKIP (0.000s)
[621.624017] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[621.687769] [131/133] (403s left) kms_getfb (getfb2-handle-not-fb)
[621.780262] Starting subtest: getfb2-handle-not-fb
[621.782291] Subtest getfb2-handle-not-fb: SUCCESS (0.000s)
[621.889900] [132/133] (403s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[622.281289] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[623.142700] [133/133] (402s left) gem_exec_whisper (basic-queues-all)
[623.208221] Starting subtest: basic-queues-all
[632.966232] Subtest basic-queues-all: SUCCESS (9.752s)
[633.147177] Closing watchdogs
results: parsing output: 0/ for test: gem_busy
results: parsing output: 1/ for test: syncobj_wait
results: parsing output: 2/ for test: gem_exec_fence
results: parsing output: 3/ for test: gem_exec_schedule
results: parsing output: 4/ for test: kms_ccs
results: parsing output: 5/ for test: gem_mmap_gtt
results: parsing output: 6/ for test: gem_exec_async
results: parsing output: 7/ for test: gem_exec_balancer
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: gem_workarounds
results: parsing output: 10/ for test: kms_async_flips
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: gem_partial_pwrite_pread
results: parsing output: 13/ for test: syncobj_wait
results: parsing output: 14/ for test: gem_spin_batch
results: parsing output: 15/ for test: sysfs_heartbeat_interval
results: parsing output: 16/ for test: gem_exec_balancer
results: parsing output: 17/ for test: i915_pm_rpm
results: parsing output: 18/ for test: gem_pxp
results: parsing output: 19/ for test: kms_prop_blob
results: parsing output: 20/ for test: syncobj_basic
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: kms_flip
results: parsing output: 23/ for test: kms_cursor_legacy
results: parsing output: 24/ for test: i915_suspend
results: parsing output: 25/ for test: gem_eio
results: parsing output: 26/ for test: gem_create
results: parsing output: 27/ for test: kms_rotation_crc
results: parsing output: 28/ for test: gem_exec_schedule
results: parsing output: 29/ for test: gem_userptr_blits
results: parsing output: 30/ for test: gem_lmem_swapping
results: parsing output: 31/ for test: gem_partial_pwrite_pread
results: parsing output: 32/ for test: kms_flip
results: parsing output: 33/ for test: syncobj_timeline
results: parsing output: 34/ for test: kms_big_fb
results: parsing output: 35/ for test: kms_cursor_edge_walk
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: perf
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: i915_hangman
results: parsing output: 40/ for test: kms_ccs
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_mmap_gtt
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: perf_pmu
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_plane_multiple
results: parsing output: 47/ for test: kms_prime
results: parsing output: 48/ for test: kms_rotation_crc
results: parsing output: 49/ for test: gem_exec_reloc
results: parsing output: 50/ for test: gem_exec_fence
results: parsing output: 51/ for test: sriov_basic
results: parsing output: 52/ for test: kms_psr
results: parsing output: 53/ for test: drm_read
results: parsing output: 54/ for test: kms_psr2_sf
results: parsing output: 55/ for test: gem_exec_flush
results: parsing output: 56/ for test: syncobj_timeline
results: parsing output: 57/ for test: gem_ctx_isolation
results: parsing output: 58/ for test: syncobj_timeline
results: parsing output: 59/ for test: kms_color_pipeline
results: parsing output: 60/ for test: kms_plane
results: parsing output: 61/ for test: kms_explicit_fence
results: parsing output: 62/ for test: gem_ctx_param
results: parsing output: 63/ for test: kms_ccs
results: parsing output: 64/ for test: kms_content_protection
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: kms_big_fb
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_cursor_crc
results: parsing output: 69/ for test: kms_chamelium_edid
results: parsing output: 70/ for test: kms_addfb_basic
results: parsing output: 71/ for test: kms_big_fb
results: parsing output: 72/ for test: api_intel_bb
results: parsing output: 73/ for test: gem_exec_gttfill
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_tiled_display
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: syncobj_wait
results: parsing output: 78/ for test: gem_mmap_gtt
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: perf_pmu
results: parsing output: 81/ for test: kms_flip
results: parsing output: 82/ for test: gem_exec_schedule
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_plane
results: parsing output: 85/ for test: kms_hdr
results: parsing output: 86/ for test: gem_render_copy
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_mmap_write_crc
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_plane_scaling
results: parsing output: 91/ for test: gem_mmap_gtt
results: parsing output: 92/ for test: gem_reg_read
results: parsing output: 93/ for test: kms_getfb
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: perf_pmu
results: parsing output: 96/ for test: kms_flip_scaled_crc
results: parsing output: 97/ for test: i915_drm_fdinfo
results: parsing output: 98/ for test: kms_ccs
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: gem_exec_schedule
results: parsing output: 101/ for test: kms_flip
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: gen9_exec_parse
results: parsing output: 104/ for test: gem_mmap_wc
results: parsing output: 105/ for test: kms_joiner
results: parsing output: 106/ for test: kms_psr2_su
results: parsing output: 107/ for test: kms_big_fb
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: gem_pxp
results: parsing output: 110/ for test: api_intel_allocator
results: parsing output: 111/ for test: gem_render_tiled_blits
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_psr
results: parsing output: 114/ for test: sw_sync
results: parsing output: 115/ for test: kms_async_flips
results: parsing output: 116/ for test: syncobj_wait
results: parsing output: 117/ for test: kms_properties
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: kms_vblank
results: parsing output: 120/ for test: gem_linear_blits
results: parsing output: 121/ for test: gem_ctx_persistence
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_pm_dc
results: parsing output: 124/ for test: kms_dp_link_training
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_cursor_crc
results: parsing output: 127/ for test: gem_mmap
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_psr
results: parsing output: 130/ for test: kms_getfb
results: parsing output: 131/ for test: kms_chamelium_hpd
results: parsing output: 132/ for test: gem_exec_whisper
Done.