[2.3.2-RELEASE][admin@pfbox.local]/root: cat /opt/all.log Sep 9 13:25:36 pfbox syslogd: kernel boot file is /boot/kernel/kernel Sep 9 13:25:45 pfbox pfbox.local nginx: 2016/09/09 13:25:45 [error] 19932#100170: send() failed (54: Connection reset by peer) Sep 9 13:26:31 pfbox check_reload_status: Syncing firewall Sep 9 13:26:35 pfbox check_reload_status: Linkup starting igb1 Sep 9 13:26:35 pfbox kernel: igb1: link state changed to DOWN Sep 9 13:26:36 pfbox php-fpm[75081]: /rc.linkup: DEVD Ethernet detached event for wan Sep 9 13:26:36 pfbox check_reload_status: Reloading filter Sep 9 13:26:37 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:26:37 pfbox xinetd[10864]: Swapping defaults Sep 9 13:26:37 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:26:37 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:26:40 pfbox kernel: igb1: link state changed to UP Sep 9 13:26:40 pfbox check_reload_status: Linkup starting igb1 Sep 9 13:26:40 pfbox check_reload_status: rc.newwanip starting igb1 Sep 9 13:26:40 pfbox php-fpm[69947]: /interfaces.php: ROUTING: setting default route to 73.241.114.1 Sep 9 13:26:40 pfbox check_reload_status: Restarting ipsec tunnels Sep 9 13:26:41 pfbox php-fpm[75081]: /rc.linkup: DEVD Ethernet attached event for wan Sep 9 13:26:41 pfbox php-fpm[75081]: /rc.linkup: HOTPLUG: Configuring interface wan Sep 9 13:26:41 pfbox kernel: igb1: link state changed to DOWN Sep 9 13:26:41 pfbox check_reload_status: Linkup starting igb1 Sep 9 13:26:41 pfbox php-fpm[90271]: /rc.newwanip: rc.newwanip: Info: starting on igb1. Sep 9 13:26:41 pfbox php-fpm[90271]: /rc.newwanip: rc.newwanip: on (IP address: ) (interface: WAN[wan]) (real interface: igb1). Sep 9 13:26:41 pfbox php-fpm[90271]: /rc.newwanip: rc.newwanip: Failed to update wan IP, restarting... Sep 9 13:26:41 pfbox check_reload_status: Configuring interface wan Sep 9 13:26:42 pfbox check_reload_status: updating dyndns wan Sep 9 13:26:42 pfbox php-fpm[92780]: /rc.interfaces_wan_configure: The command '/sbin/dhclient -c /var/etc/dhclient_wan.conf igb1 > /tmp/igb1_output 2> /tmp/igb1_error_output' returned exit code '1', the output was '' Sep 9 13:26:44 pfbox check_reload_status: Reloading filter Sep 9 13:26:44 pfbox php-fpm[69947]: /interfaces.php: Creating rrd update script Sep 9 13:26:45 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:26:45 pfbox xinetd[10864]: Swapping defaults Sep 9 13:26:45 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:26:45 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:26:46 pfbox kernel: igb1: link state changed to UP Sep 9 13:26:46 pfbox check_reload_status: Linkup starting igb1 Sep 9 13:26:46 pfbox check_reload_status: rc.newwanip starting igb1 Sep 9 13:26:46 pfbox php-fpm[75081]: /rc.linkup: ROUTING: setting default route to 73.241.114.1 Sep 9 13:26:46 pfbox check_reload_status: Restarting ipsec tunnels Sep 9 13:26:47 pfbox php-fpm[22042]: /rc.newwanip: rc.newwanip: Info: starting on igb1. Sep 9 13:26:47 pfbox php-fpm[22042]: /rc.newwanip: rc.newwanip: on (IP address: 73.241.XXX.XXX) (interface: WAN[wan]) (real interface: igb1). Sep 9 13:26:47 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:26:47 pfbox xinetd[10864]: Swapping defaults Sep 9 13:26:47 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:26:47 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:26:48 pfbox php-fpm[22042]: /rc.newwanip: ROUTING: setting default route to 73.241.114.1 Sep 9 13:26:49 pfbox check_reload_status: updating dyndns wan Sep 9 13:26:49 pfbox check_reload_status: Reloading filter Sep 9 13:26:49 pfbox php-fpm[90271]: /rc.linkup: DEVD Ethernet detached event for wan Sep 9 13:26:49 pfbox php-fpm[22042]: /rc.newwanip: The command '/usr/local/sbin/unbound -c /var/unbound/unbound.conf' returned exit code '1', the output was '[1473452809] unbound[35411:0] error: bind: address already in use [1473452809] unbound[35411:0] fatal error: could not open ports' Sep 9 13:26:50 pfbox check_reload_status: Reloading filter Sep 9 13:26:50 pfbox php-fpm[92780]: /rc.linkup: DEVD Ethernet attached event for wan Sep 9 13:26:50 pfbox php-fpm[92780]: /rc.linkup: HOTPLUG: Configuring interface wan Sep 9 13:26:50 pfbox check_reload_status: Linkup starting igb1 Sep 9 13:26:50 pfbox kernel: igb1: link state changed to DOWN Sep 9 13:26:50 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:26:50 pfbox xinetd[10864]: Swapping defaults Sep 9 13:26:50 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:26:50 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:26:50 pfbox php-fpm[22042]: /rc.newwanip: Resyncing OpenVPN instances for interface WAN. Sep 9 13:26:50 pfbox php-fpm[22042]: /rc.newwanip: Creating rrd update script Sep 9 13:26:50 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:50 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:50 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:51 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:51 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:51 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:51 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:26:51 pfbox xinetd[10864]: Swapping defaults Sep 9 13:26:51 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:26:51 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:26:51 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:51 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:51 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:52 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:52 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:52 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:52 pfbox php-fpm[22042]: /rc.newwanip: pfSense package system has detected an IP change or dynamic WAN reconnection - 73.241.XXX.XXX -> 73.241.XXX.XXX - Restarting packages. Sep 9 13:26:52 pfbox check_reload_status: Starting packages Sep 9 13:26:52 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:52 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:52 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:53 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:53 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:53 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:53 pfbox check_reload_status: updating dyndns sept8 Sep 9 13:26:53 pfbox check_reload_status: Restarting ipsec tunnels Sep 9 13:26:53 pfbox check_reload_status: Restarting OpenVPN tunnels/interfaces Sep 9 13:26:53 pfbox check_reload_status: Reloading filter Sep 9 13:26:53 pfbox check_reload_status: updating dyndns jul26 Sep 9 13:26:53 pfbox check_reload_status: Restarting ipsec tunnels Sep 9 13:26:53 pfbox check_reload_status: Restarting OpenVPN tunnels/interfaces Sep 9 13:26:53 pfbox check_reload_status: Reloading filter Sep 9 13:26:53 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:53 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:53 pfbox php-fpm[72838]: /rc.start_packages: Restarting/Starting all packages. Sep 9 13:26:53 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:54 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:54 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:54 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:26:54 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:54 pfbox xinetd[10864]: Swapping defaults Sep 9 13:26:54 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:26:54 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:26:54 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:26:54 pfbox xinetd[10864]: Swapping defaults Sep 9 13:26:54 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:26:54 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:26:54 pfbox kernel: igb1: link state changed to UP Sep 9 13:26:54 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:54 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:54 pfbox check_reload_status: Linkup starting igb1 Sep 9 13:26:54 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:54 pfbox check_reload_status: rc.newwanip starting igb1 Sep 9 13:26:54 pfbox php-fpm[92780]: /rc.linkup: ROUTING: setting default route to 73.241.114.1 Sep 9 13:26:54 pfbox check_reload_status: Restarting ipsec tunnels Sep 9 13:26:55 pfbox php-fpm[95697]: /rc.newwanip: rc.newwanip: Info: starting on igb1. Sep 9 13:26:55 pfbox php-fpm[95697]: /rc.newwanip: rc.newwanip: on (IP address: 73.241.XXX.XXX) (interface: WAN[wan]) (real interface: igb1). Sep 9 13:26:56 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:26:56 pfbox xinetd[10864]: Swapping defaults Sep 9 13:26:56 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:26:56 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:26:56 pfbox php-fpm[95697]: /rc.newwanip: ROUTING: setting default route to 73.241.114.1 Sep 9 13:26:57 pfbox check_reload_status: updating dyndns wan Sep 9 13:26:57 pfbox check_reload_status: Reloading filter Sep 9 13:26:57 pfbox php-fpm[90271]: /rc.linkup: DEVD Ethernet detached event for wan Sep 9 13:26:58 pfbox check_reload_status: Reloading filter Sep 9 13:26:58 pfbox php-fpm[72838]: /rc.linkup: DEVD Ethernet attached event for wan Sep 9 13:26:58 pfbox php-fpm[72838]: /rc.linkup: HOTPLUG: Configuring interface wan Sep 9 13:26:58 pfbox check_reload_status: Linkup starting igb1 Sep 9 13:26:58 pfbox kernel: igb1: link state changed to DOWN Sep 9 13:26:58 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:26:58 pfbox xinetd[10864]: Swapping defaults Sep 9 13:26:58 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:26:58 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:26:58 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:58 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:58 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:58 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:58 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:58 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:59 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:26:59 pfbox xinetd[10864]: Swapping defaults Sep 9 13:26:59 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:26:59 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:26:59 pfbox check_reload_status: updating dyndns jul26 Sep 9 13:26:59 pfbox check_reload_status: Restarting ipsec tunnels Sep 9 13:26:59 pfbox check_reload_status: Restarting OpenVPN tunnels/interfaces Sep 9 13:26:59 pfbox check_reload_status: Reloading filter Sep 9 13:26:59 pfbox check_reload_status: updating dyndns sept8 Sep 9 13:26:59 pfbox check_reload_status: Restarting ipsec tunnels Sep 9 13:26:59 pfbox check_reload_status: Restarting OpenVPN tunnels/interfaces Sep 9 13:26:59 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:59 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:59 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:59 pfbox php-fpm[95697]: /rc.newwanip: Resyncing OpenVPN instances for interface WAN. Sep 9 13:26:59 pfbox php-fpm[95697]: /rc.newwanip: Creating rrd update script Sep 9 13:26:59 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:59 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:26:59 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:00 pfbox check_reload_status: Reloading filter Sep 9 13:27:00 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:00 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:00 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:00 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:00 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:01 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:01 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:27:01 pfbox xinetd[10864]: Swapping defaults Sep 9 13:27:01 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:27:01 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:27:01 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:01 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:01 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:01 pfbox php-fpm[95697]: /rc.newwanip: pfSense package system has detected an IP change or dynamic WAN reconnection - 73.241.XXX.XXX -> 73.241.XXX.XXX - Restarting packages. Sep 9 13:27:01 pfbox check_reload_status: Starting packages Sep 9 13:27:01 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:01 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:02 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:02 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:02 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:02 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:02 pfbox kernel: igb1: link state changed to UP Sep 9 13:27:02 pfbox check_reload_status: Linkup starting igb1 Sep 9 13:27:02 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:02 pfbox check_reload_status: Syncing firewall Sep 9 13:27:02 pfbox kernel: arpresolve: can't allocate llinfo for 73.241.114.1 on igb1 Sep 9 13:27:02 pfbox php-fpm[32716]: /rc.start_packages: Restarting/Starting all packages. Sep 9 13:27:02 pfbox check_reload_status: Reloading filter Sep 9 13:27:02 pfbox check_reload_status: rc.newwanip starting igb1 Sep 9 13:27:02 pfbox php-fpm[72838]: /rc.linkup: ROUTING: setting default route to 73.241.114.1 Sep 9 13:27:02 pfbox check_reload_status: Restarting ipsec tunnels Sep 9 13:27:04 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:27:04 pfbox xinetd[10864]: Swapping defaults Sep 9 13:27:04 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:27:04 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:27:04 pfbox php-fpm[68597]: /rc.newwanip: rc.newwanip: Info: starting on igb1. Sep 9 13:27:04 pfbox php-fpm[68597]: /rc.newwanip: Interface is disabled, nothing to do. Sep 9 13:27:07 pfbox check_reload_status: updating dyndns wan Sep 9 13:27:07 pfbox check_reload_status: Reloading filter Sep 9 13:27:07 pfbox php-fpm[92780]: /rc.linkup: DEVD Ethernet detached event for wan Sep 9 13:27:08 pfbox check_reload_status: Reloading filter Sep 9 13:27:08 pfbox php-fpm[32716]: /rc.linkup: Linkup detected on disabled interface...Ignoring Sep 9 13:27:08 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:27:08 pfbox xinetd[10864]: Swapping defaults Sep 9 13:27:08 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:27:08 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:27:09 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:27:09 pfbox xinetd[10864]: Swapping defaults Sep 9 13:27:09 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:27:09 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) Sep 9 13:27:10 pfbox check_reload_status: Reloading filter Sep 9 13:27:10 pfbox php-fpm[68597]: /interfaces.php: Creating rrd update script Sep 9 13:27:11 pfbox xinetd[10864]: Starting reconfiguration Sep 9 13:27:11 pfbox xinetd[10864]: Swapping defaults Sep 9 13:27:11 pfbox xinetd[10864]: readjusting service 6969-udp Sep 9 13:27:11 pfbox xinetd[10864]: Reconfigured: new=0 old=1 dropped=0 (services) [2.3.2-RELEASE][admin@pfbox.local]/root:
-- Jason E Belich ja...@belich.com On Fri, Sep 9, 2016 at 1:42 PM, Jason Belich <ja...@belich.com> wrote: > I can't enable dhcp on my WAN interface, or it starts flapping. From the > logs it looks like different services are stepping on each other, causing > the connection to flap, but I can't make heads or tails of any of it. Been > trying to figure this out for months, so my eyes go crosseyed everytime i > have to deal with it at this point. All I can do is copy the addresses from > the lease file into a static config, which only works as long as Comcast > (w/ Moto 6141 modem) sees fit to let me keep an IP. > > I appreciate any help anybody can give. I've attached a log file combined > from system.log and dhcpd.log. The log starts at the point where I select > DHCP in the WAN interface page and hit apply, and ends when I disable the > interface. > > Thanks again > > -- > Jason E Belich > ja...@belich.com > _______________________________________________ pfSense mailing list https://lists.pfsense.org/mailman/listinfo/list Support the project with Gold! https://pfsense.org/gold
