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.

    Syslogs:

    
    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>
    

  • Netgate Administrator

    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