Enabled the debugs, and something definitely looks wrong here...
2019-01-17 12:59:20.783 DEBUG [kea-dhcp4.packets/21850]
DHCP4_PACKET_RECEIVED [hwtype=1 34:17:eb:f6:5e:c4], cid=[no info],
tid=0x78768f6e: DHCPDISCOVER (type 1) received from 10.50.1.24 to
10.50.5.11 on interface eth0
2019-01-17 12:59:20.783 DEBUG [kea-dhcp4.packets/21850] DHCP4_QUERY_DATA
[hwtype=1 34:17:eb:f6:5e:c4], cid=[no info], tid=0x78768f6e, packet
details: local_address=10.50.5.11:67, remote_address=10.50.1.24:67,
msg_type=DHCPDISCOVER (1), transid=0x78768f6e,
options:
type=012, len=007: "cumulus" (string)
type=051, len=004: 7200 (uint32)
type=053, len=001: 1 (uint8)
type=055, len=014: 1(uint8) 28(uint8) 2(uint8) 3(uint8) 15(uint8)
6(uint8) 119(uint8) 12(uint8) 44(uint8) 47(uint8) 26(uint8) 121(uint8)
42(uint8) 239(uint8)
2019-01-17 12:59:20.783 DEBUG [kea-dhcp4.packets/21850]
DHCP4_SUBNET_SELECTED [hwtype=1 34:17:eb:f6:5e:c4], cid=[no info],
tid=0x78768f6e: the subnet with ID 24 was selected for client assignments
2019-01-17 12:59:20.783 DEBUG [kea-dhcp4.packets/21850] DHCP4_SUBNET_DATA
[hwtype=1 34:17:eb:f6:5e:c4], cid=[no info], tid=0x78768f6e: the selected
subnet details: 10.50.24.0/24
2019-01-17 12:59:20.783 DEBUG [kea-dhcp4.hosts/21850]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation
for subnet id 24, identified by hwaddr=3417EBF65EC4
2019-01-17 12:59:20.783 DEBUG [kea-dhcp4.hosts/21850]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using
identifier: hwaddr=3417EBF65EC4
2019-01-17 12:59:20.783 DEBUG [kea-dhcp4.hosts/21850]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=3417EBF65EC4,
found 0 host(s)
2019-01-17 12:59:20.784 DEBUG [kea-dhcp4.hosts/21850]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id
24 and identifier hwaddr=3417EBF65EC4
2019-01-17 12:59:20.784 DEBUG [kea-dhcp4.hosts/21850]
HOSTS_MGR_ALTERNATE_GET4_SUBNET_ID_IDENTIFIER get one host with IPv4
reservation for subnet id 24, identified by hwaddr=3417EBF65EC4
2019-01-17 12:59:20.785 DEBUG [kea-dhcp4.hosts/21850]
HOSTS_MGR_ALTERNATE_GET4_SUBNET_ID_IDENTIFIER_HOST using subnet id 24 and
identifier hwaddr=3417EBF65EC4, found host: hwaddr=3417EBF65EC4
ipv4_subnet_id=24 hostname=dell-s4000-53 ipv4_reservation=10.50.24.112
siaddr=(no) sname=(empty) file=(empty) ipv6_reservations=(none)
2019-01-17 12:59:20.785 DEBUG [kea-dhcp4.ddns/21850]
DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 34:17:eb:f6:5e:c4], cid=[no info],
tid=0x78768f6e: processing client's Hostname option
2019-01-17 12:59:20.785 DEBUG [kea-dhcp4.ddns/21850]
DHCP4_CLIENT_HOSTNAME_DATA [hwtype=1 34:17:eb:f6:5e:c4], cid=[no info],
tid=0x78768f6e: client sent Hostname option: cumulus
2019-01-17 12:59:20.785 DEBUG [kea-dhcp4.ddns/21850]
DHCP4_RESERVED_HOSTNAME_ASSIGNED [hwtype=1 34:17:eb:f6:5e:c4], cid=[no
info], tid=0x78768f6e: server assigned reserved hostname
dell-s4000-53.rdu.cumulusnetworks.com
2019-01-17 12:59:20.785 DEBUG [kea-dhcp4.dhcpsrv/21850]
DHCPSRV_MYSQL_GET_HWADDR obtaining IPv4 leases for hardware address
hwtype=1 34:17:eb:f6:5e:c4
2019-01-17 12:59:20.786 DEBUG [kea-dhcp4.alloc-engine/21850]
ALLOC_ENGINE_V4_DISCOVER_HR client [hwtype=1 34:17:eb:f6:5e:c4], cid=[no
info], tid=0x78768f6e sending DHCPDISCOVER has reservation for the address
10.50.24.112
2019-01-17 12:59:20.786 DEBUG [kea-dhcp4.dhcpsrv/21850]
DHCPSRV_MYSQL_GET_ADDR4 obtaining IPv4 lease for address 10.50.24.112
2019-01-17 12:59:20.786 DEBUG [kea-dhcp4.dhcpsrv/21850]
DHCPSRV_MYSQL_GET_ADDR4 obtaining IPv4 lease for address 10.50.24.112
2019-01-17 12:59:20.787 INFO [kea-dhcp4.leases/21850] DHCP4_LEASE_ADVERT
[hwtype=1 34:17:eb:f6:5e:c4], cid=[no info], tid=0x78768f6e: lease
10.50.24.112 will be advertised
2019-01-17 12:59:20.787 DEBUG [kea-dhcp4.dhcp4/21850]
DHCP4_CLIENTID_IGNORED_FOR_LEASES [hwtype=1 34:17:eb:f6:5e:c4], cid=[no
info], tid=0x78768f6e: not using client identifier for lease allocation for
subnet 24
2019-01-17 12:59:20.787 DEBUG [kea-dhcp4.options/21850] DHCP4_PACKET_PACK
[hwtype=1 34:17:eb:f6:5e:c4], cid=[no info], tid=0x78768f6e: preparing
on-wire format of the packet to be sent
2019-01-17 12:59:20.787 ERROR [kea-dhcp4.options/21850]
DHCP4_PACKET_PACK_FAIL [hwtype=1 34:17:eb:f6:5e:c4], cid=[no info],
tid=0x78768f6e: preparing on-wire-format of the packet to be sent failed
DHCPv4 Option 239 is too big. At most 255 bytes are supported.
2019-01-17 12:59:20.787 DEBUG [kea-dhcp4.packets/21850] DHCP4_PACKET_SEND
[hwtype=1 34:17:eb:f6:5e:c4], cid=[no info], tid=0x78768f6e: trying to send
packet DHCPOFFER (type 2) from 10.50.5.11:67 to 10.50.24.1:67 on interface
eth0
2019-01-17 12:59:20.787 DEBUG [kea-dhcp4.packets/21850] DHCP4_RESPONSE_DATA
[hwtype=1 34:17:eb:f6:5e:c4], cid=[no info], tid=0x78768f6e: responding
with packet DHCPOFFER (type 2), packet details: local_address=10.50.5.11:67,
remote_address=10.50.24.1:67, msg_type=DHCPOFFER (2), transid=0x78768f6e,
options:
type=001, len=004: 4294967040 (uint32)
type=003, len=004: 10.50.24.1
type=006, len=004: 10.50.5.12
type=012, len=037: "dell-s4000-53.rdu.cumulusnetworks.com" (string)
type=015, len=023: "rdu.cumulusnetworks.com" (string)
type=042, len=004: 10.50.5.11
type=051, len=004: 4000 (uint32)
type=053, len=001: 2 (uint8)
type=054, len=004: 10.50.5.11
type=058, len=004: 900 (uint32)
type=059, len=004: 1800 (uint32)
type=119, len=123: "rdu.cumulusnetworks.com." (fqdn) "
mvlab.cumulusnetworks.com." (fqdn) "lab.cumulusnetworks.com." (fqdn) "
syd.cumulusnetworks.com." (fqdn) "cumulusnetworks.com." (fqdn)
*type=239, len=4096: "" (string)*
How is an empty string length 4096?
Thanks,
Jason
On Thu, Jan 17, 2019 at 11:24 AM Jason Guy <j...@cumulusnetworks.com> wrote:
> Hi all,
>
> I am seeing a problem on the kea 1.3 server where one of my custom options
> are causing the following error, only for a couple devices:
>
> 2019-01-17 11:14:17.186 INFO [kea-dhcp4.leases/21772] DHCP4_LEASE_ADVERT
> [hwtype=1 e0:07:1b:c8:10:86], cid=[no info], tid=0x69c96d1f: lease
> 10.50.22.109 will be advertised
> 2019-01-17 11:14:17.186 ERROR [kea-dhcp4.options/21772]
> DHCP4_PACKET_PACK_FAIL [hwtype=1 e0:07:1b:c8:10:86], cid=[no info],
> tid=0x69c96d1f: preparing on-wire-format of the packet to be sent failed
> DHCPv4 Option 239 is too big. At most 255 bytes are supported.
>
> However I know the defined option is not large:
> "option-data": [
> <snip>
> {
> "code": 239,
> "csv-format": true,
> "data": "http://monster-01/ztp.txt";,
> "name": "cumulus-provision-url",
> "space": "dhcp4"
> },
> <snip>
> ],
> "option-def": [
> {
> "code": 239,
> "name": "cumulus-provision-url",
> "space": "dhcp4",
> "type": "string"
> },
>
> I am not sure how to debug exactly what is causing this to fail for a
> couple of hosts. Any ideas?
>
> I am planning to upgrade to 1.4 or 1.5, but I have not had the time to
> work on that.
>
> Thanks,
> Jason
>
> _______________________________________________
> Kea-users mailing list
> Kea-users@lists.isc.org
> https://lists.isc.org/mailman/listinfo/kea-users
>
_______________________________________________
Kea-users mailing list
Kea-users@lists.isc.org
https://lists.isc.org/mailman/listinfo/kea-users
