[GELÖST] Testing: A stop job is running... Wartezeit beim Shutdown

[Gnox]

Hallo zusammen,

Wie im Titel schon ersichtlich, habe ich eine Unregelmässigkeit beim Runterfahren/Neustarten. Bin auf Testing und der Fehler ist irgendwann in den letzten Tagen aufgetaucht. Jetzt bin ich mir nicht sicher, ob es bei Testing irgendwelche Neuerungen via Updates gegeben hat, die zu dem Verhalten führen, oder ob ich wohl irgendwas verbockt habe?

Beim Shutdown kommt die Meldung: "A stop job is running for Session 3 of user XY".

Dann darf ich jedesmal 1 1/2 Minuten warten, bis der Prozess gnädigerweise beendet wird. Die Suche hier im Forum habe ich genutzt und ich habe auch einiges dazu gelesen.

Wenn ich z.B. ALT&F2 drücke und dann

Code: Alles auswählen

systemctl poweroff

eingebe, fährt die Kiste sofort ohne Verzögerung runter. Hier kurz der Auszug der letzten paar Zeilen beim Shutdown via systemctl poweroff:

Code: Alles auswählen

Sep 26 15:47:23 aorus org.kde.ksystemstats[1371]: kf.networkmanagerqt: void NetworkManager::ConnectionPrivate::onPropertiesChanged(const QVariantMap&) Unhandled property "Flags"
Sep 26 15:47:23 aorus kactivitymanagerd[1170]: The X11 connection broke (error 1). Did the X11 server die?
Sep 26 15:47:23 aorus kglobalaccel5[1176]: The X11 connection broke (error 1). Did the X11 server die?
Sep 26 15:47:23 aorus systemd[1026]: plasma-kglobalaccel.service: Succeeded.
Sep 26 15:47:23 aorus pulseaudio[1048]: ICE default IO error handler doing an exit(), pid = 1048, errno = 11
Sep 26 15:47:23 aorus org.kde.KScreen[1303]: The X11 connection broke (error 1). Did the X11 server die?
Sep 26 15:47:23 aorus systemd[1026]: plasma-kactivitymanagerd.service: Succeeded.
Sep 26 15:47:23 aorus systemd[1026]: pulseaudio.service: Main process exited, code=exited, status=1/FAILURE
Sep 26 15:47:23 aorus systemd[1026]: pulseaudio.service: Killing process 1132 (gdbus) with signal SIGKILL.
Sep 26 15:47:23 aorus systemd[1026]: pulseaudio.service: Failed with result 'exit-code'.
Sep 26 15:47:23 aorus systemd[1026]: app-com.nextcloud.desktopclient.nextcloud-5f715dca61684340921252a3c592c175.scope: Succeeded.
Sep 26 15:47:23 aorus krunner[1567]: ICE default IO error handler doing an exit(), pid = 1567, errno = 17
Sep 26 15:47:23 aorus systemd[1026]: pulseaudio.service: Scheduled restart job, restart counter is at 1.
Sep 26 15:47:23 aorus systemd[1026]: Stopped Sound Service.
Sep 26 15:47:23 aorus systemd[1026]: Starting Sound Service...
Sep 26 15:47:23 aorus krunner[1567]: file:///usr/lib/x86_64-linux-gnu/qt5/qml/org/kde/milou/ResultsView.qml:70: TypeError: Cannot read property 'iconSizes' of null
Sep 26 15:47:23 aorus krunner[1567]: file:///usr/share/plasma/look-and-feel/org.kde.breeze.desktop/contents/runcommand/RunCommand.qml:97: TypeError: Cannot read property 'gridUnit' of null
Sep 26 15:47:23 aorus krunner[1567]: file:///usr/share/plasma/look-and-feel/org.kde.breeze.desktop/contents/runcommand/RunCommand.qml:98: TypeError: Cannot read property 'gridUnit' of null
Sep 26 15:47:23 aorus krunner[1567]: file:///usr/share/plasma/look-and-feel/org.kde.breeze.desktop/contents/runcommand/RunCommand.qml:200: TypeError: Cannot read property 'iconSizes' of null
Sep 26 15:47:23 aorus krunner[1567]: file:///usr/share/plasma/look-and-feel/org.kde.breeze.desktop/contents/runcommand/RunCommand.qml:112: TypeError: Cannot read property 'smallSpacing' of null
Sep 26 15:47:23 aorus pulseaudio[1647]: Stale PID file, overwriting.
Sep 26 15:47:23 aorus systemd[1026]: plasma-krunner.service: Succeeded.
Sep 26 15:47:23 aorus systemd[1026]: plasma-krunner.service: Consumed 1.074s CPU time.
Sep 26 15:47:23 aorus systemd[1026]: Started Sound Service.
Sep 26 15:47:26 aorus systemd[1026]: Removed slice User Background Tasks Slice.
Sep 26 15:47:26 aorus systemd[1026]: background.slice: Consumed 1.371s CPU time.
Sep 26 15:47:26 aorus systemd[1026]: Stopped target Main User Target.
Sep 26 15:47:26 aorus systemd[1026]: Stopping D-Bus User Message Bus...
Sep 26 15:47:26 aorus systemd[1026]: Stopping User preferences database...
Sep 26 15:47:26 aorus systemd[1026]: Stopping Bluetooth OBEX service...
Sep 26 15:47:26 aorus systemd[1026]: Stopping Multimedia Service Session Manager...
Sep 26 15:47:26 aorus systemd[1026]: Stopping Sound Service...
Sep 26 15:47:26 aorus systemd[1026]: dconf.service: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Stopped User preferences database.
Sep 26 15:47:26 aorus systemd[1026]: obex.service: Main process exited, code=exited, status=1/FAILURE
Sep 26 15:47:26 aorus systemd[1026]: obex.service: Failed with result 'exit-code'.
Sep 26 15:47:26 aorus systemd[1026]: Stopped Bluetooth OBEX service.
Sep 26 15:47:26 aorus systemd[1026]: dbus.service: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Stopped D-Bus User Message Bus.
Sep 26 15:47:26 aorus systemd[1026]: dbus.service: Consumed 1.204s CPU time.
Sep 26 15:47:26 aorus systemd[1026]: pipewire-media-session.service: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Stopped Multimedia Service Session Manager.
Sep 26 15:47:26 aorus systemd[1026]: Stopping Multimedia Service...
Sep 26 15:47:26 aorus systemd[1026]: pipewire.service: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Stopped Multimedia Service.
Sep 26 15:47:26 aorus systemd[1026]: Removed slice User Core Session Slice.
Sep 26 15:47:26 aorus systemd[1026]: pulseaudio.service: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Stopped Sound Service.
Sep 26 15:47:26 aorus systemd[1026]: Stopped target Basic System.
Sep 26 15:47:26 aorus systemd[1026]: Stopped target Paths.
Sep 26 15:47:26 aorus systemd[1026]: Stopped target Sockets.
Sep 26 15:47:26 aorus systemd[1026]: Stopped target Timers.
Sep 26 15:47:26 aorus systemd[1026]: dbus.socket: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Closed D-Bus User Message Bus Socket.
Sep 26 15:47:26 aorus systemd[1026]: dirmngr.socket: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Closed GnuPG network certificate management daemon.
Sep 26 15:47:26 aorus systemd[1026]: gpg-agent-browser.socket: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
Sep 26 15:47:26 aorus systemd[1026]: gpg-agent-extra.socket: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Sep 26 15:47:26 aorus systemd[1026]: gpg-agent-ssh.socket: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Sep 26 15:47:26 aorus systemd[1026]: gpg-agent.socket: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Closed GnuPG cryptographic agent and passphrase cache.
Sep 26 15:47:26 aorus systemd[1026]: pipewire.socket: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Closed Multimedia System.
Sep 26 15:47:26 aorus systemd[1026]: pk-debconf-helper.socket: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Closed debconf communication socket.
Sep 26 15:47:26 aorus systemd[1026]: pulseaudio.socket: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Closed Sound System.
Sep 26 15:47:26 aorus systemd[1026]: Removed slice User Application Slice.
Sep 26 15:47:26 aorus systemd[1026]: app.slice: Consumed 4.110s CPU time.
Sep 26 15:47:26 aorus systemd[1026]: Reached target Shutdown.
Sep 26 15:47:26 aorus systemd[1026]: systemd-exit.service: Succeeded.
Sep 26 15:47:26 aorus systemd[1026]: Finished Exit the Session.
Sep 26 15:47:26 aorus systemd[1026]: Reached target Exit the Session.

