[71.618807] Initializing watchdogs
[71.618909]   /dev/watchdog0
[71.657653] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:4905 Intel Dg1 (Gen12) DG1 [Iris Xe MAX Graphics]
[71.678960] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[71.688505] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[71.693397] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[73.254141] Starting subtest: fbc-tiling-linear
[75.754038] Subtest fbc-tiling-linear: SUCCESS (2.501s)
[75.932801] [002/156] (956s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[75.984454] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[76.064683] [003/156] (956s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[77.564341] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[77.567455] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[77.755048] [004/156] (954s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[79.273826] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[79.277153] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[79.442567] [005/156] (953s left) kms_force_connector_basic (prune-stale-modes)
[79.579420] Starting subtest: prune-stale-modes
[79.601731] Subtest prune-stale-modes: SUCCESS (0.022s)
[81.252499] [006/156] (951s left) perf_pmu (most-busy-check-all)
[81.317352] Starting subtest: most-busy-check-all
[81.319045] Starting dynamic subtest: rcs0
[81.893158] Dynamic subtest rcs0: SUCCESS (0.575s)
[81.907490] Starting dynamic subtest: bcs0
[82.444893] Dynamic subtest bcs0: SUCCESS (0.551s)
[82.457227] Starting dynamic subtest: vcs0
[82.992375] Dynamic subtest vcs0: SUCCESS (0.546s)
[83.005069] Starting dynamic subtest: vcs1
[83.540528] Dynamic subtest vcs1: SUCCESS (0.548s)
[83.552721] Starting dynamic subtest: vecs0
[84.088522] Dynamic subtest vecs0: SUCCESS (0.547s)
[84.090088] Subtest most-busy-check-all: SUCCESS (2.771s)
[84.212966] [007/156] (948s left) gem_ccs (large-ctrl-surf-copy)
[84.267420] Starting subtest: large-ctrl-surf-copy
[84.270230] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[84.368699] [008/156] (948s left) kms_plane_alpha_blend (coverage-7efc)
[85.859349] Starting subtest: coverage-7efc
[86.136146] Starting dynamic subtest: pipe-A-HDMI-A-4
[86.630558] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.492s)
[86.659311] Starting dynamic subtest: pipe-D-HDMI-A-4
[87.224337] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.572s)
[87.225901] Subtest coverage-7efc: SUCCESS (1.363s)
[87.415883] [009/156] (945s left) gem_mmap_gtt (fault-concurrent)
[87.470393] Subtest fault-concurrent: SKIP (0.000s)
[87.544766] [010/156] (945s left) api_intel_allocator (gem-pool)
[87.593753] Starting subtest: gem-pool
[87.617682] Subtest gem-pool: SUCCESS (0.026s)
[87.824174] [011/156] (945s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[89.322113] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[89.325146] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[89.571844] [012/156] (943s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[91.063544] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[91.066608] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[91.174658] [013/156] (942s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[92.673956] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[92.675430] Starting dynamic subtest: pipe-A-HDMI-A-4
[92.689649] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.001s)
[92.700126] Starting dynamic subtest: pipe-B-HDMI-A-4
[92.714915] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.001s)
[92.725144] Starting dynamic subtest: pipe-C-HDMI-A-4
[92.740120] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.001s)
[92.750325] Starting dynamic subtest: pipe-D-HDMI-A-4
[92.766668] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.001s)
[92.770119] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.008s)
[92.954576] [014/156] (940s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[94.466571] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[94.468258] Starting dynamic subtest: pipe-A-HDMI-A-4
[94.487895] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[94.498552] Starting dynamic subtest: pipe-B-HDMI-A-4
[94.514216] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[94.524229] Starting dynamic subtest: pipe-C-HDMI-A-4
[94.537252] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[94.546832] Starting dynamic subtest: pipe-D-HDMI-A-4
[94.560761] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[94.563622] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[94.730351] [015/156] (938s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[96.231670] Starting subtest: hdr-rgb565-draw-blt
[96.234566] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[96.410221] [016/156] (937s left) kms_pm_rpm (legacy-planes-dpms)
[100.061995] Starting subtest: legacy-planes-dpms
[100.064959] Starting dynamic subtest: plane-65
[106.945374] Dynamic subtest plane-65: SUCCESS (6.884s)
[106.995347] Starting dynamic subtest: plane-95
[113.947430] Dynamic subtest plane-95: SUCCESS (7.001s)
[113.998675] Starting dynamic subtest: plane-125
[120.780427] Dynamic subtest plane-125: SUCCESS (6.831s)
[120.830658] Starting dynamic subtest: plane-135
[127.747680] Dynamic subtest plane-135: SUCCESS (6.966s)
[127.797533] Starting dynamic subtest: plane-145
[134.709998] Dynamic subtest plane-145: SUCCESS (6.960s)
[134.760298] Starting dynamic subtest: plane-155
[141.669762] Dynamic subtest plane-155: SUCCESS (6.957s)
[141.672405] Subtest legacy-planes-dpms: SUCCESS (41.612s)
[142.695912] [017/156] (890s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[142.753933] Starting subtest: x-tiled-to-vebox-y-tiled
[142.755878] Starting dynamic subtest: smem
[142.838800] Dynamic subtest smem: SUCCESS (0.083s)
[142.849488] Starting dynamic subtest: lmem0
[143.594305] Dynamic subtest lmem0: SUCCESS (0.753s)
[143.596145] Subtest x-tiled-to-vebox-y-tiled: SUCCESS (0.837s)
[143.837394] [018/156] (889s left) sysfs_heartbeat_interval (invalid)
[143.891190] Starting subtest: invalid
[143.895333] Starting dynamic subtest: vecs0
[143.896981] Dynamic subtest vecs0: SUCCESS (0.001s)
[143.907935] Starting dynamic subtest: vcs0
[143.909895] Dynamic subtest vcs0: SUCCESS (0.000s)
[143.920350] Starting dynamic subtest: bcs0
[143.921986] Dynamic subtest bcs0: SUCCESS (0.000s)
[143.932736] Starting dynamic subtest: rcs0
[143.934558] Dynamic subtest rcs0: SUCCESS (0.000s)
[143.946935] Starting dynamic subtest: vcs1
[143.948919] Dynamic subtest vcs1: SUCCESS (0.001s)
[143.950878] Subtest invalid: SUCCESS (0.005s)
[144.010063] [019/156] (889s left) kms_frontbuffer_tracking (fbc-stridechange)
[145.509809] Starting subtest: fbc-stridechange
[147.556779] Subtest fbc-stridechange: SUCCESS (2.051s)
[147.761502] [020/156] (886s left) gem_ctx_param (basic-default)
[147.808986] Starting subtest: basic-default
[147.810618] Subtest basic-default: SUCCESS (0.000s)
[147.919691] [021/156] (885s left) kms_psr (fbc-pr-primary-mmap-gtt)
[149.411870] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[149.478767] [022/156] (884s left) i915_pm_freq_mult (media-freq)
[149.526963] Starting subtest: media-freq
[149.528562] Starting dynamic subtest: gt0
[149.531725] Dynamic subtest gt0: SKIP (0.000s)
[149.534916] Subtest media-freq: SKIP (0.001s)
[149.643511] [023/156] (884s left) prime_busy (before-wait)
[149.694532] Starting subtest: before-wait
[149.700918] Starting dynamic subtest: rcs0
[149.751095] Dynamic subtest rcs0: SUCCESS (0.050s)
[149.761745] Starting dynamic subtest: bcs0
[149.791786] Dynamic subtest bcs0: SUCCESS (0.040s)
[149.804907] Starting dynamic subtest: vcs0
[149.834761] Dynamic subtest vcs0: SUCCESS (0.042s)
[149.859039] Starting dynamic subtest: vcs1
[149.878855] Dynamic subtest vcs1: SUCCESS (0.043s)
[149.903488] Starting dynamic subtest: vecs0
[149.923447] Dynamic subtest vecs0: SUCCESS (0.043s)
[150.034924] Subtest before-wait: SUCCESS (0.337s)
[150.188289] [024/156] (883s left) gem_exec_gttfill (engines)
[150.249160] Starting subtest: engines
[150.250884] Starting dynamic subtest: rcs0
[177.803186] Dynamic subtest rcs0: SUCCESS (27.551s)
[177.814809] Starting dynamic subtest: bcs0
[205.662316] Dynamic subtest bcs0: SUCCESS (27.858s)
[205.674323] Starting dynamic subtest: vcs0
[233.485136] Dynamic subtest vcs0: SUCCESS (27.822s)
[233.496473] Starting dynamic subtest: vcs1
[261.320136] Dynamic subtest vcs1: SUCCESS (27.834s)
[261.331407] Starting dynamic subtest: vecs0
[289.067118] Dynamic subtest vecs0: SUCCESS (27.746s)
[289.068688] Subtest engines: SUCCESS (138.814s)
[289.356088] [025/156] (744s left) syncobj_timeline (etime-multi-wait-all-submitted)
[289.461196] Starting subtest: etime-multi-wait-all-submitted
[289.566973] Subtest etime-multi-wait-all-submitted: SUCCESS (0.101s)
[289.682151] [026/156] (744s left) gem_ctx_param (get-priority-new-ctx)
[289.729340] Starting subtest: get-priority-new-ctx
[289.730960] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[289.831859] [027/156] (744s left) kms_big_fb (linear-32bpp-rotate-90)
[291.358769] Starting subtest: linear-32bpp-rotate-90
[291.458428] Subtest linear-32bpp-rotate-90: SKIP (0.097s)
[291.629620] [028/156] (742s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[295.974559] Starting subtest: heavy-verify-multi-ccs
[295.976231] Starting dynamic subtest: lmem0
[295.981246] Dynamic subtest lmem0: SKIP (0.000s)
[295.985084] Subtest heavy-verify-multi-ccs: SKIP (0.001s)
[299.787290] [FACT gem_lmem_swapping (heavy-verify-multi-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[299.790222] [029/156] (734s left) gem_ctx_shared (disjoint-timelines)
[303.120812] Starting subtest: disjoint-timelines
[303.189779] Subtest disjoint-timelines: SUCCESS (0.070s)
[303.496742] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.i915: true
[303.497472] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[303.500753] [030/156] (730s left) kms_psr (fbc-psr2-cursor-plane-move)
[304.998040] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[305.084363] [031/156] (729s left) gem_bad_reloc (negative-reloc-bltcopy)
[305.141194] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[305.278075] [032/156] (729s left) kms_chamelium_hpd (hdmi-hpd)
[306.814118] Subtest hdmi-hpd: SKIP (0.000s)
[306.942606] [033/156] (727s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[308.437895] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[308.523751] [034/156] (726s left) gem_eio (wait-10ms)
[308.666415] Starting subtest: wait-10ms
[309.039603] Subtest wait-10ms: SUCCESS (0.377s)
[310.602987] [035/156] (724s left) kms_content_protection (legacy-hdcp14)
[312.174185] Starting subtest: legacy-hdcp14
[312.179654] Subtest legacy-hdcp14: SKIP (0.002s)
[312.396666] [036/156] (722s left) syncobj_wait (reset-signaled)
[312.447779] Starting subtest: reset-signaled
[312.449766] Subtest reset-signaled: SUCCESS (0.000s)
[312.583195] [037/156] (722s left) gem_ctx_isolation (dirty-switch)
[312.643150] Starting subtest: dirty-switch
[312.644711] Starting dynamic subtest: rcs0
[313.464085] Dynamic subtest rcs0: SUCCESS (0.822s)
[313.477547] Starting dynamic subtest: bcs0
[314.326908] Dynamic subtest bcs0: SUCCESS (0.862s)
[314.339636] Starting dynamic subtest: vcs0
[315.178679] Dynamic subtest vcs0: SUCCESS (0.851s)
[315.191766] Starting dynamic subtest: vcs1
[316.020557] Dynamic subtest vcs1: SUCCESS (0.841s)
[316.033424] Starting dynamic subtest: vecs0
[316.842805] Dynamic subtest vecs0: SUCCESS (0.821s)
[316.844471] Subtest dirty-switch: SUCCESS (4.201s)
[317.190353] [038/156] (717s left) gem_flink_basic (bad-open)
[317.238984] Starting subtest: bad-open
[317.240857] Subtest bad-open: SUCCESS (0.000s)
[317.344960] [039/156] (717s left) gem_caching (writes)
[317.409212] Starting subtest: writes
[322.666737] Subtest writes: SUCCESS (5.267s)
[322.838112] [040/156] (712s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[324.350668] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[324.352303] Starting dynamic subtest: pipe-A
[325.142876] Dynamic subtest pipe-A: SUCCESS (0.792s)
[325.156532] Starting dynamic subtest: pipe-B
[325.917344] Dynamic subtest pipe-B: SUCCESS (0.773s)
[325.932801] Starting dynamic subtest: pipe-C
[326.691833] Dynamic subtest pipe-C: SUCCESS (0.773s)
[326.705842] Starting dynamic subtest: pipe-D
[327.464752] Dynamic subtest pipe-D: SUCCESS (0.772s)
[327.466569] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (3.115s)
[327.698782] [041/156] (707s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[327.752147] Starting subtest: single-wait-all-for-submit-available-submitted
[327.753968] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[327.860164] [042/156] (707s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[329.360255] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[329.363621] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[329.568769] [043/156] (706s left) kms_pm_rpm (system-suspend-idle)
[333.014466] Starting subtest: system-suspend-idle
[352.540331] Subtest system-suspend-idle: SUCCESS (4.723s)
[356.615011] [044/156] (682s left) kms_atomic_transition (modeset-transition)
[358.116920] Starting subtest: modeset-transition
[358.118284] Starting dynamic subtest: 1x-outputs
[360.271935] Dynamic subtest 1x-outputs: SUCCESS (2.141s)
[360.273349] Subtest modeset-transition: SUCCESS (2.145s)
[360.489688] [045/156] (678s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[361.992068] Starting subtest: pipe-fbc-rte
[362.001095] Subtest pipe-fbc-rte: SKIP (0.000s)
[362.180084] [046/156] (676s left) gem_spin_batch (resubmit-all)
[362.239756] Starting subtest: resubmit-all
[362.241353] Starting dynamic subtest: rcs0
[362.263360] Dynamic subtest rcs0: SUCCESS (0.023s)
[362.279063] Starting dynamic subtest: bcs0
[362.280735] Dynamic subtest bcs0: SUCCESS (0.004s)
[362.294858] Starting dynamic subtest: vcs0
[362.296595] Dynamic subtest vcs0: SUCCESS (0.002s)
[362.310125] Starting dynamic subtest: vcs1
[362.311829] Dynamic subtest vcs1: SUCCESS (0.002s)
[362.325413] Starting dynamic subtest: vecs0
[362.327122] Dynamic subtest vecs0: SUCCESS (0.002s)
[362.328889] Subtest resubmit-all: SUCCESS (0.039s)
[362.616225] [047/156] (676s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[364.119211] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[364.122275] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[364.303969] [048/156] (674s left) syncobj_basic (illegal-fd-to-handle)
[364.353716] Starting subtest: illegal-fd-to-handle
[364.355353] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[364.477790] [049/156] (674s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[365.978509] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[365.981701] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[366.219448] [050/156] (673s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[367.733740] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[368.998423] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.259s)
[369.214037] [051/156] (670s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[369.265456] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[369.372155] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[369.515676] [052/156] (670s left) api_intel_bb (misplaced-blitter)
[369.566537] Starting subtest: misplaced-blitter
[369.587555] Subtest misplaced-blitter: SUCCESS (0.022s)
[369.756500] [053/156] (669s left) kms_atomic_interruptible (universal-setplane-cursor)
[371.250391] Starting subtest: universal-setplane-cursor
[371.251988] Starting dynamic subtest: pipe-A-HDMI-A-4
[377.466983] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (6.209s)
[377.468706] Subtest universal-setplane-cursor: SUCCESS (6.211s)
[377.655671] [054/156] (662s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[379.178070] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[379.181428] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[379.371017] [055/156] (660s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[380.871593] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[380.875414] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[381.060882] [056/156] (658s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[382.556041] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[382.557771] Starting dynamic subtest: pipe-A-valid-mode
[383.897410] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.342s)
[383.899948] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.344s)
[384.110937] [057/156] (655s left) gem_pxp (create-protected-buffer)
[386.688687] Starting subtest: create-protected-buffer
[386.692183] Subtest create-protected-buffer: SKIP (0.000s)
[386.802906] [058/156] (653s left) gem_fence_thrash (bo-write-verify-threaded-x)
[386.848471] Starting subtest: bo-write-verify-threaded-x
[386.853140] Subtest bo-write-verify-threaded-x: SKIP (0.002s)
[386.937101] [059/156] (653s left) gen9_exec_parse (allowed-single)
[386.992707] Subtest allowed-single: SKIP (0.000s)
[387.071089] [060/156] (653s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[388.572081] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[388.575525] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[388.765465] [061/156] (651s left) gem_exec_balancer (bonded-semaphore)
[388.827679] Starting subtest: bonded-semaphore
[388.830903] Subtest bonded-semaphore: SKIP (0.000s)
[389.261400] [062/156] (651s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[390.754068] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[390.788452] Starting dynamic subtest: pipe-A-plane-0
[391.322449] Dynamic subtest pipe-A-plane-0: SUCCESS (0.533s)
[391.362893] Starting dynamic subtest: pipe-A-plane-3
[391.655741] Dynamic subtest pipe-A-plane-3: SUCCESS (0.330s)
[391.707230] Starting dynamic subtest: pipe-A-plane-5
[391.971780] Dynamic subtest pipe-A-plane-5: SUCCESS (0.313s)
[392.169137] Starting dynamic subtest: pipe-B-plane-0
[392.572897] Dynamic subtest pipe-B-plane-0: SUCCESS (0.416s)
[392.625792] Starting dynamic subtest: pipe-B-plane-3
[392.906599] Dynamic subtest pipe-B-plane-3: SUCCESS (0.330s)
[392.955020] Starting dynamic subtest: pipe-B-plane-5
[393.239286] Dynamic subtest pipe-B-plane-5: SUCCESS (0.328s)
[393.328449] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SUCCESS (2.575s)
[393.529127] [063/156] (646s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[395.030464] Starting subtest: fbc-rgb565-draw-mmap-wc
[395.033603] Subtest fbc-rgb565-draw-mmap-wc: SKIP (0.000s)
[395.187952] [064/156] (645s left) kms_pwrite_crc (basic)
[396.698021] Starting subtest: basic
[397.344913] Subtest basic: SUCCESS (0.643s)
[397.548001] [065/156] (643s left) gem_flink_basic (bad-flink)
[397.597595] Starting subtest: bad-flink
[397.599597] Subtest bad-flink: SUCCESS (0.000s)
[397.783904] [066/156] (643s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[399.286827] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[399.290298] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[399.467750] [067/156] (641s left) gem_ctx_persistence (legacy-engines-persistence)
[399.524467] Starting subtest: legacy-engines-persistence
[399.526288] Starting dynamic subtest: render
[400.316050] Dynamic subtest render: SUCCESS (0.791s)
[400.353181] Starting dynamic subtest: bsd1
[401.076931] Dynamic subtest bsd1: SUCCESS (0.758s)
[401.095395] Starting dynamic subtest: bsd2
[401.842870] Dynamic subtest bsd2: SUCCESS (0.763s)
[401.860455] Starting dynamic subtest: blt
[402.614913] Dynamic subtest blt: SUCCESS (0.770s)
[402.632752] Starting dynamic subtest: vebox
[403.379223] Dynamic subtest vebox: SUCCESS (0.761s)
[403.385486] Subtest legacy-engines-persistence: SUCCESS (3.857s)
[403.545529] [068/156] (637s left) gen7_exec_parse (basic-allowed)
[403.600411] Subtest basic-allowed: SKIP (0.000s)
[403.681889] [069/156] (637s left) gem_exec_schedule (reorder-wide)
[403.879108] Subtest reorder-wide: SKIP (0.000s)
[404.144095] [070/156] (637s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[405.640929] Starting subtest: psrhdr-slowdraw
[405.643918] Subtest psrhdr-slowdraw: SKIP (0.000s)
[405.807641] [071/156] (635s left) perf (non-zero-reason)
[406.103391] Starting subtest: non-zero-reason
[406.105042] Starting dynamic subtest: 0-rcs0
[417.156185] Dynamic subtest 0-rcs0: SUCCESS (11.046s)
[417.158111] Subtest non-zero-reason: SUCCESS (11.047s)
[417.239931] [072/156] (624s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[418.738407] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[418.741720] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[418.923082] [073/156] (622s left) i915_drm_fdinfo (all-busy-check-all)
[418.976920] Starting subtest: all-busy-check-all
[418.998900] Subtest all-busy-check-all: SKIP (0.020s)
[419.111139] [074/156] (622s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[420.643416] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[420.646341] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[420.827073] [075/156] (620s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[422.334850] Starting subtest: compare-crc-sanitycheck-nv12
[422.336227] Starting dynamic subtest: pipe-A-HDMI-A-4
[422.967275] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.628s)
[422.983371] Starting dynamic subtest: pipe-B-HDMI-A-4
[423.501260] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.532s)
[423.520886] Starting dynamic subtest: pipe-C-HDMI-A-4
[424.024067] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.520s)
[424.043094] Starting dynamic subtest: pipe-D-HDMI-A-4
[424.543361] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.514s)
[424.544987] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.207s)
[424.752510] [076/156] (617s left) gem_exec_reloc (basic-write-gtt)
[424.809699] Subtest basic-write-gtt: SKIP (0.000s)
[424.925271] [077/156] (616s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[426.725582] Starting subtest: cursor-rapid-movement-max-size
[426.730663] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[426.907972] [078/156] (615s left) kms_color_pipeline (plane-lut1d)
[428.412358] Starting subtest: plane-lut1d
[428.607008] Starting dynamic subtest: pipe-A-plane-0
[428.854333] Dynamic subtest pipe-A-plane-0: SUCCESS (0.248s)
[428.902761] Starting dynamic subtest: pipe-A-plane-1
[429.054499] Dynamic subtest pipe-A-plane-1: SUCCESS (0.193s)
[429.099207] Starting dynamic subtest: pipe-A-plane-2
[429.271334] Dynamic subtest pipe-A-plane-2: SUCCESS (0.209s)
[429.592288] Starting dynamic subtest: pipe-B-plane-0
[429.751077] Dynamic subtest pipe-B-plane-0: SUCCESS (0.197s)
[429.791444] Starting dynamic subtest: pipe-B-plane-1
[429.950841] Dynamic subtest pipe-B-plane-1: SUCCESS (0.192s)
[429.991884] Starting dynamic subtest: pipe-B-plane-2
[430.148323] Dynamic subtest pipe-B-plane-2: SUCCESS (0.193s)
[430.492888] Starting dynamic subtest: pipe-C-plane-0
[430.650177] Dynamic subtest pipe-C-plane-0: SUCCESS (0.180s)
[430.687981] Starting dynamic subtest: pipe-C-plane-1
[430.862971] Dynamic subtest pipe-C-plane-1: SUCCESS (0.208s)
[430.905908] Starting dynamic subtest: pipe-C-plane-2
[431.081477] Dynamic subtest pipe-C-plane-2: SUCCESS (0.209s)
[431.387448] Starting dynamic subtest: pipe-D-plane-0
[431.552708] Dynamic subtest pipe-D-plane-0: SUCCESS (0.197s)
[431.596456] Starting dynamic subtest: pipe-D-plane-1
[431.769476] Dynamic subtest pipe-D-plane-1: SUCCESS (0.209s)
[431.788436] Starting dynamic subtest: pipe-D-plane-2
[431.953052] Dynamic subtest pipe-D-plane-2: SUCCESS (0.178s)
[432.006150] Subtest plane-lut1d: SUCCESS (3.593s)
[432.210845] [079/156] (609s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[433.723330] Starting subtest: yf-tiled-16bpp-rotate-180
[433.727121] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[433.894464] [080/156] (608s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[435.407796] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[435.410821] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[435.572229] [081/156] (606s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[437.077569] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[437.081191] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[437.263216] [082/156] (605s left) gem_exec_reloc (basic-wc-read)
[437.319284] Subtest basic-wc-read: SKIP (0.000s)
[437.448858] [083/156] (604s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[438.939759] Starting subtest: short-busy-flip-before-cursor-toggle
[438.943463] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[439.070418] [084/156] (603s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[440.590876] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[440.610030] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.018s)
[440.736269] [085/156] (601s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[442.241603] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[442.244904] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[442.426811] [086/156] (600s left) kms_draw_crc (draw-method-render)
[443.925205] Starting subtest: draw-method-render
[443.926954] Starting dynamic subtest: xrgb8888-untiled
[444.340181] Dynamic subtest xrgb8888-untiled: SUCCESS (0.415s)
[444.390586] Starting dynamic subtest: rgb565-untiled
[444.690631] Dynamic subtest rgb565-untiled: SUCCESS (0.343s)
[444.740960] Starting dynamic subtest: xrgb2101010-untiled
[445.040872] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.342s)
[445.091172] Starting dynamic subtest: xbgr16161616f-untiled
[445.389119] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.342s)
[445.406496] Starting dynamic subtest: xrgb8888-xtiled
[445.557505] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.165s)
[445.610187] Starting dynamic subtest: rgb565-xtiled
[445.757739] Dynamic subtest rgb565-xtiled: SUCCESS (0.192s)
[445.799606] Starting dynamic subtest: xrgb2101010-xtiled
[445.941103] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.175s)
[445.991401] Starting dynamic subtest: xbgr16161616f-xtiled
[446.141202] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.192s)
[446.195374] Starting dynamic subtest: xrgb8888-ytiled
[446.358022] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.209s)
[446.396873] Starting dynamic subtest: rgb565-ytiled
[446.540190] Dynamic subtest rgb565-ytiled: SUCCESS (0.175s)
[446.563995] Starting dynamic subtest: xrgb2101010-ytiled
[446.707828] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.165s)
[446.761181] Starting dynamic subtest: xbgr16161616f-ytiled
[446.908123] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.192s)
[446.917678] Subtest draw-method-render: SUCCESS (2.987s)
[447.096110] [087/156] (595s left) kms_cursor_crc (cursor-sliding-256x85)
[448.827780] Starting subtest: cursor-sliding-256x85
[448.835285] Starting dynamic subtest: pipe-A-HDMI-A-4
[453.243458] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (4.411s)
[453.281728] Starting dynamic subtest: pipe-D-HDMI-A-4
[457.599348] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (4.333s)
[457.600812] Subtest cursor-sliding-256x85: SUCCESS (8.774s)
[457.992902] [088/156] (584s left) kms_cursor_crc (cursor-onscreen-256x256)
[459.714629] Starting subtest: cursor-onscreen-256x256
[459.723222] Starting dynamic subtest: pipe-A-HDMI-A-4
[462.527059] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.806s)
[462.564467] Starting dynamic subtest: pipe-D-HDMI-A-4
[465.200758] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.650s)
[465.202565] Subtest cursor-onscreen-256x256: SUCCESS (5.486s)
[465.570828] [089/156] (577s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[467.069075] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[467.072474] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[467.262211] [090/156] (575s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[468.772094] Starting subtest: fbcpsr-slowdraw
[468.775524] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[468.937089] [091/156] (574s left) kms_lease (cursor-implicit-plane)
[470.428128] Starting subtest: cursor-implicit-plane
[470.429788] Starting dynamic subtest: pipe-A-HDMI-A-4
[470.856078] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (0.425s)
[470.871463] Starting dynamic subtest: pipe-B-HDMI-A-4
[471.084406] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (0.225s)
[471.104912] Starting dynamic subtest: pipe-C-HDMI-A-4
[471.314784] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (0.227s)
[471.336392] Starting dynamic subtest: pipe-D-HDMI-A-4
[471.548583] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (0.230s)
[471.550800] Subtest cursor-implicit-plane: SUCCESS (1.120s)
[471.768959] [092/156] (571s left) syncobj_wait (invalid-reset-zero-handles)
[471.817985] Starting subtest: invalid-reset-zero-handles
[471.819531] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[471.948675] [093/156] (571s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[473.447401] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[473.450873] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[473.653840] [094/156] (569s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[475.166717] Starting subtest: yf-tiled-64bpp-rotate-90
[475.170048] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[475.394002] [095/156] (568s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[476.910757] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[476.914085] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[477.075159] [096/156] (566s left) gem_exec_fence (long-history)
[477.530382] Starting subtest: long-history
[483.603280] Subtest long-history: SUCCESS (6.070s)
[484.019118] [097/156] (559s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[485.527431] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[485.533200] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[485.726257] [098/156] (558s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[487.215479] Starting subtest: bad-rotation-90-yf-tiled-ccs
[487.217178] Starting dynamic subtest: pipe-A-HDMI-A-4
[487.236374] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[487.252082] Starting dynamic subtest: pipe-B-HDMI-A-4
[487.268994] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[487.284108] Starting dynamic subtest: pipe-C-HDMI-A-4
[487.302057] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[487.317019] Starting dynamic subtest: pipe-D-HDMI-A-4
[487.338397] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[487.342388] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.010s)
[487.501855] [099/156] (556s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[488.993092] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[490.971089] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (1.980s)
[491.159515] [100/156] (553s left) kms_chamelium_audio (dp-audio-edid)
[492.711965] Subtest dp-audio-edid: SKIP (0.000s)
[492.850214] [101/156] (551s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[494.344989] Starting subtest: addfb25-yf-tiled-legacy
[494.346607] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[494.552781] [102/156] (549s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[496.049306] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[496.054712] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[496.236746] [103/156] (548s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[497.735706] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[497.738715] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[497.905841] [104/156] (546s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[499.404081] Starting subtest: fbc-2p-pri-indfb-multidraw
[499.406962] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[499.579721] [105/156] (545s left) kms_sequence (queue-busy)
[501.073981] Starting subtest: queue-busy
[501.075588] Starting dynamic subtest: pipe-A-HDMI-A-4
[503.684692] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (2.610s)
[503.700282] Starting dynamic subtest: pipe-B-HDMI-A-4
[506.109726] Dynamic subtest pipe-B-HDMI-A-4: SUCCESS (2.422s)
[506.125197] Starting dynamic subtest: pipe-C-HDMI-A-4
[508.535638] Dynamic subtest pipe-C-HDMI-A-4: SUCCESS (2.423s)
[508.552324] Starting dynamic subtest: pipe-D-HDMI-A-4
[510.969662] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (2.432s)
[510.971108] Subtest queue-busy: SUCCESS (9.897s)
[511.177886] [106/156] (533s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[512.668456] Starting subtest: plane-upscale-20x20-with-rotation
[512.669923] Starting dynamic subtest: pipe-A
[513.075962] Dynamic subtest pipe-A: SKIP (0.404s)
[513.090931] Starting dynamic subtest: pipe-B
[513.498635] Dynamic subtest pipe-B: SKIP (0.422s)
[513.513718] Starting dynamic subtest: pipe-C
[513.921326] Dynamic subtest pipe-C: SKIP (0.420s)
[513.935988] Starting dynamic subtest: pipe-D
[514.343818] Dynamic subtest pipe-D: SKIP (0.423s)
[514.346738] Subtest plane-upscale-20x20-with-rotation: SKIP (1.675s)
[514.548679] [107/156] (530s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[516.053754] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[516.056759] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[516.181852] [108/156] (528s left) gem_exec_reloc (basic-write-wc)
[516.237954] Subtest basic-write-wc: SKIP (0.000s)
[516.344051] [109/156] (528s left) syncobj_timeline (reset-signaled)
[516.395037] Starting subtest: reset-signaled
[516.396655] Subtest reset-signaled: SUCCESS (0.000s)
[516.511627] [110/156] (528s left) kms_psr (pr-cursor-plane-move)
[518.004473] Subtest pr-cursor-plane-move: SKIP (0.000s)
[518.091527] [111/156] (527s left) syncobj_wait (invalid-wait-illegal-handle)
[518.141535] Starting subtest: invalid-wait-illegal-handle
[518.143271] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[518.275812] [112/156] (527s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[519.775184] Starting subtest: extended-pageflip-modeset-hang-oldfb
[519.776883] Starting dynamic subtest: pipe-A
[527.517156] Dynamic subtest pipe-A: SUCCESS (7.740s)
[527.534892] Starting dynamic subtest: pipe-D
[535.251385] Dynamic subtest pipe-D: SUCCESS (7.727s)
[535.253117] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (15.475s)
[535.580542] [113/156] (509s left) kms_psr (psr2-primary-blt)
[537.076330] Subtest psr2-primary-blt: SKIP (0.000s)
[537.152455] [114/156] (508s left) gem_softpin (evict-snoop-interruptible)
[537.219764] Starting subtest: evict-snoop-interruptible
[537.223190] Subtest evict-snoop-interruptible: SKIP (0.000s)
[537.327472] [115/156] (508s left) kms_force_connector_basic (force-load-detect)
[537.460116] Starting subtest: force-load-detect
[537.463123] Subtest force-load-detect: SKIP (0.000s)
[538.380985] [116/156] (507s left) kms_chamelium_edid (hdmi-mode-timings)
[539.931905] Subtest hdmi-mode-timings: SKIP (0.000s)
[540.061960] [117/156] (505s left) syncobj_timeline (invalid-signal-zero-handles)
[540.110204] Starting subtest: invalid-signal-zero-handles
[540.112032] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[540.249835] [118/156] (505s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[541.750914] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[541.754414] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[541.924969] [119/156] (504s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[543.425036] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[543.430689] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[543.615364] [120/156] (502s left) gem_mmap_gtt (fault-concurrent-x)
[543.667853] Subtest fault-concurrent-X: SKIP (0.000s)
[543.744333] [121/156] (502s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[543.802338] Starting subtest: y-tiled-to-vebox-y-tiled
[543.804220] Starting dynamic subtest: smem
[543.884047] Dynamic subtest smem: SUCCESS (0.083s)
[543.899594] Starting dynamic subtest: lmem0
[544.637984] Dynamic subtest lmem0: SUCCESS (0.749s)
[544.639907] Subtest y-tiled-to-vebox-y-tiled: SUCCESS (0.835s)
[544.901348] [122/156] (501s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[546.411354] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[546.414794] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[546.591087] [123/156] (499s left) vgem_basic (debugfs)
[546.646938] Starting subtest: debugfs
[546.648443] Subtest debugfs: SUCCESS (0.000s)
[546.747488] [124/156] (499s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[548.239439] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[548.241105] Starting dynamic subtest: pipe-A-valid-mode
[549.562987] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.323s)
[549.564601] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.325s)
[549.774032] [125/156] (496s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[551.272495] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[551.275707] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[551.468674] [126/156] (495s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[552.965164] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[552.968567] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[553.161372] [127/156] (493s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[554.662584] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[554.665909] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[554.848850] [128/156] (492s left) kms_vblank (wait-forked-hang)
[556.338092] Starting subtest: wait-forked-hang
[556.339560] Starting dynamic subtest: pipe-A-HDMI-A-4
[583.270789] Dynamic subtest pipe-A-HDMI-A-4: SUCCESS (26.927s)
[583.289081] Starting dynamic subtest: pipe-D-HDMI-A-4
[608.288212] Dynamic subtest pipe-D-HDMI-A-4: SUCCESS (25.018s)
[608.289970] Subtest wait-forked-hang: SUCCESS (51.953s)
[608.499124] [129/156] (438s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[610.009931] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[610.011472] Starting dynamic subtest: pipe-A-valid-mode
[612.611034] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.601s)
[612.612767] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SUCCESS (2.604s)
[612.820544] [130/156] (434s left) gem_ctx_exec (basic-invalid-context)
[612.872554] Starting subtest: basic-invalid-context
[612.876595] Subtest basic-invalid-context: SUCCESS (0.005s)
[613.068099] [131/156] (434s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[614.567357] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[614.570804] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[614.741573] [132/156] (432s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[616.234352] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[616.310167] [133/156] (430s left) gem_ppgtt (blt-vs-render-ctxn)
[616.365861] Starting subtest: blt-vs-render-ctxN
[649.088247] Subtest blt-vs-render-ctxN: SUCCESS (32.720s)
[649.345013] [134/156] (398s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[649.460032] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[649.566456] [135/156] (397s left) gem_softpin (invalid)
[649.622756] Starting subtest: invalid
[649.635897] Subtest invalid: SUCCESS (0.015s)
[649.816465] [136/156] (397s left) gem_exec_capture (capture)
[649.872892] Starting subtest: capture
[649.874393] Starting dynamic subtest: rcs0-smem
[650.784937] Dynamic subtest rcs0-smem: SUCCESS (0.910s)
[650.801403] Starting dynamic subtest: rcs0-lmem0
[651.664987] Dynamic subtest rcs0-lmem0: SUCCESS (0.878s)
[651.680467] Starting dynamic subtest: bcs0-smem
[652.535489] Dynamic subtest bcs0-smem: SUCCESS (0.870s)
[652.551062] Starting dynamic subtest: bcs0-lmem0
[653.401414] Dynamic subtest bcs0-lmem0: SUCCESS (0.865s)
[653.416956] Starting dynamic subtest: vcs0-smem
[654.773737] Dynamic subtest vcs0-smem: SUCCESS (1.366s)
[654.789441] Starting dynamic subtest: vcs0-lmem0
[655.636590] Dynamic subtest vcs0-lmem0: SUCCESS (0.861s)
[655.652157] Starting dynamic subtest: vcs1-smem
[656.500502] Dynamic subtest vcs1-smem: SUCCESS (0.862s)
[656.516069] Starting dynamic subtest: vcs1-lmem0
[657.356891] Dynamic subtest vcs1-lmem0: SUCCESS (0.854s)
[657.372487] Starting dynamic subtest: vecs0-smem
[658.789332] Dynamic subtest vecs0-smem: FAIL (1.389s)
[658.804169] Starting dynamic subtest: vecs0-lmem0
[659.776053] Dynamic subtest vecs0-lmem0: FAIL (0.982s)
[659.777826] Subtest capture: FAIL (9.853s)
[659.889114] [137/156] (387s left) gem_tiled_blits (basic)
[659.944898] Subtest basic: SKIP (0.000s)
[660.026494] [138/156] (387s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[661.537058] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[661.540224] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[661.736525] [139/156] (386s left) gem_mmap_offset (partial-remap)
[661.795390] Starting subtest: partial-remap
[661.797005] Starting dynamic subtest: lmem0
[661.800213] Dynamic subtest lmem0: SUCCESS (0.005s)
[661.816286] Starting dynamic subtest: smem0
[661.819887] Dynamic subtest smem0: SUCCESS (0.005s)
[661.821676] Subtest partial-remap: SUCCESS (0.013s)
[661.949922] [140/156] (386s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[663.449506] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[663.453083] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[663.624110] [141/156] (384s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[665.153024] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[665.156057] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[665.310389] [142/156] (382s left) i915_power (sanity)
[665.379680] Starting subtest: sanity
[669.422161] Subtest sanity: SUCCESS (4.036s)
[669.599594] [143/156] (378s left) gem_exec_schedule (preempt-queue)
[669.678220] Subtest preempt-queue: SKIP (0.000s)
[670.054223] [144/156] (378s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[671.556513] Starting subtest: cursorB-vs-flipA-legacy
[671.563163] Subtest cursorB-vs-flipA-legacy: SKIP (0.002s)
[671.707834] [145/156] (376s left) i915_pm_rps (reset)
[671.767633] Starting subtest: reset
[676.493728] Subtest reset: SUCCESS (4.724s)
[676.666867] [146/156] (371s left) kms_psr (fbc-psr-cursor-plane-onoff)
[678.160687] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[678.243740] [147/156] (370s left) kms_setmode (clone-exclusive-crtc)
[678.297096] Starting subtest: clone-exclusive-crtc
[678.303178] Subtest clone-exclusive-crtc: SKIP (0.000s)
[678.436163] [148/156] (370s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[679.934141] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[679.937914] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[680.108933] [149/156] (368s left) kms_flip (flip-vs-rmfb)
[681.602951] Starting subtest: flip-vs-rmfb
[681.609780] Starting dynamic subtest: A-HDMI-A4
[689.407509] Dynamic subtest A-HDMI-A4: SUCCESS (7.796s)
[689.436837] Starting dynamic subtest: B-HDMI-A4
[697.104351] Dynamic subtest B-HDMI-A4: SUCCESS (7.692s)
[697.150113] Starting dynamic subtest: C-HDMI-A4
[704.816508] Dynamic subtest C-HDMI-A4: SUCCESS (7.706s)
[704.856862] Starting dynamic subtest: D-HDMI-A4
[712.512781] Dynamic subtest D-HDMI-A4: SUCCESS (7.689s)
[712.515401] Subtest flip-vs-rmfb: SUCCESS (30.908s)
[712.794461] [150/156] (336s left) gem_exec_whisper (basic-contexts-all)
[712.857343] Starting subtest: basic-contexts-all
[720.044008] Subtest basic-contexts-all: SUCCESS (7.181s)
[720.391028] [151/156] (328s left) dmabuf_sync_file (export-basic)
[720.453941] Starting subtest: export-basic
[720.455394] Subtest export-basic: SUCCESS (0.001s)
[720.558303] [152/156] (328s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[722.056987] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[722.059728] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[722.226149] [153/156] (327s left) gem_sync (basic-each)
[722.292419] Starting subtest: basic-each
[724.676858] Subtest basic-each: SUCCESS (2.386s)
[725.026162] [154/156] (324s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[726.515448] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[726.517133] Starting dynamic subtest: pipe-A-HDMI-A-4
[726.536109] Dynamic subtest pipe-A-HDMI-A-4: SKIP (0.000s)
[726.552076] Starting dynamic subtest: pipe-B-HDMI-A-4
[726.566463] Dynamic subtest pipe-B-HDMI-A-4: SKIP (0.000s)
[726.581608] Starting dynamic subtest: pipe-C-HDMI-A-4
[726.596807] Dynamic subtest pipe-C-HDMI-A-4: SKIP (0.000s)
[726.612376] Starting dynamic subtest: pipe-D-HDMI-A-4
[726.629309] Dynamic subtest pipe-D-HDMI-A-4: SKIP (0.000s)
[726.632705] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.010s)
[726.805233] [155/156] (322s left) syncobj_wait (multi-wait-for-submit-submitted)
[726.855375] Starting subtest: multi-wait-for-submit-submitted
[726.962464] Subtest multi-wait-for-submit-submitted: SUCCESS (0.106s)
[727.179935] [156/156] (322s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[728.681786] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[728.684836] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[728.870614] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_pipe_b_c_ivb
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_force_connector_basic
results: parsing output: 5/ for test: perf_pmu
results: parsing output: 6/ for test: gem_ccs
results: parsing output: 7/ for test: kms_plane_alpha_blend
results: parsing output: 8/ for test: gem_mmap_gtt
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_plane
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_pm_rpm
results: parsing output: 16/ for test: gem_render_copy
results: parsing output: 17/ for test: sysfs_heartbeat_interval
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: i915_pm_freq_mult
results: parsing output: 22/ for test: prime_busy
results: parsing output: 23/ for test: gem_exec_gttfill
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: gem_ctx_param
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: gem_lmem_swapping
results: parsing output: 28/ for test: gem_ctx_shared
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: gem_bad_reloc
results: parsing output: 31/ for test: kms_chamelium_hpd
results: parsing output: 32/ for test: kms_psr2_sf
results: parsing output: 33/ for test: gem_eio
results: parsing output: 34/ for test: kms_content_protection
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: gem_ctx_isolation
results: parsing output: 37/ for test: gem_flink_basic
results: parsing output: 38/ for test: gem_caching
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_pm_rpm
results: parsing output: 43/ for test: kms_atomic_transition
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_spin_batch
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: syncobj_basic
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: api_intel_bb
results: parsing output: 52/ for test: kms_atomic_interruptible
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_flip_scaled_crc
results: parsing output: 56/ for test: gem_pxp
results: parsing output: 57/ for test: gem_fence_thrash
results: parsing output: 58/ for test: gen9_exec_parse
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_exec_balancer
results: parsing output: 61/ for test: kms_plane
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_pwrite_crc
results: parsing output: 64/ for test: gem_flink_basic
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_ctx_persistence
results: parsing output: 67/ for test: gen7_exec_parse
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: perf
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: i915_drm_fdinfo
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_pipe_crc_basic
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: kms_cursor_crc
results: parsing output: 77/ for test: kms_color_pipeline
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_exec_reloc
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_draw_crc
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_lease
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_chamelium_audio
results: parsing output: 100/ for test: kms_addfb_basic
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_sequence
results: parsing output: 105/ for test: kms_plane_scaling
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_busy
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: gem_softpin
results: parsing output: 114/ for test: kms_force_connector_basic
results: parsing output: 115/ for test: kms_chamelium_edid
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_mmap_gtt
results: parsing output: 120/ for test: gem_render_copy
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: vgem_basic
results: parsing output: 123/ for test: kms_flip_scaled_crc
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_vblank
results: parsing output: 128/ for test: kms_flip_scaled_crc
results: parsing output: 129/ for test: gem_ctx_exec
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_psr2_sf
results: parsing output: 132/ for test: gem_ppgtt
results: parsing output: 133/ for test: gem_exec_reloc
results: parsing output: 134/ for test: gem_softpin
results: parsing output: 135/ for test: gem_exec_capture
results: parsing output: 136/ for test: gem_tiled_blits
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_mmap_offset
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: i915_power
results: parsing output: 142/ for test: gem_exec_schedule
results: parsing output: 143/ for test: kms_cursor_legacy
results: parsing output: 144/ for test: i915_pm_rps
results: parsing output: 145/ for test: kms_psr
results: parsing output: 146/ for test: kms_setmode
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: kms_flip
results: parsing output: 149/ for test: gem_exec_whisper
results: parsing output: 150/ for test: dmabuf_sync_file
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: gem_sync
results: parsing output: 153/ for test: kms_ccs
results: parsing output: 154/ for test: syncobj_wait
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.