[Solved] Systemd automount blocks reboot

Simaryp · 2022-05-17 19:19:38

I have a systemd unit to mount a cifs share and a related automount unit with a timeout.
Whenever the share is mounted and I do a reboot the shutdown hangs up not being able to umount the share.
In an attempt to solve this, I added _netdev to options and ForceUnmount=true to [MOUNT]. This had no effect.

I have no clue what the reason could be. Maybe because i use FQDN?

Last edited by Simaryp (2022-07-08 11:35:25)

loqs · 2022-05-17 21:03:58

Does lsof show anything using the network mount that could be blocking the umount?
Edit:
Can you also please post the system journal for a shutdown to show where systemd has order the umount in the shutdown process.

Last edited by loqs (2022-05-17 21:53:55)

Simaryp · 2022-05-18 05:41:55

I have tried the following:
1. Open a folder on the mount and then close nautilus.
2. lsof | grep /mnt
Nothing shows up.

Here is a journal log.
http://ix.io/3Y1l

I had first the idea that maybe network is killed before the umount, but that does not seem to be the case.

Simaryp · 2022-07-06 17:21:35

Just wanted to push this topic a bit, since it's still unsolved.

ewaller · 2022-07-06 18:03:34

Simaryp wrote:

Just wanted to push this topic a bit, since it's still unsolved.

The accepted way to do that is to try to provide additional information, or provide us with updates on what your research into the problem has yielded since your last post.
In other words, show that you are still working the problem and are not just waiting for an answer.

Thanks

Simaryp · 2022-07-06 18:20:42

I provided a journal log as requested but never got a feedback on that. I searched in parallel in the internet, but didn't found a solution. So I am waiting for an answer, I didn't know that this is considered as a problem.

loqs · 2022-07-06 18:33:13

