[57.911955] Initializing watchdogs
[57.912013]   /dev/watchdog0
[57.926870] [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.931180] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.934182] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.939089] [001/133] (960s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[58.062631] Starting subtest: psr-indfb-scaledprimary
[63.758881] Subtest psr-indfb-scaledprimary: SUCCESS (5.739s)
[63.884340] [002/133] (954s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[63.978327] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[63.982715] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[64.070962] [003/133] (954s left) syncobj_timeline (single-wait-available-submitted)
[64.133377] Starting subtest: single-wait-available-submitted
[64.135763] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[64.228561] [004/133] (954s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[64.318335] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[64.345631] Starting dynamic subtest: pipe-A-plane-0
[64.975531] Dynamic subtest pipe-A-plane-0: SUCCESS (0.633s)
[64.982796] Starting dynamic subtest: pipe-A-plane-3
[65.374552] Dynamic subtest pipe-A-plane-3: SUCCESS (0.400s)
[66.490363] Starting dynamic subtest: pipe-B-plane-0
[67.204451] Dynamic subtest pipe-B-plane-0: SUCCESS (0.722s)
[67.213713] Starting dynamic subtest: pipe-B-plane-3
[67.692094] Dynamic subtest pipe-B-plane-3: SUCCESS (0.488s)
[68.008526] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.702s)
[68.873094] [005/133] (949s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[68.976583] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[75.858478] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.895s)
[76.013201] [006/133] (942s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[76.137296] Starting subtest: flip-vs-cursor-busy-crc-atomic
[76.999513] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.848s)
[77.870291] [007/133] (940s left) kms_addfb_basic (tile-pitch-mismatch)
[77.962817] Starting subtest: tile-pitch-mismatch
[77.985059] Subtest tile-pitch-mismatch: SKIP (0.000s)
[78.119199] [008/133] (940s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[78.247799] Starting subtest: fbc-psr2-sprite-plane-onoff
[78.258315] Starting dynamic subtest: eDP-1
[78.281297] Dynamic subtest eDP-1: SKIP (0.000s)
[78.302706] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.000s)
[78.387730] [009/133] (940s left) kms_pm_rpm (basic-pci-d3-state)
[80.038761] Starting subtest: basic-pci-d3-state
[81.489729] Subtest basic-pci-d3-state: SUCCESS (1.459s)
[81.574047] [010/133] (937s left) gem_basic (create-fd-close)
[81.631407] Starting subtest: create-fd-close
[81.635646] Subtest create-fd-close: SUCCESS (0.000s)
[81.729991] [011/133] (937s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[81.833156] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[81.849564] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.014s)
[81.920877] [012/133] (937s left) gen9_exec_parse (basic-rejected)
[81.974096] Subtest basic-rejected: SKIP (0.000s)
[82.037278] [013/133] (936s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[82.089794] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[82.193232] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.103s)
[82.289642] [014/133] (936s left) gem_fenced_exec_thrash (no-spare-fences)
[82.360254] Subtest no-spare-fences: SKIP (0.000s)
[82.432633] [015/133] (936s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc)
[82.529366] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc
[82.531751] Starting dynamic subtest: pipe-A-eDP-1
[82.554695] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[82.565044] Starting dynamic subtest: pipe-B-eDP-1
[82.585060] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[82.592512] Starting dynamic subtest: pipe-C-eDP-1
[82.613139] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[82.620501] Starting dynamic subtest: pipe-D-eDP-1
[82.643523] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[82.647769] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[84.797750] [016/133] (934s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[84.868746] Starting subtest: wait-all-for-submit-delayed-submit
[84.973972] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[85.059214] [017/133] (934s left) perf (gen12-group-exclusive-stream-ctx-handle)
[85.167947] Starting subtest: gen12-group-exclusive-stream-ctx-handle
[85.192076] Subtest gen12-group-exclusive-stream-ctx-handle: SUCCESS (0.024s)
[85.235941] [018/133] (933s left) kms_scaling_modes (scaling-mode-full)
[86.941654] Starting subtest: scaling-mode-full
[86.943863] Starting dynamic subtest: pipe-A-eDP-1
[87.049979] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.108s)
[87.058229] Starting dynamic subtest: pipe-B-eDP-1
[88.239553] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.189s)
[88.249895] Starting dynamic subtest: pipe-C-eDP-1
[89.363868] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.121s)
[89.376421] Starting dynamic subtest: pipe-D-eDP-1
[90.498049] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.132s)
[90.500428] Subtest scaling-mode-full: SUCCESS (3.557s)
[91.672939] [019/133] (927s left) gem_exec_schedule (preempt-queue-contexts-chain)
[91.762924] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[92.052982] [020/133] (927s left) kms_lease (simple-lease)
[92.139615] Starting subtest: simple-lease
[92.141928] Starting dynamic subtest: pipe-A-eDP-1
[92.646089] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.507s)
[92.654582] Starting dynamic subtest: pipe-B-eDP-1
[94.115326] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.467s)
[94.124434] Starting dynamic subtest: pipe-C-eDP-1
[95.512317] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.395s)
[95.521483] Starting dynamic subtest: pipe-D-eDP-1
[96.912146] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.399s)
[96.914515] Subtest simple-lease: SUCCESS (4.772s)
[97.706754] [021/133] (921s left) i915_query (query-regions-sanity-check)
[97.764094] Starting subtest: query-regions-sanity-check
[97.774602] Subtest query-regions-sanity-check: SUCCESS (0.013s)
[97.900969] [022/133] (921s left) kms_invalid_mode (bad-hsync-start)
[97.992569] Starting subtest: bad-hsync-start
[97.994862] Starting dynamic subtest: pipe-A-eDP-1
[98.274835] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[98.283887] Starting dynamic subtest: pipe-B-eDP-1
[98.286078] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[98.293252] Starting dynamic subtest: pipe-C-eDP-1
[98.295713] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[98.303201] Starting dynamic subtest: pipe-D-eDP-1
[98.305581] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[98.307900] Subtest bad-hsync-start: SUCCESS (0.289s)
[99.152272] [023/133] (920s left) kms_flip (flip-vs-panning)
[99.255473] Starting subtest: flip-vs-panning
[99.257831] Starting dynamic subtest: A-eDP1
[107.957100] Dynamic subtest A-eDP1: SUCCESS (8.701s)
[107.966917] Starting dynamic subtest: B-eDP1
[116.687800] Dynamic subtest B-eDP1: SUCCESS (8.728s)
[116.701070] Starting dynamic subtest: C-eDP1
[125.407913] Dynamic subtest C-eDP1: SUCCESS (8.718s)
[125.415942] Starting dynamic subtest: D-eDP1
[134.137099] Dynamic subtest D-eDP1: SUCCESS (8.727s)
[134.141677] Subtest flip-vs-panning: SUCCESS (34.887s)
[135.303319] [024/133] (884s left) kms_plane_scaling (intel-max-src-size)
[135.393533] Starting subtest: intel-max-src-size
[135.399899] Subtest intel-max-src-size: SKIP (0.000s)
[135.490281] [025/133] (883s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[135.590955] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[135.594837] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[135.655206] [026/133] (883s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[135.748393] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[135.752219] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[135.834328] [027/133] (883s left) kms_flip (basic-flip-vs-dpms)
[135.934802] Starting subtest: basic-flip-vs-dpms
[135.937095] Starting dynamic subtest: A-eDP1
[138.503670] Dynamic subtest A-eDP1: SUCCESS (2.565s)
[138.512874] Starting dynamic subtest: B-eDP1
[140.681421] Dynamic subtest B-eDP1: SUCCESS (2.176s)
[140.690193] Starting dynamic subtest: C-eDP1
[142.743390] Dynamic subtest C-eDP1: SUCCESS (2.059s)
[142.752382] Starting dynamic subtest: D-eDP1
[144.832074] Dynamic subtest D-eDP1: SUCCESS (2.090s)
[144.836773] Subtest basic-flip-vs-dpms: SUCCESS (8.900s)
[145.626047] [028/133] (873s left) kms_plane_lowres (tiling-x)
[145.717725] Starting subtest: tiling-x
[145.720036] Starting dynamic subtest: pipe-A-eDP-1
[145.724675] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[146.011811] Starting dynamic subtest: pipe-B-eDP-1
[146.016485] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[146.023862] Starting dynamic subtest: pipe-C-eDP-1
[146.028595] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[146.036400] Starting dynamic subtest: pipe-D-eDP-1
[146.041167] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[146.045700] Subtest tiling-x: SKIP (0.294s)
[146.845985] [029/133] (872s left) kms_content_protection (dp-mst-type-1)
[146.981851] Starting subtest: dp-mst-type-1
[146.986450] Subtest dp-mst-type-1: SKIP (0.000s)
[147.106273] [030/133] (872s left) sysfs_timeslice_duration (timeout)
[147.163409] Starting subtest: timeout
[147.165801] Starting dynamic subtest: vecs0
[156.881768] Dynamic subtest vecs0: SUCCESS (9.713s)
[156.891929] Starting dynamic subtest: ccs0
[158.149902] Dynamic subtest ccs0: SUCCESS (1.268s)
[158.160668] Starting dynamic subtest: vcs0
[167.890437] Dynamic subtest vcs0: SUCCESS (9.739s)
[167.899695] Starting dynamic subtest: bcs0
[168.520791] Dynamic subtest bcs0: SUCCESS (0.629s)
[168.530297] Starting dynamic subtest: rcs0
[169.777806] Dynamic subtest rcs0: SUCCESS (1.257s)
[169.788442] Starting dynamic subtest: vcs1
[178.894767] Dynamic subtest vcs1: SUCCESS (9.111s)
[178.897476] Subtest timeout: SUCCESS (31.727s)
[178.971362] [031/133] (840s left) prime_self_import (basic-llseek-size)
[179.007337] Starting subtest: basic-llseek-size
[179.027036] Subtest basic-llseek-size: SUCCESS (0.022s)
[179.123955] [032/133] (840s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[179.242376] Starting subtest: flip-vs-cursor-crc-atomic
[179.941794] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.698s)
[180.784758] [033/133] (838s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[180.877261] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[180.879521] Starting dynamic subtest: pipe-A-default-mode
[182.041255] Dynamic subtest pipe-A-default-mode: SKIP (1.159s)
[182.045680] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.161s)
[182.172634] [034/133] (837s left) drm_read (empty-nonblock)
[182.424659] Starting subtest: empty-nonblock
[182.427185] Subtest empty-nonblock: SUCCESS (0.000s)
[182.536353] [035/133] (837s left) kms_psr (fbc-pr-basic)
[182.630387] Starting subtest: fbc-pr-basic
[182.634380] Subtest fbc-pr-basic: SKIP (0.000s)
[182.701019] [036/133] (837s left) core_getclient (basic)
[182.749142] Starting subtest: basic
[182.751059] Subtest basic: SUCCESS (0.000s)
[182.841649] [037/133] (837s left) kms_flip (dpms-vs-vblank-race)
[182.938329] Starting subtest: dpms-vs-vblank-race
[182.940192] Starting dynamic subtest: A-eDP1
[188.115460] Dynamic subtest A-eDP1: SUCCESS (5.176s)
[188.123812] Starting dynamic subtest: B-eDP1
[191.699442] Dynamic subtest B-eDP1: SUCCESS (3.583s)
[191.710691] Starting dynamic subtest: C-eDP1
[196.330217] Dynamic subtest C-eDP1: SUCCESS (4.629s)
[196.338190] Starting dynamic subtest: D-eDP1
[200.965217] Dynamic subtest D-eDP1: SUCCESS (4.631s)
[200.967451] Subtest dpms-vs-vblank-race: SUCCESS (18.030s)
[201.833364] [038/133] (818s left) kms_cursor_crc (cursor-sliding-256x256)
[202.087948] Starting subtest: cursor-sliding-256x256
[202.090130] Starting dynamic subtest: pipe-A-eDP-1
[210.267386] Dynamic subtest pipe-A-eDP-1: SUCCESS (8.176s)
[210.294746] Starting dynamic subtest: pipe-D-eDP-1
[218.455426] Dynamic subtest pipe-D-eDP-1: SUCCESS (8.163s)
[218.457452] Subtest cursor-sliding-256x256: SUCCESS (16.366s)
[219.448684] [039/133] (800s left) perf_pmu (most-busy-check-all)
[219.486855] Starting subtest: most-busy-check-all
[219.489153] Starting dynamic subtest: rcs0
[220.885207] Dynamic subtest rcs0: SUCCESS (1.396s)
[220.897049] Starting dynamic subtest: bcs0
[222.933073] Dynamic subtest bcs0: SUCCESS (2.045s)
[222.944758] Starting dynamic subtest: vcs0
[224.918652] Dynamic subtest vcs0: SUCCESS (1.982s)
[224.930660] Starting dynamic subtest: vcs1
[226.901466] Dynamic subtest vcs1: SUCCESS (1.980s)
[226.912314] Starting dynamic subtest: vecs0
[228.885654] Dynamic subtest vecs0: SUCCESS (1.981s)
[228.897619] Starting dynamic subtest: ccs0
[230.933486] Dynamic subtest ccs0: SUCCESS (2.045s)
[230.936066] Subtest most-busy-check-all: SUCCESS (11.446s)
[231.035296] [040/133] (789s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[231.127186] Starting subtest: primary-y-tiled-reflect-x-270
[231.131780] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[231.234525] [041/133] (788s left) gem_ccs (block-multicopy-compressed)
[231.296210] Starting subtest: block-multicopy-compressed
[231.300585] Subtest block-multicopy-compressed: SKIP (0.000s)
[231.399707] [042/133] (788s left) gem_ringfill (engines-basic)
[236.889569] Starting subtest: engines-basic
[236.892213] Starting dynamic subtest: rcs0
[236.947407] Dynamic subtest rcs0: SUCCESS (0.058s)
[236.958957] Starting dynamic subtest: bcs0
[236.992063] Dynamic subtest bcs0: SUCCESS (0.043s)
[237.002359] Starting dynamic subtest: vcs0
[237.036229] Dynamic subtest vcs0: SUCCESS (0.042s)
[237.046546] Starting dynamic subtest: vcs1
[237.080215] Dynamic subtest vcs1: SUCCESS (0.042s)
[237.090429] Starting dynamic subtest: vecs0
[237.123773] Dynamic subtest vecs0: SUCCESS (0.041s)
[237.135093] Starting dynamic subtest: ccs0
[237.170787] Dynamic subtest ccs0: SUCCESS (0.045s)
[237.173443] Subtest engines-basic: SUCCESS (0.284s)
[237.245847] [FACT gem_ringfill (engines-basic)] new: kernel.kmod_is_loaded.vgem: true
[237.250568] [043/133] (782s left) kms_ccs (crc-sprite-planes-basic-4-tiled-lnl-ccs)
[237.345910] Starting subtest: crc-sprite-planes-basic-4-tiled-lnl-ccs
[237.350370] Subtest crc-sprite-planes-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[238.464179] [044/133] (781s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted-submitted)
[238.521506] Starting subtest: etime-multi-wait-for-submit-unsubmitted-submitted
[238.624384] Subtest etime-multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.104s)
[238.713025] [045/133] (781s left) gem_exec_whisper (basic-contexts-forked)
[238.778907] Starting subtest: basic-contexts-forked
[262.583408] Subtest basic-contexts-forked: SUCCESS (23.800s)
[262.766720] [046/133] (757s left) gen3_render_mixed_blits
[262.899906] [047/133] (757s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[263.009093] Starting subtest: plane-upscale-20x20-with-pixel-format
[263.011398] Starting dynamic subtest: pipe-A
[264.055997] Dynamic subtest pipe-A: SUCCESS (1.050s)
[264.066635] Starting dynamic subtest: pipe-B
[266.149759] Dynamic subtest pipe-B: SUCCESS (2.092s)
[266.162883] Starting dynamic subtest: pipe-C
[267.341271] Dynamic subtest pipe-C: SUCCESS (1.190s)
[267.350315] Starting dynamic subtest: pipe-D
[268.494416] Dynamic subtest pipe-D: SUCCESS (1.152s)
[268.496742] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.488s)
[269.684594] [048/133] (750s left) gem_mmap_gtt (cpuset-medium-copy)
[269.744556] Subtest cpuset-medium-copy: SKIP (0.000s)
[269.805659] [049/133] (750s left) prime_busy (hang-wait)
[269.864349] Starting subtest: hang-wait
[269.866272] Starting dynamic subtest: rcs0
[296.436112] Dynamic subtest rcs0: SUCCESS (26.569s)
[296.446491] Starting dynamic subtest: bcs0
[303.521864] Dynamic subtest bcs0: SUCCESS (7.088s)
[303.533292] Starting dynamic subtest: vcs0
[310.498846] Dynamic subtest vcs0: SUCCESS (6.976s)
[310.510068] Starting dynamic subtest: vcs1
[317.540068] Dynamic subtest vcs1: SUCCESS (7.037s)
[317.550467] Starting dynamic subtest: vecs0
[324.515057] Dynamic subtest vecs0: SUCCESS (6.971s)
[324.532369] Starting dynamic subtest: ccs0
[338.350858] Dynamic subtest ccs0: SUCCESS (13.836s)
[338.353623] Subtest hang-wait: SUCCESS (68.484s)
[338.507710] [050/133] (682s left) kms_hdr (bpc-switch)
[338.618929] Starting subtest: bpc-switch
[339.654914] Starting dynamic subtest: pipe-A-eDP-1
[343.050800] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.398s)
[343.052988] Subtest bpc-switch: SUCCESS (4.434s)
[343.151868] [051/133] (677s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render)
[343.254388] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render
[343.258647] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[343.333584] [052/133] (677s left) kms_chamelium_color (degamma)
[343.426210] Subtest degamma: SKIP (0.000s)
[343.475211] [053/133] (677s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[343.571266] Starting subtest: pr-plane-move-sf-dmg-area
[343.575558] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[343.638467] [054/133] (677s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-blt)
[343.734284] Starting subtest: fbc-rgb101010-draw-blt
[348.305926] Subtest fbc-rgb101010-draw-blt: SUCCESS (4.575s)
[349.413214] [055/133] (671s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[349.513493] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[349.517781] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[349.625206] [056/133] (671s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[349.742861] Starting subtest: modeset-atomic-cursor-hotspot
[349.747850] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[349.887514] [057/133] (670s left) kms_getfb (getfb-repeated-different-handles)
[349.975946] Starting subtest: getfb-repeated-different-handles
[349.978263] Subtest getfb-repeated-different-handles: SUCCESS (0.000s)
[350.077678] [058/133] (670s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[350.185618] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[356.982654] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.800s)
[357.109063] [059/133] (663s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[357.208241] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[364.582971] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.376s)
[364.696126] [060/133] (656s left) gem_exec_fence (syncobj-import)
[365.176935] Starting subtest: syncobj-import
[365.179705] Subtest syncobj-import: SUCCESS (0.006s)
[367.013320] [061/133] (654s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[367.107238] Starting subtest: yf-tiled-16bpp-rotate-90
[367.111680] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[367.206653] [062/133] (653s left) gen7_exec_parse (basic-allowed)
[367.269488] Subtest basic-allowed: SKIP (0.000s)
[367.343274] [063/133] (653s left) gem_eio (hibernate)
[367.546049] Starting subtest: hibernate
[383.068371] Subtest hibernate: SUCCESS (15.522s)
[390.203274] [064/133] (637s left) syncobj_wait (multi-wait-all-submitted)
[390.284768] Starting subtest: multi-wait-all-submitted
[390.394310] Subtest multi-wait-all-submitted: SUCCESS (0.108s)
[390.470675] [065/133] (637s left) kms_ccs (bad-aux-stride-yf-tiled-ccs)
[390.555829] Starting subtest: bad-aux-stride-yf-tiled-ccs
[390.560648] Starting dynamic subtest: pipe-A-eDP-1
[390.582677] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[390.594270] Starting dynamic subtest: pipe-B-eDP-1
[390.615310] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[390.624806] Starting dynamic subtest: pipe-C-eDP-1
[390.646035] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[390.655158] Starting dynamic subtest: pipe-D-eDP-1
[390.677278] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[390.681211] Subtest bad-aux-stride-yf-tiled-ccs: SKIP (0.004s)
[392.833308] [066/133] (635s left) testdisplay
[393.164569] [067/133] (635s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[393.265176] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[394.299390] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.034s)
[394.435104] [068/133] (633s left) kms_universal_plane (disable-primary-vs-flip)
[394.525204] Starting subtest: disable-primary-vs-flip
[394.527025] Starting dynamic subtest: pipe-A-eDP-1
[396.107706] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.580s)
[396.118955] Starting dynamic subtest: pipe-B-eDP-1
[398.383119] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.276s)
[398.393564] Starting dynamic subtest: pipe-C-eDP-1
[400.599758] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.214s)
[400.611610] Starting dynamic subtest: pipe-D-eDP-1
[402.781072] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.182s)
[402.783424] Subtest disable-primary-vs-flip: SUCCESS (8.257s)
[403.576243] [069/133] (624s left) gem_exec_fence (syncobj-timeline-unused-fence)
[404.116808] Starting subtest: syncobj-timeline-unused-fence
[404.122555] Subtest syncobj-timeline-unused-fence: SUCCESS (0.006s)
[404.961967] [070/133] (623s left) syncobj_timeline (invalid-single-wait-unsubmitted)
[405.018717] Starting subtest: invalid-single-wait-unsubmitted
[405.020820] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[405.114625] [071/133] (623s left) gem_ctx_persistence (legacy-engines-mixed-process)
[405.185685] Starting subtest: legacy-engines-mixed-process
[405.188065] Starting dynamic subtest: render
[406.873024] Dynamic subtest render: SUCCESS (1.687s)
[406.885262] Starting dynamic subtest: bsd1
[408.870368] Dynamic subtest bsd1: SUCCESS (1.992s)
[408.882293] Starting dynamic subtest: bsd2
[410.888067] Dynamic subtest bsd2: SUCCESS (2.014s)
[410.899272] Starting dynamic subtest: blt
[412.895435] Dynamic subtest blt: SUCCESS (2.009s)
[412.906271] Starting dynamic subtest: vebox
[414.916833] Dynamic subtest vebox: SUCCESS (2.017s)
[414.919114] Subtest legacy-engines-mixed-process: SUCCESS (9.728s)
[415.032938] [072/133] (613s left) kms_psr (psr-cursor-render)
[415.144731] Starting subtest: psr-cursor-render
[415.146952] Starting dynamic subtest: eDP-1
[416.863376] Dynamic subtest eDP-1: SUCCESS (1.718s)
[416.865642] Subtest psr-cursor-render: SUCCESS (1.720s)
[417.797588] [073/133] (610s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[417.853120] Starting subtest: multi-wait-all-for-submit-unsubmitted
[417.960371] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.107s)
[418.048742] [074/133] (610s left) gem_render_copy (linear-to-vebox-yf-tiled)
[418.101643] Starting subtest: linear-to-vebox-yf-tiled
[418.106347] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[418.284193] [075/133] (610s left) kms_vblank (ts-continuation-modeset)
[418.392280] Starting subtest: ts-continuation-modeset
[418.394256] Starting dynamic subtest: pipe-A-eDP-1
[419.909150] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.517s)
[419.924227] Starting dynamic subtest: pipe-D-eDP-1
[422.060370] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.148s)
[422.062286] Subtest ts-continuation-modeset: SUCCESS (3.669s)
[422.865312] [076/133] (605s left) kms_big_fb (y-tiled-16bpp-rotate-270)
[422.970333] Starting subtest: y-tiled-16bpp-rotate-270
[422.974689] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s)
[423.082175] [077/133] (605s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[423.183085] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[424.351878] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.170s)
[424.488211] [078/133] (604s left) kms_chamelium_color (ctm-negative)
[424.572484] Subtest ctm-negative: SKIP (0.000s)
[424.621346] [079/133] (604s left) gem_create (create-massive)
[424.690549] Starting subtest: create-massive
[424.692421] Subtest create-massive: SUCCESS (0.000s)
[424.902327] [080/133] (604s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[424.964559] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[424.966720] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[425.057628] [081/133] (603s left) gem_fence_thrash (bo-write-verify-y)
[425.096778] Starting subtest: bo-write-verify-y
[425.114872] Subtest bo-write-verify-y: SKIP (0.018s)
[425.182972] [082/133] (603s left) kms_cursor_crc (cursor-size-hints)
[425.316566] Starting subtest: cursor-size-hints
[425.318754] Starting dynamic subtest: pipe-A-eDP-1
[425.939502] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.621s)
[425.953051] Starting dynamic subtest: pipe-D-eDP-1
[427.224196] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.283s)
[427.226365] Subtest cursor-size-hints: SUCCESS (1.906s)
[428.038271] [083/133] (601s left) kms_async_flips (invalid-async-flip-atomic)
[428.130979] Starting subtest: invalid-async-flip-atomic
[428.504602] Starting dynamic subtest: pipe-A-eDP-1
[429.356863] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.852s)
[429.722646] Starting dynamic subtest: pipe-B-eDP-1
[430.485509] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.770s)
[430.843298] Starting dynamic subtest: pipe-C-eDP-1
[431.618644] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.783s)
[431.973629] Starting dynamic subtest: pipe-D-eDP-1
[432.740830] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.775s)
[432.743100] Subtest invalid-async-flip-atomic: SUCCESS (4.613s)
[433.893594] [084/133] (595s left) gem_exec_flush (basic-batch-kernel-default-wb)
[433.959742] Starting subtest: basic-batch-kernel-default-wb
[441.267865] Subtest basic-batch-kernel-default-wb: SUCCESS (7.308s)
[441.471508] [085/133] (587s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[441.595973] Starting subtest: basic-flip-after-cursor-varying-size
[442.529475] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.933s)
[443.358767] [086/133] (585s left) i915_pm_rps (basic-api)
[443.412594] Starting subtest: basic-api
[443.416754] Subtest basic-api: SKIP (0.001s)
[443.545080] [087/133] (585s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[443.637069] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[443.641408] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[443.733538] [088/133] (585s left) kms_psr (fbc-pr-primary-page-flip)
[443.838294] Starting subtest: fbc-pr-primary-page-flip
[443.842325] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[443.927318] [089/133] (585s left) kms_draw_crc (draw-method-mmap-cpu)
[444.021322] Starting subtest: draw-method-mmap-cpu
[444.023543] Starting dynamic subtest: xrgb8888-untiled
[444.254316] Dynamic subtest xrgb8888-untiled: SUCCESS (0.234s)
[444.264234] Starting dynamic subtest: rgb565-untiled
[444.475892] Dynamic subtest rgb565-untiled: SUCCESS (0.221s)
[444.485657] Starting dynamic subtest: xrgb2101010-untiled
[444.687370] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.210s)
[444.697274] Starting dynamic subtest: xbgr16161616f-untiled
[444.920741] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.233s)
[444.932523] Starting dynamic subtest: xrgb8888-xtiled
[445.064904] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.142s)
[445.078826] Starting dynamic subtest: rgb565-xtiled
[445.209622] Dynamic subtest rgb565-xtiled: SUCCESS (0.142s)
[445.219466] Starting dynamic subtest: xrgb2101010-xtiled
[445.331618] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[445.341331] Starting dynamic subtest: xbgr16161616f-xtiled
[445.465070] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[445.474769] Starting dynamic subtest: xrgb8888-4tiled
[445.609506] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.144s)
[445.619039] Starting dynamic subtest: rgb565-4tiled
[445.754422] Dynamic subtest rgb565-4tiled: SUCCESS (0.144s)
[445.765917] Starting dynamic subtest: xrgb2101010-4tiled
[445.898406] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.143s)
[445.908278] Starting dynamic subtest: xbgr16161616f-4tiled
[446.043109] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.144s)
[446.045081] Subtest draw-method-mmap-cpu: SUCCESS (2.023s)
[446.152980] [090/133] (583s left) kms_cursor_crc (cursor-onscreen-256x256)
[446.418221] Starting subtest: cursor-onscreen-256x256
[446.423377] Starting dynamic subtest: pipe-A-eDP-1
[448.531602] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.108s)
[448.562411] Starting dynamic subtest: pipe-D-eDP-1
[451.319041] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.764s)
[451.321184] Subtest cursor-onscreen-256x256: SUCCESS (4.898s)
[452.201551] [091/133] (577s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[452.314172] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[452.318419] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.000s)
[452.407055] [092/133] (577s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[452.501401] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[452.506013] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[452.605634] [093/133] (576s left) i915_drm_fdinfo (busy)
[452.640264] Starting subtest: busy
[452.642188] Starting dynamic subtest: rcs0
[452.672924] Dynamic subtest rcs0: SKIP (0.032s)
[452.685107] Starting dynamic subtest: bcs0
[452.689194] Dynamic subtest bcs0: SKIP (0.000s)
[452.698902] Starting dynamic subtest: vcs0
[452.702951] Dynamic subtest vcs0: SKIP (0.000s)
[452.712226] Starting dynamic subtest: vcs1
[452.716240] Dynamic subtest vcs1: SKIP (0.000s)
[452.725512] Starting dynamic subtest: vecs0
[452.729583] Dynamic subtest vecs0: SKIP (0.000s)
[452.738725] Starting dynamic subtest: ccs0
[452.742779] Dynamic subtest ccs0: SKIP (0.000s)
[452.746868] Subtest busy: SKIP (0.037s)
[452.789287] [094/133] (576s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode)
[453.174328] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s)
[453.999410] [095/133] (575s left) gem_ctx_param (set-priority-not-supported)
[454.055064] Starting subtest: set-priority-not-supported
[454.059564] Subtest set-priority-not-supported: SKIP (0.000s)
[454.129962] [096/133] (575s left) kms_addfb_basic (clobberred-modifier)
[454.185262] Starting subtest: clobberred-modifier
[454.189638] Subtest clobberred-modifier: SKIP (0.000s)
[454.318524] [097/133] (575s left) sw_sync (timeline_closed_signaled)
[454.356542] Starting subtest: timeline_closed_signaled
[454.358773] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[454.413839] [098/133] (575s left) api_intel_allocator (gem-pool)
[454.465407] Starting subtest: gem-pool
[454.470879] Subtest gem-pool: SUCCESS (0.008s)
[454.562574] [099/133] (575s left) gem_spin_batch (resubmit-all)
[454.620877] Starting subtest: resubmit-all
[454.622730] Starting dynamic subtest: rcs0
[454.630219] Dynamic subtest rcs0: SUCCESS (0.012s)
[454.640522] Starting dynamic subtest: bcs0
[454.642783] Dynamic subtest bcs0: SUCCESS (0.002s)
[454.652461] Starting dynamic subtest: vcs0
[454.654660] Dynamic subtest vcs0: SUCCESS (0.002s)
[454.664186] Starting dynamic subtest: vcs1
[454.666292] Dynamic subtest vcs1: SUCCESS (0.002s)
[454.675965] Starting dynamic subtest: vecs0
[454.678141] Dynamic subtest vecs0: SUCCESS (0.002s)
[454.687760] Starting dynamic subtest: ccs0
[454.689964] Dynamic subtest ccs0: SUCCESS (0.002s)
[454.692159] Subtest resubmit-all: SUCCESS (0.025s)
[455.944634] [100/133] (573s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[456.037316] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[456.041719] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[456.137910] [101/133] (573s left) gem_ctx_isolation (nonpriv-switch)
[456.200961] Starting subtest: nonpriv-switch
[456.203316] Starting dynamic subtest: rcs0
[456.474291] Dynamic subtest rcs0: SUCCESS (0.273s)
[456.484561] Starting dynamic subtest: bcs0
[456.787470] Dynamic subtest bcs0: SUCCESS (0.312s)
[456.799582] Starting dynamic subtest: vcs0
[457.098656] Dynamic subtest vcs0: SUCCESS (0.310s)
[457.108207] Starting dynamic subtest: vcs1
[459.240144] Dynamic subtest vcs1: SUCCESS (2.140s)
[459.252215] Starting dynamic subtest: vecs0
[461.193290] Dynamic subtest vecs0: SUCCESS (1.952s)
[461.203198] Starting dynamic subtest: ccs0
[463.260281] Dynamic subtest ccs0: SUCCESS (2.066s)
[463.262654] Subtest nonpriv-switch: SUCCESS (7.059s)
[463.491149] [102/133] (566s left) kms_psr (pr-primary-mmap-cpu)
[463.594340] Starting subtest: pr-primary-mmap-cpu
[463.598666] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[463.667871] [103/133] (566s left) gem_tiled_wc
[463.798856] [104/133] (566s left) kms_big_fb (yf-tiled-addfb)
[463.913566] Starting subtest: yf-tiled-addfb
[463.918068] Subtest yf-tiled-addfb: SKIP (0.000s)
[464.005374] [105/133] (566s left) gem_mmap_gtt (fault-concurrent-y)
[464.069183] Subtest fault-concurrent-Y: SKIP (0.000s)
[464.136490] [106/133] (565s left) i915_module_load (reload-no-display)
[464.175545] Starting subtest: reload-no-display
[470.100801] Subtest reload-no-display: SUCCESS (5.924s)
[470.131556] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[470.132335] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[470.135599] [107/133] (559s left) kms_flip (flip-vs-rmfb)
[471.745357] Starting subtest: flip-vs-rmfb
[471.747785] Starting dynamic subtest: A-eDP1
[480.428245] Dynamic subtest A-eDP1: SUCCESS (8.682s)
[480.438422] Starting dynamic subtest: B-eDP1
[489.058952] Dynamic subtest B-eDP1: SUCCESS (8.628s)
[489.069305] Starting dynamic subtest: C-eDP1
[497.679644] Dynamic subtest C-eDP1: SUCCESS (8.620s)
[497.691931] Starting dynamic subtest: D-eDP1
[506.306535] Dynamic subtest D-eDP1: SUCCESS (8.624s)
[506.311136] Subtest flip-vs-rmfb: SUCCESS (34.566s)
[507.539433] [FACT kms_flip (flip-vs-rmfb)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[507.540852] [FACT kms_flip (flip-vs-rmfb)] new: kernel.kmod_is_loaded.i915: true
[507.545342] [108/133] (522s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[507.639193] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[507.643181] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[507.734887] [109/133] (522s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[507.819606] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[507.823579] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[507.940273] [110/133] (522s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[508.029949] Starting subtest: fbc-farfromfence-mmap-gtt
[508.034409] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[508.132248] [111/133] (522s left) gem_exec_endless (dispatch)
[508.200650] Starting subtest: dispatch
[508.202958] Starting dynamic subtest: rcs0
[508.210578] Dynamic subtest rcs0: SUCCESS (0.008s)
[508.222219] Starting dynamic subtest: bcs0
[508.226288] Dynamic subtest bcs0: SUCCESS (0.006s)
[508.235458] Starting dynamic subtest: vcs0
[508.239900] Dynamic subtest vcs0: SUCCESS (0.006s)
[508.249839] Starting dynamic subtest: vcs1
[508.254357] Dynamic subtest vcs1: SUCCESS (0.009s)
[508.263169] Starting dynamic subtest: vecs0
[508.267106] Dynamic subtest vecs0: SUCCESS (0.007s)
[508.275527] Starting dynamic subtest: ccs0
[508.279292] Dynamic subtest ccs0: SUCCESS (0.006s)
[508.281115] Subtest dispatch: SUCCESS (0.050s)
[509.965136] [112/133] (520s left) gem_mmap_gtt (basic-copy)
[510.038523] Subtest basic-copy: SKIP (0.000s)
[510.097813] [113/133] (520s left) gem_exec_schedule (implicit-read-write)
[510.168692] Starting subtest: implicit-read-write
[510.170656] Starting dynamic subtest: rcs0
[510.264646] Dynamic subtest rcs0: SUCCESS (0.106s)
[510.276806] Starting dynamic subtest: vcs0
[510.343827] Dynamic subtest vcs0: SUCCESS (0.079s)
[510.355685] Starting dynamic subtest: vcs1
[510.423237] Dynamic subtest vcs1: SUCCESS (0.077s)
[510.435570] Starting dynamic subtest: vecs0
[510.516483] Dynamic subtest vecs0: SUCCESS (0.089s)
[510.528167] Starting dynamic subtest: ccs0
[510.656987] Dynamic subtest ccs0: SUCCESS (0.141s)
[510.659658] Subtest implicit-read-write: SUCCESS (0.499s)
[511.978334] [114/133] (518s left) drm_read (short-buffer-block)
[512.131275] Starting subtest: short-buffer-block
[512.133637] Subtest short-buffer-block: SUCCESS (0.000s)
[512.255709] [115/133] (518s left) kms_color (ctm-0-50)
[512.347912] Starting subtest: ctm-0-50
[512.350183] Starting dynamic subtest: pipe-A-eDP-1
[515.918355] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.569s)
[515.928575] Starting dynamic subtest: pipe-B-eDP-1
[520.211308] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.294s)
[520.223697] Starting dynamic subtest: pipe-C-eDP-1
[524.274032] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.062s)
[524.290057] Starting dynamic subtest: pipe-D-eDP-1
[528.271160] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.993s)
[528.273300] Subtest ctm-0-50: SUCCESS (15.922s)
[529.100871] [116/133] (501s left) gem_ppgtt (shrink-vs-evict-pinned)
[529.152676] Starting subtest: shrink-vs-evict-pinned
[534.894502] Subtest shrink-vs-evict-pinned: SUCCESS (5.740s)
[534.984824] [117/133] (495s left) gem_exec_whisper (basic-queues-forked-all)
[535.051346] Starting subtest: basic-queues-forked-all
[553.453804] Subtest basic-queues-forked-all: SUCCESS (18.397s)
[553.653505] [118/133] (476s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[553.751096] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[553.753331] Starting dynamic subtest: pipe-A-eDP-1
[554.608717] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.849s)
[554.619598] Starting dynamic subtest: pipe-B-eDP-1
[556.282682] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.669s)
[556.294747] Starting dynamic subtest: pipe-C-eDP-1
[557.848259] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.569s)
[557.858488] Starting dynamic subtest: pipe-D-eDP-1
[559.354193] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.503s)
[559.356342] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.595s)
[560.522704] [119/133] (470s left) kms_pm_rpm (system-suspend-idle)
[562.045412] Starting subtest: system-suspend-idle
[581.775031] Subtest system-suspend-idle: SUCCESS (4.640s)
[582.150529] [120/133] (448s left) perf (invalid-open-flags)
[582.298252] Starting subtest: invalid-open-flags
[582.300408] Subtest invalid-open-flags: SUCCESS (0.000s)
[582.339517] [121/133] (448s left) prime_mmap (test_invalid_sync_flags)
[582.390037] Starting subtest: test_invalid_sync_flags
[582.392110] Starting dynamic subtest: test_invalid_sync_flags-smem
[582.393909] Dynamic subtest test_invalid_sync_flags-smem: SUCCESS (0.000s)
[582.396101] Subtest test_invalid_sync_flags: SUCCESS (0.001s)
[582.482642] [122/133] (448s left) kms_pipe_stress (stress-xrgb8888-yftiled)
[582.566101] Starting subtest: stress-xrgb8888-yftiled
[582.570403] Subtest stress-xrgb8888-yftiled: SKIP (0.000s)
[582.636110] [123/133] (448s left) kms_async_flips (alternate-sync-async-flip-atomic)
[582.734167] Starting subtest: alternate-sync-async-flip-atomic
[583.110696] Starting dynamic subtest: pipe-A-eDP-1
[585.886072] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.839s)
[586.273974] Starting dynamic subtest: pipe-B-eDP-1
[589.057921] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.836s)
[589.449271] Starting dynamic subtest: pipe-C-eDP-1
[592.242069] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.834s)
[592.607934] Starting dynamic subtest: pipe-D-eDP-1
[595.340062] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.763s)
[595.342114] Subtest alternate-sync-async-flip-atomic: SUCCESS (12.786s)
[596.505704] [124/133] (434s left) gem_exec_balancer (parallel-balancer)
[596.760742] Starting subtest: parallel-balancer
[597.666610] Subtest parallel-balancer: SUCCESS (0.910s)
[597.777491] [125/133] (433s left) dumb_buffer (invalid-bpp)
[597.830182] Starting subtest: invalid-bpp
[597.832428] Subtest invalid-bpp: SUCCESS (0.000s)
[597.946778] [126/133] (432s left) gem_gtt_cpu_tlb
[598.072682] [127/133] (432s left) kms_big_fb (linear-16bpp-rotate-270)
[598.172062] Starting subtest: linear-16bpp-rotate-270
[598.192414] Subtest linear-16bpp-rotate-270: SKIP (0.020s)
[598.320148] [128/133] (432s left) gem_exec_alignment (pi)
[598.375059] Starting subtest: pi
[650.685497] Subtest pi: SUCCESS (52.331s)
[651.752691] [129/133] (379s left) syncobj_timeline (reset-multiple-signaled)
[651.859427] Starting subtest: reset-multiple-signaled
[651.861800] Subtest reset-multiple-signaled: SUCCESS (0.002s)
[651.940752] [130/133] (379s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[652.070825] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[652.075725] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[652.185362] [131/133] (378s left) gem_fence_thrash (bo-write-verify-none)
[652.228410] Starting subtest: bo-write-verify-none
[652.249200] Subtest bo-write-verify-none: SKIP (0.019s)
[652.311933] [132/133] (378s left) kms_frontbuffer_tracking (fbc-tiling-4)
[652.406529] Starting subtest: fbc-tiling-4
[656.597210] Subtest fbc-tiling-4: SUCCESS (4.195s)
[657.763993] [133/133] (373s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[657.870278] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[657.874649] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[657.959442] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: syncobj_timeline
results: parsing output: 3/ for test: kms_plane
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_cursor_legacy
results: parsing output: 6/ for test: kms_addfb_basic
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: kms_pm_rpm
results: parsing output: 9/ for test: gem_basic
results: parsing output: 10/ for test: kms_flip
results: parsing output: 11/ for test: gen9_exec_parse
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: gem_fenced_exec_thrash
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: syncobj_wait
results: parsing output: 16/ for test: perf
results: parsing output: 17/ for test: kms_scaling_modes
results: parsing output: 18/ for test: gem_exec_schedule
results: parsing output: 19/ for test: kms_lease
results: parsing output: 20/ for test: i915_query
results: parsing output: 21/ for test: kms_invalid_mode
results: parsing output: 22/ for test: kms_flip
results: parsing output: 23/ for test: kms_plane_scaling
results: parsing output: 24/ for test: kms_psr2_sf
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_flip
results: parsing output: 27/ for test: kms_plane_lowres
results: parsing output: 28/ for test: kms_content_protection
results: parsing output: 29/ for test: sysfs_timeslice_duration
results: parsing output: 30/ for test: prime_self_import
results: parsing output: 31/ for test: kms_cursor_legacy
results: parsing output: 32/ for test: kms_flip_scaled_crc
results: parsing output: 33/ for test: drm_read
results: parsing output: 34/ for test: kms_psr
results: parsing output: 35/ for test: core_getclient
results: parsing output: 36/ for test: kms_flip
results: parsing output: 37/ for test: kms_cursor_crc
results: parsing output: 38/ for test: perf_pmu
results: parsing output: 39/ for test: kms_rotation_crc
results: parsing output: 40/ for test: gem_ccs
results: parsing output: 41/ for test: gem_ringfill
results: parsing output: 42/ for test: kms_ccs
results: parsing output: 43/ for test: syncobj_timeline
results: parsing output: 44/ for test: gem_exec_whisper
results: parsing output: 45/ for test: gen3_render_mixed_blits
results: parsing output: 46/ for test: kms_plane_scaling
results: parsing output: 47/ for test: gem_mmap_gtt
results: parsing output: 48/ for test: prime_busy
results: parsing output: 49/ for test: kms_hdr
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_chamelium_color
results: parsing output: 52/ for test: kms_psr2_sf
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_cursor_legacy
results: parsing output: 56/ for test: kms_getfb
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: gem_exec_fence
results: parsing output: 60/ for test: kms_big_fb
results: parsing output: 61/ for test: gen7_exec_parse
results: parsing output: 62/ for test: gem_eio
results: parsing output: 63/ for test: syncobj_wait
results: parsing output: 64/ for test: kms_ccs
results: parsing output: 65/ for test: testdisplay
results: parsing output: 66/ for test: kms_big_fb
results: parsing output: 67/ for test: kms_universal_plane
results: parsing output: 68/ for test: gem_exec_fence
results: parsing output: 69/ for test: syncobj_timeline
results: parsing output: 70/ for test: gem_ctx_persistence
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: syncobj_wait
results: parsing output: 73/ for test: gem_render_copy
results: parsing output: 74/ for test: kms_vblank
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_big_fb
results: parsing output: 77/ for test: kms_chamelium_color
results: parsing output: 78/ for test: gem_create
results: parsing output: 79/ for test: syncobj_wait
results: parsing output: 80/ for test: gem_fence_thrash
results: parsing output: 81/ for test: kms_cursor_crc
results: parsing output: 82/ for test: kms_async_flips
results: parsing output: 83/ for test: gem_exec_flush
results: parsing output: 84/ for test: kms_cursor_legacy
results: parsing output: 85/ for test: i915_pm_rps
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_psr
results: parsing output: 88/ for test: kms_draw_crc
results: parsing output: 89/ for test: kms_cursor_crc
results: parsing output: 90/ for test: kms_flip_scaled_crc
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: i915_drm_fdinfo
results: parsing output: 93/ for test: kms_chamelium_hpd
results: parsing output: 94/ for test: gem_ctx_param
results: parsing output: 95/ for test: kms_addfb_basic
results: parsing output: 96/ for test: sw_sync
results: parsing output: 97/ for test: api_intel_allocator
results: parsing output: 98/ for test: gem_spin_batch
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_ctx_isolation
results: parsing output: 101/ for test: kms_psr
results: parsing output: 102/ for test: gem_tiled_wc
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: gem_mmap_gtt
results: parsing output: 105/ for test: i915_module_load
results: parsing output: 106/ for test: kms_flip
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: gem_exec_endless
results: parsing output: 111/ for test: gem_mmap_gtt
results: parsing output: 112/ for test: gem_exec_schedule
results: parsing output: 113/ for test: drm_read
results: parsing output: 114/ for test: kms_color
results: parsing output: 115/ for test: gem_ppgtt
results: parsing output: 116/ for test: gem_exec_whisper
results: parsing output: 117/ for test: kms_ccs
results: parsing output: 118/ for test: kms_pm_rpm
results: parsing output: 119/ for test: perf
results: parsing output: 120/ for test: prime_mmap
results: parsing output: 121/ for test: kms_pipe_stress
results: parsing output: 122/ for test: kms_async_flips
results: parsing output: 123/ for test: gem_exec_balancer
results: parsing output: 124/ for test: dumb_buffer
results: parsing output: 125/ for test: gem_gtt_cpu_tlb
results: parsing output: 126/ for test: kms_big_fb
results: parsing output: 127/ for test: gem_exec_alignment
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_fence_thrash
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_big_fb
Done.