[57.503598] Initializing watchdogs
[57.503647]   /dev/watchdog0
[57.518529] [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.522892] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.525271] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.528680] [001/133] (960s left) kms_scaling_modes (scaling-mode-center)
[57.650478] Starting subtest: scaling-mode-center
[57.652732] Starting dynamic subtest: pipe-A-eDP-1
[57.772861] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.123s)
[57.782603] Starting dynamic subtest: pipe-B-eDP-1
[59.173738] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.400s)
[59.184460] Starting dynamic subtest: pipe-C-eDP-1
[60.383989] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.208s)
[60.392690] Starting dynamic subtest: pipe-D-eDP-1
[61.510022] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.124s)
[61.512343] Subtest scaling-mode-center: SUCCESS (3.862s)
[62.682339] [002/133] (955s left) gem_exec_gttfill (engines)
[62.762135] Starting subtest: engines
[62.764506] Starting dynamic subtest: rcs0
[85.605715] Dynamic subtest rcs0: SUCCESS (22.844s)
[85.615084] Starting dynamic subtest: bcs0
[108.884849] Dynamic subtest bcs0: SUCCESS (23.283s)
[108.896511] Starting dynamic subtest: vcs0
[132.216335] Dynamic subtest vcs0: SUCCESS (23.329s)
[132.232235] Starting dynamic subtest: vcs1
[155.479565] Dynamic subtest vcs1: SUCCESS (23.261s)
[155.490182] Starting dynamic subtest: vecs0
[178.798365] Dynamic subtest vecs0: SUCCESS (23.315s)
[178.809226] Starting dynamic subtest: ccs0
[202.118799] Dynamic subtest ccs0: SUCCESS (23.319s)
[202.121833] Subtest engines: SUCCESS (139.359s)
[202.309937] [003/133] (815s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[202.448074] Starting subtest: plane-primary-overlay-mutable-zpos
[205.893663] Subtest plane-primary-overlay-mutable-zpos: SKIP (3.443s)
[207.070584] [004/133] (811s left) gem_partial_pwrite_pread (write-snoop)
[207.141629] Subtest write-snoop: SKIP (0.000s)
[207.206137] [005/133] (810s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[207.497019] Starting subtest: cursor-rapid-movement-512x170
[207.501509] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[207.637565] [006/133] (810s left) kms_plane_scaling (planes-upscale-20x20)
[207.735018] Starting subtest: planes-upscale-20x20
[207.737464] Starting dynamic subtest: pipe-A
[207.795235] Dynamic subtest pipe-A: SUCCESS (0.062s)
[207.805076] Starting dynamic subtest: pipe-B
[208.906560] Dynamic subtest pipe-B: SUCCESS (1.111s)
[208.917267] Starting dynamic subtest: pipe-C
[210.029308] Dynamic subtest pipe-C: SUCCESS (1.122s)
[210.039095] Starting dynamic subtest: pipe-D
[211.163551] Dynamic subtest pipe-D: SUCCESS (1.134s)
[211.165926] Subtest planes-upscale-20x20: SUCCESS (3.431s)
[212.312092] [007/133] (805s left) kms_cursor_crc (cursor-offscreen-32x32)
[212.465524] Starting subtest: cursor-offscreen-32x32
[212.516596] Subtest cursor-offscreen-32x32: SKIP (0.045s)
[212.805846] [008/133] (805s left) syncobj_wait (invalid-reset-illegal-handle)
[212.854475] Starting subtest: invalid-reset-illegal-handle
[212.856620] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[212.925231] [009/133] (805s left) gen9_exec_parse (basic-rejected)
[212.992270] Subtest basic-rejected: SKIP (0.000s)
[213.094500] [010/133] (805s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[213.165676] Starting subtest: single-wait-all-for-submit-available-submitted
[213.168162] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[213.272417] [011/133] (805s left) gem_pwrite_snooped
[213.399146] [012/133] (805s left) kms_color (ctm-0-25)
[213.489920] Starting subtest: ctm-0-25
[213.491682] Starting dynamic subtest: pipe-A-eDP-1
[216.950216] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.461s)
[216.959093] Starting dynamic subtest: pipe-B-eDP-1
[221.228616] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.277s)
[221.238554] Starting dynamic subtest: pipe-C-eDP-1
[225.196657] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.968s)
[225.205959] Starting dynamic subtest: pipe-D-eDP-1
[229.195639] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.998s)
[229.197793] Subtest ctm-0-25: SUCCESS (15.705s)
[230.029066] [013/133] (788s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[230.437991] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[231.236069] [014/133] (787s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[231.341215] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[231.343638] Starting dynamic subtest: pipe-A-eDP-1
[231.370743] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[231.380778] Starting dynamic subtest: pipe-B-eDP-1
[231.405373] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[231.413297] Starting dynamic subtest: pipe-C-eDP-1
[231.437334] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[231.445518] Starting dynamic subtest: pipe-D-eDP-1
[231.470044] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[231.474155] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[233.612608] [015/133] (784s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[233.727070] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[238.204733] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.483s)
[239.350746] [016/133] (779s left) gem_exec_schedule (promotion)
[239.414825] Starting subtest: promotion
[239.417088] Starting dynamic subtest: rcs0
[239.524514] Dynamic subtest rcs0: SUCCESS (0.114s)
[239.536207] Starting dynamic subtest: vcs0
[239.621575] Dynamic subtest vcs0: SUCCESS (0.097s)
[239.633673] Starting dynamic subtest: vcs1
[239.704764] Dynamic subtest vcs1: SUCCESS (0.082s)
[239.717281] Starting dynamic subtest: vecs0
[239.782774] Dynamic subtest vecs0: SUCCESS (0.078s)
[239.794590] Starting dynamic subtest: ccs0
[239.877531] Dynamic subtest ccs0: SUCCESS (0.094s)
[239.880791] Subtest promotion: SUCCESS (0.467s)
[241.024137] [017/133] (777s left) gem_render_copy (yf-tiled-to-vebox-y-tiled)
[241.102446] Starting subtest: yf-tiled-to-vebox-y-tiled
[241.107045] Subtest yf-tiled-to-vebox-y-tiled: SKIP (0.000s)
[241.296668] [018/133] (777s left) gem_mmap_gtt (big-copy-xy)
[241.358052] Subtest big-copy-XY: SKIP (0.000s)
[241.421081] [019/133] (777s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted)
[241.467925] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted
[241.571375] Subtest etime-multi-wait-all-for-submit-unsubmitted: SUCCESS (0.102s)
[241.646920] [020/133] (777s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[241.738159] Starting subtest: psr-rgb565-draw-pwrite
[248.718549] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.984s)
[248.838846] [021/133] (770s left) kms_flip (plain-flip-ts-check-interruptible)
[248.946398] Starting subtest: plain-flip-ts-check-interruptible
[248.948739] Starting dynamic subtest: A-eDP1
[257.754862] Dynamic subtest A-eDP1: SUCCESS (8.807s)
[257.765976] Starting dynamic subtest: B-eDP1
[266.593252] Dynamic subtest B-eDP1: SUCCESS (8.837s)
[266.604027] Starting dynamic subtest: C-eDP1
[275.425115] Dynamic subtest C-eDP1: SUCCESS (8.829s)
[275.434975] Starting dynamic subtest: D-eDP1
[284.271227] Dynamic subtest D-eDP1: SUCCESS (8.843s)
[284.276806] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.326s)
[285.457959] [022/133] (733s left) kms_flip (flip-vs-rmfb-interruptible)
[285.551909] Starting subtest: flip-vs-rmfb-interruptible
[285.554267] Starting dynamic subtest: A-eDP1
[294.249216] Dynamic subtest A-eDP1: SUCCESS (8.695s)
[294.261919] Starting dynamic subtest: B-eDP1
[302.881138] Dynamic subtest B-eDP1: SUCCESS (8.628s)
[302.894991] Starting dynamic subtest: C-eDP1
[311.501283] Dynamic subtest C-eDP1: SUCCESS (8.617s)
[311.512270] Starting dynamic subtest: D-eDP1
[320.124433] Dynamic subtest D-eDP1: SUCCESS (8.619s)
[320.129039] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.571s)
[321.309922] [023/133] (697s left) i915_pm_rps (reset)
[321.376829] Starting subtest: reset
[329.846076] Subtest reset: SUCCESS (8.468s)
[329.977958] [024/133] (688s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[330.085072] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[330.087396] Starting dynamic subtest: pipe-A-eDP-1
[330.107799] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[330.118678] Starting dynamic subtest: pipe-B-eDP-1
[330.138394] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[330.149297] Starting dynamic subtest: pipe-C-eDP-1
[330.168567] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[330.177074] Starting dynamic subtest: pipe-D-eDP-1
[330.196325] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[330.198510] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[332.365378] [025/133] (686s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[332.435333] Starting subtest: basic-batch-kernel-default-cmd
[332.439636] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[332.621280] [026/133] (686s left) sysfs_heartbeat_interval (idempotent)
[332.679915] Starting subtest: idempotent
[332.682491] Starting dynamic subtest: vecs0
[332.685126] Dynamic subtest vecs0: SUCCESS (0.002s)
[332.696767] Starting dynamic subtest: ccs0
[332.699099] Dynamic subtest ccs0: SUCCESS (0.002s)
[332.707880] Starting dynamic subtest: vcs0
[332.709900] Dynamic subtest vcs0: SUCCESS (0.000s)
[332.721469] Starting dynamic subtest: bcs0
[332.723755] Dynamic subtest bcs0: SUCCESS (0.001s)
[332.732151] Starting dynamic subtest: rcs0
[332.734257] Dynamic subtest rcs0: SUCCESS (0.001s)
[332.743331] Starting dynamic subtest: vcs1
[332.745818] Dynamic subtest vcs1: SUCCESS (0.000s)
[332.748142] Subtest idempotent: SUCCESS (0.012s)
[332.786260] [027/133] (686s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[332.875178] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[339.785402] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.911s)
[339.921495] [028/133] (679s left) gem_mmap_gtt (close-race)
[339.983297] Subtest close-race: SKIP (0.000s)
[340.039903] [029/133] (679s left) perf (invalid-remove-userspace-config)
[340.174907] Starting subtest: invalid-remove-userspace-config
[340.183340] Subtest invalid-remove-userspace-config: SUCCESS (0.009s)
[340.226461] [030/133] (678s left) gem_ccs (suspend-resume)
[340.280854] Starting subtest: suspend-resume
[340.285068] Subtest suspend-resume: SKIP (0.000s)
[340.381451] [031/133] (678s left) kms_chamelium_color (ctm-red-to-blue)
[340.473477] Subtest ctm-red-to-blue: SKIP (0.000s)
[340.519948] [032/133] (678s left) gem_mmap_gtt (big-bo-tiledy)
[340.574723] Subtest big-bo-tiledY: SKIP (0.000s)
[340.629099] [033/133] (678s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[340.725358] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[340.727638] Starting dynamic subtest: pipe-A-eDP-1
[346.565711] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.845s)
[346.575851] Starting dynamic subtest: pipe-B-eDP-1
[353.252722] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.681s)
[353.254974] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (12.530s)
[354.119077] [034/133] (665s left) kms_draw_crc (draw-method-blt)
[354.218754] Starting subtest: draw-method-blt
[354.220640] Starting dynamic subtest: xrgb8888-untiled
[354.461667] Dynamic subtest xrgb8888-untiled: SUCCESS (0.244s)
[354.471275] Starting dynamic subtest: rgb565-untiled
[354.683840] Dynamic subtest rgb565-untiled: SUCCESS (0.221s)
[354.692625] Starting dynamic subtest: xrgb2101010-untiled
[354.906430] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s)
[354.915511] Starting dynamic subtest: xbgr16161616f-untiled
[355.139078] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.231s)
[355.147533] Starting dynamic subtest: xrgb8888-xtiled
[355.261239] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[355.270627] Starting dynamic subtest: rgb565-xtiled
[355.383495] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[355.391933] Starting dynamic subtest: xrgb2101010-xtiled
[355.495096] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[355.506426] Starting dynamic subtest: xbgr16161616f-xtiled
[355.605720] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.109s)
[355.614127] Starting dynamic subtest: xrgb8888-4tiled
[355.727908] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[355.736117] Starting dynamic subtest: rgb565-4tiled
[355.850155] Dynamic subtest rgb565-4tiled: SUCCESS (0.121s)
[355.858545] Starting dynamic subtest: xrgb2101010-4tiled
[355.961778] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[355.972736] Starting dynamic subtest: xbgr16161616f-4tiled
[356.072495] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.110s)
[356.074406] Subtest draw-method-blt: SUCCESS (1.856s)
[356.177685] [035/133] (663s left) kms_psr (fbc-psr-no-drrs)
[356.268570] Starting subtest: fbc-psr-no-drrs
[356.270483] Starting dynamic subtest: eDP-1
[356.273827] Dynamic subtest eDP-1: SKIP (0.000s)
[356.277197] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[356.348817] [036/133] (663s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[356.411100] Subtest untiled-to-tiled: SKIP (0.000s)
[356.480719] [037/133] (663s left) gem_ctx_param (invalid-get-no-zeromap)
[356.540494] Starting subtest: invalid-get-no-zeromap
[356.542662] Subtest invalid-get-no-zeromap: SUCCESS (0.001s)
[356.637123] [038/133] (662s left) i915_drm_fdinfo (virtual-busy-hang-all)
[356.679501] Starting subtest: virtual-busy-hang-all
[356.682903] Subtest virtual-busy-hang-all: SKIP (0.000s)
[356.751609] [039/133] (662s left) syncobj_timeline (invalid-signal-zero-handles)
[356.812135] Starting subtest: invalid-signal-zero-handles
[356.814267] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[356.934442] [040/133] (662s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[357.025365] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[357.027331] Starting dynamic subtest: A-eDP1
[360.661666] Dynamic subtest A-eDP1: SUCCESS (3.633s)
[360.672362] Starting dynamic subtest: B-eDP1
[363.865344] Dynamic subtest B-eDP1: SUCCESS (3.200s)
[363.874526] Starting dynamic subtest: C-eDP1
[366.967057] Dynamic subtest C-eDP1: SUCCESS (3.103s)
[366.976622] Starting dynamic subtest: D-eDP1
[370.076907] Dynamic subtest D-eDP1: SUCCESS (3.108s)
[370.079390] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.053s)
[370.884354] [041/133] (648s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[370.991665] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[370.996123] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[371.102569] [042/133] (648s left) perf_pmu (most-busy-idle-check-all)
[371.141698] Starting subtest: most-busy-idle-check-all
[371.143916] Starting dynamic subtest: rcs0
[372.887047] Dynamic subtest rcs0: SUCCESS (1.744s)
[372.899620] Starting dynamic subtest: bcs0
[374.934833] Dynamic subtest bcs0: SUCCESS (2.045s)
[374.947421] Starting dynamic subtest: vcs0
[376.919826] Dynamic subtest vcs0: SUCCESS (1.982s)
[376.934115] Starting dynamic subtest: vcs1
[378.902891] Dynamic subtest vcs1: SUCCESS (1.980s)
[378.914823] Starting dynamic subtest: vecs0
[380.887878] Dynamic subtest vecs0: SUCCESS (1.981s)
[380.900150] Starting dynamic subtest: ccs0
[382.935780] Dynamic subtest ccs0: SUCCESS (2.045s)
[382.938976] Subtest most-busy-idle-check-all: SUCCESS (11.794s)
[383.026988] [043/133] (636s left) gem_eio (in-flight-contexts-10ms)
[383.228958] Starting subtest: in-flight-contexts-10ms
[396.897243] Subtest in-flight-contexts-10ms: SUCCESS (13.668s)
[397.069479] [044/133] (622s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[397.163218] Starting subtest: psr-rgb565-draw-mmap-cpu
[404.067779] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.907s)
[404.202607] [045/133] (615s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[404.306913] Starting subtest: fbc-farfromfence-mmap-gtt
[404.311064] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[404.414358] [046/133] (615s left) gem_busy (busy)
[404.487108] Starting subtest: busy
[404.489474] Starting dynamic subtest: all-engines
[404.553333] Dynamic subtest all-engines: SUCCESS (0.066s)
[404.562857] Starting dynamic subtest: rcs0
[405.872671] Dynamic subtest rcs0: SUCCESS (1.298s)
[405.883632] Starting dynamic subtest: bcs0
[405.902983] Dynamic subtest bcs0: SUCCESS (0.048s)
[405.912172] Starting dynamic subtest: vcs0
[405.942097] Dynamic subtest vcs0: SUCCESS (0.039s)
[405.951698] Starting dynamic subtest: vcs1
[407.900410] Dynamic subtest vcs1: SUCCESS (1.954s)
[407.911456] Starting dynamic subtest: vecs0
[409.948246] Dynamic subtest vecs0: SUCCESS (2.047s)
[409.959295] Starting dynamic subtest: ccs0
[411.932668] Dynamic subtest ccs0: SUCCESS (1.982s)
[411.934856] Subtest busy: SUCCESS (7.442s)
[412.274059] [047/133] (607s left) gem_spin_batch (legacy-resubmit)
[412.336934] Starting subtest: legacy-resubmit
[412.339398] Starting dynamic subtest: default
[412.346328] Dynamic subtest default: SUCCESS (0.009s)
[412.357728] Starting dynamic subtest: render
[412.360063] Dynamic subtest render: SUCCESS (0.002s)
[412.369501] Starting dynamic subtest: bsd
[412.371418] Dynamic subtest bsd: SUCCESS (0.003s)
[412.380182] Starting dynamic subtest: bsd1
[412.382088] Dynamic subtest bsd1: SUCCESS (0.003s)
[412.390645] Starting dynamic subtest: bsd2
[412.392586] Dynamic subtest bsd2: SUCCESS (0.001s)
[412.401637] Starting dynamic subtest: blt
[412.403629] Dynamic subtest blt: SUCCESS (0.003s)
[412.412290] Starting dynamic subtest: vebox
[412.414148] Dynamic subtest vebox: SUCCESS (0.004s)
[412.416075] Subtest legacy-resubmit: SUCCESS (0.031s)
[412.599587] [048/133] (607s left) gem_vm_create (invalid-destroy)
[412.661583] Starting subtest: invalid-destroy
[412.666691] Subtest invalid-destroy: SUCCESS (0.002s)
[412.772207] [049/133] (607s left) vgem_basic (setversion)
[412.889495] Starting subtest: setversion
[412.893823] Subtest setversion: SUCCESS (0.000s)
[412.946245] [FACT vgem_basic (setversion)] new: kernel.kmod_is_loaded.vgem: true
[412.949990] [050/133] (607s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[413.046906] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[413.049181] Starting dynamic subtest: pipe-A-eDP-1
[413.058219] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[413.068851] Starting dynamic subtest: pipe-B-eDP-1
[413.077968] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[413.087755] Starting dynamic subtest: pipe-C-eDP-1
[413.096357] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[413.105137] Starting dynamic subtest: pipe-D-eDP-1
[413.113538] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[413.117566] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[415.282223] [051/133] (604s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[415.400328] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[415.404194] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[415.502842] [052/133] (604s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[415.647390] Starting subtest: dp-mst-lic-type-0-hdcp14
[415.651864] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[415.761770] [053/133] (604s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[415.863512] Starting subtest: fbc-psr2-cursor-mmap-cpu
[415.865743] Starting dynamic subtest: eDP-1
[415.870195] Dynamic subtest eDP-1: SKIP (0.000s)
[415.874656] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.001s)
[415.938253] [054/133] (604s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[416.047266] Starting subtest: yf-tiled-addfb-size-offset-overflow
[416.051623] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[416.144477] [055/133] (604s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[416.252498] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[416.256076] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[416.365994] [056/133] (603s left) dumb_buffer (invalid-bpp)
[416.416124] Starting subtest: invalid-bpp
[416.417992] Subtest invalid-bpp: SUCCESS (0.000s)
[416.505045] [057/133] (603s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[416.607507] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[416.610900] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[416.702598] [058/133] (603s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[416.763771] Starting subtest: etime-single-wait-all-available-unsubmitted
[416.866538] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.102s)
[416.959861] [059/133] (603s left) gem_reg_read (bad-register)
[417.022862] Starting subtest: bad-register
[417.025514] Subtest bad-register: SUCCESS (0.000s)
[417.118948] [060/133] (603s left) gem_ctx_persistence (legacy-engines-cleanup)
[417.183592] Starting subtest: legacy-engines-cleanup
[417.185818] Starting dynamic subtest: render
[417.245872] Dynamic subtest render: SUCCESS (0.061s)
[417.258246] Starting dynamic subtest: bsd1
[417.290770] Dynamic subtest bsd1: SUCCESS (0.042s)
[417.303965] Starting dynamic subtest: bsd2
[417.334478] Dynamic subtest bsd2: SUCCESS (0.041s)
[417.344987] Starting dynamic subtest: blt
[417.380638] Dynamic subtest blt: SUCCESS (0.044s)
[417.391922] Starting dynamic subtest: vebox
[417.422483] Dynamic subtest vebox: SUCCESS (0.040s)
[417.424870] Subtest legacy-engines-cleanup: SUCCESS (0.240s)
[417.504418] [061/133] (602s left) gem_ctx_shared (q-in-order)
[417.571545] Starting subtest: Q-in-order
[417.574286] Starting dynamic subtest: rcs0
[417.674275] Dynamic subtest rcs0: SUCCESS (0.103s)
[417.691949] Starting dynamic subtest: vcs0
[417.759065] Dynamic subtest vcs0: SUCCESS (0.083s)
[417.776206] Starting dynamic subtest: vcs1
[417.834378] Dynamic subtest vcs1: SUCCESS (0.075s)
[417.850887] Starting dynamic subtest: vecs0
[417.923543] Dynamic subtest vecs0: SUCCESS (0.089s)
[417.940659] Starting dynamic subtest: ccs0
[418.021077] Dynamic subtest ccs0: SUCCESS (0.096s)
[418.023368] Subtest Q-in-order: SUCCESS (0.451s)
[418.985587] [062/133] (601s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180-hflip)
[419.087179] Starting subtest: linear-max-hw-stride-32bpp-rotate-180-hflip
[419.091518] Subtest linear-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[419.181234] [063/133] (601s left) kms_properties (connector-properties-legacy)
[419.268183] Starting subtest: connector-properties-legacy
[419.270662] Starting dynamic subtest: pipe-A-eDP-1
[427.189978] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.923s)
[427.200195] Starting dynamic subtest: pipe-None-DP-1
[427.204553] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[427.213541] Starting dynamic subtest: pipe-None-HDMI-A-1
[427.217712] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[427.227052] Starting dynamic subtest: pipe-None-DP-2
[427.231035] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[427.239921] Starting dynamic subtest: pipe-None-HDMI-A-2
[427.243815] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[427.252843] Starting dynamic subtest: pipe-None-DP-3
[427.257078] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[427.266274] Starting dynamic subtest: pipe-None-DP-4
[427.270610] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[427.272740] Subtest connector-properties-legacy: SUCCESS (7.935s)
[428.066119] [064/133] (592s left) kms_dp_link_training (non-uhbr-sst)
[428.158289] Starting subtest: non-uhbr-sst
[428.166854] Subtest non-uhbr-sst: SKIP (0.000s)
[428.232837] [065/133] (592s left) kms_psr2_sf (fbc-pr-plane-move-sf-dmg-area)
[428.332382] Starting subtest: fbc-pr-plane-move-sf-dmg-area
[428.336628] Subtest fbc-pr-plane-move-sf-dmg-area: SKIP (0.000s)
[428.402912] [066/133] (592s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[428.504160] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[428.506328] Starting dynamic subtest: pipe-A
[428.840821] Dynamic subtest pipe-A: SUCCESS (0.339s)
[428.850695] Starting dynamic subtest: pipe-B
[430.299358] Dynamic subtest pipe-B: SUCCESS (1.458s)
[430.308679] Starting dynamic subtest: pipe-C
[431.714527] Dynamic subtest pipe-C: SUCCESS (1.414s)
[431.724500] Starting dynamic subtest: pipe-D
[433.147531] Dynamic subtest pipe-D: SUCCESS (1.431s)
[433.149906] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.646s)
[434.297440] [067/133] (586s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[434.410949] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[434.415348] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[434.503523] [068/133] (586s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[434.590367] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[434.595036] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[435.734864] [069/133] (585s left) gem_exec_schedule (u-submit-golden-slice)
[435.820996] Starting subtest: u-submit-golden-slice
[435.823069] Starting dynamic subtest: rcs0
[435.848842] Dynamic subtest rcs0: SUCCESS (0.036s)
[435.861244] Starting dynamic subtest: bcs0
[435.884103] Dynamic subtest bcs0: SUCCESS (0.035s)
[435.897846] Starting dynamic subtest: vcs0
[435.921047] Dynamic subtest vcs0: SUCCESS (0.036s)
[435.931038] Starting dynamic subtest: vcs1
[435.953065] Dynamic subtest vcs1: SUCCESS (0.031s)
[435.963060] Starting dynamic subtest: vecs0
[435.990573] Dynamic subtest vecs0: SUCCESS (0.037s)
[436.000749] Starting dynamic subtest: ccs0
[436.027994] Dynamic subtest ccs0: SUCCESS (0.036s)
[436.030578] Subtest u-submit-golden-slice: SUCCESS (0.216s)
[437.012900] [070/133] (583s left) kms_prime (basic-crc-hybrid)
[437.104405] Subtest basic-crc-hybrid: SKIP (0.000s)
[437.214052] [071/133] (583s left) kms_psr (pr-primary-mmap-gtt)
[437.318963] Starting subtest: pr-primary-mmap-gtt
[437.322912] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[437.392687] [072/133] (583s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[437.455655] Starting subtest: multi-wait-available-unsubmitted-signaled
[437.457906] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[437.550886] [073/133] (583s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[437.643652] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[437.647633] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[437.746472] [074/133] (583s left) gem_exec_flush (basic-wb-ro-before-default)
[437.810661] Starting subtest: basic-wb-ro-before-default
[443.272560] Subtest basic-wb-ro-before-default: SUCCESS (5.462s)
[443.473861] [075/133] (577s left) gem_userptr_blits (dmabuf-unsync)
[443.539435] Subtest dmabuf-unsync: SKIP (0.000s)
[443.676090] [076/133] (577s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[443.792596] Starting subtest: verify-pxp-stale-buf-optout-execution
[444.857717] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.066s)
[444.993942] [077/133] (576s left) prime_self_import (basic-llseek-bad)
[445.025655] Starting subtest: basic-llseek-bad
[445.039014] Subtest basic-llseek-bad: SUCCESS (0.012s)
[445.146672] [078/133] (576s left) kms_plane_multiple (tiling-4)
[445.240622] Starting subtest: tiling-4
[445.242721] Starting dynamic subtest: pipe-A-eDP-1
[449.000534] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.758s)
[449.010157] Starting dynamic subtest: pipe-B-eDP-1
[453.520162] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.520s)
[453.530732] Starting dynamic subtest: pipe-C-eDP-1
[457.862077] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.340s)
[457.874944] Starting dynamic subtest: pipe-D-eDP-1
[462.188450] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.323s)
[462.190750] Subtest tiling-4: SUCCESS (16.947s)
[463.008533] [079/133] (558s left) kms_pipe_crc_basic (read-crc)
[463.116032] Starting subtest: read-crc
[463.118198] Starting dynamic subtest: pipe-A-eDP-1
[463.834059] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.718s)
[463.844430] Starting dynamic subtest: pipe-B-eDP-1
[465.181837] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.346s)
[465.194968] Starting dynamic subtest: pipe-C-eDP-1
[466.518372] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.337s)
[466.528446] Starting dynamic subtest: pipe-D-eDP-1
[467.818731] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.296s)
[467.820861] Subtest read-crc: SUCCESS (4.702s)
[468.675367] [080/133] (552s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[468.778397] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[476.093340] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.319s)
[476.241633] [081/133] (545s left) gem_exec_reloc (basic-wc-gtt)
[476.305343] Subtest basic-wc-gtt: SKIP (0.000s)
[476.378144] [082/133] (545s left) gem_ppgtt (shrink-vs-evict-pinned)
[476.432064] Starting subtest: shrink-vs-evict-pinned
[481.902686] Subtest shrink-vs-evict-pinned: SUCCESS (5.469s)
[482.001393] [083/133] (539s left) gem_exec_fence (basic-busy)
[482.175048] Starting subtest: basic-busy
[482.177500] Starting dynamic subtest: rcs0
[482.254531] Dynamic subtest rcs0: SUCCESS (0.079s)
[482.265097] Starting dynamic subtest: bcs0
[482.307685] Dynamic subtest bcs0: SUCCESS (0.051s)
[482.321862] Starting dynamic subtest: vcs0
[482.365876] Dynamic subtest vcs0: SUCCESS (0.055s)
[482.377885] Starting dynamic subtest: vcs1
[482.431339] Dynamic subtest vcs1: SUCCESS (0.063s)
[482.443334] Starting dynamic subtest: vecs0
[482.491259] Dynamic subtest vecs0: SUCCESS (0.057s)
[482.505154] Starting dynamic subtest: ccs0
[482.552382] Dynamic subtest ccs0: SUCCESS (0.058s)
[482.555098] Subtest basic-busy: SUCCESS (0.379s)
[483.931070] [084/133] (537s left) kms_invalid_mode (bad-vtotal)
[484.027484] Starting subtest: bad-vtotal
[484.029937] Starting dynamic subtest: pipe-A-eDP-1
[484.314957] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[484.324765] Starting dynamic subtest: pipe-B-eDP-1
[484.326944] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[484.336084] Starting dynamic subtest: pipe-C-eDP-1
[484.338200] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[484.347449] Starting dynamic subtest: pipe-D-eDP-1
[484.349791] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[484.352143] Subtest bad-vtotal: SUCCESS (0.295s)
[485.123533] [085/133] (536s left) kms_vblank (query-idle)
[485.219443] Starting subtest: query-idle
[485.221616] Starting dynamic subtest: pipe-A-eDP-1
[486.962769] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.745s)
[486.977948] Starting dynamic subtest: pipe-D-eDP-1
[489.366281] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.400s)
[489.368635] Subtest query-idle: SUCCESS (4.150s)
[490.189155] [086/133] (531s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[490.293432] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[490.295676] Starting dynamic subtest: pipe-A-eDP-1
[490.320607] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[490.333044] Starting dynamic subtest: pipe-B-eDP-1
[490.356180] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[490.365637] Starting dynamic subtest: pipe-C-eDP-1
[490.390061] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[490.401050] Starting dynamic subtest: pipe-D-eDP-1
[490.424807] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[490.429140] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[492.467150] [087/133] (529s left) sw_sync (sync_multi_consumer)
[492.514396] Starting subtest: sync_multi_consumer
[492.954311] Subtest sync_multi_consumer: SUCCESS (0.441s)
[493.018450] [088/133] (528s left) gem_exec_fence (long-history)
[493.424049] Starting subtest: long-history
[500.625740] Subtest long-history: SUCCESS (7.203s)
[501.105364] [089/133] (520s left) gem_eio (wait-10ms)
[501.355182] Starting subtest: wait-10ms
[501.773626] Subtest wait-10ms: SUCCESS (0.419s)
[501.899326] [090/133] (519s left) gem_exec_reloc (basic-cpu-read-active)
[501.956265] Subtest basic-cpu-read-active: SKIP (0.000s)
[502.021982] [091/133] (519s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[502.119510] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[502.123614] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[502.218220] [092/133] (519s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[502.273271] Starting subtest: invalid-single-wait-all-unsubmitted
[502.275606] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[502.404051] [093/133] (519s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping)
[502.496407] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping
[502.500152] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[502.583459] [094/133] (519s left) api_intel_bb (crc32)
[502.639566] Starting subtest: crc32
[502.641451] Starting dynamic subtest: rcs0-smem0
[502.941483] Dynamic subtest rcs0-smem0: SUCCESS (0.301s)
[502.952598] Starting dynamic subtest: bcs0-smem0
[503.298866] Dynamic subtest bcs0-smem0: SUCCESS (0.355s)
[503.311080] Starting dynamic subtest: vcs0-smem0
[503.648288] Dynamic subtest vcs0-smem0: SUCCESS (0.346s)
[503.659998] Starting dynamic subtest: vcs1-smem0
[503.987552] Dynamic subtest vcs1-smem0: SUCCESS (0.336s)
[504.000485] Starting dynamic subtest: vecs0-smem0
[504.372676] Dynamic subtest vecs0-smem0: SUCCESS (0.382s)
[504.386514] Starting dynamic subtest: ccs0-smem0
[504.704491] Dynamic subtest ccs0-smem0: SUCCESS (0.329s)
[504.707271] Subtest crc32: SUCCESS (2.066s)
[504.831855] [095/133] (517s left) kms_psr (psr2-dpms)
[504.935622] Starting subtest: psr2-dpms
[504.937860] Starting dynamic subtest: eDP-1
[507.785601] Dynamic subtest eDP-1: SUCCESS (2.851s)
[507.788044] Subtest psr2-dpms: SUCCESS (2.852s)
[508.668432] [096/133] (513s left) kms_cursor_edge_walk (64x64-top-bottom)
[508.759437] Starting subtest: 64x64-top-bottom
[508.761658] Starting dynamic subtest: pipe-A-eDP-1
[512.225768] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.468s)
[512.238687] Starting dynamic subtest: pipe-D-eDP-1
[515.351231] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.124s)
[515.353556] Subtest 64x64-top-bottom: SUCCESS (6.595s)
[516.167610] [097/133] (505s left) syncobj_wait (wait-for-submit-delayed-submit)
[516.227950] Starting subtest: wait-for-submit-delayed-submit
[516.328628] Subtest wait-for-submit-delayed-submit: SUCCESS (0.103s)
[516.409179] [098/133] (505s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[516.466602] Starting subtest: invalid-bsd1-flag-on-blt
[516.468841] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[516.567221] [099/133] (505s left) perf (whitelisted-registers-userspace-config)
[516.666020] Starting subtest: whitelisted-registers-userspace-config
[516.668470] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[516.708901] [100/133] (505s left) api_intel_bb (delta-check)
[516.771381] Starting subtest: delta-check
[516.773897] Subtest delta-check: SUCCESS (0.005s)
[516.866785] [101/133] (505s left) kms_flip (flip-vs-panning-vs-hang)
[516.962514] Starting subtest: flip-vs-panning-vs-hang
[516.964723] Starting dynamic subtest: A-eDP1
[568.386339] Dynamic subtest A-eDP1: SUCCESS (51.425s)
[568.405715] Starting dynamic subtest: D-eDP1
[608.388554] Dynamic subtest D-eDP1: SUCCESS (39.998s)
[608.391048] Subtest flip-vs-panning-vs-hang: SUCCESS (91.430s)
[609.550015] [102/133] (412s left) kms_rotation_crc (primary-rotation-270)
[609.653240] Starting subtest: primary-rotation-270
[609.657195] Subtest primary-rotation-270: SKIP (0.001s)
[609.731891] [103/133] (412s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[609.829774] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[610.509388] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.681s)
[610.647440] [104/133] (411s left) kms_sequence (queue-busy)
[610.738310] Starting subtest: queue-busy
[610.740684] Starting dynamic subtest: pipe-A-eDP-1
[612.659469] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.920s)
[612.669986] Starting dynamic subtest: pipe-B-eDP-1
[615.246408] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.586s)
[615.257172] Starting dynamic subtest: pipe-C-eDP-1
[617.860534] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.613s)
[617.876195] Starting dynamic subtest: pipe-D-eDP-1
[620.491921] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.631s)
[620.494416] Subtest queue-busy: SUCCESS (9.755s)
[621.302089] [105/133] (401s left) i915_drm_fdinfo (virtual-busy-all)
[621.342973] Starting subtest: virtual-busy-all
[621.347755] Subtest virtual-busy-all: SKIP (0.000s)
[621.430089] [106/133] (401s left) gem_exec_whisper (basic-sync-all)
[621.499571] Starting subtest: basic-sync-all
[628.822841] Subtest basic-sync-all: SUCCESS (7.323s)
[629.013905] [107/133] (393s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[629.119435] Starting subtest: pr-cursor-plane-update-sf
[629.123513] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[629.193380] [108/133] (393s left) prime_vgem (sync)
[629.255862] Starting subtest: sync
[629.258087] Starting dynamic subtest: rcs0
[629.298653] Dynamic subtest rcs0: SUCCESS (0.043s)
[629.308173] Starting dynamic subtest: bcs0
[629.345925] Dynamic subtest bcs0: SUCCESS (0.046s)
[629.355198] Starting dynamic subtest: vcs0
[629.393112] Dynamic subtest vcs0: SUCCESS (0.046s)
[629.402520] Starting dynamic subtest: vcs1
[629.438916] Dynamic subtest vcs1: SUCCESS (0.045s)
[629.448492] Starting dynamic subtest: vecs0
[629.484875] Dynamic subtest vecs0: SUCCESS (0.045s)
[629.494262] Starting dynamic subtest: ccs0
[629.530282] Dynamic subtest ccs0: SUCCESS (0.044s)
[629.532562] Subtest sync: SUCCESS (0.275s)
[629.639778] [109/133] (392s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[629.743979] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[629.748161] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[629.840376] [110/133] (392s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[629.939802] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[629.944294] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[630.054886] [111/133] (392s left) gem_exec_schedule (preemptive-hang)
[630.245443] Starting subtest: preemptive-hang
[630.248590] Starting dynamic subtest: rcs0
[639.042980] Dynamic subtest rcs0: SUCCESS (8.797s)
[639.052918] Starting dynamic subtest: bcs0
[648.001635] Dynamic subtest bcs0: SUCCESS (8.957s)
[648.011073] Starting dynamic subtest: vcs0
[657.085911] Dynamic subtest vcs0: SUCCESS (9.081s)
[657.096749] Starting dynamic subtest: vcs1
[666.047668] Dynamic subtest vcs1: SUCCESS (8.962s)
[666.058328] Starting dynamic subtest: vecs0
[675.065773] Dynamic subtest vecs0: SUCCESS (9.015s)
[675.077726] Starting dynamic subtest: ccs0
[684.033820] Dynamic subtest ccs0: SUCCESS (8.967s)
[684.036734] Subtest preemptive-hang: SUCCESS (53.790s)
[684.296504] [112/133] (338s left) gem_exec_suspend (basic-s4-devices)
[684.353658] Starting subtest: basic-S4-devices
[684.355816] Starting dynamic subtest: smem
[695.896380] Dynamic subtest smem: SUCCESS (11.540s)
[695.900759] Subtest basic-S4-devices: SUCCESS (11.542s)
[703.096695] [113/133] (326s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[703.237228] Starting subtest: y-tiled-8bpp-rotate-90
[703.241605] Subtest y-tiled-8bpp-rotate-90: SKIP (0.000s)
[703.338291] [114/133] (326s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[703.392550] Starting subtest: single-wait-all-for-submit-unsubmitted
[703.498836] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.106s)
[703.577855] [115/133] (326s left) perf (gen12-mi-rpc)
[703.659822] Starting subtest: gen12-mi-rpc
[703.662273] Starting dynamic subtest: rcs0
[703.689546] Dynamic subtest rcs0: SUCCESS (0.027s)
[703.691866] Subtest gen12-mi-rpc: SUCCESS (0.028s)
[703.731722] [116/133] (326s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[703.855819] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[707.399988] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (3.547s)
[708.748879] [117/133] (321s left) kms_pm_rpm (dpms-mode-unset-non-lpsp)
[711.081352] Starting subtest: dpms-mode-unset-non-lpsp
[712.252814] Subtest dpms-mode-unset-non-lpsp: SKIP (1.169s)
[712.642673] [118/133] (317s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[712.740666] Starting subtest: addfb25-yf-tiled-legacy
[712.742977] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[712.835047] [119/133] (317s left) kms_big_fb (x-tiled-addfb-size-overflow)
[712.941996] Starting subtest: x-tiled-addfb-size-overflow
[712.944325] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[713.047698] [120/133] (317s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[713.141878] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[713.146176] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[713.246968] [121/133] (316s left) kms_big_fb (linear-32bpp-rotate-90)
[713.349705] Starting subtest: linear-32bpp-rotate-90
[713.369576] Subtest linear-32bpp-rotate-90: SKIP (0.019s)
[713.486683] [122/133] (316s left) syncobj_wait (single-wait-all-submitted)
[713.547699] Starting subtest: single-wait-all-submitted
[713.650495] Subtest single-wait-all-submitted: SUCCESS (0.103s)
[713.730320] [123/133] (316s left) gem_exec_balancer (full-late)
[713.795750] Starting subtest: full-late
[718.773961] Subtest full-late: SUCCESS (4.977s)
[719.086252] [124/133] (311s left) gem_mmap_gtt (ptrace)
[719.139149] Subtest ptrace: SKIP (0.000s)
[719.203065] [125/133] (311s left) i915_pm_rps (waitboost)
[719.265977] Starting subtest: waitboost
[725.609867] Subtest waitboost: SUCCESS (6.344s)
[725.686480] [126/133] (304s left) prime_vgem (basic-gtt)
[725.755674] Starting subtest: basic-gtt
[725.760393] Subtest basic-gtt: SKIP (0.000s)
[725.876031] [127/133] (304s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[725.971205] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[725.975290] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[726.069343] [128/133] (304s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[726.163935] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[732.606321] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.444s)
[732.731721] [129/133] (297s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-render)
[732.831918] Starting subtest: psr-1p-offscreen-pri-indfb-draw-render
[739.781536] Subtest psr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.951s)
[739.935852] [130/133] (290s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[740.039787] Starting subtest: fbcpsr-slowdraw
[765.216189] Subtest fbcpsr-slowdraw: SUCCESS (25.178s)
[765.352454] [131/133] (265s left) kms_lease (lease-get)
[765.457778] Starting subtest: lease-get
[765.460128] Starting dynamic subtest: pipe-A-eDP-1
[765.462299] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[765.473750] Starting dynamic subtest: pipe-B-eDP-1
[765.476045] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[765.488120] Starting dynamic subtest: pipe-C-eDP-1
[765.490422] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[765.501953] Starting dynamic subtest: pipe-D-eDP-1
[765.504448] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[765.506601] Subtest lease-get: SUCCESS (0.013s)
[765.586412] [132/133] (265s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[765.689681] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[765.694411] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.000s)
[765.771480] [133/133] (264s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[765.859280] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[765.863140] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s)
[765.932165] 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.