Mai 17 21:01:22 taschenrechner systemd[1]: Started Show Plymouth Reboot Screen.
Mai 17 21:01:22 taschenrechner audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=plymouth-reboot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:01:22 taschenrechner gnome-session-c[1584]: Couldn't connect to session bus: Fehler beim Senden der Anmeldedaten: Fehler beim Senden der Nachricht: Datenübergabe unterbrochen (broken pipe)
Mai 17 21:01:22 taschenrechner systemd[820]: Stopped GNOME Session Manager (session: gnome).
Mai 17 21:01:22 taschenrechner systemd[820]: Removed slice Slice /app/gnome-session-manager.
Mai 17 21:01:22 taschenrechner systemd[820]: Stopped Sound Service.
Mai 17 21:01:22 taschenrechner wpa_supplicant[614]: wlp4s0: CTRL-EVENT-DISCONNECTED bssid=90:9a:4a:3c:d1:1d reason=3 locally_generated=1
Mai 17 21:01:22 taschenrechner wpa_supplicant[614]: wlp4s0: CTRL-EVENT-DSCP-POLICY clear_all
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.6788] device (wlp4s0): supplicant interface state: completed -> disconnected
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.6788] device (p2p-dev-wlp4s0): supplicant management interface state: completed -> disconnected
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.6790] device (wlp4s0): state change: deactivating -> disconnected (reason 'connection-removed', sys-iface-state: 'managed')
Mai 17 21:01:22 taschenrechner mkinitcpio[1511]: ==> Build complete.
Mai 17 21:01:22 taschenrechner systemd[1]: mkinitcpio-generate-shutdown-ramfs.service: Deactivated successfully.
Mai 17 21:01:22 taschenrechner systemd[1]: Finished Generate shutdown-ramfs.
Mai 17 21:01:22 taschenrechner audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=mkinitcpio-generate-shutdown-ramfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:01:22 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=mkinitcpio-generate-shutdown-ramfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.7053] dhcp4 (wlp4s0): canceled DHCP transaction
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.7054] dhcp4 (wlp4s0): activation: beginning transaction (timeout in 45 seconds)
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.7054] dhcp4 (wlp4s0): state changed no lease
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.7113] device (wlp4s0): set-hw-addr: set MAC address to F2:11:8C:88:8A:C4 (scanning)
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.7276] manager: NetworkManager state is now DISCONNECTED
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.7283] device (wlp4s0): supplicant interface state: disconnected -> interface_disabled
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.7283] device (p2p-dev-wlp4s0): supplicant management interface state: disconnected -> interface_disabled
Mai 17 21:01:22 taschenrechner dbus-daemon[494]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.4' (uid=0 pid=499 comm="/usr/bin/NetworkManager --no-daemon")
Mai 17 21:01:22 taschenrechner dbus-daemon[494]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.nm-dispatcher.service': Refusing activation, D-Bus is shutting down.
Mai 17 21:01:22 taschenrechner dbus-daemon[494]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.4' (uid=0 pid=499 comm="/usr/bin/NetworkManager --no-daemon")
Mai 17 21:01:22 taschenrechner dbus-daemon[494]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.nm-dispatcher.service': Refusing activation, D-Bus is shutting down.
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.7387] device (wlp4s0): supplicant interface state: interface_disabled -> disconnected
Mai 17 21:01:22 taschenrechner NetworkManager[499]: <info>  [1652814082.7388] device (p2p-dev-wlp4s0): supplicant management interface state: interface_disabled -> disconnected
Mai 17 21:01:22 taschenrechner systemd[820]: Stopped Application launched by gnome-shell.
Mai 17 21:01:22 taschenrechner systemd[820]: app-gnome-org.gnome.Nautilus-0.scope: Consumed 2.728s CPU time.
Mai 17 21:01:23 taschenrechner systemd[820]: Stopped GNOME Shell on Wayland.
Mai 17 21:01:23 taschenrechner systemd[820]: org.gnome.Shell@wayland.service: Consumed 2.498s CPU time.
Mai 17 21:01:23 taschenrechner systemd[820]: Removed slice User Core Session Slice.
Mai 17 21:01:23 taschenrechner systemd[820]: session.slice: Consumed 6.175s CPU time.
Mai 17 21:01:23 taschenrechner systemd[820]: Stopped target GNOME Session Manager is ready.
Mai 17 21:01:23 taschenrechner systemd[820]: Stopped target Tasks to be run before GNOME Session starts.
Mai 17 21:01:23 taschenrechner systemd[820]: Stopped target Session services which should run early before the graphical session is brought up.
Mai 17 21:01:23 taschenrechner systemd[820]: Stopped target Basic System.
Mai 17 21:01:23 taschenrechner systemd[820]: Reached target Shutdown running GNOME Session.
Mai 17 21:01:23 taschenrechner systemd[820]: Stopped target Paths.
Mai 17 21:01:23 taschenrechner systemd[820]: Stopped target Sockets.
Mai 17 21:01:23 taschenrechner systemd[820]: Stopped target Timers.
Mai 17 21:01:23 taschenrechner systemd[820]: Closed D-Bus User Message Bus Socket.
Mai 17 21:01:23 taschenrechner systemd[820]: Closed GnuPG network certificate management daemon.
Mai 17 21:01:23 taschenrechner systemd[820]: Closed GCR ssh-agent wrapper.
Mai 17 21:01:23 taschenrechner systemd[820]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
Mai 17 21:01:23 taschenrechner systemd[820]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Mai 17 21:01:23 taschenrechner systemd[820]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Mai 17 21:01:23 taschenrechner systemd[820]: Closed GnuPG cryptographic agent and passphrase cache.
Mai 17 21:01:23 taschenrechner systemd[820]: Closed p11-kit server.
Mai 17 21:01:23 taschenrechner systemd[820]: Closed PipeWire Multimedia System Socket.
Mai 17 21:01:23 taschenrechner systemd[820]: Closed Sound System.
Mai 17 21:01:23 taschenrechner systemd[820]: Removed slice User Application Slice.
Mai 17 21:01:23 taschenrechner systemd[820]: app.slice: Consumed 8.002s CPU time.
Mai 17 21:01:23 taschenrechner systemd[820]: Reached target Shutdown.
Mai 17 21:01:23 taschenrechner systemd[820]: Starting Restart DBus after GNOME Session shutdown...
Mai 17 21:01:23 taschenrechner systemd[820]: Finished Exit the Session.
Mai 17 21:01:23 taschenrechner systemd[820]: Reached target Exit the Session.
Mai 17 21:01:23 taschenrechner gnome-session-c[1637]: Couldn't connect to session bus: Verbindung ist gescheitert: Verbindungsaufbau abgelehnt
Mai 17 21:01:23 taschenrechner systemd[821]: pam_warn(systemd-user:setcred): function=[pam_sm_setcred] flags=0x8004 service=[systemd-user] terminal=[] user=[jacob] ruser=[<unknown>] rhost=[<unknown>]
Mai 17 21:01:23 taschenrechner systemd[1]: user@1000.service: Deactivated successfully.
Mai 17 21:01:23 taschenrechner systemd[1]: Stopped User Manager for UID 1000.
Mai 17 21:01:23 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@1000 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:01:23 taschenrechner systemd[1]: user@1000.service: Consumed 14.526s CPU time.
Mai 17 21:01:23 taschenrechner systemd[1]: Stopping User Runtime Directory /run/user/1000...
Mai 17 21:01:23 taschenrechner systemd[1]: run-user-1000.mount: Deactivated successfully.
Mai 17 21:01:23 taschenrechner systemd[1]: Unmounted /run/user/1000.
Mai 17 21:01:23 taschenrechner systemd[1]: user-runtime-dir@1000.service: Deactivated successfully.
Mai 17 21:01:23 taschenrechner systemd[1]: Stopped User Runtime Directory /run/user/1000.
Mai 17 21:01:23 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@1000 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:01:23 taschenrechner systemd[1]: Removed slice User Slice of UID 1000.
Mai 17 21:01:23 taschenrechner systemd[1]: user-1000.slice: Consumed 14.747s CPU time.
Mai 17 21:01:23 taschenrechner systemd[1]: Stopping Permit User Sessions...
Mai 17 21:01:23 taschenrechner systemd[1]: systemd-user-sessions.service: Deactivated successfully.
Mai 17 21:01:23 taschenrechner systemd[1]: Stopped Permit User Sessions.
Mai 17 21:01:23 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-user-sessions comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:01:23 taschenrechner systemd[1]: Stopped target User and Group Name Lookups.
Mai 17 21:01:23 taschenrechner systemd[1]: Stopped target Remote File Systems.
Mai 17 21:01:23 taschenrechner systemd[1]: Unmounting jacob cifs mount...
Mai 17 21:01:25 taschenrechner kernel: fbcon: Taking over console
Mai 17 21:01:25 taschenrechner kernel: Console: switching to colour frame buffer device 240x67
Mai 17 21:02:53 taschenrechner systemd[1]: mnt-jacob.mount: Unmounting timed out. Terminating.
Mai 17 21:02:53 taschenrechner systemd[1]: mnt-jacob.mount: Mount process exited, code=killed, status=15/TERM
Mai 17 21:02:53 taschenrechner systemd[1]: mnt-jacob.mount: Failed with result 'timeout'.
Mai 17 21:02:53 taschenrechner systemd[1]: Unmounted jacob cifs mount.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-wait-online comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner kernel: CIFS: VFS: \\gringotts.koenig-otto.org cifs_put_smb_ses: Session Logoff failure rc=-512
Mai 17 21:02:53 taschenrechner kernel: kauditd_printk_skb: 27 callbacks suppressed
Mai 17 21:02:53 taschenrechner kernel: audit: type=1131 audit(1652814173.181:132): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-wait-online comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Network is Online.
Mai 17 21:02:53 taschenrechner wpa_supplicant[614]: p2p-dev-wlp4s0: CTRL-EVENT-DSCP-POLICY clear_all
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Network.
Mai 17 21:02:53 taschenrechner systemd[1]: NetworkManager-wait-online.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Network Manager Wait Online.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopping Network Manager...
Mai 17 21:02:53 taschenrechner NetworkManager[499]: <info>  [1652814173.1877] caught SIGTERM, shutting down normally.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopping WPA supplicant...
Mai 17 21:02:53 taschenrechner NetworkManager[499]: <info>  [1652814173.1898] device (wlp4s0): state change: disconnected -> unmanaged (reason 'unmanaged', sys-iface-state: 'managed')
Mai 17 21:02:53 taschenrechner wpa_supplicant[614]: p2p-dev-wlp4s0: CTRL-EVENT-DSCP-POLICY clear_all
Mai 17 21:02:53 taschenrechner wpa_supplicant[614]: nl80211: deinit ifname=p2p-dev-wlp4s0 disabled_11b_rates=0
Mai 17 21:02:53 taschenrechner wpa_supplicant[614]: p2p-dev-wlp4s0: CTRL-EVENT-TERMINATING
Mai 17 21:02:53 taschenrechner wpa_supplicant[614]: wlp4s0: CTRL-EVENT-DSCP-POLICY clear_all
Mai 17 21:02:53 taschenrechner NetworkManager[499]: <info>  [1652814173.2524] device (wlp4s0): set-hw-addr: reset MAC address to 00:28:F8:A0:B4:C3 (unmanage)
Mai 17 21:02:53 taschenrechner wpa_supplicant[614]: wlp4s0: CTRL-EVENT-DSCP-POLICY clear_all
Mai 17 21:02:53 taschenrechner wpa_supplicant[614]: nl80211: deinit ifname=wlp4s0 disabled_11b_rates=0
Mai 17 21:02:53 taschenrechner wpa_supplicant[614]: wlp4s0: CTRL-EVENT-TERMINATING
Mai 17 21:02:53 taschenrechner systemd[1]: wpa_supplicant.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped WPA supplicant.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=wpa_supplicant comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner kernel: audit: type=1131 audit(1652814173.555:133): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=wpa_supplicant comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner NetworkManager[499]: <info>  [1652814173.5617] device (p2p-dev-wlp4s0): state change: disconnected -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Mai 17 21:02:53 taschenrechner dbus-daemon[494]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.4' (uid=0 pid=499 comm="/usr/bin/NetworkManager --no-daemon")
Mai 17 21:02:53 taschenrechner dbus-daemon[494]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.nm-dispatcher.service': Refusing activation, D-Bus is shutting down.
Mai 17 21:02:53 taschenrechner NetworkManager[499]: <info>  [1652814173.5915] exiting (success)
Mai 17 21:02:53 taschenrechner systemd[1]: NetworkManager.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Network Manager.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: Stopping D-Bus System Message Bus...
Mai 17 21:02:53 taschenrechner kernel: audit: type=1131 audit(1652814173.598:134): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: dbus.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped D-Bus System Message Bus.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dbus comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Basic System.
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-ask-password-plymouth.path: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Forward Password Requests to Plymouth Directory Watch.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Path Units.
Mai 17 21:02:53 taschenrechner systemd[1]: cups.path: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped CUPS Scheduler.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Slice Units.
Mai 17 21:02:53 taschenrechner systemd[1]: Removed slice User and Session Slice.
Mai 17 21:02:53 taschenrechner systemd[1]: user.slice: Consumed 18.691s CPU time.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Socket Units.
Mai 17 21:02:53 taschenrechner kernel: audit: type=1131 audit(1652814173.605:135): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dbus comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: cups.socket: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Closed CUPS Scheduler.
Mai 17 21:02:53 taschenrechner systemd[1]: dbus.socket: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Closed D-Bus System Message Bus Socket.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target System Initialization.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Local Encrypted Volumes.
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-ask-password-wall.path: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Forward Password Requests to Wall Directory Watch.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Local Integrity Protected Volumes.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Local Verity Protected Volumes.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopping Load/Save Screen Backlight Brightness of backlight:intel_backlight...
Mai 17 21:02:53 taschenrechner systemd[1]: Stopping Load/Save Screen Backlight Brightness of leds:tpacpi::kbd_backlight...
Mai 17 21:02:53 taschenrechner kernel: audit: type=1131 audit(1652814173.621:136): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: Stopping Set Up Additional Binary Formats...
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-backlight@backlight:intel_backlight comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-sysctl.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Apply Kernel Variables.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopping Network Time Synchronization...
Mai 17 21:02:53 taschenrechner systemd[1]: Stopping Record System Boot/Shutdown in UTMP...
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-backlight@backlight:intel_backlight.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Load/Save Screen Backlight Brightness of backlight:intel_backlight.
Mai 17 21:02:53 taschenrechner kernel: audit: type=1131 audit(1652814173.631:137): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-backlight@backlight:intel_backlight comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-backlight@leds:tpacpi::kbd_backlight.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Load/Save Screen Backlight Brightness of leds:tpacpi::kbd_backlight.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-backlight@leds:tpacpi::kbd_backlight comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-binfmt.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Set Up Additional Binary Formats.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-binfmt comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner kernel: audit: type=1131 audit(1652814173.635:138): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-backlight@leds:tpacpi::kbd_backlight comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner kernel: audit: type=1131 audit(1652814173.635:139): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-binfmt comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: Removed slice Slice /system/systemd-backlight.
Mai 17 21:02:53 taschenrechner systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point.
Mai 17 21:02:53 taschenrechner systemd[1]: proc-sys-fs-binfmt_misc.mount: Deactivated successfully.
Mai 17 21:02:53 taschenrechner audit[1650]: SYSTEM_SHUTDOWN pid=1650 uid=0 auid=4294967295 ses=4294967295 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner kernel: audit: type=1128 audit(1652814173.641:140): pid=1650 uid=0 auid=4294967295 ses=4294967295 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-update-utmp.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Record System Boot/Shutdown in UTMP.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner kernel: audit: type=1131 audit(1652814173.645:141): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-timesyncd.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Network Time Synchronization.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Create Volatile Files and Directories.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Local File Systems.
Mai 17 21:02:53 taschenrechner systemd[1]: mnt-fotos.automount: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Unset automount fotos cifs automount.
Mai 17 21:02:53 taschenrechner systemd[1]: mnt-jacob.automount: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Unset automount jacob cifs automount.
Mai 17 21:02:53 taschenrechner systemd[1]: mnt-media.automount: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Unset automount media cifs automount.
Mai 17 21:02:53 taschenrechner systemd[1]: mnt-recordings.automount: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Unset automount recordings cifs automount.
Mai 17 21:02:53 taschenrechner systemd[1]: mnt-rufus.automount: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Unset automount rufus cifs automount.
Mai 17 21:02:53 taschenrechner systemd[1]: mnt-tmp.automount: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Unset automount tmp cifs automount.
Mai 17 21:02:53 taschenrechner systemd[1]: Unmounting /boot...
Mai 17 21:02:53 taschenrechner systemd[1]: Unmounting Temporary Directory /tmp...
Mai 17 21:02:53 taschenrechner systemd[1]: boot.mount: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Unmounted /boot.
Mai 17 21:02:53 taschenrechner systemd[1]: tmp.mount: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Unmounted Temporary Directory /tmp.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Swaps.
Mai 17 21:02:53 taschenrechner systemd[1]: Deactivating swap /swapfile...
Mai 17 21:02:53 taschenrechner systemd[1]: Unmounting /efi...
Mai 17 21:02:53 taschenrechner systemd[1]: efi.mount: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Unmounted /efi.
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-fsck@dev-disk-by\x2duuid-B5E9\x2dCC1B.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-fsck@dev-disk-by\x2duuid-B5E9\x2dCC1B comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped File System Check on /dev/disk/by-uuid/B5E9-CC1B.
Mai 17 21:02:53 taschenrechner systemd[1]: Removed slice Slice /system/systemd-fsck.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped target Preparation for Local File Systems.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopping Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Create Static Device Nodes in /dev.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner audit: BPF prog-id=0 op=UNLOAD
Mai 17 21:02:53 taschenrechner systemd[1]: swapfile.swap: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Deactivated swap /swapfile.
Mai 17 21:02:53 taschenrechner systemd[1]: Reached target Unmount All Filesystems.
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-remount-fs.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Remount Root and Kernel File Systems.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner lvm[1657]:   1 logical volume(s) in volume group "volgroup0" unmonitored
Mai 17 21:02:53 taschenrechner systemd[1]: lvm2-monitor.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Stopped Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=lvm2-monitor comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: Reached target System Shutdown.
Mai 17 21:02:53 taschenrechner systemd[1]: Reached target Late Shutdown Services.
Mai 17 21:02:53 taschenrechner systemd[1]: systemd-reboot.service: Deactivated successfully.
Mai 17 21:02:53 taschenrechner systemd[1]: Finished System Reboot.
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-reboot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-reboot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mai 17 21:02:53 taschenrechner systemd[1]: Reached target System Reboot.
Mai 17 21:02:53 taschenrechner systemd[1]: Shutting down.
Mai 17 21:02:53 taschenrechner audit: BPF prog-id=0 op=UNLOAD
Mai 17 21:02:53 taschenrechner audit: BPF prog-id=0 op=UNLOAD
Mai 17 21:02:53 taschenrechner audit: BPF prog-id=0 op=UNLOAD
Mai 17 21:02:53 taschenrechner audit: BPF prog-id=0 op=UNLOAD
Mai 17 21:02:53 taschenrechner audit: BPF prog-id=0 op=UNLOAD
Mai 17 21:02:53 taschenrechner audit: BPF prog-id=0 op=UNLOAD
Mai 17 21:02:53 taschenrechner systemd[1]: Using hardware watchdog 'iTCO_wdt', version 0, device /dev/watchdog0
Mai 17 21:02:53 taschenrechner systemd[1]: Watchdog running with a timeout of 10min.
Mai 17 21:02:53 taschenrechner kernel: watchdog: watchdog0: watchdog did not stop!
Mai 17 21:02:53 taschenrechner systemd-shutdown[1]: Using hardware watchdog 'iTCO_wdt', version 0, device /dev/watchdog0
Mai 17 21:02:53 taschenrechner systemd-shutdown[1]: Watchdog running with a timeout of 10min.
Mai 17 21:02:53 taschenrechner systemd-shutdown[1]: Syncing filesystems and block devices.
Mai 17 21:02:53 taschenrechner systemd-shutdown[1]: Sending SIGTERM to remaining processes...
Mai 17 21:02:53 taschenrechner systemd-journald[353]: Journal stopped

