[60.590009] Initializing watchdogs
[60.590062]   /dev/watchdog0
[60.604775] [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]
[60.609583] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.611946] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.615362] [001/133] (960s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[60.757311] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[61.770781] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (1.016s)
[62.604679] [002/133] (958s left) gem_ctx_param (non-root-set)
[62.666788] Starting subtest: non-root-set
[62.689462] Subtest non-root-set: SUCCESS (0.023s)
[62.771725] [003/133] (958s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[62.875606] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[62.879582] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[62.964538] [004/133] (958s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[63.065143] Starting subtest: 4-tiled-16bpp-rotate-90
[63.087943] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.022s)
[63.193538] [005/133] (958s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[63.285558] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[63.289733] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[63.371662] [006/133] (957s left) api_intel_bb (object-noreloc-keep-cache-simple)
[63.425589] Starting subtest: object-noreloc-keep-cache-simple
[63.427683] Subtest object-noreloc-keep-cache-simple: SUCCESS (0.005s)
[63.534448] [007/133] (957s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[63.629318] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[63.632915] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[63.719605] [008/133] (957s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[63.807936] Starting subtest: fbcpsr-rgb565-draw-render
[70.850917] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.059s)
[70.983512] [009/133] (950s left) kms_color (ctm-green-to-red)
[71.081400] Starting subtest: ctm-green-to-red
[71.083598] Starting dynamic subtest: pipe-A-eDP-1
[71.777183] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.698s)
[71.785186] Starting dynamic subtest: pipe-B-eDP-1
[73.137067] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.359s)
[73.146121] Starting dynamic subtest: pipe-C-eDP-1
[74.520300] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.383s)
[74.528519] Starting dynamic subtest: pipe-D-eDP-1
[75.818503] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.298s)
[75.820844] Subtest ctm-green-to-red: SUCCESS (4.739s)
[76.670588] [010/133] (944s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[76.725712] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[76.730470] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[76.921917] [011/133] (944s left) gem_exec_store (cachelines)
[76.996798] Starting subtest: cachelines
[76.999218] Starting dynamic subtest: rcs0
[77.086790] Dynamic subtest rcs0: SUCCESS (0.089s)
[77.097530] Starting dynamic subtest: bcs0
[77.139622] Dynamic subtest bcs0: SUCCESS (0.053s)
[77.149280] Starting dynamic subtest: vcs0
[77.208125] Dynamic subtest vcs0: SUCCESS (0.068s)
[77.217584] Starting dynamic subtest: vcs1
[77.280255] Dynamic subtest vcs1: SUCCESS (0.071s)
[77.290060] Starting dynamic subtest: vecs0
[77.340464] Dynamic subtest vecs0: SUCCESS (0.059s)
[77.348834] Starting dynamic subtest: ccs0
[77.404593] Dynamic subtest ccs0: SUCCESS (0.063s)
[77.407212] Subtest cachelines: SUCCESS (0.408s)
[77.584476] [012/133] (943s left) kms_psr (psr2-primary-page-flip)
[77.683490] Starting subtest: psr2-primary-page-flip
[77.685861] Starting dynamic subtest: eDP-1
[79.364540] Dynamic subtest eDP-1: SUCCESS (1.679s)
[79.366868] Subtest psr2-primary-page-flip: SUCCESS (1.680s)
[80.244951] [013/133] (941s left) testdisplay
[80.555795] [014/133] (941s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[80.673984] Starting subtest: cursorA-vs-flipA-varying-size
[91.823305] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.147s)
[92.695236] [015/133] (928s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[92.815820] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[92.820367] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[92.893105] [016/133] (928s left) gem_partial_pwrite_pread (reads-display)
[92.956671] Subtest reads-display: SKIP (0.000s)
[93.023038] [017/133] (928s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[93.124417] Starting subtest: x-tiled-16bpp-rotate-180
[94.402494] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.279s)
[94.518357] [018/133] (927s left) kms_cursor_crc (cursor-offscreen-max-size)
[94.807563] Starting subtest: cursor-offscreen-max-size
[94.812808] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[94.932309] [019/133] (926s left) gem_workarounds (reset-fd)
[95.008510] Starting subtest: reset-fd
[95.196908] Subtest reset-fd: SUCCESS (0.188s)
[95.322718] [020/133] (926s left) kms_getfb (getfb-addfb-different-handles)
[95.417394] Starting subtest: getfb-addfb-different-handles
[95.419382] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[95.505125] [021/133] (926s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[95.602499] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[95.604492] Starting dynamic subtest: pipe-A-eDP-1
[95.612419] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[95.622156] Starting dynamic subtest: pipe-B-eDP-1
[95.629841] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[95.639102] Starting dynamic subtest: pipe-C-eDP-1
[95.645967] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[95.652788] Starting dynamic subtest: pipe-D-eDP-1
[95.659527] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[95.662996] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[97.764143] [022/133] (924s left) vgem_basic (bad-handle)
[97.852152] Starting subtest: bad-handle
[97.854034] Subtest bad-handle: SUCCESS (0.000s)
[97.911816] [FACT vgem_basic (bad-handle)] new: kernel.kmod_is_loaded.vgem: true
[97.915720] [023/133] (924s left) kms_atomic (plane-overlay-legacy)
[98.003979] Starting subtest: plane-overlay-legacy
[98.006256] Starting dynamic subtest: pipe-A-eDP-1
[98.425050] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.422s)
[98.427231] Subtest plane-overlay-legacy: SUCCESS (0.423s)
[99.295265] [024/133] (922s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[99.396180] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[99.400498] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[99.478581] [025/133] (922s left) prime_self_import (basic-llseek-bad)
[99.515945] Starting subtest: basic-llseek-bad
[99.529058] Subtest basic-llseek-bad: SUCCESS (0.017s)
[99.635634] [026/133] (922s left) gem_pxp (verify-pxp-stale-buf-execution)
[99.761527] Starting subtest: verify-pxp-stale-buf-execution
[100.824519] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.064s)
[100.955349] [027/133] (921s left) i915_drm_fdinfo (most-busy-check-all)
[100.997825] Starting subtest: most-busy-check-all
[101.000202] Starting dynamic subtest: rcs0
[101.004807] Dynamic subtest rcs0: SKIP (0.003s)
[101.013856] Starting dynamic subtest: bcs0
[101.018056] Dynamic subtest bcs0: SKIP (0.000s)
[101.025859] Starting dynamic subtest: vcs0
[101.030224] Dynamic subtest vcs0: SKIP (0.000s)
[101.037472] Starting dynamic subtest: vcs1
[101.041586] Dynamic subtest vcs1: SKIP (0.000s)
[101.048779] Starting dynamic subtest: vecs0
[101.052857] Dynamic subtest vecs0: SKIP (0.000s)
[101.060054] Starting dynamic subtest: ccs0
[101.064058] Dynamic subtest ccs0: SKIP (0.000s)
[101.068052] Subtest most-busy-check-all: SKIP (0.007s)
[101.104846] [028/133] (921s left) gem_exec_reloc (basic-cpu-read)
[101.210919] Subtest basic-cpu-read: SKIP (0.000s)
[101.295698] [029/133] (920s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[101.403984] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[108.842349] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.440s)
[108.965289] [030/133] (913s left) kms_plane_scaling (planes-downscale-factor-0-75)
[109.060436] Starting subtest: planes-downscale-factor-0-75
[109.062846] Starting dynamic subtest: pipe-A
[109.084739] Dynamic subtest pipe-A: SKIP (0.018s)
[109.093681] Starting dynamic subtest: pipe-B
[109.117773] Dynamic subtest pipe-B: SKIP (0.032s)
[109.125717] Starting dynamic subtest: pipe-C
[109.149394] Dynamic subtest pipe-C: SKIP (0.032s)
[109.157142] Starting dynamic subtest: pipe-D
[109.180219] Dynamic subtest pipe-D: SKIP (0.032s)
[109.184011] Subtest planes-downscale-factor-0-75: SKIP (0.116s)
[109.275794] [031/133] (912s left) kms_psr (pr-cursor-plane-move)
[109.381700] Starting subtest: pr-cursor-plane-move
[109.386065] Subtest pr-cursor-plane-move: SKIP (0.000s)
[109.460643] [032/133] (912s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[109.515848] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[109.518161] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[109.607426] [033/133] (912s left) kms_chamelium_frames (dp-crc-single)
[109.994792] Subtest dp-crc-single: SKIP (0.000s)
[110.836490] [034/133] (911s left) gem_exec_reloc (basic-wc-active)
[110.907658] Subtest basic-wc-active: SKIP (0.000s)
[110.977277] [035/133] (911s left) gem_basic (multigpu-create-close)
[111.033892] Starting subtest: multigpu-create-close
[111.038414] Subtest multigpu-create-close: SKIP (0.000s)
[111.116655] [036/133] (911s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[111.203347] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[111.205703] Starting dynamic subtest: pipe-A
[111.565034] Dynamic subtest pipe-A: SUCCESS (0.361s)
[111.577931] Starting dynamic subtest: pipe-B
[112.960074] Dynamic subtest pipe-B: SUCCESS (1.394s)
[112.970030] Starting dynamic subtest: pipe-C
[114.398993] Dynamic subtest pipe-C: SUCCESS (1.438s)
[114.408111] Starting dynamic subtest: pipe-D
[115.811481] Dynamic subtest pipe-D: SUCCESS (1.411s)
[115.813840] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.609s)
[116.969061] [037/133] (905s left) kms_addfb_basic (too-high)
[117.072049] Starting subtest: too-high
[117.073966] Subtest too-high: SUCCESS (0.001s)
[117.172968] [038/133] (905s left) gem_userptr_blits (sync-unmap-cycles)
[117.235753] Starting subtest: sync-unmap-cycles
[122.608404] Subtest sync-unmap-cycles: SUCCESS (5.371s)
[122.755463] [039/133] (899s left) syncobj_wait (wait-any-interrupted)
[122.820550] Starting subtest: wait-any-interrupted
[122.922057] Subtest wait-any-interrupted: SUCCESS (0.102s)
[123.012788] [040/133] (899s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[123.130308] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[123.134370] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[123.220418] [041/133] (899s left) kms_hdr (static-toggle)
[123.321751] Starting subtest: static-toggle
[123.328306] Subtest static-toggle: SKIP (0.000s)
[123.392990] [042/133] (899s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[123.487426] Starting subtest: extended-pageflip-modeset-hang-oldfb
[123.489664] Starting dynamic subtest: pipe-A
[131.378670] Dynamic subtest pipe-A: SUCCESS (7.892s)
[131.387794] Starting dynamic subtest: pipe-D
[139.976663] Dynamic subtest pipe-D: SUCCESS (8.595s)
[139.978848] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.492s)
[140.824795] [043/133] (881s left) kms_sequence (get-forked)
[140.916668] Starting subtest: get-forked
[140.918876] Starting dynamic subtest: pipe-A-eDP-1
[142.796065] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.879s)
[142.805277] Starting dynamic subtest: pipe-B-eDP-1
[145.422442] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.623s)
[145.431586] Starting dynamic subtest: pipe-C-eDP-1
[147.978972] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.556s)
[147.987293] Starting dynamic subtest: pipe-D-eDP-1
[150.510587] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.532s)
[150.512996] Subtest get-forked: SUCCESS (9.596s)
[151.339362] [044/133] (871s left) gem_exec_schedule (reorder-wide)
[151.529135] Subtest reorder-wide: SKIP (0.000s)
[151.708887] [045/133] (871s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[151.794985] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[151.799085] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.000s)
[151.864209] [046/133] (871s left) gem_pwrite (basic-self)
[151.931202] Subtest basic-self: SKIP (0.000s)
[152.001416] [047/133] (870s left) i915_selftest (perf)
[155.258654] Starting subtest: perf
[155.260854] Starting dynamic subtest: engine_cs
[159.609664] Dynamic subtest engine_cs: SUCCESS (4.351s)
[159.619040] Starting dynamic subtest: request
[164.855054] Dynamic subtest request: SUCCESS (5.244s)
[164.863961] Starting dynamic subtest: migrate
[169.240456] Dynamic subtest migrate: SUCCESS (4.383s)
[169.249200] Starting dynamic subtest: region
[175.110309] Dynamic subtest region: SUCCESS (5.869s)
[175.113228] Subtest perf: SUCCESS (19.852s)
[175.141247] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[175.141950] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[175.145306] [048/133] (847s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[176.740918] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[176.745380] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.001s)
[176.841395] [FACT kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[176.842658] [FACT kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)] new: kernel.kmod_is_loaded.i915: true
[176.846232] [049/133] (846s left) kms_dsc (dsc-with-bpc-formats)
[176.943807] Starting subtest: dsc-with-bpc-formats
[177.055752] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[177.097578] [050/133] (845s left) kms_flip (plain-flip-fb-recreate)
[177.192884] Starting subtest: plain-flip-fb-recreate
[177.194744] Starting dynamic subtest: A-eDP1
[186.045241] Dynamic subtest A-eDP1: SUCCESS (8.854s)
[186.055610] Starting dynamic subtest: B-eDP1
[194.877093] Dynamic subtest B-eDP1: SUCCESS (8.830s)
[194.885666] Starting dynamic subtest: C-eDP1
[203.688025] Dynamic subtest C-eDP1: SUCCESS (8.808s)
[203.700521] Starting dynamic subtest: D-eDP1
[212.512325] Dynamic subtest D-eDP1: SUCCESS (8.822s)
[212.516600] Subtest plain-flip-fb-recreate: SUCCESS (35.326s)
[213.710626] [051/133] (809s left) gem_ccs (suspend-resume)
[213.765083] Starting subtest: suspend-resume
[213.769298] Subtest suspend-resume: SKIP (0.000s)
[213.870279] [052/133] (809s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[213.976485] Starting subtest: yf-tiled-32bpp-rotate-270
[213.980784] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[214.062163] [053/133] (809s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[214.188165] Starting subtest: 2x-long-cursor-vs-flip-atomic
[214.192455] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.001s)
[214.332585] [054/133] (808s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[214.452339] Starting subtest: psr-shrfb-scaledprimary
[220.197053] Subtest psr-shrfb-scaledprimary: SUCCESS (5.748s)
[220.309586] [055/133] (802s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[220.364505] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[220.472760] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.108s)
[220.563760] [056/133] (802s left) i915_pm_rpm (system-suspend-execbuf)
[222.085841] Starting subtest: system-suspend-execbuf
[280.362736] Subtest system-suspend-execbuf: SUCCESS (43.187s)
[280.733420] [057/133] (742s left) gem_exec_balancer (sliced)
[280.793035] Starting subtest: sliced
[280.797414] Subtest sliced: SKIP (0.000s)
[281.084123] [058/133] (742s left) kms_pm_rpm (modeset-non-lpsp)
[283.406134] Starting subtest: modeset-non-lpsp
[284.572651] Subtest modeset-non-lpsp: SKIP (1.165s)
[284.984083] [059/133] (738s left) kms_vrr (flip-basic)
[285.070863] Starting subtest: flip-basic
[285.077097] Subtest flip-basic: SKIP (0.000s)
[285.145995] [060/133] (738s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[285.527551] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[286.357571] [061/133] (737s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[286.468092] Starting subtest: y-tiled-32bpp-rotate-0
[286.472813] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[286.564228] [062/133] (737s left) syncobj_wait (reset-signaled)
[286.626855] Starting subtest: reset-signaled
[286.629136] Subtest reset-signaled: SUCCESS (0.000s)
[286.723870] [063/133] (736s left) gem_exec_fence (syncobj-wait)
[287.150692] Starting subtest: syncobj-wait
[288.209102] Subtest syncobj-wait: SUCCESS (1.057s)
[290.229229] [064/133] (733s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[290.332461] Starting subtest: bad-pixel-format-y-tiled-ccs
[290.334766] Starting dynamic subtest: pipe-A-eDP-1
[290.356541] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[290.367161] Starting dynamic subtest: pipe-B-eDP-1
[290.387043] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[290.395688] Starting dynamic subtest: pipe-C-eDP-1
[290.417086] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[290.428931] Starting dynamic subtest: pipe-D-eDP-1
[290.451257] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[290.455385] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[292.552298] [065/133] (731s left) gem_spin_batch (engines)
[292.613284] Starting subtest: engines
[292.615516] Starting dynamic subtest: rcs0
[295.854072] Dynamic subtest rcs0: SUCCESS (3.237s)
[295.864408] Starting dynamic subtest: bcs0
[299.090651] Dynamic subtest bcs0: SUCCESS (3.235s)
[299.101277] Starting dynamic subtest: vcs0
[302.328929] Dynamic subtest vcs0: SUCCESS (3.238s)
[302.339032] Starting dynamic subtest: vcs1
[305.565729] Dynamic subtest vcs1: SUCCESS (3.235s)
[305.577016] Starting dynamic subtest: vecs0
[308.801599] Dynamic subtest vecs0: SUCCESS (3.234s)
[308.812932] Starting dynamic subtest: ccs0
[312.039813] Dynamic subtest ccs0: SUCCESS (3.237s)
[312.042008] Subtest engines: SUCCESS (19.432s)
[312.265315] [066/133] (711s left) gem_ctx_persistence (engines-hang)
[312.338931] Starting subtest: engines-hang
[312.341236] Starting dynamic subtest: rcs0
[312.472730] Dynamic subtest rcs0: SUCCESS (0.136s)
[312.485218] Starting dynamic subtest: bcs0
[312.574776] Dynamic subtest bcs0: SUCCESS (0.100s)
[312.585854] Starting dynamic subtest: vcs0
[312.671575] Dynamic subtest vcs0: SUCCESS (0.095s)
[312.685596] Starting dynamic subtest: vcs1
[312.781533] Dynamic subtest vcs1: SUCCESS (0.108s)
[312.798040] Starting dynamic subtest: vecs0
[312.888722] Dynamic subtest vecs0: SUCCESS (0.104s)
[312.906441] Starting dynamic subtest: ccs0
[312.940790] Dynamic subtest ccs0: SUCCESS (0.049s)
[312.943388] Subtest engines-hang: SUCCESS (0.606s)
[313.030531] [067/133] (710s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[313.144062] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[320.511119] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.372s)
[320.637299] [068/133] (703s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[320.762764] Starting subtest: basic-busy-flip-before-cursor-varying-size
[320.767391] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[320.902987] [069/133] (702s left) i915_hangman (detector)
[320.984979] Starting subtest: detector
[320.986896] Starting dynamic subtest: rcs0
[332.203692] Dynamic subtest rcs0: SUCCESS (11.224s)
[332.214864] Starting dynamic subtest: bcs0
[342.188474] Dynamic subtest bcs0: SUCCESS (9.980s)
[342.200786] Starting dynamic subtest: vcs0
[353.196193] Dynamic subtest vcs0: SUCCESS (11.001s)
[353.207784] Starting dynamic subtest: vcs1
[364.141155] Dynamic subtest vcs1: SUCCESS (10.940s)
[364.152654] Starting dynamic subtest: vecs0
[376.107963] Dynamic subtest vecs0: SUCCESS (11.963s)
[376.120093] Starting dynamic subtest: ccs0
[386.155805] Dynamic subtest ccs0: SUCCESS (10.046s)
[386.158670] Subtest detector: SUCCESS (65.170s)
[386.285329] [070/133] (637s left) kms_fbcon_fbt (fbc-suspend)
[386.352584] Starting subtest: fbc-suspend
[436.074990] Subtest fbc-suspend: SUCCESS (19.904s)
[438.439867] [071/133] (586s left) kms_psr (psr-cursor-render)
[438.555144] Starting subtest: psr-cursor-render
[438.557415] Starting dynamic subtest: eDP-1
[440.230577] Dynamic subtest eDP-1: SUCCESS (1.695s)
[440.232771] Subtest psr-cursor-render: SUCCESS (1.696s)
[441.087128] [072/133] (583s left) gem_exec_flush (basic-batch-kernel-default-wb)
[441.139612] Starting subtest: basic-batch-kernel-default-wb
[448.650668] Subtest basic-batch-kernel-default-wb: SUCCESS (7.556s)
[448.839785] [073/133] (576s left) gem_vm_create (invalid-destroy)
[448.893864] Starting subtest: invalid-destroy
[448.896119] Subtest invalid-destroy: SUCCESS (0.002s)
[449.003777] [074/133] (576s left) kms_lease (lessee-list)
[449.093379] Starting subtest: lessee-list
[449.095636] Starting dynamic subtest: pipe-A-eDP-1
[449.097920] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[449.107507] Starting dynamic subtest: pipe-B-eDP-1
[449.109327] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[449.118088] Starting dynamic subtest: pipe-C-eDP-1
[449.119923] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[449.128722] Starting dynamic subtest: pipe-D-eDP-1
[449.130548] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[449.132518] Subtest lessee-list: SUCCESS (0.009s)
[449.206718] [075/133] (575s left) device_reset (unbind-cold-reset-rebind)
[449.255702] Starting subtest: unbind-cold-reset-rebind
[449.260414] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[449.329580] [076/133] (575s left) gem_tiled_blits (basic)
[449.394202] Subtest basic: SKIP (0.000s)
[449.461883] [077/133] (575s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[449.556687] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[453.519887] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (3.978s)
[454.650829] [078/133] (570s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[454.739906] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[454.742201] Starting dynamic subtest: pipe-A-eDP-1
[454.762910] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[454.774519] Starting dynamic subtest: pipe-B-eDP-1
[454.794134] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[454.803369] Starting dynamic subtest: pipe-C-eDP-1
[454.822724] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[454.831718] Starting dynamic subtest: pipe-D-eDP-1
[454.851133] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[454.853736] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[456.905506] [079/133] (568s left) kms_psr2_su (frontbuffer-xrgb8888)
[456.957490] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[457.013280] [080/133] (568s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[457.097068] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[457.099341] Starting dynamic subtest: pipe-A-eDP-1
[457.553857] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.428s)
[457.565034] Starting dynamic subtest: pipe-B-eDP-1
[458.975115] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.422s)
[458.984794] Starting dynamic subtest: pipe-C-eDP-1
[460.318345] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.343s)
[460.330243] Starting dynamic subtest: pipe-D-eDP-1
[461.661542] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.345s)
[461.663488] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.544s)
[462.848659] [081/133] (562s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[462.971729] Starting subtest: addfb25-y-tiled-legacy
[462.973712] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[463.071692] [082/133] (562s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[463.177425] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[463.204157] Starting dynamic subtest: pipe-A-plane-0
[463.659236] Dynamic subtest pipe-A-plane-0: SUCCESS (0.455s)
[463.670856] Starting dynamic subtest: pipe-A-plane-3
[463.925179] Dynamic subtest pipe-A-plane-3: SUCCESS (0.265s)
[465.020869] Starting dynamic subtest: pipe-B-plane-0
[465.467972] Dynamic subtest pipe-B-plane-0: SUCCESS (0.455s)
[465.478328] Starting dynamic subtest: pipe-B-plane-3
[465.723455] Dynamic subtest pipe-B-plane-3: SUCCESS (0.253s)
[466.036841] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.860s)
[466.891957] [083/133] (558s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[467.005951] Starting subtest: fbc-rgb565-draw-pwrite
[471.597140] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.593s)
[472.800596] [084/133] (552s left) syncobj_timeline (single-wait-all-available-submitted)
[472.858271] Starting subtest: single-wait-all-available-submitted
[472.860623] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[472.955609] [085/133] (552s left) gem_exec_reloc (basic-write-gtt-noreloc)
[473.018201] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[473.090707] [086/133] (552s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[473.190432] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[473.194712] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[473.290491] [087/133] (552s left) gem_userptr_blits (dmabuf-sync)
[473.374053] Starting subtest: dmabuf-sync
[473.377991] Subtest dmabuf-sync: SKIP (0.002s)
[473.516585] [088/133] (552s left) gem_mmap_gtt (bad-object)
[473.577216] Subtest bad-object: SKIP (0.000s)
[473.638884] [089/133] (552s left) kms_properties (plane-properties-legacy)
[473.734301] Starting subtest: plane-properties-legacy
[473.735866] Starting dynamic subtest: pipe-A-eDP-1
[474.296876] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.562s)
[474.306895] Starting dynamic subtest: pipe-B-eDP-1
[475.562090] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.264s)
[475.572377] Starting dynamic subtest: pipe-C-eDP-1
[476.799214] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.235s)
[476.809377] Starting dynamic subtest: pipe-D-eDP-1
[478.034834] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.235s)
[478.037258] Subtest plane-properties-legacy: SUCCESS (4.301s)
[478.846571] [090/133] (546s left) gem_exec_fence (syncobj-invalid-wait)
[479.274228] Starting subtest: syncobj-invalid-wait
[479.276742] Subtest syncobj-invalid-wait: SUCCESS (0.000s)
[481.228710] [091/133] (544s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[481.366406] Starting subtest: modeset-atomic-cursor-hotspot
[481.370920] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[481.511640] [092/133] (544s left) gem_exec_reloc (basic-cpu-wc-noreloc)
[481.563388] Subtest basic-cpu-wc-noreloc: SKIP (0.000s)
[481.633585] [093/133] (544s left) gem_exec_balancer (full)
[481.701995] Starting subtest: full
[485.993229] Subtest full: SUCCESS (4.291s)
[486.308831] [094/133] (539s left) gem_ctx_shared (q-out-order)
[486.371471] Starting subtest: Q-out-order
[486.373736] Starting dynamic subtest: rcs0
[486.532396] Dynamic subtest rcs0: SUCCESS (0.159s)
[486.549941] Starting dynamic subtest: vcs0
[486.637452] Dynamic subtest vcs0: SUCCESS (0.105s)
[486.655928] Starting dynamic subtest: vcs1
[486.748386] Dynamic subtest vcs1: SUCCESS (0.110s)
[486.765998] Starting dynamic subtest: vecs0
[486.855664] Dynamic subtest vecs0: SUCCESS (0.107s)
[486.873839] Starting dynamic subtest: ccs0
[486.972265] Dynamic subtest ccs0: SUCCESS (0.116s)
[486.974816] Subtest Q-out-order: SUCCESS (0.601s)
[488.240021] [095/133] (537s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[488.332383] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[495.392553] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (7.065s)
[495.533248] [096/133] (530s left) gem_eio (wait-wedge-immediate)
[495.761110] Starting subtest: wait-wedge-immediate
[497.246498] Subtest wait-wedge-immediate: SUCCESS (1.486s)
[497.397806] [097/133] (528s left) kms_cursor_crc (cursor-size-change)
[497.481910] Starting subtest: cursor-size-change
[497.483753] Starting dynamic subtest: pipe-A-eDP-1
[498.278548] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.798s)
[498.288903] Starting dynamic subtest: pipe-D-eDP-1
[499.719796] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.440s)
[499.721957] Subtest cursor-size-change: SUCCESS (2.241s)
[500.524542] [098/133] (525s left) gem_exec_schedule (noreorder-priority)
[500.712581] Starting subtest: noreorder-priority
[500.714603] Starting dynamic subtest: rcs0
[501.856697] Dynamic subtest rcs0: SUCCESS (1.147s)
[501.870571] Starting dynamic subtest: vcs0
[503.123583] Dynamic subtest vcs0: SUCCESS (1.265s)
[503.142205] Starting dynamic subtest: vcs1
[505.160284] Dynamic subtest vcs1: SUCCESS (2.034s)
[505.173181] Starting dynamic subtest: vecs0
[507.126794] Dynamic subtest vecs0: SUCCESS (1.964s)
[507.144310] Starting dynamic subtest: ccs0
[508.272239] Dynamic subtest ccs0: SUCCESS (1.144s)
[508.274873] Subtest noreorder-priority: SUCCESS (7.567s)
[508.459897] [099/133] (517s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[508.523727] Subtest writes-after-reads: SKIP (0.000s)
[508.618759] [100/133] (517s left) kms_chamelium_audio (dp-audio)
[509.003774] Subtest dp-audio: SKIP (0.000s)
[509.829248] [101/133] (516s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[509.925774] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[509.930268] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[510.017586] [102/133] (516s left) gem_ctx_persistence (legacy-engines-queued)
[510.091120] Starting subtest: legacy-engines-queued
[510.093463] Starting dynamic subtest: render
[510.158823] Dynamic subtest render: SUCCESS (0.067s)
[510.173772] Starting dynamic subtest: bsd1
[512.110145] Dynamic subtest bsd1: SUCCESS (1.946s)
[512.123378] Starting dynamic subtest: bsd2
[514.094583] Dynamic subtest bsd2: SUCCESS (1.982s)
[514.107384] Starting dynamic subtest: blt
[514.138562] Dynamic subtest blt: SUCCESS (0.043s)
[514.149145] Starting dynamic subtest: vebox
[516.142229] Dynamic subtest vebox: SUCCESS (2.001s)
[516.145078] Subtest legacy-engines-queued: SUCCESS (6.053s)
[516.223291] [103/133] (510s left) kms_cursor_crc (cursor-random-64x64)
[516.412875] Starting subtest: cursor-random-64x64
[516.417729] Starting dynamic subtest: pipe-A-eDP-1
[519.748701] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.333s)
[519.793509] Starting dynamic subtest: pipe-D-eDP-1
[523.745120] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.961s)
[523.747448] Subtest cursor-random-64x64: SUCCESS (7.335s)
[524.632724] [104/133] (501s left) kms_pipe_crc_basic (read-crc)
[524.731716] Starting subtest: read-crc
[524.734053] Starting dynamic subtest: pipe-A-eDP-1
[525.443057] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.711s)
[525.456690] Starting dynamic subtest: pipe-B-eDP-1
[526.798208] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.355s)
[526.808362] Starting dynamic subtest: pipe-C-eDP-1
[528.096926] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.299s)
[528.111190] Starting dynamic subtest: pipe-D-eDP-1
[529.439384] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.341s)
[529.441659] Subtest read-crc: SUCCESS (4.711s)
[530.273656] [105/133] (496s left) syncobj_timeline (invalid-query-one-illegal-handle)
[530.329796] Starting subtest: invalid-query-one-illegal-handle
[530.332129] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[530.413466] [106/133] (496s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[530.505534] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[530.509981] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[530.594579] [107/133] (495s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[530.700276] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[530.702469] Starting dynamic subtest: pipe-A-default-mode
[531.828757] Dynamic subtest pipe-A-default-mode: SKIP (1.125s)
[531.833126] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.127s)
[532.004819] [108/133] (494s left) kms_plane_cursor (overlay)
[532.091409] Starting subtest: overlay
[532.095591] Starting dynamic subtest: pipe-A-eDP-1-size-64
[534.614989] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.522s)
[534.676304] Starting dynamic subtest: pipe-A-eDP-1-size-128
[537.217619] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.549s)
[537.284019] Starting dynamic subtest: pipe-A-eDP-1-size-256
[539.785859] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.515s)
[540.136663] Starting dynamic subtest: pipe-B-eDP-1-size-64
[543.250806] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.126s)
[543.314796] Starting dynamic subtest: pipe-B-eDP-1-size-128
[545.798401] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.494s)
[545.858710] Starting dynamic subtest: pipe-B-eDP-1-size-256
[548.277050] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.426s)
[548.624865] Starting dynamic subtest: pipe-C-eDP-1-size-64
[551.478911] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.864s)
[551.518489] Starting dynamic subtest: pipe-C-eDP-1-size-128
[553.767494] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.258s)
[553.809199] Starting dynamic subtest: pipe-C-eDP-1-size-256
[555.923137] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.125s)
[556.256939] Starting dynamic subtest: pipe-D-eDP-1-size-64
[559.116984] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.876s)
[559.156859] Starting dynamic subtest: pipe-D-eDP-1-size-128
[561.317387] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.169s)
[561.356549] Starting dynamic subtest: pipe-D-eDP-1-size-256
[563.507179] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.158s)
[563.825655] Subtest overlay: SUCCESS (31.727s)
[564.634867] [109/133] (461s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[564.730751] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[569.322130] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (4.594s)
[570.451673] [110/133] (456s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[570.536960] Starting subtest: fbc-psr2-cursor-mmap-gtt
[570.539555] Starting dynamic subtest: eDP-1
[570.544054] Dynamic subtest eDP-1: SKIP (0.000s)
[570.548341] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[570.613781] [111/133] (456s left) api_intel_allocator (two-level-inception-interruptible)
[570.678613] Starting subtest: two-level-inception-interruptible
[576.084142] Subtest two-level-inception-interruptible: SUCCESS (5.407s)
[576.214455] [112/133] (450s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[576.319996] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[582.964839] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.644s)
[583.077391] [113/133] (443s left) perf_pmu (init-sema)
[583.118856] Starting subtest: init-sema
[583.121323] Starting dynamic subtest: rcs0
[583.123824] Dynamic subtest rcs0: SUCCESS (0.002s)
[583.136072] Starting dynamic subtest: bcs0
[583.137903] Dynamic subtest bcs0: SUCCESS (0.000s)
[583.147436] Starting dynamic subtest: vcs0
[583.149273] Dynamic subtest vcs0: SUCCESS (0.001s)
[583.158726] Starting dynamic subtest: vcs1
[583.160569] Dynamic subtest vcs1: SUCCESS (0.000s)
[583.169974] Starting dynamic subtest: vecs0
[583.172220] Dynamic subtest vecs0: SUCCESS (0.001s)
[583.182209] Starting dynamic subtest: ccs0
[583.184204] Dynamic subtest ccs0: SUCCESS (0.000s)
[583.186157] Subtest init-sema: SUCCESS (0.008s)
[583.231944] [114/133] (443s left) vgem_basic (second-client)
[583.273978] Starting subtest: second-client
[583.295606] Subtest second-client: SUCCESS (0.022s)
[583.354464] [115/133] (443s left) kms_pm_backlight (fade-with-dpms)
[583.439150] Starting subtest: fade-with-dpms
[585.587847] Starting dynamic subtest: eDP-1
[588.075098] Dynamic subtest eDP-1: SUCCESS (2.486s)
[588.077672] Subtest fade-with-dpms: SUCCESS (4.636s)
[588.881697] [116/133] (438s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[588.986425] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[588.990754] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[589.082672] [117/133] (437s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[589.132521] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[589.237404] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.103s)
[589.313036] [118/133] (437s left) kms_psr (fbc-psr2-suspend)
[589.396487] Starting subtest: fbc-psr2-suspend
[589.398374] Starting dynamic subtest: eDP-1
[589.402284] Dynamic subtest eDP-1: SKIP (0.000s)
[589.406220] Subtest fbc-psr2-suspend: SKIP (0.001s)
[589.469829] [119/133] (437s left) kms_big_fb (linear-8bpp-rotate-0)
[589.569737] Starting subtest: linear-8bpp-rotate-0
[590.850185] Subtest linear-8bpp-rotate-0: SUCCESS (1.281s)
[590.963933] [120/133] (436s left) gen3_mixed_blits
[591.097342] [121/133] (436s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf)
[591.199685] Starting subtest: psr2-cursor-plane-move-continuous-exceed-fully-sf
[591.204224] Starting dynamic subtest: pipe-A-eDP-1
[602.207862] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.005s)
[602.219870] Starting dynamic subtest: pipe-B-eDP-1
[613.883144] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.676s)
[613.885563] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SUCCESS (22.686s)
[614.737545] [122/133] (412s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[614.800260] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[614.862136] [123/133] (412s left) gem_exec_reloc (basic-gtt-cpu)
[614.926767] Subtest basic-gtt-cpu: SKIP (0.000s)
[615.005026] [124/133] (412s left) prime_vgem (fence-wait)
[615.082140] Starting subtest: fence-wait
[615.084570] Starting dynamic subtest: rcs0
[616.147436] Dynamic subtest rcs0: SUCCESS (1.064s)
[616.158545] Starting dynamic subtest: bcs0
[617.201693] Dynamic subtest bcs0: SUCCESS (1.055s)
[617.213879] Starting dynamic subtest: vcs0
[618.245796] Dynamic subtest vcs0: SUCCESS (1.042s)
[618.258883] Starting dynamic subtest: vcs1
[619.290204] Dynamic subtest vcs1: SUCCESS (1.043s)
[619.303237] Starting dynamic subtest: vecs0
[620.334265] Dynamic subtest vecs0: SUCCESS (1.042s)
[620.347653] Starting dynamic subtest: ccs0
[621.378484] Dynamic subtest ccs0: SUCCESS (1.043s)
[621.381375] Subtest fence-wait: SUCCESS (6.298s)
[621.507147] [125/133] (405s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[621.610582] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[621.614653] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[621.705401] [126/133] (405s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[621.792434] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[621.794684] Starting dynamic subtest: pipe-A-eDP-1
[621.879956] Dynamic subtest pipe-A-eDP-1: SKIP (0.088s)
[621.892016] Starting dynamic subtest: pipe-B-eDP-1
[623.010503] Dynamic subtest pipe-B-eDP-1: SKIP (1.131s)
[623.015001] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.222s)
[624.212770] [127/133] (403s left) gen9_exec_parse (secure-batches)
[624.268296] Subtest secure-batches: SKIP (0.000s)
[624.338973] [128/133] (403s left) perf (gen12-group-concurrent-oa-buffer-read)
[624.458022] Starting subtest: gen12-group-concurrent-oa-buffer-read
[634.508246] Subtest gen12-group-concurrent-oa-buffer-read: SUCCESS (10.054s)
[634.558475] [129/133] (392s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[634.664455] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[641.395970] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.735s)
[641.513687] [130/133] (386s left) kms_content_protection (srm)
[641.659221] Starting subtest: srm
[641.666587] Subtest srm: SKIP (0.003s)
[641.788577] [131/133] (385s left) gem_exercise_blt (fast-copy-emit)
[641.857470] Starting subtest: fast-copy-emit
[641.859794] Starting dynamic subtest: linear-smem-smem-emit
[641.879079] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.022s)
[641.891368] Starting dynamic subtest: xmajor-smem-smem-emit
[641.896646] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.017s)
[641.909889] Starting dynamic subtest: tile4-smem-smem-emit
[641.914674] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.017s)
[641.925191] Starting dynamic subtest: tile64-smem-smem-emit
[641.930869] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.016s)
[641.933206] Subtest fast-copy-emit: SUCCESS (0.074s)
[642.052828] [132/133] (385s left) gem_mmap_offset (bad-extensions)
[642.105430] Starting subtest: bad-extensions
[642.107320] Subtest bad-extensions: SUCCESS (0.000s)
[642.190439] [133/133] (385s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[642.310277] Starting subtest: cursorA-vs-flipA-toggle
[653.428369] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.123s)
[654.242403] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_legacy
results: parsing output: 1/ for test: gem_ctx_param
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: api_intel_bb
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_color
results: parsing output: 9/ for test: gem_render_copy
results: parsing output: 10/ for test: gem_exec_store
results: parsing output: 11/ for test: kms_psr
results: parsing output: 12/ for test: testdisplay
results: parsing output: 13/ for test: kms_cursor_legacy
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_partial_pwrite_pread
results: parsing output: 16/ for test: kms_big_fb
results: parsing output: 17/ for test: kms_cursor_crc
results: parsing output: 18/ for test: gem_workarounds
results: parsing output: 19/ for test: kms_getfb
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: vgem_basic
results: parsing output: 22/ for test: kms_atomic
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: prime_self_import
results: parsing output: 25/ for test: gem_pxp
results: parsing output: 26/ for test: i915_drm_fdinfo
results: parsing output: 27/ for test: gem_exec_reloc
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_plane_scaling
results: parsing output: 30/ for test: kms_psr
results: parsing output: 31/ for test: syncobj_timeline
results: parsing output: 32/ for test: kms_chamelium_frames
results: parsing output: 33/ for test: gem_exec_reloc
results: parsing output: 34/ for test: gem_basic
results: parsing output: 35/ for test: kms_plane_scaling
results: parsing output: 36/ for test: kms_addfb_basic
results: parsing output: 37/ for test: gem_userptr_blits
results: parsing output: 38/ for test: syncobj_wait
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: kms_hdr
results: parsing output: 41/ for test: kms_busy
results: parsing output: 42/ for test: kms_sequence
results: parsing output: 43/ for test: gem_exec_schedule
results: parsing output: 44/ for test: kms_flip_scaled_crc
results: parsing output: 45/ for test: gem_pwrite
results: parsing output: 46/ for test: i915_selftest
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_dsc
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: gem_ccs
results: parsing output: 51/ for test: kms_big_fb
results: parsing output: 52/ for test: kms_cursor_legacy
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: syncobj_timeline
results: parsing output: 55/ for test: i915_pm_rpm
results: parsing output: 56/ for test: gem_exec_balancer
results: parsing output: 57/ for test: kms_pm_rpm
results: parsing output: 58/ for test: kms_vrr
results: parsing output: 59/ for test: kms_chamelium_hpd
results: parsing output: 60/ for test: kms_big_fb
results: parsing output: 61/ for test: syncobj_wait
results: parsing output: 62/ for test: gem_exec_fence
results: parsing output: 63/ for test: kms_ccs
results: parsing output: 64/ for test: gem_spin_batch
results: parsing output: 65/ for test: gem_ctx_persistence
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_cursor_legacy
results: parsing output: 68/ for test: i915_hangman
results: parsing output: 69/ for test: kms_fbcon_fbt
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: gem_exec_flush
results: parsing output: 72/ for test: gem_vm_create
results: parsing output: 73/ for test: kms_lease
results: parsing output: 74/ for test: device_reset
results: parsing output: 75/ for test: gem_tiled_blits
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: kms_psr2_su
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: kms_addfb_basic
results: parsing output: 81/ for test: kms_plane
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: gem_exec_reloc
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: gem_userptr_blits
results: parsing output: 87/ for test: gem_mmap_gtt
results: parsing output: 88/ for test: kms_properties
results: parsing output: 89/ for test: gem_exec_fence
results: parsing output: 90/ for test: kms_cursor_legacy
results: parsing output: 91/ for test: gem_exec_reloc
results: parsing output: 92/ for test: gem_exec_balancer
results: parsing output: 93/ for test: gem_ctx_shared
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_eio
results: parsing output: 96/ for test: kms_cursor_crc
results: parsing output: 97/ for test: gem_exec_schedule
results: parsing output: 98/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 99/ for test: kms_chamelium_audio
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: gem_ctx_persistence
results: parsing output: 102/ for test: kms_cursor_crc
results: parsing output: 103/ for test: kms_pipe_crc_basic
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_flip_scaled_crc
results: parsing output: 107/ for test: kms_plane_cursor
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: api_intel_allocator
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: perf_pmu
results: parsing output: 113/ for test: vgem_basic
results: parsing output: 114/ for test: kms_pm_backlight
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: kms_psr
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: gen3_mixed_blits
results: parsing output: 120/ for test: kms_psr2_sf
results: parsing output: 121/ for test: gem_exec_reloc
results: parsing output: 122/ for test: gem_exec_reloc
results: parsing output: 123/ for test: prime_vgem
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_psr2_sf
results: parsing output: 126/ for test: gen9_exec_parse
results: parsing output: 127/ for test: perf
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_content_protection
results: parsing output: 130/ for test: gem_exercise_blt
results: parsing output: 131/ for test: gem_mmap_offset
results: parsing output: 132/ for test: kms_cursor_legacy
Done.