ISC DHCP Users

fedora 26 ddns stopped working finding so far

classic Classic list List threaded Threaded
♦
♦
 7 messages Options
Barry Scott
Reply | Threaded
Open this post in threaded view
♦
♦
|

fedora 26 ddns stopped working finding so far

Barry Scott
I'm in the middle of debugging why dhcpd is no longer updating name/ptr info in dns.

Under fedora 25 I had a working setup so, unless there is a subtle config change I should have a good set of conf files.

I have been assuming that there is a reason why dhcpd fails to send to named.

I have defined DEBUG_DNS_UPDATES in hopes of seeing a interesting log.

I have been adding log_error lines to ddns.c which will mean that the line
number off a bit.

I see this error ever time a host tries to use ddns:

        "DDNS: ddns.c(1601): error in ddns_modify_fwd already running for 0x55e58a398240"

This is the complete sequence from dhcpd startup to the error:

Jul 30 16:31:12 fable systemd[1]: Stopped DHCPv4 Server Daemon.
Jul 30 16:31:12 fable systemd[1]: Starting DHCPv4 Server Daemon...
Jul 30 16:31:12 fable dhcpd[13240]: Internet Systems Consortium DHCP Server 4.3.5
Jul 30 16:31:12 fable dhcpd[13240]: Copyright 2004-2016 Internet Systems Consortium.
Jul 30 16:31:12 fable dhcpd[13240]: All rights reserved.
Jul 30 16:31:12 fable dhcpd[13240]: For info, please visit https://www.isc.org/software/dhcp/
Jul 30 16:31:12 fable dhcpd[13240]: WARNING: Overwriting trace file "/var/log/dhcpd-trace.log"
Jul 30 16:31:12 fable dhcpd[13240]: ldap_gssapi_principal is not set,GSSAPI Authentication for LDAP will not be used
Jul 30 16:31:12 fable dhcpd[13240]: Not searching LDAP since ldap-server, ldap-port and ldap-base-dn were not specified in the config file
Jul 30 16:31:12 fable dhcpd[13240]: Config file: /etc/dhcp/dhcpd.conf
Jul 30 16:31:12 fable dhcpd[13240]: Database file: /var/lib/dhcpd/dhcpd.leases
Jul 30 16:31:12 fable dhcpd[13240]: PID file: /var/run/dhcpd.pid
Jul 30 16:31:12 fable dhcpd[13240]: Source compiled to use binary-leases
Jul 30 16:31:12 fable dhcpd[13240]: Wrote 0 deleted host decls to leases file.
Jul 30 16:31:12 fable dhcpd[13240]: Wrote 0 new dynamic host decls to leases file.
Jul 30 16:31:12 fable dhcpd[13240]: Wrote 54 leases to leases file.
Jul 30 16:31:12 fable dhcpd[13240]: Listening on LPF/eno1/4c:72:b9:32:15:28/172.16.2.0/24
Jul 30 16:31:12 fable dhcpd[13240]: Sending on   LPF/eno1/4c:72:b9:32:15:28/172.16.2.0/24
Jul 30 16:31:12 fable systemd[1]: Started DHCPv4 Server Daemon.
Jul 30 16:31:12 fable dhcpd[13240]: Sending on   Socket/fallback/fallback-net
Jul 30 16:31:12 fable dhcpd[13240]: Server starting service.
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates ddns_update_style 3
Jul 30 16:31:17 fable dhcpd[13240]: ddns.c(123): Allocating ddns_cb=0x55e58a398240
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates goto noclient
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update s1 1 ddns_hostname rpi3
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update s3 1 ddns_domainname  chelsea.private.
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update ddns_fwd_name rpi3.chelsea.private.
Jul 30 16:31:17 fable dhcpd[13240]: qqq ddns_updates at client_updates:
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates at in do_remove 0
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates at line 375
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates ddns_ttl 1800
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates s1 1 at line 470
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates perform updates at line 587
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR before call ddns_modify_fwd
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_modify_fwd
Jul 30 16:31:17 fable dhcpd[13240]: QQQ dns_client_lazy
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_modify_fwd_add1
Jul 30 16:31:17 fable dhcpd[13240]: [176B blob data]
Jul 30 16:31:17 fable dhcpd[13240]: DDNS: ddns.c(1601): error in ddns_modify_fwd already running for 0x55e58a398240
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR after call ddns_modify_fwd
Jul 30 16:31:17 fable dhcpd[13240]: ddns.c(1618): freeing ddns_cb=0x55e58a398240
Jul 30 16:31:17 fable dhcpd[13240]: reuse_lease: lease age 16 (secs) under 25% threshold, reply with unaltered, existing lease for 172.16.2.170
Jul 30 16:31:17 fable dhcpd[13240]: DHCPREQUEST for 172.16.2.170 from b8:27:eb:b4:b3:b3 (rpi3) via eno1
Jul 30 16:31:17 fable dhcpd[13240]: DHCPACK on 172.16.2.170 to b8:27:eb:b4:b3:b3 (rpi3) via eno1

