[89.391643] Initializing watchdogs [89.391831] /dev/watchdog0 [89.433550] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600] [89.447998] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [89.454543] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [89.462794] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear) [89.703829] Starting subtest: fbc-tiling-linear [92.022706] Subtest fbc-tiling-linear: SUCCESS (2.327s) [92.357311] [002/156] (957s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes) [92.472955] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s) [92.581657] [003/156] (957s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [92.795960] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc [92.804654] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [92.931392] [004/156] (957s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [93.149073] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt [93.158030] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [93.296742] [005/156] (957s left) kms_force_connector_basic (prune-stale-modes) [93.563193] Starting subtest: prune-stale-modes [93.727067] Subtest prune-stale-modes: SUCCESS (0.158s) [94.051787] [006/156] (956s left) perf_pmu (most-busy-check-all) [94.187307] Starting subtest: most-busy-check-all [94.191048] Starting dynamic subtest: rcs0 [94.721612] Dynamic subtest rcs0: SUCCESS (0.526s) [94.743914] Starting dynamic subtest: bcs0 [95.243242] Dynamic subtest bcs0: SUCCESS (0.529s) [95.267130] Starting dynamic subtest: vcs0 [95.769803] Dynamic subtest vcs0: SUCCESS (0.525s) [95.797420] Starting dynamic subtest: vecs0 [96.300846] Dynamic subtest vecs0: SUCCESS (0.531s) [96.305126] Subtest most-busy-check-all: SUCCESS (2.115s) [96.455510] [007/156] (954s left) gem_ccs (large-ctrl-surf-copy) [96.580483] Subtest large-ctrl-surf-copy: SKIP (0.000s) [96.687949] [008/156] (953s left) kms_plane_alpha_blend (coverage-7efc) [96.867138] Starting subtest: coverage-7efc [97.074936] Starting dynamic subtest: pipe-A-HDMI-A-1 [98.056266] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.984s) [98.176318] Starting dynamic subtest: pipe-C-HDMI-A-1 [99.223185] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.065s) [99.227502] Subtest coverage-7efc: SUCCESS (2.335s) [99.612931] [009/156] (951s left) gem_mmap_gtt (fault-concurrent) [99.737896] Starting subtest: fault-concurrent [103.066918] Subtest fault-concurrent: SUCCESS (3.327s) [103.224359] [010/156] (947s left) api_intel_allocator (gem-pool) [103.337139] Starting subtest: gem-pool [103.353777] Subtest gem-pool: SUCCESS (0.018s) [103.510814] [011/156] (947s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen) [103.722889] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen [103.730664] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [103.863304] [012/156] (947s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier) [104.061502] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier [104.068279] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s) [104.205170] [013/156] (946s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs) [104.393824] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs [104.400448] Starting dynamic subtest: pipe-A-HDMI-A-1 [105.021793] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.607s) [105.048346] Starting dynamic subtest: pipe-A-HDMI-A-2 [105.483339] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.461s) [105.526079] Starting dynamic subtest: pipe-B-HDMI-A-1 [106.000487] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.513s) [106.026069] Starting dynamic subtest: pipe-B-HDMI-A-2 [106.433800] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.435s) [106.475168] Starting dynamic subtest: pipe-C-HDMI-A-1 [106.513801] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s) [106.536106] Starting dynamic subtest: pipe-C-HDMI-A-2 [106.568097] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s) [106.571813] Subtest crc-primary-rotation-180-yf-tiled-ccs: SUCCESS (2.037s) [107.289277] [014/156] (943s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs) [107.479183] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs [107.483111] Starting dynamic subtest: pipe-A-HDMI-A-1 [107.534265] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s) [107.585492] Starting dynamic subtest: pipe-A-HDMI-A-2 [107.621636] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s) [107.643777] Starting dynamic subtest: pipe-B-HDMI-A-1 [107.679542] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s) [107.702811] Starting dynamic subtest: pipe-B-HDMI-A-2 [107.734896] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s) [107.756978] Starting dynamic subtest: pipe-C-HDMI-A-1 [107.792490] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s) [107.814513] Starting dynamic subtest: pipe-C-HDMI-A-2 [107.846884] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s) [107.853449] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.016s) [108.485227] [015/156] (942s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt) [108.711113] Starting subtest: hdr-rgb565-draw-blt [108.718295] Subtest hdr-rgb565-draw-blt: SKIP (0.000s) [108.850333] [016/156] (942s left) kms_pm_rpm (legacy-planes-dpms) [110.764249] Starting subtest: legacy-planes-dpms [110.768649] Starting dynamic subtest: plane-45 [112.298839] Dynamic subtest plane-45: SUCCESS (1.534s) [112.334259] Starting dynamic subtest: plane-54 [113.832175] Dynamic subtest plane-54: SUCCESS (1.531s) [113.868979] Starting dynamic subtest: plane-63 [115.378363] Dynamic subtest plane-63: SUCCESS (1.546s) [115.382598] Subtest legacy-planes-dpms: SUCCESS (4.618s) [115.797404] [017/156] (935s left) gem_render_copy (x-tiled-to-vebox-y-tiled) [115.930155] Starting subtest: x-tiled-to-vebox-y-tiled [115.938242] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s) [116.560676] [018/156] (935s left) sysfs_heartbeat_interval (invalid) [117.024339] Starting subtest: invalid [117.090628] Starting dynamic subtest: vecs0 [117.157766] Dynamic subtest vecs0: SUCCESS (0.001s) [117.224863] Starting dynamic subtest: vcs0 [117.324275] Dynamic subtest vcs0: SUCCESS (0.001s) [117.507509] Starting dynamic subtest: bcs0 [117.512138] Dynamic subtest bcs0: SUCCESS (0.001s) [117.544628] Starting dynamic subtest: rcs0 [117.548438] Dynamic subtest rcs0: SUCCESS (0.001s) [117.552134] Subtest invalid: SUCCESS (0.012s) [117.664368] [019/156] (934s left) kms_frontbuffer_tracking (fbc-stridechange) [117.875878] Starting subtest: fbc-stridechange [119.820740] Subtest fbc-stridechange: SUCCESS (1.954s) [120.132417] [020/156] (932s left) gem_ctx_param (basic-default) [120.248093] Starting subtest: basic-default [120.255125] Subtest basic-default: SUCCESS (0.000s) [120.381475] [021/156] (931s left) kms_psr (fbc-pr-primary-mmap-gtt) [120.575097] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s) [120.677784] [022/156] (931s left) i915_pm_freq_mult (media-freq) [120.796912] Starting subtest: media-freq [120.800382] Starting dynamic subtest: gt0 [120.810219] Dynamic subtest gt0: SKIP (0.001s) [120.817983] Subtest media-freq: SKIP (0.003s) [120.961542] [023/156] (931s left) prime_busy (before-wait) [121.085112] Starting subtest: before-wait [121.101348] Starting dynamic subtest: rcs0 [121.113651] Dynamic subtest rcs0: SUCCESS (0.016s) [121.139506] Starting dynamic subtest: bcs0 [121.144484] Dynamic subtest bcs0: SUCCESS (0.017s) [121.170999] Starting dynamic subtest: vcs0 [121.174969] Dynamic subtest vcs0: SUCCESS (0.015s) [121.197394] Starting dynamic subtest: vecs0 [121.201430] Dynamic subtest vecs0: SUCCESS (0.014s) [121.278509] Subtest before-wait: SUCCESS (0.194s) [121.415169] [024/156] (931s left) gem_exec_gttfill (engines) [121.558835] Starting subtest: engines [121.565599] Starting dynamic subtest: rcs0 [149.262638] Dynamic subtest rcs0: SUCCESS (27.708s) [149.294513] Starting dynamic subtest: bcs0 [178.516230] Dynamic subtest bcs0: SUCCESS (29.253s) [178.546427] Starting dynamic subtest: vcs0 [206.433498] Dynamic subtest vcs0: SUCCESS (27.915s) [206.466316] Starting dynamic subtest: vecs0 [234.260475] Dynamic subtest vecs0: SUCCESS (27.825s) [234.264411] Subtest engines: SUCCESS (112.710s) [234.539974] [025/156] (818s left) syncobj_timeline (etime-multi-wait-all-submitted) [234.825283] Starting subtest: etime-multi-wait-all-submitted [234.929665] Subtest etime-multi-wait-all-submitted: SUCCESS (0.105s) [235.101512] [026/156] (817s left) gem_ctx_param (get-priority-new-ctx) [235.216068] Starting subtest: get-priority-new-ctx [235.219659] Subtest get-priority-new-ctx: SUCCESS (0.000s) [235.363339] [027/156] (817s left) kms_big_fb (linear-32bpp-rotate-90) [235.597888] Starting subtest: linear-32bpp-rotate-90 [235.974735] Subtest linear-32bpp-rotate-90: SKIP (0.373s) [236.278279] [028/156] (816s left) gem_lmem_swapping (heavy-verify-multi-ccs) [236.410264] Subtest heavy-verify-multi-ccs: SKIP (0.000s) [236.524417] [029/156] (816s left) gem_ctx_shared (disjoint-timelines) [236.660662] Starting subtest: disjoint-timelines [236.747374] Subtest disjoint-timelines: SUCCESS (0.086s) [237.036645] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true [237.044546] [030/156] (816s left) kms_psr (fbc-psr2-cursor-plane-move) [237.254949] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s) [237.371891] [031/156] (815s left) gem_bad_reloc (negative-reloc-bltcopy) [237.502616] Starting subtest: negative-reloc-bltcopy [240.735486] Subtest negative-reloc-bltcopy: SUCCESS (3.233s) [241.144291] [032/156] (812s left) kms_chamelium_hpd (hdmi-hpd) [241.715893] Subtest hdmi-hpd: SKIP (0.000s) [242.051195] [033/156] (811s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf) [242.257548] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s) [242.390318] [034/156] (811s left) gem_eio (wait-10ms) [242.533439] Starting subtest: wait-10ms [242.650521] Subtest wait-10ms: SUCCESS (0.115s) [242.883380] [035/156] (810s left) kms_content_protection (legacy-hdcp14) [243.114897] Starting subtest: legacy-hdcp14 [243.134640] Subtest legacy-hdcp14: SKIP (0.013s) [243.280998] [036/156] (810s left) syncobj_wait (reset-signaled) [243.398403] Starting subtest: reset-signaled [243.402328] Subtest reset-signaled: SUCCESS (0.001s) [243.562199] [037/156] (810s left) gem_ctx_isolation (dirty-switch) [243.692149] Starting subtest: dirty-switch [243.697951] Starting dynamic subtest: rcs0 [244.287698] Dynamic subtest rcs0: SUCCESS (0.590s) [244.320210] Starting dynamic subtest: bcs0 [245.253929] Dynamic subtest bcs0: SUCCESS (0.967s) [245.284534] Starting dynamic subtest: vcs0 [245.837509] Dynamic subtest vcs0: SUCCESS (0.584s) [245.867252] Starting dynamic subtest: vecs0 [246.431984] Dynamic subtest vecs0: SUCCESS (0.591s) [246.436207] Subtest dirty-switch: SUCCESS (2.740s) [246.723629] [038/156] (807s left) gem_flink_basic (bad-open) [246.836917] Starting subtest: bad-open [246.840380] Subtest bad-open: SUCCESS (0.000s) [246.994229] [039/156] (807s left) gem_caching (writes) [247.122840] Starting subtest: writes [261.412098] Subtest writes: SUCCESS (14.293s) [261.574595] [040/156] (792s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers) [261.754528] Starting subtest: plane-scaler-unity-scaling-with-modifiers [261.760287] Starting dynamic subtest: pipe-A [262.680621] Dynamic subtest pipe-A: SUCCESS (0.802s) [262.747170] Starting dynamic subtest: pipe-B [263.438264] Dynamic subtest pipe-B: SUCCESS (0.825s) [263.495122] Starting dynamic subtest: pipe-C [264.280488] Dynamic subtest pipe-C: SUCCESS (0.897s) [264.286434] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (2.529s) [264.850684] [041/156] (789s left) syncobj_timeline (single-wait-all-for-submit-available-submitted) [264.974152] Starting subtest: single-wait-all-for-submit-available-submitted [264.978271] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s) [265.132987] [042/156] (789s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render) [265.354307] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render [265.361985] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [265.519194] [043/156] (789s left) kms_pm_rpm (system-suspend-idle) [267.509136] Starting subtest: system-suspend-idle [284.430089] Subtest system-suspend-idle: SUCCESS (2.339s) [293.880809] [044/156] (769s left) kms_atomic_transition (modeset-transition) [294.088012] Starting subtest: modeset-transition [294.090978] Starting dynamic subtest: 1x-outputs [297.704015] Dynamic subtest 1x-outputs: SUCCESS (3.693s) [297.735405] Starting dynamic subtest: 2x-outputs [305.360457] Dynamic subtest 2x-outputs: SUCCESS (7.731s) [305.367519] Subtest modeset-transition: SUCCESS (11.431s) [305.757274] [045/156] (758s left) kms_frontbuffer_tracking (pipe-fbc-rte) [305.977531] Starting subtest: pipe-fbc-rte [305.995171] Subtest pipe-fbc-rte: SKIP (0.001s) [306.170623] [046/156] (757s left) gem_spin_batch (resubmit-all) [306.309706] Starting subtest: resubmit-all [306.314611] Starting dynamic subtest: rcs0 [306.324452] Dynamic subtest rcs0: SUCCESS (0.012s) [306.357749] Starting dynamic subtest: bcs0 [306.361555] Dynamic subtest bcs0: SUCCESS (0.009s) [306.390467] Starting dynamic subtest: vcs0 [306.394922] Dynamic subtest vcs0: SUCCESS (0.009s) [306.424203] Starting dynamic subtest: vecs0 [306.428370] Dynamic subtest vecs0: SUCCESS (0.009s) [306.432223] Subtest resubmit-all: SUCCESS (0.051s) [306.636352] [047/156] (757s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff) [306.841502] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff [306.848979] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [307.000270] [048/156] (757s left) syncobj_basic (illegal-fd-to-handle) [307.114587] Starting subtest: illegal-fd-to-handle [307.118420] Subtest illegal-fd-to-handle: SUCCESS (0.000s) [307.266615] [049/156] (756s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite) [307.476036] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite [310.095720] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SUCCESS (2.641s) [310.336700] [050/156] (754s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [310.564455] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [312.373143] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.816s) [312.697806] [051/156] (751s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted) [312.817142] Starting subtest: etime-single-wait-all-for-submit-unsubmitted [312.923495] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.109s) [313.080336] [052/156] (751s left) api_intel_bb (misplaced-blitter) [313.192128] Starting subtest: misplaced-blitter [313.209853] Subtest misplaced-blitter: SUCCESS (0.016s) [313.380706] [053/156] (751s left) kms_atomic_interruptible (universal-setplane-cursor) [313.581408] Starting subtest: universal-setplane-cursor [313.585112] Starting dynamic subtest: pipe-A-HDMI-A-1 [320.389349] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.811s) [320.394840] Subtest universal-setplane-cursor: SUCCESS (6.816s) [320.867454] [054/156] (744s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip) [321.523459] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip [321.655436] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [322.194174] [055/156] (743s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [322.399861] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc [322.407454] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.001s) [322.569446] [056/156] (742s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling) [322.759634] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling [322.765415] Starting dynamic subtest: pipe-A-valid-mode [323.819258] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.062s) [323.824248] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.067s) [324.269395] [057/156] (741s left) gem_pxp (create-protected-buffer) [326.930247] Starting subtest: create-protected-buffer [326.938406] Subtest create-protected-buffer: SKIP (0.000s) [327.086810] [058/156] (738s left) gem_fence_thrash (bo-write-verify-threaded-x) [327.199893] Starting subtest: bo-write-verify-threaded-x [331.907056] Subtest bo-write-verify-threaded-x: SUCCESS (4.716s) [332.069319] [059/156] (733s left) gen9_exec_parse (allowed-single) [332.208996] Starting subtest: allowed-single [332.218040] Subtest allowed-single: SUCCESS (0.011s) [332.499856] [060/156] (733s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu) [332.715045] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu [332.721057] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [332.872031] [061/156] (733s left) gem_exec_balancer (bonded-semaphore) [333.018130] Starting subtest: bonded-semaphore [337.317917] Subtest bonded-semaphore: SUCCESS (4.302s) [337.620643] [062/156] (728s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping) [337.827423] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping [337.833572] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s) [337.986686] [063/156] (728s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc) [338.218955] Starting subtest: fbc-rgb565-draw-mmap-wc [340.987890] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (2.777s) [341.318882] [064/156] (725s left) kms_pwrite_crc (basic) [341.510023] Starting subtest: basic [342.439219] Subtest basic: SUCCESS (0.931s) [342.834378] [065/156] (723s left) gem_flink_basic (bad-flink) [342.948488] Starting subtest: bad-flink [342.952683] Subtest bad-flink: SUCCESS (0.000s) [343.099876] [066/156] (723s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move) [343.318299] Starting subtest: hdr-2p-primscrn-spr-indfb-move [343.327005] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [343.475376] [067/156] (723s left) gem_ctx_persistence (legacy-engines-persistence) [343.604660] Starting subtest: legacy-engines-persistence [343.610164] Starting dynamic subtest: render [344.362997] Dynamic subtest render: SUCCESS (0.759s) [344.395301] Starting dynamic subtest: bsd [345.148748] Dynamic subtest bsd: SUCCESS (0.784s) [345.184731] Starting dynamic subtest: blt [345.912775] Dynamic subtest blt: SUCCESS (0.760s) [345.947893] Starting dynamic subtest: vebox [346.676643] Dynamic subtest vebox: SUCCESS (0.759s) [346.681209] Subtest legacy-engines-persistence: SUCCESS (3.081s) [346.852080] [068/156] (720s left) gen7_exec_parse (basic-allowed) [346.978251] Subtest basic-allowed: SKIP (0.000s) [347.091492] [069/156] (719s left) gem_exec_schedule (reorder-wide) [347.364257] Starting subtest: reorder-wide [347.368488] Starting dynamic subtest: rcs0 [347.534443] Dynamic subtest rcs0: SUCCESS (0.166s) [347.578016] Starting dynamic subtest: bcs0 [347.869234] Dynamic subtest bcs0: SUCCESS (0.330s) [347.912584] Starting dynamic subtest: vcs0 [348.040546] Dynamic subtest vcs0: SUCCESS (0.167s) [348.089868] Starting dynamic subtest: vecs0 [348.198066] Dynamic subtest vecs0: SUCCESS (0.154s) [348.202877] Subtest reorder-wide: SUCCESS (0.835s) [348.484229] [070/156] (718s left) kms_frontbuffer_tracking (psrhdr-slowdraw) [348.710008] Starting subtest: psrhdr-slowdraw [348.718123] Subtest psrhdr-slowdraw: SKIP (0.000s) [348.870237] [071/156] (718s left) perf (non-zero-reason) [349.142157] Starting subtest: non-zero-reason [349.148099] Starting dynamic subtest: 0-rcs0 [400.371244] Dynamic subtest 0-rcs0: SUCCESS (51.235s) [400.375384] Subtest non-zero-reason: SUCCESS (51.238s) [400.496647] [072/156] (666s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite) [400.717547] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite [400.724674] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [400.877597] [073/156] (666s left) i915_drm_fdinfo (all-busy-check-all) [401.005375] Starting subtest: all-busy-check-all [401.530864] Subtest all-busy-check-all: SUCCESS (0.526s) [401.693374] [074/156] (665s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt) [401.908635] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt [401.916058] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [402.078823] [075/156] (665s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [402.279062] Starting subtest: compare-crc-sanitycheck-nv12 [402.284140] Starting dynamic subtest: pipe-A-HDMI-A-1 [403.237247] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.953s) [403.278333] Starting dynamic subtest: pipe-B-HDMI-A-2 [403.939609] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.699s) [403.976538] Starting dynamic subtest: pipe-C-HDMI-A-1 [404.630873] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.681s) [404.635593] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.352s) [404.982411] [076/156] (662s left) gem_exec_reloc (basic-write-gtt) [405.106261] Starting subtest: basic-write-gtt [405.126033] Subtest basic-write-gtt: SUCCESS (0.017s) [405.371752] [077/156] (662s left) kms_cursor_crc (cursor-rapid-movement-max-size) [405.607856] Starting subtest: cursor-rapid-movement-max-size [405.618840] Subtest cursor-rapid-movement-max-size: SKIP (0.000s) [405.796658] [078/156] (662s left) kms_color_pipeline (plane-lut1d) [405.987548] Starting subtest: plane-lut1d [407.618243] Subtest plane-lut1d: SKIP (1.624s) [408.000844] [079/156] (660s left) kms_big_fb (yf-tiled-16bpp-rotate-180) [408.221034] Starting subtest: yf-tiled-16bpp-rotate-180 [412.106767] Subtest yf-tiled-16bpp-rotate-180: SUCCESS (3.885s) [412.485848] [080/156] (655s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0) [412.711816] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0 [412.721247] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s) [413.026307] [081/156] (655s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt) [413.747392] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt [413.879969] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [414.453885] [082/156] (654s left) gem_exec_reloc (basic-wc-read) [414.585933] Starting subtest: basic-wc-read [414.600783] Subtest basic-wc-read: SUCCESS (0.015s) [414.853932] [083/156] (654s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle) [415.062662] Starting subtest: short-busy-flip-before-cursor-toggle [415.069372] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s) [415.217210] [084/156] (654s left) kms_flip (2x-plain-flip-fb-recreate-interruptible) [415.412072] Starting subtest: 2x-plain-flip-fb-recreate-interruptible [415.423351] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2 [426.532786] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.112s) [426.571781] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2 [437.609503] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (11.071s) [437.648455] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2 [448.666722] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (11.049s) [448.673072] Subtest 2x-plain-flip-fb-recreate-interruptible: SUCCESS (33.259s) [449.677237] [085/156] (620s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [450.405417] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu [450.413700] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [450.522713] [086/156] (619s left) kms_draw_crc (draw-method-render) [450.722889] Starting subtest: draw-method-render [450.726390] Starting dynamic subtest: xrgb8888-untiled [451.273699] Dynamic subtest xrgb8888-untiled: SUCCESS (0.546s) [451.307810] Starting dynamic subtest: rgb565-untiled [451.572396] Dynamic subtest rgb565-untiled: SUCCESS (0.296s) [451.605427] Starting dynamic subtest: xrgb2101010-untiled [451.889474] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.314s) [451.925104] Starting dynamic subtest: xbgr16161616f-untiled [452.223097] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.329s) [452.255555] Starting dynamic subtest: xrgb8888-xtiled [452.423770] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.198s) [452.459445] Starting dynamic subtest: rgb565-xtiled [452.622004] Dynamic subtest rgb565-xtiled: SUCCESS (0.196s) [452.659327] Starting dynamic subtest: xrgb2101010-xtiled [452.806156] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.179s) [452.845133] Starting dynamic subtest: xbgr16161616f-xtiled [452.989917] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.180s) [453.024837] Starting dynamic subtest: xrgb8888-ytiled [453.188688] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.196s) [453.221454] Starting dynamic subtest: rgb565-ytiled [453.389033] Dynamic subtest rgb565-ytiled: SUCCESS (0.196s) [453.424529] Starting dynamic subtest: xrgb2101010-ytiled [453.574161] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.180s) [453.608001] Starting dynamic subtest: xbgr16161616f-ytiled [453.755461] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.179s) [453.759230] Subtest draw-method-render: SUCCESS (3.034s) [454.047696] [087/156] (615s left) kms_cursor_crc (cursor-sliding-256x85) [454.266183] Starting subtest: cursor-sliding-256x85 [454.320986] Starting dynamic subtest: pipe-A-HDMI-A-1 [458.362405] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.031s) [458.484702] Starting dynamic subtest: pipe-C-HDMI-A-1 [462.171016] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.743s) [462.177837] Subtest cursor-sliding-256x85: SUCCESS (7.898s) [462.580871] [088/156] (607s left) kms_cursor_crc (cursor-onscreen-256x256) [462.797553] Starting subtest: cursor-onscreen-256x256 [462.858874] Starting dynamic subtest: pipe-A-HDMI-A-1 [465.557261] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.698s) [465.685718] Starting dynamic subtest: pipe-C-HDMI-A-1 [468.062169] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.429s) [468.070031] Subtest cursor-onscreen-256x256: SUCCESS (5.265s) [468.423978] [089/156] (601s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render) [468.652004] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render [468.658309] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s) [468.845425] [090/156] (601s left) kms_frontbuffer_tracking (fbcpsr-slowdraw) [469.068074] Starting subtest: fbcpsr-slowdraw [469.074294] Subtest fbcpsr-slowdraw: SKIP (0.000s) [469.237874] [091/156] (601s left) kms_lease (cursor-implicit-plane) [469.428741] Starting subtest: cursor-implicit-plane [469.433064] Starting dynamic subtest: pipe-A-HDMI-A-1 [470.126645] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.690s) [470.193607] Starting dynamic subtest: pipe-A-HDMI-A-2 [470.574016] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.449s) [470.653368] Starting dynamic subtest: pipe-B-HDMI-A-1 [471.013048] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.428s) [471.075208] Starting dynamic subtest: pipe-B-HDMI-A-2 [471.460485] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.441s) [471.511494] Starting dynamic subtest: pipe-C-HDMI-A-1 [471.953278] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.486s) [472.004466] Starting dynamic subtest: pipe-C-HDMI-A-2 [472.407690] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.445s) [472.416413] Subtest cursor-implicit-plane: SUCCESS (2.980s) [472.749525] [092/156] (597s left) syncobj_wait (invalid-reset-zero-handles) [472.872166] Starting subtest: invalid-reset-zero-handles [472.876153] Subtest invalid-reset-zero-handles: SUCCESS (0.000s) [473.027563] [093/156] (597s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render) [473.250465] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render [475.299778] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SUCCESS (2.061s) [475.526838] [094/156] (595s left) kms_big_fb (yf-tiled-64bpp-rotate-90) [475.737140] Starting subtest: yf-tiled-64bpp-rotate-90 [475.745662] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s) [475.900993] [095/156] (595s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc) [476.133760] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc [476.141407] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.001s) [476.295807] [096/156] (594s left) gem_exec_fence (long-history) [476.854686] Starting subtest: long-history [482.532446] Subtest long-history: SUCCESS (5.681s) [483.247585] [097/156] (587s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt) [483.455038] Starting subtest: psr-1p-primscrn-indfb-msflip-blt [483.462139] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [483.638923] [098/156] (587s left) kms_ccs (bad-rotation-90-yf-tiled-ccs) [483.846027] Starting subtest: bad-rotation-90-yf-tiled-ccs [483.851364] Starting dynamic subtest: pipe-A-HDMI-A-1 [484.039260] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.165s) [484.099360] Starting dynamic subtest: pipe-A-HDMI-A-2 [484.143276] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.061s) [484.200230] Starting dynamic subtest: pipe-B-HDMI-A-1 [484.245174] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.060s) [484.303392] Starting dynamic subtest: pipe-B-HDMI-A-2 [484.344324] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.056s) [484.378579] Starting dynamic subtest: pipe-C-HDMI-A-1 [484.416722] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s) [484.449039] Starting dynamic subtest: pipe-C-HDMI-A-2 [484.484049] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s) [484.487452] Subtest bad-rotation-90-yf-tiled-ccs: SUCCESS (0.376s) [485.737645] [099/156] (586s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic) [486.200457] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic [492.793480] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (6.740s) [493.209986] [100/156] (578s left) kms_chamelium_audio (dp-audio-edid) [493.850744] Subtest dp-audio-edid: SKIP (0.000s) [494.261737] [101/156] (577s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [494.475777] Starting subtest: addfb25-yf-tiled-legacy [494.481566] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [494.638872] [102/156] (577s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt) [494.864592] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt [494.872166] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [495.026196] [103/156] (577s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff) [495.243603] Starting subtest: psr-2p-primscrn-cur-indfb-onoff [495.250437] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [495.406322] [104/156] (577s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw) [495.635496] Starting subtest: fbc-2p-pri-indfb-multidraw [512.438480] Subtest fbc-2p-pri-indfb-multidraw: SUCCESS (16.813s) [512.695143] [105/156] (559s left) kms_sequence (queue-busy) [512.892922] Starting subtest: queue-busy [512.896091] Starting dynamic subtest: pipe-A-HDMI-A-1 [515.780908] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.889s) [515.838739] Starting dynamic subtest: pipe-A-HDMI-A-2 [518.447093] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.663s) [518.489628] Starting dynamic subtest: pipe-B-HDMI-A-1 [521.095906] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.640s) [521.157534] Starting dynamic subtest: pipe-B-HDMI-A-2 [523.730554] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.622s) [523.787608] Starting dynamic subtest: pipe-C-HDMI-A-1 [526.347135] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.612s) [526.397323] Starting dynamic subtest: pipe-C-HDMI-A-2 [529.008290] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.654s) [529.014308] Subtest queue-busy: SUCCESS (16.114s) [529.512669] [106/156] (543s left) kms_plane_scaling (plane-upscale-20x20-with-rotation) [530.016391] Starting subtest: plane-upscale-20x20-with-rotation [530.086565] Starting dynamic subtest: pipe-A [534.328439] Dynamic subtest pipe-A: SKIP (4.450s) [534.398472] Starting dynamic subtest: pipe-B [539.366389] Dynamic subtest pipe-B: SKIP (5.033s) [539.435916] Starting dynamic subtest: pipe-C [545.213382] Dynamic subtest pipe-C: SKIP (5.845s) [545.221129] Subtest plane-upscale-20x20-with-rotation: SKIP (15.338s) [545.871606] [107/156] (527s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite) [546.149557] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite [546.156300] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [546.309930] [108/156] (526s left) gem_exec_reloc (basic-write-wc) [546.447384] Starting subtest: basic-write-wc [546.461535] Subtest basic-write-wc: SUCCESS (0.019s) [546.723881] [109/156] (526s left) syncobj_timeline (reset-signaled) [546.853438] Starting subtest: reset-signaled [546.858326] Subtest reset-signaled: SUCCESS (0.001s) [547.025266] [110/156] (526s left) kms_psr (pr-cursor-plane-move) [547.224025] Subtest pr-cursor-plane-move: SKIP (0.000s) [547.353180] [111/156] (526s left) syncobj_wait (invalid-wait-illegal-handle) [547.475219] Starting subtest: invalid-wait-illegal-handle [547.479092] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s) [547.641589] [112/156] (525s left) kms_busy (extended-pageflip-modeset-hang-oldfb) [547.862289] Starting subtest: extended-pageflip-modeset-hang-oldfb [547.867051] Starting dynamic subtest: pipe-A [552.438528] Dynamic subtest pipe-A: SUCCESS (4.572s) [552.502623] Starting dynamic subtest: pipe-C [556.372486] Dynamic subtest pipe-C: SUCCESS (3.918s) [556.375931] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (8.512s) [556.825332] [113/156] (516s left) kms_psr (psr2-primary-blt) [557.041615] Subtest psr2-primary-blt: SKIP (0.000s) [557.175167] [114/156] (516s left) gem_softpin (evict-snoop-interruptible) [557.307177] Starting subtest: evict-snoop-interruptible [557.316018] Subtest evict-snoop-interruptible: SKIP (0.000s) [557.478516] [115/156] (516s left) kms_force_connector_basic (force-load-detect) [557.764281] Starting subtest: force-load-detect [557.774824] Subtest force-load-detect: SKIP (0.000s) [558.146346] [116/156] (515s left) kms_chamelium_edid (hdmi-mode-timings) [558.759720] Subtest hdmi-mode-timings: SKIP (0.000s) [559.131003] [117/156] (514s left) syncobj_timeline (invalid-signal-zero-handles) [559.252124] Starting subtest: invalid-signal-zero-handles [559.255934] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [559.421011] [118/156] (514s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt) [559.714179] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt [559.720456] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [559.878737] [119/156] (514s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [560.106981] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt [560.114190] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [560.281490] [120/156] (514s left) gem_mmap_gtt (fault-concurrent-x) [560.404760] Starting subtest: fault-concurrent-X [563.689836] Subtest fault-concurrent-X: SUCCESS (3.288s) [563.871842] [121/156] (510s left) gem_render_copy (y-tiled-to-vebox-y-tiled) [564.002045] Starting subtest: y-tiled-to-vebox-y-tiled [564.010034] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s) [564.272075] [122/156] (510s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt) [564.494491] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt [564.500770] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s) [564.657419] [123/156] (510s left) vgem_basic (debugfs) [564.797443] Starting subtest: debugfs [564.801592] Subtest debugfs: SUCCESS (0.001s) [564.934849] [124/156] (509s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling) [565.131443] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling [565.138012] Starting dynamic subtest: pipe-A-valid-mode [566.086737] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.955s) [566.090580] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (0.960s) [566.461442] [125/156] (508s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite) [566.679484] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite [570.128997] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (3.459s) [570.341217] [126/156] (504s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu) [570.552670] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu [570.560069] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [570.726185] [127/156] (504s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt) [570.955842] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt [570.962809] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [571.111087] [128/156] (504s left) kms_vblank (wait-forked-hang) [571.294289] Starting subtest: wait-forked-hang [571.306180] Starting dynamic subtest: pipe-A-HDMI-A-1 [578.432062] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (7.125s) [578.491911] Starting dynamic subtest: pipe-A-HDMI-A-2 [585.459674] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (7.025s) [585.544147] Starting dynamic subtest: pipe-C-HDMI-A-1 [592.447031] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (6.971s) [592.511724] Starting dynamic subtest: pipe-C-HDMI-A-2 [599.413109] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (6.959s) [599.416593] Subtest wait-forked-hang: SUCCESS (28.117s) [599.817104] [129/156] (475s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling) [600.020333] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling [600.024188] Starting dynamic subtest: pipe-A-valid-mode [600.982013] Dynamic subtest pipe-A-valid-mode: SKIP (0.952s) [601.000103] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (0.960s) [601.577009] [130/156] (473s left) gem_ctx_exec (basic-invalid-context) [601.696061] Starting subtest: basic-invalid-context [601.700476] Subtest basic-invalid-context: SUCCESS (0.006s) [601.886134] [131/156] (473s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt) [602.110007] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt [602.116856] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [602.283668] [132/156] (473s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf) [602.483590] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s) [602.624298] [133/156] (473s left) gem_ppgtt (blt-vs-render-ctxn) [602.743058] Starting subtest: blt-vs-render-ctxN [635.681991] Subtest blt-vs-render-ctxN: SUCCESS (32.943s) [635.892361] [134/156] (440s left) gem_exec_reloc (basic-gtt-cpu-noreloc) [636.173429] Starting subtest: basic-gtt-cpu-noreloc [636.178369] Subtest basic-gtt-cpu-noreloc: SUCCESS (0.009s) [636.452663] [135/156] (439s left) gem_softpin (invalid) [636.572915] Starting subtest: invalid [636.589418] Subtest invalid: SUCCESS (0.013s) [636.771830] [136/156] (439s left) gem_exec_capture (capture) [636.900201] Starting subtest: capture [636.903609] Starting dynamic subtest: rcs0-smem [638.192021] Dynamic subtest rcs0-smem: SUCCESS (1.292s) [638.224254] Starting dynamic subtest: bcs0-smem [639.478614] Dynamic subtest bcs0-smem: SUCCESS (1.286s) [639.512662] Starting dynamic subtest: vcs0-smem [640.767166] Dynamic subtest vcs0-smem: SUCCESS (1.285s) [640.798451] Starting dynamic subtest: vecs0-smem [642.056504] Dynamic subtest vecs0-smem: SUCCESS (1.283s) [642.060292] Subtest capture: SUCCESS (5.158s) [642.205265] [137/156] (434s left) gem_tiled_blits (basic) [642.437521] Starting subtest: basic [642.463657] Subtest basic: SUCCESS (0.033s) [642.658444] [138/156] (433s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt) [643.065191] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt [643.071796] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [643.223419] [139/156] (433s left) gem_mmap_offset (partial-remap) [643.337800] Starting subtest: partial-remap [643.342510] Starting dynamic subtest: smem0 [643.362105] Dynamic subtest smem0: SUCCESS (0.010s) [643.366351] Subtest partial-remap: SUCCESS (0.014s) [643.550138] [140/156] (433s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt) [643.754585] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt [643.761677] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s) [643.925168] [141/156] (432s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt) [644.138847] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt [644.145450] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s) [644.302965] [142/156] (432s left) i915_power (sanity) [644.418318] Starting subtest: sanity [648.453308] Subtest sanity: SUCCESS (4.026s) [648.604865] [143/156] (428s left) gem_exec_schedule (preempt-queue) [648.779270] Starting subtest: preempt-queue [648.783089] Starting dynamic subtest: rcs0 [649.071630] Dynamic subtest rcs0: SUCCESS (0.295s) [649.116176] Starting dynamic subtest: bcs0 [649.365419] Dynamic subtest bcs0: SUCCESS (0.291s) [649.411215] Starting dynamic subtest: vcs0 [649.696003] Dynamic subtest vcs0: SUCCESS (0.326s) [649.737870] Starting dynamic subtest: vecs0 [649.987770] Dynamic subtest vecs0: SUCCESS (0.288s) [649.992560] Subtest preempt-queue: SUCCESS (1.214s) [650.403195] [144/156] (426s left) kms_cursor_legacy (cursorb-vs-flipa-legacy) [650.598095] Starting subtest: cursorB-vs-flipA-legacy [661.949031] Subtest cursorB-vs-flipA-legacy: SUCCESS (11.358s) [662.347686] [145/156] (414s left) i915_pm_rps (reset) [662.475828] Starting subtest: reset [667.073508] Subtest reset: SUCCESS (4.592s) [667.232435] [146/156] (410s left) kms_psr (fbc-psr-cursor-plane-onoff) [667.428802] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s) [667.563329] [147/156] (409s left) kms_setmode (clone-exclusive-crtc) [667.689972] Starting subtest: clone-exclusive-crtc [668.159192] Starting dynamic subtest: pipe-A-HDMI-A-1-pipe-B-HDMI-A-2 [668.480342] Dynamic subtest pipe-A-HDMI-A-1-pipe-B-HDMI-A-2: SUCCESS (0.319s) [669.012329] Starting dynamic subtest: pipe-B-HDMI-A-1-pipe-A-HDMI-A-2 [669.304478] Dynamic subtest pipe-B-HDMI-A-1-pipe-A-HDMI-A-2: SUCCESS (0.317s) [669.751538] Subtest clone-exclusive-crtc: SUCCESS (2.075s) [670.126694] [148/156] (407s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu) [670.339917] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu [670.348442] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [670.496794] [149/156] (407s left) kms_flip (flip-vs-rmfb) [670.696735] Starting subtest: flip-vs-rmfb [670.701009] Starting dynamic subtest: A-HDMI-A1 [676.430514] Dynamic subtest A-HDMI-A1: SUCCESS (5.730s) [676.468456] Starting dynamic subtest: B-HDMI-A1 [681.871756] Dynamic subtest B-HDMI-A1: SUCCESS (5.435s) [681.911264] Starting dynamic subtest: C-HDMI-A1 [687.310206] Dynamic subtest C-HDMI-A1: SUCCESS (5.433s) [687.349571] Starting dynamic subtest: A-HDMI-A2 [692.767287] Dynamic subtest A-HDMI-A2: SUCCESS (5.452s) [692.802204] Starting dynamic subtest: B-HDMI-A2 [698.220898] Dynamic subtest B-HDMI-A2: SUCCESS (5.449s) [698.260186] Starting dynamic subtest: C-HDMI-A2 [703.642623] Dynamic subtest C-HDMI-A2: SUCCESS (5.416s) [703.646401] Subtest flip-vs-rmfb: SUCCESS (32.956s) [704.200226] [150/156] (373s left) gem_exec_whisper (basic-contexts-all) [704.345118] Starting subtest: basic-contexts-all [715.906043] Subtest basic-contexts-all: SUCCESS (11.564s) [716.242274] [151/156] (361s left) dmabuf_sync_file (export-basic) [716.437169] Starting subtest: export-basic [716.442320] Subtest export-basic: SUCCESS (0.005s) [716.573895] [152/156] (361s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu) [716.843668] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu [716.851159] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [717.006825] [153/156] (361s left) gem_sync (basic-each) [717.167153] Starting subtest: basic-each [719.593184] Subtest basic-each: SUCCESS (2.447s) [719.867365] [154/156] (358s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc) [720.062619] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc [720.066449] Starting dynamic subtest: pipe-A-HDMI-A-1 [720.125351] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s) [720.188318] Starting dynamic subtest: pipe-A-HDMI-A-2 [720.223464] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s) [720.258416] Starting dynamic subtest: pipe-B-HDMI-A-1 [720.293429] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s) [720.324728] Starting dynamic subtest: pipe-B-HDMI-A-2 [720.359640] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s) [720.395165] Starting dynamic subtest: pipe-C-HDMI-A-1 [720.430252] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s) [720.461393] Starting dynamic subtest: pipe-C-HDMI-A-2 [720.502322] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s) [720.508811] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.032s) [721.212842] [155/156] (357s left) syncobj_wait (multi-wait-for-submit-submitted) [721.329031] Starting subtest: multi-wait-for-submit-submitted [721.435216] Subtest multi-wait-for-submit-submitted: SUCCESS (0.104s) [721.603278] [156/156] (356s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt) [721.828450] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt [721.835767] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [721.996112] 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.