wlp4s0 is disconnected before mnt-jacob.mount is unmounted. I do not use NetworkManager and do not know why the disconnect is that early.

Simaryp · 2022-07-06 18:45:41

Ah that will be the reason. Thank you. I will try to figure out how to delay that.

Simaryp · 2022-07-06 19:36:59

So I tried to add an 'After=NetworkManager.service' to my mount unit.
That lead to a different behaviour. Now Networkmanager keeps alive till the share is unmounted, but I still run in timout and not proper unmount:

Jul 06 21:06:47 taschenrechner systemd[1]: Stopped target Remote File Systems.
Jul 06 21:06:47 taschenrechner systemd[1]: Unmounting jacob cifs mount...
Jul 06 21:08:17 taschenrechner systemd[1]: mnt-jacob.mount: Unmounting timed out. Terminating.
Jul 06 21:08:17 taschenrechner systemd[1]: mnt-jacob.mount: Mount process exited, code=killed, status=15/TERM
Jul 06 21:08:17 taschenrechner systemd[1]: mnt-jacob.mount: Failed with result 'timeout'.
Jul 06 21:08:17 taschenrechner audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-wait-online comm="systemd" exe="/usr/lib/sy>
Jul 06 21:08:17 taschenrechner systemd[1]: Unmounted jacob cifs mount.
Jul 06 21:08:17 taschenrechner systemd[1]: Stopped target Network is Online.
Jul 06 21:08:17 taschenrechner systemd[1]: Stopped target Network.
Jul 06 21:08:17 taschenrechner wpa_supplicant[644]: p2p-dev-wlp4s0: CTRL-EVENT-DSCP-POLICY clear_all
Jul 06 21:08:17 taschenrechner kernel: CIFS: VFS: \\gringotts.koenig-otto.org cifs_put_smb_ses: Session Logoff failure rc=-512

