Bug 1047462

Summary:	NetworkManager crash after connecting to openconnect vpn
Product:	[Fedora] Fedora	Reporter:	Romain Vrignaud <rvrignaud>
Component:	NetworkManager	Assignee:	Dan Williams <dcbw>
Status:	CLOSED CURRENTRELEASE	QA Contact:	Fedora Extras Quality Assurance <extras-qa>
Severity:	unspecified	Docs Contact:
Priority:	unspecified
Version:	20	CC:	danw, dcbw, dwmw2, jeff, jklimes, komsas, psimerda, rvrignaud
Target Milestone:	---
Target Release:	---
Hardware:	Unspecified
OS:	Unspecified
Whiteboard:
Fixed In Version:		Doc Type:	Bug Fix
Doc Text:		Story Points:	---
Clone Of:		Environment:
Last Closed:	2014-12-09 12:07:26 UTC	Type:	Bug
Regression:	---	Mount Type:	---
Documentation:	---	CRM:
Verified Versions:		Category:	---
oVirt Team:	---	RHEL 7.3 requirements from Atomic Host:
Cloudforms Team:	---	Target Upstream Version:
Embargoed:

Description Romain Vrignaud 2013-12-31 09:01:04 UTC

Description of problem:
When connecting to an openconnect vpn, NetworkManager crashes after bringing up the vpn0 interface.
Same configuration on Fedora19 was working correctly.

Packages concerned:
NetworkManager-0.9.9.0-22.git20131003.fc20.x86_64
NetworkManager-0.9.9.0-22.git20131003.fc20.x86_64


How reproducible:
NetworkManager crashes every time. If not using VPN, NetworkManager is stable.


Steps to Reproduce:
1. Create a VPN openconnect with NetworkManager GUI.
2. Connect to it from gnome applet
3. See journalctl logs

Actual results:
Unit NetworkManager.service enter failed state

