Skip to content

GitLab

  • Projects
  • Groups
  • Snippets
  • Help
    • Loading...
  • Help
    • Help
    • Support
    • Community forum
    • Submit feedback
    • Contribute to GitLab
  • Sign in / Register
Kea
Kea
  • Project overview
    • Project overview
    • Details
    • Activity
    • Releases
  • Repository
    • Repository
    • Files
    • Commits
    • Branches
    • Tags
    • Contributors
    • Graph
    • Compare
  • Issues 414
    • Issues 414
    • List
    • Boards
    • Labels
    • Service Desk
    • Milestones
  • Merge Requests 67
    • Merge Requests 67
  • Operations
    • Operations
    • Incidents
  • Packages & Registries
    • Packages & Registries
    • Container Registry
  • Analytics
    • Analytics
    • Repository
    • Value Stream
  • Wiki
    • Wiki
  • Snippets
    • Snippets
  • Members
    • Members
  • Collapse sidebar
  • Activity
  • Graph
  • Create a new issue
  • Commits
  • Issue Boards
  • ISC Open Source Projects
  • KeaKea
  • Issues
  • #1352

Closed
Open
Opened Jul 29, 2020 by Wlodzimierz Wencel@wlodekMaintainer

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.

To upload designs, you'll need to enable LFS and have admin enable hashed storage. More information
Assignee
Assign to
None
Milestone
None
Assign milestone
Time tracking
None
Due date
None
Reference: isc-projects/kea#1352