[Dibbler] advertise not sending out on an interface

Laszlo Fekete blackluck at ktk.bme.hu
Wed May 22 20:56:07 CEST 2013


Hello!

I have a weird problem on my system (debian wheezy, dibbler-server version 
0.8.2-1) which I want to manage both directly attached and relayed vlans.

There are relayed vlans: 401,501-509 to eth0 interface ff05::1:3 which seems to 
be working, there are solicit, advertise, request, reply, renew messages.
There are directly connected vlans: 301, 444, 601-604 where 301 and 604 also 
seem to be okay (601-603 don't have traffic yet, so this isn't important), but 
in vlan444 got solicit messages from clients, dibbler server tell me that send 
advertise:


2013.05.22 20:22:49 Server Notice    Accepting connections. Next event in 52 
second(s).
2013.05.22 20:22:49 Server Debug     Received 44 bytes on interface vlan444/13 
(socket=6, addr=fe80::216:36ff:fe0f:f4ee.).
2013.05.22 20:22:49 Server Notice    Received SOLICIT on 
vlan444/13,TransID=0xf49af3, 3 opts: 1 8 3, 0 relay(s).
2013.05.22 20:22:49 Server Debug     2 answers buffered. Old reply for 
transID=f49af3 not found. Generating new answer.
2013.05.22 20:22:49 Server Notice    IA option (with IAADDR suboptions 
missing) received. Assigning one address.
2013.05.22 20:22:49 Server Debug     Client requested unspecified (::) address. 
Hint ignored.
2013.05.22 20:22:49 Server Debug     Cache: Cached address for client 
(DUID=00:01:00:01:19:2f:6a:a4:00:16:36:0f:f4:ee) found: 
2001:738:2001:20c4:4d69:d5be:c5b2:af1b
2013.05.22 20:22:49 Server Info      Cache: Cached address 
2001:738:2001:20c4:4d69:d5be:c5b2:af1b found. Welcome back.
2013.05.22 20:22:49 Server Info      Client requested ::, got 
2001:738:2001:20c4:4d69:d5be:c5b2:af1b (IAID=907015406, pref=1800,valid=3600).
2013.05.22 20:22:49 Server Debug     Adding client 
(DUID=00:01:00:01:19:2f:6a:a4:00:16:36:0f:f4:ee) to addrDB.
2013.05.22 20:22:49 Server Debug     Adding IA (IAID=907015406) to addrDB.
2013.05.22 20:22:49 Server Debug     Adding 
2001:738:2001:20c4:4d69:d5be:c5b2:af1b to IA (IAID=907015406) to addrDB.
2013.05.22 20:22:49 Server Debug     Address usage for class 11 increased to 
1.
2013.05.22 20:22:49 Server Debug     Preference set to 64.
2013.05.22 20:22:49 Server Debug     Cache: Entry for client 
(DUID=00:01:00:01:19:2f:6a:a4:00:16:36:0f:f4:ee) was deleted.
2013.05.22 20:22:49 Server Debug     Cache: Address 
2001:738:2001:20c4:4d69:d5be:c5b2:af1b added for client 
(DUID=00:01:00:01:19:2f:6a:a4:00:16:36:0f:f4:ee). 
2013.05.22 20:22:49 Server Debug     Deleted address 
2001:738:2001:20c4:4d69:d5be:c5b2:af1b from addrDB.
2013.05.22 20:22:49 Server Debug     Deleted empty IA (IAID=907015406) from 
addrDB.
2013.05.22 20:22:49 Server Debug     Deleted empty client 
(DUID=00:01:00:01:19:2f:6a:a4:00:16:36:0f:f4:ee) from addrDB.
2013.05.22 20:22:49 Server Debug     Address usage for class 11 decreased to 
0.
2013.05.22 20:22:49 Server Notice    Sending ADVERTISE on 
vlan444/13,transID=0xf49af3, opts: 3 2 1 7, 0 relay(s).
2013.05.22 20:22:49 Server Debug     Not executing external script (Notify 
script disabled).
2013.05.22 20:22:49 Server Debug     1 message(s) were removed from cache.
2013.05.22 20:22:49 Server Notice    Accepting connections. Next event in 52 
second(s).


