[57.602865] Initializing watchdogs
[57.603047]   /dev/watchdog0
[57.619224] [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.624052] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.626594] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.629997] [001/133] (960s left) kms_scaling_modes (scaling-mode-center)
[57.770089] Starting subtest: scaling-mode-center
[57.772530] Starting dynamic subtest: pipe-A-eDP-1
[57.891248] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.122s)
[57.899227] Starting dynamic subtest: pipe-B-eDP-1
[59.325915] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.423s)
[59.336120] Starting dynamic subtest: pipe-C-eDP-1
[60.445806] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.112s)
[60.453315] Starting dynamic subtest: pipe-D-eDP-1
[61.576129] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.123s)
[61.578581] Subtest scaling-mode-center: SUCCESS (3.783s)
[62.730951] [002/133] (955s left) gem_exec_gttfill (engines)
[62.801647] Starting subtest: engines
[62.804069] Starting dynamic subtest: rcs0
[85.715829] Dynamic subtest rcs0: SUCCESS (22.879s)
[85.727290] Starting dynamic subtest: bcs0
[108.996640] Dynamic subtest bcs0: SUCCESS (23.270s)
[109.007723] Starting dynamic subtest: vcs0
[132.311641] Dynamic subtest vcs0: SUCCESS (23.312s)
[132.322747] Starting dynamic subtest: vcs1
[155.588210] Dynamic subtest vcs1: SUCCESS (23.276s)
[155.596812] Starting dynamic subtest: vecs0
[178.887897] Dynamic subtest vecs0: SUCCESS (23.298s)
[178.900699] Starting dynamic subtest: ccs0
[202.240140] Dynamic subtest ccs0: SUCCESS (23.348s)
[202.242494] Subtest engines: SUCCESS (139.391s)
[202.450211] [003/133] (815s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[202.592119] Starting subtest: plane-primary-overlay-mutable-zpos
[206.007668] Subtest plane-primary-overlay-mutable-zpos: SKIP (3.414s)
[207.181107] [004/133] (811s left) gem_partial_pwrite_pread (write-snoop)
[207.260874] Subtest write-snoop: SKIP (0.000s)
[207.319968] [005/133] (810s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[207.624312] Starting subtest: cursor-rapid-movement-512x170
[207.628804] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[207.763020] [006/133] (810s left) kms_plane_scaling (planes-upscale-20x20)
[207.862411] Starting subtest: planes-upscale-20x20
[207.864818] Starting dynamic subtest: pipe-A
[207.924603] Dynamic subtest pipe-A: SUCCESS (0.062s)
[207.935168] Starting dynamic subtest: pipe-B
[209.113443] Dynamic subtest pipe-B: SUCCESS (1.188s)
[209.123976] Starting dynamic subtest: pipe-C
[210.230710] Dynamic subtest pipe-C: SUCCESS (1.117s)
[210.240314] Starting dynamic subtest: pipe-D
[211.354422] Dynamic subtest pipe-D: SUCCESS (1.124s)
[211.356659] Subtest planes-upscale-20x20: SUCCESS (3.492s)
[212.510229] [007/133] (805s left) kms_cursor_crc (cursor-offscreen-32x32)
[212.662373] Starting subtest: cursor-offscreen-32x32
[212.709834] Subtest cursor-offscreen-32x32: SKIP (0.043s)
[212.997186] [008/133] (805s left) syncobj_wait (invalid-reset-illegal-handle)
[213.058886] Starting subtest: invalid-reset-illegal-handle
[213.061304] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[213.166387] [009/133] (805s left) gen9_exec_parse (basic-rejected)
[213.232269] Subtest basic-rejected: SKIP (0.000s)
[213.299487] [010/133] (805s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[213.359376] Starting subtest: single-wait-all-for-submit-available-submitted
[213.361520] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[213.472698] [011/133] (805s left) gem_pwrite_snooped
[213.605897] [012/133] (804s left) kms_color (ctm-0-25)
[213.699078] Starting subtest: ctm-0-25
[213.700693] Starting dynamic subtest: pipe-A-eDP-1
[217.221515] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.522s)
[217.230392] Starting dynamic subtest: pipe-B-eDP-1
[221.519531] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.296s)
[221.528821] Starting dynamic subtest: pipe-C-eDP-1
[225.556056] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.038s)
[225.564577] Starting dynamic subtest: pipe-D-eDP-1
[229.572708] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.012s)
[229.575080] Subtest ctm-0-25: SUCCESS (15.870s)
[230.389356] [013/133] (788s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[230.775620] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[231.595877] [014/133] (787s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[231.692495] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[231.694899] Starting dynamic subtest: pipe-A-eDP-1
[231.720689] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[231.731254] Starting dynamic subtest: pipe-B-eDP-1
[231.754880] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[231.763088] Starting dynamic subtest: pipe-C-eDP-1
[231.787757] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[231.797256] Starting dynamic subtest: pipe-D-eDP-1
[231.821222] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[231.825201] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[233.872077] [015/133] (784s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[233.980951] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[238.445804] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.468s)
[239.589808] [016/133] (779s left) gem_exec_schedule (promotion)
[239.668049] Starting subtest: promotion
[239.670043] Starting dynamic subtest: rcs0
[239.775080] Dynamic subtest rcs0: SUCCESS (0.112s)
[239.784280] Starting dynamic subtest: vcs0
[239.880795] Dynamic subtest vcs0: SUCCESS (0.105s)
[239.889975] Starting dynamic subtest: vcs1
[239.975060] Dynamic subtest vcs1: SUCCESS (0.093s)
[239.985746] Starting dynamic subtest: vecs0
[240.057197] Dynamic subtest vecs0: SUCCESS (0.082s)
[240.067848] Starting dynamic subtest: ccs0
[240.140073] Dynamic subtest ccs0: SUCCESS (0.083s)
[240.142382] Subtest promotion: SUCCESS (0.478s)
[241.012747] [017/133] (777s left) gem_render_copy (yf-tiled-to-vebox-y-tiled)
[241.074176] Starting subtest: yf-tiled-to-vebox-y-tiled
[241.078744] Subtest yf-tiled-to-vebox-y-tiled: SKIP (0.000s)
[241.260729] [018/133] (777s left) gem_mmap_gtt (big-copy-xy)
[241.312962] Subtest big-copy-XY: SKIP (0.000s)
[241.367138] [019/133] (777s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted)
[241.429215] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted
[241.538992] Subtest etime-multi-wait-all-for-submit-unsubmitted: SUCCESS (0.108s)
[241.620249] [020/133] (777s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[241.723076] Starting subtest: psr-rgb565-draw-pwrite
[248.774357] Subtest psr-rgb565-draw-pwrite: SUCCESS (7.054s)
[248.877145] [021/133] (770s left) kms_flip (plain-flip-ts-check-interruptible)
[248.978550] Starting subtest: plain-flip-ts-check-interruptible
[248.980930] Starting dynamic subtest: A-eDP1
[257.815507] Dynamic subtest A-eDP1: SUCCESS (8.835s)
[257.825935] Starting dynamic subtest: B-eDP1
[266.647663] Dynamic subtest B-eDP1: SUCCESS (8.829s)
[266.656867] Starting dynamic subtest: C-eDP1
[275.464742] Dynamic subtest C-eDP1: SUCCESS (8.815s)
[275.477732] Starting dynamic subtest: D-eDP1
[284.289636] Dynamic subtest D-eDP1: SUCCESS (8.824s)
[284.291995] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.312s)
[285.473306] [022/133] (733s left) kms_flip (flip-vs-rmfb-interruptible)
[285.588328] Starting subtest: flip-vs-rmfb-interruptible
[285.590552] Starting dynamic subtest: A-eDP1
[294.257478] Dynamic subtest A-eDP1: SUCCESS (8.674s)
[294.269164] Starting dynamic subtest: B-eDP1
[302.886319] Dynamic subtest B-eDP1: SUCCESS (8.626s)
[302.896179] Starting dynamic subtest: C-eDP1
[311.504658] Dynamic subtest C-eDP1: SUCCESS (8.617s)
[311.514653] Starting dynamic subtest: D-eDP1
[320.134221] Dynamic subtest D-eDP1: SUCCESS (8.627s)
[320.139335] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.555s)
[321.334708] [023/133] (697s left) i915_pm_rps (reset)
[321.392631] Starting subtest: reset
[329.864547] Subtest reset: SUCCESS (8.468s)
[329.981337] [024/133] (689s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[330.080547] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[330.082902] Starting dynamic subtest: pipe-A-eDP-1
[330.103625] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[330.113956] Starting dynamic subtest: pipe-B-eDP-1
[330.134516] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[330.142992] Starting dynamic subtest: pipe-C-eDP-1
[330.162682] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[330.171157] Starting dynamic subtest: pipe-D-eDP-1
[330.190192] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[330.192265] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.014s)
[332.388743] [025/133] (686s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[332.447089] Starting subtest: basic-batch-kernel-default-cmd
[332.451659] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[332.642598] [026/133] (686s left) sysfs_heartbeat_interval (idempotent)
[332.691645] Starting subtest: idempotent
[332.694052] Starting dynamic subtest: vecs0
[332.696447] Dynamic subtest vecs0: SUCCESS (0.001s)
[332.706640] Starting dynamic subtest: ccs0
[332.708922] Dynamic subtest ccs0: SUCCESS (0.001s)
[332.717165] Starting dynamic subtest: vcs0
[332.719413] Dynamic subtest vcs0: SUCCESS (0.001s)
[332.727728] Starting dynamic subtest: bcs0
[332.730097] Dynamic subtest bcs0: SUCCESS (0.000s)
[332.738167] Starting dynamic subtest: rcs0
[332.740424] Dynamic subtest rcs0: SUCCESS (0.001s)
[332.748727] Starting dynamic subtest: vcs1
[332.750733] Dynamic subtest vcs1: SUCCESS (0.001s)
[332.753068] Subtest idempotent: SUCCESS (0.011s)
[332.811085] [027/133] (686s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[332.907978] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[339.880067] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.974s)
[340.013371] [028/133] (679s left) gem_mmap_gtt (close-race)
[340.066245] Subtest close-race: SKIP (0.000s)
[340.131068] [029/133] (679s left) perf (invalid-remove-userspace-config)
[340.253951] Starting subtest: invalid-remove-userspace-config
[340.260988] Subtest invalid-remove-userspace-config: SUCCESS (0.007s)
[340.309184] [030/133] (678s left) gem_ccs (suspend-resume)
[340.369022] Starting subtest: suspend-resume
[340.373657] Subtest suspend-resume: SKIP (0.000s)
[340.469142] [031/133] (678s left) kms_chamelium_color (ctm-red-to-blue)
[340.571459] Subtest ctm-red-to-blue: SKIP (0.000s)
[340.620529] [032/133] (678s left) gem_mmap_gtt (big-bo-tiledy)
[340.684300] Subtest big-bo-tiledY: SKIP (0.000s)
[340.753967] [033/133] (678s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[340.862676] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[340.865063] Starting dynamic subtest: pipe-A-eDP-1
[346.700159] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.844s)
[346.709308] Starting dynamic subtest: pipe-B-eDP-1
[353.351085] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.646s)
[353.353373] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (12.493s)
[354.201835] [034/133] (665s left) kms_draw_crc (draw-method-blt)
[354.302084] Starting subtest: draw-method-blt
[354.304182] Starting dynamic subtest: xrgb8888-untiled
[354.569387] Dynamic subtest xrgb8888-untiled: SUCCESS (0.269s)
[354.578871] Starting dynamic subtest: rgb565-untiled
[354.813889] Dynamic subtest rgb565-untiled: SUCCESS (0.242s)
[354.822602] Starting dynamic subtest: xrgb2101010-untiled
[355.036365] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.220s)
[355.047797] Starting dynamic subtest: xbgr16161616f-untiled
[355.269046] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.233s)
[355.279831] Starting dynamic subtest: xrgb8888-xtiled
[355.390993] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.120s)
[355.399369] Starting dynamic subtest: rgb565-xtiled
[355.513247] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[355.521605] Starting dynamic subtest: xrgb2101010-xtiled
[355.625344] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[355.634105] Starting dynamic subtest: xbgr16161616f-xtiled
[355.746771] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.120s)
[355.755031] Starting dynamic subtest: xrgb8888-4tiled
[355.858551] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.110s)
[355.868908] Starting dynamic subtest: rgb565-4tiled
[355.979908] Dynamic subtest rgb565-4tiled: SUCCESS (0.119s)
[355.988530] Starting dynamic subtest: xrgb2101010-4tiled
[356.091315] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[356.102152] Starting dynamic subtest: xbgr16161616f-4tiled
[356.213604] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.120s)
[356.215532] Subtest draw-method-blt: SUCCESS (1.915s)
[356.340017] [035/133] (663s left) kms_psr (fbc-psr-no-drrs)
[356.444394] Starting subtest: fbc-psr-no-drrs
[356.446441] Starting dynamic subtest: eDP-1
[356.449973] Dynamic subtest eDP-1: SKIP (0.000s)
[356.453614] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[356.515526] [036/133] (663s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[356.592032] Subtest untiled-to-tiled: SKIP (0.000s)
[356.685747] [037/133] (662s left) gem_ctx_param (invalid-get-no-zeromap)
[356.754737] Starting subtest: invalid-get-no-zeromap
[356.756871] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[356.867171] [038/133] (662s left) i915_drm_fdinfo (virtual-busy-hang-all)
[356.914253] Starting subtest: virtual-busy-hang-all
[356.917741] Subtest virtual-busy-hang-all: SKIP (0.000s)
[356.975621] [039/133] (662s left) syncobj_timeline (invalid-signal-zero-handles)
[357.035814] Starting subtest: invalid-signal-zero-handles
[357.037862] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[357.148011] [040/133] (662s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[357.245850] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[357.247799] Starting dynamic subtest: A-eDP1
[360.838210] Dynamic subtest A-eDP1: SUCCESS (3.588s)
[360.847998] Starting dynamic subtest: B-eDP1
[364.043416] Dynamic subtest B-eDP1: SUCCESS (3.204s)
[364.052754] Starting dynamic subtest: C-eDP1
[367.131990] Dynamic subtest C-eDP1: SUCCESS (3.087s)
[367.141862] Starting dynamic subtest: D-eDP1
[370.231709] Dynamic subtest D-eDP1: SUCCESS (3.097s)
[370.233906] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (12.986s)
[371.035088] [041/133] (648s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[371.147032] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[371.151470] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[371.250217] [042/133] (648s left) perf_pmu (most-busy-idle-check-all)
[371.298500] Starting subtest: most-busy-idle-check-all
[371.300655] Starting dynamic subtest: rcs0
[372.888850] Dynamic subtest rcs0: SUCCESS (1.590s)
[372.901402] Starting dynamic subtest: bcs0
[374.935672] Dynamic subtest bcs0: SUCCESS (2.043s)
[374.947332] Starting dynamic subtest: vcs0
[376.919661] Dynamic subtest vcs0: SUCCESS (1.981s)
[376.931902] Starting dynamic subtest: vcs1
[378.903284] Dynamic subtest vcs1: SUCCESS (1.981s)
[378.916818] Starting dynamic subtest: vecs0
[380.887561] Dynamic subtest vecs0: SUCCESS (1.980s)
[380.898423] Starting dynamic subtest: ccs0
[382.935717] Dynamic subtest ccs0: SUCCESS (2.045s)
[382.938692] Subtest most-busy-idle-check-all: SUCCESS (11.638s)
[383.043246] [043/133] (636s left) gem_eio (in-flight-contexts-10ms)
[383.238849] Starting subtest: in-flight-contexts-10ms
[396.921255] Subtest in-flight-contexts-10ms: SUCCESS (13.682s)
[397.045213] [044/133] (622s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[397.133906] Starting subtest: psr-rgb565-draw-mmap-cpu
[404.026094] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.895s)
[404.137041] [045/133] (615s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[404.228406] Starting subtest: fbc-farfromfence-mmap-gtt
[404.232391] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[404.311256] [046/133] (615s left) gem_busy (busy)
[404.370582] Starting subtest: busy
[404.372949] Starting dynamic subtest: all-engines
[404.408692] Dynamic subtest all-engines: SUCCESS (0.037s)
[404.420834] Starting dynamic subtest: rcs0
[405.852488] Dynamic subtest rcs0: SUCCESS (1.443s)
[405.861796] Starting dynamic subtest: bcs0
[405.898852] Dynamic subtest bcs0: SUCCESS (0.043s)
[405.909944] Starting dynamic subtest: vcs0
[405.937225] Dynamic subtest vcs0: SUCCESS (0.039s)
[405.946582] Starting dynamic subtest: vcs1
[407.900234] Dynamic subtest vcs1: SUCCESS (1.959s)
[407.911492] Starting dynamic subtest: vecs0
[409.884130] Dynamic subtest vecs0: SUCCESS (1.982s)
[409.895401] Starting dynamic subtest: ccs0
[411.932054] Dynamic subtest ccs0: SUCCESS (2.047s)
[411.934531] Subtest busy: SUCCESS (7.558s)
[412.287460] [047/133] (607s left) gem_spin_batch (legacy-resubmit)
[412.348972] Starting subtest: legacy-resubmit
[412.351466] Starting dynamic subtest: default
[412.362114] Dynamic subtest default: SUCCESS (0.013s)
[412.373586] Starting dynamic subtest: render
[412.375878] Dynamic subtest render: SUCCESS (0.002s)
[412.385306] Starting dynamic subtest: bsd
[412.387469] Dynamic subtest bsd: SUCCESS (0.004s)
[412.399821] Starting dynamic subtest: bsd1
[412.402297] Dynamic subtest bsd1: SUCCESS (0.003s)
[412.411428] Starting dynamic subtest: bsd2
[412.413564] Dynamic subtest bsd2: SUCCESS (0.001s)
[412.422580] Starting dynamic subtest: blt
[412.424716] Dynamic subtest blt: SUCCESS (0.004s)
[412.433755] Starting dynamic subtest: vebox
[412.435975] Dynamic subtest vebox: SUCCESS (0.003s)
[412.438195] Subtest legacy-resubmit: SUCCESS (0.035s)
[412.612984] [048/133] (607s left) gem_vm_create (invalid-destroy)
[412.669031] Starting subtest: invalid-destroy
[412.671417] Subtest invalid-destroy: SUCCESS (0.002s)
[412.767731] [049/133] (607s left) vgem_basic (setversion)
[412.889611] Starting subtest: setversion
[412.894059] Subtest setversion: SUCCESS (0.000s)
[412.951739] [FACT vgem_basic (setversion)] new: kernel.kmod_is_loaded.vgem: true
[412.955119] [050/133] (607s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[413.053831] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[413.056019] Starting dynamic subtest: pipe-A-eDP-1
[413.064588] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[413.074235] Starting dynamic subtest: pipe-B-eDP-1
[413.083132] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[413.094448] Starting dynamic subtest: pipe-C-eDP-1
[413.103171] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[413.112041] Starting dynamic subtest: pipe-D-eDP-1
[413.120153] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[413.124081] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[415.323942] [051/133] (604s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[415.420526] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[415.424638] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[415.519315] [052/133] (604s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[415.658047] Starting subtest: dp-mst-lic-type-0-hdcp14
[415.662592] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[415.776670] [053/133] (604s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[415.877216] Starting subtest: fbc-psr2-cursor-mmap-cpu
[415.879489] Starting dynamic subtest: eDP-1
[415.883858] Dynamic subtest eDP-1: SKIP (0.000s)
[415.888255] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.001s)
[415.965641] [054/133] (604s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[416.067868] Starting subtest: yf-tiled-addfb-size-offset-overflow
[416.072344] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[416.166670] [055/133] (604s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[416.264972] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[416.269308] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[416.357494] [056/133] (603s left) dumb_buffer (invalid-bpp)
[416.405104] Starting subtest: invalid-bpp
[416.406976] Subtest invalid-bpp: SUCCESS (0.000s)
[416.494528] [057/133] (603s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[416.593260] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[416.596801] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[416.679211] [058/133] (603s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[416.733235] Starting subtest: etime-single-wait-all-available-unsubmitted
[416.843101] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.108s)
[416.921280] [059/133] (603s left) gem_reg_read (bad-register)
[416.990695] Starting subtest: bad-register
[416.992735] Subtest bad-register: SUCCESS (0.000s)
[417.114652] [060/133] (603s left) gem_ctx_persistence (legacy-engines-cleanup)
[417.175315] Starting subtest: legacy-engines-cleanup
[417.177639] Starting dynamic subtest: render
[417.247669] Dynamic subtest render: SUCCESS (0.072s)
[417.263149] Starting dynamic subtest: bsd1
[417.294556] Dynamic subtest bsd1: SUCCESS (0.044s)
[417.306796] Starting dynamic subtest: bsd2
[417.338537] Dynamic subtest bsd2: SUCCESS (0.042s)
[417.348871] Starting dynamic subtest: blt
[417.390601] Dynamic subtest blt: SUCCESS (0.049s)
[417.403721] Starting dynamic subtest: vebox
[417.438668] Dynamic subtest vebox: SUCCESS (0.045s)
[417.441124] Subtest legacy-engines-cleanup: SUCCESS (0.265s)
[417.520200] [061/133] (603s left) gem_ctx_shared (q-in-order)
[417.583566] Starting subtest: Q-in-order
[417.585907] Starting dynamic subtest: rcs0
[417.683899] Dynamic subtest rcs0: SUCCESS (0.100s)
[417.701683] Starting dynamic subtest: vcs0
[417.762888] Dynamic subtest vcs0: SUCCESS (0.078s)
[417.780588] Starting dynamic subtest: vcs1
[417.840528] Dynamic subtest vcs1: SUCCESS (0.075s)
[417.856539] Starting dynamic subtest: vecs0
[417.918400] Dynamic subtest vecs0: SUCCESS (0.078s)
[417.935094] Starting dynamic subtest: ccs0
[418.009588] Dynamic subtest ccs0: SUCCESS (0.090s)
[418.011862] Subtest Q-in-order: SUCCESS (0.426s)
[418.985887] [062/133] (601s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180-hflip)
[419.091132] Starting subtest: linear-max-hw-stride-32bpp-rotate-180-hflip
[419.095158] Subtest linear-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[419.190415] [063/133] (601s left) kms_properties (connector-properties-legacy)
[419.282453] Starting subtest: connector-properties-legacy
[419.284736] Starting dynamic subtest: pipe-A-eDP-1
[427.227379] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.945s)
[427.238431] Starting dynamic subtest: pipe-None-DP-1
[427.242740] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[427.253599] Starting dynamic subtest: pipe-None-HDMI-A-1
[427.257932] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[427.266757] Starting dynamic subtest: pipe-None-DP-2
[427.270647] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[427.279525] Starting dynamic subtest: pipe-None-HDMI-A-2
[427.283456] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[427.292547] Starting dynamic subtest: pipe-None-DP-3
[427.296885] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[427.305949] Starting dynamic subtest: pipe-None-DP-4
[427.310279] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[427.312534] Subtest connector-properties-legacy: SUCCESS (7.957s)
[428.092588] [064/133] (592s left) kms_dp_link_training (non-uhbr-sst)
[428.187425] Starting subtest: non-uhbr-sst
[428.195892] Subtest non-uhbr-sst: SKIP (0.000s)
[428.258146] [065/133] (592s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[428.354691] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[428.358693] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.000s)
[428.427441] [066/133] (592s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[428.512300] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[428.514212] Starting dynamic subtest: pipe-A
[428.849707] Dynamic subtest pipe-A: SUCCESS (0.339s)
[428.859670] Starting dynamic subtest: pipe-B
[430.310127] Dynamic subtest pipe-B: SUCCESS (1.460s)
[430.320439] Starting dynamic subtest: pipe-C
[431.733627] Dynamic subtest pipe-C: SUCCESS (1.422s)
[431.745359] Starting dynamic subtest: pipe-D
[433.144434] Dynamic subtest pipe-D: SUCCESS (1.410s)
[433.146775] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.634s)
[434.321966] [067/133] (586s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[434.416517] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[434.420697] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[434.522469] [068/133] (586s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[434.609693] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[434.614027] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[435.760023] [069/133] (585s left) gem_exec_schedule (u-submit-golden-slice)
[435.842301] Starting subtest: u-submit-golden-slice
[435.844391] Starting dynamic subtest: rcs0
[435.869060] Dynamic subtest rcs0: SUCCESS (0.035s)
[435.882971] Starting dynamic subtest: bcs0
[435.914459] Dynamic subtest bcs0: SUCCESS (0.045s)
[435.925083] Starting dynamic subtest: vcs0
[435.947658] Dynamic subtest vcs0: SUCCESS (0.032s)
[435.958445] Starting dynamic subtest: vcs1
[435.983057] Dynamic subtest vcs1: SUCCESS (0.035s)
[435.992567] Starting dynamic subtest: vecs0
[436.014473] Dynamic subtest vecs0: SUCCESS (0.031s)
[436.023627] Starting dynamic subtest: ccs0
[436.050133] Dynamic subtest ccs0: SUCCESS (0.034s)
[436.052249] Subtest u-submit-golden-slice: SUCCESS (0.217s)
[437.996223] [070/133] (582s left) kms_prime (basic-crc-hybrid)
[438.076948] Subtest basic-crc-hybrid: SKIP (0.000s)
[438.173270] [071/133] (582s left) kms_psr (pr-primary-mmap-gtt)
[438.277438] Starting subtest: pr-primary-mmap-gtt
[438.281712] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[438.375297] [072/133] (582s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[438.440060] Starting subtest: multi-wait-available-unsubmitted-signaled
[438.442311] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[438.532832] [073/133] (582s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[438.644456] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[438.648669] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[438.759919] [074/133] (582s left) gem_exec_flush (basic-wb-ro-before-default)
[438.825918] Starting subtest: basic-wb-ro-before-default
[444.301972] Subtest basic-wb-ro-before-default: SUCCESS (5.475s)
[444.509232] [075/133] (576s left) gem_userptr_blits (dmabuf-unsync)
[444.584774] Subtest dmabuf-unsync: SKIP (0.000s)
[444.699910] [076/133] (576s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[444.820848] Starting subtest: verify-pxp-stale-buf-optout-execution
[445.883816] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.065s)
[446.016239] [077/133] (575s left) prime_self_import (basic-llseek-bad)
[446.066520] Starting subtest: basic-llseek-bad
[446.080258] Subtest basic-llseek-bad: SUCCESS (0.014s)
[446.171087] [078/133] (575s left) kms_plane_multiple (tiling-4)
[446.271077] Starting subtest: tiling-4
[446.273338] Starting dynamic subtest: pipe-A-eDP-1
[450.021739] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.750s)
[450.031936] Starting dynamic subtest: pipe-B-eDP-1
[454.551398] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.527s)
[454.562184] Starting dynamic subtest: pipe-C-eDP-1
[458.960536] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.406s)
[458.970898] Starting dynamic subtest: pipe-D-eDP-1
[463.294466] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.336s)
[463.296867] Subtest tiling-4: SUCCESS (17.024s)
[464.112730] [079/133] (557s left) kms_pipe_crc_basic (read-crc)
[464.204374] Starting subtest: read-crc
[464.206811] Starting dynamic subtest: pipe-A-eDP-1
[464.914534] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.712s)
[464.925100] Starting dynamic subtest: pipe-B-eDP-1
[466.272860] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.357s)
[466.282835] Starting dynamic subtest: pipe-C-eDP-1
[467.566751] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.293s)
[467.577071] Starting dynamic subtest: pipe-D-eDP-1
[468.893537] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.323s)
[468.895751] Subtest read-crc: SUCCESS (4.691s)
[469.729088] [080/133] (551s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[469.827247] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[477.193497] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.369s)
[477.319287] [081/133] (544s left) gem_exec_reloc (basic-wc-gtt)
[477.384665] Subtest basic-wc-gtt: SKIP (0.000s)
[477.457912] [082/133] (544s left) gem_ppgtt (shrink-vs-evict-pinned)
[477.512662] Starting subtest: shrink-vs-evict-pinned
[482.862662] Subtest shrink-vs-evict-pinned: SUCCESS (5.348s)
[482.943932] [083/133] (538s left) gem_exec_fence (basic-busy)
[483.123733] Starting subtest: basic-busy
[483.126087] Starting dynamic subtest: rcs0
[483.208328] Dynamic subtest rcs0: SUCCESS (0.085s)
[483.220072] Starting dynamic subtest: bcs0
[483.265063] Dynamic subtest bcs0: SUCCESS (0.054s)
[483.277030] Starting dynamic subtest: vcs0
[483.318172] Dynamic subtest vcs0: SUCCESS (0.051s)
[483.329673] Starting dynamic subtest: vcs1
[483.371047] Dynamic subtest vcs1: SUCCESS (0.051s)
[483.381597] Starting dynamic subtest: vecs0
[483.430256] Dynamic subtest vecs0: SUCCESS (0.057s)
[483.441465] Starting dynamic subtest: ccs0
[483.487022] Dynamic subtest ccs0: SUCCESS (0.055s)
[483.489597] Subtest basic-busy: SUCCESS (0.365s)
[484.964552] [084/133] (536s left) kms_invalid_mode (bad-vtotal)
[485.080067] Starting subtest: bad-vtotal
[485.082193] Starting dynamic subtest: pipe-A-eDP-1
[485.378563] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.301s)
[485.388881] Starting dynamic subtest: pipe-B-eDP-1
[485.391355] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[485.400596] Starting dynamic subtest: pipe-C-eDP-1
[485.402876] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[485.412805] Starting dynamic subtest: pipe-D-eDP-1
[485.418778] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[485.421116] Subtest bad-vtotal: SUCCESS (0.307s)
[486.185915] [085/133] (535s left) kms_vblank (query-idle)
[486.276232] Starting subtest: query-idle
[486.278094] Starting dynamic subtest: pipe-A-eDP-1
[488.031306] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.756s)
[488.046600] Starting dynamic subtest: pipe-D-eDP-1
[490.428229] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.393s)
[490.430604] Subtest query-idle: SUCCESS (4.152s)
[491.259032] [086/133] (530s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[491.355463] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[491.357770] Starting dynamic subtest: pipe-A-eDP-1
[491.383331] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[491.398825] Starting dynamic subtest: pipe-B-eDP-1
[491.423552] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[491.434476] Starting dynamic subtest: pipe-C-eDP-1
[491.457601] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[491.467141] Starting dynamic subtest: pipe-D-eDP-1
[491.490916] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[491.495374] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.008s)
[493.621410] [087/133] (528s left) sw_sync (sync_multi_consumer)
[493.657444] Starting subtest: sync_multi_consumer
[494.106944] Subtest sync_multi_consumer: SUCCESS (0.452s)
[494.168154] [088/133] (527s left) gem_exec_fence (long-history)
[494.572169] Starting subtest: long-history
[501.630506] Subtest long-history: SUCCESS (7.060s)
[502.142326] [089/133] (519s left) gem_eio (wait-10ms)
[502.397333] Starting subtest: wait-10ms
[502.812955] Subtest wait-10ms: SUCCESS (0.419s)
[503.000311] [090/133] (518s left) gem_exec_reloc (basic-cpu-read-active)
[503.071511] Subtest basic-cpu-read-active: SKIP (0.000s)
[503.151372] [091/133] (518s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[503.241166] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[503.245370] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[503.348564] [092/133] (518s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[503.402470] Starting subtest: invalid-single-wait-all-unsubmitted
[503.404445] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[503.503283] [093/133] (518s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping)
[503.595635] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping
[503.598997] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[503.685155] [094/133] (518s left) api_intel_bb (crc32)
[503.750129] Starting subtest: crc32
[503.752197] Starting dynamic subtest: rcs0-smem0
[504.029830] Dynamic subtest rcs0-smem0: SUCCESS (0.277s)
[504.040357] Starting dynamic subtest: bcs0-smem0
[504.407452] Dynamic subtest bcs0-smem0: SUCCESS (0.375s)
[504.419373] Starting dynamic subtest: vcs0-smem0
[504.742804] Dynamic subtest vcs0-smem0: SUCCESS (0.333s)
[504.755214] Starting dynamic subtest: vcs1-smem0
[505.110613] Dynamic subtest vcs1-smem0: SUCCESS (0.365s)
[505.122329] Starting dynamic subtest: vecs0-smem0
[505.486182] Dynamic subtest vecs0-smem0: SUCCESS (0.373s)
[505.499092] Starting dynamic subtest: ccs0-smem0
[505.851395] Dynamic subtest ccs0-smem0: SUCCESS (0.362s)
[505.854302] Subtest crc32: SUCCESS (2.101s)
[505.971097] [095/133] (516s left) kms_psr (psr2-dpms)
[506.066563] Starting subtest: psr2-dpms
[506.069061] Starting dynamic subtest: eDP-1
[508.948104] Dynamic subtest eDP-1: SUCCESS (2.883s)
[508.950367] Subtest psr2-dpms: SUCCESS (2.884s)
[509.828076] [096/133] (512s left) kms_cursor_edge_walk (64x64-top-bottom)
[509.935619] Starting subtest: 64x64-top-bottom
[509.937946] Starting dynamic subtest: pipe-A-eDP-1
[513.377460] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.442s)
[513.390161] Starting dynamic subtest: pipe-D-eDP-1
[516.522195] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.140s)
[516.524396] Subtest 64x64-top-bottom: SUCCESS (6.587s)
[517.321230] [097/133] (504s left) syncobj_wait (wait-for-submit-delayed-submit)
[517.380074] Starting subtest: wait-for-submit-delayed-submit
[517.483402] Subtest wait-for-submit-delayed-submit: SUCCESS (0.103s)
[517.577380] [098/133] (504s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[517.631555] Starting subtest: invalid-bsd1-flag-on-blt
[517.633739] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[517.724884] [099/133] (504s left) perf (whitelisted-registers-userspace-config)
[517.824733] Starting subtest: whitelisted-registers-userspace-config
[517.827150] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[517.883991] [100/133] (504s left) api_intel_bb (delta-check)
[517.943057] Starting subtest: delta-check
[517.947598] Subtest delta-check: SUCCESS (0.006s)
[518.066973] [101/133] (504s left) kms_flip (flip-vs-panning-vs-hang)
[518.152089] Starting subtest: flip-vs-panning-vs-hang
[518.154314] Starting dynamic subtest: A-eDP1
[569.408027] Dynamic subtest A-eDP1: SUCCESS (51.257s)
[569.427641] Starting dynamic subtest: D-eDP1
[609.412144] Dynamic subtest D-eDP1: SUCCESS (40.001s)
[609.414431] Subtest flip-vs-panning-vs-hang: SUCCESS (91.264s)
[610.612119] [102/133] (411s left) kms_rotation_crc (primary-rotation-270)
[610.705092] Starting subtest: primary-rotation-270
[610.709207] Subtest primary-rotation-270: SKIP (0.001s)
[610.782983] [103/133] (411s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[610.878424] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[611.547023] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.670s)
[611.743192] [104/133] (410s left) kms_sequence (queue-busy)
[611.836855] Starting subtest: queue-busy
[611.838693] Starting dynamic subtest: pipe-A-eDP-1
[613.767649] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.932s)
[613.778309] Starting dynamic subtest: pipe-B-eDP-1
[616.416308] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.645s)
[616.427110] Starting dynamic subtest: pipe-C-eDP-1
[619.042440] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.625s)
[619.052770] Starting dynamic subtest: pipe-D-eDP-1
[621.660240] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.618s)
[621.662616] Subtest queue-busy: SUCCESS (9.826s)
[622.541744] [105/133] (399s left) i915_drm_fdinfo (virtual-busy-all)
[622.587515] Starting subtest: virtual-busy-all
[622.591885] Subtest virtual-busy-all: SKIP (0.000s)
[622.662203] [106/133] (399s left) gem_exec_whisper (basic-sync-all)
[622.730719] Starting subtest: basic-sync-all
[629.924075] Subtest basic-sync-all: SUCCESS (7.191s)
[630.112883] [107/133] (392s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[630.213555] Starting subtest: pr-cursor-plane-update-sf
[630.217891] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[630.285273] [108/133] (392s left) prime_vgem (sync)
[630.367145] Starting subtest: sync
[630.369529] Starting dynamic subtest: rcs0
[630.430396] Dynamic subtest rcs0: SUCCESS (0.063s)
[630.440060] Starting dynamic subtest: bcs0
[630.498992] Dynamic subtest bcs0: SUCCESS (0.068s)
[630.509064] Starting dynamic subtest: vcs0
[630.549194] Dynamic subtest vcs0: SUCCESS (0.049s)
[630.558943] Starting dynamic subtest: vcs1
[630.595293] Dynamic subtest vcs1: SUCCESS (0.044s)
[630.604615] Starting dynamic subtest: vecs0
[630.641096] Dynamic subtest vecs0: SUCCESS (0.045s)
[630.650734] Starting dynamic subtest: ccs0
[630.688211] Dynamic subtest ccs0: SUCCESS (0.046s)
[630.690478] Subtest sync: SUCCESS (0.321s)
[630.826802] [109/133] (391s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[630.938317] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[630.942269] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[631.036650] [110/133] (391s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[631.133780] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[631.137475] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[631.241313] [111/133] (391s left) gem_exec_schedule (preemptive-hang)
[631.422133] Starting subtest: preemptive-hang
[631.424899] Starting dynamic subtest: rcs0
[641.025496] Dynamic subtest rcs0: SUCCESS (9.601s)
[641.037096] Starting dynamic subtest: bcs0
[650.046792] Dynamic subtest bcs0: SUCCESS (9.022s)
[650.058527] Starting dynamic subtest: vcs0
[659.008870] Dynamic subtest vcs0: SUCCESS (8.960s)
[659.018703] Starting dynamic subtest: vcs1
[668.030585] Dynamic subtest vcs1: SUCCESS (9.019s)
[668.042138] Starting dynamic subtest: vecs0
[677.056078] Dynamic subtest vecs0: SUCCESS (9.022s)
[677.077631] Starting dynamic subtest: ccs0
[686.078073] Dynamic subtest ccs0: SUCCESS (9.018s)
[686.080709] Subtest preemptive-hang: SUCCESS (54.652s)
[686.316977] [112/133] (336s left) gem_exec_suspend (basic-s4-devices)
[686.372298] Starting subtest: basic-S4-devices
[686.374734] Starting dynamic subtest: smem
[697.880087] Dynamic subtest smem: SUCCESS (11.505s)
[697.883758] Subtest basic-S4-devices: SUCCESS (11.508s)
[704.080606] [113/133] (324s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[704.209031] Starting subtest: y-tiled-8bpp-rotate-90
[704.213445] Subtest y-tiled-8bpp-rotate-90: SKIP (0.000s)
[704.309892] [114/133] (324s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[704.360504] Starting subtest: single-wait-all-for-submit-unsubmitted
[704.467311] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.106s)
[704.546618] [115/133] (324s left) perf (gen12-mi-rpc)
[704.642682] Starting subtest: gen12-mi-rpc
[704.647229] Starting dynamic subtest: rcs0
[704.661340] Dynamic subtest rcs0: SUCCESS (0.020s)
[704.663565] Subtest gen12-mi-rpc: SUCCESS (0.021s)
[704.708345] [116/133] (324s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[704.831354] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[708.349671] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (3.524s)
[709.489538] [117/133] (319s left) kms_pm_rpm (dpms-mode-unset-non-lpsp)
[711.137168] Starting subtest: dpms-mode-unset-non-lpsp
[712.294754] Subtest dpms-mode-unset-non-lpsp: SKIP (1.160s)
[712.681424] [118/133] (316s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[712.780196] Starting subtest: addfb25-yf-tiled-legacy
[712.782081] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[712.892219] [119/133] (316s left) kms_big_fb (x-tiled-addfb-size-overflow)
[712.980152] Starting subtest: x-tiled-addfb-size-overflow
[712.982025] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[713.077553] [120/133] (316s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[713.165275] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[713.169485] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[713.265327] [121/133] (315s left) kms_big_fb (linear-32bpp-rotate-90)
[713.363271] Starting subtest: linear-32bpp-rotate-90
[713.383747] Subtest linear-32bpp-rotate-90: SKIP (0.020s)
[713.489559] [122/133] (315s left) syncobj_wait (single-wait-all-submitted)
[713.541069] Starting subtest: single-wait-all-submitted
[713.643070] Subtest single-wait-all-submitted: SUCCESS (0.101s)
[713.738132] [123/133] (315s left) gem_exec_balancer (full-late)
[713.814661] Starting subtest: full-late
[718.768107] Subtest full-late: SUCCESS (4.952s)
[719.082720] [124/133] (310s left) gem_mmap_gtt (ptrace)
[719.150143] Subtest ptrace: SKIP (0.000s)
[719.215661] [125/133] (310s left) i915_pm_rps (waitboost)
[719.276819] Starting subtest: waitboost
[725.617224] Subtest waitboost: SUCCESS (6.338s)
[725.693960] [126/133] (303s left) prime_vgem (basic-gtt)
[725.771549] Starting subtest: basic-gtt
[725.775879] Subtest basic-gtt: SKIP (0.000s)
[725.874407] [127/133] (303s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[725.981086] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[725.985020] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[726.069400] [128/133] (303s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[726.157363] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[732.756289] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.601s)
[732.892502] [129/133] (296s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[732.995877] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[739.983380] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.989s)
[740.128480] [130/133] (289s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[740.238022] Starting subtest: fbcpsr-slowdraw
[765.489306] Subtest fbcpsr-slowdraw: SUCCESS (25.252s)
[765.616413] [131/133] (264s left) kms_lease (lease-get)
[765.712166] Starting subtest: lease-get
[765.714468] Starting dynamic subtest: pipe-A-eDP-1
[765.716688] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[765.731521] Starting dynamic subtest: pipe-B-eDP-1
[765.733705] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[765.744602] Starting dynamic subtest: pipe-C-eDP-1
[765.746775] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[765.760079] Starting dynamic subtest: pipe-D-eDP-1
[765.762202] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[765.764309] Subtest lease-get: SUCCESS (0.009s)
[765.831449] [132/133] (263s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[765.933327] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[765.937674] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.000s)
[766.008198] [133/133] (263s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[766.108278] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[766.112154] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s)
[766.179634] Closing watchdogs
results: parsing output: 0/ for test: kms_scaling_modes
results: parsing output: 1/ for test: gem_exec_gttfill
results: parsing output: 2/ for test: kms_atomic
results: parsing output: 3/ for test: gem_partial_pwrite_pread
results: parsing output: 4/ for test: kms_cursor_crc
results: parsing output: 5/ for test: kms_plane_scaling
results: parsing output: 6/ for test: kms_cursor_crc
results: parsing output: 7/ for test: syncobj_wait
results: parsing output: 8/ for test: gen9_exec_parse
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: gem_pwrite_snooped
results: parsing output: 11/ for test: kms_color
results: parsing output: 12/ for test: kms_chamelium_hpd
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_exec_schedule
results: parsing output: 16/ for test: gem_render_copy
results: parsing output: 17/ for test: gem_mmap_gtt
results: parsing output: 18/ for test: syncobj_timeline
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_flip
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: i915_pm_rps
results: parsing output: 23/ for test: kms_ccs
results: parsing output: 24/ for test: gem_exec_flush
results: parsing output: 25/ for test: sysfs_heartbeat_interval
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: gem_mmap_gtt
results: parsing output: 28/ for test: perf
results: parsing output: 29/ for test: gem_ccs
results: parsing output: 30/ for test: kms_chamelium_color
results: parsing output: 31/ for test: gem_mmap_gtt
results: parsing output: 32/ for test: kms_psr2_sf
results: parsing output: 33/ for test: kms_draw_crc
results: parsing output: 34/ for test: kms_psr
results: parsing output: 35/ for test: gem_set_tiling_vs_blt
results: parsing output: 36/ for test: gem_ctx_param
results: parsing output: 37/ for test: i915_drm_fdinfo
results: parsing output: 38/ for test: syncobj_timeline
results: parsing output: 39/ for test: kms_flip
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: perf_pmu
results: parsing output: 42/ for test: gem_eio
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_busy
results: parsing output: 46/ for test: gem_spin_batch
results: parsing output: 47/ for test: gem_vm_create
results: parsing output: 48/ for test: vgem_basic
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_content_protection
results: parsing output: 52/ for test: kms_psr
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: dumb_buffer
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: gem_reg_read
results: parsing output: 59/ for test: gem_ctx_persistence
results: parsing output: 60/ for test: gem_ctx_shared
results: parsing output: 61/ for test: kms_big_fb
results: parsing output: 62/ for test: kms_properties
results: parsing output: 63/ for test: kms_dp_link_training
results: parsing output: 64/ for test: kms_psr2_sf
results: parsing output: 65/ for test: kms_plane_scaling
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_ccs
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: kms_prime
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: syncobj_timeline
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: gem_exec_flush
results: parsing output: 74/ for test: gem_userptr_blits
results: parsing output: 75/ for test: gem_pxp
results: parsing output: 76/ for test: prime_self_import
results: parsing output: 77/ for test: kms_plane_multiple
results: parsing output: 78/ for test: kms_pipe_crc_basic
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: gem_exec_reloc
results: parsing output: 81/ for test: gem_ppgtt
results: parsing output: 82/ for test: gem_exec_fence
results: parsing output: 83/ for test: kms_invalid_mode
results: parsing output: 84/ for test: kms_vblank
results: parsing output: 85/ for test: kms_ccs
results: parsing output: 86/ for test: sw_sync
results: parsing output: 87/ for test: gem_exec_fence
results: parsing output: 88/ for test: gem_eio
results: parsing output: 89/ for test: gem_exec_reloc
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: kms_plane
results: parsing output: 93/ for test: api_intel_bb
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: kms_cursor_edge_walk
results: parsing output: 96/ for test: syncobj_wait
results: parsing output: 97/ for test: gem_exec_params
results: parsing output: 98/ for test: perf
results: parsing output: 99/ for test: api_intel_bb
results: parsing output: 100/ for test: kms_flip
results: parsing output: 101/ for test: kms_rotation_crc
results: parsing output: 102/ for test: kms_big_fb
results: parsing output: 103/ for test: kms_sequence
results: parsing output: 104/ for test: i915_drm_fdinfo
results: parsing output: 105/ for test: gem_exec_whisper
results: parsing output: 106/ for test: kms_psr2_sf
results: parsing output: 107/ for test: prime_vgem
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_big_fb
results: parsing output: 110/ for test: gem_exec_schedule
results: parsing output: 111/ for test: gem_exec_suspend
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: syncobj_wait
results: parsing output: 114/ for test: perf
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_pm_rpm
results: parsing output: 117/ for test: kms_addfb_basic
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_big_fb
results: parsing output: 121/ for test: syncobj_wait
results: parsing output: 122/ for test: gem_exec_balancer
results: parsing output: 123/ for test: gem_mmap_gtt
results: parsing output: 124/ for test: i915_pm_rps
results: parsing output: 125/ for test: prime_vgem
results: parsing output: 126/ for test: kms_frontbuffer_tracking
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_frontbuffer_tracking
results: parsing output: 130/ for test: kms_lease
results: parsing output: 131/ for test: kms_flip_scaled_crc
results: parsing output: 132/ for test: kms_flip_scaled_crc
Done.