SHUTDOWN IS VERY SLOW

Rocary · June 19, 2022, 1:15pm

Hi,

I wrote this message because I use OpenSUSE for months, but recently the shutdown process became very slow :\

How can recover a normal shutdown duration ?

Here are the logs of the last shutdown…

  2022-06-19T11:05:17.439815+02:00 localhost systemd[2605]: Stopped target plasma-workspace-x11.target.
 2022-06-19T11:05:17.439925+02:00 localhost systemd[2605]: Stopped target KDE Plasma Workspace.
 2022-06-19T11:05:17.440262+02:00 localhost systemd[2605]: Stopped target Startup of XDG autostart applications.
 2022-06-19T11:05:17.440339+02:00 localhost systemd[2605]: Stopping Geoclue Demo agent...
 2022-06-19T11:05:17.440432+02:00 localhost systemd[2605]: Stopping Calendar Reminders...
 2022-06-19T11:05:17.440508+02:00 localhost systemd[2605]: Stopping Virtual filesystem service - Apple File Conduit monitor...
 2022-06-19T11:05:17.440586+02:00 localhost systemd[2605]: Stopping Virtual filesystem service...
 2022-06-19T11:05:17.440669+02:00 localhost systemd[2605]: Stopping Virtual filesystem service - GNOME Online Accounts monitor...
 2022-06-19T11:05:17.440766+02:00 localhost systemd[2605]: Stopping Virtual filesystem service - digital camera monitor...
 2022-06-19T11:05:17.440837+02:00 localhost systemd[2605]: Stopping Virtual filesystem metadata service...
 2022-06-19T11:05:17.440926+02:00 localhost systemd[2605]: Stopping Virtual filesystem service - Media Transfer Protocol monitor...
 2022-06-19T11:05:17.441010+02:00 localhost systemd[2605]: Stopping Virtual filesystem service - disk device monitor...
 2022-06-19T11:05:17.441086+02:00 localhost systemd[2605]: Stopping Baloo File Indexer Daemon...
 2022-06-19T11:05:17.441243+02:00 localhost systemd[2605]: Stopping KActivityManager Activity manager Service...
 2022-06-19T11:05:17.441343+02:00 localhost systemd[2605]: Stopping KDE Global Shortcuts Server...
 2022-06-19T11:05:17.441850+02:00 localhost systemd[2605]: Stopping KScreen...
 2022-06-19T11:05:17.442016+02:00 localhost systemd[2605]: Stopping KDE Window Manager...
 2022-06-19T11:05:17.442107+02:00 localhost systemd[2605]: Stopping Powerdevil...
 2022-06-19T11:05:17.442181+02:00 localhost systemd[2605]: Stopping Xdg Desktop Portal For KDE...
 2022-06-19T11:05:17.442801+02:00 localhost systemd[2605]: Stopped KDE Session Management Server.
 2022-06-19T11:05:17.443274+02:00 localhost systemd[2605]: Stopped Geoclue Demo agent.
 2022-06-19T11:05:17.444495+02:00 localhost systemd[1]: run-user-1000-gvfs.mount: Deactivated successfully.
 2022-06-19T11:05:17.445164+02:00 localhost systemd[2605]: Stopped KDE Global Shortcuts Server.
 2022-06-19T11:05:17.445546+02:00 localhost systemd[2605]: Stopped KScreen.
 2022-06-19T11:05:17.445925+02:00 localhost systemd[2605]: Stopped Virtual filesystem service.
 2022-06-19T11:05:17.446323+02:00 localhost systemd[2605]: Stopped Xdg Desktop Portal For KDE.
 2022-06-19T11:05:17.446620+02:00 localhost systemd[2605]: Stopped Virtual filesystem metadata service.
 2022-06-19T11:05:17.446881+02:00 localhost systemd[2605]: Stopped Virtual filesystem service - disk device monitor.
 2022-06-19T11:05:17.447145+02:00 localhost systemd[2605]: Stopped Virtual filesystem service - Apple File Conduit monitor.
 2022-06-19T11:05:17.447397+02:00 localhost systemd[2605]: Stopped Virtual filesystem service - Media Transfer Protocol monitor.
 2022-06-19T11:05:17.447647+02:00 localhost systemd[2605]: Stopped Virtual filesystem service - digital camera monitor.
 2022-06-19T11:05:17.447900+02:00 localhost systemd[2605]: Stopped Virtual filesystem service - GNOME Online Accounts monitor.
 2022-06-19T11:05:17.448266+02:00 localhost systemd[2605]: Stopped Calendar Reminders.
 2022-06-19T11:05:17.448314+02:00 localhost systemd[2605]: app-org.kde.kalendarac@autostart.service: Consumed 15.567s CPU time.
 2022-06-19T11:05:17.448396+02:00 localhost systemd[2605]: Stopped target Current graphical user session.
 2022-06-19T11:05:17.458421+02:00 localhost systemd[2605]: Stopped Baloo File Indexer Daemon.
 2022-06-19T11:05:17.467072+02:00 localhost systemd-logind[757]: System is powering down.
 2022-06-19T11:05:17.470536+02:00 localhost systemd[2605]: Stopped KActivityManager Activity manager Service.
 2022-06-19T11:05:17.475478+02:00 localhost systemd[2605]: Stopped KDE Window Manager.
 2022-06-19T11:05:17.475628+02:00 localhost systemd[2605]: plasma-kwin_x11.service: Consumed 2min 39.950s CPU time.
 2022-06-19T11:05:17.477222+02:00 localhost systemd[1]: Stopping Session 3 of User niels...
 2022-06-19T11:05:17.477384+02:00 localhost systemd[1]: Removed slice Slice /system/modprobe.
 2022-06-19T11:05:17.477534+02:00 localhost systemd[1]: Stopped target Bluetooth Support.
 2022-06-19T11:05:17.477646+02:00 localhost systemd[1]: Stopped target Graphical Interface.
 2022-06-19T11:05:17.477830+02:00 localhost sddm[1152]: Authentication error: "Process crashed"
 2022-06-19T11:05:17.478078+02:00 localhost systemd[1]: Stopped target Multi-User System.
 2022-06-19T11:05:17.478147+02:00 localhost sddm[1152]: Auth: sddm-helper crashed (exit code 15)
 2022-06-19T11:05:17.478366+02:00 localhost kded5[5187]: X connection to :0 broken (explicit kill or server shutdown).
 2022-06-19T11:05:17.478468+02:00 localhost sddm[1152]: Authentication error: "Process crashed"
 2022-06-19T11:05:17.478664+02:00 localhost at-spi-bus-launcher[9425]: X connection to :0 broken (explicit kill or server shutdown).
 2022-06-19T11:05:17.478735+02:00 localhost sddm[1152]: Auth: sddm-helper exited with 15
 2022-06-19T11:05:17.478791+02:00 localhost sddm[1152]: Socket server stopping...
 2022-06-19T11:05:17.478847+02:00 localhost sddm[1152]: Socket server stopped.
 2022-06-19T11:05:17.478893+02:00 localhost sddm[1152]: Display server stopping...
 2022-06-19T11:05:17.478942+02:00 localhost systemd[1]: Stopped target Login Prompts.
 2022-06-19T11:05:17.479120+02:00 localhost kded5[3631]: The X11 connection broke (error 1). Did the X11 server die?
 2022-06-19T11:05:17.479219+02:00 localhost systemd[1]: Stopped target Sound Card.
 2022-06-19T11:05:17.479297+02:00 localhost systemd[1]: Stopped target Timer Units.
 2022-06-19T11:05:17.479475+02:00 localhost ModemManager[796]: <info>  caught signal, shutting down...
 2022-06-19T11:05:17.479550+02:00 localhost systemd[1]: backup-rpmdb.timer: Deactivated successfully.
 2022-06-19T11:05:17.479614+02:00 localhost systemd[1]: Stopped Backup of RPM database.
 2022-06-19T11:05:17.479663+02:00 localhost systemd[1]: backup-sysconfig.timer: Deactivated successfully.
 2022-06-19T11:05:17.479710+02:00 localhost systemd[1]: Stopped Backup of /etc/sysconfig.
 2022-06-19T11:05:17.479767+02:00 localhost systemd[1]: check-battery.timer: Deactivated successfully.
 2022-06-19T11:05:17.479838+02:00 localhost systemd[1]: Stopped Check if mainboard battery is Ok.
 2022-06-19T11:05:17.479893+02:00 localhost systemd[1]: fstrim.timer: Deactivated successfully.
 2022-06-19T11:05:17.479941+02:00 localhost systemd[1]: Stopped Discard unused blocks once a week.
 2022-06-19T11:05:17.479989+02:00 localhost knotes[16051]: The X11 connection broke (error 1). Did the X11 server die?
 2022-06-19T11:05:17.480052+02:00 localhost systemd[1]: logrotate.timer: Deactivated successfully.
 2022-06-19T11:05:17.480105+02:00 localhost systemd[1]: Stopped Daily rotation of log files.
 2022-06-19T11:05:17.480155+02:00 localhost systemd[1]: man-db.timer: Deactivated successfully.
 2022-06-19T11:05:17.480212+02:00 localhost systemd[1]: Stopped Daily man-db regeneration.
 2022-06-19T11:05:17.480269+02:00 localhost systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully.
 2022-06-19T11:05:17.480328+02:00 localhost systemd[1]: Stopped Daily Cleanup of Temporary Directories.
 2022-06-19T11:05:17.480398+02:00 localhost systemd[1]: lvm2-lvmpolld.socket: Deactivated successfully.
 2022-06-19T11:05:17.480516+02:00 localhost systemd[1]: Closed LVM2 poll daemon socket.
 2022-06-19T11:05:17.480581+02:00 localhost systemd[1]: systemd-rfkill.socket: Deactivated successfully.
 2022-06-19T11:05:17.480655+02:00 localhost systemd[1]: Closed Load/Save RF Kill Switch Status /dev/rfkill Watch.
 2022-06-19T11:05:17.480714+02:00 localhost ModemManager[796]: <info>  ModemManager is shut down
 2022-06-19T11:05:17.480782+02:00 localhost systemd[1]: Stopping Modem Manager...
 2022-06-19T11:05:17.481086+02:00 localhost avahi-daemon[707]: Got SIGTERM, quitting.
 2022-06-19T11:05:17.481188+02:00 localhost systemd[1]: Stopping Save/Restore Sound Card State...
 2022-06-19T11:05:17.481415+02:00 localhost bluetoothd[1879]: Terminating
 2022-06-19T11:05:17.481496+02:00 localhost systemd[1]: augenrules.service: Deactivated successfully.
 2022-06-19T11:05:17.481568+02:00 localhost avahi-daemon[707]: Leaving mDNS multicast group on interface wlp0s20f3.IPv6 with address 2a01:cb15:80c6:9c00:efa4:602a:a352:dba8.
 2022-06-19T11:05:17.481856+02:00 localhost systemd[1]: Stopped auditd rules generation.
 2022-06-19T11:05:17.481943+02:00 localhost avahi-daemon[707]: Leaving mDNS multicast group on interface wlp0s20f3.IPv4 with address 192.168.1.17.
 2022-06-19T11:05:17.482363+02:00 localhost avahi-daemon[707]: Leaving mDNS multicast group on interface lo.IPv6 with address ::1.
 2022-06-19T11:05:17.482550+02:00 localhost systemd[1]: Stopping Avahi mDNS/DNS-SD Stack...
 2022-06-19T11:05:17.482627+02:00 localhost avahi-daemon[707]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
 2022-06-19T11:05:17.482685+02:00 localhost systemd[1]: Stopping Bluetooth service...
 2022-06-19T11:05:17.482786+02:00 localhost systemd[1]: Stopping Thunderbolt system service...
 2022-06-19T11:05:17.482883+02:00 localhost systemd[1]: Stopping Command Scheduler...
 2022-06-19T11:05:17.482956+02:00 localhost systemd[1]: Stopping CUPS Scheduler...
 2022-06-19T11:05:17.483051+02:00 localhost systemd[1]: Stopping X Display Manager...
 2022-06-19T11:05:17.483202+02:00 localhost systemd[1]: Stopping Restore /run/initramfs on shutdown...
 2022-06-19T11:05:17.483319+02:00 localhost systemd[1]: Stopping Getty on tty1...
 2022-06-19T11:05:17.483408+02:00 localhost systemd[1]: Stopping irqbalance daemon...
 2022-06-19T11:05:17.483541+02:00 localhost systemd[1]: kbdsettings.service: Deactivated successfully.
 2022-06-19T11:05:17.483601+02:00 localhost systemd[1]: Stopped Apply settings from /etc/sysconfig/keyboard.
 2022-06-19T11:05:17.483689+02:00 localhost systemd[1]: klog.service: Deactivated successfully.
 2022-06-19T11:05:17.483773+02:00 localhost systemd[1]: Stopped Early Kernel Boot Messages.
 2022-06-19T11:05:17.483836+02:00 localhost systemd[1]: Stopping Machine Check Exception Logging Daemon...
 2022-06-19T11:05:17.483901+02:00 localhost systemd[1]: Stopping PC/SC Smart Card Daemon...
 2022-06-19T11:05:17.484226+02:00 localhost systemd[1]: Stopping Power Profiles daemon...
 2022-06-19T11:05:17.502005+02:00 localhost kernel:  3546.413914] T1919] wlp0s20f3: deauthenticating from 7c:c1:77:25:d1:65 by local choice (Reason: 3=DEAUTH_LEAVING)
 2022-06-19T11:05:19.447558+02:00 localhost kernel:  3548.360921][T16800] xhci_hcd 0000:01:00.2: xHC error in resume, USBSTS 0x401, Reinit
 2022-06-19T11:05:19.447571+02:00 localhost kernel:  3548.360926][T16800] usb usb3: root hub lost power or was reset
 2022-06-19T11:05:19.447573+02:00 localhost kernel:  3548.360927][T16800] usb usb4: root hub lost power or was reset
 2022-06-19T11:05:30.871973+02:00 localhost kernel:  3559.785251][T17412] xhci_hcd 0000:01:00.2: xHC error in resume, USBSTS 0x401, Reinit
 2022-06-19T11:05:30.871984+02:00 localhost kernel:  3559.785255][T17412] usb usb3: root hub lost power or was reset
 2022-06-19T11:05:30.871985+02:00 localhost kernel:  3559.785256][T17412] usb usb4: root hub lost power or was reset

