[Dibbler] DDNS problem with dibbler-server and ISC-client

Klose, Dieter dieter.klose at ts.fujitsu.com
Thu Jul 26 16:53:54 CEST 2012


Hi,
I am using dibbler-server version 0.8.2 on a SUSE SLES11 SP1 system and dhcp-client is ISC DHCP Client Version: 4.2.3.P2-0.7.2 on a SUSE SLES11 SP2 system.

My problem is, that dibbler-server don't tries to contact my dns-server for DDNS. In the listing of dibbler-server below you can see that client sends fqdn-option and dibbler-server detect this:

2012.07.26 14:46:38 Server Info      FQDN: Client requested (crema42test.ipv6.osd.abg.fsc.net), assigning (crema42test.ipv6.osd.abg.fsc.net).
2012.07.26 14:46:38 Server Debug     FQDN: Adding FQDN Option in REPLY message: crema42test.ipv6.osd.abg.fsc.net, FQDNMode=2
2012.07.26 14:46:38 Server Debug     FQDN: Server configuration allow DNS updates for 00:01:00:01:17:85:b5:42:00:16:3e:58:dd:4f

In tcpdump I can see, that S-bit is set in fqdn-option, so server should perform forward DNS updates.

Here is my server.conf:

log-level 8
log-mode full

# Set protocol to one of the following values: udp, tcp, any
ddns-protocol any
# Sets DDNS Update timeout (in ms)
ddns-timeout 1000

# specify address of DNS server to be used for DDNS
fqdn-ddns-address fd12::1

iface eth1 {
 T1 120
 T2 2000
 prefered-lifetime 3600
 valid-lifetime 7200
 class {
   pool fd12::/64
 }
option dns-server fd12::1
option domain ipv6.osd.abg.fsc.net

# provide fully qualified domain names for clients
# note that first, second and third entry is reserved
# for a specific address or a DUID
option fqdn 2 64
                picasso.ipv6.osd.abg.fsc.net - fd12::1,
                crema40ipv6.ipv6.osd.abg.fsc.net - fd12::2,
                test3.ipv6.osd.abg.fsc.net - 0x0001000043ce25b40013d4024bf5,
                test4.ipv6.osd.abg.fsc.net,
                test5.ipv6.osd.abg.fsc.net,
                test6.ipv6.osd.abg.fsc.net,
                test7.ipv6.osd.abg.fsc.net,
                test8.ipv6.osd.abg.fsc.net

# specify what to do with client's names that are not on the list
# 0 - reject
# 1 - send other name from allowed list
# 2 - accept any name client sends
# 3 - accept any name client sends, but append specified domain suffix
# 4 - ignore client's hint, generate name based on his address, append domain name
accept-unknown-fqdn 3 ipv6.osd.abg.fsc.net
}


Here the complete listing protocol of dibbler-server:

