2014-07-08 05:06:20

by Cody P Schafer

[permalink] [raw]
Subject: iwlwifi gets into state where it continually fails to associate until the driver is re-bound

Kernel version: 3.15.3 + 1 patch for some btrfs issues. I've also seen
this on 3.15.2.

Hardware:

02:00.0 Network controller: Intel Corporation Centrino Wireless-N 1000
[Condor Peak]
Subsystem: Intel Corporation Centrino Wireless-N 1000 BGN
Kernel driver in use: iwlwifi
Kernel modules: iwlwifi

Logs from the unbind/bind that might have a hint:

[172759.290783] wlp2s0: authentication with c0:8a:de:3a:62:49 timed out
[172821.572691] wlp2s0: authenticate with c0:8a:de:3a:62:49
[172821.583144] wlp2s0: send auth to c0:8a:de:3a:62:49 (try 1/3)
[172822.318448] wlp2s0: send auth to c0:8a:de:3a:62:49 (try 2/3)
[172823.329053] wlp2s0: send auth to c0:8a:de:3a:62:49 (try 3/3)
[172824.309696] wlp2s0: authentication with c0:8a:de:3a:62:49 timed out
[172844.678135] iwlwifi 0000:02:00.0: Error sending REPLY_SCAN_CMD:
enqueue_hcmd failed: -5
[172883.435665] iwlwifi 0000:02:00.0: Error sending REPLY_LEDS_CMD:
enqueue_hcmd failed: -5
[172883.487856] iwlwifi 0000:02:00.0: Error sending REPLY_LEDS_CMD:
enqueue_hcmd failed: -5
[172883.564215] iwlwifi 0000:02:00.0: Error sending REPLY_RXON:
enqueue_hcmd failed: -5
[172883.564221] iwlwifi 0000:02:00.0: Error clearing ASSOC_MSK on BSS (-5)
[172883.616306] ------------[ cut here ]------------
[172883.616323] WARNING: CPU: 0 PID: 9317 at
drivers/net/wireless/iwlwifi/pcie/trans.c:1157
iwl_trans_pcie_grab_nic_access+0x2bc/0x2d0 [iwlwifi]()
[172883.616325] Timeout waiting for hardware access (CSR_GP_CNTRL 0x080003d8)
[172883.616327] Modules linked in: fuse tun joydev mousedev ctr ccm
snd_hda_codec_hdmi snd_hda_codec_conexant snd_hda_codec_generic
cachefiles fscache nls_iso8859_1 nls_cp437 vfat fat arc4 iwldvm
mac80211 iwlwifi cfg80211 iTCO_wdt iTCO_vendor_support uas coretemp
intel_powerclamp snd_hda_intel kvm_intel snd_hda_controller
thinkpad_acpi kvm snd_hda_codec snd_hwdep evdev microcode nvram
mac_hid i915 snd_pcm psmouse serio_raw led_class snd_timer pcspkr
i2c_i801 intel_ips snd rfkill soundcore hwmon drm_kms_helper lpc_ich
shpchp thermal ac wmi battery drm e1000e tpm_tis tpm acpi_cpufreq ptp
pps_core video mei_me intel_agp intel_gtt button i2c_algo_bit i2c_core
mei processor btrfs xor raid6_pq xts gf128mul dm_crypt dm_mod sd_mod
crct10dif_generic crc_t10dif crct10dif_common ums_realtek usb_storage
atkbd libps2
[172883.616382] crc32c_intel ahci libahci libata ehci_pci ehci_hcd
scsi_mod usbcore usb_common i8042 serio
[172883.616391] CPU: 0 PID: 9317 Comm: tee Not tainted 3.15.3-1-ARCH #1
[172883.616393] Hardware name: LENOVO 3249MDU/3249MDU, BIOS 6QET53WW
(1.23 ) 09/15/2010
[172883.616395] 0000000000000000 0000000041ec44e8 ffff8800037479a8
ffffffff81508141
[172883.616398] ffff8800037479f0 ffff8800037479e0 ffffffff81069afd
ffff880131a49098
[172883.616400] 00000000080003d8 ffff880131a49098 0000000000000000
00000000080003d8
[172883.616403] Call Trace:
[172883.616412] [<ffffffff81508141>] dump_stack+0x4d/0x6f
[172883.616417] [<ffffffff81069afd>] warn_slowpath_common+0x7d/0xa0
[172883.616420] [<ffffffff81069b7c>] warn_slowpath_fmt+0x5c/0x80
[172883.616424] [<ffffffffa09a963c>]
iwl_trans_pcie_grab_nic_access+0x2bc/0x2d0 [iwlwifi]
[172883.616428] [<ffffffffa099c579>] iwl_write_prph+0x39/0x80 [iwlwifi]
[172883.616432] [<ffffffffa09a62b2>] iwl_pcie_tx_stop+0x42/0x130 [iwlwifi]
[172883.616436] [<ffffffffa09abb50>]
iwl_trans_pcie_stop_device+0x630/0x660 [iwlwifi]
[172883.616441] [<ffffffffa0a745fa>] iwl_down+0x1aa/0x230 [iwldvm]
[172883.616445] [<ffffffffa0a7b6da>] iwlagn_mac_stop+0x5a/0xa0 [iwldvm]
[172883.616457] [<ffffffffa0a0287f>] ieee80211_stop_device+0x4f/0x130
[mac80211]
[172883.616464] [<ffffffffa09e85aa>] ieee80211_do_stop+0x4aa/0x8a0 [mac80211]
[172883.616468] [<ffffffff8150d5aa>] ? _raw_spin_unlock_bh+0x1a/0x20
[172883.616472] [<ffffffff81437aa3>] ? dev_deactivate_many+0x223/0x270
[172883.616479] [<ffffffffa09e89ba>] ieee80211_stop+0x1a/0x20 [mac80211]
[172883.616483] [<ffffffff8140ef2d>] __dev_close_many+0x9d/0x100
[172883.616485] [<ffffffff8140f091>] dev_close_many+0x91/0x140
[172883.616488] [<ffffffff814119b5>] rollback_registered_many+0x155/0x320
[172883.616491] [<ffffffff8150aa9d>] ? wait_for_completion+0x1d/0x20
[172883.616494] [<ffffffff81411c0b>] unregister_netdevice_many+0x1b/0xb0
[172883.616501] [<ffffffffa09ea4e4>]
ieee80211_remove_interfaces+0x134/0x1d0 [mac80211]
[172883.616507] [<ffffffffa09d43ab>]
ieee80211_unregister_hw+0x5b/0x120 [mac80211]
[172883.616511] [<ffffffffa0a7c459>] iwlagn_mac_unregister+0x29/0x40 [iwldvm]
[172883.616514] [<ffffffffa0a72aab>] iwl_op_mode_dvm_stop+0x3b/0xd0 [iwldvm]
[172883.616518] [<ffffffffa099de1b>] iwl_drv_stop+0x2b/0x90 [iwlwifi]
[172883.616522] [<ffffffffa09a18b5>] iwl_pci_remove+0x25/0x30 [iwlwifi]
[172883.616526] [<ffffffff812ce84b>] pci_device_remove+0x3b/0xc0
[172883.616530] [<ffffffff813916bf>] __device_release_driver+0x7f/0xf0
[172883.616533] [<ffffffff81391753>] device_release_driver+0x23/0x30
[172883.616535] [<ffffffff8139041d>] unbind_store+0xed/0x150
[172883.616538] [<ffffffff8138f695>] drv_attr_store+0x25/0x40
[172883.616541] [<ffffffff8122e40a>] sysfs_kf_write+0x3a/0x50
[172883.616544] [<ffffffff8122d97e>] kernfs_fop_write+0xee/0x180
[172883.616547] [<ffffffff811b470a>] vfs_write+0xba/0x1e0
[172883.616550] [<ffffffff811b462c>] ? vfs_read+0x13c/0x160
[172883.616553] [<ffffffff811b5309>] SyS_write+0x59/0xd0
[172883.616557] [<ffffffff81515ee9>] system_call_fastpath+0x16/0x1b
[172883.616559] ---[ end trace 397b07cf5b717cc6 ]---
[172888.935094] iwlwifi 0000:02:00.0: Failing on timeout while
stopping DMA channel 0 [0x5a5a5a5a]
[172894.368629] iwlwifi 0000:02:00.0: Failing on timeout while
stopping DMA channel 2 [0x5a5a5a5a]
[172899.814865] iwlwifi 0000:02:00.0: Fw not loaded - dropping CMD: 9c
[172899.894929] iwlwifi 0000:02:00.0: Failing on timeout while
stopping DMA channel 5 [0x5a5a5a5a]
[172905.314775] iwlwifi 0000:02:00.0: Failing on timeout while
stopping DMA channel 7 [0x5a5a5a5a]
[173057.690063] iwlwifi 0000:02:00.0: can't disable ASPM; OS doesn't
have ASPM control
[173057.690232] iwlwifi 0000:02:00.0: irq 43 for MSI/MSI-X
[173057.696886] iwlwifi 0000:02:00.0: loaded firmware version
39.31.5.1 build 35138 op_mode iwldvm
[173057.696907] iwlwifi 0000:02:00.0: CONFIG_IWLWIFI_DEBUG disabled
[173057.696909] iwlwifi 0000:02:00.0: CONFIG_IWLWIFI_DEBUGFS disabled
[173057.696911] iwlwifi 0000:02:00.0: CONFIG_IWLWIFI_DEVICE_TRACING enabled
[173057.696913] iwlwifi 0000:02:00.0: Detected Intel(R) Centrino(R)
Wireless-N 1000 BGN, REV=0x6C
[173057.697009] iwlwifi 0000:02:00.0: L1 Enabled; Disabling L0S
[173057.718196] ieee80211 phy1: Selected rate control algorithm 'iwl-agn-rs'

Full dmesg: http://bpaste.net/show/444976/


2014-07-08 13:14:00

by Emmanuel Grumbach

[permalink] [raw]
Subject: Re: iwlwifi gets into state where it continually fails to associate until the driver is re-bound

On Tue, Jul 8, 2014 at 8:06 AM, Cody P Schafer <[email protected]> wrote:
> Kernel version: 3.15.3 + 1 patch for some btrfs issues. I've also seen
> this on 3.15.2.
>
> Hardware:
>
> 02:00.0 Network controller: Intel Corporation Centrino Wireless-N 1000
> [Condor Peak]
> Subsystem: Intel Corporation Centrino Wireless-N 1000 BGN
> Kernel driver in use: iwlwifi
> Kernel modules: iwlwifi
>
We already have a bug open on that:
https://bugzilla.kernel.org/show_bug.cgi?id=56581