[Kea-users] KEA not reacting on dhcp-requests

classic Classic list List threaded Threaded
2 messages Options
Reply | Threaded
Open this post in threaded view
|

[Kea-users] KEA not reacting on dhcp-requests

Jan Sielemann
Clients lose their lease and are disconnected - this is what's happening:

2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.packets/12781]
DHCP4_PACKET_RECEIVED [hwtype=1 00:1b:fe:04:1d:c6],
cid=[01:00:1b:fe:04:1d:c6], tid=0x99722544: DHCPDISCOVER (type 1)
received from 0.0.0.0 to 255.255.255.255 on interface net0p0
2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.packets/12781] DHCP4_QUERY_DATA
[hwtype=1 00:1b:fe:04:1d:c6], cid=[01:00:1b:fe:04:1d:c6],
tid=0x99722544, packet details: local_address=255.255.255.255:67,
remote_address=0.0.0.0:68, msg_type=DHCPDISCOVER (1), transid=0x99722544,
options:
  type=012, len=005: "75111" (string)
  type=050, len=004: 192.168.2.50 (ipv4-address)
  type=053, len=001: 1 (uint8)
  type=055, len=009: 1(uint8) 3(uint8) 6(uint8) 12(uint8) 15(uint8)
28(uint8) 40(uint8) 41(uint8)
42(uint8)                                                                                                        

  type=060, len=015: "udhcp 0.9.9-pre" (string)
  type=061, len=007: 01:00:1b:fe:04:1d:c6
2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.dhcpsrv/12781]
DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 192.168.2.0/24 for packet
received by matching address
192.168.2.1                                             
2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.packets/12781]
DHCP4_SUBNET_SELECTED [hwtype=1 00:1b:fe:04:1d:c6],
cid=[01:00:1b:fe:04:1d:c6], tid=0x99722544: the subnet with ID 1001 was
selected for client assignments
2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.packets/12781]
DHCP4_SUBNET_DATA [hwtype=1 00:1b:fe:04:1d:c6],
cid=[01:00:1b:fe:04:1d:c6], tid=0x99722544: the selected subnet details:
192.168.2.0/24                    
2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.hosts/12781]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4
reservation for subnet id 1001, identified by
hwaddr=001BFE041DC6                              
2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.hosts/12781]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using
identifier:
hwaddr=001BFE041DC6                                                           

2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.hosts/12781]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=001BFE041DC6,
found 0
host(s)                                                                     

2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.hosts/12781]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet
id 1001 and identifier
hwaddr=001BFE041DC6                                           
2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.hosts/12781]
HOSTS_MGR_ALTERNATE_GET4_SUBNET_ID_IDENTIFIER get one host with IPv4
reservation for subnet id 1001, identified by
hwaddr=001BFE041DC6                       
2019-05-07 12:26:52.323 DEBUG [kea-dhcp4.bad-packets/12781]
DHCP4_PACKET_DROP_0007 [hwtype=1 00:1b:fe:04:1d:c6],
cid=[01:00:1b:fe:04:1d:c6], tid=0x99722544: failed to process packet:
DHCPv4 Option 43 is too big.
2019-05-07 12:26:53.351 DEBUG [kea-dhcp4.packets/12781]
DHCP4_BUFFER_RECEIVED received buffer from 0.0.0.0:68 to
255.255.255.255:67 over interface
net0p0                                                          
2019-05-07 12:26:53.351 DEBUG [kea-dhcp4.options/12781]
DHCP4_BUFFER_UNPACK parsing buffer received from 0.0.0.0 to
255.255.255.255 over interface
net0p0                                                          
2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.dhcpsrv/12781]
DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 192.168.2.0/24 for packet
received by matching address
192.168.2.1                                             
2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781]
DHCP4_SUBNET_SELECTED [hwtype=1 c0:a0:bb:e5:0b:62],
cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: the subnet with ID 1001 was
selected for client assignments
2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781]
DHCP4_SUBNET_DATA [hwtype=1 c0:a0:bb:e5:0b:62],
cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: the selected subnet details:
192.168.2.0/24                    
2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781]
DHCP4_PACKET_RECEIVED [hwtype=1 c0:a0:bb:e5:0b:62],
cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: DHCPDISCOVER (type 1)
received from 0.0.0.0 to 255.255.255.255 on interface net0p0
2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781] DHCP4_QUERY_DATA
[hwtype=1 c0:a0:bb:e5:0b:62], cid=[01:c0:a0:bb:e5:0b:62],
tid=0x1c017815, packet details: local_address=255.255.255.255:67,
remote_address=0.0.0.0:68, msg_type=DHCPDISCOVER (1), transid=0x1c017815,
options:
  type=012, len=004: "apbr" (string)
  type=050, len=004: 192.168.2.7 (ipv4-address)
  type=053, len=001: 1 (uint8)
  type=055, len=009: 1(uint8) 3(uint8) 6(uint8) 12(uint8) 15(uint8)