crema40ipv6:~ # dibbler-server run
| Dibbler - a portable DHCPv6, version 0.8.2 (SERVER, Linux port)
| Authors : Tomasz Mrugalski<thomson(at)klub.com.pl>,Marek Senderski<msend(at)o2.pl>
| Licence : GNU GPL v2 only. Developed at Gdansk University of Technology.
| Homepage: http://klub.com.pl/dhcpv6/
2012.07.26 14:46:21 Server Notice    My pid (10601) is stored in /var/lib/dibbler/server.pid
2012.07.26 14:46:21 Server Notice    Detected iface eth1/3, MAC=00:16:3e:16:24:d7.
2012.07.26 14:46:21 Server Notice    Detected iface eth0/2, MAC=00:16:3e:58:ff:f0.
2012.07.26 14:46:21 Server Notice    Detected iface lo/1, MAC=00:00:00:00:00:00.
2012.07.26 14:46:21 Server Notice    Parsing /etc/dibbler/server.conf config file...
2012.07.26 14:46:21 Server Debug     DDNS: Setting protocol to any
2012.07.26 14:46:21 Server Debug     DDNS: Setting timeout to 1000ms.
2012.07.26 14:46:21 Server Info      FQDN: DDNS updates will be performed to fd12::1.
2012.07.26 14:46:21 Server Debug     FQDNMode found, setting value 2
2012.07.26 14:46:21 Server Debug     revDNS zoneroot lenght found, setting value 64
2012.07.26 14:46:21 Server Debug     FQDN:picasso.ipv6.osd.abg.fsc.net reserved for address fd12::1
2012.07.26 14:46:21 Server Debug     FQDN:crema40ipv6.ipv6.osd.abg.fsc.net reserved for address fd12::2
2012.07.26 14:46:21 Server Debug     FQDN:test3.ipv6.osd.abg.fsc.net reserved for DUID 00:01:00:00:43:ce:25:b4:00:13:d4:02:4b:f5
2012.07.26 14:46:21 Server Debug     FQDN:test4.ipv6.osd.abg.fsc.net has no reservations (is available to everyone).
2012.07.26 14:46:21 Server Debug     FQDN:test5.ipv6.osd.abg.fsc.net has no reservations (is available to everyone).
2012.07.26 14:46:21 Server Debug     FQDN:test6.ipv6.osd.abg.fsc.net has no reservations (is available to everyone).
2012.07.26 14:46:21 Server Debug     FQDN:test7.ipv6.osd.abg.fsc.net has no reservations (is available to everyone).
2012.07.26 14:46:21 Server Debug     FQDN:test8.ipv6.osd.abg.fsc.net has no reservations (is available to everyone).
2012.07.26 14:46:21 Server Debug     FQDN: Unknown fqdn names processing set to 3, domain=ipv6.osd.abg.fsc.net.
2012.07.26 14:46:21 Server Debug     FQDN: Support is enabled on the eth1 interface.
2012.07.26 14:46:21 Server Debug     FQDN: Mode set to 2: server will perform both (AAAA and PTR) updates.
2012.07.26 14:46:21 Server Debug     FQDN: revDNS zoneroot lenght set to 64.
2012.07.26 14:46:21 Server Debug     0 per-client configurations (exceptions) added.
2012.07.26 14:46:21 Server Debug     Parsing /etc/dibbler/server.conf done.
2012.07.26 14:46:21 Server Info      0 client class(es) defined.
2012.07.26 14:46:21 Server Debug     1 interface(s) specified in /etc/dibbler/server.conf
2012.07.26 14:46:21 Server Info      Mapping allow, deny list to class 0:0 allow/deny entries in total.
2012.07.26 14:46:21 Server Info      Interface eth1/3 configuration has been loaded.
2012.07.26 14:46:21 Server Notice    Running in stateful mode.
2012.07.26 14:46:21 Server Debug     Bulk-leasequery: enabled=no, TCP port=547, max conns=10, timeout=300
2012.07.26 14:46:21 Server Debug     DUID's value = 00:01:00:01:17:43:ba:65:00:16:3e:16:24:d7 was loaded from server-duid file.
2012.07.26 14:46:21 Server Info      My DUID is 00:01:00:01:17:43:ba:65:00:16:3e:16:24:d7.
2012.07.26 14:46:21 Server Info      Loading old address database (server-AddrMgr.xml), using built-in routines.
2012.07.26 14:46:21 Server Warning   Unable to open server-AddrMgr.xml.
2012.07.26 14:46:21 Server Warning   Cache: Unable to open cache file server-cache.xml.
2012.07.26 14:46:21 Server Notice    Creating multicast (ff02::1:2) socket on eth1/3 (eth1/3) interface.
2012.07.26 14:46:21 Server Notice    Creating link-local (fe80::216:3eff:fe16:24d7) socket on eth1/3 interface.
2012.07.26 14:46:21 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.
2012.07.26 14:46:21 Server Debug     Increased pools usage: currently 0 address(es) and 0 prefix(es) are leased.
2012.07.26 14:46:21 Server Notice    Accepting connections. Next event in 4294967295 second(s).
2012.07.26 14:46:28 Server Debug     Received 98 bytes on interface eth1/3 (socket=4, addr=fe80::226:9eff:fecd:b72c.).
2012.07.26 14:46:28 Server Notice    Received RENEW on eth1/3,TransID=0x60d399, 5 opts: 1 2 8 3 6, 0 relay(s).
2012.07.26 14:46:28 Server Debug     0 answers buffered. Old reply for transID=60d399 not found. Generating new answer.
2012.07.26 14:46:28 Server Info      Unable to RENEW binding for IA(iaid=750243230, client=00:01:00:01:17:a3:97:36:00:26:9e:cd:b7:2c: No such client.
2012.07.26 14:46:28 Server Debug     Preference set to 0.
2012.07.26 14:46:28 Server Notice    Sending REPLY on eth1/3,transID=0x60d399, opts: 3 2 1 7 23 24, 0 relay(s).
2012.07.26 14:46:28 Server Debug     Not executing external script (Notify script disabled).
2012.07.26 14:46:28 Server Notice    Accepting connections. Next event in 60 second(s).
2012.07.26 14:46:28 Server Debug     Received 98 bytes on interface eth1/3 (socket=4, addr=fe80::226:9eff:fecd:b72c.).
2012.07.26 14:46:28 Server Notice    Received REQUEST on eth1/3,TransID=0xb781d5, 5 opts: 1 2 8 3 6, 0 relay(s).
2012.07.26 14:46:28 Server Debug     1 answers buffered. Old reply for transID=b781d5 not found. Generating new answer.
2012.07.26 14:46:28 Server Info      Client has 0 addrs, asks for 1, 4294967295 is available, limit for client is 10, 1 will be assigned.
2012.07.26 14:46:28 Server Debug     Requested address (fd12::f2ff:a9e4:dcea:882b) is free, great!
2012.07.26 14:46:28 Server Info      Client requested fd12::f2ff:a9e4:dcea:882b, got fd12::f2ff:a9e4:dcea:882b (IAID=750243230, pref=3600,valid=7200).
2012.07.26 14:46:28 Server Debug     Adding client (DUID=00:01:00:01:17:a3:97:36:00:26:9e:cd:b7:2c) to addrDB.
2012.07.26 14:46:28 Server Debug     Adding IA (IAID=750243230) to addrDB.
2012.07.26 14:46:28 Server Debug     Adding fd12::f2ff:a9e4:dcea:882b to IA (IAID=750243230) to addrDB.
2012.07.26 14:46:28 Server Debug     Address usage for class 0 increased to 1.
2012.07.26 14:46:28 Server Debug     Preference set to 0.
2012.07.26 14:46:28 Server Notice    Sending REPLY on eth1/3,transID=0xb781d5, opts: 3 2 1 7 23 24, 0 relay(s).
2012.07.26 14:46:28 Server Debug     Not executing external script (Notify script disabled).
2012.07.26 14:46:28 Server Notice    Accepting connections. Next event in 60 second(s).
2012.07.26 14:46:37 Server Debug     Received 119 bytes on interface eth1/3 (socket=4, addr=fe80::216:3eff:fe58:dd4f.).
2012.07.26 14:46:37 Server Debug     FQDN: FQDN option received: fqdn name=crema42test.ipv6.osd.abg.fsc.net
2012.07.26 14:46:37 Server Notice    Received CONFIRM on eth1/3,TransID=0x1833f7, 5 opts: 1 6 8 39 3, 0 relay(s).
2012.07.26 14:46:37 Server Debug     2 answers buffered. Old reply for transID=1833f7 not found. Generating new answer.
2012.07.26 14:46:37 Server Debug     CONFIRM message: checking if fd12::6e1:76c:c910:9feb is supported:yes.
2012.07.26 14:46:37 Server Debug     Preference set to 0.
2012.07.26 14:46:37 Server Notice    Sending REPLY on eth1/3,transID=0x1833f7, opts: 13 3 2 1 7, 0 relay(s).
2012.07.26 14:46:37 Server Debug     Not executing external script (Notify script disabled).
2012.07.26 14:46:37 Server Notice    Accepting connections. Next event in 51 second(s).
2012.07.26 14:46:38 Server Debug     Received 137 bytes on interface eth1/3 (socket=4, addr=fe80::216:3eff:fe58:dd4f.).
2012.07.26 14:46:38 Server Debug     FQDN: FQDN option received: fqdn name=crema42test.ipv6.osd.abg.fsc.net
2012.07.26 14:46:38 Server Notice    Received RENEW on eth1/3,TransID=0x281377, 6 opts: 1 2 6 8 39 3, 0 relay(s).
2012.07.26 14:46:38 Server Debug     3 answers buffered. Old reply for transID=281377 not found. Generating new answer.
2012.07.26 14:46:38 Server Info      Unable to RENEW binding for IA(iaid=1046011215, client=00:01:00:01:17:85:b5:42:00:16:3e:58:dd:4f: No such client.
2012.07.26 14:46:38 Server Debug     Preference set to 0.
2012.07.26 14:46:38 Server Notice    Sending REPLY on eth1/3,transID=0x281377, opts: 3 2 1 7 23 24, 0 relay(s).
2012.07.26 14:46:38 Server Debug     Not executing external script (Notify script disabled).
2012.07.26 14:46:38 Server Notice    Accepting connections. Next event in 50 second(s).
2012.07.26 14:46:38 Server Debug     Received 137 bytes on interface eth1/3 (socket=4, addr=fe80::216:3eff:fe58:dd4f.).
2012.07.26 14:46:38 Server Debug     FQDN: FQDN option received: fqdn name=crema42test.ipv6.osd.abg.fsc.net
2012.07.26 14:46:38 Server Notice    Received REQUEST on eth1/3,TransID=0xd2fb21, 6 opts: 1 2 6 8 39 3, 0 relay(s).
2012.07.26 14:46:38 Server Debug     4 answers buffered. Old reply for transID=d2fb21 not found. Generating new answer.
2012.07.26 14:46:38 Server Warning   Unable to find client in the addrDB.2012.07.26 14:46:38 Server Debug     Requesting FQDN for client with DUID=00:01:00:01:17:85:b5:42:00:16:3e:58:dd:4f, addr=fe80::216:3eff:fe58:dd4f
2012.07.26 14:46:38 Server Info      FQDN: Client requested (crema42test.ipv6.osd.abg.fsc.net), assigning (crema42test.ipv6.osd.abg.fsc.net).
2012.07.26 14:46:38 Server Debug     FQDN: Adding FQDN Option in REPLY message: crema42test.ipv6.osd.abg.fsc.net, FQDNMode=2
2012.07.26 14:46:38 Server Debug     FQDN: Server configuration allow DNS updates for 00:01:00:01:17:85:b5:42:00:16:3e:58:dd:4f
2012.07.26 14:46:38 Server Warning   Unable to find client.2012.07.26 14:46:38 Server Info      Client has 0 addrs, asks for 1, 4294967294 is available, limit for client is 10, 1 will be assigned.
2012.07.26 14:46:38 Server Debug     Requested address (fd12::6e1:76c:c910:9feb) is free, great!
2012.07.26 14:46:38 Server Info      Client requested fd12::6e1:76c:c910:9feb, got fd12::6e1:76c:c910:9feb (IAID=1046011215, pref=3600,valid=7200).
2012.07.26 14:46:38 Server Debug     Adding client (DUID=00:01:00:01:17:85:b5:42:00:16:3e:58:dd:4f) to addrDB.
2012.07.26 14:46:38 Server Debug     Adding IA (IAID=1046011215) to addrDB.
2012.07.26 14:46:38 Server Debug     Adding fd12::6e1:76c:c910:9feb to IA (IAID=1046011215) to addrDB.
2012.07.26 14:46:38 Server Debug     Address usage for class 0 increased to 2.
2012.07.26 14:46:38 Server Debug     Preference set to 0.
2012.07.26 14:46:38 Server Notice    Sending REPLY on eth1/3,transID=0xd2fb21, opts: 3 2 1 7 23 24, 0 relay(s).
2012.07.26 14:46:38 Server Debug     Not executing external script (Notify script disabled).
2012.07.26 14:46:38 Server Notice    Accepting connections. Next event in 50 second(s).
2012.07.26 14:47:09 Server Debug     Received 58 bytes on interface eth1/3 (socket=4, addr=fe80::ea9a:8fff:fe64:1a4e.).
2012.07.26 14:47:09 Server Notice    Received SOLICIT on eth1/3,TransID=0x3d9a74, 4 opts: 1 8 3 6, 0 relay(s).
2012.07.26 14:47:09 Server Debug     5 answers buffered. Old reply for transID=3d9a74 not found. Generating new answer.
2012.07.26 14:47:09 Server Notice    IA option (with IAADDR suboptions missing) received. Assigning one address.
2012.07.26 14:47:09 Server Debug     Client requested unspecified (::) address. Hint ignored.
2012.07.26 14:47:09 Server Debug     Cache: There are no cached address address entries for client (DUID=00:01:00:01:17:a3:f7:bd:e8:9a:8f:64:1a:4e).
2012.07.26 14:47:09 Server Debug     Prefered class for client not found, using classid=0.
2012.07.26 14:47:09 Server Info      Client requested ::, got fd12::e4b9:fd42:f526:3a47 (IAID=1310352527, pref=3600,valid=7200).
2012.07.26 14:47:09 Server Debug     Adding client (DUID=00:01:00:01:17:a3:f7:bd:e8:9a:8f:64:1a:4e) to addrDB.
2012.07.26 14:47:09 Server Debug     Adding IA (IAID=1310352527) to addrDB.
2012.07.26 14:47:09 Server Debug     Adding fd12::e4b9:fd42:f526:3a47 to IA (IAID=1310352527) to addrDB.
2012.07.26 14:47:09 Server Debug     Address usage for class 0 increased to 3.
2012.07.26 14:47:09 Server Debug     Preference set to 0.
2012.07.26 14:47:09 Server Debug     Cache: Address fd12::e4b9:fd42:f526:3a47 added for client (DUID=00:01:00:01:17:a3:f7:bd:e8:9a:8f:64:1a:4e).
2012.07.26 14:47:09 Server Debug     Deleted address fd12::e4b9:fd42:f526:3a47 from addrDB.
2012.07.26 14:47:09 Server Debug     Deleted empty IA (IAID=1310352527) from addrDB.
2012.07.26 14:47:09 Server Debug     Deleted empty client (DUID=00:01:00:01:17:a3:f7:bd:e8:9a:8f:64:1a:4e) from addrDB.
2012.07.26 14:47:09 Server Debug     Address usage for class 0 decreased to 2.
2012.07.26 14:47:09 Server Notice    Sending ADVERTISE on eth1/3,transID=0x3d9a74, opts: 3 2 1 7 23 24, 0 relay(s).
2012.07.26 14:47:09 Server Debug     Not executing external script (Notify script disabled).
2012.07.26 14:47:09 Server Debug     1 message(s) were removed from cache.
2012.07.26 14:47:09 Server Notice    Accepting connections. Next event in 19 second(s).
2012.07.26 14:47:10 Server Debug     Received 98 bytes on interface eth1/3 (socket=4, addr=fe80::ea9a:8fff:fe64:1a4e.).
2012.07.26 14:47:10 Server Notice    Received REQUEST on eth1/3,TransID=0x848f7d, 5 opts: 1 2 8 3 6, 0 relay(s).
2012.07.26 14:47:10 Server Debug     5 answers buffered. Old reply for transID=848f7d not found. Generating new answer.
2012.07.26 14:47:10 Server Info      Client has 0 addrs, asks for 1, 4294967293 is available, limit for client is 10, 1 will be assigned.
2012.07.26 14:47:10 Server Debug     Requested address (fd12::e4b9:fd42:f526:3a47) is free, great!
2012.07.26 14:47:10 Server Info      Client requested fd12::e4b9:fd42:f526:3a47, got fd12::e4b9:fd42:f526:3a47 (IAID=1310352527, pref=3600,valid=7200).
2012.07.26 14:47:10 Server Debug     Adding client (DUID=00:01:00:01:17:a3:f7:bd:e8:9a:8f:64:1a:4e) to addrDB.
2012.07.26 14:47:10 Server Debug     Adding IA (IAID=1310352527) to addrDB.
2012.07.26 14:47:10 Server Debug     Adding fd12::e4b9:fd42:f526:3a47 to IA (IAID=1310352527) to addrDB.
2012.07.26 14:47:10 Server Debug     Address usage for class 0 increased to 3.
2012.07.26 14:47:10 Server Debug     Preference set to 0.
2012.07.26 14:47:10 Server Notice    Sending REPLY on eth1/3,transID=0x848f7d, opts: 3 2 1 7 23 24, 0 relay(s).
2012.07.26 14:47:10 Server Debug     Not executing external script (Notify script disabled).
2012.07.26 14:47:10 Server Notice    Accepting connections. Next event in 18 second(s).
2012.07.26 14:47:28 Server Debug     2 message(s) were removed from cache.
2012.07.26 14:47:28 Server Notice    Accepting connections. Next event in 9 second(s).
2012.07.26 14:47:37 Server Debug     1 message(s) were removed from cache.
2012.07.26 14:47:37 Server Notice    Accepting connections. Next event in 1 second(s).
2012.07.26 14:47:38 Server Debug     2 message(s) were removed from cache.
2012.07.26 14:47:38 Server Notice    Accepting connections. Next event in 32 second(s).

perhaps someone can see what's going wrong here?

Thanks and best regards,

Dieter



More information about the Dibbler mailing list