[Kea-users] address not being allocated with perfdhcp

Mon Oct 26 07:42:49 UTC 2020

hi All

I’m using perfdhcp to test my kea-DHCP, while trying to produce DHCP
requests via VLAN interfaces, the address allocation process doesn't end
successfully.in the DHCP log I see all sessions end with
"DHCP4_LEASE_ADVERT" message and no allocation accurse.

while using perfdhcp without VLAN the allocation accrues successfully.

while using the VLAN interface with a single DHCP client (netplan not
prefdhcp) allocation to the VLAN interface is successful.

so it seems like when using the VLAN interface with prefdhcp the client
doesn't claim the offered address.

basically, I'm a bit lost here, so if someone has any idea what is happing
here, it will be awesome.

i hope I'm just missing an option in my perfdhcp command and not a
limitation.

attached debug log of the fail transaction.

Thanks
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.isc.org/pipermail/kea-users/attachments/20201026/309608bd/attachment-0001.htm>
-------------- next part --------------

296 13:57:09.018 kea-dhcp4.packets DHCP4_BUFFER_RECEIVED received buffer from 10.1.2.1:67 to 172.17.200.3:67 over interface ens160
296 13:57:09.018 kea-dhcp4.options DHCP4_BUFFER_UNPACK parsing buffer received from 10.1.2.1 to 172.17.200.3 over interface ens160
296 13:57:09.018 kea-dhcp4.packets DHCP4_PACKET_RECEIVED [hwtype=1 00:0e:29:b3:41:f5], cid=[01:00:0e:29:b3:41:f5], tid=0x0: DHCPDISCOVER (type 1) received from 10.1.2.1 to 172.17.200.3 on interface ens160
296 13:57:09.018 kea-dhcp4.packets DHCP4_QUERY_DATA [hwtype=1 00:0e:29:b3:41:f5], cid=[01:00:0e:29:b3:41:f5], tid=0x0, packet details: local_address=172.17.200.3:67, remote_address=10.1.2.1: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:0e:29:b3:41:f5
296 13:57:09.018 kea-dhcp4.dhcpsrv DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.1.2.0/24 for packet received by matching address 10.1.2.11
296 13:57:09.018 kea-dhcp4.packets DHCP4_SUBNET_SELECTED [hwtype=1 00:0e:29:b3:41:f5], cid=[01:00:0e:29:b3:41:f5], tid=0x0: the subnet with ID 4 was selected for client assignments
296 13:57:09.018 kea-dhcp4.packets DHCP4_SUBNET_DATA [hwtype=1 00:0e:29:b3:41:f5], cid=[01:00:0e:29:b3:41:f5], tid=0x0: the selected subnet details: 10.1.2.0/24
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for subnet id 4, identified by hwaddr=000E29B341F5
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: hwaddr=000E29B341F5
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=000E29B341F5, found 0 host(s)
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 4 and identifier hwaddr=000E29B341F5
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for subnet id 4, identified by client-id=01000E29B341F5
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: client-id=01000E29B341F5
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=01000E29B341F5, found 0 host(s)
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 4 and identifier client-id=01000E29B341F5
296 13:57:09.018 kea-dhcp4.dhcp4 DHCP4_CLASS_ASSIGNED [hwtype=1 00:0e:29:b3:41:f5], cid=[01:00:0e:29:b3:41:f5], tid=0x0: client packet has been assigned to the following class(es): UNKNOWN
296 13:57:09.018 kea-dhcp4.dhcp4 DHCP4_CLASS_ASSIGNED [hwtype=1 00:0e:29:b3:41:f5], cid=[01:00:0e:29:b3:41:f5], tid=0x0: client packet has been assigned to the following class(es): ALL, UNKNOWN
296 13:57:09.018 kea-dhcp4.ddns DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 00:0e:29:b3:41:f5], cid=[01:00:0e:29:b3:41:f5], tid=0x0: processing client's Hostname option
296 13:57:09.018 kea-dhcp4.dhcpsrv DHCPSRV_MEMFILE_GET_CLIENTID obtaining IPv4 leases for client ID 01:00:0e:29:b3:41:f5
296 13:57:09.018 kea-dhcp4.dhcpsrv DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1 00:0e:29:b3:41:f5
296 13:57:09.018 kea-dhcp4.alloc-engine ALLOC_ENGINE_V4_OFFER_NEW_LEASE allocation engine will try to offer new lease to the client [hwtype=1 00:0e:29:b3:41:f5], cid=[01:00:0e:29:b3:41:f5], tid=0x0
296 13:57:09.018 kea-dhcp4.dhcpsrv DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.1.2.11
296 13:57:09.018 kea-dhcp4.dhcpsrv DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.1.2.12
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet id 4 and IPv4 address 10.1.2.12
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ALL_ADDRESS4 get all hosts with reservations for IPv4 address 10.1.2.12
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.1.2.12, found 0 host(s)
296 13:57:09.018 kea-dhcp4.hosts HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 4 and address 10.1.2.12
296 13:57:09.018 kea-dhcp4.dhcpsrv DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.1.2.12
296 13:57:09.018 kea-dhcp4.leases DHCP4_LEASE_ADVERT [hwtype=1 00:0e:29:b3:41:f5], cid=[01:00:0e:29:b3:41:f5], tid=0x0: lease 10.1.2.12 will be advertised
296 13:57:09.018 kea-dhcp4.options DHCP4_PACKET_PACK [hwtype=1 00:0e:29:b3:41:f5], cid=[no info], tid=0x0: preparing on-wire format of the packet to be sent
296 13:57:09.018 kea-dhcp4.packets DHCP4_PACKET_SEND [hwtype=1 00:0e:29:b3:41:f5], cid=[no info], tid=0x0: trying to send packet DHCPOFFER (type 2) from 172.17.200.3:67 to 10.1.2.11:67 on interface ens160
296 13:57:09.018 kea-dhcp4.packets DHCP4_RESPONSE_DATA [hwtype=1 00:0e:29:b3:41:f5], cid=[no info], tid=0x0: responding with packet DHCPOFFER (type 2), packet details: local_address=172.17.200.3:67, remote_address=10.1.2.11:67, msg_type=DHCPOFFER (2), transid=0x0,
options:
  type=001, len=004: 4294967040 (uint32)
  type=051, len=004: 1000 (uint32)
  type=053, len=001: 2 (uint8)
  type=054, len=004: 172.17.200.3
  type=058, len=004: 100 (uint32)
  type=059, len=004: 200 (uint32)