So there is either another reason the umount does not work or maybe it has something to do with the automount file. I have a quite long idle timeout time of 5min. I will try to set this to 1min and see if it is only delayed or again timed out.
Result: It timed out again, longer than the automount idle time. So it seems to have nothing to do with the automount service.

I could think of two more reasons, but no clue how to verify that>
1. Maybe something else is deactivated to early and the connection to the share can't be resolved or established.
2. I have the credentials on a hidden file in the users home. Maybe that is not reachable during the shutdown procedure by the system?
Both are maybe stupid, but I am clueless.

Edit2: That was very misleading. Although NetworkManager.service is now finishing after terminating the mount the connection is still dropped before.

Last edited by Simaryp (2022-07-06 20:03:14)

Simaryp · 2022-07-07 09:21:24

So I am near to giving up on this.
Apparently I figured out that I can do nothing in my mount units to get them triggered before Networkmanager kills the connection.
There used to be a functionality in Networkmanager to start a skript before the network connection is going down. Apparently it is not working anymore for years without a fix.

The only solution remaing I can figure out is to create a script that stops my systemd mounts that is issued when I issue a reboot or poweroff. Is there any hint on how I can do that?

twelveeighty · 2022-07-07 14:46:26

Why are you not using NetworkManager's dispatcher? https://wiki.archlinux.org/title/Networ … dispatcher

