kea-dhcp4 is dynamically changing assigned subnet and overwrite correct selection
So my config is this:
{
"Dhcp4": {
"control-socket": {
"socket-name": "/tmp/control_socket",
"socket-type": "unix"
},
"interfaces-config": {
"dhcp-socket-type": "udp",
"interfaces": [
"enp1s0f3"
]
},
"lease-database": {
"type": "memfile"
},
"loggers": [
{
"name": "kea-dhcp4",
"output_options": [
{
"output": "stdout"
}
],"debuglevel": 99,
"severity": "DEBUG"
}
],
"rebind-timer": 350000,
"renew-timer": 340000,
"shared-networks": [
{
"interface": "enp1s0f3",
"name": "one-network",
"subnet4": [
{
"id": 1,
"interface": "enp1s0f3",
"pools": [
{
"pool": "1.0.0.4-1.255.255.254"
}
],
"subnet": "1.0.0.0/8"
},
{
"id": 2,
"interface": "enp1s0f3",
"pools": [
{
"pool": "2.0.0.4-2.255.255.254"
}
],
"subnet": "2.0.0.0/8"
},
{
"id": 3,
"interface": "enp1s0f3",
"pools": [
{
"pool": "3.0.0.4-3.255.255.254"
}
],
"subnet": "3.0.0.0/8"
},
{
"id": 4,
"interface": "enp1s0f3",
"pools": [
{
"pool": "4.0.0.4-4.255.255.254"
}
],
"subnet": "4.0.0.0/8"
},
{
"id": 5,
"interface": "enp1s0f3",
"pools": [
{
"pool": "5.0.0.4-5.255.255.254"
}
],
"subnet": "5.0.0.0/8"
},
{
"id": 6,
"interface": "enp1s0f3",
"pools": [
{
"pool": "6.0.0.4-6.255.255.254"
}
],
"subnet": "6.0.0.0/8"
},
{
"id": 7,
"interface": "enp1s0f3",
"pools": [
{
"pool": "7.0.0.4-7.255.255.254"
}
],
"subnet": "7.0.0.0/8"
},
{
"id": 8,
"interface": "enp1s0f3",
"pools": [
{
"pool": "8.0.0.4-8.255.255.254"
}
],
"subnet": "8.0.0.0/8"
},
{
"id": 9,
"interface": "enp1s0f3",
"pools": [
{
"pool": "9.0.0.4-9.255.255.254"
}
],
"subnet": "9.0.0.0/8"
},
{
"id": 10,
"interface": "enp1s0f3",
"pools": [
{
"pool": "10.0.0.4-10.255.255.254"
}
],
"subnet": "10.0.0.0/8"
}
]
}
],
"valid-lifetime": 360000
}
}
perfdhcp mimic traffic from different relays (in this case there are 10 of them 1.0.0.1 - 10.0.0.1) And first client get his address correct:
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_BUFFER_RECEIVED received buffer from 11.0.0.3:67 to 11.0.0.1:67 over interface enp1s0f3
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.options/2697.140305702419520] DHCP4_BUFFER_UNPACK parsing buffer received from 11.0.0.3 to 11.0.0.1 over interface enp1s0f3
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_PACKET_RECEIVED [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: DHCPDISCOVER (type 1) received from 11.0.0.3 to 11.0.0.1 on interface enp1s0f3
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_QUERY_DATA [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0, packet details: local_address=11.0.0.1:67, remote_address=11.0.0.3:67, msg_type=DHCPDISCOVER (1), transid=0x0,
options:
type=053, len=001: 1 (uint8)
type=055, len=007: 1(uint8) 28(uint8) 2(uint8) 3(uint8) 15(uint8) 6(uint8) 12(uint8)
type=061, len=007: 01:00:0c:01:02:03:04
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.0.0.0/8 for packet received by matching address 10.0.0.1
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_SELECTED [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: the subnet with ID 10 was selected for client assignments
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_DATA [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: the selected subnet details: 10.0.0.0/8
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: hwaddr=000C01020304
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=000C01020304, found 0 host(s)
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: client-id=01000C01020304
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=01000C01020304, found 0 host(s)
2020-07-29 12:38:37.191 DEBUG [kea-dhcp4.dhcp4/2697.140305702419520] DHCP4_CLASS_ASSIGNED [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: client packet has been assigned to the following class(es): UNKNOWN
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.dhcp4/2697.140305702419520] DHCP4_CLASS_ASSIGNED [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: client packet has been assigned to the following class(es): ALL, UNKNOWN
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.ddns/2697.140305702419520] DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: processing client's Hostname option
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_CLIENTID obtaining IPv4 leases for client ID 01:00:0c:01:02:03:04
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1 00:0c:01:02:03:04
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.alloc-engine/2697.140305702419520] ALLOC_ENGINE_V4_OFFER_NEW_LEASE allocation engine will try to offer new lease to the client [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.0.0.4
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet id 10 and IPv4 address 10.0.0.4
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_ADDRESS4 get all hosts with reservations for IPv4 address 10.0.0.4
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.0.0.4, found 0 host(s)
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 10 and address 10.0.0.4
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.0.0.4
2020-07-29 12:38:37.192 INFO [kea-dhcp4.leases/2697.140305702419520] DHCP4_LEASE_ADVERT [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: lease 10.0.0.4 will be advertised
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.options/2697.140305702419520] DHCP4_PACKET_PACK [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: preparing on-wire format of the packet to be sent
2020-07-29 12:38:37.192 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_PACKET_SEND [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: trying to send packet DHCPOFFER (type 2) from 11.0.0.1:67 to 11.0.0.3:67 on interface enp1s0f3
2020-07-29 12:38:37.193 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_RESPONSE_DATA [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: responding with packet DHCPOFFER (type 2), packet details: local_address=11.0.0.1:67, remote_address=11.0.0.3:67, msg_type=DHCPOFFER (2), transid=0x0,
options:
type=001, len=004: 4278190080 (uint32)
type=051, len=004: 360000 (uint32)
type=053, len=001: 2 (uint8)
type=054, len=004: 11.0.0.1
type=058, len=004: 340000 (uint32)
type=059, len=004: 350000 (uint32)
type=061, len=007: 01:00:0c:01:02:03:04
2020-07-29 12:38:37.193 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_BUFFER_RECEIVED received buffer from 11.0.0.3:67 to 11.0.0.1:67 over interface enp1s0f3
2020-07-29 12:38:37.193 DEBUG [kea-dhcp4.options/2697.140305702419520] DHCP4_BUFFER_UNPACK parsing buffer received from 11.0.0.3 to 11.0.0.1 over interface enp1s0f3
2020-07-29 12:38:37.193 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_PACKET_RECEIVED [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: DHCPREQUEST (type 3) received from 11.0.0.3 to 11.0.0.1 on interface enp1s0f3
2020-07-29 12:38:37.193 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_QUERY_DATA [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0, packet details: local_address=11.0.0.1:67, remote_address=11.0.0.3:67, msg_type=DHCPREQUEST (3), transid=0x0,
options:
type=050, len=004: 10.0.0.4 (ipv4-address)
type=053, len=001: 3 (uint8)
type=054, len=004: 11.0.0.1 (ipv4-address)
type=055, len=007: 1(uint8) 28(uint8) 2(uint8) 3(uint8) 15(uint8) 6(uint8) 12(uint8)
type=061, len=007: 01:00:0c:01:02:03:04
2020-07-29 12:38:37.193 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.0.0.0/8 for packet received by matching address 10.0.0.1
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_SELECTED [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: the subnet with ID 10 was selected for client assignments
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_DATA [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: the selected subnet details: 10.0.0.0/8
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: hwaddr=000C01020304
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=000C01020304, found 0 host(s)
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: client-id=01000C01020304
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=01000C01020304, found 0 host(s)
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.dhcp4/2697.140305702419520] DHCP4_CLASS_ASSIGNED [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: client packet has been assigned to the following class(es): UNKNOWN
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.dhcp4/2697.140305702419520] DHCP4_CLASS_ASSIGNED [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: client packet has been assigned to the following class(es): ALL, UNKNOWN
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.ddns/2697.140305702419520] DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: processing client's Hostname option
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_CLIENTID obtaining IPv4 leases for client ID 01:00:0c:01:02:03:04
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1 00:0c:01:02:03:04
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet id 10 and IPv4 address 10.0.0.4
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_ADDRESS4 get all hosts with reservations for IPv4 address 10.0.0.4
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.0.0.4, found 0 host(s)
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 10 and address 10.0.0.4
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.0.0.4
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.alloc-engine/2697.140305702419520] ALLOC_ENGINE_V4_REQUEST_ALLOC_REQUESTED [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: trying to allocate requested address 10.0.0.4
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.0.0.4
2020-07-29 12:38:37.194 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_ADD_ADDR4 adding IPv4 lease with address 10.0.0.4
2020-07-29 12:38:37.194 INFO [kea-dhcp4.leases/2697.140305702419520] DHCP4_LEASE_ALLOC [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: lease 10.0.0.4 has been allocated for 360000 seconds
2020-07-29 12:38:37.195 DEBUG [kea-dhcp4.options/2697.140305702419520] DHCP4_PACKET_PACK [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: preparing on-wire format of the packet to be sent
2020-07-29 12:38:37.195 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_PACKET_SEND [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: trying to send packet DHCPACK (type 5) from 11.0.0.1:67 to 11.0.0.3:67 on interface enp1s0f3
2020-07-29 12:38:37.195 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_RESPONSE_DATA [hwtype=1 00:0c:01:02:03:04], cid=[01:00:0c:01:02:03:04], tid=0x0: responding with packet DHCPACK (type 5), packet details: local_address=11.0.0.1:67, remote_address=11.0.0.3:67, msg_type=DHCPACK (5), transid=0x0,
options:
type=001, len=004: 4278190080 (uint32)
type=051, len=004: 360000 (uint32)
type=053, len=001: 5 (uint8)
type=054, len=004: 11.0.0.1
type=058, len=004: 340000 (uint32)
type=059, len=004: 350000 (uint32)
type=061, len=007: 01:00:0c:01:02:03:04
problem is with all next packets, despite selecting correct subnet first it was changed later in the process:
020-07-29 12:38:38.190 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_BUFFER_RECEIVED received buffer from 11.0.0.3:67 to 11.0.0.1:67 over interface enp1s0f3
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.options/2697.140305702419520] DHCP4_BUFFER_UNPACK parsing buffer received from 11.0.0.3 to 11.0.0.1 over interface enp1s0f3
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_PACKET_RECEIVED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: DHCPDISCOVER (type 1) received from 11.0.0.3 to 11.0.0.1 on interface enp1s0f3
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_QUERY_DATA [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1, packet details: local_address=11.0.0.1:67, remote_address=11.0.0.3:67, msg_type=DHCPDISCOVER (1), transid=0x1,
options:
type=053, len=001: 1 (uint8)
type=055, len=007: 1(uint8) 28(uint8) 2(uint8) 3(uint8) 15(uint8) 6(uint8) 12(uint8)
type=061, len=007: 01:00:0c:01:02:03:05
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 8.0.0.0/8 for packet received by matching address 8.0.0.1
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_SELECTED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: the subnet with ID 8 was selected for client assignments
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_DATA [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: the selected subnet details: 8.0.0.0/8
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: hwaddr=000C01020305
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=000C01020305, found 0 host(s)
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: client-id=01000C01020305
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=01000C01020305, found 0 host(s)
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.dhcp4/2697.140305702419520] DHCP4_CLASS_ASSIGNED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: client packet has been assigned to the following class(es): UNKNOWN
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.dhcp4/2697.140305702419520] DHCP4_CLASS_ASSIGNED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: client packet has been assigned to the following class(es): ALL, UNKNOWN
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.ddns/2697.140305702419520] DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: processing client's Hostname option
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_CLIENTID obtaining IPv4 leases for client ID 01:00:0c:01:02:03:05
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1 00:0c:01:02:03:05
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.alloc-engine/2697.140305702419520] ALLOC_ENGINE_V4_OFFER_NEW_LEASE allocation engine will try to offer new lease to the client [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.0.0.5
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet id 10 and IPv4 address 10.0.0.5
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_ADDRESS4 get all hosts with reservations for IPv4 address 10.0.0.5
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.0.0.5, found 0 host(s)
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 10 and address 10.0.0.5
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.0.0.5
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_DYNAMICALLY_CHANGED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: changed selected subnet 8.0.0.0/8 to subnet 10.0.0.0/8 from shared network one-network for client assignments
2020-07-29 12:38:38.192 INFO [kea-dhcp4.leases/2697.140305702419520] DHCP4_LEASE_ADVERT [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: lease 10.0.0.5 will be advertised
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.options/2697.140305702419520] DHCP4_PACKET_PACK [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: preparing on-wire format of the packet to be sent
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_PACKET_SEND [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: trying to send packet DHCPOFFER (type 2) from 11.0.0.1:67 to 11.0.0.3:67 on interface enp1s0f3
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_RESPONSE_DATA [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: responding with packet DHCPOFFER (type 2), packet details: local_address=11.0.0.1:67, remote_address=11.0.0.3:67, msg_type=DHCPOFFER (2), transid=0x1,
options:
type=001, len=004: 4278190080 (uint32)
type=051, len=004: 360000 (uint32)
type=053, len=001: 2 (uint8)
type=054, len=004: 11.0.0.1
type=058, len=004: 340000 (uint32)
type=059, len=004: 350000 (uint32)
type=061, len=007: 01:00:0c:01:02:03:05
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_TIMERMGR_RUN_TIMER_OPERATION running operation for timer: reclaim-expired-leases
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.alloc-engine/2697.140305702419520] ALLOC_ENGINE_V4_LEASES_RECLAMATION_START starting reclamation of expired leases (limit = 100 leases or 250 milliseconds)
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_EXPIRED4 obtaining maximum 101 of expired IPv4 leases
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.alloc-engine/2697.140305702419520] ALLOC_ENGINE_V4_LEASES_RECLAMATION_COMPLETE reclaimed 0 leases in 0.054 ms
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.alloc-engine/2697.140305702419520] ALLOC_ENGINE_V4_NO_MORE_EXPIRED_LEASES all expired leases have been reclaimed
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_TIMERMGR_START_TIMER starting timer: reclaim-expired-leases
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_BUFFER_RECEIVED received buffer from 11.0.0.3:67 to 11.0.0.1:67 over interface enp1s0f3
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.options/2697.140305702419520] DHCP4_BUFFER_UNPACK parsing buffer received from 11.0.0.3 to 11.0.0.1 over interface enp1s0f3
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_PACKET_RECEIVED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: DHCPREQUEST (type 3) received from 11.0.0.3 to 11.0.0.1 on interface enp1s0f3
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_QUERY_DATA [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1, packet details: local_address=11.0.0.1:67, remote_address=11.0.0.3:67, msg_type=DHCPREQUEST (3), transid=0x1,
options:
type=050, len=004: 10.0.0.5 (ipv4-address)
type=053, len=001: 3 (uint8)
type=054, len=004: 11.0.0.1 (ipv4-address)
type=055, len=007: 1(uint8) 28(uint8) 2(uint8) 3(uint8) 15(uint8) 6(uint8) 12(uint8)
type=061, len=007: 01:00:0c:01:02:03:05
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 8.0.0.0/8 for packet received by matching address 8.0.0.1
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_SELECTED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: the subnet with ID 8 was selected for client assignments
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_DATA [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: the selected subnet details: 8.0.0.0/8
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: hwaddr=000C01020305
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=000C01020305, found 0 host(s)
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: client-id=01000C01020305
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=01000C01020305, found 0 host(s)
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.dhcp4/2697.140305702419520] DHCP4_CLASS_ASSIGNED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: client packet has been assigned to the following class(es): UNKNOWN
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.dhcp4/2697.140305702419520] DHCP4_CLASS_ASSIGNED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: client packet has been assigned to the following class(es): ALL, UNKNOWN
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.ddns/2697.140305702419520] DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: processing client's Hostname option
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_CLIENTID obtaining IPv4 leases for client ID 01:00:0c:01:02:03:05
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1 00:0c:01:02:03:05
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet id 8 and IPv4 address 10.0.0.5
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_ADDRESS4 get all hosts with reservations for IPv4 address 10.0.0.5
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.0.0.5, found 0 host(s)
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 8 and address 10.0.0.5
2020-07-29 12:38:38.193 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.0.0.5
2020-07-29 12:38:38.194 DEBUG [kea-dhcp4.alloc-engine/2697.140305702419520] ALLOC_ENGINE_V4_REQUEST_ALLOC_REQUESTED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: trying to allocate requested address 10.0.0.5
2020-07-29 12:38:38.194 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.0.0.5
2020-07-29 12:38:38.194 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_ADD_ADDR4 adding IPv4 lease with address 10.0.0.5
2020-07-29 12:38:38.194 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_DYNAMICALLY_CHANGED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: changed selected subnet 8.0.0.0/8 to subnet 10.0.0.0/8 from shared network one-network for client assignments
2020-07-29 12:38:38.194 INFO [kea-dhcp4.leases/2697.140305702419520] DHCP4_LEASE_ALLOC [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: lease 10.0.0.5 has been allocated for 360000 seconds
2020-07-29 12:38:38.194 DEBUG [kea-dhcp4.options/2697.140305702419520] DHCP4_PACKET_PACK [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: preparing on-wire format of the packet to be sent
2020-07-29 12:38:38.194 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_PACKET_SEND [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: trying to send packet DHCPACK (type 5) from 11.0.0.1:67 to 11.0.0.3:67 on interface enp1s0f3
2020-07-29 12:38:38.194 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_RESPONSE_DATA [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: responding with packet DHCPACK (type 5), packet details: local_address=11.0.0.1:67, remote_address=11.0.0.3:67, msg_type=DHCPACK (5), transid=0x1,
options:
type=001, len=004: 4278190080 (uint32)
type=051, len=004: 360000 (uint32)
type=053, len=001: 5 (uint8)
type=054, len=004: 11.0.0.1
type=058, len=004: 340000 (uint32)
type=059, len=004: 350000 (uint32)
type=061, len=007: 01:00:0c:01:02:03:05
correct subnet selection:
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 8.0.0.0/8 for packet received by matching address 8.0.0.1
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_SELECTED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: the subnet with ID 8 was selected for client assignments
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_DATA [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: the selected subnet details: 8.0.0.0/8
address assigned from different subnet:
UG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.0.0.5
2020-07-29 12:38:38.191 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet id 10 and IPv4 address 10.0.0.5
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_ADDRESS4 get all hosts with reservations for IPv4 address 10.0.0.5
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.0.0.5, found 0 host(s)
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.hosts/2697.140305702419520] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 10 and address 10.0.0.5
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.dhcpsrv/2697.140305702419520] DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.0.0.5
2020-07-29 12:38:38.192 DEBUG [kea-dhcp4.packets/2697.140305702419520] DHCP4_SUBNET_DYNAMICALLY_CHANGED [hwtype=1 00:0c:01:02:03:05], cid=[01:00:0c:01:02:03:05], tid=0x1: changed selected subnet 8.0.0.0/8 to subnet 10.0.0.0/8 from shared network one-network for client assignments
and it all depends on first received packet. In multiple iteration of this tests I am observing this results, all depends on first packet.