296 13:57:10.019 kea-dhcp4.dhcpsrv DHCPSRV_TIMERMGR_RUN_TIMER_OPERATION running operation for timer: flush-reclaimed-leases
296 13:57:10.019 kea-dhcp4.alloc-engine ALLOC_ENGINE_V4_RECLAIMED_LEASES_DELETE begin deletion of reclaimed leases expired more than 3600 seconds ago
296 13:57:10.019 kea-dhcp4.dhcpsrv DHCPSRV_MEMFILE_DELETE_EXPIRED_RECLAIMED4 deleting reclaimed IPv4 leases that expired more than 3600 seconds ago
296 13:57:10.019 kea-dhcp4.alloc-engine ALLOC_ENGINE_V4_RECLAIMED_LEASES_DELETE_COMPLETE successfully deleted 0 expired-reclaimed leases
296 13:57:10.019 kea-dhcp4.dhcpsrv DHCPSRV_TIMERMGR_START_TIMER starting timer: flush-reclaimed-leases
296 13:57:10.393 kea-dhcp4.commands COMMAND_SOCKET_CONNECTION_OPENED Opened socket 22 for incoming command connection
296 13:57:10.393 kea-dhcp4.commands COMMAND_SOCKET_READ Received 86 bytes over command socket 22
296 13:57:10.393 kea-dhcp4.commands COMMAND_RECEIVED Received command 'statistic-get-all'
296 13:57:10.406 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (203707 bytes left to send) over command socket 22
296 13:57:10.406 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (170939 bytes left to send) over command socket 22
296 13:57:10.406 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (138171 bytes left to send) over command socket 22
296 13:57:10.406 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (105403 bytes left to send) over command socket 22
296 13:57:10.407 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (72635 bytes left to send) over command socket 22
296 13:57:10.407 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (39867 bytes left to send) over command socket 22
296 13:57:10.407 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (7099 bytes left to send) over command socket 22
296 13:57:10.407 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 7099 bytes (0 bytes left to send) over command socket 22
296 13:57:10.407 kea-dhcp4.commands COMMAND_SOCKET_CONNECTION_CLOSED Closed socket 22 for existing command connection
296 13:57:14.673 kea-dhcp4.commands COMMAND_SOCKET_CONNECTION_OPENED Opened socket 22 for incoming command connection
296 13:57:14.673 kea-dhcp4.commands COMMAND_SOCKET_READ Received 52 bytes over command socket 22
296 13:57:14.673 kea-dhcp4.commands COMMAND_RECEIVED Received command 'version-get'
296 13:57:14.673 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 170 bytes (0 bytes left to send) over command socket 22
296 13:57:14.673 kea-dhcp4.commands COMMAND_SOCKET_CONNECTION_CLOSED Closed socket 22 for existing command connection
296 13:57:14.674 kea-dhcp4.commands COMMAND_SOCKET_CONNECTION_OPENED Opened socket 22 for incoming command connection
296 13:57:14.674 kea-dhcp4.commands COMMAND_SOCKET_READ Received 51 bytes over command socket 22
296 13:57:14.674 kea-dhcp4.commands COMMAND_RECEIVED Received command 'status-get'
296 13:57:14.674 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 107 bytes (0 bytes left to send) over command socket 22
296 13:57:14.674 kea-dhcp4.commands COMMAND_SOCKET_CONNECTION_CLOSED Closed socket 22 for existing command connection
296 13:57:14.674 kea-dhcp4.commands COMMAND_SOCKET_CONNECTION_OPENED Opened socket 22 for incoming command connection
296 13:57:14.675 kea-dhcp4.commands COMMAND_SOCKET_READ Received 51 bytes over command socket 22
296 13:57:14.675 kea-dhcp4.commands COMMAND_RECEIVED Received command 'config-get'
296 13:57:14.681 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (182762 bytes left to send) over command socket 22
296 13:57:14.685 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (149994 bytes left to send) over command socket 22
296 13:57:14.685 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (117226 bytes left to send) over command socket 22
296 13:57:14.686 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (84458 bytes left to send) over command socket 22
296 13:57:14.686 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (51690 bytes left to send) over command socket 22
296 13:57:14.686 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 32768 bytes (18922 bytes left to send) over command socket 22
296 13:57:14.686 kea-dhcp4.commands COMMAND_SOCKET_WRITE Sent response of 18922 bytes (0 bytes left to send) over command socket 22
296 13:57:14.686 kea-dhcp4.commands COMMAND_SOCKET_CONNECTION_CLOSED Closed socket 22 for existing command connection
^C