Ubuntu
linux-restricted-modules-2.6.20 package

kernel oops ipw3945

Bug #163981 reported by Matthew Carpenter on 2007-11-20

Affects		Status	Importance	Assigned to	Milestone
	linux-restricted-modules-2.6.20 (Ubuntu)	Invalid	Undecided	Unassigned

Bug Description

Binary package hint: linux-restricted-modules-2.6.20-16-generic

After an apparent update to /lib/modules/2.6.20-16-generic on 11/16, I continually get a kernel OOPS which disables my long-time happy ipw3945 card.

Interesting log entries:
Nov 19 11:42:40 faramir kernel: [45791.172000] ipw3945: Microcode SW error detected. Restarting.
Nov 19 11:42:41 faramir kernel: [45792.164000] ipw3945: Can't stop Rx DMA.
Nov 19 11:42:42 faramir kernel: [45792.540000] ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)
Nov 19 11:42:42 faramir NetworkManager: <WARNING>^I nm_device_802_11_wireless_get_essid (): error getting ESSID for device eth1: Resource temporarily unavailable
Nov 19 11:42:42 faramir NetworkManager: <WARNING>^I nm_device_802_11_wireless_get_mode (): error getting card mode on eth1: Resource temporarily unavailable
Nov 19 11:42:42 faramir NetworkManager: <WARNING>^I nm_device_802_11_wireless_get_mode (): error getting card mode on eth1: Resource temporarily unavailable
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): exdump(len=32): [REMOVED]
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): WPA: Installing GTK to the driver (keyidx=1 tx=0).
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): wpa_driver_wext_set_key: alg=2 key_idx=1 set_tx=0 seq_len=6 key_len=32
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): WPA: Sending EAPOL-Key 2/2
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f fe 03 11 00 20 00 00 00 00 00 00 00 4f 00 00 00
00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00 00 00 cd 52 62 8e af 1b b5 b3 3d 35 42 9d 48 df e9 19 00 00
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): WPA: Group rekeying completed with 00:11:50:16:42:69 [GTK=TKIP]
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): CTRL_IFACE monitor send - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61
6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 35 36 30 39 2d 32 00
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): State: GROUP_HANDSHAKE -> COMPLETED
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): RTM_NEWLINK: operstate=1 ifi_flags=0x1003 ([UP])
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): RTM_NEWLINK, IFLA_IFNAME: Interface 'eth1' added
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): RTM_NEWLINK: operstate=1 ifi_flags=0x11003 ([UP][LOWER_UP])
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): WEXT: Operstate: linkmode=-1, operstate=6
Nov 19 11:42:44 faramir kernel: [45794.684000] TKIP: ICV error detected: STA=00:11:50:16:42:69
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): WLINK, IFLA_IFNAME: Interface 'eth1' added
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): RTM_NEWLINK, IFLA_IFNAME: Interface 'eth1' added
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): Wireless event: cmd=0x8b15 len=20
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): Wireless event: new AP: 00:11:50:16:42:69
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): State: COMPLETED -> ASSOCIATED
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): wpa_driver_wext_set_operstate: operstate 1->0 (DORMANT)
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): WEXT: Operstate: linkmode=-1, operstate=5
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): Associated with 00:11:50:16:42:69
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): CTRL_IFACE monitor send - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61
6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 35 36 30 39 2d 32 00
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): WPA: Association event - clear replay counter
Nov 19 11:42:44 faramir kernel: [45794.700000] TKIP: ICV error detected: STA=00:11:50:16:42:69
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): EAPOL: External notification - portEnabled=0
Nov 19 11:42:44 faramir kernel: [45794.712000] TKIP: ICV error detected: STA=00:11:50:16:42:69
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): EAPOL: SUPP_PAE entering state DISCONNECTED
Nov 19 11:42:44 faramir kernel: [45794.724000] TKIP: ICV error detected: STA=00:11:50:16:42:69
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): EAPOL: SUPP_BE entering state INITIALIZE
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): EAPOL: External notification - portValid=0
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): EAPOL: External notification - EAP success=0
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): EAPOL: External notification - portEnabled=1
Nov 19 11:42:44 faramir NetworkManager: <information>^Iwpa_supplicant(9244): EAPOL: SUPP_PAE entering state CONNECTING

