Re: kern/49167: dhcpd drop lease and connection repeatedly used with a fxp interface

To: kern-bug-people%netbsd.org@localhost,gnats-admin%netbsd.org@localhost,netbsd-bugs%netbsd.org@localhost,adrian.christiansen%gmail.com@localhost
Subject: Re: kern/49167: dhcpd drop lease and connection repeatedly used with a fxp interface
From: Adrian Christiansen <adrian.christiansen%gmail.com@localhost>
Date: Sat, 6 Sep 2014 16:30:00 +0000 (UTC)
The following reply was made to PR kern/49167; it has been noted by GNATS.

From: Adrian Christiansen <adrian.christiansen%gmail.com@localhost>
To: gnats-bugs%netbsd.org@localhost
Cc: 
Subject: Re: kern/49167: dhcpd drop lease and connection repeatedly used with
 a fxp interface
Date: Sat, 6 Sep 2014 18:25:28 +0200

 Hi again,
 
 Been a busy week but I've finally got some time and calm to sit down
 with NetBSD again.
 
 I've followed your advice and ran two simple scripts that just wrote
 the time and interface down or up
  in a file. Just as when I was watching if with ifconfig fxp0 it's
 just going up and down when dhcpcd is running.
 
 But I got something interesting out of route monitor, I'll attach a
 bit of output, this is some output of dhcpcd trying to get an IP. I
 have to add that 130.244.127.161 is my ISP's DNS server 1. Which does
 not respond to ping as I found out during install.
 
 route monitor output:
 
 got message of size 88 on Sat Sep  6 18:00:23 2014
 RTM_NEWADDR: address being added to iface: len 88, metric 0, flags: <CLONING>
 sockaddrs: <NETMASK,IFP,IFA,BRD>
  255.255.255.0 fxp0:0.d0.59.b8.17.9a 192.168.1.53 192.168.1.255
 got message of size 168 on Sat Sep  6 18:00:23 2014
 RTM_ADD: Add Route: len 168, pid 0, seq 0, errno 0, flags: <UP,CLONING>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK>
  192.168.1.0  255.255.255.0
 got message of size 152 on Sat Sep  6 18:00:23 2014
 RTM_IFINFO: iface status change: len 152, if# 1, carrier: no carrier,
 flags: <UP,BROADCAST,SIMPLEX,MULTICAST>
 got message of size 168 on Sat Sep  6 18:00:23 2014
 RTM_DELETE: Delete Route: len 168, pid 340, seq 1, errno 0, flags:
 <DONE,CLONING>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK>
  192.168.1.0  255.255.255.0
 got message of size 192 on Sat Sep  6 18:00:23 2014
 RTM_ADD: Add Route: len 192, pid 340, seq 1, errno 0, flags: <UP,DONE>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK,IFA>
  192.168.1.0 66.78.70.30.0 255.255.255.0 192.168.1.53
 got message of size 184 on Sat Sep  6 18:00:23 2014
 RTM_ADD: Add Route: len 184, pid 340, seq 1, errno 0, flags:
 <UP,GATEWAY,DONE,STATIC>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK,IFA>
  default 192.168.1.1 0.0.0.0 192.168.1.53
 got message of size 200 on Sat Sep  6 18:00:23 2014
 RTM_ADD: Add Route: len 200, pid 0, seq 0, errno 0, flags:
 <UP,HOST,DONE,LLINFO,CLONED>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,IFP,IFA>
  192.168.1.1  fxp0:0.d0.59.b8.17.9a 192.168.1.53
 got message of size 160 on Sat Sep  6 18:00:23 2014
 RTM_DELETE: Delete Route: len 160, pid 340, seq 1, errno 0, flags:
 <GATEWAY,DONE,STATIC>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK>
  default 192.168.1.1 default
 got message of size 136 on Sat Sep  6 18:00:25 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  130.244.127.161
 got message of size 136 on Sat Sep  6 18:00:25 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  130.244.127.161
 got message of size 136 on Sat Sep  6 18:00:25 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  130.244.127.161
 got message of size 168 on Sat Sep  6 18:00:25 2014
 RTM_DELETE: Delete Route: len 168, pid 340, seq 1, errno 0, flags:
 <DONE,CLONING>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK>
  192.168.1.0  255.255.255.0
 got message of size 160 on Sat Sep  6 18:00:25 2014
 RTM_DELETE: Delete Route: len 160, pid 0, seq 0, errno 0, flags:
 <UP,HOST,DONE,LLINFO,CLONED>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY>
  192.168.1.1
 got message of size 88 on Sat Sep  6 18:00:25 2014
 RTM_DELADDR: address being removed from iface: len 88, metric 0,
 flags: <CLONING>
 sockaddrs: <NETMASK,IFP,IFA,BRD>
  255.255.255.0 fxp0:0.d0.59.b8.17.9a 192.168.1.53 192.168.1.255
 got message of size 152 on Sat Sep  6 18:00:25 2014
 RTM_IFINFO: iface status change: len 152, if# 1, carrier: active,
 flags: <UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST>
 got message of size 136 on Sat Sep  6 18:00:25 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  130.244.127.161
 got message of size 136 on Sat Sep  6 18:00:25 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  130.244.127.161
 got message of size 136 on Sat Sep  6 18:00:25 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  130.244.127.161
 got message of size 136 on Sat Sep  6 18:00:25 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  192.168.1.1
 got message of size 136 on Sat Sep  6 18:00:25 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  192.168.1.1
 got message of size 136 on Sat Sep  6 18:00:25 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  192.168.1.1
 got message of size 88 on Sat Sep  6 18:00:29 2014
 RTM_NEWADDR: address being added to iface: len 88, metric 0, flags: <CLONING>
 sockaddrs: <NETMASK,IFP,IFA,BRD>
  255.255.255.0 fxp0:0.d0.59.b8.17.9a 192.168.1.53 192.168.1.255
 got message of size 168 on Sat Sep  6 18:00:29 2014
 RTM_ADD: Add Route: len 168, pid 0, seq 0, errno 0, flags: <UP,CLONING>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK>
  192.168.1.0  255.255.255.0
 got message of size 152 on Sat Sep  6 18:00:29 2014
 RTM_IFINFO: iface status change: len 152, if# 1, carrier: no carrier,
 flags: <UP,BROADCAST,SIMPLEX,MULTICAST>
 got message of size 168 on Sat Sep  6 18:00:29 2014
 RTM_DELETE: Delete Route: len 168, pid 340, seq 1, errno 0, flags:
 <DONE,CLONING>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK>
  192.168.1.0  255.255.255.0
 got message of size 192 on Sat Sep  6 18:00:29 2014
 RTM_ADD: Add Route: len 192, pid 340, seq 1, errno 0, flags: <UP,DONE>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK,IFA>
  192.168.1.0 66.78.70.30.0 255.255.255.0 192.168.1.53
 got message of size 184 on Sat Sep  6 18:00:29 2014
 RTM_ADD: Add Route: len 184, pid 340, seq 1, errno 0, flags:
 <UP,GATEWAY,DONE,STATIC>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK,IFA>
  default 192.168.1.1 0.0.0.0 192.168.1.53
 got message of size 200 on Sat Sep  6 18:00:29 2014
 RTM_ADD: Add Route: len 200, pid 0, seq 0, errno 0, flags:
 <UP,HOST,DONE,LLINFO,CLONED>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,IFP,IFA>
  192.168.1.1  fxp0:0.d0.59.b8.17.9a 192.168.1.53
 got message of size 160 on Sat Sep  6 18:00:29 2014
 RTM_DELETE: Delete Route: len 160, pid 340, seq 1, errno 0, flags:
 <GATEWAY,DONE,STATIC>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK>
  default 192.168.1.1 default
 got message of size 136 on Sat Sep  6 18:00:32 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  130.244.127.161
 got message of size 136 on Sat Sep  6 18:00:32 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  130.244.127.161
 got message of size 136 on Sat Sep  6 18:00:32 2014
 RTM_MISS: Lookup failed on this address: len 136, pid 0, seq 0, errno
 0, flags: <DONE>
 locks: none inits: none
 sockaddrs: <DST>
  130.244.127.161
 got message of size 168 on Sat Sep  6 18:00:32 2014
 RTM_DELETE: Delete Route: len 168, pid 340, seq 1, errno 0, flags:
 <DONE,CLONING>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY,NETMASK>
  192.168.1.0  255.255.255.0
 got message of size 160 on Sat Sep  6 18:00:32 2014
 RTM_DELETE: Delete Route: len 160, pid 0, seq 0, errno 0, flags:
 <UP,HOST,DONE,LLINFO,CLONED>
 locks: none inits: none
 sockaddrs: <DST,GATEWAY>
  192.168.1.1
 
 There seems to be a repeating pattern going on here.
 It seems like something is trying to verify if the link is up by
 pinging the DNS server, and fails.
 
 Please let me know if I can do some future testing.
 
 /Adrian.
 
 2014-09-01 11:20 GMT+02:00 Roy Marples <roy%marples.name@localhost>:
 > The following reply was made to PR kern/49167; it has been noted by GNATS.
 >
 > From: Roy Marples <roy%marples.name@localhost>
 > To: gnats-bugs%netbsd.org@localhost
 > Cc:
 > Subject: Re: kern/49167: dhcpd drop lease and connection repeatedly used  
 > with a fxp interface
 > Date: Mon, 01 Sep 2014 10:18:36 +0100
 >
 >  Hi
 >
 >  dhcpcd respects the interface carrier status as reported by the kernel,
 >  dhclient does not which is why it just works for you.
 >  -K disables this check (which Joerg suggested).
 >
 >  A superfluous inet address on the interface would not affect dhcpcd
 >  carrier checking.
 >
 >  I would recommend running route monitor in a console and / or an
 >  ifwatchd script so that you can log if the interface link is really
 >  flapping or dhcpcd has a bug here. But I suspect it's either a driver or
 >  connectivity issue rather than dhcpcd.
 >
 >  Roy
 >
Prev by Date: Re: port-evbarm/49019 (RPI: interrupt storm when url0 is up)
Next by Date: bin/49177: usr.bin/uuencode is not functioning correctly, there are some problems in the source code.
Previous by Thread: Re: kern/49167: dhcpd drop lease and connection repeatedly used with a fxp interface
Next by Thread: PR/49134 CVS commit: [netbsd-7] src/usr.bin/cksum
Indexes:
Home | Main Index | Thread Index | Old Index