Here is my instrumented code around line ddns.c(1601):

                if (ddns_cb->flags & DDNS_UPDATE_ADDR) {
                        log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR");
                        ddns_cb->state    = DDNS_STATE_ADD_FW_NXDOMAIN;
                        ddns_cb->cur_func = ddns_fwd_srv_add1;
                        log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR before call ddns_modify_fwd");
                        result = ddns_modify_fwd(ddns_cb, MDL);
                        log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR after call ddns_modify_fwd");

It seems that the "already running" log is saying there is a bug in the code?

Is there a fix for this? If not how can I help track down a fix?

Barry

_______________________________________________
dhcp-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/dhcp-users
Bill Shirley-2
Reply | Threaded
Open this post in threaded view
♦
♦
|

Re: fedora 26 ddns stopped working finding so far

Bill Shirley-2
In named.conf I have an update log defined:
        channel update_channel {
                file "/var/log/named/update.log" versions 4 size 10m;
                print-category yes;
                print-severity yes;
                print-time yes;
                severity info;
        };
You could define it and the check to see if the update is refused.  You can also
run a tcpdump -i <interface> port domain
to watch the conversation between DHCP and DNS.  My services are both on the
same machine so <interface> would be lo.

HTH,
Bill

On 7/30/2017 11:43 AM, Barry Scott wrote:
I'm in the middle of debugging why dhcpd is no longer updating name/ptr info in dns.

Under fedora 25 I had a working setup so, unless there is a subtle config change I should have a good set of conf files.

I have been assuming that there is a reason why dhcpd fails to send to named.

I have defined DEBUG_DNS_UPDATES in hopes of seeing a interesting log.

I have been adding log_error lines to ddns.c which will mean that the line
number off a bit.

I see this error ever time a host tries to use ddns:

	"DDNS: ddns.c(1601): error in ddns_modify_fwd already running for 0x55e58a398240"

This is the complete sequence from dhcpd startup to the error:

Jul 30 16:31:12 fable systemd[1]: Stopped DHCPv4 Server Daemon.
Jul 30 16:31:12 fable systemd[1]: Starting DHCPv4 Server Daemon...
Jul 30 16:31:12 fable dhcpd[13240]: Internet Systems Consortium DHCP Server 4.3.5
Jul 30 16:31:12 fable dhcpd[13240]: Copyright 2004-2016 Internet Systems Consortium.
Jul 30 16:31:12 fable dhcpd[13240]: All rights reserved.
Jul 30 16:31:12 fable dhcpd[13240]: For info, please visit https://www.isc.org/software/dhcp/
Jul 30 16:31:12 fable dhcpd[13240]: WARNING: Overwriting trace file "/var/log/dhcpd-trace.log"
Jul 30 16:31:12 fable dhcpd[13240]: ldap_gssapi_principal is not set,GSSAPI Authentication for LDAP will not be used
Jul 30 16:31:12 fable dhcpd[13240]: Not searching LDAP since ldap-server, ldap-port and ldap-base-dn were not specified in the config file
Jul 30 16:31:12 fable dhcpd[13240]: Config file: /etc/dhcp/dhcpd.conf
Jul 30 16:31:12 fable dhcpd[13240]: Database file: /var/lib/dhcpd/dhcpd.leases
Jul 30 16:31:12 fable dhcpd[13240]: PID file: /var/run/dhcpd.pid
Jul 30 16:31:12 fable dhcpd[13240]: Source compiled to use binary-leases
Jul 30 16:31:12 fable dhcpd[13240]: Wrote 0 deleted host decls to leases file.
Jul 30 16:31:12 fable dhcpd[13240]: Wrote 0 new dynamic host decls to leases file.
Jul 30 16:31:12 fable dhcpd[13240]: Wrote 54 leases to leases file.
Jul 30 16:31:12 fable dhcpd[13240]: Listening on LPF/eno1/4c:72:b9:32:15:28/172.16.2.0/24
Jul 30 16:31:12 fable dhcpd[13240]: Sending on   LPF/eno1/4c:72:b9:32:15:28/172.16.2.0/24
Jul 30 16:31:12 fable systemd[1]: Started DHCPv4 Server Daemon.
Jul 30 16:31:12 fable dhcpd[13240]: Sending on   Socket/fallback/fallback-net
Jul 30 16:31:12 fable dhcpd[13240]: Server starting service.
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates ddns_update_style 3
Jul 30 16:31:17 fable dhcpd[13240]: ddns.c(123): Allocating ddns_cb=0x55e58a398240
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates goto noclient
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update s1 1 ddns_hostname rpi3
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update s3 1 ddns_domainname  chelsea.private.
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update ddns_fwd_name rpi3.chelsea.private.
Jul 30 16:31:17 fable dhcpd[13240]: qqq ddns_updates at client_updates:
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates at in do_remove 0
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates at line 375
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates ddns_ttl 1800
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates s1 1 at line 470
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates perform updates at line 587
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR before call ddns_modify_fwd
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_modify_fwd
Jul 30 16:31:17 fable dhcpd[13240]: QQQ dns_client_lazy
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_modify_fwd_add1
Jul 30 16:31:17 fable dhcpd[13240]: [176B blob data]
Jul 30 16:31:17 fable dhcpd[13240]: DDNS: ddns.c(1601): error in ddns_modify_fwd already running for 0x55e58a398240
Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR after call ddns_modify_fwd
Jul 30 16:31:17 fable dhcpd[13240]: ddns.c(1618): freeing ddns_cb=0x55e58a398240
Jul 30 16:31:17 fable dhcpd[13240]: reuse_lease: lease age 16 (secs) under 25% threshold, reply with unaltered, existing lease for 172.16.2.170
Jul 30 16:31:17 fable dhcpd[13240]: DHCPREQUEST for 172.16.2.170 from b8:27:eb:b4:b3:b3 (rpi3) via eno1
Jul 30 16:31:17 fable dhcpd[13240]: DHCPACK on 172.16.2.170 to b8:27:eb:b4:b3:b3 (rpi3) via eno1

Here is my instrumented code around line ddns.c(1601):

		if (ddns_cb->flags & DDNS_UPDATE_ADDR) {
                        log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR");
			ddns_cb->state    = DDNS_STATE_ADD_FW_NXDOMAIN;
			ddns_cb->cur_func = ddns_fwd_srv_add1;
                        log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR before call ddns_modify_fwd");
			result = ddns_modify_fwd(ddns_cb, MDL);
                        log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR after call ddns_modify_fwd");

It seems that the "already running" log is saying there is a bug in the code?

Is there a fix for this? If not how can I help track down a fix?

Barry

_______________________________________________
dhcp-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/dhcp-users


_______________________________________________
dhcp-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/dhcp-users
Pavel Zhukov
Reply | Threaded
Open this post in threaded view
♦
♦
|

Re: fedora 26 ddns stopped working finding so far

Pavel Zhukov
In reply to this post by Barry Scott
Barry Scott <[hidden email]> writes:

> I'm in the middle of debugging why dhcpd is no longer updating name/ptr info in dns.
>
> Under fedora 25 I had a working setup so, unless there is a subtle
> config change I should have a good set of conf files.
Hi,
Are you using RPM from the fedora repos?
If so please check this out hhtps://bugzilla.redhat.com/1471747

--
Pavel

>
> I have been assuming that there is a reason why dhcpd fails to send to named.
>
> I have defined DEBUG_DNS_UPDATES in hopes of seeing a interesting log.
>
> I have been adding log_error lines to ddns.c which will mean that the line
> number off a bit.
>
> I see this error ever time a host tries to use ddns:
>
> "DDNS: ddns.c(1601): error in ddns_modify_fwd already running for 0x55e58a398240"
>
> This is the complete sequence from dhcpd startup to the error:
>
> Jul 30 16:31:12 fable systemd[1]: Stopped DHCPv4 Server Daemon.
> Jul 30 16:31:12 fable systemd[1]: Starting DHCPv4 Server Daemon...
> Jul 30 16:31:12 fable dhcpd[13240]: Internet Systems Consortium DHCP Server 4.3.5
> Jul 30 16:31:12 fable dhcpd[13240]: Copyright 2004-2016 Internet Systems Consortium.
> Jul 30 16:31:12 fable dhcpd[13240]: All rights reserved.
> Jul 30 16:31:12 fable dhcpd[13240]: For info, please visit https://www.isc.org/software/dhcp/
> Jul 30 16:31:12 fable dhcpd[13240]: WARNING: Overwriting trace file "/var/log/dhcpd-trace.log"
> Jul 30 16:31:12 fable dhcpd[13240]: ldap_gssapi_principal is not set,GSSAPI Authentication for LDAP will not be used
> Jul 30 16:31:12 fable dhcpd[13240]: Not searching LDAP since ldap-server, ldap-port and ldap-base-dn were not specified in the config file
> Jul 30 16:31:12 fable dhcpd[13240]: Config file: /etc/dhcp/dhcpd.conf
> Jul 30 16:31:12 fable dhcpd[13240]: Database file: /var/lib/dhcpd/dhcpd.leases
> Jul 30 16:31:12 fable dhcpd[13240]: PID file: /var/run/dhcpd.pid
> Jul 30 16:31:12 fable dhcpd[13240]: Source compiled to use binary-leases
> Jul 30 16:31:12 fable dhcpd[13240]: Wrote 0 deleted host decls to leases file.
> Jul 30 16:31:12 fable dhcpd[13240]: Wrote 0 new dynamic host decls to leases file.
> Jul 30 16:31:12 fable dhcpd[13240]: Wrote 54 leases to leases file.
> Jul 30 16:31:12 fable dhcpd[13240]: Listening on LPF/eno1/4c:72:b9:32:15:28/172.16.2.0/24
> Jul 30 16:31:12 fable dhcpd[13240]: Sending on   LPF/eno1/4c:72:b9:32:15:28/172.16.2.0/24
> Jul 30 16:31:12 fable systemd[1]: Started DHCPv4 Server Daemon.
> Jul 30 16:31:12 fable dhcpd[13240]: Sending on   Socket/fallback/fallback-net
> Jul 30 16:31:12 fable dhcpd[13240]: Server starting service.
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates ddns_update_style 3
> Jul 30 16:31:17 fable dhcpd[13240]: ddns.c(123): Allocating ddns_cb=0x55e58a398240
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates goto noclient
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update s1 1 ddns_hostname rpi3
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update s3 1 ddns_domainname  chelsea.private.
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update ddns_fwd_name rpi3.chelsea.private.
> Jul 30 16:31:17 fable dhcpd[13240]: qqq ddns_updates at client_updates:
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates at in do_remove 0
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates at line 375
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates ddns_ttl 1800
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates s1 1 at line 470
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates perform updates at line 587
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR before call ddns_modify_fwd
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_modify_fwd
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ dns_client_lazy
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_modify_fwd_add1
> Jul 30 16:31:17 fable dhcpd[13240]: [176B blob data]
> Jul 30 16:31:17 fable dhcpd[13240]: DDNS: ddns.c(1601): error in ddns_modify_fwd already running for 0x55e58a398240
> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR after call ddns_modify_fwd
> Jul 30 16:31:17 fable dhcpd[13240]: ddns.c(1618): freeing ddns_cb=0x55e58a398240
> Jul 30 16:31:17 fable dhcpd[13240]: reuse_lease: lease age 16 (secs) under 25% threshold, reply with unaltered, existing lease for 172.16.2.170
> Jul 30 16:31:17 fable dhcpd[13240]: DHCPREQUEST for 172.16.2.170 from b8:27:eb:b4:b3:b3 (rpi3) via eno1
> Jul 30 16:31:17 fable dhcpd[13240]: DHCPACK on 172.16.2.170 to b8:27:eb:b4:b3:b3 (rpi3) via eno1
>
> Here is my instrumented code around line ddns.c(1601):
>
> if (ddns_cb->flags & DDNS_UPDATE_ADDR) {
>                         log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR");
> ddns_cb->state    = DDNS_STATE_ADD_FW_NXDOMAIN;
> ddns_cb->cur_func = ddns_fwd_srv_add1;
>                         log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR before call ddns_modify_fwd");
> result = ddns_modify_fwd(ddns_cb, MDL);
>                         log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR after call ddns_modify_fwd");
>
> It seems that the "already running" log is saying there is a bug in the code?
>
> Is there a fix for this? If not how can I help track down a fix?
>
> Barry
>
> _______________________________________________
> dhcp-users mailing list
> [hidden email]
> https://lists.isc.org/mailman/listinfo/dhcp-users
_______________________________________________
dhcp-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/dhcp-users
Thomas Markwalder
Reply | Threaded
Open this post in threaded view
♦
♦
|

Re: fedora 26 ddns stopped working finding so far

Thomas Markwalder
On 7/31/17 3:34 AM, Pavel Zhukov wrote:

> Barry Scott <[hidden email]> writes:
>
>> I'm in the middle of debugging why dhcpd is no longer updating name/ptr info in dns.
>>
>> Under fedora 25 I had a working setup so, unless there is a subtle
>> config change I should have a good set of conf files.
> Hi,
> Are you using RPM from the fedora repos?
> If so please check this out hhtps://bugzilla.redhat.com/1471747
>
> --
> Pavel
>
>> I have been assuming that there is a reason why dhcpd fails to send to named.
>>
>> I have defined DEBUG_DNS_UPDATES in hopes of seeing a interesting log.
>>
>> I have been adding log_error lines to ddns.c which will mean that the line
>> number off a bit.
>>
>> I see this error ever time a host tries to use ddns:
>>
>> "DDNS: ddns.c(1601): error in ddns_modify_fwd already running for 0x55e58a398240"
>>
>> This is the complete sequence from dhcpd startup to the error:
>>
>> Jul 30 16:31:12 fable systemd[1]: Stopped DHCPv4 Server Daemon.
>> Jul 30 16:31:12 fable systemd[1]: Starting DHCPv4 Server Daemon...
>> Jul 30 16:31:12 fable dhcpd[13240]: Internet Systems Consortium DHCP Server 4.3.5
>> Jul 30 16:31:12 fable dhcpd[13240]: Copyright 2004-2016 Internet Systems Consortium.
>> Jul 30 16:31:12 fable dhcpd[13240]: All rights reserved.
>> Jul 30 16:31:12 fable dhcpd[13240]: For info, please visit https://www.isc.org/software/dhcp/
>> Jul 30 16:31:12 fable dhcpd[13240]: WARNING: Overwriting trace file "/var/log/dhcpd-trace.log"
>> Jul 30 16:31:12 fable dhcpd[13240]: ldap_gssapi_principal is not set,GSSAPI Authentication for LDAP will not be used
>> Jul 30 16:31:12 fable dhcpd[13240]: Not searching LDAP since ldap-server, ldap-port and ldap-base-dn were not specified in the config file
>> Jul 30 16:31:12 fable dhcpd[13240]: Config file: /etc/dhcp/dhcpd.conf
>> Jul 30 16:31:12 fable dhcpd[13240]: Database file: /var/lib/dhcpd/dhcpd.leases
>> Jul 30 16:31:12 fable dhcpd[13240]: PID file: /var/run/dhcpd.pid
>> Jul 30 16:31:12 fable dhcpd[13240]: Source compiled to use binary-leases
>> Jul 30 16:31:12 fable dhcpd[13240]: Wrote 0 deleted host decls to leases file.
>> Jul 30 16:31:12 fable dhcpd[13240]: Wrote 0 new dynamic host decls to leases file.
>> Jul 30 16:31:12 fable dhcpd[13240]: Wrote 54 leases to leases file.
>> Jul 30 16:31:12 fable dhcpd[13240]: Listening on LPF/eno1/4c:72:b9:32:15:28/172.16.2.0/24
>> Jul 30 16:31:12 fable dhcpd[13240]: Sending on   LPF/eno1/4c:72:b9:32:15:28/172.16.2.0/24
>> Jul 30 16:31:12 fable systemd[1]: Started DHCPv4 Server Daemon.
>> Jul 30 16:31:12 fable dhcpd[13240]: Sending on   Socket/fallback/fallback-net
>> Jul 30 16:31:12 fable dhcpd[13240]: Server starting service.
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates ddns_update_style 3
>> Jul 30 16:31:17 fable dhcpd[13240]: ddns.c(123): Allocating ddns_cb=0x55e58a398240
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates goto noclient
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update s1 1 ddns_hostname rpi3
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update s3 1 ddns_domainname  chelsea.private.
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_update ddns_fwd_name rpi3.chelsea.private.
>> Jul 30 16:31:17 fable dhcpd[13240]: qqq ddns_updates at client_updates:
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates at in do_remove 0
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates at line 375
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates ddns_ttl 1800
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates s1 1 at line 470
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_updates perform updates at line 587
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR before call ddns_modify_fwd
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_modify_fwd
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ dns_client_lazy
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_modify_fwd_add1
>> Jul 30 16:31:17 fable dhcpd[13240]: [176B blob data]
>> Jul 30 16:31:17 fable dhcpd[13240]: DDNS: ddns.c(1601): error in ddns_modify_fwd already running for 0x55e58a398240
>> Jul 30 16:31:17 fable dhcpd[13240]: QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR after call ddns_modify_fwd
>> Jul 30 16:31:17 fable dhcpd[13240]: ddns.c(1618): freeing ddns_cb=0x55e58a398240
>> Jul 30 16:31:17 fable dhcpd[13240]: reuse_lease: lease age 16 (secs) under 25% threshold, reply with unaltered, existing lease for 172.16.2.170
>> Jul 30 16:31:17 fable dhcpd[13240]: DHCPREQUEST for 172.16.2.170 from b8:27:eb:b4:b3:b3 (rpi3) via eno1
>> Jul 30 16:31:17 fable dhcpd[13240]: DHCPACK on 172.16.2.170 to b8:27:eb:b4:b3:b3 (rpi3) via eno1
>>
>> Here is my instrumented code around line ddns.c(1601):
>>
>> if (ddns_cb->flags & DDNS_UPDATE_ADDR) {
>>                         log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR");
>> ddns_cb->state    = DDNS_STATE_ADD_FW_NXDOMAIN;
>> ddns_cb->cur_func = ddns_fwd_srv_add1;
>>                         log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR before call ddns_modify_fwd");
>> result = ddns_modify_fwd(ddns_cb, MDL);
>>                         log_error("QQQ ddns_fwd_srv_connector DDNS_UPDATE_ADDR after call ddns_modify_fwd");
>>
>> It seems that the "already running" log is saying there is a bug in the code?
>>
>> Is there a fix for this? If not how can I help track down a fix?
>>
>> Barry
>>
>> _______________________________________________
>> dhcp-users mailing list
>> [hidden email]
>> https://lists.isc.org/mailman/listinfo/dhcp-users
> _______________________________________________
> dhcp-users mailing list
> [hidden email]
> https://lists.isc.org/mailman/listinfo/dhcp-users

Hello:


You will also find this issue has been corrected for ISC DHCP 4.3.6,
which will be released later today. It ships with BIND 9.9.11 export
libraries which contain the correction for this issue.


Regards,

Thomas Markwalder

ISC Software Engineering



_______________________________________________
dhcp-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/dhcp-users
Barry Scott
Reply | Threaded
Open this post in threaded view
♦
♦
|

Re: fedora 26 ddns stopped working finding so far

Barry Scott
In reply to this post by Pavel Zhukov
On Monday, 31 July 2017 08:34:29 BST Pavel Zhukov wrote:
> If so please check this out hhtps://bugzilla.redhat.com/1471747

Yes I am using fedora RPMs.

Aha, known bug.

Thanks I grab from koji and test if it does not show up in the normal way
soon.

Thanks for you help.

Barry

_______________________________________________
dhcp-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/dhcp-users
Pavel Zhukov
Reply | Threaded
Open this post in threaded view
♦
♦
|

Re: fedora 26 ddns stopped working finding so far

Pavel Zhukov
Barry Scott <[hidden email]> writes:

> On Monday, 31 July 2017 08:34:29 BST Pavel Zhukov wrote:
>> If so please check this out hhtps://bugzilla.redhat.com/1471747
>
> Yes I am using fedora RPMs.
>
> Aha, known bug.
>
> Thanks I grab from koji and test if it does not show up in the normal way
> soon.
Fix should be in stable repos for a while iirc. Please let me know if
the update doesn't fix the issue (or open a bug in http://bugzilla.redhat.com)
>
> Thanks for you help.
>
> Barry
--
Pavel
_______________________________________________
dhcp-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/dhcp-users
Barry Scott
Reply | Threaded
Open this post in threaded view
♦
♦
|

Re: fedora 26 ddns stopped working finding so far

Barry Scott

> On 1 Aug 2017, at 07:30, Pavel Zhukov <[hidden email]> wrote:
>
> Barry Scott <[hidden email]> writes:
>
>> On Monday, 31 July 2017 08:34:29 BST Pavel Zhukov wrote:
>>> If so please check this out hhtps://bugzilla.redhat.com/1471747
>>
>> Yes I am using fedora RPMs.
>>
>> Aha, known bug.
>>
>> Thanks I grab from koji and test if it does not show up in the normal way
>> soon.
> Fix should be in stable repos for a while iirc. Please let me know if
> the update doesn't fix the issue (or open a bug in http://bugzilla.redhat.com)

Just updated and I have success.

Aug 02 19:10:45 fable dhcpd[958]: DHCPREQUEST for 172.16.2.170 from b8:27:eb:b4:b3:b3 via eno1
Aug 02 19:10:45 fable dhcpd[958]: DHCPACK on 172.16.2.170 to b8:27:eb:b4:b3:b3 (rpi3) via eno1
Aug 02 19:10:45 fable dhcpd[958]: Added new forward map from rpi3.chelsea.private. to 172.16.2.170
Aug 02 19:10:45 fable dhcpd[958]: Added reverse map from 170.2.16.172.in-addr.arpa. to rpi3.chelsea.private.

I'd add karma on bodhi, but I cannot find the f26 bind99...

Barry

>>
>> Thanks for you help.
>>
>> Barry
> --
> Pavel
>

_______________________________________________
dhcp-users mailing list
[hidden email]
https://lists.isc.org/mailman/listinfo/dhcp-users
Free forum by Nabble Edit this page