"peer holds all free leases"

Marc Haber mh+dhcp-users at zugschlus.de
Wed Sep 16 06:05:13 UTC 2020


Hi,

I am running a redundant DHCP setup on Debian stable, ISC DHCP version
4.4.1, with a handful of pools for a handful of different VLANs.

Yesterday afternoon, no configuration change, both servers
simultaneously began logging

Sep 16 07:22:46 chasse dhcpd[9806]: DHCPDISCOVER from b4:e6:2d:5b:99:19 via 192.168.184.254: peer holds all free leases 

for one pool. I do not understand this error message.

(1) Replication is fine:
Sep 16 07:02:24 chasse dhcpd[9806]: balancing pool 5617e1957e80 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-own (+/-)12
Sep 16 07:02:24 chasse dhcpd[9806]: balanced pool 5617e1957e80 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-misbal 18
Sep 16 07:02:24 cadencia dhcpd[10213]: balancing pool 1269af8 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-own (+/-)12
Sep 16 07:02:24 cadencia dhcpd[10213]: balanced pool 1269af8 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-misbal 18
I also see both machines talking to each other on TCP port 647,
restarting either server tears down the TCP session an rebuilds it again

(2) The pool has ample free addresses
I even increased the pool size by 20 addresses. This didn't change the
issue, no IP addresses are handed out to clients.

(3) classes are not in use.
There are on "class" statements in configuration

Why do my servers suddenly think that clients are not eligible for a
lase from this pool? I have moved one of the affected clients to a
different VLAN and it immediately got a lease assigned.

Greetings
Marc



Here is the relevant configuration. pool 184 is the one that doesn't
work, pool 189 is the one that works.

chasse:
failover peer "chasse-cadencia" {
  primary;
  address chasse.ka51.example.com;
  peer address unt381.cadencia.ka51.example.com;
  max-response-delay 60;
  max-unacked-updates 10;
  mclt 3600; # 300 if redundancy is in place
  split 192;
  load balance max seconds 3;
}

cadencia:
failover peer "chasse-cadencia" {
  secondary;
  address unt381.cadencia.ka51.example.com;
  peer address chasse.ka51.example.com;
  max-response-delay 60;
  max-unacked-updates 10;
  load balance max seconds 3;
}

identical on both (included and rsynced):
option domain-name "ka51.example.com";
option domain-search "ka51.example.com", "example.com";
option domain-name-servers dnsrec1.ka51.example.com, dnsrec2.ka51.example.com;
option ntp-servers ntp1.ka51.example.com, ntp2.ka51.example.com;

default-lease-time 14400; # 12 hours
max-lease-time 14400;  # 12 hours

not authoritative;

log-facility local7;

deny bootp;
adaptive-lease-time-threshold 80;

ddns-update-style standard;

ddns-rev-domainname "in-addr.arpa.";

zone ka51.example.com. {
  primary lasso.ka51.example.com;
  key lasso-chasse-dhcp;
}

subnet 192.168.184.0 netmask 255.255.255.0 {
  # int184
  pool {
    range 192.168.184.130 192.168.184.249;
    failover peer "chasse-cadencia";
    deny known-clients;
  }
  option routers v4gw.int184.ka51.example.com;
  ddns-domainname "ka51.example.com";
  authoritative;
}

zone 184.168.192.in-addr.arpa. {
  primary lasso.ka51.example.com;
  key lasso-chasse-dhcp;
}

subnet 192.168.189.0 netmask 255.255.255.0 {
  # int189
  pool {
    range 192.168.189.150 192.168.189.249;
    failover peer "chasse-cadencia";
    deny known-clients;
  }
  option routers v4gw.int189.ka51.example.com;
  ddns-domainname "ka51.example.com";
  authoritative;
}

zone 189.168.192.in-addr.arpa. {
  primary lasso.ka51.example.com;
  key lasso-chasse-dhcp;
}

