Losing WAN IP on APU [SOLVED]
-
I'm running pfSense-2.1.2-RELEASE-4g-amd64-nanobsd.img on an apu1c with a generic 8GB compact flash card.
I keep periodically losing my WAN IP.
This didn't seem to happen on a microSD 4GB card with pfSense-2.1.2-RELEASE-2g-amd64-nanobsd.img.
It seems to grab a modem DHCP address, and I have to log in and physically do a ifconfig re0 down, ifconfig re0 up for it to regain the public IP address from my ISP.04-27-2014 17:12:52 User.Error 172.16.1.1 Apr 27 17:12:50 php: rc.start_packages: Restarting/Starting all packages. 04-27-2014 17:12:51 System2.Warning 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: restrict ::: KOD does nothing without LIMITED. 04-27-2014 17:12:51 System2.Warning 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: restrict default: KOD does nothing without LIMITED. 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: Listening on routing socket on fd #30 for interface updates 04-27-2014 17:12:51 System2.Error 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: setsockopt IPV6_MULTICAST_IF 0 for fe80::1%4 fails: Can't assign requested address 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: Listen normally on 9 lo0 [fe80::1%4]:123 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: Listen normally on 8 lo0 [::1]:123 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: Listen normally on 7 lo0 127.0.0.1:123 04-27-2014 17:12:51 System2.Error 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: setsockopt IPV6_MULTICAST_IF 0 for fe80::20d:b9ff:fe33:8336%3 fails: Can't assign requested address 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: Listen normally on 6 re2 [fe80::20d:b9ff:fe33:8336%3]:123 04-27-2014 17:12:51 System2.Error 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: setsockopt IPV6_MULTICAST_IF 0 for fe80::20d:b9ff:fe33:8335%2 fails: Can't assign requested address 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: Listen normally on 5 re1 [fe80::20d:b9ff:fe33:8335%2]:123 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: Listen normally on 4 re1 172.16.1.1:123 04-27-2014 17:12:51 System2.Error 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: setsockopt IPV6_MULTICAST_IF 0 for fe80::20d:b9ff:fe33:8334%1 fails: Can't assign requested address 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: Listen normally on 3 re0 [fe80::20d:b9ff:fe33:8334%1]:123 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: Listen normally on 2 re0 68.112.154.60:123 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: Listen and drop on 1 v4wildcard 0.0.0.0:123 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: Listen and drop on 0 v6wildcard [::]:123 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10286]: proto: precision = 1.956 usec (-19) 04-27-2014 17:12:51 System2.Info 172.16.1.1 Apr 27 17:12:50 ntpd[10005]: Command line: /usr/local/sbin/ntpd -g -c /var/etc/ntpd.conf -p /var/run/ntpd.pid 04-27-2014 17:12:51 System2.Notice 172.16.1.1 Apr 27 17:12:50 ntpd[10005]: ntpd 4.2.7p411@1.2483-o Fri Mar 28 00:11:42 UTC 2014 (1): Starting 04-27-2014 17:12:51 User.Notice 172.16.1.1 Apr 27 17:12:50 ntp: Starting NTP Daemon. 04-27-2014 17:12:51 User.Notice 172.16.1.1 Apr 27 17:12:50 ntp: Successfully synced time after 1 attempts. 04-27-2014 17:12:51 System2.Notice 172.16.1.1 Apr 27 17:12:50 ntpdate[9127]: adjust time server 216.66.0.142 offset -0.008839 sec 04-27-2014 17:12:49 System2.Notice 172.16.1.1 Apr 27 17:12:48 ntpd[68857]: ntpd exiting on signal 15 (Terminated: 15) 04-27-2014 17:12:49 User.Notice 172.16.1.1 Apr 27 17:12:48 check_reload_status: Reloading filter 04-27-2014 17:12:49 User.Notice 172.16.1.1 Apr 27 17:12:48 check_reload_status: Starting packages 04-27-2014 17:12:49 User.Error 172.16.1.1 Apr 27 17:12:48 php: rc.newwanip: pfSense package system has detected an ip change 192.168.100.10 -> 68.112.154.60 ... Restarting packages. 04-27-2014 17:12:47 User.Error 172.16.1.1 Apr 27 17:12:45 php: rc.newwanip: Creating rrd update script 04-27-2014 17:12:47 User.Error 172.16.1.1 Apr 27 17:12:45 php: rc.newwanip: Resyncing OpenVPN instances for interface WAN. 04-27-2014 17:12:44 Daemon.Warning 172.16.1.1 Apr 27 17:12:42 dnsmasq[35963]: ignoring nameserver 127.0.0.1 - local interface 04-27-2014 17:12:44 Daemon.Info 172.16.1.1 Apr 27 17:12:42 dnsmasq[35963]: using nameserver 208.67.220.220#53 04-27-2014 17:12:44 Daemon.Info 172.16.1.1 Apr 27 17:12:42 dnsmasq[35963]: using nameserver 208.67.222.222#53 04-27-2014 17:12:44 Daemon.Info 172.16.1.1 Apr 27 17:12:42 dnsmasq[35963]: reading /etc/resolv.conf 04-27-2014 17:12:41 User.Error 172.16.1.1 Apr 27 17:12:39 apinger: Starting Alarm Pinger, apinger(13788) 04-27-2014 17:12:40 User.Error 172.16.1.1 Apr 27 17:12:38 php: rc.newwanip: Removing static route for monitor 208.67.220.220 and adding a new route through 68.112.152.1 04-27-2014 17:12:40 User.Error 172.16.1.1 Apr 27 17:12:38 php: rc.newwanip: ROUTING: setting default route to 68.112.152.1 04-27-2014 17:12:40 User.Error 172.16.1.1 Apr 27 17:12:38 php: rc.newwanip: rc.newwanip: on (IP address: 68.112.154.60) (interface: wan) (real interface: re0). 04-27-2014 17:12:40 User.Error 172.16.1.1 Apr 27 17:12:38 php: rc.newwanip: rc.newwanip: Informational is starting re0. 04-27-2014 17:12:39 System2.Debug 172.16.1.1 Apr 27 17:12:37 ntpd[68857]: new interface(s) found: waking up resolver 04-27-2014 17:12:39 System2.Info 172.16.1.1 Apr 27 17:12:37 ntpd[68857]: Listen normally on 10 re0 68.112.154.60:123 04-27-2014 17:12:38 Daemon.Info 172.16.1.1 Apr 27 17:12:36 dhclient[33143]: bound to 68.112.154.60 -- renewal in 14400 seconds. 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 check_reload_status: rc.newwanip starting re0 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: Creating resolv.conf 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: /sbin/route add default 68.112.152.1 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: Adding new routes to interface: re0 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: New Routers (re0): 68.112.152.1 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: New Broadcast Address (re0): 255.255.255.255 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: New Subnet Mask (re0): 255.255.252.0 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: New IP Address (re0): 68.112.154.60 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: ifconfig re0 inet 68.112.154.60 netmask 255.255.252.0 broadcast 255.255.255.255 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: Starting add_new_address() 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: Deleting old routes 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: Starting delete_old_states() 04-27-2014 17:12:38 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: BOUND 04-27-2014 17:12:38 Daemon.Info 172.16.1.1 Apr 27 17:12:36 dhclient[33143]: DHCPACK from 10.148.48.1 04-27-2014 17:12:37 Daemon.Info 172.16.1.1 Apr 27 17:12:36 dhclient[33143]: DHCPREQUEST on re0 to 255.255.255.255 port 67 04-27-2014 17:12:37 User.Notice 172.16.1.1 Apr 27 17:12:36 dhclient: ARPCHECK 04-27-2014 17:12:37 System2.Info 172.16.1.1 Apr 27 17:12:35 ntpd[68857]: Deleting interface #2 re0, 192.168.100.10#123, interface stats: received=0, sent=0, dropped=0, active_time=32 secs 04-27-2014 17:12:35 User.Notice 172.16.1.1 Apr 27 17:12:34 dhclient: ARPSEND 04-27-2014 17:12:35 Daemon.Info 172.16.1.1 Apr 27 17:12:34 dhclient[33143]: DHCPOFFER from 10.148.48.1 04-27-2014 17:12:35 Daemon.Info 172.16.1.1 Apr 27 17:12:34 dhclient[33143]: DHCPDISCOVER on re0 to 255.255.255.255 port 67 interval 1 04-27-2014 17:12:35 User.Notice 172.16.1.1 Apr 27 17:12:34 dhclient: Starting delete_old_states() 04-27-2014 17:12:35 User.Notice 172.16.1.1 Apr 27 17:12:34 dhclient: PREINIT 04-27-2014 17:12:35 User.Notice 172.16.1.1 Apr 27 17:12:34 dhclient: Deleting old routes 04-27-2014 17:12:35 User.Notice 172.16.1.1 Apr 27 17:12:34 dhclient: Comparing IPs: Old: New: 04-27-2014 17:12:35 User.Notice 172.16.1.1 Apr 27 17:12:34 dhclient: Starting delete_old_states() 04-27-2014 17:12:35 User.Notice 172.16.1.1 Apr 27 17:12:34 dhclient: EXPIRE 04-27-2014 17:12:24 Daemon.Info 172.16.1.1 Apr 27 17:12:22 dhclient[33143]: DHCPREQUEST on re0 to 192.168.100.1 port 67 04-27-2014 17:12:18 Daemon.Info 172.16.1.1 Apr 27 17:12:16 dhclient[33143]: DHCPREQUEST on re0 to 192.168.100.1 port 67 04-27-2014 17:12:15 Daemon.Info 172.16.1.1 Apr 27 17:12:13 dhclient[33143]: DHCPREQUEST on re0 to 192.168.100.1 port 67 04-27-2014 17:12:12 Daemon.Info 172.16.1.1 Apr 27 17:12:10 dhclient[33143]: DHCPREQUEST on re0 to 192.168.100.1 port 67 04-27-2014 17:12:10 Daemon.Info 172.16.1.1 Apr 27 17:12:08 dhclient[33143]: DHCPREQUEST on re0 to 192.168.100.1 port 67 04-27-2014 17:12:08 Daemon.Info 172.16.1.1 Apr 27 17:12:06 dhclient[33143]: DHCPREQUEST on re0 to 192.168.100.1 port 67 04-27-2014 17:12:06 Daemon.Info 172.16.1.1 Apr 27 17:12:04 dhclient[33143]: DHCPREQUEST on re0 to 192.168.100.1 port 67 04-27-2014 17:12:05 System2.Warning 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: restrict ::: KOD does nothing without LIMITED. 04-27-2014 17:12:05 System2.Warning 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: restrict default: KOD does nothing without LIMITED. 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: Listening on routing socket on fd #30 for interface updates 04-27-2014 17:12:05 System2.Error 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: setsockopt IPV6_MULTICAST_IF 0 for fe80::1%4 fails: Can't assign requested address 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: Listen normally on 9 lo0 [fe80::1%4]:123 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: Listen normally on 8 lo0 [::1]:123 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: Listen normally on 7 lo0 127.0.0.1:123 04-27-2014 17:12:05 System2.Error 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: setsockopt IPV6_MULTICAST_IF 0 for fe80::20d:b9ff:fe33:8336%3 fails: Can't assign requested address 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: Listen normally on 6 re2 [fe80::20d:b9ff:fe33:8336%3]:123 04-27-2014 17:12:05 System2.Error 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: setsockopt IPV6_MULTICAST_IF 0 for fe80::20d:b9ff:fe33:8335%2 fails: Can't assign requested address 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: Listen normally on 5 re1 [fe80::20d:b9ff:fe33:8335%2]:123 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: Listen normally on 4 re1 172.16.1.1:123 04-27-2014 17:12:05 System2.Error 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: setsockopt IPV6_MULTICAST_IF 0 for fe80::20d:b9ff:fe33:8334%1 fails: Can't assign requested address 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: Listen normally on 3 re0 [fe80::20d:b9ff:fe33:8334%1]:123 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: Listen normally on 2 re0 192.168.100.10:123 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: Listen and drop on 1 v4wildcard 0.0.0.0:123 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: Listen and drop on 0 v6wildcard [::]:123 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68857]: proto: precision = 1.955 usec (-19) 04-27-2014 17:12:05 System2.Info 172.16.1.1 Apr 27 17:12:03 ntpd[68654]: Command line: /usr/local/sbin/ntpd -g -c /var/etc/ntpd.conf -p /var/run/ntpd.pid 04-27-2014 17:12:05 System2.Notice 172.16.1.1 Apr 27 17:12:03 ntpd[68654]: ntpd 4.2.7p411@1.2483-o Fri Mar 28 00:11:42 UTC 2014 (1): Starting 04-27-2014 17:12:05 User.Notice 172.16.1.1 Apr 27 17:12:03 ntp: Starting NTP Daemon. 04-27-2014 17:12:05 User.Notice 172.16.1.1 Apr 27 17:12:03 ntp: Giving up on time sync after 3 attempts. 04-27-2014 17:12:05 Daemon.Info 172.16.1.1 Apr 27 17:12:03 dhclient[33143]: DHCPREQUEST on re0 to 192.168.100.1 port 67 04-27-2014 17:12:00 System2.Error 172.16.1.1 Apr 27 17:11:58 ntpdate[67664]: no servers can be used, exiting 04-27-2014 17:12:00 System2.Error 172.16.1.1 Apr 27 17:11:58 ntpdate[67664]: can't find host 0.pfsense.pool.ntp.org 04-27-2014 17:11:54 System2.Error 172.16.1.1 Apr 27 17:11:52 ntpdate[67035]: no servers can be used, exiting 04-27-2014 17:11:54 System2.Error 172.16.1.1 Apr 27 17:11:52 ntpdate[67035]: can't find host 0.pfsense.pool.ntp.org 04-27-2014 17:11:49 User.Error 172.16.1.1 Apr 27 17:11:47 php: rc.start_packages: Restarting/Starting all packages. 04-27-2014 17:11:48 System2.Error 172.16.1.1 Apr 27 17:11:46 ntpdate[61693]: no servers can be used, exiting 04-27-2014 17:11:48 System2.Error 172.16.1.1 Apr 27 17:11:46 ntpdate[61693]: can't find host 0.pfsense.pool.ntp.org 04-27-2014 17:11:46 System2.Notice 172.16.1.1 Apr 27 17:11:45 ntpd[75612]: ntpd exiting on signal 15 (Terminated: 15) 04-27-2014 17:11:46 User.Notice 172.16.1.1 Apr 27 17:11:45 check_reload_status: Reloading filter 04-27-2014 17:11:46 User.Notice 172.16.1.1 Apr 27 17:11:45 check_reload_status: Starting packages 04-27-2014 17:11:46 User.Error 172.16.1.1 Apr 27 17:11:45 php: rc.newwanip: pfSense package system has detected an ip change 68.112.154.60 -> 192.168.100.10 ... Restarting packages. 04-27-2014 17:11:44 User.Error 172.16.1.1 Apr 27 17:11:43 php: rc.newwanip: Creating rrd update script 04-27-2014 17:11:44 User.Error 172.16.1.1 Apr 27 17:11:43 php: rc.newwanip: Resyncing OpenVPN instances for interface WAN. 04-27-2014 17:11:40 User.Notice 172.16.1.1 Apr 27 17:11:38 check_reload_status: updating dyndns wan 04-27-2014 17:11:40 Daemon.Info 172.16.1.1 Apr 27 17:11:38 radvd[63690]: resuming normal operation 04-27-2014 17:11:40 Daemon.Warning 172.16.1.1 Apr 27 17:11:38 radvd[63690]: no auto-selected prefix on interface re1, disabling advertisements 04-27-2014 17:11:40 Daemon.Info 172.16.1.1 Apr 27 17:11:38 radvd[63690]: attempting to reread config file 04-27-2014 17:11:39 Local7.Info 172.16.1.1 Apr 27 17:11:37 dhcpd: Sending on Socket/fallback/fallback-net 04-27-2014 17:11:38 User.Error 172.16.1.1 Apr 27 17:11:37 apinger: No usable targets found, exiting 04-27-2014 17:11:38 User.Error 172.16.1.1 Apr 27 17:11:37 apinger: Starting Alarm Pinger, apinger(40741) 04-27-2014 17:11:38 Local7.Info 172.16.1.1 Apr 27 17:11:36 dhcpd: Disabling input on BPF/re1/00:0d:b9:33:83:35/172.16.1.0/27 04-27-2014 17:11:38 Local7.Info 172.16.1.1 Apr 27 17:11:36 dhcpd: Disabling output on BPF/re1/00:0d:b9:33:83:35/172.16.1.0/27 04-27-2014 17:11:38 Local7.Info 172.16.1.1 Apr 27 17:11:36 dhcpd: Received signal 15, initiating shutdown. 04-27-2014 17:11:38 Daemon.Info 172.16.1.1 Apr 27 17:11:36 dnsmasq[35963]: read /etc/hosts - 2 addresses 04-27-2014 17:11:38 Daemon.Warning 172.16.1.1 Apr 27 17:11:36 dnsmasq[35963]: ignoring nameserver 127.0.0.1 - local interface 04-27-2014 17:11:38 Daemon.Info 172.16.1.1 Apr 27 17:11:36 dnsmasq[35963]: using nameserver 208.67.220.220#53 04-27-2014 17:11:38 Daemon.Info 172.16.1.1 Apr 27 17:11:36 dnsmasq[35963]: using nameserver 208.67.222.222#53 04-27-2014 17:11:38 Daemon.Info 172.16.1.1 Apr 27 17:11:36 dnsmasq[35963]: reading /etc/resolv.conf 04-27-2014 17:11:38 Daemon.Info 172.16.1.1 Apr 27 17:11:36 dnsmasq[35963]: compile time options: IPv6 GNU-getopt no-DBus i18n IDN DHCP DHCPv6 no-Lua TFTP no-conntrack no-ipset auth 04-27-2014 17:11:38 Daemon.Info 172.16.1.1 Apr 27 17:11:36 dnsmasq[35963]: started, version 2.68 cachesize 10000 04-27-2014 17:11:37 User.Error 172.16.1.1 Apr 27 17:11:35 php: rc.newwanip: rc.newwanip: on (IP address: 192.168.100.10) (interface: wan) (real interface: re0). 04-27-2014 17:11:37 User.Error 172.16.1.1 Apr 27 17:11:35 php: rc.newwanip: rc.newwanip: Informational is starting re0. 04-27-2014 17:11:37 Daemon.Info 172.16.1.1 Apr 27 17:11:35 dnsmasq[22971]: exiting on receipt of SIGTERM 04-27-2014 17:11:36 System2.Debug 172.16.1.1 Apr 27 17:11:34 ntpd[75612]: new interface(s) found: waking up resolver 04-27-2014 17:11:36 System2.Info 172.16.1.1 Apr 27 17:11:34 ntpd[75612]: Listen normally on 10 re0 192.168.100.10:123 04-27-2014 17:11:35 User.Error 172.16.1.1 Apr 27 17:11:33 php: rc.linkup: Accept router advertisements on interface re0 04-27-2014 17:11:35 Daemon.Info 172.16.1.1 Apr 27 17:11:33 dhclient[20215]: bound to 192.168.100.10 -- renewal in 30 seconds. 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 check_reload_status: rc.newwanip starting re0 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: Creating resolv.conf 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: Adding new routes to interface: re0 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: New Routers (re0): 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: New Broadcast Address (re0): 192.168.100.255 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: New Subnet Mask (re0): 255.255.255.0 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: New IP Address (re0): 192.168.100.10 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: ifconfig re0 inet 192.168.100.10 netmask 255.255.255.0 broadcast 192.168.100.255 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: Starting add_new_address() 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: Comparing IPs: Old: New: 192.168.100.10 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: Starting delete_old_states() 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: BOUND 04-27-2014 17:11:35 Daemon.Info 172.16.1.1 Apr 27 17:11:33 dhclient[20215]: DHCPACK from 192.168.100.1 04-27-2014 17:11:35 Daemon.Info 172.16.1.1 Apr 27 17:11:33 dhclient[20215]: DHCPREQUEST on re0 to 255.255.255.255 port 67 04-27-2014 17:11:35 User.Notice 172.16.1.1 Apr 27 17:11:33 dhclient: ARPCHECK 04-27-2014 17:11:33 Daemon.Debug 172.16.1.1 Apr 27 17:11:31 dhclient[20215]: DHCPOFFER already seen. 04-27-2014 17:11:33 Daemon.Info 172.16.1.1 Apr 27 17:11:31 dhclient[20215]: DHCPOFFER from 192.168.100.1 04-27-2014 17:11:33 User.Notice 172.16.1.1 Apr 27 17:11:31 dhclient: ARPSEND 04-27-2014 17:11:33 Daemon.Info 172.16.1.1 Apr 27 17:11:31 dhclient[20215]: DHCPOFFER from 192.168.100.1 04-27-2014 17:11:32 Daemon.Info 172.16.1.1 Apr 27 17:11:31 dhclient[20215]: DHCPDISCOVER on re0 to 255.255.255.255 port 67 interval 4 04-27-2014 17:11:31 Daemon.Info 172.16.1.1 Apr 27 17:11:29 dhclient[20215]: DHCPDISCOVER on re0 to 255.255.255.255 port 67 interval 2 04-27-2014 17:11:31 Daemon.Info 172.16.1.1 Apr 27 17:11:29 dhclient[20215]: DHCPNAK from 192.168.100.1 04-27-2014 17:11:31 Daemon.Info 172.16.1.1 Apr 27 17:11:29 dhclient[20215]: DHCPREQUEST on re0 to 255.255.255.255 port 67 04-27-2014 17:11:30 User.Notice 172.16.1.1 Apr 27 17:11:28 check_reload_status: updating dyndns wan 04-27-2014 17:11:30 Daemon.Info 172.16.1.1 Apr 27 17:11:28 radvd[63690]: resuming normal operation 04-27-2014 17:11:30 Daemon.Warning 172.16.1.1 Apr 27 17:11:28 radvd[63690]: no auto-selected prefix on interface re1, disabling advertisements 04-27-2014 17:11:30 Daemon.Info 172.16.1.1 Apr 27 17:11:28 radvd[63690]: attempting to reread config file 04-27-2014 17:11:28 Daemon.Info 172.16.1.1 Apr 27 17:11:27 dhclient[20215]: DHCPREQUEST on re0 to 255.255.255.255 port 67 04-27-2014 17:11:28 Local7.Info 172.16.1.1 Apr 27 17:11:26 dhcpd: Disabling input on BPF/re1/00:0d:b9:33:83:35/172.16.1.0/27 04-27-2014 17:11:28 Local7.Info 172.16.1.1 Apr 27 17:11:26 dhcpd: Disabling output on BPF/re1/00:0d:b9:33:83:35/172.16.1.0/27 04-27-2014 17:11:28 Local7.Info 172.16.1.1 Apr 27 17:11:26 dhcpd: Received signal 15, initiating shutdown. 04-27-2014 17:11:28 Daemon.Info 172.16.1.1 Apr 27 17:11:26 dnsmasq[22971]: read /etc/hosts - 2 addresses 04-27-2014 17:11:28 Daemon.Warning 172.16.1.1 Apr 27 17:11:26 dnsmasq[22971]: ignoring nameserver 127.0.0.1 - local interface 04-27-2014 17:11:28 Daemon.Info 172.16.1.1 Apr 27 17:11:26 dnsmasq[22971]: using nameserver 208.67.220.220#53 04-27-2014 17:11:28 Daemon.Info 172.16.1.1 Apr 27 17:11:26 dnsmasq[22971]: using nameserver 208.67.222.222#53 04-27-2014 17:11:28 Daemon.Info 172.16.1.1 Apr 27 17:11:26 dnsmasq[22971]: reading /etc/resolv.conf 04-27-2014 17:11:28 Daemon.Info 172.16.1.1 Apr 27 17:11:26 dnsmasq[22971]: compile time options: IPv6 GNU-getopt no-DBus i18n IDN DHCP DHCPv6 no-Lua TFTP no-conntrack no-ipset auth 04-27-2014 17:11:28 Daemon.Info 172.16.1.1 Apr 27 17:11:26 dnsmasq[22971]: started, version 2.68 cachesize 10000 04-27-2014 17:11:27 Daemon.Info 172.16.1.1 Apr 27 17:11:26 dhclient[20215]: DHCPREQUEST on re0 to 255.255.255.255 port 67 04-27-2014 17:11:27 Daemon.Warning 172.16.1.1 Apr 27 17:11:25 dnsmasq[46779]: overflow: 7 log entries lost 04-27-2014 17:11:27 Daemon.Info 172.16.1.1 Apr 27 16:34:09 dnsmasq[46779]: started, version 2.68 cachesize 10000 04-27-2014 17:11:27 Daemon.Info 172.16.1.1 Apr 27 17:11:25 dhclient[20215]: DHCPREQUEST on re0 to 255.255.255.255 port 67 04-27-2014 17:11:27 User.Notice 172.16.1.1 Apr 27 17:11:25 dhclient: Comparing IPs: Old: New: 04-27-2014 17:11:27 User.Notice 172.16.1.1 Apr 27 17:11:25 dhclient: Starting delete_old_states() 04-27-2014 17:11:27 User.Notice 172.16.1.1 Apr 27 17:11:25 dhclient: PREINIT 04-27-2014 17:11:26 User.Error 172.16.1.1 Apr 27 17:11:25 php: rc.linkup: HOTPLUG: Configuring interface wan 04-27-2014 17:11:26 User.Error 172.16.1.1 Apr 27 17:11:25 php: rc.linkup: DEVD Ethernet attached event for wan 04-27-2014 17:11:25 User.Error 172.16.1.1 Apr 27 17:11:23 php: rc.linkup: Accept router advertisements on interface re0 04-27-2014 17:11:25 User.Error 172.16.1.1 Apr 27 17:11:23 php: rc.linkup: The command '/sbin/dhclient -c /var/etc/dhclient_wan.conf re0 > /tmp/re0_output 2> /tmp/re0_error_output' returned exit code '15', the output was '' 04-27-2014 17:11:25 Daemon.Critical 172.16.1.1 Apr 27 17:11:23 dhclient[16060]: exiting. 04-27-2014 17:11:25 Daemon.Error 172.16.1.1 Apr 27 17:11:23 dhclient[16060]: connection closed 04-27-2014 17:11:25 User.Error 172.16.1.1 Apr 27 17:11:23 php: rc.linkup: DEVD Ethernet detached event for wan 04-27-2014 17:11:24 Kernel.Notice 172.16.1.1 Apr 27 17:11:22 kernel: re0: link state changed to UP 04-27-2014 17:11:24 User.Notice 172.16.1.1 Apr 27 17:11:22 check_reload_status: Linkup starting re0 04-27-2014 17:11:22 Kernel.Notice 172.16.1.1 Apr 27 17:11:20 kernel: re0: link state changed to DOWN 04-27-2014 17:11:22 User.Notice 172.16.1.1 Apr 27 17:11:20 check_reload_status: Linkup starting re0 04-27-2014 17:11:21 Daemon.Info 172.16.1.1 Apr 27 17:11:20 dhclient[15739]: DHCPREQUEST on re0 to 255.255.255.255 port 67 04-27-2014 17:11:20 Daemon.Info 172.16.1.1 Apr 27 17:11:18 dhclient[15739]: DHCPREQUEST on re0 to 255.255.255.255 port 67 04-27-2014 17:11:20 User.Notice 172.16.1.1 Apr 27 17:11:18 dhclient: Comparing IPs: Old: New: 04-27-2014 17:11:20 User.Notice 172.16.1.1 Apr 27 17:11:18 dhclient: Starting delete_old_states() 04-27-2014 17:11:20 User.Notice 172.16.1.1 Apr 27 17:11:18 dhclient: PREINIT 04-27-2014 17:11:19 User.Error 172.16.1.1 Apr 27 17:11:18 php: rc.linkup: HOTPLUG: Configuring interface wan 04-27-2014 17:11:19 User.Error 172.16.1.1 Apr 27 17:11:18 php: rc.linkup: DEVD Ethernet attached event for wan 04-27-2014 17:11:19 Local7.Info 172.16.1.1 Apr 27 17:11:17 dhcpd: DHCPACK to 172.16.1.13 (00:24:d7:b9:42:64) via re1 04-27-2014 17:11:19 Local7.Info 172.16.1.1 Apr 27 17:11:17 dhcpd: DHCPINFORM from 172.16.1.13 via re1 04-27-2014 17:11:17 Kernel.Notice 172.16.1.1 Apr 27 17:11:15 kernel: re0: link state changed to UP 04-27-2014 17:11:17 User.Notice 172.16.1.1 Apr 27 17:11:15 check_reload_status: Linkup starting re0 04-27-2014 17:11:07 System2.Info 172.16.1.1 Apr 27 17:11:05 ntpd[75612]: 66.40.130.103 local addr 68.112.154.60 -> <null>04-27-2014 17:11:07 System2.Info 172.16.1.1 Apr 27 17:11:05 ntpd[75612]: Deleting interface #2 re0, 68.112.154.60#123, interface stats: received=38, sent=38, dropped=0, active_time=2206 secs 04-27-2014 17:11:04 Daemon.Critical 172.16.1.1 Apr 27 17:11:03 dhclient[35604]: exiting. 04-27-2014 17:11:04 Daemon.Error 172.16.1.1 Apr 27 17:11:03 dhclient[35604]: connection closed 04-27-2014 17:11:04 User.Error 172.16.1.1 Apr 27 17:11:03 php: rc.linkup: DEVD Ethernet detached event for wan 04-27-2014 17:11:02 Kernel.Notice 172.16.1.1 Apr 27 17:11:00 kernel: re0: link state changed to DOWN 04-27-2014 17:11:02 User.Notice 172.16.1.1 Apr 27 17:11:00 check_reload_status: Linkup starting re0 04-27-2014 17:05:45 Local7.Info 172.16.1.1 Apr 27 17:05:44 dhcpd: DHCPACK to 172.16.1.13 (00:24:d7:b9:42:64) via re1 04-27-2014 17:05:45 Local7.Info 172.16.1.1 Apr 27 17:05:44 dhcpd: DHCPINFORM from 172.16.1.13 via re1 04-27-2014 17:04:29 Local7.Info 172.16.1.1 Apr 27 17:04:27 dhcpd: DHCPACK on 172.16.1.12 to 00:24:8d:3c:ee:87 via re1 04-27-2014 17:04:29 Local7.Info 172.16.1.1 Apr 27 17:04:27 dhcpd: DHCPREQUEST for 172.16.1.12 from 00:24:8d:3c:ee:87 via re1 04-27-2014 17:02:40 Local7.Info 172.16.1.1 Apr 27 17:02:39 dhcpd: DHCPACK to 172.16.1.13 (00:24:d7:b9:42:64) via re1 04-27-2014 17:02:40 Local7.Info 172.16.1.1 Apr 27 17:02:39 dhcpd: DHCPINFORM from 172.16.1.13 via re1 04-27-2014 17:00:01 Cron.Info 172.16.1.1 Apr 27 17:00:00 /usr/sbin/cron[62864]: (root) CMD (/usr/bin/nice -n20 /usr/local/sbin/expiretable -v -t 3600 sshlockout) 04-27-2014 17:00:01 Cron.Info 172.16.1.1 Apr 27 17:00:00 /usr/sbin/cron[62663]: (root) CMD (/usr/bin/nice -n20 /usr/local/sbin/expiretable -v -t 3600 virusprot) 04-27-2014 16:58:56 Local7.Info 172.16.1.1 Apr 27 16:58:55 dhcpd: DHCPACK to 172.16.1.13 (00:24:d7:b9:42:64) via re1 04-27-2014 16:58:56 Local7.Info 172.16.1.1 Apr 27 16:58:55 dhcpd: DHCPINFORM from 172.16.1.13 via re1 04-27-2014 16:57:54 System4.Notice 172.16.1.1 Apr 27 16:57:52 sshlockout[31198]: sshlockout/webConfigurator v3.0 starting up 04-27-2014 16:57:54 Auth.Info 172.16.1.1 Apr 27 16:57:52 sshd[16902]: Accepted keyboard-interactive/pam for admin from 172.16.1.10 port 28481 ssh2 04-27-2014 16:56:30 Local7.Info 172.16.1.1 Apr 27 16:56:28 dhcpd: DHCPACK to 172.16.1.13 (00:24:d7:b9:42:64) via re1 04-27-2014 16:56:30 Local7.Info 172.16.1.1 Apr 27 16:56:28 dhcpd: DHCPINFORM from 172.16.1.13 via re1 04-27-2014 16:55:06 Local7.Info 172.16.1.1 Apr 27 16:55:04 dhcpd: DHCPACK to 172.16.1.13 (00:24:d7:b9:42:64) via re1 04-27-2014 16:55:06 Local7.Info 172.16.1.1 Apr 27 16:55:04 dhcpd: DHCPINFORM from 172.16.1.13 via re1 04-27-2014 16:53:53 Local7.Info 172.16.1.1 Apr 27 16:53:51 dhcpd: DHCPACK to 172.16.1.13 (00:24:d7:b9:42:64) via re1 04-27-2014 16:53:53 Local7.Info 172.16.1.1 Apr 27 16:53:51 dhcpd: DHCPINFORM from 172.16.1.13 via re1 04-27-2014 16:50:57 Local7.Info 172.16.1.1 Apr 27 16:50:55 dhcpd: DHCPACK on 172.16.1.13 to 00:24:d7:b9:42:64 (6KFXKQ1) via re1 04-27-2014 16:50:57 Local7.Info 172.16.1.1 Apr 27 16:50:55 dhcpd: DHCPREQUEST for 172.16.1.13 from 00:24:d7:b9:42:64 (6KFXKQ1) via re1 04-27-2014 16:46:28 Local7.Info 172.16.1.1 Apr 27 16:46:26 dhcpd: DHCPACK to 172.16.1.13 (00:24:d7:b9:42:64) via re1 04-27-2014 16:46:28 Local7.Info 172.16.1.1 Apr 27 16:46:26 dhcpd: DHCPINFORM from 172.16.1.13 via re1 04-27-2014 16:43:51 Local7.Info 172.16.1.1 Apr 27 16:43:49 dhcpd: DHCPACK to 172.16.1.13 (00:24:d7:b9:42:64) via re1 04-27-2014 16:43:51 Local7.Info 172.16.1.1 Apr 27 16:43:49 dhcpd: DHCPINFORM from 172.16.1.13 via re1 04-27-2014 16:38:15 Local7.Info 172.16.1.1 Apr 27 16:38:14 dhcpd: DHCPACK on 172.16.1.11 to f8:f1:b6:57:61:2f (android-cc5e5e29a352956c) via re1 04-27-2014 16:38:15 Local7.Info 172.16.1.1 Apr 27 16:38:14 dhcpd: DHCPREQUEST for 172.16.1.11 from f8:f1:b6:57:61:2f (android-cc5e5e29a352956c) via re1 04-27-2014 16:36:11 Kernel.Info 172.16.1.1 Apr 27 16:36:10 kernel: pflog0: promiscuous mode enabled 04-27-2014 16:36:11 Local0.Info 172.16.1.1 Apr 27 16:36:09 pf: 0 packets dropped by kernel 04-27-2014 16:36:11 Local0.Info 172.16.1.1 Apr 27 16:36:09 pf: 0 packets received by filter 04-27-2014 16:36:11 Local0.Info 172.16.1.1 Apr 27 16:36:09 pf: 0 packets captured 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: Enter an option: 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 7) Ping host 15) Restore recent configuration 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 6) Halt system 14) Enable Secure Shell (sshd) 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 5) Reboot system 13) Upgrade from console 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 4) Reset to factory defaults 12) pfSense Developer Shell 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 3) Reset webConfigurator password 11) Restart webConfigurator 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 2) Set interface(s) IP address 10) Filter Logs 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 1) Assign Interfaces 9) pfTop 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 0) Logout (SSH only) 8) Shell 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: OPT1 (opt1) -> re2 -> 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: v4: 172.16.1.1/27 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: LAN (lan) -> re1 -> 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: v4/DHCP4: 68.112.154.60/22 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: WAN (wan) -> re0 -> 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: *** Welcome to pfSense 2.1.2-RELEASE-nanobsd (amd64) on h-pf *** 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: FreeBSD/amd64 (h-pf.emrlab.info) (console) 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: Bootup complete 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: done. 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: Starting CRON... 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: done. 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: Starting syslog... 04-27-2014 16:36:11 System4.Info 172.16.1.1 Apr 27 16:36:09 kernel: done. 04-27-2014 16:36:11 Kernel.Info 172.16.1.1 Apr 27 16:36:09 syslogd: kernel boot file is /boot/kernel/kernel 04-27-2014 16:36:11 Syslog.Info 172.16.1.1 Apr 27 16:36:09 syslogd: restart</null>
-
I presume you mean APU and not ALIX given the re NICs. There's no such thing as an "ALIX APU", I edited your post to remove ALIX.
Your modem is handing out an IP to the firewall, typically what happens when your modem isn't connected to your ISP. Fill in the "Reject leases from" field with 192.168.0.0/16 so it'll reject your modem's leases, and that'll make things a bit better. The root problem though seems to be that you're losing Internet connectivity.
-
I stand corrected.
I moved back to my E1000 with DD-WRT last night and I have not had any problems since then.
I will try your suggestion, hopefully it will result in some success.
-
Your suggestion has helped to some degree, ( I don't have to reset the interface manually to get my public lease anymore ) however, the problem is still happening.
Logs:
05-01-2014 18:05:09 User.Notice 172.16.1.1 May 1 18:05:04 check_reload_status: updating dyndns wan 05-01-2014 18:05:09 Daemon.Info 172.16.1.1 May 1 18:05:04 radvd[66567]: resuming normal operation 05-01-2014 18:05:09 Daemon.Warning 172.16.1.1 May 1 18:05:04 radvd[66567]: no auto-selected prefix on interface re1, disabling advertisements 05-01-2014 18:05:09 Daemon.Info 172.16.1.1 May 1 18:05:04 radvd[66567]: attempting to reread config file 05-01-2014 18:05:08 Local7.Info 172.16.1.1 May 1 18:05:03 dhcpd: Sending on Socket/fallback/fallback-net 05-01-2014 18:05:07 User.Error 172.16.1.1 May 1 18:05:02 apinger: SIGHUP received, reloading configuration. 05-01-2014 18:05:07 Local7.Info 172.16.1.1 May 1 18:05:02 dhcpd: Disabling input on BPF/re1/00:0d:b9:33:83:35/172.16.1.0/27 05-01-2014 18:05:07 Local7.Info 172.16.1.1 May 1 18:05:02 dhcpd: Disabling output on BPF/re1/00:0d:b9:33:83:35/172.16.1.0/27 05-01-2014 18:05:07 Local7.Info 172.16.1.1 May 1 18:05:02 dhcpd: Received signal 15, initiating shutdown. 05-01-2014 18:05:07 User.Error 172.16.1.1 May 1 18:05:02 php: rc.newwanip: Removing static route for monitor 208.67.220.220 and adding a new route through 68.112.156.1 05-01-2014 18:05:07 User.Error 172.16.1.1 May 1 18:05:02 php: rc.newwanip: ROUTING: setting default route to 68.112.156.1 05-01-2014 18:05:07 User.Error 172.16.1.1 May 1 18:05:02 php: rc.newwanip: rc.newwanip: on (IP address: 68.112.157.243) (interface: wan) (real interface: re0). 05-01-2014 18:05:07 User.Error 172.16.1.1 May 1 18:05:02 php: rc.newwanip: rc.newwanip: Informational is starting re0. 05-01-2014 18:05:05 System2.Debug 172.16.1.1 May 1 18:05:01 ntpd[17170]: new interface(s) found: waking up resolver 05-01-2014 18:05:05 System2.Info 172.16.1.1 May 1 18:05:01 ntpd[17170]: Listen normally on 10 re0 68.112.157.243:123 05-01-2014 18:05:05 User.Error 172.16.1.1 May 1 18:05:01 apinger: alarm canceled: WAN_DHCP(208.67.220.220) *** down *** 05-01-2014 18:05:04 User.Error 172.16.1.1 May 1 18:05:00 php: rc.linkup: ROUTING: setting default route to 68.112.156.1 05-01-2014 18:05:04 User.Error 172.16.1.1 May 1 18:05:00 php: rc.linkup: Accept router advertisements on interface re0 05-01-2014 18:05:04 Daemon.Info 172.16.1.1 May 1 18:05:00 dhclient[8496]: bound to 68.112.157.243 -- renewal in 14400 seconds. 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 check_reload_status: rc.newwanip starting re0 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 dhclient: Creating resolv.conf 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 dhclient: /sbin/route add default 68.112.156.1 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 dhclient: Adding new routes to interface: re0 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 dhclient: New Routers (re0): 68.112.156.1 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 dhclient: New Broadcast Address (re0): 255.255.255.255 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 dhclient: New Subnet Mask (re0): 255.255.252.0 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 dhclient: New IP Address (re0): 68.112.157.243 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 dhclient: ifconfig re0 inet 68.112.157.243 netmask 255.255.252.0 broadcast 255.255.255.255 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 dhclient: Starting add_new_address() 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 dhclient: BOUND 05-01-2014 18:05:04 Daemon.Info 172.16.1.1 May 1 18:05:00 dhclient[8496]: DHCPACK from 10.148.48.1 05-01-2014 18:05:04 Daemon.Info 172.16.1.1 May 1 18:05:00 dhclient[8496]: DHCPREQUEST on re0 to 255.255.255.255 port 67 05-01-2014 18:05:04 User.Notice 172.16.1.1 May 1 18:05:00 dhclient: ARPCHECK 05-01-2014 18:05:02 User.Notice 172.16.1.1 May 1 18:04:58 dhclient: ARPSEND 05-01-2014 18:05:02 Daemon.Info 172.16.1.1 May 1 18:04:58 dhclient[8496]: DHCPOFFER from 10.148.48.1 05-01-2014 18:05:02 Daemon.Info 172.16.1.1 May 1 18:04:58 dhclient[8496]: DHCPDISCOVER on re0 to 255.255.255.255 port 67 interval 1 05-01-2014 18:05:02 Daemon.Info 172.16.1.1 May 1 18:04:57 dhclient[8496]: DHCPOFFER from 192.168.100.1 rejected. 05-01-2014 18:05:02 Daemon.Info 172.16.1.1 May 1 18:04:57 dhclient[8496]: DHCPOFFER from 192.168.100.1 rejected. 05-01-2014 18:05:01 Daemon.Info 172.16.1.1 May 1 18:04:57 dhclient[8496]: DHCPDISCOVER on re0 to 255.255.255.255 port 67 interval 1 05-01-2014 18:05:00 Daemon.Info 172.16.1.1 May 1 18:04:56 dhclient[8496]: DHCPDISCOVER on re0 to 255.255.255.255 port 67 interval 1 05-01-2014 18:04:53 Daemon.Info 172.16.1.1 May 1 18:04:49 dhclient[8496]: DHCPNACK from 192.168.100.1 rejected. 05-01-2014 18:04:53 Daemon.Info 172.16.1.1 May 1 18:04:49 dhclient[8496]: DHCPREQUEST on re0 to 255.255.255.255 port 67 05-01-2014 18:04:49 Daemon.Info 172.16.1.1 May 1 18:04:45 dhclient[8496]: DHCPNACK from 192.168.100.1 rejected. 05-01-2014 18:04:49 Daemon.Info 172.16.1.1 May 1 18:04:45 dhclient[8496]: DHCPREQUEST on re0 to 255.255.255.255 port 67 05-01-2014 18:04:46 User.Notice 172.16.1.1 May 1 18:04:42 check_reload_status: updating dyndns wan 05-01-2014 18:04:46 Daemon.Info 172.16.1.1 May 1 18:04:42 radvd[66567]: resuming normal operation 05-01-2014 18:04:46 Daemon.Warning 172.16.1.1 May 1 18:04:42 radvd[66567]: no auto-selected prefix on interface re1, disabling advertisements 05-01-2014 18:04:46 Daemon.Info 172.16.1.1 May 1 18:04:42 radvd[66567]: attempting to reread config file 05-01-2014 18:04:45 Local7.Info 172.16.1.1 May 1 18:04:41 dhcpd: Sending on Socket/fallback/fallback-net 05-01-2014 18:04:45 Daemon.Info 172.16.1.1 May 1 18:04:41 dhclient[8496]: DHCPREQUEST on re0 to 255.255.255.255 port 67 05-01-2014 18:04:44 Local7.Info 172.16.1.1 May 1 18:04:39 dhcpd: Disabling input on BPF/re1/00:0d:b9:33:83:35/172.16.1.0/27 05-01-2014 18:04:44 Local7.Info 172.16.1.1 May 1 18:04:39 dhcpd: Disabling output on BPF/re1/00:0d:b9:33:83:35/172.16.1.0/27 05-01-2014 18:04:44 Local7.Info 172.16.1.1 May 1 18:04:39 dhcpd: Received signal 15, initiating shutdown. 05-01-2014 18:04:44 Daemon.Info 172.16.1.1 May 1 18:04:39 dhclient[8496]: DHCPREQUEST on re0 to 255.255.255.255 port 67 05-01-2014 18:04:44 User.Notice 172.16.1.1 May 1 18:04:39 dhclient: Comparing IPs: Old: New: 05-01-2014 18:04:44 User.Notice 172.16.1.1 May 1 18:04:39 dhclient: Starting delete_old_states() 05-01-2014 18:04:44 User.Notice 172.16.1.1 May 1 18:04:39 dhclient: PREINIT 05-01-2014 18:04:43 User.Error 172.16.1.1 May 1 18:04:39 php: rc.linkup: HOTPLUG: Configuring interface wan 05-01-2014 18:04:43 User.Error 172.16.1.1 May 1 18:04:39 php: rc.linkup: DEVD Ethernet attached event for wan 05-01-2014 18:04:42 User.Error 172.16.1.1 May 1 18:04:37 php: rc.linkup: Accept router advertisements on interface re0 05-01-2014 18:04:42 User.Error 172.16.1.1 May 1 18:04:37 php: rc.linkup: The command '/sbin/dhclient -c /var/etc/dhclient_wan.conf re0 > /tmp/re0_output 2> /tmp/re0_error_output' returned exit code '15', the output was '' 05-01-2014 18:04:42 Daemon.Critical 172.16.1.1 May 1 18:04:37 dhclient[92175]: exiting. 05-01-2014 18:04:42 Daemon.Error 172.16.1.1 May 1 18:04:37 dhclient[92175]: connection closed 05-01-2014 18:04:42 User.Error 172.16.1.1 May 1 18:04:37 php: rc.linkup: DEVD Ethernet detached event for wan 05-01-2014 18:04:41 Kernel.Notice 172.16.1.1 May 1 18:04:37 kernel: re0: link state changed to UP 05-01-2014 18:04:41 User.Notice 172.16.1.1 May 1 18:04:37 check_reload_status: Linkup starting re0 05-01-2014 18:04:41 Daemon.Info 172.16.1.1 May 1 18:04:37 dhclient[92018]: DHCPREQUEST on re0 to 255.255.255.255 port 67 05-01-2014 18:04:39 User.Notice 172.16.1.1 May 1 18:04:35 check_reload_status: Linkup starting re0 05-01-2014 18:04:39 Kernel.Notice 172.16.1.1 May 1 18:04:35 kernel: re0: link state changed to DOWN 05-01-2014 18:04:39 Daemon.Info 172.16.1.1 May 1 18:04:35 dhclient[92018]: DHCPREQUEST on re0 to 255.255.255.255 port 67 05-01-2014 18:04:38 Daemon.Info 172.16.1.1 May 1 18:04:33 dhclient[92018]: DHCPREQUEST on re0 to 255.255.255.255 port 67 05-01-2014 18:04:38 User.Notice 172.16.1.1 May 1 18:04:33 dhclient: Comparing IPs: Old: New: 05-01-2014 18:04:38 User.Notice 172.16.1.1 May 1 18:04:33 dhclient: Starting delete_old_states() 05-01-2014 18:04:38 User.Notice 172.16.1.1 May 1 18:04:33 dhclient: PREINIT 05-01-2014 18:04:37 User.Error 172.16.1.1 May 1 18:04:33 php: rc.linkup: HOTPLUG: Configuring interface wan 05-01-2014 18:04:37 User.Error 172.16.1.1 May 1 18:04:33 php: rc.linkup: DEVD Ethernet attached event for wan 05-01-2014 18:04:35 Kernel.Notice 172.16.1.1 May 1 18:04:31 kernel: re0: link state changed to UP 05-01-2014 18:04:35 User.Notice 172.16.1.1 May 1 18:04:31 check_reload_status: Linkup starting re0 05-01-2014 18:04:26 Kernel.Debug 172.16.1.1 May 1 18:04:21 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:26 Kernel.Debug 172.16.1.1 May 1 18:04:21 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:26 User.Error 172.16.1.1 May 1 18:04:21 php: rc.linkup: Clearing states to old gateway 68.112.156.1. 05-01-2014 18:04:25 Kernel.Debug 172.16.1.1 May 1 18:04:21 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:25 Kernel.Debug 172.16.1.1 May 1 18:04:21 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:25 Kernel.Debug 172.16.1.1 May 1 18:04:21 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:25 Kernel.Debug 172.16.1.1 May 1 18:04:21 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:24 System2.Info 172.16.1.1 May 1 18:04:20 ntpd[17170]: 74.120.8.2 local addr 68.112.157.243 -> <null> 05-01-2014 18:04:24 System2.Info 172.16.1.1 May 1 18:04:20 ntpd[17170]: Deleting interface #2 re0, 68.112.157.243#123, interface stats: received=27, sent=27, dropped=0, active_time=1450 secs 05-01-2014 18:04:24 Kernel.Debug 172.16.1.1 May 1 18:04:20 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:24 Kernel.Debug 172.16.1.1 May 1 18:04:20 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:24 Kernel.Debug 172.16.1.1 May 1 18:04:20 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:23 Kernel.Debug 172.16.1.1 May 1 18:04:19 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:23 Kernel.Debug 172.16.1.1 May 1 18:04:19 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:23 Kernel.Debug 172.16.1.1 May 1 18:04:18 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:22 Kernel.Debug 172.16.1.1 May 1 18:04:18 kernel: arpresolve: can't allocate llinfo for 68.112.156.1 05-01-2014 18:04:21 Daemon.Critical 172.16.1.1 May 1 18:04:17 dhclient[77765]: exiting. 05-01-2014 18:04:21 Daemon.Error 172.16.1.1 May 1 18:04:17 dhclient[77765]: connection closed 05-01-2014 18:04:21 User.Error 172.16.1.1 May 1 18:04:17 php: rc.linkup: DEVD Ethernet detached event for wan 05-01-2014 18:04:19 User.Notice 172.16.1.1 May 1 18:04:15 check_reload_status: Linkup starting re0 05-01-2014 18:04:19 Kernel.Notice 172.16.1.1 May 1 18:04:15 kernel: re0: link state changed to DOWN 05-01-2014 18:04:15 User.Notice 172.16.1.1 May 1 18:04:11 check_reload_status: Reloading filter 05-01-2014 18:04:15 User.Notice 172.16.1.1 May 1 18:04:11 check_reload_status: Restarting OpenVPN tunnels/interfaces 05-01-2014 18:04:15 User.Notice 172.16.1.1 May 1 18:04:11 check_reload_status: Restarting ipsec tunnels 05-01-2014 18:04:15 User.Notice 172.16.1.1 May 1 18:04:11 check_reload_status: updating dyndns WAN_DHCP 05-01-2014 18:04:05 User.Error 172.16.1.1 May 1 18:04:01 apinger: ALARM: WAN_DHCP(208.67.220.220) *** down *** 05-01-2014 18:00:04 Cron.Info 172.16.1.1 May 1 18:00:00 /usr/sbin/cron[31652]: (root) CMD (/usr/bin/nice -n20 /usr/local/sbin/expiretable -v -t 3600 sshlockout) 05-01-2014 18:00:04 Cron.Info 172.16.1.1 May 1 18:00:00 /usr/sbin/cron[31414]: (root) CMD (/usr/bin/nice -n20 /usr/local/sbin/expiretable -v -t 3600 virusprot) 05-01-2014 18:00:04 Cron.Info 172.16.1.1 May 1 18:00:00 /usr/sbin/cron[31192]: (root) CMD (/usr/local/bin/php -q /usr/local/www/pfblocker.php cron) 05-01-2014 17:47:22 Local7.Info 172.16.1.1 May 1 17:47:18 dhcpd: DHCPACK on 172.16.1.14 to f8:f1:b6:ad:4a:a0 (android-b51578e06c580150) via re1 05-01-2014 17:47:22 Local7.Info 172.16.1.1 May 1 17:47:18 dhcpd: DHCPREQUEST for 172.16.1.14 from f8:f1:b6:ad:4a:a0 (android-b51578e06c580150) via re1</null>
-
I would suggest that DD-WRT is simply not catching the interruption in service. The connection comes back up in ~1min so there's a good chance you simply don't notice.
You could try disabling apinger for the WAN which will probably put you on par with DD-WRT but, as Chris said, it looks like your cable service is going down.
Go to System: Routing: Gateways:
Edit the wan gateway, check 'disable gateway monitoring'.Steve
