[Solved] IPv6 renewal crashes openvpn and dpinger

[Mr.Goodcat]

Hi,

after the update to 19.1 openvpn and dpinger crash upon IPv6 WAN renewal:

DHCP log:

Code Select Expand


Feb 2 12:39:59 dhcp6c[39617]: XID mismatch
Feb 2 12:39:53 dhcpd: Sending Reply to fe80::::: port 546
Feb 2 12:39:53 dhcpd: Information-request message from fe80::::: port 546, transaction ID 0x6CD09F00
Feb 2 12:39:52 dhcpd: Server starting service.
Feb 2 12:39:52 dhcpd: Sending on Socket/12/igb1/2a02:::::/64
Feb 2 12:39:52 dhcpd: Listening on Socket/12/igb1/2a02:::::/64
Feb 2 12:39:52 dhcpd: Bound to *:547
Feb 2 12:39:52 dhcpd: Wrote 1 NA, 0 TA, 0 PD leases to lease file.
Feb 2 12:39:52 dhcpd: For info, please visit https://www.isc.org/software/dhcp/
Feb 2 12:39:52 dhcpd: All rights reserved.
Feb 2 12:39:52 dhcpd: Copyright 2004-2018 Internet Systems Consortium.
Feb 2 12:39:52 dhcpd: Internet Systems Consortium DHCP Server 4.4.1
Feb 2 12:39:52 dhcpd: PID file: /var/run/dhcpdv6.pid
Feb 2 12:39:52 dhcpd: Database file: /var/db/dhcpd6.leases
Feb 2 12:39:52 dhcpd: Config file: /etc/dhcpdv6.conf
Feb 2 12:39:52 dhcpd: For info, please visit https://www.isc.org/software/dhcp/
Feb 2 12:39:52 dhcpd: All rights reserved.
Feb 2 12:39:52 dhcpd: Copyright 2004-2018 Internet Systems Consortium.
Feb 2 12:39:52 dhcpd: Internet Systems Consortium DHCP Server 4.4.1
Feb 2 12:39:51 dhcp6c: dhcp6c REQUEST on igb0 - running newipv6
Feb 2 12:39:51 dhcp6c[39617]: add an address 2a02:::::::/128 on igb0
Feb 2 12:39:51 dhcp6c[39617]: add an address 2a02:::::::/64 on igb1
Feb 2 12:39:51 dhcp6c[39617]: Received REPLY for REQUEST
Feb 2 12:39:51 dhcp6c[39617]: Sending Request
Feb 2 12:39:51 dhcp6c[39617]: Sending Solicit
Feb 2 12:39:51 dhcp6c: dhcp6c RELEASE on igb0 - running newipv6
Feb 2 12:39:51 dhcp6c[39617]: status code: success
Feb 2 12:39:51 dhcp6c[39617]: Received REPLY for RELEASE
Feb 2 12:39:51 dhcp6c: dhcp6c RELEASE on igb0 - running newipv6
Feb 2 12:39:51 dhcp6c[39617]: status code: success
Feb 2 12:39:51 dhcp6c[39617]: Received REPLY for RELEASE
Feb 2 12:39:51 dhcp6c[39617]: remove an address 2a02:::::::/64 on igb1
Feb 2 12:39:51 dhcp6c[39617]: Sending Release
Feb 2 12:39:51 dhcp6c[39617]: Start address release
Feb 2 12:39:51 dhcp6c[39617]: remove an address 2a02:::::::/128 on igb0
Feb 2 12:39:51 dhcp6c[39617]: Sending Release
Feb 2 12:39:51 dhcp6c[39617]: Start address release
Feb 2 12:39:51 dhcp6c[39617]: restarting
Feb 2 12:39:51 dhcpd: RTSOLD script - Sending SIGHUP to dhcp6c for interface wan(igb0)
Feb 2 12:39:48 dhclient: Creating resolv.conf
Feb 2 12:36:22 dhcpd: Sending Reply to fe80::::: port 546
Feb 2 12:36:22 dhcpd: Reply NA: address 2a02::::: to client with duid 00::::::::::::: iaid = -918630208 valid for 7200 seconds
Feb 2 12:36:22 dhcpd: Renew message from fe80::::: port 546, transaction ID 0x9479A500
Feb 2 12:29:40 dhcpd: DHCPACK to 192.168.0.7 (60:::::) via igb1
Feb 2 12:29:40 dhcpd: DHCPINFORM from 192.168.0.7 via igb1
Feb 2 12:28:08 dhcpd: DHCPACK on 192.168.0.205 to 00::::: via igb1
Feb 2 12:28:08 dhcpd: DHCPREQUEST for 192.168.0.205 from 00::::: via igb1
Feb 2 12:10:47 dhcpd: DHCPACK to 192.168.0.7 (60:::::) via igb1
Feb 2 12:10:47 dhcpd: DHCPINFORM from 192.168.0.7 via igb1
Feb 2 12:09:51 dhcpd: Sending Reply to fe80::::: port 546
Feb 2 12:09:51 dhcpd: Information-request message from fe80::::: port 546, transaction ID 0xAFF74100
Feb 2 12:09:50 dhcpd: Server starting service.
Feb 2 12:09:50 dhcpd: Sending on Socket/11/igb1/2a02:::::/64
Feb 2 12:09:50 dhcpd: Listening on Socket/11/igb1/2a02:::::/64
Feb 2 12:09:50 dhcpd: Bound to *:547
Feb 2 12:09:50 dhcpd: Wrote 1 NA, 0 TA, 0 PD leases to lease file.
Feb 2 12:09:50 dhcpd: For info, please visit https://www.isc.org/software/dhcp/
Feb 2 12:09:50 dhcpd: All rights reserved.
Feb 2 12:09:50 dhcpd: Copyright 2004-2018 Internet Systems Consortium.
Feb 2 12:09:50 dhcpd: Internet Systems Consortium DHCP Server 4.4.1
Feb 2 12:09:50 dhcpd: PID file: /var/run/dhcpdv6.pid
Feb 2 12:09:50 dhcpd: Database file: /var/db/dhcpd6.leases
Feb 2 12:09:50 dhcpd: Config file: /etc/dhcpdv6.conf
Feb 2 12:09:50 dhcpd: For info, please visit https://www.isc.org/software/dhcp/
Feb 2 12:09:50 dhcpd: All rights reserved.
Feb 2 12:09:50 dhcpd: Copyright 2004-2018 Internet Systems Consortium.
Feb 2 12:09:50 dhcpd: Internet Systems Consortium DHCP Server 4.4.1
Feb 2 12:09:50 dhcp6c: dhcp6c REQUEST on igb0 - running newipv6
Feb 2 12:09:50 dhcp6c[39617]: add an address 2a02:::::::/128 on igb0
Feb 2 12:09:50 dhcp6c[39617]: add an address 2a02:::::::/64 on igb1
Feb 2 12:09:50 dhcp6c[39617]: Received REPLY for REQUEST
Feb 2 12:09:49 dhcp6c[39617]: XID mismatch
Feb 2 12:09:49 dhcp6c[39617]: Sending Request
Feb 2 12:09:49 dhcp6c: dhcp6c RELEASE on igb0 - running newipv6
Feb 2 12:09:49 dhcp6c[39617]: status code: success
Feb 2 12:09:49 dhcp6c[39617]: Received REPLY for RELEASE
Feb 2 12:09:49 dhcp6c[39617]: Sending Solicit
Feb 2 12:09:49 dhcp6c: dhcp6c RELEASE on igb0 - running newipv6
Feb 2 12:09:49 dhcp6c[39617]: status code: success
Feb 2 12:09:49 dhcp6c[39617]: Received REPLY for RELEASE
Feb 2 12:09:49 dhcp6c[39617]: remove an address 2a02:::::::/64 on igb1
Feb 2 12:09:49 dhcp6c[39617]: Sending Release
Feb 2 12:09:49 dhcp6c[39617]: Start address release
Feb 2 12:09:49 dhcp6c[39617]: remove an address 2a02:::::::/128 on igb0
Feb 2 12:09:49 dhcp6c[39617]: Sending Release
Feb 2 12:09:49 dhcp6c[39617]: Start address release
Feb 2 12:09:49 dhcp6c[39617]: restarting
Feb 2 12:09:49 dhcpd: RTSOLD script - Sending SIGHUP to dhcp6c for interface wan(igb0)
Feb 2 12:09:48 dhclient: Creating resolv.conf


