D-Bus

  1. Bug #1871538
  2. Comment #17

Comment 17 for bug 1871538

Revision history for this message
Christian Ehrhardt  (paelzer) wrote :

I hit that again today on an upgrade.
Network manager didn't come up again.

Mai 20 11:04:46 Keschdeichel systemd[1]: NetworkManager.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9491] caught SIGTERM, shutting down normally.
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9504] dhcp4 (enp0s31f6): canceled DHCP transaction
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9504] dhcp4 (enp0s31f6): state changed extended -> done
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9504] device (enp0s31f6): DHCPv4: trying to acquire a new lease within 90 seconds
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9733] dhcp4 (wlp4s0): canceled DHCP transaction
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9812] dhcp4 (wlp4s0): state changed bound -> done
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9813] device (wlp4s0): DHCPv4: trying to acquire a new lease within 90 seconds
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9817] device (p2p-dev-wlp4s0): state change: disconnected -> unmanaged (reason 'unmanaged', sys-iface-state: 'managed')
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9825] device (strswanbr2-nic): released from master device strswanbr2
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9829] device (strswanbr1-nic): released from master device strswanbr1
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9831] device (veth47ecccc5): released from master device lxdbr0
Mai 20 11:04:47 Keschdeichel NetworkManager[1330]: <info> [1589965487.0047] device (virbr0-nic): released from master device virbr0
Mai 20 11:04:47 Keschdeichel NetworkManager[1330]: <info> [1589965487.0050] device (vethcc7405c8): released from master device lxdbr0
Mai 20 11:04:47 Keschdeichel NetworkManager[1330]: <info> [1589965487.0069] device (vetha09a9db4): released from master device lxdbr0
Mai 20 11:04:47 Keschdeichel NetworkManager[1330]: <info> [1589965487.0077] device (veth754add92): released from master device lxdbr0
Mai 20 11:04:47 Keschdeichel NetworkManager[1330]: <info> [1589965487.0094] device (vethc060f004): released from master device virbr0
Mai 20 11:04:47 Keschdeichel NetworkManager[1330]: <info> [1589965487.0101] device (veth3152ecc9): released from master device lxdbr0
Mai 20 11:04:47 Keschdeichel NetworkManager[1330]: <info> [1589965487.0109] device (vethb6dd0569): released from master device lxdbr0
Mai 20 11:04:47 Keschdeichel NetworkManager[1330]: <info> [1589965487.1008] device (virbr0): bridge port vnet1 was detached
Mai 20 11:04:47 Keschdeichel NetworkManager[1330]: <info> [1589965487.1025] manager: NetworkManager state is now CONNECTED_SITE
Mai 20 11:04:47 Keschdeichel NetworkManager[1330]: <info> [1589965487.1030] device (virbr0): bridge port vnet0 was detached
Mai 20 11:04:47 Keschdeichel NetworkManager[1330]: <info> [1589965487.1629] exiting (success)
Mai 20 11:04:47 Keschdeichel systemd[1]: NetworkManager.service: Succeeded.
Mai 20 11:04:48 Keschdeichel nm-openvpn[847263]: SIGTERM[hard,] received, process exiting

$ systemctl status NetworkManager
● NetworkManager.service - Network Manager
     Loaded: loaded (/lib/systemd/system/NetworkManager.service; enabled; vendor preset: enabled)
     Active: inactive (dead) since Wed 2020-05-20 11:04:47 CEST; 15min ago
       Docs: man:NetworkManager(8)
   Main PID: 1330 (code=exited, status=0/SUCCESS)
      Tasks: 0 (limit: 38269)
     Memory: 12.2M
     CGroup: /system.slice/NetworkManager.service

But that still isn't the root cause.

And before this plenty of things failed on GetNameOwner() in that sense it is similar to the former occurance.

Mai 20 11:03:56 Keschdeichel systemd[1]: Reloading.
Mai 20 11:03:56 Keschdeichel systemd[1]: /lib/systemd/system/dbus.socket:5: ListenStream= references a path below legacy directory /var/run/, updating /var/run/dbus/system_bus_socket → /run>
Mai 20 11:04:11 Keschdeichel tracker-store[976018]: OK
Mai 20 11:04:11 Keschdeichel systemd[18845]: tracker-store.service: Succeeded.
Mai 20 11:04:21 Keschdeichel systemd[1]: We couldn't coldplug machine-qemu\x2d13\x2dfocal.scope, proceeding anyway: Connection timed out
Mai 20 11:04:46 Keschdeichel systemd[1]: We couldn't coldplug machine-qemu\x2d11\x2dbionic.scope, proceeding anyway: Connection timed out
Mai 20 11:04:46 Keschdeichel systemd[1]: NetworkManager.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: wpa_supplicant.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: thermald.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: udisks2.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: switcheroo-control.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: accounts-daemon.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: gdm.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel thermald[1377]: [WARN]Terminating ...
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9491] caught SIGTERM, shutting down normally.
Mai 20 11:04:46 Keschdeichel systemd[1]: systemd-logind.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: systemd-machined.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd-machined[1376]: Failed to drop reference to machine scope, ignoring: Transport endpoint is not connected
Mai 20 11:04:46 Keschdeichel systemd-machined[1376]: Failed to drop reference to machine scope, ignoring: Transport endpoint is not connected
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9504] dhcp4 (enp0s31f6): canceled DHCP transaction
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9504] dhcp4 (enp0s31f6): state changed extended -> done
Mai 20 11:04:46 Keschdeichel NetworkManager[1330]: <info> [1589965486.9504] device (enp0s31f6): DHCPv4: trying to acquire a new lease within 90 seconds
Mai 20 11:04:46 Keschdeichel systemd[1]: avahi-daemon.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: ModemManager.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: polkit.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: bolt.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: colord.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: fwupd.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel systemd[1]: upower.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel bluetoothd[1327]: Terminating
Mai 20 11:04:46 Keschdeichel systemd[1]: bluetooth.service: Unexpected error response from GetNameOwner(): Connection terminated
Mai 20 11:04:46 Keschdeichel avahi-daemon[1326]: Got SIGTERM, quitting.
Mai 20 11:04:46 Keschdeichel systemd[1]: rtkit-daemon.service: Unexpected error response from GetNameOwner(): Connection terminated

I also have a bunch of crash files again, but all of them are apps going down due to the gnome shell reset

And again dbus seems to have timed out:

...
Mai 20 11:04:55 Keschdeichel dbus-daemon[1329]: [system] Successfully activated service 'org.freedesktop.UPower'
Mai 20 11:04:56 Keschdeichel dbus-daemon[1329]: [system] Activating via systemd: service name='org.freedesktop.ColorManager' unit='colord.service' requested by ':1.3764' (uid=121 pid=979543>
Mai 20 11:04:56 Keschdeichel dbus-daemon[1329]: [system] Successfully activated service 'org.freedesktop.ColorManager'
Mai 20 11:04:59 Keschdeichel dbus-daemon[1329]: Unknown group "power" in message bus configuration file
Mai 20 11:04:59 Keschdeichel dbus-daemon[1329]: [system] Reloaded configuration
Mai 20 11:05:12 Keschdeichel dbus-daemon[1329]: [system] Failed to activate service 'org.freedesktop.nm_dispatcher': timed out (service_start_timeout=25000ms)
Mai 20 11:05:16 Keschdeichel dbus-daemon[1329]: Unknown group "power" in message bus configuration file
Mai 20 11:05:16 Keschdeichel dbus-daemon[1329]: [system] Reloaded configuration