F11 iwlagn errors on Thinkpad W700
Robert G. (Doc) Savage
dsavage at peaknet.net
Sun Aug 16 22:45:31 UTC 2009
I apologize in advance for what promises to be a lengthy post. Suggest
heavy editing in any replies.
Since installing F11 on my Thinkpad W700 I've had a chronic problem with
the wireless disconnecting after a random time interval. It was rock
solid with F10. I've noticed a number of kernel errors in my e-mail
logs. The most prolific example was yesterday:
---------------------- Kernel Begin -----------------------
WARNING: Kernel Errors Present
iwlagn: Error sending REPLY_SC ...: 1 Time(s)
iwlagn: Error sending REPLY_TX ...: 5 Time(s)
iwlagn: Error sending Sensitiv ...: 1 Time(s)
iwlagn: Microcode SW error detected. Resta ...: 19 Time(s)
---------------------- Kernel End -------------------------
I just now updated to the 2.6.29.6-217.2.7 kernel. --changelog says John
Linville patched iwlwifi in the 217.2.5 kernel on Wednesday to fix a TX
queue race. I pulled my eth0 cable, reset the NetworkManager wireless
connection, and started a ping to my default gateway. The wifi
connection lasted 11 pings, so John's patch didn't fix this problem.
When the ping stopped I saw the following in /var/log/messages:
Aug 16 15:57:10 tiger kernel: iwlagn: Microcode SW error detected. Restarting 0x82000000.
Aug 16 15:57:10 tiger kernel: iwlagn: No space for Tx
Aug 16 15:57:10 tiger kernel: iwlagn: Error sending SENSITIVITY_CMD failed
Aug 16 15:57:10 tiger kernel: iwlagn: SENSITIVITY_CMD failed: -28
Aug 16 15:57:10 tiger kernel: Registered led device: iwl-phy0:radio
Aug 16 15:57:10 tiger kernel: Registered led device; iwl-phy0:assoc
Aug 16 15:57:10 tiger kernel: Registered led device: iwl-phy0:RX
Aug 16 15:57:10 tiger kernel: Registered led device: iwl-phy0:TX
When I click on the NetworkManager button to reset the wireless
connection /var/log/messages reports a very chatty but normal looking
restart:
Aug 16 16:03:46 tiger NetworkManager: <info> (wlan0): device state change: 8 -> 3 (reason 0)
Aug 16 16:03:46 tiger NetworkManager: <info> (wlan0): deactivating device (reason: 0)
Aug 16 16:03:46 tiger NetworkManager: <WARN> check_one_route(): (wlan0) error - 34 returned from rtnl_route_del(): Success#012
Aug 16 16:03:46 tiger avahi-daemon[1901]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.7.
Aug 16 16:03:46 tiger avahi-daemon[1901]: Interface wlan0.IPv4 no longer relevan for mDNS.
Aug 16 16:03:46 tiger NetworkManager: <info> Activation (wlan0) starting connection 'Auto <SSID>'
Aug 16 16:03:46 tiger NetworkManager: <info> (wlan0): device state change: 3 -> 4 (reason 0)
Aug 16 16:03:46 tiger NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Aug 16 16:03:46 tiger NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Aug 16 16:03:46 tiger NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Aug 16 16:03:46 tiger NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Aug 16 16:03:46 tiger NetworkManager: <info> (wlan0): supplicant connection state: completed -> disconnected
Aug 16 16:03:46 tiger NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Aug 16 16:03:46 tiger NetworkManager: <info> (wlan0): device state change: 4 -> 5 (reason 0)
Aug 16 16:03:46 tiger NetworkManager: <info> Activation (wlan0/wireless): connection 'Auto <SSID>' requires no security. No secrets needed.
Aug 16 16:03:46 tiger NetworkManager: <info> Config: added 'ssid' value '<SSID>'
Aug 16 16:03:46 tiger NetworkManager: <info> Config: added 'scan_ssid' value '1'
Aug 16 16:03:46 tiger NetworkManager: <info> Config: added 'key_mgmt' valuen 'NONE'
Aug 16 16:03:46 tiger NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Aug 16 16:03:46 tiger NetworkManager: <info> Config: set interface ap_scan to 1
Aug 16 16:03:46 tiger NetworkManager: <info> (wlan0: supplicant connection state: disconnected -> scanning
Aug 16 16:03:48 tiger ntpd[2263]: Deleting interface #10 wlan0, 192.168.1.7#123, interface stats: received=2, sent=18, dropped-0, active_time=664 secs
Aug 16 16:03:49 tiger NetworkManager: <info> (wlan0): supplicant connection state: scanning -> associating
Aug 16 16:03:49 tiger NetworkManager: <info> (wlan0): supplicant connection state: associating -> associated
Aug 16 16:03:49 tiger NetworkManager: <info> (wlan0): supplicant connection state: associated -> completed
Aug 16 16:03:49 tiger NetworkManager: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network '<SSID>'.
Aug 16 16:03:49 tiger NetworkManager: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Aug 16 16:03:49 tiger NetworkManager: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Aug 16 16:03:49 tiger NetworkManager: <info> (wlan0): device state change: 5 -> 7 (reason 0)
Aug 16 16:03:49 tiger NetworkManager: <info> Activation (wlan0) Beginning DHCP transaction.
Aug 16 16:03:49 tiger NetworkManager: <info> dhclient started with pid 4089
Aug 16 16:03:49 tiger NetworkManager: <info>Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Aug 16 16:03:49 tiger dhclient: Internet Systems Consortium DHCP Client 4.1.0
Aug 16 16:03:49 tiger dhclient: Copyright 2004-2008 Internet Systems Consortium.
Aug 16 16:03:49 tiger dhclient: All rights reserved.
Aug 16 16:03:49 tiger dhclient: For info, please visit http://www.isc.org/sw.dhcp/
Aug 16 16:03:49 tiger dhclient:
Aug 16 16:03:49 tiger NetworkManager: <info> DHCP: device wlan state changed normal exit -> preinit
Aug 16 16:03:49 tiger dhclient: Listening on LPF/wlan0/<MAC>
Aug 16 16:03:49 tiger dhclient: Sending on LPF/wlan0/<MAC>
Aug 16 16:03:49 tiger dhclient: Sending on Socket/fallback
Aug 16 16:03:50 tiger dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
Aug 16 16:03:53 tiger dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
Aug 16 16:03:53 tiger dhclient: DHCPACK from 192.168.1.2
Aug 16 16:03:53 tiger NetworkManager: <info> DHCP: device wlan0 state changed preinit -> reboot
Aug 16 16:03:53 tiger NetworkManager: <info> Activation (wlan0) Stage 4 of 5 (IP configure Get) scheduled...
Aug 16 16:03:53 tiger NetworkManager: <info> Activation (wlan0) Stage 4 of 5 (IP Configure Get) started...
Aug 16 16:03:53 tiger NetworkManager: <info> address 192.168.1.7
Aug 16 16:03:53 tiger NetworkManager: <info> prefix 24 (255.255.255.0)
Aug 16 16:03:53 tiger NetworkManager: <info> gateway 192.168.1.1
Aug 16 16:03:53 tiger NetworkManager: <info> nameserver 'xxx.xxx.xxx.xxx'
Aug 16 16:03:53 tiger NetworkManager: <info> nameserver 'yyy.yyy.yyy.yyy'
Aug 16 16:03:53 tiger NetworkManager: <info> nameserver 'zzz.zzz.zzz.zzz'
Aug 16 16:03:53 tiger NetworkManager: <info> domain name '<privatedomainname>'
Aug 16 16:03:53 tiger NetworkManager: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
Aug 16 16:03:53 tiger NetworkManager: <info> Activation (wlan0) Stage 4 of 5 (IP Configure Get) complete.
Aug 16 16:03:53 tiger NetworkManager: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
Aug 16 16:03:53 tiger avahi-daemon[1901]: Joining mDNS multicast group on interface wlan0.IPv4 with 192.168.1.7.
Aug 16 16:03:53 tiger avahi-daemon[1901]: Registering new address record for 192.168.1.7 on wlan0.IPv4.
Aug 16 16:03:53 tiger dhclient: bound to 192.168.1.7 -- renewal in 2727 seconds.
Aug 16 16:03:54 tiger NetworkManager: <info> (wlan0): device state change: 7 -> 8 (reason 0)
Aug 16 16:03:54 tiger NetworkManager: <info> Policy set 'Auto <SSID>' (wlan0) as default for routing and DNS.
Aug 16 16:03:54 tiger NetworkManager: <info> Activation (wlan0) successful, device activated.
Aug 16 16:03:54 tiger NetworkManager: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
Aug 16 16:03:54 tiger ntpd02263]: Listening on interface #11 wlan0, 192.168.1.7#123 Enabled
About a half hour later the connection failed with the same log entries:
Aug 16 16:45:25 tiger kernel: iwlagn: Microcode SW error detected. Restarting 0x82000000.
Aug 16 16:45:25 tiger kernel: iwlagn: No space for Tx
Aug 16 16:45:25 tiger kernel: iwlagn: Error sending Sensitivity_CMD: enqueue_hcmd failed: -28
Aug 16 16:45:25 tiger kernel: iwlagn: SENSITIVITY_CMD failed
Aug 16 16:45:25 tiger kernel: Registered led device: iwl-phy0:radio
Aug 16 16:45:25 tiger kernel: Registered led device: iwl-phy0:assoc
Aug 16 16:45:25 tiger kernel: Registered led device: iwl-phy0:RX
Aug 16 16:45:25 tiger kernel: Registered led device: iwl-phy0:TX
Has anyone else running seen this misbehavior on a W700 running F11?
--Doc Savage
Fairview Heights, IL
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://listman.redhat.com/archives/fedora-list/attachments/20090816/98a53eee/attachment-0001.htm>
More information about the fedora-list
mailing list