[90.700790] Initializing watchdogs [90.700987] /dev/watchdog0 [90.741360] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600] [90.756241] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [90.762820] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [90.768390] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear) [91.002793] Starting subtest: fbc-tiling-linear [93.370369] Subtest fbc-tiling-linear: SUCCESS (2.366s) [93.713069] [002/156] (957s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes) [93.833043] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s) [93.925090] [003/156] (957s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [94.127914] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc [94.130352] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [94.276549] [004/156] (957s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [94.471077] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt [94.474073] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [94.613268] [005/156] (956s left) kms_force_connector_basic (prune-stale-modes) [94.875098] Starting subtest: prune-stale-modes [95.028245] Subtest prune-stale-modes: SUCCESS (0.156s) [95.349269] [006/156] (956s left) perf_pmu (most-busy-check-all) [95.475922] Starting subtest: most-busy-check-all [95.479177] Starting dynamic subtest: rcs0 [96.008678] Dynamic subtest rcs0: SUCCESS (0.528s) [96.036054] Starting dynamic subtest: bcs0 [96.541327] Dynamic subtest bcs0: SUCCESS (0.530s) [96.559945] Starting dynamic subtest: vcs0 [97.061617] Dynamic subtest vcs0: SUCCESS (0.525s) [97.083618] Starting dynamic subtest: vecs0 [97.594738] Dynamic subtest vecs0: SUCCESS (0.528s) [97.596745] Subtest most-busy-check-all: SUCCESS (2.118s) [97.735723] [007/156] (954s left) gem_ccs (large-ctrl-surf-copy) [97.860137] Subtest large-ctrl-surf-copy: SKIP (0.000s) [97.958581] [008/156] (953s left) kms_plane_alpha_blend (coverage-7efc) [98.132247] Starting subtest: coverage-7efc [98.336291] Starting dynamic subtest: pipe-A-HDMI-A-1 [99.354161] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.025s) [99.479338] Starting dynamic subtest: pipe-C-HDMI-A-1 [100.566945] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.111s) [100.572212] Subtest coverage-7efc: SUCCESS (2.431s) [100.993180] [009/156] (950s left) gem_mmap_gtt (fault-concurrent) [101.113896] Starting subtest: fault-concurrent [104.382506] Subtest fault-concurrent: SUCCESS (3.269s) [104.531820] [010/156] (947s left) api_intel_allocator (gem-pool) [104.635616] Starting subtest: gem-pool [104.651235] Subtest gem-pool: SUCCESS (0.017s) [104.796397] [011/156] (947s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen) [105.007285] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen [105.011555] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [105.148094] [012/156] (947s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier) [105.336407] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier [105.338269] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s) [105.466401] [013/156] (946s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs) [105.646317] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs [105.649811] Starting dynamic subtest: pipe-A-HDMI-A-1 [106.219740] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.573s) [106.252571] Starting dynamic subtest: pipe-A-HDMI-A-2 [106.689277] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.467s) [106.733098] Starting dynamic subtest: pipe-B-HDMI-A-1 [107.189876] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.497s) [107.209907] Starting dynamic subtest: pipe-B-HDMI-A-2 [107.625356] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.434s) [107.645031] Starting dynamic subtest: pipe-C-HDMI-A-1 [107.657371] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s) [107.700576] Starting dynamic subtest: pipe-C-HDMI-A-2 [107.713697] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.003s) [107.715315] Subtest crc-primary-rotation-180-yf-tiled-ccs: SUCCESS (1.991s) [108.492418] [014/156] (943s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs) [108.675182] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs [108.677250] Starting dynamic subtest: pipe-A-HDMI-A-1 [108.694189] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s) [108.737324] Starting dynamic subtest: pipe-A-HDMI-A-2 [108.767295] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s) [108.802557] Starting dynamic subtest: pipe-B-HDMI-A-1 [108.832345] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s) [108.860466] Starting dynamic subtest: pipe-B-HDMI-A-2 [108.870080] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s) [108.892451] Starting dynamic subtest: pipe-C-HDMI-A-1 [108.900169] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s) [108.921266] Starting dynamic subtest: pipe-C-HDMI-A-2 [108.929964] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s) [108.931757] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.023s) [109.806244] [015/156] (942s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt) [110.002090] Starting subtest: hdr-rgb565-draw-blt [110.005106] Subtest hdr-rgb565-draw-blt: SKIP (0.000s) [110.139111] [016/156] (942s left) kms_pm_rpm (legacy-planes-dpms) [112.039097] Starting subtest: legacy-planes-dpms [112.040063] Starting dynamic subtest: plane-45 [113.716652] Dynamic subtest plane-45: SUCCESS (1.678s) [113.752963] Starting dynamic subtest: plane-54 [115.265839] Dynamic subtest plane-54: SUCCESS (1.546s) [115.301104] Starting dynamic subtest: plane-63 [116.814189] Dynamic subtest plane-63: SUCCESS (1.545s) [116.815397] Subtest legacy-planes-dpms: SUCCESS (4.778s) [117.278647] [017/156] (935s left) gem_render_copy (x-tiled-to-vebox-y-tiled) [117.400918] Starting subtest: x-tiled-to-vebox-y-tiled [117.402572] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s) [117.648363] [018/156] (934s left) sysfs_heartbeat_interval (invalid) [117.770429] Starting subtest: invalid [117.774116] Starting dynamic subtest: vecs0 [117.775169] Dynamic subtest vecs0: SUCCESS (0.001s) [117.801514] Starting dynamic subtest: vcs0 [117.802435] Dynamic subtest vcs0: SUCCESS (0.001s) [117.821857] Starting dynamic subtest: bcs0 [117.822738] Dynamic subtest bcs0: SUCCESS (0.001s) [117.841792] Starting dynamic subtest: rcs0 [117.842725] Dynamic subtest rcs0: SUCCESS (0.002s) [117.843566] Subtest invalid: SUCCESS (0.013s) [117.913395] [019/156] (934s left) kms_frontbuffer_tracking (fbc-stridechange) [118.124801] Starting subtest: fbc-stridechange [120.119105] Subtest fbc-stridechange: SUCCESS (1.990s) [120.399771] [020/156] (932s left) gem_ctx_param (basic-default) [120.504262] Starting subtest: basic-default [120.506191] Subtest basic-default: SUCCESS (0.000s) [120.633359] [021/156] (932s left) kms_psr (fbc-pr-primary-mmap-gtt) [120.830124] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s) [120.934712] [022/156] (932s left) i915_pm_freq_mult (media-freq) [121.040950] Starting subtest: media-freq [121.045450] Starting dynamic subtest: gt0 [121.047418] Dynamic subtest gt0: SKIP (0.000s) [121.051694] Subtest media-freq: SKIP (0.002s) [121.168178] [023/156] (931s left) prime_busy (before-wait) [121.283831] Starting subtest: before-wait [121.301713] Starting dynamic subtest: rcs0 [121.312517] Dynamic subtest rcs0: SUCCESS (0.013s) [121.334742] Starting dynamic subtest: bcs0 [121.335747] Dynamic subtest bcs0: SUCCESS (0.018s) [121.361997] Starting dynamic subtest: vcs0 [121.364797] Dynamic subtest vcs0: SUCCESS (0.017s) [121.385954] Starting dynamic subtest: vecs0 [121.387350] Dynamic subtest vecs0: SUCCESS (0.017s) [121.480099] Subtest before-wait: SUCCESS (0.195s) [121.618600] [024/156] (931s left) gem_exec_gttfill (engines) [121.752176] Starting subtest: engines [121.756268] Starting dynamic subtest: rcs0 [150.265421] Dynamic subtest rcs0: SUCCESS (28.516s) [150.296779] Starting dynamic subtest: bcs0 [180.009499] Dynamic subtest bcs0: SUCCESS (29.739s) [180.035462] Starting dynamic subtest: vcs0 [206.816756] Dynamic subtest vcs0: SUCCESS (26.806s) [206.841816] Starting dynamic subtest: vecs0 [234.015554] Dynamic subtest vecs0: SUCCESS (27.198s) [234.017979] Subtest engines: SUCCESS (112.267s) [234.295464] [025/156] (818s left) syncobj_timeline (etime-multi-wait-all-submitted) [234.519722] Starting subtest: etime-multi-wait-all-submitted [234.633017] Subtest etime-multi-wait-all-submitted: SUCCESS (0.111s) [234.784144] [026/156] (818s left) gem_ctx_param (get-priority-new-ctx) [234.886229] Starting subtest: get-priority-new-ctx [234.887678] Subtest get-priority-new-ctx: SUCCESS (0.000s) [235.037528] [027/156] (818s left) kms_big_fb (linear-32bpp-rotate-90) [235.255257] Starting subtest: linear-32bpp-rotate-90 [235.617524] Subtest linear-32bpp-rotate-90: SKIP (0.362s) [235.945075] [028/156] (817s left) gem_lmem_swapping (heavy-verify-multi-ccs) [236.069536] Subtest heavy-verify-multi-ccs: SKIP (0.000s) [236.170390] [029/156] (817s left) gem_ctx_shared (disjoint-timelines) [236.299122] Starting subtest: disjoint-timelines [236.376284] Subtest disjoint-timelines: SUCCESS (0.079s) [236.656429] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true [236.660487] [030/156] (817s left) kms_psr (fbc-psr2-cursor-plane-move) [236.836814] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s) [236.946820] [031/156] (816s left) gem_bad_reloc (negative-reloc-bltcopy) [237.070129] Starting subtest: negative-reloc-bltcopy [240.297489] Subtest negative-reloc-bltcopy: SUCCESS (3.225s) [240.629990] [032/156] (813s left) kms_chamelium_hpd (hdmi-hpd) [241.244504] Subtest hdmi-hpd: SKIP (0.000s) [241.590185] [033/156] (812s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf) [241.775826] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s) [241.879707] [034/156] (812s left) gem_eio (wait-10ms) [242.032968] Starting subtest: wait-10ms [242.162089] Subtest wait-10ms: SUCCESS (0.129s) [242.393800] [035/156] (811s left) kms_content_protection (legacy-hdcp14) [242.616142] Starting subtest: legacy-hdcp14 [242.641525] Subtest legacy-hdcp14: SKIP (0.019s) [242.790803] [036/156] (811s left) syncobj_wait (reset-signaled) [242.904514] Starting subtest: reset-signaled [242.908241] Subtest reset-signaled: SUCCESS (0.001s) [243.042967] [037/156] (811s left) gem_ctx_isolation (dirty-switch) [243.170342] Starting subtest: dirty-switch [243.173276] Starting dynamic subtest: rcs0 [243.767505] Dynamic subtest rcs0: SUCCESS (0.598s) [243.798384] Starting dynamic subtest: bcs0 [244.721481] Dynamic subtest bcs0: SUCCESS (0.950s) [244.760205] Starting dynamic subtest: vcs0 [245.303324] Dynamic subtest vcs0: SUCCESS (0.579s) [245.343783] Starting dynamic subtest: vecs0 [245.898965] Dynamic subtest vecs0: SUCCESS (0.591s) [245.899985] Subtest dirty-switch: SUCCESS (2.728s) [246.141564] [038/156] (808s left) gem_flink_basic (bad-open) [246.242531] Starting subtest: bad-open [246.244283] Subtest bad-open: SUCCESS (0.000s) [246.392467] [039/156] (808s left) gem_caching (writes) [246.506279] Starting subtest: writes [260.665705] Subtest writes: SUCCESS (14.161s) [260.838133] [040/156] (793s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers) [261.035072] Starting subtest: plane-scaler-unity-scaling-with-modifiers [261.038035] Starting dynamic subtest: pipe-A [261.898164] Dynamic subtest pipe-A: SUCCESS (0.868s) [261.937126] Starting dynamic subtest: pipe-B [262.757891] Dynamic subtest pipe-B: SUCCESS (0.858s) [262.783859] Starting dynamic subtest: pipe-C [263.691274] Dynamic subtest pipe-C: SUCCESS (0.930s) [263.692867] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (2.662s) [264.297400] [041/156] (790s left) syncobj_timeline (single-wait-all-for-submit-available-submitted) [264.406027] Starting subtest: single-wait-all-for-submit-available-submitted [264.407775] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s) [264.583175] [042/156] (790s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render) [264.792258] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render [264.796457] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [264.980308] [043/156] (789s left) kms_pm_rpm (system-suspend-idle) [266.933303] Starting subtest: system-suspend-idle [284.396174] Subtest system-suspend-idle: SUCCESS (2.922s) [292.896946] [044/156] (770s left) kms_atomic_transition (modeset-transition) [293.107232] Starting subtest: modeset-transition [293.110417] Starting dynamic subtest: 1x-outputs [296.748861] Dynamic subtest 1x-outputs: SUCCESS (3.643s) [296.781449] Starting dynamic subtest: 2x-outputs [304.449597] Dynamic subtest 2x-outputs: SUCCESS (7.694s) [304.451038] Subtest modeset-transition: SUCCESS (11.342s) [304.893547] [045/156] (758s left) kms_frontbuffer_tracking (pipe-fbc-rte) [305.100709] Starting subtest: pipe-fbc-rte [305.110254] Subtest pipe-fbc-rte: SKIP (0.001s) [305.264732] [046/156] (757s left) gem_spin_batch (resubmit-all) [305.391185] Starting subtest: resubmit-all [305.392313] Starting dynamic subtest: rcs0 [305.403822] Dynamic subtest rcs0: SUCCESS (0.011s) [305.447503] Starting dynamic subtest: bcs0 [305.448834] Dynamic subtest bcs0: SUCCESS (0.006s) [305.473176] Starting dynamic subtest: vcs0 [305.474398] Dynamic subtest vcs0: SUCCESS (0.005s) [305.498543] Starting dynamic subtest: vecs0 [305.499555] Dynamic subtest vecs0: SUCCESS (0.005s) [305.500624] Subtest resubmit-all: SUCCESS (0.038s) [305.696985] [047/156] (757s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff) [305.918561] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff [305.921140] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [306.064988] [048/156] (757s left) syncobj_basic (illegal-fd-to-handle) [306.172468] Starting subtest: illegal-fd-to-handle [306.173684] Subtest illegal-fd-to-handle: SUCCESS (0.000s) [306.315856] [049/156] (757s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite) [306.519349] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite [309.182319] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SUCCESS (2.663s) [309.429213] [050/156] (754s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [309.654425] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [311.448953] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.798s) [311.796568] [051/156] (751s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted) [311.903815] Starting subtest: etime-single-wait-all-for-submit-unsubmitted [312.009515] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.105s) [312.166168] [052/156] (751s left) api_intel_bb (misplaced-blitter) [312.271473] Starting subtest: misplaced-blitter [312.283266] Subtest misplaced-blitter: SUCCESS (0.015s) [312.448487] [053/156] (751s left) kms_atomic_interruptible (universal-setplane-cursor) [312.648158] Starting subtest: universal-setplane-cursor [312.649998] Starting dynamic subtest: pipe-A-HDMI-A-1 [319.567959] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.916s) [319.569030] Subtest universal-setplane-cursor: SUCCESS (6.922s) [320.049865] [054/156] (743s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip) [320.335164] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip [320.340001] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [320.491848] [055/156] (743s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [320.704720] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc [320.708729] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [320.923149] [056/156] (743s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling) [321.105944] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling [321.116450] Starting dynamic subtest: pipe-A-valid-mode [322.157051] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.046s) [322.158584] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.052s) [322.620176] [057/156] (741s left) gem_pxp (create-protected-buffer) [325.270876] Starting subtest: create-protected-buffer [325.272952] Subtest create-protected-buffer: SKIP (0.000s) [325.451259] [058/156] (739s left) gem_fence_thrash (bo-write-verify-threaded-x) [325.550974] Starting subtest: bo-write-verify-threaded-x [330.303184] Subtest bo-write-verify-threaded-x: SUCCESS (4.754s) [330.457087] [059/156] (734s left) gen9_exec_parse (allowed-single) [330.582386] Starting subtest: allowed-single [330.592571] Subtest allowed-single: SUCCESS (0.011s) [330.876842] [060/156] (733s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu) [331.094930] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu [331.098327] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [331.258219] [061/156] (733s left) gem_exec_balancer (bonded-semaphore) [331.395366] Starting subtest: bonded-semaphore [335.691713] Subtest bonded-semaphore: SUCCESS (4.297s) [335.991576] [062/156] (728s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping) [336.181903] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping [336.187939] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s) [336.324713] [063/156] (728s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc) [336.543156] Starting subtest: fbc-rgb565-draw-mmap-wc [339.309613] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (2.767s) [339.609874] [064/156] (725s left) kms_pwrite_crc (basic) [339.805388] Starting subtest: basic [340.762929] Subtest basic: SUCCESS (0.962s) [341.152185] [065/156] (723s left) gem_flink_basic (bad-flink) [341.260104] Starting subtest: bad-flink [341.261601] Subtest bad-flink: SUCCESS (0.000s) [341.415195] [066/156] (723s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move) [341.606755] Starting subtest: hdr-2p-primscrn-spr-indfb-move [341.610341] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [341.756202] [067/156] (723s left) gem_ctx_persistence (legacy-engines-persistence) [341.877036] Starting subtest: legacy-engines-persistence [341.878182] Starting dynamic subtest: render [342.661424] Dynamic subtest render: SUCCESS (0.783s) [342.695204] Starting dynamic subtest: bsd [343.425595] Dynamic subtest bsd: SUCCESS (0.756s) [343.458134] Starting dynamic subtest: blt [344.195601] Dynamic subtest blt: SUCCESS (0.763s) [344.226306] Starting dynamic subtest: vebox [344.959426] Dynamic subtest vebox: SUCCESS (0.758s) [344.963982] Subtest legacy-engines-persistence: SUCCESS (3.086s) [345.125031] [068/156] (720s left) gen7_exec_parse (basic-allowed) [345.240450] Subtest basic-allowed: SKIP (0.000s) [345.363104] [069/156] (720s left) gem_exec_schedule (reorder-wide) [345.621945] Starting subtest: reorder-wide [345.624843] Starting dynamic subtest: rcs0 [345.798290] Dynamic subtest rcs0: SUCCESS (0.168s) [345.831873] Starting dynamic subtest: bcs0 [346.136862] Dynamic subtest bcs0: SUCCESS (0.333s) [346.189715] Starting dynamic subtest: vcs0 [346.307072] Dynamic subtest vcs0: SUCCESS (0.166s) [346.351829] Starting dynamic subtest: vecs0 [346.474132] Dynamic subtest vecs0: SUCCESS (0.164s) [346.477718] Subtest reorder-wide: SUCCESS (0.850s) [346.741349] [070/156] (718s left) kms_frontbuffer_tracking (psrhdr-slowdraw) [346.945119] Starting subtest: psrhdr-slowdraw [346.948926] Subtest psrhdr-slowdraw: SKIP (0.000s) [347.108015] [071/156] (718s left) perf (non-zero-reason) [347.380175] Starting subtest: non-zero-reason [347.382171] Starting dynamic subtest: 0-rcs0 [398.126479] Dynamic subtest 0-rcs0: SUCCESS (50.747s) [398.134083] Subtest non-zero-reason: SUCCESS (50.750s) [398.241560] [072/156] (667s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite) [398.437215] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite [398.441688] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [398.591228] [073/156] (667s left) i915_drm_fdinfo (all-busy-check-all) [398.709956] Starting subtest: all-busy-check-all [399.234378] Subtest all-busy-check-all: SUCCESS (0.526s) [399.388789] [074/156] (666s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt) [399.601966] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt [399.605468] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [399.758148] [075/156] (666s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [399.959794] Starting subtest: compare-crc-sanitycheck-nv12 [399.962142] Starting dynamic subtest: pipe-A-HDMI-A-1 [400.946514] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.983s) [400.983204] Starting dynamic subtest: pipe-B-HDMI-A-2 [401.633615] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.678s) [401.670129] Starting dynamic subtest: pipe-C-HDMI-A-1 [402.331155] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.688s) [402.333335] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.375s) [402.727234] [076/156] (663s left) gem_exec_reloc (basic-write-gtt) [402.854279] Starting subtest: basic-write-gtt [402.871353] Subtest basic-write-gtt: SUCCESS (0.018s) [403.097992] [077/156] (663s left) kms_cursor_crc (cursor-rapid-movement-max-size) [403.319112] Starting subtest: cursor-rapid-movement-max-size [403.326009] Subtest cursor-rapid-movement-max-size: SKIP (0.001s) [403.465291] [078/156] (662s left) kms_color_pipeline (plane-lut1d) [403.656932] Starting subtest: plane-lut1d [405.402351] Subtest plane-lut1d: SKIP (1.742s) [405.803845] [079/156] (660s left) kms_big_fb (yf-tiled-16bpp-rotate-180) [406.026810] Starting subtest: yf-tiled-16bpp-rotate-180 [410.018176] Subtest yf-tiled-16bpp-rotate-180: SUCCESS (3.993s) [410.420363] [080/156] (656s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0) [410.631039] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0 [410.635481] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s) [410.786808] [081/156] (655s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt) [411.020090] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt [411.022011] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.001s) [411.214521] [082/156] (655s left) gem_exec_reloc (basic-wc-read) [411.341351] Starting subtest: basic-wc-read [411.356107] Subtest basic-wc-read: SUCCESS (0.016s) [411.605231] [083/156] (655s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle) [411.788552] Starting subtest: short-busy-flip-before-cursor-toggle [411.793286] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s) [411.935217] [084/156] (655s left) kms_flip (2x-plain-flip-fb-recreate-interruptible) [412.127697] Starting subtest: 2x-plain-flip-fb-recreate-interruptible [412.135274] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2 [423.281403] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.146s) [423.317692] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2 [434.314067] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (11.026s) [434.348629] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2 [445.428210] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (11.108s) [445.438139] Subtest 2x-plain-flip-fb-recreate-interruptible: SUCCESS (33.306s) [446.041359] [085/156] (621s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [446.260138] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu [446.264287] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [446.420055] [086/156] (620s left) kms_draw_crc (draw-method-render) [446.608002] Starting subtest: draw-method-render [446.609965] Starting dynamic subtest: xrgb8888-untiled [447.172876] Dynamic subtest xrgb8888-untiled: SUCCESS (0.564s) [447.201798] Starting dynamic subtest: rgb565-untiled [447.487129] Dynamic subtest rgb565-untiled: SUCCESS (0.312s) [447.519989] Starting dynamic subtest: xrgb2101010-untiled [447.805022] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.314s) [447.838267] Starting dynamic subtest: xbgr16161616f-untiled [448.137368] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.328s) [448.180063] Starting dynamic subtest: xrgb8888-xtiled [448.337711] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.195s) [448.369111] Starting dynamic subtest: rgb565-xtiled [448.538653] Dynamic subtest rgb565-xtiled: SUCCESS (0.196s) [448.576114] Starting dynamic subtest: xrgb2101010-xtiled [448.720833] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.178s) [448.753412] Starting dynamic subtest: xbgr16161616f-xtiled [448.921105] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.195s) [448.948736] Starting dynamic subtest: xrgb8888-ytiled [449.120333] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.196s) [449.153242] Starting dynamic subtest: rgb565-ytiled [449.320096] Dynamic subtest rgb565-ytiled: SUCCESS (0.195s) [449.352104] Starting dynamic subtest: xrgb2101010-ytiled [449.504084] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.178s) [449.541298] Starting dynamic subtest: xbgr16161616f-ytiled [449.704084] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.196s) [449.705589] Subtest draw-method-render: SUCCESS (3.100s) [449.982134] [087/156] (617s left) kms_cursor_crc (cursor-sliding-256x85) [450.200016] Starting subtest: cursor-sliding-256x85 [450.269533] Starting dynamic subtest: pipe-A-HDMI-A-1 [454.304846] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.035s) [454.434573] Starting dynamic subtest: pipe-C-HDMI-A-1 [458.123957] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.744s) [458.125035] Subtest cursor-sliding-256x85: SUCCESS (7.924s) [458.504338] [088/156] (608s left) kms_cursor_crc (cursor-onscreen-256x256) [458.703666] Starting subtest: cursor-onscreen-256x256 [458.745902] Starting dynamic subtest: pipe-A-HDMI-A-1 [461.446083] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.695s) [461.570982] Starting dynamic subtest: pipe-C-HDMI-A-1 [463.915696] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.407s) [463.919148] Subtest cursor-onscreen-256x256: SUCCESS (5.211s) [464.304362] [089/156] (603s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render) [464.529171] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render [464.533183] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s) [464.692059] [090/156] (602s left) kms_frontbuffer_tracking (fbcpsr-slowdraw) [464.909414] Starting subtest: fbcpsr-slowdraw [464.913078] Subtest fbcpsr-slowdraw: SKIP (0.000s) [465.056608] [091/156] (602s left) kms_lease (cursor-implicit-plane) [465.252096] Starting subtest: cursor-implicit-plane [465.253304] Starting dynamic subtest: pipe-A-HDMI-A-1 [465.947000] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.688s) [466.014997] Starting dynamic subtest: pipe-A-HDMI-A-2 [466.378851] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.431s) [466.454049] Starting dynamic subtest: pipe-B-HDMI-A-1 [466.811762] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.425s) [466.888146] Starting dynamic subtest: pipe-B-HDMI-A-2 [467.241964] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.419s) [467.276669] Starting dynamic subtest: pipe-C-HDMI-A-1 [467.711022] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.461s) [467.772072] Starting dynamic subtest: pipe-C-HDMI-A-2 [468.148743] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.438s) [468.157639] Subtest cursor-implicit-plane: SUCCESS (2.903s) [468.542949] [092/156] (599s left) syncobj_wait (invalid-reset-zero-handles) [468.651151] Starting subtest: invalid-reset-zero-handles [468.653208] Subtest invalid-reset-zero-handles: SUCCESS (0.000s) [468.837995] [093/156] (599s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render) [469.048222] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render [471.123575] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SUCCESS (2.079s) [471.339361] [094/156] (596s left) kms_big_fb (yf-tiled-64bpp-rotate-90) [471.558092] Starting subtest: yf-tiled-64bpp-rotate-90 [471.562424] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.001s) [471.705929] [095/156] (596s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc) [471.911219] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc [471.916160] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.001s) [472.096376] [096/156] (596s left) gem_exec_fence (long-history) [472.650830] Starting subtest: long-history [478.343648] Subtest long-history: SUCCESS (5.693s) [479.056498] [097/156] (589s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt) [479.261253] Starting subtest: psr-1p-primscrn-indfb-msflip-blt [479.265213] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [479.406454] [098/156] (589s left) kms_ccs (bad-rotation-90-yf-tiled-ccs) [479.603014] Starting subtest: bad-rotation-90-yf-tiled-ccs [479.608046] Starting dynamic subtest: pipe-A-HDMI-A-1 [479.747452] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.130s) [479.809566] Starting dynamic subtest: pipe-A-HDMI-A-2 [479.840849] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.049s) [479.901167] Starting dynamic subtest: pipe-B-HDMI-A-1 [479.924132] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.051s) [479.986206] Starting dynamic subtest: pipe-B-HDMI-A-2 [480.010966] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.046s) [480.038802] Starting dynamic subtest: pipe-C-HDMI-A-1 [480.046733] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s) [480.073684] Starting dynamic subtest: pipe-C-HDMI-A-2 [480.081191] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s) [480.082053] Subtest bad-rotation-90-yf-tiled-ccs: SUCCESS (0.306s) [481.016832] [099/156] (587s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic) [481.212929] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic [488.042142] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (6.825s) [488.494112] [100/156] (580s left) kms_chamelium_audio (dp-audio-edid) [489.144705] Subtest dp-audio-edid: SKIP (0.000s) [489.509812] [101/156] (579s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [489.725290] Starting subtest: addfb25-yf-tiled-legacy [489.728794] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [489.877625] [102/156] (578s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt) [490.106072] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt [490.110408] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [490.261615] [103/156] (578s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff) [490.483224] Starting subtest: psr-2p-primscrn-cur-indfb-onoff [490.487250] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [490.646424] [104/156] (578s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw) [490.868339] Starting subtest: fbc-2p-pri-indfb-multidraw [507.590748] Subtest fbc-2p-pri-indfb-multidraw: SUCCESS (16.722s) [507.856260] [105/156] (561s left) kms_sequence (queue-busy) [508.059697] Starting subtest: queue-busy [508.061963] Starting dynamic subtest: pipe-A-HDMI-A-1 [510.954994] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.896s) [511.019971] Starting dynamic subtest: pipe-A-HDMI-A-2 [513.591993] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.631s) [513.633553] Starting dynamic subtest: pipe-B-HDMI-A-1 [516.285790] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.687s) [516.347550] Starting dynamic subtest: pipe-B-HDMI-A-2 [518.935168] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.642s) [518.997009] Starting dynamic subtest: pipe-C-HDMI-A-1 [521.564132] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.621s) [521.615092] Starting dynamic subtest: pipe-C-HDMI-A-2 [524.207155] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.636s) [524.211141] Subtest queue-busy: SUCCESS (16.157s) [524.559010] [106/156] (544s left) kms_plane_scaling (plane-upscale-20x20-with-rotation) [524.746101] Starting subtest: plane-upscale-20x20-with-rotation [524.748093] Starting dynamic subtest: pipe-A [529.495667] Dynamic subtest pipe-A: SKIP (4.741s) [529.557639] Starting dynamic subtest: pipe-B [533.698133] Dynamic subtest pipe-B: SKIP (4.198s) [533.758923] Starting dynamic subtest: pipe-C [539.926815] Dynamic subtest pipe-C: SKIP (6.228s) [539.932131] Subtest plane-upscale-20x20-with-rotation: SKIP (15.176s) [540.572213] [107/156] (528s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite) [540.851906] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite [540.855021] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [541.005391] [108/156] (528s left) gem_exec_reloc (basic-write-wc) [541.130797] Starting subtest: basic-write-wc [541.149790] Subtest basic-write-wc: SUCCESS (0.018s) [541.405320] [109/156] (528s left) syncobj_timeline (reset-signaled) [541.525179] Starting subtest: reset-signaled [541.526821] Subtest reset-signaled: SUCCESS (0.003s) [541.675389] [110/156] (527s left) kms_psr (pr-cursor-plane-move) [541.879429] Subtest pr-cursor-plane-move: SKIP (0.000s) [541.999185] [111/156] (527s left) syncobj_wait (invalid-wait-illegal-handle) [542.113950] Starting subtest: invalid-wait-illegal-handle [542.117154] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s) [542.273318] [112/156] (527s left) kms_busy (extended-pageflip-modeset-hang-oldfb) [542.490858] Starting subtest: extended-pageflip-modeset-hang-oldfb [542.498970] Starting dynamic subtest: pipe-A [546.363159] Dynamic subtest pipe-A: SUCCESS (3.860s) [546.437677] Starting dynamic subtest: pipe-C [550.376702] Dynamic subtest pipe-C: SUCCESS (3.997s) [550.378928] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (7.888s) [550.794466] [113/156] (519s left) kms_psr (psr2-primary-blt) [551.000994] Subtest psr2-primary-blt: SKIP (0.000s) [551.116877] [114/156] (518s left) gem_softpin (evict-snoop-interruptible) [551.241492] Starting subtest: evict-snoop-interruptible [551.247767] Subtest evict-snoop-interruptible: SKIP (0.000s) [551.396996] [115/156] (518s left) kms_force_connector_basic (force-load-detect) [551.690738] Starting subtest: force-load-detect [551.696951] Subtest force-load-detect: SKIP (0.000s) [552.092356] [116/156] (518s left) kms_chamelium_edid (hdmi-mode-timings) [552.728943] Subtest hdmi-mode-timings: SKIP (0.000s) [553.110039] [117/156] (517s left) syncobj_timeline (invalid-signal-zero-handles) [553.221079] Starting subtest: invalid-signal-zero-handles [553.223276] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [553.375744] [118/156] (517s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt) [553.598056] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt [553.601993] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [553.758161] [119/156] (516s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [553.976184] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt [553.980636] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [554.124752] [120/156] (516s left) gem_mmap_gtt (fault-concurrent-x) [554.238094] Starting subtest: fault-concurrent-X [557.446224] Subtest fault-concurrent-X: SUCCESS (3.209s) [557.608289] [121/156] (513s left) gem_render_copy (y-tiled-to-vebox-y-tiled) [557.726828] Starting subtest: y-tiled-to-vebox-y-tiled [557.728581] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s) [557.992367] [122/156] (512s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt) [558.209500] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt [558.213173] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s) [558.378511] [123/156] (512s left) vgem_basic (debugfs) [558.504195] Starting subtest: debugfs [558.505133] Subtest debugfs: SUCCESS (0.001s) [558.672295] [124/156] (512s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling) [558.872198] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling [558.876348] Starting dynamic subtest: pipe-A-valid-mode [559.870397] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.998s) [559.871650] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.006s) [560.215228] [125/156] (510s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite) [560.434269] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite [564.017982] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (3.586s) [564.238675] [126/156] (507s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu) [564.459204] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu [564.464142] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.001s) [564.618760] [127/156] (506s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt) [564.836904] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt [564.841788] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [564.988206] [128/156] (506s left) kms_vblank (wait-forked-hang) [565.167985] Starting subtest: wait-forked-hang [565.176923] Starting dynamic subtest: pipe-A-HDMI-A-1 [572.353412] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (7.180s) [572.394055] Starting dynamic subtest: pipe-A-HDMI-A-2 [579.408272] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (7.041s) [579.480547] Starting dynamic subtest: pipe-C-HDMI-A-1 [586.357833] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (6.941s) [586.414989] Starting dynamic subtest: pipe-C-HDMI-A-2 [593.399985] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (7.034s) [593.401689] Subtest wait-forked-hang: SUCCESS (28.233s) [593.771151] [129/156] (477s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling) [593.956869] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling [593.970438] Starting dynamic subtest: pipe-A-valid-mode [594.926928] Dynamic subtest pipe-A-valid-mode: SKIP (0.959s) [594.928956] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (0.965s) [595.488279] [130/156] (476s left) gem_ctx_exec (basic-invalid-context) [595.597329] Starting subtest: basic-invalid-context [595.605613] Subtest basic-invalid-context: SUCCESS (0.008s) [595.788244] [131/156] (476s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt) [595.988994] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt [595.992977] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [596.144668] [132/156] (475s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf) [596.328202] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s) [596.452294] [133/156] (475s left) gem_ppgtt (blt-vs-render-ctxn) [596.574018] Starting subtest: blt-vs-render-ctxN [629.632372] Subtest blt-vs-render-ctxN: SUCCESS (33.053s) [629.803607] [134/156] (442s left) gem_exec_reloc (basic-gtt-cpu-noreloc) [630.034913] Starting subtest: basic-gtt-cpu-noreloc [630.044065] Subtest basic-gtt-cpu-noreloc: SUCCESS (0.008s) [630.278052] [135/156] (441s left) gem_softpin (invalid) [630.394473] Starting subtest: invalid [630.405435] Subtest invalid: SUCCESS (0.013s) [630.578325] [136/156] (441s left) gem_exec_capture (capture) [630.701237] Starting subtest: capture [630.704461] Starting dynamic subtest: rcs0-smem [631.994998] Dynamic subtest rcs0-smem: SUCCESS (1.292s) [632.032951] Starting dynamic subtest: bcs0-smem [633.283010] Dynamic subtest bcs0-smem: SUCCESS (1.281s) [633.325140] Starting dynamic subtest: vcs0-smem [634.573699] Dynamic subtest vcs0-smem: SUCCESS (1.286s) [634.614167] Starting dynamic subtest: vecs0-smem [635.862334] Dynamic subtest vecs0-smem: SUCCESS (1.283s) [635.864958] Subtest capture: SUCCESS (5.154s) [636.058351] [137/156] (436s left) gem_tiled_blits (basic) [636.289981] Starting subtest: basic [636.320660] Subtest basic: SUCCESS (0.037s) [636.509198] [138/156] (436s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt) [636.826980] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt [636.831678] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [636.978978] [139/156] (435s left) gem_mmap_offset (partial-remap) [637.087014] Starting subtest: partial-remap [637.088270] Starting dynamic subtest: smem0 [637.105814] Dynamic subtest smem0: SUCCESS (0.010s) [637.108981] Subtest partial-remap: SUCCESS (0.014s) [637.275026] [140/156] (435s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt) [637.492144] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt [637.497056] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s) [637.746193] [141/156] (435s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt) [637.944977] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt [637.950799] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s) [638.097175] [142/156] (434s left) i915_power (sanity) [638.204322] Starting subtest: sanity [642.230795] Subtest sanity: SUCCESS (4.025s) [642.394863] [143/156] (430s left) gem_exec_schedule (preempt-queue) [642.545508] Starting subtest: preempt-queue [642.547496] Starting dynamic subtest: rcs0 [642.834700] Dynamic subtest rcs0: SUCCESS (0.285s) [642.872313] Starting dynamic subtest: bcs0 [643.121951] Dynamic subtest bcs0: SUCCESS (0.284s) [643.159083] Starting dynamic subtest: vcs0 [643.405642] Dynamic subtest vcs0: SUCCESS (0.284s) [643.445073] Starting dynamic subtest: vecs0 [643.692956] Dynamic subtest vecs0: SUCCESS (0.281s) [643.694094] Subtest preempt-queue: SUCCESS (1.149s) [644.114078] [144/156] (429s left) kms_cursor_legacy (cursorb-vs-flipa-legacy) [644.312958] Starting subtest: cursorB-vs-flipA-legacy [655.808580] Subtest cursorB-vs-flipA-legacy: SUCCESS (11.490s) [656.212128] [145/156] (417s left) i915_pm_rps (reset) [656.351016] Starting subtest: reset [660.938608] Subtest reset: SUCCESS (4.591s) [661.110572] [146/156] (412s left) kms_psr (fbc-psr-cursor-plane-onoff) [661.305870] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s) [661.417599] [147/156] (412s left) kms_setmode (clone-exclusive-crtc) [661.530904] Starting subtest: clone-exclusive-crtc [662.028668] Starting dynamic subtest: pipe-A-HDMI-A-1-pipe-B-HDMI-A-2 [662.354318] Dynamic subtest pipe-A-HDMI-A-1-pipe-B-HDMI-A-2: SUCCESS (0.321s) [662.888062] Starting dynamic subtest: pipe-B-HDMI-A-1-pipe-A-HDMI-A-2 [663.208198] Dynamic subtest pipe-B-HDMI-A-1-pipe-A-HDMI-A-2: SUCCESS (0.341s) [663.666060] Subtest clone-exclusive-crtc: SUCCESS (2.136s) [664.026512] [148/156] (409s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu) [664.246079] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu [664.253029] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [664.421270] [149/156] (409s left) kms_flip (flip-vs-rmfb) [664.610980] Starting subtest: flip-vs-rmfb [664.623145] Starting dynamic subtest: A-HDMI-A1 [670.275388] Dynamic subtest A-HDMI-A1: SUCCESS (5.656s) [670.307391] Starting dynamic subtest: B-HDMI-A1 [675.689795] Dynamic subtest B-HDMI-A1: SUCCESS (5.407s) [675.723342] Starting dynamic subtest: C-HDMI-A1 [681.135773] Dynamic subtest C-HDMI-A1: SUCCESS (5.439s) [681.168802] Starting dynamic subtest: A-HDMI-A2 [686.531797] Dynamic subtest A-HDMI-A2: SUCCESS (5.390s) [686.565362] Starting dynamic subtest: B-HDMI-A2 [691.956306] Dynamic subtest B-HDMI-A2: SUCCESS (5.418s) [691.991330] Starting dynamic subtest: C-HDMI-A2 [697.362920] Dynamic subtest C-HDMI-A2: SUCCESS (5.399s) [697.366263] Subtest flip-vs-rmfb: SUCCESS (32.753s) [697.912273] [150/156] (375s left) gem_exec_whisper (basic-contexts-all) [698.054611] Starting subtest: basic-contexts-all [708.588965] Subtest basic-contexts-all: SUCCESS (10.528s) [709.131830] [151/156] (364s left) dmabuf_sync_file (export-basic) [709.397041] Starting subtest: export-basic [709.403327] Subtest export-basic: SUCCESS (0.014s) [709.638810] [152/156] (364s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu) [709.960266] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu [709.972721] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [710.303319] [153/156] (364s left) gem_sync (basic-each) [710.516414] Starting subtest: basic-each [712.967647] Subtest basic-each: SUCCESS (2.458s) [713.244770] [154/156] (361s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc) [713.440195] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc [713.442721] Starting dynamic subtest: pipe-A-HDMI-A-1 [713.466173] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s) [713.528249] Starting dynamic subtest: pipe-A-HDMI-A-2 [713.560328] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s) [713.621169] Starting dynamic subtest: pipe-B-HDMI-A-1 [713.654138] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s) [713.691702] Starting dynamic subtest: pipe-B-HDMI-A-2 [713.701692] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s) [713.731154] Starting dynamic subtest: pipe-C-HDMI-A-1 [713.740281] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s) [713.770368] Starting dynamic subtest: pipe-C-HDMI-A-2 [713.779757] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s) [713.781480] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.046s) [714.628533] [155/156] (360s left) syncobj_wait (multi-wait-for-submit-submitted) [714.743251] Starting subtest: multi-wait-for-submit-submitted [714.849334] Subtest multi-wait-for-submit-submitted: SUCCESS (0.104s) [715.002585] [156/156] (359s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt) [715.227330] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt [715.230866] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [715.379629] 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.