28(uint8) 44(uint8) 46(uint8)
47(uint8)                                                                                                        

  type=060, len=015: "udhcp 0.9.9-pre" (string)
  type=061, len=007: 01:c0:a0:bb:e5:0b:62
2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.dhcpsrv/12781]
DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 192.168.2.0/24 for packet
received by matching address
192.168.2.1                                             
2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781]
DHCP4_SUBNET_SELECTED [hwtype=1 c0:a0:bb:e5:0b:62],
cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: the subnet with ID 1001 was
selected for client assignments
2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781]
DHCP4_SUBNET_DATA [hwtype=1 c0:a0:bb:e5:0b:62],
cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: the selected subnet details:
192.168.2.0/24                    
2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.hosts/12781]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4
reservation for subnet id 1001, identified by
hwaddr=C0A0BBE50B62                              
2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.hosts/12781]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using
identifier:
hwaddr=C0A0BBE50B62                                                           

2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.hosts/12781]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=C0A0BBE50B62,
found 0
host(s)                                                                     

2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.hosts/12781]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet
id 1001 and identifier
hwaddr=C0A0BBE50B62                                           
2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.hosts/12781]
HOSTS_MGR_ALTERNATE_GET4_SUBNET_ID_IDENTIFIER get one host with IPv4
reservation for subnet id 1001, identified by
hwaddr=C0A0BBE50B62                       
2019-05-07 12:26:53.353 DEBUG [kea-dhcp4.bad-packets/12781]
DHCP4_PACKET_DROP_0007 [hwtype=1 c0:a0:bb:e5:0b:62],
cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: failed to process packet:
DHCPv4 Option 43 is too big.

--
------------------------------------------------------------------------
Logo JSIIT Consulting JSIIT Consulting Phone: +49 40 22865465
Jan Sielemann Telefax: +49 40 22865468
Riekbornweg 22 Mobile: +49 1578 6769143
22547 Hamburg GPG-Public-Key: 1D6CA679CEBDF96C
<https://jsiit.info/public/1D6CA679CEBDF96C.pub.gpg>

------------------------------------------------------------------------
_______________________________________________
Kea-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/kea-users
Reply | Threaded
Open this post in threaded view
|

Re: [Kea-users] KEA not reacting on dhcp-requests

Thomas Markwalder
The server is rejecting the packet due to option 43 content:

2019-05-07 12:26:53.353 DEBUG [kea-dhcp4.bad-packets/12781]
DHCP4_PACKET_DROP_0007 [hwtype=1 c0:a0:bb:e5:0b:62],
cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: failed to process packet:
DHCPv4 Option 43 is too big.

This happens when the length for a DHCPv4 option is larger than 255.  A PCAP would be helpful.

Cheers,

Thomas

A pcap file would be helpful here, but

On 5/7/19 6:33 AM, Jan Sielemann wrote:

