[56.017585] Initializing watchdogs [56.017634] /dev/watchdog0 [56.032646] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics] [56.037044] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [56.039299] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [56.042699] [001/156] (960s left) kms_pm_rpm (system-suspend-idle) [57.632497] Starting subtest: system-suspend-idle [77.184981] Subtest system-suspend-idle: SUCCESS (4.295s) [77.589583] [002/156] (938s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted) [77.660627] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted [77.663091] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.001s) [77.768080] [003/156] (938s left) gem_mmap_offset (clear-via-pagefault) [77.824744] Starting subtest: clear-via-pagefault [77.826675] Starting dynamic subtest: smem0 [149.546592] Dynamic subtest smem0: SUCCESS (71.533s) [149.551176] Subtest clear-via-pagefault: SUCCESS (71.536s) [149.848976] [004/156] (866s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format) [150.067366] Starting subtest: plane-downscale-factor-0-75-with-pixel-format [150.069645] Starting dynamic subtest: pipe-A [151.405354] Dynamic subtest pipe-A: SUCCESS (1.340s) [151.414563] Starting dynamic subtest: pipe-B [153.715764] Dynamic subtest pipe-B: SUCCESS (2.309s) [153.725017] Starting dynamic subtest: pipe-C [154.876970] Dynamic subtest pipe-C: SUCCESS (1.160s) [154.888428] Starting dynamic subtest: pipe-D [156.106090] Dynamic subtest pipe-D: SUCCESS (1.228s) [156.108542] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (6.039s) [157.340265] [005/156] (859s left) gem_softpin (allocator-basic-reserve) [157.417545] Starting subtest: allocator-basic-reserve [157.495402] Subtest allocator-basic-reserve: SUCCESS (0.077s) [157.632459] [006/156] (859s left) gem_ccs (ctrl-surf-copy-new-ctx) [157.698258] Starting subtest: ctrl-surf-copy-new-ctx [157.702462] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s) [157.792757] [007/156] (859s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render) [157.899456] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render [165.268012] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.373s) [165.400679] [008/156] (851s left) i915_query (query-topology-garbage-items) [165.458719] Starting subtest: query-topology-garbage-items [165.460710] Subtest query-topology-garbage-items: SUCCESS (0.000s) [165.572073] [009/156] (851s left) kms_psr2_su (page_flip-nv12) [165.631944] Subtest page_flip-NV12: SKIP (0.000s) [165.690271] [010/156] (851s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [165.791271] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-mmap-gtt [165.795500] Subtest hdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [165.891040] [011/156] (851s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf) [165.994365] Starting subtest: psr2-overlay-plane-update-continuous-sf [165.996744] Starting dynamic subtest: pipe-A-eDP-1 [167.119026] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.129s) [167.130418] Starting dynamic subtest: pipe-B-eDP-1 [168.904008] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.784s) [168.906275] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (2.915s) [169.714926] [012/156] (847s left) kms_big_fb (linear-64bpp-rotate-0) [169.819927] Starting subtest: linear-64bpp-rotate-0 [171.441572] Subtest linear-64bpp-rotate-0: SUCCESS (1.623s) [171.628848] [013/156] (845s left) gem_readwrite (read-write) [171.680957] Subtest read-write: SKIP (0.000s) [171.739307] [014/156] (845s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy) [171.852189] Starting subtest: 2x-flip-vs-cursor-legacy [171.856832] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s) [171.995741] [015/156] (845s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-blt) [172.094809] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-blt [172.098839] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s) [172.185881] [016/156] (844s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [172.297668] Starting subtest: basic-flip-after-cursor-legacy [173.219771] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.922s) [174.041419] [017/156] (843s left) syncobj_basic (bad-flags-handle-to-fd) [174.101877] Starting subtest: bad-flags-handle-to-fd [174.104158] Subtest bad-flags-handle-to-fd: SUCCESS (0.000s) [174.210792] [018/156] (843s left) kms_frontbuffer_tracking (basic) [174.317161] Starting subtest: basic [177.679984] Subtest basic: SUCCESS (3.369s) [177.794216] [019/156] (839s left) gem_lmem_swapping (heavy-verify-random-ccs) [177.843433] Subtest heavy-verify-random-ccs: SKIP (0.000s) [177.882156] [020/156] (839s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu) [177.983863] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu [177.987288] Subtest psrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [178.082143] [021/156] (839s left) gem_exec_fence (basic-wait-all) [178.140434] Starting subtest: basic-wait-all [178.231453] Subtest basic-wait-all: SUCCESS (0.091s) [180.562024] [FACT gem_exec_fence (basic-wait-all)] new: kernel.kmod_is_loaded.vgem: true [180.565561] [022/156] (836s left) kms_psr (fbc-psr-primary-page-flip) [180.669541] Starting subtest: fbc-psr-primary-page-flip [180.671542] Starting dynamic subtest: eDP-1 [180.675763] Dynamic subtest eDP-1: SKIP (0.000s) [180.679888] Subtest fbc-psr-primary-page-flip: SKIP (0.001s) [180.742544] [023/156] (836s left) gem_ctx_freq (sysfs) [180.794810] Starting subtest: sysfs [180.797463] Starting dynamic subtest: gt0 [186.289794] Dynamic subtest gt0: SUCCESS (5.495s) [186.305624] Starting dynamic subtest: gt1 [227.464970] Dynamic subtest gt1: SUCCESS (41.169s) [227.467772] Subtest sysfs: SUCCESS (46.667s) [227.613050] [024/156] (789s left) api_intel_bb (blit-reloc-purge-cache) [227.677483] Starting subtest: blit-reloc-purge-cache [227.681777] Subtest blit-reloc-purge-cache: SKIP (0.001s) [227.799928] [025/156] (789s left) sriov_basic (enable-vfs-autoprobe-off) [227.849737] Starting subtest: enable-vfs-autoprobe-off [227.851957] Starting dynamic subtest: numvfs-1 [227.932316] Dynamic subtest numvfs-1: FAIL (0.028s) [227.942597] Starting dynamic subtest: numvfs-2 [228.013964] Dynamic subtest numvfs-2: FAIL (0.004s) [228.028745] Starting dynamic subtest: numvfs-3 [228.102113] Dynamic subtest numvfs-3: FAIL (0.004s) [228.114557] Starting dynamic subtest: numvfs-4 [228.187524] Dynamic subtest numvfs-4: FAIL (0.004s) [228.198587] Starting dynamic subtest: numvfs-5 [228.271024] Dynamic subtest numvfs-5: FAIL (0.004s) [228.284066] Starting dynamic subtest: numvfs-6 [228.357174] Dynamic subtest numvfs-6: FAIL (0.004s) [228.368275] Starting dynamic subtest: numvfs-7 [228.443470] Dynamic subtest numvfs-7: FAIL (0.005s) [228.455073] Starting dynamic subtest: numvfs-random [228.519719] Dynamic subtest numvfs-random: FAIL (0.004s) [228.533613] Starting dynamic subtest: numvfs-all [228.594785] Dynamic subtest numvfs-all: FAIL (0.522s) [228.596508] Subtest enable-vfs-autoprobe-off: FAIL (0.582s) [228.636701] [026/156] (788s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc) [228.740895] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc [228.742557] Starting dynamic subtest: pipe-A-eDP-1 [229.258420] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.496s) [229.269188] Starting dynamic subtest: pipe-B-eDP-1 [230.685080] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.417s) [230.698487] Starting dynamic subtest: pipe-C-eDP-1 [232.057872] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.373s) [232.069733] Starting dynamic subtest: pipe-D-eDP-1 [233.554215] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.495s) [233.556139] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.785s) [234.758565] [027/156] (782s left) gem_render_copy (y-tiled-to-vebox-linear) [234.825871] Starting subtest: y-tiled-to-vebox-linear [234.830311] Subtest y-tiled-to-vebox-linear: SKIP (0.000s) [235.011330] [028/156] (782s left) tools_test (sysfs_l3_parity) [235.062464] Starting subtest: sysfs_l3_parity [235.153220] Subtest sysfs_l3_parity: SKIP (0.104s) [235.209717] [029/156] (782s left) kms_cursor_legacy (cursora-vs-flipb-toggle) [235.321288] Starting subtest: cursorA-vs-flipB-toggle [235.326951] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s) [235.478716] [030/156] (782s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier) [235.562310] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier [235.587479] Starting dynamic subtest: pipe-A-plane-0 [235.865628] Dynamic subtest pipe-A-plane-0: SUCCESS (0.278s) [235.878899] Starting dynamic subtest: pipe-A-plane-3 [236.076045] Dynamic subtest pipe-A-plane-3: SUCCESS (0.211s) [236.086389] Starting dynamic subtest: pipe-A-plane-5 [236.090410] Dynamic subtest pipe-A-plane-5: SKIP (0.000s) [237.155332] Starting dynamic subtest: pipe-B-plane-0 [237.423526] Dynamic subtest pipe-B-plane-0: SUCCESS (0.278s) [237.435673] Starting dynamic subtest: pipe-B-plane-3 [237.634308] Dynamic subtest pipe-B-plane-3: SUCCESS (0.211s) [237.644870] Starting dynamic subtest: pipe-B-plane-5 [237.649195] Dynamic subtest pipe-B-plane-5: SKIP (0.000s) [237.945416] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier: SUCCESS (2.384s) [238.833581] [031/156] (778s left) gem_exec_schedule (preempt-queue) [238.914303] Subtest preempt-queue: SKIP (0.000s) [239.202914] [032/156] (778s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-pwrite) [239.316177] Starting subtest: fbchdr-rgb565-draw-pwrite [239.320681] Subtest fbchdr-rgb565-draw-pwrite: SKIP (0.000s) [239.434531] [033/156] (778s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc) [239.543462] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc [239.547680] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [239.661192] [034/156] (778s left) kms_dp_link_training (uhbr-mst) [239.759408] Starting subtest: uhbr-mst [239.768279] Subtest uhbr-mst: SKIP (0.000s) [239.832034] [035/156] (778s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render) [239.942071] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render [247.260552] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.322s) [247.374231] [036/156] (770s left) syncobj_timeline (single-wait-for-submit-signaled) [247.428098] Starting subtest: single-wait-for-submit-signaled [247.430502] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s) [247.523994] [037/156] (770s left) gem_exec_async (concurrent-writes) [247.580351] Starting subtest: concurrent-writes [247.582791] Starting dynamic subtest: rcs0 [247.594261] Dynamic subtest rcs0: SUCCESS (0.013s) [247.610816] Starting dynamic subtest: bcs0 [247.613079] Dynamic subtest bcs0: SUCCESS (0.008s) [247.626199] Starting dynamic subtest: vcs0 [247.628246] Dynamic subtest vcs0: SUCCESS (0.005s) [247.640557] Starting dynamic subtest: vcs1 [247.642782] Dynamic subtest vcs1: SUCCESS (0.006s) [247.652999] Starting dynamic subtest: vecs0 [247.655144] Dynamic subtest vecs0: SUCCESS (0.005s) [247.665373] Starting dynamic subtest: ccs0 [247.667633] Dynamic subtest ccs0: SUCCESS (0.006s) [247.669890] Subtest concurrent-writes: SUCCESS (0.048s) [247.855294] [038/156] (770s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-render) [247.962518] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-render [247.966768] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s) [248.051310] [039/156] (770s left) perf (invalid-open-flags) [248.198194] Starting subtest: invalid-open-flags [248.200117] Subtest invalid-open-flags: SUCCESS (0.000s) [248.237842] [040/156] (769s left) kms_ccs (random-ccs-data-y-tiled-ccs) [248.330846] Starting subtest: random-ccs-data-y-tiled-ccs [248.332645] Starting dynamic subtest: pipe-A-eDP-1 [248.353158] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [248.364541] Starting dynamic subtest: pipe-B-eDP-1 [248.383257] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [248.393270] Starting dynamic subtest: pipe-C-eDP-1 [248.412776] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [248.423841] Starting dynamic subtest: pipe-D-eDP-1 [248.442623] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [248.445983] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.005s) [250.624901] [041/156] (767s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf) [250.713776] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-sf [250.716085] Starting dynamic subtest: pipe-A-eDP-1 [250.790276] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s) [250.801861] Starting dynamic subtest: pipe-B-eDP-1 [251.896720] Dynamic subtest pipe-B-eDP-1: SKIP (1.103s) [251.901240] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (1.185s) [253.188081] [042/156] (765s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs) [253.282994] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs [253.285298] Starting dynamic subtest: pipe-A-eDP-1 [255.409625] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.128s) [255.422377] Starting dynamic subtest: pipe-B-eDP-1 [257.770220] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.358s) [257.781027] Starting dynamic subtest: pipe-C-eDP-1 [259.955991] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.184s) [259.967286] Starting dynamic subtest: pipe-D-eDP-1 [262.058112] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.101s) [262.060002] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.776s) [263.242397] [043/156] (755s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted) [263.307347] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted [263.414114] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.106s) [263.506531] [044/156] (754s left) gem_userptr_blits (mmap-offset-banned) [263.582719] Starting subtest: mmap-offset-banned [263.585098] Starting dynamic subtest: wb [263.587823] Dynamic subtest wb: SUCCESS (0.000s) [263.599033] Starting dynamic subtest: wc [263.601198] Dynamic subtest wc: SUCCESS (0.000s) [263.613736] Starting dynamic subtest: uc [263.615902] Dynamic subtest uc: SUCCESS (0.000s) [263.617993] Subtest mmap-offset-banned: SUCCESS (0.002s) [263.700891] [045/156] (754s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-move) [263.791653] Starting subtest: fbchdr-1p-primscrn-cur-indfb-move [263.795696] Subtest fbchdr-1p-primscrn-cur-indfb-move: SKIP (0.000s) [263.904947] [046/156] (754s left) kms_universal_plane (universal-plane-sanity) [264.002422] Starting subtest: universal-plane-sanity [264.004618] Starting dynamic subtest: pipe-A-eDP-1 [264.600407] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.599s) [264.611994] Starting dynamic subtest: pipe-B-eDP-1 [265.854675] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.250s) [265.867178] Starting dynamic subtest: pipe-C-eDP-1 [267.083486] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.226s) [267.094907] Starting dynamic subtest: pipe-D-eDP-1 [268.296130] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.211s) [268.298491] Subtest universal-plane-sanity: SUCCESS (4.292s) [269.163262] [047/156] (749s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff) [269.270281] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff [273.162720] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (3.897s) [274.376627] [048/156] (744s left) kms_atomic (plane-overlay-legacy) [274.474658] Starting subtest: plane-overlay-legacy [274.477015] Starting dynamic subtest: pipe-A-eDP-1 [274.873176] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.399s) [274.875534] Subtest plane-overlay-legacy: SUCCESS (0.401s) [275.687484] [049/156] (742s left) gem_exec_whisper (basic-forked-all) [275.755117] Starting subtest: basic-forked-all [289.420940] Subtest basic-forked-all: SUCCESS (13.657s) [289.608709] [050/156] (729s left) kms_pm_backlight (basic-brightness) [289.706544] Starting subtest: basic-brightness [289.757317] Starting dynamic subtest: eDP-1 [290.257726] Dynamic subtest eDP-1: SUCCESS (0.499s) [290.260173] Subtest basic-brightness: SUCCESS (0.552s) [291.135377] [051/156] (727s left) dmabuf_sync_file (export-wait-after-attach) [291.175814] Starting subtest: export-wait-after-attach [291.177784] Subtest export-wait-after-attach: SUCCESS (0.001s) [291.239574] [052/156] (727s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc) [291.351825] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc [291.355857] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [291.456990] [053/156] (727s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0) [291.554101] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0 [292.590772] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.037s) [292.752739] [054/156] (726s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen) [292.874259] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen [292.878010] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [292.975470] [055/156] (725s left) kms_flip (2x-flip-vs-panning-interruptible) [293.064637] Starting subtest: 2x-flip-vs-panning-interruptible [293.076233] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.012s) [293.178849] [056/156] (725s left) kms_flip (basic-plain-flip) [293.270226] Starting subtest: basic-plain-flip [293.272107] Starting dynamic subtest: A-eDP1 [294.924757] Dynamic subtest A-eDP1: SUCCESS (1.652s) [294.938491] Starting dynamic subtest: B-eDP1 [296.654121] Dynamic subtest B-eDP1: SUCCESS (1.725s) [296.668434] Starting dynamic subtest: C-eDP1 [298.390045] Dynamic subtest C-eDP1: SUCCESS (1.715s) [298.402524] Starting dynamic subtest: D-eDP1 [300.033782] Dynamic subtest D-eDP1: SUCCESS (1.659s) [300.038741] Subtest basic-plain-flip: SUCCESS (6.767s) [301.216098] [057/156] (717s left) gem_mmap_gtt (bad-object) [301.268467] Subtest bad-object: SKIP (0.000s) [301.327603] [058/156] (717s left) kms_cursor_crc (cursor-rapid-movement-64x64) [301.497458] Starting subtest: cursor-rapid-movement-64x64 [301.502265] Starting dynamic subtest: pipe-A-eDP-1 [302.046871] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.547s) [302.078308] Starting dynamic subtest: pipe-D-eDP-1 [303.252189] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.184s) [303.254616] Subtest cursor-rapid-movement-64x64: SUCCESS (1.755s) [304.096894] [059/156] (714s left) kms_setmode (invalid-clone-single-crtc) [304.151462] Starting subtest: invalid-clone-single-crtc [304.158052] Subtest invalid-clone-single-crtc: SKIP (0.000s) [304.244395] [060/156] (714s left) gem_shrink (reclaim) [304.303133] Starting subtest: reclaim [306.774085] Subtest reclaim: SUCCESS (2.472s) [306.927664] [061/156] (712s left) kms_psr (psr2-sprite-blt) [307.028629] Starting subtest: psr2-sprite-blt [307.030959] Starting dynamic subtest: eDP-1 [308.782724] Dynamic subtest eDP-1: SUCCESS (1.754s) [308.785831] Subtest psr2-sprite-blt: SUCCESS (1.756s) [309.649234] [062/156] (709s left) kms_frontbuffer_tracking (hdr-2p-primscrn-indfb-msflip-blt) [309.746979] Starting subtest: hdr-2p-primscrn-indfb-msflip-blt [309.751293] Subtest hdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s) [309.852553] [063/156] (709s left) sysfs_heartbeat_interval (nopreempt) [309.912092] Starting subtest: nopreempt [309.914393] Starting dynamic subtest: vecs0 [319.494276] Dynamic subtest vecs0: SUCCESS (9.582s) [319.513036] Starting dynamic subtest: ccs0 [323.434413] Dynamic subtest ccs0: SUCCESS (3.940s) [323.447398] Starting dynamic subtest: vcs0 [333.443946] Dynamic subtest vcs0: SUCCESS (10.010s) [333.459903] Starting dynamic subtest: bcs0 [337.390408] Dynamic subtest bcs0: SUCCESS (3.944s) [337.405152] Starting dynamic subtest: rcs0 [341.413277] Dynamic subtest rcs0: SUCCESS (4.019s) [341.426559] Starting dynamic subtest: vcs1 [351.500641] Dynamic subtest vcs1: SUCCESS (10.086s) [351.503591] Subtest nopreempt: SUCCESS (41.593s) [351.578219] [064/156] (667s left) gem_exec_balancer (sequential) [351.629848] Starting subtest: sequential [354.742627] Subtest sequential: SUCCESS (3.113s) [355.078083] [065/156] (664s left) syncobj_timeline (invalid-signal-illegal-handle) [355.134439] Starting subtest: invalid-signal-illegal-handle [355.136776] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s) [355.248589] [066/156] (664s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render) [355.354746] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render [355.358846] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s) [355.474338] [067/156] (663s left) perf (gen12-mi-rpc) [355.563736] Starting subtest: gen12-mi-rpc [355.566004] Starting dynamic subtest: rcs0 [355.584823] Dynamic subtest rcs0: SUCCESS (0.018s) [355.587024] Subtest gen12-mi-rpc: SUCCESS (0.019s) [355.629070] [068/156] (663s left) kms_chamelium_edid (hdmi-edid-change-during-suspend) [356.052979] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s) [356.852666] [069/156] (662s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc) [356.952904] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc [356.957143] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [357.076480] [070/156] (662s left) i915_module_load (resize-bar) [357.111181] Starting subtest: resize-bar [357.117866] Subtest resize-bar: SKIP (0.006s) [357.160833] [071/156] (662s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier) [357.265178] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier [357.290367] Starting dynamic subtest: pipe-A-plane-0 [357.578756] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s) [357.590567] Starting dynamic subtest: pipe-A-plane-3 [357.823154] Dynamic subtest pipe-A-plane-3: SUCCESS (0.244s) [357.835093] Starting dynamic subtest: pipe-A-plane-5 [357.838973] Dynamic subtest pipe-A-plane-5: SKIP (0.000s) [358.968534] Starting dynamic subtest: pipe-B-plane-0 [359.269926] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s) [359.283946] Starting dynamic subtest: pipe-B-plane-3 [359.503944] Dynamic subtest pipe-B-plane-3: SUCCESS (0.230s) [359.515777] Starting dynamic subtest: pipe-B-plane-5 [359.520540] Dynamic subtest pipe-B-plane-5: SKIP (0.000s) [359.830059] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.567s) [360.662999] [072/156] (658s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu) [360.763311] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu [360.767604] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [360.865050] [073/156] (658s left) kms_big_fb (x-tiled-16bpp-rotate-180) [360.960556] Starting subtest: x-tiled-16bpp-rotate-180 [362.340147] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.380s) [362.466003] [074/156] (657s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu) [362.561549] Starting subtest: fbc-rgb565-draw-mmap-cpu [366.581991] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.023s) [367.761175] [075/156] (651s left) gem_exec_balancer (full-late-pulse) [367.818452] Starting subtest: full-late-pulse [372.375113] Subtest full-late-pulse: SUCCESS (4.556s) [372.728938] [076/156] (647s left) kms_big_fb (y-tiled-64bpp-rotate-270) [372.831161] Starting subtest: y-tiled-64bpp-rotate-270 [372.835322] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s) [372.930699] [077/156] (646s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt) [373.035033] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt [373.039176] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [373.132057] [078/156] (646s left) syncobj_wait (invalid-reset-one-illegal-handle) [373.200258] Starting subtest: invalid-reset-one-illegal-handle [373.202779] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s) [373.308368] [079/156] (646s left) kms_color_pipeline (plane-lut1d) [373.406469] Starting subtest: plane-lut1d [373.577144] Starting dynamic subtest: pipe-A-plane-0 [373.743331] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s) [373.756781] Starting dynamic subtest: pipe-A-plane-1 [373.910102] Dynamic subtest pipe-A-plane-1: SUCCESS (0.165s) [373.923970] Starting dynamic subtest: pipe-A-plane-2 [374.043226] Dynamic subtest pipe-A-plane-2: SUCCESS (0.133s) [375.262960] Starting dynamic subtest: pipe-B-plane-0 [375.442672] Dynamic subtest pipe-B-plane-0: SUCCESS (0.189s) [375.454767] Starting dynamic subtest: pipe-B-plane-1 [375.586952] Dynamic subtest pipe-B-plane-1: SUCCESS (0.144s) [375.598609] Starting dynamic subtest: pipe-B-plane-2 [375.776257] Dynamic subtest pipe-B-plane-2: SUCCESS (0.188s) [376.975965] Starting dynamic subtest: pipe-C-plane-0 [377.121142] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s) [377.133277] Starting dynamic subtest: pipe-C-plane-1 [377.287067] Dynamic subtest pipe-C-plane-1: SUCCESS (0.165s) [377.299617] Starting dynamic subtest: pipe-C-plane-2 [377.453951] Dynamic subtest pipe-C-plane-2: SUCCESS (0.166s) [378.720672] Starting dynamic subtest: pipe-D-plane-0 [378.865904] Dynamic subtest pipe-D-plane-0: SUCCESS (0.155s) [378.877494] Starting dynamic subtest: pipe-D-plane-1 [379.010326] Dynamic subtest pipe-D-plane-1: SUCCESS (0.143s) [379.022415] Starting dynamic subtest: pipe-D-plane-2 [379.155458] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s) [379.446734] Subtest plane-lut1d: SUCCESS (6.041s) [380.261977] [080/156] (639s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt) [380.368719] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt [380.373018] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [380.484519] [081/156] (639s left) kms_busy (basic-hang) [380.580890] Starting subtest: basic-hang [380.582817] Starting dynamic subtest: flip-pipe-A [381.176741] Dynamic subtest flip-pipe-A: SUCCESS (0.595s) [381.189379] Starting dynamic subtest: modeset-pipe-A [384.590391] Dynamic subtest modeset-pipe-A: SUCCESS (3.411s) [384.602199] Starting dynamic subtest: flip-pipe-D [385.786275] Dynamic subtest flip-pipe-D: SUCCESS (1.193s) [385.798086] Starting dynamic subtest: modeset-pipe-D [389.207758] Dynamic subtest modeset-pipe-D: SUCCESS (3.423s) [389.210026] Subtest basic-hang: SUCCESS (8.628s) [390.016585] [082/156] (630s left) sw_sync (sync_multi_consumer_producer) [390.060334] Starting subtest: sync_multi_consumer_producer [390.332335] Subtest sync_multi_consumer_producer: SUCCESS (0.271s) [390.399845] [083/156] (629s left) gem_caching (reads) [390.452137] Subtest reads: SKIP (0.000s) [390.521752] [084/156] (629s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90) [390.611629] Starting subtest: primary-yf-tiled-reflect-x-90 [390.616012] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s) [390.689913] [085/156] (629s left) gem_pxp (verify-pxp-execution-after-suspend-resume) [390.803843] Starting subtest: verify-pxp-execution-after-suspend-resume [397.881371] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.076s) [398.015314] [086/156] (622s left) gem_exec_schedule (implicit-boths) [398.088756] Starting subtest: implicit-boths [398.090683] Starting dynamic subtest: rcs0 [398.183227] Dynamic subtest rcs0: SUCCESS (0.104s) [398.200154] Starting dynamic subtest: vcs0 [398.264960] Dynamic subtest vcs0: SUCCESS (0.080s) [398.279264] Starting dynamic subtest: vcs1 [398.350483] Dynamic subtest vcs1: SUCCESS (0.084s) [398.365538] Starting dynamic subtest: vecs0 [398.429664] Dynamic subtest vecs0: SUCCESS (0.078s) [398.443537] Starting dynamic subtest: ccs0 [398.521736] Dynamic subtest ccs0: SUCCESS (0.091s) [398.524494] Subtest implicit-boths: SUCCESS (0.443s) [399.630275] [087/156] (620s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-msflip-blt) [399.731142] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-msflip-blt [399.735494] Subtest fbcpsrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [399.827129] [088/156] (620s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing) [399.924927] Starting subtest: plane-all-transition-nonblocking-fencing [399.927218] Starting dynamic subtest: pipe-A-eDP-1 [402.420496] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.497s) [402.728347] Starting dynamic subtest: pipe-B-eDP-1 [405.963469] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.247s) [406.260375] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (6.337s) [407.085590] [089/156] (613s left) syncobj_wait (reset-signaled) [407.140898] Starting subtest: reset-signaled [407.143066] Subtest reset-signaled: SUCCESS (0.000s) [407.249253] [090/156] (613s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [407.357919] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt [407.361867] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [407.447045] [091/156] (613s left) kms_draw_crc (draw-method-render) [407.538382] Starting subtest: draw-method-render [407.540786] Starting dynamic subtest: xrgb8888-untiled [407.791189] Dynamic subtest xrgb8888-untiled: SUCCESS (0.255s) [407.802714] Starting dynamic subtest: rgb565-untiled [408.004036] Dynamic subtest rgb565-untiled: SUCCESS (0.210s) [408.015974] Starting dynamic subtest: xrgb2101010-untiled [408.235002] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.232s) [408.246520] Starting dynamic subtest: xbgr16161616f-untiled [408.457293] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s) [408.468715] Starting dynamic subtest: xrgb8888-xtiled [408.592121] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s) [408.611262] Starting dynamic subtest: rgb565-xtiled [408.712896] Dynamic subtest rgb565-xtiled: SUCCESS (0.120s) [408.727183] Starting dynamic subtest: xrgb2101010-xtiled [408.834994] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s) [408.846826] Starting dynamic subtest: xbgr16161616f-xtiled [408.968576] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s) [408.982412] Starting dynamic subtest: xrgb8888-4tiled [409.090591] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.120s) [409.102265] Starting dynamic subtest: rgb565-4tiled [409.201778] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s) [409.213568] Starting dynamic subtest: xrgb2101010-4tiled [409.324292] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.122s) [409.335983] Starting dynamic subtest: xbgr16161616f-4tiled [409.446562] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.121s) [409.448614] Subtest draw-method-render: SUCCESS (1.911s) [409.556445] [092/156] (610s left) gem_exec_fence (syncobj-repeat) [409.999494] Starting subtest: syncobj-repeat [410.191748] Subtest syncobj-repeat: SUCCESS (0.191s) [411.558665] [093/156] (608s left) gem_exec_flush (basic-wb-pro-default) [411.625342] Starting subtest: basic-wb-pro-default [417.101768] Subtest basic-wb-pro-default: SUCCESS (5.476s) [417.292281] [094/156] (603s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-plflip-blt) [417.396392] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-plflip-blt [417.401362] Subtest fbcpsrhdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s) [417.522403] [095/156] (603s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt) [417.630438] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt [417.634668] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [417.758007] [096/156] (602s left) kms_plane_alpha_blend (constant-alpha-mid) [417.859756] Starting subtest: constant-alpha-mid [418.145407] Starting dynamic subtest: pipe-A-eDP-1 [419.071624] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.926s) [419.083897] Starting dynamic subtest: pipe-D-eDP-1 [420.563437] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.490s) [420.566118] Subtest constant-alpha-mid: SUCCESS (2.705s) [421.445639] [097/156] (599s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs) [421.549478] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs [421.552391] Starting dynamic subtest: pipe-A-eDP-1 [421.579904] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [421.592666] Starting dynamic subtest: pipe-B-eDP-1 [421.613440] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [421.624503] Starting dynamic subtest: pipe-C-eDP-1 [421.646305] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [421.657172] Starting dynamic subtest: pipe-D-eDP-1 [421.679462] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [421.683538] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.005s) [423.805497] [098/156] (596s left) gen9_exec_parse (batch-without-end) [423.865854] Subtest batch-without-end: SKIP (0.000s) [423.926185] [099/156] (596s left) perf (i915-ref-count) [423.996457] Starting subtest: i915-ref-count [424.034878] Subtest i915-ref-count: SUCCESS (0.036s) [424.094259] [100/156] (596s left) i915_drm_fdinfo (memory-info-shared) [424.133440] Starting subtest: memory-info-shared [424.135811] Starting dynamic subtest: smem0 [424.183669] Dynamic subtest smem0: SUCCESS (0.051s) [424.186753] Subtest memory-info-shared: SUCCESS (0.051s) [424.273628] [101/156] (596s left) kms_content_protection (atomic) [424.428433] Starting subtest: atomic [424.433124] Subtest atomic: SKIP (0.000s) [424.594174] [102/156] (596s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-upscaling) [424.699148] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-upscaling [424.703199] Subtest flip-64bpp-yftile-to-16bpp-yftile-upscaling: SKIP (0.000s) [424.775127] [103/156] (596s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip) [424.881790] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip [425.917778] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.035s) [426.043741] [104/156] (595s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling) [426.139178] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling [426.142651] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s) [426.215111] [105/156] (594s left) kms_lease (lease-uevent) [426.304967] Starting subtest: lease-uevent [427.312435] Subtest lease-uevent: SUCCESS (1.008s) [427.389618] [106/156] (593s left) gem_caching (read-writes) [427.446973] Subtest read-writes: SKIP (0.000s) [427.512104] [107/156] (593s left) kms_psr (fbc-pr-sprite-plane-move) [427.614491] Starting subtest: fbc-pr-sprite-plane-move [427.618784] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s) [427.690205] [108/156] (593s left) gem_exec_schedule (u-fairslice) [427.757954] Starting subtest: u-fairslice [427.759904] Starting dynamic subtest: rcs0 [429.774561] Dynamic subtest rcs0: SUCCESS (2.022s) [429.787057] Starting dynamic subtest: bcs0 [431.796001] Dynamic subtest bcs0: SUCCESS (2.021s) [431.808232] Starting dynamic subtest: vcs0 [433.816970] Dynamic subtest vcs0: SUCCESS (2.018s) [433.827976] Starting dynamic subtest: vcs1 [435.840971] Dynamic subtest vcs1: SUCCESS (2.022s) [435.852037] Starting dynamic subtest: vecs0 [437.859519] Dynamic subtest vecs0: SUCCESS (2.019s) [437.870815] Starting dynamic subtest: ccs0 [439.880943] Dynamic subtest ccs0: SUCCESS (2.019s) [439.882769] Subtest u-fairslice: SUCCESS (12.128s) [440.181681] [109/156] (581s left) perf (sysctl-defaults) [440.262638] Starting subtest: sysctl-defaults [440.264613] Subtest sysctl-defaults: SUCCESS (0.000s) [440.326712] [110/156] (580s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions) [440.452161] Starting subtest: flip-vs-cursor-atomic-transitions [448.517873] Subtest flip-vs-cursor-atomic-transitions: SUCCESS (8.064s) [449.349629] [111/156] (572s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted) [449.402758] Starting subtest: etime-single-wait-all-available-unsubmitted [449.510025] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.106s) [449.603056] [112/156] (571s left) kms_cursor_crc (cursor-offscreen-512x512) [449.872990] Starting subtest: cursor-offscreen-512x512 [449.877499] Subtest cursor-offscreen-512x512: SKIP (0.001s) [450.021973] [113/156] (571s left) gem_pread (bench) [450.070387] Subtest bench: SKIP (0.000s) [450.140174] [114/156] (571s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-blt) [450.237473] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-blt [450.241895] Subtest hdr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s) [450.320839] [115/156] (571s left) kms_flip (plain-flip-ts-check-interruptible) [450.414491] Starting subtest: plain-flip-ts-check-interruptible [450.416810] Starting dynamic subtest: A-eDP1 [459.237061] Dynamic subtest A-eDP1: SUCCESS (8.820s) [459.251170] Starting dynamic subtest: B-eDP1 [468.072532] Dynamic subtest B-eDP1: SUCCESS (8.833s) [468.086597] Starting dynamic subtest: C-eDP1 [476.895295] Dynamic subtest C-eDP1: SUCCESS (8.820s) [476.909855] Starting dynamic subtest: D-eDP1 [485.730402] Dynamic subtest D-eDP1: SUCCESS (8.832s) [485.735344] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.315s) [486.941884] [116/156] (534s left) syncobj_wait (wait-delayed-signal) [486.999717] Starting subtest: wait-delayed-signal [487.103155] Subtest wait-delayed-signal: SUCCESS (0.102s) [487.185880] [117/156] (534s left) kms_vrr (flip-suspend) [487.277752] Starting subtest: flip-suspend [487.283888] Subtest flip-suspend: SKIP (0.000s) [487.353797] [118/156] (534s left) gem_workarounds (reset) [487.414193] Starting subtest: reset [487.590095] Subtest reset: SUCCESS (0.176s) [487.734968] [119/156] (533s left) kms_async_flips (crc) [487.828670] Starting subtest: crc [488.201332] Starting dynamic subtest: pipe-A-eDP-1 [491.132135] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.929s) [491.513386] Starting dynamic subtest: pipe-B-eDP-1 [494.473268] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.973s) [494.851762] Starting dynamic subtest: pipe-C-eDP-1 [497.763575] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.926s) [498.136135] Starting dynamic subtest: pipe-D-eDP-1 [500.999972] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.876s) [501.002288] Subtest crc: SUCCESS (13.170s) [502.178967] [120/156] (519s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-indfb-msflip-blt) [502.274409] Starting subtest: fbchdr-2p-scndscrn-indfb-msflip-blt [502.278840] Subtest fbchdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s) [502.379800] [121/156] (519s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move) [502.478875] Starting subtest: fbc-2p-scndscrn-cur-indfb-move [502.483288] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s) [502.593659] [122/156] (519s left) kms_scaling_modes (scaling-mode-none) [502.686677] Starting subtest: scaling-mode-none [502.689054] Starting dynamic subtest: pipe-A-eDP-1 [502.781456] Dynamic subtest pipe-A-eDP-1: SKIP (0.093s) [502.796738] Starting dynamic subtest: pipe-B-eDP-1 [502.822016] Dynamic subtest pipe-B-eDP-1: SKIP (0.037s) [502.834008] Starting dynamic subtest: pipe-C-eDP-1 [502.857564] Dynamic subtest pipe-C-eDP-1: SKIP (0.034s) [502.869411] Starting dynamic subtest: pipe-D-eDP-1 [502.897773] Dynamic subtest pipe-D-eDP-1: SKIP (0.040s) [502.902243] Subtest scaling-mode-none: SKIP (0.211s) [503.023515] [123/156] (518s left) vgem_basic (dmabuf-export) [503.070755] Starting subtest: dmabuf-export [503.103334] Subtest dmabuf-export: SUCCESS (0.034s) [503.210855] [124/156] (518s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-fullscreen) [503.318853] Starting subtest: psrhdr-2p-primscrn-spr-indfb-fullscreen [503.322599] Subtest psrhdr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s) [503.424779] [125/156] (518s left) kms_frontbuffer_tracking (fbchdr-2p-pri-indfb-multidraw) [503.526769] Starting subtest: fbchdr-2p-pri-indfb-multidraw [503.530425] Subtest fbchdr-2p-pri-indfb-multidraw: SKIP (0.000s) [503.633048] [126/156] (518s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt) [503.727611] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt [503.731149] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [503.833521] [127/156] (518s left) perf_pmu (enable-race) [503.871634] Starting subtest: enable-race [503.873578] Starting dynamic subtest: rcs0 [516.632905] Dynamic subtest rcs0: SUCCESS (12.758s) [516.647360] Starting dynamic subtest: bcs0 [529.377319] Dynamic subtest bcs0: SUCCESS (12.740s) [529.391533] Starting dynamic subtest: vcs0 [545.483754] Dynamic subtest vcs0: SUCCESS (16.099s) [545.498054] Starting dynamic subtest: vcs1 [561.481472] Dynamic subtest vcs1: SUCCESS (15.991s) [561.495943] Starting dynamic subtest: vecs0 [577.416948] Dynamic subtest vecs0: SUCCESS (15.930s) [577.431520] Starting dynamic subtest: ccs0 [590.165886] Dynamic subtest ccs0: SUCCESS (12.745s) [590.168414] Subtest enable-race: SUCCESS (86.278s) [590.290251] [128/156] (431s left) kms_psr (pr-sprite-mmap-gtt) [590.395721] Starting subtest: pr-sprite-mmap-gtt [590.400223] Subtest pr-sprite-mmap-gtt: SKIP (0.000s) [590.472065] [129/156] (431s left) kms_hdr (invalid-metadata-sizes) [590.567824] Starting subtest: invalid-metadata-sizes [590.569971] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010 [590.574384] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s) [590.586903] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F [590.591139] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s) [590.595410] Subtest invalid-metadata-sizes: SKIP (0.005s) [590.653083] [130/156] (431s left) kms_pm_rpm (dpms-mode-unset-lpsp) [592.585855] Starting subtest: dpms-mode-unset-lpsp [596.668401] Subtest dpms-mode-unset-lpsp: SUCCESS (4.084s) [597.093217] [131/156] (425s left) gem_ctx_persistence (legacy-engines-hang) [597.161415] Starting subtest: legacy-engines-hang [597.163784] Starting dynamic subtest: render [597.246764] Dynamic subtest render: SUCCESS (0.084s) [597.261925] Starting dynamic subtest: bsd1 [597.317347] Dynamic subtest bsd1: SUCCESS (0.068s) [597.331414] Starting dynamic subtest: bsd2 [597.441542] Dynamic subtest bsd2: SUCCESS (0.121s) [597.457785] Starting dynamic subtest: blt [597.543303] Dynamic subtest blt: SUCCESS (0.099s) [597.559354] Starting dynamic subtest: vebox [597.638426] Dynamic subtest vebox: SUCCESS (0.092s) [597.640939] Subtest legacy-engines-hang: SUCCESS (0.478s) [597.724568] [132/156] (424s left) gem_create (create-massive) [597.778255] Starting subtest: create-massive [597.780177] Subtest create-massive: SUCCESS (0.000s) [597.965443] [133/156] (424s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt) [598.086595] Starting subtest: psr-rgb101010-draw-blt [605.391134] Subtest psr-rgb101010-draw-blt: SUCCESS (7.308s) [605.516083] [134/156] (416s left) sw_sync (sync_busy) [605.555671] Starting subtest: sync_busy [605.557977] Subtest sync_busy: SUCCESS (0.002s) [605.617790] [135/156] (416s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt) [605.725379] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt [605.729503] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [605.826445] [136/156] (416s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc) [605.926386] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc [605.930472] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [606.035790] [137/156] (416s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip) [606.138512] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip [606.142957] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [606.266120] [138/156] (416s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling) [606.365235] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling [606.368851] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.000s) [606.451326] [139/156] (416s left) core_debugfs (read-all-entries) [606.506642] Starting subtest: read-all-entries [606.603745] Subtest read-all-entries: SUCCESS (0.096s) [606.674100] [140/156] (416s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt) [606.784822] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt [606.788132] Subtest hdr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [606.894786] [141/156] (415s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs) [606.978548] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs [606.980714] Starting dynamic subtest: pipe-A-eDP-1 [607.123374] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.135s) [607.137902] Starting dynamic subtest: pipe-B-eDP-1 [607.155160] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [607.166191] Starting dynamic subtest: pipe-C-eDP-1 [607.181436] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s) [607.192772] Starting dynamic subtest: pipe-D-eDP-1 [607.208535] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s) [607.210380] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.144s) [609.461139] [142/156] (413s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-onoff) [609.556905] Starting subtest: fbchdr-1p-primscrn-spr-indfb-onoff [609.560824] Subtest fbchdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s) [609.657452] [143/156] (413s left) i915_pm_rpm (debugfs-read) [611.586933] Starting subtest: debugfs-read [612.739283] Subtest debugfs-read: SUCCESS (1.145s) [613.135783] [144/156] (409s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite) [613.238266] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite [622.785550] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.550s) [622.918887] [145/156] (399s left) i915_query (query-topology-unsupported) [622.976612] Starting subtest: query-topology-unsupported [622.981034] Subtest query-topology-unsupported: SKIP (0.000s) [623.068174] [146/156] (399s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-mmap-gtt) [623.174348] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-mmap-gtt [623.178416] Subtest hdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [623.285989] [147/156] (399s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt) [623.394268] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt [623.398478] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [623.498170] [148/156] (399s left) gem_mmap_wc (copy) [623.563499] Subtest copy: SKIP (0.000s) [623.633069] [149/156] (399s left) kms_lease (cursor-implicit-plane) [623.735139] Starting subtest: cursor-implicit-plane [623.737101] Starting dynamic subtest: pipe-A-eDP-1 [624.179319] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.445s) [624.191017] Starting dynamic subtest: pipe-B-eDP-1 [625.317232] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.134s) [625.329399] Starting dynamic subtest: pipe-C-eDP-1 [626.435105] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.117s) [626.448153] Starting dynamic subtest: pipe-D-eDP-1 [627.549274] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.113s) [627.551551] Subtest cursor-implicit-plane: SUCCESS (3.816s) [628.425215] [150/156] (394s left) kms_cursor_edge_walk (64x64-top-bottom) [628.523143] Starting subtest: 64x64-top-bottom [628.525494] Starting dynamic subtest: pipe-A-eDP-1 [631.984147] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.460s) [631.997005] Starting dynamic subtest: pipe-D-eDP-1 [635.113387] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.127s) [635.115638] Subtest 64x64-top-bottom: SUCCESS (6.590s) [635.932956] [151/156] (387s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-indfb-pgflip-blt) [636.033472] Starting subtest: psrhdr-1p-primscrn-indfb-pgflip-blt [636.037794] Subtest psrhdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [636.136991] [152/156] (387s left) kms_chamelium_hpd (dp-hpd-for-each-pipe) [636.524270] Subtest dp-hpd-for-each-pipe: SKIP (0.000s) [637.330367] [153/156] (385s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-cpu) [637.429552] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-cpu [637.433876] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [637.526021] [154/156] (385s left) kms_cursor_legacy (short-flip-after-cursor-toggle) [637.650806] Starting subtest: short-flip-after-cursor-toggle [638.567568] Subtest short-flip-after-cursor-toggle: SUCCESS (0.919s) [639.388167] [155/156] (383s left) syncobj_wait (wait-all-interrupted) [639.447387] Starting subtest: wait-all-interrupted [639.550001] Subtest wait-all-interrupted: SUCCESS (0.102s) [639.629698] [156/156] (383s left) gem_exec_capture (userptr) [639.701851] Starting subtest: userptr [640.542734] Subtest userptr: SUCCESS (0.838s) [640.686170] Closing watchdogs results: parsing output: 0/ for test: kms_pm_rpm results: parsing output: 1/ for test: syncobj_timeline results: parsing output: 2/ for test: gem_mmap_offset results: parsing output: 3/ for test: kms_plane_scaling results: parsing output: 4/ for test: gem_softpin results: parsing output: 5/ for test: gem_ccs results: parsing output: 6/ for test: kms_frontbuffer_tracking results: parsing output: 7/ for test: i915_query results: parsing output: 8/ for test: kms_psr2_su results: parsing output: 9/ for test: kms_frontbuffer_tracking results: parsing output: 10/ for test: kms_psr2_sf results: parsing output: 11/ for test: kms_big_fb results: parsing output: 12/ for test: gem_readwrite results: parsing output: 13/ for test: kms_cursor_legacy results: parsing output: 14/ for test: kms_frontbuffer_tracking results: parsing output: 15/ for test: kms_cursor_legacy results: parsing output: 16/ for test: syncobj_basic results: parsing output: 17/ for test: kms_frontbuffer_tracking results: parsing output: 18/ for test: gem_lmem_swapping results: parsing output: 19/ for test: kms_frontbuffer_tracking results: parsing output: 20/ for test: gem_exec_fence results: parsing output: 21/ for test: kms_psr results: parsing output: 22/ for test: gem_ctx_freq results: parsing output: 23/ for test: api_intel_bb results: parsing output: 24/ for test: sriov_basic results: parsing output: 25/ for test: kms_ccs results: parsing output: 26/ for test: gem_render_copy results: parsing output: 27/ for test: tools_test results: parsing output: 28/ for test: kms_cursor_legacy results: parsing output: 29/ for test: kms_plane results: parsing output: 30/ for test: gem_exec_schedule results: parsing output: 31/ for test: kms_frontbuffer_tracking results: parsing output: 32/ for test: kms_frontbuffer_tracking results: parsing output: 33/ for test: kms_dp_link_training results: parsing output: 34/ for test: kms_frontbuffer_tracking results: parsing output: 35/ for test: syncobj_timeline results: parsing output: 36/ for test: gem_exec_async results: parsing output: 37/ for test: kms_frontbuffer_tracking results: parsing output: 38/ for test: perf results: parsing output: 39/ for test: kms_ccs results: parsing output: 40/ for test: kms_psr2_sf results: parsing output: 41/ for test: kms_ccs results: parsing output: 42/ for test: syncobj_timeline results: parsing output: 43/ for test: gem_userptr_blits results: parsing output: 44/ for test: kms_frontbuffer_tracking results: parsing output: 45/ for test: kms_universal_plane results: parsing output: 46/ for test: kms_frontbuffer_tracking results: parsing output: 47/ for test: kms_atomic results: parsing output: 48/ for test: gem_exec_whisper results: parsing output: 49/ for test: kms_pm_backlight results: parsing output: 50/ for test: dmabuf_sync_file results: parsing output: 51/ for test: kms_frontbuffer_tracking results: parsing output: 52/ for test: kms_big_fb results: parsing output: 53/ for test: kms_frontbuffer_tracking results: parsing output: 54/ for test: kms_flip results: parsing output: 55/ for test: kms_flip results: parsing output: 56/ for test: gem_mmap_gtt results: parsing output: 57/ for test: kms_cursor_crc results: parsing output: 58/ for test: kms_setmode results: parsing output: 59/ for test: gem_shrink results: parsing output: 60/ for test: kms_psr results: parsing output: 61/ for test: kms_frontbuffer_tracking results: parsing output: 62/ for test: sysfs_heartbeat_interval results: parsing output: 63/ for test: gem_exec_balancer results: parsing output: 64/ for test: syncobj_timeline results: parsing output: 65/ for test: kms_frontbuffer_tracking results: parsing output: 66/ for test: perf results: parsing output: 67/ for test: kms_chamelium_edid results: parsing output: 68/ for test: kms_frontbuffer_tracking results: parsing output: 69/ for test: i915_module_load results: parsing output: 70/ for test: kms_plane results: parsing output: 71/ for test: kms_frontbuffer_tracking results: parsing output: 72/ for test: kms_big_fb results: parsing output: 73/ for test: kms_frontbuffer_tracking results: parsing output: 74/ for test: gem_exec_balancer results: parsing output: 75/ for test: kms_big_fb results: parsing output: 76/ for test: kms_frontbuffer_tracking results: parsing output: 77/ for test: syncobj_wait results: parsing output: 78/ for test: kms_color_pipeline results: parsing output: 79/ for test: kms_frontbuffer_tracking results: parsing output: 80/ for test: kms_busy results: parsing output: 81/ for test: sw_sync results: parsing output: 82/ for test: gem_caching results: parsing output: 83/ for test: kms_rotation_crc results: parsing output: 84/ for test: gem_pxp results: parsing output: 85/ for test: gem_exec_schedule results: parsing output: 86/ for test: kms_frontbuffer_tracking results: parsing output: 87/ for test: kms_atomic_transition results: parsing output: 88/ for test: syncobj_wait results: parsing output: 89/ for test: kms_frontbuffer_tracking results: parsing output: 90/ for test: kms_draw_crc results: parsing output: 91/ for test: gem_exec_fence results: parsing output: 92/ for test: gem_exec_flush results: parsing output: 93/ for test: kms_frontbuffer_tracking results: parsing output: 94/ for test: kms_frontbuffer_tracking results: parsing output: 95/ for test: kms_plane_alpha_blend results: parsing output: 96/ for test: kms_ccs results: parsing output: 97/ for test: gen9_exec_parse results: parsing output: 98/ for test: perf results: parsing output: 99/ for test: i915_drm_fdinfo results: parsing output: 100/ for test: kms_content_protection results: parsing output: 101/ for test: kms_flip_scaled_crc results: parsing output: 102/ for test: kms_big_fb results: parsing output: 103/ for test: kms_flip_scaled_crc results: parsing output: 104/ for test: kms_lease results: parsing output: 105/ for test: gem_caching results: parsing output: 106/ for test: kms_psr results: parsing output: 107/ for test: gem_exec_schedule results: parsing output: 108/ for test: perf results: parsing output: 109/ for test: kms_cursor_legacy results: parsing output: 110/ for test: syncobj_timeline results: parsing output: 111/ for test: kms_cursor_crc results: parsing output: 112/ for test: gem_pread results: parsing output: 113/ for test: kms_frontbuffer_tracking results: parsing output: 114/ for test: kms_flip results: parsing output: 115/ for test: syncobj_wait results: parsing output: 116/ for test: kms_vrr results: parsing output: 117/ for test: gem_workarounds results: parsing output: 118/ for test: kms_async_flips results: parsing output: 119/ for test: kms_frontbuffer_tracking results: parsing output: 120/ for test: kms_frontbuffer_tracking results: parsing output: 121/ for test: kms_scaling_modes results: parsing output: 122/ for test: vgem_basic results: parsing output: 123/ for test: kms_frontbuffer_tracking results: parsing output: 124/ for test: kms_frontbuffer_tracking results: parsing output: 125/ for test: kms_frontbuffer_tracking results: parsing output: 126/ for test: perf_pmu results: parsing output: 127/ for test: kms_psr results: parsing output: 128/ for test: kms_hdr results: parsing output: 129/ for test: kms_pm_rpm results: parsing output: 130/ for test: gem_ctx_persistence results: parsing output: 131/ for test: gem_create results: parsing output: 132/ for test: kms_frontbuffer_tracking results: parsing output: 133/ for test: sw_sync results: parsing output: 134/ for test: kms_frontbuffer_tracking results: parsing output: 135/ for test: kms_frontbuffer_tracking results: parsing output: 136/ for test: kms_big_fb results: parsing output: 137/ for test: kms_flip_scaled_crc results: parsing output: 138/ for test: core_debugfs results: parsing output: 139/ for test: kms_frontbuffer_tracking results: parsing output: 140/ for test: kms_ccs results: parsing output: 141/ for test: kms_frontbuffer_tracking results: parsing output: 142/ for test: i915_pm_rpm results: parsing output: 143/ for test: kms_frontbuffer_tracking results: parsing output: 144/ for test: i915_query results: parsing output: 145/ for test: kms_frontbuffer_tracking results: parsing output: 146/ for test: kms_frontbuffer_tracking results: parsing output: 147/ for test: gem_mmap_wc results: parsing output: 148/ for test: kms_lease results: parsing output: 149/ for test: kms_cursor_edge_walk results: parsing output: 150/ for test: kms_frontbuffer_tracking results: parsing output: 151/ for test: kms_chamelium_hpd results: parsing output: 152/ for test: kms_frontbuffer_tracking results: parsing output: 153/ for test: kms_cursor_legacy results: parsing output: 154/ for test: syncobj_wait results: parsing output: 155/ for test: gem_exec_capture Done.