Re: dhcpcd refreshing lease very often.

[Mike Pumford <mpumford%mudcovered.org.uk@localhost>]

On 10/08/2020 12:49, Roy Marples wrote:
> On 10/08/2020 12:00, Mike Pumford wrote:
> > I've also noticed its intermittent. So its clearly being triggered by 
> > some network activity.
> dhcpcd will renew if the carrier goes down/up or you issue `dhcpcd -n` 
> on the command line. Those are the only early triggers.
Finally have some debug output the repeated requests start from a renewal:

Aug 11 20:48:50 trigati dhcpcd[12897]: wm0: sending NA for 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 20:53:56 trigati dhcpcd[12897]: wm0: renewing lease of 192.168.1.2
Aug 11 20:53:56 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 4.5 seconds
Aug 11 20:54:01 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 9.0 seconds
Aug 11 20:54:10 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 16.8 seconds
Aug 11 20:54:26 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 32.8 seconds
Aug 11 20:54:59 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 63.0 seconds
Aug 11 20:56:00 trigati dhcpcd[12897]: wm0: Router Advertisement from 
fe80::20d:b9ff:fe4a:7230
Aug 11 20:56:00 trigati dhcpcd[12897]: wm0: adding address 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 20:56:00 trigati dhcpcd[12897]: wm0: pltime 604800 seconds, 
vltime 2592000 seconds
Aug 11 20:56:00 trigati dhcpcd[12897]: wm0: sending NA for 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 20:56:00 trigati dhcpcd[12897]: wm0: executing 
`/libexec/dhcpcd-run-hooks' ROUTERADVERT
Aug 11 20:56:01 trigati dhcpcd[12897]: wm0: sending NA for 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 20:56:01 trigati dhcpcd[12897]: wm0: sending NA for 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 20:56:02 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 64.8 seconds
Aug 11 20:57:07 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 63.4 seconds
Aug 11 20:58:11 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 64.8 seconds
Aug 11 20:59:15 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 63.7 seconds
Aug 11 21:00:19 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 63.2 seconds
Aug 11 21:01:09 trigati dhcpcd[12897]: wm0: Router Advertisement from 
fe80::20d:b9ff:fe4a:7230
Aug 11 21:01:09 trigati dhcpcd[12897]: wm0: adding address 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 21:01:09 trigati dhcpcd[12897]: wm0: pltime 604800 seconds, 
vltime 2592000 seconds
Aug 11 21:01:09 trigati dhcpcd[12897]: wm0: sending NA for 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 21:01:09 trigati dhcpcd[12897]: wm0: executing 
`/libexec/dhcpcd-run-hooks' ROUTERADVERT
Aug 11 21:01:10 trigati dhcpcd[12897]: wm0: sending NA for 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 21:01:10 trigati dhcpcd[12897]: wm0: sending NA for 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 21:01:22 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 63.0 seconds
Aug 11 21:02:25 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 63.3 seconds
Aug 11 21:03:29 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 64.2 seconds
Aug 11 21:04:33 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 63.6 seconds
Aug 11 21:05:37 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 64.8 seconds
Aug 11 21:06:41 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 64.8 seconds
Aug 11 21:07:46 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 65.0 seconds
Aug 11 21:08:51 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 64.2 seconds
Aug 11 21:09:28 trigati dhcpcd[12897]: wm0: Router Advertisement from 
fe80::20d:b9ff:fe4a:7230
Aug 11 21:09:28 trigati dhcpcd[12897]: wm0: adding address 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 21:09:28 trigati dhcpcd[12897]: wm0: pltime 604800 seconds, 
vltime 2592000 seconds
Aug 11 21:09:28 trigati dhcpcd[12897]: wm0: sending NA for 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 21:09:28 trigati dhcpcd[12897]: wm0: executing 
`/libexec/dhcpcd-run-hooks' ROUTERADVERT
Aug 11 21:09:29 trigati dhcpcd[12897]: wm0: sending NA for 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 21:09:29 trigati dhcpcd[12897]: wm0: sending NA for 
2001:8b0:84:1:12c3:7bff:fe95:20ed/64
Aug 11 21:09:55 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 64.3 seconds
Aug 11 21:11:00 trigati dhcpcd[12897]: wm0: sending REQUEST (xid 
0xc8152c37), next in 64.0 seconds

I've run tcpdump and the dhcp response is making it back to the client 
so its not packet loss.

Mike