| From: | "baolong lin" <lbl52001@gmail.com> |
| To: | dhcp-bugs@isc.org |
| Subject: | 4.3.4 with 2 patches - Dhcp cilent 6 exit abnormal during boot up |
| Date: | Sun, 27 May 2018 20:17:51 +0000 |
Bug Report from www.isc.org:
Name: baolong lin
Email: lbl52001@gmail.com
Software Version: 4.3.4 with 2 patches
OS: Linux
Subject:Dhcp cilent 6 exit abnormal during boot up
Bug Detail
===========
My current dhcp client: 4.3.4 with 2 patches:
rt44373 251bb4b903f498785d106a222dd218e3c887e6e6
rt22675 f1fc5ede225a74f61ad994334c2dbdc6cfdd306f
It happens when reboot the machine, there is an unexpired leased address, but got max lifetime 0 while rebinding.
Do expire will be triggered, but the start_renew6 timer has not been cancelled. In the source code like:
if (has_addrs == ISC_FALSE) {
log_info("PRC: Bound lease is devoid of active addresses."
" Re-initializing.");
dhc6_lease_destroy(&lease, MDL);
client->active_lease = NULL;
start_init6(client);
return;
}
But in start_renew6, it does not check whether client have active_lease or not, it will change the MRD of the client then do_refresh6.
Unfortunately, in my environment, dhcp client does not get the leased address during the updated MRD, it will end with "Max retransmission duration exceeded."
Suggest to check timers to cancel all timers related to this client, and add return when active_lease == null at the beginning of start_renew6.
Full logs below:
PRC: Confirming active lease (INIT-REBOOT).
XMT: Forming Confirm, 0 ms elapsed.
XMT: X-- IA_NA c2:66:c3:32
XMT: | X-- Confirm Address 2001:1b70:82a8:90:70:2:0:3
XMT: V IA_NA appended.
XMT: Confirm on eth11.70, interval 970ms.
RCV: Reply message on eth11.70 from fe80::216:36ff:fe90:102.
RCV: X-- Server ID: 00:01:00:01:22:45:08:2b:00:16:36:90:01:02
message status code Success: "All addresses still on link."
PRC: Bound to lease 00:01:00:01:22:45:08:2b:00:16:36:90:01:02.
PRC: Rebind event scheduled in -98 seconds, to run for 228 seconds.
PRC: Depreference scheduled in -50 seconds.
PRC: Expiration scheduled in 130 seconds.
PRC: Rebinding lease on eth11.70.
2
XMT: Forming Rebind, 0 ms elapsed.
XMT: X-- IA_NA c2:66:c3:32
XMT: | X-- Requested renew +3600
XMT: | X-- Requested rebind +5400
XMT: | | X-- IAADDR 2001:1b70:82a8:90:70:2:0:3
XMT: | | | X-- Preferred lifetime +7200
XMT: | | | X-- Max lifetime +7500
XMT: V IA_NA appended.
XMT: Rebind on eth11.70, interval 10550ms.
PRC: Address 2001:1b70:82a8:90:70:2:0:3 depreferred.
PRC: Expiration scheduled in 125 seconds.
RCV: Reply message on eth11.70 from fe80::216:36ff:fe90:102.
RCV: X-- IA_NA c2:66:c3:32
RCV: | X-- starts 1527165843
RCV: | X-- t1 - renew +36
RCV: | X-- t2 - rebind +72
RCV: | X-- [Options]
RCV: | | X-- IAADDR 2001:1b70:82a8:90:70:2:0:3
RCV: | | | X-- Preferred lifetime 0.
RCV: | | | X-- Max lifetime 0.
RCV: X-- Server ID: 00:01:00:01:22:45:08:2b:00:16:36:90:01:02
PRC: Bound to lease 00:01:00:01:22:45:08:2b:00:16:36:90:01:02.
PRC: Renewal event scheduled in 34 seconds, to run for 36 seconds.
PRC: Depreference scheduled in -2 seconds.
PRC: Expiration scheduled in -2 seconds.
PRC: Address 2001:1b70:82a8:90:70:2:0:3 depreferred.
PRC: Renewal event scheduled in 34 seconds, to run for 36 seconds.
PRC: Expiration scheduled in -2 seconds.
PRC: Address 2001:1b70:82a8:90:70:2:0:3 expired.
PRC: Bound lease is devoid of active addresses. Re-initializing.
PRC: Soliciting for leases (INIT).
XMT: Forming Solicit, 0 ms elapsed.
XMT: X-- IA_NA c2:66:c3:32
XMT: | X-- Request renew in +3600
XMT: | X-- Request rebind in +5400
XMT: Solicit on eth11.70, interval 1030ms.
XMT: Forming Solicit, 1030 ms elapsed.
XMT: X-- IA_NA c2:66:c3:32
XMT: | X-- Request renew in +3600
XMT: | X-- Request rebind in +5400
XMT: Solicit on eth11.70, interval 1970ms.
XMT: Forming Solicit, 3000 ms elapsed.
XMT: X-- IA_NA c2:66:c3:32
XMT: | X-- Request renew in +3600
XMT: | X-- Request rebind in +5400
XMT: Solicit on eth11.70, interval 3780ms.
XMT: Forming Solicit, 6780 ms elapsed.
XMT: X-- IA_NA c2:66:c3:32
XMT: | X-- Request renew in +3600
XMT: | X-- Request rebind in +5400
XMT: Solicit on eth11.70, interval 7860ms.
RCV: Advertise message on eth11.70 from fe80::216:36ff:fe90:102.
RCV: X-- Server ID: 00:01:00:01:22:45:08:2b:00:16:36:90:01:02
PRC: Lease failed to satisfy.
RCV: Advertise message on eth11.70 from fe80::216:36ff:fe90:102.
RCV: X-- Server ID: 00:01:00:01:22:45:08:2b:00:16:36:90:01:02
PRC: Lease failed to satisfy.
XMT: Forming Solicit, 14650 ms elapsed.
XMT: X-- IA_NA c2:66:c3:32
XMT: | X-- Request renew in +3600
XMT: | X-- Request rebind in +5400
XMT: Solicit on eth11.70, interval 16320ms.
RCV: Advertise message on eth11.70 from fe80::216:36ff:fe90:102.
RCV: X-- Server ID: 00:01:00:01:22:45:08:2b:00:16:36:90:01:02
PRC: Lease failed to satisfy.
XMT: Forming Solicit, 30980 ms elapsed.
XMT: X-- IA_NA c2:66:c3:32
XMT: | X-- Request renew in +3600
XMT: | X-- Request rebind in +5400
XMT: Solicit on eth11.70, interval 31390ms.
RCV: Advertise message on eth11.70 from fe80::216:36ff:fe90:102.
RCV: X-- Server ID: 00:01:00:01:22:45:08:2b:00:16:36:90:01:02
PRC: Lease failed to satisfy.
PRC: Renewing lease on eth11.70.
Cannot renew without an active binding.
XMT: Forming Solicit, 0 ms elapsed.
XMT: X-- IA_NA c2:66:c3:32
XMT: | X-- Request renew in +3600
XMT: | X-- Request rebind in +5400
XMT: Solicit on eth11.70, interval 9910ms.
RCV: Advertise message on eth11.70 from fe80::216:36ff:fe90:102.
XMT: Forming Solicit, 9910 ms elapsed.
XMT: X-- IA_NA c2:66:c3:32
XMT: | X-- Request renew in +3600
XMT: | X-- Request rebind in +5400
XMT: Solicit on eth11.70, interval 20580ms.
RCV: Advertise message on eth11.70 from fe80::216:36ff:fe90:102.
XMT: Forming Solicit, 30510 ms elapsed.
XMT: X-- IA_NA c2:66:c3:32
XMT: | X-- Request renew in +3600
XMT: | X-- Request rebind in +5400
XMT: Solicit on eth11.70, interval 5510ms.
RCV: Advertise message on eth11.70 from fe80::216:36ff:fe90:102.
Max retransmission duration exceeded.
---
This email was received through isc.org Bug Submission Form