[Pkg-utopia-maintainers] Bug#863482: network-manager-openvpn: Openvpn autoconnect fails after returning from suspension.
Eamonn Collins
eamonncollins16 at yandex.com
Sat May 27 15:09:23 UTC 2017
Package: network-manager-openvpn
Version: 1.2.8-2
Severity: normal
Dear Maintainer,
*** Reporter, please consider answering these questions, where appropriate ***
* What led up to the situation?
1 - Install openvpn-network-manager-gnome on Debian Stretch Laptop.
2 - Add VPN servers in the GUI menu and test them.
3 - Open 'nm-connection-editor' and set up auto-connect.
4 - Close laptop lid, open laptop again.
5 - Observe the wifi and vpns attempts to connect to the web, it should
fail. You will notice you need to manually select the wifi
network from the gnome network manager before it connects.
* What was the outcome of this action?
Openvpn tunnel was not able to auto-connect after returning from
suspension.
* What outcome did you expect instead?
It should auto-connect easily after opening your laptop.
Here is a recording of the system logs during the event, edited for privacy:
/var/log/syslog
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.1294] policy:
auto-activating connection 'My-Wifi'
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.1307] device
(wlp9s0): Activation: starting connection 'My-Wifi'
(333ac53a-b217-46c2-9ad4-193ca45cf8cb)
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.1309] device
(wlp9s0): state change: disconnected -> prepare (reason 'none') [30 40 0]
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.1412] device
(wlp9s0): set-hw-addr: reset MAC address to 10:08:B1:B3:A7:25 (preserve)
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7382] device
(wlp9s0): supplicant interface state: disconnected -> disabled
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7386] device
(wlp9s0): state change: prepare -> config (reason 'none') [40 50 0]
May 5 08:20:30 Hostname kernel: [ 8527.354441] IPv6: ADDRCONF(NETDEV_UP):
wlp9s0: link is not ready
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7388] device
(wlp9s0): Activation: (wifi) access point 'My-Wifi' has security, but secrets
are required.
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7389] device
(wlp9s0): state change: config -> need-auth (reason 'none') [50 60 0]
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7577] device
(wlp9s0): state change: need-auth -> prepare (reason 'none') [60 40 0]
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7580] device
(wlp9s0): state change: prepare -> config (reason 'none') [40 50 0]
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7582] device
(wlp9s0): Activation: (wifi) connection 'My-Wifi' has security, and secrets
exist. No new secrets needed.
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7583] Config:
added 'ssid' value 'My-Wifi'
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7583] Config:
added 'scan_ssid' value '1'
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7583] Config:
added 'key_mgmt' value 'WPA-PSK'
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7583] Config:
added 'psk' value '<hidden>'
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7798] device
(wlp9s0): supplicant interface state: disabled -> disconnected
May 5 08:20:30 Hostname wpa_supplicant[862]: wlp9s0: SME: Trying to
authenticate with 30:a1:c2:d4:ba:b2 (SSID='My-Wifi' freq=2417 MHz)
May 5 08:20:30 Hostname kernel: [ 8527.404924] wlp9s0: authenticate with
30:a1:c2:d4:ba:b2
May 5 08:20:30 Hostname kernel: [ 8527.416086] wlp9s0: send auth to
30:a1:c2:d4:ba:b2 (try 1/3)
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.7992] device
(wlp9s0): supplicant interface state: disconnected -> authenticating
May 5 08:20:30 Hostname wpa_supplicant[862]: wlp9s0: Trying to associate with
30:a1:c2:d4:ba:b2 (SSID='My-Wifi' freq=2417 MHz)
May 5 08:20:30 Hostname kernel: [ 8527.418625] wlp9s0: authenticated
May 5 08:20:30 Hostname kernel: [ 8527.420808] wlp9s0: associate with
30:a1:c2:d4:ba:b2 (try 1/3)
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.8066] device
(wlp9s0): supplicant interface state: authenticating -> associating
May 5 08:20:30 Hostname wpa_supplicant[862]: wlp9s0: Associated with
30:a1:c2:d4:ba:b2
May 5 08:20:30 Hostname kernel: [ 8527.425258] wlp9s0: RX AssocResp from
30:a1:c2:d4:ba:b2 (capab=0x411 status=0 aid=8)
May 5 08:20:30 Hostname kernel: [ 8527.425413] wlp9s0: associated
May 5 08:20:30 Hostname kernel: [ 8527.425453] IPv6: ADDRCONF(NETDEV_CHANGE):
wlp9s0: link becomes ready
May 5 08:20:30 Hostname wpa_supplicant[862]: wlp9s0: CTRL-EVENT-REGDOM-CHANGE
init=COUNTRY_IE type=COUNTRY alpha2=US
May 5 08:20:30 Hostname NetworkManager[743]: <info> [1493943630.8134] device
(wlp9s0): supplicant interface state: associating -> associated
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.2874] device
(wlp9s0): supplicant interface state: associated -> 4-way handshake
May 5 08:20:31 Hostname wpa_supplicant[862]: wlp9s0: WPA: Key negotiation
completed with 30:a1:c2:d4:ba:b2 [PTK=CCMP GTK=CCMP]
May 5 08:20:31 Hostname wpa_supplicant[862]: wlp9s0: CTRL-EVENT-CONNECTED -
Connection to 30:a1:c2:d4:ba:b2 completed [id=0 id_str=]
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.2977] device
(wlp9s0): supplicant interface state: 4-way handshake -> completed
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.2978] device
(wlp9s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.
Connected to wireless network 'My-Wifi'.
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.2979] device
(wlp9s0): state change: config -> ip-config (reason 'none') [50 70 0]
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.2983] dhcp4
(wlp9s0): activation: beginning transaction (timeout in 45 seconds)
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.2999] dhcp4
(wlp9s0): dhclient started with pid 7888
May 5 08:20:31 Hostname dhclient[7888]: DHCPREQUEST of 192.168.1.106 on wlp9s0
to 255.255.255.255 port 67
May 5 08:20:31 Hostname dhclient[7888]: DHCPACK of 192.168.1.106 from
192.168.1.1
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.3861] dhcp4
(wlp9s0): address 192.168.1.106
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.3861] dhcp4
(wlp9s0): plen 24 (255.255.255.0)
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.3861] dhcp4
(wlp9s0): gateway 192.168.1.1
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.3861] dhcp4
(wlp9s0): server identifier 192.168.1.1
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.3861] dhcp4
(wlp9s0): lease time 86400
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.3861] dhcp4
(wlp9s0): nameserver '192.168.1.1'
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.3862] dhcp4
(wlp9s0): state changed unknown -> bound
May 5 08:20:31 Hostname avahi-daemon[693]: Joining mDNS multicast group on
interface wlp9s0.IPv4 with address 192.168.1.106.
May 5 08:20:31 Hostname avahi-daemon[693]: New relevant interface wlp9s0.IPv4
for mDNS.
May 5 08:20:31 Hostname avahi-daemon[693]: Registering new address record for
192.168.1.106 on wlp9s0.IPv4.
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.3895] device
(wlp9s0): state change: ip-config -> ip-check (reason 'none') [70 80 0]
May 5 08:20:31 Hostname dhclient[7888]: bound to 192.168.1.106 -- renewal in
36944 seconds.
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.3915] device
(wlp9s0): state change: ip-check -> secondaries (reason 'none') [80 90 0]
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.3917] policy:
set 'My-Wifi' (wlp9s0) as default for IPv4 routing and DNS
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.4071] vpn-
connection[0x55672790c740,47015659-2d54-4510-b706-a2ed8a53d7b9,"Openvpn-
Server",0]: Started the VPN service, PID 7899
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.4148] vpn-
connection[0x55672790c740,47015659-2d54-4510-b706-a2ed8a53d7b9,"Openvpn-
Server",0]: Saw the service appear; activating connection
May 5 08:20:31 Hostname gnome-shell[1153]: JS LOG: Invalid connection type:
vpn
May 5 08:20:31 Hostname gnome-shell[1153]: JS LOG: Invalid connection type:
vpn
May 5 08:20:31 Hostname NetworkManager[743]: <error> [1493943631.4804] vpn-
connection[0x55672790c740,47015659-2d54-4510-b706-a2ed8a53d7b9,"Openvpn-
Server",0]: Failed to request VPN secrets #3: No agents were available for this
request.
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.4804] device
(wlp9s0): state change: secondaries -> failed (reason 'secondary-connection-
failed') [90 120 54]
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.4806] policy:
disabling autoconnect for connection 'My-Wifi'.
May 5 08:20:31 Hostname NetworkManager[743]: <warn> [1493943631.4814] device
(wlp9s0): Activation: failed for connection 'My-Wifi'
May 5 08:20:31 Hostname gnome-shell[823]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.5014] vpn-
connection[0x55672790c740,47015659-2d54-4510-b706-a2ed8a53d7b9,"Openvpn-
Server",0]: VPN service disappeared
May 5 08:20:31 Hostname gnome-shell[823]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.5034] device
(wlp9s0): state change: failed -> disconnected (reason 'none') [120 30 0]
May 5 08:20:31 Hostname gnome-shell[823]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[1153]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[1153]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[1153]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[1153]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[1153]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[1153]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[1153]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[1153]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[1153]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[823]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[823]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[823]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[823]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[823]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[823]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname gnome-shell[823]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.5386] dhcp4
(wlp9s0): canceled DHCP transaction, DHCP client pid 7888
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.5386] dhcp4
(wlp9s0): state changed bound -> done
May 5 08:20:31 Hostname avahi-daemon[693]: Withdrawing address record for
192.168.1.106 on wlp9s0.
May 5 08:20:31 Hostname avahi-daemon[693]: Leaving mDNS multicast group on
interface wlp9s0.IPv4 with address 192.168.1.106.
May 5 08:20:31 Hostname kernel: [ 8528.158463] wlp9s0: deauthenticating from
30:a1:c2:d4:ba:b2 by local choice (Reason: 3=DEAUTH_LEAVING)
May 5 08:20:31 Hostname wpa_supplicant[862]: wlp9s0: CTRL-EVENT-DISCONNECTED
bssid=30:a1:c2:d4:ba:b2 reason=3 locally_generated=1
May 5 08:20:31 Hostname avahi-daemon[693]: Interface wlp9s0.IPv4 no longer
relevant for mDNS.
May 5 08:20:31 Hostname NetworkManager[743]: <info> [1493943631.5581] device
(wlp9s0): set-hw-addr: set MAC address to 5A:C7:D5:E2:AE:65 (scanning)
May 5 08:20:31 Hostname gnome-settings-daemon.desktop[1240]: loadInfo is null
for channel http://127.0.0.1:12546/
May 5 08:20:32 Hostname kernel: [ 8528.871747] IPv6: ADDRCONF(NETDEV_UP):
wlp9s0: link is not ready
May 5 08:20:32 Hostname gnome-shell[823]: JS LOG: An active wireless
connection, in infrastructure mode, involves no access point?
May 5 08:20:32 Hostname NetworkManager[743]: <warn> [1493943632.2628] sup-
iface[0x5567279d6bf0,wlp9s0]: connection disconnected (reason -3)
May 5 08:20:32 Hostname NetworkManager[743]: <info> [1493943632.2629] device
(wlp9s0): supplicant interface state: completed -> disconnected
May 5 08:20:32 Hostname NetworkManager[743]: <info> [1493943632.2630] device
(wlp9s0): supplicant interface state: disconnected -> disabled
May 5 08:20:32 Hostname wpa_supplicant[862]: wlp9s0: CTRL-EVENT-REGDOM-CHANGE
init=CORE type=WORLD
May 5 08:20:32 Hostname NetworkManager[743]: <info> [1493943632.2966] device
(wlp9s0): supplicant interface state: disabled -> disconnected
-- System Information:
Debian Release: 9.0
APT prefers testing
APT policy: (500, 'testing')
Architecture: amd64
(x86_64)
Kernel: Linux 4.9.0-3-amd64 (SMP w/4 CPU cores)
Locale: LANG=en_AU.UTF-8, LC_CTYPE=en_AU.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
Init: systemd (via /run/systemd/system)
Versions of packages network-manager-openvpn depends on:
ii adduser 3.115
ii libc6 2.24-10
ii libglib2.0-0 2.50.3-2
ii libnm0 1.6.2-3
ii network-manager 1.6.2-3
ii openvpn 2.4.0-5
network-manager-openvpn recommends no packages.
network-manager-openvpn suggests no packages.
-- no debconf information
More information about the Pkg-utopia-maintainers
mailing list