56 Posts in 20 Topics by 17 members

IPv6 Capable xDSL Devices

Forum » IPv6 Capable xDSL Devices » OpenWRT DHCP troubles

Page: 1 Go to End
Author Topic: OpenWRT DHCP troubles 2552 Views

OpenWRT DHCP troubles Link to this post

I've compiled WIDE-DHCPv6 for OpenWRT (kamikaze on a DSL-502T), after giving up on ISC DHCP 4.1.0p (which had trouble generating a DUID on ppp0). I only get replies in information-only mode, which means that I can't receive the prefix delegation.

Apologies for the spam:

root@OpenWrt:~# opkg list_installed | grep dhcp
dhcp4-client - 4.1.0p1-2 -
wide-dhcpv6c - 20080615-1 -
root@OpenWrt:~# grep fe80 /tmp/ppplog
sent [IPV6CP ConfReq id=0x1 <addr fe80::d539:d04f:e38e:1710>]
rcvd [IPV6CP ConfAck id=0x1 <addr fe80::d539:d04f:e38e:1710>]
rcvd [IPV6CP ConfReq id=0xa3 <addr fe80::0090:1a00:c142:bf14>]
sent [IPV6CP ConfAck id=0xa3 <addr fe80::0090:1a00:c142:bf14>]
local LL address fe80::d539:d04f:e38e:1710
remote LL address fe80::0090:1a00:c142:bf14
root@OpenWrt:~# ping6 fe80::0090:1a00:c142:bf14 -I ppp0
PING fe80::0090:1a00:c142:bf14 (fe80::90:1a00:c142:bf14): 56 data bytes
64 bytes from fe80::90:1a00:c142:bf14: seq=0 ttl=255 time=42.274 ms
64 bytes from fe80::90:1a00:c142:bf14: seq=1 ttl=255 time=44.147 ms
64 bytes from fe80::90:1a00:c142:bf14: seq=2 ttl=255 time=42.236 ms
^C
--- fe80::0090:1a00:c142:bf14 ping statistics ---
3 packets transmitted, 3 packets received, 0% packet loss
round-trip min/avg/max = 42.236/42.885/44.147 ms
root@OpenWrt:~# cat /etc/dhcp6c.conf
interface ppp0 {
send ia-pd 0;
request domain-name-servers, domain-name;
send domain-name-servers, domain-name;
};

id-assoc pd 0 {
prefix-interface br-lan {
   sla-id 1;
};
};