Zum Vergleich das Journal wenn ich die Kiste "normal" über das Menü herunterfahre:

Code: Alles auswählen

Sep 26 15:42:31 aorus org.kde.kdeconnect[2047]: ICE default IO error handler doing an exit(), pid = 2047, errno = 11
Sep 26 15:42:31 aorus kactivitymanagerd[1167]: The X11 connection broke (error 1). Did the X11 server die?
Sep 26 15:42:31 aorus kglobalaccel5[1173]: The X11 connection broke (error 1). Did the X11 server die?
Sep 26 15:42:31 aorus org.kde.KScreen[1298]: The X11 connection broke (error 1). Did the X11 server die?
Sep 26 15:42:31 aorus systemd[1023]: plasma-kglobalaccel.service: Succeeded.
Sep 26 15:42:31 aorus systemd[1023]: pulseaudio.service: Succeeded.
Sep 26 15:42:31 aorus systemd[1023]: plasma-kactivitymanagerd.service: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Removed slice User Background Tasks Slice.
Sep 26 15:44:01 aorus systemd[1023]: Stopped target Main User Target.
Sep 26 15:44:01 aorus systemd[1023]: Stopping D-Bus User Message Bus...
Sep 26 15:44:01 aorus systemd[1023]: Stopping User preferences database...
Sep 26 15:44:01 aorus systemd[1023]: Stopping Bluetooth OBEX service...
Sep 26 15:44:01 aorus systemd[1023]: Stopping Multimedia Service Session Manager...
Sep 26 15:44:01 aorus systemd[1023]: dconf.service: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Stopped User preferences database.
Sep 26 15:44:01 aorus systemd[1023]: obex.service: Main process exited, code=exited, status=1/FAILURE
Sep 26 15:44:01 aorus systemd[1023]: obex.service: Failed with result 'exit-code'.
Sep 26 15:44:01 aorus systemd[1023]: Stopped Bluetooth OBEX service.
Sep 26 15:44:01 aorus systemd[1023]: dbus.service: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Stopped D-Bus User Message Bus.
Sep 26 15:44:01 aorus systemd[1023]: dbus.service: Consumed 3.960s CPU time.
Sep 26 15:44:01 aorus systemd[1023]: pipewire-media-session.service: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Stopped Multimedia Service Session Manager.
Sep 26 15:44:01 aorus systemd[1023]: Stopping Multimedia Service...
Sep 26 15:44:01 aorus systemd[1023]: pipewire.service: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Stopped Multimedia Service.
Sep 26 15:44:01 aorus systemd[1023]: Removed slice User Core Session Slice.
Sep 26 15:44:01 aorus systemd[1023]: Stopped target Basic System.
Sep 26 15:44:01 aorus systemd[1023]: Stopped target Paths.
Sep 26 15:44:01 aorus systemd[1023]: Stopped target Sockets.
Sep 26 15:44:01 aorus systemd[1023]: Stopped target Timers.
Sep 26 15:44:01 aorus systemd[1023]: dbus.socket: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Closed D-Bus User Message Bus Socket.
Sep 26 15:44:01 aorus systemd[1023]: dirmngr.socket: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Closed GnuPG network certificate management daemon.
Sep 26 15:44:01 aorus systemd[1023]: gpg-agent-browser.socket: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
Sep 26 15:44:01 aorus systemd[1023]: gpg-agent-extra.socket: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Sep 26 15:44:01 aorus systemd[1023]: gpg-agent-ssh.socket: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Sep 26 15:44:01 aorus systemd[1023]: gpg-agent.socket: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Closed GnuPG cryptographic agent and passphrase cache.
Sep 26 15:44:01 aorus systemd[1023]: pipewire.socket: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Closed Multimedia System.
Sep 26 15:44:01 aorus systemd[1023]: pk-debconf-helper.socket: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Closed debconf communication socket.
Sep 26 15:44:01 aorus systemd[1023]: pulseaudio.socket: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Closed Sound System.
Sep 26 15:44:01 aorus systemd[1023]: Removed slice User Application Slice.
Sep 26 15:44:01 aorus systemd[1023]: app.slice: Consumed 6.954s CPU time.
Sep 26 15:44:01 aorus systemd[1023]: Reached target Shutdown.
Sep 26 15:44:01 aorus systemd[1023]: systemd-exit.service: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Finished Exit the Session.
Sep 26 15:44:01 aorus systemd[1023]: Reached target Exit the Session.

