FS#23767 - [kernel26] Flaky WLAN connection with iwlagn 5100

Attached to Project: Arch Linux
Opened by Frank Thieme (fthieme) - Thursday, 14 April 2011, 21:38 GMT
Last edited by Tobias Powalowski (tpowa) - Tuesday, 07 June 2011, 06:19 GMT
Task Type Bug Report
Category Packages: Core
Status Closed
Assigned To Tobias Powalowski (tpowa)
Thomas Bächler (brain0)
Architecture x86_64
Severity Medium
Priority Normal
Reported Version
Due in Version Undecided
Due Date Undecided
Percent Complete 100%
Votes 0
Private No

Details

Description: My WLAN connection is dropped from time to time. Some day I just can use wired LAN, on some days there is no loss at all. This never occured on Chakra, what I was using some months ago.

Yesterday I had nearly never a useful connection. I tried a live CD of CentOS 5.6 and had a stable connection for over 30 minutes, until I rebooted in my Archlinux system.


Additional info:

You can see lines like this in messages.log

Apr 14 22:32:31 localhost NetworkManager[1655]: <info> wpa_supplicant started
Apr 14 22:32:41 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: init -> starting
Apr 14 22:32:41 localhost NetworkManager[1655]: <info> wpa_supplicant die count reset
Apr 14 22:32:41 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: starting -> ready
Apr 14 22:32:41 localhost NetworkManager[1655]: <info> (wlan0): device state change: 20 -> 30 (reason 42)
Apr 14 22:32:41 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: ready -> inactive
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) starting connection 'crazy (Auto)'
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> (wlan0): device state change: 30 -> 40 (reason 0)
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> (wlan0): device state change: 40 -> 50 (reason 0)
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0/wireless): access point 'crazy (Auto)' has security, but secrets are required.
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> (wlan0): device state change: 50 -> 60 (reason 0)
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> (wlan0): device state change: 60 -> 40 (reason 0)
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> (wlan0): device state change: 40 -> 50 (reason 0)
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0/wireless): connection 'crazy (Auto)' has security, and secrets exist. No new secrets needed.
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Config: added 'ssid' value 'crazy'
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Config: added 'scan_ssid' value '1'
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Config: added 'psk' value '<omitted>'
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> Config: set interface ap_scan to 1
Apr 14 22:32:45 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: inactive -> scanning
Apr 14 22:32:47 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: scanning -> associating
Apr 14 22:32:47 localhost kernel: cfg80211: Calling CRDA for country: DE
Apr 14 22:32:47 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: associating -> associated
Apr 14 22:32:47 localhost kernel: cfg80211: Regulatory domain changed to country: DE
Apr 14 22:32:47 localhost kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 14 22:32:47 localhost kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm)
Apr 14 22:32:47 localhost kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Apr 14 22:32:47 localhost kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Apr 14 22:32:47 localhost kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 40000 KHz), (N/A, 2698 mBm)
Apr 14 22:32:48 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Apr 14 22:32:48 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
Apr 14 22:32:48 localhost NetworkManager[1655]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'crazy'.
Apr 14 22:32:48 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Apr 14 22:32:48 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Apr 14 22:32:48 localhost NetworkManager[1655]: <info> (wlan0): device state change: 50 -> 70 (reason 0)
Apr 14 22:32:48 localhost NetworkManager[1655]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Apr 14 22:32:48 localhost NetworkManager[1655]: <info> dhcpcd started with pid 18396
Apr 14 22:32:48 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Apr 14 22:32:48 localhost dhcpcd[18396]: version 5.2.12 starting
Apr 14 22:32:48 localhost NetworkManager[1655]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
Apr 14 22:32:48 localhost dhcpcd[18396]: wlan0: rebinding lease of 192.168.42.2
Apr 14 22:32:48 localhost dhcpcd[18396]: wlan0: acknowledged 192.168.42.2 from 192.168.42.1
Apr 14 22:32:48 localhost dhcpcd[18396]: wlan0: checking for 192.168.42.2
Apr 14 22:32:53 localhost dhcpcd[18396]: wlan0: leased 192.168.42.2 for 444000 seconds
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> (wlan0): DHCPv4 state changed preinit -> bound
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> address 192.168.42.2
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> prefix 24 (255.255.255.0)
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> gateway 192.168.42.1
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> hostname 'stars'
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> nameserver '192.168.42.1'
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> domain name 'fthieme.local'
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
Apr 14 22:32:53 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Apr 14 22:32:54 localhost NetworkManager[1655]: <info> (wlan0): device state change: 70 -> 100 (reason 0)
Apr 14 22:32:54 localhost NetworkManager[1655]: <info> Policy set 'crazy (Auto)' (wlan0) as default for IPv4 routing and DNS.
Apr 14 22:32:54 localhost NetworkManager[1655]: <info> Activation (wlan0) successful, device activated.
Apr 14 22:32:54 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Apr 14 22:33:47 localhost NetworkManager[1655]: <info> wpa_supplicant stopped
Apr 14 22:33:47 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: completed -> down
Apr 14 22:33:47 localhost NetworkManager[1655]: <info> (wlan0): device state change: 100 -> 20 (reason 10)
Apr 14 22:33:47 localhost NetworkManager[1655]: <info> (wlan0): deactivating device (reason: 10).
Apr 14 22:33:47 localhost dhcpcd[18396]: received SIGTERM, stopping
Apr 14 22:33:47 localhost dhcpcd[18396]: wlan0: removing interface
Apr 14 22:33:47 localhost NetworkManager[1655]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 18396
Apr 14 22:33:47 localhost NetworkManager[1655]: <warn> (pid 18396) unhandled DHCP event for interface wlan0
Apr 14 22:33:47 localhost NetworkManager[1655]: <info> wpa_supplicant started
Apr 14 22:33:47 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: starting -> ready
Apr 14 22:33:47 localhost NetworkManager[1655]: <info> (wlan0): device state change: 20 -> 30 (reason 42)
Apr 14 22:33:47 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: ready -> inactive
Apr 14 22:33:54 localhost kernel: cfg80211: Calling CRDA to update world regulatory domain
Apr 14 22:33:54 localhost NetworkManager[1655]: <info> wpa_supplicant stopped
Apr 14 22:33:54 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: inactive -> down
Apr 14 22:33:54 localhost NetworkManager[1655]: <info> (wlan0): device state change: 30 -> 20 (reason 10)
Apr 14 22:33:54 localhost NetworkManager[1655]: <info> (wlan0): deactivating device (reason: 10).
Apr 14 22:33:54 localhost kernel: cfg80211: World regulatory domain updated:
Apr 14 22:33:54 localhost kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 14 22:33:54 localhost kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 14 22:33:54 localhost kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 14 22:33:54 localhost kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Apr 14 22:33:54 localhost kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 14 22:33:54 localhost kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Apr 14 22:33:54 localhost kernel: cfg80211: Calling CRDA for country: DE
Apr 14 22:33:54 localhost NetworkManager[1655]: <info> wpa_supplicant started
Apr 14 22:33:54 localhost kernel: cfg80211: Regulatory domain changed to country: DE
Apr 14 22:33:54 localhost kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Apr 14 22:33:54 localhost kernel: cfg80211: (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm)
Apr 14 22:33:54 localhost kernel: cfg80211: (5150000 KHz - 5250000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Apr 14 22:33:54 localhost kernel: cfg80211: (5250000 KHz - 5350000 KHz @ 40000 KHz), (N/A, 2000 mBm)
Apr 14 22:33:54 localhost kernel: cfg80211: (5470000 KHz - 5725000 KHz @ 40000 KHz), (N/A, 2698 mBm)
Apr 14 22:33:54 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: starting -> ready
Apr 14 22:33:54 localhost NetworkManager[1655]: <info> (wlan0): device state change: 20 -> 30 (reason 42)
Apr 14 22:33:54 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: ready -> inactive
Apr 14 22:33:58 localhost NetworkManager[1655]: <info> wpa_supplicant stopped
Apr 14 22:33:58 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: inactive -> down
Apr 14 22:33:58 localhost NetworkManager[1655]: <info> (wlan0): device state change: 30 -> 20 (reason 10)
Apr 14 22:33:58 localhost NetworkManager[1655]: <info> (wlan0): deactivating device (reason: 10).
Apr 14 22:33:58 localhost NetworkManager[1655]: <info> wpa_supplicant die count 3; ignoring for 10 seconds
Apr 14 22:33:58 localhost NetworkManager[1655]: <info> wpa_supplicant started
Apr 14 22:34:08 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: init -> starting
Apr 14 22:34:08 localhost NetworkManager[1655]: <info> wpa_supplicant die count reset
Apr 14 22:34:08 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: starting -> ready
Apr 14 22:34:08 localhost NetworkManager[1655]: <info> (wlan0): device state change: 20 -> 30 (reason 42)
Apr 14 22:34:08 localhost NetworkManager[1655]: <info> (wlan0): supplicant interface state: ready -> inactive
Apr 14 22:34:12 localhost NetworkManager[1655]: <info> Activation (wlan0) starting connection 'crazy (Auto)'
Apr 14 22:34:12 localhost NetworkManager[1655]: <info> (wlan0): device state change: 30 -> 40 (reason 0)
Apr 14 22:34:12 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Apr 14 22:34:12 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Apr 14 22:34:12 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Apr 14 22:34:12 localhost NetworkManager[1655]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.

and so on...


Steps to reproduce:
This task depends upon

Closed by  Tobias Powalowski (tpowa)
Tuesday, 07 June 2011, 06:19 GMT
Reason for closing:  No response
Comment by Jelle van der Waa (jelly) - Friday, 15 April 2011, 07:29 GMT
this is probably an upstream bug, try to report it upstream. And please put you're dmesg output as an attachment.
Comment by Tobias Powalowski (tpowa) - Saturday, 21 May 2011, 06:17 GMT
Does the latest firmware package fix your problem?

Loading...