Revision history for this message

Matthew Carpenter (matt-eisgr) wrote on 2007-11-20:

Next follows these (not in /var/log/syslog):

[ 1458.204000] ipw3945: Error sending LEDS_CMD: time out after 500ms.
[ 1458.204000] ipw3945: Microcode SW error detected. Restarting.
[ 1458.704000] ipw3945: Error sending cmd #07 to daemon: time out after 500ms.
[ 1460.688000] ipw3945: Can't stop Rx DMA.
[ 1461.300000] ipw3945: Error sending LEDS_CMD: time out after 500ms.
[ 1462.340000] ipw3945: 3945ABG card ucode DOWNLOAD FAILED
[ 1462.340000] ipw3945: Unable to load firmware: -110
[ 1463.336000] ipw3945: 3945ABG card ucode DOWNLOAD FAILED
[ 1463.336000] ipw3945: Unable to load firmware: -110
[ 1464.336000] ipw3945: 3945ABG card ucode DOWNLOAD FAILED
[ 1464.336000] ipw3945: Unable to load firmware: -110
[ 1465.332000] ipw3945: 3945ABG card ucode DOWNLOAD FAILED
[ 1465.332000] ipw3945: Unable to load firmware: -110
[ 1466.332000] ipw3945: 3945ABG card ucode DOWNLOAD FAILED
[ 1466.332000] ipw3945: Unable to load firmware: -110
[ 1466.344000] ipw3945: Unable to initialize device after 5 attempts.
[ 1466.608000] ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)
[ 1499.668000] ipw3945: Error sending LEDS_CMD: time out after 500ms.
[ 1499.760000] ipw3945: Microcode SW error detected. Restarting.
[ 1500.752000] ipw3945: Can't stop Rx DMA.
[ 1502.400000] ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)

After a time, we reach the kernel oops. Likely has to do with a large file download I'm doing...

Revision history for this message

Matthew Carpenter (matt-eisgr) wrote on 2007-11-20:

Download full text (6.5 KiB)

