[58.614956] Initializing watchdogs
[58.614998]   /dev/watchdog0
[58.630249] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[58.634158] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.636163] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.639587] [001/133] (960s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[58.745762] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[58.750110] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[58.830911] [002/133] (960s left) core_getversion (all-cards)
[58.868250] Starting subtest: all-cards
[58.875529] Subtest all-cards: SUCCESS (0.008s)
[58.915472] [003/133] (960s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[59.000025] Starting subtest: y-tiled-32bpp-rotate-0
[59.004304] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[59.095822] [004/133] (960s left) gem_exec_fence (syncobj-timeline-export)
[59.647036] Starting subtest: syncobj-timeline-export
[59.652408] Subtest syncobj-timeline-export: SUCCESS (0.008s)
[60.900495] [FACT gem_exec_fence (syncobj-timeline-export)] new: kernel.kmod_is_loaded.vgem: true
[60.903552] [005/133] (958s left) kms_big_fb (4-tiled-8bpp-rotate-0)
[61.009666] Starting subtest: 4-tiled-8bpp-rotate-0
[62.283149] Subtest 4-tiled-8bpp-rotate-0: SUCCESS (1.270s)
[62.420902] [006/133] (956s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[62.501810] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[62.505261] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[62.565490] [007/133] (956s left) kms_flip (2x-plain-flip-interruptible)
[62.635346] Starting subtest: 2x-plain-flip-interruptible
[62.643150] Subtest 2x-plain-flip-interruptible: SKIP (0.008s)
[62.712460] [008/133] (956s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[62.784189] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[62.787897] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[62.879928] [009/133] (956s left) kms_joiner (invalid-modeset-force-big-joiner)
[62.957023] Starting subtest: invalid-modeset-force-big-joiner
[62.960673] Subtest invalid-modeset-force-big-joiner: SKIP (0.000s)
[63.033360] [010/133] (956s left) gem_gpgpu_fill (basic)
[63.101087] Starting subtest: basic
[63.102894] Starting dynamic subtest: smem
[63.107441] Dynamic subtest smem: SUCCESS (0.009s)
[63.109453] Subtest basic: SUCCESS (0.009s)
[63.236626] [011/133] (956s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[63.315025] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[63.318708] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[63.424691] [012/133] (956s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[63.501359] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[63.503431] Starting dynamic subtest: pipe-A-eDP-1
[63.522500] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[63.530070] Starting dynamic subtest: pipe-B-eDP-1
[63.549577] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[63.558515] Starting dynamic subtest: pipe-C-eDP-1
[63.576182] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[63.582996] Starting dynamic subtest: pipe-D-eDP-1
[63.602038] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[63.605746] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.003s)
[65.654533] [013/133] (953s left) gem_mmap_wc (write-wc-read-gtt)
[65.704504] Subtest write-wc-read-gtt: SKIP (0.000s)
[65.762732] [014/133] (953s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[65.830978] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[65.832945] Starting dynamic subtest: pipe-A-eDP-1
[65.856460] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.026s)
[65.865828] Starting dynamic subtest: pipe-B-eDP-1
[65.885756] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.021s)
[65.893461] Starting dynamic subtest: pipe-C-eDP-1
[65.912787] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.023s)
[65.920532] Starting dynamic subtest: pipe-D-eDP-1
[65.940676] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.022s)
[65.942922] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.094s)
[68.221101] [015/133] (951s left) gem_exec_schedule (independent)
[68.294895] Starting subtest: independent
[68.296940] Starting dynamic subtest: rcs0
[69.849616] Dynamic subtest rcs0: SUCCESS (1.561s)
[69.860735] Starting dynamic subtest: vcs0
[71.842384] Dynamic subtest vcs0: SUCCESS (1.990s)
[71.850562] Starting dynamic subtest: vcs1
[73.888390] Dynamic subtest vcs1: SUCCESS (2.043s)
[73.897892] Starting dynamic subtest: vecs0
[75.873767] Dynamic subtest vecs0: SUCCESS (1.984s)
[75.884724] Starting dynamic subtest: ccs0
[77.849074] Dynamic subtest ccs0: SUCCESS (1.974s)
[77.852094] Subtest independent: SUCCESS (9.556s)
[78.149573] [016/133] (941s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move)
[78.240650] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move
[78.244684] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[78.352667] [017/133] (941s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[78.436092] Starting subtest: extended-modeset-hang-oldfb-with-reset
[78.438303] Starting dynamic subtest: pipe-A
[89.998587] Dynamic subtest pipe-A: SUCCESS (11.559s)
[90.008371] Starting dynamic subtest: pipe-D
[102.271690] Dynamic subtest pipe-D: SUCCESS (12.266s)
[102.273953] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (23.829s)
[103.071404] [018/133] (916s left) gem_exec_flush (basic-uc-rw-default)
[103.137407] Starting subtest: basic-uc-rw-default
[108.625838] Subtest basic-uc-rw-default: SUCCESS (5.463s)
[108.796334] [019/133] (911s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[108.880398] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[112.771687] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (3.894s)
[113.957216] [020/133] (905s left) syncobj_timeline (single-wait-all-available-signaled)
[114.012931] Starting subtest: single-wait-all-available-signaled
[114.015176] Subtest single-wait-all-available-signaled: SUCCESS (0.000s)
[114.100559] [021/133] (905s left) gem_eio (banned)
[114.327047] Starting subtest: banned
[114.916180] Subtest banned: SUCCESS (0.589s)
[115.056094] [022/133] (904s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[115.137964] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[115.141466] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[115.243195] [023/133] (904s left) gen9_exec_parse (bb-start-out)
[115.291941] Subtest bb-start-out: SKIP (0.000s)
[115.352446] [024/133] (904s left) gem_ctx_shared (q-independent)
[115.405921] Starting subtest: Q-independent
[115.407716] Starting dynamic subtest: rcs0
[115.468865] Dynamic subtest rcs0: SUCCESS (0.064s)
[115.479318] Starting dynamic subtest: vcs0
[115.507977] Dynamic subtest vcs0: SUCCESS (0.040s)
[115.516538] Starting dynamic subtest: vcs1
[115.545730] Dynamic subtest vcs1: SUCCESS (0.037s)
[115.554064] Starting dynamic subtest: vecs0
[115.585346] Dynamic subtest vecs0: SUCCESS (0.039s)
[115.593926] Starting dynamic subtest: ccs0
[115.636822] Dynamic subtest ccs0: SUCCESS (0.051s)
[115.639335] Subtest Q-independent: SUCCESS (0.232s)
[116.940385] [025/133] (903s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[117.006927] Starting subtest: crc-primary-rotation-180-4-tiled-lnl-ccs
[117.011603] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[118.084621] [026/133] (902s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[118.160683] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[122.638374] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (4.482s)
[123.804073] [027/133] (896s left) kms_addfb_basic (bo-too-small)
[123.888723] Starting subtest: bo-too-small
[123.891062] Subtest bo-too-small: SUCCESS (0.000s)
[123.979704] [028/133] (896s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[124.064725] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[124.067014] Starting dynamic subtest: pipe-A-eDP-1
[124.091187] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[124.101652] Starting dynamic subtest: pipe-B-eDP-1
[124.124114] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[124.131930] Starting dynamic subtest: pipe-C-eDP-1
[124.154712] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[124.162537] Starting dynamic subtest: pipe-D-eDP-1
[124.186614] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[124.190674] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.003s)
[126.267968] [029/133] (893s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[126.634300] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[127.471179] [030/133] (892s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[127.552524] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[134.749145] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.201s)
[134.875070] [031/133] (885s left) i915_drm_fdinfo (busy)
[134.919301] Starting subtest: busy
[134.921553] Starting dynamic subtest: rcs0
[134.957763] Dynamic subtest rcs0: SKIP (0.035s)
[134.967965] Starting dynamic subtest: bcs0
[134.972564] Dynamic subtest bcs0: SKIP (0.001s)
[134.980542] Starting dynamic subtest: vcs0
[134.984999] Dynamic subtest vcs0: SKIP (0.000s)
[134.992456] Starting dynamic subtest: vcs1
[134.996487] Dynamic subtest vcs1: SKIP (0.000s)
[135.003788] Starting dynamic subtest: vecs0
[135.007834] Dynamic subtest vecs0: SKIP (0.000s)
[135.015126] Starting dynamic subtest: ccs0
[135.019174] Dynamic subtest ccs0: SKIP (0.000s)
[135.023175] Subtest busy: SKIP (0.039s)
[135.056947] [032/133] (885s left) kms_psr (fbc-psr2-no-drrs)
[135.134497] Starting subtest: fbc-psr2-no-drrs
[135.136750] Starting dynamic subtest: eDP-1
[135.140843] Dynamic subtest eDP-1: SKIP (0.000s)
[135.144984] Subtest fbc-psr2-no-drrs: SKIP (0.001s)
[135.203465] [033/133] (885s left) gem_mmap_offset (blt-coherency)
[135.260336] Starting subtest: blt-coherency
[135.265366] Subtest blt-coherency: SUCCESS (0.007s)
[135.390211] [034/133] (885s left) gem_pipe_control_store_loop (reused-buffer)
[135.441762] Starting subtest: reused-buffer
[137.593088] Subtest reused-buffer: SUCCESS (2.152s)
[137.710753] [035/133] (882s left) syncobj_timeline (invalid-signal-zero-handles)
[137.767250] Starting subtest: invalid-signal-zero-handles
[137.769572] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[137.873726] [036/133] (882s left) kms_pm_rpm (system-suspend-idle)
[139.974044] Starting subtest: system-suspend-idle
[159.297448] Subtest system-suspend-idle: SUCCESS (4.291s)
[159.683567] [037/133] (860s left) kms_plane_alpha_blend (alpha-basic)
[159.793525] Starting subtest: alpha-basic
[160.084283] Starting dynamic subtest: pipe-A-eDP-1
[162.006859] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.886s)
[162.041296] Starting dynamic subtest: pipe-D-eDP-1
[164.754039] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.691s)
[164.756472] Subtest alpha-basic: SUCCESS (4.886s)
[165.629458] [038/133] (854s left) perf (polling)
[165.753439] Starting subtest: polling
[165.755874] Starting dynamic subtest: 0-rcs0
[175.779574] Dynamic subtest 0-rcs0: SUCCESS (9.969s)
[175.789231] Starting dynamic subtest: 1-vecs0
[185.841994] Dynamic subtest 1-vecs0: SUCCESS (9.991s)
[185.844515] Subtest polling: SUCCESS (19.962s)
[185.883674] [039/133] (834s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[185.965440] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[192.736875] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.773s)
[192.877188] [040/133] (827s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[192.952981] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render
[192.957681] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[193.043099] [041/133] (827s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[193.094731] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[193.149396] [042/133] (827s left) syncobj_wait (multi-wait-all-submitted-signaled)
[193.210779] Starting subtest: multi-wait-all-submitted-signaled
[193.316636] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.106s)
[193.392540] [043/133] (827s left) kms_flip (modeset-vs-vblank-race)
[193.475431] Starting subtest: modeset-vs-vblank-race
[193.477604] Starting dynamic subtest: A-eDP1
[198.222955] Dynamic subtest A-eDP1: SUCCESS (4.749s)
[198.234327] Starting dynamic subtest: B-eDP1
[202.948902] Dynamic subtest B-eDP1: SUCCESS (4.724s)
[202.960966] Starting dynamic subtest: C-eDP1
[207.667671] Dynamic subtest C-eDP1: SUCCESS (4.716s)
[207.678522] Starting dynamic subtest: D-eDP1
[212.205666] Dynamic subtest D-eDP1: SUCCESS (4.536s)
[212.208158] Subtest modeset-vs-vblank-race: SUCCESS (18.733s)
[213.370865] [044/133] (807s left) syncobj_wait (wait-all-snapshot)
[213.435384] Starting subtest: wait-all-snapshot
[213.515303] Subtest wait-all-snapshot: SUCCESS (0.081s)
[213.589407] [045/133] (807s left) kms_chamelium_hpd (common-hpd-after-suspend)
[213.954467] Subtest common-hpd-after-suspend: SKIP (0.000s)
[214.774210] [046/133] (806s left) prime_busy (hang)
[214.842541] Starting subtest: hang
[214.844472] Starting dynamic subtest: rcs0
[241.366460] Dynamic subtest rcs0: SUCCESS (26.514s)
[241.377301] Starting dynamic subtest: bcs0
[248.506652] Dynamic subtest bcs0: SUCCESS (7.135s)
[248.515457] Starting dynamic subtest: vcs0
[255.490270] Dynamic subtest vcs0: SUCCESS (6.983s)
[255.499207] Starting dynamic subtest: vcs1
[262.522562] Dynamic subtest vcs1: SUCCESS (7.034s)
[262.530957] Starting dynamic subtest: vecs0
[269.504899] Dynamic subtest vecs0: SUCCESS (6.982s)
[269.513202] Starting dynamic subtest: ccs0
[283.344419] Dynamic subtest ccs0: SUCCESS (13.839s)
[283.346926] Subtest hang: SUCCESS (68.491s)
[283.455313] [047/133] (737s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff)
[283.541189] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff
[283.545236] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[283.638445] [048/133] (737s left) kms_lease (master-vs-lease)
[283.713518] Starting subtest: master-vs-lease
[283.715960] Subtest master-vs-lease: SUCCESS (0.002s)
[283.808447] [049/133] (737s left) gem_lmem_swapping (random-engines)
[283.849808] Subtest random-engines: SKIP (0.000s)
[283.895626] [050/133] (737s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[283.981278] Starting subtest: fbc-psr-sprite-mmap-cpu
[283.983509] Starting dynamic subtest: eDP-1
[283.987612] Dynamic subtest eDP-1: SKIP (0.000s)
[283.991638] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[284.059273] [051/133] (737s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[284.127523] Starting subtest: plane-ctm3x4-lut1d
[284.295711] Starting dynamic subtest: pipe-A-plane-0
[284.592134] Dynamic subtest pipe-A-plane-0: FAIL (0.153s)
[284.603692] Starting dynamic subtest: pipe-A-plane-1
[284.786165] Dynamic subtest pipe-A-plane-1: FAIL (0.154s)
[284.796941] Starting dynamic subtest: pipe-A-plane-2
[285.006252] Dynamic subtest pipe-A-plane-2: FAIL (0.166s)
[285.990562] Starting dynamic subtest: pipe-B-plane-0
[286.439714] Dynamic subtest pipe-B-plane-0: FAIL (0.185s)
[286.450944] Starting dynamic subtest: pipe-B-plane-1
[286.769738] Dynamic subtest pipe-B-plane-1: FAIL (0.156s)
[286.780624] Starting dynamic subtest: pipe-B-plane-2
[287.087936] Dynamic subtest pipe-B-plane-2: FAIL (0.632s)
[288.112549] Starting dynamic subtest: pipe-C-plane-0
[288.614932] Dynamic subtest pipe-C-plane-0: FAIL (0.129s)
[288.626634] Starting dynamic subtest: pipe-C-plane-1
[289.025439] Dynamic subtest pipe-C-plane-1: FAIL (0.611s)
[289.034399] Starting dynamic subtest: pipe-C-plane-2
[289.392770] Dynamic subtest pipe-C-plane-2: FAIL (0.165s)
[290.235026] Starting dynamic subtest: pipe-D-plane-0
[290.828942] Dynamic subtest pipe-D-plane-0: FAIL (0.129s)
[290.840631] Starting dynamic subtest: pipe-D-plane-1
[291.299100] Dynamic subtest pipe-D-plane-1: FAIL (0.610s)
[291.310659] Starting dynamic subtest: pipe-D-plane-2
[291.750057] Dynamic subtest pipe-D-plane-2: FAIL (0.543s)
[291.815642] Subtest plane-ctm3x4-lut1d: FAIL (7.687s)
[292.631222] [052/133] (728s left) kms_flip (flip-vs-panning)
[292.717425] Starting subtest: flip-vs-panning
[292.719312] Starting dynamic subtest: A-eDP1
[301.435298] Dynamic subtest A-eDP1: SUCCESS (8.719s)
[301.448074] Starting dynamic subtest: B-eDP1
[310.166483] Dynamic subtest B-eDP1: SUCCESS (8.727s)
[310.177328] Starting dynamic subtest: C-eDP1
[318.892052] Dynamic subtest C-eDP1: SUCCESS (8.722s)
[318.900664] Starting dynamic subtest: D-eDP1
[327.629218] Dynamic subtest D-eDP1: SUCCESS (8.733s)
[327.633628] Subtest flip-vs-panning: SUCCESS (34.913s)
[328.789089] [053/133] (692s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-ccs)
[328.849187] Starting subtest: y-tiled-ccs-to-yf-tiled-ccs
[328.853430] Subtest y-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[329.042450] [054/133] (692s left) i915_pm_rps (fence-order)
[329.103479] Starting subtest: fence-order
[332.553954] Subtest fence-order: SUCCESS (3.444s)
[332.678302] [055/133] (688s left) syncobj_basic (bad-flags-fd-to-handle)
[332.731259] Starting subtest: bad-flags-fd-to-handle
[332.733566] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[332.847930] [056/133] (688s left) syncobj_timeline (wait-zero-handles)
[332.898552] Starting subtest: wait-zero-handles
[332.900788] Subtest wait-zero-handles: SUCCESS (0.000s)
[332.991088] [057/133] (688s left) gem_eio (in-flight-contexts-10ms)
[333.223095] Starting subtest: in-flight-contexts-10ms
[346.874687] Subtest in-flight-contexts-10ms: SUCCESS (13.646s)
[347.041012] [058/133] (674s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[347.124690] Starting subtest: x-tiled-16bpp-rotate-180
[348.416207] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.291s)
[348.540681] [059/133] (672s left) kms_flip (flip-vs-expired-vblank-interruptible)
[348.618168] Starting subtest: flip-vs-expired-vblank-interruptible
[348.620159] Starting dynamic subtest: A-eDP1
[357.328388] Dynamic subtest A-eDP1: SUCCESS (8.674s)
[357.345993] Starting dynamic subtest: B-eDP1
[365.961605] Dynamic subtest B-eDP1: SUCCESS (8.626s)
[365.971737] Starting dynamic subtest: C-eDP1
[374.601537] Dynamic subtest C-eDP1: SUCCESS (8.624s)
[374.613276] Starting dynamic subtest: D-eDP1
[383.191413] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[383.193603] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.567s)
[384.358817] [060/133] (637s left) kms_getfb (getfb-handle-zero)
[384.431979] Starting subtest: getfb-handle-zero
[384.434292] Subtest getfb-handle-zero: SUCCESS (0.000s)
[384.521477] [061/133] (637s left) syncobj_timeline (etime-multi-wait-available-unsubmitted)
[384.587239] Starting subtest: etime-multi-wait-available-unsubmitted
[384.692664] Subtest etime-multi-wait-available-unsubmitted: SUCCESS (0.105s)
[384.768708] [062/133] (636s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-wc)
[384.844297] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-wc
[384.848766] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[384.939826] [063/133] (636s left) kms_vblank (ts-continuation-modeset-hang)
[385.019707] Starting subtest: ts-continuation-modeset-hang
[385.021906] Starting dynamic subtest: pipe-A-eDP-1
[386.839499] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.818s)
[386.853740] Starting dynamic subtest: pipe-D-eDP-1
[389.357568] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.517s)
[389.359928] Subtest ts-continuation-modeset-hang: SUCCESS (4.339s)
[390.159751] [064/133] (631s left) kms_cursor_crc (cursor-random-64x64)
[390.322250] Starting subtest: cursor-random-64x64
[390.327211] Starting dynamic subtest: pipe-A-eDP-1
[393.658003] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.332s)
[393.688346] Starting dynamic subtest: pipe-D-eDP-1
[397.637295] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.956s)
[397.639466] Subtest cursor-random-64x64: SUCCESS (7.315s)
[398.511983] [065/133] (623s left) kms_plane (plane-panning-bottom-right)
[398.586744] Starting subtest: plane-panning-bottom-right
[398.589072] Starting dynamic subtest: pipe-A
[400.258755] Dynamic subtest pipe-A: SUCCESS (1.670s)
[400.270524] Starting dynamic subtest: pipe-B
[402.544066] Dynamic subtest pipe-B: SUCCESS (2.285s)
[402.546379] Subtest plane-panning-bottom-right: SUCCESS (3.959s)
[403.354500] [066/133] (618s left) kms_plane_cursor (primary)
[403.422888] Starting subtest: primary
[403.427695] Starting dynamic subtest: pipe-A-eDP-1-size-64
[405.972756] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.550s)
[405.998106] Starting dynamic subtest: pipe-A-eDP-1-size-128
[408.529183] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.539s)
[408.558628] Starting dynamic subtest: pipe-A-eDP-1-size-256
[411.150540] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.605s)
[411.475139] Starting dynamic subtest: pipe-B-eDP-1-size-64
[414.741638] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.278s)
[414.767216] Starting dynamic subtest: pipe-B-eDP-1-size-128
[417.320689] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.561s)
[417.346247] Starting dynamic subtest: pipe-B-eDP-1-size-256
[419.877699] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.539s)
[420.195286] Starting dynamic subtest: pipe-C-eDP-1-size-64
[423.075890] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.893s)
[423.103100] Starting dynamic subtest: pipe-C-eDP-1-size-128
[425.275934] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.181s)
[425.303537] Starting dynamic subtest: pipe-C-eDP-1-size-256
[427.486987] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.192s)
[427.811408] Starting dynamic subtest: pipe-D-eDP-1-size-64
[430.704906] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.906s)
[430.732649] Starting dynamic subtest: pipe-D-eDP-1-size-128
[432.916177] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.192s)
[432.944574] Starting dynamic subtest: pipe-D-eDP-1-size-256
[435.171964] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.236s)
[435.481645] Subtest primary: SUCCESS (32.060s)
[436.272609] [067/133] (585s left) kms_properties (invalid-properties-atomic)
[436.344329] Starting subtest: invalid-properties-atomic
[436.616259] Subtest invalid-properties-atomic: SUCCESS (0.272s)
[436.687786] [068/133] (585s left) i915_hangman (detector)
[436.753225] Starting subtest: detector
[436.755074] Starting dynamic subtest: rcs0
[447.815865] Dynamic subtest rcs0: SUCCESS (11.061s)
[447.828446] Starting dynamic subtest: bcs0
[458.822454] Dynamic subtest bcs0: SUCCESS (11.003s)
[458.836704] Starting dynamic subtest: vcs0
[470.726871] Dynamic subtest vcs0: SUCCESS (11.901s)
[470.742396] Starting dynamic subtest: vcs1
[481.735299] Dynamic subtest vcs1: SUCCESS (11.004s)
[481.748532] Starting dynamic subtest: vecs0
[492.806294] Dynamic subtest vecs0: SUCCESS (11.066s)
[492.819171] Starting dynamic subtest: ccs0
[503.814995] Dynamic subtest ccs0: SUCCESS (11.004s)
[503.818312] Subtest detector: SUCCESS (67.055s)
[503.945501] [069/133] (517s left) gem_exec_balancer (full-late-pulse)
[504.012833] Starting subtest: full-late-pulse
[508.649275] Subtest full-late-pulse: SUCCESS (4.635s)
[508.947191] [070/133] (512s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[509.037436] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[509.042178] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[509.128068] [071/133] (512s left) gem_ctx_bad_destroy (invalid-default-ctx)
[509.185099] Starting subtest: invalid-default-ctx
[509.187547] Subtest invalid-default-ctx: SUCCESS (0.000s)
[509.273831] [072/133] (512s left) kms_psr (fbc-pr-basic)
[509.357011] Starting subtest: fbc-pr-basic
[509.361408] Subtest fbc-pr-basic: SKIP (0.000s)
[509.432788] [073/133] (512s left) kms_vblank (query-busy-hang)
[509.505735] Starting subtest: query-busy-hang
[509.507891] Starting dynamic subtest: pipe-A-eDP-1
[535.592960] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.080s)
[535.607837] Starting dynamic subtest: pipe-D-eDP-1
[550.580181] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.983s)
[550.582669] Subtest query-busy-hang: SUCCESS (41.067s)
[551.451456] [074/133] (470s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[551.541542] Starting subtest: multiplane-rotation-cropping-top
[568.980958] Subtest multiplane-rotation-cropping-top: SUCCESS (17.438s)
[569.094902] [075/133] (453s left) kms_rotation_crc (primary-rotation-90)
[569.172169] Starting subtest: primary-rotation-90
[569.176012] Subtest primary-rotation-90: SKIP (0.001s)
[569.242081] [076/133] (452s left) kms_addfb_basic (basic-y-tiled-legacy)
[569.294886] Starting subtest: basic-y-tiled-legacy
[569.298832] Subtest basic-y-tiled-legacy: SKIP (0.000s)
[569.408164] [077/133] (452s left) gem_exec_reloc (basic-active)
[569.459610] Subtest basic-active: SKIP (0.000s)
[569.530830] [078/133] (452s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[569.580071] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[569.582062] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[569.676735] [079/133] (452s left) kms_psr (pr-sprite-plane-onoff)
[569.756922] Starting subtest: pr-sprite-plane-onoff
[569.760763] Subtest pr-sprite-plane-onoff: SKIP (0.000s)
[569.835241] [080/133] (452s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[569.880598] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[569.988615] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.107s)
[570.066509] [081/133] (452s left) gem_spin_batch (legacy)
[570.128275] Starting subtest: legacy
[570.130830] Starting dynamic subtest: default
[573.367248] Dynamic subtest default: SUCCESS (3.233s)
[573.378642] Starting dynamic subtest: render
[576.654605] Dynamic subtest render: SUCCESS (3.231s)
[576.666362] Starting dynamic subtest: bsd
[579.891766] Dynamic subtest bsd: SUCCESS (3.234s)
[579.903420] Starting dynamic subtest: bsd1
[583.128483] Dynamic subtest bsd1: SUCCESS (3.233s)
[583.141130] Starting dynamic subtest: bsd2
[586.363247] Dynamic subtest bsd2: SUCCESS (3.230s)
[586.374760] Starting dynamic subtest: blt
[589.602391] Dynamic subtest blt: SUCCESS (3.235s)
[589.615255] Starting dynamic subtest: vebox
[592.838476] Dynamic subtest vebox: SUCCESS (3.231s)
[592.841254] Subtest legacy: SUCCESS (22.650s)
[594.979502] [082/133] (427s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[595.069364] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[595.073645] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[595.166217] [083/133] (427s left) gem_workarounds (suspend-resume)
[595.231780] Starting subtest: suspend-resume
[611.164711] Subtest suspend-resume: SUCCESS (1.208s)
[611.292528] [084/133] (411s left) perf (oa-exponents)
[611.387296] Starting subtest: oa-exponents
[611.389702] Starting dynamic subtest: 0-rcs0
[613.205254] Dynamic subtest 0-rcs0: SUCCESS (1.775s)
[613.215765] Starting dynamic subtest: 1-vecs0
[615.073415] Dynamic subtest 1-vecs0: SUCCESS (1.828s)
[615.076319] Subtest oa-exponents: SUCCESS (3.605s)
[615.120796] [085/133] (407s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[615.206737] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[615.209043] Starting dynamic subtest: pipe-A
[615.229832] Dynamic subtest pipe-A: SKIP (0.017s)
[615.240063] Starting dynamic subtest: pipe-B
[615.260991] Dynamic subtest pipe-B: SKIP (0.030s)
[615.271054] Starting dynamic subtest: pipe-C
[615.290063] Dynamic subtest pipe-C: SKIP (0.029s)
[615.300109] Starting dynamic subtest: pipe-D
[615.321123] Dynamic subtest pipe-D: SKIP (0.029s)
[615.324998] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.109s)
[615.425807] [086/133] (407s left) kms_big_fb (linear-16bpp-rotate-270)
[615.506286] Starting subtest: linear-16bpp-rotate-270
[615.530410] Subtest linear-16bpp-rotate-270: SKIP (0.020s)
[615.648375] [087/133] (407s left) api_intel_bb (bb-with-allocator)
[615.708253] Starting subtest: bb-with-allocator
[615.711976] Subtest bb-with-allocator: SUCCESS (0.006s)
[615.829860] [088/133] (406s left) gem_exec_store (basic)
[615.897818] Starting subtest: basic
[615.995113] Subtest basic: SUCCESS (0.098s)
[616.192852] [089/133] (406s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling)
[616.276941] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling
[616.281491] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s)
[616.367174] [090/133] (406s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[616.441411] Starting subtest: psr-rgb565-draw-mmap-wc
[623.246355] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.738s)
[623.349018] [091/133] (399s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[623.436431] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[623.440390] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[623.547845] [092/133] (399s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[623.644198] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[624.451729] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.804s)
[624.607445] [093/133] (398s left) gem_fence_thrash (bo-write-verify-x)
[624.641090] Starting subtest: bo-write-verify-x
[624.666118] Subtest bo-write-verify-x: SKIP (0.021s)
[624.734685] [094/133] (398s left) syncobj_timeline (device-submit-unordered)
[624.794754] Starting subtest: device-submit-unordered
[624.796667] Subtest device-submit-unordered: SUCCESS (0.001s)
[624.887351] [095/133] (398s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[624.978822] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[632.275506] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.275s)
[632.392094] [096/133] (390s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[632.477478] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[632.583530] [097/133] (390s left) perf (invalid-create-userspace-config)
[632.678830] Starting subtest: invalid-create-userspace-config
[632.681238] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[632.724375] [098/133] (390s left) kms_psr (psr2-cursor-plane-onoff)
[632.806683] Starting subtest: psr2-cursor-plane-onoff
[632.808978] Starting dynamic subtest: eDP-1
[634.467288] Dynamic subtest eDP-1: SUCCESS (1.655s)
[634.469628] Subtest psr2-cursor-plane-onoff: SUCCESS (1.656s)
[635.268409] [099/133] (387s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[635.366027] Starting subtest: basic-flip-after-cursor-atomic
[636.285738] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.919s)
[637.094983] [100/133] (386s left) gem_workarounds (reset-context)
[637.163600] Starting subtest: reset-context
[637.332198] Subtest reset-context: SUCCESS (0.167s)
[637.452206] [101/133] (385s left) i915_selftest (perf)
[641.954503] Starting subtest: perf
[641.957261] Starting dynamic subtest: engine_cs
[644.656442] Dynamic subtest engine_cs: SUCCESS (2.699s)
[644.665765] Starting dynamic subtest: request
[651.930338] Dynamic subtest request: SUCCESS (7.257s)
[651.940467] Starting dynamic subtest: migrate
[654.752175] Dynamic subtest migrate: SUCCESS (2.833s)
[654.761097] Starting dynamic subtest: region
[659.072293] Dynamic subtest region: SUCCESS (4.317s)
[659.074861] Subtest perf: SUCCESS (17.115s)
[659.102408] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[659.103119] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[659.106312] [102/133] (364s left) gen3_render_linear_blits
[660.635158] [FACT gen3_render_linear_blits] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[660.636589] [FACT gen3_render_linear_blits] new: kernel.kmod_is_loaded.i915: true
[660.640584] [103/133] (362s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[660.710298] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[660.714730] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[660.904757] [104/133] (362s left) perf_pmu (idle)
[660.942089] Starting subtest: idle
[660.944494] Starting dynamic subtest: rcs0
[661.489090] Dynamic subtest rcs0: SUCCESS (0.544s)
[661.499826] Starting dynamic subtest: bcs0
[662.017559] Dynamic subtest bcs0: SUCCESS (0.525s)
[662.029727] Starting dynamic subtest: vcs0
[662.541816] Dynamic subtest vcs0: SUCCESS (0.522s)
[662.552608] Starting dynamic subtest: vcs1
[663.057230] Dynamic subtest vcs1: SUCCESS (0.513s)
[663.065741] Starting dynamic subtest: vecs0
[663.591877] Dynamic subtest vecs0: SUCCESS (0.527s)
[663.603364] Starting dynamic subtest: ccs0
[664.114975] Dynamic subtest ccs0: SUCCESS (0.525s)
[664.117988] Subtest idle: SUCCESS (3.172s)
[664.228912] [105/133] (359s left) kms_dp_link_training (uhbr-mst)
[664.314807] Starting subtest: uhbr-mst
[664.323022] Subtest uhbr-mst: SKIP (0.000s)
[664.398432] [106/133] (359s left) gem_render_copy (x-tiled)
[664.446261] Starting subtest: x-tiled
[664.448517] Starting dynamic subtest: smem
[664.490385] Dynamic subtest smem: SUCCESS (0.043s)
[664.493582] Subtest x-tiled: SUCCESS (0.044s)
[664.728551] [107/133] (358s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move)
[664.802543] Starting subtest: psr-2p-scndscrn-cur-indfb-move
[664.806829] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[664.908387] [108/133] (358s left) kms_cursor_crc (cursor-onscreen-32x10)
[665.051509] Starting subtest: cursor-onscreen-32x10
[665.098458] Subtest cursor-onscreen-32x10: SKIP (0.044s)
[665.346770] [109/133] (358s left) gem_exec_schedule (semaphore-power)
[665.525032] Subtest semaphore-power: SKIP (0.000s)
[665.700744] [110/133] (357s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[665.783070] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[665.787201] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[665.883929] [111/133] (357s left) kms_draw_crc (draw-method-blt)
[665.969641] Starting subtest: draw-method-blt
[665.971951] Starting dynamic subtest: xrgb8888-untiled
[666.223905] Dynamic subtest xrgb8888-untiled: SUCCESS (0.255s)
[666.235367] Starting dynamic subtest: rgb565-untiled
[666.457078] Dynamic subtest rgb565-untiled: SUCCESS (0.230s)
[666.466476] Starting dynamic subtest: xrgb2101010-untiled
[666.679353] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.220s)
[666.690448] Starting dynamic subtest: xbgr16161616f-untiled
[666.923405] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.241s)
[666.932087] Starting dynamic subtest: xrgb8888-xtiled
[667.045587] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[667.054665] Starting dynamic subtest: rgb565-xtiled
[667.168089] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[667.177050] Starting dynamic subtest: xrgb2101010-xtiled
[667.279098] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[667.287599] Starting dynamic subtest: xbgr16161616f-xtiled
[667.390212] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.110s)
[667.398640] Starting dynamic subtest: xrgb8888-4tiled
[667.501492] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.111s)
[667.515933] Starting dynamic subtest: rgb565-4tiled
[667.623697] Dynamic subtest rgb565-4tiled: SUCCESS (0.120s)
[667.632930] Starting dynamic subtest: xrgb2101010-4tiled
[667.734981] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.109s)
[667.745908] Starting dynamic subtest: xbgr16161616f-4tiled
[667.857212] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.120s)
[667.859192] Subtest draw-method-blt: SUCCESS (1.890s)
[667.973574] [112/133] (355s left) kms_content_protection (type1)
[668.101848] Starting subtest: type1
[668.105889] Subtest type1: SKIP (0.000s)
[668.217731] [113/133] (355s left) perf (non-zero-reason)
[668.339557] Starting subtest: non-zero-reason
[668.341582] Starting dynamic subtest: 0-rcs0
[673.723646] Dynamic subtest 0-rcs0: SUCCESS (5.327s)
[673.735211] Starting dynamic subtest: 1-vcs1
[677.546300] Dynamic subtest 1-vcs1: SUCCESS (3.874s)
[677.549334] Subtest non-zero-reason: SUCCESS (9.202s)
[677.595281] [114/133] (346s left) kms_hdr (invalid-metadata-sizes)
[677.691921] Starting subtest: invalid-metadata-sizes
[677.698709] Subtest invalid-metadata-sizes: SKIP (0.001s)
[677.775287] [115/133] (346s left) kms_ccs (random-ccs-data-yf-tiled-ccs)
[677.851008] Starting subtest: random-ccs-data-yf-tiled-ccs
[677.853401] Starting dynamic subtest: pipe-A-eDP-1
[677.879778] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[677.891787] Starting dynamic subtest: pipe-B-eDP-1
[677.914958] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[677.924149] Starting dynamic subtest: pipe-C-eDP-1
[677.948570] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[677.958853] Starting dynamic subtest: pipe-D-eDP-1
[677.983107] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[677.987256] Subtest random-ccs-data-yf-tiled-ccs: SKIP (0.004s)
[680.137008] [116/133] (343s left) kms_async_flips (test-time-stamp)
[680.201122] Starting subtest: test-time-stamp
[680.577366] Starting dynamic subtest: pipe-A-eDP-1
[681.331846] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.753s)
[681.723666] Starting dynamic subtest: pipe-B-eDP-1
[682.476176] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.757s)
[682.858889] Starting dynamic subtest: pipe-C-eDP-1
[683.620435] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.765s)
[683.985569] Starting dynamic subtest: pipe-D-eDP-1
[684.749428] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.768s)
[684.751758] Subtest test-time-stamp: SUCCESS (4.548s)
[685.903825] [117/133] (338s left) api_intel_bb (intel-bb-blit-y)
[685.965293] Starting subtest: intel-bb-blit-y
[689.315849] Subtest intel-bb-blit-y: SUCCESS (3.350s)
[689.422919] [118/133] (334s left) fbdev (eof)
[689.468952] Starting subtest: eof
[689.471356] Subtest eof: SUCCESS (0.000s)
[689.541171] [119/133] (334s left) gem_userptr_blits (invalid-mmap-offset-unsync)
[689.604850] Subtest invalid-mmap-offset-unsync: SKIP (0.000s)
[689.735068] [120/133] (334s left) prime_mmap (test_refcounting)
[689.784016] Starting subtest: test_refcounting
[689.785976] Starting dynamic subtest: test_refcounting-smem
[689.812430] Dynamic subtest test_refcounting-smem: SUCCESS (0.031s)
[689.814916] Subtest test_refcounting: SUCCESS (0.031s)
[689.926840] [121/133] (334s left) gem_ctx_isolation (clean)
[689.983164] Starting subtest: clean
[689.985471] Starting dynamic subtest: rcs0
[690.031627] Dynamic subtest rcs0: SUCCESS (0.051s)
[690.041262] Starting dynamic subtest: bcs0
[690.072615] Dynamic subtest bcs0: SUCCESS (0.040s)
[690.084558] Starting dynamic subtest: vcs0
[690.107850] Dynamic subtest vcs0: SUCCESS (0.034s)
[690.118520] Starting dynamic subtest: vcs1
[691.593172] Dynamic subtest vcs1: SUCCESS (1.483s)
[691.604672] Starting dynamic subtest: vecs0
[693.571739] Dynamic subtest vecs0: SUCCESS (1.977s)
[693.583008] Starting dynamic subtest: ccs0
[695.539377] Dynamic subtest ccs0: SUCCESS (1.966s)
[695.541896] Subtest clean: SUCCESS (5.557s)
[695.776593] [122/133] (328s left) kms_pm_dc (dc5-psr)
[696.878629] Starting subtest: dc5-psr
[699.772697] Subtest dc5-psr: SUCCESS (2.896s)
[700.639725] [123/133] (323s left) gem_exec_capture (capture)
[700.712623] Starting subtest: capture
[700.714964] Starting dynamic subtest: rcs0-smem
[701.561518] Dynamic subtest rcs0-smem: SUCCESS (0.847s)
[701.576357] Starting dynamic subtest: bcs0-smem
[702.391574] Dynamic subtest bcs0-smem: SUCCESS (0.830s)
[702.402577] Starting dynamic subtest: vcs0-smem
[703.225463] Dynamic subtest vcs0-smem: SUCCESS (0.835s)
[703.237307] Starting dynamic subtest: vcs1-smem
[704.558503] Dynamic subtest vcs1-smem: SUCCESS (1.331s)
[704.567954] Starting dynamic subtest: vecs0-smem
[705.452557] Dynamic subtest vecs0-smem: FAIL (0.822s)
[705.462592] Starting dynamic subtest: ccs0-smem
[706.719365] Dynamic subtest ccs0-smem: SUCCESS (1.334s)
[706.721786] Subtest capture: FAIL (6.003s)
[706.837981] [124/133] (317s left) gem_userptr_blits (invalid-null-pointer)
[706.905269] Starting subtest: invalid-null-pointer
[706.907323] Subtest invalid-null-pointer: SUCCESS (0.001s)
[707.054976] [125/133] (317s left) gem_eio (wait-wedge-1us)
[707.254785] Starting subtest: wait-wedge-1us
[708.659208] Subtest wait-wedge-1us: SUCCESS (1.405s)
[708.797974] [126/133] (315s left) kms_cursor_edge_walk (256x256-top-bottom)
[708.877416] Starting subtest: 256x256-top-bottom
[708.879698] Starting dynamic subtest: pipe-A-eDP-1
[712.315457] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.440s)
[712.325584] Starting dynamic subtest: pipe-D-eDP-1
[715.441760] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.124s)
[715.444153] Subtest 256x256-top-bottom: SUCCESS (6.567s)
[716.250509] [127/133] (308s left) gem_create (create-ext-set-pat)
[716.414705] Starting subtest: create-ext-set-pat
[716.417280] Subtest create-ext-set-pat: SUCCESS (0.001s)
[716.526292] [128/133] (307s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[716.604465] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[716.609728] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.000s)
[716.685172] [129/133] (307s left) syncobj_wait (single-wait-submitted)
[716.732284] Starting subtest: single-wait-submitted
[716.833241] Subtest single-wait-submitted: SUCCESS (0.102s)
[716.910332] [130/133] (307s left) gem_exec_reloc (basic-range-active)
[716.971162] Subtest basic-range-active: SKIP (0.000s)
[717.051573] [131/133] (307s left) kms_flip (bo-too-big)
[717.128405] Starting subtest: bo-too-big
[717.130635] Starting dynamic subtest: A-eDP1
[718.433198] Dynamic subtest A-eDP1: SUCCESS (1.304s)
[718.444162] Starting dynamic subtest: B-eDP1
[719.724289] Dynamic subtest B-eDP1: SUCCESS (1.293s)
[719.736290] Starting dynamic subtest: C-eDP1
[721.070289] Dynamic subtest C-eDP1: SUCCESS (1.345s)
[721.082629] Starting dynamic subtest: D-eDP1
[722.394684] Dynamic subtest D-eDP1: SUCCESS (1.323s)
[722.397003] Subtest bo-too-big: SUCCESS (5.273s)
[723.641090] [132/133] (300s left) gem_ctx_persistence (heartbeat-hang)
[723.754919] Starting subtest: heartbeat-hang
[723.759644] Subtest heartbeat-hang: SKIP (0.000s)
[724.041660] [133/133] (300s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[724.167286] Starting subtest: pr-plane-move-sf-dmg-area
[724.171306] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[724.256286] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: core_getversion
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: gem_exec_fence
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: kms_psr2_sf
results: parsing output: 6/ for test: kms_flip
results: parsing output: 7/ for test: kms_plane
results: parsing output: 8/ for test: kms_joiner
results: parsing output: 9/ for test: gem_gpgpu_fill
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_ccs
results: parsing output: 12/ for test: gem_mmap_wc
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: gem_exec_schedule
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_busy
results: parsing output: 17/ for test: gem_exec_flush
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: syncobj_timeline
results: parsing output: 20/ for test: gem_eio
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gen9_exec_parse
results: parsing output: 23/ for test: gem_ctx_shared
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_addfb_basic
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: kms_chamelium_frames
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: i915_drm_fdinfo
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: gem_mmap_offset
results: parsing output: 33/ for test: gem_pipe_control_store_loop
results: parsing output: 34/ for test: syncobj_timeline
results: parsing output: 35/ for test: kms_pm_rpm
results: parsing output: 36/ for test: kms_plane_alpha_blend
results: parsing output: 37/ for test: perf
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_pipe_b_c_ivb
results: parsing output: 41/ for test: syncobj_wait
results: parsing output: 42/ for test: kms_flip
results: parsing output: 43/ for test: syncobj_wait
results: parsing output: 44/ for test: kms_chamelium_hpd
results: parsing output: 45/ for test: prime_busy
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_lease
results: parsing output: 48/ for test: gem_lmem_swapping
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: kms_color_pipeline
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: gem_render_copy
results: parsing output: 53/ for test: i915_pm_rps
results: parsing output: 54/ for test: syncobj_basic
results: parsing output: 55/ for test: syncobj_timeline
results: parsing output: 56/ for test: gem_eio
results: parsing output: 57/ for test: kms_big_fb
results: parsing output: 58/ for test: kms_flip
results: parsing output: 59/ for test: kms_getfb
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_vblank
results: parsing output: 63/ for test: kms_cursor_crc
results: parsing output: 64/ for test: kms_plane
results: parsing output: 65/ for test: kms_plane_cursor
results: parsing output: 66/ for test: kms_properties
results: parsing output: 67/ for test: i915_hangman
results: parsing output: 68/ for test: gem_exec_balancer
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: gem_ctx_bad_destroy
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: kms_vblank
results: parsing output: 73/ for test: kms_rotation_crc
results: parsing output: 74/ for test: kms_rotation_crc
results: parsing output: 75/ for test: kms_addfb_basic
results: parsing output: 76/ for test: gem_exec_reloc
results: parsing output: 77/ for test: syncobj_timeline
results: parsing output: 78/ for test: kms_psr
results: parsing output: 79/ for test: syncobj_wait
results: parsing output: 80/ for test: gem_spin_batch
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: gem_workarounds
results: parsing output: 83/ for test: perf
results: parsing output: 84/ for test: kms_plane_scaling
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: api_intel_bb
results: parsing output: 87/ for test: gem_exec_store
results: parsing output: 88/ for test: kms_flip_scaled_crc
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: gem_fence_thrash
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_addfb_basic
results: parsing output: 96/ for test: perf
results: parsing output: 97/ for test: kms_psr
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: gem_workarounds
results: parsing output: 100/ for test: i915_selftest
results: parsing output: 101/ for test: gen3_render_linear_blits
results: parsing output: 102/ for test: gem_render_copy
results: parsing output: 103/ for test: perf_pmu
results: parsing output: 104/ for test: kms_dp_link_training
results: parsing output: 105/ for test: gem_render_copy
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_cursor_crc
results: parsing output: 108/ for test: gem_exec_schedule
results: parsing output: 109/ for test: kms_big_fb
results: parsing output: 110/ for test: kms_draw_crc
results: parsing output: 111/ for test: kms_content_protection
results: parsing output: 112/ for test: perf
results: parsing output: 113/ for test: kms_hdr
results: parsing output: 114/ for test: kms_ccs
results: parsing output: 115/ for test: kms_async_flips
results: parsing output: 116/ for test: api_intel_bb
results: parsing output: 117/ for test: fbdev
results: parsing output: 118/ for test: gem_userptr_blits
results: parsing output: 119/ for test: prime_mmap
results: parsing output: 120/ for test: gem_ctx_isolation
results: parsing output: 121/ for test: kms_pm_dc
results: parsing output: 122/ for test: gem_exec_capture
results: parsing output: 123/ for test: gem_userptr_blits
results: parsing output: 124/ for test: gem_eio
results: parsing output: 125/ for test: kms_cursor_edge_walk
results: parsing output: 126/ for test: gem_create
results: parsing output: 127/ for test: kms_flip_scaled_crc
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: gem_exec_reloc
results: parsing output: 130/ for test: kms_flip
results: parsing output: 131/ for test: gem_ctx_persistence
results: parsing output: 132/ for test: kms_psr2_sf
Done.