Bug Reports

[<-- Reservation]

I'm getting a couple of Log/DHCP log messages, over and over again, and I beleive they are related

Apr 17 08:33:13    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 17 07:33:13    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 17 06:33:34    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 17 05:33:11    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 17 04:33:10    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 17 03:33:10    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 17 02:33:09    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 17 01:33:08    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 17 00:33:07    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 17 00:23:07    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 16 23:33:21    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 16 22:46:22    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 16 22:33:06    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 16 22:23:06    dnsmasq[66652]: reading /var/db/dhcpd.leases
Apr 16 21:33:30    dnsmasq[66652]: reading /var/db/dhcpd.leases

is this just logging every time it reads the dhcp leases file? or is related to the below?


this part I believe to be the "bug"

Apr 17 08:33:35    dhcpd: DHCPREQUEST for 192.x.x.12 from 00:x:x:x:x:x via sis0 <-- Reservation
Apr 17 08:33:35    dhcpd: uid lease 192.x.x.195 for client 00:x:x:x:x:x is duplicate on 192.x.x/24
Apr 17 08:32:25    dhcpd: Wrote 19 leases to leases file.
Apr 17 08:32:25    dhcpd: Wrote 0 new dynamic host decls to leases file.
Apr 17 08:32:25    dhcpd: Wrote 0 deleted host decls to leases file.
Apr 17 08:13:13    dhcpd: DHCPREQUEST for 192.x.x.48 from 00:x:x:x:x:x via sis0 <-- Reservation
Apr 17 08:13:13    dhcpd: uid lease 192.x.x.197 for client 00:x:x:x:x:x is duplicate on 192.x.x/24
Apr 17 08:06:50    dhcpd: DHCPREQUEST for 192.x.x.49 from 00:x:x:x:x:x via sis0 <-- Reservation
Apr 17 08:06:50    dhcpd: uid lease 192.x.x.199 for client 00:x:x:x:x:x is duplicate on 192.x.x/24
Apr 17 07:33:35    dhcpd: DHCPREQUEST for 192.x.x.12 from 00:x:x:x:x:x via sis0 <-- Reservation
Apr 17 07:33:35    dhcpd: uid lease 192.x.x.195 for client 00:x:x:x:x:x is duplicate on 192.x.x/24

should it not "write" a host as deleted if it was converted to a Reservation IP?

the constant "uid lease ..... for client...  is duplicate on...." messages stopped with reboot, as i thought it would (it flushes the dhcp leases file).  leads me to believe this is a "bug" of sorts

The first set of messages are the lease file rolling over, this is more frequent since 1.31 as some users had run out of space before it rolled.

Did you 'release' the IP from the client or just renew , windows or osx or linux ?

on my 1.31 system, I just booted ubuntu got an ip, made a reservation on a new address and did a reconnect, and got the new reservation and my dhcpd log says

Code:

Apr 19 12:57:04         dhcpd: DHCPREQUEST for 192.168.1.199 from 00:0c:29:22:4b:3e (xx) via em1
Apr 19 12:57:04         dhcpd: DHCPACK on 192.168.1.199 to 00:0c:29:22:4b:3e (xx) via em1
Apr 19 13:05:52         dhcpd: DHCPDISCOVER from 00:0c:29:22:4b:3e (xx) via em1
Apr 19 13:05:53         dhcpd: DHCPOFFER on 192.168.1.199 to 00:0c:29:22:4b:3e (xx) via em1
Apr 19 13:05:53         dhcpd: Wrote 1 leases to leases file.
Apr 19 13:05:53         dhcpd: DHCPREQUEST for 192.168.1.199 (192.168.1.1) from 00:0c:29:22:4b:3e (xx) via em1
Apr 19 13:05:53         dhcpd: DHCPACK on 192.168.1.199 to 00:0c:29:22:4b:3e (xx) via em1
Apr 19 13:08:32         dhcpd: Internet Systems Consortium DHCP Server V3.0.7
Apr 19 13:08:32         dhcpd: Copyright 2004-2008 Internet Systems Consortium.
Apr 19 13:08:32         dhcpd: All rights reserved.
Apr 19 13:08:32         dhcpd: For info, please visit http://www.isc.org/sw/dhcp/
Apr 19 13:08:32         dhcpd: Wrote 0 deleted host decls to leases file.
Apr 19 13:08:32         dhcpd: Wrote 0 new dynamic host decls to leases file.
Apr 19 13:08:32         dhcpd: Wrote 1 leases to leases file.
Apr 19 13:08:32         dhcpd: Listening on BPF/em1/00:0c:29:ae:f9:37/192.168.1/24
Apr 19 13:08:32         dhcpd: Sending on BPF/em1/00:0c:29:ae:f9:37/192.168.1/24
Apr 19 13:08:32         dhcpd: Sending on Socket/fallback/fallback-net
Apr 19 13:09:35         dhcpd: DHCPREQUEST for 192.168.1.199 from 00:0c:29:22:4b:3e via em1: lease 192.168.1.199 unavailable.
Apr 19 13:09:35         dhcpd: DHCPNAK on 192.168.1.199 to 00:0c:29:22:4b:3e via em1
Apr 19 13:09:35         dhcpd: DHCPDISCOVER from 00:0c:29:22:4b:3e via em1
Apr 19 13:09:35         dhcpd: DHCPOFFER on 192.168.1.44 to 00:0c:29:22:4b:3e via em1
Apr 19 13:09:35         dhcpd: DHCPREQUEST for 192.168.1.44 (192.168.1.1) from 00:0c:29:22:4b:3e via em1
Apr 19 13:09:35         dhcpd: DHCPACK on 192.168.1.44 to 00:0c:29:22:4b:3e via em1

The first set of messages are the lease file rolling over, this is more frequent since 1.31 as some users had run out of space before it rolled.

Did you 'release' the IP from the client or just renew , windows or osx or linux ?

on my 1.31 system, I just booted ubuntu got an ip, made a reservation on a new address and did a reconnect, and got the new reservation and my dhcpd log says

v1.31...  mixed clients, Linux & Windows and Cisco APs (some form of *nix)... I took the lazy route, didn't bother release/renewing the client, just waited for the client's reservation to expire and when it self-renewed it switched to the new IP.  Thats when these "uid lease" messages started. 

Looking at my copy and paste, the .12 is a windows machine, and .48/.49 are Cisco APs (easier to let them auto-renew to the new IP vs consoling back into them to do it manually)