[56.866256] Initializing watchdogs
[56.866302]   /dev/watchdog0
[56.881209] [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]
[56.885527] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.887892] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.891226] [001/155] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[57.034452] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[57.038842] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[57.142787] [002/155] (960s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted)
[57.193915] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[57.196389] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[57.291338] [003/155] (960s left) gem_softpin (32b-excludes-last-page)
[57.356860] Starting subtest: 32b-excludes-last-page
[57.361779] Subtest 32b-excludes-last-page: SUCCESS (0.004s)
[57.492545] [004/155] (960s left) gem_exec_create (forked)
[57.558023] Starting subtest: forked
[57.560447] Starting dynamic subtest: smem
[79.292868] Dynamic subtest smem: SUCCESS (21.599s)
[79.295882] Subtest forked: SUCCESS (21.600s)
[79.469778] [005/155] (938s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs)
[79.562125] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs
[79.564884] Starting dynamic subtest: pipe-A-eDP-1
[79.590137] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[79.599508] Starting dynamic subtest: pipe-B-eDP-1
[79.623736] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[79.630745] Starting dynamic subtest: pipe-C-eDP-1
[79.654613] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[79.661620] Starting dynamic subtest: pipe-D-eDP-1
[79.685788] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[79.690683] Subtest random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.003s)
[82.256328] [006/155] (935s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[82.362539] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[82.364718] Starting dynamic subtest: pipe-A
[82.384486] Dynamic subtest pipe-A: SKIP (0.017s)
[82.395069] Starting dynamic subtest: pipe-B
[82.423349] Dynamic subtest pipe-B: SKIP (0.041s)
[82.430738] Starting dynamic subtest: pipe-C
[82.461822] Dynamic subtest pipe-C: SKIP (0.033s)
[82.475415] Starting dynamic subtest: pipe-D
[82.487272] Dynamic subtest pipe-D: SKIP (0.030s)
[82.491228] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.122s)
[82.595228] [007/155] (935s left) gen9_exec_parse (allowed-all)
[82.656312] Subtest allowed-all: SKIP (0.000s)
[82.726691] [008/155] (934s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[82.827103] Starting subtest: y-tiled-16bpp-rotate-90
[82.831101] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[82.911861] [009/155] (934s left) syncobj_timeline (wait-any-complex)
[82.963845] Starting subtest: wait-any-complex
[83.027279] Subtest wait-any-complex: SUCCESS (0.063s)
[83.101658] [010/155] (934s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[83.205143] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[87.628772] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.425s)
[88.790077] [011/155] (928s left) kms_busy (extended-pageflip-hang-oldfb)
[88.881465] Starting subtest: extended-pageflip-hang-oldfb
[88.883818] Starting dynamic subtest: pipe-A
[89.337693] Dynamic subtest pipe-A: SUCCESS (0.451s)
[89.352236] Starting dynamic subtest: pipe-D
[90.496348] Dynamic subtest pipe-D: SUCCESS (1.156s)
[90.498853] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.614s)
[91.340119] [012/155] (926s left) kms_frontbuffer_tracking (fbc-stridechange)
[91.451353] Starting subtest: fbc-stridechange
[94.732348] Subtest fbc-stridechange: SUCCESS (3.285s)
[95.940904] [013/155] (921s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[96.085935] Starting subtest: dp-mst-lic-type-0-hdcp14
[96.090615] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[96.209639] [014/155] (921s left) gem_eio (in-flight-contexts-10ms)
[96.431582] Starting subtest: in-flight-contexts-10ms
[110.281148] Subtest in-flight-contexts-10ms: SUCCESS (13.847s)
[110.411437] [015/155] (907s left) kms_hdmi_inject (inject-audio)
[110.744851] Starting subtest: inject-audio
[111.039306] Subtest inject-audio: SUCCESS (0.296s)
[111.609850] [016/155] (906s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[111.700321] Starting subtest: fbc-pr-sprite-mmap-cpu
[111.704492] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[111.765833] [017/155] (906s left) syncobj_timeline (invalid-transfer-illegal-handle)
[111.819052] Starting subtest: invalid-transfer-illegal-handle
[111.821071] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[111.936111] [018/155] (906s left) gem_exec_suspend (basic-s3-devices)
[111.989596] Starting subtest: basic-S3-devices
[111.991778] Starting dynamic subtest: smem
[123.258066] Dynamic subtest smem: SUCCESS (11.265s)
[123.261070] Subtest basic-S3-devices: SUCCESS (11.266s)
[123.436925] [019/155] (894s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[123.710385] Starting subtest: cursor-rapid-movement-512x512
[123.714667] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[123.879907] [020/155] (894s left) gem_pxp (reject-modify-context-protection-off-3)
[124.004760] Starting subtest: reject-modify-context-protection-off-3
[124.007613] Subtest reject-modify-context-protection-off-3: SUCCESS (0.002s)
[124.120775] [021/155] (894s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[124.209302] Starting subtest: disable-crc-after-crtc
[124.211658] Starting dynamic subtest: pipe-A-eDP-1
[125.875226] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.665s)
[125.886161] Starting dynamic subtest: pipe-B-eDP-1
[128.530143] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.656s)
[128.540920] Starting dynamic subtest: pipe-C-eDP-1
[130.862779] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.330s)
[130.872051] Starting dynamic subtest: pipe-D-eDP-1
[133.158994] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.295s)
[133.161424] Subtest disable-crc-after-crtc: SUCCESS (8.950s)
[133.981580] [022/155] (884s left) gem_wait (await)
[134.046422] Starting subtest: await
[134.048805] Starting dynamic subtest: all-engines
[135.221654] Dynamic subtest all-engines: SUCCESS (1.175s)
[135.238256] Starting dynamic subtest: rcs0
[136.275840] Dynamic subtest rcs0: SUCCESS (1.053s)
[136.291474] Starting dynamic subtest: bcs0
[137.335908] Dynamic subtest bcs0: SUCCESS (1.058s)
[137.351487] Starting dynamic subtest: vcs0
[138.391519] Dynamic subtest vcs0: SUCCESS (1.055s)
[138.409302] Starting dynamic subtest: vcs1
[139.448426] Dynamic subtest vcs1: SUCCESS (1.056s)
[139.465528] Starting dynamic subtest: vecs0
[140.503187] Dynamic subtest vecs0: SUCCESS (1.054s)
[140.520602] Starting dynamic subtest: ccs0
[141.560327] Dynamic subtest ccs0: SUCCESS (1.056s)
[141.562833] Subtest await: SUCCESS (7.514s)
[141.759376] [FACT gem_wait (await)] new: kernel.kmod_is_loaded.vgem: true
[141.762614] [023/155] (876s left) gem_exec_create (basic)
[141.819760] Starting subtest: basic
[141.821955] Starting dynamic subtest: smem
[145.276123] Dynamic subtest smem: SUCCESS (3.454s)
[145.278581] Subtest basic: SUCCESS (3.455s)
[145.458719] [024/155] (872s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[145.557295] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[146.181467] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.626s)
[146.343122] [025/155] (871s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[146.452491] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[153.243416] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.794s)
[153.379820] [026/155] (864s left) kms_flip (basic-flip-vs-wf_vblank)
[153.484455] Starting subtest: basic-flip-vs-wf_vblank
[153.486759] Starting dynamic subtest: A-eDP1
[155.382132] Dynamic subtest A-eDP1: SUCCESS (1.895s)
[155.395768] Starting dynamic subtest: B-eDP1
[157.274358] Dynamic subtest B-eDP1: SUCCESS (1.891s)
[157.285060] Starting dynamic subtest: C-eDP1
[159.168950] Dynamic subtest C-eDP1: SUCCESS (1.894s)
[159.181256] Starting dynamic subtest: D-eDP1
[161.068622] Dynamic subtest D-eDP1: SUCCESS (1.897s)
[161.070781] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.585s)
[162.286863] [027/155] (856s left) gem_exec_reloc (basic-write-gtt-active)
[162.346756] Subtest basic-write-gtt-active: SKIP (0.000s)
[162.420571] [028/155] (856s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-gtt)
[162.512020] Starting subtest: psr-rgb101010-draw-mmap-gtt
[162.516104] Subtest psr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[162.612728] [029/155] (855s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-msflip-blt)
[162.714260] Starting subtest: fbchdr-2p-primscrn-indfb-msflip-blt
[162.718138] Subtest fbchdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[162.818138] [030/155] (855s left) api_intel_allocator (alloc-simple)
[162.865973] Starting subtest: alloc-simple
[162.868242] Subtest alloc-simple: SUCCESS (0.003s)
[162.987975] [031/155] (855s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[163.037556] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[163.039877] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.001s)
[163.127961] [032/155] (855s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[163.215615] Starting subtest: primary-yf-tiled-reflect-x-180
[163.274831] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[163.376046] [033/155] (855s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[163.464072] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[163.467806] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[163.547093] [034/155] (855s left) syncobj_eventfd (timeline-wait-signaled)
[163.590559] Starting subtest: timeline-wait-signaled
[163.592517] Subtest timeline-wait-signaled: SUCCESS (0.000s)
[163.687699] [035/155] (855s left) kms_flip (wf_vblank-ts-check)
[163.776852] Starting subtest: wf_vblank-ts-check
[163.778497] Starting dynamic subtest: A-eDP1
[172.673120] Dynamic subtest A-eDP1: SUCCESS (8.897s)
[172.684146] Starting dynamic subtest: B-eDP1
[181.583124] Dynamic subtest B-eDP1: SUCCESS (8.908s)
[181.594143] Starting dynamic subtest: C-eDP1
[190.460063] Dynamic subtest C-eDP1: SUCCESS (8.874s)
[190.469475] Starting dynamic subtest: D-eDP1
[199.370396] Dynamic subtest D-eDP1: SUCCESS (8.910s)
[199.375402] Subtest wf_vblank-ts-check: SUCCESS (35.597s)
[200.583708] [036/155] (818s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[201.142613] Starting subtest: syncobj-timeline-invalid-flags
[201.144801] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.001s)
[202.383786] [037/155] (816s left) gem_gpgpu_fill (basic)
[202.446318] Starting subtest: basic
[202.448789] Starting dynamic subtest: smem
[202.455078] Dynamic subtest smem: SUCCESS (0.009s)
[202.457563] Subtest basic: SUCCESS (0.010s)
[202.609089] [038/155] (816s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[202.701832] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-wc
[202.706296] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[202.803193] [039/155] (816s left) perf (invalid-oa-format-id)
[202.925714] Starting subtest: invalid-oa-format-id
[202.938746] Subtest invalid-oa-format-id: SUCCESS (0.013s)
[202.990154] [040/155] (815s left) kms_addfb_basic (unused-handle)
[203.045815] Starting subtest: unused-handle
[203.047981] Subtest unused-handle: SUCCESS (0.000s)
[203.181825] [041/155] (815s left) gem_mmap_gtt (ptrace)
[203.236819] Subtest ptrace: SKIP (0.000s)
[203.314854] [042/155] (815s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt)
[203.420916] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt
[203.424499] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[203.519228] [043/155] (815s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[203.616627] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[203.619971] Subtest fbchdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[203.718596] [044/155] (815s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[203.814984] Starting subtest: fbc-rgb565-draw-render
[207.957523] Subtest fbc-rgb565-draw-render: SUCCESS (4.146s)
[209.162782] [045/155] (810s left) kms_pm_dc (dc6-psr)
[210.269419] Starting subtest: dc6-psr
[213.152687] Subtest dc6-psr: SUCCESS (2.883s)
[213.958954] [046/155] (805s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[214.043297] Starting subtest: nonblocking-crc-frame-sequence
[214.045473] Starting dynamic subtest: pipe-A-eDP-1
[214.773468] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.728s)
[214.784380] Starting dynamic subtest: pipe-B-eDP-1
[216.235654] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.460s)
[216.245725] Starting dynamic subtest: pipe-C-eDP-1
[217.587119] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.350s)
[217.597657] Starting dynamic subtest: pipe-D-eDP-1
[218.997953] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.411s)
[219.000385] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.954s)
[219.859141] [047/155] (799s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[219.954360] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[219.956690] Starting dynamic subtest: pipe-A-default-mode
[222.623255] Dynamic subtest pipe-A-default-mode: SUCCESS (2.670s)
[222.625966] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.671s)
[223.461382] [048/155] (795s left) kms_feature_discovery (display-2x)
[223.558513] Starting subtest: display-2x
[223.562550] Subtest display-2x: SKIP (0.000s)
[223.633517] [049/155] (795s left) gem_exec_schedule (u-fairslice-all)
[223.703768] Starting subtest: u-fairslice-all
[225.760802] Subtest u-fairslice-all: SUCCESS (2.061s)
[227.306925] [050/155] (792s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-pwrite)
[227.413403] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-pwrite
[227.417712] Subtest fbchdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[227.514231] [051/155] (791s left) core_auth (getclient-master-drop)
[227.550157] Starting subtest: getclient-master-drop
[227.579705] Subtest getclient-master-drop: SUCCESS (0.031s)
[227.709835] [052/155] (791s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[227.802545] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[227.804852] Starting dynamic subtest: pipe-A-eDP-1
[227.875911] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[227.886326] Starting dynamic subtest: pipe-B-eDP-1
[228.998767] Dynamic subtest pipe-B-eDP-1: SKIP (1.120s)
[229.003692] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.199s)
[230.255775] [053/155] (789s left) gem_mmap_gtt (fault-concurrent)
[230.322758] Subtest fault-concurrent: SKIP (0.000s)
[230.385554] [054/155] (789s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-wc)
[230.489840] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-wc
[230.494401] Subtest fbcpsrhdr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[230.586969] [055/155] (789s left) kms_atomic_transition (modeset-transition-nonblocking)
[230.677815] Starting subtest: modeset-transition-nonblocking
[230.679745] Starting dynamic subtest: 1x-outputs
[241.729980] Dynamic subtest 1x-outputs: SUCCESS (11.052s)
[241.732555] Subtest modeset-transition-nonblocking: SUCCESS (11.053s)
[242.560753] [056/155] (777s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[242.653249] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[242.655931] Starting dynamic subtest: pipe-A-eDP-1
[242.679977] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[242.692592] Starting dynamic subtest: pipe-B-eDP-1
[242.714216] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[242.724467] Starting dynamic subtest: pipe-C-eDP-1
[242.746216] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[242.755601] Starting dynamic subtest: pipe-D-eDP-1
[242.776829] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[242.781168] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.005s)
[244.865743] [057/155] (774s left) kms_flip (flip-vs-expired-vblank-interruptible)
[244.964381] Starting subtest: flip-vs-expired-vblank-interruptible
[244.966733] Starting dynamic subtest: A-eDP1
[253.621282] Dynamic subtest A-eDP1: SUCCESS (8.656s)
[253.631801] Starting dynamic subtest: B-eDP1
[255.338193] Dynamic subtest B-eDP1: FAIL (1.491s)
[255.348678] Starting dynamic subtest: C-eDP1
[263.713485] Dynamic subtest C-eDP1: SUCCESS (8.598s)
[263.724078] Starting dynamic subtest: D-eDP1
[272.331058] Dynamic subtest D-eDP1: SUCCESS (8.616s)
[272.333481] Subtest flip-vs-expired-vblank-interruptible: FAIL (27.369s)
[273.588878] [058/155] (746s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[273.700312] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[273.704678] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[273.802480] [059/155] (745s left) gem_media_fill (media-fill)
[273.861156] Subtest media-fill: SKIP (0.000s)
[273.986399] [060/155] (745s left) kms_chamelium_frames (dp-crc-single)
[274.373622] Subtest dp-crc-single: SKIP (0.000s)
[275.238571] [061/155] (744s left) core_auth (basic-auth)
[275.288185] Starting subtest: basic-auth
[275.290560] Subtest basic-auth: SUCCESS (0.002s)
[275.408466] [062/155] (744s left) gem_mmap_gtt (hang)
[275.467372] Subtest hang: SKIP (0.000s)
[275.536715] [063/155] (744s left) perf_pmu (busy-idle)
[275.572407] Starting subtest: busy-idle
[275.574712] Starting dynamic subtest: rcs0
[276.259360] Dynamic subtest rcs0: SUCCESS (0.689s)
[276.272299] Starting dynamic subtest: bcs0
[276.935380] Dynamic subtest bcs0: SUCCESS (0.674s)
[276.948487] Starting dynamic subtest: vcs0
[278.265290] Dynamic subtest vcs0: SUCCESS (1.326s)
[278.281481] Starting dynamic subtest: vcs1
[280.314542] Dynamic subtest vcs1: SUCCESS (2.047s)
[280.327559] Starting dynamic subtest: vecs0
[282.300427] Dynamic subtest vecs0: SUCCESS (1.983s)
[282.314203] Starting dynamic subtest: ccs0
[282.967896] Dynamic subtest ccs0: SUCCESS (0.666s)
[282.970629] Subtest busy-idle: SUCCESS (7.399s)
[283.064650] [064/155] (736s left) kms_cursor_legacy (single-move)
[283.185574] Starting subtest: single-move
[283.187779] Starting dynamic subtest: pipe-A
[288.568129] Dynamic subtest pipe-A: SUCCESS (5.379s)
[288.579792] Starting dynamic subtest: pipe-B
[293.950519] Dynamic subtest pipe-B: SUCCESS (5.379s)
[293.964644] Starting dynamic subtest: pipe-C
[299.331146] Dynamic subtest pipe-C: SUCCESS (5.379s)
[299.342816] Starting dynamic subtest: pipe-D
[304.714089] Dynamic subtest pipe-D: SUCCESS (5.380s)
[304.725853] Starting dynamic subtest: all-pipes
[310.102694] Dynamic subtest all-pipes: SUCCESS (5.386s)
[310.105056] Subtest single-move: SUCCESS (26.915s)
[310.246718] [065/155] (709s left) perf_pmu (rc6)
[310.286806] Starting subtest: rc6
[310.289150] Starting dynamic subtest: gt0
[316.660189] Dynamic subtest gt0: SUCCESS (6.373s)
[316.677706] Starting dynamic subtest: runtime-pm-gt0
[324.391841] Dynamic subtest runtime-pm-gt0: SUCCESS (7.728s)
[324.404749] Starting dynamic subtest: runtime-pm-long-gt0
[336.895435] Dynamic subtest runtime-pm-long-gt0: SUCCESS (12.501s)
[336.909034] Starting dynamic subtest: other-idle-gt0
[343.241987] Dynamic subtest other-idle-gt0: SUCCESS (6.344s)
[343.255744] Starting dynamic subtest: gt1
[349.584087] Dynamic subtest gt1: SUCCESS (6.340s)
[349.596143] Starting dynamic subtest: runtime-pm-gt1
[357.063858] Dynamic subtest runtime-pm-gt1: SUCCESS (7.477s)
[357.076939] Starting dynamic subtest: runtime-pm-long-gt1
[369.501366] Dynamic subtest runtime-pm-long-gt1: SUCCESS (12.435s)
[369.518675] Starting dynamic subtest: other-idle-gt1
[375.848891] Dynamic subtest other-idle-gt1: SUCCESS (6.344s)
[375.851563] Subtest rc6: SUCCESS (65.559s)
[377.008101] [066/155] (643s left) kms_chamelium_color (ctm-green-to-red)
[377.104185] Subtest ctm-green-to-red: SKIP (0.000s)
[377.154446] [067/155] (642s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[377.250302] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[377.260741] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.010s)
[377.314629] [068/155] (642s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-blt)
[377.414724] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-blt
[377.418088] Subtest hdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[377.485508] [069/155] (642s left) kms_ccs (crc-primary-basic-4-tiled-lnl-ccs)
[377.573402] Starting subtest: crc-primary-basic-4-tiled-lnl-ccs
[377.577067] Subtest crc-primary-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[378.724128] [070/155] (641s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-blt)
[378.818794] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-blt
[378.822755] Subtest hdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[378.900886] [071/155] (641s left) kms_big_fb (linear-8bpp-rotate-90)
[379.000626] Starting subtest: linear-8bpp-rotate-90
[379.020817] Subtest linear-8bpp-rotate-90: SKIP (0.020s)
[379.102912] [072/155] (641s left) syncobj_timeline (etime-multi-wait-submitted)
[379.158555] Starting subtest: etime-multi-wait-submitted
[379.262105] Subtest etime-multi-wait-submitted: SUCCESS (0.101s)
[379.315539] [073/155] (641s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-blt)
[379.411835] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-blt
[379.416129] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[379.494646] [074/155] (640s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[379.601123] Starting subtest: plane-lut1d-ctm3x4-lut1d
[379.758610] Starting dynamic subtest: pipe-A-plane-0
[379.925086] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s)
[379.938312] Starting dynamic subtest: pipe-A-plane-1
[380.059923] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[380.070611] Starting dynamic subtest: pipe-A-plane-2
[380.202973] Dynamic subtest pipe-A-plane-2: SUCCESS (0.144s)
[381.406312] Starting dynamic subtest: pipe-B-plane-0
[381.594951] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s)
[381.606072] Starting dynamic subtest: pipe-B-plane-1
[381.784470] Dynamic subtest pipe-B-plane-1: SUCCESS (0.188s)
[381.796356] Starting dynamic subtest: pipe-B-plane-2
[381.939859] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[383.099971] Starting dynamic subtest: pipe-C-plane-0
[383.253575] Dynamic subtest pipe-C-plane-0: SUCCESS (0.166s)
[383.264702] Starting dynamic subtest: pipe-C-plane-1
[383.420876] Dynamic subtest pipe-C-plane-1: SUCCESS (0.166s)
[383.431176] Starting dynamic subtest: pipe-C-plane-2
[383.575655] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[384.805057] Starting dynamic subtest: pipe-D-plane-0
[384.940700] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[384.950861] Starting dynamic subtest: pipe-D-plane-1
[385.074234] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[385.084758] Starting dynamic subtest: pipe-D-plane-2
[385.229370] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[385.536307] Subtest plane-lut1d-ctm3x4-lut1d: SUCCESS (5.935s)
[386.326944] [075/155] (634s left) gem_mmap_wc (write-wc-read-gtt)
[386.391222] Subtest write-wc-read-gtt: SKIP (0.000s)
[386.456322] [076/155] (634s left) kms_addfb_basic (bad-pitch-128)
[386.506325] Starting subtest: bad-pitch-128
[386.508689] Subtest bad-pitch-128: SUCCESS (0.000s)
[386.613556] [077/155] (633s left) kms_vblank (query-forked)
[386.704069] Starting subtest: query-forked
[386.706168] Starting dynamic subtest: pipe-A-eDP-1
[388.517838] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.812s)
[388.535311] Starting dynamic subtest: pipe-D-eDP-1
[391.049133] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.531s)
[391.051586] Subtest query-forked: SUCCESS (4.347s)
[391.851426] [078/155] (628s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier)
[391.960332] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier
[391.985917] Starting dynamic subtest: pipe-A-plane-0
[392.252981] Dynamic subtest pipe-A-plane-0: SUCCESS (0.267s)
[392.263368] Starting dynamic subtest: pipe-A-plane-3
[392.463957] Dynamic subtest pipe-A-plane-3: SUCCESS (0.210s)
[392.473915] Starting dynamic subtest: pipe-A-plane-5
[392.478519] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[393.600577] Starting dynamic subtest: pipe-B-plane-0
[393.869170] Dynamic subtest pipe-B-plane-0: SUCCESS (0.278s)
[393.881631] Starting dynamic subtest: pipe-B-plane-3
[394.079791] Dynamic subtest pipe-B-plane-3: SUCCESS (0.208s)
[394.090465] Starting dynamic subtest: pipe-B-plane-5
[394.095607] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[394.399949] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SUCCESS (2.442s)
[395.548669] [079/155] (625s left) vgem_basic (setversion)
[395.593271] Starting subtest: setversion
[395.597935] Subtest setversion: SUCCESS (0.000s)
[395.635932] [080/155] (625s left) perf_pmu (busy-start)
[395.676621] Starting subtest: busy-start
[395.679040] Starting dynamic subtest: rcs0
[398.228668] Dynamic subtest rcs0: SUCCESS (2.552s)
[398.240896] Starting dynamic subtest: bcs0
[400.779288] Dynamic subtest bcs0: SUCCESS (2.548s)
[400.792572] Starting dynamic subtest: vcs0
[404.281446] Dynamic subtest vcs0: SUCCESS (3.499s)
[404.294495] Starting dynamic subtest: vcs1
[408.313707] Dynamic subtest vcs1: SUCCESS (4.029s)
[408.326627] Starting dynamic subtest: vecs0
[412.286759] Dynamic subtest vecs0: SUCCESS (3.965s)
[412.299494] Starting dynamic subtest: ccs0
[414.832598] Dynamic subtest ccs0: SUCCESS (2.549s)
[414.835206] Subtest busy-start: SUCCESS (19.156s)
[414.906269] [081/155] (605s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[415.001977] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[415.006446] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[415.083152] [082/155] (605s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-indfb-msflip-blt)
[415.189845] Starting subtest: psrhdr-2p-scndscrn-indfb-msflip-blt
[415.194021] Subtest psrhdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[415.257560] [083/155] (605s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[415.365413] Starting subtest: cursor-vs-flip-varying-size
[446.483023] Subtest cursor-vs-flip-varying-size: SUCCESS (31.118s)
[447.317777] [084/155] (573s left) gem_exec_reloc (basic-cpu-wc)
[447.382985] Subtest basic-cpu-wc: SKIP (0.000s)
[447.460256] [085/155] (573s left) kms_lease (implicit-plane-lease)
[447.556227] Starting subtest: implicit-plane-lease
[447.558404] Subtest implicit-plane-lease: SUCCESS (0.003s)
[447.638925] [086/155] (573s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[447.737499] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[451.986843] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.255s)
[453.135983] [087/155] (567s left) kms_psr (fbc-psr-cursor-render)
[453.238962] Starting subtest: fbc-psr-cursor-render
[453.241231] Starting dynamic subtest: eDP-1
[453.245511] Dynamic subtest eDP-1: SKIP (0.000s)
[453.249747] Subtest fbc-psr-cursor-render: SKIP (0.001s)
[453.290016] [088/155] (567s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[453.411645] Starting subtest: cursorB-vs-flipA-varying-size
[453.416177] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[453.537616] [089/155] (567s left) gem_userptr_blits (map-fixed-invalidate)
[453.612847] Starting subtest: map-fixed-invalidate
[453.617313] Subtest map-fixed-invalidate: SKIP (0.000s)
[453.719081] [090/155] (567s left) prime_busy (hang)
[453.779117] Starting subtest: hang
[453.781363] Starting dynamic subtest: rcs0
[467.797825] Dynamic subtest rcs0: SUCCESS (14.014s)
[467.809770] Starting dynamic subtest: bcs0
[474.957079] Dynamic subtest bcs0: SUCCESS (7.158s)
[474.966888] Starting dynamic subtest: vcs0
[481.929407] Dynamic subtest vcs0: SUCCESS (6.970s)
[481.939141] Starting dynamic subtest: vcs1
[488.965119] Dynamic subtest vcs1: SUCCESS (7.035s)
[488.974706] Starting dynamic subtest: vecs0
[495.939747] Dynamic subtest vecs0: SUCCESS (6.974s)
[495.954919] Starting dynamic subtest: ccs0
[509.777726] Dynamic subtest ccs0: SUCCESS (13.836s)
[509.779814] Subtest hang: SUCCESS (55.995s)
[509.875682] [091/155] (511s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[509.982842] Starting subtest: pipe-fbc-rte
[509.989685] Starting dynamic subtest: pipe-B-eDP-1
[515.459060] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.482s)
[515.467276] Subtest pipe-fbc-rte: SUCCESS (5.484s)
[517.714802] [092/155] (503s left) gem_eio (in-flight-internal-immediate)
[517.888274] Starting subtest: in-flight-internal-immediate
[519.341302] Subtest in-flight-internal-immediate: SUCCESS (1.453s)
[519.453261] [093/155] (501s left) i915_pm_freq_api (freq-reset-multiple)
[519.518860] Starting subtest: freq-reset-multiple
[519.521069] Starting dynamic subtest: gt0
[527.954471] Dynamic subtest gt0: SUCCESS (8.436s)
[527.964416] Starting dynamic subtest: gt1
[535.344910] Dynamic subtest gt1: SUCCESS (7.389s)
[535.347467] Subtest freq-reset-multiple: SUCCESS (15.827s)
[535.400528] [094/155] (485s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[535.503562] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[535.528237] Starting dynamic subtest: pipe-A-plane-0
[535.927830] Dynamic subtest pipe-A-plane-0: SUCCESS (0.400s)
[535.939336] Starting dynamic subtest: pipe-A-plane-3
[536.251939] Dynamic subtest pipe-A-plane-3: SUCCESS (0.321s)
[537.383089] Starting dynamic subtest: pipe-B-plane-0
[537.785807] Dynamic subtest pipe-B-plane-0: SUCCESS (0.411s)
[537.796947] Starting dynamic subtest: pipe-B-plane-3
[538.107750] Dynamic subtest pipe-B-plane-3: SUCCESS (0.321s)
[538.417275] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (2.916s)
[539.209867] [095/155] (482s left) kms_psr2_sf (pr-overlay-primary-update-sf-dmg-area)
[539.310073] Starting subtest: pr-overlay-primary-update-sf-dmg-area
[539.314690] Subtest pr-overlay-primary-update-sf-dmg-area: SKIP (0.000s)
[539.381845] [096/155] (481s left) kms_vrr (max-min)
[539.472332] Starting subtest: max-min
[539.478966] Subtest max-min: SKIP (0.000s)
[539.523906] [097/155] (481s left) i915_drm_fdinfo (most-busy-check-all)
[539.561341] Starting subtest: most-busy-check-all
[539.563577] Starting dynamic subtest: rcs0
[539.568223] Dynamic subtest rcs0: SKIP (0.004s)
[539.578668] Starting dynamic subtest: bcs0
[539.582786] Dynamic subtest bcs0: SKIP (0.000s)
[539.592196] Starting dynamic subtest: vcs0
[539.596223] Dynamic subtest vcs0: SKIP (0.000s)
[539.605172] Starting dynamic subtest: vcs1
[539.609222] Dynamic subtest vcs1: SKIP (0.000s)
[539.618484] Starting dynamic subtest: vecs0
[539.622600] Dynamic subtest vecs0: SKIP (0.000s)
[539.631768] Starting dynamic subtest: ccs0
[539.635801] Dynamic subtest ccs0: SKIP (0.000s)
[539.639896] Subtest most-busy-check-all: SKIP (0.009s)
[539.680340] [098/155] (481s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[539.797896] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[539.802323] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[539.867836] [099/155] (481s left) gem_create (create-massive)
[539.926972] Starting subtest: create-massive
[539.929032] Subtest create-massive: SUCCESS (0.000s)
[540.097871] [100/155] (481s left) kms_pm_rpm (modeset-non-lpsp)
[541.631569] Starting subtest: modeset-non-lpsp
[542.787737] Subtest modeset-non-lpsp: SKIP (1.154s)
[543.155674] [101/155] (478s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-render)
[543.251238] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-render
[543.255594] Subtest hdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[543.322574] [102/155] (478s left) kms_psr (pr-cursor-render)
[543.422267] Starting subtest: pr-cursor-render
[543.426173] Subtest pr-cursor-render: SKIP (0.000s)
[543.471874] [103/155] (478s left) gem_softpin (evict-prime-sanity-check)
[543.525888] Starting subtest: evict-prime-sanity-check
[543.528340] Starting dynamic subtest: rcs0
[544.667728] Dynamic subtest rcs0: SUCCESS (1.142s)
[544.681093] Starting dynamic subtest: bcs0
[545.787435] Dynamic subtest bcs0: SUCCESS (1.117s)
[545.804060] Starting dynamic subtest: vcs0
[547.311469] Dynamic subtest vcs0: SUCCESS (1.503s)
[547.325054] Starting dynamic subtest: vcs1
[549.280359] Dynamic subtest vcs1: SUCCESS (1.983s)
[549.297155] Starting dynamic subtest: vecs0
[551.282698] Dynamic subtest vecs0: SUCCESS (2.000s)
[551.293660] Starting dynamic subtest: ccs0
[552.416258] Dynamic subtest ccs0: SUCCESS (1.132s)
[552.427600] Starting dynamic subtest: all
[553.528959] Dynamic subtest all: SUCCESS (1.110s)
[553.531689] Subtest evict-prime-sanity-check: SUCCESS (10.003s)
[553.586436] [104/155] (468s left) gem_exec_schedule (u-submit-late-slice)
[553.648369] Starting subtest: u-submit-late-slice
[553.650599] Starting dynamic subtest: rcs0
[553.712181] Dynamic subtest rcs0: SUCCESS (0.064s)
[553.729595] Starting dynamic subtest: bcs0
[553.769789] Dynamic subtest bcs0: SUCCESS (0.056s)
[553.781817] Starting dynamic subtest: vcs0
[553.820348] Dynamic subtest vcs0: SUCCESS (0.050s)
[553.831257] Starting dynamic subtest: vcs1
[553.868938] Dynamic subtest vcs1: SUCCESS (0.047s)
[553.883415] Starting dynamic subtest: vecs0
[553.927018] Dynamic subtest vecs0: SUCCESS (0.057s)
[553.940533] Starting dynamic subtest: ccs0
[553.991202] Dynamic subtest ccs0: SUCCESS (0.064s)
[553.993669] Subtest u-submit-late-slice: SUCCESS (0.344s)
[555.381160] [105/155] (466s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[555.489270] Starting subtest: 4-tiled-8bpp-rotate-90
[555.511707] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.021s)
[555.614932] [106/155] (466s left) dumb_buffer (map-valid)
[555.669046] Starting subtest: map-valid
[555.671330] Subtest map-valid: SUCCESS (0.000s)
[555.754622] [107/155] (466s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-render)
[555.847222] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-render
[555.851107] Subtest fbchdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[555.920096] [108/155] (465s left) i915_pm_rc6_residency (rc6-idle)
[555.985114] Starting subtest: rc6-idle
[556.031857] Starting dynamic subtest: gt0-rcs0
[580.245223] Dynamic subtest gt0-rcs0: SUCCESS (24.212s)
[580.255122] Starting dynamic subtest: gt0-bcs0
[604.520856] Dynamic subtest gt0-bcs0: SUCCESS (24.273s)
[604.531118] Starting dynamic subtest: gt0-ccs0
[628.793880] Dynamic subtest gt0-ccs0: SUCCESS (24.270s)
[628.803948] Starting dynamic subtest: gt1-vcs0
[634.968078] Dynamic subtest gt1-vcs0: SUCCESS (6.169s)
[634.979728] Starting dynamic subtest: gt1-vecs0
[641.140529] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[641.143349] Subtest rc6-idle: SUCCESS (85.156s)
[641.197688] [109/155] (380s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[641.297715] Starting subtest: fbcpsr-rgb565-draw-pwrite
[648.301636] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (7.008s)
[648.383353] [110/155] (373s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[648.492595] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[655.435382] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.947s)
[655.537830] [111/155] (366s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-onoff)
[655.628538] Starting subtest: hdr-2p-primscrn-spr-indfb-onoff
[655.632429] Subtest hdr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[655.704589] [112/155] (366s left) kms_psr (psr-suspend)
[655.789419] Starting subtest: psr-suspend
[655.791762] Starting dynamic subtest: eDP-1
[673.660640] Dynamic subtest eDP-1: SUCCESS (2.913s)
[673.663060] Subtest psr-suspend: SUCCESS (2.914s)
[674.557723] [113/155] (347s left) gem_ctx_param (invalid-get-ringsize)
[674.619471] Starting subtest: invalid-get-ringsize
[674.621961] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[674.708181] [114/155] (347s left) kms_cursor_edge_walk (128x128-top-bottom)
[674.813161] Starting subtest: 128x128-top-bottom
[674.815544] Starting dynamic subtest: pipe-A-eDP-1
[678.307366] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.408s)
[678.318787] Starting dynamic subtest: pipe-D-eDP-1
[681.543132] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.182s)
[681.545600] Subtest 128x128-top-bottom: SUCCESS (6.594s)
[682.419044] [115/155] (339s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[682.517751] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[682.522190] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.000s)
[682.569211] [116/155] (339s left) syncobj_wait (multi-wait-for-submit-submitted)
[682.627566] Starting subtest: multi-wait-for-submit-submitted
[682.729729] Subtest multi-wait-for-submit-submitted: SUCCESS (0.102s)
[682.788575] [117/155] (339s left) kms_getfb (getfb-handle-not-fb)
[682.896714] Starting subtest: getfb-handle-not-fb
[682.899085] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[682.990192] [118/155] (339s left) gem_ctx_persistence (many-contexts)
[683.042684] Starting subtest: many-contexts
[720.807662] Subtest many-contexts: SUCCESS (37.679s)
[720.863487] [119/155] (301s left) gem_exec_balancer (full-pulse)
[720.920028] Starting subtest: full-pulse
[726.128694] Subtest full-pulse: SUCCESS (5.207s)
[726.421933] [120/155] (295s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-pwrite)
[726.520323] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-pwrite
[726.524471] Subtest psrhdr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[726.588399] [121/155] (295s left) gem_ctx_sseu (engines)
[726.651859] Subtest engines: SKIP (0.000s)
[726.735440] [122/155] (295s left) kms_hdr (static-toggle-dpms)
[726.829228] Starting subtest: static-toggle-dpms
[726.831535] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[726.835539] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[726.846835] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[726.851260] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[726.855528] Subtest static-toggle-dpms: SKIP (0.003s)
[726.895360] [123/155] (295s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[726.950085] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[726.952475] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[727.032147] [124/155] (295s left) i915_suspend (fence-restore-untiled)
[727.090206] Starting subtest: fence-restore-untiled
[727.094596] Subtest fence-restore-untiled: SKIP (0.000s)
[727.173610] [125/155] (295s left) kms_psr (fbc-psr2-primary-blt)
[727.270919] Starting subtest: fbc-psr2-primary-blt
[727.273193] Starting dynamic subtest: eDP-1
[727.277405] Dynamic subtest eDP-1: SKIP (0.000s)
[727.281472] Subtest fbc-psr2-primary-blt: SKIP (0.001s)
[727.322582] [126/155] (295s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-5)
[727.411582] Starting subtest: planes-upscale-20x20-downscale-factor-0-5
[727.413500] Starting dynamic subtest: pipe-A
[727.433244] Dynamic subtest pipe-A: SKIP (0.017s)
[727.443644] Starting dynamic subtest: pipe-B
[727.468605] Dynamic subtest pipe-B: SKIP (0.036s)
[727.478612] Starting dynamic subtest: pipe-C
[727.506862] Dynamic subtest pipe-C: SKIP (0.036s)
[727.516772] Starting dynamic subtest: pipe-D
[727.537209] Dynamic subtest pipe-D: SKIP (0.031s)
[727.540751] Subtest planes-upscale-20x20-downscale-factor-0-5: SKIP (0.123s)
[727.624124] [127/155] (295s left) vgem_basic (unload)
[727.661945] Starting subtest: unload
[756.723593] Subtest unload: SUCCESS (29.052s)
[756.803579] [128/155] (265s left) gem_exec_schedule (deep)
[756.982966] Starting subtest: deep
[756.985370] Starting dynamic subtest: rcs0
[763.095399] Dynamic subtest rcs0: SKIP (6.106s)
[763.108472] Starting dynamic subtest: vcs0
[773.201033] Dynamic subtest vcs0: SUCCESS (10.105s)
[773.214891] Starting dynamic subtest: vcs1
[783.952171] Dynamic subtest vcs1: SUCCESS (10.753s)
[783.974056] Starting dynamic subtest: vecs0
[794.750714] Dynamic subtest vecs0: SUCCESS (10.799s)
[794.762713] Starting dynamic subtest: ccs0
[806.680932] Dynamic subtest ccs0: SUCCESS (11.929s)
[806.683502] Subtest deep: SUCCESS (49.699s)
[807.844436] [129/155] (214s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-pwrite)
[807.951042] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-pwrite
[807.955174] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[808.032965] [130/155] (214s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[808.135038] Starting subtest: yf-tiled-64bpp-rotate-180
[808.139609] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[808.216061] [131/155] (214s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-move)
[808.318705] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-move
[808.323124] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[808.389646] [132/155] (214s left) prime_vgem (basic-write)
[808.477854] Starting subtest: basic-write
[808.515793] Subtest basic-write: SKIP (0.035s)
[808.622066] [133/155] (214s left) kms_addfb_basic (master-rmfb)
[808.672564] Starting subtest: master-rmfb
[808.674835] Subtest master-rmfb: SUCCESS (0.001s)
[808.812796] [134/155] (214s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[808.926749] Starting subtest: 2x-cursor-vs-flip-atomic
[808.930982] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s)
[809.046059] [135/155] (213s left) prime_self_import (basic-llseek-size)
[809.078391] Starting subtest: basic-llseek-size
[809.091362] Subtest basic-llseek-size: SUCCESS (0.012s)
[809.190862] [136/155] (213s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[809.571947] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[810.402125] [137/155] (212s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[810.504765] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[810.509317] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[810.576756] [138/155] (212s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[810.683957] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[819.541792] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.862s)
[819.653561] [139/155] (203s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[819.750916] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[819.755014] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[819.825999] [140/155] (203s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[819.916345] Starting subtest: psr-2p-primscrn-spr-indfb-move
[819.920264] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[819.994862] [141/155] (203s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-render)
[820.098487] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-render
[820.102620] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[820.187995] [142/155] (203s left) kms_psr (psr2-sprite-blt)
[820.295936] Starting subtest: psr2-sprite-blt
[820.298321] Starting dynamic subtest: eDP-1
[821.984747] Dynamic subtest eDP-1: SUCCESS (1.689s)
[821.987195] Subtest psr2-sprite-blt: SUCCESS (1.690s)
[822.812361] [143/155] (200s left) core_setmaster (master-drop-set-root)
[822.855353] Starting subtest: master-drop-set-root
[822.870921] Subtest master-drop-set-root: SUCCESS (0.012s)
[822.919707] [144/155] (200s left) gem_exec_schedule (manyslice)
[822.992596] Starting subtest: manyslice
[822.994793] Starting dynamic subtest: rcs0
[828.106102] Dynamic subtest rcs0: SUCCESS (5.122s)
[828.123986] Starting dynamic subtest: bcs0
[834.222570] Dynamic subtest bcs0: SUCCESS (6.112s)
[834.235819] Starting dynamic subtest: vcs0
[839.824767] Dynamic subtest vcs0: SUCCESS (5.600s)
[839.841601] Starting dynamic subtest: vcs1
[846.412549] Dynamic subtest vcs1: SUCCESS (6.585s)
[846.424871] Starting dynamic subtest: vecs0
[851.987324] Dynamic subtest vecs0: SUCCESS (5.571s)
[852.003912] Starting dynamic subtest: ccs0
[858.163956] Dynamic subtest ccs0: SUCCESS (6.173s)
[858.166877] Subtest manyslice: SUCCESS (35.174s)
[858.443953] [145/155] (164s left) kms_getfb (getfb-handle-zero)
[858.545838] Starting subtest: getfb-handle-zero
[858.548160] Subtest getfb-handle-zero: SUCCESS (0.000s)
[858.617868] [146/155] (164s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[858.710829] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[858.712999] Starting dynamic subtest: pipe-A-eDP-1
[858.735880] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[858.747158] Starting dynamic subtest: pipe-B-eDP-1
[858.769570] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[858.779883] Starting dynamic subtest: pipe-C-eDP-1
[858.802167] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[858.812106] Starting dynamic subtest: pipe-D-eDP-1
[858.834696] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[858.838908] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.007s)
[860.905980] [147/155] (162s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[861.007447] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[867.473660] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.470s)
[867.580190] [148/155] (155s left) gem_mmap_gtt (basic-read-write)
[867.640061] Subtest basic-read-write: SKIP (0.000s)
[867.698222] [149/155] (155s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-msflip-blt)
[867.805764] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-msflip-blt
[867.809982] Subtest fbcpsrhdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[867.892877] [150/155] (155s left) kms_cursor_crc (cursor-offscreen-64x21)
[868.077011] Starting subtest: cursor-offscreen-64x21
[868.123498] Subtest cursor-offscreen-64x21: SKIP (0.042s)
[868.334609] [151/155] (155s left) gem_exec_capture (capture-invisible)
[868.388122] Starting subtest: capture-invisible
[868.390430] Starting dynamic subtest: smem0
[868.394986] Dynamic subtest smem0: SKIP (0.000s)
[868.399467] Subtest capture-invisible: SKIP (0.001s)
[868.469635] [152/155] (155s left) kms_frontbuffer_tracking (hdr-rgb565-draw-render)
[868.558265] Starting subtest: hdr-rgb565-draw-render
[868.561915] Subtest hdr-rgb565-draw-render: SKIP (0.000s)
[868.637978] [153/155] (155s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180-hflip)
[868.736726] Starting subtest: linear-max-hw-stride-64bpp-rotate-180-hflip
[868.740476] Subtest linear-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[868.817209] [154/155] (154s left) kms_properties (plane-properties-atomic)
[868.905842] Starting subtest: plane-properties-atomic
[868.907710] Starting dynamic subtest: pipe-A-eDP-1
[869.457270] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.551s)
[869.469198] Starting dynamic subtest: pipe-B-eDP-1
[870.772137] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.312s)
[870.783233] Starting dynamic subtest: pipe-C-eDP-1
[872.046910] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.273s)
[872.061271] Starting dynamic subtest: pipe-D-eDP-1
[873.366708] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.317s)
[873.368944] Subtest plane-properties-atomic: SUCCESS (4.460s)
[874.216757] [155/155] (149s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[874.330300] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt
[874.334441] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[874.399329] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: syncobj_wait
results: parsing output: 2/ for test: gem_softpin
results: parsing output: 3/ for test: gem_exec_create
results: parsing output: 4/ for test: kms_ccs
results: parsing output: 5/ for test: kms_plane_scaling
results: parsing output: 6/ for test: gen9_exec_parse
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_busy
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_content_protection
results: parsing output: 13/ for test: gem_eio
results: parsing output: 14/ for test: kms_hdmi_inject
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: syncobj_timeline
results: parsing output: 17/ for test: gem_exec_suspend
results: parsing output: 18/ for test: kms_cursor_crc
results: parsing output: 19/ for test: gem_pxp
results: parsing output: 20/ for test: kms_pipe_crc_basic
results: parsing output: 21/ for test: gem_wait
results: parsing output: 22/ for test: gem_exec_create
results: parsing output: 23/ for test: kms_big_fb
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: gem_exec_reloc
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: api_intel_allocator
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: kms_rotation_crc
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: syncobj_eventfd
results: parsing output: 34/ for test: kms_flip
results: parsing output: 35/ for test: gem_exec_fence
results: parsing output: 36/ for test: gem_gpgpu_fill
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: perf
results: parsing output: 39/ for test: kms_addfb_basic
results: parsing output: 40/ for test: gem_mmap_gtt
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_pm_dc
results: parsing output: 45/ for test: kms_pipe_crc_basic
results: parsing output: 46/ for test: kms_flip_scaled_crc
results: parsing output: 47/ for test: kms_feature_discovery
results: parsing output: 48/ for test: gem_exec_schedule
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: core_auth
results: parsing output: 51/ for test: kms_psr2_sf
results: parsing output: 52/ for test: gem_mmap_gtt
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_atomic_transition
results: parsing output: 55/ for test: kms_ccs
results: parsing output: 56/ for test: kms_flip
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: gem_media_fill
results: parsing output: 59/ for test: kms_chamelium_frames
results: parsing output: 60/ for test: core_auth
results: parsing output: 61/ for test: gem_mmap_gtt
results: parsing output: 62/ for test: perf_pmu
results: parsing output: 63/ for test: kms_cursor_legacy
results: parsing output: 64/ for test: perf_pmu
results: parsing output: 65/ for test: kms_chamelium_color
results: parsing output: 66/ for test: kms_flip
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_ccs
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: syncobj_timeline
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_color_pipeline
results: parsing output: 74/ for test: gem_mmap_wc
results: parsing output: 75/ for test: kms_addfb_basic
results: parsing output: 76/ for test: kms_vblank
results: parsing output: 77/ for test: kms_plane
results: parsing output: 78/ for test: vgem_basic
results: parsing output: 79/ for test: perf_pmu
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: gem_exec_reloc
results: parsing output: 84/ for test: kms_lease
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_psr
results: parsing output: 87/ for test: kms_cursor_legacy
results: parsing output: 88/ for test: gem_userptr_blits
results: parsing output: 89/ for test: prime_busy
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: gem_eio
results: parsing output: 92/ for test: i915_pm_freq_api
results: parsing output: 93/ for test: kms_plane
results: parsing output: 94/ for test: kms_psr2_sf
results: parsing output: 95/ for test: kms_vrr
results: parsing output: 96/ for test: i915_drm_fdinfo
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: gem_create
results: parsing output: 99/ for test: kms_pm_rpm
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_psr
results: parsing output: 102/ for test: gem_softpin
results: parsing output: 103/ for test: gem_exec_schedule
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: dumb_buffer
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: i915_pm_rc6_residency
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: kms_psr
results: parsing output: 112/ for test: gem_ctx_param
results: parsing output: 113/ for test: kms_cursor_edge_walk
results: parsing output: 114/ for test: kms_flip_scaled_crc
results: parsing output: 115/ for test: syncobj_wait
results: parsing output: 116/ for test: kms_getfb
results: parsing output: 117/ for test: gem_ctx_persistence
results: parsing output: 118/ for test: gem_exec_balancer
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: gem_ctx_sseu
results: parsing output: 121/ for test: kms_hdr
results: parsing output: 122/ for test: syncobj_wait
results: parsing output: 123/ for test: i915_suspend
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: kms_plane_scaling
results: parsing output: 126/ for test: vgem_basic
results: parsing output: 127/ for test: gem_exec_schedule
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: prime_vgem
results: parsing output: 132/ for test: kms_addfb_basic
results: parsing output: 133/ for test: kms_cursor_legacy
results: parsing output: 134/ for test: prime_self_import
results: parsing output: 135/ for test: kms_chamelium_hpd
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: kms_psr
results: parsing output: 142/ for test: core_setmaster
results: parsing output: 143/ for test: gem_exec_schedule
results: parsing output: 144/ for test: kms_getfb
results: parsing output: 145/ for test: kms_ccs
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: gem_mmap_gtt
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_cursor_crc
results: parsing output: 150/ for test: gem_exec_capture
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: kms_big_fb
results: parsing output: 153/ for test: kms_properties
results: parsing output: 154/ for test: kms_frontbuffer_tracking
Done.