[59.680294] Initializing watchdogs
[59.680344]   /dev/watchdog0
[59.696455] [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]
[59.701631] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.703993] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.707630] [001/133] (960s left) kms_big_fb (linear-8bpp-rotate-90)
[59.834970] Starting subtest: linear-8bpp-rotate-90
[59.874129] Subtest linear-8bpp-rotate-90: SKIP (0.039s)
[59.983133] [002/133] (960s left) gem_ctx_param (non-root-set)
[60.027912] Starting subtest: non-root-set
[60.045071] Subtest non-root-set: SUCCESS (0.017s)
[60.134277] [003/133] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[60.230292] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[60.234579] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[60.327806] [004/133] (959s left) gem_pxp (create-protected-buffer)
[60.458094] Starting subtest: create-protected-buffer
[60.461090] Subtest create-protected-buffer: SUCCESS (0.000s)
[60.572860] [005/133] (959s left) kms_invalid_mode (bad-htotal)
[60.672231] Starting subtest: bad-htotal
[60.674122] Starting dynamic subtest: pipe-A-eDP-1
[60.962065] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[60.969705] Starting dynamic subtest: pipe-B-eDP-1
[60.972082] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[60.979294] Starting dynamic subtest: pipe-C-eDP-1
[60.981565] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[60.988970] Starting dynamic subtest: pipe-D-eDP-1
[60.990910] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[60.992892] Subtest bad-htotal: SUCCESS (0.291s)
[61.764875] [006/133] (958s left) kms_vblank (invalid)
[61.867824] Starting subtest: invalid
[61.870169] Starting dynamic subtest: pipe-A-eDP-1
[62.271720] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.403s)
[62.273693] Subtest invalid: SUCCESS (0.404s)
[63.102072] [007/133] (957s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[63.197926] Starting subtest: fbc-2p-pri-indfb-multidraw
[63.202317] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[63.308552] [008/133] (957s left) gem_softpin (overlap)
[63.377510] Starting subtest: overlap
[64.284761] Subtest overlap: SUCCESS (0.904s)
[64.393242] [009/133] (956s left) kms_psr (fbc-psr-basic)
[64.492264] Starting subtest: fbc-psr-basic
[64.494203] Starting dynamic subtest: eDP-1
[64.498277] Dynamic subtest eDP-1: SKIP (0.000s)
[64.502395] Subtest fbc-psr-basic: SKIP (0.000s)
[64.563138] [010/133] (955s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs)
[64.657355] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs
[64.659707] Starting dynamic subtest: pipe-A-eDP-1
[65.125866] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.437s)
[65.135467] Starting dynamic subtest: pipe-B-eDP-1
[66.590346] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.459s)
[66.599444] Starting dynamic subtest: pipe-C-eDP-1
[67.945885] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.352s)
[67.956822] Starting dynamic subtest: pipe-D-eDP-1
[69.268336] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.319s)
[69.274593] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs: SUCCESS (4.570s)
[70.452409] [011/133] (950s left) gem_vm_create (invalid-destroy)
[70.512328] Starting subtest: invalid-destroy
[70.514740] Subtest invalid-destroy: SUCCESS (0.002s)
[70.627840] [012/133] (950s left) i915_pm_rpm (debugfs-forcewake-user)
[72.173632] Starting subtest: debugfs-forcewake-user
[83.565761] Subtest debugfs-forcewake-user: SUCCESS (11.385s)
[83.951696] [013/133] (936s left) core_setmaster_vs_auth
[84.109307] [014/133] (936s left) gem_softpin (noreloc)
[84.166635] Starting subtest: noreloc
[89.590064] Subtest noreloc: SUCCESS (5.422s)
[89.722211] [015/133] (931s left) kms_flip (flip-vs-expired-vblank-interruptible)
[89.821771] Starting subtest: flip-vs-expired-vblank-interruptible
[89.824068] Starting dynamic subtest: A-eDP1
[98.454898] Dynamic subtest A-eDP1: SUCCESS (8.630s)
[98.462935] Starting dynamic subtest: B-eDP1
[107.083232] Dynamic subtest B-eDP1: SUCCESS (8.626s)
[107.091674] Starting dynamic subtest: C-eDP1
[115.703899] Dynamic subtest C-eDP1: SUCCESS (8.619s)
[115.713929] Starting dynamic subtest: D-eDP1
[124.340517] Dynamic subtest D-eDP1: SUCCESS (8.634s)
[124.342949] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.517s)
[125.499434] [016/133] (895s left) gem_mmap_gtt (isolation)
[125.566152] Subtest isolation: SKIP (0.000s)
[125.627518] [017/133] (895s left) kms_psr2_sf (pr-cursor-plane-move-continuous-sf)
[125.740276] Starting subtest: pr-cursor-plane-move-continuous-sf
[125.744901] Subtest pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[125.825842] [018/133] (895s left) gem_exec_params (larger-than-life-batch)
[125.887523] Starting subtest: larger-than-life-batch
[128.063481] Subtest larger-than-life-batch: SUCCESS (2.176s)
[128.983349] [019/133] (891s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[129.135642] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[129.139832] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[129.242586] [020/133] (891s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[129.645839] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[130.418702] [021/133] (890s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[130.510802] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[130.514695] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[130.578850] [022/133] (890s left) kms_dsc (dsc-basic)
[130.676257] Starting subtest: dsc-basic
[130.689633] Subtest dsc-basic: SKIP (0.000s)
[130.745644] [023/133] (890s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[130.806071] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[130.917845] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.109s)
[131.001347] [024/133] (890s left) kms_atomic_transition (plane-all-transition-nonblocking)
[131.093326] Starting subtest: plane-all-transition-nonblocking
[131.095748] Starting dynamic subtest: pipe-A-eDP-1
[133.586144] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.494s)
[133.902587] Starting dynamic subtest: pipe-B-eDP-1
[137.181838] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.287s)
[137.485608] Subtest plane-all-transition-nonblocking: SUCCESS (6.392s)
[138.295735] [025/133] (882s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[138.438776] Starting subtest: dp-mst-lic-type-0-hdcp14
[138.443299] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[138.572779] [026/133] (882s left) kms_color (ctm-max)
[138.671206] Starting subtest: ctm-max
[138.673124] Starting dynamic subtest: pipe-A-eDP-1
[139.385495] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.715s)
[139.394876] Starting dynamic subtest: pipe-B-eDP-1
[140.807375] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.420s)
[140.816076] Starting dynamic subtest: pipe-C-eDP-1
[142.179906] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.374s)
[142.189185] Starting dynamic subtest: pipe-D-eDP-1
[143.521792] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.340s)
[143.524098] Subtest ctm-max: SUCCESS (4.852s)
[144.326853] [027/133] (876s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[144.440649] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[144.445121] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[144.549288] [028/133] (876s left) kms_async_flips (crc)
[144.650143] Starting subtest: crc
[145.024984] Starting dynamic subtest: pipe-A-eDP-1
[147.984576] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.958s)
[148.363063] Starting dynamic subtest: pipe-B-eDP-1
[151.268670] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.914s)
[151.658187] Starting dynamic subtest: pipe-C-eDP-1
[154.575386] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.925s)
[154.939564] Starting dynamic subtest: pipe-D-eDP-1
[157.783890] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.851s)
[157.786240] Subtest crc: SUCCESS (13.132s)
[158.963446] [029/133] (862s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[159.056695] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[159.058922] Starting dynamic subtest: pipe-A-eDP-1
[159.068065] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[159.079272] Starting dynamic subtest: pipe-B-eDP-1
[159.088071] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[159.096620] Starting dynamic subtest: pipe-C-eDP-1
[159.105172] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[159.113080] Starting dynamic subtest: pipe-D-eDP-1
[159.121561] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[159.125912] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[161.331658] [030/133] (860s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[161.436380] Starting subtest: fbc-1p-pri-indfb-multidraw
[174.483883] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.050s)
[175.858210] [031/133] (845s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[175.947978] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[175.952268] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[176.027837] [032/133] (845s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[176.145864] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[176.150256] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[176.243153] [033/133] (845s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[176.344186] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[176.348127] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[176.427074] [034/133] (845s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[176.525149] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[176.529276] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.000s)
[176.592695] [035/133] (844s left) kms_dp_link_training (uhbr-mst)
[176.685763] Starting subtest: uhbr-mst
[176.694247] Subtest uhbr-mst: SKIP (0.000s)
[176.753427] [036/133] (844s left) kms_plane_multiple (2x-tiling-x)
[176.846264] Starting subtest: 2x-tiling-x
[176.849798] Subtest 2x-tiling-x: SKIP (0.000s)
[176.916269] [037/133] (844s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[177.020693] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[177.024718] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[177.111406] [038/133] (844s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[177.211223] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[177.214808] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[177.308640] [039/133] (844s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[177.400479] Starting subtest: 4-tiled-32bpp-rotate-0
[178.868394] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.468s)
[179.002650] [040/133] (842s left) gem_ctx_persistence (engines-mixed-process)
[179.070427] Starting subtest: engines-mixed-process
[179.072406] Starting dynamic subtest: rcs0
[180.903421] Dynamic subtest rcs0: SUCCESS (1.832s)
[180.914172] Starting dynamic subtest: bcs0
[182.886421] Dynamic subtest bcs0: SUCCESS (1.981s)
[182.897617] Starting dynamic subtest: vcs0
[184.921938] Dynamic subtest vcs0: SUCCESS (2.034s)
[184.933100] Starting dynamic subtest: vcs1
[186.916091] Dynamic subtest vcs1: SUCCESS (1.993s)
[186.927414] Starting dynamic subtest: vecs0
[188.890762] Dynamic subtest vecs0: SUCCESS (1.973s)
[188.904154] Starting dynamic subtest: ccs0
[190.897871] Dynamic subtest ccs0: SUCCESS (2.003s)
[190.900643] Subtest engines-mixed-process: SUCCESS (11.827s)
[190.983882] [041/133] (830s left) i915_drm_fdinfo (virtual-busy-hang-all)
[191.025799] Starting subtest: virtual-busy-hang-all
[191.030498] Subtest virtual-busy-hang-all: SKIP (0.000s)
[191.095159] [042/133] (830s left) kms_flip (2x-blocking-wf_vblank)
[191.201640] Starting subtest: 2x-blocking-wf_vblank
[191.210449] Subtest 2x-blocking-wf_vblank: SKIP (0.011s)
[191.301809] [043/133] (830s left) kms_chamelium_color (gamma)
[191.394195] Subtest gamma: SKIP (0.000s)
[191.441403] [044/133] (830s left) sw_sync (sync_multi_timeline_wait)
[191.485031] Starting subtest: sync_multi_timeline_wait
[191.487573] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[191.539336] [045/133] (830s left) prime_self_import (basic-with_fd_dup)
[191.578708] Starting subtest: basic-with_fd_dup
[191.597932] Subtest basic-with_fd_dup: SUCCESS (0.019s)
[191.713300] [046/133] (830s left) i915_drm_fdinfo (virtual-busy)
[191.755525] Starting subtest: virtual-busy
[191.759538] Subtest virtual-busy: SKIP (0.000s)
[191.824595] [047/133] (830s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[191.907024] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[191.908801] Starting dynamic subtest: pipe-A
[191.928556] Dynamic subtest pipe-A: SKIP (0.018s)
[191.937481] Starting dynamic subtest: pipe-B
[191.961904] Dynamic subtest pipe-B: SKIP (0.033s)
[191.970565] Starting dynamic subtest: pipe-C
[191.994906] Dynamic subtest pipe-C: SKIP (0.032s)
[192.003155] Starting dynamic subtest: pipe-D
[192.026601] Dynamic subtest pipe-D: SKIP (0.032s)
[192.029986] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.117s)
[192.137241] [048/133] (829s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[192.227447] Starting subtest: fbc-rgb565-draw-pwrite
[196.887409] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.660s)
[198.067888] [049/133] (824s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180-hflip)
[198.169369] Starting subtest: linear-max-hw-stride-64bpp-rotate-180-hflip
[198.173487] Subtest linear-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[198.262713] [050/133] (823s left) kms_fbcon_fbt (psr)
[198.314089] Starting subtest: psr
[205.599289] Subtest psr: SUCCESS (7.280s)
[205.674548] [051/133] (816s left) kms_pm_backlight (fade-with-suspend)
[205.764761] Starting subtest: fade-with-suspend
[221.353617] Starting dynamic subtest: eDP-1
[223.861257] Dynamic subtest eDP-1: SUCCESS (2.448s)
[223.863610] Subtest fade-with-suspend: SUCCESS (3.782s)
[224.740834] [052/133] (797s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[224.828873] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[224.895039] [053/133] (797s left) prime_vgem (basic-blt)
[225.059331] Starting subtest: basic-blt
[225.082826] Subtest basic-blt: SUCCESS (0.026s)
[225.202505] [FACT prime_vgem (basic-blt)] new: kernel.kmod_is_loaded.vgem: true
[225.206290] [054/133] (797s left) gem_softpin (allocator-basic-reserve)
[225.262260] Starting subtest: allocator-basic-reserve
[225.339471] Subtest allocator-basic-reserve: SUCCESS (0.075s)
[225.466860] [055/133] (796s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc)
[225.570667] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc
[232.385035] Subtest fbcpsr-rgb101010-draw-mmap-wc: SUCCESS (6.722s)
[232.487338] [056/133] (789s left) gem_exec_schedule (u-fairslice)
[232.566311] Starting subtest: u-fairslice
[232.568249] Starting dynamic subtest: rcs0
[234.589638] Dynamic subtest rcs0: SUCCESS (2.008s)
[234.599153] Starting dynamic subtest: bcs0
[236.611791] Dynamic subtest bcs0: SUCCESS (2.010s)
[236.623491] Starting dynamic subtest: vcs0
[238.635264] Dynamic subtest vcs0: SUCCESS (2.014s)
[238.647475] Starting dynamic subtest: vcs1
[240.658876] Dynamic subtest vcs1: SUCCESS (2.012s)
[240.669352] Starting dynamic subtest: vecs0
[242.676730] Dynamic subtest vecs0: SUCCESS (2.013s)
[242.687602] Starting dynamic subtest: ccs0
[244.702831] Dynamic subtest ccs0: SUCCESS (2.022s)
[244.705326] Subtest u-fairslice: SUCCESS (12.085s)
[245.013229] [057/133] (777s left) gem_lmem_evict (dontneed-evict-race)
[245.061016] Subtest dontneed-evict-race: SKIP (0.000s)
[245.117735] [058/133] (777s left) gem_exec_reloc (basic-gtt-cpu-active)
[245.181708] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[245.267915] [059/133] (777s left) kms_pipe_crc_basic (suspend-read-crc)
[245.360235] Starting subtest: suspend-read-crc
[245.362171] Starting dynamic subtest: pipe-A-eDP-1
[263.265631] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.535s)
[263.276571] Starting dynamic subtest: pipe-B-eDP-1
[281.291473] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.232s)
[281.302497] Starting dynamic subtest: pipe-C-eDP-1
[299.231543] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.112s)
[299.242491] Starting dynamic subtest: pipe-D-eDP-1
[317.256434] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.108s)
[317.259512] Subtest suspend-read-crc: SUCCESS (11.994s)
[318.087370] [060/133] (704s left) gem_mmap_gtt (basic)
[318.159845] Subtest basic: SKIP (0.000s)
[318.222102] [061/133] (704s left) syncobj_wait (invalid-signal-illegal-handle)
[318.285357] Starting subtest: invalid-signal-illegal-handle
[318.287495] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[318.404947] [062/133] (704s left) perf_pmu (busy-idle-check-all)
[318.447793] Starting subtest: busy-idle-check-all
[318.450435] Starting dynamic subtest: rcs0
[319.099872] Dynamic subtest rcs0: SUCCESS (0.653s)
[319.113109] Starting dynamic subtest: bcs0
[319.755645] Dynamic subtest bcs0: SUCCESS (0.653s)
[319.768958] Starting dynamic subtest: vcs0
[320.485519] Dynamic subtest vcs0: FAIL (0.643s)
[320.497733] Starting dynamic subtest: vcs1
[321.123470] Dynamic subtest vcs1: FAIL (0.621s)
[321.134825] Starting dynamic subtest: vecs0
[321.742614] Dynamic subtest vecs0: FAIL (0.627s)
[321.753504] Starting dynamic subtest: ccs0
[323.061579] Dynamic subtest ccs0: SUCCESS (1.406s)
[323.064400] Subtest busy-idle-check-all: FAIL (4.614s)
[323.182154] [063/133] (699s left) kms_hdr (bpc-switch-suspend)
[323.281371] Starting subtest: bpc-switch-suspend
[324.374769] Starting dynamic subtest: pipe-A-eDP-1
[343.548820] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.616s)
[343.551199] Subtest bpc-switch-suspend: SUCCESS (5.709s)
[343.660488] [064/133] (679s left) gem_ctx_persistence (legacy-engines-hang)
[343.728858] Starting subtest: legacy-engines-hang
[343.731237] Starting dynamic subtest: render
[343.866770] Dynamic subtest render: SUCCESS (0.133s)
[343.881477] Starting dynamic subtest: bsd1
[343.963912] Dynamic subtest bsd1: SUCCESS (0.091s)
[343.979792] Starting dynamic subtest: bsd2
[344.053795] Dynamic subtest bsd2: SUCCESS (0.085s)
[344.069022] Starting dynamic subtest: blt
[344.150940] Dynamic subtest blt: SUCCESS (0.092s)
[344.166776] Starting dynamic subtest: vebox
[344.247840] Dynamic subtest vebox: SUCCESS (0.091s)
[344.250650] Subtest legacy-engines-hang: SUCCESS (0.505s)
[344.344689] [065/133] (678s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[344.446087] Starting subtest: yf-tiled-32bpp-rotate-270
[344.450798] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[344.562326] [066/133] (678s left) kms_cursor_crc (cursor-offscreen-128x42)
[344.780591] Starting subtest: cursor-offscreen-128x42
[344.832722] Subtest cursor-offscreen-128x42: SKIP (0.045s)
[345.016317] [067/133] (677s left) gem_create (create-valid-nonaligned)
[345.081602] Starting subtest: create-valid-nonaligned
[345.083280] Subtest create-valid-nonaligned: SUCCESS (0.000s)
[345.272853] [068/133] (677s left) kms_rotation_crc (primary-rotation-270)
[345.368134] Starting subtest: primary-rotation-270
[345.372535] Subtest primary-rotation-270: SKIP (0.001s)
[345.456530] [069/133] (677s left) kms_plane (pixel-format-y-tiled-ccs-modifier-source-clamping)
[345.559178] Starting subtest: pixel-format-y-tiled-ccs-modifier-source-clamping
[345.563465] Subtest pixel-format-y-tiled-ccs-modifier-source-clamping: SKIP (0.000s)
[345.642292] [070/133] (677s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[345.754990] Starting subtest: verify-pxp-stale-buf-optout-execution
[346.817264] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.040s)
[346.947517] [071/133] (676s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[347.080618] Starting subtest: basic-flip-before-cursor-legacy
[348.011220] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.914s)
[348.858257] [072/133] (674s left) kms_async_flips (test-cursor)
[348.952814] Starting subtest: test-cursor
[349.333883] Starting dynamic subtest: pipe-A-eDP-1
[350.162838] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.817s)
[350.555949] Starting dynamic subtest: pipe-B-eDP-1
[351.312077] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.756s)
[351.694796] Starting dynamic subtest: pipe-C-eDP-1
[352.448216] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.754s)
[352.817682] Starting dynamic subtest: pipe-D-eDP-1
[353.589369] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.772s)
[353.591798] Subtest test-cursor: SUCCESS (4.584s)
[354.787966] [073/133] (668s left) core_setmaster (master-drop-set-shared-fd)
[354.882886] Starting subtest: master-drop-set-shared-fd
[354.904846] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[354.945285] [074/133] (668s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[355.002872] Starting subtest: multi-wait-available-unsubmitted-signaled
[355.005163] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.000s)
[355.113665] [075/133] (668s left) kms_cursor_crc (cursor-tearing-framebuffer-change)
[355.223656] Starting subtest: cursor-tearing-framebuffer-change
[355.225796] Starting dynamic subtest: pipe-A-eDP-1
[355.793692] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.563s)
[355.804082] Starting dynamic subtest: pipe-D-eDP-1
[357.014949] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.215s)
[357.017344] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.780s)
[357.854757] [076/133] (665s left) gem_eio (in-flight-external)
[358.078059] Starting subtest: in-flight-external
[359.676592] Subtest in-flight-external: SUCCESS (1.591s)
[359.847882] [077/133] (663s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[359.956995] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[359.961456] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[360.064234] [078/133] (663s left) kms_pm_rpm (i2c)
[361.693506] Starting subtest: i2c
[366.972780] Subtest i2c: SUCCESS (5.268s)
[367.100845] [079/133] (656s left) kms_vblank (query-forked-hang)
[367.206187] Starting subtest: query-forked-hang
[367.208067] Starting dynamic subtest: pipe-A-eDP-1
[369.073398] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.862s)
[369.088955] Starting dynamic subtest: pipe-D-eDP-1
[371.669383] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.591s)
[371.671803] Subtest query-forked-hang: SUCCESS (4.458s)
[372.536549] [080/133] (650s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[372.645027] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[372.649273] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[372.742049] [081/133] (650s left) kms_pipe_crc_basic (bad-source)
[372.832830] Starting subtest: bad-source
[372.835118] Subtest bad-source: SUCCESS (0.000s)
[372.909869] [082/133] (650s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[372.972605] Starting subtest: single-wait-for-submit-available-signaled
[372.974968] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.001s)
[373.092990] [083/133] (650s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[373.159954] Starting subtest: yf-tiled-to-vebox-yf-tiled
[373.164539] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[373.365613] [084/133] (650s left) api_intel_bb (crc32)
[373.420633] Starting subtest: crc32
[373.422925] Starting dynamic subtest: rcs0-smem0
[373.706552] Dynamic subtest rcs0-smem0: SUCCESS (0.286s)
[373.720304] Starting dynamic subtest: bcs0-smem0
[374.021587] Dynamic subtest bcs0-smem0: SUCCESS (0.313s)
[374.034863] Starting dynamic subtest: vcs0-smem0
[374.387971] Dynamic subtest vcs0-smem0: SUCCESS (0.363s)
[374.399109] Starting dynamic subtest: vcs1-smem0
[374.764640] Dynamic subtest vcs1-smem0: SUCCESS (0.373s)
[374.778065] Starting dynamic subtest: vecs0-smem0
[375.072039] Dynamic subtest vecs0-smem0: SUCCESS (0.302s)
[375.086055] Starting dynamic subtest: ccs0-smem0
[375.441066] Dynamic subtest ccs0-smem0: SUCCESS (0.367s)
[375.444221] Subtest crc32: SUCCESS (2.022s)
[375.573911] [085/133] (648s left) template (a)
[375.631444] Starting subtest: A
[375.633433] Subtest A: SUCCESS (0.000s)
[375.732533] [086/133] (648s left) gem_exec_fence (parallel)
[375.916507] Starting subtest: parallel
[375.919036] Starting dynamic subtest: rcs0
[378.063774] Dynamic subtest rcs0: SUCCESS (2.151s)
[378.077275] Starting dynamic subtest: bcs0
[380.218613] Dynamic subtest bcs0: SUCCESS (2.154s)
[380.229319] Starting dynamic subtest: vcs0
[382.370891] Dynamic subtest vcs0: SUCCESS (2.151s)
[382.382818] Starting dynamic subtest: vcs1
[384.525812] Dynamic subtest vcs1: SUCCESS (2.154s)
[384.541880] Starting dynamic subtest: vecs0
[386.677727] Dynamic subtest vecs0: SUCCESS (2.150s)
[386.689472] Starting dynamic subtest: ccs0
[388.833981] Dynamic subtest ccs0: SUCCESS (2.155s)
[388.835927] Subtest parallel: SUCCESS (12.920s)
[389.651982] [087/133] (634s left) kms_psr (psr-sprite-blt)
[389.753554] Starting subtest: psr-sprite-blt
[389.755701] Starting dynamic subtest: eDP-1
[391.457499] Dynamic subtest eDP-1: SUCCESS (1.705s)
[391.459824] Subtest psr-sprite-blt: SUCCESS (1.706s)
[392.292481] [088/133] (631s left) gem_mmap (bad-object)
[392.355688] Subtest bad-object: SKIP (0.000s)
[392.420839] [089/133] (631s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[392.472581] Starting subtest: etime-single-wait-available-unsubmitted
[392.573625] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.101s)
[392.667886] [090/133] (631s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[392.768751] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[392.771049] Starting dynamic subtest: pipe-A-eDP-1
[392.793105] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[392.807261] Starting dynamic subtest: pipe-B-eDP-1
[392.827978] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[392.838365] Starting dynamic subtest: pipe-C-eDP-1
[392.859061] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[392.868830] Starting dynamic subtest: pipe-D-eDP-1
[392.889733] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[392.893699] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[395.054067] [091/133] (628s left) kms_psr (fbc-pr-basic)
[395.151375] Starting subtest: fbc-pr-basic
[395.155476] Subtest fbc-pr-basic: SKIP (0.000s)
[395.225434] [092/133] (628s left) fbdev (unaligned-read)
[395.278496] Starting subtest: unaligned-read
[395.290824] Subtest unaligned-read: SUCCESS (0.015s)
[395.349408] [093/133] (628s left) gem_ctx_param (set-priority-not-supported)
[395.415120] Starting subtest: set-priority-not-supported
[395.419394] Subtest set-priority-not-supported: SKIP (0.000s)
[395.491371] [094/133] (628s left) gem_exec_schedule (preempt)
[395.563191] Starting subtest: preempt
[395.565135] Starting dynamic subtest: rcs0
[395.583608] Dynamic subtest rcs0: SUCCESS (0.028s)
[395.595533] Starting dynamic subtest: bcs0
[395.603948] Dynamic subtest bcs0: SUCCESS (0.020s)
[395.614707] Starting dynamic subtest: vcs0
[395.629931] Dynamic subtest vcs0: SUCCESS (0.025s)
[395.641887] Starting dynamic subtest: vcs1
[395.651045] Dynamic subtest vcs1: SUCCESS (0.021s)
[395.661827] Starting dynamic subtest: vecs0
[395.672187] Dynamic subtest vecs0: SUCCESS (0.020s)
[395.682961] Starting dynamic subtest: ccs0
[395.699484] Dynamic subtest ccs0: SUCCESS (0.027s)
[395.701811] Subtest preempt: SUCCESS (0.144s)
[397.641788] [095/133] (626s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[397.741055] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[404.813315] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.073s)
[404.938511] [096/133] (619s left) sw_sync (sync_multi_consumer)
[404.990760] Starting subtest: sync_multi_consumer
[405.420564] Subtest sync_multi_consumer: SUCCESS (0.431s)
[405.483161] [097/133] (618s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[405.583827] Starting subtest: y-tiled-addfb-size-offset-overflow
[405.588816] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[405.701681] [098/133] (618s left) perf_pmu (all-busy-idle-check-all)
[405.740748] Starting subtest: all-busy-idle-check-all
[407.610802] Subtest all-busy-idle-check-all: SUCCESS (1.867s)
[407.716501] [099/133] (616s left) gem_eio (wait-wedge-10ms)
[407.923603] Starting subtest: wait-wedge-10ms
[409.683950] Subtest wait-wedge-10ms: SUCCESS (1.758s)
[409.821936] [100/133] (614s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[409.919430] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu
[413.993046] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (4.075s)
[415.182123] [101/133] (609s left) kms_psr (pr-sprite-mmap-cpu)
[415.295071] Starting subtest: pr-sprite-mmap-cpu
[415.299367] Subtest pr-sprite-mmap-cpu: SKIP (0.000s)
[415.382820] [102/133] (609s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[415.449361] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[415.526491] [103/133] (608s left) syncobj_timeline (invalid-query-illegal-handle)
[415.596838] Starting subtest: invalid-query-illegal-handle
[415.599181] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[415.715529] [104/133] (608s left) kms_cursor_crc (cursor-random-32x10)
[415.874946] Starting subtest: cursor-random-32x10
[415.919360] Subtest cursor-random-32x10: SKIP (0.041s)
[416.197020] [105/133] (608s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[416.291426] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[416.293204] Starting dynamic subtest: pipe-A-eDP-1
[418.432486] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.141s)
[418.443057] Starting dynamic subtest: pipe-B-eDP-1
[420.679217] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.243s)
[420.690212] Starting dynamic subtest: pipe-C-eDP-1
[422.725077] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.045s)
[422.736660] Starting dynamic subtest: pipe-D-eDP-1
[424.770546] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.042s)
[424.773067] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.476s)
[425.998398] [106/133] (598s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[426.096894] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[426.100759] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[426.191209] [107/133] (598s left) gem_pread (display)
[426.248820] Subtest display: SKIP (0.000s)
[426.311902] [108/133] (598s left) gem_exec_basic (basic)
[426.372292] Starting subtest: basic
[426.374613] Starting dynamic subtest: rcs0-smem
[426.376959] Dynamic subtest rcs0-smem: SUCCESS (0.003s)
[426.389238] Starting dynamic subtest: bcs0-smem
[426.391082] Dynamic subtest bcs0-smem: SUCCESS (0.002s)
[426.401299] Starting dynamic subtest: vcs0-smem
[426.403198] Dynamic subtest vcs0-smem: SUCCESS (0.001s)
[426.413170] Starting dynamic subtest: vcs1-smem
[426.415082] Dynamic subtest vcs1-smem: SUCCESS (0.001s)
[426.424990] Starting dynamic subtest: vecs0-smem
[426.426923] Dynamic subtest vecs0-smem: SUCCESS (0.002s)
[426.439739] Starting dynamic subtest: ccs0-smem
[426.441611] Dynamic subtest ccs0-smem: SUCCESS (0.001s)
[426.443423] Subtest basic: SUCCESS (0.014s)
[427.618679] [109/133] (597s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[427.720570] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[427.724751] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[427.822316] [110/133] (597s left) kms_plane_scaling (plane-downscale-factor-0-75-with-modifiers)
[427.920253] Starting subtest: plane-downscale-factor-0-75-with-modifiers
[427.922487] Starting dynamic subtest: pipe-A
[428.263587] Dynamic subtest pipe-A: SUCCESS (0.343s)
[428.274809] Starting dynamic subtest: pipe-B
[429.718935] Dynamic subtest pipe-B: SUCCESS (1.454s)
[429.730181] Starting dynamic subtest: pipe-C
[431.153242] Dynamic subtest pipe-C: SUCCESS (1.432s)
[431.171166] Starting dynamic subtest: pipe-D
[432.568889] Dynamic subtest pipe-D: SUCCESS (1.413s)
[432.571214] Subtest plane-downscale-factor-0-75-with-modifiers: SUCCESS (4.648s)
[433.729622] [111/133] (591s left) gem_exec_balancer (bonded-pair)
[433.804611] Starting subtest: bonded-pair
[433.809444] Subtest bonded-pair: SKIP (0.000s)
[434.105361] [112/133] (590s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[434.201961] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[441.595068] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.395s)
[441.747531] [113/133] (583s left) kms_legacy_colorkey (invalid-plane)
[441.844542] Starting subtest: invalid-plane
[441.846695] Starting dynamic subtest: zero-id
[441.848543] Dynamic subtest zero-id: SUCCESS (0.000s)
[441.858988] Starting dynamic subtest: outrange-id
[441.861094] Dynamic subtest outrange-id: SUCCESS (0.000s)
[441.863167] Subtest invalid-plane: SUCCESS (0.001s)
[441.918312] [114/133] (583s left) api_intel_bb (purge-bb)
[441.981120] Starting subtest: purge-bb
[441.983459] Subtest purge-bb: SUCCESS (0.004s)
[442.090677] [115/133] (582s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[442.198578] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[448.892220] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.699s)
[449.010142] [116/133] (576s left) perf_pmu (rc6)
[449.056039] Starting subtest: rc6
[449.058342] Starting dynamic subtest: gt0
[455.417897] Dynamic subtest gt0: SUCCESS (6.362s)
[455.430702] Starting dynamic subtest: runtime-pm-gt0
[463.190929] Dynamic subtest runtime-pm-gt0: SUCCESS (7.772s)
[463.203787] Starting dynamic subtest: runtime-pm-long-gt0
[475.684098] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.490s)
[475.697665] Starting dynamic subtest: other-idle-gt0
[482.026039] Dynamic subtest other-idle-gt0: SUCCESS (6.340s)
[482.039719] Starting dynamic subtest: gt1
[488.369889] Dynamic subtest gt1: SUCCESS (6.339s)
[488.383305] Starting dynamic subtest: runtime-pm-gt1
[495.874519] Dynamic subtest runtime-pm-gt1: SUCCESS (7.499s)
[495.887923] Starting dynamic subtest: runtime-pm-long-gt1
[508.368366] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.490s)
[508.381921] Starting dynamic subtest: other-idle-gt1
[514.712224] Dynamic subtest other-idle-gt1: SUCCESS (6.339s)
[514.715388] Subtest rc6: SUCCESS (65.651s)
[515.868443] [117/133] (509s left) kms_atomic_transition (plane-primary-toggle-with-vblank-wait)
[515.975074] Starting subtest: plane-primary-toggle-with-vblank-wait
[515.977310] Starting dynamic subtest: pipe-A-eDP-1
[516.148139] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.175s)
[516.469066] Starting dynamic subtest: pipe-B-eDP-1
[517.383693] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.924s)
[517.683744] Subtest plane-primary-toggle-with-vblank-wait: SUCCESS (1.707s)
[518.520641] [118/133] (506s left) gem_cs_tlb (engines)
[518.573775] Starting subtest: engines
[518.576166] Starting dynamic subtest: rcs0
[520.730087] Dynamic subtest rcs0: SUCCESS (2.152s)
[520.743175] Starting dynamic subtest: bcs0
[522.883623] Dynamic subtest bcs0: SUCCESS (2.152s)
[522.896414] Starting dynamic subtest: vcs0
[525.037815] Dynamic subtest vcs0: SUCCESS (2.152s)
[525.051719] Starting dynamic subtest: vcs1
[527.191279] Dynamic subtest vcs1: SUCCESS (2.152s)
[527.203602] Starting dynamic subtest: vecs0
[529.344904] Dynamic subtest vecs0: SUCCESS (2.153s)
[529.356349] Starting dynamic subtest: ccs0
[531.499587] Dynamic subtest ccs0: SUCCESS (2.153s)
[531.501812] Subtest engines: SUCCESS (12.920s)
[531.595277] [119/133] (493s left) kms_cdclk (mode-transition-all-outputs)
[531.691766] Starting subtest: mode-transition-all-outputs
[531.982765] Subtest mode-transition-all-outputs: SKIP (0.287s)
[532.761691] [120/133] (492s left) kms_psr (fbc-pr-sprite-plane-onoff)
[532.855113] Starting subtest: fbc-pr-sprite-plane-onoff
[532.859608] Subtest fbc-pr-sprite-plane-onoff: SKIP (0.000s)
[532.906372] [121/133] (492s left) gem_exec_whisper (basic-normal-all)
[532.970069] Starting subtest: basic-normal-all
[535.391281] Subtest basic-normal-all: SUCCESS (2.416s)
[535.554118] [122/133] (489s left) kms_flip (2x-flip-vs-panning-interruptible)
[535.666710] Starting subtest: 2x-flip-vs-panning-interruptible
[535.679404] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.011s)
[535.735082] [123/133] (489s left) kms_addfb_basic (unused-pitches)
[535.795069] Starting subtest: unused-pitches
[535.796949] Subtest unused-pitches: SUCCESS (0.000s)
[535.906556] [124/133] (489s left) gem_ctx_shared (q-out-order)
[535.984586] Starting subtest: Q-out-order
[535.987045] Starting dynamic subtest: rcs0
[536.172374] Dynamic subtest rcs0: SUCCESS (0.188s)
[536.185142] Starting dynamic subtest: vcs0
[536.277838] Dynamic subtest vcs0: SUCCESS (0.105s)
[536.296087] Starting dynamic subtest: vcs1
[536.366545] Dynamic subtest vcs1: SUCCESS (0.087s)
[536.385860] Starting dynamic subtest: vecs0
[536.451705] Dynamic subtest vecs0: SUCCESS (0.085s)
[536.478959] Starting dynamic subtest: ccs0
[536.544333] Dynamic subtest ccs0: SUCCESS (0.092s)
[536.547260] Subtest Q-out-order: SUCCESS (0.563s)
[537.656782] [125/133] (487s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[537.747684] Starting subtest: psr-rgb565-draw-mmap-wc
[544.646101] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.899s)
[544.751022] [126/133] (480s left) gem_media_fill (media-fill)
[544.812525] Subtest media-fill: SKIP (0.000s)
[544.903444] [127/133] (480s left) kms_vrr (seamless-rr-switch-virtual)
[544.991720] Starting subtest: seamless-rr-switch-virtual
[544.998182] Subtest seamless-rr-switch-virtual: SKIP (0.000s)
[545.056233] [128/133] (480s left) gem_exec_flush (basic-uc-set-default)
[545.127767] Starting subtest: basic-uc-set-default
[550.594606] Subtest basic-uc-set-default: SUCCESS (5.466s)
[550.760600] [129/133] (474s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[550.871415] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[550.875512] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[550.950261] [130/133] (474s left) gen9_exec_parse (bb-large)
[551.012258] Subtest bb-large: SKIP (0.000s)
[551.078425] [131/133] (474s left) vgem_basic (bad-flag)
[551.118322] Starting subtest: bad-flag
[551.120629] Subtest bad-flag: SUCCESS (0.000s)
[551.163243] [132/133] (474s left) i915_suspend (forcewake)
[551.209723] Starting subtest: forcewake
[583.372819] Subtest forcewake: SUCCESS (2.243s)
[583.436653] [133/133] (442s left) kms_psr (psr2-primary-blt)
[583.540373] Starting subtest: psr2-primary-blt
[583.543247] Starting dynamic subtest: eDP-1
[585.351240] Dynamic subtest eDP-1: SUCCESS (1.759s)
[585.353703] Subtest psr2-primary-blt: SUCCESS (1.760s)
[586.227529] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: gem_ctx_param
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: gem_pxp
results: parsing output: 4/ for test: kms_invalid_mode
results: parsing output: 5/ for test: kms_vblank
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_softpin
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: gem_vm_create
results: parsing output: 11/ for test: i915_pm_rpm
results: parsing output: 12/ for test: core_setmaster_vs_auth
results: parsing output: 13/ for test: gem_softpin
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: gem_mmap_gtt
results: parsing output: 16/ for test: kms_psr2_sf
results: parsing output: 17/ for test: gem_exec_params
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_chamelium_hpd
results: parsing output: 20/ for test: kms_flip_scaled_crc
results: parsing output: 21/ for test: kms_dsc
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: kms_atomic_transition
results: parsing output: 24/ for test: kms_content_protection
results: parsing output: 25/ for test: kms_color
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_async_flips
results: parsing output: 28/ for test: kms_ccs
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_psr2_sf
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_flip_scaled_crc
results: parsing output: 34/ for test: kms_dp_link_training
results: parsing output: 35/ for test: kms_plane_multiple
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: gem_ctx_persistence
results: parsing output: 40/ for test: i915_drm_fdinfo
results: parsing output: 41/ for test: kms_flip
results: parsing output: 42/ for test: kms_chamelium_color
results: parsing output: 43/ for test: sw_sync
results: parsing output: 44/ for test: prime_self_import
results: parsing output: 45/ for test: i915_drm_fdinfo
results: parsing output: 46/ for test: kms_plane_scaling
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: kms_fbcon_fbt
results: parsing output: 50/ for test: kms_pm_backlight
results: parsing output: 51/ for test: kms_pipe_b_c_ivb
results: parsing output: 52/ for test: prime_vgem
results: parsing output: 53/ for test: gem_softpin
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: gem_exec_schedule
results: parsing output: 56/ for test: gem_lmem_evict
results: parsing output: 57/ for test: gem_exec_reloc
results: parsing output: 58/ for test: kms_pipe_crc_basic
results: parsing output: 59/ for test: gem_mmap_gtt
results: parsing output: 60/ for test: syncobj_wait
results: parsing output: 61/ for test: perf_pmu
results: parsing output: 62/ for test: kms_hdr
results: parsing output: 63/ for test: gem_ctx_persistence
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: kms_cursor_crc
results: parsing output: 66/ for test: gem_create
results: parsing output: 67/ for test: kms_rotation_crc
results: parsing output: 68/ for test: kms_plane
results: parsing output: 69/ for test: gem_pxp
results: parsing output: 70/ for test: kms_cursor_legacy
results: parsing output: 71/ for test: kms_async_flips
results: parsing output: 72/ for test: core_setmaster
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_cursor_crc
results: parsing output: 75/ for test: gem_eio
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_pm_rpm
results: parsing output: 78/ for test: kms_vblank
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_pipe_crc_basic
results: parsing output: 81/ for test: syncobj_timeline
results: parsing output: 82/ for test: gem_render_copy
results: parsing output: 83/ for test: api_intel_bb
results: parsing output: 84/ for test: template
results: parsing output: 85/ for test: gem_exec_fence
results: parsing output: 86/ for test: kms_psr
results: parsing output: 87/ for test: gem_mmap
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: fbdev
results: parsing output: 92/ for test: gem_ctx_param
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: sw_sync
results: parsing output: 96/ for test: kms_big_fb
results: parsing output: 97/ for test: perf_pmu
results: parsing output: 98/ for test: gem_eio
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: gem_exec_reloc
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: kms_cursor_crc
results: parsing output: 104/ for test: kms_ccs
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: gem_pread
results: parsing output: 107/ for test: gem_exec_basic
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_plane_scaling
results: parsing output: 110/ for test: gem_exec_balancer
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_legacy_colorkey
results: parsing output: 113/ for test: api_intel_bb
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: perf_pmu
results: parsing output: 116/ for test: kms_atomic_transition
results: parsing output: 117/ for test: gem_cs_tlb
results: parsing output: 118/ for test: kms_cdclk
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: gem_exec_whisper
results: parsing output: 121/ for test: kms_flip
results: parsing output: 122/ for test: kms_addfb_basic
results: parsing output: 123/ for test: gem_ctx_shared
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_media_fill
results: parsing output: 126/ for test: kms_vrr
results: parsing output: 127/ for test: gem_exec_flush
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gen9_exec_parse
results: parsing output: 130/ for test: vgem_basic
results: parsing output: 131/ for test: i915_suspend
results: parsing output: 132/ for test: kms_psr
Done.