DHCP does not work correctly on WAN interface


  • I just installed pfsense v. 1.2.2 and immediately encountered a problem with pfsense renewing the WAN IP address from my ISP's DHCP server. I even upgraded to 1.2.3-RC1 but still have the same problem.

    pfsense receives the IP address correctly during the initial handshake, but but cannot renew the lease. My internet connection just dies after 6 hours and I most manually force pfsense to release and renew the lease. Here are the logs, the DHCPREQUEST and DIRECT messages are sent forever:

    
    Sep 15 15:15:41 	dhclient[51495]: SENDING DIRECT
    Sep 15 15:15:41 	dhclient[51495]: DHCPREQUEST on fxp1 to 84.251.112.1 port 67
    Sep 15 15:15:32 	dhclient[51495]: SENDING DIRECT
    Sep 15 15:15:32 	dhclient[51495]: DHCPREQUEST on fxp1 to 84.251.112.1 port 67
    Sep 15 15:15:25 	dhclient[51495]: SENDING DIRECT
    Sep 15 15:15:25 	dhclient[51495]: DHCPREQUEST on fxp1 to 84.251.112.1 port 67
    Sep 15 09:15:30 	php: : rc.newwanip working with (IP address: 88.195.223.xxx) (interface: wan) (interface real: fxp1).
    Sep 15 09:15:30 	php: : Informational: rc.newwanip is starting .
    Sep 15 09:15:29 	check_reload_status: rc.newwanip starting
    Sep 15 09:15:25 	dhclient[51349]: bound to 88.195.223.xxx -- renewal in 21600 seconds.
    Sep 15 09:15:25 	dhclient[51349]: DHCPACK from 84.251.112.1
    Sep 15 09:15:25 	dhclient[51349]: DHCPREQUEST on fxp1 to 255.255.255.255 port 67
    Sep 15 09:15:23 	dhclient[51349]: DHCPOFFER from 84.251.112.1
    Sep 15 09:15:23 	dhclient[51349]: DHCPDISCOVER on fxp1 to 255.255.255.255 port 67 interval 4
    Sep 15 09:15:17 	last message repeated 2 times
    Sep 15 09:15:09 	dhclient[51349]: DHCPREQUEST on fxp1 to 255.255.255.255 port 67
    

    I tried switching my WAN interface to a xl0 but it did not help. The same hardware was previously used with Smoothwall express and the same ISP without any problems.

    Any ideas how to resolve this?


    • Pfsense tries to renew the lease at exactly the same second the lease expires. Could this be the problem, i.e. pfsense sends the request for renewal a little too late?

    • Is there a way to force pfsense to perform the full DHCP handshake everytime? This would solve the problem.


  • Not sure I am getting what you mean.  As far as I know, there is nothing that requires you to renew the lease at a particular time - e.g. there is no such thing as "too late" that I am aware of.


  • Updated to RC3, still have the same problem.

    What I meant with "too late" was that everything works correctly when pfsense makes a full DHCP handshake. The renewal (DHCPREQUEST and SENDING DIRECT) usually does not work. Could it be possible that pfsense tries to renew the lease "too late" ie a full DHCP handshake would be required instead?

    Well, whatever the reason, pfsense does not seem to work with my ISP, TeliaSonera.


  • Hmmm, could be an odd incompatibility between the freebsd dhcp client and your ISP's dhcp server.  Not sure offhand what to suggest…

    p.s. some years back, a friend of mine had been using a firewall appliance from GTA and had to replace it with a different product since their DHCP client seemed to misbehave when dealing with his new ISP.  I mention this because GTA's firewall product is based on a FreeBSD kernel and utilities, so it wouldn't be the first time I have seen this.


  • From the GUI, go to Diagnostics/Command

    type in "dhclient fxp1"

    Click on Execute.

    Should stop those annoying requests.

    For those with other NIC's, the command is "dhclient (WAN interface name)"

    http://www.nabble.com/dhclient-cant-renew-lease.-td22166599.html


  • I'm having the same problem but thankfully i've got a static IP from my ISP (sunwave.net). When I changed my account with them to static IPs they said to still use DHCP for whatever reason. Now my logs are filling up with

    Oct 29 18:42:42 	dhclient[350]: SENDING DIRECT
    Oct 29 18:42:42 	dhclient[350]: DHCPREQUEST on bge0 to 208.98.210.129 port 67
    Oct 29 18:41:53 	dhclient[350]: SENDING DIRECT
    Oct 29 18:41:53 	dhclient[350]: DHCPREQUEST on bge0 to 208.98.210.129 port 67
    Oct 29 18:41:13 	dhclient[350]: SENDING DIRECT
    Oct 29 18:41:13 	dhclient[350]: DHCPREQUEST on bge0 to 208.98.210.129 port 67
    Oct 29 18:40:47 	dhclient[350]: SENDING DIRECT
    Oct 29 18:40:47 	dhclient[350]: DHCPREQUEST on bge0 to 208.98.210.129 port 67
    Oct 29 18:40:25 	dhclient[350]: SENDING DIRECT
    Oct 29 18:40:25 	dhclient[350]: DHCPREQUEST on bge0 to 208.98.210.129 port 67
    Oct 29 18:40:02 	dhclient[350]: SENDING DIRECT
    Oct 29 18:40:02 	dhclient[350]: DHCPREQUEST on bge0 to 208.98.210.129 port 67
    

    Until finally

    Oct 29 18:43:34 	dhclient[350]: bound to 208.98.XXX.XXX -- renewal in 43200 seconds.
    Oct 29 18:43:34 	dhclient[350]: DHCPACK from 208.98.210.131
    Oct 29 18:43:34 	dhclient[350]: DHCPREQUEST on bge0 to 255.255.255.255 port 67
    

    So no real problem for me since everything still works, just a pain having all this crap in the logs.


  • Yes, eventually it will get an address but will repeat SENDING DIRECT after the next renewal time filling up your logs again.

    Should it happen again, just use the command I mentioned above and it should stop it for good until your next reboot.

    I have 3 pfSense sites, this happens on both my sites that use Optimum Online Cable.  The site that has Time Warner Road Runner Cable does not have this issue at all so it has something to do with the ISP's DHCP Server.


  • Had the same problem DHCPREQUEST but received no DHCPACK on opt1. However, proper functioning of the WAN.
    Normally dhclinet attempt to get a new IP and it begins with the mid-Lessing time, but when lesing time has expired will send a broadcast, then you lose the network maybe 1-2 seconds before regaining the connection and have an IP address .

    Did a packet capture on opt1 but saw no DHCP request to the DHCP server, do not know where the traffic went, but it did not take the correct route, try adding a static route to the server and then it worked immediately, and renew the less every time.

    The problem seems to be the ISP's DHCP server is not in the same subnet as opt1 IP-address and traffic does not route correctly. They use a DHCP Relay function to a central DHCP server for every customer, insted of one in every local telephone station. (i am using ADSL)

    To finde the server to make a static route
    cat /var/db/dhclient.leases. [interface name]
    look for the "option dhcp-server-identifier", where you'll find the DHCP servers IP-adress and then add a static route for it

    Before:

    
    Nov 6 15:22:46 	dhclient[400]: bound to 81.233.xx.xxx -- renewal in 600 seconds.
    Nov 6 15:22:46 	dhclient[400]: DHCPACK from 81.232.150.1
    Nov 6 15:22:46 	dhclient[400]: DHCPREQUEST on em2 to 255.255.255.255 port 67
    Nov 6 15:21:51 	dhclient[400]: SENDING DIRECT
    Nov 6 15:21:51 	dhclient[400]: DHCPREQUEST on em2 to 62.20.251.122 port 67
    Nov 6 15:21:17 	dhclient[400]: SENDING DIRECT
    Nov 6 15:21:17 	dhclient[400]: DHCPREQUEST on em2 to 62.20.251.122 port 67
    
    

    After:

    
    Nov 6 15:42:46 	dhclient[400]: bound to 81.233.xx.xxx -- renewal in 600 seconds.
    Nov 6 15:42:46 	dhclient[400]: DHCPACK from 62.20.251.122
    Nov 6 15:42:46 	dhclient[400]: SENDING DIRECT
    Nov 6 15:42:46 	dhclient[400]: DHCPREQUEST on em2 to 62.20.251.122 port 67
    Nov 6 15:32:46 	dhclient[400]: bound to 81.233.xx.xxx -- renewal in 600 seconds.
    Nov 6 15:32:46 	dhclient[400]: DHCPACK from 62.20.251.122
    Nov 6 15:32:46 	dhclient[400]: SENDING DIRECT
    Nov 6 15:32:46 	dhclient[400]: DHCPREQUEST on em2 to 62.20.251.122 port 67
    
    

  • Your default route is not over the WAN interface?


  • I have similar problem - my pfsense 1.2.2 can't renew IP address if the modem was suddenly powered down, lost link from the provider OR if the WAN cable was unplugged without properly releasing the IP address.
    If any of the above happens - the whole LAN will sit without internet until you do a manual release/renew or completely restart the router.

    Apparently pfsense after doing full handshake first time doesn't do it afterwards and hence not getting an IP address. But this is just a guess.

    We have two routers to choose from: crappy Dlink that freezes under heavy load, and pfsense that doesn't freeze, but has this significant DHCP renewing problem.

    Is there any way to schedule pfsense to automatically reboot at, say 7AM everyday - because right now what they do is manually power down the router (using a button on the case) and pfsense tells in the logs that "/was not properly unmounted, filesystem not clean". I am afraid its not going to boot one day.

    As far as I know my provider uses Cisco routers, may be they have some proprietary DHCP algorithms.

    Here is a log if anybody is interested and thank you.

    Nov 10 16:35:46 	kernel: fxp0: link state changed to DOWN
    Nov 10 16:35:53 	kernel: fxp0: link state changed to UP
    Nov 10 16:35:57 	check_reload_status: rc.linkup starting
    Nov 10 16:35:59 	php: : Processing fxp0 - start
    Nov 10 16:35:59 	php: : DEVD Ethernet attached event for fxp0
    Nov 10 16:35:59 	php: : HOTPLUG: Configuring wan interface fxp0
    Nov 10 16:36:02 	dhclient[949]: short write: wanted 20 got 0 bytes
    Nov 10 16:36:02 	dhclient[949]: short write: wanted 20 got 0 bytes
    Nov 10 16:36:02 	dhclient[949]: exiting.
    Nov 10 16:36:02 	dhclient[949]: exiting.
    Nov 10 16:36:02 	dhclient[40814]: DHCPREQUEST on fxp0 to 255.255.255.255 port 67
    Nov 10 16:36:11 	last message repeated 4 times
    Nov 10 16:36:19 	dhclient[40814]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 2
    Nov 10 16:36:21 	dhclient[40814]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 3
    Nov 10 16:36:24 	dhclient[40814]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 8
    Nov 10 16:36:32 	dhclient[40814]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 14
    Nov 10 16:36:41 	kernel: fxp0: link state changed to DOWN
    Nov 10 16:36:42 	kernel: fxp0: link state changed to UP
    Nov 10 16:36:46 	dhclient[40814]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 9
    Nov 10 16:36:55 	dhclient[40814]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 15
    Nov 10 16:37:10 	dhclient[40814]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 10
    Nov 10 16:37:14 	kernel: fxp0: link state changed to DOWN
    Nov 10 16:37:20 	dhclient[40814]: No DHCPOFFERS received.
    Nov 10 16:37:20 	dhclient[40814]: Trying recorded lease 10.10.67.20
    Nov 10 16:37:21 	dhclient[40814]: bound: renewal in 68860 seconds.
    Nov 10 16:37:23 	dnsmasq[979]: exiting on receipt of SIGTERM
    Nov 10 16:37:24 	dnsmasq[41025]: started, version 2.39 cachesize 150
    Nov 10 16:37:24 	dnsmasq[41025]: compile time options: IPv6 GNU-getopt ISC-leasefile no-DBus no-I18N TFTP
    Nov 10 16:37:24 	dnsmasq[41025]: reading /etc/resolv.conf
    Nov 10 16:37:24 	dnsmasq[41025]: using nameserver 10.10.10.84#53
    Nov 10 16:37:24 	dnsmasq[41025]: using nameserver 10.10.10.85#53
    Nov 10 16:37:24 	dnsmasq[41025]: read /etc/hosts - 2 addresses
    Nov 10 16:37:26 	dhcpd: Internet Systems Consortium DHCP Server V3.0.5
    Nov 10 16:37:26 	dhcpd: Copyright 2004-2006 Internet Systems Consortium.
    Nov 10 16:37:26 	dhcpd: All rights reserved.
    Nov 10 16:37:26 	dhcpd: For info, please visit http://www.isc.org/sw/dhcp/
    Nov 10 16:37:26 	php: : Processing start -
    Nov 10 16:37:26 	php: : Not a valid interface action ""
    Nov 10 16:37:26 	php: : Processing -
    Nov 10 16:37:26 	php: : Not a valid interface action ""
    Nov 10 16:37:26 	check_reload_status: reloading filter
    Nov 10 16:37:29 	kernel: fxp0: link state changed to UP
    Nov 10 16:37:32 	kernel: fxp0: link state changed to DOWN
    Nov 10 16:37:33 	kernel: fxp0: link state changed to UP
    Nov 10 16:37:34 	check_reload_status: rc.linkup starting
    Nov 10 16:37:36 	php: : Processing fxp0 - start
    Nov 10 16:37:36 	php: : DEVD Ethernet attached event for fxp0
    Nov 10 16:37:36 	php: : HOTPLUG: Configuring wan interface fxp0
    Nov 10 16:37:39 	dhclient[40997]: short write: wanted 20 got 0 bytes
    Nov 10 16:37:39 	dhclient[40997]: short write: wanted 20 got 0 bytes
    Nov 10 16:37:39 	dhclient[40997]: exiting.
    Nov 10 16:37:39 	dhclient[40997]: exiting.
    Nov 10 16:37:39 	dhclient[41213]: DHCPREQUEST on fxp0 to 255.255.255.255 port 67
    Nov 10 16:37:47 	last message repeated 3 times
    Nov 10 16:37:51 	dhclient[41213]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 1
    Nov 10 16:37:52 	dhclient[41213]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 1
    Nov 10 16:37:53 	dhclient[41213]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 2
    Nov 10 16:37:55 	dhclient[41213]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 5
    Nov 10 16:38:00 	dhclient[41213]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 10
    Nov 10 16:38:10 	dhclient[41213]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 20
    Nov 10 16:38:10 	dhclient[41213]: DHCPOFFER from 10.10.67.253
    Nov 10 16:38:12 	dhclient[41213]: DHCPREQUEST on fxp0 to 255.255.255.255 port 67
    Nov 10 16:38:12 	dhclient[41213]: DHCPACK from 10.10.67.253
    Nov 10 16:38:12 	dhclient[41213]: bound to 10.10.66.67 -- renewal in 259200 seconds.
    Nov 10 16:38:15 	dnsmasq[41025]: exiting on receipt of SIGTERM
    Nov 10 16:38:16 	dnsmasq[41387]: started, version 2.39 cachesize 150
    Nov 10 16:38:16 	dnsmasq[41387]: compile time options: IPv6 GNU-getopt ISC-leasefile no-DBus no-I18N TFTP
    Nov 10 16:38:16 	dnsmasq[41387]: reading /etc/resolv.conf
    Nov 10 16:38:16 	dnsmasq[41387]: using nameserver 10.10.10.84#53
    Nov 10 16:38:16 	dnsmasq[41387]: using nameserver 10.10.10.85#53
    Nov 10 16:38:16 	dnsmasq[41387]: read /etc/hosts - 2 addresses
    Nov 10 16:38:17 	dhcpd: Internet Systems Consortium DHCP Server V3.0.5
    Nov 10 16:38:17 	dhcpd: Copyright 2004-2006 Internet Systems Consortium.
    Nov 10 16:38:17 	dhcpd: All rights reserved.
    Nov 10 16:38:17 	dhcpd: For info, please visit http://www.isc.org/sw/dhcp/
    Nov 10 16:38:17 	php: : Processing start - fxp0
    Nov 10 16:38:17 	php: : Hotplug event detected for start but ignoring since interface is not set for DHCP
    Nov 10 16:38:17 	php: : Processing fxp0 - start
    Nov 10 16:38:17 	php: : DEVD Ethernet attached event for fxp0
    Nov 10 16:38:17 	php: : HOTPLUG: Configuring wan interface fxp0
    Nov 10 16:38:21 	dhclient[41359]: short write: wanted 20 got 0 bytes
    Nov 10 16:38:21 	dhclient[41359]: short write: wanted 20 got 0 bytes
    Nov 10 16:38:21 	dhclient[41359]: exiting.
    Nov 10 16:38:21 	dhclient[41359]: exiting.
    Nov 10 16:38:21 	dhclient[41457]: DHCPREQUEST on fxp0 to 255.255.255.255 port 67
    Nov 10 16:38:21 	dhclient[41457]: DHCPACK from 10.10.67.254
    Nov 10 16:38:21 	dhclient[41457]: bound to 10.10.66.67 -- renewal in 259200 seconds.
    Nov 10 16:38:23 	dnsmasq[41387]: exiting on receipt of SIGTERM
    Nov 10 16:38:25 	dnsmasq[41540]: started, version 2.39 cachesize 150
    Nov 10 16:38:25 	dnsmasq[41540]: compile time options: IPv6 GNU-getopt ISC-leasefile no-DBus no-I18N TFTP
    Nov 10 16:38:25 	dnsmasq[41540]: reading /etc/resolv.conf
    Nov 10 16:38:25 	dnsmasq[41540]: using nameserver 10.10.10.84#53
    Nov 10 16:38:25 	dnsmasq[41540]: using nameserver 10.10.10.85#53
    Nov 10 16:38:25 	dnsmasq[41540]: read /etc/hosts - 2 addresses
    Nov 10 16:38:26 	dhcpd: Internet Systems Consortium DHCP Server V3.0.5
    Nov 10 16:38:26 	dhcpd: Copyright 2004-2006 Internet Systems Consortium.
    Nov 10 16:38:26 	dhcpd: All rights reserved.
    Nov 10 16:38:26 	dhcpd: For info, please visit http://www.isc.org/sw/dhcp/
    Nov 10 16:38:26 	php: : Processing start -
    Nov 10 16:38:26 	php: : Not a valid interface action ""
    Nov 10 16:38:26 	php: : Processing -
    Nov 10 16:38:26 	php: : Not a valid interface action ""
    Nov 10 16:38:26 	check_reload_status: rc.newwanip starting
    Nov 10 16:38:28 	php: : Informational: rc.newwanip is starting fxp0.
    Nov 10 16:38:28 	php: : rc.newwanip working with (IP address: 10.10.66.67) (interface: wan) (interface real: fxp0).
    Nov 10 16:38:35 	php: : Informational: DHClient spawned /etc/rc.newwanip and the new ip is wan - 10.10.66.67.
    Nov 10 16:38:35 	php: : Creating rrd update script
    Nov 10 16:38:39 	php: : Resyncing configuration for all packages.
    Nov 10 16:38:41 	kernel: fxp0: promiscuous mode disabled
    Nov 10 16:38:44 	php: : pfSense package system has detected an ip change 10.10.67.20 -> 10.10.66.67 ... Restarting packages.
    Nov 10 16:38:44 	php: : Configuring slbd
    Nov 10 16:38:44 	check_reload_status: reloading filter
    Nov 10 16:38:49 	check_reload_status: updating dyndns
    Nov 10 16:39:58 	kernel: fxp0: link state changed to DOWN
    Nov 10 16:40:41 	kernel: fxp0: link state changed to UP
    Nov 10 16:40:41 	check_reload_status: rc.linkup starting
    Nov 10 16:40:43 	php: : Processing fxp0 - start
    Nov 10 16:40:43 	php: : DEVD Ethernet attached event for fxp0
    Nov 10 16:40:43 	php: : HOTPLUG: Configuring wan interface fxp0
    Nov 10 16:40:46 	dhclient[41511]: short write: wanted 20 got 0 bytes
    Nov 10 16:40:46 	dhclient[41511]: short write: wanted 20 got 0 bytes
    Nov 10 16:40:46 	dhclient[41511]: exiting.
    Nov 10 16:40:46 	dhclient[41511]: exiting.
    Nov 10 16:40:46 	dhclient[42111]: DHCPREQUEST on fxp0 to 255.255.255.255 port 67
    Nov 10 16:40:55 	last message repeated 3 times
    Nov 10 16:41:05 	dhclient[42111]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 1
    Nov 10 16:41:06 	dhclient[42111]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 2
    Nov 10 16:41:08 	dhclient[42111]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 3
    Nov 10 16:41:11 	dhclient[42111]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 5
    Nov 10 16:41:16 	dhclient[42111]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 5
    Nov 10 16:41:21 	dhclient[42111]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 8
    Nov 10 16:41:29 	dhclient[42111]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 21
    Nov 10 16:41:29 	kernel: fxp0: link state changed to DOWN
    Nov 10 16:41:30 	kernel: fxp0: link state changed to UP
    Nov 10 16:41:32 	kernel: fxp0: link state changed to DOWN
    Nov 10 16:41:36 	kernel: fxp0: link state changed to UP
    Nov 10 16:41:50 	dhclient[42111]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 11
    Nov 10 16:42:01 	dhclient[42111]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 5
    Nov 10 16:42:06 	dhclient[42111]: No DHCPOFFERS received.
    Nov 10 16:42:06 	dhclient[42111]: Trying recorded lease 10.10.66.67
    Nov 10 16:42:07 	dhclient[42111]: bound: renewal in 258975 seconds.
    Nov 10 16:42:09 	dnsmasq[41540]: reading /etc/resolv.conf
    Nov 10 16:42:09 	dnsmasq[41540]: using nameserver 10.10.10.84#53
    Nov 10 16:42:09 	dnsmasq[41540]: using nameserver 10.10.10.85#53
    Nov 10 16:42:09 	dnsmasq[41540]: exiting on receipt of SIGTERM
    Nov 10 16:42:10 	dnsmasq[42257]: started, version 2.39 cachesize 150
    Nov 10 16:42:10 	dnsmasq[42257]: compile time options: IPv6 GNU-getopt ISC-leasefile no-DBus no-I18N TFTP
    Nov 10 16:42:10 	dnsmasq[42257]: reading /etc/resolv.conf
    Nov 10 16:42:10 	dnsmasq[42257]: using nameserver 10.10.10.84#53
    Nov 10 16:42:10 	dnsmasq[42257]: using nameserver 10.10.10.85#53
    Nov 10 16:42:10 	dnsmasq[42257]: read /etc/hosts - 2 addresses
    Nov 10 16:42:12 	dhcpd: Internet Systems Consortium DHCP Server V3.0.5
    Nov 10 16:42:12 	dhcpd: Copyright 2004-2006 Internet Systems Consortium.
    Nov 10 16:42:12 	dhcpd: All rights reserved.
    Nov 10 16:42:12 	dhcpd: For info, please visit http://www.isc.org/sw/dhcp/
    Nov 10 16:42:12 	php: : Processing start -
    Nov 10 16:42:12 	php: : Not a valid interface action ""
    Nov 10 16:42:12 	php: : Processing -
    Nov 10 16:42:12 	php: : Not a valid interface action ""
    Nov 10 16:42:12 	check_reload_status: reloading filter
    Nov 10 16:42:21 	dhcpd: uid lease 192.168.0.197 for client 00:19:b9:1a:11:45 is duplicate on 192.168.0/24
    Nov 10 16:42:21 	dhcpd: uid lease 192.168.0.197 for client 00:19:b9:1a:11:45 is duplicate on 192.168.0/24
    Nov 10 16:42:24 	kernel: fxp0: link state changed to DOWN
    Nov 10 16:42:26 	kernel: fxp0: link state changed to UP
    Nov 10 16:42:30 	check_reload_status: rc.linkup starting
    Nov 10 16:42:32 	php: : Processing fxp0 - start
    Nov 10 16:42:32 	php: : DEVD Ethernet attached event for fxp0
    Nov 10 16:42:32 	php: : HOTPLUG: Configuring wan interface fxp0
    Nov 10 16:42:35 	dhclient[42229]: short write: wanted 20 got 0 bytes
    Nov 10 16:42:35 	dhclient[42229]: short write: wanted 20 got 0 bytes
    Nov 10 16:42:35 	dhclient[42229]: exiting.
    Nov 10 16:42:35 	dhclient[42229]: exiting.
    Nov 10 16:42:35 	dhclient[42445]: DHCPREQUEST on fxp0 to 255.255.255.255 port 67
    Nov 10 16:42:45 	last message repeated 4 times
    Nov 10 16:42:53 	dhclient[42445]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 2
    Nov 10 16:42:55 	dhclient[42445]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 4
    Nov 10 16:42:58 	kernel: fxp0: link state changed to DOWN
    Nov 10 16:42:59 	dhclient[42445]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 10
    Nov 10 16:43:09 	dhclient[42445]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 14
    Nov 10 16:43:13 	kernel: fxp0: link state changed to UP
    Nov 10 16:43:16 	kernel: fxp0: link state changed to DOWN
    Nov 10 16:43:17 	kernel: fxp0: link state changed to UP
    Nov 10 16:43:23 	dhclient[42445]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 12
    Nov 10 16:43:35 	dhclient[42445]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 12
    Nov 10 16:43:47 	dhclient[42445]: DHCPDISCOVER on fxp0 to 255.255.255.255 port 67 interval 7
    Nov 10 16:43:54 	dhclient[42445]: No DHCPOFFERS received.
    Nov 10 16:43:54 	dhclient[42445]: Trying recorded lease 10.10.66.67
    Nov 10 16:43:55 	dhclient[42445]: bound: renewal in 258867 seconds.
    Nov 10 16:43:57 	dnsmasq[42257]: exiting on receipt of SIGTERM
    Nov 10 16:43:58 	dnsmasq[42664]: started, version 2.39 cachesize 150
    Nov 10 16:43:58 	dnsmasq[42664]: compile time options: IPv6 GNU-getopt ISC-leasefile no-DBus no-I18N TFTP
    Nov 10 16:43:58 	dnsmasq[42664]: reading /etc/resolv.conf
    Nov 10 16:43:58 	dnsmasq[42664]: using nameserver 10.10.10.84#53
    Nov 10 16:43:58 	dnsmasq[42664]: using nameserver 10.10.10.85#53
    Nov 10 16:43:58 	dnsmasq[42664]: read /etc/hosts - 2 addresses
    Nov 10 16:44:00 	dhcpd: Internet Systems Consortium DHCP Server V3.0.5
    Nov 10 16:44:00 	dhcpd: Copyright 2004-2006 Internet Systems Consortium.
    Nov 10 16:44:00 	dhcpd: All rights reserved.
    Nov 10 16:44:00 	dhcpd: For info, please visit http://www.isc.org/sw/dhcp/
    Nov 10 16:44:00 	php: : Processing start -
    Nov 10 16:44:00 	php: : Not a valid interface action ""
    Nov 10 16:44:00 	php: : Processing -
    Nov 10 16:44:00 	php: : Not a valid interface action ""
    Nov 10 16:44:00 	check_reload_status: reloading filter
    Nov 10 16:44:37 	dhcpd: uid lease 192.168.0.196 for client 00:06:5b:50:a3:66 is duplicate on 192.168.0/24
    Nov 10 16:46:37 	dhcpd: uid lease 192.168.0.196 for client 00:06:5b:50:a3:66 is duplicate on 192.168.0/24
    Nov 10 16:46:37 	dhcpd: uid lease 192.168.0.196 for client 00:06:5b:50:a3:66 is duplicate on 192.168.0/24
    Nov 10 16:49:28 	dhcpd: uid lease 192.168.0.197 for client 00:19:b9:1a:11:45 is duplicate on 192.168.0/24
    Nov 10 16:49:49 	dhcpd: uid lease 192.168.0.197 for client 00:19:b9:1a:11:45 is duplicate on 192.168.0/24
    Nov 10 16:58:41 	check_reload_status: reloading filter
    ******************NO INTERNET UP UNTIL THIS POINT - I DID MANUAL RELEASE/RENEW HERE**************************
    Nov 10 17:15:54 	dhclient[42446]: connection closed
    Nov 10 17:15:54 	dhclient[42446]: connection closed
    Nov 10 17:15:54 	dhclient[42446]: exiting.
    Nov 10 17:15:54 	dhclient[42446]: exiting.
    Nov 10 17:15:56 	dhclient[46138]: DHCPREQUEST on fxp0 to 255.255.255.255 port 67
    Nov 10 17:16:01 	dhclient[46138]: DHCPREQUEST on fxp0 to 255.255.255.255 port 67
    Nov 10 17:16:01 	dhclient[46138]: DHCPACK from 10.10.67.254
    Nov 10 17:16:02 	dhclient[46138]: bound to 10.10.66.67 -- renewal in 259200 seconds.
    Nov 10 17:16:02 	check_reload_status: rc.newwanip starting
    Nov 10 17:16:04 	php: : Informational: rc.newwanip is starting fxp0.
    Nov 10 17:16:04 	php: : rc.newwanip working with (IP address: 10.10.66.67) (interface: wan) (interface real: fxp0).
    Nov 10 17:16:10 	php: : Informational: DHClient spawned /etc/rc.newwanip and the new ip is wan - 10.10.66.67.
    Nov 10 17:16:10 	dnsmasq[42664]: reading /etc/resolv.conf
    Nov 10 17:16:10 	dnsmasq[42664]: using nameserver 10.10.10.84#53
    Nov 10 17:16:10 	dnsmasq[42664]: using nameserver 10.10.10.85#53
    Nov 10 17:16:10 	php: : Creating rrd update script
    Nov 10 17:16:10 	php: : Configuring slbd
    Nov 10 17:16:10 	check_reload_status: reloading filter
    Nov 10 17:16:15 	check_reload_status: updating dyndns
    

  • sure, create a cron job.


  • In Cron is it possible to schedule release/renew of DHCP lease? How would the script look like?

    For reboot every day at 6AM, would this be correct? Thank you very much.

    minute  hour  	mday  	month  	wday  	who  	command
     *   	 6   	 *   	 *   	 *   	root   	shutdown -r now 
    


  • Hello!
    I just installed pfsense and I have the same problem, almost..

    I only get a lease ack if/when the dhcprequest is sent to 255.255.255.255:

    boot:
    Nov 28 09:17:31 dhclient[5711]: DHCPREQUEST on em0 to 255.255.255.255 port 67
    Nov 28 09:17:32 dhclient[5711]: bound to 85.24.. – renewal in 450 seconds.

    then, 450 secs later:
    Nov 28 09:25:04 dhclient[5711]: DHCPREQUEST on em0 to 85.24.202.1 port 67
    Nov 28 09:25:04 dhclient[5711]: SENDING DIRECT
    Nov 28 09:25:07 dhclient[5711]: DHCPREQUEST on em0 to 85.24.202.1 port 67
    Nov 28 09:25:07 dhclient[5711]: SENDING DIRECT

    this goes on until:
    Nov 28 09:45:23 dhclient[5711]: DHCPREQUEST on em0 to 255.255.255.255 port 67
    Nov 28 09:45:24 dhclient[5711]: DHCPACK from 85.24.202.1
    Nov 28 09:45:24 dhclient[5711]: bound to 85.24.. – renewal in 450 seconds.

    What I have found so far:
    On my windows machine, I get a new lease directly after 450 secs, when the DHCPREQUEST is sent directly to the dhcp-server.
    So I ran wireshark, and the Windows request has lots of more stuff (OPTION, and different stuff in some of the OPTION) than what the PFSENSE-request has...

    So, any idés of how to solve this?
    The problem is that sometimes the 255.255.255.255 request after 2x450 secs gives me a new IP, or, does not work at all, so that I manually has to go into the pfsense webinterface and renew the ip :(

    this is what pfsense looks like: ( i tried to add as much as I could to the dhcpd-conf file, but it´s still not enough)
    http://basse.host22.com/junk/greenshot_2009-11-28_09-53-02.png
    and windows:
    http://basse.host22.com/junk/greenshot_2009-11-28_09-52-46.png

    Any help would be really appreciated!

    update:
    I tried the 2.0 alpha, and it worked great! Any ETA on a stable 2.0? :P
    I will try to capture these updates also, and see how they differ from the current stable pfsense updates...