Random Dropped Connections In OpenVPN Gateway Group



  • I am having an issue with a gateway group that I have created where members of the group are randomly disconnecting from different openvpn servers. I'm on 2.4.4-RELEASE-p3.

    The gateway group has 3 different interfaces. All 3 of the interfaces are openvpn connections that connect to 3 different geographically located openvpn servers. All the settings in the interface settings are blank or unckecked except for enabling the interface and the interface name.

    I have checked the configuration for the openvpn client settings and have confirmed that they are configured exactly as recommended by the vpn provider as per the instructions here: https://www.ivpn.net/setup/router-pfsense.html

    I can connect to the openvpn without issue. But I am getting random disconnects 3-6 total times per day. The disconnects can happen on any of the 3 interfaces in the gateway group and I don't think I've noticed more than 1 interface being down at the same time. I'm not able to figure out what might be misconfigured or how to resolve it.

    I should note that I also have a openvpn server running on the pFsense box that is connected to an offsite office. This openvpn is completely stable.

    Here are some System Logs from when one of the interfaces went down:

    Jun 16 08:40:12 	php_pfb 		[pfBlockerNG] filterlog daemon started
    Jun 16 08:40:12 	php_pfb 		[pfBlockerNG] filterlog daemon started
    Jun 16 08:40:12 	php 		[pfBlockerNG] DNSBL parser daemon started
    Jun 16 08:40:12 	php-fpm 		[pfBlockerNG] Restarting firewall filter daemon
    Jun 16 08:40:12 	check_reload_status 		Reloading filter
    Jun 16 08:40:12 	check_reload_status 		Syncing firewall
    Jun 16 08:40:10 	php-fpm 		/rc.start_packages: Restarting/Starting all packages.
    Jun 16 08:40:09 	check_reload_status 		Starting packages
    Jun 16 08:40:09 	php-fpm 		/rc.newwanip: pfSense package system has detected an IP change or dynamic WAN reconnection - 10.12.16.7 -> 10.12.48.15 - Restarting packages.
    Jun 16 08:40:07 	php-fpm 		/rc.newwanip: Creating rrd update script
    Jun 16 08:40:07 	php-fpm 		/rc.newwanip: RRD create failed exited with 1, the error is: ERROR: you must define at least one Data Source
    Jun 16 08:40:07 	php-fpm 		/rc.newwanip: RRD create failed exited with 1, the error is: ERROR: you must define at least one Data Source
    Jun 16 08:40:07 	php-fpm 		/rc.newwanip: RRD create failed exited with 1, the error is: ERROR: you must define at least one Data Source
    Jun 16 08:40:07 	php-fpm 		/rc.newwanip: RRD create failed exited with 1, the error is: ERROR: you must define at least one Data Source
    Jun 16 08:40:07 	php-fpm 		/rc.newwanip: RRD create failed exited with 1, the error is: ERROR: you must define at least one Data Source
    Jun 16 08:40:07 	php-fpm 		/rc.newwanip: RRD create failed exited with 1, the error is: ERROR: you must define at least one Data Source
    Jun 16 08:40:07 	php-fpm 		/rc.newwanip: RRD create failed exited with 1, the error is: ERROR: you must define at least one Data Source
    Jun 16 08:40:07 	php-fpm 		/rc.newwanip: RRD create failed exited with 1, the error is: ERROR: you must define at least one Data Source
    Jun 16 08:40:04 	php-fpm 		/rc.newwanip: 33010MONITOR: IVPN_NY1 is available now, adding to routing group IVPN_Group 10.12.48.1|10.12.48.15|IVPN_NY1|8.494ms|0.774ms|0.0%|none
    Jun 16 08:39:57 	php-fpm 		/rc.newwanip: Gateway, none 'available' for inet6, use the first one configured. ''
    Jun 16 08:39:52 	php-fpm 		/rc.newwanip: IP Address has changed, killing states on former IP Address 10.12.16.7.
    Jun 16 08:39:52 	php-fpm 		/rc.newwanip: rc.newwanip: on (IP address: 10.12.48.15) (interface: IVPN_NY1[opt7]) (real interface: ovpnc4).
    Jun 16 08:39:52 	php-fpm 		/rc.newwanip: rc.newwanip: Info: starting on ovpnc4.
    Jun 16 08:39:51 	check_reload_status 		rc.newwanip starting ovpnc4
    Jun 16 08:39:51 	kernel 		ovpnc4: link state changed to UP
    Jun 16 08:39:50 	check_reload_status 		Reloading filter
    Jun 16 08:39:50 	kernel 		ovpnc4: link state changed to DOWN
    Jun 16 08:38:55 	php-fpm 		/rc.dyndns.update: MONITOR: IVPN_NY1 is down, omitting from routing group IVPN_Group 10.12.16.1|10.12.16.7|IVPN_NY1|11.917ms|5.887ms|23%|down
    Jun 16 08:38:55 	php-fpm 		/rc.openvpn: OpenVPN: One or more OpenVPN tunnel endpoints may have changed its IP. Reloading endpoints that may use IVPN_NY1.
    Jun 16 08:38:55 	php-fpm 		/rc.openvpn: Gateway, none 'available' for inet6, use the first one configured. ''
    Jun 16 08:38:54 	check_reload_status 		Reloading filter
    Jun 16 08:38:54 	check_reload_status 		Restarting OpenVPN tunnels/interfaces
    Jun 16 08:38:54 	check_reload_status 		Restarting ipsec tunnels
    Jun 16 08:38:54 	check_reload_status 		updating dyndns IVPN_NY1
    Jun 16 08:38:54 	rc.gateway_alarm 	5655 	>>> Gateway alarm: IVPN_NY1 (Addr:10.12.16.1 Alarm:1 RTT:11.853ms RTTsd:5.801ms Loss:21%)
    Jun 16 08:12:56 	php 		[pfBlockerNG] No changes to Firewall rules, skipping Filter Reload 
    

    And here are the OpenVPN logs from the same timeframe:

    Jun 16 08:39:51 	openvpn 	27878 	Initialization Sequence Completed
    Jun 16 08:39:51 	openvpn 	27878 	/sbin/route add -net 128.0.0.0 10.12.48.1 128.0.0.0
    Jun 16 08:39:51 	openvpn 	27878 	/sbin/route add -net 0.0.0.0 10.12.48.1 128.0.0.0
    Jun 16 08:39:51 	openvpn 	27878 	/sbin/route add -net 64.120.44.114 96.244.82.1 255.255.255.255
    Jun 16 08:39:51 	openvpn 	27878 	/usr/local/sbin/ovpn-linkup ovpnc4 1500 1553 10.12.48.15 255.255.252.0 init
    Jun 16 08:39:51 	openvpn 	27878 	/sbin/route add -net 10.12.48.0 10.12.48.1 255.255.252.0
    Jun 16 08:39:51 	openvpn 	27878 	/sbin/ifconfig ovpnc4 10.12.48.15 10.12.48.1 mtu 1500 netmask 255.255.252.0 up
    Jun 16 08:39:51 	openvpn 	27878 	do_ifconfig, tt->did_ifconfig_ipv6_setup=0
    Jun 16 08:39:51 	openvpn 	27878 	TUN/TAP device /dev/tun4 opened
    Jun 16 08:39:51 	openvpn 	27878 	TUN/TAP device ovpnc4 exists previously, keep at program end
    Jun 16 08:39:51 	openvpn 	27878 	ROUTE_GATEWAY 96.244.82.1/255.255.255.0 IFACE=em0 HWADDR=00:1c:c4:47:6d:1d
    Jun 16 08:39:50 	openvpn 	27878 	/usr/local/sbin/ovpn-linkdown ovpnc4 1500 1553 10.12.16.7 255.255.252.0 init
    Jun 16 08:39:50 	openvpn 	27878 	Closing TUN/TAP interface
    Jun 16 08:39:50 	openvpn 	27878 	/sbin/route delete -net 128.0.0.0 10.12.16.1 128.0.0.0
    Jun 16 08:39:50 	openvpn 	27878 	/sbin/route delete -net 0.0.0.0 10.12.16.1 128.0.0.0
    Jun 16 08:39:50 	openvpn 	27878 	/sbin/route delete -net 64.120.44.114 96.244.82.1 255.255.255.255
    Jun 16 08:39:50 	openvpn 	27878 	NOTE: Pulled options changed on restart, will need to close and reopen TUN/TAP device.
    Jun 16 08:39:50 	openvpn 	27878 	Preserving previous TUN/TAP instance: ovpnc4
    Jun 16 08:39:50 	openvpn 	27878 	Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
    Jun 16 08:39:50 	openvpn 	27878 	Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
    Jun 16 08:39:50 	openvpn 	27878 	OPTIONS IMPORT: data channel crypto options modified
    Jun 16 08:39:50 	openvpn 	27878 	OPTIONS IMPORT: adjusting link_mtu to 1625
    Jun 16 08:39:50 	openvpn 	27878 	OPTIONS IMPORT: peer-id set
    Jun 16 08:39:50 	openvpn 	27878 	OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
    Jun 16 08:39:50 	openvpn 	27878 	OPTIONS IMPORT: route-related options modified
    Jun 16 08:39:50 	openvpn 	27878 	OPTIONS IMPORT: route options modified
    Jun 16 08:39:50 	openvpn 	27878 	OPTIONS IMPORT: --ifconfig/up options modified
    Jun 16 08:39:50 	openvpn 	27878 	OPTIONS IMPORT: compression parms modified
    Jun 16 08:39:50 	openvpn 	27878 	OPTIONS IMPORT: explicit notify parm(s) modified
    Jun 16 08:39:50 	openvpn 	27878 	OPTIONS IMPORT: timers and/or timeouts modified
    Jun 16 08:39:50 	openvpn 	27878 	PUSH: Received control message: 'PUSH_REPLY,redirect-gateway def1,explicit-exit-notify 3,comp-lzo no,route-gateway 10.12.48.1,topology subnet,ping 10,ping-restart 60,dhcp-option DNS 10.12.48.1,ifconfig 10.12.48.15 255.255.252.0,peer-id 13,cipher AES-256-GCM'
    Jun 16 08:39:50 	openvpn 	27878 	SENT CONTROL [us-ny1.gw.ivpn.net]: 'PUSH_REQUEST' (status=1)
    Jun 16 08:39:49 	openvpn 	27878 	[us-ny1.gw.ivpn.net] Peer Connection Initiated with [AF_INET]64.120.44.114:2050
    Jun 16 08:39:49 	openvpn 	27878 	Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-RSA-AES256-GCM-SHA384, 4096 bit RSA
    Jun 16 08:39:49 	openvpn 	27878 	WARNING: 'auth' is used inconsistently, local='auth [null-digest]', remote='auth SHA1'
    Jun 16 08:39:49 	openvpn 	27878 	WARNING: 'cipher' is used inconsistently, local='cipher AES-256-GCM', remote='cipher AES-256-CBC'
    Jun 16 08:39:49 	openvpn 	27878 	WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1550', remote='link-mtu 1558'
    Jun 16 08:39:49 	openvpn 	27878 	VERIFY OK: depth=0, C=CH, ST=Zurich, L=Zurich, O=IVPN.net, OU=IVPN, CN=us-ny1.gw.ivpn.net, emailAddress=support@ivpn.net
    Jun 16 08:39:49 	openvpn 	27878 	VERIFY OK: depth=1, C=CH, ST=Zurich, L=Zurich, O=IVPN.net, OU=IVPN, CN=IVPN Root CA v2, emailAddress=support@ivpn.net
    Jun 16 08:39:49 	openvpn 	27878 	VERIFY OK: depth=2, C=MT, ST=Malta, L=Malta, O=IVPN.net, CN=IVPN.net CA, emailAddress=support@ivpn.net
    Jun 16 08:39:49 	openvpn 	27878 	TLS: Initial packet from [AF_INET]64.120.44.114:2050, sid=b9ee9ea0 d3ffc0c4
    Jun 16 08:39:49 	openvpn 	27878 	UDPv4 link remote: [AF_INET]64.120.44.114:2050
    Jun 16 08:39:49 	openvpn 	27878 	UDPv4 link local (bound): [AF_INET]96.244.82.174:0
    Jun 16 08:39:49 	openvpn 	27878 	Socket Buffers: R=[42080->2097152] S=[57344->2097152]
    Jun 16 08:39:49 	openvpn 	27878 	TCP/UDP: Preserving recently used remote address: [AF_INET]64.120.44.114:2050
    Jun 16 08:39:49 	openvpn 	27878 	NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
    Jun 16 08:39:49 	openvpn 	27878 	WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info.
    Jun 16 08:39:39 	openvpn 	27878 	Restart pause, 10 second(s)
    Jun 16 08:39:39 	openvpn 	27878 	SIGUSR1[soft,ping-restart] received, process restarting
    Jun 16 08:39:39 	openvpn 	27878 	[us-ny1.gw.ivpn.net] Inactivity timeout (--ping-restart), restarting
    Jun 16 08:39:20 	openvpn 	28934 	MANAGEMENT: Client disconnected 
    

    Any help in figuring this out is greatly appreciated.


Log in to reply