Bug in intel wireless module freezes the machine

[dcengija]

I'm pretty sure that this bug still persists in 11.2RC2, ever since 10.3 (and some kernel update) on the same box:

https://bugzilla.novell.com/show_bug.cgi?id=402100

https://bugzilla.redhat.com/show_bug.cgi?id=514033
[ubuntu] [SOLVED] 8.10 randomly freezing caps lock blinking flashing - Ubuntu Forums

It is related to iwlwifi-4965 on Lenovo Thinkpads (mine is R61)

Has anyone experienced similar problem?

Basically, while on wireless network, the machine freezes with caps lock led blinking (sign of kernel panic). I have this problem where we have 802.11n network, and not at home where I have 802.11g.

Any ideas?

[lwfinger]

On 11/04/2009 02:56 AM, dcengija wrote:
>
> I'm pretty sure that this bug still persists in 11.2RC2, ever since 10.3
> (and some kernel update) on the same box:
>
> https://bugzilla.novell.com/show_bug.cgi?id=402100
>
> https://bugzilla.redhat.com/show_bug.cgi?id=514033
> '[ubuntu] [SOLVED] 8.10 randomly freezing caps lock blinking flashing -
> Ubuntu Forums' (http://ubuntuforums.org/showthread.php?t=968792)
>
> It is related to iwlwifi-4965 on Lenovo Thinkpads (mine is R61)
>
> Has anyone experienced similar problem?
>
> Basically, while on wireless network, the machine freezes with caps
> lock led blinking (sign of kernel panic). I have this problem where we
> have 802.11n network, and not at home where I have 802.11g.

Whatever the fix was in the Ubuntu thread is certainly in the 2.6.31
kernel in 11.2, thus you would seem to have a different problem. The
same thing is true for the Novell thread.

You need to capture some info on the panic. If you press the CTRL,
ALT, and F10 keys at the same time, you will switch to a console log
screen. When the panic occurs, you will get a dump there.
Unfortunately, it is not possible to get a machine-readable version of
that info unless you use netconsole, which requires a wired connection
as well. You need to use pen and paper. If you want to get back to the
regular GUI console, use CTRL/ALT/F7.

[dcengija]

Originally Posted by lwfinger

On 11/04/2009 02:56 AM, dcengija wrote:
>
> I'm pretty sure that this bug still persists in 11.2RC2, ever since 10.3
> (and some kernel update) on the same box:
>
> https://bugzilla.novell.com/show_bug.cgi?id=402100
>
> https://bugzilla.redhat.com/show_bug.cgi?id=514033
> '[ubuntu] [SOLVED] 8.10 randomly freezing caps lock blinking flashing -
> Ubuntu Forums' ([ubuntu] [SOLVED] 8.10 randomly freezing caps lock blinking flashing - Ubuntu Forums)
>
> It is related to iwlwifi-4965 on Lenovo Thinkpads (mine is R61)
>
> Has anyone experienced similar problem?
>
> Basically, while on wireless network, the machine freezes with caps
> lock led blinking (sign of kernel panic). I have this problem where we
> have 802.11n network, and not at home where I have 802.11g.

Whatever the fix was in the Ubuntu thread is certainly in the 2.6.31
kernel in 11.2, thus you would seem to have a different problem. The
same thing is true for the Novell thread.

You need to capture some info on the panic. If you press the CTRL,
ALT, and F10 keys at the same time, you will switch to a console log
screen. When the panic occurs, you will get a dump there.
Unfortunately, it is not possible to get a machine-readable version of
that info unless you use netconsole, which requires a wired connection
as well. You need to use pen and paper. If you want to get back to the
regular GUI console, use CTRL/ALT/F7.

Thank you for your reply. I don't know if this problem is the same as explained in the linked URLs, or another one, but the manifestation (and end result) is the same.

What I have noticed is this:

linux-82ra:/home/davor # modprobe -l *iwl*
kernel/drivers/net/wireless/iwlwifi/iwlcore.ko
kernel/drivers/net/wireless/iwlwifi/iwlagn.ko
kernel/drivers/net/wireless/iwlwifi/iwl3945.ko