but can't see this advertise with tcpdump just the solicit messages on that 
interface (or any other interface on the server) for that vlan:

20:22:49.503289 IP6 fe80::216:36ff:fe0f:f4ee.546 > ff02::1:2.547: dhcp6 solicit
20:23:50.106217 IP6 fe80::213:d4ff:feac:3a9f.546 > ff02::1:2.547: dhcp6 solicit


The configurations are the same for these vlans (except the prefixes) in dibbler 
server, radvd, sysctl parameters, interfaces, no blocking with ip6tables.

Maybe do you have any idea whats wrong with that vlan? What should I check?


Here is the server start debug (except the lot load cached entries):

2013.05.22 20:22:32 Server Notice    | Dibbler - a portable DHCPv6, version 
0.8.2 (SERVER, Linux port)
2013.05.22 20:22:32 Server Notice    My pid (28389) is stored in 
/var/lib/dibbler/server.pid
2013.05.22 20:22:32 Server Notice    Detected iface vlan604/17, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface vlan603/16, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface vlan602/15, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface vlan601/14, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface vlan444/13, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface vlan301/12, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface vlan205/11, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface vlan202/10, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface vlan201/9, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface bond0/8, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface eth5/7, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface eth4/6, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface eth3/5, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface eth2/4, 
MAC=00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Notice    Detected iface eth1/3, 
MAC=00:15:17:4e:7a:71.
2013.05.22 20:22:32 Server Notice    Detected iface eth0/2, 
MAC=00:15:17:4e:7a:70.
2013.05.22 20:22:32 Server Notice    Detected iface lo/1, 
MAC=00:00:00:00:00:00.
2013.05.22 20:22:32 Server Notice    Parsing /etc/dibbler/server.conf config 
file...
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     0 per-client configurations (exceptions) 
added.
2013.05.22 20:22:32 Server Debug     Parsing /etc/dibbler/server.conf done.
2013.05.22 20:22:32 Server Info      0 client class(es) defined.
2013.05.22 20:22:32 Server Debug     16 interface(s) specified in 
/etc/dibbler/server.conf

... mapping allow/deny lists, everyone the same...

2013.05.22 20:22:32 Server Notice    Running in stateful mode.
2013.05.22 20:22:32 Server Debug     Bulk-leasequery: enabled=no, TCP 
port=547, max conns=10, timeout=300
2013.05.22 20:22:32 Server Debug     DUID's value = 
00:01:00:01:19:2f:b4:ed:00:15:17:bf:08:c1 was loaded from server-duid file.
2013.05.22 20:22:32 Server Info      My DUID is 
00:01:00:01:19:2f:b4:ed:00:15:17:bf:08:c1.
2013.05.22 20:22:32 Server Info      Loading old address database (server-
AddrMgr.xml), using built-in routines.
2013.05.22 20:22:32 Server Info      DB timestamp:1369246949, 
now()=1369246952, db is 3 second(s) old.

... Loaded IA from a file messages ....

2013.05.22 20:22:32 Server Debug     Cache:server-cache.xml file: parsing 
started, expecting 300 entries.
2013.05.22 20:22:32 Server Debug     Cache: Address 
2001:738:2001:20d7:ab77:1f3e:6beb:df68 added for client 
(DUID=00:01:00:01:15:ff:e2:6f:00:1d:72:39:19:9c). 

... and so on a lot of cache address ...

