On Sat, Nov 17, 2012 at 9:56 AM, Jeppe Øland <[email protected]> wrote:
> I just noticed one thing with pfSense 2.0.1. > > Since I was moving some gear around, I had to reboot the firewall, and it > booted at a time when no network could be obtained. > I'm connecting to the internet through a switch, so there was a link > detected, but nothing was connected on the other side. > > Parts of the boot log: > > Nov 16 22:01:07 kernel: Trying to mount root from ufs:/dev/ad4s1a > Nov 16 22:01:07 kernel: pflog0: promiscuous mode enabled > Nov 16 22:01:09 apinger: Starting Alarm Pinger, apinger(31602) > Nov 16 22:01:09 apinger: No usable targets found, exiting > Nov 16 22:01:10 php: : ROUTING: setting default route to <PREVIOUS GW> > Nov 16 22:01:10 php: : The command '/sbin/route change -inet default > '<PREVIOUS GW>'' returned exit code '1', the output was 'route: writing to > routing socket: No such process route: writing to routing socket: Network > is unreachable change net default: gateway <PREVIOUS GW> Network is > unreachable' > Nov 16 22:01:10 check_reload_status: Linkup starting em0 > Nov 16 22:01:10 kernel: em0: link state changed to UP > Nov 16 22:01:10 dhcpleases: Could not deliver signal HUP to process > because its pidfile does not exist, No such file or directory. > Nov 16 22:01:10 dhcpd: Internet Systems Consortium DHCP Server 4.2.3 > Nov 16 22:01:10 dhcpd: Copyright 2004-2011 Internet Systems Consortium. > Nov 16 22:01:10 dhcpd: All rights reserved. > Nov 16 22:01:10 dhcpd: For info, please visit > https://www.isc.org/software/dhcp/ > Nov 16 22:01:10 dhcpleases: Could not deliver signal HUP to process > because its pidfile does not exist, No such file or directory. > Nov 16 22:01:10 dhcpleases: Could not deliver signal HUP to process > because its pidfile does not exist, No such file or directory. > Nov 16 22:01:10 dhcpleases: Could not deliver signal HUP to process > because its pidfile does not exist, No such file or directory. > Nov 16 22:01:10 check_reload_status: Updating all dyndns > Nov 16 22:01:10 dnsmasq[45115]: started, version 2.55 cachesize 10000 > Nov 16 22:01:10 dnsmasq[45115]: compile time options: IPv6 GNU-getopt > no-DBus I18N DHCP TFTP > Nov 16 22:01:10 dnsmasq[45115]: reading /etc/resolv.conf > Nov 16 22:01:10 dnsmasq[45115]: using nameserver 8.8.4.4#53 > Nov 16 22:01:10 dnsmasq[45115]: using nameserver 8.8.8.8#53 > Nov 16 22:01:10 dnsmasq[45115]: using nameserver 8.8.4.4#53 > Nov 16 22:01:10 dnsmasq[45115]: using nameserver 8.8.8.8#53 > Nov 16 22:01:10 dnsmasq[45115]: ignoring nameserver 127.0.0.1 - local > interface > Nov 16 22:01:10 dnsmasq[45115]: ignoring nameserver 127.0.0.1 - local > interface > Nov 16 22:01:10 dnsmasq[45115]: read /etc/hosts - 14 addresses > Nov 16 22:01:16 php: : DynDns: updatedns() starting > Nov 16 22:01:16 php: : There was an error trying to determine the IP for > interface - wan(em1_vlan1001). Probably interface has no ip or is down. > Dyndns update not possible for dyndns. > Nov 16 22:01:17 php: : DynDns: updatedns() starting > Nov 16 22:01:17 php: : There was an error trying to determine the IP for > interface - opt1(em1_vlan1002). Probably interface has no ip or is down. > Dyndns update not possible for dyndns. > Nov 16 22:01:26 php: : OpenNTPD is starting up. > Nov 16 22:01:28 check_reload_status: Restarting ipsec tunnels > Nov 16 22:01:30 php: : Creating rrd update script > Nov 16 22:01:31 php: : Restarting/Starting all packages. > Nov 16 22:01:32 login: login on ttyv0 as root > Nov 16 22:01:32 sshlockout[30168]: sshlockout/webConfigurator v3.0 > starting up > Nov 16 22:01:33 php: : IPSEC: One or more IPsec tunnel endpoints has > changed its IP. Refreshing. > Nov 16 22:01:35 check_reload_status: Reloading filter > Nov 16 22:11:31 dhclient[9805]: connection closed > Nov 16 22:11:31 dhclient[9805]: connection closed > Nov 16 22:11:31 dhclient[9805]: exiting. > Nov 16 22:11:31 dhclient[9805]: exiting. > Nov 16 22:11:35 dhclient: PREINIT > Nov 16 22:11:35 dhclient[3292]: DHCPREQUEST on em1_vlan1001 to > 255.255.255.255 port 67 > Nov 16 22:11:37 dhclient[3292]: DHCPREQUEST on em1_vlan1001 to > 255.255.255.255 port 67 > Nov 16 22:11:39 dhclient[3292]: DHCPREQUEST on em1_vlan1001 to > 255.255.255.255 port 67 > Nov 16 22:11:44 dhclient[3292]: DHCPREQUEST on em1_vlan1001 to > 255.255.255.255 port 67 > Nov 16 22:11:49 dhclient[3292]: DHCPDISCOVER on em1_vlan1001 to > 255.255.255.255 port 67 interval 1 > Nov 16 22:11:50 dhclient[3292]: DHCPDISCOVER on em1_vlan1001 to > 255.255.255.255 port 67 interval 2 > Nov 16 22:11:52 dhclient[3292]: DHCPDISCOVER on em1_vlan1001 to > 255.255.255.255 port 67 interval 5 > Nov 16 22:11:57 dhclient[3292]: DHCPDISCOVER on em1_vlan1001 to > 255.255.255.255 port 67 interval 14 > Nov 16 22:12:11 dhclient[3292]: DHCPDISCOVER on em1_vlan1001 to > 255.255.255.255 port 67 interval 9 > Nov 16 22:12:20 dhclient[3292]: DHCPDISCOVER on em1_vlan1001 to > 255.255.255.255 port 67 interval 14 > Nov 16 22:12:34 dhclient[3292]: DHCPDISCOVER on em1_vlan1001 to > 255.255.255.255 port 67 interval 14 > Nov 16 22:12:48 dhclient[3292]: DHCPDISCOVER on em1_vlan1001 to > 255.255.255.255 port 67 interval 2 > Nov 16 22:12:50 dhclient[3292]: No DHCPOFFERS received. > Nov 16 22:12:50 dhclient[3292]: Trying recorded lease <PREVIOUS IP> > Nov 16 22:12:50 dhclient: TIMEOUT > > As can be seen from "Nov 16 22:01:09", apinger quit. > > A while later, I plugged WAN into the switch: > > Nov 17 00:29:56 dhclient[51811]: DHCPDISCOVER on em1_vlan1001 to > 255.255.255.255 port 67 interval 11 > Nov 17 00:29:57 dhclient[51811]: DHCPOFFER from <NEW GW> > Nov 17 00:29:58 dhclient: ARPSEND > Nov 17 00:29:59 dhclient: ARPCHECK > Nov 17 00:29:59 dhclient[51811]: DHCPREQUEST on em1_vlan1001 to > 255.255.255.255 port 67 > Nov 17 00:29:59 dhclient[51811]: DHCPACK from <NEW GW> > Nov 17 00:29:59 dhclient: BOUND > Nov 17 00:29:59 dhclient: Starting add_new_address() > Nov 17 00:29:59 dhclient: ifconfig em1_vlan1001 inet <NEW IP> netmask > <NEW MASK> broadcast <NEW BROADCAST> > Nov 17 00:29:59 dhclient: New IP Address (em1_vlan1001): <NEW IP> > Nov 17 00:29:59 dhclient: New Subnet Mask (em1_vlan1001): <NEW MASK> > Nov 17 00:29:59 dhclient: New Broadcast Address (em1_vlan1001): <NEW > BROADCAST> > Nov 17 00:29:59 dhclient: New Routers (em1_vlan1001): <NEW GW> > Nov 17 00:29:59 dhclient: Adding new routes to interface: em1_vlan1001 > Nov 17 00:29:59 dhclient: /sbin/route add default <NEW GW> > Nov 17 00:29:59 dhclient: Creating resolv.conf > Nov 17 00:29:59 dhclient[51811]: bound to <NEW IP> -- renewal in 1800 > seconds. > > Normally you should have rc.newwanip kick in here that does all the magic. Either you did not leave enough time to the script to execute(iirc needs ~10secs to kick in) or something happened preventing rc.newwanip from running. > The connection started working immediately, but apinger didn't kick in so > the RRD quality graph was quiet. > > I now manually clicked System/Routing/Edit gateway/Save/Apply to restart > it. > > Nov 17 00:37:30 check_reload_status: Syncing firewall > Nov 17 00:37:32 php: /system_gateways.php: ROUTING: setting default route > to <NEW GW> > Nov 17 00:37:32 check_reload_status: Reloading filter > Nov 17 00:37:33 apinger: Starting Alarm Pinger, apinger(62336) > > Now everything was happy. > > Looks like there's a bug where apinger ends up not running ... or am I on > crack? > > Regards, > -Jeppe > > _______________________________________________ > List mailing list > [email protected] > http://lists.pfsense.org/mailman/listinfo/list > >
_______________________________________________ List mailing list [email protected] http://lists.pfsense.org/mailman/listinfo/list
