[57.208769] Initializing watchdogs
[57.208821]   /dev/watchdog0
[57.234671] [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.239073] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.241494] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.244925] [001/155] (960s left) gem_exec_fence (syncobj-signal)
[57.731211] Starting subtest: syncobj-signal
[57.738373] Subtest syncobj-signal: SUCCESS (0.008s)
[59.418362] [FACT gem_exec_fence (syncobj-signal)] new: kernel.kmod_is_loaded.vgem: true
[59.423876] [002/155] (958s left) syncobj_wait (wait-for-submit-snapshot)
[59.475982] Starting subtest: wait-for-submit-snapshot
[59.558969] Subtest wait-for-submit-snapshot: SUCCESS (0.081s)
[59.635153] [003/155] (958s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-blt)
[59.755608] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-blt
[59.760289] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[59.851793] [004/155] (958s left) syncobj_wait (reset-multiple-signaled)
[59.903198] Starting subtest: reset-multiple-signaled
[59.905205] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[60.004241] [005/155] (957s left) kms_psr (fbc-pr-suspend)
[60.103974] Starting subtest: fbc-pr-suspend
[60.108104] Subtest fbc-pr-suspend: SKIP (0.000s)
[60.187107] [006/155] (957s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[60.284299] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf
[60.288881] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[60.355932] [007/155] (957s left) kms_content_protection (uevent-hdcp14)
[60.504692] Starting subtest: uevent-hdcp14
[60.508817] Subtest uevent-hdcp14: SKIP (0.000s)
[60.631055] [008/155] (957s left) gem_tiled_pread_pwrite
[60.734977] [009/155] (957s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-msflip-blt)
[60.836105] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-msflip-blt
[60.839862] Subtest fbcpsrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[60.947633] [010/155] (957s left) kms_frontbuffer_tracking (fbcpsrhdr-slowdraw)
[61.057769] Starting subtest: fbcpsrhdr-slowdraw
[61.061540] Subtest fbcpsrhdr-slowdraw: SKIP (0.000s)
[61.186774] [011/155] (956s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[61.285400] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-wc
[61.289052] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[61.384940] [012/155] (956s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-render)
[61.484349] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-render
[61.487846] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[61.591621] [013/155] (956s left) syncobj_basic (bad-create-flags)
[61.641477] Starting subtest: bad-create-flags
[61.643471] Subtest bad-create-flags: SUCCESS (0.000s)
[61.746763] [014/155] (956s left) kms_flip (flip-vs-suspend)
[61.851147] Starting subtest: flip-vs-suspend
[61.853134] Starting dynamic subtest: A-eDP1
[207.093423] Dynamic subtest A-eDP1: SUCCESS (12.194s)
[207.096406] Subtest flip-vs-suspend: SUCCESS (12.196s)
[207.237483] [015/155] (811s left) kms_flip (2x-flip-vs-dpms)
[207.328192] Starting subtest: 2x-flip-vs-dpms
[207.338634] Subtest 2x-flip-vs-dpms: SKIP (0.010s)
[207.417558] [016/155] (810s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[207.529009] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[207.533391] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[207.637975] [017/155] (810s left) gem_eio (execbuf)
[207.806099] Starting subtest: execbuf
[208.022591] Subtest execbuf: SUCCESS (0.209s)
[208.140832] [018/155] (810s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[208.240254] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[208.243730] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[208.346551] [019/155] (810s left) gem_pxp (regular-baseline-src-copy-readible)
[208.461513] Starting subtest: regular-baseline-src-copy-readible
[208.522577] Subtest regular-baseline-src-copy-readible: SUCCESS (0.060s)
[208.659823] [020/155] (809s left) gem_exec_params (dr1-dirt)
[208.711700] Starting subtest: DR1-dirt
[208.713321] Subtest DR1-dirt: SUCCESS (0.000s)
[208.812295] [021/155] (809s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[208.863672] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[208.922885] [022/155] (809s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-blt)
[209.017450] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-blt
[209.021287] Subtest fbchdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[209.127378] [023/155] (809s left) kms_properties (get_properties-sanity-non-atomic)
[209.217020] Starting subtest: get_properties-sanity-non-atomic
[209.219205] Subtest get_properties-sanity-non-atomic: SUCCESS (0.001s)
[209.293821] [024/155] (809s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[209.393284] Starting subtest: plane-lut1d-ctm3x4-lut1d
[209.554759] Starting dynamic subtest: pipe-A-plane-0
[209.738776] Dynamic subtest pipe-A-plane-0: SUCCESS (0.178s)
[209.748499] Starting dynamic subtest: pipe-A-plane-1
[209.910772] Dynamic subtest pipe-A-plane-1: SUCCESS (0.165s)
[209.920762] Starting dynamic subtest: pipe-A-plane-2
[210.048633] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s)
[211.288364] Starting dynamic subtest: pipe-B-plane-0
[211.483016] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s)
[211.493901] Starting dynamic subtest: pipe-B-plane-1
[211.631807] Dynamic subtest pipe-B-plane-1: SUCCESS (0.144s)
[211.642080] Starting dynamic subtest: pipe-B-plane-2
[211.804559] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[213.093492] Starting dynamic subtest: pipe-C-plane-0
[213.222962] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[213.232424] Starting dynamic subtest: pipe-C-plane-1
[213.393388] Dynamic subtest pipe-C-plane-1: SUCCESS (0.166s)
[213.405468] Starting dynamic subtest: pipe-C-plane-2
[213.553789] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[214.730973] Starting dynamic subtest: pipe-D-plane-0
[214.869413] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[214.878408] Starting dynamic subtest: pipe-D-plane-1
[215.005121] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[215.015128] Starting dynamic subtest: pipe-D-plane-2
[215.141001] Dynamic subtest pipe-D-plane-2: SUCCESS (0.133s)
[215.434053] Subtest plane-lut1d-ctm3x4-lut1d: SUCCESS (5.892s)
[216.332412] [025/155] (802s left) kms_chamelium_hpd (dp-hpd-storm)
[216.737894] Subtest dp-hpd-storm: SKIP (0.000s)
[217.567519] [026/155] (801s left) kms_vblank (ts-continuation-idle-hang)
[217.662710] Starting subtest: ts-continuation-idle-hang
[217.664672] Starting dynamic subtest: pipe-A-eDP-1
[244.010787] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.252s)
[244.024318] Starting dynamic subtest: pipe-D-eDP-1
[259.049728] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.029s)
[259.051669] Subtest ts-continuation-idle-hang: SUCCESS (41.285s)
[259.847258] [027/155] (758s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-factor-0-25)
[259.942701] Starting subtest: planes-downscale-factor-0-5-upscale-factor-0-25
[259.944968] Starting dynamic subtest: pipe-A
[259.960453] Dynamic subtest pipe-A: SKIP (0.017s)
[259.970736] Starting dynamic subtest: pipe-B
[259.990694] Dynamic subtest pipe-B: SKIP (0.030s)
[259.999837] Starting dynamic subtest: pipe-C
[260.023223] Dynamic subtest pipe-C: SKIP (0.030s)
[260.032111] Starting dynamic subtest: pipe-D
[260.051527] Dynamic subtest pipe-D: SKIP (0.030s)
[260.055254] Subtest planes-downscale-factor-0-5-upscale-factor-0-25: SKIP (0.108s)
[260.162739] [028/155] (758s left) gem_ctx_exec (basic-invalid-context)
[260.216854] Starting subtest: basic-invalid-context
[260.221934] Subtest basic-invalid-context: SUCCESS (0.006s)
[260.349290] [029/155] (758s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[260.444261] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-gtt
[260.448374] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[260.540381] [030/155] (758s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[260.631438] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[264.971433] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.344s)
[266.145925] [031/155] (752s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[266.247649] Starting subtest: plane-upscale-factor-0-25-with-rotation
[266.250099] Starting dynamic subtest: pipe-A
[266.472105] Dynamic subtest pipe-A: SUCCESS (0.226s)
[266.481943] Starting dynamic subtest: pipe-B
[267.818931] Dynamic subtest pipe-B: SUCCESS (1.346s)
[267.828400] Starting dynamic subtest: pipe-C
[269.153023] Dynamic subtest pipe-C: SUCCESS (1.332s)
[269.164247] Starting dynamic subtest: pipe-D
[270.486146] Dynamic subtest pipe-D: SUCCESS (1.332s)
[270.488358] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.239s)
[271.672143] [032/155] (747s left) kms_flip (modeset-vs-vblank-race)
[271.776580] Starting subtest: modeset-vs-vblank-race
[271.778789] Starting dynamic subtest: A-eDP1
[276.596872] Dynamic subtest A-eDP1: SUCCESS (4.818s)
[276.609253] Starting dynamic subtest: B-eDP1
[281.440251] Dynamic subtest B-eDP1: SUCCESS (4.839s)
[281.455200] Starting dynamic subtest: C-eDP1
[286.138554] Dynamic subtest C-eDP1: SUCCESS (4.694s)
[286.149425] Starting dynamic subtest: D-eDP1
[290.892907] Dynamic subtest D-eDP1: SUCCESS (4.751s)
[290.895321] Subtest modeset-vs-vblank-race: SUCCESS (19.112s)
[292.050035] [033/155] (726s left) kms_pm_dc (dc6-dpms)
[293.151044] Starting subtest: dc6-dpms
[295.227619] Subtest dc6-dpms: SUCCESS (2.075s)
[295.306923] [034/155] (723s left) perf (oa-formats)
[295.421591] Starting subtest: oa-formats
[295.423753] Starting dynamic subtest: 0-rcs0
[295.437986] Dynamic subtest 0-rcs0: SUCCESS (0.017s)
[295.447405] Starting dynamic subtest: 1-vecs0
[295.460719] Dynamic subtest 1-vecs0: SUCCESS (0.022s)
[295.463007] Subtest oa-formats: SUCCESS (0.041s)
[295.509261] [035/155] (723s left) gem_pread (snoop)
[295.570387] Subtest snoop: SKIP (0.000s)
[295.630096] [036/155] (723s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-move)
[295.730341] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-move
[295.734600] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[295.849293] [037/155] (723s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-pwrite)
[295.950773] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-pwrite
[295.954908] Subtest hdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[296.043215] [038/155] (723s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs)
[296.135194] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs
[296.137368] Starting dynamic subtest: pipe-A-eDP-1
[296.146394] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[296.155903] Starting dynamic subtest: pipe-B-eDP-1
[296.164987] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[296.173996] Starting dynamic subtest: pipe-C-eDP-1
[296.183002] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[296.191498] Starting dynamic subtest: pipe-D-eDP-1
[296.200141] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[296.204540] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[298.439199] [039/155] (720s left) kms_psr (fbc-psr-basic)
[298.525742] Starting subtest: fbc-psr-basic
[298.528072] Starting dynamic subtest: eDP-1
[298.532447] Dynamic subtest eDP-1: SKIP (0.000s)
[298.536902] Subtest fbc-psr-basic: SKIP (0.001s)
[298.603137] [040/155] (720s left) prime_self_import (export-vs-gem_close-race)
[298.638832] Starting subtest: export-vs-gem_close-race
[303.716685] Subtest export-vs-gem_close-race: SUCCESS (5.078s)
[303.796074] [041/155] (715s left) gem_pxp (create-valid-protected-context)
[303.858244] Starting subtest: create-valid-protected-context
[303.860786] Subtest create-valid-protected-context: SUCCESS (0.002s)
[303.974459] [042/155] (715s left) kms_ccs (bad-rotation-90-4-tiled-lnl-ccs)
[304.076972] Starting subtest: bad-rotation-90-4-tiled-lnl-ccs
[304.081676] Subtest bad-rotation-90-4-tiled-lnl-ccs: SKIP (0.000s)
[305.173629] [043/155] (714s left) gem_eio (wait-wedge-1us)
[305.351152] Starting subtest: wait-wedge-1us
[307.283285] Subtest wait-wedge-1us: SUCCESS (1.933s)
[307.442027] [044/155] (712s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-move)
[307.548157] Starting subtest: fbchdr-2p-primscrn-cur-indfb-move
[307.552294] Subtest fbchdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[307.644991] [045/155] (711s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[307.748780] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[307.753208] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[307.850281] [046/155] (711s left) gem_ccs (ctrl-surf-copy)
[307.916095] Starting subtest: ctrl-surf-copy
[307.920229] Subtest ctrl-surf-copy: SKIP (0.000s)
[308.040083] [047/155] (711s left) i915_query (query-topology-coherent-slice-mask)
[308.084545] Starting subtest: query-topology-coherent-slice-mask
[308.088762] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[308.200087] [048/155] (711s left) vgem_basic (setversion)
[308.242725] Starting subtest: setversion
[308.246264] Subtest setversion: SUCCESS (0.000s)
[308.298585] [049/155] (711s left) kms_chamelium_frames (hdmi-crc-fast)
[308.665552] Subtest hdmi-crc-fast: SKIP (0.000s)
[309.485881] [050/155] (710s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[309.580697] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[309.582946] Starting dynamic subtest: pipe-A-eDP-1
[310.369510] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.782s)
[310.381192] Starting dynamic subtest: pipe-B-eDP-1
[311.943575] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.570s)
[311.955598] Starting dynamic subtest: pipe-C-eDP-1
[313.486205] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.543s)
[313.496107] Starting dynamic subtest: pipe-D-eDP-1
[314.963591] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.476s)
[314.965957] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.376s)
[316.144326] [051/155] (703s left) i915_pm_rpm (gem-execbuf-stress)
[318.395072] Starting subtest: gem-execbuf-stress
[318.397238] Starting dynamic subtest: smem0
[331.323951] Dynamic subtest smem0: SUCCESS (12.934s)
[331.335504] Starting dynamic subtest: extra-wait-smem0
[394.278979] Dynamic subtest extra-wait-smem0: SUCCESS (62.939s)
[394.281731] Subtest gem-execbuf-stress: SUCCESS (75.878s)
[394.660722] [052/155] (625s left) gem_softpin (safe-alignment)
[394.729013] Starting subtest: safe-alignment
[394.752809] Subtest safe-alignment: SUCCESS (0.024s)
[396.310988] [053/155] (623s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[396.412222] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[397.447267] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.036s)
[397.556919] [054/155] (622s left) gem_eio (in-flight-internal-10ms)
[397.738750] Starting subtest: in-flight-internal-10ms
[399.315170] Subtest in-flight-internal-10ms: SUCCESS (1.575s)
[399.433598] [055/155] (620s left) gem_ctx_persistence (legacy-engines-cleanup)
[399.478628] Starting subtest: legacy-engines-cleanup
[399.480901] Starting dynamic subtest: render
[399.553128] Dynamic subtest render: SUCCESS (0.074s)
[399.568077] Starting dynamic subtest: bsd1
[399.590979] Dynamic subtest bsd1: SUCCESS (0.035s)
[399.602963] Starting dynamic subtest: bsd2
[399.635254] Dynamic subtest bsd2: SUCCESS (0.041s)
[399.648387] Starting dynamic subtest: blt
[399.682389] Dynamic subtest blt: SUCCESS (0.044s)
[399.694983] Starting dynamic subtest: vebox
[399.724067] Dynamic subtest vebox: SUCCESS (0.039s)
[399.726495] Subtest legacy-engines-cleanup: SUCCESS (0.246s)
[399.805512] [056/155] (620s left) gem_exec_fence (expired-history)
[400.216300] Starting subtest: expired-history
[407.005105] Subtest expired-history: SUCCESS (6.787s)
[407.525578] [057/155] (612s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[407.629668] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[407.634041] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[407.720462] [058/155] (612s left) kms_plane (plane-panning-bottom-right)
[407.807921] Starting subtest: plane-panning-bottom-right
[407.810112] Starting dynamic subtest: pipe-A
[409.529851] Dynamic subtest pipe-A: SUCCESS (1.723s)
[409.539846] Starting dynamic subtest: pipe-B
[411.994958] Dynamic subtest pipe-B: SUCCESS (2.461s)
[411.997259] Subtest plane-panning-bottom-right: SUCCESS (4.187s)
[412.800104] [059/155] (607s left) sw_sync (sync_expired_merge)
[412.840956] Starting subtest: sync_expired_merge
[413.915022] Subtest sync_expired_merge: SUCCESS (1.074s)
[413.978744] [060/155] (606s left) gem_exercise_blt (fast-copy)
[414.028511] Starting subtest: fast-copy
[414.030836] Starting dynamic subtest: linear-smem-smem
[414.048267] Dynamic subtest linear-smem-smem: SUCCESS (0.022s)
[414.058812] Starting dynamic subtest: xmajor-smem-smem
[414.065329] Dynamic subtest xmajor-smem-smem: SUCCESS (0.017s)
[414.074888] Starting dynamic subtest: tile4-smem-smem
[414.082110] Dynamic subtest tile4-smem-smem: SUCCESS (0.016s)
[414.090946] Starting dynamic subtest: tile64-smem-smem
[414.099124] Dynamic subtest tile64-smem-smem: SUCCESS (0.016s)
[414.101446] Subtest fast-copy: SUCCESS (0.073s)
[414.205157] [061/155] (605s left) kms_big_fb (4-tiled-addfb)
[414.295323] Starting subtest: 4-tiled-addfb
[414.297713] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[414.387262] [062/155] (605s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[414.440255] Starting subtest: etime-single-wait-all-available-unsubmitted
[414.541666] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.102s)
[414.619566] [063/155] (605s left) prime_busy (before-wait)
[414.674393] Starting subtest: before-wait
[414.680259] Starting dynamic subtest: rcs0
[414.714698] Dynamic subtest rcs0: SUCCESS (0.037s)
[414.726900] Starting dynamic subtest: bcs0
[414.781131] Dynamic subtest bcs0: SUCCESS (0.065s)
[414.792351] Starting dynamic subtest: vcs0
[414.821607] Dynamic subtest vcs0: SUCCESS (0.041s)
[414.830724] Starting dynamic subtest: vcs1
[414.863547] Dynamic subtest vcs1: SUCCESS (0.040s)
[414.872865] Starting dynamic subtest: vecs0
[414.905815] Dynamic subtest vecs0: SUCCESS (0.041s)
[414.915317] Starting dynamic subtest: ccs0
[414.946146] Dynamic subtest ccs0: SUCCESS (0.039s)
[415.054209] Subtest before-wait: SUCCESS (0.378s)
[415.142495] [064/155] (605s left) kms_vrr (seamless-rr-switch-vrr)
[415.245303] Starting subtest: seamless-rr-switch-vrr
[415.251763] Subtest seamless-rr-switch-vrr: SKIP (0.000s)
[415.329135] [065/155] (605s left) kms_cursor_crc (cursor-offscreen-128x128)
[415.551546] Starting subtest: cursor-offscreen-128x128
[415.554172] Starting dynamic subtest: pipe-A-eDP-1
[417.482335] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.928s)
[417.513629] Starting dynamic subtest: pipe-D-eDP-1
[420.124679] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.618s)
[420.126872] Subtest cursor-offscreen-128x128: SUCCESS (4.574s)
[420.970101] [066/155] (599s left) kms_frontbuffer_tracking (fbcpsr-2p-pri-indfb-multidraw)
[421.075178] Starting subtest: fbcpsr-2p-pri-indfb-multidraw
[421.079477] Subtest fbcpsr-2p-pri-indfb-multidraw: SKIP (0.000s)
[421.174754] [067/155] (599s left) kms_pm_rpm (pm-caching)
[423.402311] Starting subtest: pm-caching
[423.406852] Subtest pm-caching: SKIP (0.000s)
[423.801546] [068/155] (596s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-pwrite)
[423.899332] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-pwrite
[423.903372] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[423.999814] [069/155] (596s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[424.097067] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[424.109339] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.010s)
[424.202411] [070/155] (596s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[424.297486] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[424.308719] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.010s)
[424.411799] [071/155] (596s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[424.541293] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[435.720326] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.181s)
[436.538934] [072/155] (584s left) gem_exec_schedule (u-semaphore-user)
[436.609977] Starting subtest: u-semaphore-user
[436.659963] Subtest u-semaphore-user: SUCCESS (0.059s)
[438.292821] [073/155] (582s left) syncobj_eventfd (timeline-wait-before-signal)
[438.342233] Starting subtest: timeline-wait-before-signal
[438.373245] Subtest timeline-wait-before-signal: SUCCESS (0.031s)
[438.463344] [074/155] (582s left) kms_psr (fbc-pr-cursor-blt)
[438.556700] Starting subtest: fbc-pr-cursor-blt
[438.560893] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[438.641771] [075/155] (582s left) kms_flip (flip-vs-panning-interruptible)
[438.729615] Starting subtest: flip-vs-panning-interruptible
[438.735589] Starting dynamic subtest: A-eDP1
[447.442036] Dynamic subtest A-eDP1: SUCCESS (8.712s)
[447.454109] Starting dynamic subtest: B-eDP1
[456.172759] Dynamic subtest B-eDP1: SUCCESS (8.730s)
[456.186149] Starting dynamic subtest: C-eDP1
[464.886512] Dynamic subtest C-eDP1: SUCCESS (8.714s)
[464.896151] Starting dynamic subtest: D-eDP1
[473.621113] Dynamic subtest D-eDP1: SUCCESS (8.734s)
[473.623123] Subtest flip-vs-panning-interruptible: SUCCESS (34.900s)
[474.783822] [076/155] (546s left) syncobj_timeline (single-wait-all-available-signaled)
[474.839794] Starting subtest: single-wait-all-available-signaled
[474.842239] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[474.949588] [077/155] (545s left) kms_psr (psr-sprite-render)
[475.050693] Starting subtest: psr-sprite-render
[475.052890] Starting dynamic subtest: eDP-1
[476.794519] Dynamic subtest eDP-1: SUCCESS (1.742s)
[476.796585] Subtest psr-sprite-render: SUCCESS (1.743s)
[477.656856] [078/155] (543s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[477.752446] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[486.496460] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (8.749s)
[486.611480] [079/155] (534s left) kms_chamelium_color (ctm-green-to-red)
[486.705105] Subtest ctm-green-to-red: SKIP (0.000s)
[486.752609] [080/155] (534s left) kms_addfb_basic (bad-pitch-1024)
[486.802826] Starting subtest: bad-pitch-1024
[486.805384] Subtest bad-pitch-1024: SUCCESS (0.000s)
[486.939630] [081/155] (534s left) syncobj_basic (bad-pad-handle-to-fd)
[486.991036] Starting subtest: bad-pad-handle-to-fd
[486.992979] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[487.107351] [082/155] (533s left) kms_frontbuffer_tracking (psrhdr-2p-pri-indfb-multidraw)
[487.198220] Starting subtest: psrhdr-2p-pri-indfb-multidraw
[487.202610] Subtest psrhdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[487.288802] [083/155] (533s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[487.372220] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[487.374552] Starting dynamic subtest: pipe-A-eDP-1
[487.431511] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[487.442589] Starting dynamic subtest: pipe-B-eDP-1
[487.491398] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[487.502138] Starting dynamic subtest: pipe-C-eDP-1
[487.549751] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[487.559526] Starting dynamic subtest: pipe-D-eDP-1
[487.606023] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[487.609743] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.008s)
[489.732668] [084/155] (531s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-msflip-blt)
[489.823952] Starting subtest: hdr-1p-primscrn-shrfb-msflip-blt
[489.827881] Subtest hdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[489.909882] [085/155] (531s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[490.006579] Starting subtest: yf-tiled-32bpp-rotate-90
[490.010933] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[490.098501] [086/155] (531s left) i915_drm_fdinfo (busy-idle)
[490.132758] Starting subtest: busy-idle
[490.134764] Starting dynamic subtest: rcs0
[490.155203] Dynamic subtest rcs0: SKIP (0.021s)
[490.166283] Starting dynamic subtest: bcs0
[490.170437] Dynamic subtest bcs0: SKIP (0.000s)
[490.179852] Starting dynamic subtest: vcs0
[490.184055] Dynamic subtest vcs0: SKIP (0.001s)
[490.193065] Starting dynamic subtest: vcs1
[490.196929] Dynamic subtest vcs1: SKIP (0.000s)
[490.205471] Starting dynamic subtest: vecs0
[490.211246] Dynamic subtest vecs0: SKIP (0.000s)
[490.222172] Starting dynamic subtest: ccs0
[490.226160] Dynamic subtest ccs0: SKIP (0.000s)
[490.230145] Subtest busy-idle: SKIP (0.030s)
[490.266272] [087/155] (531s left) kms_hdr (invalid-metadata-sizes)
[490.367440] Starting subtest: invalid-metadata-sizes
[490.373795] Subtest invalid-metadata-sizes: SKIP (0.000s)
[490.453876] [088/155] (530s left) core_setmaster (master-drop-set-shared-fd)
[490.540650] Starting subtest: master-drop-set-shared-fd
[490.548109] Subtest master-drop-set-shared-fd: SUCCESS (0.010s)
[490.587944] [089/155] (530s left) gem_ctx_shared (disjoint-timelines)
[490.652254] Starting subtest: disjoint-timelines
[490.678437] Subtest disjoint-timelines: SUCCESS (0.026s)
[490.900183] [090/155] (530s left) kms_ccs (bad-aux-stride-4-tiled-mtl-mc-ccs)
[490.987958] Starting subtest: bad-aux-stride-4-tiled-mtl-mc-ccs
[490.989881] Starting dynamic subtest: pipe-A-eDP-1
[491.047212] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.060s)
[491.057200] Starting dynamic subtest: pipe-B-eDP-1
[491.107662] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.059s)
[491.117170] Starting dynamic subtest: pipe-C-eDP-1
[491.166437] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.058s)
[491.176717] Starting dynamic subtest: pipe-D-eDP-1
[491.224255] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.057s)
[491.226768] Subtest bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.239s)
[493.477626] [091/155] (527s left) gem_watchdog (default-virtual)
[493.543423] Starting subtest: default-virtual
[499.816008] Subtest default-virtual: SUCCESS (6.281s)
[499.913526] [092/155] (521s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt)
[500.018693] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt
[506.949667] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.934s)
[507.098738] [093/155] (514s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[507.211279] Starting subtest: cursorB-vs-flipA-atomic
[507.215848] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[507.368462] [094/155] (514s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[507.449830] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[507.454252] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.000s)
[507.524895] [095/155] (514s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-move)
[507.622418] Starting subtest: hdr-2p-scndscrn-spr-indfb-move
[507.626621] Subtest hdr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[507.719250] [096/155] (513s left) api_intel_bb (delta-check)
[507.784081] Starting subtest: delta-check
[507.787163] Subtest delta-check: SUCCESS (0.006s)
[507.912757] [097/155] (513s left) perf_pmu (faulting-read)
[507.953799] Starting subtest: faulting-read
[507.955962] Starting dynamic subtest: wb
[507.958276] Dynamic subtest wb: SUCCESS (0.001s)
[507.968257] Starting dynamic subtest: wc
[507.969801] Dynamic subtest wc: SUCCESS (0.001s)
[507.978705] Starting dynamic subtest: uc
[507.980242] Dynamic subtest uc: SUCCESS (0.000s)
[507.981776] Subtest faulting-read: SUCCESS (0.006s)
[508.071159] [098/155] (513s left) perf_pmu (all-busy-idle-check-all)
[508.108154] Starting subtest: all-busy-idle-check-all
[509.282243] Subtest all-busy-idle-check-all: SUCCESS (1.172s)
[509.392536] [099/155] (512s left) kms_feature_discovery (dp-mst)
[509.487928] Starting subtest: dp-mst
[509.492342] Subtest dp-mst: SKIP (0.000s)
[509.561636] [100/155] (512s left) gem_ctx_isolation (clean)
[509.623395] Starting subtest: clean
[509.625825] Starting dynamic subtest: rcs0
[509.667663] Dynamic subtest rcs0: SUCCESS (0.044s)
[509.679332] Starting dynamic subtest: bcs0
[509.745292] Dynamic subtest bcs0: SUCCESS (0.077s)
[509.755970] Starting dynamic subtest: vcs0
[509.804233] Dynamic subtest vcs0: SUCCESS (0.058s)
[509.815815] Starting dynamic subtest: vcs1
[511.263805] Dynamic subtest vcs1: SUCCESS (1.458s)
[511.273871] Starting dynamic subtest: vecs0
[513.272798] Dynamic subtest vecs0: SUCCESS (2.008s)
[513.282815] Starting dynamic subtest: ccs0
[515.258422] Dynamic subtest ccs0: SUCCESS (1.984s)
[515.260877] Subtest clean: SUCCESS (5.635s)
[515.502708] [101/155] (506s left) gem_exec_reloc (basic-concurrent0)
[515.565277] Subtest basic-concurrent0: SKIP (0.000s)
[515.648678] [102/155] (506s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt)
[515.751004] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt
[515.755238] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[515.858675] [103/155] (506s left) gem_mmap_offset (bad-extensions)
[515.913774] Starting subtest: bad-extensions
[515.915671] Subtest bad-extensions: SUCCESS (0.000s)
[515.996940] [104/155] (505s left) gen9_exec_parse (bb-start-cmd)
[516.062205] Subtest bb-start-cmd: SKIP (0.000s)
[516.124306] [105/155] (505s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[516.176489] Subtest tiled-to-tiled: SKIP (0.000s)
[516.261204] [106/155] (505s left) kms_flip (2x-nonexisting-fb-interruptible)
[516.354714] Starting subtest: 2x-nonexisting-fb-interruptible
[516.365493] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.010s)
[516.444240] [107/155] (505s left) kms_lease (lease-invalid-plane)
[516.529046] Starting subtest: lease-invalid-plane
[516.531071] Starting dynamic subtest: pipe-A-eDP-1
[516.533037] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[516.542723] Starting dynamic subtest: pipe-B-eDP-1
[516.544686] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[516.554228] Starting dynamic subtest: pipe-C-eDP-1
[516.556035] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[516.565152] Starting dynamic subtest: pipe-D-eDP-1
[516.566867] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[516.568697] Subtest lease-invalid-plane: SUCCESS (0.004s)
[516.608698] [108/155] (505s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[516.692860] Starting subtest: primary-yf-tiled-reflect-x-180
[516.753321] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[516.856092] [109/155] (505s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[516.964354] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[516.968471] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[517.053704] [110/155] (505s left) gem_userptr_blits (unsync-unmap-after-close)
[517.113602] Subtest unsync-unmap-after-close: SKIP (0.000s)
[517.246175] [111/155] (505s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-blt)
[517.345368] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-blt
[517.348807] Subtest fbchdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[517.457178] [112/155] (504s left) gem_mmap_gtt (bad-object)
[517.525133] Subtest bad-object: SKIP (0.000s)
[517.587430] [113/155] (504s left) syncobj_wait (invalid-reset-one-illegal-handle)
[517.634811] Starting subtest: invalid-reset-one-illegal-handle
[517.636689] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[517.744451] [114/155] (504s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[517.859985] Starting subtest: short-flip-before-cursor-atomic-transitions
[518.809193] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.948s)
[519.665116] [115/155] (502s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[519.764623] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[519.769126] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[519.859252] [116/155] (502s left) kms_joiner (basic-big-joiner)
[519.955424] Starting subtest: basic-big-joiner
[519.959451] Subtest basic-big-joiner: SKIP (0.000s)
[520.022394] [117/155] (502s left) kms_addfb_basic (bad-pitch-32)
[520.069979] Starting subtest: bad-pitch-32
[520.071931] Subtest bad-pitch-32: SUCCESS (0.000s)
[520.224521] [118/155] (502s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[520.319002] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[525.383355] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (5.068s)
[525.496088] [119/155] (497s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[525.609658] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[532.377438] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.773s)
[532.502858] [120/155] (490s left) gem_exec_flush (basic-uc-ro-default)
[532.554206] Starting subtest: basic-uc-ro-default
[538.008718] Subtest basic-uc-ro-default: SUCCESS (5.454s)
[538.208095] [121/155] (484s left) api_intel_bb (reset-flags)
[538.267819] Starting subtest: reset-flags
[538.276126] Subtest reset-flags: SUCCESS (0.011s)
[538.412555] [122/155] (484s left) kms_big_fb (linear-32bpp-rotate-0)
[538.511531] Starting subtest: linear-32bpp-rotate-0
[539.925338] Subtest linear-32bpp-rotate-0: SUCCESS (1.412s)
[540.056823] [123/155] (482s left) kms_async_flips (async-flip-with-page-flip-events-linear-atomic)
[540.166049] Starting subtest: async-flip-with-page-flip-events-linear-atomic
[540.547249] Starting dynamic subtest: pipe-A-eDP-1
[543.295400] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.748s)
[543.667462] Starting dynamic subtest: pipe-B-eDP-1
[546.432608] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.771s)
[546.795107] Starting dynamic subtest: pipe-C-eDP-1
[549.562669] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.774s)
[549.923697] Starting dynamic subtest: pipe-D-eDP-1
[552.696217] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.778s)
[552.698586] Subtest async-flip-with-page-flip-events-linear-atomic: SUCCESS (12.526s)
[553.883887] [124/155] (468s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[553.989989] Starting subtest: psr-2p-primscrn-cur-indfb-move
[553.994289] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[554.092449] [125/155] (468s left) gem_exec_schedule (pi-common)
[554.269388] Starting subtest: pi-common
[554.271624] Starting dynamic subtest: rcs0
[554.298347] Dynamic subtest rcs0: SUCCESS (0.034s)
[554.310641] Starting dynamic subtest: bcs0
[554.335708] Dynamic subtest bcs0: SUCCESS (0.036s)
[554.349764] Starting dynamic subtest: vcs0
[554.368457] Dynamic subtest vcs0: SUCCESS (0.032s)
[554.383132] Starting dynamic subtest: vcs1
[554.397927] Dynamic subtest vcs1: SUCCESS (0.029s)
[554.412279] Starting dynamic subtest: vecs0
[554.426260] Dynamic subtest vecs0: SUCCESS (0.027s)
[554.440092] Starting dynamic subtest: ccs0
[554.455480] Dynamic subtest ccs0: SUCCESS (0.028s)
[554.457702] Subtest pi-common: SUCCESS (0.192s)
[554.685404] [126/155] (468s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[554.794258] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[554.798293] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[554.904831] [127/155] (468s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[555.021113] Starting subtest: basic-busy-flip-before-cursor-varying-size
[555.025671] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[555.167103] [128/155] (467s left) kms_cursor_legacy (torture-bo)
[555.287109] Starting subtest: torture-bo
[555.289393] Starting dynamic subtest: pipe-A
[561.440605] Dynamic subtest pipe-A: SUCCESS (6.148s)
[561.453313] Starting dynamic subtest: pipe-B
[567.969234] Dynamic subtest pipe-B: SUCCESS (6.525s)
[567.982267] Starting dynamic subtest: pipe-C
[574.092960] Dynamic subtest pipe-C: SUCCESS (6.122s)
[574.103033] Starting dynamic subtest: pipe-D
[581.707441] Dynamic subtest pipe-D: SUCCESS (7.610s)
[581.721193] Starting dynamic subtest: all-pipes
[587.551182] Dynamic subtest all-pipes: SUCCESS (5.840s)
[587.553754] Subtest torture-bo: SUCCESS (32.256s)
[587.722027] [129/155] (435s left) kms_psr (pr-sprite-plane-move)
[587.818881] Starting subtest: pr-sprite-plane-move
[587.823342] Subtest pr-sprite-plane-move: SKIP (0.000s)
[587.892473] [130/155] (435s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[587.990717] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[587.994967] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[588.094252] [131/155] (434s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[588.150041] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[588.154396] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[588.339889] [132/155] (434s left) syncobj_timeline (single-wait-available-submitted)
[588.395497] Starting subtest: single-wait-available-submitted
[588.397768] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[588.508591] [133/155] (434s left) kms_plane_cursor (overlay)
[588.599768] Starting subtest: overlay
[588.603944] Starting dynamic subtest: pipe-A-eDP-1-size-64
[591.127233] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.529s)
[591.188080] Starting dynamic subtest: pipe-A-eDP-1-size-128
[593.618425] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.438s)
[593.680081] Starting dynamic subtest: pipe-A-eDP-1-size-256
[596.198209] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.527s)
[596.563577] Starting dynamic subtest: pipe-B-eDP-1-size-64
[599.843164] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.291s)
[599.903115] Starting dynamic subtest: pipe-B-eDP-1-size-128
[602.359059] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.463s)
[602.417924] Starting dynamic subtest: pipe-B-eDP-1-size-256
[604.881515] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.473s)
[605.229996] Starting dynamic subtest: pipe-C-eDP-1-size-64
[608.061906] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.845s)
[608.101724] Starting dynamic subtest: pipe-C-eDP-1-size-128
[610.263047] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.170s)
[610.302137] Starting dynamic subtest: pipe-C-eDP-1-size-256
[612.418823] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.125s)
[612.749868] Starting dynamic subtest: pipe-D-eDP-1-size-64
[615.682938] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.945s)
[615.722526] Starting dynamic subtest: pipe-D-eDP-1-size-128
[617.894745] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.181s)
[617.938759] Starting dynamic subtest: pipe-D-eDP-1-size-256
[620.050428] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.125s)
[620.371715] Subtest overlay: SUCCESS (31.764s)
[621.245523] [134/155] (401s left) gem_exec_balancer (parallel)
[621.507237] Starting subtest: parallel
[622.431199] Subtest parallel: SUCCESS (0.927s)
[623.306920] [135/155] (399s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[623.415518] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[623.419891] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[623.517140] [136/155] (399s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-mmap-cpu)
[623.616107] Starting subtest: fbcpsrhdr-rgb565-draw-mmap-cpu
[623.620226] Subtest fbcpsrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[623.701233] [137/155] (399s left) i915_power (sanity)
[623.750379] Starting subtest: sanity
[627.804275] Subtest sanity: SUCCESS (4.046s)
[629.294516] [138/155] (394s left) kms_vblank (query-forked-busy)
[629.384728] Starting subtest: query-forked-busy
[629.386592] Starting dynamic subtest: pipe-A-eDP-1
[631.259005] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.875s)
[631.273978] Starting dynamic subtest: pipe-D-eDP-1
[633.810822] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.547s)
[633.813098] Subtest query-forked-busy: SUCCESS (4.426s)
[634.638085] [139/155] (388s left) kms_async_flips (invalid-async-flip)
[634.731737] Starting subtest: invalid-async-flip
[635.106284] Starting dynamic subtest: pipe-A-eDP-1
[635.948072] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.842s)
[636.304215] Starting dynamic subtest: pipe-B-eDP-1
[637.187424] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.892s)
[637.550046] Starting dynamic subtest: pipe-C-eDP-1
[638.412105] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.874s)
[638.769356] Starting dynamic subtest: pipe-D-eDP-1
[639.637767] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.876s)
[639.640301] Subtest invalid-async-flip: SUCCESS (4.907s)
[640.788372] [140/155] (382s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[640.886832] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[640.889079] Starting dynamic subtest: pipe-A-default-mode
[643.627975] Dynamic subtest pipe-A-default-mode: SUCCESS (2.739s)
[643.630220] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.740s)
[644.472404] [141/155] (379s left) i915_drm_fdinfo (virtual-busy-idle)
[644.518789] Starting subtest: virtual-busy-idle
[644.522675] Subtest virtual-busy-idle: SKIP (0.000s)
[644.602611] [142/155] (378s left) kms_psr (psr-cursor-mmap-gtt)
[644.691700] Starting subtest: psr-cursor-mmap-gtt
[644.693853] Starting dynamic subtest: eDP-1
[646.451578] Dynamic subtest eDP-1: SUCCESS (1.757s)
[646.453953] Subtest psr-cursor-mmap-gtt: SUCCESS (1.758s)
[647.314889] [143/155] (376s left) gem_exec_parallel (contexts)
[647.368114] Starting subtest: contexts
[647.370419] Starting dynamic subtest: rcs0
[650.985612] Dynamic subtest rcs0: SUCCESS (3.615s)
[650.998922] Starting dynamic subtest: bcs0
[654.749721] Dynamic subtest bcs0: SUCCESS (3.761s)
[654.759686] Starting dynamic subtest: vcs0
[658.778907] Dynamic subtest vcs0: SUCCESS (4.026s)
[658.792253] Starting dynamic subtest: vcs1
[664.657464] Dynamic subtest vcs1: SUCCESS (5.876s)
[664.671426] Starting dynamic subtest: vecs0
[670.630974] Dynamic subtest vecs0: SUCCESS (5.971s)
[670.642446] Starting dynamic subtest: ccs0
[674.737068] Dynamic subtest ccs0: SUCCESS (4.102s)
[674.739422] Subtest contexts: SUCCESS (27.362s)
[674.929269] [144/155] (348s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt)
[675.034363] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt
[675.038635] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[675.128248] [145/155] (348s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-pwrite)
[675.238608] Starting subtest: psrhdr-rgb101010-draw-pwrite
[675.243234] Subtest psrhdr-rgb101010-draw-pwrite: SKIP (0.000s)
[675.334576] [146/155] (348s left) syncobj_timeline (multi-wait-all-signaled)
[675.385823] Starting subtest: multi-wait-all-signaled
[675.388153] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[675.492797] [147/155] (348s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-gtt)
[675.591907] Starting subtest: psr-rgb101010-draw-mmap-gtt
[675.596116] Subtest psr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[675.693149] [148/155] (348s left) kms_cursor_crc (cursor-random-32x32)
[675.833723] Starting subtest: cursor-random-32x32
[675.874779] Subtest cursor-random-32x32: SKIP (0.041s)
[676.156861] [149/155] (347s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[676.262831] Starting subtest: yf-tiled-64bpp-rotate-0
[676.266493] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[676.366432] [150/155] (347s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[676.461099] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[680.832150] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.375s)
[682.027157] [151/155] (341s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[682.134478] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-mmap-wc
[682.138619] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[682.236939] [152/155] (341s left) kms_plane_multiple (2x-tiling-none)
[682.326500] Starting subtest: 2x-tiling-none
[682.330741] Subtest 2x-tiling-none: SKIP (0.000s)
[682.411111] [153/155] (341s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt)
[682.514612] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt
[682.518777] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[682.608789] [154/155] (341s left) gem_exec_whisper (basic-contexts-priority)
[682.666381] Starting subtest: basic-contexts-priority
[705.838555] Subtest basic-contexts-priority: SUCCESS (23.179s)
[706.024426] [155/155] (318s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[706.123404] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[706.125770] Starting dynamic subtest: pipe-A-eDP-1
[706.243431] Dynamic subtest pipe-A-eDP-1: SKIP (0.121s)
[706.255310] Starting dynamic subtest: pipe-B-eDP-1
[707.477719] Dynamic subtest pipe-B-eDP-1: SKIP (1.235s)
[707.482355] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (1.359s)
[708.676915] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_fence
results: parsing output: 1/ for test: syncobj_wait
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: syncobj_wait
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: kms_psr2_sf
results: parsing output: 6/ for test: kms_content_protection
results: parsing output: 7/ for test: gem_tiled_pread_pwrite
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: syncobj_basic
results: parsing output: 13/ for test: kms_flip
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: gem_eio
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_pxp
results: parsing output: 19/ for test: gem_exec_params
results: parsing output: 20/ for test: gem_mmap_wc
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_properties
results: parsing output: 23/ for test: kms_color_pipeline
results: parsing output: 24/ for test: kms_chamelium_hpd
results: parsing output: 25/ for test: kms_vblank
results: parsing output: 26/ for test: kms_plane_scaling
results: parsing output: 27/ for test: gem_ctx_exec
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_plane_scaling
results: parsing output: 31/ for test: kms_flip
results: parsing output: 32/ for test: kms_pm_dc
results: parsing output: 33/ for test: perf
results: parsing output: 34/ for test: gem_pread
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_ccs
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: prime_self_import
results: parsing output: 40/ for test: gem_pxp
results: parsing output: 41/ for test: kms_ccs
results: parsing output: 42/ for test: gem_eio
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: gem_ccs
results: parsing output: 46/ for test: i915_query
results: parsing output: 47/ for test: vgem_basic
results: parsing output: 48/ for test: kms_chamelium_frames
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: i915_pm_rpm
results: parsing output: 51/ for test: gem_softpin
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: gem_eio
results: parsing output: 54/ for test: gem_ctx_persistence
results: parsing output: 55/ for test: gem_exec_fence
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_plane
results: parsing output: 58/ for test: sw_sync
results: parsing output: 59/ for test: gem_exercise_blt
results: parsing output: 60/ for test: kms_big_fb
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: prime_busy
results: parsing output: 63/ for test: kms_vrr
results: parsing output: 64/ for test: kms_cursor_crc
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_pm_rpm
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_flip
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: kms_cursor_legacy
results: parsing output: 71/ for test: gem_exec_schedule
results: parsing output: 72/ for test: syncobj_eventfd
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: kms_flip
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: kms_psr
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_chamelium_color
results: parsing output: 79/ for test: kms_addfb_basic
results: parsing output: 80/ for test: syncobj_basic
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_ccs
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: i915_drm_fdinfo
results: parsing output: 86/ for test: kms_hdr
results: parsing output: 87/ for test: core_setmaster
results: parsing output: 88/ for test: gem_ctx_shared
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: gem_watchdog
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_cursor_legacy
results: parsing output: 93/ for test: kms_flip_scaled_crc
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: api_intel_bb
results: parsing output: 96/ for test: perf_pmu
results: parsing output: 97/ for test: perf_pmu
results: parsing output: 98/ for test: kms_feature_discovery
results: parsing output: 99/ for test: gem_ctx_isolation
results: parsing output: 100/ for test: gem_exec_reloc
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: gem_mmap_offset
results: parsing output: 103/ for test: gen9_exec_parse
results: parsing output: 104/ for test: gem_set_tiling_vs_blt
results: parsing output: 105/ for test: kms_flip
results: parsing output: 106/ for test: kms_lease
results: parsing output: 107/ for test: kms_rotation_crc
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: gem_userptr_blits
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gem_mmap_gtt
results: parsing output: 112/ for test: syncobj_wait
results: parsing output: 113/ for test: kms_cursor_legacy
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_joiner
results: parsing output: 116/ for test: kms_addfb_basic
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_exec_flush
results: parsing output: 120/ for test: api_intel_bb
results: parsing output: 121/ for test: kms_big_fb
results: parsing output: 122/ for test: kms_async_flips
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_exec_schedule
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_cursor_legacy
results: parsing output: 127/ for test: kms_cursor_legacy
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_render_copy
results: parsing output: 131/ for test: syncobj_timeline
results: parsing output: 132/ for test: kms_plane_cursor
results: parsing output: 133/ for test: gem_exec_balancer
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: i915_power
results: parsing output: 137/ for test: kms_vblank
results: parsing output: 138/ for test: kms_async_flips
results: parsing output: 139/ for test: kms_flip_scaled_crc
results: parsing output: 140/ for test: i915_drm_fdinfo
results: parsing output: 141/ for test: kms_psr
results: parsing output: 142/ for test: gem_exec_parallel
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: syncobj_timeline
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: kms_cursor_crc
results: parsing output: 148/ for test: kms_big_fb
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: kms_plane_multiple
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: gem_exec_whisper
results: parsing output: 154/ for test: kms_psr2_sf
Done.