General log:

Code Select Expand


Feb 2 12:40:02 opnsense: /usr/local/etc/rc.newwanip: The command '/usr/local/sbin/unbound -c '/var/unbound/unbound.conf'' returned exit code '1', the output was '[1549107602] unbound[24967:0] error: bind: address already in use [1549107602] unbound[24967:0] fatal error: could not open ports'
Feb 2 12:39:59 opnsense: /usr/local/etc/rc.newwanipv6: The command '/usr/local/opnsense/scripts/dns/unbound_dhcpd.py /domain 'lan'' returned exit code '1', the output was 'Unable to lock on the pidfile.'
Feb 2 12:39:58 opnsense: /usr/local/etc/rc.newwanipv6: Dynamic DNS: (Success) IP Address Updated Successfully!
Feb 2 12:39:58 opnsense: /usr/local/etc/rc.newwanipv6: Dynamic DNS: updating cache file /var/cache/dyndns_wan_my-domain.com_1.cache: 62...
Feb 2 12:39:57 opnsense: /usr/local/etc/rc.newwanip: Dynamic DNS: (Success) IP Address Updated Successfully!
Feb 2 12:39:57 opnsense: /usr/local/etc/rc.newwanip: Dynamic DNS: updating cache file /var/cache/dyndns_wan_my-domain.com_1.cache: 62...
Feb 2 12:39:55 opnsense: /usr/local/etc/rc.newwanipv6: Dynamic DNS (famaku.hopto.org): No change in my IP address and/or 25 days has not passed. Not updating dynamic DNS entry.
Feb 2 12:39:53 opnsense: /usr/local/etc/rc.newwanipv6: Resyncing OpenVPN instances for interface WAN.
Feb 2 12:39:52 opnsense: /usr/local/etc/rc.newwanipv6: Adding static route for monitor 2001::::::: via fe80:::::%igb0
Feb 2 12:39:52 opnsense: /usr/local/etc/rc.newwanipv6: Removing static route for monitor 2001::::::: via fe80:::::%igb0
Feb 2 12:39:52 opnsense: /usr/local/etc/rc.newwanip: Dynamic DNS (famaku.hopto.org): No change in my IP address and/or 25 days has not passed. Not updating dynamic DNS entry.
Feb 2 12:39:52 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: keeping current default gateway 'fe80:::::%igb0'
Feb 2 12:39:52 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: setting IPv6 default route to fe80:::::
Feb 2 12:39:52 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: keeping current default gateway '62...'
Feb 2 12:39:52 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: setting IPv4 default route to 62...
Feb 2 12:39:52 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: IPv6 default gateway set to wan
Feb 2 12:39:52 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: IPv4 default gateway set to wan
Feb 2 12:39:52 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: entering configure using 'wan'
Feb 2 12:39:51 opnsense: /usr/local/etc/rc.newwanipv6: On (IP address: 2a02:::::::) (interface: WAN[wan]) (real interface: igb0).
Feb 2 12:39:51 opnsense: /usr/local/etc/rc.newwanipv6: IP renewal is starting on 'igb0'
Feb 2 12:39:51 opnsense: /usr/local/etc/rc.newwanipv6: Failed to detect IP for WAN[wan]
Feb 2 12:39:51 opnsense: /usr/local/etc/rc.newwanipv6: On (IP address: ) (interface: WAN[wan]) (real interface: igb0).
Feb 2 12:39:51 opnsense: /usr/local/etc/rc.newwanipv6: IP renewal is starting on 'igb0'
Feb 2 12:39:51 opnsense: /usr/local/etc/rc.newwanipv6: Failed to detect IP for WAN[wan]
Feb 2 12:39:51 opnsense: /usr/local/etc/rc.newwanipv6: On (IP address: ) (interface: WAN[wan]) (real interface: igb0).
Feb 2 12:39:51 opnsense: /usr/local/etc/rc.newwanipv6: IP renewal is starting on 'igb0'
Feb 2 12:39:50 opnsense: /usr/local/etc/rc.newwanip: Resyncing OpenVPN instances for interface WAN.
Feb 2 12:39:49 opnsense: /usr/local/etc/rc.newwanip: Adding static route for monitor 2001::::::: via fe80:::::%igb0
Feb 2 12:39:49 opnsense: /usr/local/etc/rc.newwanip: Removing static route for monitor 2001::::::: via fe80:::::%igb0
Feb 2 12:39:49 opnsense: /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway 'fe80:::::%igb0'
Feb 2 12:39:49 opnsense: /usr/local/etc/rc.newwanip: ROUTING: setting IPv6 default route to fe80:::::
Feb 2 12:39:49 opnsense: /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway '62...'
Feb 2 12:39:49 opnsense: /usr/local/etc/rc.newwanip: ROUTING: setting IPv4 default route to 62...
Feb 2 12:39:49 opnsense: /usr/local/etc/rc.newwanip: ROUTING: IPv6 default gateway set to wan
Feb 2 12:39:49 opnsense: /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to wan
Feb 2 12:39:49 opnsense: /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'wan'
Feb 2 12:39:48 opnsense: /usr/local/etc/rc.newwanip: Accept router advertisements on interface igb0
Feb 2 12:39:48 opnsense: /usr/local/etc/rc.newwanip: On (IP address: 62...) (interface: WAN[wan]) (real interface: igb0).
Feb 2 12:39:48 opnsense: /usr/local/etc/rc.newwanip: IP renewal is starting on 'igb0'
Feb 2 12:12:49 opnsense: /status_services.php: Adding static route for monitor 2001::::::: via fe80:::::%igb0
Feb 2 12:12:49 opnsense: /status_services.php: Removing static route for monitor 2001::::::: via fe80:::::%igb0
Feb 2 12:10:02 opnsense: /usr/local/etc/rc.newwanipv6: Dynamic DNS: (Success) IP Address Updated Successfully!
Feb 2 12:10:02 opnsense: /usr/local/etc/rc.newwanipv6: Dynamic DNS: updating cache file /var/cache/dyndns_wan_my-domain.com_1.cache: 62...
Feb 2 12:09:55 opnsense: /usr/local/etc/rc.newwanipv6: Dynamic DNS (famaku.hopto.org): No change in my IP address and/or 25 days has not passed. Not updating dynamic DNS entry.
Feb 2 12:09:54 opnsense: /usr/local/etc/rc.newwanip: Dynamic DNS: (Success) IP Address Updated Successfully!
Feb 2 12:09:54 opnsense: /usr/local/etc/rc.newwanip: Dynamic DNS: updating cache file /var/cache/dyndns_wan_my-domain.com_1.cache: 62...
Feb 2 12:09:53 opnsense: /usr/local/etc/rc.newwanipv6: Resyncing OpenVPN instances for interface WAN.
Feb 2 12:09:52 opnsense: /usr/local/etc/rc.newwanip: Dynamic DNS (famaku.hopto.org): No change in my IP address and/or 25 days has not passed. Not updating dynamic DNS entry.
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanipv6: Adding static route for monitor 2001::::::: via fe80:::::%igb0
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanipv6: Removing static route for monitor 2001::::::: via fe80:::::%igb0
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: keeping current default gateway 'fe80:::::%igb0'
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: setting IPv6 default route to fe80:::::
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: keeping current default gateway '62...'
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: setting IPv4 default route to 62...
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: IPv6 default gateway set to wan
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: IPv4 default gateway set to wan
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanipv6: ROUTING: entering configure using 'wan'
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanipv6: On (IP address: 2a02:::::::) (interface: WAN[wan]) (real interface: igb0).
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanipv6: IP renewal is starting on 'igb0'
Feb 2 12:09:50 opnsense: /usr/local/etc/rc.newwanip: Resyncing OpenVPN instances for interface WAN.
Feb 2 12:09:49 opnsense: /usr/local/etc/rc.newwanipv6: Failed to detect IP for WAN[wan]
Feb 2 12:09:49 opnsense: /usr/local/etc/rc.newwanipv6: On (IP address: ) (interface: WAN[wan]) (real interface: igb0).
Feb 2 12:09:49 opnsense: /usr/local/etc/rc.newwanipv6: IP renewal is starting on 'igb0'
Feb 2 12:09:49 opnsense: /usr/local/etc/rc.newwanipv6: Failed to detect IP for WAN[wan]
Feb 2 12:09:49 opnsense: /usr/local/etc/rc.newwanipv6: On (IP address: ) (interface: WAN[wan]) (real interface: igb0).
Feb 2 12:09:49 opnsense: /usr/local/etc/rc.newwanipv6: IP renewal is starting on 'igb0'
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: Adding static route for monitor 2001::::::: via fe80:::::%igb0
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: Removing static route for monitor 2001::::::: via fe80:::::%igb0
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway 'fe80:::::%igb0'
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: ROUTING: setting IPv6 default route to fe80:::::
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway '62...'
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: ROUTING: setting IPv4 default route to 62...
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: ROUTING: IPv6 default gateway set to wan
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to wan
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'wan'
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: Accept router advertisements on interface igb0
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: On (IP address: 62...) (interface: WAN[wan]) (real interface: igb0).
Feb 2 12:09:48 opnsense: /usr/local/etc/rc.newwanip: IP renewal is starting on 'igb0'


