[89.505676] Initializing watchdogs [89.505853] /dev/watchdog0 [89.548892] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600] [89.563848] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [89.570720] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [89.578930] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear) [89.810819] Starting subtest: fbc-tiling-linear [92.133970] Subtest fbc-tiling-linear: SUCCESS (2.334s) [92.458712] [002/156] (957s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes) [92.582243] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s) [92.681259] [003/156] (957s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [92.901722] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc [92.909378] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [93.041096] [004/156] (957s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [93.259671] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt [93.267745] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [93.390598] [005/156] (957s left) kms_force_connector_basic (prune-stale-modes) [93.671939] Starting subtest: prune-stale-modes [93.836860] Subtest prune-stale-modes: SUCCESS (0.170s) [94.170144] [006/156] (956s left) perf_pmu (most-busy-check-all) [94.302078] Starting subtest: most-busy-check-all [94.306483] Starting dynamic subtest: rcs0 [94.828567] Dynamic subtest rcs0: SUCCESS (0.526s) [94.856254] Starting dynamic subtest: bcs0 [95.360884] Dynamic subtest bcs0: SUCCESS (0.533s) [95.383738] Starting dynamic subtest: vcs0 [95.887504] Dynamic subtest vcs0: SUCCESS (0.527s) [95.911234] Starting dynamic subtest: vecs0 [96.408700] Dynamic subtest vecs0: SUCCESS (0.520s) [96.412177] Subtest most-busy-check-all: SUCCESS (2.110s) [96.555814] [007/156] (954s left) gem_ccs (large-ctrl-surf-copy) [96.680548] Subtest large-ctrl-surf-copy: SKIP (0.000s) [96.789928] [008/156] (953s left) kms_plane_alpha_blend (coverage-7efc) [96.981365] Starting subtest: coverage-7efc [97.211481] Starting dynamic subtest: pipe-A-HDMI-A-1 [98.295450] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.083s) [98.400568] Starting dynamic subtest: pipe-C-HDMI-A-1 [99.391406] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.011s) [99.397787] Subtest coverage-7efc: SUCCESS (2.405s) [99.749508] [009/156] (951s left) gem_mmap_gtt (fault-concurrent) [99.870594] Starting subtest: fault-concurrent [103.186472] Subtest fault-concurrent: SUCCESS (3.318s) [103.399215] [010/156] (947s left) api_intel_allocator (gem-pool) [103.517144] Starting subtest: gem-pool [103.530918] Subtest gem-pool: SUCCESS (0.021s) [103.717347] [011/156] (947s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen) [103.927644] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen [103.935318] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [104.072564] [012/156] (947s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier) [104.269074] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier [104.275817] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s) [104.405554] [013/156] (946s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs) [104.598559] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs [104.603461] Starting dynamic subtest: pipe-A-HDMI-A-1 [105.226749] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.599s) [105.249044] Starting dynamic subtest: pipe-A-HDMI-A-2 [105.701964] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.469s) [105.786083] Starting dynamic subtest: pipe-B-HDMI-A-1 [106.323999] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.622s) [106.347698] Starting dynamic subtest: pipe-B-HDMI-A-2 [106.744411] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.428s) [106.785973] Starting dynamic subtest: pipe-C-HDMI-A-1 [106.825096] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s) [106.847043] Starting dynamic subtest: pipe-C-HDMI-A-2 [106.879302] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.003s) [106.882752] Subtest crc-primary-rotation-180-yf-tiled-ccs: SUCCESS (2.142s) [107.621824] [014/156] (943s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs) [107.823172] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs [107.826759] Starting dynamic subtest: pipe-A-HDMI-A-1 [107.875577] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s) [107.919281] Starting dynamic subtest: pipe-A-HDMI-A-2 [107.967076] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s) [107.993121] Starting dynamic subtest: pipe-B-HDMI-A-1 [108.024930] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s) [108.048711] Starting dynamic subtest: pipe-B-HDMI-A-2 [108.080682] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s) [108.102808] Starting dynamic subtest: pipe-C-HDMI-A-1 [108.134201] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s) [108.156552] Starting dynamic subtest: pipe-C-HDMI-A-2 [108.188803] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s) [108.195231] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.019s) [109.274335] [015/156] (942s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt) [109.942470] Starting subtest: hdr-rgb565-draw-blt [110.158866] Subtest hdr-rgb565-draw-blt: SKIP (0.000s) [110.248255] [016/156] (941s left) kms_pm_rpm (legacy-planes-dpms) [112.186960] Starting subtest: legacy-planes-dpms [112.190781] Starting dynamic subtest: plane-45 [113.710715] Dynamic subtest plane-45: SUCCESS (1.526s) [113.760667] Starting dynamic subtest: plane-54 [115.338028] Dynamic subtest plane-54: SUCCESS (1.623s) [115.372383] Starting dynamic subtest: plane-63 [116.882679] Dynamic subtest plane-63: SUCCESS (1.544s) [116.888342] Subtest legacy-planes-dpms: SUCCESS (4.702s) [117.321426] [017/156] (934s left) gem_render_copy (x-tiled-to-vebox-y-tiled) [117.452469] Starting subtest: x-tiled-to-vebox-y-tiled [117.460418] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s) [117.706113] [018/156] (934s left) sysfs_heartbeat_interval (invalid) [117.835858] Starting subtest: invalid [117.839926] Starting dynamic subtest: vecs0 [117.843757] Dynamic subtest vecs0: SUCCESS (0.002s) [117.868128] Starting dynamic subtest: vcs0 [117.871971] Dynamic subtest vcs0: SUCCESS (0.001s) [117.895525] Starting dynamic subtest: bcs0 [117.898899] Dynamic subtest bcs0: SUCCESS (0.002s) [117.921888] Starting dynamic subtest: rcs0 [117.926458] Dynamic subtest rcs0: SUCCESS (0.001s) [117.930538] Subtest invalid: SUCCESS (0.015s) [118.017396] [019/156] (934s left) kms_frontbuffer_tracking (fbc-stridechange) [118.236036] Starting subtest: fbc-stridechange [120.178791] Subtest fbc-stridechange: SUCCESS (1.949s) [120.489470] [020/156] (931s left) gem_ctx_param (basic-default) [120.601025] Starting subtest: basic-default [120.604586] Subtest basic-default: SUCCESS (0.000s) [120.736579] [021/156] (931s left) kms_psr (fbc-pr-primary-mmap-gtt) [120.935080] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s) [121.045223] [022/156] (931s left) i915_pm_freq_mult (media-freq) [121.164133] Starting subtest: media-freq [121.167560] Starting dynamic subtest: gt0 [121.174704] Dynamic subtest gt0: SKIP (0.000s) [121.183677] Subtest media-freq: SKIP (0.001s) [121.305540] [023/156] (931s left) prime_busy (before-wait) [121.428669] Starting subtest: before-wait [121.438503] Starting dynamic subtest: rcs0 [121.453119] Dynamic subtest rcs0: SUCCESS (0.014s) [121.483741] Starting dynamic subtest: bcs0 [121.487224] Dynamic subtest bcs0: SUCCESS (0.021s) [121.513817] Starting dynamic subtest: vcs0 [121.518168] Dynamic subtest vcs0: SUCCESS (0.019s) [121.540702] Starting dynamic subtest: vecs0 [121.544520] Dynamic subtest vecs0: SUCCESS (0.015s) [121.628410] Subtest before-wait: SUCCESS (0.201s) [121.803053] [024/156] (930s left) gem_exec_gttfill (engines) [121.938862] Starting subtest: engines [121.943432] Starting dynamic subtest: rcs0 [150.157500] Dynamic subtest rcs0: SUCCESS (28.216s) [150.185342] Starting dynamic subtest: bcs0 [179.989122] Dynamic subtest bcs0: SUCCESS (29.830s) [180.020409] Starting dynamic subtest: vcs0 [207.451072] Dynamic subtest vcs0: SUCCESS (27.459s) [207.482522] Starting dynamic subtest: vecs0 [235.232863] Dynamic subtest vecs0: SUCCESS (27.782s) [235.236958] Subtest engines: SUCCESS (113.295s) [235.514296] [025/156] (817s left) syncobj_timeline (etime-multi-wait-all-submitted) [235.796389] Starting subtest: etime-multi-wait-all-submitted [235.905563] Subtest etime-multi-wait-all-submitted: SUCCESS (0.108s) [236.040841] [026/156] (816s left) gem_ctx_param (get-priority-new-ctx) [236.150684] Starting subtest: get-priority-new-ctx [236.154761] Subtest get-priority-new-ctx: SUCCESS (0.002s) [236.291109] [027/156] (816s left) kms_big_fb (linear-32bpp-rotate-90) [236.524053] Starting subtest: linear-32bpp-rotate-90 [236.885548] Subtest linear-32bpp-rotate-90: SKIP (0.354s) [237.190731] [028/156] (815s left) gem_lmem_swapping (heavy-verify-multi-ccs) [237.322741] Subtest heavy-verify-multi-ccs: SKIP (0.000s) [237.434405] [029/156] (815s left) gem_ctx_shared (disjoint-timelines) [237.567547] Starting subtest: disjoint-timelines [237.650683] Subtest disjoint-timelines: SUCCESS (0.081s) [237.911636] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true [237.918472] [030/156] (815s left) kms_psr (fbc-psr2-cursor-plane-move) [238.113523] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s) [238.230303] [031/156] (815s left) gem_bad_reloc (negative-reloc-bltcopy) [238.359115] Starting subtest: negative-reloc-bltcopy [241.597418] Subtest negative-reloc-bltcopy: SUCCESS (3.236s) [242.000210] [032/156] (811s left) kms_chamelium_hpd (hdmi-hpd) [242.584608] Subtest hdmi-hpd: SKIP (0.000s) [242.953998] [033/156] (810s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf) [243.167877] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s) [243.290808] [034/156] (810s left) gem_eio (wait-10ms) [243.440138] Starting subtest: wait-10ms [243.549590] Subtest wait-10ms: SUCCESS (0.114s) [243.809439] [035/156] (809s left) kms_content_protection (legacy-hdcp14) [244.040301] Starting subtest: legacy-hdcp14 [244.068942] Subtest legacy-hdcp14: SKIP (0.017s) [244.216267] [036/156] (809s left) syncobj_wait (reset-signaled) [244.331111] Starting subtest: reset-signaled [244.334425] Subtest reset-signaled: SUCCESS (0.001s) [244.478404] [037/156] (809s left) gem_ctx_isolation (dirty-switch) [244.609439] Starting subtest: dirty-switch [244.613039] Starting dynamic subtest: rcs0 [245.208143] Dynamic subtest rcs0: SUCCESS (0.593s) [245.244349] Starting dynamic subtest: bcs0 [246.172065] Dynamic subtest bcs0: SUCCESS (0.970s) [246.204120] Starting dynamic subtest: vcs0 [246.763429] Dynamic subtest vcs0: SUCCESS (0.590s) [246.798761] Starting dynamic subtest: vecs0 [247.347843] Dynamic subtest vecs0: SUCCESS (0.581s) [247.351689] Subtest dirty-switch: SUCCESS (2.743s) [247.612227] [038/156] (806s left) gem_flink_basic (bad-open) [247.730838] Starting subtest: bad-open [247.734426] Subtest bad-open: SUCCESS (0.000s) [247.878240] [039/156] (806s left) gem_caching (writes) [247.995532] Starting subtest: writes [262.211089] Subtest writes: SUCCESS (14.215s) [262.379288] [040/156] (791s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers) [262.567165] Starting subtest: plane-scaler-unity-scaling-with-modifiers [262.571281] Starting dynamic subtest: pipe-A [263.472552] Dynamic subtest pipe-A: SUCCESS (0.821s) [263.569226] Starting dynamic subtest: pipe-B [264.283107] Dynamic subtest pipe-B: SUCCESS (0.862s) [264.341357] Starting dynamic subtest: pipe-C [265.105838] Dynamic subtest pipe-C: SUCCESS (0.851s) [265.110686] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (2.539s) [265.683235] [041/156] (788s left) syncobj_timeline (single-wait-all-for-submit-available-submitted) [265.805930] Starting subtest: single-wait-all-for-submit-available-submitted [265.809797] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s) [265.997346] [042/156] (788s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render) [266.212671] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render [266.219799] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [266.365832] [043/156] (788s left) kms_pm_rpm (system-suspend-idle) [268.359288] Starting subtest: system-suspend-idle [285.413874] Subtest system-suspend-idle: SUCCESS (2.277s) [297.933584] [044/156] (768s left) kms_atomic_transition (modeset-transition) [298.137136] Starting subtest: modeset-transition [298.141852] Starting dynamic subtest: 1x-outputs [301.752724] Dynamic subtest 1x-outputs: SUCCESS (3.720s) [301.783835] Starting dynamic subtest: 2x-outputs [309.379915] Dynamic subtest 2x-outputs: SUCCESS (7.728s) [309.386423] Subtest modeset-transition: SUCCESS (11.455s) [309.768888] [045/156] (757s left) kms_frontbuffer_tracking (pipe-fbc-rte) [309.996188] Starting subtest: pipe-fbc-rte [310.013184] Subtest pipe-fbc-rte: SKIP (0.002s) [310.156969] [046/156] (756s left) gem_spin_batch (resubmit-all) [310.290332] Starting subtest: resubmit-all [310.294053] Starting dynamic subtest: rcs0 [310.303994] Dynamic subtest rcs0: SUCCESS (0.013s) [310.339999] Starting dynamic subtest: bcs0 [310.343831] Dynamic subtest bcs0: SUCCESS (0.006s) [310.371868] Starting dynamic subtest: vcs0 [310.375850] Dynamic subtest vcs0: SUCCESS (0.006s) [310.404551] Starting dynamic subtest: vecs0 [310.408430] Dynamic subtest vecs0: SUCCESS (0.006s) [310.412642] Subtest resubmit-all: SUCCESS (0.047s) [310.631175] [047/156] (756s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff) [310.836567] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff [310.843912] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [311.000484] [048/156] (756s left) syncobj_basic (illegal-fd-to-handle) [311.117036] Starting subtest: illegal-fd-to-handle [311.121506] Subtest illegal-fd-to-handle: SUCCESS (0.000s) [311.278873] [049/156] (756s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite) [311.498411] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite [314.135963] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SUCCESS (2.662s) [314.394839] [050/156] (753s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [314.615748] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [316.428459] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.822s) [316.735990] [051/156] (750s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted) [316.850738] Starting subtest: etime-single-wait-all-for-submit-unsubmitted [316.948928] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.101s) [317.097198] [052/156] (750s left) api_intel_bb (misplaced-blitter) [317.213150] Starting subtest: misplaced-blitter [317.225143] Subtest misplaced-blitter: SUCCESS (0.015s) [317.398203] [053/156] (750s left) kms_atomic_interruptible (universal-setplane-cursor) [317.599883] Starting subtest: universal-setplane-cursor [317.604673] Starting dynamic subtest: pipe-A-HDMI-A-1 [324.429482] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.837s) [324.432873] Subtest universal-setplane-cursor: SUCCESS (6.843s) [324.815665] [054/156] (743s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip) [325.033612] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip [325.042405] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [325.196508] [055/156] (742s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [325.420787] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc [325.426815] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.001s) [325.574586] [056/156] (742s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling) [325.764958] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling [325.772409] Starting dynamic subtest: pipe-A-valid-mode [326.837994] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.070s) [326.842234] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.074s) [327.220865] [057/156] (740s left) gem_pxp (create-protected-buffer) [329.882056] Starting subtest: create-protected-buffer [329.888782] Subtest create-protected-buffer: SKIP (0.000s) [330.044920] [058/156] (738s left) gem_fence_thrash (bo-write-verify-threaded-x) [330.147918] Starting subtest: bo-write-verify-threaded-x [335.087116] Subtest bo-write-verify-threaded-x: SUCCESS (4.943s) [335.244186] [059/156] (733s left) gen9_exec_parse (allowed-single) [335.382920] Starting subtest: allowed-single [335.396788] Subtest allowed-single: SUCCESS (0.012s) [335.675705] [060/156] (732s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu) [335.892912] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu [335.900987] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [336.046102] [061/156] (732s left) gem_exec_balancer (bonded-semaphore) [336.182308] Starting subtest: bonded-semaphore [340.502379] Subtest bonded-semaphore: SUCCESS (4.322s) [340.792148] [062/156] (727s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping) [340.997196] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping [341.004258] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s) [341.163678] [063/156] (727s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc) [341.376438] Starting subtest: fbc-rgb565-draw-mmap-wc [344.081899] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (2.717s) [344.420866] [064/156] (724s left) kms_pwrite_crc (basic) [344.630389] Starting subtest: basic [345.552670] Subtest basic: SUCCESS (0.922s) [345.900398] [065/156] (723s left) gem_flink_basic (bad-flink) [346.013173] Starting subtest: bad-flink [346.017229] Subtest bad-flink: SUCCESS (0.000s) [346.166603] [066/156] (723s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move) [346.374215] Starting subtest: hdr-2p-primscrn-spr-indfb-move [346.381833] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [346.538178] [067/156] (722s left) gem_ctx_persistence (legacy-engines-persistence) [346.662865] Starting subtest: legacy-engines-persistence [346.667080] Starting dynamic subtest: render [347.446629] Dynamic subtest render: SUCCESS (0.781s) [347.481762] Starting dynamic subtest: bsd [348.217501] Dynamic subtest bsd: SUCCESS (0.766s) [348.250653] Starting dynamic subtest: blt [348.987067] Dynamic subtest blt: SUCCESS (0.765s) [349.024577] Starting dynamic subtest: vebox [349.750486] Dynamic subtest vebox: SUCCESS (0.757s) [349.755915] Subtest legacy-engines-persistence: SUCCESS (3.092s) [349.914227] [068/156] (719s left) gen7_exec_parse (basic-allowed) [350.042022] Subtest basic-allowed: SKIP (0.000s) [350.171346] [069/156] (719s left) gem_exec_schedule (reorder-wide) [350.447221] Starting subtest: reorder-wide [350.451752] Starting dynamic subtest: rcs0 [350.619647] Dynamic subtest rcs0: SUCCESS (0.171s) [350.666910] Starting dynamic subtest: bcs0 [350.948390] Dynamic subtest bcs0: SUCCESS (0.322s) [350.982392] Starting dynamic subtest: vcs0 [351.124908] Dynamic subtest vcs0: SUCCESS (0.173s) [351.181244] Starting dynamic subtest: vecs0 [351.285109] Dynamic subtest vecs0: SUCCESS (0.148s) [351.290470] Subtest reorder-wide: SUCCESS (0.830s) [351.582114] [070/156] (718s left) kms_frontbuffer_tracking (psrhdr-slowdraw) [351.801826] Starting subtest: psrhdr-slowdraw [351.809905] Subtest psrhdr-slowdraw: SKIP (0.000s) [351.968708] [071/156] (717s left) perf (non-zero-reason) [352.239214] Starting subtest: non-zero-reason [352.244433] Starting dynamic subtest: 0-rcs0 [402.969728] Dynamic subtest 0-rcs0: SUCCESS (50.741s) [402.973762] Subtest non-zero-reason: SUCCESS (50.744s) [403.092796] [072/156] (666s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite) [403.320418] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite [403.328623] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [403.476962] [073/156] (666s left) i915_drm_fdinfo (all-busy-check-all) [403.604535] Starting subtest: all-busy-check-all [404.131696] Subtest all-busy-check-all: SUCCESS (0.522s) [404.291436] [074/156] (665s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt) [404.508104] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt [404.516015] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [404.660548] [075/156] (665s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [404.855532] Starting subtest: compare-crc-sanitycheck-nv12 [404.859855] Starting dynamic subtest: pipe-A-HDMI-A-1 [405.824773] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.960s) [405.864416] Starting dynamic subtest: pipe-B-HDMI-A-2 [406.511876] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.682s) [406.546957] Starting dynamic subtest: pipe-C-HDMI-A-1 [407.250819] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.730s) [407.256054] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.395s) [407.675325] [076/156] (662s left) gem_exec_reloc (basic-write-gtt) [407.800654] Starting subtest: basic-write-gtt [407.821032] Subtest basic-write-gtt: SUCCESS (0.019s) [408.077905] [077/156] (662s left) kms_cursor_crc (cursor-rapid-movement-max-size) [408.304968] Starting subtest: cursor-rapid-movement-max-size [408.313449] Subtest cursor-rapid-movement-max-size: SKIP (0.000s) [408.465864] [078/156] (662s left) kms_color_pipeline (plane-lut1d) [408.668694] Starting subtest: plane-lut1d [410.352606] Subtest plane-lut1d: SKIP (1.682s) [410.686961] [079/156] (659s left) kms_big_fb (yf-tiled-16bpp-rotate-180) [410.906412] Starting subtest: yf-tiled-16bpp-rotate-180 [414.605789] Subtest yf-tiled-16bpp-rotate-180: SUCCESS (3.696s) [415.000196] [080/156] (655s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0) [415.214068] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0 [415.221578] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s) [415.382948] [081/156] (655s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt) [415.611932] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt [415.619378] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [415.763549] [082/156] (655s left) gem_exec_reloc (basic-wc-read) [415.898404] Starting subtest: basic-wc-read [415.912168] Subtest basic-wc-read: SUCCESS (0.017s) [416.165819] [083/156] (654s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle) [416.355448] Starting subtest: short-busy-flip-before-cursor-toggle [416.364934] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s) [416.519271] [084/156] (654s left) kms_flip (2x-plain-flip-fb-recreate-interruptible) [416.734944] Starting subtest: 2x-plain-flip-fb-recreate-interruptible [416.745583] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2 [427.862944] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.117s) [427.900258] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2 [438.858053] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.991s) [438.895256] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2 [449.898478] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (11.030s) [449.901776] Subtest 2x-plain-flip-fb-recreate-interruptible: SUCCESS (33.167s) [450.585320] [085/156] (620s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [450.796480] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu [450.803648] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [450.955167] [086/156] (620s left) kms_draw_crc (draw-method-render) [451.166423] Starting subtest: draw-method-render [451.170644] Starting dynamic subtest: xrgb8888-untiled [451.727933] Dynamic subtest xrgb8888-untiled: SUCCESS (0.564s) [451.760010] Starting dynamic subtest: rgb565-untiled [452.027678] Dynamic subtest rgb565-untiled: SUCCESS (0.295s) [452.064295] Starting dynamic subtest: xrgb2101010-untiled [452.344594] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.312s) [452.378044] Starting dynamic subtest: xbgr16161616f-untiled [452.680638] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.330s) [452.713329] Starting dynamic subtest: xrgb8888-xtiled [452.894771] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.212s) [452.932434] Starting dynamic subtest: rgb565-xtiled [453.094513] Dynamic subtest rgb565-xtiled: SUCCESS (0.195s) [453.134574] Starting dynamic subtest: xrgb2101010-xtiled [453.278189] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.180s) [453.312551] Starting dynamic subtest: xbgr16161616f-xtiled [453.460776] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.179s) [453.495842] Starting dynamic subtest: xrgb8888-ytiled [453.661673] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.197s) [453.693782] Starting dynamic subtest: rgb565-ytiled [453.860684] Dynamic subtest rgb565-ytiled: SUCCESS (0.195s) [453.898731] Starting dynamic subtest: xrgb2101010-ytiled [454.043408] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.179s) [454.082163] Starting dynamic subtest: xbgr16161616f-ytiled [454.226083] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.180s) [454.229677] Subtest draw-method-render: SUCCESS (3.066s) [454.504826] [087/156] (617s left) kms_cursor_crc (cursor-sliding-256x85) [454.734652] Starting subtest: cursor-sliding-256x85 [454.783913] Starting dynamic subtest: pipe-A-HDMI-A-1 [458.904519] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.009s) [459.000919] Starting dynamic subtest: pipe-C-HDMI-A-1 [462.586069] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.730s) [462.593434] Subtest cursor-sliding-256x85: SUCCESS (7.856s) [462.978426] [088/156] (608s left) kms_cursor_crc (cursor-onscreen-256x256) [463.201473] Starting subtest: cursor-onscreen-256x256 [463.268808] Starting dynamic subtest: pipe-A-HDMI-A-1 [465.989831] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.719s) [466.125454] Starting dynamic subtest: pipe-C-HDMI-A-1 [468.471920] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.405s) [468.475219] Subtest cursor-onscreen-256x256: SUCCESS (5.272s) [468.849698] [089/156] (602s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render) [469.080602] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render [469.086915] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s) [469.272576] [090/156] (602s left) kms_frontbuffer_tracking (fbcpsr-slowdraw) [469.488633] Starting subtest: fbcpsr-slowdraw [469.495455] Subtest fbcpsr-slowdraw: SKIP (0.000s) [469.646935] [091/156] (602s left) kms_lease (cursor-implicit-plane) [469.843860] Starting subtest: cursor-implicit-plane [469.848840] Starting dynamic subtest: pipe-A-HDMI-A-1 [470.537131] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.686s) [470.605240] Starting dynamic subtest: pipe-A-HDMI-A-2 [470.983154] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.447s) [471.028998] Starting dynamic subtest: pipe-B-HDMI-A-1 [471.428261] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.436s) [471.475334] Starting dynamic subtest: pipe-B-HDMI-A-2 [471.895488] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.460s) [471.948773] Starting dynamic subtest: pipe-C-HDMI-A-1 [472.334016] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.429s) [472.379716] Starting dynamic subtest: pipe-C-HDMI-A-2 [472.795421] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.442s) [472.803124] Subtest cursor-implicit-plane: SUCCESS (2.951s) [473.174872] [092/156] (599s left) syncobj_wait (invalid-reset-zero-handles) [473.294480] Starting subtest: invalid-reset-zero-handles [473.299084] Subtest invalid-reset-zero-handles: SUCCESS (0.000s) [473.465284] [093/156] (598s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render) [473.687937] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render [475.788785] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SUCCESS (2.110s) [476.016753] [094/156] (596s left) kms_big_fb (yf-tiled-64bpp-rotate-90) [476.239674] Starting subtest: yf-tiled-64bpp-rotate-90 [476.246623] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s) [476.404588] [095/156] (596s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc) [476.612734] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc [476.620235] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [476.783114] [096/156] (595s left) gem_exec_fence (long-history) [477.338795] Starting subtest: long-history [483.046846] Subtest long-history: SUCCESS (5.711s) [483.828442] [097/156] (588s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt) [484.063307] Starting subtest: psr-1p-primscrn-indfb-msflip-blt [484.070881] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [484.215160] [098/156] (588s left) kms_ccs (bad-rotation-90-yf-tiled-ccs) [484.402437] Starting subtest: bad-rotation-90-yf-tiled-ccs [484.405928] Starting dynamic subtest: pipe-A-HDMI-A-1 [484.595831] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.147s) [484.661530] Starting dynamic subtest: pipe-A-HDMI-A-2 [484.712872] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.076s) [484.775444] Starting dynamic subtest: pipe-B-HDMI-A-1 [484.826670] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.064s) [484.896329] Starting dynamic subtest: pipe-B-HDMI-A-2 [484.928977] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.074s) [484.960556] Starting dynamic subtest: pipe-C-HDMI-A-1 [484.996086] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s) [485.026492] Starting dynamic subtest: pipe-C-HDMI-A-2 [485.063451] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s) [485.066888] Subtest bad-rotation-90-yf-tiled-ccs: SUCCESS (0.402s) [485.884479] [099/156] (587s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic) [486.087256] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic [492.669992] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (6.579s) [493.077707] [100/156] (580s left) kms_chamelium_audio (dp-audio-edid) [493.711449] Subtest dp-audio-edid: SKIP (0.000s) [494.115391] [101/156] (579s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [494.315275] Starting subtest: addfb25-yf-tiled-legacy [494.319908] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [494.475158] [102/156] (578s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt) [494.707320] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt [494.714572] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [494.875951] [103/156] (578s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff) [495.100512] Starting subtest: psr-2p-primscrn-cur-indfb-onoff [495.107313] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [495.276506] [104/156] (578s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw) [495.491139] Starting subtest: fbc-2p-pri-indfb-multidraw [512.288690] Subtest fbc-2p-pri-indfb-multidraw: SUCCESS (16.806s) [512.545521] [105/156] (561s left) kms_sequence (queue-busy) [512.755665] Starting subtest: queue-busy [512.758962] Starting dynamic subtest: pipe-A-HDMI-A-1 [515.656718] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.901s) [515.719408] Starting dynamic subtest: pipe-A-HDMI-A-2 [518.290627] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.626s) [518.337754] Starting dynamic subtest: pipe-B-HDMI-A-1 [520.994646] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.690s) [521.037822] Starting dynamic subtest: pipe-B-HDMI-A-2 [523.629558] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.631s) [523.691914] Starting dynamic subtest: pipe-C-HDMI-A-1 [526.265436] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.622s) [526.316385] Starting dynamic subtest: pipe-C-HDMI-A-2 [528.929098] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.660s) [528.935456] Subtest queue-busy: SUCCESS (16.173s) [529.301663] [106/156] (544s left) kms_plane_scaling (plane-upscale-20x20-with-rotation) [529.507593] Starting subtest: plane-upscale-20x20-with-rotation [529.512606] Starting dynamic subtest: pipe-A [535.412088] Dynamic subtest pipe-A: SKIP (5.899s) [535.477847] Starting dynamic subtest: pipe-B [540.183699] Dynamic subtest pipe-B: SKIP (4.766s) [540.245655] Starting dynamic subtest: pipe-C [544.613803] Dynamic subtest pipe-C: SKIP (4.430s) [544.621731] Subtest plane-upscale-20x20-with-rotation: SKIP (15.106s) [545.217925] [107/156] (528s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite) [545.489242] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite [545.496729] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [545.656074] [108/156] (528s left) gem_exec_reloc (basic-write-wc) [545.788628] Starting subtest: basic-write-wc [545.810641] Subtest basic-write-wc: SUCCESS (0.020s) [546.086811] [109/156] (528s left) syncobj_timeline (reset-signaled) [546.214069] Starting subtest: reset-signaled [546.217546] Subtest reset-signaled: SUCCESS (0.002s) [546.370539] [110/156] (527s left) kms_psr (pr-cursor-plane-move) [546.577863] Subtest pr-cursor-plane-move: SKIP (0.000s) [546.714685] [111/156] (527s left) syncobj_wait (invalid-wait-illegal-handle) [546.839380] Starting subtest: invalid-wait-illegal-handle [546.843924] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s) [547.004495] [112/156] (527s left) kms_busy (extended-pageflip-modeset-hang-oldfb) [547.232905] Starting subtest: extended-pageflip-modeset-hang-oldfb [547.236316] Starting dynamic subtest: pipe-A [551.595213] Dynamic subtest pipe-A: SUCCESS (4.364s) [551.668633] Starting dynamic subtest: pipe-C [555.608552] Dynamic subtest pipe-C: SUCCESS (3.999s) [555.611760] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (8.384s) [556.059767] [113/156] (518s left) kms_psr (psr2-primary-blt) [556.255655] Subtest psr2-primary-blt: SKIP (0.000s) [556.391028] [114/156] (518s left) gem_softpin (evict-snoop-interruptible) [556.523698] Starting subtest: evict-snoop-interruptible [556.532039] Subtest evict-snoop-interruptible: SKIP (0.000s) [556.688608] [115/156] (518s left) kms_force_connector_basic (force-load-detect) [556.989393] Starting subtest: force-load-detect [556.998385] Subtest force-load-detect: SKIP (0.000s) [557.382626] [116/156] (517s left) kms_chamelium_edid (hdmi-mode-timings) [557.999829] Subtest hdmi-mode-timings: SKIP (0.000s) [558.336194] [117/156] (516s left) syncobj_timeline (invalid-signal-zero-handles) [558.460605] Starting subtest: invalid-signal-zero-handles [558.466067] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [558.632034] [118/156] (516s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt) [558.859443] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt [558.867534] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [559.014663] [119/156] (516s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [559.240627] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt [559.247064] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [559.397568] [120/156] (516s left) gem_mmap_gtt (fault-concurrent-x) [559.512313] Starting subtest: fault-concurrent-X [562.732344] Subtest fault-concurrent-X: SUCCESS (3.223s) [562.913527] [121/156] (512s left) gem_render_copy (y-tiled-to-vebox-y-tiled) [563.037824] Starting subtest: y-tiled-to-vebox-y-tiled [563.046013] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s) [563.326398] [122/156] (512s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt) [563.551301] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt [563.557684] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s) [563.711649] [123/156] (512s left) vgem_basic (debugfs) [563.848849] Starting subtest: debugfs [563.852804] Subtest debugfs: SUCCESS (0.001s) [564.025243] [124/156] (511s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling) [564.217764] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling [564.221770] Starting dynamic subtest: pipe-A-valid-mode [565.279540] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.051s) [565.287685] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.057s) [565.708438] [125/156] (510s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite) [565.951268] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite [569.465883] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (3.523s) [569.680495] [126/156] (506s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu) [569.888099] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu [569.894713] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [570.049274] [127/156] (506s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt) [570.277765] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt [570.285630] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [570.429155] [128/156] (505s left) kms_vblank (wait-forked-hang) [570.630008] Starting subtest: wait-forked-hang [570.633398] Starting dynamic subtest: pipe-A-HDMI-A-1 [577.670151] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (7.033s) [577.733288] Starting dynamic subtest: pipe-A-HDMI-A-2 [584.640949] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (6.967s) [584.713839] Starting dynamic subtest: pipe-C-HDMI-A-1 [591.590489] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (6.936s) [591.645526] Starting dynamic subtest: pipe-C-HDMI-A-2 [598.644830] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (7.045s) [598.649261] Subtest wait-forked-hang: SUCCESS (28.023s) [599.080611] [129/156] (477s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling) [599.285225] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling [599.292376] Starting dynamic subtest: pipe-A-valid-mode [600.166176] Dynamic subtest pipe-A-valid-mode: SKIP (0.877s) [600.173490] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (0.885s) [600.831216] [130/156] (475s left) gem_ctx_exec (basic-invalid-context) [601.448556] Starting subtest: basic-invalid-context [601.518598] Subtest basic-invalid-context: SUCCESS (0.006s) [602.005314] [131/156] (475s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt) [602.442179] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt [602.449457] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [602.608408] [132/156] (474s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf) [602.811177] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s) [602.948881] [133/156] (474s left) gem_ppgtt (blt-vs-render-ctxn) [603.085607] Starting subtest: blt-vs-render-ctxN [636.052716] Subtest blt-vs-render-ctxN: SUCCESS (32.975s) [636.254200] [134/156] (441s left) gem_exec_reloc (basic-gtt-cpu-noreloc) [636.533317] Starting subtest: basic-gtt-cpu-noreloc [636.544778] Subtest basic-gtt-cpu-noreloc: SUCCESS (0.009s) [636.790809] [135/156] (440s left) gem_softpin (invalid) [636.922633] Starting subtest: invalid [636.933642] Subtest invalid: SUCCESS (0.012s) [637.119405] [136/156] (440s left) gem_exec_capture (capture) [637.245461] Starting subtest: capture [637.251862] Starting dynamic subtest: rcs0-smem [638.535863] Dynamic subtest rcs0-smem: SUCCESS (1.291s) [638.572884] Starting dynamic subtest: bcs0-smem [639.834011] Dynamic subtest bcs0-smem: SUCCESS (1.286s) [639.872695] Starting dynamic subtest: vcs0-smem [641.113270] Dynamic subtest vcs0-smem: SUCCESS (1.279s) [641.148877] Starting dynamic subtest: vecs0-smem [642.401164] Dynamic subtest vecs0-smem: SUCCESS (1.283s) [642.405587] Subtest capture: SUCCESS (5.153s) [642.550539] [137/156] (435s left) gem_tiled_blits (basic) [642.787673] Starting subtest: basic [642.818916] Subtest basic: SUCCESS (0.038s) [643.023131] [138/156] (434s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt) [643.423516] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt [643.430692] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [643.590940] [139/156] (434s left) gem_mmap_offset (partial-remap) [643.714778] Starting subtest: partial-remap [643.718461] Starting dynamic subtest: smem0 [643.738159] Dynamic subtest smem0: SUCCESS (0.010s) [643.742968] Subtest partial-remap: SUCCESS (0.014s) [643.882417] [140/156] (434s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt) [644.098420] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt [644.105029] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s) [644.271729] [141/156] (434s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt) [644.482441] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt [644.490851] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s) [644.634999] [142/156] (433s left) i915_power (sanity) [644.747640] Starting subtest: sanity [648.775832] Subtest sanity: SUCCESS (4.024s) [648.948568] [143/156] (429s left) gem_exec_schedule (preempt-queue) [649.111604] Starting subtest: preempt-queue [649.115525] Starting dynamic subtest: rcs0 [649.413558] Dynamic subtest rcs0: SUCCESS (0.293s) [649.452511] Starting dynamic subtest: bcs0 [649.706114] Dynamic subtest bcs0: SUCCESS (0.295s) [649.751565] Starting dynamic subtest: vcs0 [649.999020] Dynamic subtest vcs0: SUCCESS (0.293s) [650.043728] Starting dynamic subtest: vecs0 [650.288581] Dynamic subtest vecs0: SUCCESS (0.286s) [650.292227] Subtest preempt-queue: SUCCESS (1.181s) [650.728547] [144/156] (427s left) kms_cursor_legacy (cursorb-vs-flipa-legacy) [650.928843] Starting subtest: cursorB-vs-flipA-legacy [662.261010] Subtest cursorB-vs-flipA-legacy: SUCCESS (11.339s) [662.610723] [145/156] (416s left) i915_pm_rps (reset) [662.745857] Starting subtest: reset [667.331105] Subtest reset: SUCCESS (4.591s) [667.490148] [146/156] (411s left) kms_psr (fbc-psr-cursor-plane-onoff) [667.680910] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s) [667.817029] [147/156] (411s left) kms_setmode (clone-exclusive-crtc) [667.935765] Starting subtest: clone-exclusive-crtc [668.432436] Starting dynamic subtest: pipe-A-HDMI-A-1-pipe-B-HDMI-A-2 [668.778791] Dynamic subtest pipe-A-HDMI-A-1-pipe-B-HDMI-A-2: SUCCESS (0.343s) [669.339855] Starting dynamic subtest: pipe-B-HDMI-A-1-pipe-A-HDMI-A-2 [669.633778] Dynamic subtest pipe-B-HDMI-A-1-pipe-A-HDMI-A-2: SUCCESS (0.322s) [670.112297] Subtest clone-exclusive-crtc: SUCCESS (2.181s) [670.502241] [148/156] (408s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu) [670.722040] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu [670.728382] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [670.890172] [149/156] (408s left) kms_flip (flip-vs-rmfb) [671.089185] Starting subtest: flip-vs-rmfb [671.093407] Starting dynamic subtest: A-HDMI-A1 [676.802517] Dynamic subtest A-HDMI-A1: SUCCESS (5.717s) [676.839734] Starting dynamic subtest: B-HDMI-A1 [682.251692] Dynamic subtest B-HDMI-A1: SUCCESS (5.443s) [682.286565] Starting dynamic subtest: C-HDMI-A1 [687.719774] Dynamic subtest C-HDMI-A1: SUCCESS (5.463s) [687.754323] Starting dynamic subtest: A-HDMI-A2 [693.178104] Dynamic subtest A-HDMI-A2: SUCCESS (5.452s) [693.210586] Starting dynamic subtest: B-HDMI-A2 [698.650542] Dynamic subtest B-HDMI-A2: SUCCESS (5.467s) [698.684317] Starting dynamic subtest: C-HDMI-A2 [704.108433] Dynamic subtest C-HDMI-A2: SUCCESS (5.455s) [704.112633] Subtest flip-vs-rmfb: SUCCESS (33.038s) [704.667978] [150/156] (374s left) gem_exec_whisper (basic-contexts-all) [704.808653] Starting subtest: basic-contexts-all [717.332783] Subtest basic-contexts-all: SUCCESS (12.523s) [717.617562] [151/156] (361s left) dmabuf_sync_file (export-basic) [717.743989] Starting subtest: export-basic [717.747827] Subtest export-basic: SUCCESS (0.003s) [717.895158] [152/156] (361s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu) [718.123698] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu [718.130132] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [718.283548] [153/156] (361s left) gem_sync (basic-each) [718.439854] Starting subtest: basic-each [720.687970] Subtest basic-each: SUCCESS (2.268s) [720.964452] [154/156] (358s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc) [721.157866] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc [721.162121] Starting dynamic subtest: pipe-A-HDMI-A-1 [721.216903] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s) [721.285848] Starting dynamic subtest: pipe-A-HDMI-A-2 [721.324724] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s) [721.357764] Starting dynamic subtest: pipe-B-HDMI-A-1 [721.392920] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s) [721.424542] Starting dynamic subtest: pipe-B-HDMI-A-2 [721.460881] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s) [721.497792] Starting dynamic subtest: pipe-C-HDMI-A-1 [721.533710] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s) [721.565229] Starting dynamic subtest: pipe-C-HDMI-A-2 [721.604027] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s) [721.610464] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.033s) [722.263251] [155/156] (357s left) syncobj_wait (multi-wait-for-submit-submitted) [722.386270] Starting subtest: multi-wait-for-submit-submitted [722.485661] Subtest multi-wait-for-submit-submitted: SUCCESS (0.103s) [722.651249] [156/156] (357s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt) [722.857854] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt [722.865597] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [723.009647] 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.