[58.435194] Initializing watchdogs
[58.435339]   /dev/watchdog0
[58.451366] [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]
[58.456238] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.458901] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.462467] [001/156] (960s left) sysfs_heartbeat_interval (idempotent)
[58.543909] Starting subtest: idempotent
[58.546380] Starting dynamic subtest: vecs0
[58.548730] Dynamic subtest vecs0: SUCCESS (0.001s)
[58.558560] Starting dynamic subtest: ccs0
[58.560877] Dynamic subtest ccs0: SUCCESS (0.001s)
[58.570670] Starting dynamic subtest: vcs0
[58.572804] Dynamic subtest vcs0: SUCCESS (0.000s)
[58.580081] Starting dynamic subtest: bcs0
[58.582459] Dynamic subtest bcs0: SUCCESS (0.001s)
[58.592555] Starting dynamic subtest: rcs0
[58.594472] Dynamic subtest rcs0: SUCCESS (0.000s)
[58.602763] Starting dynamic subtest: vcs1
[58.604977] Dynamic subtest vcs1: SUCCESS (0.000s)
[58.607153] Subtest idempotent: SUCCESS (0.006s)
[58.666481] [002/156] (960s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[58.727653] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[58.730264] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[58.822525] [003/156] (960s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling)
[58.919160] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling
[58.921604] Starting dynamic subtest: pipe-A-default-mode
[61.678080] Dynamic subtest pipe-A-default-mode: SUCCESS (2.715s)
[61.680360] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (2.716s)
[62.508935] [004/156] (956s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[62.612602] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[62.616803] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[62.695839] [005/156] (956s left) kms_flip (bo-too-big-interruptible)
[62.798900] Starting subtest: bo-too-big-interruptible
[62.801298] Starting dynamic subtest: A-eDP1
[64.180766] Dynamic subtest A-eDP1: SUCCESS (1.365s)
[64.190976] Starting dynamic subtest: B-eDP1
[65.520730] Dynamic subtest B-eDP1: SUCCESS (1.328s)
[65.530947] Starting dynamic subtest: C-eDP1
[66.849092] Dynamic subtest C-eDP1: SUCCESS (1.317s)
[66.858601] Starting dynamic subtest: D-eDP1
[68.191498] Dynamic subtest D-eDP1: SUCCESS (1.333s)
[68.196575] Subtest bo-too-big-interruptible: SUCCESS (5.353s)
[69.411073] [006/156] (949s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[69.506937] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[69.509476] Starting dynamic subtest: pipe-A-eDP-1
[69.571546] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[69.580715] Starting dynamic subtest: pipe-B-eDP-1
[69.638407] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[69.647714] Starting dynamic subtest: pipe-C-eDP-1
[69.707722] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[69.717442] Starting dynamic subtest: pipe-D-eDP-1
[69.775224] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[69.779449] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.007s)
[71.796433] [007/156] (947s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[71.898057] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[71.902637] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[71.964897] [008/156] (947s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-onoff)
[72.056262] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-onoff
[72.060333] Subtest psrhdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[72.152342] [009/156] (947s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[72.251019] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[72.253456] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[72.428094] [010/156] (946s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[72.718594] Starting subtest: x-tiled-8bpp-rotate-0
[74.115432] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.394s)
[74.261468] [011/156] (945s left) kms_fence_pin_leak
[74.445683] [012/156] (944s left) gem_create (create-size-update)
[74.495080] Starting subtest: create-size-update
[74.496724] Subtest create-size-update: SUCCESS (0.000s)
[74.715083] [013/156] (944s left) i915_query (engine-info-invalid)
[74.768340] Starting subtest: engine-info-invalid
[74.770695] Subtest engine-info-invalid: SUCCESS (0.000s)
[74.852446] [014/156] (944s left) dmabuf (all-tests)
[74.884499] Starting subtest: all-tests
[74.888696] Subtest all-tests: SKIP (0.000s)
[74.937700] [015/156] (944s left) perf (polling)
[75.050335] Starting subtest: polling
[75.052221] Starting dynamic subtest: 0-rcs0
[85.077110] Dynamic subtest 0-rcs0: SUCCESS (10.014s)
[85.086666] Starting dynamic subtest: 1-vecs0
[95.107424] Dynamic subtest 1-vecs0: SUCCESS (10.022s)
[95.110090] Subtest polling: SUCCESS (20.037s)
[95.153781] [016/156] (924s left) kms_flip (dpms-off-confusion)
[95.259605] Starting subtest: dpms-off-confusion
[95.262107] Starting dynamic subtest: A-eDP1
[103.924511] Dynamic subtest A-eDP1: SUCCESS (8.664s)
[103.933122] Starting dynamic subtest: B-eDP1
[112.665401] Dynamic subtest B-eDP1: SUCCESS (8.737s)
[112.673316] Starting dynamic subtest: C-eDP1
[121.386405] Dynamic subtest C-eDP1: SUCCESS (8.717s)
[121.397144] Starting dynamic subtest: D-eDP1
[130.021412] Dynamic subtest D-eDP1: SUCCESS (8.634s)
[130.023805] Subtest dpms-off-confusion: SUCCESS (34.759s)
[131.202324] [017/156] (888s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[131.331927] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[132.307142] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (0.974s)
[133.133319] [018/156] (886s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-blt)
[133.236611] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-blt
[133.240962] Subtest hdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[133.320481] [019/156] (886s left) kms_lease (lease-revoke)
[133.409819] Starting subtest: lease-revoke
[133.412191] Starting dynamic subtest: pipe-A-eDP-1
[133.440585] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.030s)
[133.451796] Starting dynamic subtest: pipe-B-eDP-1
[133.469733] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.027s)
[133.478661] Starting dynamic subtest: pipe-C-eDP-1
[133.497030] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.030s)
[133.505495] Starting dynamic subtest: pipe-D-eDP-1
[133.526205] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.028s)
[133.528685] Subtest lease-revoke: SUCCESS (0.118s)
[133.634971] [020/156] (886s left) kms_hdr (bpc-switch)
[133.721797] Starting subtest: bpc-switch
[134.801301] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[138.306843] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (3.507s)
[139.371668] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[142.856340] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (3.495s)
[142.858823] Subtest bpc-switch: SUCCESS (9.134s)
[142.977262] [021/156] (876s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[143.076459] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[143.080665] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[143.147706] [022/156] (876s left) kms_plane_scaling (2x-scaler-multi-pipe)
[143.243091] Starting subtest: 2x-scaler-multi-pipe
[143.247579] Subtest 2x-scaler-multi-pipe: SKIP (0.001s)
[143.380956] [023/156] (876s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff)
[143.487046] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff
[143.491490] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[143.595933] [024/156] (876s left) kms_cursor_crc (cursor-sliding-256x85)
[143.851472] Starting subtest: cursor-sliding-256x85
[143.903787] Subtest cursor-sliding-256x85: SKIP (0.048s)
[144.095221] [025/156] (875s left) prime_self_import (basic-with_one_bo_two_files)
[144.126202] Starting subtest: basic-with_one_bo_two_files
[144.141478] Subtest basic-with_one_bo_two_files: SUCCESS (0.016s)
[144.247038] [026/156] (875s left) kms_async_flips (async-flip-dpms)
[144.343184] Starting subtest: async-flip-dpms
[144.722074] Starting dynamic subtest: pipe-A-eDP-1
[148.635168] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.911s)
[149.033702] Starting dynamic subtest: pipe-B-eDP-1
[151.913402] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.885s)
[152.295459] Starting dynamic subtest: pipe-C-eDP-1
[155.162522] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.874s)
[155.538771] Starting dynamic subtest: pipe-D-eDP-1
[158.426769] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.896s)
[158.429106] Subtest async-flip-dpms: SUCCESS (14.083s)
[159.621992] [027/156] (860s left) syncobj_timeline (wait-for-submit-complex)
[159.685444] Starting subtest: wait-for-submit-complex
[159.748324] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[159.822687] [028/156] (860s left) kms_vblank (ts-continuation-suspend)
[159.914125] Starting subtest: ts-continuation-suspend
[159.916359] Starting dynamic subtest: pipe-A-eDP-1
[176.352849] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.618s)
[176.368290] Starting dynamic subtest: pipe-D-eDP-1
[193.369715] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.190s)
[193.372217] Subtest ts-continuation-suspend: SUCCESS (3.811s)
[194.233205] [029/156] (825s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[194.337228] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[194.341743] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[194.433873] [030/156] (825s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[194.542220] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[194.546491] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[194.646560] [031/156] (825s left) kms_flip (2x-flip-vs-suspend)
[194.744102] Starting subtest: 2x-flip-vs-suspend
[194.748775] Subtest 2x-flip-vs-suspend: SKIP (0.002s)
[194.865621] [032/156] (825s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[194.968525] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[198.778028] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.721s)
[199.993924] [033/156] (820s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[200.124583] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[209.752468] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.535s)
[210.611907] [034/156] (809s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[210.712930] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[210.717500] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[210.799460] [035/156] (809s left) gem_exec_schedule (u-fairslice-all)
[210.869333] Starting subtest: u-fairslice-all
[212.939114] Subtest u-fairslice-all: SUCCESS (2.057s)
[214.918704] [036/156] (805s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[214.985632] Starting subtest: etime-single-wait-available-unsubmitted
[215.094314] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.108s)
[215.171186] [037/156] (805s left) gem_pwrite (basic-random)
[215.232442] Subtest basic-random: SKIP (0.000s)
[215.293568] [038/156] (805s left) gem_exec_balancer (indices)
[215.351524] Starting subtest: indices
[217.814410] Subtest indices: SUCCESS (2.455s)
[218.154100] [039/156] (802s left) gem_exec_schedule (pi-distinct-iova)
[218.342831] Starting subtest: pi-distinct-iova
[218.344822] Starting dynamic subtest: rcs0
[218.468370] Dynamic subtest rcs0: SUCCESS (0.127s)
[218.480572] Starting dynamic subtest: vcs0
[218.545101] Dynamic subtest vcs0: SUCCESS (0.076s)
[218.561458] Starting dynamic subtest: vcs1
[218.622173] Dynamic subtest vcs1: SUCCESS (0.076s)
[218.632628] Starting dynamic subtest: vecs0
[218.705184] Dynamic subtest vecs0: SUCCESS (0.083s)
[218.718462] Starting dynamic subtest: ccs0
[218.810843] Dynamic subtest ccs0: SUCCESS (0.105s)
[218.813622] Subtest pi-distinct-iova: SUCCESS (0.471s)
[219.930402] [040/156] (800s left) kms_chamelium_hpd (hdmi-hpd)
[220.324018] Subtest hdmi-hpd: SKIP (0.000s)
[221.148585] [041/156] (799s left) core_getversion (all-cards)
[221.188585] Starting subtest: all-cards
[221.196462] Subtest all-cards: SUCCESS (0.011s)
[221.243539] [042/156] (799s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[221.331046] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[221.333392] Starting dynamic subtest: pipe-A
[221.396976] Dynamic subtest pipe-A: SUCCESS (0.065s)
[221.406462] Starting dynamic subtest: pipe-B
[222.594691] Dynamic subtest pipe-B: SUCCESS (1.196s)
[222.606545] Starting dynamic subtest: pipe-C
[223.706184] Dynamic subtest pipe-C: SUCCESS (1.109s)
[223.716078] Starting dynamic subtest: pipe-D
[224.830542] Dynamic subtest pipe-D: SUCCESS (1.123s)
[224.832930] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.496s)
[226.008075] [043/156] (794s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-wc)
[226.115610] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-wc
[226.119698] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[226.218444] [044/156] (794s left) gem_ctx_persistence (legacy-engines-mixed-process)
[226.279132] Starting subtest: legacy-engines-mixed-process
[226.281419] Starting dynamic subtest: render
[227.845142] Dynamic subtest render: SUCCESS (1.565s)
[227.854908] Starting dynamic subtest: bsd1
[229.893483] Dynamic subtest bsd1: SUCCESS (2.046s)
[229.903886] Starting dynamic subtest: bsd2
[231.880625] Dynamic subtest bsd2: SUCCESS (1.984s)
[231.891018] Starting dynamic subtest: blt
[233.852703] Dynamic subtest blt: SUCCESS (1.969s)
[233.864414] Starting dynamic subtest: vebox
[235.852549] Dynamic subtest vebox: SUCCESS (1.997s)
[235.855061] Subtest legacy-engines-mixed-process: SUCCESS (9.568s)
[235.930653] [045/156] (784s left) gem_gpgpu_fill (basic)
[235.989179] Starting subtest: basic
[235.992627] Starting dynamic subtest: smem
[235.998335] Dynamic subtest smem: SUCCESS (0.011s)
[236.000609] Subtest basic: SUCCESS (0.011s)
[236.144177] [046/156] (784s left) kms_psr (pr-no-drrs)
[236.242300] Starting subtest: pr-no-drrs
[236.247042] Subtest pr-no-drrs: SKIP (0.000s)
[236.316362] [047/156] (784s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[236.422876] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-wc
[236.426993] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[236.530428] [048/156] (784s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-onoff)
[236.632524] Starting subtest: psrhdr-2p-primscrn-cur-indfb-onoff
[236.637115] Subtest psrhdr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[236.746690] [049/156] (784s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[236.841519] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[236.845342] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[236.926631] [050/156] (783s left) gem_mmap_offset (bad-flags)
[236.982422] Starting subtest: bad-flags
[236.984448] Subtest bad-flags: SUCCESS (0.000s)
[237.112007] [051/156] (783s left) gem_eio (in-flight-contexts-immediate)
[237.333484] Starting subtest: in-flight-contexts-immediate
[248.861939] Subtest in-flight-contexts-immediate: SUCCESS (11.523s)
[249.026397] [052/156] (771s left) gen9_exec_parse (unaligned-access)
[249.091858] Subtest unaligned-access: SKIP (0.000s)
[249.166964] [053/156] (771s left) kms_big_fb (y-tiled-addfb)
[249.269778] Starting subtest: y-tiled-addfb
[249.274238] Subtest y-tiled-addfb: SKIP (0.000s)
[249.361092] [054/156] (771s left) kms_big_fb (linear-16bpp-rotate-0)
[249.454167] Starting subtest: linear-16bpp-rotate-0
[250.822253] Subtest linear-16bpp-rotate-0: SUCCESS (1.368s)
[250.969723] [055/156] (770s left) kms_dirtyfb (drrs-dirtyfb-ioctl)
[251.059274] Starting subtest: drrs-dirtyfb-ioctl
[251.070403] Subtest drrs-dirtyfb-ioctl: SKIP (0.001s)
[251.157464] [056/156] (770s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[251.254148] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[251.257582] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[251.364767] [057/156] (769s left) gem_exec_reloc (basic-write-cpu)
[251.425777] Subtest basic-write-cpu: SKIP (0.000s)
[251.491681] [058/156] (769s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-mc-ccs)
[251.592166] Starting subtest: crc-primary-suspend-y-tiled-gen12-mc-ccs
[251.594252] Starting dynamic subtest: pipe-A-eDP-1
[251.601862] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[251.612434] Starting dynamic subtest: pipe-B-eDP-1
[251.620131] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[251.630790] Starting dynamic subtest: pipe-C-eDP-1
[251.637656] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[251.645997] Starting dynamic subtest: pipe-D-eDP-1
[251.652977] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[251.656534] Subtest crc-primary-suspend-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[253.886427] [059/156] (767s left) gem_tiled_partial_pwrite_pread (writes)
[253.953724] Subtest writes: SKIP (0.000s)
[254.026476] [060/156] (767s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-gtt)
[254.135792] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-gtt
[254.140022] Subtest fbcpsrhdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[254.223069] [061/156] (767s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[254.311107] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[254.313356] Starting dynamic subtest: pipe-A-eDP-1
[254.336113] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[254.347482] Starting dynamic subtest: pipe-B-eDP-1
[254.369446] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[254.378918] Starting dynamic subtest: pipe-C-eDP-1
[254.400990] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[254.410393] Starting dynamic subtest: pipe-D-eDP-1
[254.432654] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[254.436850] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[256.547994] [062/156] (764s left) gem_exec_schedule (fairslice)
[256.633291] Starting subtest: fairslice
[256.635315] Starting dynamic subtest: rcs0
[258.648421] Dynamic subtest rcs0: SUCCESS (2.018s)
[258.657649] Starting dynamic subtest: bcs0
[260.670259] Dynamic subtest bcs0: SUCCESS (2.020s)
[260.683543] Starting dynamic subtest: vcs0
[262.692492] Dynamic subtest vcs0: SUCCESS (2.018s)
[262.702103] Starting dynamic subtest: vcs1
[264.710007] Dynamic subtest vcs1: SUCCESS (2.017s)
[264.720389] Starting dynamic subtest: vecs0
[266.728767] Dynamic subtest vecs0: SUCCESS (2.018s)
[266.738430] Starting dynamic subtest: ccs0
[268.754069] Dynamic subtest ccs0: SUCCESS (2.022s)
[268.756330] Subtest fairslice: SUCCESS (12.119s)
[269.056917] [063/156] (752s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-onoff)
[269.174901] Starting subtest: hdr-1p-primscrn-cur-indfb-onoff
[269.178962] Subtest hdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[269.278818] [064/156] (752s left) syncobj_basic (bad-pad-fd-to-handle)
[269.332604] Starting subtest: bad-pad-fd-to-handle
[269.335271] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[269.453377] [065/156] (752s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-render)
[269.553876] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-render
[269.558333] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[269.664068] [066/156] (751s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size)
[269.791153] Starting subtest: cursorB-vs-flipB-varying-size
[269.795637] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s)
[269.945224] [067/156] (751s left) gem_exec_reloc (basic-cpu-gtt-active)
[270.007357] Subtest basic-cpu-gtt-active: SKIP (0.000s)
[270.074996] [068/156] (751s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[270.129230] Starting subtest: yf-tiled-ccs-to-yf-tiled
[270.132962] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[270.314430] [069/156] (751s left) gem_exec_params (invalid-bsd1-flag-on-blt)
[270.376524] Starting subtest: invalid-bsd1-flag-on-blt
[270.378921] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s)
[270.489101] [070/156] (751s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[270.596870] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[275.102084] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.508s)
[276.246833] [071/156] (745s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[276.346984] Starting subtest: pr-cursor-plane-update-sf
[276.351420] Subtest pr-cursor-plane-update-sf: SKIP (0.000s)
[276.413900] [072/156] (745s left) kms_psr (psr2-sprite-mmap-cpu)
[276.511309] Starting subtest: psr2-sprite-mmap-cpu
[276.513624] Starting dynamic subtest: eDP-1
[278.243611] Dynamic subtest eDP-1: SUCCESS (1.731s)
[278.246020] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.732s)
[279.140531] [073/156] (742s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-onoff)
[279.240754] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-onoff
[279.244848] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[279.334471] [074/156] (742s left) gem_eio (create)
[279.553507] Starting subtest: create
[279.862003] Subtest create: SUCCESS (0.311s)
[279.996899] [075/156] (741s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[280.089438] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[280.093078] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[280.186346] [076/156] (741s left) gem_exec_whisper (basic-fds-all)
[280.251142] Starting subtest: basic-fds-all
[291.663536] Subtest basic-fds-all: SUCCESS (11.409s)
[291.851291] [077/156] (730s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[291.906129] Subtest writes-after-reads: SKIP (0.000s)
[291.973077] [078/156] (730s left) kms_color (ctm-0-75)
[292.068891] Starting subtest: ctm-0-75
[292.071106] Starting dynamic subtest: pipe-A-eDP-1
[297.037376] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.971s)
[297.049121] Starting dynamic subtest: pipe-B-eDP-1
[302.783157] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.746s)
[302.793389] Starting dynamic subtest: pipe-C-eDP-1
[308.153497] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.371s)
[308.163960] Starting dynamic subtest: pipe-D-eDP-1
[313.475758] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.322s)
[313.478143] Subtest ctm-0-75: SUCCESS (21.413s)
[314.290889] [079/156] (707s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[314.390615] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[314.394963] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[314.486924] [080/156] (707s left) kms_invalid_mode (zero-vdisplay)
[314.586193] Starting subtest: zero-vdisplay
[314.588442] Starting dynamic subtest: pipe-A-eDP-1
[314.883066] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.299s)
[314.894621] Starting dynamic subtest: pipe-B-eDP-1
[314.896657] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[314.906536] Starting dynamic subtest: pipe-C-eDP-1
[314.908909] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[314.918329] Starting dynamic subtest: pipe-D-eDP-1
[314.920647] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[314.922949] Subtest zero-vdisplay: SUCCESS (0.304s)
[315.745123] [081/156] (706s left) prime_busy (hang-wait)
[315.794119] Starting subtest: hang-wait
[315.796510] Starting dynamic subtest: rcs0
[342.306551] Dynamic subtest rcs0: SUCCESS (26.505s)
[342.315481] Starting dynamic subtest: bcs0
[349.452549] Dynamic subtest bcs0: SUCCESS (7.144s)
[349.461466] Starting dynamic subtest: vcs0
[356.486750] Dynamic subtest vcs0: SUCCESS (7.032s)
[356.497325] Starting dynamic subtest: vcs1
[363.465084] Dynamic subtest vcs1: SUCCESS (6.972s)
[363.475634] Starting dynamic subtest: vecs0
[370.433872] Dynamic subtest vecs0: SUCCESS (6.968s)
[370.446114] Starting dynamic subtest: ccs0
[384.408401] Dynamic subtest ccs0: SUCCESS (13.964s)
[384.411353] Subtest hang-wait: SUCCESS (68.597s)
[384.522567] [082/156] (637s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[384.631259] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[384.635578] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[384.724075] [083/156] (637s left) kms_addfb_basic (invalid-get-prop)
[384.778263] Starting subtest: invalid-get-prop
[384.780198] Subtest invalid-get-prop: SUCCESS (0.000s)
[384.941696] [084/156] (637s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[385.004329] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[385.109737] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[385.198206] [085/156] (637s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-move)
[385.299097] Starting subtest: fbchdr-2p-primscrn-spr-indfb-move
[385.303374] Subtest fbchdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[385.428872] [086/156] (636s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[385.522870] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[385.527004] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[385.625166] [087/156] (636s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[386.009743] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[386.843225] [088/156] (635s left) api_intel_bb (blit-noreloc-keep-cache)
[386.896296] Starting subtest: blit-noreloc-keep-cache
[386.904572] Subtest blit-noreloc-keep-cache: SUCCESS (0.010s)
[386.996861] [089/156] (635s left) gem_exec_fence (syncobj-channel)
[387.479462] Starting subtest: syncobj-channel
[388.497862] Subtest syncobj-channel: SUCCESS (1.015s)
[388.912332] [FACT gem_exec_fence (syncobj-channel)] new: kernel.kmod_is_loaded.vgem: true
[388.915890] [090/156] (633s left) gem_exec_reloc (basic-write-cpu-active)
[388.975452] Subtest basic-write-cpu-active: SKIP (0.000s)
[389.046151] [091/156] (633s left) kms_frontbuffer_tracking (fbcpsrhdr-stridechange)
[389.139077] Starting subtest: fbcpsrhdr-stridechange
[389.143208] Subtest fbcpsrhdr-stridechange: SKIP (0.000s)
[389.245455] [092/156] (633s left) gem_tiling_max_stride
[389.366792] [093/156] (633s left) gem_ctx_persistence (engines-cleanup)
[389.425568] Starting subtest: engines-cleanup
[389.427996] Starting dynamic subtest: rcs0
[389.483124] Dynamic subtest rcs0: SUCCESS (0.057s)
[389.495961] Starting dynamic subtest: bcs0
[389.518418] Dynamic subtest bcs0: SUCCESS (0.033s)
[389.528756] Starting dynamic subtest: vcs0
[389.559363] Dynamic subtest vcs0: SUCCESS (0.039s)
[389.573278] Starting dynamic subtest: vcs1
[389.596268] Dynamic subtest vcs1: SUCCESS (0.034s)
[389.606165] Starting dynamic subtest: vecs0
[389.635317] Dynamic subtest vecs0: SUCCESS (0.038s)
[389.645848] Starting dynamic subtest: ccs0
[389.670239] Dynamic subtest ccs0: SUCCESS (0.033s)
[389.672571] Subtest engines-cleanup: SUCCESS (0.246s)
[389.762833] [094/156] (632s left) kms_cdclk (mode-transition-all-outputs)
[389.849956] Starting subtest: mode-transition-all-outputs
[390.147057] Subtest mode-transition-all-outputs: SKIP (0.290s)
[390.992835] [095/156] (631s left) kms_cursor_crc (cursor-onscreen-128x42)
[391.212613] Starting subtest: cursor-onscreen-128x42
[391.264152] Subtest cursor-onscreen-128x42: SKIP (0.045s)
[391.505277] [096/156] (631s left) kms_scaling_modes (scaling-mode-center)
[391.590152] Starting subtest: scaling-mode-center
[391.592513] Starting dynamic subtest: pipe-A-eDP-1
[391.682536] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.094s)
[391.697100] Starting dynamic subtest: pipe-B-eDP-1
[392.923328] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.237s)
[392.935282] Starting dynamic subtest: pipe-C-eDP-1
[394.136214] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.210s)
[394.147782] Starting dynamic subtest: pipe-D-eDP-1
[395.280545] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.143s)
[395.282979] Subtest scaling-mode-center: SUCCESS (3.693s)
[396.526585] [097/156] (626s left) kms_psr (fbc-psr-cursor-mmap-gtt)
[396.614906] Starting subtest: fbc-psr-cursor-mmap-gtt
[396.617216] Starting dynamic subtest: eDP-1
[396.621610] Dynamic subtest eDP-1: SKIP (0.000s)
[396.626149] Subtest fbc-psr-cursor-mmap-gtt: SKIP (0.001s)
[396.691144] [098/156] (626s left) gem_lmem_swapping (verify-random-ccs)
[396.729987] Subtest verify-random-ccs: SKIP (0.000s)
[396.778175] [099/156] (626s left) kms_pm_lpsp (screens-disabled)
[397.883399] Starting subtest: screens-disabled
[397.887640] Subtest screens-disabled: SKIP (0.000s)
[397.991054] [100/156] (625s left) kms_content_protection (type1)
[398.132422] Starting subtest: type1
[398.137122] Subtest type1: SKIP (0.000s)
[398.265195] [101/156] (624s left) gem_mmap (short-mmap)
[398.312763] Subtest short-mmap: SKIP (0.000s)
[398.375976] [102/156] (624s left) kms_pm_rpm (modeset-pc8-residency-stress)
[400.020098] Starting subtest: modeset-pc8-residency-stress
[400.025102] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[400.681861] [103/156] (622s left) gen9_exec_parse (bb-start-far)
[400.750017] Subtest bb-start-far: SKIP (0.000s)
[400.829577] [104/156] (622s left) gem_exec_reloc (basic-wc-read-noreloc)
[400.898337] Subtest basic-wc-read-noreloc: SKIP (0.000s)
[400.967636] [105/156] (622s left) gem_exec_reloc (basic-wc-read)
[401.023477] Subtest basic-wc-read: SKIP (0.000s)
[401.095430] [106/156] (622s left) drm_read (empty-block)
[401.235853] Starting subtest: empty-block
[402.236048] Subtest empty-block: SUCCESS (1.000s)
[402.361180] [107/156] (620s left) perf_pmu (busy-idle)
[402.405499] Starting subtest: busy-idle
[402.407898] Starting dynamic subtest: rcs0
[403.095102] Dynamic subtest rcs0: SUCCESS (0.690s)
[403.107523] Starting dynamic subtest: bcs0
[403.756719] Dynamic subtest bcs0: SUCCESS (0.658s)
[403.769928] Starting dynamic subtest: vcs0
[404.855582] Dynamic subtest vcs0: SUCCESS (1.096s)
[404.868090] Starting dynamic subtest: vcs1
[406.841891] Dynamic subtest vcs1: SUCCESS (1.982s)
[406.854088] Starting dynamic subtest: vecs0
[408.825930] Dynamic subtest vecs0: SUCCESS (1.980s)
[408.838702] Starting dynamic subtest: ccs0
[409.494663] Dynamic subtest ccs0: SUCCESS (0.667s)
[409.497669] Subtest busy-idle: SUCCESS (7.088s)
[409.591576] [108/156] (613s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[409.698651] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[409.703187] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[409.794687] [109/156] (613s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[409.891011] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc
[409.895021] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[410.012301] [110/156] (613s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-plflip-blt)
[410.108051] Starting subtest: hdr-1p-primscrn-shrfb-plflip-blt
[410.112287] Subtest hdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[410.227567] [111/156] (613s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-mmap-gtt)
[410.321146] Starting subtest: psrhdr-rgb101010-draw-mmap-gtt
[410.325663] Subtest psrhdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[410.425678] [112/156] (613s left) gem_mmap_gtt (big-copy)
[410.480167] Subtest big-copy: SKIP (0.000s)
[410.544494] [113/156] (613s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-pwrite)
[410.638718] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-pwrite
[410.642237] Subtest hdr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[410.738918] [114/156] (612s left) i915_hangman (engine-error-state-capture)
[410.791834] Starting subtest: engine-error-state-capture
[410.793732] Starting dynamic subtest: rcs0
[416.856352] Dynamic subtest rcs0: SUCCESS (6.062s)
[416.867754] Starting dynamic subtest: bcs0
[422.808212] Dynamic subtest bcs0: SUCCESS (5.952s)
[422.822647] Starting dynamic subtest: vcs0
[428.761218] Dynamic subtest vcs0: SUCCESS (5.952s)
[428.774532] Starting dynamic subtest: vcs1
[433.753181] Dynamic subtest vcs1: SUCCESS (4.990s)
[433.765953] Starting dynamic subtest: vecs0
[438.743692] Dynamic subtest vecs0: SUCCESS (4.989s)
[438.755796] Starting dynamic subtest: ccs0
[444.823934] Dynamic subtest ccs0: SUCCESS (6.079s)
[444.826981] Subtest engine-error-state-capture: SUCCESS (34.039s)
[444.918384] [115/156] (578s left) syncobj_timeline (invalid-transfer-illegal-handle)
[444.966196] Starting subtest: invalid-transfer-illegal-handle
[444.968108] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[445.077173] [116/156] (578s left) kms_big_fb (4-tiled-16bpp-rotate-270)
[445.187901] Starting subtest: 4-tiled-16bpp-rotate-270
[445.208322] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.020s)
[445.351046] [117/156] (578s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-shrfb-msflip-blt)
[445.452625] Starting subtest: psrhdr-2p-primscrn-shrfb-msflip-blt
[445.456793] Subtest psrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[445.554110] [118/156] (578s left) prime_self_import (basic-with_one_bo)
[445.602666] Starting subtest: basic-with_one_bo
[445.627630] Subtest basic-with_one_bo: SUCCESS (0.023s)
[445.784580] [119/156] (578s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[445.886799] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[445.890245] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[445.989762] [120/156] (577s left) kms_busy (basic)
[446.085668] Starting subtest: basic
[446.087715] Starting dynamic subtest: flip
[446.689377] Dynamic subtest flip: SUCCESS (0.604s)
[446.702130] Starting dynamic subtest: modeset
[450.098629] Dynamic subtest modeset: SUCCESS (3.409s)
[450.101084] Subtest basic: SUCCESS (4.015s)
[450.903923] [121/156] (573s left) gem_ccs (ctrl-surf-copy-new-ctx)
[450.962650] Starting subtest: ctrl-surf-copy-new-ctx
[450.967514] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[451.068236] [122/156] (572s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[451.167614] Starting subtest: 4-tiled-64bpp-rotate-270
[451.187403] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.019s)
[451.304799] [123/156] (572s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-move)
[451.408065] Starting subtest: fbchdr-1p-primscrn-spr-indfb-move
[451.412083] Subtest fbchdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[451.501351] [124/156] (572s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[451.561046] Starting subtest: yf-tiled-to-vebox-x-tiled
[451.565797] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[451.741716] [125/156] (572s left) kms_flip (flip-vs-fences-interruptible)
[451.849093] Starting subtest: flip-vs-fences-interruptible
[451.854917] Subtest flip-vs-fences-interruptible: SKIP (0.000s)
[451.974476] [126/156] (572s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[452.067597] Starting subtest: primary-x-tiled-reflect-x-180
[459.105555] Subtest primary-x-tiled-reflect-x-180: SUCCESS (7.043s)
[460.358058] [127/156] (563s left) kms_big_fb (yf-tiled-32bpp-rotate-0)
[460.465240] Starting subtest: yf-tiled-32bpp-rotate-0
[460.469586] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s)
[460.567276] [128/156] (563s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-cpu)
[460.679918] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-cpu
[464.703727] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (4.030s)
[465.867053] [129/156] (558s left) kms_frontbuffer_tracking (fbchdr-stridechange)
[465.973562] Starting subtest: fbchdr-stridechange
[465.977512] Subtest fbchdr-stridechange: SKIP (0.000s)
[466.091514] [130/156] (558s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[466.213207] Starting subtest: basic-flip-before-cursor-varying-size
[467.144539] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.929s)
[467.994246] [131/156] (556s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[468.089528] Starting subtest: fbcpsr-rgb101010-draw-render
[472.855515] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (4.771s)
[472.981608] [132/156] (551s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[473.069868] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[473.072090] Starting dynamic subtest: pipe-A
[473.408072] Dynamic subtest pipe-A: SUCCESS (0.340s)
[473.418710] Starting dynamic subtest: pipe-B
[474.838728] Dynamic subtest pipe-B: SUCCESS (1.430s)
[474.849145] Starting dynamic subtest: pipe-C
[476.259405] Dynamic subtest pipe-C: SUCCESS (1.420s)
[476.270622] Starting dynamic subtest: pipe-D
[477.690790] Dynamic subtest pipe-D: SUCCESS (1.431s)
[477.693030] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.623s)
[478.842301] [133/156] (545s left) i915_pm_rps (fence-order)
[478.905248] Starting subtest: fence-order
[482.170026] Subtest fence-order: SUCCESS (3.263s)
[482.287221] [134/156] (542s left) kms_explicit_fence (multiplane-atomic-fence-wait)
[482.425531] Starting subtest: multiplane-atomic-fence-wait
[482.660847] Subtest multiplane-atomic-fence-wait: SUCCESS (0.232s)
[483.771176] [135/156] (540s left) gem_exec_big (single)
[483.829537] Starting subtest: single
[487.409332] Subtest single: FAIL (3.518s)
[487.452622] [136/156] (537s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-mmap-wc)
[487.618636] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-mmap-wc
[487.622974] Subtest psrhdr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[487.722282] [137/156] (536s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-move)
[487.829626] Starting subtest: hdr-2p-scndscrn-cur-indfb-move
[487.833742] Subtest hdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[487.925546] [138/156] (536s left) kms_cursor_crc (cursor-sliding-128x128)
[488.148862] Starting subtest: cursor-sliding-128x128
[488.151299] Starting dynamic subtest: pipe-A-eDP-1
[491.422548] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.269s)
[491.459666] Starting dynamic subtest: pipe-D-eDP-1
[495.362536] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.914s)
[495.365003] Subtest cursor-sliding-128x128: SUCCESS (7.215s)
[496.234240] [139/156] (528s left) gem_spin_batch (resubmit-all)
[496.308652] Starting subtest: resubmit-all
[496.311055] Starting dynamic subtest: rcs0
[496.319002] Dynamic subtest rcs0: SUCCESS (0.010s)
[496.332199] Starting dynamic subtest: bcs0
[496.334372] Dynamic subtest bcs0: SUCCESS (0.003s)
[496.344786] Starting dynamic subtest: vcs0
[496.346920] Dynamic subtest vcs0: SUCCESS (0.002s)
[496.357110] Starting dynamic subtest: vcs1
[496.359252] Dynamic subtest vcs1: SUCCESS (0.002s)
[496.369818] Starting dynamic subtest: vecs0
[496.371951] Dynamic subtest vecs0: SUCCESS (0.002s)
[496.381912] Starting dynamic subtest: ccs0
[496.383993] Dynamic subtest ccs0: SUCCESS (0.002s)
[496.386078] Subtest resubmit-all: SUCCESS (0.026s)
[497.892568] [140/156] (526s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[497.993928] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[497.998099] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[498.100938] [141/156] (526s left) gem_exec_suspend (basic-s3)
[498.156891] Starting subtest: basic-S3
[498.159223] Starting dynamic subtest: smem
[517.960700] Dynamic subtest smem: SUCCESS (6.611s)
[517.963473] Subtest basic-S3: SUCCESS (6.613s)
[518.168749] [142/156] (506s left) perf_pmu (pmu-read)
[518.217790] Starting subtest: pmu-read
[518.220158] Subtest pmu-read: SUCCESS (0.003s)
[518.325886] [143/156] (506s left) kms_vblank (query-forked-hang)
[518.419792] Starting subtest: query-forked-hang
[518.421989] Starting dynamic subtest: pipe-A-eDP-1
[544.790107] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.105s)
[544.805435] Starting dynamic subtest: pipe-D-eDP-1
[569.747501] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.942s)
[569.749765] Subtest query-forked-hang: SUCCESS (51.052s)
[570.549355] [144/156] (454s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[570.621934] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[570.624388] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[570.742139] [145/156] (454s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[570.841913] Starting subtest: psr-rgb101010-draw-mmap-wc
[577.965413] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (7.126s)
[578.068611] [146/156] (446s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[578.173991] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[585.276886] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.104s)
[585.400612] [147/156] (439s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[585.497983] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[585.502601] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[585.607658] [148/156] (439s left) kms_psr (fbc-psr2-sprite-blt)
[585.693317] Starting subtest: fbc-psr2-sprite-blt
[585.695668] Starting dynamic subtest: eDP-1
[585.699890] Dynamic subtest eDP-1: SKIP (0.000s)
[585.704365] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[585.772037] [149/156] (439s left) kms_cursor_legacy (flip-vs-cursor-atomic)
[585.886565] Starting subtest: flip-vs-cursor-atomic
[593.439627] Subtest flip-vs-cursor-atomic: SUCCESS (7.547s)
[594.331491] [150/156] (430s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[594.428210] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[594.432413] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (0.000s)
[594.513211] [151/156] (430s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[594.608073] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs-cc
[594.610416] Starting dynamic subtest: pipe-A-eDP-1
[594.633289] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[594.645345] Starting dynamic subtest: pipe-B-eDP-1
[594.666669] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[594.677157] Starting dynamic subtest: pipe-C-eDP-1
[594.699814] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[594.712127] Starting dynamic subtest: pipe-D-eDP-1
[594.733854] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[594.738307] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[596.901460] [152/156] (428s left) kms_psr (fbc-pr-sprite-blt)
[596.998304] Starting subtest: fbc-pr-sprite-blt
[597.002714] Subtest fbc-pr-sprite-blt: SKIP (0.000s)
[597.073870] [153/156] (428s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-pwrite)
[597.171871] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-pwrite
[597.176475] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[597.284401] [154/156] (428s left) gem_userptr_blits (invalid-mmap-offset-unsync)
[597.347900] Subtest invalid-mmap-offset-unsync: SKIP (0.000s)
[597.477228] [155/156] (427s left) gem_basic (create-fd-close)
[597.536154] Starting subtest: create-fd-close
[597.540554] Subtest create-fd-close: SUCCESS (0.000s)
[597.629698] [156/156] (427s left) kms_plane_alpha_blend (constant-alpha-mid)
[597.730863] Starting subtest: constant-alpha-mid
[598.014170] Starting dynamic subtest: pipe-A-eDP-1
[598.982932] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.967s)
[598.994335] Starting dynamic subtest: pipe-D-eDP-1
[600.526442] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.541s)
[600.528885] Subtest constant-alpha-mid: SUCCESS (2.797s)
[601.365676] Closing watchdogs
results: parsing output: 0/ for test: sysfs_heartbeat_interval
results: parsing output: 1/ for test: syncobj_wait
results: parsing output: 2/ for test: kms_flip_scaled_crc
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: kms_ccs
results: parsing output: 6/ for test: kms_psr2_sf
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: syncobj_wait
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: kms_fence_pin_leak
results: parsing output: 11/ for test: gem_create
results: parsing output: 12/ for test: i915_query
results: parsing output: 13/ for test: dmabuf
results: parsing output: 14/ for test: perf
results: parsing output: 15/ for test: kms_flip
results: parsing output: 16/ for test: kms_cursor_legacy
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_lease
results: parsing output: 19/ for test: kms_hdr
results: parsing output: 20/ for test: kms_plane
results: parsing output: 21/ for test: kms_plane_scaling
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_cursor_crc
results: parsing output: 24/ for test: prime_self_import
results: parsing output: 25/ for test: kms_async_flips
results: parsing output: 26/ for test: syncobj_timeline
results: parsing output: 27/ for test: kms_vblank
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_cursor_legacy
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_exec_schedule
results: parsing output: 35/ for test: syncobj_timeline
results: parsing output: 36/ for test: gem_pwrite
results: parsing output: 37/ for test: gem_exec_balancer
results: parsing output: 38/ for test: gem_exec_schedule
results: parsing output: 39/ for test: kms_chamelium_hpd
results: parsing output: 40/ for test: core_getversion
results: parsing output: 41/ for test: kms_plane_scaling
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_ctx_persistence
results: parsing output: 44/ for test: gem_gpgpu_fill
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: gem_mmap_offset
results: parsing output: 50/ for test: gem_eio
results: parsing output: 51/ for test: gen9_exec_parse
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_dirtyfb
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: gem_exec_reloc
results: parsing output: 57/ for test: kms_ccs
results: parsing output: 58/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_ccs
results: parsing output: 61/ for test: gem_exec_schedule
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: syncobj_basic
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_cursor_legacy
results: parsing output: 66/ for test: gem_exec_reloc
results: parsing output: 67/ for test: gem_render_copy
results: parsing output: 68/ for test: gem_exec_params
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_psr2_sf
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: gem_eio
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: gem_exec_whisper
results: parsing output: 76/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 77/ for test: kms_color
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_invalid_mode
results: parsing output: 80/ for test: prime_busy
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_addfb_basic
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_chamelium_frames
results: parsing output: 87/ for test: api_intel_bb
results: parsing output: 88/ for test: gem_exec_fence
results: parsing output: 89/ for test: gem_exec_reloc
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: gem_tiling_max_stride
results: parsing output: 92/ for test: gem_ctx_persistence
results: parsing output: 93/ for test: kms_cdclk
results: parsing output: 94/ for test: kms_cursor_crc
results: parsing output: 95/ for test: kms_scaling_modes
results: parsing output: 96/ for test: kms_psr
results: parsing output: 97/ for test: gem_lmem_swapping
results: parsing output: 98/ for test: kms_pm_lpsp
results: parsing output: 99/ for test: kms_content_protection
results: parsing output: 100/ for test: gem_mmap
results: parsing output: 101/ for test: kms_pm_rpm
results: parsing output: 102/ for test: gen9_exec_parse
results: parsing output: 103/ for test: gem_exec_reloc
results: parsing output: 104/ for test: gem_exec_reloc
results: parsing output: 105/ for test: drm_read
results: parsing output: 106/ for test: perf_pmu
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
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_mmap_gtt
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: i915_hangman
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: kms_big_fb
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: prime_self_import
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_busy
results: parsing output: 120/ for test: gem_ccs
results: parsing output: 121/ for test: kms_big_fb
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: gem_render_copy
results: parsing output: 124/ for test: kms_flip
results: parsing output: 125/ for test: kms_rotation_crc
results: parsing output: 126/ for test: kms_big_fb
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_cursor_legacy
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_plane_scaling
results: parsing output: 132/ for test: i915_pm_rps
results: parsing output: 133/ for test: kms_explicit_fence
results: parsing output: 134/ for test: gem_exec_big
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_cursor_crc
results: parsing output: 138/ for test: gem_spin_batch
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: gem_exec_suspend
results: parsing output: 141/ for test: perf_pmu
results: parsing output: 142/ for test: kms_vblank
results: parsing output: 143/ for test: syncobj_timeline
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: kms_psr
results: parsing output: 148/ for test: kms_cursor_legacy
results: parsing output: 149/ for test: kms_plane
results: parsing output: 150/ for test: kms_ccs
results: parsing output: 151/ for test: kms_psr
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: gem_userptr_blits
results: parsing output: 154/ for test: gem_basic
results: parsing output: 155/ for test: kms_plane_alpha_blend
Done.