Here is what the servers log on startup:
Sep 16 07:58:42 chasse systemd[1]: Stopping ISC DHCP Server for IPv4 (dhcpd.conf)...
Sep 16 07:58:42 chasse systemd[1]: isc-dhcp-server-v4.service: Main process exited, code=killed, status=15/TERM
Sep 16 07:58:42 chasse systemd[1]: isc-dhcp-server-v4.service: Succeeded.
Sep 16 07:58:42 chasse systemd[1]: Stopped ISC DHCP Server for IPv4 (dhcpd.conf).
Sep 16 07:58:48 chasse systemd[1]: Starting ISC DHCP Server for IPv4 (dhcpd.conf)...
Sep 16 07:58:48 chasse dhcpd[19535]: Internet Systems Consortium DHCP Server 4.4.1
Sep 16 07:58:48 chasse dhcpd[19535]: Copyright 2004-2018 Internet Systems Consortium.
Sep 16 07:58:48 chasse dhcpd[19535]: All rights reserved.
Sep 16 07:58:48 chasse dhcpd[19535]: For info, please visit https://www.isc.org/software/dhcp/
Sep 16 07:58:48 chasse systemd[1]: Started ISC DHCP Server for IPv4 (dhcpd.conf).
Sep 16 07:58:48 chasse dhcpd[19536]: Internet Systems Consortium DHCP Server 4.4.1
Sep 16 07:58:49 chasse dhcpd[19536]: Copyright 2004-2018 Internet Systems Consortium.
Sep 16 07:58:49 chasse dhcpd[19536]: All rights reserved.
Sep 16 07:58:49 chasse dhcpd[19536]: For info, please visit https://www.isc.org/software/dhcp/
Sep 16 07:58:49 chasse dhcpd[19536]: Wrote 0 deleted host decls to leases file.
Sep 16 07:58:49 chasse dhcpd[19536]: Wrote 0 new dynamic host decls to leases file.
Sep 16 07:58:49 chasse dhcpd[19536]: Wrote 1012 leases to leases file.
Sep 16 07:58:49 chasse dhcpd[19536]: failover peer chasse-cadencia: I move from normal to startup
Sep 16 07:58:49 chasse dhcpd[19536]: Server starting service.
Sep 16 07:58:49 chasse dhcpd[19536]: failover peer chasse-cadencia: peer moves from normal to communications-interrupted
Sep 16 07:58:49 chasse dhcpd[19536]: failover peer chasse-cadencia: I move from startup to normal
Sep 16 07:58:49 chasse dhcpd[19536]: balancing pool 5650a31a3ac0 192.168.189.0/24  total 100  free 49  backup 49  lts 0  max-own (+/-)10
Sep 16 07:58:49 chasse dhcpd[19536]: balanced pool 5650a31a3ac0 192.168.189.0/24  total 100  free 49  backup 49  lts 0  max-misbal 15
Sep 16 07:58:49 chasse dhcpd[19536]: balancing pool 5650a316be80 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-own (+/-)12
Sep 16 07:58:49 chasse dhcpd[19536]: balanced pool 5650a316be80 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-misbal 18
Sep 16 07:58:49 chasse dhcpd[19536]: failover peer chasse-cadencia: peer moves from communications-interrupted to normal
Sep 16 07:58:49 chasse dhcpd[19536]: failover peer chasse-cadencia: Both servers normal

Sep 16 07:58:42 cadencia dhcpd[10213]: peer chasse-cadencia: disconnected
Sep 16 07:58:42 cadencia dhcpd[10213]: failover peer chasse-cadencia: I move from normal to communications-interrupted
Sep 16 07:58:47 cadencia ippl: ICMP message type redirect - for host from 192.168.251.254
Sep 16 07:58:49 cadencia dhcpd[10213]: failover peer chasse-cadencia: peer moves from normal to normal
Sep 16 07:58:49 cadencia dhcpd[10213]: failover peer chasse-cadencia: I move from communications-interrupted to normal
Sep 16 07:58:49 cadencia dhcpd[10213]: failover peer chasse-cadencia: Both servers normal
Sep 16 07:58:49 cadencia dhcpd[10213]: balancing pool 128ae38 192.168.189.0/24  total 100  free 49  backup 49  lts 0  max-own (+/-)10
Sep 16 07:58:49 cadencia dhcpd[10213]: balanced pool 128ae38 192.168.189.0/24  total 100  free 49  backup 49  lts 0  max-misbal 15
Sep 16 07:58:49 cadencia dhcpd[10213]: balancing pool 1269af8 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-own (+/-)12
Sep 16 07:58:49 cadencia dhcpd[10213]: balanced pool 1269af8 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-misbal 18