root@OpenWrt:~# dhcp6c -c /etc/dhcp6c.conf -d -D -f ppp0
Nov/05/2009 22:46:07: get_duid: extracted an existing DUID from /var/db/dhcp6c_duid: 00:01:00:01:c7:93:2d:32:00:0d:08:00:03:02
Nov/05/2009 22:46:07: cfdebug_print: <3>[interface] (9)
Nov/05/2009 22:46:07: cfdebug_print: <5>[ppp0] (4)
Nov/05/2009 22:46:07: cfdebug_print: <3>begin of closure [{] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>[send] (4)
Nov/05/2009 22:46:07: cfdebug_print: <3>[ia-pd] (5)
Nov/05/2009 22:46:07: cfdebug_print: <3>[0] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>[request] (7)
Nov/05/2009 22:46:07: cfdebug_print: <3>[domain-name-servers] (19)
Nov/05/2009 22:46:07: cfdebug_print: <3>[,] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>[domain-name] (11)
Nov/05/2009 22:46:07: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>[send] (4)
Nov/05/2009 22:46:07: cfdebug_print: <3>[domain-name-servers] (19)
Nov/05/2009 22:46:07: cfdebug_print: <3>[,] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>[domain-name] (11)
Nov/05/2009 22:46:07: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>end of closure [}] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>[id-assoc] (8)
Nov/05/2009 22:46:07: cfdebug_print: <13>[pd] (2)
Nov/05/2009 22:46:07: cfdebug_print: <13>[0] (1)
Nov/05/2009 22:46:07: cfdebug_print: <13>begin of closure [{] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>[prefix-interface] (16)
Nov/05/2009 22:46:07: cfdebug_print: <5>[br-lan] (6)
Nov/05/2009 22:46:07: cfdebug_print: <3>begin of closure [{] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>[sla-id] (6)
Nov/05/2009 22:46:07: cfdebug_print: <3>[1] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>end of closure [}] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>end of closure [}] (1)
Nov/05/2009 22:46:07: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:07: configure_pool: called
Nov/05/2009 22:46:07: add_options: invalid operation (0) for option type (16)
Nov/05/2009 22:46:07: add_options: invalid operation (0) for option type (17)
Nov/05/2009 22:46:07: clear_poolconf: called
Nov/05/2009 22:46:07: dhcp6_reset_timer: reset a timer on ppp0, state=INIT, timeo=0, retrans=395
Nov/05/2009 22:46:07: client6_send: a new XID (305d3d) is generated
Nov/05/2009 22:46:07: copy_option: set client ID (len 14)
Nov/05/2009 22:46:07: copy_option: set elapsed time (len 2)
Nov/05/2009 22:46:07: copy_option: set option request (len 4)
Nov/05/2009 22:46:07: copyout_option: set IA_PD
Nov/05/2009 22:46:07: client6_send: send solicit to fe80::90:1a00:c142:bf14
Nov/05/2009 22:46:07: dhcp6_reset_timer: reset a timer on ppp0, state=SOLICIT, timeo=0, retrans=1078
Nov/05/2009 22:46:08: copy_option: set client ID (len 14)
Nov/05/2009 22:46:08: copy_option: set elapsed time (len 2)
Nov/05/2009 22:46:08: copy_option: set option request (len 4)
Nov/05/2009 22:46:08: copyout_option: set IA_PD
Nov/05/2009 22:46:08: client6_send: send solicit to fe80::90:1a00:c142:bf14
Nov/05/2009 22:46:08: dhcp6_reset_timer: reset a timer on ppp0, state=SOLICIT, timeo=1, retrans=2105
^C
root@OpenWrt:~# cat /etc/dhcp6c.conf.working
interface ppp0 {
send ia-pd 0;
request domain-name-servers, domain-name;
send domain-name-servers, domain-name;
   information-only;
};

id-assoc pd 0 {
prefix-interface br-lan {
   sla-id 1;
};
};

