[89.388470] Initializing watchdogs [89.388645] /dev/watchdog0 [89.430244] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600] [89.445299] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [89.451800] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [89.461226] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear) [89.707559] Starting subtest: fbc-tiling-linear [92.027477] Subtest fbc-tiling-linear: SUCCESS (2.332s) [92.351731] [002/156] (957s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes) [92.472174] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s) [92.579654] [003/156] (957s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [92.815269] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc [92.821694] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [92.963249] [004/156] (957s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [93.184961] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt [93.191710] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [93.321190] [005/156] (957s left) kms_force_connector_basic (prune-stale-modes) [93.615457] Starting subtest: prune-stale-modes [93.784970] Subtest prune-stale-modes: SUCCESS (0.174s) [94.120801] [006/156] (956s left) perf_pmu (most-busy-check-all) [94.251512] Starting subtest: most-busy-check-all [94.257163] Starting dynamic subtest: rcs0 [94.874761] Dynamic subtest rcs0: SUCCESS (0.530s) [94.941420] Starting dynamic subtest: bcs0 [95.398499] Dynamic subtest bcs0: SUCCESS (0.517s) [95.464971] Starting dynamic subtest: vcs0 [95.825132] Dynamic subtest vcs0: SUCCESS (0.525s) [95.847833] Starting dynamic subtest: vecs0 [96.352161] Dynamic subtest vecs0: SUCCESS (0.528s) [96.355671] Subtest most-busy-check-all: SUCCESS (2.104s) [96.503369] [007/156] (954s left) gem_ccs (large-ctrl-surf-copy) [96.629236] Subtest large-ctrl-surf-copy: SKIP (0.000s) [96.745977] [008/156] (953s left) kms_plane_alpha_blend (coverage-7efc) [96.923261] Starting subtest: coverage-7efc [97.135506] Starting dynamic subtest: pipe-A-HDMI-A-1 [98.128494] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.998s) [98.253109] Starting dynamic subtest: pipe-C-HDMI-A-1 [99.300133] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (1.084s) [99.310111] Subtest coverage-7efc: SUCCESS (2.371s) [99.700113] [009/156] (951s left) gem_mmap_gtt (fault-concurrent) [99.822530] Starting subtest: fault-concurrent [103.110223] Subtest fault-concurrent: SUCCESS (3.290s) [103.279082] [010/156] (947s left) api_intel_allocator (gem-pool) [103.396293] Starting subtest: gem-pool [103.409947] Subtest gem-pool: SUCCESS (0.019s) [103.560961] [011/156] (947s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen) [103.768327] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen [103.777710] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [103.915662] [012/156] (947s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier) [104.115105] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier [104.124590] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s) [104.250646] [013/156] (946s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs) [104.446149] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs [104.451541] Starting dynamic subtest: pipe-A-HDMI-A-1 [105.066789] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.595s) [105.089383] Starting dynamic subtest: pipe-A-HDMI-A-2 [105.538055] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.463s) [105.583655] Starting dynamic subtest: pipe-B-HDMI-A-1 [106.028281] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.493s) [106.052763] Starting dynamic subtest: pipe-B-HDMI-A-2 [106.455055] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.430s) [106.502529] Starting dynamic subtest: pipe-C-HDMI-A-1 [106.542156] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.003s) [106.564727] Starting dynamic subtest: pipe-C-HDMI-A-2 [106.597302] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s) [106.600472] Subtest crc-primary-rotation-180-yf-tiled-ccs: SUCCESS (2.005s) [107.278529] [014/156] (943s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs) [107.475062] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs [107.479153] Starting dynamic subtest: pipe-A-HDMI-A-1 [107.531225] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s) [107.579441] Starting dynamic subtest: pipe-A-HDMI-A-2 [107.641388] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s) [107.663091] Starting dynamic subtest: pipe-B-HDMI-A-1 [107.696753] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s) [107.718655] Starting dynamic subtest: pipe-B-HDMI-A-2 [107.751860] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s) [107.773913] Starting dynamic subtest: pipe-C-HDMI-A-1 [107.819067] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s) [107.845359] Starting dynamic subtest: pipe-C-HDMI-A-2 [107.879755] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s) [107.886600] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.020s) [108.549436] [015/156] (942s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt) [108.763760] Starting subtest: hdr-rgb565-draw-blt [108.771087] Subtest hdr-rgb565-draw-blt: SKIP (0.000s) [108.907190] [016/156] (942s left) kms_pm_rpm (legacy-planes-dpms) [110.844130] Starting subtest: legacy-planes-dpms [110.848569] Starting dynamic subtest: plane-45 [112.390415] Dynamic subtest plane-45: SUCCESS (1.547s) [112.436308] Starting dynamic subtest: plane-54 [113.969490] Dynamic subtest plane-54: SUCCESS (1.576s) [114.007145] Starting dynamic subtest: plane-63 [115.534355] Dynamic subtest plane-63: SUCCESS (1.564s) [115.537927] Subtest legacy-planes-dpms: SUCCESS (4.695s) [115.979980] [017/156] (935s left) gem_render_copy (x-tiled-to-vebox-y-tiled) [116.114430] Starting subtest: x-tiled-to-vebox-y-tiled [116.122591] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s) [116.358603] [018/156] (935s left) sysfs_heartbeat_interval (invalid) [116.481271] Starting subtest: invalid [116.484745] Starting dynamic subtest: vecs0 [116.489538] Dynamic subtest vecs0: SUCCESS (0.001s) [116.513063] Starting dynamic subtest: vcs0 [116.517272] Dynamic subtest vcs0: SUCCESS (0.001s) [116.540294] Starting dynamic subtest: bcs0 [116.545171] Dynamic subtest bcs0: SUCCESS (0.001s) [116.567977] Starting dynamic subtest: rcs0 [116.572581] Dynamic subtest rcs0: SUCCESS (0.001s) [116.576573] Subtest invalid: SUCCESS (0.010s) [116.666054] [019/156] (935s left) kms_frontbuffer_tracking (fbc-stridechange) [116.890536] Starting subtest: fbc-stridechange [118.817830] Subtest fbc-stridechange: SUCCESS (1.938s) [119.126985] [020/156] (932s left) gem_ctx_param (basic-default) [119.243558] Starting subtest: basic-default [119.247139] Subtest basic-default: SUCCESS (0.000s) [119.378140] [021/156] (932s left) kms_psr (fbc-pr-primary-mmap-gtt) [119.568257] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s) [119.672023] [022/156] (932s left) i915_pm_freq_mult (media-freq) [119.783488] Starting subtest: media-freq [119.787622] Starting dynamic subtest: gt0 [119.796511] Dynamic subtest gt0: SKIP (0.000s) [119.805675] Subtest media-freq: SKIP (0.002s) [119.926436] [023/156] (932s left) prime_busy (before-wait) [120.049499] Starting subtest: before-wait [120.063648] Starting dynamic subtest: rcs0 [120.076545] Dynamic subtest rcs0: SUCCESS (0.012s) [120.106499] Starting dynamic subtest: bcs0 [120.111198] Dynamic subtest bcs0: SUCCESS (0.012s) [120.139460] Starting dynamic subtest: vcs0 [120.143603] Dynamic subtest vcs0: SUCCESS (0.017s) [120.166104] Starting dynamic subtest: vecs0 [120.170601] Dynamic subtest vecs0: SUCCESS (0.014s) [120.234450] Subtest before-wait: SUCCESS (0.185s) [120.379559] [024/156] (931s left) gem_exec_gttfill (engines) [120.518117] Starting subtest: engines [120.522231] Starting dynamic subtest: rcs0 [148.660270] Dynamic subtest rcs0: SUCCESS (28.143s) [148.687600] Starting dynamic subtest: bcs0 [178.543044] Dynamic subtest bcs0: SUCCESS (29.879s) [178.571299] Starting dynamic subtest: vcs0 [206.045099] Dynamic subtest vcs0: SUCCESS (27.501s) [206.074220] Starting dynamic subtest: vecs0 [233.735706] Dynamic subtest vecs0: SUCCESS (27.690s) [233.740422] Subtest engines: SUCCESS (113.221s) [234.019646] [025/156] (818s left) syncobj_timeline (etime-multi-wait-all-submitted) [234.301635] Starting subtest: etime-multi-wait-all-submitted [234.411477] Subtest etime-multi-wait-all-submitted: SUCCESS (0.110s) [234.560370] [026/156] (817s left) gem_ctx_param (get-priority-new-ctx) [234.676030] Starting subtest: get-priority-new-ctx [234.680279] Subtest get-priority-new-ctx: SUCCESS (0.000s) [234.821245] [027/156] (817s left) kms_big_fb (linear-32bpp-rotate-90) [235.047793] Starting subtest: linear-32bpp-rotate-90 [235.369842] Subtest linear-32bpp-rotate-90: SKIP (0.318s) [235.651322] [028/156] (816s left) gem_lmem_swapping (heavy-verify-multi-ccs) [235.788493] Subtest heavy-verify-multi-ccs: SKIP (0.000s) [235.908068] [029/156] (816s left) gem_ctx_shared (disjoint-timelines) [236.041286] Starting subtest: disjoint-timelines [236.130089] Subtest disjoint-timelines: SUCCESS (0.079s) [236.397706] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true [236.404852] [030/156] (816s left) kms_psr (fbc-psr2-cursor-plane-move) [236.596479] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s) [236.717651] [031/156] (816s left) gem_bad_reloc (negative-reloc-bltcopy) [236.845512] Starting subtest: negative-reloc-bltcopy [240.058061] Subtest negative-reloc-bltcopy: SUCCESS (3.202s) [240.437497] [032/156] (812s left) kms_chamelium_hpd (hdmi-hpd) [241.011137] Subtest hdmi-hpd: SKIP (0.000s) [241.393469] [033/156] (811s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf) [241.613965] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s) [241.735215] [034/156] (811s left) gem_eio (wait-10ms) [241.912094] Starting subtest: wait-10ms [242.054278] Subtest wait-10ms: SUCCESS (0.142s) [242.317185] [035/156] (811s left) kms_content_protection (legacy-hdcp14) [242.555944] Starting subtest: legacy-hdcp14 [242.575573] Subtest legacy-hdcp14: SKIP (0.013s) [242.740751] [036/156] (810s left) syncobj_wait (reset-signaled) [242.854951] Starting subtest: reset-signaled [242.858957] Subtest reset-signaled: SUCCESS (0.001s) [243.021548] [037/156] (810s left) gem_ctx_isolation (dirty-switch) [243.159771] Starting subtest: dirty-switch [243.164333] Starting dynamic subtest: rcs0 [243.738813] Dynamic subtest rcs0: SUCCESS (0.579s) [243.770240] Starting dynamic subtest: bcs0 [244.691723] Dynamic subtest bcs0: SUCCESS (0.952s) [244.740640] Starting dynamic subtest: vcs0 [245.280616] Dynamic subtest vcs0: SUCCESS (0.587s) [245.311884] Starting dynamic subtest: vecs0 [245.883419] Dynamic subtest vecs0: SUCCESS (0.599s) [245.887245] Subtest dirty-switch: SUCCESS (2.727s) [246.160983] [038/156] (807s left) gem_flink_basic (bad-open) [246.273553] Starting subtest: bad-open [246.276800] Subtest bad-open: SUCCESS (0.000s) [246.451587] [039/156] (807s left) gem_caching (writes) [246.572412] Starting subtest: writes [260.777827] Subtest writes: SUCCESS (14.207s) [260.957249] [040/156] (792s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers) [261.143833] Starting subtest: plane-scaler-unity-scaling-with-modifiers [261.147204] Starting dynamic subtest: pipe-A [262.074362] Dynamic subtest pipe-A: SUCCESS (0.838s) [262.172969] Starting dynamic subtest: pipe-B [262.828811] Dynamic subtest pipe-B: SUCCESS (0.824s) [262.878288] Starting dynamic subtest: pipe-C [263.688373] Dynamic subtest pipe-C: SUCCESS (0.881s) [263.693949] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (2.549s) [264.309727] [041/156] (789s left) syncobj_timeline (single-wait-all-for-submit-available-submitted) [264.427550] Starting subtest: single-wait-all-for-submit-available-submitted [264.432522] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s) [264.574749] [042/156] (789s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render) [264.793605] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render [264.800497] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [264.946616] [043/156] (789s left) kms_pm_rpm (system-suspend-idle) [266.917901] Starting subtest: system-suspend-idle [284.408503] Subtest system-suspend-idle: SUCCESS (2.178s) [293.862399] [044/156] (769s left) kms_atomic_transition (modeset-transition) [294.061596] Starting subtest: modeset-transition [294.066154] Starting dynamic subtest: 1x-outputs [297.679997] Dynamic subtest 1x-outputs: SUCCESS (3.698s) [297.712461] Starting dynamic subtest: 2x-outputs [305.345894] Dynamic subtest 2x-outputs: SUCCESS (7.748s) [305.352200] Subtest modeset-transition: SUCCESS (11.450s) [305.730039] [045/156] (757s left) kms_frontbuffer_tracking (pipe-fbc-rte) [305.939172] Starting subtest: pipe-fbc-rte [305.959236] Subtest pipe-fbc-rte: SKIP (0.001s) [306.097856] [046/156] (757s left) gem_spin_batch (resubmit-all) [306.230403] Starting subtest: resubmit-all [306.233761] Starting dynamic subtest: rcs0 [306.244454] Dynamic subtest rcs0: SUCCESS (0.012s) [306.283879] Starting dynamic subtest: bcs0 [306.287409] Dynamic subtest bcs0: SUCCESS (0.014s) [306.316692] Starting dynamic subtest: vcs0 [306.320735] Dynamic subtest vcs0: SUCCESS (0.009s) [306.348669] Starting dynamic subtest: vecs0 [306.353437] Dynamic subtest vecs0: SUCCESS (0.009s) [306.357486] Subtest resubmit-all: SUCCESS (0.060s) [306.577900] [047/156] (757s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff) [306.791790] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff [306.798405] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [306.956462] [048/156] (756s left) syncobj_basic (illegal-fd-to-handle) [307.067701] Starting subtest: illegal-fd-to-handle [307.070863] Subtest illegal-fd-to-handle: SUCCESS (0.000s) [307.221587] [049/156] (756s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite) [307.435579] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite [310.083152] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SUCCESS (2.669s) [310.326947] [050/156] (753s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [310.535612] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [312.294148] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.765s) [312.613979] [051/156] (751s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted) [312.726657] Starting subtest: etime-single-wait-all-for-submit-unsubmitted [312.894194] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.109s) [313.347686] [052/156] (751s left) api_intel_bb (misplaced-blitter) [313.906252] Starting subtest: misplaced-blitter [313.972287] Subtest misplaced-blitter: SUCCESS (0.014s) [314.380930] [053/156] (750s left) kms_atomic_interruptible (universal-setplane-cursor) [314.583571] Starting subtest: universal-setplane-cursor [314.588345] Starting dynamic subtest: pipe-A-HDMI-A-1 [321.415408] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.833s) [321.419216] Subtest universal-setplane-cursor: SUCCESS (6.840s) [321.780959] [054/156] (743s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip) [321.999885] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip [322.007224] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [322.159614] [055/156] (742s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [322.380985] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc [322.389570] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [322.545452] [056/156] (742s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling) [322.736123] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling [322.740452] Starting dynamic subtest: pipe-A-valid-mode [323.800565] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.054s) [323.804618] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (1.059s) [324.271203] [057/156] (741s left) gem_pxp (create-protected-buffer) [326.927398] Starting subtest: create-protected-buffer [326.935676] Subtest create-protected-buffer: SKIP (0.000s) [327.088267] [058/156] (738s left) gem_fence_thrash (bo-write-verify-threaded-x) [327.203938] Starting subtest: bo-write-verify-threaded-x [332.285926] Subtest bo-write-verify-threaded-x: SUCCESS (5.090s) [332.456341] [059/156] (733s left) gen9_exec_parse (allowed-single) [332.589844] Starting subtest: allowed-single [332.605651] Subtest allowed-single: SUCCESS (0.013s) [332.914028] [060/156] (732s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu) [333.131830] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu [333.138234] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [333.290694] [061/156] (732s left) gem_exec_balancer (bonded-semaphore) [333.428249] Starting subtest: bonded-semaphore [337.730395] Subtest bonded-semaphore: SUCCESS (4.304s) [338.034406] [062/156] (727s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping) [338.225303] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping [338.232585] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s) [338.387807] [063/156] (727s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc) [338.613000] Starting subtest: fbc-rgb565-draw-mmap-wc [341.356602] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (2.750s) [341.653821] [064/156] (724s left) kms_pwrite_crc (basic) [341.863214] Starting subtest: basic [342.763728] Subtest basic: SUCCESS (0.902s) [343.151643] [065/156] (723s left) gem_flink_basic (bad-flink) [343.269277] Starting subtest: bad-flink [343.274081] Subtest bad-flink: SUCCESS (0.000s) [343.417144] [066/156] (722s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move) [343.636834] Starting subtest: hdr-2p-primscrn-spr-indfb-move [343.643666] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s) [343.786211] [067/156] (722s left) gem_ctx_persistence (legacy-engines-persistence) [343.919266] Starting subtest: legacy-engines-persistence [343.922531] Starting dynamic subtest: render [344.718401] Dynamic subtest render: SUCCESS (0.795s) [344.752443] Starting dynamic subtest: bsd [345.484792] Dynamic subtest bsd: SUCCESS (0.761s) [345.517506] Starting dynamic subtest: blt [346.253375] Dynamic subtest blt: SUCCESS (0.766s) [346.291540] Starting dynamic subtest: vebox [347.011023] Dynamic subtest vebox: SUCCESS (0.749s) [347.014604] Subtest legacy-engines-persistence: SUCCESS (3.095s) [347.181976] [068/156] (719s left) gen7_exec_parse (basic-allowed) [347.308467] Subtest basic-allowed: SKIP (0.000s) [347.426075] [069/156] (719s left) gem_exec_schedule (reorder-wide) [347.691620] Starting subtest: reorder-wide [347.697148] Starting dynamic subtest: rcs0 [347.866986] Dynamic subtest rcs0: SUCCESS (0.165s) [347.907700] Starting dynamic subtest: bcs0 [348.184177] Dynamic subtest bcs0: SUCCESS (0.322s) [348.239197] Starting dynamic subtest: vcs0 [348.351804] Dynamic subtest vcs0: SUCCESS (0.161s) [348.402092] Starting dynamic subtest: vecs0 [348.513965] Dynamic subtest vecs0: SUCCESS (0.154s) [348.518504] Subtest reorder-wide: SUCCESS (0.820s) [348.796250] [070/156] (718s left) kms_frontbuffer_tracking (psrhdr-slowdraw) [349.010703] Starting subtest: psrhdr-slowdraw [349.017784] Subtest psrhdr-slowdraw: SKIP (0.000s) [349.186982] [071/156] (717s left) perf (non-zero-reason) [349.472336] Starting subtest: non-zero-reason [349.476211] Starting dynamic subtest: 0-rcs0 [400.106202] Dynamic subtest 0-rcs0: SUCCESS (50.644s) [400.110760] Subtest non-zero-reason: SUCCESS (50.647s) [400.237729] [072/156] (666s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite) [400.453687] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite [400.462517] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [400.608198] [073/156] (666s left) i915_drm_fdinfo (all-busy-check-all) [400.736427] Starting subtest: all-busy-check-all [401.256092] Subtest all-busy-check-all: SUCCESS (0.520s) [401.434009] [074/156] (665s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt) [401.652482] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt [401.659045] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [401.805382] [075/156] (665s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12) [402.001666] Starting subtest: compare-crc-sanitycheck-nv12 [402.007309] Starting dynamic subtest: pipe-A-HDMI-A-1 [402.944437] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.939s) [402.991998] Starting dynamic subtest: pipe-B-HDMI-A-2 [403.650895] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.689s) [403.683823] Starting dynamic subtest: pipe-C-HDMI-A-1 [404.335762] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.685s) [404.340185] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.340s) [404.700981] [076/156] (662s left) gem_exec_reloc (basic-write-gtt) [404.837971] Starting subtest: basic-write-gtt [404.851165] Subtest basic-write-gtt: SUCCESS (0.018s) [405.103946] [077/156] (662s left) kms_cursor_crc (cursor-rapid-movement-max-size) [405.332360] Starting subtest: cursor-rapid-movement-max-size [405.343667] Subtest cursor-rapid-movement-max-size: SKIP (0.000s) [405.502851] [078/156] (662s left) kms_color_pipeline (plane-lut1d) [405.698207] Starting subtest: plane-lut1d [407.355909] Subtest plane-lut1d: SKIP (1.655s) [407.719653] [079/156] (660s left) kms_big_fb (yf-tiled-16bpp-rotate-180) [407.946269] Starting subtest: yf-tiled-16bpp-rotate-180 [411.722728] Subtest yf-tiled-16bpp-rotate-180: SUCCESS (3.776s) [412.120529] [080/156] (655s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0) [412.330343] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0 [412.338176] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s) [412.486935] [081/156] (655s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt) [412.708299] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt [412.715518] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [412.872815] [082/156] (655s left) gem_exec_reloc (basic-wc-read) [412.999809] Starting subtest: basic-wc-read [413.019437] Subtest basic-wc-read: SUCCESS (0.019s) [413.318139] [083/156] (654s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle) [413.523342] Starting subtest: short-busy-flip-before-cursor-toggle [413.530709] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s) [413.698980] [084/156] (654s left) kms_flip (2x-plain-flip-fb-recreate-interruptible) [413.910668] Starting subtest: 2x-plain-flip-fb-recreate-interruptible [413.914959] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2 [425.052670] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.140s) [425.089323] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2 [436.073853] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (11.014s) [436.109572] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2 [447.076723] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.996s) [447.082614] Subtest 2x-plain-flip-fb-recreate-interruptible: SUCCESS (33.174s) [448.088783] [085/156] (620s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [448.819087] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu [448.827662] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [448.935401] [086/156] (620s left) kms_draw_crc (draw-method-render) [449.133199] Starting subtest: draw-method-render [449.136563] Starting dynamic subtest: xrgb8888-untiled [449.681462] Dynamic subtest xrgb8888-untiled: SUCCESS (0.552s) [449.714801] Starting dynamic subtest: rgb565-untiled [449.983471] Dynamic subtest rgb565-untiled: SUCCESS (0.295s) [450.015535] Starting dynamic subtest: xrgb2101010-untiled [450.282346] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.295s) [450.318259] Starting dynamic subtest: xbgr16161616f-untiled [450.615046] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.330s) [450.648596] Starting dynamic subtest: xrgb8888-xtiled [450.832546] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.213s) [450.866756] Starting dynamic subtest: rgb565-xtiled [451.033815] Dynamic subtest rgb565-xtiled: SUCCESS (0.196s) [451.064678] Starting dynamic subtest: xrgb2101010-xtiled [451.215921] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.179s) [451.250587] Starting dynamic subtest: xbgr16161616f-xtiled [451.415131] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.195s) [451.450335] Starting dynamic subtest: xrgb8888-ytiled [451.614759] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.198s) [451.653021] Starting dynamic subtest: rgb565-ytiled [451.815760] Dynamic subtest rgb565-ytiled: SUCCESS (0.194s) [451.886308] Starting dynamic subtest: xrgb2101010-ytiled [452.030511] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.212s) [452.069816] Starting dynamic subtest: xbgr16161616f-ytiled [452.232455] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.197s) [452.236472] Subtest draw-method-render: SUCCESS (3.105s) [452.508613] [087/156] (616s left) kms_cursor_crc (cursor-sliding-256x85) [452.712189] Starting subtest: cursor-sliding-256x85 [452.763169] Starting dynamic subtest: pipe-A-HDMI-A-1 [456.799203] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.039s) [456.930019] Starting dynamic subtest: pipe-C-HDMI-A-1 [460.607951] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.743s) [460.613933] Subtest cursor-sliding-256x85: SUCCESS (7.897s) [461.072391] [088/156] (608s left) kms_cursor_crc (cursor-onscreen-256x256) [461.285539] Starting subtest: cursor-onscreen-256x256 [461.339188] Starting dynamic subtest: pipe-A-HDMI-A-1 [464.025239] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.687s) [464.154185] Starting dynamic subtest: pipe-C-HDMI-A-1 [466.525808] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.419s) [466.531512] Subtest cursor-onscreen-256x256: SUCCESS (5.239s) [467.016294] [089/156] (602s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render) [467.241752] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render [467.250258] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s) [467.406341] [090/156] (602s left) kms_frontbuffer_tracking (fbcpsr-slowdraw) [467.628366] Starting subtest: fbcpsr-slowdraw [467.635847] Subtest fbcpsr-slowdraw: SKIP (0.000s) [467.824397] [091/156] (601s left) kms_lease (cursor-implicit-plane) [468.012245] Starting subtest: cursor-implicit-plane [468.016266] Starting dynamic subtest: pipe-A-HDMI-A-1 [468.691469] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.664s) [468.731631] Starting dynamic subtest: pipe-A-HDMI-A-2 [469.196458] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.502s) [469.270634] Starting dynamic subtest: pipe-B-HDMI-A-1 [469.621439] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.425s) [469.697287] Starting dynamic subtest: pipe-B-HDMI-A-2 [470.065039] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.435s) [470.130353] Starting dynamic subtest: pipe-C-HDMI-A-1 [470.496207] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.422s) [470.539562] Starting dynamic subtest: pipe-C-HDMI-A-2 [470.964510] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.456s) [470.968190] Subtest cursor-implicit-plane: SUCCESS (2.950s) [471.328176] [092/156] (598s left) syncobj_wait (invalid-reset-zero-handles) [471.456490] Starting subtest: invalid-reset-zero-handles [471.461423] Subtest invalid-reset-zero-handles: SUCCESS (0.000s) [471.607067] [093/156] (598s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render) [471.821723] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render [473.879040] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SUCCESS (2.069s) [474.104968] [094/156] (595s left) kms_big_fb (yf-tiled-64bpp-rotate-90) [474.328181] Starting subtest: yf-tiled-64bpp-rotate-90 [474.335421] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s) [474.495398] [095/156] (595s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc) [474.734201] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc [474.742496] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [474.876874] [096/156] (595s left) gem_exec_fence (long-history) [475.436583] Starting subtest: long-history [481.119611] Subtest long-history: SUCCESS (5.693s) [481.857866] [097/156] (588s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt) [482.081512] Starting subtest: psr-1p-primscrn-indfb-msflip-blt [482.089029] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [482.241136] [098/156] (588s left) kms_ccs (bad-rotation-90-yf-tiled-ccs) [482.447093] Starting subtest: bad-rotation-90-yf-tiled-ccs [482.451641] Starting dynamic subtest: pipe-A-HDMI-A-1 [482.635083] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.162s) [482.689562] Starting dynamic subtest: pipe-A-HDMI-A-2 [482.729330] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.081s) [482.784699] Starting dynamic subtest: pipe-B-HDMI-A-1 [482.829557] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.078s) [482.888438] Starting dynamic subtest: pipe-B-HDMI-A-2 [482.933565] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.068s) [482.990308] Starting dynamic subtest: pipe-C-HDMI-A-1 [483.034469] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s) [483.065206] Starting dynamic subtest: pipe-C-HDMI-A-2 [483.098693] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s) [483.101929] Subtest bad-rotation-90-yf-tiled-ccs: SUCCESS (0.440s) [483.995512] [099/156] (586s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic) [484.195172] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic [490.729349] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (6.532s) [491.071713] [100/156] (579s left) kms_chamelium_audio (dp-audio-edid) [491.704223] Subtest dp-audio-edid: SKIP (0.000s) [492.052700] [101/156] (578s left) kms_addfb_basic (addfb25-yf-tiled-legacy) [492.271604] Starting subtest: addfb25-yf-tiled-legacy [492.274594] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s) [492.432192] [102/156] (578s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt) [492.666756] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt [492.673462] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [492.833651] [103/156] (578s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff) [493.048603] Starting subtest: psr-2p-primscrn-cur-indfb-onoff [493.067146] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [493.216283] [104/156] (577s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw) [493.447937] Starting subtest: fbc-2p-pri-indfb-multidraw [510.229935] Subtest fbc-2p-pri-indfb-multidraw: SUCCESS (16.796s) [510.471596] [105/156] (560s left) kms_sequence (queue-busy) [510.665652] Starting subtest: queue-busy [510.672148] Starting dynamic subtest: pipe-A-HDMI-A-1 [513.603549] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.925s) [513.668421] Starting dynamic subtest: pipe-A-HDMI-A-2 [516.316417] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.710s) [516.372443] Starting dynamic subtest: pipe-B-HDMI-A-1 [519.112383] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.673s) [519.210225] Starting dynamic subtest: pipe-B-HDMI-A-2 [521.624792] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.625s) [521.688581] Starting dynamic subtest: pipe-C-HDMI-A-1 [524.224032] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.592s) [524.267713] Starting dynamic subtest: pipe-C-HDMI-A-2 [526.862786] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.629s) [526.866400] Subtest queue-busy: SUCCESS (16.190s) [527.271284] [106/156] (544s left) kms_plane_scaling (plane-upscale-20x20-with-rotation) [527.462006] Starting subtest: plane-upscale-20x20-with-rotation [527.465864] Starting dynamic subtest: pipe-A [532.190321] Dynamic subtest pipe-A: SKIP (4.708s) [532.250370] Starting dynamic subtest: pipe-B [537.816508] Dynamic subtest pipe-B: SKIP (5.617s) [537.884568] Starting dynamic subtest: pipe-C [542.091078] Dynamic subtest pipe-C: SKIP (4.239s) [542.101519] Subtest plane-upscale-20x20-with-rotation: SKIP (14.575s) [542.640717] [107/156] (528s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite) [542.921418] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite [542.929406] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [543.078566] [108/156] (528s left) gem_exec_reloc (basic-write-wc) [543.208274] Starting subtest: basic-write-wc [543.228954] Subtest basic-write-wc: SUCCESS (0.021s) [543.475980] [109/156] (528s left) syncobj_timeline (reset-signaled) [543.593464] Starting subtest: reset-signaled [543.597398] Subtest reset-signaled: SUCCESS (0.002s) [543.761119] [110/156] (528s left) kms_psr (pr-cursor-plane-move) [543.974580] Subtest pr-cursor-plane-move: SKIP (0.000s) [544.107405] [111/156] (527s left) syncobj_wait (invalid-wait-illegal-handle) [544.227384] Starting subtest: invalid-wait-illegal-handle [544.230888] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s) [544.393416] [112/156] (527s left) kms_busy (extended-pageflip-modeset-hang-oldfb) [544.618680] Starting subtest: extended-pageflip-modeset-hang-oldfb [544.623201] Starting dynamic subtest: pipe-A [549.189831] Dynamic subtest pipe-A: SUCCESS (4.569s) [549.257396] Starting dynamic subtest: pipe-C [553.139205] Dynamic subtest pipe-C: SUCCESS (3.937s) [553.142755] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (8.526s) [553.545681] [113/156] (518s left) kms_psr (psr2-primary-blt) [553.747354] Subtest psr2-primary-blt: SKIP (0.000s) [553.884768] [114/156] (518s left) gem_softpin (evict-snoop-interruptible) [554.011753] Starting subtest: evict-snoop-interruptible [554.019363] Subtest evict-snoop-interruptible: SKIP (0.000s) [554.170617] [115/156] (518s left) kms_force_connector_basic (force-load-detect) [554.457121] Starting subtest: force-load-detect [554.465996] Subtest force-load-detect: SKIP (0.000s) [554.842585] [116/156] (517s left) kms_chamelium_edid (hdmi-mode-timings) [555.477260] Subtest hdmi-mode-timings: SKIP (0.000s) [555.885940] [117/156] (516s left) syncobj_timeline (invalid-signal-zero-handles) [556.006071] Starting subtest: invalid-signal-zero-handles [556.009527] Subtest invalid-signal-zero-handles: SUCCESS (0.000s) [556.161504] [118/156] (516s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt) [556.383521] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt [556.390164] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [556.546245] [119/156] (516s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [556.770167] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt [556.777051] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [556.984733] [120/156] (516s left) gem_mmap_gtt (fault-concurrent-x) [557.102709] Starting subtest: fault-concurrent-X [560.324623] Subtest fault-concurrent-X: SUCCESS (3.223s) [560.491550] [121/156] (512s left) gem_render_copy (y-tiled-to-vebox-y-tiled) [560.615757] Starting subtest: y-tiled-to-vebox-y-tiled [560.623783] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s) [560.892485] [122/156] (512s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt) [561.106523] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt [561.115148] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s) [561.263784] [123/156] (512s left) vgem_basic (debugfs) [561.394964] Starting subtest: debugfs [561.399081] Subtest debugfs: SUCCESS (0.002s) [561.537436] [124/156] (511s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling) [561.760697] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling [561.768192] Starting dynamic subtest: pipe-A-valid-mode [562.837554] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.068s) [562.843497] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (1.074s) [563.199762] [125/156] (510s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite) [563.431680] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite [566.936402] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (3.517s) [567.146563] [126/156] (506s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu) [567.371600] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu [567.379078] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [567.534208] [127/156] (506s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt) [567.757437] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt [567.765062] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [567.916814] [128/156] (505s left) kms_vblank (wait-forked-hang) [568.106777] Starting subtest: wait-forked-hang [568.110174] Starting dynamic subtest: pipe-A-HDMI-A-1 [575.192640] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (7.085s) [575.258654] Starting dynamic subtest: pipe-A-HDMI-A-2 [582.167558] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (6.957s) [582.211283] Starting dynamic subtest: pipe-C-HDMI-A-1 [589.126245] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (6.955s) [589.187696] Starting dynamic subtest: pipe-C-HDMI-A-2 [596.176334] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (7.037s) [596.182458] Subtest wait-forked-hang: SUCCESS (28.071s) [596.520377] [129/156] (477s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling) [596.729509] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling [596.734358] Starting dynamic subtest: pipe-A-valid-mode [597.770951] Dynamic subtest pipe-A-valid-mode: SKIP (0.878s) [597.911729] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (0.883s) [598.553909] [130/156] (475s left) gem_ctx_exec (basic-invalid-context) [598.672386] Starting subtest: basic-invalid-context [598.683462] Subtest basic-invalid-context: SUCCESS (0.007s) [598.858251] [131/156] (475s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt) [599.073029] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt [599.080278] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [599.227048] [132/156] (475s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf) [599.428185] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s) [599.554574] [133/156] (475s left) gem_ppgtt (blt-vs-render-ctxn) [599.681294] Starting subtest: blt-vs-render-ctxN [632.639051] Subtest blt-vs-render-ctxN: SUCCESS (32.961s) [632.811221] [134/156] (442s left) gem_exec_reloc (basic-gtt-cpu-noreloc) [633.087976] Starting subtest: basic-gtt-cpu-noreloc [633.098547] Subtest basic-gtt-cpu-noreloc: SUCCESS (0.009s) [633.357510] [135/156] (441s left) gem_softpin (invalid) [633.482060] Starting subtest: invalid [633.497796] Subtest invalid: SUCCESS (0.015s) [633.667655] [136/156] (441s left) gem_exec_capture (capture) [633.798854] Starting subtest: capture [633.803311] Starting dynamic subtest: rcs0-smem [635.085722] Dynamic subtest rcs0-smem: SUCCESS (1.288s) [635.121645] Starting dynamic subtest: bcs0-smem [636.374361] Dynamic subtest bcs0-smem: SUCCESS (1.284s) [636.414217] Starting dynamic subtest: vcs0-smem [637.662989] Dynamic subtest vcs0-smem: SUCCESS (1.286s) [637.702577] Starting dynamic subtest: vecs0-smem [638.950325] Dynamic subtest vecs0-smem: SUCCESS (1.284s) [638.954953] Subtest capture: SUCCESS (5.154s) [639.102765] [137/156] (436s left) gem_tiled_blits (basic) [639.333607] Starting subtest: basic [639.367678] Subtest basic: SUCCESS (0.037s) [639.575357] [138/156] (435s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt) [639.967973] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt [639.975274] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [640.160718] [139/156] (435s left) gem_mmap_offset (partial-remap) [640.275441] Starting subtest: partial-remap [640.278861] Starting dynamic subtest: smem0 [640.299805] Dynamic subtest smem0: SUCCESS (0.008s) [640.303749] Subtest partial-remap: SUCCESS (0.011s) [640.452481] [140/156] (435s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt) [640.675798] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt [640.683800] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s) [640.836960] [141/156] (434s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt) [641.061195] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt [641.068913] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s) [641.221654] [142/156] (434s left) i915_power (sanity) [641.338687] Starting subtest: sanity [645.365578] Subtest sanity: SUCCESS (4.026s) [645.517739] [143/156] (430s left) gem_exec_schedule (preempt-queue) [645.686858] Starting subtest: preempt-queue [645.690261] Starting dynamic subtest: rcs0 [645.979172] Dynamic subtest rcs0: SUCCESS (0.290s) [646.012309] Starting dynamic subtest: bcs0 [646.276947] Dynamic subtest bcs0: SUCCESS (0.292s) [646.309135] Starting dynamic subtest: vcs0 [646.567706] Dynamic subtest vcs0: SUCCESS (0.286s) [646.599840] Starting dynamic subtest: vecs0 [646.861665] Dynamic subtest vecs0: SUCCESS (0.290s) [646.865378] Subtest preempt-queue: SUCCESS (1.176s) [647.287162] [144/156] (428s left) kms_cursor_legacy (cursorb-vs-flipa-legacy) [647.494184] Starting subtest: cursorB-vs-flipA-legacy [658.874577] Subtest cursorB-vs-flipA-legacy: SUCCESS (11.379s) [659.306532] [145/156] (416s left) i915_pm_rps (reset) [659.438033] Starting subtest: reset [664.030792] Subtest reset: SUCCESS (4.594s) [664.192515] [146/156] (412s left) kms_psr (fbc-psr-cursor-plane-onoff) [664.399255] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s) [664.522646] [147/156] (411s left) kms_setmode (clone-exclusive-crtc) [664.639810] Starting subtest: clone-exclusive-crtc [665.120448] Starting dynamic subtest: pipe-A-HDMI-A-1-pipe-B-HDMI-A-2 [665.425942] Dynamic subtest pipe-A-HDMI-A-1-pipe-B-HDMI-A-2: SUCCESS (0.305s) [665.943842] Starting dynamic subtest: pipe-B-HDMI-A-1-pipe-A-HDMI-A-2 [666.216655] Dynamic subtest pipe-B-HDMI-A-1-pipe-A-HDMI-A-2: SUCCESS (0.299s) [666.680184] Subtest clone-exclusive-crtc: SUCCESS (2.045s) [667.067337] [148/156] (409s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu) [667.284191] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu [667.293594] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [667.438316] [149/156] (409s left) kms_flip (flip-vs-rmfb) [667.642742] Starting subtest: flip-vs-rmfb [667.647206] Starting dynamic subtest: A-HDMI-A1 [673.378643] Dynamic subtest A-HDMI-A1: SUCCESS (5.733s) [673.420042] Starting dynamic subtest: B-HDMI-A1 [678.821233] Dynamic subtest B-HDMI-A1: SUCCESS (5.437s) [678.858482] Starting dynamic subtest: C-HDMI-A1 [684.270180] Dynamic subtest C-HDMI-A1: SUCCESS (5.444s) [684.308660] Starting dynamic subtest: A-HDMI-A2 [689.714654] Dynamic subtest A-HDMI-A2: SUCCESS (5.439s) [689.752251] Starting dynamic subtest: B-HDMI-A2 [695.175756] Dynamic subtest B-HDMI-A2: SUCCESS (5.455s) [695.212367] Starting dynamic subtest: C-HDMI-A2 [700.618646] Dynamic subtest C-HDMI-A2: SUCCESS (5.436s) [700.622187] Subtest flip-vs-rmfb: SUCCESS (32.987s) [701.580579] [150/156] (375s left) gem_exec_whisper (basic-contexts-all) [702.222237] Starting subtest: basic-contexts-all [713.664067] Subtest basic-contexts-all: SUCCESS (11.746s) [713.970150] [151/156] (363s left) dmabuf_sync_file (export-basic) [714.130602] Starting subtest: export-basic [714.135665] Subtest export-basic: SUCCESS (0.003s) [714.313801] [152/156] (363s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu) [714.529587] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu [714.536697] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [714.711617] [153/156] (362s left) gem_sync (basic-each) [714.860571] Starting subtest: basic-each [717.347306] Subtest basic-each: SUCCESS (2.499s) [717.614745] [154/156] (360s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc) [717.821616] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc [717.825868] Starting dynamic subtest: pipe-A-HDMI-A-1 [717.885874] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s) [717.946918] Starting dynamic subtest: pipe-A-HDMI-A-2 [718.003007] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s) [718.036033] Starting dynamic subtest: pipe-B-HDMI-A-1 [718.072119] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s) [718.102653] Starting dynamic subtest: pipe-B-HDMI-A-2 [718.138037] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s) [718.168554] Starting dynamic subtest: pipe-C-HDMI-A-1 [718.205841] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s) [718.236298] Starting dynamic subtest: pipe-C-HDMI-A-2 [718.271787] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s) [718.278348] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.039s) [718.950580] [155/156] (358s left) syncobj_wait (multi-wait-for-submit-submitted) [719.072848] Starting subtest: multi-wait-for-submit-submitted [719.180351] Subtest multi-wait-for-submit-submitted: SUCCESS (0.109s) [719.336031] [156/156] (358s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt) [719.549485] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt [719.558773] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [719.726779] 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.