linux-82ra:/home/davor # lsmod | grep iwl
iwlagn                180704  0
iwlcore               204964  1 iwlagn
mac80211              217656  2 iwlagn,iwlcore
cfg80211              103424  3 iwlagn,iwlcore,mac80211

e.g. iwl3945 module is loaded. However, /var/log/messages shows something different:

* there's no mention of iwl3945
* iwl-4965 is recognized and loaded

linux-82ra:/var/log # grep iwl.*4965 messages
[...]
Oct 29 07:46:59 linux-82ra kernel: [   24.951056] iwlagn 0000:03:00.0: firmware: requesting iwlwifi-4965-2.ucode
Oct 29 09:44:32 linux-82ra kernel: [   29.816934] iwlagn 0000:03:00.0: firmware: requesting iwlwifi-4965-2.ucode
Oct 29 19:21:08 linux-82ra kernel: [12321.007996] iwlagn 0000:03:00.0: Detected Intel Wireless WiFi Link 4965AGN REV=0x4
Oct 29 19:21:13 linux-82ra kernel: [12325.853087] iwlagn 0000:03:00.0: firmware: requesting iwlwifi-4965-2.ucode
Oct 30 10:49:05 linux-82ra kernel: [   26.920785] iwlagn 0000:03:00.0: firmware: requesting iwlwifi-4965-2.ucode
[...]
Nov  4 07:30:17 linux-82ra kernel: [   28.115336] iwlagn 0000:03:00.0: firmware: requesting iwlwifi-4965-2.ucode
Nov  4 09:19:53 linux-82ra kernel: [   30.948253] iwlagn 0000:03:00.0: firmware: requesting iwlwifi-4965-2.ucode

And this is how the wireless adapter is initialized:

Nov  4 09:19:53 linux-82ra kernel: [   30.895425] e1000e 0000:00:19.0: irq 31 for MSI/MSI-X
Nov  4 09:19:53 linux-82ra kernel: [   30.947216] e1000e 0000:00:19.0: irq 31 for MSI/MSI-X
Nov  4 09:19:53 linux-82ra kernel: [   30.947567] ADDRCONF(NETDEV_UP): eth0: link is not ready
Nov  4 09:19:53 linux-82ra kernel: [   30.948253] iwlagn 0000:03:00.0: firmware: requesting iwlwifi-4965-2.ucode
Nov  4 09:19:53 linux-82ra kernel: [   31.352356] iwlagn 0000:03:00.0: loaded firmware version 228.61.2.24
Nov  4 09:19:53 linux-82ra kernel: [   31.571585] Registered led device: iwl-phy0::radio
Nov  4 09:19:53 linux-82ra kernel: [   31.573144] Registered led device: iwl-phy0::assoc
Nov  4 09:19:53 linux-82ra kernel: [   31.574607] Registered led device: iwl-phy0::RX
Nov  4 09:19:53 linux-82ra kernel: [   31.574803] Registered led device: iwl-phy0::TX
Nov  4 09:19:53 linux-82ra kernel: [   31.604843] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Nov  4 09:19:53 linux-82ra kernel: [   31.606389] /dev/vmnet: open called by PID 1570 (vmnet-bridge)
Nov  4 09:19:53 linux-82ra kernel: [   31.606401] /dev/vmnet: hub 0 does not exist, allocating memory.
Nov  4 09:19:53 linux-82ra kernel: [   31.606418] /dev/vmnet: port on hub 0 successfully opened
Nov  4 09:19:53 linux-82ra kernel: [   31.606435] bridge-wlan0: is a Wireless Adapter
Nov  4 09:19:53 linux-82ra kernel: [   31.606439] bridge-wlan0: up
Nov  4 09:19:53 linux-82ra kernel: [   31.609290] bridge-wlan0: attached
Nov  4 09:19:53 linux-82ra kernel: [   31.609350] bridge-wlan0: disabling the bridge
Nov  4 09:19:53 linux-82ra kernel: [   31.611499] bridge-wlan0: down
Nov  4 09:19:53 linux-82ra kernel: [   31.611505] bridge-wlan0: detached
Nov  4 09:19:53 linux-82ra kernel: [   31.697926] NET: Registered protocol family 17