Mir ist beim Betrachten der beiden Protokolle aufgefallen, dass "pulseaudio" beim Herunterfahren via "systemctl poweroff" abgewürgt wird. Also habe ich versucht, pulseaudio händisch - vor dem normalen Herunterfahren übers Menü - zu killen, und erst dann den Rechner herunterzufahren. Siehe da: keine Verzögerung beim Abschalten.

Ebenfalls keine Verzögerung habe ich, wenn ich mich vor dem Herunterfahren abmelde und dann beim Login-Bildschirm denn Rechner herunterfahre.

In diesem Beitrag war es ja pcmanfm, das zum Fehlverhalten geführt hat. Allerdings habe ich pcmanfm nicht installiert; und ich sehe nicht, wo der Fehler sonst liegen sollte, ausser bei pulseaudio??

Ist das nun ein Problem von Testing (wurde da was geändert, was noch nicht ganz rund läuft), oder habe ich irgend einen Fehler in meiner Konfiguration?

Falls weitere Angaben zur Lösung des Problems benötigt werden, liefere ich die sehr gerne nach.

[Emess]

@Gnox

A stop job is running for Session 3 of user XY"

Steht nicht noch dabei was er versucht zu beenden?

[Gnox]

Eben nicht. Ich hatte in der Zwischenzeit testweise einen zusätzlichen User angelegt, um zu sehen, ob es sich da auch so verhält. Da (beim Herunterfahren mit dem anderen User) ist dann die Meldung gekommen: "A stop job is running for Make remote CUPS printers available locally".
Ich weiss jetzt allerdings nicht, ob das Problem bei beiden Nutzern das gleiche ist...

