[Kea-users] (no subject)

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

[Kea-users] (no subject)

Jason Guy
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
[hidden email]
https://lists.isc.org/mailman/listinfo/kea-users
Reply | Threaded
Open this post in threaded view
|

Re: [Kea-users] (no subject)

Jason Guy
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 <[hidden email]> 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
[hidden email]
https://lists.isc.org/mailman/listinfo/kea-users

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