Simaryp · 2022-07-07 19:56:18

I tried that, but it's not working anymore. Apparently I could have saved that time, because it's mentioned in the wiki.

Note:
Since NetworkManager 0.9.8, the pre-down and down events are not executed on shutdown or restart, see this bug report for more info.

seth · 2022-07-07 23:00:25

"Networking that Just Works." …

The wiki suggests to have a lazy umount (instead, see the NFS reference) in the dispatcher script (also matching "down") - however this might leave the remote FS unsynced.

The core issue is probably that you've a user specific connection and that gets cut when you log out, before NM stops of systemd tries to umount the filesystem.
Therefore GDMs PostSession script will likely hit too late as well.

If https://wiki.archlinux.org/title/Networ … et_on_boot is an option, the connection might stay around long enough™

Simaryp · 2022-07-08 04:49:23

I will try that out. Thank you for the hint.

I am really surprized that this is such a complicated problem, because it is so obvious that net shares should be unmounted before killing network.

If it's only networkmanager one could think about replacing it,but I have the feeling that it's deeply integrated into gnome. And actually jt is usually a convenient tool too.

I found this here, which indicates that netctl with a modified WPA_supplicant service should work:
https://bbs.archlinux.org/viewtopic.php … 5#p1540245

seth · 2022-07-08 07:20:38

