RADVD and IPv6 Killed by Modem Reboot
-
I'm running 2.1-RC1 (i386) built on Sat Aug 24 11:30:48 EDT 2013. I have comcast. Their IPv6 is via DHCP6-PD.
For whatever reason, my modem rebooted. In normal operation, the pfsense WAN gets a /128 with a 2001: prefix and the LAN get /64 2601: prefix. After this reboot event, both are gone and RADVD is gone from the services page. IPv6 will not automatically come back at this point without some user intervention.
During the reboot, the modem acted as a DHCP (IPv4) server temporarily. It does this to provide connectivity to the modem's status/diag page. It gave pfsense an address of 192.168.100.10
It doesn't seem to offer any IPv6 connectivity. It keeps a 30 second DHCP lease time so that when normal connectivity is restored, the router will attempt to renew and then get connectivity from Comcast's DHCP server.
As an additional note, it looks like the reboot causes the link to go down. When normal connectivity is restored, the link remains up.
I setup remote syslogging so I can provide any relevant logs from this event. I'm not sure what's relevant though.
Here's RADVD's final words:
Aug 27 14:24:50 cerberus.localdomain php: rc.linkup: Shutting down Router Advertisment daemon cleanly Aug 27 14:24:50 cerberus.localdomain radvd[37768]: Exiting, sigterm or sigint received. Aug 27 14:24:50 cerberus.localdomain radvd[37768]: sending stop adverts Aug 27 14:24:50 cerberus.localdomain radvd[37768]: removing /var/run/radvd.pid
To me it seems the steps to reproduce this are as follows:
Create two networks. One with DHCP6-PD and one without any IPv6
Have pfsense connect to the DHCP6-PD one
Bring down the link and connect it to the one without IPv6
Reestablish connectivity to the DHCP6-PD without affecting the link and remove the one without IPv6 -
Yeah, I see the same thing; see here.
-
Somehow the ticket for DHCPv6-PD issues was recently closed anyway: https://redmine.pfsense.org/issues/2919
-
I guess a possible solution would be to renew (if it exists) or try and re-establish (if it doesn't) IPv6 connectivity any time the WAN IPv4 address changes?
-
Well that's discouraging :-. In any case, here's the output from the logfile. The lack of ability to reattempt IPv6 seems to be due to something in rc.linkup. It's what kills RADVD according to the logs.
Aug 27 14:24:19 cerberus.localdomain check_reload_status: Linkup starting vr1 Aug 27 14:24:19 cerberus.localdomain kernel: vr1: link state changed to DOWN Aug 27 14:24:24 cerberus.localdomain php: rc.linkup: DEVD Ethernet detached event for wan Aug 27 14:24:24 cerberus.localdomain dhclient[9182]: connection closed Aug 27 14:24:24 cerberus.localdomain dhclient[9182]: exiting. Aug 27 14:24:28 cerberus.localdomain php: rc.linkup: Clearing states to old gateway 67.x.y.1. Aug 27 14:24:32 cerberus.localdomain kernel: arpresolve: can't allocate llinfo for 67.x.y.1 Aug 27 14:24:36 cerberus.localdomain check_reload_status: Linkup starting vr1 Aug 27 14:24:36 cerberus.localdomain kernel: vr1: link state changed to UP Aug 27 14:24:39 cerberus.localdomain check_reload_status: Linkup starting vr1 Aug 27 14:24:39 cerberus.localdomain kernel: vr1: link state changed to DOWN Aug 27 14:24:41 cerberus.localdomain php: rc.linkup: DEVD Ethernet attached event for wan Aug 27 14:24:41 cerberus.localdomain php: rc.linkup: HOTPLUG: Configuring interface wan Aug 27 14:24:41 cerberus.localdomain php: rc.linkup: The command '/sbin/ifconfig 'vr1' inet delete' returned exit code '1', the output was 'ifconfig: ioctl (SIOCDIFADDR): Can't assign requested address' Aug 27 14:24:41 cerberus.localdomain check_reload_status: Linkup starting vr1 Aug 27 14:24:41 cerberus.localdomain kernel: vr1: link state changed to UP Aug 27 14:24:41 cerberus.localdomain dhclient: PREINIT Aug 27 14:24:41 cerberus.localdomain dhclient: Starting delete_old_states() Aug 27 14:24:41 cerberus.localdomain dhclient: Comparing IPs: Old: New: Aug 27 14:24:41 cerberus.localdomain dhclient[8440]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Aug 27 14:24:42 cerberus.localdomain dhclient[8440]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Aug 27 14:24:42 cerberus.localdomain kernel: arpresolve: can't allocate llinfo for 67.x.y.1 Aug 27 14:24:44 cerberus.localdomain php: rc.linkup: DEVD Ethernet detached event for wan Aug 27 14:24:44 cerberus.localdomain dhclient[8988]: connection closed Aug 27 14:24:44 cerberus.localdomain dhclient[8988]: exiting. Aug 27 14:24:44 cerberus.localdomain php: rc.linkup: The command '/sbin/dhclient -c /var/etc/dhclient_wan.conf vr1 > /tmp/vr1_output 2> /tmp/vr1_error_output' returned exit code '15', the output was '' Aug 27 14:24:44 cerberus.localdomain php: rc.linkup: Accept router advertisements on interface vr1 Aug 27 14:24:46 cerberus.localdomain php: rc.linkup: DEVD Ethernet attached event for wan Aug 27 14:24:46 cerberus.localdomain php: rc.linkup: HOTPLUG: Configuring interface wan Aug 27 14:24:46 cerberus.localdomain dnsmasq[36122]: exiting on receipt of SIGTERM Aug 27 14:24:47 cerberus.localdomain php: rc.linkup: The command '/sbin/ifconfig 'vr1' inet delete' returned exit code '1', the output was 'ifconfig: ioctl (SIOCDIFADDR): Can't assign requested address' Aug 27 14:24:47 cerberus.localdomain dhclient: PREINIT Aug 27 14:24:47 cerberus.localdomain dhclient: Starting delete_old_states() Aug 27 14:24:47 cerberus.localdomain dhclient: Comparing IPs: Old: New: Aug 27 14:24:47 cerberus.localdomain dhclient[14008]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Aug 27 14:24:47 cerberus.localdomain dhclient[14008]: DHCPNAK from 192.168.100.1 Aug 27 14:24:47 cerberus.localdomain dhclient[14008]: DHCPDISCOVER on vr1 to 255.255.255.255 port 67 interval 1 Aug 27 14:24:47 cerberus.localdomain dnsmasq[16494]: started, version 2.66 cachesize 10000 Aug 27 14:24:48 cerberus.localdomain dnsmasq[16494]: compile time options: IPv6 GNU-getopt no-DBus i18n IDN DHCP DHCPv6 no-Lua TFTP no-conntrack no-ipset auth Aug 27 14:24:48 cerberus.localdomain dnsmasq[16494]: reading /etc/resolv.conf Aug 27 14:24:48 cerberus.localdomain dnsmasq[16494]: using nameserver 75.75.76.76#53 Aug 27 14:24:48 cerberus.localdomain dnsmasq[16494]: using nameserver 75.75.75.75#53 Aug 27 14:24:48 cerberus.localdomain dnsmasq[16494]: using nameserver 2001:558:feed::2#53 Aug 27 14:24:48 cerberus.localdomain dnsmasq[16494]: using nameserver 2001:558:feed::1#53 Aug 27 14:24:48 cerberus.localdomain dnsmasq[16494]: ignoring nameserver 127.0.0.1 - local interface Aug 27 14:24:48 cerberus.localdomain dnsmasq[16494]: read /etc/hosts - 2 addresses Aug 27 14:24:48 cerberus.localdomain dhclient[14008]: DHCPDISCOVER on vr1 to 255.255.255.255 port 67 interval 1 Aug 27 14:24:49 cerberus.localdomain dhclient[14008]: DHCPOFFER from 192.168.100.1 Aug 27 14:24:49 cerberus.localdomain dhclient: ARPSEND Aug 27 14:24:49 cerberus.localdomain dhclient[14008]: DHCPOFFER from 192.168.100.1 Aug 27 14:24:49 cerberus.localdomain dhclient[14008]: DHCPOFFER already seen. Aug 27 14:24:49 cerberus.localdomain dhcpd: Internet Systems Consortium DHCP Server 4.2.5-P1 Aug 27 14:24:49 cerberus.localdomain dhcpd: Copyright 2004-2013 Internet Systems Consortium. Aug 27 14:24:49 cerberus.localdomain dhcpd: All rights reserved. Aug 27 14:24:49 cerberus.localdomain dhcpd: For info, please visit https://www.isc.org/software/dhcp/ Aug 27 14:24:49 cerberus.localdomain dhcpd: Internet Systems Consortium DHCP Server 4.2.5-P1 Aug 27 14:24:49 cerberus.localdomain dhcpd: Copyright 2004-2013 Internet Systems Consortium. Aug 27 14:24:49 cerberus.localdomain dhcpd: All rights reserved. Aug 27 14:24:49 cerberus.localdomain dhcpd: For info, please visit https://www.isc.org/software/dhcp/ Aug 27 14:24:49 cerberus.localdomain dhcpd: Wrote 0 deleted host decls to leases file. Aug 27 14:24:49 cerberus.localdomain dhcpd: Wrote 0 new dynamic host decls to leases file. Aug 27 14:24:49 cerberus.localdomain dhcpd: Wrote 3 leases to leases file. Aug 27 14:24:49 cerberus.localdomain dhcpd: Listening on BPF/vr0/zz:xx:yy:aa:bb:cc/192.168.112.0/24 Aug 27 14:24:49 cerberus.localdomain dhcpd: Sending on BPF/vr0/zz:xx:yy:aa:bb:cc/192.168.112.0/24 Aug 27 14:24:49 cerberus.localdomain dhcpd: Sending on Socket/fallback/fallback-net Aug 27 14:24:50 cerberus.localdomain php: rc.linkup: Shutting down Router Advertisment daemon cleanly Aug 27 14:24:50 cerberus.localdomain radvd[37768]: Exiting, sigterm or sigint received. Aug 27 14:24:50 cerberus.localdomain radvd[37768]: sending stop adverts Aug 27 14:24:50 cerberus.localdomain radvd[37768]: removing /var/run/radvd.pid Aug 27 14:24:51 cerberus.localdomain check_reload_status: updating dyndns wan Aug 27 14:24:51 cerberus.localdomain dhclient: ARPCHECK Aug 27 14:24:51 cerberus.localdomain dhclient[14008]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Aug 27 14:24:51 cerberus.localdomain dhclient[14008]: DHCPACK from 192.168.100.1 Aug 27 14:24:51 cerberus.localdomain dhclient: BOUND Aug 27 14:24:51 cerberus.localdomain dhclient: Starting delete_old_states() Aug 27 14:24:51 cerberus.localdomain dhclient: Comparing IPs: Old: New: 192.168.100.10 Aug 27 14:24:51 cerberus.localdomain dhclient: Starting add_new_address() Aug 27 14:24:51 cerberus.localdomain dhclient: ifconfig vr1 inet 192.168.100.10 netmask 255.255.255.0 broadcast 192.168.100.255 Aug 27 14:24:51 cerberus.localdomain dhclient: New IP Address (vr1): 192.168.100.10 Aug 27 14:24:51 cerberus.localdomain dhclient: New Subnet Mask (vr1): 255.255.255.0 Aug 27 14:24:51 cerberus.localdomain dhclient: New Broadcast Address (vr1): 192.168.100.255 Aug 27 14:24:51 cerberus.localdomain dhclient: New Routers (vr1): 192.168.100.1 Aug 27 14:24:51 cerberus.localdomain dhclient: Adding new routes to interface: vr1 Aug 27 14:24:51 cerberus.localdomain dhclient: /sbin/route add default 192.168.100.1 Aug 27 14:24:51 cerberus.localdomain dhclient: Creating resolv.conf Aug 27 14:24:51 cerberus.localdomain dhclient[14008]: bound to 192.168.100.10 -- renewal in 30 seconds. Aug 27 14:24:51 cerberus.localdomain php: rc.linkup: Accept router advertisements on interface vr1 Aug 27 14:24:51 cerberus.localdomain check_reload_status: rc.newwanip starting vr1 Aug 27 14:24:52 cerberus.localdomain php: rc.linkup: ROUTING: setting default route to 192.168.100.1 Aug 27 14:24:54 cerberus.localdomain dnsmasq[16494]: exiting on receipt of SIGTERM Aug 27 14:24:55 cerberus.localdomain kernel: ovpns2: link state changed to DOWN Aug 27 14:24:55 cerberus.localdomain dnsmasq[34026]: started, version 2.66 cachesize 10000 Aug 27 14:24:55 cerberus.localdomain dnsmasq[34026]: compile time options: IPv6 GNU-getopt no-DBus i18n IDN DHCP DHCPv6 no-Lua TFTP no-conntrack no-ipset auth Aug 27 14:24:55 cerberus.localdomain dnsmasq[34026]: reading /etc/resolv.conf Aug 27 14:24:55 cerberus.localdomain dnsmasq[34026]: using nameserver 75.75.76.76#53 Aug 27 14:24:55 cerberus.localdomain dnsmasq[34026]: using nameserver 75.75.75.75#53 Aug 27 14:24:55 cerberus.localdomain dnsmasq[34026]: using nameserver 2001:558:feed::2#53 Aug 27 14:24:55 cerberus.localdomain dnsmasq[34026]: using nameserver 2001:558:feed::1#53 Aug 27 14:24:55 cerberus.localdomain dnsmasq[34026]: ignoring nameserver 127.0.0.1 - local interface Aug 27 14:24:55 cerberus.localdomain dnsmasq[34026]: read /etc/hosts - 2 addresses Aug 27 14:24:55 cerberus.localdomain check_reload_status: Reloading filter Aug 27 14:24:56 cerberus.localdomain dhcpd: Internet Systems Consortium DHCP Server 4.2.5-P1 Aug 27 14:24:56 cerberus.localdomain dhcpd: Copyright 2004-2013 Internet Systems Consortium. Aug 27 14:24:56 cerberus.localdomain dhcpd: All rights reserved. Aug 27 14:24:56 cerberus.localdomain dhcpd: For info, please visit https://www.isc.org/software/dhcp/ Aug 27 14:24:56 cerberus.localdomain dhcpd: Internet Systems Consortium DHCP Server 4.2.5-P1 Aug 27 14:24:56 cerberus.localdomain dhcpd: Copyright 2004-2013 Internet Systems Consortium. Aug 27 14:24:56 cerberus.localdomain dhcpd: All rights reserved. Aug 27 14:24:56 cerberus.localdomain dhcpd: For info, please visit https://www.isc.org/software/dhcp/ Aug 27 14:24:56 cerberus.localdomain dhcpd: Wrote 0 deleted host decls to leases file. Aug 27 14:24:56 cerberus.localdomain dhcpd: Wrote 0 new dynamic host decls to leases file. Aug 27 14:24:56 cerberus.localdomain dhcpd: Wrote 3 leases to leases file. Aug 27 14:24:56 cerberus.localdomain dhcpd: Listening on BPF/vr0/zz:xx:yy:aa:bb:cc/192.168.112.0/24 Aug 27 14:24:56 cerberus.localdomain dhcpd: Sending on BPF/vr0/zz:xx:yy:aa:bb:cc/192.168.112.0/24 Aug 27 14:24:56 cerberus.localdomain dhcpd: Sending on Socket/fallback/fallback-net Aug 27 14:24:57 cerberus.localdomain dhcp6c[20345]: client6_timo: no responses were received Aug 27 14:24:58 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting vr1. Aug 27 14:24:58 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 192.168.100.10) (interface: wan) (real interface: vr1). Aug 27 14:24:58 cerberus.localdomain php: rc.newwanip: ROUTING: setting default route to 192.168.100.1 Aug 27 14:24:58 cerberus.localdomain dhcp6c[20345]: client6_timo: no responses were received Aug 27 14:24:58 cerberus.localdomain dhcp6c[20345]: check_exit: exiting Aug 27 14:24:59 cerberus.localdomain apinger: Starting Alarm Pinger, apinger(39118) Aug 27 14:24:59 cerberus.localdomain apinger: No usable targets found, exiting Aug 27 14:25:06 cerberus.localdomain php: rc.newwanip: Resyncing OpenVPN instances for interface WAN. Aug 27 14:25:06 cerberus.localdomain kernel: ovpns1: link state changed to DOWN Aug 27 14:25:06 cerberus.localdomain check_reload_status: Reloading filter Aug 27 14:25:07 cerberus.localdomain kernel: ovpns1: link state changed to UP Aug 27 14:25:07 cerberus.localdomain kernel: ovpns2: link state changed to UP Aug 27 14:25:07 cerberus.localdomain check_reload_status: rc.newwanip starting ovpns1 Aug 27 14:25:07 cerberus.localdomain check_reload_status: rc.newwanip starting ovpns2 Aug 27 14:25:07 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Aug 27 14:25:09 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change 67.x.y.z -> 192.168.100.10 ... Restarting packages. Aug 27 14:25:09 cerberus.localdomain check_reload_status: Starting packages Aug 27 14:25:10 cerberus.localdomain dnsmasq[34026]: reading /etc/resolv.conf Aug 27 14:25:10 cerberus.localdomain dnsmasq[34026]: using nameserver 2001:558:feed::2#53 Aug 27 14:25:10 cerberus.localdomain dnsmasq[34026]: using nameserver 2001:558:feed::1#53 Aug 27 14:25:10 cerberus.localdomain dnsmasq[34026]: ignoring nameserver 127.0.0.1 - local interface Aug 27 14:25:17 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting ovpns1. Aug 27 14:25:17 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 172.29.251.1) (interface: ) (real interface: ovpns1). Aug 27 14:25:17 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting ovpns2. Aug 27 14:25:17 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 172.29.249.1) (interface: ) (real interface: ovpns2). Aug 27 14:25:17 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change -> 172.29.251.1 ... Restarting packages. Aug 27 14:25:17 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change -> 172.29.249.1 ... Restarting packages. Aug 27 14:25:20 cerberus.localdomain php: rc.start_packages: Restarting/Starting all packages. Aug 27 14:25:21 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:25:21 cerberus.localdomain dhclient[28258]: DHCPACK from 192.168.100.1 Aug 27 14:25:21 cerberus.localdomain dhclient: RENEW Aug 27 14:25:21 cerberus.localdomain dhclient: Creating resolv.conf Aug 27 14:25:21 cerberus.localdomain dhclient[28258]: bound to 192.168.100.10 -- renewal in 30 seconds. Aug 27 14:25:24 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Aug 27 14:25:28 cerberus.localdomain php: rc.start_packages: Restarting/Starting all packages. Aug 27 14:25:28 cerberus.localdomain kernel: pid 59109 (php), uid 0, was killed: out of swap space Aug 27 14:25:31 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Aug 27 14:25:35 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Aug 27 14:25:51 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:25:51 cerberus.localdomain dhclient[28258]: DHCPACK from 192.168.100.1 Aug 27 14:25:51 cerberus.localdomain dhclient: RENEW Aug 27 14:25:51 cerberus.localdomain dhclient: Creating resolv.conf Aug 27 14:25:51 cerberus.localdomain dhclient[28258]: bound to 192.168.100.10 -- renewal in 30 seconds. Aug 27 14:26:21 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:26:21 cerberus.localdomain dhclient[28258]: DHCPACK from 192.168.100.1 Aug 27 14:26:21 cerberus.localdomain dhclient: RENEW Aug 27 14:26:21 cerberus.localdomain dhclient: Creating resolv.conf Aug 27 14:26:21 cerberus.localdomain dhclient[28258]: bound to 192.168.100.10 -- renewal in 30 seconds. Aug 27 14:26:34 cerberus.localdomain kernel: cannot forward src fe80:1::96de:80ff:fe76:b365, dst 2601:9:pfsense-WAN::, nxt 58, rcvif vr0, outif vr1 Aug 27 14:26:51 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:26:51 cerberus.localdomain dhclient[28258]: DHCPACK from 192.168.100.1 Aug 27 14:26:51 cerberus.localdomain dhclient: RENEW Aug 27 14:26:51 cerberus.localdomain dhclient: Creating resolv.conf Aug 27 14:26:51 cerberus.localdomain dhclient[28258]: bound to 192.168.100.10 -- renewal in 30 seconds. Aug 27 14:27:21 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:27:21 cerberus.localdomain dhclient[28258]: DHCPACK from 192.168.100.1 Aug 27 14:27:21 cerberus.localdomain dhclient: RENEW Aug 27 14:27:21 cerberus.localdomain dhclient: Creating resolv.conf Aug 27 14:27:21 cerberus.localdomain dhclient[28258]: bound to 192.168.100.10 -- renewal in 30 seconds. Aug 27 14:27:51 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:27:51 cerberus.localdomain dhclient[28258]: DHCPACK from 192.168.100.1 Aug 27 14:27:51 cerberus.localdomain dhclient: RENEW Aug 27 14:27:51 cerberus.localdomain dhclient: Creating resolv.conf Aug 27 14:27:51 cerberus.localdomain dhclient[28258]: bound to 192.168.100.10 -- renewal in 30 seconds. Aug 27 14:28:21 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:28:21 cerberus.localdomain dhclient[28258]: DHCPACK from 192.168.100.1 Aug 27 14:28:21 cerberus.localdomain dhclient: RENEW Aug 27 14:28:21 cerberus.localdomain dhclient: Creating resolv.conf Aug 27 14:28:21 cerberus.localdomain dhclient[28258]: bound to 192.168.100.10 -- renewal in 30 seconds. Aug 27 14:28:51 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:28:52 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:28:54 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:28:59 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:29:04 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:29:11 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Aug 27 14:29:19 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Aug 27 14:29:20 cerberus.localdomain dhclient[28258]: DHCPNAK from 96.120.88.245 Aug 27 14:29:20 cerberus.localdomain dhclient[28258]: DHCPDISCOVER on vr1 to 255.255.255.255 port 67 interval 2 Aug 27 14:29:20 cerberus.localdomain dhclient[28258]: DHCPOFFER from 96.120.88.245 Aug 27 14:29:20 cerberus.localdomain dhclient: ARPSEND Aug 27 14:29:22 cerberus.localdomain dhclient: ARPCHECK Aug 27 14:29:22 cerberus.localdomain dhclient[28258]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Aug 27 14:29:22 cerberus.localdomain dhclient[28258]: DHCPACK from 96.120.88.245 Aug 27 14:29:22 cerberus.localdomain dhclient: BOUND Aug 27 14:29:22 cerberus.localdomain dhclient: Starting delete_old_states() Aug 27 14:29:22 cerberus.localdomain dhclient: Comparing IPs: Old: New: 67.x.y.z Aug 27 14:29:22 cerberus.localdomain dhclient: Starting add_new_address() Aug 27 14:29:22 cerberus.localdomain dhclient: ifconfig vr1 inet 67.x.y.z netmask 255.255.252.0 broadcast 255.255.255.255 Aug 27 14:29:22 cerberus.localdomain dhclient: New IP Address (vr1): 67.x.y.z Aug 27 14:29:22 cerberus.localdomain dhclient: New Subnet Mask (vr1): 255.255.252.0 Aug 27 14:29:22 cerberus.localdomain dhclient: New Broadcast Address (vr1): 255.255.255.255 Aug 27 14:29:22 cerberus.localdomain dhclient: New Routers (vr1): 67.x.y.1 Aug 27 14:29:22 cerberus.localdomain dhclient: Adding new routes to interface: vr1 Aug 27 14:29:22 cerberus.localdomain dhclient: /sbin/route add default 67.x.y.1 Aug 27 14:29:22 cerberus.localdomain dhclient: Creating resolv.conf Aug 27 14:29:22 cerberus.localdomain check_reload_status: rc.newwanip starting vr1 Aug 27 14:29:23 cerberus.localdomain dhclient[28258]: bound to 67.x.y.z -- renewal in 161405 seconds. Aug 27 14:29:27 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting vr1. Aug 27 14:29:27 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 67.x.y.z) (interface: wan) (real interface: vr1). Aug 27 14:29:27 cerberus.localdomain php: rc.newwanip: ROUTING: setting default route to 67.x.y.1 Aug 27 14:29:28 cerberus.localdomain apinger: Starting Alarm Pinger, apinger(15848) Aug 27 14:29:28 cerberus.localdomain apinger: No usable targets found, exiting Aug 27 14:29:33 cerberus.localdomain dnsmasq[34026]: reading /etc/resolv.conf Aug 27 14:29:33 cerberus.localdomain dnsmasq[34026]: using nameserver 75.75.76.76#53 Aug 27 14:29:33 cerberus.localdomain dnsmasq[34026]: using nameserver 75.75.75.75#53 Aug 27 14:29:33 cerberus.localdomain dnsmasq[34026]: using nameserver 2001:558:feed::2#53 Aug 27 14:29:33 cerberus.localdomain dnsmasq[34026]: using nameserver 2001:558:feed::1#53 Aug 27 14:29:33 cerberus.localdomain dnsmasq[34026]: ignoring nameserver 127.0.0.1 - local interface Aug 27 14:29:34 cerberus.localdomain php: rc.newwanip: Resyncing OpenVPN instances for interface WAN. Aug 27 14:29:34 cerberus.localdomain kernel: ovpns1: link state changed to DOWN Aug 27 14:29:34 cerberus.localdomain check_reload_status: Reloading filter Aug 27 14:29:35 cerberus.localdomain kernel: ovpns2: link state changed to DOWN Aug 27 14:29:35 cerberus.localdomain kernel: ovpns1: link state changed to UP Aug 27 14:29:35 cerberus.localdomain check_reload_status: rc.newwanip starting ovpns1 Aug 27 14:29:35 cerberus.localdomain kernel: ovpns2: link state changed to UP Aug 27 14:29:35 cerberus.localdomain check_reload_status: rc.newwanip starting ovpns2 Aug 27 14:29:37 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change 192.168.100.10 -> 67.x.y.z ... Restarting packages. Aug 27 14:29:37 cerberus.localdomain check_reload_status: Starting packages Aug 27 14:29:44 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting ovpns1. Aug 27 14:29:44 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 172.29.251.1) (interface: ) (real interface: ovpns1). Aug 27 14:29:44 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting ovpns2. Aug 27 14:29:44 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 172.29.249.1) (interface: ) (real interface: ovpns2). Aug 27 14:29:44 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change -> 172.29.251.1 ... Restarting packages. Aug 27 14:29:44 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change -> 172.29.249.1 ... Restarting packages. Aug 27 14:29:47 cerberus.localdomain php: rc.start_packages: Restarting/Starting all packages. Aug 27 14:29:51 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Aug 27 14:29:55 cerberus.localdomain php: rc.start_packages: Restarting/Starting all packages. Aug 27 14:29:59 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Aug 27 14:30:03 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan).
-
Ohhhhh, I see now. I had made comments in the bug and forums that I am now retaining IPv6 addressing after a cable modem reboot. Now I see the difference from my setup and what is being discussed here.
My pfSense installation is running in an ESXi VM. So, when the CM reboots, apinger declares the gateway down, but I don't see an actual interface link loss.
It used to be that if the gateway was down, IPv6 addressing would be lost, radvd killed and never recover without a reboot. That has been fixed.
However, the link loss handled by rc.link up is apparently still broken.
Hope that clarifies it for Ermal and team.
-
Unfortunately I'm not that familiar with PHP. I was looking around to try and figure out why this keeps happening (other than my modem is rebooting). The file services.inc has the string "Shutting down Router Advertisment daemon cleanly". It looks like the function services_radvd_configure gets some sort of config array for dhcpdv6. So long as there are greater than 0 elements in this new array it'll simply restart RADVD. Otherwise it shuts it down cleanly. Based on the logfile, it would seem that there should be 0 elements because the modem only provides the 192.168.100.0/24 subnet with no IPv6. Thus, it seems like it is correct in shutting down RADVD during this period.
However, when normal connectivity is restored, what is supposed to (re)start RADVD?
-
It is normal to get the radvd service shutdown when your ipv6 lease expires.
Normally when the modem hands over a temporary ipv4 lease it should handover even a v6 lease.Not sure how you can build a reliable configuration process for v6 if the modem does not treat it as first class?
-
I don't think it would be normal for a modem to hand out a v6 lease in this case. Essentially it is disconnected from the cable company during this period. What v6 lease would it have to hand out? I strongly suspect all cable modems do this.
During bootup, pfsense assumes v6 is around and so initiates DHCP6 or whatever it's configured for*. The issue is when the WAN link goes down and pfsense is introduced to a v4-only network. Pfsense rightly turns off v6 stuff. But when the network changes again to a v4/v6 network, pfsense needs to assume that v6 might once again be available and go about it's usual v6 configuration setup. Or somehow detect that v6 is available again.
- I assume this is what pfsense does
-
As I had mentioned earlier, I think given the reality of how IPv6 is deployed to consumers through cable today, pfSense should not assume that IPv6 connectivity can only change at boot time and when the physical WAN link goes up/down (which I think is what it's doing now?); at the very least, it should assume that IPv6 connectivity can also (re-)appear anytime the IPv4 lease on the LAN expires.
-
@ermal:
Normally when the modem hands over a temporary ipv4 lease it should handover even a v6 lease.
I'm not aware of any DOCSIS 3.0 cable modem that would do this.
-
I put a fix and should be on new snapshots.
Test those out and it should fix what you are seeing.
Especially you should have get the v6 information from v4 setting ticket on. -
@ermal:
Especially you should have get the v6 information from v4 setting ticket on.
Is this safe to do for a cable modem setup (IPv4 configuration type = DHCP, IPv6 configuration type = DHCP6)? What exactly does this option actually do anyway? The description is not particularly helpful in figuring out under which circumstances this setting should be enabled, and from reading the board I just have vague memories of it being somehow related to PPPoE (which I don't think is typically used with cable modems).
-
Especially you should have get the v6 information from v4 setting ticket on
I wasn't sure what was meant for this so I tried the Interface -> WAN setting that says
Request a IPv6 prefix/information through the IPv4 connectivity link
This completely prevented pfsense from getting IPv6 connectivity.
The fix didn't seem to work as power cycling the modem still caused a loss of IPv6 connectivity until some user intervention occurred.
The version is 2.1-RC2 (i386) built on Thu Sep 5 09:59:12 EDT 2013.
-
Especially you should have get the v6 information from v4 setting ticket on
I wasn't sure what was meant for this so I tried the Interface -> WAN setting that says
It should work for you with this option ticket on.
Since it will restart ipv6 anytime you get a new ipv4.Request a IPv6 prefix/information through the IPv4 connectivity link
This completely prevented pfsense from getting IPv6 connectivity.
The fix didn't seem to work as power cycling the modem still caused a loss of IPv6 connectivity until some user intervention occurred.
The version is 2.1-RC2 (i386) built on Thu Sep 5 09:59:12 EDT 2013.
Is not for you.
-
I upgraded to 2.1-RC2 (i386) built on Fri Sep 6 08:32:59 EDT 2013 FreeBSD 8.3-RELEASE-p10 but I still lost all IPv6 connectivity this morning when the modem rebooted randomly.
-
Can you provide system log for this and describe your setup?
-
The system is an Alix 2D3 board. I only use the WAN and LAN. DHCP and DHCP6-PD on the WAN. Direct connection between the modem and pfsense's WAN. No packages except the openvpn client export.
The log looks quite similar to the one I posted previously. I filtered out "pf:|ntp|openvpn|dhcpd|dnsmasq|arpresolve:" so if you want the logs that include those terms, let me know.
Sep 6 21:29:39 cerberus.localdomain apinger: ALARM: WAN_DHCP(67.x.y.1) *** down *** Sep 6 21:29:39 cerberus.localdomain apinger: ALARM: WAN_DHCP6(fe80::201:5cff:fe62:c446%vr1) *** down *** Sep 6 21:29:49 cerberus.localdomain check_reload_status: updating dyndns WAN_DHCP Sep 6 21:29:49 cerberus.localdomain check_reload_status: Restarting ipsec tunnels Sep 6 21:29:49 cerberus.localdomain check_reload_status: Restarting OpenVPN tunnels/interfaces Sep 6 21:29:49 cerberus.localdomain check_reload_status: Reloading filter Sep 6 21:29:49 cerberus.localdomain check_reload_status: updating dyndns WAN_DHCP6 Sep 6 21:29:49 cerberus.localdomain check_reload_status: Restarting OpenVPN tunnels/interfaces Sep 6 21:30:10 cerberus.localdomain check_reload_status: Linkup starting vr1 Sep 6 21:30:10 cerberus.localdomain kernel: vr1: link state changed to DOWN Sep 6 21:30:14 cerberus.localdomain php: rc.linkup: DEVD Ethernet detached event for wan Sep 6 21:30:15 cerberus.localdomain dhclient[9504]: connection closed Sep 6 21:30:15 cerberus.localdomain dhclient[9504]: exiting. Sep 6 21:30:19 cerberus.localdomain php: rc.linkup: Clearing states to old gateway 67.x.y.1. Sep 6 21:30:31 cerberus.localdomain kernel: cannot forward src fe80:1::96de:80ff:fe76:b365, dst 2601:9:7680:6fc:20d:b9ff:fe1c:4ad0, nxt 58, rcvif vr0, outif vr1 Sep 6 21:30:33 cerberus.localdomain check_reload_status: Linkup starting vr1 Sep 6 21:30:33 cerberus.localdomain kernel: vr1: link state changed to UP Sep 6 21:30:38 cerberus.localdomain php: rc.linkup: DEVD Ethernet attached event for wan Sep 6 21:30:38 cerberus.localdomain php: rc.linkup: HOTPLUG: Configuring interface wan Sep 6 21:30:38 cerberus.localdomain php: rc.linkup: The command '/sbin/ifconfig 'vr1' inet delete' returned exit code '1', the output was 'ifconfig: ioctl (SIOCDIFADDR): Can't assign requested address' Sep 6 21:30:38 cerberus.localdomain dhclient: PREINIT Sep 6 21:30:38 cerberus.localdomain dhclient: Starting delete_old_states() Sep 6 21:30:38 cerberus.localdomain dhclient: Comparing IPs: Old: New: Sep 6 21:30:38 cerberus.localdomain dhclient[91152]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Sep 6 21:30:38 cerberus.localdomain dhclient[91152]: DHCPNAK from 192.168.100.1 Sep 6 21:30:38 cerberus.localdomain dhclient[91152]: DHCPDISCOVER on vr1 to 255.255.255.255 port 67 interval 2 Sep 6 21:30:40 cerberus.localdomain dhclient[91152]: DHCPDISCOVER on vr1 to 255.255.255.255 port 67 interval 5 Sep 6 21:30:40 cerberus.localdomain kernel: ovpns2: link state changed to DOWN Sep 6 21:30:40 cerberus.localdomain check_reload_status: Reloading filter Sep 6 21:30:40 cerberus.localdomain dhclient[91152]: DHCPOFFER from 192.168.100.1 Sep 6 21:30:40 cerberus.localdomain dhclient: ARPSEND Sep 6 21:30:40 cerberus.localdomain dhclient[91152]: DHCPOFFER from 192.168.100.1 Sep 6 21:30:40 cerberus.localdomain dhclient[91152]: DHCPOFFER already seen. Sep 6 21:30:42 cerberus.localdomain dhclient: ARPCHECK Sep 6 21:30:42 cerberus.localdomain dhclient[91152]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Sep 6 21:30:42 cerberus.localdomain dhclient[91152]: DHCPACK from 192.168.100.1 Sep 6 21:30:42 cerberus.localdomain dhclient: BOUND Sep 6 21:30:42 cerberus.localdomain dhclient: Starting delete_old_states() Sep 6 21:30:42 cerberus.localdomain dhclient: Comparing IPs: Old: New: 192.168.100.10 Sep 6 21:30:42 cerberus.localdomain dhclient: Starting add_new_address() Sep 6 21:30:42 cerberus.localdomain dhclient: ifconfig vr1 inet 192.168.100.10 netmask 255.255.255.0 broadcast 192.168.100.255 Sep 6 21:30:42 cerberus.localdomain dhclient: New IP Address (vr1): 192.168.100.10 Sep 6 21:30:42 cerberus.localdomain dhclient: New Subnet Mask (vr1): 255.255.255.0 Sep 6 21:30:42 cerberus.localdomain dhclient: New Broadcast Address (vr1): 192.168.100.255 Sep 6 21:30:42 cerberus.localdomain dhclient: New Routers (vr1): 192.168.100.1 Sep 6 21:30:42 cerberus.localdomain dhclient: Adding new routes to interface: vr1 Sep 6 21:30:42 cerberus.localdomain dhclient: /sbin/route add default 192.168.100.1 Sep 6 21:30:42 cerberus.localdomain dhclient: Creating resolv.conf Sep 6 21:30:42 cerberus.localdomain dhclient[91152]: bound to 192.168.100.10 -- renewal in 30 seconds. Sep 6 21:30:42 cerberus.localdomain php: rc.linkup: Accept router advertisements on interface vr1 Sep 6 21:30:42 cerberus.localdomain check_reload_status: rc.newwanip starting vr1 Sep 6 21:30:42 cerberus.localdomain php: rc.linkup: ROUTING: setting default route to 192.168.100.1 Sep 6 21:30:43 cerberus.localdomain dhcp6c[19206]: client6_timo: no responses were received Sep 6 21:30:45 cerberus.localdomain dhcp6c[19206]: client6_timo: no responses were received Sep 6 21:30:45 cerberus.localdomain dhcp6c[19206]: check_exit: exiting Sep 6 21:30:48 cerberus.localdomain php: rc.linkup: Shutting down Router Advertisment daemon cleanly Sep 6 21:30:48 cerberus.localdomain radvd[32292]: Exiting, sigterm or sigint received. Sep 6 21:30:48 cerberus.localdomain radvd[32292]: sending stop adverts Sep 6 21:30:48 cerberus.localdomain radvd[32292]: removing /var/run/radvd.pid Sep 6 21:30:48 cerberus.localdomain check_reload_status: updating dyndns wan Sep 6 21:30:49 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting vr1. Sep 6 21:30:49 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 192.168.100.10) (interface: wan) (real interface: vr1). Sep 6 21:30:49 cerberus.localdomain php: rc.newwanip: ROUTING: setting default route to 192.168.100.1 Sep 6 21:30:49 cerberus.localdomain apinger: SIGHUP received, reloading configuration. Sep 6 21:30:49 cerberus.localdomain apinger: alarm canceled (config reload): WAN_DHCP(67.x.y.1) *** down *** Sep 6 21:30:49 cerberus.localdomain apinger: alarm canceled (config reload): WAN_DHCP6(fe80::201:5cff:fe62:c446%vr1) *** down *** Sep 6 21:30:52 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Sep 6 21:30:54 cerberus.localdomain php: rc.newwanip: Resyncing OpenVPN instances for interface WAN. Sep 6 21:30:54 cerberus.localdomain kernel: ovpns1: link state changed to DOWN Sep 6 21:30:54 cerberus.localdomain check_reload_status: Reloading filter Sep 6 21:30:55 cerberus.localdomain kernel: ovpns1: link state changed to UP Sep 6 21:30:55 cerberus.localdomain kernel: ovpns2: link state changed to UP Sep 6 21:30:55 cerberus.localdomain check_reload_status: rc.newwanip starting ovpns1 Sep 6 21:30:55 cerberus.localdomain check_reload_status: rc.newwanip starting ovpns2 Sep 6 21:30:57 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change 67.x.y.z -> 192.168.100.10 ... Restarting packages. Sep 6 21:30:57 cerberus.localdomain check_reload_status: Starting packages Sep 6 21:31:03 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting ovpns2. Sep 6 21:31:03 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 172.29.249.1) (interface: ) (real interface: ovpns2). Sep 6 21:31:03 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting ovpns1. Sep 6 21:31:03 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change -> 172.29.249.1 ... Restarting packages. Sep 6 21:31:03 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 172.29.251.1) (interface: ) (real interface: ovpns1). Sep 6 21:31:03 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change -> 172.29.251.1 ... Restarting packages. Sep 6 21:31:06 cerberus.localdomain php: rc.start_packages: Restarting/Starting all packages. Sep 6 21:31:10 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Sep 6 21:31:12 cerberus.localdomain dhclient[99092]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Sep 6 21:31:13 cerberus.localdomain dhclient[99092]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Sep 6 21:31:14 cerberus.localdomain php: rc.start_packages: Restarting/Starting all packages. Sep 6 21:31:14 cerberus.localdomain dhclient[99092]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Sep 6 21:31:16 cerberus.localdomain dhclient[99092]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Sep 6 21:31:16 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Sep 6 21:31:18 cerberus.localdomain dhclient[99092]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Sep 6 21:31:20 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Sep 6 21:31:23 cerberus.localdomain dhclient[99092]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Sep 6 21:31:36 cerberus.localdomain dhclient[99092]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Sep 6 21:31:36 cerberus.localdomain dhclient[99092]: DHCPNAK from 96.120.88.245 Sep 6 21:31:36 cerberus.localdomain dhclient[99092]: DHCPDISCOVER on vr1 to 255.255.255.255 port 67 interval 1 Sep 6 21:31:36 cerberus.localdomain dhclient[99092]: DHCPOFFER from 96.120.88.245 Sep 6 21:31:36 cerberus.localdomain dhclient: ARPSEND Sep 6 21:31:38 cerberus.localdomain dhclient: ARPCHECK Sep 6 21:31:38 cerberus.localdomain dhclient[99092]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Sep 6 21:31:38 cerberus.localdomain dhclient[99092]: DHCPACK from 96.120.88.245 Sep 6 21:31:38 cerberus.localdomain dhclient: BOUND Sep 6 21:31:38 cerberus.localdomain dhclient: Starting delete_old_states() Sep 6 21:31:38 cerberus.localdomain dhclient: Comparing IPs: Old: New: 67.x.y.z Sep 6 21:31:38 cerberus.localdomain dhclient: Starting add_new_address() Sep 6 21:31:38 cerberus.localdomain dhclient: ifconfig vr1 inet 67.x.y.z netmask 255.255.252.0 broadcast 255.255.255.255 Sep 6 21:31:38 cerberus.localdomain dhclient: New IP Address (vr1): 67.x.y.z Sep 6 21:31:38 cerberus.localdomain dhclient: New Subnet Mask (vr1): 255.255.252.0 Sep 6 21:31:38 cerberus.localdomain dhclient: New Broadcast Address (vr1): 255.255.255.255 Sep 6 21:31:38 cerberus.localdomain dhclient: New Routers (vr1): 67.x.y.1 Sep 6 21:31:38 cerberus.localdomain dhclient: Adding new routes to interface: vr1 Sep 6 21:31:38 cerberus.localdomain dhclient: /sbin/route add default 67.x.y.1 Sep 6 21:31:38 cerberus.localdomain dhclient: Creating resolv.conf Sep 6 21:31:38 cerberus.localdomain check_reload_status: rc.newwanip starting vr1 Sep 6 21:31:39 cerberus.localdomain dhclient[99092]: bound to 67.x.y.z -- renewal in 172800 seconds. Sep 6 21:31:43 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting vr1. Sep 6 21:31:43 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 67.x.y.z) (interface: wan) (real interface: vr1). Sep 6 21:31:43 cerberus.localdomain php: rc.newwanip: ROUTING: setting default route to 67.x.y.1 Sep 6 21:31:43 cerberus.localdomain apinger: SIGHUP received, reloading configuration. Sep 6 21:31:48 cerberus.localdomain php: rc.newwanip: Resyncing OpenVPN instances for interface WAN. Sep 6 21:31:48 cerberus.localdomain kernel: ovpns1: link state changed to DOWN Sep 6 21:31:48 cerberus.localdomain check_reload_status: Reloading filter Sep 6 21:31:49 cerberus.localdomain kernel: ovpns1: link state changed to UP Sep 6 21:31:49 cerberus.localdomain kernel: ovpns2: link state changed to DOWN Sep 6 21:31:49 cerberus.localdomain check_reload_status: rc.newwanip starting ovpns1 Sep 6 21:31:49 cerberus.localdomain kernel: ovpns2: link state changed to UP Sep 6 21:31:49 cerberus.localdomain check_reload_status: rc.newwanip starting ovpns2 Sep 6 21:31:51 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change 192.168.100.10 -> 67.x.y.z ... Restarting packages. Sep 6 21:31:51 cerberus.localdomain check_reload_status: Starting packages Sep 6 21:31:56 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting ovpns1. Sep 6 21:31:56 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 172.29.251.1) (interface: ) (real interface: ovpns1). Sep 6 21:31:57 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change -> 172.29.251.1 ... Restarting packages. Sep 6 21:31:57 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting ovpns2. Sep 6 21:31:57 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 172.29.249.1) (interface: ) (real interface: ovpns2). Sep 6 21:31:57 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change -> 172.29.249.1 ... Restarting packages. Sep 6 21:32:00 cerberus.localdomain php: rc.start_packages: Restarting/Starting all packages. Sep 6 21:32:04 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Sep 6 21:32:07 cerberus.localdomain php: rc.start_packages: Restarting/Starting all packages. Sep 6 21:32:10 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Sep 6 21:32:14 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan).
-
Can you sync to 2.1-RELEASE code and retry and also re-post the system log?
I need to see an event fired that is not logged at the moment.
-
Did a gitsync with pfSsh.php playback gitsync RELENG_2_1
Rebooted system.
Unplugged power to modem. Plugged it back in. IPv6 did not return.Here's the system log. This time I only filtered out "pf:|ntpd|openvpn"
Sep 10 20:49:11 cerberus.localdomain kernel: vr1: link state changed to DOWN Sep 10 20:49:15 cerberus.localdomain php: rc.linkup: DEVD Ethernet detached event for wan Sep 10 20:49:15 cerberus.localdomain dhclient[9345]: connection closed Sep 10 20:49:15 cerberus.localdomain dhclient[9345]: exiting. Sep 10 20:49:17 cerberus.localdomain kernel: arpresolve: can't allocate llinfo for 67.x.y.1 Sep 10 20:49:19 cerberus.localdomain php: rc.linkup: Clearing states to old gateway 67.x.y.1. Sep 10 20:49:19 cerberus.localdomain apinger: ALARM: WAN_DHCP(67.x.y.1) *** down *** Sep 10 20:49:19 cerberus.localdomain apinger: ALARM: WAN_DHCP6(fe80::201:5cff:fe62:c446%vr1) *** down *** Sep 10 20:49:29 cerberus.localdomain check_reload_status: updating dyndns WAN_DHCP Sep 10 20:49:29 cerberus.localdomain check_reload_status: Restarting ipsec tunnels Sep 10 20:49:29 cerberus.localdomain check_reload_status: Restarting OpenVPN tunnels/interfaces Sep 10 20:49:29 cerberus.localdomain check_reload_status: Reloading filter Sep 10 20:49:29 cerberus.localdomain check_reload_status: updating dyndns WAN_DHCP6 Sep 10 20:49:29 cerberus.localdomain check_reload_status: Restarting OpenVPN tunnels/interfaces Sep 10 20:49:34 cerberus.localdomain kernel: vr1: link state changed to UP Sep 10 20:49:34 cerberus.localdomain check_reload_status: Linkup starting vr1 Sep 10 20:49:36 cerberus.localdomain kernel: vr1: link state changed to DOWN Sep 10 20:49:36 cerberus.localdomain check_reload_status: Linkup starting vr1 Sep 10 20:49:38 cerberus.localdomain kernel: vr1: link state changed to UP Sep 10 20:49:38 cerberus.localdomain check_reload_status: Linkup starting vr1 Sep 10 20:49:42 cerberus.localdomain dhcp6c[20389]: client6_timo: no responses were received Sep 10 20:49:44 cerberus.localdomain php: rc.linkup: DEVD Ethernet attached event for wan Sep 10 20:49:44 cerberus.localdomain php: rc.linkup: HOTPLUG: Configuring interface wan Sep 10 20:49:45 cerberus.localdomain php: rc.linkup: DEVD Ethernet detached event for wan Sep 10 20:49:45 cerberus.localdomain php: rc.linkup: The command '/sbin/ifconfig 'vr1' inet delete' returned exit code '1', the output was 'ifconfig: ioctl (SIOCDIFADDR): Can't assign requested address' Sep 10 20:49:45 cerberus.localdomain dhclient: PREINIT Sep 10 20:49:45 cerberus.localdomain php: rc.linkup: DEVD Ethernet attached event for wan Sep 10 20:49:45 cerberus.localdomain php: rc.linkup: HOTPLUG: Configuring interface wan Sep 10 20:49:45 cerberus.localdomain dhclient: Starting delete_old_states() Sep 10 20:49:46 cerberus.localdomain dhclient: Comparing IPs: Old: New: Sep 10 20:49:46 cerberus.localdomain dhclient[84541]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Sep 10 20:49:46 cerberus.localdomain dhclient[84541]: DHCPNAK from 192.168.100.1 Sep 10 20:49:46 cerberus.localdomain dhclient[84541]: DHCPDISCOVER on vr1 to 255.255.255.255 port 67 interval 1 Sep 10 20:49:46 cerberus.localdomain dhcp6c[20389]: client6_timo: no responses were received Sep 10 20:49:46 cerberus.localdomain dhcp6c[20389]: check_exit: exiting Sep 10 20:49:46 cerberus.localdomain php: rc.linkup: The command '/sbin/ifconfig 'vr1' inet delete' returned exit code '1', the output was 'ifconfig: ioctl (SIOCDIFADDR): Can't assign requested address' Sep 10 20:49:46 cerberus.localdomain dhclient[86700]: dhclient already running, pid: 84541. Sep 10 20:49:46 cerberus.localdomain dhclient[86700]: exiting. Sep 10 20:49:46 cerberus.localdomain php: rc.linkup: The command '/sbin/dhclient -c /var/etc/dhclient_wan.conf vr1 > /tmp/vr1_output 2> /tmp/vr1_error_output' returned exit code '1', the output was '' Sep 10 20:49:46 cerberus.localdomain php: rc.linkup: Accept router advertisements on interface vr1 Sep 10 20:49:47 cerberus.localdomain dhclient[84541]: DHCPDISCOVER on vr1 to 255.255.255.255 port 67 interval 2 Sep 10 20:49:47 cerberus.localdomain dhclient[84541]: DHCPOFFER from 192.168.100.1 Sep 10 20:49:47 cerberus.localdomain dhclient: ARPSEND Sep 10 20:49:47 cerberus.localdomain dhclient[84541]: DHCPOFFER from 192.168.100.1 Sep 10 20:49:47 cerberus.localdomain dhclient[84541]: DHCPOFFER already seen. Sep 10 20:49:48 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:48 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Sep 10 20:49:48 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:49 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:49 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:49 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:49 cerberus.localdomain dhclient: ARPCHECK Sep 10 20:49:49 cerberus.localdomain dhclient[84541]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Sep 10 20:49:49 cerberus.localdomain dhclient[84541]: DHCPACK from 192.168.100.1 Sep 10 20:49:49 cerberus.localdomain dhclient: BOUND Sep 10 20:49:49 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:49 cerberus.localdomain dhclient: Starting delete_old_states() Sep 10 20:49:49 cerberus.localdomain dhclient: Comparing IPs: Old: New: 192.168.100.10 Sep 10 20:49:49 cerberus.localdomain dhclient: Starting add_new_address() Sep 10 20:49:49 cerberus.localdomain dhclient: ifconfig vr1 inet 192.168.100.10 netmask 255.255.255.0 broadcast 192.168.100.255 Sep 10 20:49:49 cerberus.localdomain dhclient: New IP Address (vr1): 192.168.100.10 Sep 10 20:49:49 cerberus.localdomain dhclient: New Subnet Mask (vr1): 255.255.255.0 Sep 10 20:49:49 cerberus.localdomain dhclient: New Broadcast Address (vr1): 192.168.100.255 Sep 10 20:49:49 cerberus.localdomain dhclient: New Routers (vr1): 192.168.100.1 Sep 10 20:49:49 cerberus.localdomain dhclient: Adding new routes to interface: vr1 Sep 10 20:49:49 cerberus.localdomain php: rc.linkup: Clearing states to old gateway 192.168.100.1. Sep 10 20:49:49 cerberus.localdomain dhclient: /sbin/route add default 192.168.100.1 Sep 10 20:49:49 cerberus.localdomain dhclient: Creating resolv.conf Sep 10 20:49:49 cerberus.localdomain check_reload_status: rc.newwanip starting vr1 Sep 10 20:49:49 cerberus.localdomain dhclient[84541]: bound to 192.168.100.10 -- renewal in 30 seconds. Sep 10 20:49:49 cerberus.localdomain php: rc.linkup: Accept router advertisements on interface vr1 Sep 10 20:49:49 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:49 cerberus.localdomain dnsmasq[36693]: exiting on receipt of SIGTERM Sep 10 20:49:49 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:50 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:50 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:50 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:50 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:50 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:50 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:50 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:50 cerberus.localdomain dnsmasq[97041]: started, version 2.66 cachesize 10000 Sep 10 20:49:50 cerberus.localdomain dnsmasq[97041]: compile time options: IPv6 GNU-getopt no-DBus i18n IDN DHCP DHCPv6 no-Lua TFTP no-conntrack no-ipset auth Sep 10 20:49:51 cerberus.localdomain dnsmasq[97041]: reading /etc/resolv.conf Sep 10 20:49:51 cerberus.localdomain dnsmasq[97041]: using nameserver 75.75.76.76#53 Sep 10 20:49:51 cerberus.localdomain dnsmasq[97041]: using nameserver 75.75.75.75#53 Sep 10 20:49:51 cerberus.localdomain dnsmasq[97041]: using nameserver 2001:558:feed::2#53 Sep 10 20:49:51 cerberus.localdomain dnsmasq[97041]: using nameserver 2001:558:feed::1#53 Sep 10 20:49:51 cerberus.localdomain dnsmasq[97041]: ignoring nameserver 127.0.0.1 - local interface Sep 10 20:49:51 cerberus.localdomain dnsmasq[97041]: read /etc/hosts - 2 addresses Sep 10 20:49:51 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:51 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:49:52 cerberus.localdomain dnsmasq[97041]: exiting on receipt of SIGTERM Sep 10 20:49:52 cerberus.localdomain dhcpd: Internet Systems Consortium DHCP Server 4.2.5-P1 Sep 10 20:49:52 cerberus.localdomain dhcpd: Copyright 2004-2013 Internet Systems Consortium. Sep 10 20:49:52 cerberus.localdomain dhcpd: All rights reserved. Sep 10 20:49:52 cerberus.localdomain dhcpd: For info, please visit https://www.isc.org/software/dhcp/ Sep 10 20:49:52 cerberus.localdomain dhcpd: Internet Systems Consortium DHCP Server 4.2.5-P1 Sep 10 20:49:52 cerberus.localdomain dhcpd: Copyright 2004-2013 Internet Systems Consortium. Sep 10 20:49:52 cerberus.localdomain dhcpd: All rights reserved. Sep 10 20:49:52 cerberus.localdomain dhcpd: For info, please visit https://www.isc.org/software/dhcp/ Sep 10 20:49:52 cerberus.localdomain dhcpd: Wrote 0 deleted host decls to leases file. Sep 10 20:49:52 cerberus.localdomain dhcpd: Wrote 0 new dynamic host decls to leases file. Sep 10 20:49:52 cerberus.localdomain dhcpd: Wrote 3 leases to leases file. Sep 10 20:49:53 cerberus.localdomain dhcpd: Listening on BPF/vr0/00:0d:b9:1c:4a:d0/192.168.112.0/24 Sep 10 20:49:53 cerberus.localdomain dhcpd: Sending on BPF/vr0/00:0d:b9:1c:4a:d0/192.168.112.0/24 Sep 10 20:49:53 cerberus.localdomain dhcpd: Sending on Socket/fallback/fallback-net Sep 10 20:49:53 cerberus.localdomain dnsmasq[6118]: started, version 2.66 cachesize 10000 Sep 10 20:49:53 cerberus.localdomain dnsmasq[6118]: compile time options: IPv6 GNU-getopt no-DBus i18n IDN DHCP DHCPv6 no-Lua TFTP no-conntrack no-ipset auth Sep 10 20:49:53 cerberus.localdomain dnsmasq[6118]: reading /etc/resolv.conf Sep 10 20:49:53 cerberus.localdomain dnsmasq[6118]: using nameserver 75.75.76.76#53 Sep 10 20:49:53 cerberus.localdomain dnsmasq[6118]: using nameserver 75.75.75.75#53 Sep 10 20:49:53 cerberus.localdomain dnsmasq[6118]: using nameserver 2001:558:feed::2#53 Sep 10 20:49:53 cerberus.localdomain dnsmasq[6118]: using nameserver 2001:558:feed::1#53 Sep 10 20:49:53 cerberus.localdomain dnsmasq[6118]: ignoring nameserver 127.0.0.1 - local interface Sep 10 20:49:53 cerberus.localdomain dnsmasq[6118]: read /etc/hosts - 2 addresses Sep 10 20:49:54 cerberus.localdomain php: rc.linkup: Shutting down Router Advertisment daemon cleanly Sep 10 20:49:54 cerberus.localdomain radvd[32891]: Exiting, sigterm or sigint received. Sep 10 20:49:54 cerberus.localdomain radvd[32891]: sending stop adverts Sep 10 20:49:54 cerberus.localdomain radvd[32891]: removing /var/run/radvd.pid Sep 10 20:49:54 cerberus.localdomain check_reload_status: updating dyndns wan Sep 10 20:49:54 cerberus.localdomain dhcpd: Internet Systems Consortium DHCP Server 4.2.5-P1 Sep 10 20:49:54 cerberus.localdomain dhcpd: Copyright 2004-2013 Internet Systems Consortium. Sep 10 20:49:54 cerberus.localdomain dhcpd: All rights reserved. Sep 10 20:49:54 cerberus.localdomain dhcpd: For info, please visit https://www.isc.org/software/dhcp/ Sep 10 20:49:54 cerberus.localdomain dhcpd: Internet Systems Consortium DHCP Server 4.2.5-P1 Sep 10 20:49:54 cerberus.localdomain dhcpd: Copyright 2004-2013 Internet Systems Consortium. Sep 10 20:49:54 cerberus.localdomain dhcpd: All rights reserved. Sep 10 20:49:54 cerberus.localdomain dhcpd: For info, please visit https://www.isc.org/software/dhcp/ Sep 10 20:49:54 cerberus.localdomain dhcpd: Wrote 0 deleted host decls to leases file. Sep 10 20:49:54 cerberus.localdomain dhcpd: Wrote 0 new dynamic host decls to leases file. Sep 10 20:49:54 cerberus.localdomain dhcpd: Wrote 3 leases to leases file. Sep 10 20:49:54 cerberus.localdomain dhcpd: Listening on BPF/vr0/00:0d:b9:1c:4a:d0/192.168.112.0/24 Sep 10 20:49:54 cerberus.localdomain dhcpd: Sending on BPF/vr0/00:0d:b9:1c:4a:d0/192.168.112.0/24 Sep 10 20:49:54 cerberus.localdomain dhcpd: Sending on Socket/fallback/fallback-net Sep 10 20:49:55 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting vr1. Sep 10 20:49:55 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 192.168.100.10) (interface: wan) (real interface: vr1). Sep 10 20:49:55 cerberus.localdomain apinger: SIGHUP received, reloading configuration. Sep 10 20:49:55 cerberus.localdomain apinger: alarm canceled (config reload): WAN_DHCP(67.x.y.1) *** down *** Sep 10 20:49:55 cerberus.localdomain apinger: alarm canceled (config reload): WAN_DHCP6(fe80::201:5cff:fe62:c446%vr1) *** down *** Sep 10 20:49:55 cerberus.localdomain apinger: No usable targets found, exiting Sep 10 20:50:00 cerberus.localdomain php: rc.newwanip: Resyncing OpenVPN instances for interface WAN. Sep 10 20:50:02 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change 67.x.y.z -> 192.168.100.10 ... Restarting packages. Sep 10 20:50:03 cerberus.localdomain check_reload_status: Starting packages Sep 10 20:50:03 cerberus.localdomain check_reload_status: Reloading filter Sep 10 20:50:04 cerberus.localdomain dnsmasq[6118]: reading /etc/resolv.conf Sep 10 20:50:04 cerberus.localdomain dnsmasq[6118]: using nameserver 2001:558:feed::2#53 Sep 10 20:50:04 cerberus.localdomain dnsmasq[6118]: using nameserver 2001:558:feed::1#53 Sep 10 20:50:04 cerberus.localdomain dnsmasq[6118]: ignoring nameserver 127.0.0.1 - local interface Sep 10 20:50:09 cerberus.localdomain php: rc.start_packages: Restarting/Starting all packages. Sep 10 20:50:09 cerberus.localdomain php: rc.start_packages: XML error: SYSTEM or PUBLIC, the URI is missing at line 1 in /usr/local/pkg/git.xml Sep 10 20:50:09 cerberus.localdomain php: rc.start_packages: XML error: SYSTEM or PUBLIC, the URI is missing at line 1 in /usr/local/pkg/git.xml Sep 10 20:50:13 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:13 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan). Sep 10 20:50:13 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:13 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:13 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:13 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:13 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:13 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:13 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:14 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:14 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:14 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:14 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:14 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:14 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:14 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:14 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:14 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv4 gateway for interface (wan). Sep 10 20:50:19 cerberus.localdomain dhclient[94022]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Sep 10 20:50:21 cerberus.localdomain dhclient[94022]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Sep 10 20:50:23 cerberus.localdomain dhclient[94022]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Sep 10 20:50:27 cerberus.localdomain dhclient[94022]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Sep 10 20:50:36 cerberus.localdomain dhclient[94022]: DHCPREQUEST on vr1 to 192.168.100.1 port 67 Sep 10 20:50:45 cerberus.localdomain dhclient[94022]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Sep 10 20:50:45 cerberus.localdomain dhclient[94022]: DHCPNAK from 96.120.88.245 Sep 10 20:50:45 cerberus.localdomain dhclient[94022]: DHCPDISCOVER on vr1 to 255.255.255.255 port 67 interval 1 Sep 10 20:50:45 cerberus.localdomain dhclient[94022]: DHCPOFFER from 96.120.88.245 Sep 10 20:50:45 cerberus.localdomain dhclient: ARPSEND Sep 10 20:50:47 cerberus.localdomain dhclient: ARPCHECK Sep 10 20:50:47 cerberus.localdomain dhclient[94022]: DHCPREQUEST on vr1 to 255.255.255.255 port 67 Sep 10 20:50:47 cerberus.localdomain dhclient[94022]: DHCPACK from 96.120.88.245 Sep 10 20:50:47 cerberus.localdomain dhclient: BOUND Sep 10 20:50:47 cerberus.localdomain dhclient: Starting delete_old_states() Sep 10 20:50:47 cerberus.localdomain dhclient: Comparing IPs: Old: New: 67.x.y.z Sep 10 20:50:47 cerberus.localdomain dhclient: Starting add_new_address() Sep 10 20:50:47 cerberus.localdomain dhclient: ifconfig vr1 inet 67.x.y.z netmask 255.255.252.0 broadcast 255.255.255.255 Sep 10 20:50:47 cerberus.localdomain dhclient: New IP Address (vr1): 67.x.y.z Sep 10 20:50:47 cerberus.localdomain dhclient: New Subnet Mask (vr1): 255.255.252.0 Sep 10 20:50:47 cerberus.localdomain dhclient: New Broadcast Address (vr1): 255.255.255.255 Sep 10 20:50:47 cerberus.localdomain dhclient: New Routers (vr1): 67.x.y.1 Sep 10 20:50:47 cerberus.localdomain dhclient: Adding new routes to interface: vr1 Sep 10 20:50:47 cerberus.localdomain dhclient: /sbin/route add default 67.x.y.1 Sep 10 20:50:47 cerberus.localdomain dhclient: Creating resolv.conf Sep 10 20:50:47 cerberus.localdomain check_reload_status: rc.newwanip starting vr1 Sep 10 20:50:48 cerberus.localdomain dhclient[94022]: bound to 67.x.y.z -- renewal in 89425 seconds. Sep 10 20:50:51 cerberus.localdomain php: rc.newwanip: rc.newwanip: Informational is starting vr1. Sep 10 20:50:51 cerberus.localdomain php: rc.newwanip: rc.newwanip: on (IP address: 67.x.y.z) (interface: wan) (real interface: vr1). Sep 10 20:50:51 cerberus.localdomain php: rc.newwanip: ROUTING: setting default route to 67.x.y.1 Sep 10 20:50:52 cerberus.localdomain apinger: Starting Alarm Pinger, apinger(26451) Sep 10 20:50:57 cerberus.localdomain dnsmasq[6118]: reading /etc/resolv.conf Sep 10 20:50:57 cerberus.localdomain dnsmasq[6118]: using nameserver 75.75.76.76#53 Sep 10 20:50:57 cerberus.localdomain dnsmasq[6118]: using nameserver 75.75.75.75#53 Sep 10 20:50:57 cerberus.localdomain dnsmasq[6118]: using nameserver 2001:558:feed::2#53 Sep 10 20:50:57 cerberus.localdomain dnsmasq[6118]: using nameserver 2001:558:feed::1#53 Sep 10 20:50:57 cerberus.localdomain dnsmasq[6118]: ignoring nameserver 127.0.0.1 - local interface Sep 10 20:50:59 cerberus.localdomain php: rc.newwanip: Resyncing OpenVPN instances for interface WAN. Sep 10 20:51:01 cerberus.localdomain php: rc.newwanip: pfSense package system has detected an ip change 192.168.100.10 -> 67.x.y.z ... Restarting packages. Sep 10 20:51:01 cerberus.localdomain check_reload_status: Starting packages Sep 10 20:51:01 cerberus.localdomain check_reload_status: Reloading filter Sep 10 20:51:07 cerberus.localdomain php: rc.start_packages: Restarting/Starting all packages. Sep 10 20:51:07 cerberus.localdomain php: rc.start_packages: XML error: SYSTEM or PUBLIC, the URI is missing at line 1 in /usr/local/pkg/git.xml Sep 10 20:51:07 cerberus.localdomain php: rc.start_packages: XML error: SYSTEM or PUBLIC, the URI is missing at line 1 in /usr/local/pkg/git.xml Sep 10 20:51:11 cerberus.localdomain php: rc.filter_configure_sync: Could not find IPv6 gateway for interface(wan).