[Bug 1777125] [NEW] On boot up NetworkManager runs nm_dispatcher for only 1 of 2 tun interfaces
Nick Tait
1777125 at bugs.launchpad.net
Fri Jun 15 12:15:59 UTC 2018
Public bug reported:
I have two tun interfaces used with OpenVPN, called tun0 (UDP peer-to-
peer VPN) and tun1 (TCP server VPN), and I have a script in /etc/network
/if-up.d which I use to add routing table entries when the interface
comes up. (FYI The reason for adding routes in an if-up.d script is so I
can administer all the policy-based routes in one place.)
When I start up OpenVPN for the tun0 interface, the if-up.d scripts run
successfully for that interface. And when I start up OpenVPN for the
tun1 interface, the if-up.d scripts run successfully for that interface.
So I know that everything is configured correctly.
BTW The two OpenVPN instances are configured as systemd services called
openvpn-server at p2p.service and openvpn-server at server.service, and
neither tun0 and tun1 are configured in /etc/network/interfaces.
However around 50% of the time when I reboot, the if-up.d scripts are
run for only one of the two interfaces (even though both interfaces had
started successfully), and it seems random which one. (The other 50% of
the time it works properly and runs the if-up.d scripts for both
interfaces.)
As far as I can tell, it is NetworkManager which is supposed to start
nm-dispatcher to run the if-up.d scripts when each tun interface comes
up, and it seems that if both interfaces come up at the same time, then
it only runs nm-dispatcher for one of them?
I've pasted below an extract from my syslog after a reboot, where nm-
dispatcher was only run for tun1, not tun0:
$ egrep 'NetworkManager|nm-dispatcher' /var/log/syslog
Jun 15 20:49:55 maimbo NetworkManager[1015]: <info> [1529052595.6455] NetworkManager (version 1.10.6) is starting... (for the first time)
Jun 15 20:49:55 maimbo NetworkManager[1015]: <info> [1529052595.6456] Read config: /etc/NetworkManager/NetworkManager.conf (lib: 10-dns-resolved.conf, no-mac-addr-change.conf) (etc: 10-globally-managed-devices.conf, default-wifi-powersave-on.conf)
Jun 15 20:49:55 maimbo NetworkManager[1015]: <info> [1529052595.8380] manager[0x55bf3e4fe050]: monitoring kernel firmware directory '/lib/firmware'.
Jun 15 20:49:55 maimbo NetworkManager[1015]: <info> [1529052595.8387] monitoring ifupdown state file '/run/network/ifstate'.
Jun 15 20:49:55 maimbo dbus-daemon[860]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.26' (uid=0 pid=1015 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Jun 15 20:49:58 maimbo NetworkManager[1015]: <info> [1529052598.3561] hostname: hostname: using hostnamed
Jun 15 20:49:58 maimbo dbus-daemon[860]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.26' (uid=0 pid=1015 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Jun 15 20:49:58 maimbo NetworkManager[1015]: <info> [1529052598.3562] hostname: hostname changed from (none) to "maimbo"
Jun 15 20:49:58 maimbo NetworkManager[1015]: <info> [1529052598.3572] dns-mgr[0x55bf3e51b970]: init: dns=systemd-resolved, rc-manager=symlink, plugin=systemd-resolved
Jun 15 20:49:58 maimbo NetworkManager[1015]: <info> [1529052598.3585] manager[0x55bf3e4fe050]: rfkill: WiFi hardware radio set enabled
Jun 15 20:49:58 maimbo NetworkManager[1015]: <info> [1529052598.3585] manager[0x55bf3e4fe050]: rfkill: WWAN hardware radio set enabled
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7231] init!
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7234] interface-parser: parsing file /etc/network/interfaces
Jun 15 20:49:59 maimbo nm-dispatcher: req:1 'hostname': new request (2 scripts)
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7234] interface-parser: source line includes interfaces file(s) /etc/network/interfaces.d/*
Jun 15 20:49:59 maimbo nm-dispatcher: req:1 'hostname': start running ordered scripts...
Jun 15 20:49:59 maimbo NetworkManager[1015]: <warn> [1529052599.7235] interfaces file /etc/network/interfaces.d/* doesn't exist
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7236] interface-parser: finished parsing file /etc/network/interfaces
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7243] guessed connection type (enp3s3) = 802-3-ethernet
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7244] update_connection_setting_from_if_block: name:enp3s3, type:802-3-ethernet, id:Ifupdown (enp3s3), uuid: 40b6c586-e29c-6c7e-50ee-b622c62bcef9
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7248] adding enp3s3 to connections
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7248] adding iface enp3s3 to eni_ifaces
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7249] deleting enp3s3 from connections
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7250] guessed connection type (enp3s3) = 802-3-ethernet
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7250] update_connection_setting_from_if_block: name:enp3s3, type:802-3-ethernet, id:Ifupdown (enp3s3), uuid: 40b6c586-e29c-6c7e-50ee-b622c62bcef9
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7252] addresses count: 1
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7252] No dns-nameserver configured in /etc/network/interfaces
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7253] adding enp3s3 to connections
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7253] adding iface enp3s3 to eni_ifaces
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7254] guessed connection type (enp3s3.160) = 802-3-ethernet
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7255] update_connection_setting_from_if_block: name:enp3s3.160, type:802-3-ethernet, id:Ifupdown (enp3s3.160), uuid: 49a5a233-a11a-69e4-b350-a5a5e8f425d5
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7255] addresses count: 1
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7256] No dns-nameserver configured in /etc/network/interfaces
Jun 15 20:50:01 maimbo NetworkManager[1015]: <info> [1529052599.7256] adding enp3s3.160 to connections
Jun 15 20:50:01 maimbo NetworkManager[1015]: <info> [1529052599.7256] adding iface enp3s3.160 to eni_ifaces
Jun 15 20:50:01 maimbo NetworkManager[1015]: <info> [1529052599.7257] deleting enp3s3.160 from connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7258] guessed connection type (enp3s3.160) = 802-3-ethernet
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7258] update_connection_setting_from_if_block: name:enp3s3.160, type:802-3-ethernet, id:Ifupdown (enp3s3.160), uuid: 49a5a233-a11a-69e4-b350-a5a5e8f425d5
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7259] adding enp3s3.160 to connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7260] adding iface enp3s3.160 to eni_ifaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7261] guessed connection type (enp3s3.168) = 802-3-ethernet
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7261] update_connection_setting_from_if_block: name:enp3s3.168, type:802-3-ethernet, id:Ifupdown (enp3s3.168), uuid: 4ab0caee-fc7e-ad1a-0a14-22abb4bd1a23
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7262] addresses count: 1
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7262] No dns-nameserver configured in /etc/network/interfaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7265] adding enp3s3.168 to connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7266] adding iface enp3s3.168 to eni_ifaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7266] deleting enp3s3.168 from connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7267] guessed connection type (enp3s3.168) = 802-3-ethernet
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7268] update_connection_setting_from_if_block: name:enp3s3.168, type:802-3-ethernet, id:Ifupdown (enp3s3.168), uuid: 4ab0caee-fc7e-ad1a-0a14-22abb4bd1a23
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7269] adding enp3s3.168 to connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7269] adding iface enp3s3.168 to eni_ifaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7270] guessed connection type (enp3s3.300) = 802-3-ethernet
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7270] update_connection_setting_from_if_block: name:enp3s3.300, type:802-3-ethernet, id:Ifupdown (enp3s3.300), uuid: e320a74f-4c95-e1c5-eaab-08e124c883f8
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7271] addresses count: 1
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7271] No dns-nameserver configured in /etc/network/interfaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7272] adding enp3s3.300 to connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7272] adding iface enp3s3.300 to eni_ifaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7273] guessed connection type (enp3s8) = 802-3-ethernet
Jun 15 20:50:03 maimbo NetworkManager[1015]: <info> [1529052599.7274] update_connection_setting_from_if_block: name:enp3s8, type:802-3-ethernet, id:Ifupdown (enp3s8), uuid: a05bfd79-4089-d95f-db9d-45010a2e0fbd
Jun 15 20:50:03 maimbo NetworkManager[1015]: <info> [1529052599.7275] adding enp3s8 to connections
Jun 15 20:50:03 maimbo NetworkManager[1015]: <info> [1529052599.7275] adding iface enp3s8 to eni_ifaces
Jun 15 20:50:04 maimbo NetworkManager[1015]: <info> [1529052599.7276] deleting enp3s8 from connections
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7276] guessed connection type (enp3s8) = 802-3-ethernet
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7277] update_connection_setting_from_if_block: name:enp3s8, type:802-3-ethernet, id:Ifupdown (enp3s8), uuid: a05bfd79-4089-d95f-db9d-45010a2e0fbd
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7278] adding enp3s8 to connections
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7279] adding iface enp3s8 to eni_ifaces
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7279] autoconnect
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7279] autoconnect
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7280] autoconnect
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7280] autoconnect
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7281] management mode: unmanaged
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7304] devices added (path: /sys/devices/pci0000:00/0000:00:1e.0/0000:03:03.0/net/enp3s3, iface: enp3s3)
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7305] locking wired connection setting
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7306] settings-connection[0x55bf3e4c8c40,40b6c586-e29c-6c7e-50ee-b622c62bcef9]: write: failure to update connection: writing settings not supported
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7307] devices added (path: /sys/devices/virtual/net/enp3s3.160, iface: enp3s3.160)
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7308] locking wired connection setting
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7309] settings-connection[0x55bf3e4c8e00,49a5a233-a11a-69e4-b350-a5a5e8f425d5]: write: failure to update connection: writing settings not supported
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7310] devices added (path: /sys/devices/virtual/net/enp3s3.168, iface: enp3s3.168)
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7311] locking wired connection setting
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7311] settings-connection[0x55bf3e54e8e0,4ab0caee-fc7e-ad1a-0a14-22abb4bd1a23]: write: failure to update connection: writing settings not supported
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7313] devices added (path: /sys/devices/virtual/net/enp3s3.300, iface: enp3s3.300)
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7314] locking wired connection setting
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7314] settings-connection[0x55bf3e54e9c0,e320a74f-4c95-e1c5-eaab-08e124c883f8]: write: failure to update connection: writing settings not supported
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7315] devices added (path: /sys/devices/virtual/net/lo, iface: lo)
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7316] device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7316] end _init.
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7316] settings: loaded plugin ifupdown: (C) 2008 Canonical Ltd. To report bugs please use the NetworkManager mailing list. (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-settings-plugin-ifupdown.so)
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7318] settings: loaded plugin keyfile: (c) 2007 - 2016 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7318] (1045667008) ... get_connections.
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7319] (1045667008) ... get_connections (managed=false): return empty list.
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.8265] get unmanaged devices count: 4
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.8266] manager: rfkill: WiFi enabled by radio killswitch; enabled by state file
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.8267] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8267] manager: Networking is enabled by state file
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8272] dhcp-init: Using DHCP client 'dhclient'
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8572] Loaded device plugin: NMBondDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8573] Loaded device plugin: NMBridgeDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8573] Loaded device plugin: NMDummyDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8574] Loaded device plugin: NMEthernetDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8574] Loaded device plugin: NMInfinibandDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8575] Loaded device plugin: NMIPTunnelDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8575] Loaded device plugin: NMMacsecDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8576] Loaded device plugin: NMMacvlanDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8576] Loaded device plugin: NMPppDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8577] Loaded device plugin: NMTunDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8577] Loaded device plugin: NMVethDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8578] Loaded device plugin: NMVlanDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8578] Loaded device plugin: NMVxlanDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.2737] Loaded device plugin: NMBluezManager (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-bluetooth.so)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.3169] Loaded device plugin: NMAtmManager (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-adsl.so)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.5003] Loaded device plugin: NMTeamFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-team.so)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.6081] Loaded device plugin: NMWifiFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wifi.so)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.6866] Loaded device plugin: NMWwanFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wwan.so)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7383] device (lo): carrier: link connected
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7415] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7435] device (enp3s3): carrier: link connected
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7463] manager: (enp3s3): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7477] device (enp3s3.160): carrier: link connected
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7501] manager: (enp3s3.160): new VLAN device (/org/freedesktop/NetworkManager/Devices/3)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7513] device (enp3s3.168): carrier: link connected
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7538] manager: (enp3s3.168): new VLAN device (/org/freedesktop/NetworkManager/Devices/4)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7555] device (enp3s3.300): carrier: link connected
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7577] manager: (enp3s3.300): new VLAN device (/org/freedesktop/NetworkManager/Devices/5)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052602.1137] manager: NetworkManager state is now CONNECTED_GLOBAL
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052602.1589] modem-manager: ModemManager available
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052602.1652] manager: startup complete
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052607.8477] manager: (tun0): new Tun device (/org/freedesktop/NetworkManager/Devices/6)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052607.9229] devices added (path: /sys/devices/virtual/net/tun0, iface: tun0)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052607.9230] device added (path: /sys/devices/virtual/net/tun0, iface: tun0): no ifupdown configuration found.
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052607.9234] device (tun0): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052607.9311] keyfile: add connection in-memory (20ce3fb2-4bb9-4cfc-ab0e-c09b03c3c82d,"tun0")
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1890] device (tun0): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1916] device (tun0): Activation: starting connection 'tun0' (20ce3fb2-4bb9-4cfc-ab0e-c09b03c3c82d)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1934] devices added (path: /sys/devices/virtual/net/tun1, iface: tun1)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1934] device added (path: /sys/devices/virtual/net/tun1, iface: tun1): no ifupdown configuration found.
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1945] device (tun0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1979] manager: (tun1): new Tun device (/org/freedesktop/NetworkManager/Devices/7)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2045] keyfile: add connection in-memory (36a1db01-85ab-4f1e-8181-2ace88956184,"tun1")
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2061] device (tun1): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2069] device (tun1): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2092] device (tun1): Activation: starting connection 'tun1' (36a1db01-85ab-4f1e-8181-2ace88956184)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2107] device (tun0): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2121] device (tun1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2130] device (tun0): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2132] device (tun0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2139] device (tun1): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2152] device (tun1): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2155] device (tun1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2161] device (tun0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2169] device (tun0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:17 maimbo NetworkManager[1015]: <info> [1529052617.5681] device (tun0): Activation: successful, device activated.
Jun 15 20:50:17 maimbo NetworkManager[1015]: <info> [1529052617.5707] device (tun1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:17 maimbo NetworkManager[1015]: <info> [1529052617.5751] device (tun1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:17 maimbo NetworkManager[1015]: <info> [1529052617.8037] device (tun1): Activation: successful, device activated.
Jun 15 20:50:17 maimbo dbus-daemon[860]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.26' (uid=0 pid=1015 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Jun 15 20:50:17 maimbo nm-dispatcher: req:1 'up' [tun1]: new request (2 scripts)
Jun 15 20:50:17 maimbo nm-dispatcher: req:1 'up' [tun1]: start running ordered scripts...
Jun 15 20:50:18 maimbo nm-dispatcher[2572]: /etc/network/if-up.d/nick-pbr: About to acquire lock for interface: tun1
Jun 15 20:50:18 maimbo nm-dispatcher[2572]: /etc/network/if-up.d/nick-pbr: Acquired lock for interface: tun1
Jun 15 20:50:18 maimbo nm-dispatcher[2572]: /etc/network/if-up.d/nick-pbr: Interface has started: tun1
Jun 15 20:50:18 maimbo nm-dispatcher[2572]: /etc/network/if-up.d/nick-pbr: Adding routes for interface: tun1
Jun 15 20:50:18 maimbo nm-dispatcher[2572]: /etc/network/if-up.d/nick-pbr: Flushing cache
The key observation in the log is that we see the following line for
tun1, but no equivalent line for tun0:
Jun 15 20:50:17 maimbo nm-dispatcher: req:1 'up' [tun1]: start running
ordered scripts...
As a result of this issue I need to check my routing tables after every
reboot to see if anything is missing (and if so restart the
corresponding OpenVPN service).
Other info:
$ lsb_release -rd
Description: Ubuntu 18.04 LTS
Release: 18.04
$ apt-cache policy network-manager
network-manager:
Installed: 1.10.6-2ubuntu1
Candidate: 1.10.6-2ubuntu1
Version table:
*** 1.10.6-2ubuntu1 500
500 http://nz.archive.ubuntu.com/ubuntu bionic/main amd64 Packages
100 /var/lib/dpkg/status
** Affects: ifupdown (Ubuntu)
Importance: Undecided
Status: New
--
You received this bug notification because you are a member of Ubuntu
Foundations Bugs, which is subscribed to ifupdown in Ubuntu.
https://bugs.launchpad.net/bugs/1777125
Title:
On boot up NetworkManager runs nm_dispatcher for only 1 of 2 tun
interfaces
Status in ifupdown package in Ubuntu:
New
Bug description:
I have two tun interfaces used with OpenVPN, called tun0 (UDP peer-to-
peer VPN) and tun1 (TCP server VPN), and I have a script in
/etc/network/if-up.d which I use to add routing table entries when the
interface comes up. (FYI The reason for adding routes in an if-up.d
script is so I can administer all the policy-based routes in one
place.)
When I start up OpenVPN for the tun0 interface, the if-up.d scripts
run successfully for that interface. And when I start up OpenVPN for
the tun1 interface, the if-up.d scripts run successfully for that
interface. So I know that everything is configured correctly.
BTW The two OpenVPN instances are configured as systemd services
called openvpn-server at p2p.service and openvpn-server at server.service,
and neither tun0 and tun1 are configured in /etc/network/interfaces.
However around 50% of the time when I reboot, the if-up.d scripts are
run for only one of the two interfaces (even though both interfaces
had started successfully), and it seems random which one. (The other
50% of the time it works properly and runs the if-up.d scripts for
both interfaces.)
As far as I can tell, it is NetworkManager which is supposed to start
nm-dispatcher to run the if-up.d scripts when each tun interface comes
up, and it seems that if both interfaces come up at the same time,
then it only runs nm-dispatcher for one of them?
I've pasted below an extract from my syslog after a reboot, where nm-
dispatcher was only run for tun1, not tun0:
$ egrep 'NetworkManager|nm-dispatcher' /var/log/syslog
Jun 15 20:49:55 maimbo NetworkManager[1015]: <info> [1529052595.6455] NetworkManager (version 1.10.6) is starting... (for the first time)
Jun 15 20:49:55 maimbo NetworkManager[1015]: <info> [1529052595.6456] Read config: /etc/NetworkManager/NetworkManager.conf (lib: 10-dns-resolved.conf, no-mac-addr-change.conf) (etc: 10-globally-managed-devices.conf, default-wifi-powersave-on.conf)
Jun 15 20:49:55 maimbo NetworkManager[1015]: <info> [1529052595.8380] manager[0x55bf3e4fe050]: monitoring kernel firmware directory '/lib/firmware'.
Jun 15 20:49:55 maimbo NetworkManager[1015]: <info> [1529052595.8387] monitoring ifupdown state file '/run/network/ifstate'.
Jun 15 20:49:55 maimbo dbus-daemon[860]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.26' (uid=0 pid=1015 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Jun 15 20:49:58 maimbo NetworkManager[1015]: <info> [1529052598.3561] hostname: hostname: using hostnamed
Jun 15 20:49:58 maimbo dbus-daemon[860]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.26' (uid=0 pid=1015 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Jun 15 20:49:58 maimbo NetworkManager[1015]: <info> [1529052598.3562] hostname: hostname changed from (none) to "maimbo"
Jun 15 20:49:58 maimbo NetworkManager[1015]: <info> [1529052598.3572] dns-mgr[0x55bf3e51b970]: init: dns=systemd-resolved, rc-manager=symlink, plugin=systemd-resolved
Jun 15 20:49:58 maimbo NetworkManager[1015]: <info> [1529052598.3585] manager[0x55bf3e4fe050]: rfkill: WiFi hardware radio set enabled
Jun 15 20:49:58 maimbo NetworkManager[1015]: <info> [1529052598.3585] manager[0x55bf3e4fe050]: rfkill: WWAN hardware radio set enabled
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7231] init!
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7234] interface-parser: parsing file /etc/network/interfaces
Jun 15 20:49:59 maimbo nm-dispatcher: req:1 'hostname': new request (2 scripts)
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7234] interface-parser: source line includes interfaces file(s) /etc/network/interfaces.d/*
Jun 15 20:49:59 maimbo nm-dispatcher: req:1 'hostname': start running ordered scripts...
Jun 15 20:49:59 maimbo NetworkManager[1015]: <warn> [1529052599.7235] interfaces file /etc/network/interfaces.d/* doesn't exist
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7236] interface-parser: finished parsing file /etc/network/interfaces
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7243] guessed connection type (enp3s3) = 802-3-ethernet
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7244] update_connection_setting_from_if_block: name:enp3s3, type:802-3-ethernet, id:Ifupdown (enp3s3), uuid: 40b6c586-e29c-6c7e-50ee-b622c62bcef9
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7248] adding enp3s3 to connections
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7248] adding iface enp3s3 to eni_ifaces
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7249] deleting enp3s3 from connections
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7250] guessed connection type (enp3s3) = 802-3-ethernet
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7250] update_connection_setting_from_if_block: name:enp3s3, type:802-3-ethernet, id:Ifupdown (enp3s3), uuid: 40b6c586-e29c-6c7e-50ee-b622c62bcef9
Jun 15 20:49:59 maimbo NetworkManager[1015]: <info> [1529052599.7252] addresses count: 1
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7252] No dns-nameserver configured in /etc/network/interfaces
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7253] adding enp3s3 to connections
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7253] adding iface enp3s3 to eni_ifaces
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7254] guessed connection type (enp3s3.160) = 802-3-ethernet
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7255] update_connection_setting_from_if_block: name:enp3s3.160, type:802-3-ethernet, id:Ifupdown (enp3s3.160), uuid: 49a5a233-a11a-69e4-b350-a5a5e8f425d5
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7255] addresses count: 1
Jun 15 20:50:00 maimbo NetworkManager[1015]: <info> [1529052599.7256] No dns-nameserver configured in /etc/network/interfaces
Jun 15 20:50:01 maimbo NetworkManager[1015]: <info> [1529052599.7256] adding enp3s3.160 to connections
Jun 15 20:50:01 maimbo NetworkManager[1015]: <info> [1529052599.7256] adding iface enp3s3.160 to eni_ifaces
Jun 15 20:50:01 maimbo NetworkManager[1015]: <info> [1529052599.7257] deleting enp3s3.160 from connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7258] guessed connection type (enp3s3.160) = 802-3-ethernet
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7258] update_connection_setting_from_if_block: name:enp3s3.160, type:802-3-ethernet, id:Ifupdown (enp3s3.160), uuid: 49a5a233-a11a-69e4-b350-a5a5e8f425d5
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7259] adding enp3s3.160 to connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7260] adding iface enp3s3.160 to eni_ifaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7261] guessed connection type (enp3s3.168) = 802-3-ethernet
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7261] update_connection_setting_from_if_block: name:enp3s3.168, type:802-3-ethernet, id:Ifupdown (enp3s3.168), uuid: 4ab0caee-fc7e-ad1a-0a14-22abb4bd1a23
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7262] addresses count: 1
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7262] No dns-nameserver configured in /etc/network/interfaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7265] adding enp3s3.168 to connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7266] adding iface enp3s3.168 to eni_ifaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7266] deleting enp3s3.168 from connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7267] guessed connection type (enp3s3.168) = 802-3-ethernet
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7268] update_connection_setting_from_if_block: name:enp3s3.168, type:802-3-ethernet, id:Ifupdown (enp3s3.168), uuid: 4ab0caee-fc7e-ad1a-0a14-22abb4bd1a23
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7269] adding enp3s3.168 to connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7269] adding iface enp3s3.168 to eni_ifaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7270] guessed connection type (enp3s3.300) = 802-3-ethernet
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7270] update_connection_setting_from_if_block: name:enp3s3.300, type:802-3-ethernet, id:Ifupdown (enp3s3.300), uuid: e320a74f-4c95-e1c5-eaab-08e124c883f8
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7271] addresses count: 1
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7271] No dns-nameserver configured in /etc/network/interfaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7272] adding enp3s3.300 to connections
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7272] adding iface enp3s3.300 to eni_ifaces
Jun 15 20:50:02 maimbo NetworkManager[1015]: <info> [1529052599.7273] guessed connection type (enp3s8) = 802-3-ethernet
Jun 15 20:50:03 maimbo NetworkManager[1015]: <info> [1529052599.7274] update_connection_setting_from_if_block: name:enp3s8, type:802-3-ethernet, id:Ifupdown (enp3s8), uuid: a05bfd79-4089-d95f-db9d-45010a2e0fbd
Jun 15 20:50:03 maimbo NetworkManager[1015]: <info> [1529052599.7275] adding enp3s8 to connections
Jun 15 20:50:03 maimbo NetworkManager[1015]: <info> [1529052599.7275] adding iface enp3s8 to eni_ifaces
Jun 15 20:50:04 maimbo NetworkManager[1015]: <info> [1529052599.7276] deleting enp3s8 from connections
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7276] guessed connection type (enp3s8) = 802-3-ethernet
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7277] update_connection_setting_from_if_block: name:enp3s8, type:802-3-ethernet, id:Ifupdown (enp3s8), uuid: a05bfd79-4089-d95f-db9d-45010a2e0fbd
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7278] adding enp3s8 to connections
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7279] adding iface enp3s8 to eni_ifaces
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7279] autoconnect
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7279] autoconnect
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7280] autoconnect
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7280] autoconnect
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7281] management mode: unmanaged
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7304] devices added (path: /sys/devices/pci0000:00/0000:00:1e.0/0000:03:03.0/net/enp3s3, iface: enp3s3)
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7305] locking wired connection setting
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7306] settings-connection[0x55bf3e4c8c40,40b6c586-e29c-6c7e-50ee-b622c62bcef9]: write: failure to update connection: writing settings not supported
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7307] devices added (path: /sys/devices/virtual/net/enp3s3.160, iface: enp3s3.160)
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7308] locking wired connection setting
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7309] settings-connection[0x55bf3e4c8e00,49a5a233-a11a-69e4-b350-a5a5e8f425d5]: write: failure to update connection: writing settings not supported
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7310] devices added (path: /sys/devices/virtual/net/enp3s3.168, iface: enp3s3.168)
Jun 15 20:50:06 maimbo NetworkManager[1015]: <info> [1529052599.7311] locking wired connection setting
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7311] settings-connection[0x55bf3e54e8e0,4ab0caee-fc7e-ad1a-0a14-22abb4bd1a23]: write: failure to update connection: writing settings not supported
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7313] devices added (path: /sys/devices/virtual/net/enp3s3.300, iface: enp3s3.300)
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7314] locking wired connection setting
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7314] settings-connection[0x55bf3e54e9c0,e320a74f-4c95-e1c5-eaab-08e124c883f8]: write: failure to update connection: writing settings not supported
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7315] devices added (path: /sys/devices/virtual/net/lo, iface: lo)
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7316] device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7316] end _init.
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7316] settings: loaded plugin ifupdown: (C) 2008 Canonical Ltd. To report bugs please use the NetworkManager mailing list. (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-settings-plugin-ifupdown.so)
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7318] settings: loaded plugin keyfile: (c) 2007 - 2016 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7318] (1045667008) ... get_connections.
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.7319] (1045667008) ... get_connections (managed=false): return empty list.
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.8265] get unmanaged devices count: 4
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.8266] manager: rfkill: WiFi enabled by radio killswitch; enabled by state file
Jun 15 20:50:07 maimbo NetworkManager[1015]: <info> [1529052599.8267] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8267] manager: Networking is enabled by state file
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8272] dhcp-init: Using DHCP client 'dhclient'
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8572] Loaded device plugin: NMBondDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8573] Loaded device plugin: NMBridgeDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8573] Loaded device plugin: NMDummyDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8574] Loaded device plugin: NMEthernetDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8574] Loaded device plugin: NMInfinibandDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8575] Loaded device plugin: NMIPTunnelDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8575] Loaded device plugin: NMMacsecDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8576] Loaded device plugin: NMMacvlanDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8576] Loaded device plugin: NMPppDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8577] Loaded device plugin: NMTunDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8577] Loaded device plugin: NMVethDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8578] Loaded device plugin: NMVlanDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052599.8578] Loaded device plugin: NMVxlanDeviceFactory (internal)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.2737] Loaded device plugin: NMBluezManager (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-bluetooth.so)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.3169] Loaded device plugin: NMAtmManager (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-adsl.so)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.5003] Loaded device plugin: NMTeamFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-team.so)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.6081] Loaded device plugin: NMWifiFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wifi.so)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.6866] Loaded device plugin: NMWwanFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wwan.so)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7383] device (lo): carrier: link connected
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7415] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7435] device (enp3s3): carrier: link connected
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7463] manager: (enp3s3): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7477] device (enp3s3.160): carrier: link connected
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7501] manager: (enp3s3.160): new VLAN device (/org/freedesktop/NetworkManager/Devices/3)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7513] device (enp3s3.168): carrier: link connected
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7538] manager: (enp3s3.168): new VLAN device (/org/freedesktop/NetworkManager/Devices/4)
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7555] device (enp3s3.300): carrier: link connected
Jun 15 20:50:08 maimbo NetworkManager[1015]: <info> [1529052601.7577] manager: (enp3s3.300): new VLAN device (/org/freedesktop/NetworkManager/Devices/5)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052602.1137] manager: NetworkManager state is now CONNECTED_GLOBAL
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052602.1589] modem-manager: ModemManager available
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052602.1652] manager: startup complete
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052607.8477] manager: (tun0): new Tun device (/org/freedesktop/NetworkManager/Devices/6)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052607.9229] devices added (path: /sys/devices/virtual/net/tun0, iface: tun0)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052607.9230] device added (path: /sys/devices/virtual/net/tun0, iface: tun0): no ifupdown configuration found.
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052607.9234] device (tun0): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052607.9311] keyfile: add connection in-memory (20ce3fb2-4bb9-4cfc-ab0e-c09b03c3c82d,"tun0")
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1890] device (tun0): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1916] device (tun0): Activation: starting connection 'tun0' (20ce3fb2-4bb9-4cfc-ab0e-c09b03c3c82d)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1934] devices added (path: /sys/devices/virtual/net/tun1, iface: tun1)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1934] device added (path: /sys/devices/virtual/net/tun1, iface: tun1): no ifupdown configuration found.
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1945] device (tun0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.1979] manager: (tun1): new Tun device (/org/freedesktop/NetworkManager/Devices/7)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2045] keyfile: add connection in-memory (36a1db01-85ab-4f1e-8181-2ace88956184,"tun1")
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2061] device (tun1): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2069] device (tun1): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2092] device (tun1): Activation: starting connection 'tun1' (36a1db01-85ab-4f1e-8181-2ace88956184)
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2107] device (tun0): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:09 maimbo NetworkManager[1015]: <info> [1529052608.2121] device (tun1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2130] device (tun0): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2132] device (tun0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2139] device (tun1): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2152] device (tun1): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2155] device (tun1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2161] device (tun0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:10 maimbo NetworkManager[1015]: <info> [1529052608.2169] device (tun0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:17 maimbo NetworkManager[1015]: <info> [1529052617.5681] device (tun0): Activation: successful, device activated.
Jun 15 20:50:17 maimbo NetworkManager[1015]: <info> [1529052617.5707] device (tun1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:17 maimbo NetworkManager[1015]: <info> [1529052617.5751] device (tun1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
Jun 15 20:50:17 maimbo NetworkManager[1015]: <info> [1529052617.8037] device (tun1): Activation: successful, device activated.
Jun 15 20:50:17 maimbo dbus-daemon[860]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.26' (uid=0 pid=1015 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Jun 15 20:50:17 maimbo nm-dispatcher: req:1 'up' [tun1]: new request (2 scripts)
Jun 15 20:50:17 maimbo nm-dispatcher: req:1 'up' [tun1]: start running ordered scripts...
Jun 15 20:50:18 maimbo nm-dispatcher[2572]: /etc/network/if-up.d/nick-pbr: About to acquire lock for interface: tun1
Jun 15 20:50:18 maimbo nm-dispatcher[2572]: /etc/network/if-up.d/nick-pbr: Acquired lock for interface: tun1
Jun 15 20:50:18 maimbo nm-dispatcher[2572]: /etc/network/if-up.d/nick-pbr: Interface has started: tun1
Jun 15 20:50:18 maimbo nm-dispatcher[2572]: /etc/network/if-up.d/nick-pbr: Adding routes for interface: tun1
Jun 15 20:50:18 maimbo nm-dispatcher[2572]: /etc/network/if-up.d/nick-pbr: Flushing cache
The key observation in the log is that we see the following line for
tun1, but no equivalent line for tun0:
Jun 15 20:50:17 maimbo nm-dispatcher: req:1 'up' [tun1]: start running
ordered scripts...
As a result of this issue I need to check my routing tables after
every reboot to see if anything is missing (and if so restart the
corresponding OpenVPN service).
Other info:
$ lsb_release -rd
Description: Ubuntu 18.04 LTS
Release: 18.04
$ apt-cache policy network-manager
network-manager:
Installed: 1.10.6-2ubuntu1
Candidate: 1.10.6-2ubuntu1
Version table:
*** 1.10.6-2ubuntu1 500
500 http://nz.archive.ubuntu.com/ubuntu bionic/main amd64 Packages
100 /var/lib/dpkg/status
To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/ifupdown/+bug/1777125/+subscriptions
More information about the foundations-bugs
mailing list