Any ideas on how to fix this?  :-\

[Mr.Goodcat]

OpenVPN encounters the following error:

OpenVPN log:

Code Select Expand


Feb 2 16:51:00 openvpn[32421]: Exiting due to fatal error
Feb 2 16:51:00 openvpn[32421]: Cannot open TUN/TAP dev /dev/tun1: Device busy (errno=16)
Feb 2 16:51:00 openvpn[32421]: TUN/TAP device ovpns1 exists previously, keep at program end
Feb 2 16:51:00 openvpn[32421]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Feb 2 16:51:00 openvpn[27320]: library versions: OpenSSL 1.0.2q 20 Nov 2018, LZO 2.10
Feb 2 16:51:00 openvpn[27320]: OpenVPN 2.4.6 amd64-portbld-freebsd11.2 [SSL (OpenSSL)] [LZO] [LZ4] [MH/RECVDA] [AEAD] built on Jan 28 2019
Feb 2 16:50:56 openvpn[51681]: Exiting due to fatal error
Feb 2 16:50:56 openvpn[51681]: Cannot open TUN/TAP dev /dev/tun1: Device busy (errno=16)



Another crash, with log starting directly after a reboot:

Code Select Expand


Feb 4 23:22:04 openvpn[56796]: Exiting due to fatal error
Feb 4 23:22:04 openvpn[56796]: Cannot open TUN/TAP dev /dev/tun1: Device busy (errno=16)
Feb 4 23:22:04 openvpn[56796]: TUN/TAP device ovpns1 exists previously, keep at program end
Feb 4 23:22:04 openvpn[56796]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Feb 4 23:22:04 openvpn[92830]: library versions: OpenSSL 1.0.2q 20 Nov 2018, LZO 2.10
Feb 4 23:22:04 openvpn[92830]: OpenVPN 2.4.6 amd64-portbld-freebsd11.2 [SSL (OpenSSL)] [LZO] [LZ4] [MH/RECVDA] [AEAD] built on Jan 28 2019
Feb 4 23:21:45 openvpn[3419]: Initialization Sequence Completed
Feb 4 23:21:45 openvpn[3419]: UDPv4 link remote: [AF_UNSPEC]
Feb 4 23:21:45 openvpn[3419]: UDPv4 link local (bound): [AF_INET]62.143.29.81:1194
Feb 4 23:21:45 openvpn[3419]: Could not determine IPv4/IPv6 protocol. Using AF_INET
Feb 4 23:21:44 openvpn[92506]: Exiting due to fatal error
Feb 4 23:21:44 openvpn[92506]: Cannot open TUN/TAP dev /dev/tun1: Device busy (errno=16)
Feb 4 23:21:44 openvpn[92506]: TUN/TAP device ovpns1 exists previously, keep at program end
Feb 4 23:21:44 openvpn[92506]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Feb 4 23:21:44 openvpn[27041]: library versions: OpenSSL 1.0.2q 20 Nov 2018, LZO 2.10
Feb 4 23:21:44 openvpn[27041]: OpenVPN 2.4.6 amd64-portbld-freebsd11.2 [SSL (OpenSSL)] [LZO] [LZ4] [MH/RECVDA] [AEAD] built on Jan 28 2019
Feb 4 23:21:44 openvpn[3419]: /usr/local/etc/inc/plugins.inc.d/openvpn/ovpn-linkup ovpns1 1500 1622 10.10.0.1 10.10.0.2 init
Feb 4 23:21:44 openvpn[3419]: /sbin/ifconfig ovpns1 10.10.0.1 10.10.0.2 mtu 1500 netmask 255.255.255.255 up
Feb 4 23:21:44 openvpn[3419]: do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Feb 4 23:21:44 openvpn[3419]: TUN/TAP device /dev/tun1 opened
Feb 4 23:21:44 openvpn[3419]: TUN/TAP device ovpns1 exists previously, keep at program end
Feb 4 23:21:44 openvpn[3419]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Feb 4 23:21:44 openvpn[10066]: library versions: OpenSSL 1.0.2q 20 Nov 2018, LZO 2.10
Feb 4 23:21:44 openvpn[10066]: OpenVPN 2.4.6 amd64-portbld-freebsd11.2 [SSL (OpenSSL)] [LZO] [LZ4] [MH/RECVDA] [AEAD] built on Jan 28 2019
Feb 4 23:21:44 openvpn[5353]: SIGTERM[hard,] received, process exiting
Feb 4 23:21:40 openvpn[5353]: /usr/local/etc/inc/plugins.inc.d/openvpn/ovpn-linkdown ovpns1 1500 1622 10.10.0.1 10.10.0.2 init
Feb 4 23:21:40 openvpn[5353]: event_wait : Interrupted system call (code=4)
Feb 4 23:21:37 openvpn[5353]: Initialization Sequence Completed
Feb 4 23:21:37 openvpn[5353]: UDPv4 link remote: [AF_UNSPEC]
Feb 4 23:21:37 openvpn[5353]: UDPv4 link local (bound): [AF_INET]62.143.29.81:1194
Feb 4 23:21:37 openvpn[5353]: Could not determine IPv4/IPv6 protocol. Using AF_INET
Feb 4 23:21:36 openvpn[5353]: /usr/local/etc/inc/plugins.inc.d/openvpn/ovpn-linkup ovpns1 1500 1622 10.10.0.1 10.10.0.2 init
Feb 4 23:21:36 openvpn[5353]: /sbin/ifconfig ovpns1 10.10.0.1 10.10.0.2 mtu 1500 netmask 255.255.255.255 up
Feb 4 23:21:36 openvpn[5353]: do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Feb 4 23:21:36 openvpn[5353]: TUN/TAP device /dev/tun1 opened
Feb 4 23:21:36 openvpn[5353]: TUN/TAP device ovpns1 exists previously, keep at program end
Feb 4 23:21:36 openvpn[5353]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Feb 4 23:21:36 openvpn[66634]: library versions: OpenSSL 1.0.2q 20 Nov 2018, LZO 2.10
Feb 4 23:21:36 openvpn[66634]: OpenVPN 2.4.6 amd64-portbld-freebsd11.2 [SSL (OpenSSL)] [LZO] [LZ4] [MH/RECVDA] [AEAD] built on Jan 28 2019
Feb 4 23:21:36 openvpn[18472]: SIGTERM[hard,] received, process exiting
Feb 4 23:21:34 openvpn[18472]: /usr/local/etc/inc/plugins.inc.d/openvpn/ovpn-linkdown ovpns1 1500 1622 10.10.0.1 10.10.0.2 init
Feb 4 23:21:34 openvpn[18472]: event_wait : Interrupted system call (code=4)
Feb 4 23:05:20 openvpn[18472]: Initialization Sequence Completed
Feb 4 23:05:20 openvpn[18472]: UDPv4 link remote: [AF_UNSPEC]
Feb 4 23:05:20 openvpn[18472]: UDPv4 link local (bound): [AF_INET]62.143.29.81:1194
Feb 4 23:05:20 openvpn[18472]: Could not determine IPv4/IPv6 protocol. Using AF_INET
Feb 4 23:05:20 openvpn[18472]: /usr/local/etc/inc/plugins.inc.d/openvpn/ovpn-linkup ovpns1 1500 1622 10.10.0.1 10.10.0.2 init
Feb 4 23:05:20 openvpn[18472]: /sbin/ifconfig ovpns1 10.10.0.1 10.10.0.2 mtu 1500 netmask 255.255.255.255 up
Feb 4 23:05:20 openvpn[18472]: do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Feb 4 23:05:20 openvpn[18472]: TUN/TAP device /dev/tun1 opened
Feb 4 23:05:20 openvpn[18472]: TUN/TAP device ovpns1 exists previously, keep at program end
Feb 4 23:05:20 openvpn[18472]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Feb 4 23:05:20 openvpn[62690]: library versions: OpenSSL 1.0.2q 20 Nov 2018, LZO 2.10
Feb 4 23:05:20 openvpn[62690]: OpenVPN 2.4.6 amd64-portbld-freebsd11.2 [SSL (OpenSSL)] [LZO] [LZ4] [MH/RECVDA] [AEAD] built on Jan 28 2019
Feb 4 23:05:20 openvpn[94020]: SIGTERM[hard,] received, process exiting
Feb 4 23:05:16 openvpn[94020]: /usr/local/etc/inc/plugins.inc.d/openvpn/ovpn-linkdown ovpns1 1500 1622 10.10.0.1 10.10.0.2 init
Feb 4 23:05:16 openvpn[94020]: event_wait : Interrupted system call (code=4)
Feb 4 23:05:15 openvpn[94020]: Initialization Sequence Completed
Feb 4 23:05:15 openvpn[94020]: UDPv4 link remote: [AF_UNSPEC]
Feb 4 23:05:15 openvpn[94020]: UDPv4 link local (bound): [AF_INET]62.143.29.81:1194
Feb 4 23:05:15 openvpn[94020]: Could not determine IPv4/IPv6 protocol. Using AF_INET
Feb 4 23:05:14 openvpn[94020]: /usr/local/etc/inc/plugins.inc.d/openvpn/ovpn-linkup ovpns1 1500 1622 10.10.0.1 10.10.0.2 init
Feb 4 23:05:14 openvpn[94020]: /sbin/ifconfig ovpns1 10.10.0.1 10.10.0.2 mtu 1500 netmask 255.255.255.255 up
Feb 4 23:05:14 openvpn[94020]: do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Feb 4 23:05:14 openvpn[94020]: TUN/TAP device /dev/tun1 opened
Feb 4 23:05:14 openvpn[94020]: TUN/TAP device ovpns1 exists previously, keep at program end
Feb 4 23:05:14 openvpn[94020]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Feb 4 23:05:14 openvpn[21994]: library versions: OpenSSL 1.0.2q 20 Nov 2018, LZO 2.10
Feb 4 23:05:14 openvpn[21994]: OpenVPN 2.4.6 amd64-portbld-freebsd11.2 [SSL (OpenSSL)] [LZO] [LZ4] [MH/RECVDA] [AEAD] built on Jan 28 2019
Feb 4 23:05:14 openvpn[71439]: SIGTERM[hard,] received, process exiting
Feb 4 23:05:12 openvpn[71439]: /usr/local/etc/inc/plugins.inc.d/openvpn/ovpn-linkdown ovpns1 1500 1622 10.10.0.1 10.10.0.2 init
Feb 4 23:05:12 openvpn[71439]: event_wait : Interrupted system call (code=4)
Feb 4 23:05:00 openvpn[71439]: Initialization Sequence Completed
Feb 4 23:05:00 openvpn[71439]: UDPv4 link remote: [AF_UNSPEC]
Feb 4 23:05:00 openvpn[71439]: UDPv4 link local (bound): [AF_INET]62.143.29.81:1194
Feb 4 23:05:00 openvpn[71439]: Could not determine IPv4/IPv6 protocol. Using AF_INET
Feb 4 23:04:59 openvpn[71439]: /usr/local/etc/inc/plugins.inc.d/openvpn/ovpn-linkup ovpns1 1500 1622 10.10.0.1 10.10.0.2 init
Feb 4 23:04:59 openvpn[71439]: /sbin/ifconfig ovpns1 10.10.0.1 10.10.0.2 mtu 1500 netmask 255.255.255.255 up
Feb 4 23:04:59 openvpn[71439]: do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Feb 4 23:04:59 openvpn[71439]: TUN/TAP device /dev/tun1 opened
Feb 4 23:04:59 openvpn[71439]: TUN/TAP device ovpns1 exists previously, keep at program end
Feb 4 23:04:59 openvpn[71439]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Feb 4 23:04:59 openvpn[23819]: library versions: OpenSSL 1.0.2q 20 Nov 2018, LZO 2.10
Feb 4 23:04:59 openvpn[23819]: OpenVPN 2.4.6 amd64-portbld-freebsd11.2 [SSL (OpenSSL)] [LZO] [LZ4] [MH/RECVDA] [AEAD] built on Jan 28 2019


[Mr.Goodcat]

After updating to 19.1.1 the issue persists  :(
Any idea on the root cause? The very same setup worked flawlessly under 18.7.x.

[Space]

Regarding OpenVPN. The funny thing is that the Dashboard shows it as Failed even though it's still running:

Code Select Expand

[root@OPNvirt:~ # ps -x | grep openvpn
76436  -  Ss      0:01.26 /usr/local/sbin/openvpn --config /var/etc/openvpn/server1.conf


Best regards,

    Space

[Mr.Goodcat]

Regarding OpenVPN. The funny thing is that the Dashboard shows it as Failed even though it's still running:

Code Select Expand

[root@OPNvirt:~ # ps -x | grep openvpn
76436  -  Ss      0:01.26 /usr/local/sbin/openvpn --config /var/etc/openvpn/server1.conf


Best regards,

    Space

Wow, you are correct!  :o

[Mr.Goodcat]

So far the below patch seems to have fixed my issues.

Code Select Expand


opnsense-patch c83bb8d


Update:
The patch worked flawlessly throughout the night and fixed the issues. A big thanks to Franco and the OPNSense team!  :D