WLAN hangs after about 20 hours
Affects | Status | Importance | Assigned to | Milestone | |
---|---|---|---|---|---|
Raspbian |
Fix Released
|
Undecided
|
Unassigned |
Bug Description
Hi,
I'm using wheezy installed on my second, new Pi, Rev. B 512 MB, bought several days ago.
The Pi is connected using an active hub, 2500 mA Powersupply, running with only a WLAN Adaptor (Logilink WL0084B) attached.
No screen, no mouse, no keyboard ...
It's running the current wheezy (Linux raspberrypi 3.6.11+ #456 PREEMPT Mon May 20 17:42:15 BST 2013 armv6l GNU/Linux).
The Pi has no stress at all, just hanging around.
After about 20 or some more or less hours, I get an error when trying to connect unsing rlogin -> "No route to host".
I saved the syslog, restarted the Pi.
And again ... about a day later, I was no longer able to login -> "No route to host".
Checking syslog, there are a lot of error messages - much of them repeating. syslog has about 6 MB.
I detected this problem earlier with my other one. But because I needed it, I did not reproduce this strange behaviour and connected it via LAN.
Now the other Pi is idle and I wanted to use the time to find out what happened.
I paste a part of the syslog here. The whole syslog is in the attachment.
I hope, I could support you .... and sorry for my poor english - I'm bavarian ;) ...
Regards,
-ds-
aka dreamshader
Jun 15 04:41:01 raspberrypi dhclient: DHCPACK from 192.168.1.100
Jun 15 04:41:02 raspberrypi avahi-daemon[2276]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.113.
Jun 15 04:41:02 raspberrypi avahi-daemon[2276]: New relevant interface wlan0.IPv4 for mDNS.
Jun 15 04:41:02 raspberrypi avahi-daemon[2276]: Registering new address record for 192.168.1.113 on wlan0.IPv4.
Jun 15 04:41:02 raspberrypi dhclient: bound to 192.168.1.113 -- renewal in 776219585 seconds.
Jun 15 04:41:03 raspberrypi wpa_action: creating sendsigs omission pidfile: /run/sendsigs.
Jun 15 04:41:03 raspberrypi wpa_action: bssid=00:
Jun 15 04:41:03 raspberrypi wpa_action: ssid=TP-LINK
Jun 15 04:41:03 raspberrypi wpa_action: id=0
Jun 15 04:41:03 raspberrypi wpa_action: mode=station
Jun 15 04:41:03 raspberrypi wpa_action: pairwise_
Jun 15 04:41:03 raspberrypi wpa_action: group_cipher=TKIP
Jun 15 04:41:03 raspberrypi wpa_action: key_mgmt=WPA-PSK
Jun 15 04:41:03 raspberrypi wpa_action: wpa_state=COMPLETED
Jun 15 04:41:03 raspberrypi wpa_action: ip_address=
Jun 15 04:41:03 raspberrypi wpa_action: address=
Jun 15 04:41:03 raspberrypi wpa_action: WPA_IFACE=wlan0 WPA_ACTION=
Jun 15 04:41:03 raspberrypi wpa_action: WPA_ID=0 WPA_ID_STR= WPA_CTRL_
Jun 15 04:41:03 raspberrypi wpa_action: ifdown wlan0
Jun 15 04:41:03 raspberrypi dhclient: Internet Systems Consortium DHCP Client 4.2.2
Jun 15 04:41:03 raspberrypi dhclient: Copyright 2004-2011 Internet Systems Consortium.
Jun 15 04:41:03 raspberrypi dhclient: All rights reserved.
Jun 15 04:41:03 raspberrypi dhclient: For info, please visit https:/
Jun 15 04:41:03 raspberrypi dhclient:
Jun 15 04:41:03 raspberrypi ntpd[2399]: Listen normally on 106 wlan0 192.168.1.113 UDP 123
Jun 15 04:41:03 raspberrypi ntpd[2399]: peers refreshed
Jun 15 04:41:04 raspberrypi dhclient: Listening on LPF/wlan0/
Jun 15 04:41:04 raspberrypi dhclient: Sending on LPF/wlan0/
Jun 15 04:41:04 raspberrypi dhclient: Sending on Socket/fallback
Jun 15 04:41:05 raspberrypi dhclient: DHCPRELEASE on wlan0 to 192.168.1.100 port 67
Jun 15 04:41:05 raspberrypi avahi-daemon[2276]: Withdrawing address record for 192.168.1.113 on wlan0.
Jun 15 04:41:05 raspberrypi avahi-daemon[2276]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.113.
Jun 15 04:41:05 raspberrypi avahi-daemon[2276]: Interface wlan0.IPv4 no longer relevant for mDNS.
Jun 15 04:41:06 raspberrypi kernel: [11526.593034] wlan0: deauthenticating from 00:14:78:ad:bb:de by local choice (reason=3)
Jun 15 04:41:06 raspberrypi wpa_supplicant[
Jun 15 04:41:06 raspberrypi kernel: [11526.630702] cfg80211: Calling CRDA for country: CN
Jun 15 04:41:06 raspberrypi wpa_action: removing sendsigs omission pidfile: /run/sendsigs.
Jun 15 04:41:06 raspberrypi wpa_action: WPA_IFACE=wlan0 WPA_ACTION=
Jun 15 04:41:06 raspberrypi wpa_action: WPA_ID=0 WPA_ID_STR= WPA_CTRL_
Jun 15 04:41:06 raspberrypi wpa_action: ifup wlan0=default
Jun 15 04:41:06 raspberrypi ifplugd(
Jun 15 04:41:07 raspberrypi dhclient: Internet Systems Consortium DHCP Client 4.2.2
Jun 15 04:41:07 raspberrypi dhclient: Copyright 2004-2011 Internet Systems Consortium.
Jun 15 04:41:07 raspberrypi dhclient: All rights reserved.
Jun 15 04:41:07 raspberrypi dhclient: For info, please visit https:/
Jun 15 04:41:07 raspberrypi dhclient:
Jun 15 04:41:07 raspberrypi dhclient: Listening on LPF/wlan0/
Jun 15 04:41:07 raspberrypi dhclient: Sending on LPF/wlan0/
Jun 15 04:41:07 raspberrypi dhclient: Sending on Socket/fallback
Jun 15 04:41:07 raspberrypi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 3
Jun 15 04:41:07 raspberrypi ntpd[2399]: Deleting interface #106 wlan0, 192.168.1.113#123, interface stats: received=3, sent=3, dropped=1, active_time=4 secs
Jun 15 04:41:07 raspberrypi ntpd[2399]: 217.115.155.125 interface 192.168.1.113 -> (none)
Jun 15 04:41:07 raspberrypi ntpd[2399]: 178.63.9.212 interface 192.168.1.113 -> (none)
Jun 15 04:41:07 raspberrypi ntpd[2399]: 176.9.92.196 interface 192.168.1.113 -> (none)
Jun 15 04:41:07 raspberrypi ntpd[2399]: 176.9.47.150 interface 192.168.1.113 -> (none)
Jun 15 04:41:07 raspberrypi ntpd[2399]: peers refreshed
Jun 15 04:41:08 raspberrypi wpa_supplicant[
Jun 15 04:41:08 raspberrypi kernel: [11528.770334] wlan0: authenticate with 00:14:78:ad:bb:de
Jun 15 04:41:08 raspberrypi wpa_supplicant[
Jun 15 04:41:08 raspberrypi kernel: [11528.856251] wlan0: send auth to 00:14:78:ad:bb:de (try 1/3)
Jun 15 04:41:08 raspberrypi kernel: [11528.858643] wlan0: authenticated
Jun 15 04:41:08 raspberrypi kernel: [11528.860586] rt2800usb 1-1.3.2:1.0: wlan0: disabling HT due to WEP/TKIP use
Jun 15 04:41:08 raspberrypi kernel: [11528.905531] wlan0: associate with 00:14:78:ad:bb:de (try 1/3)
Jun 15 04:41:08 raspberrypi kernel: [11528.920610] wlan0: RX AssocResp from 00:14:78:ad:bb:de (capab=0x431 status=0 aid=4)
Jun 15 04:41:08 raspberrypi wpa_supplicant[
Jun 15 04:41:08 raspberrypi kernel: [11528.936957] wlan0: associated
Jun 15 04:41:09 raspberrypi ifplugd(
Jun 15 04:41:10 raspberrypi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 3
Jun 15 04:41:13 raspberrypi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 4
Jun 15 04:41:17 raspberrypi dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 11
Jun 15 04:41:18 raspberrypi wpa_supplicant[
Jun 15 04:41:18 raspberrypi kernel: [11538.941236] wlan0: disassociating from 00:14:78:ad:bb:de by local choice (reason=3)
Jun 15 04:41:18 raspberrypi kernel: [11538.976148] cfg80211: Calling CRDA to update world regulatory domain
Jun 15 04:41:18 raspberrypi wpa_supplicant[
Jun 15 04:41:18 raspberrypi wpa_supplicant[
Jun 15 04:41:18 raspberrypi kernel: [11538.988858] wlan0: deauthenticating from 00:14:78:ad:bb:de by local choice (reason=3)
Jun 15 04:41:19 raspberrypi ifplugd(
Jun 15 04:41:20 raspberrypi wpa_supplicant[
Jun 15 04:41:20 raspberrypi kernel: [11540.950768] wlan0: authenticate with 00:14:78:ad:bb:de
Jun 15 04:41:20 raspberrypi wpa_supplicant[
Jun 15 04:41:20 raspberrypi kernel: [11541.036653] wlan0: send auth to 00:14:78:ad:bb:de (try 1/3)
Jun 15 04:41:20 raspberrypi kernel: [11541.038376] wlan0: authenticated
Jun 15 04:41:20 raspberrypi kernel: [11541.040278] rt2800usb 1-1.3.2:1.0: wlan0: disabling HT due to WEP/TKIP use
Jun 15 04:41:20 raspberrypi kernel: [11541.085871] wlan0: associate with 00:14:78:ad:bb:de (try 1/3)
Jun 15 04:41:20 raspberrypi kernel: [11541.088708] wlan0: RX AssocResp from 00:14:78:ad:bb:de (capab=0x431 status=0 aid=5)
Jun 15 04:41:20 raspberrypi kernel: [11541.105744] wlan0: associated
Jun 15 04:41:20 raspberrypi wpa_supplicant[
Jun 15 04:41:21 raspberrypi ifplugd(
Jun 15 04:41:21 raspberrypi kernel: [11542.059156] ------------[ cut here ]------------
Jun 15 04:41:21 raspberrypi kernel: [11542.059215] WARNING: at net/core/
Jun 15 04:41:21 raspberrypi kernel: [11542.059244] Unknown code:44104 jt:157 tf:218 k:12
Jun 15 04:41:21 raspberrypi kernel: [11542.059253] Modules linked in: i2c_dev snd_bcm2835 snd_pcm snd_seq snd_timer snd_seq_device snd snd_page_alloc spidev arc4 rt2800usb rt2800lib crc_ccitt rt2x00usb rt2x00lib mac80211 cfg80211 rfkill leds_gpio led_class i2c_bcm2708 spi_bcm2708
Jun 15 04:41:21 raspberrypi kernel: [11542.059383] [<c0013a7c>] (unwind_
Jun 15 04:41:21 raspberrypi kernel: [11542.059413] [<c001e2b4>] (warn_slowpath_
Jun 15 04:41:21 raspberrypi kernel: [11542.059439] [<c001e360>] (warn_slowpath_
Jun 15 04:41:21 raspberrypi kernel: [11542.059463] [<c030343c>] (sk_run_
Jun 15 04:41:21 raspberrypi kernel: [11542.059501] [<c0303904>] (sk_filter+
Jun 15 04:41:21 raspberrypi kernel: [11542.059535] [<c02e19e8>] (sock_queue_
Jun 15 04:41:21 raspberrypi kernel: [11542.059575] [<c036f9cc>] (packet_
Jun 15 04:41:21 raspberrypi kernel: [11542.059893] [<c02eead0>] (__netif_
Jun 15 04:41:21 raspberrypi kernel: [11542.060252] [<bf070a40>] (ieee80211_
Jun 15 04:41:21 raspberrypi kernel: [11542.060614] [<bf0718c8>] (ieee80211_
Jun 15 04:41:21 raspberrypi kernel: [11542.060975] [<bf073124>] (ieee80211_
Jun 15 04:41:21 raspberrypi kernel: [11542.061239] [<bf073e30>] (ieee80211_
Jun 15 04:41:21 raspberrypi kernel: [11542.061331] [<bf0b32bc>] (rt2x00lib_
Jun 15 04:41:21 raspberrypi kernel: [11542.061386] [<bf0c53d0>] (rt2x00usb_
Jun 15 04:41:21 raspberrypi kernel: [11542.061413] [<c0035010>] (process_
Jun 15 04:41:21 raspberrypi kernel: [11542.061445] [<c00355f0>] (worker_
Jun 15 04:41:21 raspberrypi kernel: [11542.061482] [<c003a7b4>] (kthread+0x88/0x94) from [<c000e9fc>] (kernel_
Jun 15 04:41:21 raspberrypi kernel: [11542.061507] ---[ end trace ff4653297fad5ace ]---
Jun 15 04:41:21 raspberrypi kernel: [11542.070162] ------------[ cut here ]------------
Jun 15 04:41:21 raspberrypi kernel: [11542.070223] WARNING: at net/core/
Jun 15 04:41:21 raspberrypi kernel: [11542.070252] Unknown code:44104 jt:157 tf:218 k:12
Jun 15 04:41:21 raspberrypi kernel: [11542.070262] Modules linked in: i2c_dev snd_bcm2835 snd_pcm snd_seq snd_timer snd_seq_device snd snd_page_alloc spidev arc4 rt2800usb rt2800lib crc_ccitt rt2x00usb rt2x00lib mac80211 cfg80211 rfkill leds_gpio led_class i2c_bcm2708 spi_bcm2708
Jun 15 04:41:21 raspberrypi kernel: [11542.070392] [<c0013a7c>] (unwind_
Jun 15 04:41:21 raspberrypi kernel: [11542.070421] [<c001e2b4>] (warn_slowpath_
Jun 15 04:41:21 raspberrypi kernel: [11542.070447] [<c001e360>] (warn_slowpath_
Jun 15 04:41:21 raspberrypi kernel: [11542.070471] [<c030343c>] (sk_run_
Jun 15 04:41:21 raspberrypi kernel: [11542.070510] [<c0303904>] (sk_filter+
Jun 15 04:41:21 raspberrypi kernel: [11542.070546] [<c02e19e8>] (sock_queue_
Jun 15 04:41:21 raspberrypi kernel: [11542.070577] [<c036f9cc>] (packet_
Jun 15 04:41:21 raspberrypi kernel: [11542.070627] [<c02f2f28>] (dev_hard_
Jun 15 04:41:21 raspberrypi kernel: [11542.070657] [<c0309150>] (sch_direct_
Jun 15 04:41:21 raspberrypi kernel: [11542.070685] [<c02f35b4>] (dev_queue_
Jun 15 04:41:21 raspberrypi kernel: [11542.070720] [<c0370f0c>] (packet_
Jun 15 04:41:21 raspberrypi kernel: [11542.070761] [<c02ddf60>] (sock_sendmsg+
Jun 15 04:41:21 raspberrypi kernel: [11542.070797] [<c02dfa38>] (sys_sendto+
Jun 15 04:41:21 raspberrypi kernel: [11542.070810] ---[ end trace ff4653297fad5acf ]---
I had a similar result, with a Pi I left running just doing intermittent wgets. Except it crashed after 4 days, not 20 hours. Same symptom -- stream of messages to syslog -- and the same line in net/core/filter.c but different actual message.
Firmware version:
$ uname -a
Linux rasputin 3.6.11+ #462 PREEMPT Mon Jun 3 22:15:00 BST 2013 armv6l GNU/Linux
I had 85 Megs of log errors before I noticed. I won't post them all here! However, a typical chunk of them follows:
Jun 26 06:25:06 rasputin rsyslogd: [origin software="rsyslogd" swVersion="5.8.11" x-pid="1921" x-info="http:// www.rsyslog. com"] rsyslogd was HUPed filter. c:375 sk_run_ filter+ 0x148/0x5dc( ) backtrace+ 0x0/0xf0) from [<c001e2b4>] (warn_slowpath_ common+ 0x4c/0x64) common+ 0x4c/0x64) from [<c001e360>] (warn_slowpath_ fmt+0x30/ 0x40) fmt+0x30/ 0x40) from [<c030343c>] (sk_run_ filter+ 0x148/0x5dc) filter+ 0x148/0x5dc) from [<c0303904>] (sk_filter+ 0x34/0xa4) 0x34/0xa4) from [<c02e19e8>] (sock_queue_ rcv_skb+ 0x24/0x1e4) rcv_skb+ 0x24/0x1e4) from [<c036f9cc>] (packet_ rcv_spkt+ 0x140/0x168) rcv_spkt+ 0x140/0x168) from [<c02eead0>] (__netif_ receive_ skb+0x5d0/ 0x868) receive_ skb+0x5d0/ 0x868) from [<bf067a40>] (ieee80211_ deliver_ skb+0x64/ 0x17c [mac80211]) deliver_ skb+0x64/ 0x17c [mac80211]) from [<bf0688c8>] (ieee80211_ rx_handlers+ 0xaec/0x2064 [mac80211]) rx_handlers+ 0xaec/0x2064 [mac80211]) from [<bf06a124>] (ieee80211_ prepare_ and_rx_ handle+ 0x2e4/0x93c [mac80211]) prepare_ and_rx_ handle+ 0x2e4/0x93c [mac80211]) from [<bf06ae30>] (ieee80211_ rx+0x6b4/ 0x778 [mac80211]) rx+0x6b4/ 0x778 [mac80211]) from [<bf0aa2bc>] (rt2x00lib_ rxdone+ 0x1f4/0x450 [rt2x00lib]) rxdone+ 0x1f4/0x450 [rt2x00lib]) from [<bf0bc3d0>] (rt2x00usb_work_...
Jun 25 06:25:07 rasputin kernel: [289387.487375] sk_run_filter: 7 callbacks suppressed
Jun 25 06:25:07 rasputin kernel: [289387.487415] ------------[ cut here ]------------
Jun 25 06:25:07 rasputin kernel: [289387.487450] WARNING: at net/core/
Jun 25 06:25:07 rasputin kernel: [289387.487463] Unknown code:49224 jt:152 tf:218 k:12
Jun 25 06:25:07 rasputin kernel: [289387.487470] Modules linked in: snd_bcm2835 snd_pcm snd_page_alloc snd_seq snd_seq_device snd_timer snd arc4 rt2800usb rt2800lib rt2x00usb rt2x00lib mac80211 cfg80211 rfkill crc_ccitt leds_gpio led_class
Jun 25 06:25:07 rasputin kernel: [289387.487700] [<c0013a7c>] (unwind_
Jun 25 06:25:07 rasputin kernel: [289387.487731] [<c001e2b4>] (warn_slowpath_
Jun 25 06:25:07 rasputin kernel: [289387.487759] [<c001e360>] (warn_slowpath_
Jun 25 06:25:07 rasputin kernel: [289387.487796] [<c030343c>] (sk_run_
Jun 25 06:25:07 rasputin kernel: [289387.487822] [<c0303904>] (sk_filter+
Jun 25 06:25:07 rasputin kernel: [289387.487854] [<c02e19e8>] (sock_queue_
Jun 25 06:25:07 rasputin kernel: [289387.487896] [<c036f9cc>] (packet_
Jun 25 06:25:07 rasputin kernel: [289387.488215] [<c02eead0>] (__netif_
Jun 25 06:25:07 rasputin kernel: [289387.488580] [<bf067a40>] (ieee80211_
Jun 25 06:25:07 rasputin kernel: [289387.488940] [<bf0688c8>] (ieee80211_
Jun 25 06:25:07 rasputin kernel: [289387.489303] [<bf06a124>] (ieee80211_
Jun 25 06:25:07 rasputin kernel: [289387.489565] [<bf06ae30>] (ieee80211_
Jun 25 06:25:07 rasputin kernel: [289387.489657] [<bf0aa2bc>] (rt2x00lib_