[91.617410] Initializing watchdogs [91.617724] /dev/watchdog0 [91.658803] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600] [91.673572] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [91.681818] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [91.687917] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear) [91.909565] Starting subtest: fbc-tiling-linear [94.217419] Subtest fbc-tiling-linear: SUCCESS (2.305s) [94.568081] [002/156] (957s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes) [94.681063] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s) [94.786504] [003/156] (957s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [94.988564] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc [94.995488] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [95.135069] [004/156] (957s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [95.343365] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt [95.348106] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [95.484040] [005/156] (957s left) kms_force_connector_basic (prune-stale-modes) [95.779081] Starting subtest: prune-stale-modes [95.964147] Subtest prune-stale-modes: SUCCESS (0.191s) [96.318797] [006/156] (956s left) perf_pmu (most-busy-check-all) [96.442691] Starting subtest: most-busy-check-all [96.445120] Starting dynamic subtest: rcs0 [96.974238] Dynamic subtest rcs0: SUCCESS (0.528s) [97.003994] Starting dynamic subtest: bcs0 [97.502099] Dynamic subtest bcs0: SUCCESS (0.528s) [97.526627] Starting dynamic subtest: vcs0 [98.025425] Dynamic subtest vcs0: SUCCESS (0.524s) [98.048066] Starting dynamic subtest: vecs0 [98.553951] Dynamic subtest vecs0: SUCCESS (0.526s) [98.555742] Subtest most-busy-check-all: SUCCESS (2.110s) [98.713474] [007/156] (953s left) gem_ccs (large-ctrl-surf-copy) [98.832191] Subtest large-ctrl-surf-copy: SKIP (0.000s) [98.941050] [008/156] (953s left) kms_plane_alpha_blend (coverage-7efc) [99.120221] Starting subtest: coverage-7efc [99.318861] Starting dynamic subtest: pipe-A-HDMI-A-1 [100.348279] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.028s) [100.488087] Starting dynamic subtest: pipe-C-HDMI-A-1 [101.658869] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.209s) [101.668726] Subtest coverage-7efc: SUCCESS (2.533s) [102.007862] [009/156] (950s left) gem_mmap_gtt (fault-concurrent) [102.119127] Starting subtest: fault-concurrent [105.347397] Subtest fault-concurrent: SUCCESS (3.225s) [105.511464] [010/156] (947s left) api_intel_allocator (gem-pool) [105.613643] Starting subtest: gem-pool [105.631816] Subtest gem-pool: SUCCESS (0.018s) [105.792269] [011/156] (947s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen) [106.003206] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen [106.008524] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [106.157585] [012/156] (946s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier) [106.346190] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier [106.349231] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s) [106.474922] [013/156] (946s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs) [106.656362] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs [106.667288] Starting dynamic subtest: pipe-A-HDMI-A-1 [107.230087] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.561s) [107.260942] Starting dynamic subtest: pipe-A-HDMI-A-2 [107.694074] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.460s) [107.734288] Starting dynamic subtest: pipe-B-HDMI-A-1 [108.211322] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.510s) [108.240137] Starting dynamic subtest: pipe-B-HDMI-A-2 [108.637132] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.430s) [108.659349] Starting dynamic subtest: pipe-C-HDMI-A-1 [108.678497] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.003s) [108.747724] Starting dynamic subtest: pipe-C-HDMI-A-2 [108.774073] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.003s) [108.776994] Subtest crc-primary-rotation-180-yf-tiled-ccs: SUCCESS (1.984s) [109.626342] [014/156] (943s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs) [109.821192] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs [109.823118] Starting dynamic subtest: pipe-A-HDMI-A-1 [109.845470] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s) [109.868759] Starting dynamic subtest: pipe-A-HDMI-A-2 [109.910468] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s) [109.958145] Starting dynamic subtest: pipe-B-HDMI-A-1 [109.973368] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s) [109.995229] Starting dynamic subtest: pipe-B-HDMI-A-2 [110.012646] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s) [110.034316] Starting dynamic subtest: pipe-C-HDMI-A-1 [110.050056] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s) [110.070695] Starting dynamic subtest: pipe-C-HDMI-A-2 [110.085610] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s) [110.088571] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.021s) [110.932186] [015/156] (942s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt) [111.142222] Starting subtest: hdr-rgb565-draw-blt [111.146310] Subtest hdr-rgb565-draw-blt: SKIP (0.000s) [111.283985] [016/156] (942s left) kms_pm_rpm (legacy-planes-dpms) [113.222284] Starting subtest: legacy-planes-dpms [113.224352] Starting dynamic subtest: plane-45 [114.795441] Dynamic subtest plane-45: SUCCESS (1.569s) [114.841236] Starting dynamic subtest: plane-54 [116.401339] Dynamic subtest plane-54: SUCCESS (1.606s) [116.436797] Starting dynamic subtest: plane-63 [118.012028] Dynamic subtest plane-63: SUCCESS (1.610s) [118.014783] Subtest legacy-planes-dpms: SUCCESS (4.792s) [118.405851] [017/156] (935s left) gem_render_copy (x-tiled-to-vebox-y-tiled) [118.529374] Starting subtest: x-tiled-to-vebox-y-tiled [118.533323] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s) [118.805701] [018/156] (934s left) sysfs_heartbeat_interval (invalid) [118.924301] Starting subtest: invalid [118.927377] Starting dynamic subtest: vecs0 [118.929939] Dynamic subtest vecs0: SUCCESS (0.001s) [118.956835] Starting dynamic subtest: vcs0 [118.958502] Dynamic subtest vcs0: SUCCESS (0.001s) [118.980280] Starting dynamic subtest: bcs0 [118.982555] Dynamic subtest bcs0: SUCCESS (0.001s) [119.003693] Starting dynamic subtest: rcs0 [119.006569] Dynamic subtest rcs0: SUCCESS (0.001s) [119.009428] Subtest invalid: SUCCESS (0.013s) [119.089242] [019/156] (934s left) kms_frontbuffer_tracking (fbc-stridechange) [119.293209] Starting subtest: fbc-stridechange [121.252402] Subtest fbc-stridechange: SUCCESS (1.956s) [121.585044] [020/156] (932s left) gem_ctx_param (basic-default) [121.691216] Starting subtest: basic-default [121.693253] Subtest basic-default: SUCCESS (0.000s) [121.826509] [021/156] (932s left) kms_psr (fbc-pr-primary-mmap-gtt) [122.017132] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s) [122.126332] [022/156] (931s left) i915_pm_freq_mult (media-freq) [122.234186] Starting subtest: media-freq [122.235888] Starting dynamic subtest: gt0 [122.240094] Dynamic subtest gt0: SKIP (0.000s) [122.244111] Subtest media-freq: SKIP (0.003s) [122.372260] [023/156] (931s left) prime_busy (before-wait) [122.485206] Starting subtest: before-wait [122.498274] Starting dynamic subtest: rcs0 [122.508598] Dynamic subtest rcs0: SUCCESS (0.012s) [122.536342] Starting dynamic subtest: bcs0 [122.537938] Dynamic subtest bcs0: SUCCESS (0.018s) [122.562648] Starting dynamic subtest: vcs0 [122.564670] Dynamic subtest vcs0: SUCCESS (0.015s) [122.586139] Starting dynamic subtest: vecs0 [122.587842] Dynamic subtest vecs0: SUCCESS (0.017s) [122.677905] Subtest before-wait: SUCCESS (0.194s) [122.830108] [024/156] (931s left) gem_exec_gttfill (engines) [122.960048] Starting subtest: engines [122.962086] Starting dynamic subtest: rcs0 [150.932714] Dynamic subtest rcs0: SUCCESS (27.973s) [150.960363] Starting dynamic subtest: bcs0 [180.238381] Dynamic subtest bcs0: SUCCESS (29.302s) [180.270725] Starting dynamic subtest: vcs0 [207.718902] Dynamic subtest vcs0: SUCCESS (27.478s) [207.746214] Starting dynamic subtest: vecs0 [235.022695] Dynamic subtest vecs0: SUCCESS (27.304s) [235.024296] Subtest engines: SUCCESS (112.066s) [235.286308] [025/156] (819s left) syncobj_timeline (etime-multi-wait-all-submitted) [235.537397] Starting subtest: etime-multi-wait-all-submitted [235.649668] Subtest etime-multi-wait-all-submitted: SUCCESS (0.111s) [235.796059] [026/156] (818s left) gem_ctx_param (get-priority-new-ctx) [235.897284] Starting subtest: get-priority-new-ctx [235.900222] Subtest get-priority-new-ctx: SUCCESS (0.000s) [236.045322] [027/156] (818s left) kms_big_fb (linear-32bpp-rotate-90) [236.281468] Starting subtest: linear-32bpp-rotate-90 [236.632414] Subtest linear-32bpp-rotate-90: SKIP (0.346s) [236.928205] [028/156] (817s left) gem_lmem_swapping (heavy-verify-multi-ccs) [237.055107] Subtest heavy-verify-multi-ccs: SKIP (0.000s) [237.164120] [029/156] (817s left) gem_ctx_shared (disjoint-timelines) [237.297152] Starting subtest: disjoint-timelines [237.381680] Subtest disjoint-timelines: SUCCESS (0.086s) [237.663492] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true [237.668456] [030/156] (817s left) kms_psr (fbc-psr2-cursor-plane-move) [237.870637] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s) [237.988210] [031/156] (816s left) gem_bad_reloc (negative-reloc-bltcopy) [238.107367] Starting subtest: negative-reloc-bltcopy [241.315122] Subtest negative-reloc-bltcopy: SUCCESS (3.204s) [241.618394] [032/156] (813s left) kms_chamelium_hpd (hdmi-hpd) [242.191281] Subtest hdmi-hpd: SKIP (0.000s) [242.598028] [033/156] (812s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf) [242.786157] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s) [242.905127] [034/156] (812s left) gem_eio (wait-10ms) [243.062220] Starting subtest: wait-10ms [243.180970] Subtest wait-10ms: SUCCESS (0.116s) [243.457864] [035/156] (811s left) kms_content_protection (legacy-hdcp14) [243.678747] Starting subtest: legacy-hdcp14 [243.718764] Subtest legacy-hdcp14: SKIP (0.023s) [243.887269] [036/156] (811s left) syncobj_wait (reset-signaled) [243.997629] Starting subtest: reset-signaled [244.000337] Subtest reset-signaled: SUCCESS (0.001s) [244.147604] [037/156] (811s left) gem_ctx_isolation (dirty-switch) [244.284191] Starting subtest: dirty-switch [244.286923] Starting dynamic subtest: rcs0 [244.877779] Dynamic subtest rcs0: SUCCESS (0.595s) [244.906710] Starting dynamic subtest: bcs0 [245.872253] Dynamic subtest bcs0: SUCCESS (0.988s) [245.901348] Starting dynamic subtest: vcs0 [246.463058] Dynamic subtest vcs0: SUCCESS (0.593s) [246.493894] Starting dynamic subtest: vecs0 [247.060386] Dynamic subtest vecs0: SUCCESS (0.593s) [247.062083] Subtest dirty-switch: SUCCESS (2.780s) [247.330546] [038/156] (808s left) gem_flink_basic (bad-open) [247.436135] Starting subtest: bad-open [247.438380] Subtest bad-open: SUCCESS (0.000s) [247.580877] [039/156] (808s left) gem_caching (writes) [247.694539] Starting subtest: writes [261.849316] Subtest writes: SUCCESS (14.154s) [262.015670] [040/156] (793s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers) [262.196886] Starting subtest: plane-scaler-unity-scaling-with-modifiers [262.200402] Starting dynamic subtest: pipe-A [263.043142] Dynamic subtest pipe-A: SUCCESS (0.848s) [263.082650] Starting dynamic subtest: pipe-B [264.011971] Dynamic subtest pipe-B: SUCCESS (0.965s) [264.041597] Starting dynamic subtest: pipe-C [264.930700] Dynamic subtest pipe-C: SUCCESS (0.916s) [264.935108] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (2.736s) [265.515532] [041/156] (790s left) syncobj_timeline (single-wait-all-for-submit-available-submitted) [265.625096] Starting subtest: single-wait-all-for-submit-available-submitted [265.627942] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s) [265.782190] [042/156] (790s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render) [266.002067] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render [266.005512] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [266.168348] [043/156] (789s left) kms_pm_rpm (system-suspend-idle) [268.141460] Starting subtest: system-suspend-idle [285.443629] Subtest system-suspend-idle: SUCCESS (2.090s) [292.934362] [044/156] (770s left) kms_atomic_transition (modeset-transition) [293.124640] Starting subtest: modeset-transition [293.127237] Starting dynamic subtest: 1x-outputs [296.771157] Dynamic subtest 1x-outputs: SUCCESS (3.647s) [296.801122] Starting dynamic subtest: 2x-outputs [304.471819] Dynamic subtest 2x-outputs: SUCCESS (7.686s) [304.474518] Subtest modeset-transition: SUCCESS (11.336s) [304.830865] [045/156] (758s left) kms_frontbuffer_tracking (pipe-fbc-rte) [305.042491] Starting subtest: pipe-fbc-rte [305.052626] Subtest pipe-fbc-rte: SKIP (0.001s) [305.205149] [046/156] (758s left) gem_spin_batch (resubmit-all) [305.334610] Starting subtest: resubmit-all [305.336212] Starting dynamic subtest: rcs0 [305.346187] Dynamic subtest rcs0: SUCCESS (0.011s) [305.379947] Starting dynamic subtest: bcs0 [305.381551] Dynamic subtest bcs0: SUCCESS (0.008s) [305.409578] Starting dynamic subtest: vcs0 [305.411612] Dynamic subtest vcs0: SUCCESS (0.010s) [305.438638] Starting dynamic subtest: vecs0 [305.441271] Dynamic subtest vecs0: SUCCESS (0.010s) [305.443713] Subtest resubmit-all: SUCCESS (0.055s) [305.677653] [047/156] (757s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff) [305.892524] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff [305.896983] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [306.059835] [048/156] (757s left) syncobj_basic (illegal-fd-to-handle) [306.168388] Starting subtest: illegal-fd-to-handle [306.170087] Subtest illegal-fd-to-handle: SUCCESS (0.000s) [306.329221] [049/156] (757s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite) [306.539130] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite [309.278320] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SUCCESS (2.720s) [309.525547] [050/156] (754s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [309.726509] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [311.505493] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.769s) [311.859176] [051/156] (752s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted) [311.969578] Starting subtest: etime-single-wait-all-for-submit-unsubmitted [312.076813] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.106s) [312.259299] [052/156] (751s left) api_intel_bb (misplaced-blitter) [312.369637] Starting subtest: misplaced-blitter [312.382467] Subtest misplaced-blitter: SUCCESS (0.016s) [312.549558] [053/156] (751s left) kms_atomic_interruptible (universal-setplane-cursor) [312.749508] Starting subtest: universal-setplane-cursor [312.751574] Starting dynamic subtest: pipe-A-HDMI-A-1 [319.731833] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.958s) [319.733635] Subtest universal-setplane-cursor: SUCCESS (6.964s) [320.093313] [054/156] (744s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip) [320.314820] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip [320.319673] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [320.476380] [055/156] (743s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [320.683751] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc [320.687575] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [320.841758] [056/156] (743s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling) [321.039535] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling [321.041988] Starting dynamic subtest: pipe-A-valid-mode [322.191240] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.143s) [322.193995] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.149s) [322.536857] [057/156] (742s left) gem_pxp (create-protected-buffer) [325.190136] Starting subtest: create-protected-buffer [325.195069] Subtest create-protected-buffer: SKIP (0.001s) [325.353415] [058/156] (739s left) gem_fence_thrash (bo-write-verify-threaded-x) [325.453839] Starting subtest: bo-write-verify-threaded-x [330.764528] Subtest bo-write-verify-threaded-x: SUCCESS (5.303s) [330.960575] [059/156] (733s left) gen9_exec_parse (allowed-single) [331.087799] Starting subtest: allowed-single [331.098990] Subtest allowed-single: SUCCESS (0.012s) [331.409246] [060/156] (733s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu) [331.628500] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu [331.632790] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [331.806192] [061/156] (733s left) gem_exec_balancer (bonded-semaphore) [331.936294] Starting subtest: bonded-semaphore [336.228229] Subtest bonded-semaphore: SUCCESS (4.288s) [336.530420] [062/156] (728s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping) [336.736816] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping [336.741663] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s) [336.882303] [063/156] (728s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc) [337.098596] Starting subtest: fbc-rgb565-draw-mmap-wc [339.857499] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (2.758s) [340.160201] [064/156] (725s left) kms_pwrite_crc (basic) [340.353591] Starting subtest: basic [341.323082] Subtest basic: SUCCESS (0.974s) [341.665910] [065/156] (723s left) gem_flink_basic (bad-flink) [341.777337] Starting subtest: bad-flink [341.780641] Subtest bad-flink: SUCCESS (0.000s) [341.933700] [066/156] (723s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move) [342.130342] Starting subtest: hdr-2p-primscrn-spr-indfb-move [342.134663] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [342.286118] [067/156] (723s left) gem_ctx_persistence (legacy-engines-persistence) [342.405314] Starting subtest: legacy-engines-persistence [342.407174] Starting dynamic subtest: render [343.145177] Dynamic subtest render: SUCCESS (0.738s) [343.179773] Starting dynamic subtest: bsd [343.908649] Dynamic subtest bsd: SUCCESS (0.758s) [343.941895] Starting dynamic subtest: blt [344.680546] Dynamic subtest blt: SUCCESS (0.769s) [344.713382] Starting dynamic subtest: vebox [345.448024] Dynamic subtest vebox: SUCCESS (0.758s) [345.450083] Subtest legacy-engines-persistence: SUCCESS (3.047s) [345.617689] [068/156] (720s left) gen7_exec_parse (basic-allowed) [345.739753] Subtest basic-allowed: SKIP (0.000s) [345.860277] [069/156] (720s left) gem_exec_schedule (reorder-wide) [346.130051] Starting subtest: reorder-wide [346.131832] Starting dynamic subtest: rcs0 [346.295115] Dynamic subtest rcs0: SUCCESS (0.162s) [346.334871] Starting dynamic subtest: bcs0 [346.634910] Dynamic subtest bcs0: SUCCESS (0.335s) [346.682289] Starting dynamic subtest: vcs0 [346.805909] Dynamic subtest vcs0: SUCCESS (0.161s) [346.859304] Starting dynamic subtest: vecs0 [346.967007] Dynamic subtest vecs0: SUCCESS (0.157s) [346.970051] Subtest reorder-wide: SUCCESS (0.837s) [347.233520] [070/156] (718s left) kms_frontbuffer_tracking (psrhdr-slowdraw) [347.452862] Starting subtest: psrhdr-slowdraw [347.458474] Subtest psrhdr-slowdraw: SKIP (0.000s) [347.618108] [071/156] (718s left) perf (non-zero-reason) [347.909642] Starting subtest: non-zero-reason [347.911845] Starting dynamic subtest: 0-rcs0 [398.753493] Dynamic subtest 0-rcs0: SUCCESS (50.832s) [398.755190] Subtest non-zero-reason: SUCCESS (50.834s) [398.882159] [072/156] (667s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite) [399.091083] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite [399.099628] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [399.247003] [073/156] (667s left) i915_drm_fdinfo (all-busy-check-all) [399.365712] Starting subtest: all-busy-check-all [399.890044] Subtest all-busy-check-all: SUCCESS (0.525s) [400.048883] [074/156] (666s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt) [400.247657] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt [400.251790] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [400.414919] [075/156] (666s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [400.606305] Starting subtest: compare-crc-sanitycheck-nv12 [400.610226] Starting dynamic subtest: pipe-A-HDMI-A-1 [401.565616] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.954s) [401.604277] Starting dynamic subtest: pipe-B-HDMI-A-2 [402.266290] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.696s) [402.305512] Starting dynamic subtest: pipe-C-HDMI-A-1 [402.955570] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.681s) [402.958346] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.355s) [403.322103] [076/156] (663s left) gem_exec_reloc (basic-write-gtt) [403.449212] Starting subtest: basic-write-gtt [403.469024] Subtest basic-write-gtt: SUCCESS (0.018s) [403.727495] [077/156] (662s left) kms_cursor_crc (cursor-rapid-movement-max-size) [403.955069] Starting subtest: cursor-rapid-movement-max-size [403.961572] Subtest cursor-rapid-movement-max-size: SKIP (0.000s) [404.125993] [078/156] (662s left) kms_color_pipeline (plane-lut1d) [404.310492] Starting subtest: plane-lut1d [406.029784] Subtest plane-lut1d: SKIP (1.709s) [406.366564] [079/156] (660s left) kms_big_fb (yf-tiled-16bpp-rotate-180) [406.585338] Starting subtest: yf-tiled-16bpp-rotate-180 [410.444806] Subtest yf-tiled-16bpp-rotate-180: SUCCESS (3.862s) [410.835233] [080/156] (656s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0) [411.049957] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0 [411.053329] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s) [411.203186] [081/156] (655s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt) [411.405766] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt [411.410723] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [411.570098] [082/156] (655s left) gem_exec_reloc (basic-wc-read) [411.688599] Starting subtest: basic-wc-read [411.705729] Subtest basic-wc-read: SUCCESS (0.016s) [411.948050] [083/156] (655s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle) [412.142773] Starting subtest: short-busy-flip-before-cursor-toggle [412.147365] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s) [412.303647] [084/156] (655s left) kms_flip (2x-plain-flip-fb-recreate-interruptible) [412.509525] Starting subtest: 2x-plain-flip-fb-recreate-interruptible [412.516606] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2 [423.631738] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.115s) [423.666750] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2 [434.655123] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (11.015s) [434.689040] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2 [445.709502] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (11.046s) [445.712876] Subtest 2x-plain-flip-fb-recreate-interruptible: SUCCESS (33.204s) [446.421011] [085/156] (621s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [446.637202] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu [446.643765] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [446.791116] [086/156] (620s left) kms_draw_crc (draw-method-render) [446.976975] Starting subtest: draw-method-render [446.978462] Starting dynamic subtest: xrgb8888-untiled [447.500027] Dynamic subtest xrgb8888-untiled: SUCCESS (0.525s) [447.530833] Starting dynamic subtest: rgb565-untiled [447.798861] Dynamic subtest rgb565-untiled: SUCCESS (0.295s) [447.832291] Starting dynamic subtest: xrgb2101010-untiled [448.099086] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.296s) [448.132534] Starting dynamic subtest: xbgr16161616f-untiled [448.435643] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.330s) [448.465450] Starting dynamic subtest: xrgb8888-xtiled [448.632745] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.194s) [448.675557] Starting dynamic subtest: rgb565-xtiled [448.832246] Dynamic subtest rgb565-xtiled: SUCCESS (0.195s) [448.871699] Starting dynamic subtest: xrgb2101010-xtiled [448.999250] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.162s) [449.033523] Starting dynamic subtest: xbgr16161616f-xtiled [449.184280] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.180s) [449.219723] Starting dynamic subtest: xrgb8888-ytiled [449.384335] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.199s) [449.421323] Starting dynamic subtest: rgb565-ytiled [449.582661] Dynamic subtest rgb565-ytiled: SUCCESS (0.194s) [449.624905] Starting dynamic subtest: xrgb2101010-ytiled [449.767174] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.180s) [449.800538] Starting dynamic subtest: xbgr16161616f-ytiled [449.969249] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.197s) [449.970994] Subtest draw-method-render: SUCCESS (2.996s) [450.251092] [087/156] (617s left) kms_cursor_crc (cursor-sliding-256x85) [450.446041] Starting subtest: cursor-sliding-256x85 [450.491514] Starting dynamic subtest: pipe-A-HDMI-A-1 [454.499701] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.010s) [454.623553] Starting dynamic subtest: pipe-C-HDMI-A-1 [458.322411] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.759s) [458.327171] Subtest cursor-sliding-256x85: SUCCESS (7.878s) [458.793410] [088/156] (609s left) kms_cursor_crc (cursor-onscreen-256x256) [458.992189] Starting subtest: cursor-onscreen-256x256 [459.044408] Starting dynamic subtest: pipe-A-HDMI-A-1 [461.746773] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.693s) [461.864544] Starting dynamic subtest: pipe-C-HDMI-A-1 [464.197319] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.392s) [464.199407] Subtest cursor-onscreen-256x256: SUCCESS (5.207s) [464.568535] [089/156] (603s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render) [464.787511] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render [464.793044] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s) [464.954332] [090/156] (603s left) kms_frontbuffer_tracking (fbcpsr-slowdraw) [465.173672] Starting subtest: fbcpsr-slowdraw [465.178682] Subtest fbcpsr-slowdraw: SKIP (0.000s) [465.336347] [091/156] (602s left) kms_lease (cursor-implicit-plane) [465.520688] Starting subtest: cursor-implicit-plane [465.522530] Starting dynamic subtest: pipe-A-HDMI-A-1 [466.192978] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.667s) [466.251845] Starting dynamic subtest: pipe-A-HDMI-A-2 [466.654666] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.456s) [466.732170] Starting dynamic subtest: pipe-B-HDMI-A-1 [467.079187] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.419s) [467.128634] Starting dynamic subtest: pipe-B-HDMI-A-2 [467.541622] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.443s) [467.614129] Starting dynamic subtest: pipe-C-HDMI-A-1 [467.943532] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.395s) [467.982504] Starting dynamic subtest: pipe-C-HDMI-A-2 [468.386555] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.440s) [468.388637] Subtest cursor-implicit-plane: SUCCESS (2.873s) [468.764369] [092/156] (599s left) syncobj_wait (invalid-reset-zero-handles) [468.911844] Starting subtest: invalid-reset-zero-handles [468.914617] Subtest invalid-reset-zero-handles: SUCCESS (0.000s) [469.068010] [093/156] (599s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render) [469.279664] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render [471.409498] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SUCCESS (2.130s) [471.634023] [094/156] (596s left) kms_big_fb (yf-tiled-64bpp-rotate-90) [471.853168] Starting subtest: yf-tiled-64bpp-rotate-90 [471.860136] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s) [472.037594] [095/156] (596s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc) [472.251580] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc [472.257502] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.001s) [472.416733] [096/156] (596s left) gem_exec_fence (long-history) [472.972938] Starting subtest: long-history [478.677536] Subtest long-history: SUCCESS (5.698s) [479.358788] [097/156] (589s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt) [479.569297] Starting subtest: psr-1p-primscrn-indfb-msflip-blt [479.574566] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [479.747586] [098/156] (589s left) kms_ccs (bad-rotation-90-yf-tiled-ccs) [479.944481] Starting subtest: bad-rotation-90-yf-tiled-ccs [479.948398] Starting dynamic subtest: pipe-A-HDMI-A-1 [480.145899] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.182s) [480.206644] Starting dynamic subtest: pipe-A-HDMI-A-2 [480.242810] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.095s) [480.303746] Starting dynamic subtest: pipe-B-HDMI-A-1 [480.339785] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.093s) [480.402846] Starting dynamic subtest: pipe-B-HDMI-A-2 [480.436128] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.091s) [480.498939] Starting dynamic subtest: pipe-C-HDMI-A-1 [480.528960] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s) [480.587795] Starting dynamic subtest: pipe-C-HDMI-A-2 [480.619534] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s) [480.621069] Subtest bad-rotation-90-yf-tiled-ccs: SUCCESS (0.496s) [481.633824] [099/156] (587s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic) [481.829599] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic [488.965544] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (7.135s) [489.365869] [100/156] (579s left) kms_chamelium_audio (dp-audio-edid) [489.953255] Subtest dp-audio-edid: SKIP (0.000s) [490.315183] [101/156] (578s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [490.504669] Starting subtest: addfb25-yf-tiled-legacy [490.506338] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [490.668268] [102/156] (578s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt) [490.886159] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt [490.890387] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [491.051022] [103/156] (578s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff) [491.250660] Starting subtest: psr-2p-primscrn-cur-indfb-onoff [491.255897] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [491.400506] [104/156] (578s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw) [491.617491] Starting subtest: fbc-2p-pri-indfb-multidraw [508.397076] Subtest fbc-2p-pri-indfb-multidraw: SUCCESS (16.773s) [508.621738] [105/156] (561s left) kms_sequence (queue-busy) [508.824051] Starting subtest: queue-busy [508.826595] Starting dynamic subtest: pipe-A-HDMI-A-1 [511.754511] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.924s) [511.825372] Starting dynamic subtest: pipe-A-HDMI-A-2 [514.405037] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.643s) [514.463769] Starting dynamic subtest: pipe-B-HDMI-A-1 [517.036979] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.620s) [517.078573] Starting dynamic subtest: pipe-B-HDMI-A-2 [519.715564] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.671s) [519.771928] Starting dynamic subtest: pipe-C-HDMI-A-1 [522.345479] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.621s) [522.390940] Starting dynamic subtest: pipe-C-HDMI-A-2 [524.972314] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.620s) [524.975633] Subtest queue-busy: SUCCESS (16.147s) [525.347272] [106/156] (544s left) kms_plane_scaling (plane-upscale-20x20-with-rotation) [525.540595] Starting subtest: plane-upscale-20x20-with-rotation [525.543654] Starting dynamic subtest: pipe-A [532.089623] Dynamic subtest pipe-A: SKIP (6.546s) [532.147950] Starting dynamic subtest: pipe-B [537.754940] Dynamic subtest pipe-B: SKIP (5.665s) [537.822013] Starting dynamic subtest: pipe-C [542.709943] Dynamic subtest pipe-C: SKIP (4.947s) [542.715790] Subtest plane-upscale-20x20-with-rotation: SKIP (17.168s) [543.299593] [107/156] (526s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite) [543.548536] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite [543.552499] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [543.718879] [108/156] (526s left) gem_exec_reloc (basic-write-wc) [543.904820] Starting subtest: basic-write-wc [543.922441] Subtest basic-write-wc: SUCCESS (0.019s) [544.168222] [109/156] (526s left) syncobj_timeline (reset-signaled) [544.286605] Starting subtest: reset-signaled [544.289807] Subtest reset-signaled: SUCCESS (0.002s) [544.451572] [110/156] (525s left) kms_psr (pr-cursor-plane-move) [544.657013] Subtest pr-cursor-plane-move: SKIP (0.000s) [544.777233] [111/156] (525s left) syncobj_wait (invalid-wait-illegal-handle) [544.895568] Starting subtest: invalid-wait-illegal-handle [544.897451] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s) [545.050326] [112/156] (525s left) kms_busy (extended-pageflip-modeset-hang-oldfb) [545.250486] Starting subtest: extended-pageflip-modeset-hang-oldfb [545.261918] Starting dynamic subtest: pipe-A [549.079148] Dynamic subtest pipe-A: SUCCESS (3.817s) [549.143625] Starting dynamic subtest: pipe-C [553.037207] Dynamic subtest pipe-C: SUCCESS (3.945s) [553.039023] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (7.789s) [553.469879] [113/156] (517s left) kms_psr (psr2-primary-blt) [553.678669] Subtest psr2-primary-blt: SKIP (0.000s) [553.809288] [114/156] (516s left) gem_softpin (evict-snoop-interruptible) [553.936187] Starting subtest: evict-snoop-interruptible [553.941169] Subtest evict-snoop-interruptible: SKIP (0.000s) [554.108413] [115/156] (516s left) kms_force_connector_basic (force-load-detect) [554.365332] Starting subtest: force-load-detect [554.371493] Subtest force-load-detect: SKIP (0.000s) [554.723767] [116/156] (516s left) kms_chamelium_edid (hdmi-mode-timings) [555.330992] Subtest hdmi-mode-timings: SKIP (0.000s) [555.691249] [117/156] (515s left) syncobj_timeline (invalid-signal-zero-handles) [555.814009] Starting subtest: invalid-signal-zero-handles [555.816527] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [555.970943] [118/156] (515s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt) [556.194671] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt [556.198134] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [556.354321] [119/156] (514s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [556.580618] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt [556.586402] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [556.736769] [120/156] (514s left) gem_mmap_gtt (fault-concurrent-x) [556.847483] Starting subtest: fault-concurrent-X [560.099008] Subtest fault-concurrent-X: SUCCESS (3.253s) [560.270494] [121/156] (511s left) gem_render_copy (y-tiled-to-vebox-y-tiled) [560.397548] Starting subtest: y-tiled-to-vebox-y-tiled [560.401758] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s) [560.690109] [122/156] (510s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt) [560.886161] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt [560.891588] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s) [561.055708] [123/156] (510s left) vgem_basic (debugfs) [561.186533] Starting subtest: debugfs [561.189073] Subtest debugfs: SUCCESS (0.001s) [561.323120] [124/156] (510s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling) [561.520289] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling [561.523839] Starting dynamic subtest: pipe-A-valid-mode [562.617784] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.096s) [562.621747] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.105s) [563.015217] [125/156] (508s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite) [563.226808] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite [566.693793] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (3.468s) [566.928619] [126/156] (505s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu) [567.148533] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu [567.153753] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [567.333525] [127/156] (504s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt) [567.531723] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt [567.536316] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [567.716338] [128/156] (504s left) kms_vblank (wait-forked-hang) [567.916533] Starting subtest: wait-forked-hang [567.918091] Starting dynamic subtest: pipe-A-HDMI-A-1 [575.083596] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (7.164s) [575.147510] Starting dynamic subtest: pipe-A-HDMI-A-2 [582.063153] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (6.970s) [582.126828] Starting dynamic subtest: pipe-C-HDMI-A-1 [589.098527] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (7.019s) [589.153759] Starting dynamic subtest: pipe-C-HDMI-A-2 [596.060683] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (6.956s) [596.062788] Subtest wait-forked-hang: SUCCESS (28.150s) [596.411780] [129/156] (475s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling) [596.596347] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling [596.600057] Starting dynamic subtest: pipe-A-valid-mode [597.486785] Dynamic subtest pipe-A-valid-mode: SKIP (0.883s) [597.489860] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (0.889s) [598.043639] [130/156] (474s left) gem_ctx_exec (basic-invalid-context) [598.158978] Starting subtest: basic-invalid-context [598.168789] Subtest basic-invalid-context: SUCCESS (0.006s) [598.328322] [131/156] (474s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt) [598.537765] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt [598.541675] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [598.714070] [132/156] (473s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf) [598.912453] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s) [599.039295] [133/156] (473s left) gem_ppgtt (blt-vs-render-ctxn) [599.163865] Starting subtest: blt-vs-render-ctxN [632.126720] Subtest blt-vs-render-ctxN: SUCCESS (32.962s) [632.305557] [134/156] (440s left) gem_exec_reloc (basic-gtt-cpu-noreloc) [632.562624] Starting subtest: basic-gtt-cpu-noreloc [632.570018] Subtest basic-gtt-cpu-noreloc: SUCCESS (0.009s) [632.817512] [135/156] (440s left) gem_softpin (invalid) [632.943584] Starting subtest: invalid [632.955727] Subtest invalid: SUCCESS (0.013s) [633.135283] [136/156] (439s left) gem_exec_capture (capture) [633.257547] Starting subtest: capture [633.259463] Starting dynamic subtest: rcs0-smem [634.544330] Dynamic subtest rcs0-smem: SUCCESS (1.285s) [634.576895] Starting dynamic subtest: bcs0-smem [635.831229] Dynamic subtest bcs0-smem: SUCCESS (1.278s) [635.862311] Starting dynamic subtest: vcs0-smem [637.112524] Dynamic subtest vcs0-smem: SUCCESS (1.284s) [637.149210] Starting dynamic subtest: vecs0-smem [638.404383] Dynamic subtest vecs0-smem: SUCCESS (1.280s) [638.407289] Subtest capture: SUCCESS (5.142s) [638.567139] [137/156] (434s left) gem_tiled_blits (basic) [638.781171] Starting subtest: basic [638.822000] Subtest basic: SUCCESS (0.039s) [639.025969] [138/156] (434s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt) [639.367543] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt [639.371321] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [639.564527] [139/156] (433s left) gem_mmap_offset (partial-remap) [639.670647] Starting subtest: partial-remap [639.672547] Starting dynamic subtest: smem0 [639.692129] Dynamic subtest smem0: SUCCESS (0.010s) [639.694895] Subtest partial-remap: SUCCESS (0.014s) [639.877115] [140/156] (433s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt) [640.077915] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt [640.082524] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s) [640.233189] [141/156] (433s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt) [640.436194] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt [640.441742] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s) [640.595344] [142/156] (433s left) i915_power (sanity) [640.704793] Starting subtest: sanity [644.736206] Subtest sanity: SUCCESS (4.024s) [644.901903] [143/156] (428s left) gem_exec_schedule (preempt-queue) [645.066534] Starting subtest: preempt-queue [645.068243] Starting dynamic subtest: rcs0 [645.363684] Dynamic subtest rcs0: SUCCESS (0.294s) [645.398208] Starting dynamic subtest: bcs0 [645.664076] Dynamic subtest bcs0: SUCCESS (0.294s) [645.697056] Starting dynamic subtest: vcs0 [645.956044] Dynamic subtest vcs0: SUCCESS (0.291s) [645.998292] Starting dynamic subtest: vecs0 [646.247711] Dynamic subtest vecs0: SUCCESS (0.283s) [646.250922] Subtest preempt-queue: SUCCESS (1.182s) [646.669620] [144/156] (427s left) kms_cursor_legacy (cursorb-vs-flipa-legacy) [646.860228] Starting subtest: cursorB-vs-flipA-legacy [658.296949] Subtest cursorB-vs-flipA-legacy: SUCCESS (11.426s) [658.685370] [145/156] (415s left) i915_pm_rps (reset) [658.818385] Starting subtest: reset [663.406855] Subtest reset: SUCCESS (4.587s) [663.567621] [146/156] (410s left) kms_psr (fbc-psr-cursor-plane-onoff) [663.772538] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s) [663.941197] [147/156] (410s left) kms_setmode (clone-exclusive-crtc) [664.058962] Starting subtest: clone-exclusive-crtc [664.528506] Starting dynamic subtest: pipe-A-HDMI-A-1-pipe-B-HDMI-A-2 [664.898671] Dynamic subtest pipe-A-HDMI-A-1-pipe-B-HDMI-A-2: SUCCESS (0.366s) [665.443399] Starting dynamic subtest: pipe-B-HDMI-A-1-pipe-A-HDMI-A-2 [665.723521] Dynamic subtest pipe-B-HDMI-A-1-pipe-A-HDMI-A-2: SUCCESS (0.303s) [666.221879] Subtest clone-exclusive-crtc: SUCCESS (2.160s) [666.641306] [148/156] (407s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu) [666.855514] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu [666.861107] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [667.031007] [149/156] (407s left) kms_flip (flip-vs-rmfb) [667.234928] Starting subtest: flip-vs-rmfb [667.238386] Starting dynamic subtest: A-HDMI-A1 [672.886420] Dynamic subtest A-HDMI-A1: SUCCESS (5.653s) [672.920770] Starting dynamic subtest: B-HDMI-A1 [678.301302] Dynamic subtest B-HDMI-A1: SUCCESS (5.406s) [678.334851] Starting dynamic subtest: C-HDMI-A1 [683.701151] Dynamic subtest C-HDMI-A1: SUCCESS (5.393s) [683.736153] Starting dynamic subtest: A-HDMI-A2 [689.120947] Dynamic subtest A-HDMI-A2: SUCCESS (5.411s) [689.156751] Starting dynamic subtest: B-HDMI-A2 [694.496562] Dynamic subtest B-HDMI-A2: SUCCESS (5.367s) [694.531979] Starting dynamic subtest: C-HDMI-A2 [699.903336] Dynamic subtest C-HDMI-A2: SUCCESS (5.398s) [699.906768] Subtest flip-vs-rmfb: SUCCESS (32.675s) [700.548875] [150/156] (374s left) gem_exec_whisper (basic-contexts-all) [700.687664] Starting subtest: basic-contexts-all [712.370477] Subtest basic-contexts-all: SUCCESS (11.674s) [712.657704] [151/156] (362s left) dmabuf_sync_file (export-basic) [712.796868] Starting subtest: export-basic [712.805503] Subtest export-basic: SUCCESS (0.006s) [712.951958] [152/156] (361s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu) [713.159036] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu [713.163830] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [713.333636] [153/156] (361s left) gem_sync (basic-each) [713.465504] Starting subtest: basic-each [715.830145] Subtest basic-each: SUCCESS (2.363s) [716.099565] [154/156] (359s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc) [716.285328] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc [716.287575] Starting dynamic subtest: pipe-A-HDMI-A-1 [716.312509] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s) [716.376803] Starting dynamic subtest: pipe-A-HDMI-A-2 [716.419501] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s) [716.456845] Starting dynamic subtest: pipe-B-HDMI-A-1 [716.474626] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s) [716.503688] Starting dynamic subtest: pipe-B-HDMI-A-2 [716.521299] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s) [716.550035] Starting dynamic subtest: pipe-C-HDMI-A-1 [716.566489] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s) [716.595104] Starting dynamic subtest: pipe-C-HDMI-A-2 [716.613553] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s) [716.616640] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.035s) [717.377059] [155/156] (357s left) syncobj_wait (multi-wait-for-submit-submitted) [717.496865] Starting subtest: multi-wait-for-submit-submitted [717.604091] Subtest multi-wait-for-submit-submitted: SUCCESS (0.109s) [717.762985] [156/156] (357s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt) [717.978563] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt [717.983177] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [718.144676] 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.