Bug 2007 – Log prints Microcode SW error and WARNING after triggerring S3 in IBSS

Bug 2007 - Log prints Microcode SW error and WARNING after triggerring S3 in IBSS

Summary:

Log prints Microcode SW error and WARNING after triggerring S3 in IBSS

Status:	VERIFIED FIXED

Product:	iwlwifi
Component:	IBSS
Version:	iwlwifi-2.6 development tree
Platform:	All Cards Fedora 10

Importance:	P1 major
Assigned To:	reinette chatre
QA Contact:	Jeff Zheng

URL:
Keywords:

Depends on:
Blocks:
	Show dependency tree / graph

Reported:	2009-05-24 18:43 by ximin luo
Modified:	2009-06-08 10:04 (History)

Attachments
Add an attachment (proposed patch, testcase, etc.)

Note

You need to log in before you can comment on or make changes to this bug.

Description From ximin luo 2009-05-24 18:43:55

Testing Environment
==============================================
Platform        :       Intel SDV M3M41
Wireless Card   :       Intel(R) WiFi Link 5300
OS              :       Redhat Fedora release 10 (Cambridge) 64bit
uCode           :       iwlwifi-5000-2.ucode 8.24.2.12
Source          :       commit 752993656c62c4bba383cd22732095987addce54
                        (Wed May 20 15:53:39 2009)
Peer            :       Intel SDV M3M31

Issue
==============================================
Log prints Microcode SW error and WARNING: at
drivers/net/wireless/iwlwifi/iwl-core.c:2238
ieee80211_bss_info_change_notify+0x129/0x157 [mac80211]().

Steps to Reproduce
==============================================
1. modprobe -r iwlagn
2. modprobe iwlagn debug50=0x40000
3. iwconfig wlan0 mode ad-hoc
4. ifconfig wlan0 up
5. iwconfig wlan0 channel <> essid <>
6. ifconfig wlan0 <IP_ADDR>
7. peer machine joint this IBSS and reproduce step 1~6
8. ping success and "echo -n mem > /sys/power/state"
9. Microcode SW error and WARNING after resuming from suspend