Sep 16 08:00:57 cadencia systemd[1]: Stopping ISC DHCP Server for IPv4 (dhcpd.conf)...
Sep 16 08:00:57 cadencia systemd[1]: isc-dhcp-server-v4.service: Main process exited, code=killed, status=15/TERM
Sep 16 08:00:57 cadencia systemd[1]: isc-dhcp-server-v4.service: Succeeded.
Sep 16 08:00:57 cadencia systemd[1]: Stopped ISC DHCP Server for IPv4 (dhcpd.conf).
Sep 16 08:01:10 cadencia ippl: ICMP message type redirect - for host from 192.168.251.254
Sep 16 08:01:14 cadencia systemd[1]: Starting ISC DHCP Server for IPv4 (dhcpd.conf)...
Sep 16 08:01:14 cadencia dhcpd[5819]: Internet Systems Consortium DHCP Server 4.4.1
Sep 16 08:01:14 cadencia dhcpd[5819]: Copyright 2004-2018 Internet Systems Consortium.
Sep 16 08:01:14 cadencia dhcpd[5819]: All rights reserved.
Sep 16 08:01:14 cadencia dhcpd[5819]: For info, please visit https://www.isc.org/software/dhcp/
Sep 16 08:01:14 cadencia systemd[1]: Started ISC DHCP Server for IPv4 (dhcpd.conf).
Sep 16 08:01:14 cadencia dhcpd[5820]: Internet Systems Consortium DHCP Server 4.4.1
Sep 16 08:01:14 cadencia dhcpd[5820]: Copyright 2004-2018 Internet Systems Consortium.
Sep 16 08:01:14 cadencia dhcpd[5820]: All rights reserved.
Sep 16 08:01:14 cadencia dhcpd[5820]: For info, please visit https://www.isc.org/software/dhcp/
Sep 16 08:01:14 cadencia dhcpd[5820]: Wrote 0 deleted host decls to leases file.
Sep 16 08:01:14 cadencia dhcpd[5820]: Wrote 0 new dynamic host decls to leases file.
Sep 16 08:01:14 cadencia dhcpd[5820]: Wrote 1012 leases to leases file.
Sep 16 08:01:14 cadencia dhcpd[5820]: failover peer chasse-cadencia: I move from normal to startup
Sep 16 08:01:14 cadencia dhcpd[5820]: Server starting service.
Sep 16 08:01:14 cadencia dhcpd[5820]: failover peer chasse-cadencia: peer moves from normal to communications-interrupted
Sep 16 08:01:14 cadencia dhcpd[5820]: failover peer chasse-cadencia: I move from startup to normal
Sep 16 08:01:14 cadencia dhcpd[5820]: balancing pool 121fe38 192.168.189.0/24  total 100  free 49  backup 49  lts 0  max-own (+/-)10
Sep 16 08:01:14 cadencia dhcpd[5820]: balanced pool 121fe38 192.168.189.0/24  total 100  free 49  backup 49  lts 0  max-misbal 15
Sep 16 08:01:14 cadencia dhcpd[5820]: balancing pool 11feaf8 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-own (+/-)12
Sep 16 08:01:14 cadencia dhcpd[5820]: balanced pool 11feaf8 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-misbal 18
Sep 16 08:01:15 cadencia dhcpd[5820]: failover peer chasse-cadencia: peer moves from communications-interrupted to normal
Sep 16 08:01:15 cadencia dhcpd[5820]: failover peer chasse-cadencia: Both servers normal


