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.

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

Reply via email to