You're not supposed to run a wpa_supplicant service, NM will call wpa_supplicant.

The conflict is mostly the system-wide mount and the user-session local wifi connection that serves as it's "cable".
You have to explicitly umount the share before cutting the cable and that's what networkdispatcher is for. It's just that in this case you're logging out, hence cutting the cable, because you're shutting down the system and NM decided that at this point you MUST NOT run those NM dispatcher hooks (instead of eg. simply communicating the shutdown condition to the hooks) because NM is still a gnome project and gnome developers know better than you what you need and want… (grrrr)
It's not a problem at all if you've actual control over your network connections and mounts.

Gnome seems to emit a dbus signal on logout, https://unix.stackexchange.com/question … privileges
nb that the script is from 2014, no idea whether the infrastructure has changed since then (and you obviously don't have to store any of this on your desktop)

Simaryp · 2022-07-08 07:37:21

Ah, now I understand the origin of the ordering issue better and your solution attempt too.
This machine is asingle user machine so I don't care if connectuon is set up system wide.

I will try it out. But then I will needto take a break from it for vacation .

Simaryp · 2022-07-08 11:18:23

That worked! Thank you very much.

I will change it to solved, although this solution might not be suitable for everybody.

Arch Linux

#1 2022-05-17 19:19:38

[Solved] Systemd automount blocks reboot

#2 2022-05-17 21:03:58

Re: [Solved] Systemd automount blocks reboot

#3 2022-05-18 05:41:55

Re: [Solved] Systemd automount blocks reboot

#4 2022-07-06 17:21:35

Re: [Solved] Systemd automount blocks reboot

#5 2022-07-06 18:03:34

Re: [Solved] Systemd automount blocks reboot

#6 2022-07-06 18:20:42

Re: [Solved] Systemd automount blocks reboot

#7 2022-07-06 18:33:13

Re: [Solved] Systemd automount blocks reboot

#8 2022-07-06 18:45:41

Re: [Solved] Systemd automount blocks reboot

#9 2022-07-06 19:36:59

Re: [Solved] Systemd automount blocks reboot

#10 2022-07-07 09:21:24

Re: [Solved] Systemd automount blocks reboot

#11 2022-07-07 14:46:26

Re: [Solved] Systemd automount blocks reboot

#12 2022-07-07 19:56:18

Re: [Solved] Systemd automount blocks reboot

#13 2022-07-07 23:00:25

Re: [Solved] Systemd automount blocks reboot

#14 2022-07-08 04:49:23

Re: [Solved] Systemd automount blocks reboot

#15 2022-07-08 07:20:38

Re: [Solved] Systemd automount blocks reboot

#16 2022-07-08 07:37:21

Re: [Solved] Systemd automount blocks reboot

#17 2022-07-08 11:18:23

Re: [Solved] Systemd automount blocks reboot

Board footer