Re: bin/48140 (dhcpcd fails to get lease in -current)

[Andreas Gustafsson <gson%gson.org@localhost>]

The following reply was made to PR bin/48140; it has been noted by GNATS.

From: Andreas Gustafsson <gson%gson.org@localhost>
To: Roy Marples <roy%marples.name@localhost>
Cc: gnats-bugs%netbsd.org@localhost
Subject: Re: bin/48140 (dhcpcd fails to get lease in -current)
Date: Sat, 11 Oct 2014 19:33:06 +0300

 Roy Marples wrote:
 > Are you sure it's dhcpcd at fault here?
 
 I'm not saying dhcpcd is necessarily at fault - it could be that the
 new dhcpcd version just exposed a bug somewhere else - but I am sure
 that the problem appeared with the recent 6.5.0 import.  I noticed it
 by running the same automated test I had used to bisect the two
 earlier failures in this PR against the current sources.  I have since
 run the same test multiple times against both the pre- and post-import
 sources, and the results are consistent.
 
 > Also, did you trim some lines as theres clue what it's waiting on
 
 I did not trim any of the dhcpcd output.
 
 > - try adding the -d flag for more information.
 
 Done.  Before the import:
 
   dhcpcd[1294]: version 6.4.7 starting
   dhcpcd[1294]: ne2: disabling Kernel IPv6 auto link-local support
   dhcpcd[1294]: ne2: disabling Kernel IPv6 RA support
   dhcpcd[1294]: ne2: adding address fe80::59c1:f175:aeb3:433
   dhcpcd[1294]: ne2: vltime infinity, pltime infinity
   dhcpcd[1294]: ne2: executing `/libexec/dhcpcd-run-hooks' PREINIT
   dhcpcd[1294]: DUID 00:01:00:01:1b:cc:13:9a:52:54:00:12:34:56
   dhcpcd[1294]: ne2: IAID 00:12:34:56
   dhcpcd[1294]: ne2: delaying IPv6 router solictation for 1.0 seconds
   dhcpcd[1294]: ne2: delaying IPv4 for 0.5 seconds
   dhcpcd[1294]: ne2: soliciting a DHCP lease
   dhcpcd[1294]: ne2: sending DISCOVER (xid 0xd7cefa51), next in 4.6 seconds
   dhcpcd[1294]: ne2: offered 10.0.2.15 from 10.0.2.2
   dhcpcd[1294]: ne2: sending REQUEST (xid 0xd7cefa51), next in 3.3 seconds
   dhcpcd[1294]: ne2: acknowledged 10.0.2.15 from 10.0.2.2
   dhcpcd[1294]: ne2: checking for 10.0.2.15
   dhcpcd[1294]: ne2: sending ARP probe (1 of 3), next in 1.6 seconds
   dhcpcd[1294]: ne2: soliciting an IPv6 router
   dhcpcd[1294]: ne2: sending Router Solicitation
   dhcpcd[1294]: ne2: sending ARP probe (2 of 3), next in 1.3 seconds
   dhcpcd[1294]: ne2: sending ARP probe (3 of 3), next in 2.0 seconds
   dhcpcd[1294]: ne2: sending Router Solicitation
   dhcpcd[1294]: ne2: leased 10.0.2.15 for 86400 seconds
   dhcpcd[1294]: ne2: renew in 43200 seconds, rebind in 75600 seconds
   dhcpcd[1294]: ne2: adding IP address 10.0.2.15/24
   dhcpcd[1294]: ne2: adding host route to 10.0.2.15 via 127.0.0.1
   dhcpcd[1294]: ne2: adding route to 10.0.2.0/24
   dhcpcd[1294]: ne2: adding default route via 10.0.2.2
   dhcpcd[1294]: ne2: writing lease `/var/db/dhcpcd-ne2.lease'
   dhcpcd[1294]: ne2: executing `/libexec/dhcpcd-run-hooks' BOUND
   dhcpcd[1294]: forking to background
   dhcpcd[1294]: forked to background, child pid 1749
 
 After the import:
 
   dhcpcd[1587]: version 6.5.0 starting
   dhcpcd[1587]: ne2: disabling Kernel IPv6 auto link-local support
   dhcpcd[1587]: ne2: disabling Kernel IPv6 RA support
   dhcpcd[1587]: ne2: adding address fe80::59c1:f175:aeb3:433
   dhcpcd[1587]: ne2: vltime infinity, pltime infinity
   dhcpcd[1587]: ne2: executing `/libexec/dhcpcd-run-hooks' PREINIT
   dhcpcd[1587]: timed out
   dhcpcd[1587]: allowing 8 seconds for IPv4LL timeout
   Oct 11 16:15:06  dhcpcd[1587]: timed out
   dhcpcd[1587]: timed out
   dhcpcd[1587]: forking to background
   dhcpcd[1587]: forked to background, child pid 1662
 
 > I'm running exactly what was imported on FreeBSD and OpenBSD qemu images 
 > just fine *, and of course on the NetBSD host as well. All i386.
 > For reference my qemu is at version 2.1.0 from pkgsrc and I did not 
 > specify a nic model type.
 
 It is also working for me on real hardware.
 -- 
 Andreas Gustafsson, gson%gson.org@localhost