In journalctl:
Dec 31 09:44:48 NetworkManager[19975]: <info> Starting VPN service 'openconnect'...
Dec 31 09:44:48 NetworkManager[19975]: <info> VPN service 'openconnect' started (org.freedesktop.NetworkManager.openconnect), PID 20070
Dec 31 09:44:48 NetworkManager[19975]: <info> VPN service 'openconnect' appeared; activating connections
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: property 'cookie-flags' unknown
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: property 'certsigs-flags' unknown
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: property 'autoconnect-flags' unknown
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: property 'gateway-flags' unknown
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: property 'gwcert-flags' unknown
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: property 'xmlconfig-flags' unknown
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: property 'lasthost-flags' unknown
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: property 'certsigs' unknown
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: property 'save_passwords' unknown
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: property 'autoconnect' unknown
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: property 'lasthost' unknown
Dec 31 09:44:50 NetworkManager[19975]: ** (nm-openconnect-service:20070): WARNING **: Created tundev vpn0
Dec 31 09:44:50 NetworkManager[19975]: <info> (vpn0): carrier is OFF
Dec 31 09:44:50 NetworkManager[19975]: <info> (vpn0): new Tun device (driver: 'unknown' ifindex: 13)
Dec 31 09:44:50 NetworkManager[19975]: <info> (vpn0): exported as /org/freedesktop/NetworkManager/Devices/3
Dec 31 09:44:50 NetworkManager[19975]: <info> (vpn0): No existing connection detected.
Dec 31 09:44:50 NetworkManager[19975]: ** Message: openconnect started with pid 20081
Dec 31 09:44:50 NetworkManager[19975]: <info> VPN plugin state changed: starting (3)
Dec 31 09:44:50 NetworkManager[19975]: <info> VPN connection 'xxx' (Connect) reply received.
Dec 31 09:44:50 openconnect[20081]: Attempting to connect to server xxx.xxx.xxx.xxx:443
Dec 31 09:44:50 openconnect[20081]: Négociation SSL avec xxx.xxx.xxx.xxx
Dec 31 09:44:50 openconnect[20081]: Connected to HTTPS on xxx.xxx.xxx.xxx
Dec 31 09:44:50 openconnect[20081]: Got CONNECT response: HTTP/1.1 200 OK
Dec 31 09:44:50 openconnect[20081]: CSTP connected. DPD 30, Keepalive 20
Dec 31 09:44:50 NetworkManager[19975]: <info> VPN connection 'xxx' (IP Config Get) reply received.
Dec 31 09:44:50 NetworkManager[19975]: <info> VPN connection 'xxx' (IP4 Config Get) reply received.
Dec 31 09:44:50 NetworkManager[19975]: <info> VPN connection 'xxx' (IP6 Config Get) reply received.
Dec 31 09:44:50 NetworkManager[19975]: <info> VPN Gateway: xxx.xxx.xxx.xxx
Dec 31 09:44:50 NetworkManager[19975]: <info> Tunnel Device: vpn0
Dec 31 09:44:50 NetworkManager[19975]: <info> IPv4 configuration:
Dec 31 09:44:50 NetworkManager[19975]: <info>   Internal Address: xxx.xxx.xxx.xxx
Dec 31 09:44:50 NetworkManager[19975]: <info>   Internal Prefix: 24
Dec 31 09:44:50 NetworkManager[19975]: <info>   Internal Point-to-Point Address: xxx.xxx.xxx.xxx
Dec 31 09:44:50 NetworkManager[19975]: <info>   Maximum Segment Size (MSS): 0
Dec 31 09:44:50 NetworkManager[19975]: <info>   Static Route: xxx.xxx.xxx.xxx/32   Next Hop: 0.0.0.0
Dec 31 09:44:50 NetworkManager[19975]: <info>   Static Route: xxx.xxx.xxx.xxx2/32   Next Hop: 0.0.0.0
Dec 31 09:44:50 NetworkManager[19975]: <info>   Static Route: xxx.xxx.xxx.xxx/32   Next Hop: 0.0.0.0
Dec 31 09:44:50 NetworkManager[19975]: <info>   Static Route: xxx.xxx.xxx.xxx/22   Next Hop: 0.0.0.0
Dec 31 09:44:50 openconnect[20081]: Unknown DTLS parameters for requested CipherSuite 'DES-CBC-SHA'
Dec 31 09:44:50 NetworkManager[19975]: SIOCSIFMTU: Opération non permise
Dec 31 09:44:50 NetworkManager[19975]: Set up DTLS failed; using SSL instead
Dec 31 09:44:50 openconnect[20081]: Connected vpn0 as xxx.xxx.xxx.xxx + xxx.xxx.xxx.xxx, using SSL
Dec 31 09:44:50 NetworkManager[19975]: <info>   Static Route: xxx.xxx.xxx.xxx/8   Next Hop: 0.0.0.0
Dec 31 09:44:50 NetworkManager[19975]: <info>   Forbid Default Route: yes
Dec 31 09:44:50 NetworkManager[19975]: <info>   Internal DNS: xxx.xxx.xxx.xxx
Dec 31 09:44:50 NetworkManager[19975]: <info>   Internal DNS: xxx.xxx.xxx.xxx
Dec 31 09:44:50 NetworkManager[19975]: <info>   DNS Domain: 'vpn.example.com'
Dec 31 09:44:50 NetworkManager[19975]: <info> IPv6 configuration:
Dec 31 09:44:50 NetworkManager[19975]: <info>   Internal Address: xxx.xxx.xxx.xxx
Dec 31 09:44:50 NetworkManager[19975]: <info>   Internal Prefix: 64
Dec 31 09:44:50 NetworkManager[19975]: <info>   Internal Point-to-Point Address: xxx.xxx.xxx.xxx
Dec 31 09:44:50 NetworkManager[19975]: <info>   Maximum Segment Size (MSS): 0
Dec 31 09:44:50 NetworkManager[19975]: <info>   Forbid Default Route: no
Dec 31 09:44:50 NetworkManager[19975]: <info>   DNS Domain: 'vpn.example.com'
Dec 31 09:44:50 NetworkManager[19975]: <info> Login Banner:
Dec 31 09:44:50 NetworkManager[19975]: <info> -----------------------------------------
Dec 31 09:44:50 NetworkManager[19975]: <info> xxx
Dec 31 09:44:50 NetworkManager[19975]: <info> -----------------------------------------
Dec 31 09:44:50 NetworkManager[19975]: <info> (vpn0): link connected
Dec 31 09:44:50 NetworkManager[19975]: **
Dec 31 09:44:50 NetworkManager[19975]: ERROR:platform/nm-linux-platform.c:2244:build_rtnl_addr: assertion failed: (!nle)
Dec 31 09:44:50 abrt-hook-ccpp[20087]: Saved core dump of pid 19975 (/usr/sbin/NetworkManager) to /var/tmp/abrt/ccpp-2013-12-31-09:44:50-19975 (36286464 bytes)
Dec 31 09:44:50 systemd[1]: NetworkManager.service: main process exited, code=dumped, status=6/ABRT
Dec 31 09:44:50 systemd[1]: Unit NetworkManager.service entered failed state.