This looks like kernel panic (or about-to-panic) log:

Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] iwlagn 0000:03:00.0: HCMD skipped: index (218) 3 215
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] iwlagn 0000:03:00.0: HCMD skipped: index (218) 3 216
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] iwlagn 0000:03:00.0: HCMD skipped: index (218) 3 217
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] ------------[ cut here ]------------
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] WARNING: at /usr/src/packages/BUILD/kernel-desktop-2.6.31.5/linux-2.6.31/drivers/net/wireless/iwlwifi/iwl-tx.c:1111 iwl_tx_cmd_complete+0x14d/0x1a0 [iwlcore]()
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] Hardware name: 8919W6X
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] wrong command queue 0, sequence 0x0 readp=218 writep=3
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] Modules linked in: nfs lockd fscache nfs_acl auth_rpcgss sunrpc ip6t_LOG xt_tcpudp xt_pkttype xt_physdev ipt_LOG xt_limit af_packet rfcomm sco bridge snd_pcm_oss stp snd_mixer_oss llc snd_seq bnep l2cap snd_seq_device edd vmnet ppdev parport_pc parport vmblock vmci vmmon ip6t_REJECT nf_conntrack_ipv6 ip6table_raw xt_NOTRACK ipt_REJECT xt_state iptable_raw cpufreq_conservative iptable_filter cpufreq_userspace cpufreq_powersave acpi_cpufreq speedstep_lib ip6table_mangle nf_conntrack_netbios_ns nf_conntrack_ipv4 nf_conntrack nf_defrag_ipv4 ip_tables ip6table_filter ip6_tables x_tables binfmt_misc fuse nls_iso8859_1 nls_cp437 vfat fat loop dm_mod snd_hda_codec_analog arc4 ecb cryptomgr aead pcompress crypto_blkcipher crypto_hash crypto_algapi snd_hda_intel iwlagn snd_hda_codec iwlcore pcmcia snd_hwdep sdhci_pci snd_pcm mac80211 sdhci yenta_socket ohci1394 snd_timer sr_mod btusb iTCO_wdt rsrc_nonstatic snd cfg80211 bluetooth iTCO_vendor_support mmc_core i2c_i801 intel_agp
Nov  4 09:15:40 linux-82ra kernel: pcmcia_core ricoh_mmc sg thinkpad_acpi ieee1394 nvidia(P) snd_page_alloc cdrom pcspkr video battery ac wmi button rfkill joydev e1000e ext4 jbd2 crc16 fan thermal processor thermal_sys ide_pci_generic piix ide_core ata_generic
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] Pid: 0, comm: swapper Tainted: P           2.6.31.5-0.1-desktop #1
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] Call Trace:
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c020845a>] try_stack_unwind+0x17a/0x1a0
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c020708c>] dump_trace+0x6c/0x130
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c0208008>] show_trace_log_lvl+0x58/0x80
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c0208056>] show_trace+0x26/0x40
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c06929e3>] dump_stack+0x79/0x91
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c0251408>] warn_slowpath_common+0x78/0xc0
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c02514c1>] warn_slowpath_fmt+0x31/0x50
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<f9c9989d>] iwl_tx_cmd_complete+0x14d/0x1a0 [iwlcore]
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<f9d36fb2>] iwl_rx_handle+0x122/0x460 [iwlagn]
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<f9d39e35>] iwl_irq_tasklet_legacy+0x3f5/0xbc0 [iwlagn]
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c0257829>] tasklet_action+0xf9/0x110
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c02590a4>] __do_softirq+0xb4/0x250
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c02592d5>] do_softirq+0x95/0xb0
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c025965d>] irq_exit+0x8d/0xa0
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c020606c>] do_IRQ+0x5c/0xe0
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c0204750>] common_interrupt+0x30/0x38
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<f7e0f5d3>] acpi_idle_enter_bm+0x282/0x2c2 [processor]
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c05855fe>] cpuidle_idle_call+0x8e/0xf0
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c0202cb5>] cpu_idle+0xa5/0x100
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c068028c>] rest_init+0x7c/0xa0
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c097dcea>] start_kernel+0x392/0x3a9
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715]  [<c097d087>] i386_start_kernel+0x87/0x9f
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] ---[ end trace 563fa61de01681cc ]---
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] iwlagn 0000:03:00.0: Read index for DMA queue txq id (4), index 215, is out of range [0-256] 3 218.
Nov  4 09:15:40 linux-82ra kernel: [ 2586.851715] iwlagn 0000:03:00.0: Read index for DMA queue txq id (4), index 211, is out of range [0-256] 3 218.
Nov  4 09:15:40 linux-82ra kernel: [ 2586.873518] iwlagn 0000:03:00.0: No space for Tx
Nov  4 09:15:40 linux-82ra kernel: [ 2586.873528] iwlagn 0000:03:00.0: Error sending SENSITIVITY_CMD: enqueue_hcmd failed: -28
Nov  4 09:15:40 linux-82ra kernel: [ 2586.873535] iwlagn 0000:03:00.0: SENSITIVITY_CMD failed
Nov  4 09:15:40 linux-82ra kernel: [ 2587.099117] Registered led device: iwl-phy0::radio
Nov  4 09:15:40 linux-82ra kernel: [ 2587.099190] Registered led device: iwl-phy0::assoc
Nov  4 09:15:40 linux-82ra kernel: [ 2587.099252] Registered led device: iwl-phy0::RX
Nov  4 09:15:40 linux-82ra kernel: [ 2587.099312] Registered led device: iwl-phy0::TX
Nov  4 09:15:58 linux-82ra vmnetBridge: RTM_NEWLINK: name:wlan0 index:4 flags:0x00011043
Nov  4 09:15:58 linux-82ra vmnetBridge: Can't add interface wlan0 4 (does exist).
Nov  4 09:16:03 linux-82ra vmnetBridge: RTM_NEWLINK: name:wlan0 index:4 flags:0x00011043
Nov  4 09:16:03 linux-82ra vmnetBridge: Can't add interface wlan0 4 (does exist).
Nov  4 09:16:20 linux-82ra vmnetBridge: RTM_NEWLINK: name:wlan0 index:4 flags:0x00011043
Nov  4 09:16:20 linux-82ra vmnetBridge: Can't add interface wlan0 4 (does exist).
Nov  4 09:16:43 linux-82ra vmnetBridge: RTM_NEWLINK: name:wlan0 index:4 flags:0x00011043
Nov  4 09:16:43 linux-82ra vmnetBridge: Can't add interface wlan0 4 (does exist).
Nov  4 09:17:09 linux-82ra vmnetBridge: RTM_NEWLINK: name:wlan0 index:4 flags:0x00011043
Nov  4 09:17:09 linux-82ra vmnetBridge: Can't add interface wlan0 4 (does exist).
Nov  4 09:17:24 linux-82ra vmnetBridge: RTM_NEWLINK: name:wlan0 index:4 flags:0x00011043
Nov  4 09:17:24 linux-82ra vmnetBridge: Can't add interface wlan0 4 (does exist).
Nov  4 09:17:26 linux-82ra kernel: [ 2692.826810] iwlagn 0000:03:00.0: Microcode SW error detected.  Restarting 0x2000000.
Nov  4 09:17:26 linux-82ra kernel: [ 2693.061646] Registered led device: iwl-phy0::radio
Nov  4 09:Nov  4 09:19:39 linux-82ra kernel: imklog 4.4.1, log source = /proc/kmsg started.
Nov  4 09:19:39 linux-82ra kernel: [   16.358756] type=1505 audit(1257322777.604:2): operation="profile_load" pid=1038 name=/bin/ping
Nov  4 09:19:39 linux-82ra kernel: [   16.395523] type=1505 audit(1257322777.641:3): operation="profile_load" pid=1053 name=/sbin/klogd

I would say that intel wireless driver still has some issues.

Has anyone else experiences similar problems? It is quite hard to trace this bug, as I don't have access to 802.11n networks at home, but just at work, and when at work, I have to, well... work :-)