2013.05.22 20:22:32 Server Info      Did not find any useful information in 
server-cache.xml
2013.05.22 20:22:32 Server Notice    Relay init: Creating socket on the 
underlaying interface: eth0/2.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff05::1:3) socket on 
relayvlan501/1024 (eth0/2) interface.
2013.05.22 20:22:32 Server Notice    Creating link-local 
(fe80::215:17ff:fe4e:7a70) socket on eth0/2 interface.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff05::1:3) socket on 
relayvlan502/1025 (eth0/2) interface.
2013.05.22 20:22:32 Server Notice    Address ff05::1:3 is already bound on the 
eth0.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff05::1:3) socket on 
relayvlan503/1026 (eth0/2) interface.
2013.05.22 20:22:32 Server Notice    Address ff05::1:3 is already bound on the 
eth0.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff05::1:3) socket on 
relayvlan504/1027 (eth0/2) interface.
2013.05.22 20:22:32 Server Notice    Address ff05::1:3 is already bound on the 
eth0.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff05::1:3) socket on 
relayvlan505/1028 (eth0/2) interface.
2013.05.22 20:22:32 Server Notice    Address ff05::1:3 is already bound on the 
eth0.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff05::1:3) socket on 
relayvlan506/1029 (eth0/2) interface.
2013.05.22 20:22:32 Server Notice    Address ff05::1:3 is already bound on the 
eth0.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff05::1:3) socket on 
relayvlan507/1030 (eth0/2) interface.
2013.05.22 20:22:32 Server Notice    Address ff05::1:3 is already bound on the 
eth0.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff05::1:3) socket on 
relayvlan508/1031 (eth0/2) interface.
2013.05.22 20:22:32 Server Notice    Address ff05::1:3 is already bound on the 
eth0.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff05::1:3) socket on 
relayvlan509/1032 (eth0/2) interface.
2013.05.22 20:22:32 Server Notice    Address ff05::1:3 is already bound on the 
eth0.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff05::1:3) socket on 
relayvlan401/1033 (eth0/2) interface.
2013.05.22 20:22:32 Server Notice    Address ff05::1:3 is already bound on the 
eth0.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff02::1:2) socket on 
vlan301/12 (vlan301/12) interface.
2013.05.22 20:22:32 Server Notice    Creating link-local 
(fe80::215:17ff:febf:8c1) socket on vlan301/12 interface.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff02::1:2) socket on 
vlan444/13 (vlan444/13) interface.
2013.05.22 20:22:32 Server Notice    Creating link-local 
(fe80::215:17ff:febf:8c1) socket on vlan444/13 interface.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff02::1:2) socket on 
vlan601/14 (vlan601/14) interface.
2013.05.22 20:22:32 Server Notice    Creating link-local 
(fe80::215:17ff:febf:8c1) socket on vlan601/14 interface.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff02::1:2) socket on 
vlan602/15 (vlan602/15) interface.
2013.05.22 20:22:32 Server Notice    Creating link-local 
(fe80::215:17ff:febf:8c1) socket on vlan602/15 interface.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff02::1:2) socket on 
vlan603/16 (vlan603/16) interface.
2013.05.22 20:22:32 Server Notice    Creating link-local 
(fe80::215:17ff:febf:8c1) socket on vlan603/16 interface.
2013.05.22 20:22:32 Server Notice    Creating multicast (ff02::1:2) socket on 
vlan604/17 (vlan604/17) interface.
2013.05.22 20:22:32 Server Notice    Creating link-local 
(fe80::215:17ff:febf:8c1) socket on vlan604/17 interface.
2013.05.22 20:22:32 Server Debug     Cache: size set to 1048576 bytes, 1 cache 
entry size is 115 bytes, so maximum 9118 address-client pair(s) may be cached.
2013.05.22 20:22:32 Server Debug     Increased pools usage: currently 230 
address(es) and 0 prefix(es) are leased.
2013.05.22 20:22:32 Server Notice    Accepting connections. Next event in 74 
second(s).

... and the game begins...


Thanks,
blackluck



More information about the Dibbler mailing list