Sep 16 08:00:57 chasse dhcpd[19536]: peer chasse-cadencia: disconnected
Sep 16 08:00:57 chasse dhcpd[19536]: failover peer chasse-cadencia: I move from normal to communications-interrupted
Sep 16 08:01:01 chasse dhcpd[814]: Relay-forward message from 2a01:238:42bc:a181::70:100 port 547, link address 2a01:238:42bc:a18c::70:100, peer ad
Sep 16 08:01:01 chasse dhcpd[814]: No subnet found for link-address 2a01:238:42bc:a18c::70:100.
Sep 16 08:01:02 chasse dhcpd[19536]: DHCPDISCOVER from 2c:f4:32:db:c3:06 via 192.168.184.254: peer holds all free leases
Sep 16 08:01:04 chasse dhcpd[19536]: DHCPDISCOVER from 2c:f4:32:db:c3:06 via 192.168.184.254: peer holds all free leases
Sep 16 08:01:08 chasse dhcpd[19536]: DHCPDISCOVER from 2c:f4:32:db:c3:06 via 192.168.184.254: peer holds all free leases
Sep 16 08:01:13 chasse dhcpd[19536]: DHCPDISCOVER from 2c:f4:32:db:c3:06 via 192.168.184.254: peer holds all free leases
Sep 16 08:01:14 chasse dhcpd[814]: Solicit message from fe80::5054:ff:fe9e:9a15 port 546, transaction ID 0x1D67E000
Sep 16 08:01:14 chasse dhcpd[814]: Lease for b55e:67ff:0:0:0:0 leased to d6:16:75:5:f2:ba
Sep 16 08:01:14 chasse dhcpd[814]: Unable to pick client address: no IPv6 pools on this shared network
Sep 16 08:01:14 chasse dhcpd[814]: Sending Reply to fe80::5054:ff:fe9e:9a15 port 546
Sep 16 08:01:14 chasse dhcpd[814]: Relay-forward message from 2a01:238:42bc:a181::70:100 port 547, link address 2a01:238:42bc:a181::70:100, peer ad
Sep 16 08:01:14 chasse dhcpd[814]: Lease for b55e:67ff:0:0:0:0 leased to d6:16:75:5:f2:ba
Sep 16 08:01:14 chasse dhcpd[814]: Unable to pick client address: no IPv6 pools on this shared network
Sep 16 08:01:14 chasse dhcpd[814]: Sending Relay-reply to 2a01:238:42bc:a181::70:100 port 547
Sep 16 08:01:14 chasse dhcpd[19536]: failover peer chasse-cadencia: peer moves from normal to normal
Sep 16 08:01:14 chasse dhcpd[19536]: failover peer chasse-cadencia: I move from communications-interrupted to normal
Sep 16 08:01:14 chasse dhcpd[19536]: failover peer chasse-cadencia: Both servers normal
Sep 16 08:01:14 chasse dhcpd[19536]: balancing pool 5650a31a3ac0 192.168.189.0/24  total 100  free 49  backup 49  lts 0  max-own (+/-)10
Sep 16 08:01:14 chasse dhcpd[19536]: balanced pool 5650a31a3ac0 192.168.189.0/24  total 100  free 49  backup 49  lts 0  max-misbal 15
Sep 16 08:01:14 chasse dhcpd[19536]: balancing pool 5650a316be80 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-own (+/-)12
Sep 16 08:01:14 chasse dhcpd[19536]: balanced pool 5650a316be80 192.168.184.0/24  total 120  free 58  backup 59  lts 0  max-misbal 18

-- 
-----------------------------------------------------------------------------
Marc Haber         | "I don't trust Computers. They | Mailadresse im Header
Leimen, Germany    |  lose things."    Winona Ryder | Fon: *49 6224 1600402
Nordisch by Nature |  How to make an American Quilt | Fax: *49 6224 1600421


More information about the dhcp-users mailing list