[PLUG] Weird issue network ports dhcp client RPi

Chuck Hast wchast at gmail.com
Thu Oct 20 00:36:54 UTC 2016


I ran it, and I see nothing talking on port 67. I do see some other stuff
but as
soon as I disconnect the wired port the wifi goes down and tcpdump drops
the whole thing reporting the port as closed.

If I do not plug the ethernet cable back in within a few seconds it will
not come
back up either, but I can bring each one of them up by doing a ifdown port#
ifup port#

Now if I do a ifup port# it comes back and says the port is up, so I have
to do
a ifdown on the port it says it is removing some stale files, then I can do
ifup
and bring the port back up, If I am watching the systlog on the console I
can see
where it request a dhcp assignment, and the dhcp server sends it an address.


On Wed, Oct 19, 2016 at 4:24 PM, Mike C. <mconnors1 at gmail.com> wrote:

> >
> > Here is the syslog of when I disconnect the RJ-45 connector:
> >
> > Oct 19 14:09:18 WxPi dhcpcd[464]: eth0: carrier lost
> > Oct 19 14:09:18 WxPi rsyslogd-2007: action 'action 17' suspended, next
> > retry is Wed Oct 19 14:09:48 2016 [try http://www.rsyslog.com/e/2007 ]
> > Oct 19 14:09:18 WxPi kernel: [  131.168314] smsc95xx 1-1.1:1.0 eth0: link
> > down
> > Oct 19 14:09:18 WxPi dhcpcd[464]: eth0: deleting route to 192.168.7.0/24
> > Oct 19 14:09:18 WxPi avahi-daemon[452]: Withdrawing address record for
> > 192.168.7.55 on eth0.
> > Oct 19 14:09:18 WxPi dhcpcd[464]: eth0: deleting default route via
> > 192.168.7.1
> > Oct 19 14:09:18 WxPi avahi-daemon[452]: Leaving mDNS multicast group on
> > interface eth0.IPv4 with address 192.168.7.55.
> > Oct 19 14:09:18 WxPi avahi-daemon[452]: Interface eth0.IPv4 no longer
> > relevant for mDNS.
> > Oct 19 14:09:18 WxPi dhcpcd[1218]: sending commands to master dhcpcd
> > process
> > Oct 19 14:09:18 WxPi avahi-daemon[452]: Interface eth0.IPv6 no longer
> > relevant for mDNS.
> > Oct 19 14:09:18 WxPi avahi-daemon[452]: Leaving mDNS multicast group on
> > interface eth0.IPv6 with address fe80::ba27:ebff:fe19:4ac7.
> > Oct 19 14:09:18 WxPi avahi-daemon[452]: Withdrawing address record for
> > fe80::ba27:ebff:fe19:4ac7 on eth0.
> > Oct 19 14:09:18 WxPi kernel: [  131.451171] smsc95xx 1-1.1:1.0 eth0:
> > hardware isn't capable of remote wakeup
> > Oct 19 14:09:18 WxPi dhcpcd[464]: control command: /sbin/dhcpcd -k eth0
> > Oct 19 14:09:18 WxPi dhcpcd[464]: eth0: removing interface
> > Oct 19 14:09:18 WxPi kernel: [  131.539033] smsc95xx 1-1.1:1.0 eth0:
> > hardware isn't capable of remote wakeup
> > Oct 19 14:09:18 WxPi kernel: [  131.539493] IPv6: ADDRCONF(NETDEV_UP):
> > eth0: link is not ready
> > Oct 19 14:09:20 WxPi ntpd[598]: Deleting interface #7 eth0,
> > fe80::ba27:ebff:fe19:4ac7#123, interface stats: received=0, sent=0,
> > dropped=0, active_tim
> > e=112 secs
> > Oct 19 14:09:20 WxPi ntpd[598]: Deleting interface #4 eth0,
> > 192.168.7.55#123, interface stats: received=0, sent=0, dropped=0,
> > active_time=112 secs
> > Oct 19 14:09:20 WxPi ntpd[598]: peers refreshed
> > Oct 19 14:09:29 WxPi dhcpcd[1349]: sending commands to master dhcpcd
> > process
> > Oct 19 14:09:29 WxPi dhcpcd[464]: control command: /sbin/dhcpcd -k wlan0
> > Oct 19 14:09:29 WxPi dhcpcd[464]: wlan0: removing interface
> > Oct 19 14:09:29 WxPi dhcpcd[464]: wlan0: deleting address
> > fe80::37d2:4b61:ba0e:fe01
> > Oct 19 14:09:29 WxPi avahi-daemon[452]: Withdrawing address record for
> > fe80::37d2:4b61:ba0e:fe01 on wlan0.
> > Oct 19 14:09:29 WxPi avahi-daemon[452]: Leaving mDNS multicast group on
> > interface wlan0.IPv6 with address fe80::37d2:4b61:ba0e:fe01.
> > Oct 19 14:09:29 WxPi avahi-daemon[452]: Interface wlan0.IPv6 no longer
> > relevant for mDNS.
> > Oct 19 14:09:29 WxPi avahi-daemon[452]: Withdrawing address record for
> > 192.168.7.65 on wlan0.
> > Oct 19 14:09:29 WxPi avahi-daemon[452]: Leaving mDNS multicast group on
> > interface wlan0.IPv4 with address 192.168.7.65.
> > Oct 19 14:09:29 WxPi avahi-daemon[452]: Interface wlan0.IPv4 no longer
> > relevant for mDNS.
> > Oct 19 14:09:29 WxPi dhcpcd[464]: wlan0: releasing lease of 192.168.7.65
> > Oct 19 14:09:29 WxPi wpa_supplicant[562]: wlan0: CTRL-EVENT-DISCONNECTED
> > bssid=c4:3d:c7:95:7f:84 reason=3 locally_generated=1
> > Oct 19 14:09:29 WxPi dhclient: receive_packet failed on wlan0: Network is
> > down
> > Oct 19 14:09:29 WxPi kernel: [  142.552261] R8188EU: ERROR indicate
> > disassoc
> > Oct 19 14:09:29 WxPi kernel: [  142.584187] IPv6: ADDRCONF(NETDEV_UP):
> > wlan0: link is not ready
> > Oct 19 14:09:29 WxPi dhcpcd[464]: wlan0: dhcp_openudp: Cannot assign
> > requested address
> > Oct 19 14:09:29 WxPi dhcpcd[464]: wlan0: deleting route to
> 192.168.7.0/24
> > Oct 19 14:09:29 WxPi dhcpcd[464]: wlan0: deleting default route via
> > 192.168.7.1
> > Oct 19 14:09:29 WxPi kernel: [  142.906954] R8188EU: ERROR indicate
> > disassoc
> > Oct 19 14:09:29 WxPi wpa_supplicant[562]: wlan0: CTRL-EVENT-TERMINATING
> > Oct 19 14:09:29 WxPi kernel: [  143.031122] IPv6:
> ADDRCONF(NETDEV_CHANGE):
> > eth0: link becomes ready
> > Oct 19 14:09:29 WxPi kernel: [  143.033077] smsc95xx 1-1.1:1.0 eth0: link
> > up, 100Mbps, full-duplex, lpa 0xCDE1
> > Oct 19 14:09:30 WxPi kernel: [  143.081165] IPv6: ADDRCONF(NETDEV_UP):
> > wlan0: link is not ready
> > Oct 19 14:09:30 WxPi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255
> > port 67 interval 5
> > Oct 19 14:09:30 WxPi dhclient: receive_packet failed on wlan0: Network is
> > down
> > Oct 19 14:09:30 WxPi weewx[1082]: genimages: Generated 12 images for
> > StandardReport in 2.98 seconds
> > Oct 19 14:09:30 WxPi weewx[1082]: reportengine: copied 0 files to
> > /var/www/weewx
> > Oct 19 14:09:32 WxPi ntpd[598]: Deleting interface #6 wlan0,
> > fe80::37d2:4b61:ba0e:fe01#123, interface stats: received=0, sent=0,
> > dropped=0, active_time=124 secs
> > Oct 19 14:09:32 WxPi ntpd[598]: Deleting interface #5 wlan0,
> > 192.168.7.65#123, interface stats: received=40, sent=40, dropped=0,
> > active_time=124 secs
> > Oct 19 14:09:32 WxPi ntpd[598]: 52.6.160.3 interface 192.168.7.65 ->
> (none)
> > Oct 19 14:09:32 WxPi ntpd[598]: 108.61.73.244 interface 192.168.7.65 ->
> > (none)
> > Oct 19 14:09:32 WxPi ntpd[598]: 108.61.73.243 interface 192.168.7.65 ->
> > (none)
> > Oct 19 14:09:32 WxPi ntpd[598]: 209.242.224.117 interface 192.168.7.65 ->
> > (none)
> > Oct 19 14:09:32 WxPi ntpd[598]: peers refreshed
> > Oct 19 14:09:35 WxPi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255
> > port 67 interval 7
> > Oct 19 14:09:39 WxPi dhcpcd[1531]: sending commands to master dhcpcd
> > process
> > Oct 19 14:09:39 WxPi dhcpcd[464]: control command: /sbin/dhcpcd -k wlan0
> > Oct 19 14:09:40 WxPi dhclient: receive_packet failed on wlan0: Network is
> > down
> > Oct 19 14:09:40 WxPi kernel: [  153.089925] R8188EU: ERROR indicate
> > disassoc
> > Oct 19 14:09:40 WxPi kernel: [  153.109697] IPv6: ADDRCONF(NETDEV_UP):
> > wlan0: link is not ready
> > Oct 19 14:09:40 WxPi dhcpcd[1552]: sending commands to master dhcpcd
> > process
> > Oct 19 14:09:40 WxPi dhcpcd[464]: control command: /sbin/dhcpcd -k eth0
> > Oct 19 14:09:40 WxPi kernel: [  153.318215] smsc95xx 1-1.1:1.0 eth0:
> > hardware isn't capable of remote wakeup
> > Oct 19 14:09:40 WxPi kernel: [  153.398516] smsc95xx 1-1.1:1.0 eth0:
> > hardware isn't capable of remote wakeup
> > Oct 19 14:09:40 WxPi dhcpcd[1574]: sending commands to master dhcpcd
> > process
> > Oct 19 14:09:40 WxPi dhcpcd[464]: control command: /sbin/dhcpcd -k wlan0
> > Oct 19 14:09:40 WxPi kernel: [  153.712882] R8188EU: ERROR indicate
> > disassoc
> > Oct 19 14:09:40 WxPi dhclient: receive_packet failed on wlan0: Network is
> > down
> > Oct 19 14:09:40 WxPi kernel: [  153.730475] IPv6: ADDRCONF(NETDEV_UP):
> > wlan0: link is not ready
> > Oct 19 14:09:40 WxPi dhcpcd[1594]: sending commands to master dhcpcd
> > process
> > Oct 19 14:09:40 WxPi dhcpcd[464]: control command: /sbin/dhcpcd -k eth0
> > Oct 19 14:09:40 WxPi kernel: [  153.944581] smsc95xx 1-1.1:1.0 eth0:
> > hardware isn't capable of remote wakeup
> > Oct 19 14:09:40 WxPi kernel: [  154.018462] smsc95xx 1-1.1:1.0 eth0:
> > hardware isn't capable of remote wakeup
> > Oct 19 14:09:41 WxPi kernel: [  154.198274] smsc95xx 1-1.1:1.0 eth0:
> > hardware isn't capable of remote wakeup
> > Oct 19 14:09:41 WxPi dhcpcd[1616]: sending commands to master dhcpcd
> > process
> > Oct 19 14:09:41 WxPi dhcpcd[464]: control command: /sbin/dhcpcd -k eth0
> > Oct 19 14:09:41 WxPi kernel: [  154.308373] smsc95xx 1-1.1:1.0 eth0:
> > hardware isn't capable of remote wakeup
> > Oct 19 14:09:42 WxPi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255
> > port 67 interval 13
> > Oct 19 14:09:42 WxPi kernel: [  155.967581] smsc95xx 1-1.1:1.0 eth0: link
> > up, 100Mbps, full-duplex, lpa 0xCDE1
> > Oct 19 14:09:43 WxPi dhcpcd[1621]: sending commands to master dhcpcd
> > process
> > Oct 19 14:09:43 WxPi dhcpcd[464]: control command: /sbin/dhcpcd -h WxPi
> > --noipv4ll eth0
> > Oct 19 14:09:43 WxPi dhcpcd[464]: eth0: adding address
> > fe80::e5f3:3df4:4a8c:b8f7
> > Oct 19 14:09:43 WxPi dhcpcd[464]: eth0: IAID eb:19:4a:c7
> > Oct 19 14:09:43 WxPi dhcpcd[464]: eth0: soliciting a DHCP lease
> > Oct 19 14:09:43 WxPi dhcpcd[464]: eth0: offered 192.168.7.55 from
> > 192.168.7.2
> > Oct 19 14:09:44 WxPi dhcpcd[464]: eth0: soliciting an IPv6 router
> > Oct 19 14:09:44 WxPi avahi-daemon[452]: Joining mDNS multicast group on
> > interface eth0.IPv6 with address fe80::e5f3:3df4:4a8c:b8f7.
> > Oct 19 14:09:44 WxPi avahi-daemon[452]: New relevant interface eth0.IPv6
> > for mDNS.
> > Oct 19 14:09:44 WxPi avahi-daemon[452]: Registering new address record
> for
> > fe80::e5f3:3df4:4a8c:b8f7 on eth0.*.
> > Oct 19 14:09:46 WxPi ntpd[598]: Listen normally on 8 eth0
> > fe80::e5f3:3df4:4a8c:b8f7 UDP 123
> > Oct 19 14:09:46 WxPi ntpd[598]: peers refreshed
> > Oct 19 14:09:48 WxPi dhcpcd[1662]: sending commands to master dhcpcd
> > process
> > Oct 19 14:09:48 WxPi rsyslogd-2007: action 'action 17' suspended, next
> > retry is Wed Oct 19 14:10:18 2016 [try http://www.rsyslog.com/e/2007 ]
> > Oct 19 14:09:48 WxPi dhcpcd[464]: control command: /sbin/dhcpcd -k wlan0
> > Oct 19 14:09:48 WxPi dhclient: receive_packet failed on wlan0: Network is
> > down
> > Oct 19 14:09:48 WxPi kernel: [  161.535377] R8188EU: ERROR indicate
> > disassoc
> > Oct 19 14:09:48 WxPi kernel: [  161.559180] IPv6: ADDRCONF(NETDEV_UP):
> > wlan0: link is not ready
> > Oct 19 14:09:48 WxPi dhcpcd[1682]: sending commands to master dhcpcd
> > process
> > Oct 19 14:09:48 WxPi dhcpcd[464]: control command: /sbin/dhcpcd -k eth0
> > Oct 19 14:09:48 WxPi dhcpcd[464]: eth0: removing interface
> > Oct 19 14:09:48 WxPi dhcpcd[464]: eth0: deleting address
> > fe80::e5f3:3df4:4a8c:b8f7
> > Oct 19 14:09:48 WxPi avahi-daemon[452]: Withdrawing address record for
> > fe80::e5f3:3df4:4a8c:b8f7 on eth0.
> > Oct 19 14:09:48 WxPi avahi-daemon[452]: Leaving mDNS multicast group on
> > interface eth0.IPv6 with address fe80::e5f3:3df4:4a8c:b8f7.
> > Oct 19 14:09:48 WxPi avahi-daemon[452]: Interface eth0.IPv6 no longer
> > relevant for mDNS.
> > Oct 19 14:09:48 WxPi kernel: [  161.848160] smsc95xx 1-1.1:1.0 eth0:
> > hardware isn't capable of remote wakeup
> > Oct 19 14:09:48 WxPi kernel: [  161.930581] smsc95xx 1-1.1:1.0 eth0:
> > hardware isn't capable of remote wakeup
> > Oct 19 14:09:50 WxPi ntpd[598]: Deleting interface #8 eth0,
> > fe80::e5f3:3df4:4a8c:b8f7#123, interface stats: received=0, sent=0,
> > dropped=0, active_time=4 secs
> > Oct 19 14:09:50 WxPi ntpd[598]: peers refreshed
> > Oct 19 14:09:50 WxPi kernel: [  163.542314] smsc95xx 1-1.1:1.0 eth0: link
> > up, 100Mbps, full-duplex, lpa 0xCDE1
> > Oct 19 14:09:55 WxPi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255
> > port 67 interval 10
> > Oct 19 14:10:05 WxPi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255
> > port 67 interval 21
> > Oct 19 14:10:26 WxPi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255
> > port 67 interval 5
> > Oct 19 14:10:31 WxPi dhclient: No DHCPOFFERS received.
> > Oct 19 14:10:31 WxPi dhclient: No working leases in persistent database -
> > sleeping.
> > Oct 19 14:10:59 WxPi rsyslogd-2007: action 'action 17' suspended, next
> > retry is Wed Oct 19 14:11:29 2016 [try http://www.rsyslog.com/e/2007 ]
> > Oct 19 14:10:59 WxPi systemd[1]: Started Synchronise Hardware Clock to
> > System Clock.
> > Oct 19 14:10:59 WxPi systemd[1]: Started Turns off Raspberry Pi display
> > backlight on shutdown/reboot.
> >
> > With the modifications eth0 will come back but not reliably. But wlan0
> > never
> > comes back. After a bit the indicator on the USB wifi radio stops
> flashing
> > as I
> > guess it has been removed from operation.
> >
>
> I apologize for not changing the subject line on my first reply. Fixed.
>
> It seems there's not an ipv6 DHCP problem with eth0:
>
> Oct 19 14:09:44 WxPi dhcpcd[464]: eth0: soliciting an IPv6 router
> Oct 19 14:09:44 WxPi avahi-daemon[452]: Joining mDNS multicast group on
> interface eth0.IPv6 with address fe80::e5f3:3df4:4a8c:b8f7.
> Oct 19 14:09:44 WxPi avahi-daemon[452]: New relevant interface eth0.IPv6
> for mDNS.
> Oct 19 14:09:44 WxPi avahi-daemon[452]: Registering new address record for
> fe80::e5f3:3df4:4a8c:b8f7 on eth0.*.
>
> -- All good there.
>
> However, it looks there's a WLAN0 problem:
>
> Oct 19 14:09:29 WxPi wpa_supplicant[562]: wlan0: CTRL-EVENT-DISCONNECTED
> bssid=c4:3d:c7:95:7f:84 reason=3 locally_generated=1
> Oct 19 14:09:29 WxPi dhclient: receive_packet failed on wlan0: Network is
> down
> Oct 19 14:09:29 WxPi kernel: [  142.552261] R8188EU: ERROR indicate
> disassoc
> Oct 19 14:09:29 WxPi kernel: [  142.584187] IPv6: ADDRCONF(NETDEV_UP):
>
> Then it seems to resolve itself:
>
> Oct 19 14:09:55 WxPi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255
> port 67 interval 10
> Oct 19 14:10:05 WxPi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255
> port 67 interval 21
> Oct 19 14:10:26 WxPi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255
> port 67 interval 5
> Oct 19 14:10:31 WxPi dhclient: No DHCPOFFERS received.
> Oct 19 14:10:31 WxPi dhclient: No working leases in persistent database -
> sleeping.
>
> But then WLAN0 can't get a DHCP offer from the DHCP server. I don't have
> any experience with Raspberry Pi, but I doubt if you can run tcpdump on the
> wlan0 interface for DHCP communication on port 67.
> _______________________________________________
> PLUG mailing list
> PLUG at lists.pdxlinux.org
> http://lists.pdxlinux.org/mailman/listinfo/plug
>



-- 

Chuck Hast  -- KP4DJT --
Glass, five thousand years of history and getting better.
The only container material that the USDA gives blanket approval on.



More information about the PLUG mailing list