[56.337111] Initializing watchdogs [56.337155] /dev/watchdog0 [56.352013] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics] [56.355972] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [56.358158] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [56.361982] [001/155] (960s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-blt) [56.479114] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-blt [56.483464] Subtest psrhdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s) [56.585611] [002/155] (960s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-pwrite) [56.676559] Starting subtest: fbchdr-rgb565-draw-pwrite [56.680719] Subtest fbchdr-rgb565-draw-pwrite: SKIP (0.000s) [56.775202] [003/155] (960s left) gem_exec_reloc (basic-wc-read-active) [56.841119] Subtest basic-wc-read-active: SKIP (0.000s) [56.902181] [004/155] (960s left) gem_exec_balancer (invalid-balancer) [56.958499] Starting subtest: invalid-balancer [57.837321] Subtest invalid-balancer: SUCCESS (0.897s) [58.123846] [005/155] (958s left) gem_exec_reloc (basic-write-cpu-noreloc) [58.192575] Subtest basic-write-cpu-noreloc: SKIP (0.000s) [58.257035] [006/155] (958s left) gem_ctx_persistence (engines-queued) [58.320985] Starting subtest: engines-queued [58.323601] Starting dynamic subtest: rcs0 [58.406680] Dynamic subtest rcs0: SUCCESS (0.087s) [58.417892] Starting dynamic subtest: bcs0 [58.432281] Dynamic subtest bcs0: SUCCESS (0.026s) [58.439515] Starting dynamic subtest: vcs0 [60.189395] Dynamic subtest vcs0: SUCCESS (1.786s) [60.197379] Starting dynamic subtest: vcs1 [62.172027] Dynamic subtest vcs1: SUCCESS (2.009s) [62.181349] Starting dynamic subtest: vecs0 [64.219061] Dynamic subtest vecs0: SUCCESS (2.067s) [64.228607] Starting dynamic subtest: ccs0 [64.392656] Dynamic subtest ccs0: SUCCESS (0.176s) [64.395208] Subtest engines-queued: SUCCESS (6.154s) [64.518129] [007/155] (952s left) syncobj_timeline (multi-wait-available-submitted-signaled) [64.573349] Starting subtest: multi-wait-available-submitted-signaled [64.576139] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.001s) [64.677450] [008/155] (952s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-msflip-blt) [64.756794] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-msflip-blt [64.761113] Subtest fbcpsrhdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s) [64.837225] [009/155] (952s left) i915_pm_rps (thresholds) [64.903944] Starting subtest: thresholds [64.908712] Subtest thresholds: SKIP (0.000s) [65.027308] [010/155] (952s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc) [65.102846] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc [65.107441] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [65.199178] [011/155] (952s left) syncobj_wait (invalid-signal-bad-pad) [65.248966] Starting subtest: invalid-signal-bad-pad [65.251018] Subtest invalid-signal-bad-pad: SUCCESS (0.000s) [65.329320] [012/155] (951s left) kms_big_fb (y-tiled-64bpp-rotate-90) [65.420572] Starting subtest: y-tiled-64bpp-rotate-90 [65.424188] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s) [65.513415] [013/155] (951s left) gem_ccs (block-copy-uncompressed) [65.575396] Starting subtest: block-copy-uncompressed [65.577209] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem [65.597520] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.024s) [65.606586] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem [65.614941] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s) [65.622494] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem [65.631926] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s) [65.639285] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem [65.648930] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s) [65.650968] Subtest block-copy-uncompressed: SUCCESS (0.077s) [65.763311] [014/155] (951s left) kms_flip (2x-flip-vs-panning-interruptible) [65.843990] Starting subtest: 2x-flip-vs-panning-interruptible [65.851261] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.008s) [65.934656] [015/155] (951s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-blt) [66.018291] Starting subtest: psrhdr-rgb101010-draw-blt [66.021849] Subtest psrhdr-rgb101010-draw-blt: SKIP (0.000s) [66.117622] [016/155] (951s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-pwrite) [66.211251] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-pwrite [66.214871] Subtest hdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [66.297288] [017/155] (951s left) syncobj_timeline (invalid-query-zero-handles) [66.367490] Starting subtest: invalid-query-zero-handles [66.369510] Subtest invalid-query-zero-handles: SUCCESS (0.000s) [66.466748] [018/155] (951s left) kms_content_protection (content-type-change) [66.582652] Starting subtest: content-type-change [66.586659] Subtest content-type-change: SKIP (0.000s) [66.702722] [019/155] (950s left) gem_fence_thrash (bo-write-verify-x) [66.731157] Starting subtest: bo-write-verify-x [66.753465] Subtest bo-write-verify-x: SKIP (0.021s) [66.812024] [020/155] (950s left) gem_exec_parallel (fds) [66.874041] Starting subtest: fds [66.875966] Starting dynamic subtest: rcs0 [69.442651] Dynamic subtest rcs0: SUCCESS (2.581s) [69.451046] Starting dynamic subtest: bcs0 [73.197955] Dynamic subtest bcs0: SUCCESS (3.768s) [73.207632] Starting dynamic subtest: vcs0 [77.538584] Dynamic subtest vcs0: SUCCESS (4.348s) [77.549649] Starting dynamic subtest: vcs1 [81.471801] Dynamic subtest vcs1: SUCCESS (3.937s) [81.480850] Starting dynamic subtest: vecs0 [85.518916] Dynamic subtest vecs0: SUCCESS (4.046s) [85.528041] Starting dynamic subtest: ccs0 [88.882957] Dynamic subtest ccs0: SUCCESS (3.362s) [88.885422] Subtest fds: SUCCESS (22.046s) [89.078957] [021/155] (928s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling) [89.160062] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling [89.162184] Starting dynamic subtest: pipe-A-default-mode [91.951053] Dynamic subtest pipe-A-default-mode: SUCCESS (2.786s) [91.953789] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.787s) [92.779433] [022/155] (924s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip) [92.864744] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip [93.533397] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.670s) [93.694955] [023/155] (923s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf) [93.770373] Starting subtest: psr2-cursor-plane-move-continuous-sf [93.772673] Starting dynamic subtest: pipe-A-eDP-1 [104.128527] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.358s) [104.138569] Starting dynamic subtest: pipe-B-eDP-1 [115.176238] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.044s) [115.178576] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.405s) [115.985705] [024/155] (901s left) i915_query (query-topology-garbage-items) [116.045234] Starting subtest: query-topology-garbage-items [116.047630] Subtest query-topology-garbage-items: SUCCESS (0.001s) [116.146305] [025/155] (901s left) gem_mmap_gtt (basic-small-bo) [116.195742] Subtest basic-small-bo: SKIP (0.000s) [116.256888] [026/155] (901s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu) [116.339875] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu [122.879120] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.544s) [122.999961] [027/155] (894s left) kms_cursor_legacy (cursora-vs-flipa-varying-size) [123.103293] Starting subtest: cursorA-vs-flipA-varying-size [134.229082] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.123s) [135.037128] [028/155] (882s left) kms_properties (crtc-properties-atomic) [135.121131] Starting subtest: crtc-properties-atomic [135.123538] Starting dynamic subtest: pipe-A-eDP-1 [135.561372] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.439s) [135.571209] Starting dynamic subtest: pipe-B-eDP-1 [136.696639] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.135s) [136.705775] Starting dynamic subtest: pipe-C-eDP-1 [137.816031] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.120s) [137.830332] Starting dynamic subtest: pipe-D-eDP-1 [138.946622] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.127s) [138.948842] Subtest crtc-properties-atomic: SUCCESS (3.825s) [139.752969] [029/155] (878s left) kms_frontbuffer_tracking (psr-rgb565-draw-render) [139.841583] Starting subtest: psr-rgb565-draw-render [146.946537] Subtest psr-rgb565-draw-render: SUCCESS (7.109s) [147.068074] [030/155] (870s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc) [147.162163] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc [147.166120] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [147.260310] [031/155] (870s left) kms_plane (pixel-format-4-tiled-dg2-mc-ccs-modifier) [147.332462] Starting subtest: pixel-format-4-tiled-dg2-mc-ccs-modifier [147.336548] Subtest pixel-format-4-tiled-dg2-mc-ccs-modifier: SKIP (0.000s) [147.402439] [032/155] (870s left) gem_exec_balancer (parallel-ordering) [147.646274] Starting subtest: parallel-ordering [148.610276] Subtest parallel-ordering: SUCCESS (0.964s) [148.732677] [033/155] (869s left) kms_vblank (query-forked-busy-hang) [148.805189] Starting subtest: query-forked-busy-hang [148.807427] Starting dynamic subtest: pipe-A-eDP-1 [174.982600] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.176s) [174.995910] Starting dynamic subtest: pipe-D-eDP-1 [190.018105] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.032s) [190.020528] Subtest query-forked-busy-hang: SUCCESS (41.213s) [190.820601] [034/155] (827s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move) [190.917811] Starting subtest: fbc-2p-scndscrn-cur-indfb-move [190.922149] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s) [191.011625] [035/155] (827s left) perf_pmu (invalid-init) [191.049866] Starting subtest: invalid-init [191.052117] Subtest invalid-init: SUCCESS (0.000s) [191.154113] [036/155] (827s left) syncobj_timeline (single-wait-all-available-signaled) [191.208933] Starting subtest: single-wait-all-available-signaled [191.211717] Subtest single-wait-all-available-signaled: SUCCESS (0.000s) [191.325747] [037/155] (826s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-render) [191.408682] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-render [191.413177] Subtest fbchdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s) [191.527821] [038/155] (826s left) gem_userptr_blits (nohangcheck) [191.596315] Starting subtest: nohangcheck [191.848195] Subtest nohangcheck: SUCCESS (0.251s) [191.974218] [039/155] (826s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc) [192.041490] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc [192.043765] Starting dynamic subtest: pipe-A-eDP-1 [192.064970] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [192.075258] Starting dynamic subtest: pipe-B-eDP-1 [192.094777] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [192.103722] Starting dynamic subtest: pipe-C-eDP-1 [192.123989] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [192.132310] Starting dynamic subtest: pipe-D-eDP-1 [192.152021] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [192.154283] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.005s) [194.287337] [040/155] (824s left) kms_cursor_crc (cursor-sliding-512x170) [194.540836] Starting subtest: cursor-sliding-512x170 [194.545257] Subtest cursor-sliding-512x170: SKIP (0.001s) [194.690419] [041/155] (823s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-msflip-blt) [194.777062] Starting subtest: hdr-1p-primscrn-shrfb-msflip-blt [194.781483] Subtest hdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [194.885126] [042/155] (823s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0) [194.974358] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0 [195.942795] Subtest x-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (0.968s) [196.059302] [043/155] (822s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier) [196.126790] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier [196.153101] Starting dynamic subtest: pipe-A-plane-0 [196.452613] Dynamic subtest pipe-A-plane-0: SUCCESS (0.300s) [196.461729] Starting dynamic subtest: pipe-A-plane-3 [196.686034] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s) [196.694297] Starting dynamic subtest: pipe-A-plane-5 [196.697737] Dynamic subtest pipe-A-plane-5: SKIP (0.000s) [197.758935] Starting dynamic subtest: pipe-B-plane-0 [198.051607] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s) [198.063312] Starting dynamic subtest: pipe-B-plane-3 [198.284771] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s) [198.294114] Starting dynamic subtest: pipe-B-plane-5 [198.298267] Dynamic subtest pipe-B-plane-5: SKIP (0.000s) [198.611547] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.484s) [199.446191] [044/155] (819s left) kms_flip (basic-flip-vs-modeset) [199.520261] Starting subtest: basic-flip-vs-modeset [199.522469] Starting dynamic subtest: A-eDP1 [202.147211] Dynamic subtest A-eDP1: SUCCESS (2.626s) [202.156303] Starting dynamic subtest: B-eDP1 [204.332712] Dynamic subtest B-eDP1: SUCCESS (2.186s) [204.342045] Starting dynamic subtest: C-eDP1 [206.515081] Dynamic subtest C-eDP1: SUCCESS (2.181s) [206.524942] Starting dynamic subtest: D-eDP1 [208.665762] Dynamic subtest D-eDP1: SUCCESS (2.150s) [208.668362] Subtest basic-flip-vs-modeset: SUCCESS (9.149s) [209.474301] [045/155] (809s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt) [209.565640] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt [209.570023] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [209.674917] [046/155] (808s left) core_getversion (basic) [209.708319] Starting subtest: basic [209.712565] Subtest basic: SUCCESS (0.000s) [209.774582] [047/155] (808s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf) [209.863174] Starting subtest: fbc-psr2-overlay-plane-move-continuous-sf [209.865472] Starting dynamic subtest: pipe-A-eDP-1 [209.959485] Dynamic subtest pipe-A-eDP-1: SKIP (0.099s) [209.970622] Starting dynamic subtest: pipe-B-eDP-1 [211.161528] Dynamic subtest pipe-B-eDP-1: SKIP (1.199s) [211.166270] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (1.302s) [212.362210] [048/155] (806s left) gem_ctx_persistence (legacy-engines-queued) [212.418316] Starting subtest: legacy-engines-queued [212.420645] Starting dynamic subtest: render [212.492573] Dynamic subtest render: SUCCESS (0.074s) [212.505556] Starting dynamic subtest: bsd1 [214.170686] Dynamic subtest bsd1: SUCCESS (1.673s) [214.182733] Starting dynamic subtest: bsd2 [216.219571] Dynamic subtest bsd2: SUCCESS (2.045s) [216.231514] Starting dynamic subtest: blt [216.270339] Dynamic subtest blt: SUCCESS (0.049s) [216.281907] Starting dynamic subtest: vebox [218.203422] Dynamic subtest vebox: SUCCESS (1.928s) [218.206213] Subtest legacy-engines-queued: SUCCESS (5.784s) [218.283165] [049/155] (800s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-render) [218.368106] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-render [218.372210] Subtest hdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s) [218.464665] [050/155] (800s left) kms_lease (possible-crtcs-filtering) [218.533417] Starting subtest: possible-crtcs-filtering [218.538330] Subtest possible-crtcs-filtering: SUCCESS (0.005s) [218.650578] [051/155] (800s left) gem_fenced_exec_thrash (no-spare-fences) [218.706472] Subtest no-spare-fences: SKIP (0.000s) [218.777335] [052/155] (800s left) gem_softpin (noreloc) [218.828862] Starting subtest: noreloc [224.258867] Subtest noreloc: SUCCESS (5.428s) [224.405718] [053/155] (794s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-onoff) [224.485148] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-onoff [224.489577] Subtest psrhdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s) [224.574454] [054/155] (794s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling) [224.640811] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling [224.645186] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.000s) [224.709327] [055/155] (794s left) gem_mmap_wc (pf-nonblock) [224.767565] Subtest pf-nonblock: SKIP (0.000s) [224.818158] [056/155] (794s left) gem_exec_schedule (submit-late-slice) [224.888708] Starting subtest: submit-late-slice [224.890961] Starting dynamic subtest: rcs0 [224.913977] Dynamic subtest rcs0: SUCCESS (0.034s) [224.923826] Starting dynamic subtest: bcs0 [224.942457] Dynamic subtest bcs0: SUCCESS (0.028s) [224.951514] Starting dynamic subtest: vcs0 [224.972883] Dynamic subtest vcs0: SUCCESS (0.029s) [224.981598] Starting dynamic subtest: vcs1 [225.005891] Dynamic subtest vcs1: SUCCESS (0.032s) [225.014957] Starting dynamic subtest: vecs0 [225.033530] Dynamic subtest vecs0: SUCCESS (0.027s) [225.043025] Starting dynamic subtest: ccs0 [225.062408] Dynamic subtest ccs0: SUCCESS (0.028s) [225.064723] Subtest submit-late-slice: SUCCESS (0.183s) [226.361873] [057/155] (792s left) gem_eio (wait-immediate) [226.583035] Starting subtest: wait-immediate [226.944851] Subtest wait-immediate: SUCCESS (0.363s) [227.067026] [058/155] (791s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-shrfb-plflip-blt) [227.141168] Starting subtest: fbchdr-2p-primscrn-shrfb-plflip-blt [227.145319] Subtest fbchdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [227.243653] [059/155] (791s left) kms_frontbuffer_tracking (fbc-modesetfrombusy) [227.322227] Starting subtest: fbc-modesetfrombusy [231.216104] Subtest fbc-modesetfrombusy: SUCCESS (3.897s) [232.413303] [060/155] (786s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc) [232.487959] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc [232.492389] Subtest fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [232.574653] [061/155] (786s left) gem_exec_params (sol-reset-not-gen7) [232.637450] Starting subtest: sol-reset-not-gen7 [232.639766] Subtest sol-reset-not-gen7: SUCCESS (0.002s) [232.756435] [062/155] (786s left) gem_pxp (verify-pxp-key-change-after-suspend-resume) [232.866046] Starting subtest: verify-pxp-key-change-after-suspend-resume [240.130672] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.266s) [240.256662] [063/155] (779s left) prime_vgem (busy) [240.412426] Starting subtest: busy [240.414659] Starting dynamic subtest: rcs0 [240.458873] Dynamic subtest rcs0: SUCCESS (0.049s) [240.468839] Starting dynamic subtest: bcs0 [240.517690] Dynamic subtest bcs0: SUCCESS (0.058s) [240.527321] Starting dynamic subtest: vcs0 [240.565645] Dynamic subtest vcs0: SUCCESS (0.047s) [240.575231] Starting dynamic subtest: vcs1 [240.608734] Dynamic subtest vcs1: SUCCESS (0.042s) [240.618337] Starting dynamic subtest: vecs0 [240.656267] Dynamic subtest vecs0: SUCCESS (0.047s) [240.665770] Starting dynamic subtest: ccs0 [240.701671] Dynamic subtest ccs0: SUCCESS (0.044s) [240.703850] Subtest busy: SUCCESS (0.293s) [240.817429] [FACT prime_vgem (busy)] new: kernel.kmod_is_loaded.vgem: true [240.821094] [064/155] (778s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic) [240.913136] Starting subtest: 2x-long-flip-vs-cursor-atomic [240.917586] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s) [241.057596] [065/155] (778s left) kms_cursor_crc (cursor-rapid-movement-128x42) [241.253445] Starting subtest: cursor-rapid-movement-128x42 [241.297289] Subtest cursor-rapid-movement-128x42: SKIP (0.041s) [241.480101] [066/155] (777s left) kms_scaling_modes (scaling-mode-full-aspect) [241.551710] Starting subtest: scaling-mode-full-aspect [241.553702] Starting dynamic subtest: pipe-A-eDP-1 [241.643354] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.093s) [241.656329] Starting dynamic subtest: pipe-B-eDP-1 [242.826795] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.178s) [242.836635] Starting dynamic subtest: pipe-C-eDP-1 [243.957642] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.129s) [243.968283] Starting dynamic subtest: pipe-D-eDP-1 [245.090362] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s) [245.092662] Subtest scaling-mode-full-aspect: SUCCESS (3.541s) [246.247825] [067/155] (773s left) kms_plane (plane-position-hole) [246.330375] Starting subtest: plane-position-hole [246.827061] Starting dynamic subtest: pipe-A-plane-1 [248.109045] Dynamic subtest pipe-A-plane-1: SUCCESS (1.282s) [248.120294] Starting dynamic subtest: pipe-A-plane-2 [249.333718] Dynamic subtest pipe-A-plane-2: SUCCESS (1.223s) [249.343837] Starting dynamic subtest: pipe-A-plane-3 [250.553968] Dynamic subtest pipe-A-plane-3: SUCCESS (1.220s) [250.564507] Starting dynamic subtest: pipe-A-plane-4 [251.807204] Dynamic subtest pipe-A-plane-4: SUCCESS (1.251s) [251.819983] Starting dynamic subtest: pipe-A-plane-5 [251.830954] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s) [252.993634] Starting dynamic subtest: pipe-B-plane-1 [254.292583] Dynamic subtest pipe-B-plane-1: SUCCESS (1.307s) [254.302787] Starting dynamic subtest: pipe-B-plane-2 [255.523226] Dynamic subtest pipe-B-plane-2: SUCCESS (1.229s) [255.533106] Starting dynamic subtest: pipe-B-plane-3 [256.755247] Dynamic subtest pipe-B-plane-3: SUCCESS (1.232s) [256.765817] Starting dynamic subtest: pipe-B-plane-4 [257.964103] Dynamic subtest pipe-B-plane-4: SUCCESS (1.209s) [257.975073] Starting dynamic subtest: pipe-B-plane-5 [257.988570] Dynamic subtest pipe-B-plane-5: SUCCESS (0.023s) [257.990917] Subtest plane-position-hole: SUCCESS (11.660s) [258.784425] [068/155] (760s left) vgem_basic (second-client) [258.828082] Starting subtest: second-client [258.850121] Subtest second-client: SUCCESS (0.021s) [258.909986] [069/155] (760s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-4) [258.993158] Starting subtest: fbcpsrhdr-tiling-4 [258.997276] Subtest fbcpsrhdr-tiling-4: SKIP (0.000s) [259.092170] [070/155] (760s left) gem_mmap_offset (bad-object) [259.143534] Starting subtest: bad-object [259.145482] Subtest bad-object: SUCCESS (0.001s) [259.251292] [071/155] (760s left) kms_prime (basic-crc-vgem) [259.313596] Starting subtest: basic-crc-vgem [259.315898] Starting dynamic subtest: second-to-first [260.259692] Dynamic subtest second-to-first: SUCCESS (0.950s) [260.261757] Subtest basic-crc-vgem: SUCCESS (0.951s) [260.350452] [072/155] (759s left) kms_setmode (invalid-clone-single-crtc) [260.410277] Starting subtest: invalid-clone-single-crtc [260.415720] Subtest invalid-clone-single-crtc: SKIP (0.000s) [260.503770] [073/155] (759s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [260.578146] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip [260.581958] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s) [260.661943] [074/155] (759s left) kms_big_fb (linear-8bpp-rotate-90) [260.737704] Starting subtest: linear-8bpp-rotate-90 [260.760228] Subtest linear-8bpp-rotate-90: SKIP (0.022s) [260.864237] [075/155] (758s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt) [260.939267] Starting subtest: psr-rgb101010-draw-blt [268.273902] Subtest psr-rgb101010-draw-blt: SUCCESS (7.338s) [268.380208] [076/155] (751s left) kms_atomic (plane-invalid-params) [268.453817] Starting subtest: plane-invalid-params [268.456148] Starting dynamic subtest: pipe-A-eDP-1 [268.858232] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.403s) [268.860444] Subtest plane-invalid-params: SUCCESS (0.404s) [269.738708] [077/155] (750s left) kms_psr (pr-sprite-blt) [269.804949] Starting subtest: pr-sprite-blt [269.810270] Subtest pr-sprite-blt: SKIP (0.000s) [269.878084] [078/155] (749s left) kms_cursor_crc (cursor-onscreen-32x32) [269.993573] Starting subtest: cursor-onscreen-32x32 [270.039619] Subtest cursor-onscreen-32x32: SKIP (0.041s) [270.320495] [079/155] (749s left) kms_getfb (getfb-handle-not-fb) [270.390466] Starting subtest: getfb-handle-not-fb [270.392721] Subtest getfb-handle-not-fb: SUCCESS (0.000s) [270.492196] [080/155] (749s left) perf_pmu (busy-idle-check-all) [270.530993] Starting subtest: busy-idle-check-all [270.533262] Starting dynamic subtest: rcs0 [271.183474] Dynamic subtest rcs0: SUCCESS (0.652s) [271.196262] Starting dynamic subtest: bcs0 [271.835489] Dynamic subtest bcs0: SUCCESS (0.650s) [271.848202] Starting dynamic subtest: vcs0 [273.213499] Dynamic subtest vcs0: SUCCESS (1.374s) [273.227663] Starting dynamic subtest: vcs1 [275.262729] Dynamic subtest vcs1: SUCCESS (2.045s) [275.276715] Starting dynamic subtest: vecs0 [277.246795] Dynamic subtest vecs0: SUCCESS (1.981s) [277.259628] Starting dynamic subtest: ccs0 [277.902886] Dynamic subtest ccs0: SUCCESS (0.656s) [277.905646] Subtest busy-idle-check-all: SUCCESS (7.373s) [278.021369] [081/155] (741s left) gem_render_copy (y-tiled-to-vebox-yf-tiled) [278.076674] Starting subtest: y-tiled-to-vebox-yf-tiled [278.080738] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s) [278.265841] [082/155] (741s left) gem_exec_params (no-blt) [278.329022] Starting subtest: no-blt [278.335146] Subtest no-blt: SUCCESS (0.004s) [278.454474] [083/155] (741s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions) [278.551600] Starting subtest: flip-vs-cursor-atomic-transitions [286.424966] Subtest flip-vs-cursor-atomic-transitions: SUCCESS (7.872s) [287.282221] [084/155] (732s left) kms_flip (flip-vs-absolute-wf_vblank) [287.368337] Starting subtest: flip-vs-absolute-wf_vblank [287.370633] Starting dynamic subtest: A-eDP1 [296.308662] Dynamic subtest A-eDP1: SUCCESS (8.943s) [296.320296] Starting dynamic subtest: B-eDP1 [305.244307] Dynamic subtest B-eDP1: SUCCESS (8.935s) [305.255683] Starting dynamic subtest: C-eDP1 [314.169974] Dynamic subtest C-eDP1: SUCCESS (8.922s) [314.182839] Starting dynamic subtest: D-eDP1 [323.108024] Dynamic subtest D-eDP1: SUCCESS (8.938s) [323.110598] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.746s) [324.337860] [085/155] (695s left) kms_3d (basic) [324.401649] Starting subtest: basic [324.405782] Subtest basic: SKIP (0.000s) [324.491585] [086/155] (695s left) kms_flip (2x-flip-vs-wf_vblank-interruptible) [324.573805] Starting subtest: 2x-flip-vs-wf_vblank-interruptible [324.582954] Subtest 2x-flip-vs-wf_vblank-interruptible: SKIP (0.008s) [324.656940] [087/155] (695s left) kms_color_pipeline (plane-ctm3x4) [324.734663] Starting subtest: plane-ctm3x4 [324.880915] Starting dynamic subtest: pipe-A-plane-0 [325.003338] Dynamic subtest pipe-A-plane-0: SUCCESS (0.122s) [325.013996] Starting dynamic subtest: pipe-A-plane-1 [325.125294] Dynamic subtest pipe-A-plane-1: SUCCESS (0.121s) [325.136419] Starting dynamic subtest: pipe-A-plane-2 [325.258133] Dynamic subtest pipe-A-plane-2: SUCCESS (0.132s) [326.428856] Starting dynamic subtest: pipe-B-plane-0 [326.551238] Dynamic subtest pipe-B-plane-0: SUCCESS (0.133s) [326.561702] Starting dynamic subtest: pipe-B-plane-1 [326.685086] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s) [326.695739] Starting dynamic subtest: pipe-B-plane-2 [326.796231] Dynamic subtest pipe-B-plane-2: SUCCESS (0.110s) [327.963417] Starting dynamic subtest: pipe-C-plane-0 [328.065718] Dynamic subtest pipe-C-plane-0: SUCCESS (0.111s) [328.076596] Starting dynamic subtest: pipe-C-plane-1 [328.176741] Dynamic subtest pipe-C-plane-1: SUCCESS (0.110s) [328.187360] Starting dynamic subtest: pipe-C-plane-2 [328.311088] Dynamic subtest pipe-C-plane-2: SUCCESS (0.132s) [329.517103] Starting dynamic subtest: pipe-D-plane-0 [329.617799] Dynamic subtest pipe-D-plane-0: SUCCESS (0.111s) [329.628803] Starting dynamic subtest: pipe-D-plane-1 [329.727153] Dynamic subtest pipe-D-plane-1: SUCCESS (0.110s) [329.737977] Starting dynamic subtest: pipe-D-plane-2 [329.837979] Dynamic subtest pipe-D-plane-2: SUCCESS (0.110s) [330.127257] Subtest plane-ctm3x4: SUCCESS (5.390s) [330.975705] [088/155] (689s left) kms_ccs (crc-primary-basic-y-tiled-ccs) [331.050431] Starting subtest: crc-primary-basic-y-tiled-ccs [331.052773] Starting dynamic subtest: pipe-A-eDP-1 [331.076222] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [331.086958] Starting dynamic subtest: pipe-B-eDP-1 [331.109091] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [331.119297] Starting dynamic subtest: pipe-C-eDP-1 [331.141484] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [331.151276] Starting dynamic subtest: pipe-D-eDP-1 [331.173201] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [331.177226] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.005s) [333.327064] [089/155] (687s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render) [333.405227] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render [340.710085] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.312s) [340.824447] [090/155] (679s left) gem_exec_schedule (wide) [341.013859] Starting subtest: wide [341.015724] Starting dynamic subtest: rcs0 [350.532441] Dynamic subtest rcs0: SUCCESS (9.518s) [350.545868] Starting dynamic subtest: vcs0 [361.546981] Dynamic subtest vcs0: SUCCESS (11.013s) [361.558058] Starting dynamic subtest: vcs1 [371.455648] Dynamic subtest vcs1: SUCCESS (9.906s) [371.468724] Starting dynamic subtest: vecs0 [382.101217] Dynamic subtest vecs0: SUCCESS (10.643s) [382.112639] Starting dynamic subtest: ccs0 [394.261834] Dynamic subtest ccs0: SUCCESS (12.158s) [394.264508] Subtest wide: SUCCESS (53.247s) [396.546708] [091/155] (623s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip) [396.644121] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip [396.648485] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s) [396.741959] [092/155] (623s left) gem_exec_nop (basic-sequential) [396.808294] Starting subtest: basic-sequential [411.705252] Subtest basic-sequential: SUCCESS (14.906s) [411.930184] [093/155] (608s left) gem_exec_flush (basic-uc-rw-default) [412.003180] Starting subtest: basic-uc-rw-default [417.459948] Subtest basic-uc-rw-default: SUCCESS (5.459s) [417.648628] [094/155] (602s left) kms_chamelium_hpd (hdmi-hpd-storm) [418.022683] Subtest hdmi-hpd-storm: SKIP (0.000s) [418.837480] [095/155] (601s left) gem_ctx_shared (q-smoketest-all) [418.905756] Starting subtest: Q-smoketest-all [451.298121] Subtest Q-smoketest-all: SUCCESS (32.343s) [452.267819] [096/155] (568s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move) [452.344979] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move [459.726347] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.389s) [459.861270] [097/155] (560s left) gem_lmem_swapping (verify-random-ccs) [459.906980] Subtest verify-random-ccs: SKIP (0.000s) [459.949187] [098/155] (560s left) sysfs_heartbeat_interval (idempotent) [460.002789] Starting subtest: idempotent [460.005127] Starting dynamic subtest: vecs0 [460.007361] Dynamic subtest vecs0: SUCCESS (0.001s) [460.017704] Starting dynamic subtest: ccs0 [460.019819] Dynamic subtest ccs0: SUCCESS (0.001s) [460.029770] Starting dynamic subtest: vcs0 [460.032025] Dynamic subtest vcs0: SUCCESS (0.001s) [460.041919] Starting dynamic subtest: bcs0 [460.044124] Dynamic subtest bcs0: SUCCESS (0.001s) [460.053996] Starting dynamic subtest: rcs0 [460.056206] Dynamic subtest rcs0: SUCCESS (0.001s) [460.066056] Starting dynamic subtest: vcs1 [460.068259] Dynamic subtest vcs1: SUCCESS (0.000s) [460.070492] Subtest idempotent: SUCCESS (0.008s) [460.146657] [099/155] (560s left) gem_userptr_blits (unsync-unmap-after-close) [460.218819] Subtest unsync-unmap-after-close: SKIP (0.000s) [460.348074] [100/155] (560s left) gen7_exec_parse (load-register-reg) [460.407186] Subtest load-register-reg: SKIP (0.000s) [460.481192] [101/155] (560s left) kms_vblank (ts-continuation-idle) [460.555427] Starting subtest: ts-continuation-idle [460.557725] Starting dynamic subtest: pipe-A-eDP-1 [460.972047] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.415s) [460.986730] Starting dynamic subtest: pipe-D-eDP-1 [462.087381] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.112s) [462.089645] Subtest ts-continuation-idle: SUCCESS (1.531s) [462.953201] [102/155] (557s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-mmap-wc) [463.037252] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-mmap-wc [463.041207] Subtest psrhdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [463.137217] [103/155] (557s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-shrfb-pgflip-blt) [463.219460] Starting subtest: fbchdr-1p-primscrn-shrfb-pgflip-blt [463.223626] Subtest fbchdr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [463.313488] [104/155] (557s left) gem_spin_batch (engines) [463.378404] Starting subtest: engines [463.380801] Starting dynamic subtest: rcs0 [466.612378] Dynamic subtest rcs0: SUCCESS (3.232s) [466.625553] Starting dynamic subtest: bcs0 [469.847415] Dynamic subtest bcs0: SUCCESS (3.232s) [469.862881] Starting dynamic subtest: vcs0 [473.082093] Dynamic subtest vcs0: SUCCESS (3.231s) [473.092422] Starting dynamic subtest: vcs1 [476.316547] Dynamic subtest vcs1: SUCCESS (3.232s) [476.329266] Starting dynamic subtest: vecs0 [479.550548] Dynamic subtest vecs0: SUCCESS (3.231s) [479.563483] Starting dynamic subtest: ccs0 [482.786562] Dynamic subtest ccs0: SUCCESS (3.233s) [482.789251] Subtest engines: SUCCESS (19.407s) [483.021970] [105/155] (537s left) kms_invalid_mode (bad-vtotal) [483.102380] Starting subtest: bad-vtotal [483.105049] Starting dynamic subtest: pipe-A-eDP-1 [483.393143] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.293s) [483.404071] Starting dynamic subtest: pipe-B-eDP-1 [483.406025] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [483.416120] Starting dynamic subtest: pipe-C-eDP-1 [483.418357] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [483.428140] Starting dynamic subtest: pipe-D-eDP-1 [483.430399] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [483.432706] Subtest bad-vtotal: SUCCESS (0.300s) [484.213257] [106/155] (536s left) kms_pm_rpm (basic-pci-d3-state) [486.316900] Starting subtest: basic-pci-d3-state [487.791404] Subtest basic-pci-d3-state: SUCCESS (1.476s) [487.892980] [107/155] (533s left) gem_exec_params (rel-constants-invalid) [487.961661] Starting subtest: rel-constants-invalid [487.964075] Subtest rel-constants-invalid: SUCCESS (0.000s) [488.066246] [108/155] (533s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-plflip-blt) [488.150300] Starting subtest: fbcpsr-2p-scndscrn-indfb-plflip-blt [488.154894] Subtest fbcpsr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s) [488.233700] [109/155] (532s left) gem_exercise_blt (fast-copy) [488.291899] Starting subtest: fast-copy [488.294180] Starting dynamic subtest: linear-smem-smem [488.312846] Dynamic subtest linear-smem-smem: SUCCESS (0.021s) [488.323385] Starting dynamic subtest: xmajor-smem-smem [488.330068] Dynamic subtest xmajor-smem-smem: SUCCESS (0.017s) [488.340302] Starting dynamic subtest: tile4-smem-smem [488.346076] Dynamic subtest tile4-smem-smem: SUCCESS (0.015s) [488.356095] Starting dynamic subtest: tile64-smem-smem [488.362839] Dynamic subtest tile64-smem-smem: SUCCESS (0.016s) [488.365339] Subtest fast-copy: SUCCESS (0.072s) [488.517432] [110/155] (532s left) prime_mmap (test_userptr) [488.573998] Starting subtest: test_userptr [488.576342] Starting dynamic subtest: test_userptr-smem [488.578608] Dynamic subtest test_userptr-smem: SUCCESS (0.000s) [488.580522] Subtest test_userptr: SUCCESS (0.001s) [488.676172] [111/155] (532s left) kms_properties (get_properties-sanity-atomic) [488.761195] Starting subtest: get_properties-sanity-atomic [488.763790] Subtest get_properties-sanity-atomic: SUCCESS (0.002s) [488.836701] [112/155] (532s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render) [488.903462] Starting subtest: fbcpsr-rgb565-draw-render [495.982472] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.082s) [496.108725] [113/155] (525s left) perf (short-reads) [496.236629] Starting subtest: short-reads [496.248507] Subtest short-reads: SUCCESS (0.014s) [496.294948] [114/155] (525s left) kms_psr (fbc-psr-cursor-plane-onoff) [496.363451] Starting subtest: fbc-psr-cursor-plane-onoff [496.365735] Starting dynamic subtest: eDP-1 [496.370220] Dynamic subtest eDP-1: SKIP (0.000s) [496.374639] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s) [496.437453] [115/155] (525s left) api_intel_bb (render-ccs) [496.488752] Starting subtest: render-ccs [496.828849] Subtest render-ccs: SUCCESS (0.340s) [496.919957] [116/155] (524s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc) [496.997044] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc [497.001183] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [497.089542] [117/155] (524s left) i915_drm_fdinfo (all-busy-check-all) [497.130091] Starting subtest: all-busy-check-all [497.134695] Subtest all-busy-check-all: SKIP (0.004s) [497.195097] [118/155] (524s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted) [497.261237] Starting subtest: etime-multi-wait-for-submit-unsubmitted [497.370049] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.108s) [497.462916] [119/155] (524s left) kms_chamelium_color (ctm-blue-to-red) [497.537080] Subtest ctm-blue-to-red: SKIP (0.000s) [497.583418] [120/155] (524s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu) [497.668684] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu [497.673158] Subtest hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [497.777236] [121/155] (523s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-render) [497.856982] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-render [497.861421] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s) [497.943897] [122/155] (523s left) gem_exec_schedule (pi-common) [498.113067] Starting subtest: pi-common [498.115411] Starting dynamic subtest: rcs0 [498.148408] Dynamic subtest rcs0: SUCCESS (0.039s) [498.159773] Starting dynamic subtest: bcs0 [498.194083] Dynamic subtest bcs0: SUCCESS (0.045s) [498.205081] Starting dynamic subtest: vcs0 [498.231059] Dynamic subtest vcs0: SUCCESS (0.036s) [498.244787] Starting dynamic subtest: vcs1 [498.265561] Dynamic subtest vcs1: SUCCESS (0.034s) [498.278806] Starting dynamic subtest: vecs0 [498.297107] Dynamic subtest vecs0: SUCCESS (0.030s) [498.312081] Starting dynamic subtest: ccs0 [498.324552] Dynamic subtest ccs0: SUCCESS (0.027s) [498.326636] Subtest pi-common: SUCCESS (0.216s) [498.550447] [123/155] (523s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-pwrite) [498.634804] Starting subtest: psrhdr-rgb565-draw-pwrite [498.638957] Subtest psrhdr-rgb565-draw-pwrite: SKIP (0.000s) [498.739612] [124/155] (523s left) api_intel_bb (crc32) [498.785515] Starting subtest: crc32 [498.787422] Starting dynamic subtest: rcs0-smem0 [499.117173] Dynamic subtest rcs0-smem0: SUCCESS (0.331s) [499.131412] Starting dynamic subtest: bcs0-smem0 [499.419271] Dynamic subtest bcs0-smem0: SUCCESS (0.299s) [499.433195] Starting dynamic subtest: vcs0-smem0 [499.751604] Dynamic subtest vcs0-smem0: SUCCESS (0.331s) [499.768295] Starting dynamic subtest: vcs1-smem0 [500.069558] Dynamic subtest vcs1-smem0: SUCCESS (0.313s) [500.083059] Starting dynamic subtest: vecs0-smem0 [500.382452] Dynamic subtest vecs0-smem0: SUCCESS (0.310s) [500.397260] Starting dynamic subtest: ccs0-smem0 [500.696634] Dynamic subtest ccs0-smem0: SUCCESS (0.312s) [500.699647] Subtest crc32: SUCCESS (1.912s) [500.826775] [125/155] (521s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [500.912522] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu [500.916576] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [501.010301] [126/155] (520s left) kms_psr (fbc-psr2-sprite-mmap-cpu) [501.080710] Starting subtest: fbc-psr2-sprite-mmap-cpu [501.083557] Starting dynamic subtest: eDP-1 [501.088308] Dynamic subtest eDP-1: SKIP (0.000s) [501.092256] Subtest fbc-psr2-sprite-mmap-cpu: SKIP (0.001s) [501.157404] [127/155] (520s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75) [501.228284] Starting subtest: planes-upscale-20x20-downscale-factor-0-75 [501.230624] Starting dynamic subtest: pipe-A [501.251321] Dynamic subtest pipe-A: SKIP (0.019s) [501.262397] Starting dynamic subtest: pipe-B [501.283150] Dynamic subtest pipe-B: SKIP (0.031s) [501.293940] Starting dynamic subtest: pipe-C [501.314458] Dynamic subtest pipe-C: SKIP (0.031s) [501.324972] Starting dynamic subtest: pipe-D [501.346392] Dynamic subtest pipe-D: SKIP (0.030s) [501.350828] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.113s) [501.460290] [128/155] (520s left) kms_psr (psr2-sprite-plane-onoff) [501.535723] Starting subtest: psr2-sprite-plane-onoff [501.538059] Starting dynamic subtest: eDP-1 [503.291820] Dynamic subtest eDP-1: SUCCESS (1.755s) [503.294260] Subtest psr2-sprite-plane-onoff: SUCCESS (1.756s) [504.148184] [129/155] (517s left) kms_fbcon_fbt (fbc-suspend) [504.195428] Starting subtest: fbc-suspend [553.973131] Subtest fbc-suspend: SUCCESS (19.506s) [555.347900] [130/155] (466s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render) [555.428686] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render [555.432815] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s) [555.527309] [131/155] (466s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-render) [555.600638] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-render [555.605037] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s) [555.697776] [132/155] (466s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [555.770851] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt [555.775197] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [555.865929] [133/155] (466s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs) [555.941637] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs [555.943697] Starting dynamic subtest: pipe-A-eDP-1 [555.965176] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [555.978120] Starting dynamic subtest: pipe-B-eDP-1 [556.000674] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [556.011717] Starting dynamic subtest: pipe-C-eDP-1 [556.033181] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [556.043319] Starting dynamic subtest: pipe-D-eDP-1 [556.065222] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [556.069219] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs: SKIP (0.005s) [558.197169] [134/155] (464s left) gem_exec_reloc (basic-concurrent16) [558.255691] Subtest basic-concurrent16: SKIP (0.000s) [558.340029] [135/155] (463s left) kms_chamelium_frames (hdmi-crc-single) [558.706077] Subtest hdmi-crc-single: SKIP (0.000s) [559.492567] [136/155] (462s left) kms_psr (fbc-pr-cursor-mmap-gtt) [559.567938] Starting subtest: fbc-pr-cursor-mmap-gtt [559.572726] Subtest fbc-pr-cursor-mmap-gtt: SKIP (0.000s) [559.645981] [137/155] (462s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-blt) [559.735410] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-blt [563.797772] Subtest fbc-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (4.106s) [564.958120] [138/155] (457s left) gem_ctx_engines (execute-oneforall) [565.013653] Starting subtest: execute-oneforall [565.504226] Subtest execute-oneforall: SUCCESS (0.494s) [567.068658] [139/155] (455s left) kms_rmfb (rmfb-ioctl) [567.142426] Starting subtest: rmfb-ioctl [567.144728] Starting dynamic subtest: pipe-A-eDP-1 [567.247023] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s) [567.259877] Starting dynamic subtest: pipe-B-eDP-1 [568.397361] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.153s) [568.409227] Starting dynamic subtest: pipe-C-eDP-1 [569.602332] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.208s) [569.614321] Starting dynamic subtest: pipe-D-eDP-1 [570.748477] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.148s) [570.750676] Subtest rmfb-ioctl: SUCCESS (3.621s) [571.965900] [140/155] (450s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu) [572.050911] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu [572.055289] Subtest psrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [572.132391] [141/155] (450s left) i915_hangman (engine-engine-hang) [572.194324] Starting subtest: engine-engine-hang [572.196843] Starting dynamic subtest: rcs0 [573.902470] Dynamic subtest rcs0: SUCCESS (1.712s) [573.914724] Starting dynamic subtest: bcs0 [575.885455] Dynamic subtest bcs0: SUCCESS (1.984s) [575.898497] Starting dynamic subtest: vcs0 [577.933795] Dynamic subtest vcs0: SUCCESS (2.049s) [577.949642] Starting dynamic subtest: vcs1 [579.917092] Dynamic subtest vcs1: SUCCESS (1.984s) [579.931017] Starting dynamic subtest: vecs0 [581.902006] Dynamic subtest vecs0: SUCCESS (1.982s) [581.914004] Starting dynamic subtest: ccs0 [583.885565] Dynamic subtest ccs0: SUCCESS (1.980s) [583.888600] Subtest engine-engine-hang: SUCCESS (11.707s) [583.972983] [142/155] (438s left) gem_fenced_exec_thrash (no-spare-fences-interruptible) [584.035101] Subtest no-spare-fences-interruptible: SKIP (0.000s) [584.118591] [143/155] (438s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc) [584.197440] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc [584.199676] Starting dynamic subtest: pipe-A-eDP-1 [584.222386] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [584.233969] Starting dynamic subtest: pipe-B-eDP-1 [584.255685] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [584.266380] Starting dynamic subtest: pipe-C-eDP-1 [584.288076] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [584.300102] Starting dynamic subtest: pipe-D-eDP-1 [584.322320] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [584.326870] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s) [586.523513] [144/155] (436s left) syncobj_eventfd (invalid-bad-pad) [586.576974] Starting subtest: invalid-bad-pad [586.578837] Subtest invalid-bad-pad: SUCCESS (0.000s) [586.689213] [145/155] (436s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render) [586.768668] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render [586.772787] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [586.885668] [146/155] (435s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0-hflip) [586.976115] Starting subtest: linear-max-hw-stride-32bpp-rotate-0-hflip [586.980483] Subtest linear-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s) [587.077422] [147/155] (435s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt) [587.160909] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt [594.049353] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.889s) [594.193659] [148/155] (428s left) perf_pmu (rc6-suspend) [594.233095] Starting subtest: rc6-suspend [617.059950] Subtest rc6-suspend: SUCCESS (7.617s) [617.920696] [149/155] (404s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt) [618.010788] Starting subtest: hdr-rgb565-draw-blt [618.014862] Subtest hdr-rgb565-draw-blt: SKIP (0.000s) [618.108772] [150/155] (404s left) kms_debugfs (display-on-read-all) [618.177440] Starting subtest: display-on-read-all [618.337973] Subtest display-on-read-all: SUCCESS (0.166s) [618.458282] [151/155] (404s left) kms_feature_discovery (chamelium) [618.537329] Starting subtest: chamelium [618.540717] Subtest chamelium: SKIP (0.000s) [618.608747] [152/155] (404s left) kms_plane_alpha_blend (alpha-basic) [618.680680] Starting subtest: alpha-basic [618.960983] Starting dynamic subtest: pipe-A-eDP-1 [620.838345] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.911s) [620.868745] Starting dynamic subtest: pipe-D-eDP-1 [623.491187] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.667s) [623.493511] Subtest alpha-basic: SUCCESS (4.889s) [624.371264] [153/155] (398s left) gem_eio (in-flight-external) [624.572016] Starting subtest: in-flight-external [626.331611] Subtest in-flight-external: SUCCESS (1.777s) [626.477809] [154/155] (396s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt) [626.559371] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt [626.563399] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [626.669493] [155/155] (396s left) gem_exec_fence (syncobj-timeline-signal) [627.213731] Starting subtest: syncobj-timeline-signal [627.220738] Subtest syncobj-timeline-signal: SUCCESS (0.010s) [628.337345] Closing watchdogs results: parsing output: 0/ for test: kms_frontbuffer_tracking results: parsing output: 1/ for test: kms_frontbuffer_tracking results: parsing output: 2/ for test: gem_exec_reloc results: parsing output: 3/ for test: gem_exec_balancer results: parsing output: 4/ for test: gem_exec_reloc results: parsing output: 5/ for test: gem_ctx_persistence results: parsing output: 6/ for test: syncobj_timeline results: parsing output: 7/ for test: kms_frontbuffer_tracking results: parsing output: 8/ for test: i915_pm_rps results: parsing output: 9/ for test: kms_frontbuffer_tracking results: parsing output: 10/ for test: syncobj_wait results: parsing output: 11/ for test: kms_big_fb results: parsing output: 12/ for test: gem_ccs results: parsing output: 13/ for test: kms_flip results: parsing output: 14/ for test: kms_frontbuffer_tracking results: parsing output: 15/ for test: kms_frontbuffer_tracking results: parsing output: 16/ for test: syncobj_timeline results: parsing output: 17/ for test: kms_content_protection results: parsing output: 18/ for test: gem_fence_thrash results: parsing output: 19/ for test: gem_exec_parallel results: parsing output: 20/ for test: kms_flip_scaled_crc results: parsing output: 21/ for test: kms_big_fb results: parsing output: 22/ for test: kms_psr2_sf results: parsing output: 23/ for test: i915_query results: parsing output: 24/ for test: gem_mmap_gtt results: parsing output: 25/ for test: kms_frontbuffer_tracking results: parsing output: 26/ for test: kms_cursor_legacy results: parsing output: 27/ for test: kms_properties results: parsing output: 28/ for test: kms_frontbuffer_tracking results: parsing output: 29/ for test: kms_frontbuffer_tracking results: parsing output: 30/ for test: kms_plane results: parsing output: 31/ for test: gem_exec_balancer results: parsing output: 32/ for test: kms_vblank results: parsing output: 33/ for test: kms_frontbuffer_tracking results: parsing output: 34/ for test: perf_pmu results: parsing output: 35/ for test: syncobj_timeline results: parsing output: 36/ for test: kms_frontbuffer_tracking results: parsing output: 37/ for test: gem_userptr_blits results: parsing output: 38/ for test: kms_ccs results: parsing output: 39/ for test: kms_cursor_crc results: parsing output: 40/ for test: kms_frontbuffer_tracking results: parsing output: 41/ for test: kms_big_fb results: parsing output: 42/ for test: kms_plane results: parsing output: 43/ for test: kms_flip results: parsing output: 44/ for test: kms_frontbuffer_tracking results: parsing output: 45/ for test: core_getversion results: parsing output: 46/ for test: kms_psr2_sf results: parsing output: 47/ for test: gem_ctx_persistence results: parsing output: 48/ for test: kms_frontbuffer_tracking results: parsing output: 49/ for test: kms_lease results: parsing output: 50/ for test: gem_fenced_exec_thrash results: parsing output: 51/ for test: gem_softpin results: parsing output: 52/ for test: kms_frontbuffer_tracking results: parsing output: 53/ for test: kms_flip_scaled_crc results: parsing output: 54/ for test: gem_mmap_wc results: parsing output: 55/ for test: gem_exec_schedule results: parsing output: 56/ for test: gem_eio results: parsing output: 57/ for test: kms_frontbuffer_tracking results: parsing output: 58/ for test: kms_frontbuffer_tracking results: parsing output: 59/ for test: kms_frontbuffer_tracking results: parsing output: 60/ for test: gem_exec_params results: parsing output: 61/ for test: gem_pxp results: parsing output: 62/ for test: prime_vgem results: parsing output: 63/ for test: kms_cursor_legacy results: parsing output: 64/ for test: kms_cursor_crc results: parsing output: 65/ for test: kms_scaling_modes results: parsing output: 66/ for test: kms_plane results: parsing output: 67/ for test: vgem_basic results: parsing output: 68/ for test: kms_frontbuffer_tracking results: parsing output: 69/ for test: gem_mmap_offset results: parsing output: 70/ for test: kms_prime results: parsing output: 71/ for test: kms_setmode results: parsing output: 72/ for test: kms_big_fb results: parsing output: 73/ for test: kms_big_fb results: parsing output: 74/ for test: kms_frontbuffer_tracking results: parsing output: 75/ for test: kms_atomic results: parsing output: 76/ for test: kms_psr results: parsing output: 77/ for test: kms_cursor_crc results: parsing output: 78/ for test: kms_getfb results: parsing output: 79/ for test: perf_pmu results: parsing output: 80/ for test: gem_render_copy results: parsing output: 81/ for test: gem_exec_params results: parsing output: 82/ for test: kms_cursor_legacy results: parsing output: 83/ for test: kms_flip results: parsing output: 84/ for test: kms_3d results: parsing output: 85/ for test: kms_flip results: parsing output: 86/ for test: kms_color_pipeline results: parsing output: 87/ for test: kms_ccs results: parsing output: 88/ for test: kms_frontbuffer_tracking results: parsing output: 89/ for test: gem_exec_schedule results: parsing output: 90/ for test: kms_big_fb results: parsing output: 91/ for test: gem_exec_nop results: parsing output: 92/ for test: gem_exec_flush results: parsing output: 93/ for test: kms_chamelium_hpd results: parsing output: 94/ for test: gem_ctx_shared results: parsing output: 95/ for test: kms_frontbuffer_tracking results: parsing output: 96/ for test: gem_lmem_swapping results: parsing output: 97/ for test: sysfs_heartbeat_interval results: parsing output: 98/ for test: gem_userptr_blits results: parsing output: 99/ for test: gen7_exec_parse results: parsing output: 100/ for test: kms_vblank results: parsing output: 101/ for test: kms_frontbuffer_tracking results: parsing output: 102/ for test: kms_frontbuffer_tracking results: parsing output: 103/ for test: gem_spin_batch results: parsing output: 104/ for test: kms_invalid_mode results: parsing output: 105/ for test: kms_pm_rpm results: parsing output: 106/ for test: gem_exec_params results: parsing output: 107/ for test: kms_frontbuffer_tracking results: parsing output: 108/ for test: gem_exercise_blt results: parsing output: 109/ for test: prime_mmap results: parsing output: 110/ for test: kms_properties results: parsing output: 111/ for test: kms_frontbuffer_tracking results: parsing output: 112/ for test: perf results: parsing output: 113/ for test: kms_psr results: parsing output: 114/ for test: api_intel_bb results: parsing output: 115/ for test: kms_frontbuffer_tracking results: parsing output: 116/ for test: i915_drm_fdinfo results: parsing output: 117/ for test: syncobj_timeline results: parsing output: 118/ for test: kms_chamelium_color results: parsing output: 119/ for test: kms_frontbuffer_tracking results: parsing output: 120/ for test: kms_frontbuffer_tracking results: parsing output: 121/ for test: gem_exec_schedule results: parsing output: 122/ for test: kms_frontbuffer_tracking results: parsing output: 123/ for test: api_intel_bb results: parsing output: 124/ for test: kms_frontbuffer_tracking results: parsing output: 125/ for test: kms_psr results: parsing output: 126/ for test: kms_plane_scaling results: parsing output: 127/ for test: kms_psr results: parsing output: 128/ for test: kms_fbcon_fbt results: parsing output: 129/ for test: kms_frontbuffer_tracking results: parsing output: 130/ for test: kms_frontbuffer_tracking results: parsing output: 131/ for test: kms_frontbuffer_tracking results: parsing output: 132/ for test: kms_ccs results: parsing output: 133/ for test: gem_exec_reloc results: parsing output: 134/ for test: kms_chamelium_frames results: parsing output: 135/ for test: kms_psr results: parsing output: 136/ for test: kms_frontbuffer_tracking results: parsing output: 137/ for test: gem_ctx_engines results: parsing output: 138/ for test: kms_rmfb results: parsing output: 139/ for test: kms_frontbuffer_tracking results: parsing output: 140/ for test: i915_hangman results: parsing output: 141/ for test: gem_fenced_exec_thrash results: parsing output: 142/ for test: kms_ccs results: parsing output: 143/ for test: syncobj_eventfd results: parsing output: 144/ for test: kms_frontbuffer_tracking results: parsing output: 145/ for test: kms_big_fb results: parsing output: 146/ for test: kms_frontbuffer_tracking results: parsing output: 147/ for test: perf_pmu results: parsing output: 148/ for test: kms_frontbuffer_tracking results: parsing output: 149/ for test: kms_debugfs results: parsing output: 150/ for test: kms_feature_discovery results: parsing output: 151/ for test: kms_plane_alpha_blend results: parsing output: 152/ for test: gem_eio results: parsing output: 153/ for test: kms_frontbuffer_tracking results: parsing output: 154/ for test: gem_exec_fence Done.