[Kea-users] DHCP6 subnet select fails. Basic config

[Tim Howe]

On Fri, 12 Jan 2018 06:15:44 +0000
Francis Dupont <fdupont at isc.org> wrote:

> I found at least 2 easy ways to fix your problem:
>  - the first one is to have a second relay which fills the link address:
>   the code skips all link addresses which are undefined or link local
>   as it wants to be able to identify the relay. BTW the interface-id
>   option must be added by the first (closest to client) relay.

	Hello again.  So, I have been working on implementing the
above.  I ran in to quite a bit of trouble with DHCPv6 UDP packets
being forwarded to my Kea server which turned out to be a Juniper
switch bug(!)  I mention it here in case anyone else runs into this
PR960687:
https://prsearch.juniper.net/InfoCenter/index?page=prcontent&id=PR960687

	So, now I can see that my client is requesting DHCP and PD, and
the Kea server is selecting the configured options and appears to be
assigning them to that client.  However, when all is said and done, the
client doesn't appear to have any addresses and my leases file is
empty.  There is a single error at the end of the debug that claims it
can't find a route to the host, but I don't know which host it is
talking about.  Is the client not accepting the assignment and therefor
something isn't completing?

2018-01-22 12:11:34.741 DEBUG [kea-dhcp6.packets/55758] DHCP6_BUFFER_RECEIVED received buffer from 2607:fda0:101:1601::1:547 to 2607:fda0:101:1600::2:0 over interface igb2.1599
2018-01-22 12:11:34.741 DEBUG [kea-dhcp6.options/55758] DHCP6_BUFFER_UNPACK parsing buffer received from 2607:fda0:101:1601::1 to 2607:fda0:101:1600::2 over interface igb2.1599
2018-01-22 12:11:34.742 DEBUG [kea-dhcp6.packets/55758] DHCP6_PACKET_RECEIVED duid=[00:03:00:01:1c:74:0d:4b:cd:76], tid=0xa09dfc: SOLICIT (type 1) received from 2607:fda0:101:1601::1 to 2607:fda0:101:1600::2 on interface igb2.1599
2018-01-22 12:11:34.742 DEBUG [kea-dhcp6.packets/55758] DHCP6_QUERY_DATA duid=[00:03:00:01:1c:74:0d:4b:cd:76], tid=0xa09dfc, packet details: localAddr=[2607:fda0:101:1600::2]:0 remoteAddr=[2607:fda0:101:1601::1]:547
msgtype=1(SOLICIT), transid=0xa09dfc
type=00001, len=00010: 00:03:00:01:1c:74:0d:4b:cd:76
type=00003(IA_NA), len=00012: iaid=1, t1=0, t2=0
type=00008, len=00002: 65535 (uint16)
type=00025(IA_PD), len=00012: iaid=0, t1=0, t2=0
2 relay(s):
relay[0]: msg-type=12(RELAY_FORWARD), hop-count=1,
link-address=2607:fda0:101:1601::1, peer-address=fe80::1e74:dff:fe4b:cd76, 1 option(s)
type=00053, len=00031: 00:02:00:00:05:83:01:64:30:3a:30:37:3a:63:61:3a:33:33:3a:30:34:3a:30:30:00:00:00:00:00:00:00
relay[1]: msg-type=12(RELAY_FORWARD), hop-count=0,
link-address=::, peer-address=fe80::1e74:dff:fe4b:cd76, 1 option(s)
type=00018, len=00007: 33:2f:32:2f:31:2f:31

2018-01-22 12:11:34.742 DEBUG [kea-dhcp6.dhcpsrv/55758] DHCPSRV_CFGMGR_SUBNET6_IFACE_ID selected subnet 2607:fda0:101:1601::/64 (interface-id match) for incoming packet
2018-01-22 12:11:34.742 DEBUG [kea-dhcp6.hosts/55758] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: duid=000300011C740D4BCD76
2018-01-22 12:11:34.742 DEBUG [kea-dhcp6.hosts/55758] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier duid=000300011C740D4BCD76, found 0 host(s)
2018-01-22 12:11:34.742 DEBUG [kea-dhcp6.hosts/55758] HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 1 and identifier duid=000300011C740D4BCD76
2018-01-22 12:11:34.742 DEBUG [kea-dhcp6.leases/55758] DHCP6_PROCESS_IA_NA_REQUEST duid=[00:03:00:01:1c:74:0d:4b:cd:76], tid=0xa09dfc: server is processing IA_NA option with iaid=1 and hint=(no hint)
2018-01-22 12:11:34.742 DEBUG [kea-dhcp6.dhcpsrv/55758] DHCPSRV_MEMFILE_GET_IAID_SUBID_DUID obtaining IPv6 leases for IAID 1, Subnet ID 1, DUID 00:03:00:01:1c:74:0d:4b:cd:76 and lease type IA_NA
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.alloc-engine/55758] ALLOC_ENGINE_V6_ALLOC_UNRESERVED no static reservations available - trying to dynamically allocate leases for client duid=[00:03:00:01:1c:74:0d:4b:cd:76], tid=0xa09dfc
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.hosts/55758] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS6 get one host with reservation for subnet id 1 and including IPv6 address 2607:fda0:101:1601:4::
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.hosts/55758] HOSTS_CFG_GET_ALL_SUBNET_ID_ADDRESS6 get all hosts with reservations for subnet id 1 and IPv6 address 2607:fda0:101:1601:4::
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.hosts/55758] HOSTS_CFG_GET_ALL_SUBNET_ID_ADDRESS6_COUNT using subnet id 1 and address 2607:fda0:101:1601:4::, found 0 host(s)
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.hosts/55758] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS6_NULL host not found using subnet id 1 and address 2607:fda0:101:1601:4::
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.dhcpsrv/55758] DHCPSRV_MEMFILE_GET_ADDR6 obtaining IPv6 lease for address 2607:fda0:101:1601:4:: and lease type IA_NA
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.dhcpsrv/55758] DHCPSRV_MEMFILE_GET_ADDR6 obtaining IPv6 lease for address 2607:fda0:101:1601:4:: and lease type IA_NA
2018-01-22 12:11:34.743 INFO  [kea-dhcp6.leases/55758] DHCP6_LEASE_ADVERT duid=[00:03:00:01:1c:74:0d:4b:cd:76], tid=0xa09dfc: lease for address 2607:fda0:101:1601:4:: and iaid=1 will be advertised
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.leases/55758] DHCP6_LEASE_DATA duid=[00:03:00:01:1c:74:0d:4b:cd:76], tid=0xa09dfc: detailed lease information for iaid=1: Type:          IA_NA(0)
Address:       2607:fda0:101:1601:4::
Prefix length: 128
IAID:          1
Pref life:     3000
Valid life:    4000
Cltt:          1516651894
DUID:          00:03:00:01:1c:74:0d:4b:cd:76
Hardware addr: 1c:74:0d:4b:cd:76
Subnet ID:     1
State:         default