[MSfree]

Ich hatte auch mal so ein Problem, dessen Ursache sich leider auch nicht offensichtlich aus den Logs entnehmen ließ.

Zunächst mal so viel, laut deinem Log-Auszug kommt es zwischen diesen zwei Zeilen zu der 90s Pause:

Code: Alles auswählen

Sep 26 15:42:31 aorus systemd[1023]: plasma-kactivitymanagerd.service: Succeeded.
Sep 26 15:44:01 aorus systemd[1023]: Removed slice User Background Tasks Slice.

Wie bei mir, ist auch bei dir nicht ersichtlich, was zwischen dem erfolgreichen Beenden des plasma-kactivitymanagerd.service und dem Entfernen der User Background slice passiert. Der Fehler ist jedenfalls nicht im kactivitymanagerd zu suchen, denn der wurde erfolgreich und in kurzer Zeit beendet. Log-Einträge entstehen immer nach einer Aktion und nicht bevor die Aktion ausgelöst wird.

Versuch doch bitte mal folgendes:

Logge dich nur aus der KDE-Umgebung aus, ohne die Kiste runterzufahren.
Dann fahre den Rechner vom Login-Screen runter.

Wenn das schnell geht (war bei mir so), dann ist der Fehler irgendwo im KDE zu suchen.

[Gnox]

Versuch doch bitte mal folgendes:

Logge dich nur aus der KDE-Umgebung aus, ohne die Kiste runterzufahren.
Dann fahre den Rechner vom Login-Screen runter.

Ebenfalls keine Verzögerung habe ich, wenn ich mich vor dem Herunterfahren abmelde und dann beim Login-Bildschirm denn Rechner herunterfahre.

Hatte ich ja schon probiert; das funktioniert dann wie in Deinem Thread beschrieben, ohne Probleme.

[Gnox]

Kurzes Update, falls jemand das gleiche Problemchen haben sollte: Nach dem Update auf Kernel 5.14 - welches automatisch über die Testing-Quellen erfolgte - und einer anschliessenden Deinstallation der proprietären NVIDIA-Treiber, taucht die Meldung nicht mehr auf und das System bootet wieder smooth und zügig.