[63.807022] Initializing watchdogs
[63.807069]   /dev/watchdog0
[63.821779] [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]
[63.825985] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[63.828324] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[63.831569] [001/133] (960s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[63.941111] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[63.943438] Starting dynamic subtest: pipe-A-eDP-1
[64.220995] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.250s)
[64.230723] Starting dynamic subtest: pipe-B-eDP-1
[65.453699] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.230s)
[65.460597] Starting dynamic subtest: pipe-C-eDP-1
[66.661621] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.206s)
[66.670583] Starting dynamic subtest: pipe-D-eDP-1
[67.892767] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.231s)
[67.895173] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.920s)
[69.130175] [002/133] (955s left) gem_render_copy (yf-tiled-ccs-to-y-tiled)
[69.188304] Starting subtest: yf-tiled-ccs-to-y-tiled
[69.192570] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s)
[69.393269] [003/133] (955s left) gem_eio (in-flight-1us)
[69.553976] Starting subtest: in-flight-1us
[76.810627] Subtest in-flight-1us: SUCCESS (7.254s)
[76.973396] [FACT gem_eio (in-flight-1us)] new: kernel.kmod_is_loaded.vgem: true
[76.976297] [004/133] (947s left) kms_cursor_crc (cursor-size-change)
[77.085993] Starting subtest: cursor-size-change
[77.087961] Starting dynamic subtest: pipe-A-eDP-1
[77.910483] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.825s)
[77.919432] Starting dynamic subtest: pipe-D-eDP-1
[79.374413] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.463s)
[79.376711] Subtest cursor-size-change: SUCCESS (2.290s)
[80.212041] [005/133] (944s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[80.325191] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[80.339070] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.012s)
[80.440144] [006/133] (944s left) gem_ctx_persistence (heartbeat-close)
[80.502417] Starting subtest: heartbeat-close
[80.506896] Subtest heartbeat-close: SKIP (0.000s)
[80.629083] [007/133] (943s left) core_setmaster (master-drop-set-shared-fd)
[80.723479] Starting subtest: master-drop-set-shared-fd
[80.745428] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[80.785688] [008/133] (943s left) gem_exec_schedule (wide)
[80.977028] Starting subtest: wide
[80.979721] Starting dynamic subtest: rcs0
[90.988476] Dynamic subtest rcs0: SUCCESS (10.008s)
[90.997436] Starting dynamic subtest: vcs0
[101.849073] Dynamic subtest vcs0: SUCCESS (10.861s)
[101.859470] Starting dynamic subtest: vcs1
[111.594469] Dynamic subtest vcs1: SUCCESS (9.744s)
[111.605433] Starting dynamic subtest: vecs0
[121.206023] Dynamic subtest vecs0: SUCCESS (9.611s)
[121.216520] Starting dynamic subtest: ccs0
[134.590614] Dynamic subtest ccs0: SUCCESS (13.384s)
[134.592720] Subtest wide: SUCCESS (53.610s)
[137.037224] [009/133] (887s left) gem_exec_fence (syncobj-timeline-signal)
[137.642695] Starting subtest: syncobj-timeline-signal
[137.652155] Subtest syncobj-timeline-signal: SUCCESS (0.011s)
[139.023800] [010/133] (885s left) kms_lease (setcrtc-implicit-plane)
[139.134891] Starting subtest: setcrtc-implicit-plane
[139.137280] Starting dynamic subtest: pipe-A-eDP-1
[139.605327] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.471s)
[139.613430] Starting dynamic subtest: pipe-B-eDP-1
[140.741577] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.133s)
[140.750125] Starting dynamic subtest: pipe-C-eDP-1
[141.853566] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.112s)
[141.862144] Starting dynamic subtest: pipe-D-eDP-1
[142.983485] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.130s)
[142.986270] Subtest setcrtc-implicit-plane: SUCCESS (3.850s)
[143.841780] [011/133] (880s left) kms_vrr (lobf)
[143.947097] Starting subtest: lobf
[143.951218] Subtest lobf: SKIP (0.000s)
[144.028120] [012/133] (880s left) gem_exec_reloc (basic-write-cpu-active)
[144.097832] Subtest basic-write-cpu-active: SKIP (0.000s)
[144.190921] [013/133] (880s left) syncobj_timeline (wait-any-interrupted)
[144.258287] Starting subtest: wait-any-interrupted
[144.359307] Subtest wait-any-interrupted: SUCCESS (0.101s)
[144.434326] [014/133] (880s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[144.538515] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[145.560829] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.023s)
[145.740275] [015/133] (879s left) kms_content_protection (type1)
[145.893314] Starting subtest: type1
[145.897307] Subtest type1: SKIP (0.000s)
[146.043761] [016/133] (878s left) gem_softpin (allocator-nopin-reserve)
[146.107238] Starting subtest: allocator-nopin-reserve
[146.218940] Subtest allocator-nopin-reserve: SUCCESS (0.110s)
[146.370131] [017/133] (878s left) gem_ctx_shared (q-smoketest)
[146.441740] Starting subtest: Q-smoketest
[146.444247] Starting dynamic subtest: rcs0
[152.010305] Dynamic subtest rcs0: SUCCESS (5.522s)
[152.020465] Starting dynamic subtest: bcs0
[157.498175] Dynamic subtest bcs0: SUCCESS (5.468s)
[157.507867] Starting dynamic subtest: vcs0
[162.967226] Dynamic subtest vcs0: SUCCESS (5.487s)
[162.975850] Starting dynamic subtest: vcs1
[168.455329] Dynamic subtest vcs1: SUCCESS (5.482s)
[168.465679] Starting dynamic subtest: vecs0
[173.928930] Dynamic subtest vecs0: SUCCESS (5.477s)
[173.936861] Starting dynamic subtest: ccs0
[179.411832] Dynamic subtest ccs0: SUCCESS (5.479s)
[179.414162] Subtest Q-smoketest: SUCCESS (32.919s)
[179.631234] [018/133] (845s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[179.735899] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[179.738312] Starting dynamic subtest: pipe-A-default-mode
[182.482321] Dynamic subtest pipe-A-default-mode: SUCCESS (2.747s)
[182.484709] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.748s)
[183.320166] [019/133] (841s left) syncobj_wait (multi-wait-for-submit-signaled)
[183.377108] Starting subtest: multi-wait-for-submit-signaled
[183.379125] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[183.497027] [020/133] (841s left) gem_ctx_param (invalid-set-ringsize)
[183.569453] Starting subtest: invalid-set-ringsize
[183.571744] Subtest invalid-set-ringsize: SUCCESS (0.000s)
[183.654028] [021/133] (841s left) kms_frontbuffer_tracking (psr-rgb565-draw-render)
[183.746673] Starting subtest: psr-rgb565-draw-render
[190.878532] Subtest psr-rgb565-draw-render: SUCCESS (7.135s)
[191.039952] [022/133] (834s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[191.141229] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[191.169084] Starting dynamic subtest: pipe-A-plane-0
[191.173638] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[191.183733] Starting dynamic subtest: pipe-A-plane-3
[191.188351] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[191.198071] Starting dynamic subtest: pipe-A-plane-5
[191.202640] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[192.229385] Starting dynamic subtest: pipe-B-plane-0
[192.233875] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[192.241877] Starting dynamic subtest: pipe-B-plane-3
[192.246480] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[192.254169] Starting dynamic subtest: pipe-B-plane-5
[192.258857] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[192.532909] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (1.387s)
[193.414185] [023/133] (831s left) i915_pciid
[193.588046] [024/133] (831s left) kms_plane_scaling (planes-downscale-factor-0-5-unity-scaling)
[193.680086] Starting subtest: planes-downscale-factor-0-5-unity-scaling
[193.682259] Starting dynamic subtest: pipe-A
[193.701542] Dynamic subtest pipe-A: SKIP (0.018s)
[193.709718] Starting dynamic subtest: pipe-B
[193.732683] Dynamic subtest pipe-B: SKIP (0.031s)
[193.741830] Starting dynamic subtest: pipe-C
[193.763204] Dynamic subtest pipe-C: SKIP (0.030s)
[193.770920] Starting dynamic subtest: pipe-D
[193.794229] Dynamic subtest pipe-D: SKIP (0.031s)
[193.798179] Subtest planes-downscale-factor-0-5-unity-scaling: SKIP (0.111s)
[193.903645] [025/133] (831s left) kms_universal_plane (universal-plane-functional)
[194.000256] Starting subtest: universal-plane-functional
[194.002530] Starting dynamic subtest: pipe-A-eDP-1
[197.909672] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.907s)
[197.917727] Starting dynamic subtest: pipe-B-eDP-1
[202.370320] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.458s)
[202.379555] Starting dynamic subtest: pipe-C-eDP-1
[206.706849] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.333s)
[206.716051] Starting dynamic subtest: pipe-D-eDP-1
[211.028739] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.322s)
[211.031124] Subtest universal-plane-functional: SUCCESS (17.026s)
[211.890412] [026/133] (813s left) i915_suspend (forcewake)
[211.957492] Starting subtest: forcewake
[244.679973] Subtest forcewake: SUCCESS (3.170s)
[250.797813] [027/133] (780s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[250.915001] Starting subtest: fbc-pr-cursor-mmap-cpu
[250.920032] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[251.000574] [028/133] (780s left) gem_mmap_gtt (cpuset-basic-small-copy)
[251.058814] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[251.111547] [029/133] (780s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[251.201964] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[251.207018] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[251.318854] [030/133] (780s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[251.415876] Starting subtest: crc-primary-basic-yf-tiled-ccs
[251.418552] Starting dynamic subtest: pipe-A-eDP-1
[251.443823] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[251.453150] Starting dynamic subtest: pipe-B-eDP-1
[251.477974] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[251.486669] Starting dynamic subtest: pipe-C-eDP-1
[251.511952] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[251.520027] Starting dynamic subtest: pipe-D-eDP-1
[251.545505] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[251.550818] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.005s)
[253.637411] [031/133] (777s left) kms_psr (pr-cursor-blt)
[253.738042] Starting subtest: pr-cursor-blt
[253.743185] Subtest pr-cursor-blt: SKIP (0.000s)
[253.821995] [032/133] (777s left) syncobj_eventfd (timeline-wait-before-signal)
[253.883430] Starting subtest: timeline-wait-before-signal
[253.914604] Subtest timeline-wait-before-signal: SUCCESS (0.031s)
[254.005313] [033/133] (777s left) prime_busy (hang-wait)
[254.075144] Starting subtest: hang-wait
[254.077553] Starting dynamic subtest: rcs0
[279.910885] Dynamic subtest rcs0: SUCCESS (25.772s)
[279.921863] Starting dynamic subtest: bcs0
[287.063374] Dynamic subtest bcs0: SUCCESS (7.146s)
[287.072156] Starting dynamic subtest: vcs0
[294.101160] Dynamic subtest vcs0: SUCCESS (7.032s)
[294.109592] Starting dynamic subtest: vcs1
[301.075977] Dynamic subtest vcs1: SUCCESS (6.970s)
[301.086202] Starting dynamic subtest: vecs0
[308.050168] Dynamic subtest vecs0: SUCCESS (6.966s)
[308.060804] Starting dynamic subtest: ccs0
[321.954264] Dynamic subtest ccs0: SUCCESS (13.897s)
[321.957414] Subtest hang-wait: SUCCESS (67.796s)
[322.117909] [034/133] (709s left) gem_mmap_gtt (basic-copy)
[322.183101] Subtest basic-copy: SKIP (0.000s)
[322.255807] [035/133] (709s left) kms_chamelium_frames (dp-frame-dump)
[322.664030] Subtest dp-frame-dump: SKIP (0.000s)
[323.499199] [036/133] (708s left) gem_mmap_gtt (big-copy-xy)
[323.562024] Subtest big-copy-XY: SKIP (0.000s)
[323.615996] [037/133] (708s left) kms_feature_discovery (display-3x)
[323.717550] Starting subtest: display-3x
[323.721864] Subtest display-3x: SKIP (0.000s)
[323.803654] [038/133] (708s left) gem_exec_schedule (u-semaphore-user)
[323.887597] Starting subtest: u-semaphore-user
[323.934082] Subtest u-semaphore-user: SUCCESS (0.059s)
[325.498425] [039/133] (706s left) gem_tiled_blits (normal)
[325.554570] Subtest normal: SKIP (0.000s)
[325.623010] [040/133] (706s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[325.728091] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[325.733814] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[325.837714] [041/133] (706s left) gen7_exec_parse (chained-batch)
[325.906173] Subtest chained-batch: SKIP (0.000s)
[325.973141] [042/133] (706s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[326.066866] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[326.069568] Starting dynamic subtest: pipe-A
[326.428833] Dynamic subtest pipe-A: SUCCESS (0.362s)
[326.437428] Starting dynamic subtest: pipe-B
[327.800142] Dynamic subtest pipe-B: SUCCESS (1.370s)
[327.809879] Starting dynamic subtest: pipe-C
[329.255901] Dynamic subtest pipe-C: SUCCESS (1.454s)
[329.265012] Starting dynamic subtest: pipe-D
[330.678428] Dynamic subtest pipe-D: SUCCESS (1.421s)
[330.680864] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.610s)
[331.847387] [043/133] (700s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[331.960004] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[331.965069] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[332.059423] [044/133] (700s left) kms_async_flips (invalid-async-flip)
[332.150948] Starting subtest: invalid-async-flip
[332.522927] Starting dynamic subtest: pipe-A-eDP-1
[333.367191] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.844s)
[333.727747] Starting dynamic subtest: pipe-B-eDP-1
[334.504167] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.783s)
[334.858235] Starting dynamic subtest: pipe-C-eDP-1
[335.618852] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.764s)
[335.969022] Starting dynamic subtest: pipe-D-eDP-1
[336.756853] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.792s)
[336.759384] Subtest invalid-async-flip: SUCCESS (4.604s)
[337.928866] [045/133] (694s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[338.033788] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[338.038764] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.000s)
[338.118287] [046/133] (694s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0-hflip)
[338.227061] Starting subtest: linear-max-hw-stride-32bpp-rotate-0-hflip
[338.232006] Subtest linear-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[338.351890] [047/133] (693s left) gem_basic (create-fd-close)
[338.416734] Starting subtest: create-fd-close
[338.421883] Subtest create-fd-close: SUCCESS (0.000s)
[338.543913] [048/133] (693s left) gem_exec_reloc (basic-cpu-wc)
[338.605607] Subtest basic-cpu-wc: SKIP (0.000s)
[338.693077] [049/133] (693s left) kms_pm_rpm (legacy-planes)
[340.614698] Starting subtest: legacy-planes
[340.617709] Starting dynamic subtest: plane-64
[348.896308] Dynamic subtest plane-64: SUCCESS (8.287s)
[348.908878] Starting dynamic subtest: plane-94
[357.210440] Dynamic subtest plane-94: SUCCESS (8.318s)
[357.222726] Starting dynamic subtest: plane-124
[365.488357] Dynamic subtest plane-124: SUCCESS (8.280s)
[365.500974] Starting dynamic subtest: plane-134
[373.983447] Dynamic subtest plane-134: SUCCESS (8.494s)
[373.986514] Subtest legacy-planes: SUCCESS (33.391s)
[374.384352] [050/133] (657s left) core_auth (getclient-master-drop)
[374.426881] Starting subtest: getclient-master-drop
[374.450983] Subtest getclient-master-drop: SUCCESS (0.021s)
[374.586202] [051/133] (657s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[374.638414] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[374.640894] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[374.759493] [052/133] (657s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[374.862039] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[374.866928] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[374.981949] [053/133] (657s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[375.042838] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[375.045719] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[375.158963] [054/133] (657s left) kms_cursor_edge_walk (64x64-right-edge)
[375.257097] Starting subtest: 64x64-right-edge
[375.259808] Starting dynamic subtest: pipe-A-eDP-1
[378.729069] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.471s)
[378.739019] Starting dynamic subtest: pipe-D-eDP-1
[381.844633] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.116s)
[381.847139] Subtest 64x64-right-edge: SUCCESS (6.591s)
[382.654816] [055/133] (649s left) prime_vgem (basic-gtt)
[382.738016] Starting subtest: basic-gtt
[382.743142] Subtest basic-gtt: SKIP (0.000s)
[382.865073] [056/133] (649s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[382.959099] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[382.961807] Starting dynamic subtest: pipe-A-eDP-1
[382.986498] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[383.000998] Starting dynamic subtest: pipe-B-eDP-1
[383.027873] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[383.038681] Starting dynamic subtest: pipe-C-eDP-1
[383.063494] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[383.072282] Starting dynamic subtest: pipe-D-eDP-1
[383.097594] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[383.102833] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[385.144497] [057/133] (647s left) gem_madvise (dontneed-before-exec)
[385.188182] Starting subtest: dontneed-before-exec
[385.221784] Subtest dontneed-before-exec: SKIP (0.030s)
[385.290321] [058/133] (647s left) kms_pwrite_crc
[386.892645] [059/133] (645s left) gem_exec_schedule (in-order)
[386.972659] Starting subtest: in-order
[386.974882] Starting dynamic subtest: rcs0
[387.069596] Dynamic subtest rcs0: SUCCESS (0.106s)
[387.079876] Starting dynamic subtest: vcs0
[387.163142] Dynamic subtest vcs0: SUCCESS (0.092s)
[387.175049] Starting dynamic subtest: vcs1
[387.241154] Dynamic subtest vcs1: SUCCESS (0.077s)
[387.251772] Starting dynamic subtest: vecs0
[387.317603] Dynamic subtest vecs0: SUCCESS (0.075s)
[387.329739] Starting dynamic subtest: ccs0
[387.422673] Dynamic subtest ccs0: SUCCESS (0.104s)
[387.425651] Subtest in-order: SUCCESS (0.460s)
[388.604124] [060/133] (644s left) gem_ccs (block-copy-uncompressed)
[388.674786] Starting subtest: block-copy-uncompressed
[388.677310] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[388.696855] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.023s)
[388.705848] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[388.716202] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[388.727056] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[388.735181] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[388.743866] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[388.752086] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[388.754969] Subtest block-copy-uncompressed: SUCCESS (0.081s)
[388.911668] [061/133] (643s left) i915_drm_fdinfo (memory-info-resident)
[388.954825] Starting subtest: memory-info-resident
[388.957207] Starting dynamic subtest: smem0
[391.656729] Dynamic subtest smem0: SUCCESS (2.702s)
[391.659126] Subtest memory-info-resident: SUCCESS (2.703s)
[391.746035] [062/133] (641s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[391.858793] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[391.871672] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.011s)
[391.956054] [063/133] (640s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[392.053840] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[399.416930] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.365s)
[399.552823] [064/133] (633s left) kms_psr (psr-sprite-mmap-gtt)
[399.662876] Starting subtest: psr-sprite-mmap-gtt
[399.665477] Starting dynamic subtest: eDP-1
[401.119467] Dynamic subtest eDP-1: SKIP (1.449s)
[401.125158] Subtest psr-sprite-mmap-gtt: SKIP (1.452s)
[401.345037] [065/133] (631s left) kms_cursor_legacy (single-bo)
[401.473161] Starting subtest: single-bo
[401.475807] Starting dynamic subtest: pipe-A
[406.856005] Dynamic subtest pipe-A: SUCCESS (5.379s)
[406.867161] Starting dynamic subtest: pipe-B
[412.241149] Dynamic subtest pipe-B: SUCCESS (5.381s)
[412.251939] Starting dynamic subtest: pipe-C
[417.623104] Dynamic subtest pipe-C: SUCCESS (5.379s)
[417.634046] Starting dynamic subtest: pipe-D
[423.006551] Dynamic subtest pipe-D: SUCCESS (5.380s)
[423.017751] Starting dynamic subtest: all-pipes
[428.395842] Dynamic subtest all-pipes: SUCCESS (5.385s)
[428.398551] Subtest single-bo: SUCCESS (26.917s)
[428.566055] [066/133] (604s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[428.684978] Starting subtest: psr-rgb565-draw-mmap-gtt
[428.689839] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[428.814281] [067/133] (604s left) i915_pm_rps (basic-api)
[428.884975] Starting subtest: basic-api
[428.889952] Subtest basic-api: SKIP (0.001s)
[429.011633] [068/133] (604s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[429.121854] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[429.124541] Starting dynamic subtest: pipe-A-eDP-1
[429.216060] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s)
[429.226678] Starting dynamic subtest: pipe-B-eDP-1
[430.398631] Dynamic subtest pipe-B-eDP-1: SKIP (1.181s)
[430.403995] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.282s)
[431.698536] [069/133] (601s left) intel_hwmon (hwmon-write)
[431.758139] Subtest hwmon-write: SKIP (0.000s)
[431.828419] [070/133] (601s left) gem_lmem_swapping (heavy-multi)
[431.873055] Subtest heavy-multi: SKIP (0.000s)
[431.925138] [071/133] (601s left) perf_pmu (init-busy)
[431.964689] Starting subtest: init-busy
[431.967493] Starting dynamic subtest: rcs0
[431.970022] Dynamic subtest rcs0: SUCCESS (0.002s)
[431.984964] Starting dynamic subtest: bcs0
[431.987947] Dynamic subtest bcs0: SUCCESS (0.001s)
[432.001052] Starting dynamic subtest: vcs0
[432.003997] Dynamic subtest vcs0: SUCCESS (0.001s)
[432.017162] Starting dynamic subtest: vcs1
[432.019794] Dynamic subtest vcs1: SUCCESS (0.001s)
[432.031165] Starting dynamic subtest: vecs0
[432.033920] Dynamic subtest vecs0: SUCCESS (0.001s)
[432.043082] Starting dynamic subtest: ccs0
[432.045883] Dynamic subtest ccs0: SUCCESS (0.001s)
[432.048637] Subtest init-busy: SUCCESS (0.008s)
[432.096587] [072/133] (601s left) gem_pread (self)
[432.158198] Subtest self: SKIP (0.000s)
[432.228515] [073/133] (601s left) gem_exec_flush (basic-uc-rw-default)
[432.295131] Starting subtest: basic-uc-rw-default
[437.766141] Subtest basic-uc-rw-default: SUCCESS (5.458s)
[437.953387] [074/133] (595s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[438.078501] Starting subtest: cursorB-vs-flipA-legacy
[438.083103] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[438.231446] [075/133] (595s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[438.342206] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[438.346985] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[438.462069] [076/133] (595s left) kms_addfb_basic (basic)
[438.516036] Starting subtest: basic
[438.518905] Subtest basic: SUCCESS (0.000s)
[438.677685] [077/133] (594s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[438.772426] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[438.776978] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[438.852488] [078/133] (594s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[438.966910] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[442.983417] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.019s)
[444.141410] [079/133] (589s left) syncobj_wait (wait-any-snapshot)
[444.208207] Starting subtest: wait-any-snapshot
[444.288916] Subtest wait-any-snapshot: SUCCESS (0.082s)
[444.379081] [080/133] (589s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[444.474100] Starting subtest: crc-primary-suspend-y-tiled-ccs
[444.476582] Starting dynamic subtest: pipe-A-eDP-1
[444.485962] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[444.495980] Starting dynamic subtest: pipe-B-eDP-1
[444.506181] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[444.515066] Starting dynamic subtest: pipe-C-eDP-1
[444.525754] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[444.534666] Starting dynamic subtest: pipe-D-eDP-1
[444.544948] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[444.549994] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.005s)
[446.789287] [081/133] (586s left) kms_prop_blob (blob-prop-validate)
[446.856366] Starting subtest: blob-prop-validate
[446.859003] Subtest blob-prop-validate: SUCCESS (0.000s)
[446.991748] [082/133] (586s left) kms_psr (psr2-primary-mmap-gtt)
[447.084459] Starting subtest: psr2-primary-mmap-gtt
[447.086744] Starting dynamic subtest: eDP-1
[448.565008] Dynamic subtest eDP-1: SKIP (1.477s)
[448.570150] Subtest psr2-primary-mmap-gtt: SKIP (1.479s)
[448.695495] [083/133] (585s left) kms_psr (fbc-pr-suspend)
[448.793155] Starting subtest: fbc-pr-suspend
[448.798610] Subtest fbc-pr-suspend: SKIP (0.000s)
[448.883339] [084/133] (584s left) kms_pipe_crc_basic (nonblocking-crc)
[448.979768] Starting subtest: nonblocking-crc
[448.982578] Starting dynamic subtest: pipe-A-eDP-1
[449.700170] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.722s)
[449.711101] Starting dynamic subtest: pipe-B-eDP-1
[451.127097] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.424s)
[451.136757] Starting dynamic subtest: pipe-C-eDP-1
[452.472799] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.344s)
[452.485151] Starting dynamic subtest: pipe-D-eDP-1
[453.815155] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.342s)
[453.818178] Subtest nonblocking-crc: SUCCESS (4.837s)
[454.636530] [085/133] (579s left) prime_mmap (test_correct_cpu_write)
[454.698857] Starting subtest: test_correct_cpu_write
[454.701732] Starting dynamic subtest: test_correct_cpu_write-smem
[454.704144] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[454.706782] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[454.818665] [086/133] (579s left) syncobj_basic (bad-create-flags)
[454.880740] Starting subtest: bad-create-flags
[454.883151] Subtest bad-create-flags: SUCCESS (0.000s)
[454.993102] [087/133] (578s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[455.085149] Starting subtest: psr-2p-primscrn-cur-indfb-move
[455.089794] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[455.194806] [088/133] (578s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[455.298406] Starting subtest: y-tiled-32bpp-rotate-0
[455.303170] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[455.420753] [089/133] (578s left) kms_vblank (wait-forked-busy-hang)
[455.518800] Starting subtest: wait-forked-busy-hang
[455.520988] Starting dynamic subtest: pipe-A-eDP-1
[482.246768] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.725s)
[482.261158] Starting dynamic subtest: pipe-D-eDP-1
[506.236525] Dynamic subtest pipe-D-eDP-1: SUCCESS (23.990s)
[506.238848] Subtest wait-forked-busy-hang: SUCCESS (50.720s)
[507.091342] [090/133] (526s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[507.185137] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[507.187926] Starting dynamic subtest: pipe-A-eDP-1
[508.445477] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.260s)
[508.456366] Starting dynamic subtest: pipe-B-eDP-1
[510.706749] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.260s)
[510.716216] Starting dynamic subtest: pipe-C-eDP-1
[513.053649] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.346s)
[513.062679] Starting dynamic subtest: pipe-D-eDP-1
[515.385828] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.330s)
[515.388502] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (8.203s)
[516.638249] [091/133] (517s left) syncobj_timeline (etime-multi-wait-submitted)
[516.699141] Starting subtest: etime-multi-wait-submitted
[516.797762] Subtest etime-multi-wait-submitted: SUCCESS (0.101s)
[516.888714] [092/133] (517s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[517.005573] Starting subtest: flip-vs-cursor-legacy
[524.065965] Subtest flip-vs-cursor-legacy: SUCCESS (7.058s)
[524.865025] [093/133] (509s left) gem_create (create-ext-placement-all)
[525.039942] Starting subtest: create-ext-placement-all
[525.042726] Subtest create-ext-placement-all: SUCCESS (0.000s)
[525.167000] [094/133] (509s left) gem_exec_balancer (individual)
[525.243950] Starting subtest: individual
[527.407865] Subtest individual: SUCCESS (2.166s)
[527.762028] [095/133] (506s left) api_intel_allocator (reloc-allocator)
[527.818590] Starting subtest: reloc-allocator
[527.820806] Starting dynamic subtest: basic
[527.823032] Dynamic subtest basic: SUCCESS (0.007s)
[527.832461] Starting dynamic subtest: parallel-one
[527.835172] Dynamic subtest parallel-one: SUCCESS (0.008s)
[527.844706] Starting dynamic subtest: print
[527.847167] Dynamic subtest print: SUCCESS (0.000s)
[527.856335] Starting dynamic subtest: reuse
[527.859171] Dynamic subtest reuse: SUCCESS (0.000s)
[527.868840] Starting dynamic subtest: fork-reopen-allocator
[527.871319] Dynamic subtest fork-reopen-allocator: SUCCESS (0.015s)
[527.873974] Subtest reloc-allocator: SUCCESS (0.037s)
[527.988907] [096/133] (506s left) kms_addfb_basic (bad-pitch-128)
[528.050554] Starting subtest: bad-pitch-128
[528.052493] Subtest bad-pitch-128: SUCCESS (0.000s)
[528.192521] [097/133] (506s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[528.291177] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[528.296367] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[528.411310] [098/133] (505s left) gen3_render_mixed_blits
[528.531216] [099/133] (505s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[528.624690] Starting subtest: yf-tiled-64bpp-rotate-180
[528.628965] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[528.752231] [100/133] (505s left) gem_mmap_offset (mmap-unmap)
[528.808381] Starting subtest: mmap-unmap
[528.810130] Starting dynamic subtest: smem0
[551.827999] Dynamic subtest smem0: SUCCESS (23.018s)
[551.830918] Subtest mmap-unmap: SUCCESS (23.019s)
[551.989934] [101/133] (482s left) gem_mmap_gtt (coherency)
[552.050968] Subtest coherency: SKIP (0.000s)
[552.117833] [102/133] (482s left) kms_big_fb (linear-32bpp-rotate-0)
[552.223858] Starting subtest: linear-32bpp-rotate-0
[553.714956] Subtest linear-32bpp-rotate-0: SUCCESS (1.491s)
[553.897362] [103/133] (480s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[553.974525] Starting subtest: x-tiled-to-vebox-y-tiled
[553.979175] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[554.188618] [104/133] (480s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[554.252790] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[554.255761] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[554.365931] [105/133] (480s left) template (a)
[554.419990] Starting subtest: A
[554.422887] Subtest A: SUCCESS (0.000s)
[554.565027] [106/133] (480s left) kms_hdr (invalid-hdr)
[554.656964] Starting subtest: invalid-hdr
[556.763981] Starting dynamic subtest: pipe-A-eDP-1
[559.741492] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.978s)
[559.744189] Subtest invalid-hdr: SUCCESS (5.087s)
[559.896387] [107/133] (474s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt)
[559.996954] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt
[560.001992] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[560.093892] [108/133] (474s left) kms_cursor_crc (cursor-rapid-movement-64x64)
[560.277000] Starting subtest: cursor-rapid-movement-64x64
[560.293193] Starting dynamic subtest: pipe-A-eDP-1
[561.910032] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.619s)
[561.938977] Starting dynamic subtest: pipe-D-eDP-1
[563.146755] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.212s)
[563.149297] Subtest cursor-rapid-movement-64x64: SUCCESS (2.867s)
[564.004180] [109/133] (470s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[564.116666] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[568.420917] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.308s)
[569.610914] [110/133] (465s left) kms_psr (fbc-psr-sprite-blt)
[569.706967] Starting subtest: fbc-psr-sprite-blt
[569.709821] Starting dynamic subtest: eDP-1
[569.715054] Dynamic subtest eDP-1: SKIP (0.000s)
[569.720459] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[569.794443] [111/133] (465s left) kms_flip (plain-flip-interruptible)
[569.896553] Starting subtest: plain-flip-interruptible
[569.899135] Starting dynamic subtest: A-eDP1
[571.593075] Dynamic subtest A-eDP1: SUCCESS (1.696s)
[571.604944] Starting dynamic subtest: B-eDP1
[573.229294] Dynamic subtest B-eDP1: SUCCESS (1.635s)
[573.241450] Starting dynamic subtest: C-eDP1
[574.839517] Dynamic subtest C-eDP1: SUCCESS (1.609s)
[574.850971] Starting dynamic subtest: D-eDP1
[576.472085] Dynamic subtest D-eDP1: SUCCESS (1.631s)
[576.477131] Subtest plain-flip-interruptible: SUCCESS (6.580s)
[577.652633] [112/133] (457s left) kms_cursor_crc (cursor-offscreen-32x32)
[577.789879] Starting subtest: cursor-offscreen-32x32
[577.835804] Subtest cursor-offscreen-32x32: SKIP (0.041s)
[578.127589] [113/133] (456s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[578.241170] Starting subtest: 4-tiled-addfb-size-offset-overflow
[578.243471] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[578.360944] [114/133] (456s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[578.451515] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[578.456836] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[578.573855] [115/133] (456s left) syncobj_timeline (invalid-signal-illegal-point)
[578.644044] Starting subtest: invalid-signal-illegal-point
[578.646777] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[578.767365] [116/133] (456s left) gem_exec_create (madvise)
[578.834055] Starting subtest: madvise
[578.835898] Starting dynamic subtest: smem
[600.518303] Dynamic subtest smem: SUCCESS (21.678s)
[600.520840] Subtest madvise: SUCCESS (21.679s)
[601.539452] [117/133] (433s left) kms_flip (flip-vs-expired-vblank-interruptible)
[601.642891] Starting subtest: flip-vs-expired-vblank-interruptible
[601.645620] Starting dynamic subtest: A-eDP1
[610.345808] Dynamic subtest A-eDP1: SUCCESS (8.701s)
[610.355208] Starting dynamic subtest: B-eDP1
[618.979330] Dynamic subtest B-eDP1: SUCCESS (8.629s)
[618.990867] Starting dynamic subtest: C-eDP1
[627.599127] Dynamic subtest C-eDP1: SUCCESS (8.615s)
[627.615570] Starting dynamic subtest: D-eDP1
[636.233060] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[636.239143] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.589s)
[637.428695] [118/133] (397s left) kms_plane_multiple (2x-tiling-y)
[637.526981] Starting subtest: 2x-tiling-y
[637.532196] Subtest 2x-tiling-y: SKIP (0.000s)
[637.621485] [119/133] (397s left) gem_sync (basic-store-all)
[637.722008] Starting subtest: basic-store-all
[640.900919] Subtest basic-store-all: SUCCESS (3.194s)
[641.112052] [120/133] (394s left) kms_color (ctm-0-75)
[641.219017] Starting subtest: ctm-0-75
[641.221413] Starting dynamic subtest: pipe-A-eDP-1
[646.047987] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.830s)
[646.057674] Starting dynamic subtest: pipe-B-eDP-1
[651.691054] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.640s)
[651.702084] Starting dynamic subtest: pipe-C-eDP-1
[657.064021] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.372s)
[657.074620] Starting dynamic subtest: pipe-D-eDP-1
[662.404439] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.337s)
[662.407185] Subtest ctm-0-75: SUCCESS (21.183s)
[663.279412] [121/133] (372s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[663.385142] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[663.389541] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[663.493442] [122/133] (371s left) kms_pm_rpm (dpms-non-lpsp)
[665.626303] Starting subtest: dpms-non-lpsp
[665.631993] Subtest dpms-non-lpsp: SKIP (0.001s)
[666.020016] [123/133] (369s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[666.606682] Starting subtest: syncobj-timeline-invalid-wait
[666.613445] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.005s)
[667.581988] [124/133] (367s left) kms_invalid_mode (bad-htotal)
[667.680806] Starting subtest: bad-htotal
[667.683163] Starting dynamic subtest: pipe-A-eDP-1
[667.966098] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[667.976992] Starting dynamic subtest: pipe-B-eDP-1
[667.979771] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[667.989913] Starting dynamic subtest: pipe-C-eDP-1
[667.992135] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[668.000760] Starting dynamic subtest: pipe-D-eDP-1
[668.002877] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[668.004877] Subtest bad-htotal: SUCCESS (0.292s)
[668.839976] [125/133] (366s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[668.929153] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[675.444905] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.517s)
[675.581621] [126/133] (360s left) kms_frontbuffer_tracking (fbcpsr-tiling-4)
[675.693132] Starting subtest: fbcpsr-tiling-4
[682.693948] Subtest fbcpsr-tiling-4: SUCCESS (7.005s)
[682.807434] [127/133] (352s left) gem_exec_big (single)
[682.880401] Starting subtest: single
[685.341564] Subtest single: SUCCESS (2.462s)
[685.556852] [128/133] (350s left) kms_chamelium_hpd (common-hpd-after-suspend)
[686.000904] Subtest common-hpd-after-suspend: SKIP (0.000s)
[686.803970] [129/133] (348s left) gem_render_linear_blits (basic)
[686.869786] Starting subtest: basic
[686.895858] Subtest basic: SUCCESS (0.026s)
[687.010917] [130/133] (348s left) gem_wait (await)
[687.088912] Starting subtest: await
[687.091818] Starting dynamic subtest: all-engines
[688.248176] Dynamic subtest all-engines: SUCCESS (1.158s)
[688.267174] Starting dynamic subtest: rcs0
[689.310144] Dynamic subtest rcs0: SUCCESS (1.060s)
[689.329949] Starting dynamic subtest: bcs0
[690.376148] Dynamic subtest bcs0: SUCCESS (1.062s)
[690.394813] Starting dynamic subtest: vcs0
[691.435175] Dynamic subtest vcs0: SUCCESS (1.057s)
[691.452506] Starting dynamic subtest: vcs1
[692.493145] Dynamic subtest vcs1: SUCCESS (1.055s)
[692.510370] Starting dynamic subtest: vecs0
[693.551436] Dynamic subtest vecs0: SUCCESS (1.056s)
[693.571474] Starting dynamic subtest: ccs0
[694.613186] Dynamic subtest ccs0: SUCCESS (1.059s)
[694.616100] Subtest await: SUCCESS (7.524s)
[694.820484] [131/133] (341s left) gem_eio (in-flight-contexts-10ms)
[695.025948] Starting subtest: in-flight-contexts-10ms
[708.500159] Subtest in-flight-contexts-10ms: SUCCESS (13.472s)
[708.699949] [132/133] (327s left) vgem_basic (busy-fence)
[708.746778] Starting subtest: busy-fence
[708.749013] Subtest busy-fence: SUCCESS (0.000s)
[708.817821] [133/133] (327s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu)
[708.926859] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu
[716.346137] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (7.419s)
[716.492263] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: gem_render_copy
results: parsing output: 2/ for test: gem_eio
results: parsing output: 3/ for test: kms_cursor_crc
results: parsing output: 4/ for test: kms_flip
results: parsing output: 5/ for test: gem_ctx_persistence
results: parsing output: 6/ for test: core_setmaster
results: parsing output: 7/ for test: gem_exec_schedule
results: parsing output: 8/ for test: gem_exec_fence
results: parsing output: 9/ for test: kms_lease
results: parsing output: 10/ for test: kms_vrr
results: parsing output: 11/ for test: gem_exec_reloc
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: kms_content_protection
results: parsing output: 15/ for test: gem_softpin
results: parsing output: 16/ for test: gem_ctx_shared
results: parsing output: 17/ for test: kms_flip_scaled_crc
results: parsing output: 18/ for test: syncobj_wait
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_plane
results: parsing output: 22/ for test: i915_pciid
results: parsing output: 23/ for test: kms_plane_scaling
results: parsing output: 24/ for test: kms_universal_plane
results: parsing output: 25/ for test: i915_suspend
results: parsing output: 26/ for test: kms_psr
results: parsing output: 27/ for test: gem_mmap_gtt
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_ccs
results: parsing output: 30/ for test: kms_psr
results: parsing output: 31/ for test: syncobj_eventfd
results: parsing output: 32/ for test: prime_busy
results: parsing output: 33/ for test: gem_mmap_gtt
results: parsing output: 34/ for test: kms_chamelium_frames
results: parsing output: 35/ for test: gem_mmap_gtt
results: parsing output: 36/ for test: kms_feature_discovery
results: parsing output: 37/ for test: gem_exec_schedule
results: parsing output: 38/ for test: gem_tiled_blits
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: gen7_exec_parse
results: parsing output: 41/ for test: kms_plane_scaling
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_async_flips
results: parsing output: 44/ for test: kms_flip_scaled_crc
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: gem_basic
results: parsing output: 47/ for test: gem_exec_reloc
results: parsing output: 48/ for test: kms_pm_rpm
results: parsing output: 49/ for test: core_auth
results: parsing output: 50/ for test: syncobj_wait
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: syncobj_wait
results: parsing output: 53/ for test: kms_cursor_edge_walk
results: parsing output: 54/ for test: prime_vgem
results: parsing output: 55/ for test: kms_ccs
results: parsing output: 56/ for test: gem_madvise
results: parsing output: 57/ for test: kms_pwrite_crc
results: parsing output: 58/ for test: gem_exec_schedule
results: parsing output: 59/ for test: gem_ccs
results: parsing output: 60/ for test: i915_drm_fdinfo
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_cursor_legacy
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: i915_pm_rps
results: parsing output: 67/ for test: kms_psr2_sf
results: parsing output: 68/ for test: intel_hwmon
results: parsing output: 69/ for test: gem_lmem_swapping
results: parsing output: 70/ for test: perf_pmu
results: parsing output: 71/ for test: gem_pread
results: parsing output: 72/ for test: gem_exec_flush
results: parsing output: 73/ for test: kms_cursor_legacy
results: parsing output: 74/ for test: kms_big_fb
results: parsing output: 75/ for test: kms_addfb_basic
results: parsing output: 76/ for test: kms_flip_scaled_crc
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: syncobj_wait
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: kms_prop_blob
results: parsing output: 81/ for test: kms_psr
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: kms_pipe_crc_basic
results: parsing output: 84/ for test: prime_mmap
results: parsing output: 85/ for test: syncobj_basic
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: kms_vblank
results: parsing output: 89/ for test: kms_ccs
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: kms_cursor_legacy
results: parsing output: 92/ for test: gem_create
results: parsing output: 93/ for test: gem_exec_balancer
results: parsing output: 94/ for test: api_intel_allocator
results: parsing output: 95/ for test: kms_addfb_basic
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: gen3_render_mixed_blits
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: gem_mmap_offset
results: parsing output: 100/ for test: gem_mmap_gtt
results: parsing output: 101/ for test: kms_big_fb
results: parsing output: 102/ for test: gem_render_copy
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: template
results: parsing output: 105/ for test: kms_hdr
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_cursor_crc
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: kms_flip
results: parsing output: 111/ for test: kms_cursor_crc
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: gem_exec_create
results: parsing output: 116/ for test: kms_flip
results: parsing output: 117/ for test: kms_plane_multiple
results: parsing output: 118/ for test: gem_sync
results: parsing output: 119/ for test: kms_color
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_pm_rpm
results: parsing output: 122/ for test: gem_exec_fence
results: parsing output: 123/ for test: kms_invalid_mode
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_exec_big
results: parsing output: 127/ for test: kms_chamelium_hpd
results: parsing output: 128/ for test: gem_render_linear_blits
results: parsing output: 129/ for test: gem_wait
results: parsing output: 130/ for test: gem_eio
results: parsing output: 131/ for test: vgem_basic
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.