Expected results:
NetworkManager set up the VPN properly and do not crash.

Additional info:

Comment 1 Paulius Sladkevičius 2014-01-08 12:10:22 UTC

I can confirm that I've same issue on my side. Fedora 20.

Packages concerned:
NetworkManager.x86_64          1:0.9.9.0-22.git20131003.fc20
openconnect.x86_64             5.01-4.fc20

In journalctl:

Jan 08 11:39:28 boruze NetworkManager[906]: ** (nm-openconnect-service:2753): WARNING **: property 'cookie-flags' unknown
Jan 08 11:39:28 boruze NetworkManager[906]: ** (nm-openconnect-service:2753): WARNING **: property 'certsigs-flags' unknown
Jan 08 11:39:28 boruze NetworkManager[906]: ** (nm-openconnect-service:2753): WARNING **: property 'lasthost-flags' unknown
Jan 08 11:39:28 boruze NetworkManager[906]: ** (nm-openconnect-service:2753): WARNING **: property 'autoconnect-flags' unknown
Jan 08 11:39:28 boruze NetworkManager[906]: ** (nm-openconnect-service:2753): WARNING **: property 'gateway-flags' unknown
Jan 08 11:39:28 boruze NetworkManager[906]: ** (nm-openconnect-service:2753): WARNING **: property 'gwcert-flags' unknown
Jan 08 11:39:28 boruze NetworkManager[906]: ** (nm-openconnect-service:2753): WARNING **: property 'xmlconfig-flags' unknown
Jan 08 11:39:28 boruze NetworkManager[906]: ** (nm-openconnect-service:2753): WARNING **: property 'lasthost' unknown
Jan 08 11:39:28 boruze NetworkManager[906]: ** (nm-openconnect-service:2753): WARNING **: property 'save_passwords' unknown
Jan 08 11:39:28 boruze NetworkManager[906]: ** (nm-openconnect-service:2753): WARNING **: Created tundev vpn0
Jan 08 11:39:28 boruze NetworkManager[906]: <info> (vpn0): carrier is OFF
Jan 08 11:39:28 boruze NetworkManager[906]: <info> (vpn0): new Tun device (driver: 'unknown' ifindex: 4)
Jan 08 11:39:28 boruze NetworkManager[906]: <info> (vpn0): exported as /org/freedesktop/NetworkManager/Devices/4
Jan 08 11:39:28 boruze NetworkManager[906]: <info> (vpn0): No existing connection detected.
Jan 08 11:39:28 boruze NetworkManager[906]: <info> VPN plugin state changed: starting (3)
Jan 08 11:39:28 boruze NetworkManager[906]: ** Message: openconnect started with pid 2789
Jan 08 11:39:28 boruze NetworkManager[906]: <info> VPN connection 'VPN Net' (Connect) reply received.
Jan 08 11:39:28 boruze openconnect[2789]: Attempting to connect to server xxx.xxx.xxx.xxx:443
Jan 08 11:39:28 boruze openconnect[2789]: SSL negotiation with domain.com
Jan 08 11:39:28 boruze openconnect[2789]: Connected to HTTPS on domain.com
Jan 08 11:39:28 boruze openconnect[2789]: Got CONNECT response: HTTP/1.1 200 OK
Jan 08 11:39:28 boruze openconnect[2789]: CSTP connected. DPD 0, Keepalive 20
Jan 08 11:39:28 boruze NetworkManager[906]: <info> VPN connection 'VPN Net' (IP Config Get) reply received.
Jan 08 11:39:28 boruze NetworkManager[906]: SIOCSIFMTU: Operation not permitted
Jan 08 11:39:28 boruze openconnect[2789]: Connected vpn0 as xxx.xxx.xxx.xxx + 2001:4ca0:2fff:4::9a, using SSL
Jan 08 11:39:28 boruze NetworkManager[906]: <info> VPN connection 'VPN Net' (IP4 Config Get) reply received.
Jan 08 11:39:28 boruze NetworkManager[906]: <info> VPN connection 'VPN Net' (IP6 Config Get) reply received.
Jan 08 11:39:28 boruze NetworkManager[906]: <info> VPN Gateway: xxx.xxx.xxx.xxx
Jan 08 11:39:28 boruze NetworkManager[906]: <info> Tunnel Device: vpn0
Jan 08 11:39:28 boruze NetworkManager[906]: <info> IPv4 configuration:
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Internal Address: xxx.xxx.xxx.xxx
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Internal Prefix: 24
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Internal Point-to-Point Address: xxx.xxx.xxx.xxx
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Maximum Segment Size (MSS): 0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/16   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/8   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/24   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/24   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/24   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/24   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/16   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/15   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/16   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/16   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/16   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Static Route: xxx.xxx.xxx.xxx/16   Next Hop: 0.0.0.0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Forbid Default Route: yes
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Internal DNS: xxx.xxx.xxx.xxx
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Internal DNS: xxx.xxx.xxx.xxx
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   DNS Domain: 'domain.com'
Jan 08 11:39:28 boruze NetworkManager[906]: <info> IPv6 configuration:
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Internal Address: 2001:4ca0:2fff:4::9a
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Internal Prefix: 64
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Internal Point-to-Point Address: 2001:4ca0:2fff:4::9a
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Maximum Segment Size (MSS): 0
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   Forbid Default Route: yes
Jan 08 11:39:28 boruze NetworkManager[906]: <info>   DNS Domain: 'domain.com'
Jan 08 11:39:28 boruze NetworkManager[906]: <info> (vpn0): link connected
Jan 08 11:39:28 boruze NetworkManager[906]: **
Jan 08 11:39:28 boruze NetworkManager[906]: ERROR:platform/nm-linux-platform.c:2244:build_rtnl_addr: assertion failed: (!nle)
Jan 08 11:39:29 boruze abrt-hook-ccpp[2794]: Saved core dump of pid 906 (/usr/sbin/NetworkManager) to /var/tmp/abrt/ccpp-2014-01-08-11:39:28-906 (36327424 bytes)
Jan 08 11:39:29 boruze gnome-session[1148]: (gnome-shell:1574): Gjs-WARNING **: JS ERROR: TypeError: this._connectionItems.get(...) is null
Jan 08 11:39:29 boruze gnome-session[1148]: NMConnectionSection<.removeConnection@/usr/share/gnome-shell/js/ui/status/network.js:274
Jan 08 11:39:29 boruze gnome-session[1148]: wrapper@/usr/share/gjs-1.0/lang.js:213
Jan 08 11:39:29 boruze gnome-session[1148]: NMApplet<._connectionRemoved@/usr/share/gnome-shell/js/ui/status/network.js:1607
Jan 08 11:39:29 boruze gnome-session[1148]: wrapper@/usr/share/gjs-1.0/lang.js:213

Comment 2 Paulius Sladkevičius 2014-01-08 14:47:32 UTC

I can confirm that with older packages it works fine:

NetworkManager.x86_64          1:0.9.9.0-19.git20131003.fc20
NetworkManager-glib.x86_64     1:0.9.9.0-19.git20131003.fc20

Comment 3 David Woodhouse 2014-07-08 16:21:03 UTC

Reassigning to NetworkManager.

Comment 4 David Woodhouse 2014-12-08 16:22:32 UTC

Is this still happening?

Comment 5 Romain Vrignaud 2014-12-08 16:25:58 UTC

I don't have problem anymore with NetworkManager-0.9.9.0-46.git20131003.fc20.x86_64 package.

Comment 6 Jirka Klimes 2014-12-09 12:07:26 UTC

Closing per comment #5.