2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.leases/55758] DHCP6_PROCESS_IA_PD_REQUEST duid=[00:03:00:01:1c:74:0d:4b:cd:76], tid=0xa09dfc: server is processing IA_PD option with iaid=0 and hint=(no hint)
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.dhcpsrv/55758] DHCPSRV_MEMFILE_GET_IAID_SUBID_DUID obtaining IPv6 leases for IAID 0, Subnet ID 1, DUID 00:03:00:01:1c:74:0d:4b:cd:76 and lease type IA_PD
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.alloc-engine/55758] ALLOC_ENGINE_V6_ALLOC_UNRESERVED no static reservations available - trying to dynamically allocate leases for client duid=[00:03:00:01:1c:74:0d:4b:cd:76], tid=0xa09dfc
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.hosts/55758] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS6 get one host with reservation for subnet id 1 and including IPv6 address 2607:fda0:110:100::
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.hosts/55758] HOSTS_CFG_GET_ALL_SUBNET_ID_ADDRESS6 get all hosts with reservations for subnet id 1 and IPv6 address 2607:fda0:110:100::
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.hosts/55758] HOSTS_CFG_GET_ALL_SUBNET_ID_ADDRESS6_COUNT using subnet id 1 and address 2607:fda0:110:100::, found 0 host(s)
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.hosts/55758] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS6_NULL host not found using subnet id 1 and address 2607:fda0:110:100::
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.dhcpsrv/55758] DHCPSRV_MEMFILE_GET_ADDR6 obtaining IPv6 lease for address 2607:fda0:110:100:: and lease type IA_PD
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.dhcpsrv/55758] DHCPSRV_MEMFILE_GET_ADDR6 obtaining IPv6 lease for address 2607:fda0:110:100:: and lease type IA_NA
2018-01-22 12:11:34.743 INFO  [kea-dhcp6.leases/55758] DHCP6_PD_LEASE_ADVERT duid=[00:03:00:01:1c:74:0d:4b:cd:76], tid=0xa09dfc: lease for prefix 2607:fda0:110:100::/56 and iaid=0 will be advertised
2018-01-22 12:11:34.743 DEBUG [kea-dhcp6.packets/55758] DHCP6_RESPONSE_DATA responding with packet type 2 data is localAddr=[2607:fda0:101:1600::2]:547 remoteAddr=[2607:fda0:101:1601::1]:547
msgtype=2(ADVERTISE), transid=0xa09dfc
type=00001, len=00010: 00:03:00:01:1c:74:0d:4b:cd:76
type=00002, len=00014: 00:01:00:87:21:d9:8c:b2:ac:1f:6b:22:56:3a
type=00003(IA_NA), len=00040: iaid=1, t1=1000, t2=2000,
options:
  type=00005(IAADDR), len=00024: address=2607:fda0:101:1601:4::, preferred-lft=3000, valid-lft=4000
type=00025(IA_PD), len=00041: iaid=0, t1=1000, t2=2000,
options:
  type=00026(IAPREFIX), len=00025: prefix=2607:fda0:110:100::/56, preferred-lft=3000, valid-lft=4000
2 relay(s):
relay[0]: msg-type=13(RELAY_REPLY), hop-count=1,
link-address=2607:fda0:101:1601::1, peer-address=fe80::1e74:dff:fe4b:cd76, 0 option(s)
relay[1]: msg-type=13(RELAY_REPLY), hop-count=0,
link-address=::, peer-address=fe80::1e74:dff:fe4b:cd76, 1 option(s)
type=00018, len=00007: 33:2f:32:2f:31:2f:31

2018-01-22 12:11:34.743 ERROR [kea-dhcp6.packets/55758] DHCP6_PACKET_SEND_FAIL failed to send DHCPv6 packet: pkt6 send failed: sendmsg() returned with an error: No route to host

--TimH