> Clients lose their lease and are disconnected - this is what's happening:
>
> 2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.packets/12781]
> DHCP4_PACKET_RECEIVED [hwtype=1 00:1b:fe:04:1d:c6],
> cid=[01:00:1b:fe:04:1d:c6], tid=0x99722544: DHCPDISCOVER (type 1)
> received from 0.0.0.0 to 255.255.255.255 on interface net0p0
> 2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.packets/12781] DHCP4_QUERY_DATA
> [hwtype=1 00:1b:fe:04:1d:c6], cid=[01:00:1b:fe:04:1d:c6],
> tid=0x99722544, packet details: local_address=255.255.255.255:67,
> remote_address=0.0.0.0:68, msg_type=DHCPDISCOVER (1), transid=0x99722544,
> options:
>    type=012, len=005: "75111" (string)
>    type=050, len=004: 192.168.2.50 (ipv4-address)
>    type=053, len=001: 1 (uint8)
>    type=055, len=009: 1(uint8) 3(uint8) 6(uint8) 12(uint8) 15(uint8)
> 28(uint8) 40(uint8) 41(uint8)
> 42(uint8)
>
>    type=060, len=015: "udhcp 0.9.9-pre" (string)
>    type=061, len=007: 01:00:1b:fe:04:1d:c6
> 2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.dhcpsrv/12781]
> DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 192.168.2.0/24 for packet
> received by matching address
> 192.168.2.1
> 2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.packets/12781]
> DHCP4_SUBNET_SELECTED [hwtype=1 00:1b:fe:04:1d:c6],
> cid=[01:00:1b:fe:04:1d:c6], tid=0x99722544: the subnet with ID 1001 was
> selected for client assignments
> 2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.packets/12781]
> DHCP4_SUBNET_DATA [hwtype=1 00:1b:fe:04:1d:c6],
> cid=[01:00:1b:fe:04:1d:c6], tid=0x99722544: the selected subnet details:
> 192.168.2.0/24
> 2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.hosts/12781]
> HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4
> reservation for subnet id 1001, identified by
> hwaddr=001BFE041DC6
> 2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.hosts/12781]
> HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using
> identifier:
> hwaddr=001BFE041DC6
>
> 2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.hosts/12781]
> HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=001BFE041DC6,
> found 0
> host(s)
>
> 2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.hosts/12781]
> HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet
> id 1001 and identifier
> hwaddr=001BFE041DC6
> 2019-05-07 12:26:52.322 DEBUG [kea-dhcp4.hosts/12781]
> HOSTS_MGR_ALTERNATE_GET4_SUBNET_ID_IDENTIFIER get one host with IPv4
> reservation for subnet id 1001, identified by
> hwaddr=001BFE041DC6
> 2019-05-07 12:26:52.323 DEBUG [kea-dhcp4.bad-packets/12781]
> DHCP4_PACKET_DROP_0007 [hwtype=1 00:1b:fe:04:1d:c6],
> cid=[01:00:1b:fe:04:1d:c6], tid=0x99722544: failed to process packet:
> DHCPv4 Option 43 is too big.
> 2019-05-07 12:26:53.351 DEBUG [kea-dhcp4.packets/12781]
> DHCP4_BUFFER_RECEIVED received buffer from 0.0.0.0:68 to
> 255.255.255.255:67 over interface
> net0p0
> 2019-05-07 12:26:53.351 DEBUG [kea-dhcp4.options/12781]
> DHCP4_BUFFER_UNPACK parsing buffer received from 0.0.0.0 to
> 255.255.255.255 over interface
> net0p0
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.dhcpsrv/12781]
> DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 192.168.2.0/24 for packet
> received by matching address
> 192.168.2.1
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781]
> DHCP4_SUBNET_SELECTED [hwtype=1 c0:a0:bb:e5:0b:62],
> cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: the subnet with ID 1001 was
> selected for client assignments
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781]
> DHCP4_SUBNET_DATA [hwtype=1 c0:a0:bb:e5:0b:62],
> cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: the selected subnet details:
> 192.168.2.0/24
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781]
> DHCP4_PACKET_RECEIVED [hwtype=1 c0:a0:bb:e5:0b:62],
> cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: DHCPDISCOVER (type 1)
> received from 0.0.0.0 to 255.255.255.255 on interface net0p0
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781] DHCP4_QUERY_DATA
> [hwtype=1 c0:a0:bb:e5:0b:62], cid=[01:c0:a0:bb:e5:0b:62],
> tid=0x1c017815, packet details: local_address=255.255.255.255:67,
> remote_address=0.0.0.0:68, msg_type=DHCPDISCOVER (1), transid=0x1c017815,
> options:
>    type=012, len=004: "apbr" (string)
>    type=050, len=004: 192.168.2.7 (ipv4-address)
>    type=053, len=001: 1 (uint8)
>    type=055, len=009: 1(uint8) 3(uint8) 6(uint8) 12(uint8) 15(uint8)
> 28(uint8) 44(uint8) 46(uint8)
> 47(uint8)
>
>    type=060, len=015: "udhcp 0.9.9-pre" (string)
>    type=061, len=007: 01:c0:a0:bb:e5:0b:62
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.dhcpsrv/12781]
> DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 192.168.2.0/24 for packet
> received by matching address
> 192.168.2.1
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781]
> DHCP4_SUBNET_SELECTED [hwtype=1 c0:a0:bb:e5:0b:62],
> cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: the subnet with ID 1001 was
> selected for client assignments
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.packets/12781]
> DHCP4_SUBNET_DATA [hwtype=1 c0:a0:bb:e5:0b:62],
> cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: the selected subnet details:
> 192.168.2.0/24
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.hosts/12781]
> HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4
> reservation for subnet id 1001, identified by
> hwaddr=C0A0BBE50B62
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.hosts/12781]
> HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using
> identifier:
> hwaddr=C0A0BBE50B62
>
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.hosts/12781]
> HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=C0A0BBE50B62,
> found 0
> host(s)
>
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.hosts/12781]
> HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet
> id 1001 and identifier
> hwaddr=C0A0BBE50B62
> 2019-05-07 12:26:53.352 DEBUG [kea-dhcp4.hosts/12781]
> HOSTS_MGR_ALTERNATE_GET4_SUBNET_ID_IDENTIFIER get one host with IPv4
> reservation for subnet id 1001, identified by
> hwaddr=C0A0BBE50B62
> 2019-05-07 12:26:53.353 DEBUG [kea-dhcp4.bad-packets/12781]
> DHCP4_PACKET_DROP_0007 [hwtype=1 c0:a0:bb:e5:0b:62],
> cid=[01:c0:a0:bb:e5:0b:62], tid=0x1c017815: failed to process packet:
> DHCPv4 Option 43 is too big.
>

_______________________________________________
Kea-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/kea-users