Finally....
[ 1458.204000] ipw3945: Error sending LEDS_CMD: time out after 500ms.
[ 1458.204000] ipw3945: Microcode SW error detected.  Restarting.
[ 1458.704000] ipw3945: Error sending cmd #07 to daemon: time out after 500ms.
[ 1460.688000] ipw3945: Can't stop Rx DMA.
[ 1461.300000] ipw3945: Error sending LEDS_CMD: time out after 500ms.
[ 1462.340000] ipw3945: 3945ABG card ucode DOWNLOAD FAILED
[ 1462.340000] ipw3945: Unable to load firmware: -110
[ 1463.336000] ipw3945: 3945ABG card ucode DOWNLOAD FAILED
[ 1463.336000] ipw3945: Unable to load firmware: -110
[ 1464.336000] ipw3945: 3945ABG card ucode DOWNLOAD FAILED
[ 1464.336000] ipw3945: Unable to load firmware: -110
[ 1465.332000] ipw3945: 3945ABG card ucode DOWNLOAD FAILED
[ 1465.332000] ipw3945: Unable to load firmware: -110
[ 1466.332000] ipw3945: 3945ABG card ucode DOWNLOAD FAILED
[ 1466.332000] ipw3945: Unable to load firmware: -110
[ 1466.344000] ipw3945: Unable to initialize device after 5 attempts.
[ 1466.608000] ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)
[ 1499.668000] ipw3945: Error sending LEDS_CMD: time out after 500ms.
[ 1499.760000] ipw3945: Microcode SW error detected.  Restarting.
[ 1500.752000] ipw3945: Can't stop Rx DMA.
[ 1502.400000] ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)
[ 1560.592000] IN=eth1 OUT= MAC=00:19:d2:22:45:7d:00:50:bf:9c:c3:9c:08:00 SRC=66.253.44.99 DST=192.168.72.49 LEN=228 TOS=0x00 PREC=0x00 TTL=46 ID=45541 DF PROTO=TCP SPT=30030 DPT=59810 WINDOW=10156 RES=0x00 ACK PSH URGP=0
[ 1680.548000] IN=eth1 OUT= MAC=00:19:d2:22:45:7d:00:50:bf:9c:c3:9c:08:00 SRC=66.253.44.99 DST=192.168.72.49 LEN=228 TOS=0x00 PREC=0x00 TTL=46 ID=45543 DF PROTO=TCP SPT=30030 DPT=59810 WINDOW=10156 RES=0x00 ACK PSH URGP=0
[ 1764.452000] ipw3945: Microcode SW error detected.  Restarting.
[ 1765.444000] ipw3945: Can't stop Rx DMA.
[ 1767.092000] ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)
[ 1769.184000] ipw3945: association process canceled
[ 1769.196000] TKIP: ICV error detected: STA=00:11:50:16:42:69
[ 1769.196000] TKIP: ICV error detected: STA=00:11:50:16:42:69
[ 1769.200000] TKIP: ICV error detected: STA=00:11:50:16:42:69
[ 1776.996000] ipw3945: Microcode SW error detected.  Restarting.
[ 1778.344000] ipw3945: Error sending LEDS_CMD: time out after 500ms.
[ 1778.352000] ipw3945: Can't stop Rx DMA.
[ 1778.636000] ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)
[ 1806.032000] ipw3945: Microcode SW error detected.  Restarting.
[ 1807.376000] ipw3945: Error sending LEDS_CMD: time out after 500ms.
[ 1808.372000] ipw3945: Can't stop Rx DMA.
[ 1808.756000] ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)
[ 1820.800000] ipw3945: Error sending cmd #07 to daemon: time out after 500ms.
[ 1821.300000] ipw3945: Error sending SCAN_ABORT_CMD: time out after 500ms.
[ 1821.800000] ipw3945: Error sending cmd #08 to daemon: time out after 500ms.
[ 1822.300000] ipw3945: Error sending ADD_STA: time out after 500ms.
[ 1822.800000] ipw3945: Error sending SCAN_ABORT_CMD: time out after 500ms.
[ 1822.800000] ------------[ cut here ]------------
[ 1822.800000] kernel BUG at kernel/workqueue.c:323!
[ 1822.800000] invalid opcode: 0000 [#1]
[ 1822.800000] SMP
[ 1822.800000] Modules linked in: michael_mic arc4 ieee80211_crypt_tkip hci_usb videodev v4l2_common v4l1_compat appletalk ax25 ipx p8023 vmnet(P) vmmon(P) binfmt_misc rfcomm l2cap bluetooth ipv6 ppdev ipt_LOG xt_limit xt_tcpudp xt_state xt_multiport iptable_filter iptable_mangle ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_conntrack nfnetlink ip_tables x_tables acpi_cpufreq cpufreq_ondemand cpufreq_conservative cpufreq_powersave cpufreq_stats freq_table cpufreq_userspace dev_acpi pcc_acpi sony_acpi tc1100_wmi sbs container button i2c_ec battery ac video dock asus_acpi backlight xfrm_user xfrm4_tunnel tunnel4 ipcomp esp4 ah4 deflate zlib_deflate twofish twofish_common serpent blowfish des cbc ecb blkcipher aes xcbc sha256 sha1 crypto_null af_key dm_crypt dm_mod sbp2 parport_pc lp parport fuse joydev snd_hda_intel snd_hda_codec snd_usb_audio snd_pcm_oss snd_seq_dummy snd_mixer_oss snd_pcm snd_seq_oss xpad snd_usb_lib ipw3945 af_packet snd_seq_midi snd_seq_midi_event snd_seq ieee80211 snd_timer nvidia(P) snd_rawmidi snd_seq_device snd_hwdep psmouse ieee80211_crypt snd soundcore i2c_core serio_raw sdhci mmc_core iTCO_wdt iTCO_vendor_support snd_page_alloc intel_agp agpgart shpchp pci_hotplug tsdev evdev ext3 jbd mbcache sr_mod cdrom sg sd_mod generic usbhid hid ata_piix raid10 e1000 ohci1394 ahci ieee1394 ata_generic libata scsi_mod uhci_hcd ehci_hcd usbcore raid456 xor raid1 raid0 multipath linear md_mod thermal processor fan fbcon tileblit font bitblit softcursor vesafb capability commoncap
[ 1822.800000] CPU:    0
[ 1822.800000] EIP:    0060:[<c013736e>]    Tainted: P      VLI
[ 1822.800000] EFLAGS: 00010296   (2.6.20-16-generic #2)
[ 1822.800000] EIP is at run_workqueue+0xee/0x140
[ 1822.800000] eax: 00001db4   ebx: f7da81cc   ecx: 00000246   edx: 00000000
[ 1822.800000] esi: f7da81c0   edi: f7da81c8   ebp: 00000246   esp: f7ea5f58
[ 1822.800000] ds: 007b   es: 007b   ss: 0068
[ 1822.800000] Process ipw3945/0 (pid: 3893, ti=f7ea4000 task=df879030 task.ti=f7ea4000)
[ 1822.800000] Stack: 00000000 f7da81cc f7da81d4 f7ea5f9c f7da81cb f7da81e0 00000001 f7da81c0
[ 1822.800000]        f7da81cc f7da81d4 f7ea5f9c c0137d97 00000001 00000000 c20145c0 00010000
[ 1822.800000]        00000000 00000000 df879030 c0120b40 00100100 00200200 ffffffff ffffffff
[ 1822.800000] Call Trace:
[ 1822.800000]  [<c0137d97>] worker_thread+0x147/0x170
[ 1822.800000]  [<c0120b40>] default_wake_function+0x0/0x10
[ 1822.800000]  [<c0137c50>] worker_thread+0x0/0x170
[ 1822.800000]  [<c013ac4a>] kthread+0xba/0xf0
[ 1822.800000]  [<c013ab8e>] keventd_create_kthread+0x6e/0x70
[ 1822.800000]  [<c01044c7>] kernel_thread_helper+0x7/0x10
[ 1822.800000]  =======================
[ 1822.800000] Code: 24 00 00 00 00 e8 f3 72 fe ff 8b 44 24 10 3b 46 0c 0f 85 78 ff ff ff 83 6e 34 01 89 ea 83 c4 1c 89 f0 5b 5e 5f 5d e9 a2 76 1b 00 <0f> 0b eb fe 65 a1 08 00 00 00 8b 90 a4 00 00 00 05 94 01 00 00
[ 1822.800000] EIP: [<c013736e>] run_workqueue+0xee/0x140 SS:ESP 0068:f7ea5f58
[ 1822.800000]  <3>ipw3945: Error sending LEDS_CMD: time out after 500ms.
[ 1832.332000] bridge-eth1: disabling the bridge
[ 1832.352000] bridge-eth1: down
[ 1832.360000] ADDRCONF(NETDEV_UP): eth1: link is not ready
[ 1832.360000] bridge-eth1: enabling the bridge
[ 1832.360000] bridge-eth1: is a Wireless Adapter
[ 1832.360000] bridge-eth1: up

Revision history for this message

Daniel T Chen (crimsun) wrote on 2008-11-15:

Is this symptom still reproducible in 8.10?

Changed in linux-restricted-modules-2.6.20:
status:	New → Incomplete

Revision history for this message

Jonathan Thomas (echidnaman) wrote on 2009-04-19:

We are closing this bug report because it lacks the information we need to investigate the problem, as described in the previous comments. Please reopen it if you can give us the missing information, and don't hesitate to submit bug reports in the future. To reopen the bug report you can click on the current status, under the Status column, and change the Status back to "New". Thanks again!

Changed in linux-restricted-modules-2.6.20 (Ubuntu):
status:	Incomplete → Invalid

Report a bug

This report contains Public information

Everyone can see this information.

You are

Subscribing...

Edit bug mail

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.

Ubuntulinux-restricted-modules-2.6.20 package

kernel oops ipw3945

Bug Description

Other bug subscribers

Remote bug watches

Ubuntu
linux-restricted-modules-2.6.20 package