<div dir="ltr"><div dir="ltr">Enabled the debugs, and something definitely looks wrong here...<div><div><br></div><div>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<br></div><div>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=<a href="http://10.50.5.11:67">10.50.5.11:67</a>, remote_address=<a href="http://10.50.1.24:67">10.50.1.24:67</a>, msg_type=DHCPDISCOVER (1), transid=0x78768f6e,</div><div>options:</div><div>  type=012, len=007: "cumulus" (string)</div><div>  type=051, len=004: 7200 (uint32)</div><div>  type=053, len=001: 1 (uint8)</div><div>  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)</div><div>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</div><div>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: <a href="http://10.50.24.0/24">10.50.24.0/24</a></div><div>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</div><div>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</div><div>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)</div><div>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</div><div>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</div><div>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)</div><div>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</div><div>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</div><div>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 <a href="http://dell-s4000-53.rdu.cumulusnetworks.com">dell-s4000-53.rdu.cumulusnetworks.com</a></div><div>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</div><div>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</div><div>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</div><div>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</div><div>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</div><div>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</div><div>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</div><div>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.</div><div>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 <a href="http://10.50.5.11:67">10.50.5.11:67</a> to <a href="http://10.50.24.1:67">10.50.24.1:67</a> on interface eth0</div><div>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=<a href="http://10.50.5.11:67">10.50.5.11:67</a>, remote_address=<a href="http://10.50.24.1:67">10.50.24.1:67</a>, msg_type=DHCPOFFER (2), transid=0x78768f6e,</div><div>options:</div><div>  type=001, len=004: 4294967040 (uint32)</div><div>  type=003, len=004: 10.50.24.1</div><div>  type=006, len=004: 10.50.5.12</div><div>  type=012, len=037: "<a href="http://dell-s4000-53.rdu.cumulusnetworks.com">dell-s4000-53.rdu.cumulusnetworks.com</a>" (string)</div><div>  type=015, len=023: "<a href="http://rdu.cumulusnetworks.com">rdu.cumulusnetworks.com</a>" (string)</div><div>  type=042, len=004: 10.50.5.11</div><div>  type=051, len=004: 4000 (uint32)</div><div>  type=053, len=001: 2 (uint8)</div><div>  type=054, len=004: 10.50.5.11</div><div>  type=058, len=004: 900 (uint32)</div><div>  type=059, len=004: 1800 (uint32)</div><div>  type=119, len=123: "<a href="http://rdu.cumulusnetworks.com">rdu.cumulusnetworks.com</a>." (fqdn) "<a href="http://mvlab.cumulusnetworks.com">mvlab.cumulusnetworks.com</a>." (fqdn) "<a href="http://lab.cumulusnetworks.com">lab.cumulusnetworks.com</a>." (fqdn) "<a href="http://syd.cumulusnetworks.com">syd.cumulusnetworks.com</a>." (fqdn) "<a href="http://cumulusnetworks.com">cumulusnetworks.com</a>." (fqdn)</div><div>  <b>type=239, len=4096: "" (string)</b></div><div><br></div><div>How is an empty string length 4096?</div></div><div><br></div><div>Thanks,<br>Jason</div></div></div><br><div class="gmail_quote"><div dir="ltr" class="gmail_attr">On Thu, Jan 17, 2019 at 11:24 AM Jason Guy <<a href="mailto:jguy@cumulusnetworks.com">jguy@cumulusnetworks.com</a>> wrote:<br></div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><div dir="ltr"><div dir="ltr"><div dir="ltr"><div dir="ltr">Hi all,<div><br></div><div>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:</div><div><br></div><div><div>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</div><div>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.</div></div><div><br></div><div>However I know the defined option is not large:</div><div><div>        "option-data": [</div><div>           <snip></div><div>            {</div><div>                "code": 239,</div><div>                "csv-format": true,</div><div>                "data": "<a href="http://monster-01/ztp.txt" target="_blank">http://monster-01/ztp.txt</a>",</div><div>                "name": "cumulus-provision-url",</div><div>                "space": "dhcp4"</div><div>            },</div><div>          <snip></div><div>        ],</div><div>        "option-def": [</div><div>            {</div><div>                "code": 239,</div><div>                "name": "cumulus-provision-url",</div><div>                "space": "dhcp4",</div><div>                "type": "string"</div><div>            },</div></div><div><br></div><div>I am not sure how to debug exactly what is causing this to fail for a couple of hosts. Any ideas?</div><div><br></div><div>I am planning to upgrade to 1.4 or 1.5, but I have not had the time to work on that. </div><div><br></div><div>Thanks,<br>Jason</div><div><br></div></div></div></div></div>
_______________________________________________<br>
Kea-users mailing list<br>
<a href="mailto:Kea-users@lists.isc.org" target="_blank">Kea-users@lists.isc.org</a><br>
<a href="https://lists.isc.org/mailman/listinfo/kea-users" rel="noreferrer" target="_blank">https://lists.isc.org/mailman/listinfo/kea-users</a><br>
</blockquote></div>