hi- i have an install of 2.1-BETA0 (i386) built on Fri Jul 13 19:59:57 EDT 2012, and the wan interface [dhcp] seems to periodically be losing it's address. under status -> interfaces, and in the output of ifconfig, the interface is up/active, no apparent problems, but simply does not have an ip address. going to interfaces -> wan -> save -> apply [without making any changes] brings it back to life, and it continues to work again for some time [it's been at least a few hours so far between incidents] until eventually doing the same thing again.
i'm not sure where the best place is to look for clues regarding this, but i did find some possibly related info [see below] in /var/log/system.log. the messages the seemed interesting to me were: php: : rc.newwanip: Failed to update wan IP, restarting... and php: : The command '/sbin/dhclient -c /var/etc/dhclient_wan.conf ue0 > /tmp/ue0_output > /tmp/ue0_error_output' returned exit code '1', the output was '' it seems to get an address ok prior to the "failed to update wan ip..." message, so i'm wondering how i can find out what the failure actually was, and dhclient exiting with code 1 seems to happen regularly. historically, nonzero exit statuses mean to me that something isn't happening as was expected. is that the case here? what else can i do to determine why this is happening? thanks -ben /var/log/system.log: Jul 17 07:03:42 gw1 check_reload_status: Syncing firewall Jul 17 07:03:46 gw1 php: /interfaces.php: Calling interface down for interface wan, destroy is Jul 17 07:03:46 gw1 dhclient[7538]: connection closed Jul 17 07:03:46 gw1 dhclient[7538]: connection closed Jul 17 07:03:46 gw1 dhclient[7538]: exiting. Jul 17 07:03:46 gw1 dhclient[7538]: exiting. Jul 17 07:03:47 gw1 php: /interfaces.php: Deny router advertisements for interface wan Jul 17 07:03:47 gw1 dhclient: PREINIT Jul 17 07:03:47 gw1 dhclient[25544]: DHCPREQUEST on ue0 to 255.255.255.255 port 67 Jul 17 07:03:47 gw1 dhclient[25544]: DHCPACK from 192.0.2.1 Jul 17 07:03:47 gw1 dhclient: REBOOT Jul 17 07:03:47 gw1 dhclient: Starting add_new_address() Jul 17 07:03:47 gw1 dhclient: ifconfig ue0 inet 192.0.2.199 netmask 255.255.252.0 broadcast 192.0.2.255 Jul 17 07:03:47 gw1 dhclient: New IP Address (ue0): 192.0.2.199 Jul 17 07:03:47 gw1 dhclient: New Subnet Mask (ue0): 255.255.252.0 Jul 17 07:03:47 gw1 dhclient: New Broadcast Address (ue0): 192.0.2.255 Jul 17 07:03:47 gw1 dhclient: New Routers (ue0): 192.0.2.1 Jul 17 07:03:47 gw1 dhclient: Adding new routes to interface: ue0 Jul 17 07:03:47 gw1 dhclient: /sbin/route add default 192.0.2.1 Jul 17 07:03:47 gw1 dhclient: Creating resolv.conf Jul 17 07:03:47 gw1 dhclient[25544]: bound to 192.0.2.199 -- renewal in 1800 seconds. Jul 17 07:03:47 gw1 check_reload_status: rc.newwanip starting ue0 Jul 17 07:03:47 gw1 php: /interfaces.php: ROUTING: setting default route to 192.0.2.1 Jul 17 07:03:49 gw1 check_reload_status: updating dyndns wan Jul 17 07:03:50 gw1 php: : rc.newwanip: Informational is starting ue0. Jul 17 07:03:50 gw1 php: : rc.newwanip: on (IP address: 192.0.2.199) (interface: wan) (real interface: ue0). Jul 17 07:03:50 gw1 php: : ROUTING: setting default route to 192.0.2.1 Jul 17 07:03:50 gw1 check_reload_status: Updating all dyndns Jul 17 07:03:50 gw1 check_reload_status: Restarting ipsec tunnels Jul 17 07:03:50 gw1 check_reload_status: Reloading filter Jul 17 07:03:50 gw1 check_reload_status: Restarting OpenVPN tunnels/interfaces Jul 17 07:03:52 gw1 php: /interfaces.php: Creating rrd update script Jul 17 11:13:02 192.168.1.191 ap3 ntp: Clock synchronized to network time server ntp.example.net (adjusted -0 seconds) Jul 17 11:14:43 192.168.1.191 ap3 dot11: Rotated CCMP group key. Jul 17 07:33:47 gw1 dhclient[30874]: DHCPREQUEST on ue0 to 172.20.0.3 port 67 Jul 17 07:33:47 gw1 dhclient[30874]: DHCPACK from 172.20.0.3 Jul 17 07:33:47 gw1 dhclient: RENEW Jul 17 07:33:47 gw1 dhclient: Creating resolv.conf Jul 17 07:33:47 gw1 dhclient[30874]: bound to 192.0.2.199 -- renewal in 1800 seconds. Jul 17 07:37:16 gw1 sshd[32912]: Accepted keyboard-interactive/pam for admin from 216.120.170.5 port 17522 ssh2 Jul 17 07:45:26 gw1 check_reload_status: Linkup starting ue0 Jul 17 07:45:26 gw1 kernel: ue0: link state changed to DOWN Jul 17 07:45:26 gw1 kernel: ue0: link state changed to UP Jul 17 07:45:27 gw1 check_reload_status: Linkup starting ue0 Jul 17 07:45:28 gw1 php: : DEVD Ethernet detached event for wan Jul 17 07:45:28 gw1 php: : Calling interface down for interface wan, destroy is Jul 17 07:45:28 gw1 dhclient[25562]: connection closed Jul 17 07:45:28 gw1 dhclient[25562]: connection closed Jul 17 07:45:28 gw1 dhclient[25562]: exiting. Jul 17 07:45:28 gw1 dhclient[25562]: exiting. Jul 17 07:45:29 gw1 php: : DEVD Ethernet attached event for wan Jul 17 07:45:29 gw1 php: : HOTPLUG: Configuring interface wan Jul 17 07:45:29 gw1 php: : Deny router advertisements for interface wan Jul 17 07:45:29 gw1 dhclient: PREINIT Jul 17 07:45:29 gw1 dhclient[16016]: DHCPREQUEST on ue0 to 255.255.255.255 port 67 Jul 17 07:45:29 gw1 dhclient[16016]: DHCPACK from 192.0.2.1 Jul 17 07:45:29 gw1 dhclient: REBOOT Jul 17 07:45:29 gw1 dhclient: Starting add_new_address() Jul 17 07:45:29 gw1 dhclient: ifconfig ue0 inet 192.0.2.199 netmask 255.255.252.0 broadcast 192.0.2.255 Jul 17 07:45:29 gw1 dhclient: New IP Address (ue0): 192.0.2.199 Jul 17 07:45:29 gw1 dhclient: New Subnet Mask (ue0): 255.255.252.0 Jul 17 07:45:29 gw1 dhclient: New Broadcast Address (ue0): 192.0.2.255 Jul 17 07:45:29 gw1 dhclient: New Routers (ue0): 192.0.2.1 Jul 17 07:45:29 gw1 dhclient: Adding new routes to interface: ue0 Jul 17 07:45:29 gw1 dhclient: /sbin/route add default 192.0.2.1 Jul 17 07:45:29 gw1 dhclient: Creating resolv.conf Jul 17 07:45:30 gw1 dhclient[16016]: bound to 192.0.2.199 -- renewal in 1800 seconds. Jul 17 07:45:30 gw1 check_reload_status: rc.newwanip starting ue0 Jul 17 07:45:32 gw1 php: : rc.newwanip: Informational is starting ue0. Jul 17 07:45:32 gw1 php: : rc.newwanip: on (IP address: 192.0.2.199) (interface: wan) (real interface: ue0). Jul 17 07:45:32 gw1 php: : ROUTING: setting default route to 192.0.2.1 Jul 17 07:45:33 gw1 check_reload_status: Reloading filter Jul 17 07:55:30 gw1 check_reload_status: Linkup starting ue0 Jul 17 07:55:30 gw1 kernel: ue0: link state changed to DOWN Jul 17 07:55:30 gw1 kernel: ue0: link state changed to UP Jul 17 07:55:30 gw1 check_reload_status: Linkup starting ue0 Jul 17 07:55:33 gw1 php: : DEVD Ethernet attached event for wan Jul 17 07:55:33 gw1 php: : DEVD Ethernet detached event for wan Jul 17 07:55:33 gw1 php: : Calling interface down for interface wan, destroy is Jul 17 07:55:33 gw1 dhclient[16040]: connection closed Jul 17 07:55:33 gw1 dhclient[16040]: connection closed Jul 17 07:55:33 gw1 dhclient[16040]: exiting. Jul 17 07:55:33 gw1 dhclient[16040]: exiting. Jul 17 07:55:33 gw1 php: : HOTPLUG: Configuring interface wan Jul 17 07:55:33 gw1 php: : Deny router advertisements for interface wan Jul 17 07:55:33 gw1 dhclient: PREINIT Jul 17 07:55:33 gw1 dhclient[26917]: DHCPREQUEST on ue0 to 255.255.255.255 port 67 Jul 17 07:55:33 gw1 dhclient[26917]: DHCPACK from 192.0.2.1 Jul 17 07:55:33 gw1 dhclient: REBOOT Jul 17 07:55:33 gw1 dhclient: Starting add_new_address() Jul 17 07:55:33 gw1 dhclient: ifconfig ue0 inet 192.0.2.199 netmask 255.255.252.0 broadcast 192.0.2.255 Jul 17 07:55:33 gw1 dhclient: New IP Address (ue0): 192.0.2.199 Jul 17 07:55:33 gw1 dhclient: New Subnet Mask (ue0): 255.255.252.0 Jul 17 07:55:33 gw1 dhclient: New Broadcast Address (ue0): 192.0.2.255 Jul 17 07:55:33 gw1 dhclient: New Routers (ue0): 192.0.2.1 Jul 17 07:55:33 gw1 dhclient: Adding new routes to interface: ue0 Jul 17 07:55:33 gw1 dhclient: /sbin/route add default 192.0.2.1 Jul 17 07:55:33 gw1 dhclient: Creating resolv.conf Jul 17 07:55:34 gw1 dhclient[26917]: bound to 192.0.2.199 -- renewal in 1800 seconds. Jul 17 07:55:34 gw1 check_reload_status: rc.newwanip starting ue0 Jul 17 07:55:36 gw1 php: : rc.newwanip: Informational is starting ue0. Jul 17 07:55:36 gw1 php: : rc.newwanip: on (IP address: ) (interface: wan) (real interface: ue0). Jul 17 07:55:36 gw1 php: : rc.newwanip: Failed to update wan IP, restarting... Jul 17 07:55:36 gw1 check_reload_status: Configuring interface wan Jul 17 07:55:37 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:37 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:37 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:37 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:38 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:38 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:38 gw1 php: : Deny router advertisements for interface wan Jul 17 07:55:38 gw1 dhclient[34589]: dhclient already running, pid: 32836. Jul 17 07:55:38 gw1 dhclient[34589]: dhclient already running, pid: 32836. Jul 17 07:55:38 gw1 dhclient[34589]: exiting. Jul 17 07:55:38 gw1 dhclient[34589]: exiting. Jul 17 07:55:38 gw1 php: : The command '/sbin/dhclient -c /var/etc/dhclient_wan.conf ue0 > /tmp/ue0_output > /tmp/ue0_error_output' returned exit code '1', the output was '' Jul 17 07:55:39 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:40 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:40 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:42 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:42 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:45 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:45 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:48 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:49 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:51 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:51 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:51 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:51 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:52 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:52 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:52 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:52 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:53 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 Jul 17 07:55:53 gw1 kernel: arpresolve: can't allocate llinfo for 192.0.2.1 _______________________________________________ List mailing list [email protected] http://lists.pfsense.org/mailman/listinfo/list