Part of log message
===============================================
iwlagn 0000:02:00.0: Not a valid iwl_rxon_assoc_cmd field values
iwlagn 0000:02:00.0: Invalid RXON configuration.  Not committing.
iwlagn 0000:02:00.0: check 52 fields 1 | 16
iwlagn 0000:02:00.0: check 52 CCK 2 | 16
iwlagn 0000:02:00.0: check mac addr 3 | 16
iwlagn 0000:02:00.0: check basic rate 4 | 17
iwlagn 0000:02:00.0: check assoc id 5 | 17
iwlagn 0000:02:00.0: check CCK and short slot 6 | 17
iwlagn 0000:02:00.0: check CCK & auto detect 7 | 17
iwlagn 0000:02:00.0: check TGG and auto detect 8 | 17
iwlagn 0000:02:00.0: Tuning to channel 0
iwlagn 0000:02:00.0: Not a valid iwl_rxon_assoc_cmd field values
iwlagn 0000:02:00.0: Invalid RXON configuration.  Not committing.
iwlagn 0000:02:00.0: Microcode SW error detected.  Restarting 0x2000000.
iwlagn 0000:02:00.0: Start IWL Error Log Dump:
iwlagn 0000:02:00.0: Status: 0x000212E4, count: 5
iwlagn 0000:02:00.0: Desc                               Time       data1     
data2      line
iwlagn 0000:02:00.0: SYSASSERT                    (#05) 0000000228 0x00000001
0x00000000 1309
iwlagn 0000:02:00.0: blink1  blink2  ilink1  ilink2
iwlagn 0000:02:00.0: 0x0391A 0x0391A 0x008B2 0x00000
iwlagn 0000:02:00.0: Start IWL Event Log Dump: display count 30, wraps 0
iwlagn 0000:02:00.0: EVT_LOGT:0000000000:0x0000027a:0117
iwlagn 0000:02:00.0: EVT_LOGT:0000000007:0x00000325:1074
iwlagn 0000:02:00.0: EVT_LOGT:0000000023:0x00000000:1208
iwlagn 0000:02:00.0: EVT_LOGT:0000001227:0x0000007a:1501
iwlagn 0000:02:00.0: EVT_LOGT:0000001227:0x00000001:1527
iwlagn 0000:02:00.0: EVT_LOGT:0000001228:0x00000000:1527
iwlagn 0000:02:00.0: EVT_LOGT:0000002258:0x00000000:0480
iwlagn 0000:02:00.0: EVT_LOGT:0000006235:0x00000001:0480
iwlagn 0000:02:00.0: EVT_LOGT:0000006333:0x00000002:0480
iwlagn 0000:02:00.0: EVT_LOGT:0000006341:0x00000003:0480
iwlagn 0000:02:00.0: EVT_LOGT:0000006402:0x00000004:0480
iwlagn 0000:02:00.0: EVT_LOGT:0000015590:0x0400005a:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000015591:0x0400005a:1512
iwlagn 0000:02:00.0: EVT_LOGT:0000015591:0x00000000:1512
iwlagn 0000:02:00.0: EVT_LOGT:0000015592:0x00000002:1527
iwlagn 0000:02:00.0: EVT_LOGT:0000015593:0x00000001:1527
iwlagn 0000:02:00.0: EVT_LOGT:0000015677:0x440100b0:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000015798:0x440200b0:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000015874:0x440300b0:0401
… …
… …
iwlagn 0000:02:00.0: EVT_LOGT:0000006333:0x00000002:0480
iwlagn 0000:02:00.0: EVT_LOGT:0000006341:0x00000003:0480
iwlagn 0000:02:00.0: EVT_LOGT:0000006402:0x00000004:0480
iwlagn 0000:02:00.0: EVT_LOGT:0000015590:0x0400005a:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000015591:0x0400005a:1512
iwlagn 0000:02:00.0: EVT_LOGT:0000015591:0x00000000:1512
iwlagn 0000:02:00.0: EVT_LOGT:0000015592:0x00000002:1527
iwlagn 0000:02:00.0: EVT_LOGT:0000015593:0x00000001:1527
iwlagn 0000:02:00.0: EVT_LOGT:0000015677:0x440100b0:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000015798:0x440200b0:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000015874:0x440300b0:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000015951:0x440400b0:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000016026:0x440500b0:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000016105:0x0406009b:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000016184:0x0407009c:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000016258:0x040800a4:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000017011:0x04090077:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000017170:0x040a0014:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000017171:0x00000325:1074
iwlagn 0000:02:00.0: EVT_LOGT:0000017322:0x040b0018:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000017401:0x040c004e:0401
iwlagn 0000:02:00.0: EVT_LOGT:0000017408:0x00000000:0125
ieee80211 phy94: I iwl_irq_handle_error Restarting adapter due to uCode error.
iwlagn 0000:02:00.0: Error sending REPLY_TX_BEACON: time out after 500ms.
iwlagn 0000:02:00.0: channel 0 not IBSS channel
------------[ cut here ]------------
WARNING: at drivers/net/wireless/iwlwifi/iwl-core.c:2238
ieee80211_bss_info_change_notify+0x129/0x157 [mac80211]()
Hardware name: Montevina platform
Modules linked in: iwlagn iwlcore mac80211 e1000 sit tunnel4 nfsd lockd nfs_acl
auth_rpcgss exportfs bridge stp bnep sco l2ca
p bluetooth sunrpc ipv6 cpufreq_ondemand acpi_cpufreq dm_mirror dm_region_hash
dm_log dm_multipath dm_mod uinput snd_hda_inte
l snd_hda_codec snd_hwdep snd_seq_dummy snd_seq_oss snd_seq_midi_event snd_seq
snd_seq_device snd_pcm_oss snd_mixer_oss snd_p
cm snd_timer sr_mod snd sg video output battery soundcore snd_page_alloc
i2c_i801 i2c_core ac button pcspkr cdrom ata_generic
 ata_piix libata sd_mod scsi_mod ext3 jbd mbcache uhci_hcd ohci_hcd ehci_hcd
[last unloaded: mac80211]
Pid: 31558, comm: bash Tainted: G        W  2.6.30-rc6-wl #4
Call Trace:
 [<ffffffff8103ace3>] ? warn_slowpath_fmt+0xce/0x100
 [<ffffffffa04bc582>] ? ieee80211_bss_info_change_notify+0x129/0x157 [mac80211]
 [<ffffffffa04d22cd>] ? ieee80211_reconfig+0x272/0x38f [mac80211]
 [<ffffffffa04d219b>] ? ieee80211_reconfig+0x140/0x38f [mac80211]
 [<ffffffff812deda6>] ? mutex_lock_nested+0x282/0x291
 [<ffffffff812b9a6c>] ? wiphy_resume+0x4f/0x6f
 [<ffffffff812b9a7e>] ? wiphy_resume+0x61/0x6f
 [<ffffffff811e0f29>] ? device_resume+0x11a/0x33c
 [<ffffffff81067017>] ? suspend_devices_and_enter+0x175/0x1ae
 [<ffffffff810671d2>] ? enter_state+0x158/0x1ba
 [<ffffffff810672e8>] ? state_store+0xb4/0xd5
 [<ffffffff8110b635>] ? sysfs_write_file+0xd5/0x10c
 [<ffffffff810bdeb6>] ? vfs_write+0xad/0x156
 [<ffffffff810be01b>] ? sys_write+0x45/0x6e
 [<ffffffff8100b9eb>] ? system_call_fastpath+0x16/0x1b
---[ end trace 529d04a013a0d0bc ]---
PM: Finishing wakeup.
Restarting tasks ... done.
Registered led device: iwl-phy94::radio
Registered led device: iwl-phy94::assoc
Registered led device: iwl-phy94::RX
Registered led device: iwl-phy94::TX

------- Comment #1 From ximin luo 2009-05-24 23:17:33 -------

Retest this issue on 4965 card, got firmware error on line 1384 and WARNING at
iwl-core.c:2502.
Test Environment
===============================================
Platform        :       Intel SDV M3M31
Wireless Card   :       Intel(R) WiFi Link 4965
OS              :       Redhat Fedora release 10 (Cambridge) 32bit
uCode           :       iwlwifi-4965-2.ucode 228.57.2.23
Source          :       commit c612430a75805c0fb5d2b0339653567afa111ab6
                     (2.6.30-rc6-wl)
Peer            :       Intel SDV M3M31

Part of log
===============================================
iwlagn 0000:03:00.0: Tuning to channel 0
iwlagn 0000:03:00.0: Not a valid iwl_rxon_assoc_cmd field values
iwlagn 0000:03:00.0: Invalid RXON configuration.  Not committing.
iwlagn 0000:03:00.0: Microcode SW error detected.  Restarting 0x2000000.
iwlagn 0000:03:00.0: Start IWL Error Log Dump:
iwlagn 0000:03:00.0: Status: 0x000213E6, count: 5
iwlagn 0000:03:00.0: Desc                               Time       data1     
data2      line
iwlagn 0000:03:00.0: SYSASSERT                    (#05) 0022436713 0x00000001
0x00000000 1384
iwlagn 0000:03:00.0: blink1  blink2  ilink1  ilink2
iwlagn 0000:03:00.0: 0x0217A 0x021C2 0x006DE 0x00000
iwlagn 0000:03:00.0: Start IWL Event Log Dump: display count 11, wraps 0
iwlagn 0000:03:00.0: EVT_LOGT:0000000000:0x0000023b:0117
iwlagn 0000:03:00.0: EVT_LOGT:0000000014:0x00000000:1208
iwlagn 0000:03:00.0: EVT_LOGT:0000023477:0x0400009b:0401
iwlagn 0000:03:00.0: EVT_LOGT:0000025727:0x0401009c:0401
iwlagn 0000:03:00.0: EVT_LOGT:0000025762:0x040200a4:0401
iwlagn 0000:03:00.0: EVT_LOGT:0000028217:0x04030077:0401
iwlagn 0000:03:00.0: EVT_LOGT:0000030545:0x04040014:0401
iwlagn 0000:03:00.0: EVT_LOGT:0000030545:0x0000043e:1074
iwlagn 0000:03:00.0: EVT_LOGT:0022434450:0x04050018:0401
iwlagn 0000:03:00.0: EVT_LOGT:0022436712:0x0406004e:0401
iwlagn 0000:03:00.0: EVT_LOGT:0022436716:0x00000000:0125
ieee80211 phy0: I iwl_irq_handle_error Restarting adapter due to uCode error.
e1000: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX
iwlagn 0000:03:00.0: Error sending REPLY_TX_PWR_TABLE_CMD: time out after
500ms.
iwlagn 0000:03:00.0: channel 0 not IBSS channel
------------[ cut here ]------------
WARNING: at drivers/net/wireless/iwlwifi/iwl-core.c:2502
warn_slowpath_null+0x10/0x15()
Hardware name: Crestline & ICH8M Chipset
Modules linked in: iwlagn iwlcore rfkill mac80211 cfg80211 aes_i586 aes_generic
netconsole sco bridge stp bnep l2cap bluetooth sunrpc ipv6 cpufreq_ondemand
acpi_cpufreq dm_mirror dm_region_hash dm_log dm_multipath dm_mod uinput arc4
ecb e1000 battery video i2c_i801 i2c_core output button iTCO_wdt
iTCO_vendor_support ac serio_raw pcspkr sr_mod sg cdrom ata_generic ata_piix
libata sd_mod scsi_mod ext3 jbd mbcache uhci_hcd ohci_hcd ehci_hcd [last
unloaded: cfg80211]
Pid: 21007, comm: bash Tainted: G        W  2.6.30-rc6-wl #16
Call Trace:
 [<c04291c0>] warn_slowpath_fmt+0x72/0xa1
 [<c050f888>] ? vsnprintf+0x7e/0x30c
 [<c043cd00>] ? cleanup_srcu_struct+0x36/0x91
 [<c0429583>] ? try_acquire_console_sem+0x27/0x46
 [<c04291ff>] warn_slowpath_null+0x10/0x15
 [<f9551e72>] iwl_bss_info_changed+0x85/0x6d7 [iwlcore]
 [<f9551ded>] ? iwl_bss_info_changed+0x0/0x6d7 [iwlcore]
 [<f95211e1>] ieee80211_bss_info_change_notify+0x121/0x150 [mac80211]
 [<f9534739>] ieee80211_reconfig+0x1cb/0x2c0 [mac80211]
 [<f952d77c>] ieee80211_resume+0x15/0x17 [mac80211]
 [<f895f914>] wiphy_resume+0x4f/0x5d [cfg80211]
 [<c058a539>] device_resume+0xeb/0x29e
 [<c044b0db>] suspend_devices_and_enter+0x150/0x183
 [<c044b263>] enter_state+0x130/0x190
 [<c044b352>] state_store+0x8f/0xa2
 [<c044b2c3>] ? state_store+0x0/0xa2
 [<c050afb9>] kobj_attr_store+0x1a/0x22
 [<c04cbb97>] sysfs_write_file+0xb4/0xdf
 [<c04cbae3>] ? sysfs_write_file+0x0/0xdf
 [<c048ed4c>] vfs_write+0x8a/0x12e
 [<c048ee89>] sys_write+0x3b/0x60
 [<c0402984>] sysenter_do_call+0x12/0x22
---[ end trace 6106c92c682a1121 ]---
PM: Finishing wakeup.
Restarting tasks ... done.

------- Comment #2 From Abhijeet Kolekar 2009-06-01 11:07:40 -------

http://www.intellinuxwireless.org/bugzilla/attachment.cgi?id=2016
Can you try this patch?

------- Comment #3 From Jeff Zheng 2009-06-01 16:59:35 -------


*** This bug has been marked as a duplicate of bug 2005 ***

------- Comment #4 From Abhijeet Kolekar 2009-06-08 10:04:52 -------

Fixed in recent abat.