[58.316864] Initializing watchdogs
[58.316909]   /dev/watchdog0
[58.332357] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[58.336204] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.338730] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.341995] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[58.462072] Starting subtest: fbc-tiling-linear
[62.426874] Subtest fbc-tiling-linear: SUCCESS (4.032s)
[63.587723] [002/156] (955s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[63.651545] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[63.704757] [003/156] (955s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[63.787578] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[63.791748] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[63.881269] [004/156] (955s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[63.965312] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[63.969808] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[64.069450] [005/156] (954s left) kms_force_connector_basic (prune-stale-modes)
[64.130771] Subtest prune-stale-modes: SKIP (0.000s)
[64.186547] [006/156] (954s left) perf_pmu (most-busy-check-all)
[64.226440] Starting subtest: most-busy-check-all
[64.228708] Starting dynamic subtest: rcs0
[66.238637] Dynamic subtest rcs0: SUCCESS (2.026s)
[66.249572] Starting dynamic subtest: bcs0
[68.222033] Dynamic subtest bcs0: SUCCESS (1.992s)
[68.232772] Starting dynamic subtest: vcs0
[70.207337] Dynamic subtest vcs0: SUCCESS (1.993s)
[70.218558] Starting dynamic subtest: vcs1
[72.253966] Dynamic subtest vcs1: SUCCESS (2.053s)
[72.266067] Starting dynamic subtest: vecs0
[74.238608] Dynamic subtest vecs0: SUCCESS (1.989s)
[74.249943] Starting dynamic subtest: ccs0
[76.222325] Dynamic subtest ccs0: SUCCESS (1.986s)
[76.225085] Subtest most-busy-check-all: SUCCESS (12.048s)
[76.335376] [007/156] (942s left) gem_ccs (large-ctrl-surf-copy)
[76.393507] Starting subtest: large-ctrl-surf-copy
[76.398333] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[76.489528] [008/156] (942s left) kms_plane_alpha_blend (coverage-7efc)
[76.568987] Starting subtest: coverage-7efc
[76.853151] Starting dynamic subtest: pipe-A-eDP-1
[77.456382] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.604s)
[77.488036] Starting dynamic subtest: pipe-D-eDP-1
[78.762267] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.283s)
[78.764638] Subtest coverage-7efc: SUCCESS (2.196s)
[79.590682] [009/156] (939s left) gem_mmap_gtt (fault-concurrent)
[79.650970] Subtest fault-concurrent: SKIP (0.000s)
[79.709832] [010/156] (939s left) api_intel_allocator (gem-pool)
[79.757293] Starting subtest: gem-pool
[79.762470] Subtest gem-pool: SUCCESS (0.007s)
[79.893803] [011/156] (939s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[79.969211] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[79.973267] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[80.063134] [012/156] (939s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[80.139865] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[80.143962] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[80.222532] [013/156] (939s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[80.300834] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[80.303190] Starting dynamic subtest: pipe-A-eDP-1
[80.325879] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[80.335524] Starting dynamic subtest: pipe-B-eDP-1
[80.356093] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[80.363833] Starting dynamic subtest: pipe-C-eDP-1
[80.384439] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[80.392102] Starting dynamic subtest: pipe-D-eDP-1
[80.414046] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[80.418341] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.004s)
[82.551158] [014/156] (936s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[82.632053] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[82.634395] Starting dynamic subtest: pipe-A-eDP-1
[82.657773] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[82.668270] Starting dynamic subtest: pipe-B-eDP-1
[82.690225] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[82.698100] Starting dynamic subtest: pipe-C-eDP-1
[82.720663] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[82.728497] Starting dynamic subtest: pipe-D-eDP-1
[82.750270] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[82.754254] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.002s)
[84.917998] [015/156] (934s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[85.008231] Starting subtest: hdr-rgb565-draw-blt
[85.012097] Subtest hdr-rgb565-draw-blt: SKIP (0.000s)
[85.117704] [016/156] (934s left) kms_pm_rpm (legacy-planes-dpms)
[87.353713] Starting subtest: legacy-planes-dpms
[87.355895] Starting dynamic subtest: plane-65
[95.665368] Dynamic subtest plane-65: SUCCESS (8.312s)
[95.678197] Starting dynamic subtest: plane-95
[103.968750] Dynamic subtest plane-95: SUCCESS (8.301s)
[103.979529] Starting dynamic subtest: plane-125
[112.320098] Dynamic subtest plane-125: SUCCESS (8.350s)
[112.329749] Starting dynamic subtest: plane-135
[120.604612] Dynamic subtest plane-135: SUCCESS (8.282s)
[120.607511] Subtest legacy-planes-dpms: SUCCESS (33.250s)
[120.997127] [017/156] (898s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[121.050269] Starting subtest: x-tiled-to-vebox-y-tiled
[121.055158] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[121.231656] [018/156] (898s left) sysfs_heartbeat_interval (invalid)
[121.293977] Starting subtest: invalid
[121.296539] Starting dynamic subtest: vecs0
[121.298901] Dynamic subtest vecs0: SUCCESS (0.000s)
[121.306504] Starting dynamic subtest: ccs0
[121.308913] Dynamic subtest ccs0: SUCCESS (0.000s)
[121.316877] Starting dynamic subtest: vcs0
[121.319159] Dynamic subtest vcs0: SUCCESS (0.000s)
[121.327117] Starting dynamic subtest: bcs0
[121.329380] Dynamic subtest bcs0: SUCCESS (0.000s)
[121.337587] Starting dynamic subtest: rcs0
[121.339959] Dynamic subtest rcs0: SUCCESS (0.000s)
[121.347510] Starting dynamic subtest: vcs1
[121.350232] Dynamic subtest vcs1: SUCCESS (0.000s)
[121.352685] Subtest invalid: SUCCESS (0.003s)
[121.395017] [019/156] (898s left) kms_frontbuffer_tracking (fbc-stridechange)
[121.492126] Starting subtest: fbc-stridechange
[124.752069] Subtest fbc-stridechange: SUCCESS (3.265s)
[125.880789] [020/156] (893s left) gem_ctx_param (basic-default)
[125.924729] Starting subtest: basic-default
[125.927116] Subtest basic-default: SUCCESS (0.000s)
[125.988761] [021/156] (893s left) kms_psr (fbc-pr-primary-mmap-gtt)
[126.055336] Starting subtest: fbc-pr-primary-mmap-gtt
[126.059849] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[126.122661] [022/156] (893s left) i915_pm_freq_mult (media-freq)
[126.179558] Starting subtest: media-freq
[126.181711] Starting dynamic subtest: gt0
[126.185714] Dynamic subtest gt0: SKIP (0.000s)
[126.193384] Starting dynamic subtest: gt1
[126.197467] Dynamic subtest gt1: SKIP (0.000s)
[126.201698] Subtest media-freq: SKIP (0.001s)
[126.263479] [023/156] (893s left) prime_busy (before-wait)
[126.321782] Starting subtest: before-wait
[126.326397] Starting dynamic subtest: rcs0
[126.348972] Dynamic subtest rcs0: SUCCESS (0.024s)
[126.358755] Starting dynamic subtest: bcs0
[126.387464] Dynamic subtest bcs0: SUCCESS (0.038s)
[126.395839] Starting dynamic subtest: vcs0
[126.429628] Dynamic subtest vcs0: SUCCESS (0.042s)
[126.438442] Starting dynamic subtest: vcs1
[126.470600] Dynamic subtest vcs1: SUCCESS (0.041s)
[126.478606] Starting dynamic subtest: vecs0
[126.509827] Dynamic subtest vecs0: SUCCESS (0.038s)
[126.518018] Starting dynamic subtest: ccs0
[126.549733] Dynamic subtest ccs0: SUCCESS (0.039s)
[126.657677] Subtest before-wait: SUCCESS (0.334s)
[126.740433] [024/156] (892s left) gem_exec_gttfill (engines)
[126.813168] Starting subtest: engines
[126.815439] Starting dynamic subtest: rcs0
[149.514052] Dynamic subtest rcs0: SUCCESS (22.702s)
[149.524880] Starting dynamic subtest: bcs0
[172.754929] Dynamic subtest bcs0: SUCCESS (23.239s)
[172.766729] Starting dynamic subtest: vcs0
[196.005976] Dynamic subtest vcs0: SUCCESS (23.248s)
[196.015275] Starting dynamic subtest: vcs1
[219.277308] Dynamic subtest vcs1: SUCCESS (23.270s)
[219.286692] Starting dynamic subtest: vecs0
[242.529649] Dynamic subtest vecs0: SUCCESS (23.250s)
[242.541481] Starting dynamic subtest: ccs0
[265.773353] Dynamic subtest ccs0: SUCCESS (23.242s)
[265.776078] Subtest engines: SUCCESS (138.956s)
[265.957053] [025/156] (753s left) syncobj_timeline (etime-multi-wait-all-submitted)
[266.071562] Starting subtest: etime-multi-wait-all-submitted
[266.177860] Subtest etime-multi-wait-all-submitted: SUCCESS (0.107s)
[266.250570] [026/156] (753s left) gem_ctx_param (get-priority-new-ctx)
[266.310084] Starting subtest: get-priority-new-ctx
[266.312573] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[266.412891] [027/156] (753s left) kms_big_fb (linear-32bpp-rotate-90)
[266.502739] Starting subtest: linear-32bpp-rotate-90
[266.521135] Subtest linear-32bpp-rotate-90: SKIP (0.018s)
[266.643439] [028/156] (753s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[266.687548] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[266.727960] [029/156] (753s left) gem_ctx_shared (disjoint-timelines)
[266.785705] Starting subtest: disjoint-timelines
[266.863278] Subtest disjoint-timelines: SUCCESS (0.080s)
[267.108453] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[267.111905] [030/156] (752s left) kms_psr (fbc-psr2-cursor-plane-move)
[267.187962] Starting subtest: fbc-psr2-cursor-plane-move
[267.190156] Starting dynamic subtest: eDP-1
[267.194189] Dynamic subtest eDP-1: SKIP (0.000s)
[267.198178] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[267.257811] [031/156] (752s left) gem_bad_reloc (negative-reloc-bltcopy)
[267.318720] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[267.410506] [032/156] (752s left) kms_chamelium_hpd (hdmi-hpd)
[267.770741] Subtest hdmi-hpd: SKIP (0.000s)
[268.574823] [033/156] (751s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[268.653322] Starting subtest: pr-cursor-plane-move-continuous-exceed-fully-sf
[268.657919] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[268.718546] [034/156] (751s left) gem_eio (wait-10ms)
[268.940415] Starting subtest: wait-10ms
[269.272427] Subtest wait-10ms: SUCCESS (0.332s)
[269.415887] [035/156] (750s left) kms_content_protection (legacy-hdcp14)
[269.548136] Starting subtest: legacy-hdcp14
[269.551808] Subtest legacy-hdcp14: SKIP (0.000s)
[269.673103] [036/156] (750s left) syncobj_wait (reset-signaled)
[269.727293] Starting subtest: reset-signaled
[269.729049] Subtest reset-signaled: SUCCESS (0.000s)
[269.839293] [037/156] (750s left) gem_ctx_isolation (dirty-switch)
[269.888873] Starting subtest: dirty-switch
[269.890939] Starting dynamic subtest: rcs0
[270.077752] Dynamic subtest rcs0: SUCCESS (0.189s)
[270.087770] Starting dynamic subtest: bcs0
[270.257169] Dynamic subtest bcs0: SUCCESS (0.178s)
[270.266510] Starting dynamic subtest: vcs0
[270.468073] Dynamic subtest vcs0: SUCCESS (0.211s)
[270.478975] Starting dynamic subtest: vcs1
[272.480331] Dynamic subtest vcs1: SUCCESS (2.011s)
[272.489969] Starting dynamic subtest: vecs0
[274.432924] Dynamic subtest vecs0: SUCCESS (1.953s)
[274.445290] Starting dynamic subtest: ccs0
[276.431890] Dynamic subtest ccs0: SUCCESS (1.998s)
[276.434090] Subtest dirty-switch: SUCCESS (6.542s)
[276.667130] [038/156] (743s left) gem_flink_basic (bad-open)
[276.718071] Starting subtest: bad-open
[276.720520] Subtest bad-open: SUCCESS (0.000s)
[276.811329] [039/156] (743s left) gem_caching (writes)
[276.868694] Subtest writes: SKIP (0.000s)
[276.925835] [040/156] (743s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[277.014841] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[277.016743] Starting dynamic subtest: pipe-A
[277.351582] Dynamic subtest pipe-A: SUCCESS (0.339s)
[277.362275] Starting dynamic subtest: pipe-B
[278.797322] Dynamic subtest pipe-B: SUCCESS (1.445s)
[278.806443] Starting dynamic subtest: pipe-C
[280.208381] Dynamic subtest pipe-C: SUCCESS (1.410s)
[280.219772] Starting dynamic subtest: pipe-D
[281.648570] Dynamic subtest pipe-D: SUCCESS (1.439s)
[281.651083] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.636s)
[282.822193] [041/156] (737s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[282.876301] Starting subtest: single-wait-all-for-submit-available-submitted
[282.878769] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[282.986299] [042/156] (737s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[283.069052] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[283.073231] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[283.163699] [043/156] (737s left) kms_pm_rpm (system-suspend-idle)
[285.405621] Starting subtest: system-suspend-idle
[305.368245] Subtest system-suspend-idle: SUCCESS (4.626s)
[305.765875] [044/156] (714s left) kms_atomic_transition (modeset-transition)
[305.851571] Starting subtest: modeset-transition
[305.853496] Starting dynamic subtest: 1x-outputs
[316.753027] Dynamic subtest 1x-outputs: SUCCESS (11.126s)
[316.755529] Subtest modeset-transition: SUCCESS (11.128s)
[317.605008] [045/156] (703s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[317.685732] Starting subtest: pipe-fbc-rte
[317.692422] Starting dynamic subtest: pipe-B-eDP-1
[323.138700] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.491s)
[323.146522] Subtest pipe-fbc-rte: SUCCESS (5.493s)
[325.602772] [046/156] (695s left) gem_spin_batch (resubmit-all)
[325.663066] Starting subtest: resubmit-all
[325.665541] Starting dynamic subtest: rcs0
[325.673070] Dynamic subtest rcs0: SUCCESS (0.010s)
[325.687596] Starting dynamic subtest: bcs0
[325.689717] Dynamic subtest bcs0: SUCCESS (0.002s)
[325.699535] Starting dynamic subtest: vcs0
[325.701702] Dynamic subtest vcs0: SUCCESS (0.003s)
[325.711401] Starting dynamic subtest: vcs1
[325.713571] Dynamic subtest vcs1: SUCCESS (0.002s)
[325.723453] Starting dynamic subtest: vecs0
[325.725691] Dynamic subtest vecs0: SUCCESS (0.002s)
[325.735749] Starting dynamic subtest: ccs0
[325.738079] Dynamic subtest ccs0: SUCCESS (0.002s)
[325.740357] Subtest resubmit-all: SUCCESS (0.023s)
[327.668114] [047/156] (693s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[327.742422] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[327.746592] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[327.829580] [048/156] (692s left) syncobj_basic (illegal-fd-to-handle)
[327.879777] Starting subtest: illegal-fd-to-handle
[327.882007] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[327.974938] [049/156] (692s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[328.057164] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[328.061518] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[328.163052] [050/156] (692s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[328.248099] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[329.371828] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.126s)
[329.492762] [051/156] (691s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[329.549004] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[329.654317] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[329.757316] [052/156] (691s left) api_intel_bb (misplaced-blitter)
[329.800912] Starting subtest: misplaced-blitter
[329.808281] Subtest misplaced-blitter: SUCCESS (0.008s)
[329.932597] [053/156] (691s left) kms_atomic_interruptible (universal-setplane-cursor)
[330.013189] Starting subtest: universal-setplane-cursor
[330.015569] Starting dynamic subtest: pipe-A-eDP-1
[337.409466] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.401s)
[337.411800] Subtest universal-setplane-cursor: SUCCESS (7.402s)
[338.242785] [054/156] (682s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[338.345206] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[338.349099] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[338.451383] [055/156] (682s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[338.540168] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[338.544623] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[338.626411] [056/156] (682s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[338.704066] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[338.708398] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[338.791155] [057/156] (682s left) gem_pxp (create-protected-buffer)
[338.908365] Starting subtest: create-protected-buffer
[338.910913] Subtest create-protected-buffer: SUCCESS (0.000s)
[339.037365] [058/156] (682s left) gem_fence_thrash (bo-write-verify-threaded-x)
[339.072281] Starting subtest: bo-write-verify-threaded-x
[339.089910] Subtest bo-write-verify-threaded-x: SKIP (0.015s)
[339.153983] [059/156] (682s left) gen9_exec_parse (allowed-single)
[339.211416] Subtest allowed-single: SKIP (0.000s)
[339.281515] [060/156] (681s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[339.353933] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[339.357504] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[339.456903] [061/156] (681s left) gem_exec_balancer (bonded-semaphore)
[339.510250] Starting subtest: bonded-semaphore
[339.514068] Subtest bonded-semaphore: SKIP (0.000s)
[339.812975] [062/156] (681s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[339.885502] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[339.890007] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[339.956567] [063/156] (681s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[340.046250] Starting subtest: fbc-rgb565-draw-mmap-wc
[344.049984] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.008s)
[345.272134] [064/156] (676s left) kms_pwrite_crc (basic)
[345.342250] Starting subtest: basic
[346.014221] Subtest basic: SUCCESS (0.672s)
[346.893483] [065/156] (674s left) gem_flink_basic (bad-flink)
[346.956290] Starting subtest: bad-flink
[346.958317] Subtest bad-flink: SUCCESS (0.000s)
[347.063740] [066/156] (674s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[347.142091] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[347.146338] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[347.227177] [067/156] (674s left) gem_ctx_persistence (legacy-engines-persistence)
[347.289217] Starting subtest: legacy-engines-persistence
[347.291631] Starting dynamic subtest: render
[348.103727] Dynamic subtest render: SUCCESS (0.814s)
[348.120992] Starting dynamic subtest: bsd1
[348.882608] Dynamic subtest bsd1: SUCCESS (0.776s)
[348.896369] Starting dynamic subtest: bsd2
[349.652843] Dynamic subtest bsd2: SUCCESS (0.767s)
[349.667833] Starting dynamic subtest: blt
[350.405986] Dynamic subtest blt: SUCCESS (0.751s)
[350.420442] Starting dynamic subtest: vebox
[351.171218] Dynamic subtest vebox: SUCCESS (0.763s)
[351.173898] Subtest legacy-engines-persistence: SUCCESS (3.882s)
[351.256665] [068/156] (670s left) gen7_exec_parse (basic-allowed)
[351.316576] Subtest basic-allowed: SKIP (0.000s)
[351.391835] [069/156] (670s left) gem_exec_schedule (reorder-wide)
[351.577377] Subtest reorder-wide: SKIP (0.000s)
[351.772865] [070/156] (669s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[351.869146] Starting subtest: psrhdr-slowdraw
[351.873206] Subtest psrhdr-slowdraw: SKIP (0.000s)
[351.986946] [071/156] (669s left) perf (non-zero-reason)
[352.114210] Starting subtest: non-zero-reason
[352.116471] Starting dynamic subtest: 0-rcs0
[357.469971] Dynamic subtest 0-rcs0: SUCCESS (5.355s)
[357.484755] Starting dynamic subtest: 1-vcs1
[361.364626] Dynamic subtest 1-vcs1: SUCCESS (3.894s)
[361.367688] Subtest non-zero-reason: SUCCESS (9.250s)
[361.415462] [072/156] (660s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[361.497080] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[361.501492] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[361.590782] [073/156] (660s left) i915_drm_fdinfo (all-busy-check-all)
[361.638683] Starting subtest: all-busy-check-all
[361.642856] Subtest all-busy-check-all: SKIP (0.003s)
[361.717516] [074/156] (660s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[361.815444] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[361.819696] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[361.913366] [075/156] (659s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[361.990772] Starting subtest: compare-crc-sanitycheck-nv12
[361.993025] Starting dynamic subtest: pipe-A-eDP-1
[362.657376] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.664s)
[362.669992] Starting dynamic subtest: pipe-B-eDP-1
[363.975183] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.316s)
[363.986237] Starting dynamic subtest: pipe-C-eDP-1
[365.224486] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.249s)
[365.235328] Starting dynamic subtest: pipe-D-eDP-1
[366.501617] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.276s)
[366.503872] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.511s)
[367.326751] [076/156] (654s left) gem_exec_reloc (basic-write-gtt)
[367.395747] Subtest basic-write-gtt: SKIP (0.000s)
[367.471422] [077/156] (654s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[367.722784] Starting subtest: cursor-rapid-movement-max-size
[367.729636] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[367.848686] [078/156] (654s left) kms_color_pipeline (plane-lut1d)
[367.923961] Starting subtest: plane-lut1d
[368.048493] Starting dynamic subtest: pipe-A-plane-0
[368.192396] Dynamic subtest pipe-A-plane-0: SUCCESS (0.144s)
[368.204045] Starting dynamic subtest: pipe-A-plane-1
[368.336318] Dynamic subtest pipe-A-plane-1: SUCCESS (0.144s)
[368.347138] Starting dynamic subtest: pipe-A-plane-2
[368.480690] Dynamic subtest pipe-A-plane-2: SUCCESS (0.144s)
[369.716309] Starting dynamic subtest: pipe-B-plane-0
[369.861004] Dynamic subtest pipe-B-plane-0: SUCCESS (0.155s)
[369.872628] Starting dynamic subtest: pipe-B-plane-1
[369.995010] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[370.007531] Starting dynamic subtest: pipe-B-plane-2
[370.127713] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[371.354984] Starting dynamic subtest: pipe-C-plane-0
[371.475769] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[371.486226] Starting dynamic subtest: pipe-C-plane-1
[371.631606] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[371.643030] Starting dynamic subtest: pipe-C-plane-2
[371.776012] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[372.988693] Starting dynamic subtest: pipe-D-plane-0
[373.113295] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[373.123826] Starting dynamic subtest: pipe-D-plane-1
[373.247048] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[373.260302] Starting dynamic subtest: pipe-D-plane-2
[373.379886] Dynamic subtest pipe-D-plane-2: SUCCESS (0.132s)
[373.668229] Subtest plane-lut1d: SUCCESS (5.744s)
[374.559672] [079/156] (647s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[374.642895] Starting subtest: yf-tiled-16bpp-rotate-180
[374.647475] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[374.740766] [080/156] (647s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[374.824157] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[375.791667] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (0.968s)
[375.933400] [081/156] (646s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[376.014880] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[376.019131] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[376.115193] [082/156] (646s left) gem_exec_reloc (basic-wc-read)
[376.178906] Subtest basic-wc-read: SKIP (0.000s)
[376.250555] [083/156] (645s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[376.345376] Starting subtest: short-busy-flip-before-cursor-toggle
[376.349024] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[376.491705] [084/156] (645s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[376.565649] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[376.575106] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.008s)
[376.674731] [085/156] (645s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[376.757555] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[376.761150] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[376.853869] [086/156] (645s left) kms_draw_crc (draw-method-render)
[376.924081] Starting subtest: draw-method-render
[376.926093] Starting dynamic subtest: xrgb8888-untiled
[377.177712] Dynamic subtest xrgb8888-untiled: SUCCESS (0.255s)
[377.187939] Starting dynamic subtest: rgb565-untiled
[377.433011] Dynamic subtest rgb565-untiled: SUCCESS (0.255s)
[377.445630] Starting dynamic subtest: xrgb2101010-untiled
[377.644195] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.210s)
[377.654553] Starting dynamic subtest: xbgr16161616f-untiled
[377.888370] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.244s)
[377.898637] Starting dynamic subtest: xrgb8888-xtiled
[378.021604] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.132s)
[378.031701] Starting dynamic subtest: rgb565-xtiled
[378.143845] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[378.157318] Starting dynamic subtest: xrgb2101010-xtiled
[378.266023] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.120s)
[378.278388] Starting dynamic subtest: xbgr16161616f-xtiled
[378.377146] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.110s)
[378.390081] Starting dynamic subtest: xrgb8888-4tiled
[378.510486] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.131s)
[378.521013] Starting dynamic subtest: rgb565-4tiled
[378.621419] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[378.631533] Starting dynamic subtest: xrgb2101010-4tiled
[378.743710] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.121s)
[378.756639] Starting dynamic subtest: xbgr16161616f-4tiled
[378.877090] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[378.879034] Subtest draw-method-render: SUCCESS (1.956s)
[378.993503] [087/156] (643s left) kms_cursor_crc (cursor-sliding-256x85)
[379.217946] Starting subtest: cursor-sliding-256x85
[379.261021] Subtest cursor-sliding-256x85: SKIP (0.041s)
[379.433003] [088/156] (642s left) kms_cursor_crc (cursor-onscreen-256x256)
[379.641204] Starting subtest: cursor-onscreen-256x256
[379.645190] Starting dynamic subtest: pipe-A-eDP-1
[381.755921] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.111s)
[381.788058] Starting dynamic subtest: pipe-D-eDP-1
[384.538053] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.761s)
[384.540297] Subtest cursor-onscreen-256x256: SUCCESS (4.897s)
[385.413862] [089/156] (637s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[385.490703] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[385.494788] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[385.590198] [090/156] (636s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[385.667099] Starting subtest: fbcpsr-slowdraw
[410.797723] Subtest fbcpsr-slowdraw: SUCCESS (25.133s)
[410.924378] [091/156] (611s left) kms_lease (cursor-implicit-plane)
[411.001311] Starting subtest: cursor-implicit-plane
[411.003625] Starting dynamic subtest: pipe-A-eDP-1
[411.467062] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.462s)
[411.477824] Starting dynamic subtest: pipe-B-eDP-1
[412.561252] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.095s)
[412.572995] Starting dynamic subtest: pipe-C-eDP-1
[413.684978] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.120s)
[413.697818] Starting dynamic subtest: pipe-D-eDP-1
[414.828333] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.141s)
[414.830612] Subtest cursor-implicit-plane: SUCCESS (3.824s)
[415.688347] [092/156] (606s left) syncobj_wait (invalid-reset-zero-handles)
[415.736683] Starting subtest: invalid-reset-zero-handles
[415.738945] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[415.822788] [093/156] (606s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[415.903417] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[415.908102] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[415.987346] [094/156] (606s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[416.070683] Starting subtest: yf-tiled-64bpp-rotate-90
[416.075173] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[416.180672] [095/156] (606s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[416.259239] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[416.263457] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[416.371642] [096/156] (606s left) gem_exec_fence (long-history)
[416.814379] Starting subtest: long-history
[424.345610] Subtest long-history: SUCCESS (7.531s)
[425.803609] [097/156] (596s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[425.896842] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[433.220248] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.329s)
[433.344455] [098/156] (589s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[433.418145] Starting subtest: bad-rotation-90-yf-tiled-ccs
[433.420393] Starting dynamic subtest: pipe-A-eDP-1
[433.443046] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[433.455311] Starting dynamic subtest: pipe-B-eDP-1
[433.480123] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[433.490140] Starting dynamic subtest: pipe-C-eDP-1
[433.511611] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[433.521330] Starting dynamic subtest: pipe-D-eDP-1
[433.543022] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[433.547365] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[435.705625] [099/156] (587s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[435.814792] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[454.177037] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (18.363s)
[455.053304] [100/156] (567s left) kms_chamelium_audio (dp-audio-edid)
[455.423548] Subtest dp-audio-edid: SKIP (0.000s)
[456.264017] [101/156] (566s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[456.336918] Starting subtest: addfb25-yf-tiled-legacy
[456.338785] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[456.435582] [102/156] (566s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[456.508099] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[456.512244] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[456.623028] [103/156] (566s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[456.718719] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[456.723151] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[456.823426] [104/156] (566s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[456.901454] Starting subtest: fbc-2p-pri-indfb-multidraw
[456.905673] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[457.025947] [105/156] (566s left) kms_sequence (queue-busy)
[457.094859] Starting subtest: queue-busy
[457.097065] Starting dynamic subtest: pipe-A-eDP-1
[459.022606] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.928s)
[459.034065] Starting dynamic subtest: pipe-B-eDP-1
[461.683152] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.658s)
[461.693996] Starting dynamic subtest: pipe-C-eDP-1
[464.300617] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.617s)
[464.311223] Starting dynamic subtest: pipe-D-eDP-1
[466.922403] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.620s)
[466.924587] Subtest queue-busy: SUCCESS (9.830s)
[467.808105] [106/156] (555s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[467.880897] Starting subtest: plane-upscale-20x20-with-rotation
[467.883132] Starting dynamic subtest: pipe-A
[468.106118] Dynamic subtest pipe-A: SUCCESS (0.226s)
[468.118945] Starting dynamic subtest: pipe-B
[469.384240] Dynamic subtest pipe-B: SUCCESS (1.278s)
[469.395557] Starting dynamic subtest: pipe-C
[470.712852] Dynamic subtest pipe-C: SUCCESS (1.328s)
[470.724531] Starting dynamic subtest: pipe-D
[472.036439] Dynamic subtest pipe-D: SUCCESS (1.323s)
[472.038757] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.158s)
[473.221282] [107/156] (549s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[473.309318] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[473.313778] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[473.433645] [108/156] (549s left) gem_exec_reloc (basic-write-wc)
[473.493633] Subtest basic-write-wc: SKIP (0.000s)
[473.580217] [109/156] (549s left) syncobj_timeline (reset-signaled)
[473.636130] Starting subtest: reset-signaled
[473.638462] Subtest reset-signaled: SUCCESS (0.001s)
[473.744887] [110/156] (549s left) kms_psr (pr-cursor-plane-move)
[473.818870] Starting subtest: pr-cursor-plane-move
[473.823334] Subtest pr-cursor-plane-move: SKIP (0.000s)
[473.891684] [111/156] (549s left) syncobj_wait (invalid-wait-illegal-handle)
[473.940373] Starting subtest: invalid-wait-illegal-handle
[473.942554] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[474.033409] [112/156] (549s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[474.107136] Starting subtest: extended-pageflip-modeset-hang-oldfb
[474.111556] Starting dynamic subtest: pipe-A
[482.003766] Dynamic subtest pipe-A: SUCCESS (7.897s)
[482.018430] Starting dynamic subtest: pipe-D
[490.626062] Dynamic subtest pipe-D: SUCCESS (8.618s)
[490.628410] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.522s)
[491.468396] [113/156] (531s left) kms_psr (psr2-primary-blt)
[491.547180] Starting subtest: psr2-primary-blt
[491.549756] Starting dynamic subtest: eDP-1
[493.279354] Dynamic subtest eDP-1: SUCCESS (1.731s)
[493.281357] Subtest psr2-primary-blt: SUCCESS (1.732s)
[494.159741] [114/156] (529s left) gem_softpin (evict-snoop-interruptible)
[494.220350] Starting subtest: evict-snoop-interruptible
[494.224397] Subtest evict-snoop-interruptible: SKIP (0.000s)
[494.348376] [115/156] (529s left) kms_force_connector_basic (force-load-detect)
[494.398122] Subtest force-load-detect: SKIP (0.000s)
[494.464260] [116/156] (529s left) kms_chamelium_edid (hdmi-mode-timings)
[494.836532] Subtest hdmi-mode-timings: SKIP (0.000s)
[495.664776] [117/156] (527s left) syncobj_timeline (invalid-signal-zero-handles)
[495.715399] Starting subtest: invalid-signal-zero-handles
[495.717682] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[495.804225] [118/156] (527s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[495.883215] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[495.887065] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[496.005934] [119/156] (527s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[496.084649] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[496.088652] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[496.197706] [120/156] (527s left) gem_mmap_gtt (fault-concurrent-x)
[496.245889] Subtest fault-concurrent-X: SKIP (0.000s)
[496.311481] [121/156] (527s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[496.373638] Starting subtest: y-tiled-to-vebox-y-tiled
[496.378168] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[496.572821] [122/156] (527s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[496.654342] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[496.658461] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[496.733745] [123/156] (527s left) vgem_basic (debugfs)
[496.786208] Starting subtest: debugfs
[496.788120] Subtest debugfs: SUCCESS (0.000s)
[496.844651] [124/156] (527s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[496.925086] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[496.927409] Starting dynamic subtest: pipe-A-default-mode
[498.055730] Dynamic subtest pipe-A-default-mode: SKIP (1.128s)
[498.060024] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.129s)
[498.205611] [125/156] (525s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[498.295071] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[498.299076] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[498.394304] [126/156] (525s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[498.476314] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[498.480813] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[498.575390] [127/156] (525s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[498.664518] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[498.668623] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[498.761617] [128/156] (525s left) kms_vblank (wait-forked-hang)
[498.840670] Starting subtest: wait-forked-hang
[498.842873] Starting dynamic subtest: pipe-A-eDP-1
[526.339763] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.503s)
[526.356129] Starting dynamic subtest: pipe-D-eDP-1
[541.401741] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.057s)
[541.404517] Subtest wait-forked-hang: SUCCESS (42.565s)
[542.306657] [129/156] (481s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[542.380288] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[542.382770] Starting dynamic subtest: pipe-A-default-mode
[543.550268] Dynamic subtest pipe-A-default-mode: SKIP (1.167s)
[543.554541] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.168s)
[543.695728] [130/156] (480s left) gem_ctx_exec (basic-invalid-context)
[543.741977] Starting subtest: basic-invalid-context
[543.746909] Subtest basic-invalid-context: SUCCESS (0.004s)
[543.869251] [131/156] (480s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[543.944668] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[543.948823] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[544.056973] [132/156] (480s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[544.130452] Starting subtest: fbc-psr2-cursor-plane-update-sf
[544.132692] Starting dynamic subtest: pipe-A-eDP-1
[544.208848] Dynamic subtest pipe-A-eDP-1: SKIP (0.081s)
[544.220152] Starting dynamic subtest: pipe-B-eDP-1
[545.307631] Dynamic subtest pipe-B-eDP-1: SKIP (1.095s)
[545.312499] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.178s)
[546.581568] [133/156] (477s left) gem_ppgtt (blt-vs-render-ctxn)
[546.647265] Starting subtest: blt-vs-render-ctxN
[579.459652] Subtest blt-vs-render-ctxN: SUCCESS (32.805s)
[579.607478] [134/156] (444s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[579.707708] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[579.784413] [135/156] (444s left) gem_softpin (invalid)
[579.839818] Starting subtest: invalid
[579.848423] Subtest invalid: SUCCESS (0.008s)
[579.971381] [136/156] (444s left) gem_exec_capture (capture)
[580.033510] Starting subtest: capture
[580.036623] Starting dynamic subtest: rcs0-smem
[580.869453] Dynamic subtest rcs0-smem: SUCCESS (0.839s)
[580.881502] Starting dynamic subtest: bcs0-smem
[581.858767] Dynamic subtest bcs0-smem: SUCCESS (0.987s)
[581.871412] Starting dynamic subtest: vcs0-smem
[582.684291] Dynamic subtest vcs0-smem: SUCCESS (0.824s)
[582.696797] Starting dynamic subtest: vcs1-smem
[583.499089] Dynamic subtest vcs1-smem: SUCCESS (0.816s)
[583.512654] Starting dynamic subtest: vecs0-smem
[584.895998] Dynamic subtest vecs0-smem: FAIL (1.333s)
[584.908807] Starting dynamic subtest: ccs0-smem
[586.151260] Dynamic subtest ccs0-smem: SUCCESS (1.317s)
[586.153682] Subtest capture: FAIL (6.121s)
[586.261181] [137/156] (438s left) gem_tiled_blits (basic)
[586.315540] Subtest basic: SKIP (0.000s)
[586.384316] [138/156] (438s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[586.473727] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[593.753681] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.286s)
[593.882382] [139/156] (430s left) gem_mmap_offset (partial-remap)
[593.940219] Starting subtest: partial-remap
[593.942489] Starting dynamic subtest: smem0
[593.950807] Dynamic subtest smem0: SUCCESS (0.003s)
[593.953057] Subtest partial-remap: SUCCESS (0.004s)
[594.061743] [140/156] (430s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[594.139631] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[594.143626] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[594.240165] [141/156] (430s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[594.319824] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[594.324166] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[594.416642] [142/156] (430s left) i915_power (sanity)
[594.466558] Starting subtest: sanity
[598.517348] Subtest sanity: SUCCESS (4.046s)
[599.713303] [143/156] (425s left) gem_exec_schedule (preempt-queue)
[599.788182] Subtest preempt-queue: SKIP (0.000s)
[600.097692] [144/156] (424s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[600.214786] Starting subtest: cursorB-vs-flipA-legacy
[600.219337] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[600.364748] [145/156] (424s left) i915_pm_rps (reset)
[600.426489] Starting subtest: reset
[608.862482] Subtest reset: SUCCESS (8.436s)
[608.975594] [146/156] (415s left) kms_psr (fbc-psr-cursor-plane-onoff)
[609.055113] Starting subtest: fbc-psr-cursor-plane-onoff
[609.057385] Starting dynamic subtest: eDP-1
[609.061572] Dynamic subtest eDP-1: SKIP (0.000s)
[609.065732] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[609.151305] [147/156] (415s left) kms_setmode (clone-exclusive-crtc)
[609.205817] Starting subtest: clone-exclusive-crtc
[609.212113] Subtest clone-exclusive-crtc: SKIP (0.000s)
[609.322239] [148/156] (415s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[609.399609] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[616.079165] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.684s)
[616.211532] [149/156] (408s left) kms_flip (flip-vs-rmfb)
[616.288193] Starting subtest: flip-vs-rmfb
[616.290530] Starting dynamic subtest: A-eDP1
[624.997365] Dynamic subtest A-eDP1: SUCCESS (8.711s)
[625.012371] Starting dynamic subtest: B-eDP1
[633.618761] Dynamic subtest B-eDP1: SUCCESS (8.619s)
[633.631605] Starting dynamic subtest: C-eDP1
[642.281414] Dynamic subtest C-eDP1: SUCCESS (8.661s)
[642.292297] Starting dynamic subtest: D-eDP1
[651.011999] Dynamic subtest D-eDP1: SUCCESS (8.729s)
[651.014426] Subtest flip-vs-rmfb: SUCCESS (34.732s)
[652.170676] [150/156] (372s left) gem_exec_whisper (basic-contexts-all)
[652.240365] Starting subtest: basic-contexts-all
[662.860928] Subtest basic-contexts-all: SUCCESS (10.619s)
[663.050716] [151/156] (362s left) dmabuf_sync_file (export-basic)
[663.099547] Starting subtest: export-basic
[663.101540] Subtest export-basic: SUCCESS (0.001s)
[663.158974] [152/156] (362s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[663.247293] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[663.251597] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[663.345448] [153/156] (361s left) gem_sync (basic-each)
[663.420616] Starting subtest: basic-each
[665.826044] Subtest basic-each: SUCCESS (2.417s)
[666.018389] [154/156] (359s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[666.103079] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[666.105410] Starting dynamic subtest: pipe-A-eDP-1
[666.131328] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[666.145026] Starting dynamic subtest: pipe-B-eDP-1
[666.170210] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[666.181003] Starting dynamic subtest: pipe-C-eDP-1
[666.205152] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[666.215752] Starting dynamic subtest: pipe-D-eDP-1
[666.239771] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[666.243836] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[668.419583] [155/156] (356s left) syncobj_wait (multi-wait-for-submit-submitted)
[668.463578] Starting subtest: multi-wait-for-submit-submitted
[668.567362] Subtest multi-wait-for-submit-submitted: SUCCESS (0.104s)
[668.660311] [156/156] (356s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[668.746803] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[668.751151] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[668.867330] 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.