Thank you for the help :shame:

arvidjaar · June 19, 2022, 3:03pm

This looks like some hardware issue with USB. What is connected to usb3 and usb4? Post output lsusb.

Also did to try booting previous kernel versions?

Rocary · June 19, 2022, 5:45pm

Hi arvidjaar,

Thanks for your reply

Here is the output of lsusb, I have only an USB Bluetooth mouse !

Bus 006 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub 
Bus 005 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub 
Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub 
Bus 001 Device 004: ID 27c6:538d Shenzhen Goodix Technology Co.,Ltd. FingerPrint 
Bus 001 Device 003: ID 0bda:5538 Realtek Semiconductor Corp. Integrated_Webcam_HD 
Bus 001 Device 006: ID 8087:0026 Intel Corp. AX201 Bluetooth 
Bus 001 Device 005: ID 187c:0550 Alienware Corporation LED controller 
Bus 001 Device 002: ID 413c:301b Dell Computer Corp. Universal Bluetooth Receiver 
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub 
Bus 004 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub 
Bus 003 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

I didn’t try with older kernel versions, how can I do that ?

arvidjaar · June 19, 2022, 9:01pm

On GRUB menu there is Advanced submenu which lists older kernels. But by default openSUSE only keeps two last versions, so both should be 5.18 at this point. Do you remember when this issue started?

You could try installing 5.17 directly from https://download.opensuse.org/history/20220525/tumbleweed/repo/oss/kernel-default-5.17.9-1.1.x86_64.rpm

pelmacon · June 20, 2022, 12:39am

It may be related to the following rsyslog shutdown issue.

https://forums.opensuse.org/showthread.php/571409-Ninety-second-shutdown-delay-quot-A-stop-job-is-running-for-system-logging-service-quot