[57.357282] Initializing watchdogs
[57.357326]   /dev/watchdog0
[57.370550] [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.374709] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.377108] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.380352] [001/134] (960s left) gem_exec_balancer (parallel-contexts)
[57.665487] Starting subtest: parallel-contexts
[61.413443] Subtest parallel-contexts: SUCCESS (3.748s)
[61.539154] [002/134] (956s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[61.601125] Starting subtest: rel-constants-invalid-rel-gen5
[61.603416] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[61.711628] [003/134] (956s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[63.252592] Starting subtest: modeset-lpsp-stress-no-wait
[73.381516] Subtest modeset-lpsp-stress-no-wait: SUCCESS (10.132s)
[73.466567] [004/134] (944s left) gem_mmap_gtt (basic-write-read)
[73.529394] Subtest basic-write-read: SKIP (0.000s)
[73.588459] [005/134] (944s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-msflip-blt)
[73.685115] Starting subtest: fbcpsr-2p-scndscrn-shrfb-msflip-blt
[73.689577] Subtest fbcpsr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[73.785384] [006/134] (944s left) i915_drm_fdinfo (busy-idle-check-all)
[73.823326] Starting subtest: busy-idle-check-all
[73.825329] Starting dynamic subtest: rcs0
[73.829852] Dynamic subtest rcs0: SKIP (0.004s)
[73.836430] Starting dynamic subtest: bcs0
[73.840702] Dynamic subtest bcs0: SKIP (0.000s)
[73.847405] Starting dynamic subtest: vcs0
[73.851730] Dynamic subtest vcs0: SKIP (0.000s)
[73.857920] Starting dynamic subtest: vcs1
[73.862059] Dynamic subtest vcs1: SKIP (0.000s)
[73.868418] Starting dynamic subtest: vecs0
[73.872886] Dynamic subtest vecs0: SKIP (0.000s)
[73.879026] Starting dynamic subtest: ccs0
[73.883050] Dynamic subtest ccs0: SKIP (0.000s)
[73.887028] Subtest busy-idle-check-all: SKIP (0.007s)
[73.918561] [007/134] (944s left) gem_pwrite (basic-self)
[73.974154] Subtest basic-self: SKIP (0.000s)
[74.051901] [008/134] (944s left) gem_ctx_persistence (engines-hostile-preempt)
[74.109783] Starting subtest: engines-hostile-preempt
[74.112185] Starting dynamic subtest: rcs0
[74.218659] Dynamic subtest rcs0: SUCCESS (0.109s)
[74.227699] Starting dynamic subtest: bcs0
[74.282209] Dynamic subtest bcs0: SUCCESS (0.062s)
[74.292056] Starting dynamic subtest: vcs0
[75.842378] Dynamic subtest vcs0: SUCCESS (1.558s)
[75.852428] Starting dynamic subtest: vcs1
[77.890317] Dynamic subtest vcs1: SUCCESS (2.047s)
[77.900069] Starting dynamic subtest: vecs0
[79.912923] Dynamic subtest vecs0: SUCCESS (2.021s)
[79.923089] Starting dynamic subtest: ccs0
[79.980043] Dynamic subtest ccs0: SUCCESS (0.068s)
[79.982919] Subtest engines-hostile-preempt: SUCCESS (5.870s)
[80.064217] [009/134] (938s left) kms_chamelium_audio (dp-audio)
[80.455968] Subtest dp-audio: SKIP (0.000s)
[81.241875] [010/134] (936s left) gem_exec_params (mmapped)
[81.315450] Starting subtest: mmapped
[81.320204] Subtest mmapped: SUCCESS (0.003s)
[81.471690] [011/134] (936s left) api_intel_bb (add-remove-objects)
[81.528826] Starting subtest: add-remove-objects
[81.534625] Subtest add-remove-objects: SUCCESS (0.008s)
[81.692160] [012/134] (936s left) kms_addfb_basic (addfb25-bad-modifier)
[81.783632] Starting subtest: addfb25-bad-modifier
[81.785750] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[81.895246] [013/134] (936s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[82.272658] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[83.073889] [014/134] (935s left) gem_exec_reloc (basic-cpu-read-noreloc)
[83.143801] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[83.222937] [015/134] (935s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[83.330830] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[83.335153] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[83.436739] [016/134] (934s left) syncobj_timeline (wait-all-snapshot)
[83.486852] Starting subtest: wait-all-snapshot
[83.567239] Subtest wait-all-snapshot: SUCCESS (0.081s)
[83.652190] [017/134] (934s left) i915_fb_tiling (basic-x-tiling)
[83.709257] Subtest basic-x-tiling: SKIP (0.000s)
[83.766782] [018/134] (934s left) kms_psr (psr-cursor-blt)
[83.860837] Starting subtest: psr-cursor-blt
[83.863103] Starting dynamic subtest: eDP-1
[85.583668] Dynamic subtest eDP-1: SUCCESS (1.723s)
[85.586060] Subtest psr-cursor-blt: SUCCESS (1.724s)
[86.470707] [019/134] (932s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[91.419719] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[91.424646] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.002s)
[92.287189] [020/134] (926s left) gem_exec_reloc (basic-softpin)
[92.358644] Subtest basic-softpin: SKIP (0.000s)
[92.420970] [021/134] (926s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[92.523436] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[92.527594] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[92.651864] [022/134] (926s left) gem_compute (compute-square)
[92.716411] Starting subtest: compute-square
[92.721250] Subtest compute-square: SUCCESS (0.007s)
[92.849485] [023/134] (925s left) kms_lease (lease-revoke)
[92.942231] Starting subtest: lease-revoke
[92.944477] Starting dynamic subtest: pipe-A-eDP-1
[92.973672] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.029s)
[92.981455] Starting dynamic subtest: pipe-B-eDP-1
[93.000721] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.027s)
[93.012312] Starting dynamic subtest: pipe-C-eDP-1
[93.031828] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.029s)
[93.041631] Starting dynamic subtest: pipe-D-eDP-1
[93.059397] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.027s)
[93.061628] Subtest lease-revoke: SUCCESS (0.118s)
[93.200443] [024/134] (925s left) kms_flip (nonexisting-fb)
[93.289216] Starting subtest: nonexisting-fb
[93.291130] Starting dynamic subtest: A-eDP1
[94.472394] Dynamic subtest A-eDP1: SUCCESS (1.184s)
[94.479461] Starting dynamic subtest: B-eDP1
[95.599029] Dynamic subtest B-eDP1: SUCCESS (1.125s)
[95.607663] Starting dynamic subtest: C-eDP1
[96.730418] Dynamic subtest C-eDP1: SUCCESS (1.130s)
[96.742270] Starting dynamic subtest: D-eDP1
[97.867901] Dynamic subtest D-eDP1: SUCCESS (1.135s)
[97.872829] Subtest nonexisting-fb: SUCCESS (4.585s)
[99.054199] [025/134] (919s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[99.172464] Starting subtest: psr-farfromfence-mmap-gtt
[99.176778] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[99.295700] [026/134] (919s left) kms_flip (2x-dpms-vs-vblank-race-interruptible)
[99.384986] Starting subtest: 2x-dpms-vs-vblank-race-interruptible
[99.395805] Subtest 2x-dpms-vs-vblank-race-interruptible: SKIP (0.010s)
[99.477554] [027/134] (919s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[99.581848] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[100.328371] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.748s)
[100.511953] [028/134] (918s left) kms_selftest (drm_plane_helper)
[100.553176] Starting subtest: drm_plane_helper
[100.590286] Starting dynamic subtest: drm_test_check_plane_state
[100.717455] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.127s)
[100.726220] Starting dynamic subtest: drm_test_check_invalid_plane_state
[100.828560] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.112s)
[100.830839] Subtest drm_plane_helper: SUCCESS (0.277s)
[101.035918] [029/134] (917s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[101.140545] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[101.144972] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[101.263022] [030/134] (917s left) i915_pm_rc6_residency (media-rc6-accuracy)
[101.331389] Starting subtest: media-rc6-accuracy
[101.336496] Subtest media-rc6-accuracy: SKIP (0.000s)
[101.460866] [031/134] (917s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[101.550608] Starting subtest: disable-crc-after-crtc
[101.552889] Starting dynamic subtest: pipe-A-eDP-1
[103.185244] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.632s)
[103.194391] Starting dynamic subtest: pipe-B-eDP-1
[105.440477] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.256s)
[105.450157] Starting dynamic subtest: pipe-C-eDP-1
[107.698756] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.256s)
[107.706661] Starting dynamic subtest: pipe-D-eDP-1
[109.943737] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.246s)
[109.946181] Subtest disable-crc-after-crtc: SUCCESS (8.394s)
[110.793207] [032/134] (908s left) gem_userptr_blits (set-cache-level)
[110.858968] Starting subtest: set-cache-level
[110.863111] Subtest set-cache-level: SKIP (0.000s)
[110.983565] [033/134] (908s left) gem_pread (uncached)
[111.044282] Subtest uncached: SKIP (0.000s)
[111.110982] [034/134] (908s left) gem_userptr_blits (create-destroy-sync)
[111.181605] Starting subtest: create-destroy-sync
[116.198980] Subtest create-destroy-sync: SUCCESS (5.016s)
[116.334085] [035/134] (902s left) gem_tiled_fence_blits (normal)
[116.397322] Subtest normal: SKIP (0.000s)
[116.463227] [036/134] (902s left) gem_pxp (fail-invalid-protected-context)
[116.578633] Starting subtest: fail-invalid-protected-context
[116.581318] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[116.691019] [037/134] (902s left) gem_exec_params (sol-reset-invalid)
[116.751052] Starting subtest: sol-reset-invalid
[116.755733] Subtest sol-reset-invalid: SUCCESS (0.004s)
[117.979102] [038/134] (901s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[118.083556] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[118.087766] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[118.203876] [039/134] (901s left) gem_softpin (noreloc-s3)
[118.275317] Starting subtest: noreloc-S3
[138.346908] Subtest noreloc-S3: SUCCESS (5.414s)
[138.508586] [040/134] (880s left) gem_exec_params (invalid-bsd-ring)
[138.587100] Starting subtest: invalid-bsd-ring
[138.589616] Subtest invalid-bsd-ring: SUCCESS (0.000s)
[138.713499] [041/134] (880s left) kms_atomic (plane-overlay-legacy)
[138.808524] Starting subtest: plane-overlay-legacy
[138.810869] Starting dynamic subtest: pipe-A-eDP-1
[139.221709] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.412s)
[139.223743] Subtest plane-overlay-legacy: SUCCESS (0.413s)
[140.044266] [042/134] (879s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[140.152295] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[147.425333] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.276s)
[147.582881] [043/134] (871s left) gem_ctx_persistence (hang)
[147.661269] Starting subtest: hang
[147.666038] Subtest hang: SKIP (0.000s)
[147.804183] [044/134] (871s left) gem_exec_whisper (basic-queues-priority)
[147.872040] Starting subtest: basic-queues-priority
[170.808697] Subtest basic-queues-priority: SUCCESS (22.933s)
[171.001191] [045/134] (848s left) gen9_exec_parse (secure-batches)
[171.072649] Subtest secure-batches: SKIP (0.000s)
[171.163498] [046/134] (848s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[171.262303] Starting subtest: primary-x-tiled-reflect-x-180
[178.400974] Subtest primary-x-tiled-reflect-x-180: SUCCESS (7.138s)
[179.656588] [047/134] (839s left) kms_psr (fbc-pr-sprite-plane-move)
[179.762966] Starting subtest: fbc-pr-sprite-plane-move
[179.767209] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[179.859005] [048/134] (839s left) kms_chamelium_frames (hdmi-frame-dump)
[180.244785] Subtest hdmi-frame-dump: SKIP (0.000s)
[181.038101] [049/134] (838s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[181.146963] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[182.180974] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.033s)
[182.332303] [050/134] (837s left) drm_read (empty-block)
[182.485364] Starting subtest: empty-block
[183.486329] Subtest empty-block: SUCCESS (1.000s)
[183.665160] [051/134] (836s left) core_auth (getclient-master-drop)
[183.709983] Starting subtest: getclient-master-drop
[183.728179] Subtest getclient-master-drop: SUCCESS (0.020s)
[183.832078] [052/134] (835s left) template (b)
[183.885245] Starting subtest: B
[183.887428] Subtest B: SUCCESS (0.000s)
[183.982529] [053/134] (835s left) syncobj_timeline (invalid-query-bad-pad)
[184.044128] Starting subtest: invalid-query-bad-pad
[184.046542] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[184.149100] [054/134] (835s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[184.265746] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[184.269972] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[184.396767] [055/134] (835s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[184.489970] Starting subtest: fb-8bpc-vs-panel-6bpc
[184.491783] Starting dynamic subtest: pipe-A-eDP-1
[185.838232] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.348s)
[185.840157] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.349s)
[186.632008] [056/134] (833s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[186.719006] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[186.727122] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.010s)
[186.839110] [057/134] (833s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[186.936306] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[190.626254] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.693s)
[191.839675] [058/134] (828s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[191.942464] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[198.895213] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.954s)
[199.042420] [059/134] (821s left) kms_psr (pr-cursor-blt)
[199.128910] Starting subtest: pr-cursor-blt
[199.133505] Subtest pr-cursor-blt: SKIP (0.000s)
[199.221829] [060/134] (820s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-blt)
[199.313925] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-blt
[199.317991] Subtest fbc-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[199.414539] [061/134] (820s left) kms_plane_lowres (tiling-y)
[199.516221] Starting subtest: tiling-y
[199.520634] Subtest tiling-y: SKIP (0.000s)
[199.596104] [062/134] (820s left) gem_softpin (evict-prime-sanity-check)
[199.659168] Starting subtest: evict-prime-sanity-check
[199.661475] Starting dynamic subtest: rcs0
[200.796868] Dynamic subtest rcs0: SUCCESS (1.138s)
[200.809129] Starting dynamic subtest: bcs0
[201.929875] Dynamic subtest bcs0: SUCCESS (1.132s)
[201.941511] Starting dynamic subtest: vcs0
[203.556365] Dynamic subtest vcs0: SUCCESS (1.623s)
[203.567924] Starting dynamic subtest: vcs1
[205.537400] Dynamic subtest vcs1: SUCCESS (1.979s)
[205.549124] Starting dynamic subtest: vecs0
[207.522487] Dynamic subtest vecs0: SUCCESS (1.983s)
[207.533767] Starting dynamic subtest: ccs0
[208.660914] Dynamic subtest ccs0: SUCCESS (1.138s)
[208.672290] Starting dynamic subtest: all
[209.807084] Dynamic subtest all: SUCCESS (1.144s)
[209.809808] Subtest evict-prime-sanity-check: SUCCESS (10.149s)
[209.898760] [063/134] (810s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[209.998264] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[217.277134] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.280s)
[217.417678] [064/134] (802s left) gem_eio (in-flight-immediate)
[217.634170] Starting subtest: in-flight-immediate
[226.554733] Subtest in-flight-immediate: SUCCESS (8.919s)
[226.739578] [FACT gem_eio (in-flight-immediate)] new: kernel.kmod_is_loaded.vgem: true
[226.742910] [065/134] (793s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[226.843191] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[226.845112] Starting dynamic subtest: pipe-A
[227.191636] Dynamic subtest pipe-A: SUCCESS (0.351s)
[227.201760] Starting dynamic subtest: pipe-B
[228.602896] Dynamic subtest pipe-B: SUCCESS (1.410s)
[228.613861] Starting dynamic subtest: pipe-C
[230.022912] Dynamic subtest pipe-C: SUCCESS (1.419s)
[230.034165] Starting dynamic subtest: pipe-D
[231.461141] Dynamic subtest pipe-D: SUCCESS (1.437s)
[231.463503] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.621s)
[232.646391] [066/134] (787s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area-big-fb)
[237.711976] Starting subtest: pr-primary-plane-update-sf-dmg-area-big-fb
[237.716516] Subtest pr-primary-plane-update-sf-dmg-area-big-fb: SKIP (0.002s)
[238.562776] [067/134] (781s left) gem_exec_schedule (wide)
[238.738632] Starting subtest: wide
[238.741261] Starting dynamic subtest: rcs0
[249.126571] Dynamic subtest rcs0: SUCCESS (10.385s)
[249.139052] Starting dynamic subtest: vcs0
[261.416092] Dynamic subtest vcs0: SUCCESS (12.289s)
[261.428976] Starting dynamic subtest: vcs1
[270.743556] Dynamic subtest vcs1: SUCCESS (9.326s)
[270.755759] Starting dynamic subtest: vecs0
[281.861344] Dynamic subtest vecs0: SUCCESS (11.117s)
[281.873532] Starting dynamic subtest: ccs0
[295.647859] Dynamic subtest ccs0: SUCCESS (13.785s)
[295.650284] Subtest wide: SUCCESS (56.907s)
[297.388158] [068/134] (723s left) gem_exec_capture (userptr)
[297.451952] Starting subtest: userptr
[298.299199] Subtest userptr: SUCCESS (0.845s)
[298.427750] [069/134] (722s left) kms_vblank (ts-continuation-modeset-rpm)
[298.557662] Starting subtest: ts-continuation-modeset-rpm
[298.559524] Starting dynamic subtest: pipe-A-eDP-1
[301.034263] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.477s)
[301.048900] Starting dynamic subtest: pipe-D-eDP-1
[303.275214] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.237s)
[303.277419] Subtest ts-continuation-modeset-rpm: SUCCESS (4.718s)
[304.089982] [070/134] (716s left) kms_invalid_mode (zero-clock)
[304.197333] Starting subtest: zero-clock
[304.199631] Starting dynamic subtest: pipe-A-eDP-1
[304.491375] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.295s)
[304.501106] Starting dynamic subtest: pipe-B-eDP-1
[304.503518] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[304.512477] Starting dynamic subtest: pipe-C-eDP-1
[304.515134] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[304.523839] Starting dynamic subtest: pipe-D-eDP-1
[304.526018] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[304.528143] Subtest zero-clock: SUCCESS (0.301s)
[305.299110] [071/134] (715s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[305.407954] Starting subtest: psr-modesetfrombusy
[311.590989] Subtest psr-modesetfrombusy: SUCCESS (6.186s)
[311.759637] [072/134] (708s left) syncobj_wait (wait-for-submit-snapshot)
[311.820788] Starting subtest: wait-for-submit-snapshot
[311.900679] Subtest wait-for-submit-snapshot: SUCCESS (0.081s)
[311.987198] [073/134] (708s left) gem_exec_schedule (semaphore-user)
[312.055400] Starting subtest: semaphore-user
[312.107019] Subtest semaphore-user: SUCCESS (0.057s)
[313.603812] [074/134] (707s left) perf (global-sseu-config)
[313.721085] Starting subtest: global-sseu-config
[313.725458] Subtest global-sseu-config: SKIP (0.000s)
[313.770078] [075/134] (706s left) kms_scaling_modes (scaling-mode-full-aspect)
[313.869023] Starting subtest: scaling-mode-full-aspect
[313.870947] Starting dynamic subtest: pipe-A-eDP-1
[313.963132] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.096s)
[313.972792] Starting dynamic subtest: pipe-B-eDP-1
[315.094692] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.130s)
[315.108741] Starting dynamic subtest: pipe-C-eDP-1
[316.204187] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.107s)
[316.214411] Starting dynamic subtest: pipe-D-eDP-1
[317.434468] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.229s)
[317.436857] Subtest scaling-mode-full-aspect: SUCCESS (3.569s)
[318.599599] [076/134] (702s left) gem_ringfill (engines-basic)
[324.566855] Starting subtest: engines-basic
[324.569528] Starting dynamic subtest: rcs0
[324.656799] Dynamic subtest rcs0: SUCCESS (0.090s)
[324.669383] Starting dynamic subtest: bcs0
[324.702660] Dynamic subtest bcs0: SUCCESS (0.044s)
[324.713533] Starting dynamic subtest: vcs0
[324.745797] Dynamic subtest vcs0: SUCCESS (0.041s)
[324.756200] Starting dynamic subtest: vcs1
[324.787576] Dynamic subtest vcs1: SUCCESS (0.040s)
[324.797960] Starting dynamic subtest: vecs0
[324.831511] Dynamic subtest vecs0: SUCCESS (0.042s)
[324.842301] Starting dynamic subtest: ccs0
[324.875615] Dynamic subtest ccs0: SUCCESS (0.042s)
[324.878011] Subtest engines-basic: SUCCESS (0.312s)
[324.987212] [077/134] (695s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs)
[325.088727] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs
[325.091043] Starting dynamic subtest: pipe-A-eDP-1
[325.100169] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[325.111252] Starting dynamic subtest: pipe-B-eDP-1
[325.120294] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[325.129911] Starting dynamic subtest: pipe-C-eDP-1
[325.138526] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[325.148250] Starting dynamic subtest: pipe-D-eDP-1
[325.157040] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[325.161428] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[327.259724] [078/134] (693s left) kms_color (gamma)
[327.360985] Starting subtest: gamma
[327.363322] Starting dynamic subtest: pipe-A-eDP-1
[328.074075] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.714s)
[328.084269] Starting dynamic subtest: pipe-B-eDP-1
[329.456682] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.382s)
[329.467952] Starting dynamic subtest: pipe-C-eDP-1
[330.832331] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.374s)
[330.842315] Starting dynamic subtest: pipe-D-eDP-1
[332.163118] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.329s)
[332.165296] Subtest gamma: SUCCESS (4.802s)
[332.988895] [079/134] (687s left) gem_exec_schedule (lateslice)
[333.060723] Starting subtest: lateslice
[333.063141] Starting dynamic subtest: rcs0
[333.071631] Dynamic subtest rcs0: SUCCESS (0.022s)
[333.083783] Starting dynamic subtest: bcs0
[333.088584] Dynamic subtest bcs0: SUCCESS (0.016s)
[333.098329] Starting dynamic subtest: vcs0
[333.105971] Dynamic subtest vcs0: SUCCESS (0.017s)
[333.119713] Starting dynamic subtest: vcs1
[333.129513] Dynamic subtest vcs1: SUCCESS (0.022s)
[333.138913] Starting dynamic subtest: vecs0
[333.149807] Dynamic subtest vecs0: SUCCESS (0.019s)
[333.159456] Starting dynamic subtest: ccs0
[333.171044] Dynamic subtest ccs0: SUCCESS (0.020s)
[333.173272] Subtest lateslice: SUCCESS (0.122s)
[334.665272] [080/134] (686s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[334.759264] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[334.763351] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[334.901383] [081/134] (686s left) kms_cursor_crc (cursor-sliding-512x512)
[335.183186] Starting subtest: cursor-sliding-512x512
[335.187651] Subtest cursor-sliding-512x512: SKIP (0.001s)
[335.374981] [082/134] (685s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[335.659490] Starting subtest: cursor-rapid-movement-256x85
[335.708688] Subtest cursor-rapid-movement-256x85: SKIP (0.044s)
[335.929201] [083/134] (685s left) gem_eio (create-ext)
[336.151405] Starting subtest: create-ext
[336.509864] Subtest create-ext: SUCCESS (0.358s)
[336.666991] [084/134] (684s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-5)
[336.768096] Starting subtest: planes-upscale-20x20-downscale-factor-0-5
[336.769960] Starting dynamic subtest: pipe-A
[336.786734] Dynamic subtest pipe-A: SKIP (0.017s)
[336.796643] Starting dynamic subtest: pipe-B
[336.819784] Dynamic subtest pipe-B: SKIP (0.031s)
[336.829328] Starting dynamic subtest: pipe-C
[336.850158] Dynamic subtest pipe-C: SKIP (0.030s)
[336.859244] Starting dynamic subtest: pipe-D
[336.880939] Dynamic subtest pipe-D: SKIP (0.030s)
[336.884529] Subtest planes-upscale-20x20-downscale-factor-0-5: SKIP (0.111s)
[337.014164] [085/134] (684s left) gem_exec_fence (syncobj-timeline-chain-engines)
[337.549469] Starting subtest: syncobj-timeline-chain-engines
[337.670095] Subtest syncobj-timeline-chain-engines: SUCCESS (0.121s)
[338.693385] [086/134] (682s left) prime_vgem (shrink)
[338.766199] Starting subtest: shrink
[338.782872] Subtest shrink: SUCCESS (0.018s)
[338.910645] [087/134] (682s left) i915_pm_rps (thresholds-idle)
[338.970427] Starting subtest: thresholds-idle
[338.975048] Subtest thresholds-idle: SKIP (0.001s)
[339.087122] [088/134] (682s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[339.186310] Starting subtest: fbcpsr-rgb565-draw-blt
[346.179272] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (6.996s)
[346.338604] [089/134] (674s left) gem_wait (invalid-buf)
[346.409648] Starting subtest: invalid-buf
[346.412052] Subtest invalid-buf: SUCCESS (0.000s)
[346.677279] [090/134] (674s left) kms_psr (pr-sprite-blt)
[346.770266] Starting subtest: pr-sprite-blt
[346.774677] Subtest pr-sprite-blt: SKIP (0.000s)
[346.869664] [091/134] (674s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[346.968051] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[346.972187] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[347.065928] [092/134] (674s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[347.164002] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[347.166102] Starting dynamic subtest: pipe-A-eDP-1
[347.964477] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.794s)
[347.974082] Starting dynamic subtest: pipe-B-eDP-1
[349.520254] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.554s)
[349.530055] Starting dynamic subtest: pipe-C-eDP-1
[351.012047] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.488s)
[351.022749] Starting dynamic subtest: pipe-D-eDP-1
[352.535086] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.524s)
[352.536933] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.365s)
[353.718546] [093/134] (667s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[353.821643] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[360.393946] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.574s)
[360.542346] [094/134] (660s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[360.630280] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[360.657623] Starting dynamic subtest: pipe-A-plane-0
[361.314187] Dynamic subtest pipe-A-plane-0: SUCCESS (0.656s)
[361.324262] Starting dynamic subtest: pipe-A-plane-3
[361.745952] Dynamic subtest pipe-A-plane-3: SUCCESS (0.432s)
[361.754696] Starting dynamic subtest: pipe-A-plane-5
[361.758093] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[362.811438] Starting dynamic subtest: pipe-B-plane-0
[363.502759] Dynamic subtest pipe-B-plane-0: SUCCESS (0.700s)
[363.512058] Starting dynamic subtest: pipe-B-plane-3
[363.935849] Dynamic subtest pipe-B-plane-3: SUCCESS (0.433s)
[363.945644] Starting dynamic subtest: pipe-B-plane-5
[363.949305] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[364.245849] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.613s)
[365.092184] [095/134] (656s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[365.183897] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[365.186193] Starting dynamic subtest: pipe-A-eDP-1
[365.650154] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.439s)
[365.662292] Starting dynamic subtest: pipe-B-eDP-1
[367.117320] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.465s)
[367.129951] Starting dynamic subtest: pipe-C-eDP-1
[368.483479] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.365s)
[368.494906] Starting dynamic subtest: pipe-D-eDP-1
[369.811976] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.329s)
[369.814207] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.603s)
[371.011696] [096/134] (650s left) kms_cursor_crc (cursor-dpms)
[371.144445] Starting subtest: cursor-dpms
[371.146901] Starting dynamic subtest: pipe-A-eDP-1
[374.173549] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.027s)
[374.183031] Starting dynamic subtest: pipe-D-eDP-1
[377.780776] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.604s)
[377.782771] Subtest cursor-dpms: SUCCESS (6.633s)
[378.630262] [097/134] (642s left) syncobj_timeline (multi-wait-signaled)
[378.695304] Starting subtest: multi-wait-signaled
[378.697720] Subtest multi-wait-signaled: SUCCESS (0.001s)
[378.805457] [098/134] (642s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[383.789785] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[383.792194] Starting dynamic subtest: pipe-A-eDP-1
[390.430702] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.639s)
[390.441111] Starting dynamic subtest: pipe-B-eDP-1
[397.063281] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.631s)
[397.065513] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (13.274s)
[397.942040] [099/134] (623s left) kms_async_flips (alternate-sync-async-flip-atomic)
[398.044413] Starting subtest: alternate-sync-async-flip-atomic
[398.420498] Starting dynamic subtest: pipe-A-eDP-1
[401.219055] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.799s)
[401.618400] Starting dynamic subtest: pipe-B-eDP-1
[404.358105] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.750s)
[404.736556] Starting dynamic subtest: pipe-C-eDP-1
[407.483264] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.755s)
[407.848841] Starting dynamic subtest: pipe-D-eDP-1
[410.612619] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.771s)
[410.614691] Subtest alternate-sync-async-flip-atomic: SUCCESS (12.571s)
[411.795305] [100/134] (609s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[411.861876] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[411.864126] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[411.986812] [101/134] (609s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[412.049332] Starting subtest: etime-multi-wait-all-available-unsubmitted
[412.151053] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.103s)
[412.254886] [102/134] (609s left) gem_eio (execbuf)
[412.480610] Starting subtest: execbuf
[412.816117] Subtest execbuf: SUCCESS (0.334s)
[412.979662] [103/134] (608s left) dumb_buffer (map-valid)
[413.031933] Starting subtest: map-valid
[413.034103] Subtest map-valid: SUCCESS (0.000s)
[413.155924] [104/134] (608s left) kms_content_protection (atomic-dpms-hdcp14)
[413.307548] Starting subtest: atomic-dpms-hdcp14
[413.311579] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[413.451157] [105/134] (608s left) gem_exec_balancer (bonded-false-hang)
[413.710170] Starting subtest: bonded-false-hang
[413.715387] Subtest bonded-false-hang: SKIP (0.000s)
[413.842576] [106/134] (608s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt)
[413.943448] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt
[413.947856] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[414.066685] [107/134] (607s left) device_reset (unbind-reset-rebind)
[414.108466] Starting subtest: unbind-reset-rebind
[418.751257] Subtest unbind-reset-rebind: SUCCESS (4.637s)
[418.799579] [108/134] (603s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[418.891078] Starting subtest: bad-pixel-format-y-tiled-ccs
[418.893363] Starting dynamic subtest: pipe-A-eDP-1
[418.916897] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[418.929032] Starting dynamic subtest: pipe-B-eDP-1
[418.948553] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[418.957539] Starting dynamic subtest: pipe-C-eDP-1
[418.978752] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[418.987948] Starting dynamic subtest: pipe-D-eDP-1
[419.010028] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[419.014378] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.005s)
[421.081389] [109/134] (601s left) i915_query (query-topology-known-pci-ids)
[421.148614] Starting subtest: query-topology-known-pci-ids
[421.152917] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[421.275865] [110/134] (600s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted)
[421.334634] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted
[421.439656] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.105s)
[421.530962] [111/134] (600s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[421.631256] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[422.221474] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.592s)
[422.418402] [112/134] (599s left) perf_pmu (interrupts-sync)
[422.461735] Starting subtest: interrupts-sync
[423.565918] Subtest interrupts-sync: SUCCESS (1.104s)
[423.707085] [113/134] (598s left) perf_pmu (module-unload)
[423.808123] Starting subtest: module-unload
[432.300281] Subtest module-unload: FAIL (8.391s)
[432.332138] [FACT perf_pmu (module-unload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[432.332893] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true
[432.336064] [114/134] (589s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)
[433.924985] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling
[433.930214] Starting dynamic subtest: pipe-A-default-mode
[435.077993] Dynamic subtest pipe-A-default-mode: SKIP (1.148s)
[435.082579] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.151s)
[435.207328] [FACT kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[435.208578] [FACT kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)] new: kernel.kmod_is_loaded.i915: true
[435.212299] [115/134] (587s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[435.329150] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[435.331439] Starting dynamic subtest: pipe-A-eDP-1
[435.352260] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[435.363015] Starting dynamic subtest: pipe-B-eDP-1
[435.381958] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[435.389879] Starting dynamic subtest: pipe-C-eDP-1
[435.408670] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[435.416915] Starting dynamic subtest: pipe-D-eDP-1
[435.435862] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[435.437710] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[437.846746] [116/134] (584s left) gem_mmap_gtt (zero-extend)
[437.914650] Subtest zero-extend: SKIP (0.000s)
[437.988004] [117/134] (584s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[438.087802] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[439.155236] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.067s)
[439.324666] [118/134] (583s left) gem_softpin (noreloc)
[439.397535] Starting subtest: noreloc
[444.790726] Subtest noreloc: SUCCESS (5.390s)
[444.963349] [119/134] (577s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[445.063995] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[445.075392] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.010s)
[445.175338] [120/134] (577s left) vgem_basic (setversion)
[445.225453] Starting subtest: setversion
[445.229469] Subtest setversion: SUCCESS (0.000s)
[445.293171] [121/134] (577s left) kms_feature_discovery (psr1)
[445.394904] Starting subtest: psr1
[445.397253] Subtest psr1: SUCCESS (0.000s)
[445.490555] [122/134] (577s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[445.617637] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[454.564710] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (8.946s)
[455.376278] [123/134] (567s left) sysfs_heartbeat_interval (mixed)
[455.443960] Starting subtest: mixed
[455.446274] Starting dynamic subtest: vecs0
[460.721049] Dynamic subtest vecs0: SUCCESS (5.276s)
[460.733318] Starting dynamic subtest: ccs0
[465.933575] Dynamic subtest ccs0: SUCCESS (5.210s)
[465.944639] Starting dynamic subtest: vcs0
[471.175114] Dynamic subtest vcs0: SUCCESS (5.240s)
[471.186350] Starting dynamic subtest: bcs0
[476.369795] Dynamic subtest bcs0: SUCCESS (5.192s)
[476.380475] Starting dynamic subtest: rcs0
[481.582841] Dynamic subtest rcs0: SUCCESS (5.211s)
[481.594475] Starting dynamic subtest: vcs1
[486.803738] Dynamic subtest vcs1: SUCCESS (5.219s)
[486.806295] Subtest mixed: SUCCESS (31.360s)
[486.890347] [124/134] (535s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[487.025142] Starting subtest: cursorB-vs-flipA-atomic
[487.029578] Subtest cursorB-vs-flipA-atomic: SKIP (0.001s)
[487.194076] [125/134] (535s left) gem_lmem_swapping (verify-ccs)
[487.233036] Subtest verify-ccs: SKIP (0.000s)
[487.284041] [126/134] (535s left) kms_addfb_basic (unused-handle)
[487.337781] Starting subtest: unused-handle
[487.339982] Subtest unused-handle: SUCCESS (0.000s)
[487.507171] [127/134] (535s left) kms_pm_dc (dc5-psr)
[488.612993] Starting subtest: dc5-psr
[491.493315] Subtest dc5-psr: SUCCESS (2.879s)
[492.301214] [128/134] (530s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[492.406264] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[499.197803] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.794s)
[499.369092] [129/134] (523s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[499.478432] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[506.908333] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.432s)
[507.079020] [130/134] (515s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[507.183976] Starting subtest: y-tiled-16bpp-rotate-90
[507.188530] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[507.304105] [131/134] (515s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[507.398907] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[507.401220] Starting dynamic subtest: pipe-A-default-mode
[510.194360] Dynamic subtest pipe-A-default-mode: SUCCESS (2.796s)
[510.196537] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.797s)
[511.037004] [132/134] (512s left) kms_rmfb (close-fd)
[511.146086] Starting subtest: close-fd
[511.148252] Starting dynamic subtest: pipe-A-eDP-1
[511.253154] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[511.263377] Starting dynamic subtest: pipe-B-eDP-1
[512.393443] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.140s)
[512.403793] Starting dynamic subtest: pipe-C-eDP-1
[513.627864] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.232s)
[513.637319] Starting dynamic subtest: pipe-D-eDP-1
[514.760876] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s)
[514.763240] Subtest close-fd: SUCCESS (3.615s)
[515.977607] [133/134] (507s left) kms_atomic_interruptible (legacy-setmode)
[516.071794] Starting subtest: legacy-setmode
[516.073941] Starting dynamic subtest: pipe-A-eDP-1
[522.969998] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.896s)
[522.972261] Subtest legacy-setmode: SUCCESS (6.897s)
[523.849834] [134/134] (499s left) kms_flip (2x-flip-vs-wf_vblank-interruptible)
[523.946331] Starting subtest: 2x-flip-vs-wf_vblank-interruptible
[523.957325] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.010s)
[524.041599] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_balancer
results: parsing output: 1/ for test: gem_exec_params
results: parsing output: 2/ for test: kms_pm_rpm
results: parsing output: 3/ for test: gem_mmap_gtt
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: i915_drm_fdinfo
results: parsing output: 6/ for test: gem_pwrite
results: parsing output: 7/ for test: gem_ctx_persistence
results: parsing output: 8/ for test: kms_chamelium_audio
results: parsing output: 9/ for test: gem_exec_params
results: parsing output: 10/ for test: api_intel_bb
results: parsing output: 11/ for test: kms_addfb_basic
results: parsing output: 12/ for test: kms_chamelium_hpd
results: parsing output: 13/ for test: gem_exec_reloc
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: i915_fb_tiling
results: parsing output: 17/ for test: kms_psr
results: parsing output: 18/ for test: kms_psr2_sf
results: parsing output: 19/ for test: gem_exec_reloc
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: gem_compute
results: parsing output: 22/ for test: kms_lease
results: parsing output: 23/ for test: kms_flip
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: kms_selftest
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: i915_pm_rc6_residency
results: parsing output: 30/ for test: kms_pipe_crc_basic
results: parsing output: 31/ for test: gem_userptr_blits
results: parsing output: 32/ for test: gem_pread
results: parsing output: 33/ for test: gem_userptr_blits
results: parsing output: 34/ for test: gem_tiled_fence_blits
results: parsing output: 35/ for test: gem_pxp
results: parsing output: 36/ for test: gem_exec_params
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: gem_softpin
results: parsing output: 39/ for test: gem_exec_params
results: parsing output: 40/ for test: kms_atomic
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_ctx_persistence
results: parsing output: 43/ for test: gem_exec_whisper
results: parsing output: 44/ for test: gen9_exec_parse
results: parsing output: 45/ for test: kms_rotation_crc
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_chamelium_frames
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: drm_read
results: parsing output: 50/ for test: core_auth
results: parsing output: 51/ for test: template
results: parsing output: 52/ for test: syncobj_timeline
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_dither
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_psr
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_plane_lowres
results: parsing output: 61/ for test: gem_softpin
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_eio
results: parsing output: 64/ for test: kms_plane_scaling
results: parsing output: 65/ for test: kms_psr2_sf
results: parsing output: 66/ for test: gem_exec_schedule
results: parsing output: 67/ for test: gem_exec_capture
results: parsing output: 68/ for test: kms_vblank
results: parsing output: 69/ for test: kms_invalid_mode
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: syncobj_wait
results: parsing output: 72/ for test: gem_exec_schedule
results: parsing output: 73/ for test: perf
results: parsing output: 74/ for test: kms_scaling_modes
results: parsing output: 75/ for test: gem_ringfill
results: parsing output: 76/ for test: kms_ccs
results: parsing output: 77/ for test: kms_color
results: parsing output: 78/ for test: gem_exec_schedule
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_cursor_crc
results: parsing output: 81/ for test: kms_cursor_crc
results: parsing output: 82/ for test: gem_eio
results: parsing output: 83/ for test: kms_plane_scaling
results: parsing output: 84/ for test: gem_exec_fence
results: parsing output: 85/ for test: prime_vgem
results: parsing output: 86/ for test: i915_pm_rps
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_wait
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_plane
results: parsing output: 94/ for test: kms_ccs
results: parsing output: 95/ for test: kms_cursor_crc
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: kms_psr2_sf
results: parsing output: 98/ for test: kms_async_flips
results: parsing output: 99/ for test: syncobj_wait
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: gem_eio
results: parsing output: 102/ for test: dumb_buffer
results: parsing output: 103/ for test: kms_content_protection
results: parsing output: 104/ for test: gem_exec_balancer
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: device_reset
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: i915_query
results: parsing output: 109/ for test: syncobj_timeline
results: parsing output: 110/ for test: kms_big_fb
results: parsing output: 111/ for test: perf_pmu
results: parsing output: 112/ for test: perf_pmu
results: parsing output: 113/ for test: kms_flip_scaled_crc
results: parsing output: 114/ for test: kms_ccs
results: parsing output: 115/ for test: gem_mmap_gtt
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: gem_softpin
results: parsing output: 118/ for test: kms_flip
results: parsing output: 119/ for test: vgem_basic
results: parsing output: 120/ for test: kms_feature_discovery
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: sysfs_heartbeat_interval
results: parsing output: 123/ for test: kms_cursor_legacy
results: parsing output: 124/ for test: gem_lmem_swapping
results: parsing output: 125/ for test: kms_addfb_basic
results: parsing output: 126/ for test: kms_pm_dc
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: kms_flip_scaled_crc
results: parsing output: 131/ for test: kms_rmfb
results: parsing output: 132/ for test: kms_atomic_interruptible
results: parsing output: 133/ for test: kms_flip
Done.