[57.421644] Initializing watchdogs
[57.421780]   /dev/watchdog0
[57.437450] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.441952] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.446558] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.450195] [001/156] (960s left) kms_cursor_crc (cursor-sliding-512x512)
[57.740832] Starting subtest: cursor-sliding-512x512
[57.744667] Subtest cursor-sliding-512x512: SKIP (0.001s)
[57.893040] [002/156] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[57.990899] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[64.984173] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (7.000s)
[65.117247] [003/156] (952s left) gem_ctx_persistence (legacy-engines-hostile)
[65.172767] Starting subtest: legacy-engines-hostile
[65.175232] Starting dynamic subtest: render
[65.293944] Dynamic subtest render: SUCCESS (0.120s)
[65.303932] Starting dynamic subtest: bsd1
[65.402874] Dynamic subtest bsd1: SUCCESS (0.108s)
[65.413760] Starting dynamic subtest: bsd2
[65.471759] Dynamic subtest bsd2: SUCCESS (0.067s)
[65.479780] Starting dynamic subtest: blt
[65.612624] Dynamic subtest blt: SUCCESS (0.139s)
[65.623072] Starting dynamic subtest: vebox
[65.705669] Dynamic subtest vebox: SUCCESS (0.092s)
[65.708150] Subtest legacy-engines-hostile: SUCCESS (0.533s)
[65.783110] [004/156] (952s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size)
[65.910614] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size
[97.628695] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (31.716s)
[98.515981] [005/156] (919s left) kms_properties (crtc-properties-legacy)
[98.612158] Starting subtest: crtc-properties-legacy
[98.614428] Starting dynamic subtest: pipe-A-eDP-1
[99.066068] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.455s)
[99.074222] Starting dynamic subtest: pipe-B-eDP-1
[100.305099] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.237s)
[100.313487] Starting dynamic subtest: pipe-C-eDP-1
[101.528289] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.224s)
[101.537084] Starting dynamic subtest: pipe-D-eDP-1
[102.662489] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.133s)
[102.664804] Subtest crtc-properties-legacy: SUCCESS (4.054s)
[103.463201] [006/156] (914s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[103.569746] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[103.574168] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[103.686743] [007/156] (914s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[103.781649] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[103.785935] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[103.877532] [008/156] (914s left) gem_eio (in-flight-internal-1us)
[104.049072] Starting subtest: in-flight-internal-1us
[105.321204] Subtest in-flight-internal-1us: SUCCESS (1.272s)
[105.465981] [009/156] (912s left) gem_ctx_persistence (engines-mixed)
[105.530092] Starting subtest: engines-mixed
[105.531929] Starting dynamic subtest: rcs0
[105.600972] Dynamic subtest rcs0: SUCCESS (0.072s)
[105.611167] Starting dynamic subtest: bcs0
[105.664719] Dynamic subtest bcs0: SUCCESS (0.063s)
[105.674649] Starting dynamic subtest: vcs0
[105.714721] Dynamic subtest vcs0: SUCCESS (0.049s)
[105.724429] Starting dynamic subtest: vcs1
[105.776069] Dynamic subtest vcs1: SUCCESS (0.061s)
[105.786355] Starting dynamic subtest: vecs0
[105.830356] Dynamic subtest vecs0: SUCCESS (0.053s)
[105.841544] Starting dynamic subtest: ccs0
[105.898941] Dynamic subtest ccs0: SUCCESS (0.068s)
[105.901597] Subtest engines-mixed: SUCCESS (0.371s)
[105.972856] [010/156] (912s left) gem_exec_schedule (promotion)
[106.044938] Starting subtest: promotion
[106.046911] Starting dynamic subtest: rcs0
[106.147093] Dynamic subtest rcs0: SUCCESS (0.105s)
[106.157705] Starting dynamic subtest: vcs0
[106.233975] Dynamic subtest vcs0: SUCCESS (0.087s)
[106.245405] Starting dynamic subtest: vcs1
[106.318832] Dynamic subtest vcs1: SUCCESS (0.085s)
[106.329647] Starting dynamic subtest: vecs0
[106.403366] Dynamic subtest vecs0: SUCCESS (0.084s)
[106.411700] Starting dynamic subtest: ccs0
[106.505213] Dynamic subtest ccs0: SUCCESS (0.101s)
[106.507428] Subtest promotion: SUCCESS (0.466s)
[107.347782] [011/156] (910s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-blt)
[107.452898] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-blt
[107.457136] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[107.562293] [012/156] (910s left) kms_pm_dc (dc5-psr)
[108.667052] Starting subtest: dc5-psr
[111.537611] Subtest dc5-psr: SUCCESS (2.870s)
[112.404155] [013/156] (906s left) gem_madvise (dontneed-before-exec)
[112.439347] Starting subtest: dontneed-before-exec
[112.453792] Subtest dontneed-before-exec: SKIP (0.013s)
[112.513714] [014/156] (905s left) gem_exec_flush (basic-wb-set-default)
[112.582700] Starting subtest: basic-wb-set-default
[118.065118] Subtest basic-wb-set-default: SUCCESS (5.455s)
[118.232983] [015/156] (900s left) kms_dsc (dsc-with-bpc)
[118.321903] Starting subtest: dsc-with-bpc
[118.353432] Subtest dsc-with-bpc: SKIP (0.001s)
[118.405627] [016/156] (900s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[118.490145] Starting subtest: plane-upscale-factor-0-25-with-rotation
[118.492479] Starting dynamic subtest: pipe-A
[118.714587] Dynamic subtest pipe-A: SUCCESS (0.227s)
[118.722699] Starting dynamic subtest: pipe-B
[119.988569] Dynamic subtest pipe-B: SUCCESS (1.274s)
[119.998169] Starting dynamic subtest: pipe-C
[121.321967] Dynamic subtest pipe-C: SUCCESS (1.333s)
[121.330303] Starting dynamic subtest: pipe-D
[122.666998] Dynamic subtest pipe-D: SUCCESS (1.345s)
[122.669471] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.179s)
[123.834250] [017/156] (894s left) kms_pipe_stress (stress-xrgb8888-4tiled)
[123.937610] Starting subtest: stress-xrgb8888-4tiled
[129.799542] Subtest stress-xrgb8888-4tiled: SUCCESS (5.863s)
[129.846804] [018/156] (888s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[129.948453] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[129.952039] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[130.045295] [019/156] (888s left) gem_exec_reloc (basic-write-read-noreloc)
[130.100053] Subtest basic-write-read-noreloc: SKIP (0.000s)
[130.163210] [020/156] (888s left) gem_lmem_swapping (basic)
[130.204316] Subtest basic: SKIP (0.000s)
[130.239081] [021/156] (888s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[130.625911] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[131.456810] [022/156] (887s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-cpu)
[131.546766] Starting subtest: hdr-rgb565-draw-mmap-cpu
[131.551096] Subtest hdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[131.635620] [023/156] (887s left) i915_suspend (debugfs-reader)
[131.681927] Starting subtest: debugfs-reader
[149.525256] Subtest debugfs-reader: SUCCESS (3.247s)
[149.620259] [024/156] (869s left) kms_color (ctm-green-to-red)
[149.720026] Starting subtest: ctm-green-to-red
[149.722995] Starting dynamic subtest: pipe-A-eDP-1
[150.477727] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.757s)
[150.486958] Starting dynamic subtest: pipe-B-eDP-1
[151.878719] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.400s)
[151.888471] Starting dynamic subtest: pipe-C-eDP-1
[153.232383] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.355s)
[153.243511] Starting dynamic subtest: pipe-D-eDP-1
[154.569416] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.336s)
[154.572125] Subtest ctm-green-to-red: SUCCESS (4.852s)
[155.418331] [025/156] (863s left) kms_cursor_crc (cursor-random-256x256)
[155.650534] Starting subtest: cursor-random-256x256
[155.653050] Starting dynamic subtest: pipe-A-eDP-1
[158.999020] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.345s)
[159.026458] Starting dynamic subtest: pipe-D-eDP-1
[163.043608] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.024s)
[163.046553] Subtest cursor-random-256x256: SUCCESS (7.392s)
[163.901537] [026/156] (855s left) syncobj_timeline (wait-for-submit-snapshot)
[163.962947] Starting subtest: wait-for-submit-snapshot
[164.045411] Subtest wait-for-submit-snapshot: SUCCESS (0.084s)
[164.121413] [027/156] (854s left) gen3_render_tiledy_blits
[164.232384] [028/156] (854s left) gem_mmap_gtt (basic-small-copy-xy)
[164.275762] Subtest basic-small-copy-XY: SKIP (0.000s)
[164.343733] [029/156] (854s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-render)
[164.442793] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-render
[164.447847] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[164.536596] [030/156] (854s left) prime_vgem (sync)
[164.671188] Starting subtest: sync
[164.673430] Starting dynamic subtest: rcs0
[164.710693] Dynamic subtest rcs0: SUCCESS (0.041s)
[164.719842] Starting dynamic subtest: bcs0
[164.756585] Dynamic subtest bcs0: SUCCESS (0.045s)
[164.765869] Starting dynamic subtest: vcs0
[164.805256] Dynamic subtest vcs0: SUCCESS (0.048s)
[164.814529] Starting dynamic subtest: vcs1
[164.854846] Dynamic subtest vcs1: SUCCESS (0.049s)
[164.865947] Starting dynamic subtest: vecs0
[164.898872] Dynamic subtest vecs0: SUCCESS (0.043s)
[164.907889] Starting dynamic subtest: ccs0
[164.947777] Dynamic subtest ccs0: SUCCESS (0.048s)
[164.950019] Subtest sync: SUCCESS (0.280s)
[165.063710] [FACT prime_vgem (sync)] new: kernel.kmod_is_loaded.vgem: true
[165.067619] [031/156] (854s left) kms_plane_scaling (planes-downscale-factor-0-5)
[165.163825] Starting subtest: planes-downscale-factor-0-5
[165.166270] Starting dynamic subtest: pipe-A
[165.183712] Dynamic subtest pipe-A: SKIP (0.017s)
[165.193519] Starting dynamic subtest: pipe-B
[165.214014] Dynamic subtest pipe-B: SKIP (0.029s)
[165.223282] Starting dynamic subtest: pipe-C
[165.244530] Dynamic subtest pipe-C: SKIP (0.029s)
[165.253881] Starting dynamic subtest: pipe-D
[165.272877] Dynamic subtest pipe-D: SKIP (0.029s)
[165.276841] Subtest planes-downscale-factor-0-5: SKIP (0.105s)
[165.370143] [032/156] (853s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[165.464747] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[165.467026] Starting dynamic subtest: pipe-A-eDP-1
[165.558384] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[165.568842] Starting dynamic subtest: pipe-B-eDP-1
[166.685404] Dynamic subtest pipe-B-eDP-1: SKIP (1.123s)
[166.690780] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.225s)
[167.870606] [033/156] (851s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs)
[167.981738] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs
[167.984501] Starting dynamic subtest: pipe-A-eDP-1
[168.011128] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[168.021419] Starting dynamic subtest: pipe-B-eDP-1
[168.046960] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[168.055795] Starting dynamic subtest: pipe-C-eDP-1
[168.082462] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[168.091840] Starting dynamic subtest: pipe-D-eDP-1
[168.117744] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[168.122695] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[170.236279] [034/156] (849s left) i915_pm_freq_api (freq-suspend)
[170.297888] Starting subtest: freq-suspend
[170.300904] Starting dynamic subtest: gt0
[186.494209] Dynamic subtest gt0: SUCCESS (1.211s)
[186.506776] Starting dynamic subtest: gt1
[202.519267] Dynamic subtest gt1: SUCCESS (1.189s)
[202.522076] Subtest freq-suspend: SUCCESS (2.404s)
[203.586340] [035/156] (816s left) gem_mmap_gtt (hang-busy)
[203.649225] Subtest hang-busy: SKIP (0.000s)
[203.705909] [036/156] (816s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-blt)
[203.819328] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-blt
[203.825262] Subtest hdr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[203.916441] [037/156] (816s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[204.017274] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[204.022443] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.000s)
[204.097265] [038/156] (816s left) i915_drm_fdinfo (busy-check-all)
[204.136144] Starting subtest: busy-check-all
[204.138045] Starting dynamic subtest: rcs0
[204.142777] Dynamic subtest rcs0: SKIP (0.004s)
[204.154672] Starting dynamic subtest: bcs0
[204.159492] Dynamic subtest bcs0: SKIP (0.000s)
[204.169556] Starting dynamic subtest: vcs0
[204.174286] Dynamic subtest vcs0: SKIP (0.000s)
[204.190376] Starting dynamic subtest: vcs1
[204.197170] Dynamic subtest vcs1: SKIP (0.000s)
[204.208612] Starting dynamic subtest: vecs0
[204.213213] Dynamic subtest vecs0: SKIP (0.000s)
[204.221943] Starting dynamic subtest: ccs0
[204.227313] Dynamic subtest ccs0: SKIP (0.000s)
[204.232003] Subtest busy-check-all: SKIP (0.009s)
[204.273893] [039/156] (816s left) kms_joiner (invalid-modeset-force-big-joiner)
[204.392341] Starting subtest: invalid-modeset-force-big-joiner
[204.397903] Subtest invalid-modeset-force-big-joiner: SKIP (0.000s)
[204.456206] [040/156] (816s left) kms_big_fb (linear-addfb)
[204.550284] Starting subtest: linear-addfb
[204.552760] Subtest linear-addfb: SUCCESS (0.000s)
[204.635528] [041/156] (815s left) kms_psr (fbc-pr-primary-mmap-cpu)
[204.731206] Starting subtest: fbc-pr-primary-mmap-cpu
[204.735439] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[204.818017] [042/156] (815s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[204.926916] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[204.928963] Starting dynamic subtest: pipe-A-default-mode
[207.626784] Dynamic subtest pipe-A-default-mode: SUCCESS (2.700s)
[207.629397] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.702s)
[208.493040] [043/156] (812s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[208.597854] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[213.331253] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (4.740s)
[213.430718] [044/156] (807s left) gem_exec_whisper (basic-normal)
[213.498331] Starting subtest: basic-normal
[217.048115] Subtest basic-normal: SUCCESS (3.551s)
[217.232710] [045/156] (803s left) gem_ctx_sseu (mmap-args)
[217.296471] Subtest mmap-args: SKIP (0.000s)
[217.369728] [046/156] (803s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[217.466258] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[217.468863] Starting dynamic subtest: pipe-A-eDP-1
[217.494063] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[217.504311] Starting dynamic subtest: pipe-B-eDP-1
[217.531348] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[217.541289] Starting dynamic subtest: pipe-C-eDP-1
[217.567339] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[217.577067] Starting dynamic subtest: pipe-D-eDP-1
[217.603800] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[217.608669] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[219.794412] [047/156] (801s left) kms_vrr (flip-basic)
[219.899478] Starting subtest: flip-basic
[219.907611] Subtest flip-basic: SKIP (0.000s)
[219.970974] [048/156] (800s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-blt)
[220.066098] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-blt
[220.070647] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[220.163105] [049/156] (800s left) kms_frontbuffer_tracking (fbc-stridechange)
[220.262261] Starting subtest: fbc-stridechange
[223.591299] Subtest fbc-stridechange: SUCCESS (3.335s)
[224.725366] [050/156] (796s left) kms_prop_blob (blob-prop-lifetime)
[224.774450] Starting subtest: blob-prop-lifetime
[224.777104] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[224.866483] [051/156] (796s left) gem_exec_reloc (basic-cpu-gtt-active)
[224.917731] Subtest basic-cpu-gtt-active: SKIP (0.000s)
[224.985174] [052/156] (796s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[225.096737] Starting subtest: cursorB-vs-flipA-varying-size
[225.101466] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[225.245278] [053/156] (795s left) gem_exec_schedule (independent)
[225.319615] Starting subtest: independent
[225.321870] Starting dynamic subtest: rcs0
[226.730919] Dynamic subtest rcs0: SUCCESS (1.423s)
[226.743264] Starting dynamic subtest: vcs0
[228.660139] Dynamic subtest vcs0: SUCCESS (1.926s)
[228.670846] Starting dynamic subtest: vcs1
[230.704841] Dynamic subtest vcs1: SUCCESS (2.042s)
[230.719956] Starting dynamic subtest: vecs0
[232.695479] Dynamic subtest vecs0: SUCCESS (1.988s)
[232.709488] Starting dynamic subtest: ccs0
[234.665457] Dynamic subtest ccs0: SUCCESS (1.968s)
[234.668448] Subtest independent: SUCCESS (9.360s)
[234.981193] [054/156] (786s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[235.078226] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[235.080614] Starting dynamic subtest: A-eDP1
[238.630771] Dynamic subtest A-eDP1: SUCCESS (3.549s)
[238.641493] Starting dynamic subtest: B-eDP1
[241.806457] Dynamic subtest B-eDP1: SUCCESS (3.177s)
[241.817908] Starting dynamic subtest: C-eDP1
[245.010673] Dynamic subtest C-eDP1: SUCCESS (3.202s)
[245.022280] Starting dynamic subtest: D-eDP1
[248.219322] Dynamic subtest D-eDP1: SUCCESS (3.206s)
[248.222380] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.141s)
[249.028855] [055/156] (772s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-render)
[249.134155] Starting subtest: psrhdr-rgb565-draw-render
[249.138448] Subtest psrhdr-rgb565-draw-render: SKIP (0.000s)
[249.224446] [056/156] (772s left) kms_plane (pixel-format-y-tiled-ccs-modifier)
[249.308590] Starting subtest: pixel-format-y-tiled-ccs-modifier
[249.313242] Subtest pixel-format-y-tiled-ccs-modifier: SKIP (0.000s)
[249.380102] [057/156] (771s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[249.481276] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc
[249.486444] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[249.583820] [058/156] (771s left) gem_unref_active_buffers
[249.782060] [059/156] (771s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[249.892418] Starting subtest: y-tiled-64bpp-rotate-0
[249.898073] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[249.985380] [060/156] (771s left) gen9_exec_parse (shadow-peek)
[250.041721] Subtest shadow-peek: SKIP (0.000s)
[250.102754] [061/156] (771s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[250.159965] Starting subtest: multi-wait-for-submit-available-submitted
[250.162297] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.000s)
[250.253782] [062/156] (771s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[250.305345] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[250.310019] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[250.488602] [063/156] (771s left) kms_psr (pr-primary-blt)
[250.582422] Starting subtest: pr-primary-blt
[250.587447] Subtest pr-primary-blt: SKIP (0.000s)
[250.669842] [064/156] (770s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-msflip-blt)
[250.777077] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-msflip-blt
[250.781772] Subtest fbcpsrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[250.870056] [065/156] (770s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-pwrite)
[250.973953] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-pwrite
[250.979170] Subtest fbchdr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[251.066028] [066/156] (770s left) gem_ccs (ctrl-surf-copy)
[251.124693] Starting subtest: ctrl-surf-copy
[251.130176] Subtest ctrl-surf-copy: SKIP (0.000s)
[251.219485] [067/156] (770s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[251.320888] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[251.326047] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[251.408717] [068/156] (770s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier)
[251.494887] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier
[251.520650] Starting dynamic subtest: pipe-A-plane-0
[251.786987] Dynamic subtest pipe-A-plane-0: SUCCESS (0.266s)
[251.797508] Starting dynamic subtest: pipe-A-plane-3
[251.998883] Dynamic subtest pipe-A-plane-3: SUCCESS (0.210s)
[252.008948] Starting dynamic subtest: pipe-A-plane-5
[252.012803] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[253.115473] Starting dynamic subtest: pipe-B-plane-0
[253.406924] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[253.418123] Starting dynamic subtest: pipe-B-plane-3
[253.639800] Dynamic subtest pipe-B-plane-3: SUCCESS (0.232s)
[253.650289] Starting dynamic subtest: pipe-B-plane-5
[253.655247] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[253.962452] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SUCCESS (2.467s)
[254.799469] [069/156] (766s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[254.902269] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[261.832766] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.936s)
[261.970374] [070/156] (759s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[262.082487] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt
[262.088345] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[262.155149] [071/156] (759s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[262.255231] Starting subtest: psr-rgb565-draw-render
[269.341001] Subtest psr-rgb565-draw-render: SUCCESS (7.092s)
[269.471416] [072/156] (752s left) kms_frontbuffer_tracking (psrhdr-2p-pri-indfb-multidraw)
[269.574481] Starting subtest: psrhdr-2p-pri-indfb-multidraw
[269.579346] Subtest psrhdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[269.676845] [073/156] (752s left) kms_rotation_crc (sprite-rotation-270)
[269.768964] Starting subtest: sprite-rotation-270
[269.773978] Subtest sprite-rotation-270: SKIP (0.001s)
[269.873278] [074/156] (752s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[270.002829] Starting subtest: flip-vs-cursor-legacy
[277.392138] Subtest flip-vs-cursor-legacy: SUCCESS (7.392s)
[278.206658] [075/156] (743s left) gem_partial_pwrite_pread (reads)
[278.270282] Subtest reads: SKIP (0.000s)
[278.341871] [076/156] (743s left) kms_busy (extended-pageflip-hang-newfb)
[278.441162] Starting subtest: extended-pageflip-hang-newfb
[278.443962] Starting dynamic subtest: pipe-A
[304.515746] Dynamic subtest pipe-A: SUCCESS (26.070s)
[304.529089] Starting dynamic subtest: pipe-D
[329.484457] Dynamic subtest pipe-D: SUCCESS (24.966s)
[329.487433] Subtest extended-pageflip-hang-newfb: SUCCESS (51.041s)
[330.374639] [077/156] (691s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[330.469268] Starting subtest: fb-8bpc-vs-panel-6bpc
[330.471709] Starting dynamic subtest: pipe-A-eDP-1
[331.862281] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.391s)
[331.865190] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.393s)
[332.745844] [078/156] (689s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-blt)
[332.842269] Starting subtest: fbchdr-rgb565-draw-blt
[332.847054] Subtest fbchdr-rgb565-draw-blt: SKIP (0.000s)
[332.932499] [079/156] (689s left) gem_vm_create (execbuf)
[332.987422] Starting subtest: execbuf
[332.997734] Subtest execbuf: SUCCESS (0.011s)
[333.122275] [080/156] (689s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[333.243959] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[333.374759] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.134s)
[333.508735] [081/156] (688s left) i915_pm_rpm (gem-idle)
[335.035468] Starting subtest: gem-idle
[341.248956] Subtest gem-idle: SUCCESS (6.210s)
[341.579262] [082/156] (680s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0-hflip)
[342.715694] Starting subtest: linear-max-hw-stride-64bpp-rotate-0-hflip
[342.720931] Subtest linear-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[342.821662] [083/156] (679s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[342.917107] Starting subtest: yf-tiled-16bpp-rotate-0
[342.922221] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[343.004353] [084/156] (679s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[343.057444] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[343.060212] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[343.151347] [085/156] (679s left) gem_exec_balancer (bonded-true-hang)
[343.404601] Starting subtest: bonded-true-hang
[343.410455] Subtest bonded-true-hang: SKIP (0.000s)
[343.506951] [086/156] (679s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[343.602139] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[347.306723] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.712s)
[348.501637] [087/156] (674s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[348.606138] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[348.611326] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[348.702312] [088/156] (673s left) kms_async_flips (invalid-async-flip-atomic)
[348.796941] Starting subtest: invalid-async-flip-atomic
[349.169221] Starting dynamic subtest: pipe-A-eDP-1
[349.986734] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.818s)
[350.343861] Starting dynamic subtest: pipe-B-eDP-1
[351.213496] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.880s)
[351.575432] Starting dynamic subtest: pipe-C-eDP-1
[352.338639] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.773s)
[352.702474] Starting dynamic subtest: pipe-D-eDP-1
[353.489195] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.799s)
[353.491755] Subtest invalid-async-flip-atomic: SUCCESS (4.695s)
[354.648101] [089/156] (667s left) perf_pmu (invalid-open)
[354.692323] Starting subtest: invalid-open
[354.695361] Subtest invalid-open: SUCCESS (0.000s)
[354.804673] [090/156] (667s left) api_intel_bb (object-reloc-keep-cache)
[354.862002] Starting subtest: object-reloc-keep-cache
[354.867296] Subtest object-reloc-keep-cache: SKIP (0.003s)
[354.986100] [091/156] (667s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-rte)
[355.082936] Starting subtest: fbcpsrhdr-1p-rte
[355.087958] Subtest fbcpsrhdr-1p-rte: SKIP (0.000s)
[355.180148] [092/156] (667s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc)
[355.271120] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc
[355.273452] Starting dynamic subtest: pipe-A-eDP-1
[355.299097] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[355.312230] Starting dynamic subtest: pipe-B-eDP-1
[355.338442] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[355.348141] Starting dynamic subtest: pipe-C-eDP-1
[355.374536] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[355.384083] Starting dynamic subtest: pipe-D-eDP-1
[355.410571] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[355.416027] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[357.505302] [093/156] (665s left) kms_frontbuffer_tracking (fbc-2p-rte)
[357.605365] Starting subtest: fbc-2p-rte
[357.611017] Subtest fbc-2p-rte: SKIP (0.000s)
[357.684266] [094/156] (665s left) gem_spin_batch (legacy-resubmit-new)
[357.743145] Starting subtest: legacy-resubmit-new
[357.745743] Starting dynamic subtest: default
[357.751680] Dynamic subtest default: SUCCESS (0.007s)
[357.767054] Starting dynamic subtest: render
[357.769423] Dynamic subtest render: SUCCESS (0.003s)
[357.780038] Starting dynamic subtest: bsd
[357.782211] Dynamic subtest bsd: SUCCESS (0.006s)
[357.792218] Starting dynamic subtest: bsd1
[357.794998] Dynamic subtest bsd1: SUCCESS (0.004s)
[357.804839] Starting dynamic subtest: bsd2
[357.807198] Dynamic subtest bsd2: SUCCESS (0.006s)
[357.816912] Starting dynamic subtest: blt
[357.819427] Dynamic subtest blt: SUCCESS (0.004s)
[357.829437] Starting dynamic subtest: vebox
[357.832235] Dynamic subtest vebox: SUCCESS (0.004s)
[357.835077] Subtest legacy-resubmit-new: SUCCESS (0.040s)
[359.698104] [095/156] (663s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[359.798711] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[367.125346] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.332s)
[367.237953] [096/156] (655s left) gem_partial_pwrite_pread (writes-after-reads-display)
[367.294141] Subtest writes-after-reads-display: SKIP (0.000s)
[367.362644] [097/156] (655s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[367.409466] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[367.518792] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.109s)
[367.596624] [098/156] (655s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[367.708447] Starting subtest: flip-vs-cursor-busy-crc-atomic
[368.398452] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.687s)
[369.244897] [099/156] (653s left) kms_rotation_crc (multiplane-rotation-cropping-top)
[369.334122] Starting subtest: multiplane-rotation-cropping-top
[386.913597] Subtest multiplane-rotation-cropping-top: SUCCESS (17.582s)
[387.017845] [100/156] (636s left) gem_exec_params (secure-non-root)
[387.093145] Starting subtest: secure-non-root
[387.097730] Subtest secure-non-root: SKIP (0.000s)
[387.209817] [101/156] (635s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[387.305108] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[387.309445] Subtest hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[387.401874] [102/156] (635s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[387.497332] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[387.501853] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[387.597760] [103/156] (635s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-cpu)
[387.693421] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-cpu
[391.772316] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (4.083s)
[392.944489] [104/156] (630s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[393.046253] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[393.051446] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[393.134954] [105/156] (630s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[393.230014] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-wc
[393.234936] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[393.328087] [106/156] (630s left) kms_3d (basic)
[393.375423] Starting subtest: basic
[395.625450] Subtest basic: SUCCESS (2.253s)
[396.119337] [107/156] (627s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[396.218457] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[396.223322] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[396.298341] [108/156] (627s left) syncobj_timeline (invalid-query-one-illegal-handle)
[396.345219] Starting subtest: invalid-query-one-illegal-handle
[396.348063] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[396.435877] [109/156] (627s left) kms_cdclk (plane-scaling)
[396.525186] Starting subtest: plane-scaling
[396.527683] Starting dynamic subtest: pipe-A-eDP-1
[396.813499] Dynamic subtest pipe-A-eDP-1: SKIP (0.285s)
[396.824041] Starting dynamic subtest: pipe-B-eDP-1
[396.828794] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[396.838355] Starting dynamic subtest: pipe-C-eDP-1
[396.843440] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[396.853408] Starting dynamic subtest: pipe-D-eDP-1
[396.858779] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[396.863988] Subtest plane-scaling: SKIP (0.293s)
[397.683184] [110/156] (625s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[397.796528] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[397.802346] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[397.882860] [111/156] (625s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[397.977703] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-pwrite
[397.982373] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[398.067233] [112/156] (625s left) gem_exec_gttfill (all-engines)
[398.127578] Starting subtest: all-engines
[421.228088] Subtest all-engines: SUCCESS (23.098s)
[421.424918] [113/156] (602s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[421.584277] Starting subtest: 4-tiled-16bpp-rotate-90
[421.603108] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.019s)
[421.722134] [114/156] (601s left) api_intel_allocator (default-alignment)
[421.776176] Starting subtest: default-alignment
[421.778959] Subtest default-alignment: SUCCESS (0.003s)
[421.891695] [115/156] (601s left) kms_content_protection (dp-mst-type-1)
[422.037049] Starting subtest: dp-mst-type-1
[422.042599] Subtest dp-mst-type-1: SKIP (0.000s)
[422.151957] [116/156] (601s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-blt)
[422.254284] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-blt
[422.259620] Subtest hdr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[422.345595] [117/156] (601s left) perf_pmu (busy-idle-check-all)
[422.400718] Starting subtest: busy-idle-check-all
[422.402973] Starting dynamic subtest: rcs0
[423.054004] Dynamic subtest rcs0: SUCCESS (0.652s)
[423.068129] Starting dynamic subtest: bcs0
[423.708452] Dynamic subtest bcs0: SUCCESS (0.652s)
[423.730776] Starting dynamic subtest: vcs0
[425.705468] Dynamic subtest vcs0: SUCCESS (1.993s)
[425.719469] Starting dynamic subtest: vcs1
[427.690886] Dynamic subtest vcs1: SUCCESS (1.983s)
[427.707910] Starting dynamic subtest: vecs0
[429.672714] Dynamic subtest vecs0: SUCCESS (1.979s)
[429.685339] Starting dynamic subtest: ccs0
[430.333605] Dynamic subtest ccs0: SUCCESS (0.659s)
[430.336474] Subtest busy-idle-check-all: SUCCESS (7.935s)
[430.448288] [118/156] (593s left) kms_psr (fbc-pr-suspend)
[430.557455] Starting subtest: fbc-pr-suspend
[430.563236] Subtest fbc-pr-suspend: SKIP (0.000s)
[430.627533] [119/156] (593s left) kms_addfb_basic (too-wide)
[430.713106] Starting subtest: too-wide
[430.715621] Subtest too-wide: SUCCESS (0.000s)
[430.798806] [120/156] (593s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs)
[430.881367] Starting subtest: missing-ccs-buffer-yf-tiled-ccs
[430.884419] Starting dynamic subtest: pipe-A-eDP-1
[430.911195] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[430.923585] Starting dynamic subtest: pipe-B-eDP-1
[430.949203] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[430.960271] Starting dynamic subtest: pipe-C-eDP-1
[430.987055] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[430.997675] Starting dynamic subtest: pipe-D-eDP-1
[431.024382] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[431.029437] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.004s)
[433.161183] [121/156] (590s left) prime_mmap (test_dup)
[433.213930] Starting subtest: test_dup
[433.215960] Starting dynamic subtest: test_dup-smem
[433.242134] Dynamic subtest test_dup-smem: SUCCESS (0.031s)
[433.245010] Subtest test_dup: SUCCESS (0.031s)
[433.317804] [122/156] (590s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-plflip-blt)
[433.416249] Starting subtest: fbchdr-1p-primscrn-indfb-plflip-blt
[433.421209] Subtest fbchdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[433.511780] [123/156] (590s left) syncobj_wait (wait-for-submit-complex)
[433.576765] Starting subtest: wait-for-submit-complex
[433.639561] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[433.718769] [124/156] (590s left) kms_flip (2x-flip-vs-fences-interruptible)
[433.808707] Starting subtest: 2x-flip-vs-fences-interruptible
[433.813832] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[433.899873] [125/156] (590s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode)
[434.276214] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s)
[435.155546] [126/156] (589s left) kms_psr (fbc-psr-sprite-plane-move)
[435.251427] Starting subtest: fbc-psr-sprite-plane-move
[435.253807] Starting dynamic subtest: eDP-1
[435.258596] Dynamic subtest eDP-1: SKIP (0.000s)
[435.263285] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[435.323345] [127/156] (588s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render)
[435.414373] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render
[435.419570] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[435.505625] [128/156] (588s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[435.606719] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[443.177430] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.578s)
[443.292247] [129/156] (581s left) sysfs_heartbeat_interval (invalid)
[443.364777] Starting subtest: invalid
[443.367146] Starting dynamic subtest: vecs0
[443.369823] Dynamic subtest vecs0: SUCCESS (0.000s)
[443.382883] Starting dynamic subtest: ccs0
[443.385079] Dynamic subtest ccs0: SUCCESS (0.000s)
[443.395069] Starting dynamic subtest: vcs0
[443.397310] Dynamic subtest vcs0: SUCCESS (0.000s)
[443.407601] Starting dynamic subtest: bcs0
[443.410169] Dynamic subtest bcs0: SUCCESS (0.000s)
[443.420826] Starting dynamic subtest: rcs0
[443.423314] Dynamic subtest rcs0: SUCCESS (0.000s)
[443.433234] Starting dynamic subtest: vcs1
[443.435446] Dynamic subtest vcs1: SUCCESS (0.000s)
[443.437806] Subtest invalid: SUCCESS (0.006s)
[443.477449] [130/156] (580s left) kms_atomic_transition (plane-all-transition)
[443.562243] Starting subtest: plane-all-transition
[443.564717] Starting dynamic subtest: pipe-A-eDP-1
[446.055574] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[446.365589] Starting dynamic subtest: pipe-B-eDP-1
[449.560006] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.206s)
[449.861871] Subtest plane-all-transition: SUCCESS (6.301s)
[450.678358] [131/156] (573s left) core_setmaster (master-drop-set-root)
[450.746960] Starting subtest: master-drop-set-root
[450.771962] Subtest master-drop-set-root: SUCCESS (0.025s)
[450.836957] [132/156] (573s left) kms_vblank (invalid)
[450.922243] Starting subtest: invalid
[450.924446] Starting dynamic subtest: pipe-A-eDP-1
[451.335427] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.414s)
[451.338356] Subtest invalid: SUCCESS (0.416s)
[452.166265] [133/156] (572s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[452.267946] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[452.272284] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[452.356885] [134/156] (572s left) kms_getfb (getfb-handle-valid)
[452.439202] Starting subtest: getfb-handle-valid
[452.441823] Subtest getfb-handle-valid: SUCCESS (0.000s)
[452.556358] [135/156] (572s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[452.654442] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[456.311648] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (3.664s)
[457.471793] [136/156] (567s left) gem_ctx_engines (execute-oneforall)
[457.523223] Starting subtest: execute-oneforall
[458.063647] Subtest execute-oneforall: SUCCESS (0.541s)
[459.784566] [137/156] (564s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-indfb-pgflip-blt)
[459.884136] Starting subtest: psrhdr-2p-primscrn-indfb-pgflip-blt
[459.889200] Subtest psrhdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[459.978085] [138/156] (564s left) kms_lease (lease-get)
[460.073139] Starting subtest: lease-get
[460.076210] Starting dynamic subtest: pipe-A-eDP-1
[460.078880] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[460.090320] Starting dynamic subtest: pipe-B-eDP-1
[460.093183] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[460.104058] Starting dynamic subtest: pipe-C-eDP-1
[460.106681] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[460.117196] Starting dynamic subtest: pipe-D-eDP-1
[460.120014] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[460.122636] Subtest lease-get: SUCCESS (0.009s)
[460.192052] [139/156] (564s left) kms_psr2_sf (pr-overlay-primary-update-sf-dmg-area)
[460.288245] Starting subtest: pr-overlay-primary-update-sf-dmg-area
[460.293029] Subtest pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[460.358941] [140/156] (564s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[460.453194] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[460.458209] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[460.559374] [141/156] (564s left) gem_exec_reloc (basic-gtt-active)
[460.625979] Subtest basic-gtt-active: SKIP (0.000s)
[460.708079] [142/156] (564s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[460.803730] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[464.902818] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.103s)
[466.065374] [143/156] (558s left) perf (i915-ref-count)
[466.134574] Starting subtest: i915-ref-count
[466.218663] Subtest i915-ref-count: SUCCESS (0.081s)
[466.278930] [144/156] (558s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[466.374224] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[470.107056] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.739s)
[471.276795] [145/156] (553s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-onoff)
[471.378761] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-onoff
[471.383453] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[471.477424] [146/156] (553s left) gem_media_vme
[471.602058] [147/156] (553s left) kms_frontbuffer_tracking (hdr-2p-pri-indfb-multidraw)
[471.701691] Starting subtest: hdr-2p-pri-indfb-multidraw
[471.706643] Subtest hdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[471.801489] [148/156] (553s left) kms_psr (psr-cursor-mmap-cpu)
[471.894195] Starting subtest: psr-cursor-mmap-cpu
[471.896691] Starting dynamic subtest: eDP-1
[473.626084] Dynamic subtest eDP-1: SUCCESS (1.731s)
[473.628787] Subtest psr-cursor-mmap-cpu: SUCCESS (1.732s)
[474.461421] [149/156] (550s left) gem_exec_fence (syncobj-timeline-export)
[475.028289] Starting subtest: syncobj-timeline-export
[475.035285] Subtest syncobj-timeline-export: SUCCESS (0.008s)
[476.800839] [150/156] (548s left) gem_exec_capture (capture)
[476.865934] Starting subtest: capture
[476.868349] Starting dynamic subtest: rcs0-smem
[477.695381] Dynamic subtest rcs0-smem: SUCCESS (0.831s)
[477.706427] Starting dynamic subtest: bcs0-smem
[478.519844] Dynamic subtest bcs0-smem: SUCCESS (0.824s)
[478.530206] Starting dynamic subtest: vcs0-smem
[479.345437] Dynamic subtest vcs0-smem: SUCCESS (0.821s)
[479.356144] Starting dynamic subtest: vcs1-smem
[480.671286] Dynamic subtest vcs1-smem: SUCCESS (1.327s)
[480.682352] Starting dynamic subtest: vecs0-smem
[481.578173] Dynamic subtest vecs0-smem: FAIL (0.839s)
[481.590065] Starting dynamic subtest: ccs0-smem
[482.834452] Dynamic subtest ccs0-smem: SUCCESS (1.320s)
[482.837482] Subtest capture: FAIL (5.968s)
[482.948108] [151/156] (542s left) sw_sync (alloc_fence)
[482.985310] Starting subtest: alloc_fence
[482.988210] Subtest alloc_fence: SUCCESS (0.000s)
[483.044660] [152/156] (542s left) syncobj_wait (invalid-wait-illegal-handle)
[483.099440] Starting subtest: invalid-wait-illegal-handle
[483.102215] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[483.201720] [153/156] (542s left) gem_eio (wait-immediate)
[483.394453] Starting subtest: wait-immediate
[483.717243] Subtest wait-immediate: SUCCESS (0.325s)
[483.861022] [154/156] (541s left) kms_cursor_crc (cursor-offscreen-128x42)
[484.102138] Starting subtest: cursor-offscreen-128x42
[484.148109] Subtest cursor-offscreen-128x42: SKIP (0.039s)
[484.355341] [155/156] (541s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[484.412050] Starting subtest: yf-tiled-ccs-to-yf-tiled
[484.416154] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[484.598956] [156/156] (540s left) gem_exec_params (dr4-dirt)
[484.651693] Starting subtest: DR4-dirt
[484.654271] Subtest DR4-dirt: SUCCESS (0.000s)
[484.745952] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_crc
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: gem_ctx_persistence
results: parsing output: 3/ for test: kms_cursor_legacy
results: parsing output: 4/ for test: kms_properties
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_eio
results: parsing output: 8/ for test: gem_ctx_persistence
results: parsing output: 9/ for test: gem_exec_schedule
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_pm_dc
results: parsing output: 12/ for test: gem_madvise
results: parsing output: 13/ for test: gem_exec_flush
results: parsing output: 14/ for test: kms_dsc
results: parsing output: 15/ for test: kms_plane_scaling
results: parsing output: 16/ for test: kms_pipe_stress
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: gem_exec_reloc
results: parsing output: 19/ for test: gem_lmem_swapping
results: parsing output: 20/ for test: kms_chamelium_edid
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: i915_suspend
results: parsing output: 23/ for test: kms_color
results: parsing output: 24/ for test: kms_cursor_crc
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: gen3_render_tiledy_blits
results: parsing output: 27/ for test: gem_mmap_gtt
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: prime_vgem
results: parsing output: 30/ for test: kms_plane_scaling
results: parsing output: 31/ for test: kms_psr2_sf
results: parsing output: 32/ for test: kms_ccs
results: parsing output: 33/ for test: i915_pm_freq_api
results: parsing output: 34/ for test: gem_mmap_gtt
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_flip_scaled_crc
results: parsing output: 37/ for test: i915_drm_fdinfo
results: parsing output: 38/ for test: kms_joiner
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: kms_psr
results: parsing output: 41/ for test: kms_flip_scaled_crc
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_exec_whisper
results: parsing output: 44/ for test: gem_ctx_sseu
results: parsing output: 45/ for test: kms_ccs
results: parsing output: 46/ for test: kms_vrr
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_prop_blob
results: parsing output: 50/ for test: gem_exec_reloc
results: parsing output: 51/ for test: kms_cursor_legacy
results: parsing output: 52/ for test: gem_exec_schedule
results: parsing output: 53/ for test: kms_flip
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_plane
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_unref_active_buffers
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: gen9_exec_parse
results: parsing output: 60/ for test: syncobj_timeline
results: parsing output: 61/ for test: gem_render_copy
results: parsing output: 62/ for test: kms_psr
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: gem_ccs
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_plane
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_rotation_crc
results: parsing output: 73/ for test: kms_cursor_legacy
results: parsing output: 74/ for test: gem_partial_pwrite_pread
results: parsing output: 75/ for test: kms_busy
results: parsing output: 76/ for test: kms_dither
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_vm_create
results: parsing output: 79/ for test: gem_pxp
results: parsing output: 80/ for test: i915_pm_rpm
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: gem_exec_balancer
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_async_flips
results: parsing output: 88/ for test: perf_pmu
results: parsing output: 89/ for test: api_intel_bb
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: gem_spin_batch
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_partial_pwrite_pread
results: parsing output: 96/ for test: syncobj_timeline
results: parsing output: 97/ for test: kms_cursor_legacy
results: parsing output: 98/ for test: kms_rotation_crc
results: parsing output: 99/ for test: gem_exec_params
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_3d
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: syncobj_timeline
results: parsing output: 108/ for test: kms_cdclk
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gem_exec_gttfill
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: api_intel_allocator
results: parsing output: 114/ for test: kms_content_protection
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: perf_pmu
results: parsing output: 117/ for test: kms_psr
results: parsing output: 118/ for test: kms_addfb_basic
results: parsing output: 119/ for test: kms_ccs
results: parsing output: 120/ for test: prime_mmap
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: syncobj_wait
results: parsing output: 123/ for test: kms_flip
results: parsing output: 124/ for test: kms_chamelium_hpd
results: parsing output: 125/ for test: kms_psr
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: sysfs_heartbeat_interval
results: parsing output: 129/ for test: kms_atomic_transition
results: parsing output: 130/ for test: core_setmaster
results: parsing output: 131/ for test: kms_vblank
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_getfb
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: gem_ctx_engines
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_lease
results: parsing output: 138/ for test: kms_psr2_sf
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: gem_exec_reloc
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: perf
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: gem_media_vme
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: kms_psr
results: parsing output: 148/ for test: gem_exec_fence
results: parsing output: 149/ for test: gem_exec_capture
results: parsing output: 150/ for test: sw_sync
results: parsing output: 151/ for test: syncobj_wait
results: parsing output: 152/ for test: gem_eio
results: parsing output: 153/ for test: kms_cursor_crc
results: parsing output: 154/ for test: gem_render_copy
results: parsing output: 155/ for test: gem_exec_params
Done.