[57.139712] Initializing watchdogs
[57.139846]   /dev/watchdog0
[57.154672] [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.159118] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.161369] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.164730] [001/132] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[57.302893] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[57.307126] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[57.410780] [002/132] (960s left) kms_cursor_crc (cursor-onscreen-64x21)
[57.602488] Starting subtest: cursor-onscreen-64x21
[57.653312] Subtest cursor-onscreen-64x21: SKIP (0.046s)
[57.930557] [003/132] (959s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[63.003568] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[63.005965] Starting dynamic subtest: pipe-A-eDP-1
[63.783878] Dynamic subtest pipe-A-eDP-1: SKIP (0.773s)
[63.790852] Starting dynamic subtest: pipe-B-eDP-1
[64.911530] Dynamic subtest pipe-B-eDP-1: SKIP (1.123s)
[64.916423] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.899s)
[66.114211] [004/132] (951s left) kms_rotation_crc (primary-y-tiled-reflect-x-90)
[66.204882] Starting subtest: primary-y-tiled-reflect-x-90
[66.209357] Subtest primary-y-tiled-reflect-x-90: SKIP (0.001s)
[66.296383] [005/132] (951s left) kms_vrr (negative-basic)
[66.388426] Starting subtest: negative-basic
[66.395393] Starting dynamic subtest: pipe-A-eDP-1
[71.812251] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.405s)
[72.114132] Subtest negative-basic: SUCCESS (5.715s)
[72.945982] [006/132] (944s left) gem_exec_whisper (basic-fds-priority)
[73.017737] Starting subtest: basic-fds-priority
[95.948731] Subtest basic-fds-priority: SUCCESS (22.918s)
[96.150193] [007/132] (921s left) kms_chamelium_edid (dp-edid-resolution-list)
[96.540753] Subtest dp-edid-resolution-list: SKIP (0.000s)
[97.342223] [008/132] (920s left) kms_frontbuffer_tracking (fbc-suspend)
[97.437710] Starting subtest: fbc-suspend
[135.785124] Subtest fbc-suspend: SUCCESS (11.274s)
[137.013900] [009/132] (880s left) syncobj_timeline (multi-wait-submitted-signaled)
[137.081390] Starting subtest: multi-wait-submitted-signaled
[137.083769] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[137.221653] [010/132] (880s left) syncobj_wait (multi-wait-all-submitted)
[137.283981] Starting subtest: multi-wait-all-submitted
[137.393698] Subtest multi-wait-all-submitted: SUCCESS (0.105s)
[137.491091] [011/132] (880s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[137.583076] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[137.587723] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[137.713742] [012/132] (880s left) gem_partial_pwrite_pread (write-uncached)
[137.776962] Subtest write-uncached: SKIP (0.000s)
[137.864876] [013/132] (880s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[137.963435] Starting subtest: fbc-pr-sprite-mmap-gtt
[137.967545] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[138.062494] [014/132] (880s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[138.167576] Starting subtest: x-tiled-32bpp-rotate-270
[138.186849] Subtest x-tiled-32bpp-rotate-270: SKIP (0.019s)
[138.344146] [015/132] (879s left) gem_exec_fence (basic-wait)
[138.522710] Starting subtest: basic-wait
[138.525062] Starting dynamic subtest: rcs0
[138.643252] Dynamic subtest rcs0: SUCCESS (0.118s)
[138.652662] Starting dynamic subtest: bcs0
[138.710029] Dynamic subtest bcs0: SUCCESS (0.065s)
[138.719044] Starting dynamic subtest: vcs0
[138.771373] Dynamic subtest vcs0: SUCCESS (0.060s)
[138.780795] Starting dynamic subtest: vcs1
[138.831710] Dynamic subtest vcs1: SUCCESS (0.058s)
[138.840630] Starting dynamic subtest: vecs0
[138.892159] Dynamic subtest vecs0: SUCCESS (0.059s)
[138.901259] Starting dynamic subtest: ccs0
[138.950778] Dynamic subtest ccs0: SUCCESS (0.057s)
[138.953195] Subtest basic-wait: SUCCESS (0.423s)
[141.074390] [FACT gem_exec_fence (basic-wait)] new: kernel.kmod_is_loaded.vgem: true
[141.077452] [016/132] (877s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[141.185437] Starting subtest: plane-lut1d-post-ctm3x4
[141.198237] Starting dynamic subtest: pipe-A-eDP-1
[141.939241] Dynamic subtest pipe-A-eDP-1: FAIL (0.336s)
[142.628387] Starting dynamic subtest: pipe-B-eDP-1
[143.492808] Dynamic subtest pipe-B-eDP-1: FAIL (0.336s)
[144.060801] Starting dynamic subtest: pipe-C-eDP-1
[144.918386] Dynamic subtest pipe-C-eDP-1: FAIL (0.271s)
[145.401522] Starting dynamic subtest: pipe-D-eDP-1
[146.284686] Dynamic subtest pipe-D-eDP-1: FAIL (0.290s)
[146.286752] Subtest plane-lut1d-post-ctm3x4: FAIL (4.756s)
[146.812015] [017/132] (871s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[146.904971] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[146.907403] Starting dynamic subtest: pipe-A-default-mode
[149.636331] Dynamic subtest pipe-A-default-mode: SUCCESS (2.720s)
[149.638770] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.721s)
[150.520042] [018/132] (867s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[150.632943] Starting subtest: 2x-absolute-wf_vblank-interruptible
[150.645625] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[150.744388] [019/132] (867s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[150.857661] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[157.351633] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.482s)
[157.480348] [020/132] (860s left) gem_exec_params (invalid-bsd2-flag-on-render)
[157.544882] Starting subtest: invalid-bsd2-flag-on-render
[157.547149] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[157.688441] [021/132] (860s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[157.785037] Starting subtest: psr-rgb101010-draw-blt
[165.120512] Subtest psr-rgb101010-draw-blt: SUCCESS (7.331s)
[165.289850] [022/132] (853s left) syncobj_timeline (invalid-wait-bad-flags)
[165.352793] Starting subtest: invalid-wait-bad-flags
[165.355336] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[165.485405] [023/132] (853s left) gem_mmap_gtt (cpuset-big-copy-odd)
[165.543159] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[165.608535] [024/132] (852s left) gem_mmap_wc (bad-size)
[165.668945] Subtest bad-size: SKIP (0.000s)
[165.741148] [025/132] (852s left) gem_exec_balancer (invalid-bonds)
[165.791596] Starting subtest: invalid-bonds
[165.796048] Subtest invalid-bonds: SKIP (0.000s)
[166.128146] [026/132] (852s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[166.235069] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[166.239216] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[166.374996] [027/132] (852s left) kms_lease (lease-get)
[166.471809] Starting subtest: lease-get
[166.474169] Starting dynamic subtest: pipe-A-eDP-1
[166.476389] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[166.490550] Starting dynamic subtest: pipe-B-eDP-1
[166.493109] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[166.502935] Starting dynamic subtest: pipe-C-eDP-1
[166.505084] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[166.513378] Starting dynamic subtest: pipe-D-eDP-1
[166.515514] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[166.517690] Subtest lease-get: SUCCESS (0.008s)
[166.622170] [028/132] (852s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[166.731076] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[170.981541] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.253s)
[172.136210] [029/132] (846s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[172.545592] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[173.319765] [030/132] (845s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[173.415708] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[177.102505] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.688s)
[178.284865] [031/132] (840s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[178.383227] Starting subtest: yf-tiled-64bpp-rotate-0
[178.387620] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[178.523500] [032/132] (840s left) gem_mmap_gtt (medium-copy-odd)
[178.593906] Subtest medium-copy-odd: SKIP (0.000s)
[178.665089] [033/132] (840s left) gem_pxp (protected-raw-src-copy-not-readible)
[178.791158] Starting subtest: protected-raw-src-copy-not-readible
[178.851539] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.062s)
[179.017362] [034/132] (839s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[179.136096] Starting subtest: flip-vs-cursor-crc-atomic
[179.884566] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.748s)
[180.694245] [035/132] (838s left) kms_atomic_transition (plane-all-transition-fencing)
[180.794786] Starting subtest: plane-all-transition-fencing
[180.797106] Starting dynamic subtest: pipe-A-eDP-1
[183.296328] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.502s)
[183.606394] Starting dynamic subtest: pipe-B-eDP-1
[186.809064] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.213s)
[187.107221] Subtest plane-all-transition-fencing: SUCCESS (6.310s)
[187.951457] [036/132] (831s left) prime_self_import (basic-with_one_bo_two_files)
[187.994198] Starting subtest: basic-with_one_bo_two_files
[188.014080] Subtest basic-with_one_bo_two_files: SUCCESS (0.022s)
[188.172684] [037/132] (830s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[188.277452] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[188.279718] Starting dynamic subtest: pipe-A-eDP-1
[188.302181] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[188.311887] Starting dynamic subtest: pipe-B-eDP-1
[188.333083] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[188.341302] Starting dynamic subtest: pipe-C-eDP-1
[188.362672] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[188.370383] Starting dynamic subtest: pipe-D-eDP-1
[188.391976] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[188.396276] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[190.626916] [038/132] (828s left) i915_drm_fdinfo (all-busy-check-all)
[190.669738] Starting subtest: all-busy-check-all
[190.674319] Subtest all-busy-check-all: SKIP (0.003s)
[190.769915] [039/132] (828s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[190.906397] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[190.908684] Starting dynamic subtest: pipe-A-default-mode
[191.197043] Dynamic subtest pipe-A-default-mode: SKIP (0.287s)
[191.201664] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.289s)
[192.061596] [040/132] (827s left) kms_addfb_basic (bad-pitch-0)
[192.122594] Starting subtest: bad-pitch-0
[192.125032] Subtest bad-pitch-0: SUCCESS (0.000s)
[192.281356] [041/132] (827s left) gem_ctx_persistence (heartbeat-stop)
[192.341548] Starting subtest: heartbeat-stop
[192.346320] Subtest heartbeat-stop: SKIP (0.000s)
[192.482679] [042/132] (826s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[192.569009] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[192.573175] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[192.712635] [043/132] (826s left) gem_exec_schedule (smoketest)
[192.895339] Starting subtest: smoketest
[192.897496] Starting dynamic subtest: rcs0
[198.450548] Dynamic subtest rcs0: SUCCESS (5.502s)
[198.461660] Starting dynamic subtest: bcs0
[203.966810] Dynamic subtest bcs0: SUCCESS (5.511s)
[203.988392] Starting dynamic subtest: vcs0
[209.463368] Dynamic subtest vcs0: SUCCESS (5.493s)
[209.479443] Starting dynamic subtest: vcs1
[214.965202] Dynamic subtest vcs1: SUCCESS (5.495s)
[214.975305] Starting dynamic subtest: vecs0
[220.460679] Dynamic subtest vecs0: SUCCESS (5.493s)
[220.473379] Starting dynamic subtest: ccs0
[225.971960] Dynamic subtest ccs0: SUCCESS (5.509s)
[225.973951] Subtest smoketest: SUCCESS (33.015s)
[226.192433] [044/132] (793s left) gem_exec_parallel (contexts)
[226.252822] Starting subtest: contexts
[226.255051] Starting dynamic subtest: rcs0
[229.891663] Dynamic subtest rcs0: SUCCESS (3.640s)
[229.903107] Starting dynamic subtest: bcs0
[233.297229] Dynamic subtest bcs0: SUCCESS (3.405s)
[233.308790] Starting dynamic subtest: vcs0
[237.356114] Dynamic subtest vcs0: SUCCESS (4.057s)
[237.365639] Starting dynamic subtest: vcs1
[241.426601] Dynamic subtest vcs1: SUCCESS (4.070s)
[241.435460] Starting dynamic subtest: vecs0
[245.368260] Dynamic subtest vecs0: SUCCESS (3.937s)
[245.377113] Starting dynamic subtest: ccs0
[248.986270] Dynamic subtest ccs0: SUCCESS (3.617s)
[248.988926] Subtest contexts: SUCCESS (22.743s)
[249.201154] [045/132] (770s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[249.266965] Starting subtest: invalid-bsd2-flag-on-blt
[249.269451] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[249.402272] [046/132] (770s left) kms_psr (fbc-psr2-primary-render)
[249.494128] Starting subtest: fbc-psr2-primary-render
[249.496350] Starting dynamic subtest: eDP-1
[249.501001] Dynamic subtest eDP-1: SKIP (0.000s)
[249.505426] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[249.593261] [047/132] (769s left) prime_vgem (coherency-gtt)
[249.672853] Starting subtest: coherency-gtt
[249.677098] Subtest coherency-gtt: SKIP (0.000s)
[249.820624] [048/132] (769s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[249.940177] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[249.944669] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[250.108686] [049/132] (769s left) kms_flip (flip-vs-expired-vblank)
[250.211860] Starting subtest: flip-vs-expired-vblank
[250.213528] Starting dynamic subtest: A-eDP1
[258.957924] Dynamic subtest A-eDP1: SUCCESS (8.747s)
[258.971167] Starting dynamic subtest: B-eDP1
[267.670294] Dynamic subtest B-eDP1: SUCCESS (8.709s)
[267.686658] Starting dynamic subtest: C-eDP1
[276.292353] Dynamic subtest C-eDP1: SUCCESS (8.619s)
[276.303593] Starting dynamic subtest: D-eDP1
[284.927111] Dynamic subtest D-eDP1: SUCCESS (8.633s)
[284.932337] Subtest flip-vs-expired-vblank: SUCCESS (34.720s)
[286.134168] [050/132] (733s left) gem_mmap_wc (write-read)
[286.207676] Subtest write-read: SKIP (0.000s)
[286.277696] [051/132] (733s left) kms_psr (psr-sprite-render)
[286.366426] Starting subtest: psr-sprite-render
[286.368707] Starting dynamic subtest: eDP-1
[288.126880] Dynamic subtest eDP-1: SUCCESS (1.759s)
[288.129260] Subtest psr-sprite-render: SUCCESS (1.760s)
[288.992804] [052/132] (730s left) kms_psr (psr2-cursor-blt)
[289.097221] Starting subtest: psr2-cursor-blt
[289.099512] Starting dynamic subtest: eDP-1
[290.844728] Dynamic subtest eDP-1: SUCCESS (1.744s)
[290.847148] Subtest psr2-cursor-blt: SUCCESS (1.746s)
[291.666136] [053/132] (728s left) kms_prime (basic-modeset-hybrid)
[291.749333] Subtest basic-modeset-hybrid: SKIP (0.000s)
[291.854680] [054/132] (727s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[291.965617] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[291.969585] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[292.107127] [055/132] (727s left) kms_psr (fbc-psr-sprite-plane-onoff)
[292.206649] Starting subtest: fbc-psr-sprite-plane-onoff
[292.208900] Starting dynamic subtest: eDP-1
[292.212842] Dynamic subtest eDP-1: SKIP (0.000s)
[292.216810] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[292.300530] [056/132] (727s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[292.375377] Starting subtest: stress-mm-invalidate-close-overlap
[294.521447] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.149s)
[294.679754] [057/132] (725s left) gem_exec_params (batch-first)
[294.755581] Starting subtest: batch-first
[294.763711] Subtest batch-first: SUCCESS (0.010s)
[294.914137] [058/132] (725s left) i915_pm_rpm (gem-mmap-type)
[297.037404] Starting subtest: gem-mmap-type
[297.040459] Starting dynamic subtest: wb-smem0
[300.786408] Dynamic subtest wb-smem0: SUCCESS (3.745s)
[300.796018] Starting dynamic subtest: wc-smem0
[303.964790] Dynamic subtest wc-smem0: SUCCESS (3.177s)
[303.974113] Starting dynamic subtest: uc-smem0
[307.133850] Dynamic subtest uc-smem0: SUCCESS (3.169s)
[307.136131] Subtest gem-mmap-type: SUCCESS (10.095s)
[307.282415] [059/132] (712s left) dumb_buffer (create-clear)
[307.345238] Starting subtest: create-clear
[341.411814] Subtest create-clear: SUCCESS (34.054s)
[341.553873] [060/132] (678s left) i915_module_load (resize-bar)
[341.599253] Starting subtest: resize-bar
[341.608503] Subtest resize-bar: SKIP (0.006s)
[341.652344] [061/132] (678s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[341.731663] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[341.735756] Subtest y-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[341.941699] [062/132] (678s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[342.069160] Starting subtest: basic-busy-flip-before-cursor-varying-size
[342.073847] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[342.238428] [063/132] (677s left) gem_eio (kms)
[342.518180] Starting subtest: kms
[363.445942] Subtest kms: SUCCESS (20.924s)
[364.637009] [064/132] (655s left) gem_exec_fence (keep-in-fence)
[364.825845] Starting subtest: keep-in-fence
[364.828369] Starting dynamic subtest: rcs0
[364.902727] Dynamic subtest rcs0: SUCCESS (0.076s)
[364.914929] Starting dynamic subtest: bcs0
[364.955751] Dynamic subtest bcs0: SUCCESS (0.052s)
[364.967034] Starting dynamic subtest: vcs0
[366.917692] Dynamic subtest vcs0: SUCCESS (1.958s)
[366.929742] Starting dynamic subtest: vcs1
[368.901968] Dynamic subtest vcs1: SUCCESS (1.982s)
[368.914092] Starting dynamic subtest: vecs0
[370.885764] Dynamic subtest vecs0: SUCCESS (1.982s)
[370.897697] Starting dynamic subtest: ccs0
[371.125294] Dynamic subtest ccs0: SUCCESS (0.238s)
[371.128363] Subtest keep-in-fence: SUCCESS (6.302s)
[373.017677] [065/132] (647s left) kms_big_fb (linear-8bpp-rotate-270)
[373.130624] Starting subtest: linear-8bpp-rotate-270
[373.153945] Subtest linear-8bpp-rotate-270: SKIP (0.023s)
[373.331141] [066/132] (647s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[373.434780] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[380.431429] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.000s)
[380.571608] [067/132] (639s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[380.662157] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[380.666423] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[381.770485] [068/132] (638s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[381.880140] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[381.884509] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[381.998142] [069/132] (638s left) i915_query (query-garbage)
[382.049891] Starting subtest: query-garbage
[382.052160] Subtest query-garbage: SUCCESS (0.000s)
[382.165744] [070/132] (638s left) gem_huc_copy (huc-copy)
[382.229600] Starting subtest: huc-copy
[382.231865] Subtest huc-copy: SUCCESS (0.003s)
[382.383200] [071/132] (638s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[382.491288] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[386.193618] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.707s)
[387.368946] [072/132] (633s left) gem_create (create-invalid-size)
[387.437544] Starting subtest: create-invalid-size
[387.440040] Subtest create-invalid-size: SUCCESS (0.000s)
[387.648739] [073/132] (632s left) kms_pm_rpm (drm-resources-equal)
[389.182856] Starting subtest: drm-resources-equal
[394.545406] Subtest drm-resources-equal: SUCCESS (5.364s)
[394.660939] [074/132] (626s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[394.765847] Starting subtest: random-ccs-data-yf-tiled-ccs
[394.768193] Starting dynamic subtest: pipe-A-eDP-1
[394.792601] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[394.802416] Starting dynamic subtest: pipe-B-eDP-1
[394.825125] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[394.833739] Starting dynamic subtest: pipe-C-eDP-1
[394.856286] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[394.864798] Starting dynamic subtest: pipe-D-eDP-1
[394.888604] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[394.892959] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.005s)
[397.059306] [075/132] (623s left) kms_lease (empty-lease)
[397.165611] Starting subtest: empty-lease
[397.167903] Starting dynamic subtest: pipe-A-eDP-1
[397.170183] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[397.185630] Starting dynamic subtest: pipe-B-eDP-1
[397.187776] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[397.196807] Starting dynamic subtest: pipe-C-eDP-1
[397.198969] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[397.208058] Starting dynamic subtest: pipe-D-eDP-1
[397.210507] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[397.213008] Subtest empty-lease: SUCCESS (0.009s)
[397.315756] [076/132] (623s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[397.416862] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[397.420891] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[397.536465] [077/132] (623s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[397.593265] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[397.595582] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[397.727398] [078/132] (623s left) api_intel_allocator (gem-pool)
[397.792117] Starting subtest: gem-pool
[397.797604] Subtest gem-pool: SUCCESS (0.008s)
[397.973366] [079/132] (622s left) core_getclient (basic)
[398.031953] Starting subtest: basic
[398.033764] Subtest basic: SUCCESS (0.000s)
[398.162527] [080/132] (622s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[398.260639] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[398.262730] Starting dynamic subtest: pipe-A
[398.622649] Dynamic subtest pipe-A: SUCCESS (0.364s)
[398.634388] Starting dynamic subtest: pipe-B
[400.080092] Dynamic subtest pipe-B: SUCCESS (1.455s)
[400.089537] Starting dynamic subtest: pipe-C
[401.464024] Dynamic subtest pipe-C: SUCCESS (1.382s)
[401.474179] Starting dynamic subtest: pipe-D
[402.904966] Dynamic subtest pipe-D: SUCCESS (1.440s)
[402.907112] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.647s)
[404.077950] [081/132] (616s left) syncobj_timeline (etime-single-wait-submitted)
[404.141510] Starting subtest: etime-single-wait-submitted
[404.250001] Subtest etime-single-wait-submitted: SUCCESS (0.108s)
[404.348728] [082/132] (616s left) gem_basic (create-close)
[404.413884] Starting subtest: create-close
[404.418536] Subtest create-close: SUCCESS (0.000s)
[404.554795] [083/132] (616s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[404.655818] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[411.340138] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.686s)
[411.515044] [084/132] (609s left) gem_spin_batch (legacy-resubmit)
[411.585006] Starting subtest: legacy-resubmit
[411.587250] Starting dynamic subtest: default
[411.591933] Dynamic subtest default: SUCCESS (0.006s)
[411.603789] Starting dynamic subtest: render
[411.606043] Dynamic subtest render: SUCCESS (0.001s)
[411.615450] Starting dynamic subtest: bsd
[411.617429] Dynamic subtest bsd: SUCCESS (0.002s)
[411.628558] Starting dynamic subtest: bsd1
[411.630416] Dynamic subtest bsd1: SUCCESS (0.002s)
[411.639204] Starting dynamic subtest: bsd2
[411.641133] Dynamic subtest bsd2: SUCCESS (0.001s)
[411.650358] Starting dynamic subtest: blt
[411.652237] Dynamic subtest blt: SUCCESS (0.002s)
[411.660606] Starting dynamic subtest: vebox
[411.662458] Dynamic subtest vebox: SUCCESS (0.002s)
[411.664303] Subtest legacy-resubmit: SUCCESS (0.022s)
[411.867925] [085/132] (609s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[411.962379] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[411.964588] Starting dynamic subtest: pipe-A-eDP-1
[411.984327] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[411.995449] Starting dynamic subtest: pipe-B-eDP-1
[412.014042] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[412.023367] Starting dynamic subtest: pipe-C-eDP-1
[412.042678] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[412.052280] Starting dynamic subtest: pipe-D-eDP-1
[412.070590] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[412.072438] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.011s)
[414.238617] [086/132] (606s left) perf_pmu (semaphore-wait-idle)
[414.283492] Starting subtest: semaphore-wait-idle
[414.286200] Starting dynamic subtest: rcs0
[414.799531] Dynamic subtest rcs0: SUCCESS (0.514s)
[414.812537] Starting dynamic subtest: bcs0
[415.314067] Dynamic subtest bcs0: SUCCESS (0.511s)
[415.327389] Starting dynamic subtest: vcs0
[415.827545] Dynamic subtest vcs0: SUCCESS (0.511s)
[415.840230] Starting dynamic subtest: vcs1
[416.341730] Dynamic subtest vcs1: SUCCESS (0.510s)
[416.354260] Starting dynamic subtest: vecs0
[416.855403] Dynamic subtest vecs0: SUCCESS (0.511s)
[416.866383] Starting dynamic subtest: ccs0
[417.372371] Dynamic subtest ccs0: SUCCESS (0.513s)
[417.375364] Subtest semaphore-wait-idle: SUCCESS (3.089s)
[417.531541] [087/132] (603s left) gem_wait (write-wait)
[417.606044] Starting subtest: write-wait
[417.608410] Starting dynamic subtest: all-engines
[418.737271] Dynamic subtest all-engines: SUCCESS (1.134s)
[418.754164] Starting dynamic subtest: rcs0
[419.794933] Dynamic subtest rcs0: SUCCESS (1.055s)
[419.811601] Starting dynamic subtest: bcs0
[420.851377] Dynamic subtest bcs0: SUCCESS (1.055s)
[420.868238] Starting dynamic subtest: vcs0
[421.906200] Dynamic subtest vcs0: SUCCESS (1.053s)
[421.923200] Starting dynamic subtest: vcs1
[422.958920] Dynamic subtest vcs1: SUCCESS (1.050s)
[422.976148] Starting dynamic subtest: vecs0
[424.011365] Dynamic subtest vecs0: SUCCESS (1.050s)
[424.028357] Starting dynamic subtest: ccs0
[425.068357] Dynamic subtest ccs0: SUCCESS (1.055s)
[425.070782] Subtest write-wait: SUCCESS (7.466s)
[425.291148] [088/132] (595s left) kms_atomic (plane-primary-legacy)
[425.385325] Starting subtest: plane-primary-legacy
[425.387475] Starting dynamic subtest: pipe-A-eDP-1
[425.830854] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.443s)
[425.833137] Subtest plane-primary-legacy: SUCCESS (0.445s)
[426.669172] [089/132] (594s left) sysfs_preempt_timeout (invalid)
[426.734015] Starting subtest: invalid
[426.736496] Starting dynamic subtest: vecs0
[426.738843] Dynamic subtest vecs0: SUCCESS (0.000s)
[426.750005] Starting dynamic subtest: ccs0
[426.752364] Dynamic subtest ccs0: SUCCESS (0.000s)
[426.761299] Starting dynamic subtest: vcs0
[426.763290] Dynamic subtest vcs0: SUCCESS (0.000s)
[426.772516] Starting dynamic subtest: bcs0
[426.774724] Dynamic subtest bcs0: SUCCESS (0.000s)
[426.783933] Starting dynamic subtest: rcs0
[426.786108] Dynamic subtest rcs0: SUCCESS (0.000s)
[426.794955] Starting dynamic subtest: vcs1
[426.797223] Dynamic subtest vcs1: SUCCESS (0.000s)
[426.799368] Subtest invalid: SUCCESS (0.006s)
[426.835443] [090/132] (594s left) gem_exec_fence (syncobj-timeline-signal)
[427.367981] Starting subtest: syncobj-timeline-signal
[427.374115] Subtest syncobj-timeline-signal: SUCCESS (0.008s)
[428.960720] [091/132] (592s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[429.161900] Starting subtest: cursor-rapid-movement-64x21
[429.208376] Subtest cursor-rapid-movement-64x21: SKIP (0.041s)
[429.493842] [092/132] (591s left) perf (oa-formats)
[429.633113] Starting subtest: oa-formats
[429.635372] Starting dynamic subtest: 0-rcs0
[429.647954] Dynamic subtest 0-rcs0: SUCCESS (0.016s)
[429.658737] Starting dynamic subtest: 1-vcs1
[429.669771] Dynamic subtest 1-vcs1: SUCCESS (0.021s)
[429.672161] Subtest oa-formats: SUCCESS (0.038s)
[429.719868] [093/132] (591s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[429.824757] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[429.826723] Starting dynamic subtest: pipe-A-eDP-1
[429.887784] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.064s)
[429.898229] Starting dynamic subtest: pipe-B-eDP-1
[429.948228] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.061s)
[429.957420] Starting dynamic subtest: pipe-C-eDP-1
[430.013289] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.064s)
[430.022444] Starting dynamic subtest: pipe-D-eDP-1
[430.077626] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.063s)
[430.079544] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.256s)
[432.241991] [094/132] (589s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[432.312642] Starting subtest: invalid-bsd1-flag-on-blt
[432.314952] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[432.452229] [095/132] (589s left) gem_ctx_persistence (engines-cleanup)
[432.520926] Starting subtest: engines-cleanup
[432.523272] Starting dynamic subtest: rcs0
[432.638270] Dynamic subtest rcs0: SUCCESS (0.117s)
[432.650412] Starting dynamic subtest: bcs0
[432.703988] Dynamic subtest bcs0: SUCCESS (0.063s)
[432.716046] Starting dynamic subtest: vcs0
[432.731800] Dynamic subtest vcs0: SUCCESS (0.026s)
[432.741351] Starting dynamic subtest: vcs1
[432.843893] Dynamic subtest vcs1: SUCCESS (0.111s)
[432.855523] Starting dynamic subtest: vecs0
[432.899177] Dynamic subtest vecs0: SUCCESS (0.053s)
[432.910617] Starting dynamic subtest: ccs0
[432.933769] Dynamic subtest ccs0: SUCCESS (0.033s)
[432.936145] Subtest engines-cleanup: SUCCESS (0.414s)
[433.103056] [096/132] (588s left) i915_suspend (debugfs-reader)
[433.155347] Starting subtest: debugfs-reader
[451.231397] Subtest debugfs-reader: SUCCESS (3.124s)
[451.324430] [097/132] (570s left) kms_draw_crc (draw-method-mmap-wc)
[451.435181] Starting subtest: draw-method-mmap-wc
[451.437209] Starting dynamic subtest: xrgb8888-untiled
[451.674303] Dynamic subtest xrgb8888-untiled: SUCCESS (0.233s)
[451.683942] Starting dynamic subtest: rgb565-untiled
[451.879292] Dynamic subtest rgb565-untiled: SUCCESS (0.199s)
[451.889147] Starting dynamic subtest: xrgb2101010-untiled
[452.107434] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s)
[452.117050] Starting dynamic subtest: xbgr16161616f-untiled
[452.323723] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[452.333382] Starting dynamic subtest: xrgb8888-xtiled
[452.460537] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.132s)
[452.469645] Starting dynamic subtest: rgb565-xtiled
[452.608541] Dynamic subtest rgb565-xtiled: SUCCESS (0.144s)
[452.617828] Starting dynamic subtest: xrgb2101010-xtiled
[452.733742] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[452.743000] Starting dynamic subtest: xbgr16161616f-xtiled
[452.870204] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[452.879669] Starting dynamic subtest: xrgb8888-4tiled
[453.029447] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.155s)
[453.038259] Starting dynamic subtest: rgb565-4tiled
[453.177364] Dynamic subtest rgb565-4tiled: SUCCESS (0.144s)
[453.194807] Starting dynamic subtest: xrgb2101010-4tiled
[453.336178] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.153s)
[453.347999] Starting dynamic subtest: xbgr16161616f-4tiled
[453.506596] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.165s)
[453.508481] Subtest draw-method-mmap-wc: SUCCESS (2.023s)
[453.638737] [098/132] (568s left) gem_userptr_blits (sync-unmap)
[453.708342] Starting subtest: sync-unmap
[453.722967] Subtest sync-unmap: SUCCESS (0.012s)
[453.904219] [099/132] (567s left) kms_content_protection (dp-mst-suspend-resume)
[454.057835] Starting subtest: dp-mst-suspend-resume
[454.061647] Subtest dp-mst-suspend-resume: SKIP (0.000s)
[454.230927] [100/132] (567s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[454.328410] Starting subtest: addfb25-y-tiled-small-legacy
[454.332161] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[454.461278] [101/132] (567s left) syncobj_timeline (wait-for-submit-snapshot)
[454.522855] Starting subtest: wait-for-submit-snapshot
[454.603330] Subtest wait-for-submit-snapshot: SUCCESS (0.080s)
[454.703139] [102/132] (567s left) gem_eio (in-flight-internal-1us)
[454.921897] Starting subtest: in-flight-internal-1us
[455.902282] Subtest in-flight-internal-1us: SUCCESS (0.962s)
[456.088917] [103/132] (565s left) gem_create (create-size-update)
[456.147289] Starting subtest: create-size-update
[456.149244] Subtest create-size-update: SUCCESS (0.000s)
[456.378327] [104/132] (565s left) kms_draw_crc (fill-fb)
[456.471201] Starting subtest: fill-fb
[456.905894] Subtest fill-fb: SUCCESS (0.430s)
[457.050897] [105/132] (564s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[457.153037] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[457.155342] Starting dynamic subtest: pipe-A-eDP-1
[457.642666] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.462s)
[457.653368] Starting dynamic subtest: pipe-B-eDP-1
[459.183518] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.509s)
[459.193490] Starting dynamic subtest: pipe-C-eDP-1
[460.625904] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.436s)
[460.635890] Starting dynamic subtest: pipe-D-eDP-1
[462.022027] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.382s)
[462.024294] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.794s)
[463.236900] [106/132] (558s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[463.347090] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[463.351443] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[463.488955] [107/132] (558s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[463.586546] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[463.590788] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[463.725127] [108/132] (558s left) gem_mmap_offset (bad-flags)
[463.786286] Starting subtest: bad-flags
[463.788622] Subtest bad-flags: SUCCESS (0.000s)
[463.908965] [109/132] (558s left) gem_mmap_gtt (big-copy)
[463.976293] Subtest big-copy: SKIP (0.000s)
[464.048029] [110/132] (558s left) kms_plane_multiple (tiling-y)
[464.149983] Starting subtest: tiling-y
[464.153909] Subtest tiling-y: SKIP (0.000s)
[464.244998] [111/132] (557s left) kms_plane_scaling (planes-upscale-factor-0-25)
[464.341215] Starting subtest: planes-upscale-factor-0-25
[464.343139] Starting dynamic subtest: pipe-A
[464.402246] Dynamic subtest pipe-A: SUCCESS (0.062s)
[464.413911] Starting dynamic subtest: pipe-B
[465.555653] Dynamic subtest pipe-B: SUCCESS (1.145s)
[465.567267] Starting dynamic subtest: pipe-C
[466.670777] Dynamic subtest pipe-C: SUCCESS (1.108s)
[466.680339] Starting dynamic subtest: pipe-D
[467.825314] Dynamic subtest pipe-D: SUCCESS (1.149s)
[467.827534] Subtest planes-upscale-factor-0-25: SUCCESS (3.471s)
[469.019346] [112/132] (553s left) gem_ctx_param (set-priority-range)
[469.082324] Starting subtest: set-priority-range
[469.099729] Subtest set-priority-range: SUCCESS (0.017s)
[469.243425] [113/132] (553s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render)
[469.345337] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render
[469.349472] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[469.484878] [114/132] (552s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[469.592074] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[476.195383] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.592s)
[476.363271] [115/132] (546s left) kms_scaling_modes (scaling-mode-center)
[476.465736] Starting subtest: scaling-mode-center
[476.468131] Starting dynamic subtest: pipe-A-eDP-1
[476.561246] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.097s)
[476.572816] Starting dynamic subtest: pipe-B-eDP-1
[477.755613] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.191s)
[477.769181] Starting dynamic subtest: pipe-C-eDP-1
[478.865911] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.105s)
[478.876184] Starting dynamic subtest: pipe-D-eDP-1
[479.994513] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[479.996842] Subtest scaling-mode-center: SUCCESS (3.527s)
[481.183572] [116/132] (541s left) gen9_exec_parse (secure-batches)
[481.251228] Subtest secure-batches: SKIP (0.000s)
[481.341051] [117/132] (541s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[481.442819] Starting subtest: 4-tiled-64bpp-rotate-180
[483.067480] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.623s)
[483.260733] [118/132] (539s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[488.334162] Starting subtest: psr2-plane-move-sf-dmg-area
[488.336571] Starting dynamic subtest: pipe-A-eDP-1
[493.652521] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.318s)
[493.662783] Starting dynamic subtest: pipe-B-eDP-1
[498.960382] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.305s)
[498.962762] Subtest psr2-plane-move-sf-dmg-area: SUCCESS (10.627s)
[499.863048] [119/132] (522s left) kms_vblank (query-forked-busy)
[499.962841] Starting subtest: query-forked-busy
[499.964990] Starting dynamic subtest: pipe-A-eDP-1
[501.839784] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.875s)
[501.855043] Starting dynamic subtest: pipe-D-eDP-1
[504.387959] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.547s)
[504.390365] Subtest query-forked-busy: SUCCESS (4.426s)
[505.218401] [120/132] (517s left) gem_ccs (block-copy-uncompressed)
[505.272985] Starting subtest: block-copy-uncompressed
[505.275394] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[505.296986] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.023s)
[505.309979] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[505.315623] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[505.326186] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[505.330741] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[505.340454] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[505.347526] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[505.349899] Subtest block-copy-uncompressed: SUCCESS (0.075s)
[505.493190] [121/132] (517s left) syncobj_eventfd (binary-wait-signaled)
[505.551913] Starting subtest: binary-wait-signaled
[505.554278] Subtest binary-wait-signaled: SUCCESS (0.000s)
[505.670136] [122/132] (517s left) kms_draw_crc (draw-method-mmap-gtt)
[505.767149] Starting subtest: draw-method-mmap-gtt
[505.771538] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[505.915959] [123/132] (516s left) gem_exec_schedule (preempt-other)
[505.997289] Starting subtest: preempt-other
[505.999281] Starting dynamic subtest: rcs0
[506.061646] Dynamic subtest rcs0: SUCCESS (0.072s)
[506.071469] Starting dynamic subtest: bcs0
[506.129900] Dynamic subtest bcs0: SUCCESS (0.068s)
[506.141299] Starting dynamic subtest: vcs0
[506.196445] Dynamic subtest vcs0: SUCCESS (0.066s)
[506.206888] Starting dynamic subtest: vcs1
[506.263069] Dynamic subtest vcs1: SUCCESS (0.066s)
[506.273556] Starting dynamic subtest: vecs0
[506.332922] Dynamic subtest vecs0: SUCCESS (0.069s)
[506.343376] Starting dynamic subtest: ccs0
[506.404936] Dynamic subtest ccs0: SUCCESS (0.071s)
[506.406824] Subtest preempt-other: SUCCESS (0.417s)
[507.995862] [124/132] (514s left) kms_plane_lowres (tiling-4)
[508.096850] Starting subtest: tiling-4
[508.099030] Starting dynamic subtest: pipe-A-eDP-1
[508.103407] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[508.397802] Starting dynamic subtest: pipe-B-eDP-1
[508.402413] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[508.411271] Starting dynamic subtest: pipe-C-eDP-1
[508.415912] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[508.424623] Starting dynamic subtest: pipe-D-eDP-1
[508.428878] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[508.432779] Subtest tiling-4: SKIP (0.300s)
[509.238847] [125/132] (513s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[509.347349] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[515.525830] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.180s)
[516.741683] [126/132] (506s left) vgem_basic (setversion)
[516.791854] Starting subtest: setversion
[516.796360] Subtest setversion: SUCCESS (0.000s)
[516.874668] [127/132] (506s left) gem_exec_suspend (basic-s3-devices)
[516.943182] Starting subtest: basic-S3-devices
[516.945363] Starting dynamic subtest: smem
[528.798624] Dynamic subtest smem: SUCCESS (11.851s)
[528.801784] Subtest basic-S3-devices: SUCCESS (11.854s)
[529.009179] [128/132] (493s left) gem_eio (in-flight-suspend)
[529.264467] Starting subtest: in-flight-suspend
[571.558243] Subtest in-flight-suspend: SUCCESS (13.611s)
[571.728191] [129/132] (451s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[571.850512] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[579.439449] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.587s)
[579.597906] [130/132] (443s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[579.704128] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[584.178804] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.476s)
[585.357744] [131/132] (437s left) perf (non-zero-reason)
[585.466881] Starting subtest: non-zero-reason
[585.469524] Starting dynamic subtest: 0-rcs0
[590.863802] Dynamic subtest 0-rcs0: SUCCESS (5.393s)
[590.875162] Starting dynamic subtest: 1-vcs0
[594.766831] Dynamic subtest 1-vcs0: SUCCESS (3.901s)
[594.769912] Subtest non-zero-reason: SUCCESS (9.296s)
[594.820402] [132/132] (428s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[594.878454] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[594.980187] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.103s)
[595.083563] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_cursor_crc
results: parsing output: 2/ for test: kms_psr2_sf
results: parsing output: 3/ for test: kms_rotation_crc
results: parsing output: 4/ for test: kms_vrr
results: parsing output: 5/ for test: gem_exec_whisper
results: parsing output: 6/ for test: kms_chamelium_edid
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: syncobj_wait
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: gem_partial_pwrite_pread
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: gem_exec_fence
results: parsing output: 15/ for test: kms_color_pipeline
results: parsing output: 16/ for test: kms_flip_scaled_crc
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_exec_params
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: gem_mmap_gtt
results: parsing output: 23/ for test: gem_mmap_wc
results: parsing output: 24/ for test: gem_exec_balancer
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_lease
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_chamelium_hpd
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: gem_mmap_gtt
results: parsing output: 32/ for test: gem_pxp
results: parsing output: 33/ for test: kms_cursor_legacy
results: parsing output: 34/ for test: kms_atomic_transition
results: parsing output: 35/ for test: prime_self_import
results: parsing output: 36/ for test: kms_ccs
results: parsing output: 37/ for test: i915_drm_fdinfo
results: parsing output: 38/ for test: kms_flip_scaled_crc
results: parsing output: 39/ for test: kms_addfb_basic
results: parsing output: 40/ for test: gem_ctx_persistence
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_exec_schedule
results: parsing output: 43/ for test: gem_exec_parallel
results: parsing output: 44/ for test: gem_exec_params
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: prime_vgem
results: parsing output: 47/ for test: kms_cursor_legacy
results: parsing output: 48/ for test: kms_flip
results: parsing output: 49/ for test: gem_mmap_wc
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: kms_psr
results: parsing output: 52/ for test: kms_prime
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: gem_userptr_blits
results: parsing output: 56/ for test: gem_exec_params
results: parsing output: 57/ for test: i915_pm_rpm
results: parsing output: 58/ for test: dumb_buffer
results: parsing output: 59/ for test: i915_module_load
results: parsing output: 60/ for test: gem_render_copy
results: parsing output: 61/ for test: kms_cursor_legacy
results: parsing output: 62/ for test: gem_eio
results: parsing output: 63/ for test: gem_exec_fence
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: i915_query
results: parsing output: 69/ for test: gem_huc_copy
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_create
results: parsing output: 72/ for test: kms_pm_rpm
results: parsing output: 73/ for test: kms_ccs
results: parsing output: 74/ for test: kms_lease
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: syncobj_wait
results: parsing output: 77/ for test: api_intel_allocator
results: parsing output: 78/ for test: core_getclient
results: parsing output: 79/ for test: kms_plane_scaling
results: parsing output: 80/ for test: syncobj_timeline
results: parsing output: 81/ for test: gem_basic
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_spin_batch
results: parsing output: 84/ for test: kms_ccs
results: parsing output: 85/ for test: perf_pmu
results: parsing output: 86/ for test: gem_wait
results: parsing output: 87/ for test: kms_atomic
results: parsing output: 88/ for test: sysfs_preempt_timeout
results: parsing output: 89/ for test: gem_exec_fence
results: parsing output: 90/ for test: kms_cursor_crc
results: parsing output: 91/ for test: perf
results: parsing output: 92/ for test: kms_ccs
results: parsing output: 93/ for test: gem_exec_params
results: parsing output: 94/ for test: gem_ctx_persistence
results: parsing output: 95/ for test: i915_suspend
results: parsing output: 96/ for test: kms_draw_crc
results: parsing output: 97/ for test: gem_userptr_blits
results: parsing output: 98/ for test: kms_content_protection
results: parsing output: 99/ for test: kms_addfb_basic
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: gem_eio
results: parsing output: 102/ for test: gem_create
results: parsing output: 103/ for test: kms_draw_crc
results: parsing output: 104/ for test: kms_ccs
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_mmap_offset
results: parsing output: 108/ for test: gem_mmap_gtt
results: parsing output: 109/ for test: kms_plane_multiple
results: parsing output: 110/ for test: kms_plane_scaling
results: parsing output: 111/ for test: gem_ctx_param
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_scaling_modes
results: parsing output: 115/ for test: gen9_exec_parse
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: kms_psr2_sf
results: parsing output: 118/ for test: kms_vblank
results: parsing output: 119/ for test: gem_ccs
results: parsing output: 120/ for test: syncobj_eventfd
results: parsing output: 121/ for test: kms_draw_crc
results: parsing output: 122/ for test: gem_exec_schedule
results: parsing output: 123/ for test: kms_plane_lowres
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: vgem_basic
results: parsing output: 126/ for test: gem_exec_suspend
results: parsing output: 127/ for test: gem_eio
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: perf
results: parsing output: 131/ for test: syncobj_timeline
Done.