[Pdns-users] Multiple notifications when notifying IPv6 addresses

Nikolaos Milas nmilas at admin.noa.gr
Wed Apr 3 20:12:15 UTC 2013 

We have observed that when the master (v3.2) notifies a slave using an 
IPv6 address, then multiple unnecessary notifications are being sent.

The same behavior is observed using pdns_control. One notification is 
sent when the target is notified over an IPv4 address, multiple 
notifications are sent when the target is notified over an IPv6 address.

[ Master is using the new ldap backend 
(http://repo.or.cz/w/pdns-ldap-backend.git) but this behavior should not 
be backend-specific. ]

Is this known behavior? Is it a bug?

Please advise.

Logs follow.

Regards,
Nick

======================= test using bind slave ========================

    On the master:

        # pdns_control notify-host 204.251.195.in-addr.arpa 195.251.204.197

    Log:

        Mar 21 16:09:06 vmres pdns[30302]: Notification request to host
        195.251.204.197 for domain '204.251.195.in-addr.arpa' received
        Mar 21 16:09:06 vmres pdns[30302]: [LdapBackend] LDAP servers =
        localhost
        Mar 21 16:09:06 vmres pdns[30302]: [LdapBackend] Ldap connection
        succeeded
        Mar 21 16:09:06 vmres pdns[30302]: AXFR of domain
        '204.251.195.in-addr.arpa' initiated by 195.251.204.197
        Mar 21 16:09:06 vmres pdns[30302]: AXFR of domain
        '204.251.195.in-addr.arpa' allowed: client IP 195.251.204.197 is
        in allow-axfr-ips
        Mar 21 16:09:06 vmres pdns[30302]: [LdapBackend] LDAP servers =
        localhost
        Mar 21 16:09:06 vmres pdns[30302]: [LdapBackend] Ldap connection
        succeeded
        Mar 21 16:09:06 vmres pdns[30302]: [LdapBackend] LDAP servers =
        localhost
        Mar 21 16:09:06 vmres pdns[30302]: [LdapBackend] Ldap connection
        succeeded
        Mar 21 16:09:06 vmres pdns[30302]: AXFR of domain
        '204.251.195.in-addr.arpa' to 195.251.204.197 finished
        Mar 21 16:09:06 vmres pdns[30302]: [LdapBackend] Ldap connection
        closed
        Mar 21 16:09:06 vmres pdns[30302]: [LdapBackend] Ldap connection
        closed
        Mar 21 16:09:06 vmres pdns[30302]: [LdapBackend] Ldap connection
        closed
        Mar 21 16:09:07 vmres pdns[30302]: Removed from notification
        list: '204.251.195.in-addr.arpa' to 195.251.204.197:53 (was
        acknowledged)

    On the slave (log):

        Mar 21 16:09:06 dnslab named[25294]: client
        194.177.195.158#16228: received notify for zone
        '204.251.195.in-addr.arpa'
        Mar 21 16:09:06 dnslab named[25294]: zone
        204.251.195.in-addr.arpa/IN: Transfer started.
        Mar 21 16:09:06 dnslab named[25294]: transfer of
        '204.251.195.in-addr.arpa/IN' from 194.177.195.158#53: connected
        using 195.251.204.197#49889
        Mar 21 16:09:06 dnslab named[25294]: zone
        204.251.195.in-addr.arpa/IN: transferred serial 2013032107
        Mar 21 16:09:06 dnslab named[25294]: transfer of
        '204.251.195.in-addr.arpa/IN' from 194.177.195.158#53: end of
        transfer

    On the master:

        # pdns_control notify-host 204.251.195.in-addr.arpa
        2001:648:2011:11::197

    Log:

        Mar 21 16:07:10 vmres pdns[30302]: Notification request to host
        2001:648:2011:11::197 for domain '204.251.195.in-addr.arpa' 
received
        Mar 21 16:07:11 vmres pdns[30302]: [LdapBackend] LDAP servers =
        localhost
        Mar 21 16:07:11 vmres pdns[30302]: [LdapBackend] Ldap connection
        succeeded
        Mar 21 16:07:11 vmres pdns[30302]: AXFR of domain
        '204.251.195.in-addr.arpa' initiated by 195.251.204.197
        Mar 21 16:07:11 vmres pdns[30302]: AXFR of domain
        '204.251.195.in-addr.arpa' allowed: client IP 195.251.204.197 is
        in allow-axfr-ips
        Mar 21 16:07:11 vmres pdns[30302]: [LdapBackend] LDAP servers =
        localhost
        Mar 21 16:07:11 vmres pdns[30302]: [LdapBackend] Ldap connection
        succeeded
        Mar 21 16:07:11 vmres pdns[30302]: [LdapBackend] LDAP servers =
        localhost
        Mar 21 16:07:11 vmres pdns[30302]: [LdapBackend] Ldap connection
        succeeded
        Mar 21 16:07:11 vmres pdns[30302]: AXFR of domain
        '204.251.195.in-addr.arpa' to 195.251.204.197 finished
        Mar 21 16:07:11 vmres pdns[30302]: [LdapBackend] Ldap connection
        closed
        Mar 21 16:07:11 vmres pdns[30302]: [LdapBackend] Ldap connection
        closed
        Mar 21 16:07:11 vmres pdns[30302]: [LdapBackend] Ldap connection
        closed
        Mar 21 16:07:12 vmres pdns[30302]: Received spurious notify
        answer for '204.251.195.in-addr.arpa' from
        [2001:648:2011:11::197]:53
        Mar 21 16:07:15 vmres pdns[30302]: Received spurious notify
        answer for '204.251.195.in-addr.arpa' from
        [2001:648:2011:11::197]:53
        Mar 21 16:07:19 vmres pdns[30302]: Received spurious notify
        answer for '204.251.195.in-addr.arpa' from
        [2001:648:2011:11::197]:53
        Mar 21 16:07:28 vmres pdns[30302]: Received spurious notify
        answer for '204.251.195.in-addr.arpa' from
        [2001:648:2011:11::197]:53

    On the slave (log):

        Mar 21 16:07:11 dnslab named[25294]: client
        2001:648:2011:14::158#13654: received notify for zone
        '204.251.195.in-addr.arpa'
        Mar 21 16:07:11 dnslab named[25294]: zone
        204.251.195.in-addr.arpa/IN: Transfer started.
        Mar 21 16:07:11 dnslab named[25294]: transfer of
        '204.251.195.in-addr.arpa/IN' from 194.177.195.158#53: connected
        using 195.251.204.197#34108
        Mar 21 16:07:11 dnslab named[25294]: zone
        204.251.195.in-addr.arpa/IN: transferred serial 2013032106
        Mar 21 16:07:11 dnslab named[25294]: transfer of
        '204.251.195.in-addr.arpa/IN' from 194.177.195.158#53: end of
        transfer
        Mar 21 16:07:14 dnslab named[25294]: client
        2001:648:2011:14::158#13654: received notify for zone
        '204.251.195.in-addr.arpa'
        Mar 21 16:07:28 dnslab last message repeated 2 times

========================================================================


===================== test using powerdns slave ========================

Server Logs:

Mar 21 18:12:54 vmres pdns[31320]: Notification request to host 
2001:648:2011:11::197 for domain '204.251.195.in-addr.arpa' received
Mar 21 18:12:54 vmres pdns[31320]: [LdapBackend] LDAP servers = localhost
Mar 21 18:12:54 vmres pdns[31320]: [LdapBackend] Ldap connection succeeded
Mar 21 18:12:54 vmres pdns[31320]: AXFR of domain 
'204.251.195.in-addr.arpa' initiated by 195.251.204.197
Mar 21 18:12:54 vmres pdns[31320]: AXFR of domain 
'204.251.195.in-addr.arpa' allowed: client IP 195.251.204.197 is in 
allow-axfr-ips
Mar 21 18:12:54 vmres pdns[31320]: [LdapBackend] LDAP servers = localhost
Mar 21 18:12:54 vmres pdns[31320]: [LdapBackend] Ldap connection succeeded
Mar 21 18:12:54 vmres pdns[31320]: [LdapBackend] LDAP servers = localhost
Mar 21 18:12:54 vmres pdns[31320]: [LdapBackend] Ldap connection succeeded
Mar 21 18:12:54 vmres pdns[31320]: AXFR of domain 
'204.251.195.in-addr.arpa' to 195.251.204.197 finished
Mar 21 18:12:54 vmres pdns[31320]: [LdapBackend] Ldap connection closed
Mar 21 18:12:54 vmres pdns[31320]: [LdapBackend] Ldap connection closed
Mar 21 18:12:54 vmres pdns[31320]: [LdapBackend] Ldap connection closed
Mar 21 18:12:55 vmres pdns[31320]: Received spurious notify answer for 
'204.251.195.in-addr.arpa' from [2001:648:2011:11::197]:53
Mar 21 18:12:58 vmres pdns[31320]: Received spurious notify answer for 
'204.251.195.in-addr.arpa' from [2001:648:2011:11::197]:53
Mar 21 18:13:02 vmres pdns[31320]: Received spurious notify answer for 
'204.251.195.in-addr.arpa' from [2001:648:2011:11::197]:53
Mar 21 18:13:12 vmres pdns[31320]: Received spurious notify answer for 
'204.251.195.in-addr.arpa' from [2001:648:2011:11::197]:53

Slave logs:

Mar 21 18:12:54 dnslab pdns[25568]: 1 slave domain needs checking, 0 
queued for AXFR
Mar 21 18:12:54 dnslab pdns[25568]: Received serial number updates for 1 
zones, had 0 timeouts
Mar 21 18:12:54 dnslab pdns[25568]: Domain '204.251.195.in-addr.arpa' is 
stale, master serial 2013032108, our serial 2013032107
Mar 21 18:12:54 dnslab pdns[25568]: Initiating transfer of 
'204.251.195.in-addr.arpa' from remote '194.177.195.158'
Mar 21 18:12:54 dnslab pdns[25568]: AXFR started for 
'204.251.195.in-addr.arpa', transaction started
Mar 21 18:12:54 dnslab pdns[25568]: Zone '204.251.195.in-addr.arpa' 
(/etc/powerdns/named-data/204.251.195.in-addr.arpa.zone) reloaded
Mar 21 18:12:54 dnslab pdns[25568]: AXFR done for 
'204.251.195.in-addr.arpa', zone committed with serial number 2013032108
Mar 21 18:12:57 dnslab pdns[25568]: 1 slave domain needs checking, 0 
queued for AXFR
Mar 21 18:12:57 dnslab pdns[25568]: Received serial number updates for 1 
zones, had 0 timeouts
Mar 21 18:12:57 dnslab pdns[25568]: Domain '204.251.195.in-addr.arpa' is 
fresh (not presigned, no RRSIG check)
Mar 21 18:13:02 dnslab pdns[25568]: 1 slave domain needs checking, 0 
queued for AXFR
Mar 21 18:13:02 dnslab pdns[25568]: Received serial number updates for 1 
zones, had 0 timeouts
Mar 21 18:13:02 dnslab pdns[25568]: Domain '204.251.195.in-addr.arpa' is 
fresh (not presigned, no RRSIG check)
Mar 21 18:13:11 dnslab pdns[25568]: 1 slave domain needs checking, 0 
queued for AXFR
Mar 21 18:13:11 dnslab pdns[25568]: Received serial number updates for 1 
zones, had 0 timeouts
Mar 21 18:13:11 dnslab pdns[25568]: Domain '204.251.195.in-addr.arpa' is 
fresh (not presigned, no RRSIG check)

========================================================================

More information about the Pdns-users mailing list