root@OpenWrt:~# dhcp6c -c /etc/dhcp6c.conf.working -d -D -f ppp0
Nov/05/2009 22:46:30: get_duid: extracted an existing DUID from /var/db/dhcp6c_duid: 00:01:00:01:c7:93:2d:32:00:0d:08:00:03:02
Nov/05/2009 22:46:30: cfdebug_print: <3>[interface] (9)
Nov/05/2009 22:46:30: cfdebug_print: <5>[ppp0] (4)
Nov/05/2009 22:46:30: cfdebug_print: <3>begin of closure [{] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>[send] (4)
Nov/05/2009 22:46:30: cfdebug_print: <3>[ia-pd] (5)
Nov/05/2009 22:46:30: cfdebug_print: <3>[0] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>[request] (7)
Nov/05/2009 22:46:30: cfdebug_print: <3>[domain-name-servers] (19)
Nov/05/2009 22:46:30: cfdebug_print: <3>[,] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>[domain-name] (11)
Nov/05/2009 22:46:30: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>[send] (4)
Nov/05/2009 22:46:30: cfdebug_print: <3>[domain-name-servers] (19)
Nov/05/2009 22:46:30: cfdebug_print: <3>[,] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>[domain-name] (11)
Nov/05/2009 22:46:30: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>[information-only] (16)
Nov/05/2009 22:46:30: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>end of closure [}] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>[id-assoc] (8)
Nov/05/2009 22:46:30: cfdebug_print: <13>[pd] (2)
Nov/05/2009 22:46:30: cfdebug_print: <13>[0] (1)
Nov/05/2009 22:46:30: cfdebug_print: <13>begin of closure [{] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>[prefix-interface] (16)
Nov/05/2009 22:46:30: cfdebug_print: <5>[br-lan] (6)
Nov/05/2009 22:46:30: cfdebug_print: <3>begin of closure [{] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>[sla-id] (6)
Nov/05/2009 22:46:30: cfdebug_print: <3>[1] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>end of closure [}] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>end of closure [}] (1)
Nov/05/2009 22:46:30: cfdebug_print: <3>end of sentence [;] (1)
Nov/05/2009 22:46:30: configure_pool: called
Nov/05/2009 22:46:30: add_options: invalid operation (0) for option type (16)
Nov/05/2009 22:46:30: add_options: invalid operation (0) for option type (17)
Nov/05/2009 22:46:30: clear_poolconf: called
Nov/05/2009 22:46:30: dhcp6_reset_timer: reset a timer on ppp0, state=INIT, timeo=0, retrans=150
Nov/05/2009 22:46:30: client6_send: a new XID (58f59e) is generated
Nov/05/2009 22:46:30: copy_option: set client ID (len 14)
Nov/05/2009 22:46:30: copy_option: set elapsed time (len 2)
Nov/05/2009 22:46:30: copy_option: set option request (len 4)
Nov/05/2009 22:46:30: client6_send: send information request to fe80::90:1a00:c142:bf14
Nov/05/2009 22:46:30: dhcp6_reset_timer: reset a timer on ppp0, state=INFOREQ, timeo=0, retrans=924
Nov/05/2009 22:46:30: client6_recv: receive reply from fe80::90:1a00:c142:bf14 on ppp0
Nov/05/2009 22:46:30: dhcp6_get_options: get DHCP option server ID, len 23
Nov/05/2009 22:46:30: DUID: 00:02:00:00:0a:4c:45:33:32:30:2f:37:34:35:41:43:33:33:45:58:32:2f:c1
Nov/05/2009 22:46:30: dhcp6_get_options: get DHCP option client ID, len 14
Nov/05/2009 22:46:30: DUID: 00:01:00:01:c7:93:2d:32:00:0d:08:00:03:02
Nov/05/2009 22:46:30: dhcp6_get_options: get DHCP option DNS, len 64
Nov/05/2009 22:46:30: dhcp6_get_addr: duplicated DNS address (2001:e20:1000:4::2)
Nov/05/2009 22:46:30: dhcp6_get_addr: duplicated DNS address (2001:e20:1000:6::2)
Nov/05/2009 22:46:30: dhcp6_get_options: get DHCP option domain search list, len 10
Nov/05/2009 22:46:30: dhcp6_get_options: get DHCP option status code, len 2
Nov/05/2009 22:46:30: status code: success
Nov/05/2009 22:46:30: client6_recvreply: status code: success
Nov/05/2009 22:46:30: info_printf: nameserver[0] 2001:e20:1000:4::2
Nov/05/2009 22:46:30: info_printf: nameserver[1] 2001:e20:1000:6::2
Nov/05/2009 22:46:30: info_printf: Domain search list[0] wxnz.net.
Nov/05/2009 22:46:30: dhcp6_remove_event: removing an event on ppp0, state=INFOREQ
Nov/05/2009 22:46:30: client6_recvreply: got an expected reply, sleeping.
^C
root@OpenWrt:~#

Re: OpenWRT DHCP troubles Link to this post

Hi There,

I would be interested if you gave this another try - it looks like I may have caused it... It seems there was a problem with your prefix in our radius server (too many zeros, not enough magic!).

Let me know how that goes now.

Cheers,

Callum
WxC

    2552 Views
Go to Top