[Pkg-utopia-maintainers] Bug#934707: [dbus, systemd, sssd]: Unresponsive domain and nonexistent user in policy lead to reload fail and fall of dependant daemons.

Tue Aug 13 19:08:18 BST 2019

Package: dbus
Version: 1.12.16-1
Severity: normal

Dear Maintainer.

As subject says, if sssd can't reach dc, dbus get NoReply fail and succesfully restarted aftervards with following consequences:
1. reload process lagging due sssd timeouts;
2. other daemons fail because 1 or dbus restart;
3. failed reload at packet install process may lead to overall install fail;

Because of automatic config reload, 1 is strikes at periodic manner.
The most notorious example of 2 is NetworkManager, which gets SIGTERM (org.freedesktop.nm_dispatcher timeout), shutdown network and require manual restart, despite the fact that dbus already restarted. Nice to have bunch of isolated instances at midnight because of network spike (:
3 is theoretical and depends on packages postinstall scripts and hooks.

Steps to reproduce:
1a. Get minimal setup with some additional packages:
      # debootstrap --arch=amd64 --include=network-manager,sssd,iio-sensor-proxy,ovirt-guest-agent,linux-image-amd64,grub-pc stable /mnt
      # #Do other stuff to boot from...
1b. Or add network-manager, sssd, iio-sensor-proxy and ovirt-guest-agent to fresh setup. Last two will add policy with currently nonexistent users gdm and geoclue. Skip sssd realm config. You may notice NoReply fail due setup.
2. Check NetworkManager status and restart if already dead.
3. Do systemctl reload dbus.service. Notice time taken.
4. Check NetworkManager again. Status change to 'dead' after step 3 or some short time.
5. Check dbus journal. Notice org.freedesktop.nm_dispatcher activation fail, NoReply and restart.
BONUS STAGE: remove one user policy (eg. purge ovirt-guest-agent OR iio-sensor-proxy), restart NetworkManager and do dbus reload again. This time NM not fail.

-- System Information:
Debian Release: 10.0
  APT prefers stable
  APT policy: (500, 'stable')
Architecture: amd64 (x86_64)

Kernel: Linux 4.19.0-5-amd64 (SMP w/1 CPU core)
Locale: LANG=C, LC_CTYPE=C.UTF-8 (charmap=UTF-8), LANGUAGE=C (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
Init: systemd (via /run/systemd/system)
LSM: AppArmor: enabled

Versions of packages dbus depends on:
ii  adduser       3.118
ii  libapparmor1  2.13.2-10
ii  libaudit1     1:2.8.4-3
ii  libc6         2.28-10
ii  libcap-ng0    0.7.9-2
ii  libdbus-1-3   1.12.16-1
ii  libexpat1     2.2.6-2
ii  libselinux1   2.8-1+b1
ii  libsystemd0   241-5

dbus recommends no packages.

Versions of packages dbus suggests:
ii  dbus-user-session [default-dbus-session-bus]  1.12.16-1

Versions of packages dbus is related to:
pn  dbus-x11      <none>
ii  systemd       241-5
ii  systemd-sysv  241-5

-- no debconf information
-------------- next part --------------
-- Logs begin at Tue 2019-08-13 14:27:09 UTC, end at Tue 2019-08-13 17:45:55 UTC. --
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: NetworkManager-wait-online.service: Succeeded.
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: Stopped Network Manager Wait Online.
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: Stopping Network Manager Wait Online...
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: Starting Network Manager...
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.3909] NetworkManager (version 1.14.6) is starting... (after a restart)
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.3917] Read config: /etc/NetworkManager/NetworkManager.conf (lib: no-mac-addr-change.conf)
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: Started Network Manager.
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: Starting Network Manager Wait Online...
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4021] bus-manager: acquired D-Bus service "org.freedesktop.NetworkManager"
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4029] manager[0x55a91f88b020]: monitoring kernel firmware directory '/lib/firmware'.
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4031] monitoring ifupdown state file '/run/network/ifstate'.
Aug 13 17:43:29 test-dbus-fail.fake.domain dbus-daemon[7179]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.8' (uid=0 pid=7338 comm="/usr/sbin/NetworkManager --no-daemon ")
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: Starting Hostname Service...
Aug 13 17:43:29 test-dbus-fail.fake.domain dbus-daemon[7179]: [system] Successfully activated service 'org.freedesktop.hostname1'
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: Started Hostname Service.
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4680] hostname: hostname: using hostnamed
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4681] hostname: hostname changed from (none) to "test-dbus-fail.fake.domain"
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4683] dns-mgr[0x55a91f8998f0]: init: dns=default, rc-manager=resolvconf
Aug 13 17:43:29 test-dbus-fail.fake.domain dbus-daemon[7179]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.8' (uid=0 pid=7338 comm="/usr/sbin/NetworkManager --no-daemon ")
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: Starting Network Manager Script Dispatcher Service...
Aug 13 17:43:29 test-dbus-fail.fake.domain dbus-daemon[7179]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: Started Network Manager Script Dispatcher Service.
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4866] ifupdown:       interface-parser: parsing file /etc/network/interfaces
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4867] ifupdown:       interface-parser: source line includes interfaces file(s) /etc/network/interfaces.d
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4867] ifupdown:       interface-parser: finished parsing file /etc/network/interfaces
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4868] ifupdown: management mode: unmanaged
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4868] settings: Loaded settings plugin: SettingsPluginIfupdown ("/usr/lib/x86_64-linux-gnu/NetworkManager/1.14.6/libnm-settings-plugin-ifupdown.so")
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4868] settings: Loaded settings plugin: NMSKeyfilePlugin (internal)
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4894] keyfile: new connection /etc/NetworkManager/system-connections/eth-ens3-auto (debdefb0-687f-4982-a39f-c23013e94442,"eth-ens3-auto")
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4912] manager: rfkill: WiFi enabled by radio killswitch; enabled by state file
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4919] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4929] manager: Networking is enabled by state file
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4933] dhcp-init: Using DHCP client 'dhclient'
Aug 13 17:43:29 test-dbus-fail.fake.domain nm-dispatcher[7347]: req:1 'hostname': new request (1 scripts)
Aug 13 17:43:29 test-dbus-fail.fake.domain nm-dispatcher[7347]: req:1 'hostname': start running ordered scripts...
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4956] Loaded device plugin: NMAtmManager (/usr/lib/x86_64-linux-gnu/NetworkManager/1.14.6/libnm-device-plugin-adsl.so)
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.4974] Loaded device plugin: NMBluezManager (/usr/lib/x86_64-linux-gnu/NetworkManager/1.14.6/libnm-device-plugin-bluetooth.so)
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5002] Loaded device plugin: NMTeamFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/1.14.6/libnm-device-plugin-team.so)
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5007] Loaded device plugin: NMWifiFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/1.14.6/libnm-device-plugin-wifi.so)
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5011] Loaded device plugin: NMWwanFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/1.14.6/libnm-device-plugin-wwan.so)
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5020] device (lo): carrier: link connected
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5031] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5063] device (ens3): carrier: link connected
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5074] manager: (ens3): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2)
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5129] manager: (ens3): assume: will attempt to assume matching connection 'eth-ens3-auto' (debdefb0-687f-4982-a39f-c23013e94442) (indicated)
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5131] device (ens3): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'assume')
Aug 13 17:43:29 test-dbus-fail.fake.domain nm-dispatcher[7347]: req:2 'connectivity-change': new request (1 scripts)
Aug 13 17:43:29 test-dbus-fail.fake.domain nm-dispatcher[7347]: req:2 'connectivity-change': start running ordered scripts...
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5202] device (ens3): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'assume')
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5244] device (ens3): Activation: starting connection 'eth-ens3-auto' (debdefb0-687f-4982-a39f-c23013e94442)
Aug 13 17:43:29 test-dbus-fail.fake.domain dbus-daemon[7179]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.8' (uid=0 pid=7338 comm="/usr/sbin/NetworkManager --no-daemon ")
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: Starting Authorization Manager...
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5491] device (ens3): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'assume')
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5530] device (ens3): state change: prepare -> config (reason 'none', sys-iface-state: 'assume')
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5551] device (ens3): state change: config -> ip-config (reason 'none', sys-iface-state: 'assume')
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5574] dhcp4 (ens3): activation: beginning transaction (timeout in 45 seconds)
Aug 13 17:43:29 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718209.5599] dhcp4 (ens3): dhclient started with pid 7353
Aug 13 17:43:29 test-dbus-fail.fake.domain kernel: audit: type=1400 audit(1565718209.559:31): apparmor="ALLOWED" operation="open" profile="/usr/sbin/sssd" name="/sys/devices/pci0000:00/0000:00:03.0/virtio0/net/ens3/type" pid=3663 comm="sssd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Aug 13 17:43:29 test-dbus-fail.fake.domain audit[3663]: AVC apparmor="ALLOWED" operation="open" profile="/usr/sbin/sssd" name="/sys/devices/pci0000:00/0000:00:03.0/virtio0/net/ens3/type" pid=3663 comm="sssd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Aug 13 17:43:29 test-dbus-fail.fake.domain polkitd[7352]: started daemon version 0.105 using authority implementation `local' version `0.105'
Aug 13 17:43:29 test-dbus-fail.fake.domain dbus-daemon[7179]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Aug 13 17:43:29 test-dbus-fail.fake.domain systemd[1]: Started Authorization Manager.
Aug 13 17:43:29 test-dbus-fail.fake.domain dhclient[7353]: DHCPDISCOVER on ens3 to 255.255.255.255 port 67 interval 7
Aug 13 17:43:30 test-dbus-fail.fake.domain dhclient[7353]: DHCPOFFER of 10.110.7.23 from 10.110.7.2
Aug 13 17:43:30 test-dbus-fail.fake.domain dhclient[7353]: DHCPREQUEST for 10.110.7.23 on ens3 to 255.255.255.255 port 67
Aug 13 17:43:30 test-dbus-fail.fake.domain dhclient[7353]: DHCPACK of 10.110.7.23 from 10.110.7.2
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6206] dhcp4 (ens3):   address 10.110.7.23
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6206] dhcp4 (ens3):   plen 24 (255.255.255.0)
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6207] dhcp4 (ens3):   gateway 10.110.7.254
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6207] dhcp4 (ens3):   lease time 600
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6207] dhcp4 (ens3):   nameserver '10.110.7.1'
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6207] dhcp4 (ens3):   domain name 'fake.domain'
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6208] dhcp (ens3):   domain search 'fake.domain.'
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6208] dhcp4 (ens3): state changed unknown -> bound
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6217] device (ens3): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'assume')
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6224] device (ens3): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'assume')
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6226] device (ens3): state change: secondaries -> activated (reason 'none', sys-iface-state: 'assume')
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6231] manager: NetworkManager state is now CONNECTED_LOCAL
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6354] manager: NetworkManager state is now CONNECTED_SITE
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6364] policy: set 'eth-ens3-auto' (ens3) as default for IPv4 routing and DNS
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6377] policy: set-hostname: set hostname to 'test-dbus-fail.fake.domain' (from system configuration)
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6400] device (ens3): Activation: successful, device activated.
Aug 13 17:43:30 test-dbus-fail.fake.domain nm-dispatcher[7347]: req:3 'up' [ens3]: new request (1 scripts)
Aug 13 17:43:30 test-dbus-fail.fake.domain nm-dispatcher[7347]: req:3 'up' [ens3]: start running ordered scripts...
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6440] manager: NetworkManager state is now CONNECTED_GLOBAL
Aug 13 17:43:30 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718210.6461] manager: startup complete
Aug 13 17:43:30 test-dbus-fail.fake.domain systemd[1]: Started Network Manager Wait Online.
Aug 13 17:43:30 test-dbus-fail.fake.domain nm-dispatcher[7347]: req:4 'connectivity-change': new request (1 scripts)
Aug 13 17:43:30 test-dbus-fail.fake.domain nm-dispatcher[7347]: req:5 'hostname': new request (1 scripts)
Aug 13 17:43:30 test-dbus-fail.fake.domain nm-dispatcher[7347]: req:4 'connectivity-change': start running ordered scripts...
Aug 13 17:43:30 test-dbus-fail.fake.domain nm-dispatcher[7347]: req:5 'hostname': start running ordered scripts...
Aug 13 17:43:30 test-dbus-fail.fake.domain dhclient[7353]: bound to 10.110.7.23 -- renewal in 290 seconds.
Aug 13 17:43:40 test-dbus-fail.fake.domain systemd[1]: Reloading D-Bus System Message Bus.
Aug 13 17:43:40 test-dbus-fail.fake.domain systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Aug 13 17:43:55 test-dbus-fail.fake.domain dbus-daemon[7179]: Unknown username "gdm" in message bus configuration file
Aug 13 17:44:05 test-dbus-fail.fake.domain dbus-send[7399]: Error org.freedesktop.DBus.Error.NoReply: Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.
Aug 13 17:44:05 test-dbus-fail.fake.domain systemd[1]: dbus.service: Control process exited, code=exited, status=1/FAILURE
Aug 13 17:44:15 test-dbus-fail.fake.domain dbus-daemon[7179]: Unknown username "geoclue" in message bus configuration file
Aug 13 17:44:15 test-dbus-fail.fake.domain dbus-daemon[7179]: [system] Reloaded configuration
Aug 13 17:44:15 test-dbus-fail.fake.domain systemd[1]: systemd-hostnamed.service: Succeeded.
Aug 13 17:44:15 test-dbus-fail.fake.domain systemd[1]: dbus.service: Failed with result 'exit-code'.
Aug 13 17:44:15 test-dbus-fail.fake.domain systemd[1]: Reload failed for D-Bus System Message Bus.
Aug 13 17:44:15 test-dbus-fail.fake.domain systemd[1]: polkit.service: Main process exited, code=killed, status=15/TERM
Aug 13 17:44:15 test-dbus-fail.fake.domain systemd[1]: polkit.service: Succeeded.
Aug 13 17:45:17 test-dbus-fail.fake.domain python[284]: [90B blob data]
Aug 13 17:45:18 test-dbus-fail.fake.domain python[284]: [155B blob data]
Aug 13 17:45:18 test-dbus-fail.fake.domain python[284]: [100B blob data]
Aug 13 17:45:18 test-dbus-fail.fake.domain userhelper[7427]: pam_succeed_if(ovirt-container-list:auth): requirement "user = ovirtagent" was met by user "ovirtagent"
Aug 13 17:45:18 test-dbus-fail.fake.domain systemd[1]: Started D-Bus System Message Bus.
Aug 13 17:45:35 test-dbus-fail.fake.domain dbus-daemon[7428]: dbus[7428]: Unknown username "gdm" in message bus configuration file
Aug 13 17:45:55 test-dbus-fail.fake.domain dbus-daemon[7428]: dbus[7428]: Unknown username "geoclue" in message bus configuration file
Aug 13 17:45:55 test-dbus-fail.fake.domain audit[3663]: AVC apparmor="ALLOWED" operation="open" profile="/usr/sbin/sssd" name="/sys/devices/virtual/net/lo/type" pid=3663 comm="sssd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Aug 13 17:45:55 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718355.0622] caught SIGTERM, shutting down normally.
Aug 13 17:45:55 test-dbus-fail.fake.domain kernel: audit: type=1400 audit(1565718355.058:32): apparmor="ALLOWED" operation="open" profile="/usr/sbin/sssd" name="/sys/devices/virtual/net/lo/type" pid=3663 comm="sssd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
Aug 13 17:45:55 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718355.0692] dhcp4 (ens3): canceled DHCP transaction, DHCP client pid 7353
Aug 13 17:45:55 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718355.0692] dhcp4 (ens3): state changed bound -> done
Aug 13 17:45:55 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718355.0692] device (ens3): DHCPv4: 480 seconds grace period started
Aug 13 17:45:55 test-dbus-fail.fake.domain userhelper[7427]: running '/usr/share/ovirt-guest-agent/container-list' with root privileges on behalf of 'ovirtagent'
Aug 13 17:45:55 test-dbus-fail.fake.domain NetworkManager[7338]: <info>  [1565718355.0771] exiting (success)
Aug 13 17:45:55 test-dbus-fail.fake.domain systemd[1]: systemd-logind.service: Succeeded.
Aug 13 17:45:55 test-dbus-fail.fake.domain systemd[1]: systemd-logind.service: Service has no hold-off time (RestartSec=0), scheduling restart.
Aug 13 17:45:55 test-dbus-fail.fake.domain systemd[1]: systemd-logind.service: Scheduled restart job, restart counter is at 11.
Aug 13 17:45:55 test-dbus-fail.fake.domain systemd[1]: Stopped Login Service.
Aug 13 17:45:55 test-dbus-fail.fake.domain systemd[1]: Starting Login Service...
Aug 13 17:45:55 test-dbus-fail.fake.domain systemd[1]: NetworkManager.service: Succeeded.
Aug 13 17:45:55 test-dbus-fail.fake.domain systemd-logind[7429]: New seat seat0.
Aug 13 17:45:55 test-dbus-fail.fake.domain systemd-logind[7429]: Watching system buttons on /dev/input/event3 (Power Button)
Aug 13 17:45:55 test-dbus-fail.fake.domain systemd-logind[7429]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Aug 13 17:45:55 test-dbus-fail.fake.domain systemd[1]: Started Login Service.
Aug 13 17:45:55 test-dbus-fail.fake.domain systemd-logind[7429]: New session 1 of user root.