2013-01-26 05:51:45

by Kalle Valo

[permalink] [raw]
Subject: WARNING: at net/mac80211/agg-tx.c:685 ieee80211_start_tx_ba_cb

Hi,

I noticed this warning in the log just by accident, I haven't had any
problems. I'm running latest wireless-testing (commit 9d9540984a) on
thinkpad x230 with Intel 6205.

[ 10.262245] wlan0: authenticate with 00:26:f2:f7:cf:b2
[ 10.272891] wlan0: send auth to 00:26:f2:f7:cf:b2 (try 1/3)
[ 10.275093] wlan0: authenticated
[ 10.278751] wlan0: associate with 00:26:f2:f7:cf:b2 (try 1/3)
[ 10.282307] wlan0: RX AssocResp from 00:26:f2:f7:cf:b2 (capab=0x411
status=0 aid=3)
[ 10.303014] wlan0: associated
[ 10.303062] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 19.806954] EXT4-fs (dm-1): mounted filesystem with ordered data
mode. Opts: errors=remount-ro
[ 836.534120] wlan0: deauthenticating from 00:26:f2:f7:cf:b2 by local
choice (reason=3)
[ 836.570864] ------------[ cut here ]------------
[ 836.570894] WARNING: at net/mac80211/agg-tx.c:685
ieee80211_start_tx_ba_cb+0x1b5/0x240 [mac80211]()
[ 836.570896] Hardware name: 2324JB2
[ 836.570898] Modules linked in: fuse uvcvideo videobuf2_core videodev
videobuf2_vmalloc videobuf2_memops sha256_generic arc4 dm_crypt dm_mod
snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_intel snd_hda_codec
snd_hwdep snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss
coretemp snd_seq_midi kvm_intel snd_rawmidi kvm snd_seq_midi_event
snd_seq snd_timer snd_seq_device thinkpad_acpi iwldvm wmi nvram mac80211
snd iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 iwlwifi nf_nat_ipv4
nf_nat nf_conntrack ip_tables rfcomm x_tables cfg80211 microcode
ehci_pci psmouse bnep soundcore ehci_hcd snd_page_alloc bluetooth rfkill
binfmt_misc ext4 mbcache jbd2 sd_mod crc32c_intel ahci libahci
aesni_intel ablk_helper cryptd lrw aes_x86_64 xts gf128mul i915 fbcon
tileblit font bitblit softcursor sdhci_pci sdhci drm_kms_helper mmc_core
drm e1000e intel_agp cfbcopyarea i2c_algo_bit intel_gtt agpgart i2c_core
video cfbimgblt cfbfillrect
[ 836.570983] Pid: 228, comm: kworker/u:4 Not tainted 3.8.0-rc4-wl #158
[ 836.570985] Call Trace:
[ 836.570994] [<ffffffff8103d270>] ? warn_slowpath_common+0x80/0xc0
[ 836.571008] [<ffffffffa043e795>] ?
ieee80211_start_tx_ba_cb+0x1b5/0x240 [mac80211]
[ 836.571020] [<ffffffffa044344a>] ? ieee80211_iface_work+0x23a/0x360
[mac80211]
[ 836.571026] [<ffffffff8105a638>] ? process_one_work+0x128/0x440
[ 836.571030] [<ffffffff8105a400>] ?
cwq_activate_delayed_work+0x50/0xb0
[ 836.571041] [<ffffffffa0443210>] ?
ieee80211_recalc_smps_work+0x20/0x20 [mac80211]
[ 836.571046] [<ffffffff8105c258>] ? worker_thread+0x158/0x3f0
[ 836.571050] [<ffffffff8105c100>] ? manage_workers+0x120/0x120
[ 836.571056] [<ffffffff810614d8>] ? kthread+0xb8/0xc0
[ 836.571061] [<ffffffff81060000>] ? do_schedule_next_timer+0x60/0xf0
[ 836.571066] [<ffffffff81061420>] ? flush_kthread_work+0x130/0x130
[ 836.571072] [<ffffffff81483b1c>] ? ret_from_fork+0x7c/0xb0
[ 836.571078] [<ffffffff81061420>] ? flush_kthread_work+0x130/0x130
[ 836.571080] ---[ end trace 47a8f428a09862e9 ]---
[ 836.627476] cfg80211: Calling CRDA to update world regulatory domain
[ 836.627777] e1000e 0000:00:19.0: irq 42 for MSI/MSI-X
[ 836.744181] cfg80211: World regulatory domain updated:

--
Kalle Valo


2013-01-29 10:59:46

by Johannes Berg

[permalink] [raw]
Subject: Re: WARNING: at net/mac80211/agg-tx.c:685 ieee80211_start_tx_ba_cb

On Sun, 2013-01-27 at 08:07 +0200, Kalle Valo wrote:
> Johannes Berg <[email protected]> writes:

> Jan 26 19:54:36 x230 kernel: [18654.186629] WARNING: at
> net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
> [mac80211]()

These must be during suspend/resume, care to post a backtrace or two
(are they all identical?)

johannes


2013-01-27 06:07:04

by Kalle Valo

[permalink] [raw]
Subject: Re: WARNING: at net/mac80211/agg-tx.c:685 ieee80211_start_tx_ba_cb

Johannes Berg <[email protected]> writes:

> On Sat, 2013-01-26 at 07:51 +0200, Kalle Valo wrote:
>> Hi,
>>
>> I noticed this warning in the log just by accident, I haven't had any
>> problems. I'm running latest wireless-testing (commit 9d9540984a) on
>> thinkpad x230 with Intel 6205.
>>
>> [ 10.262245] wlan0: authenticate with 00:26:f2:f7:cf:b2
>> [ 10.272891] wlan0: send auth to 00:26:f2:f7:cf:b2 (try 1/3)
>> [ 10.275093] wlan0: authenticated
>> [ 10.278751] wlan0: associate with 00:26:f2:f7:cf:b2 (try 1/3)
>> [ 10.282307] wlan0: RX AssocResp from 00:26:f2:f7:cf:b2 (capab=0x411 status=0 aid=3)
>> [ 10.303014] wlan0: associated
>> [ 10.303062] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
>> [ 836.534120] wlan0: deauthenticating from 00:26:f2:f7:cf:b2 by local choice (reason=3)
>> [ 836.570864] ------------[ cut here ]------------
>> [ 836.570894] WARNING: at net/mac80211/agg-tx.c:685 ieee80211_start_tx_ba_cb+0x1b5/0x240 [mac80211]()
>
> Smells like a race to me. We probably requested aggregation and then
> deauthenticated, thus killing the session data. Then the driver probably
> had a bug and said it started the session or something ...

Just did a grep and noticed even more warnings:

x230:~$ zgrep --no-filename WARNING /var/log/syslog*
Jan 26 19:54:36 x230 kernel: [18654.186629] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 26 20:59:57 x230 kernel: [20275.013461] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 25 20:22:01 x230 kernel: [ 836.570894] WARNING: at
net/mac80211/agg-tx.c:685 ieee80211_start_tx_ba_cb+0x1b5/0x240
[mac80211]()
Jan 22 21:39:44 x230 kernel: [29075.830248] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 22 22:05:49 x230 kernel: [29135.651334] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 22 23:01:11 x230 kernel: [31878.606310] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 23 18:36:33 x230 kernel: [ 9113.473283] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 18 09:11:18 x230 kernel: [65051.228555] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 18 21:43:10 x230 kernel: [81530.791082] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 18 22:15:40 x230 kernel: [82837.817517] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 20 10:31:23 x230 kernel: [88609.307199] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 20 22:51:56 x230 kernel: [98535.334962] WARNING: at
drivers/net/wireless/iwlwifi/dvm/tx.c:434 iwlagn_tx_skb+0x7c1/0x840
[iwldvm]()
Jan 21 07:18:08 x230 kernel: [99957.410266] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 22 09:17:12 x230 kernel: [120963.155777] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 15 08:04:25 x230 kernel: [40630.492569] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()
Jan 15 16:24:50 x230 kernel: [56111.825186] WARNING: at
drivers/net/wireless/iwlwifi/dvm/tx.c:434 iwlagn_tx_skb+0x7c1/0x840
[iwldvm]()
Jan 16 07:20:36 x230 kernel: [13251.280305] WARNING: at
net/mac80211/util.c:599 ieee80211_can_queue_work.isra.10+0x32/0x40
[mac80211]()

These are with various versions of wireless-testing, I update pretty
frequently.

--
Kalle Valo

2013-01-26 20:53:25

by Johannes Berg

[permalink] [raw]
Subject: Re: WARNING: at net/mac80211/agg-tx.c:685 ieee80211_start_tx_ba_cb

On Sat, 2013-01-26 at 07:51 +0200, Kalle Valo wrote:
> Hi,
>
> I noticed this warning in the log just by accident, I haven't had any
> problems. I'm running latest wireless-testing (commit 9d9540984a) on
> thinkpad x230 with Intel 6205.
>
> [ 10.262245] wlan0: authenticate with 00:26:f2:f7:cf:b2
> [ 10.272891] wlan0: send auth to 00:26:f2:f7:cf:b2 (try 1/3)
> [ 10.275093] wlan0: authenticated
> [ 10.278751] wlan0: associate with 00:26:f2:f7:cf:b2 (try 1/3)
> [ 10.282307] wlan0: RX AssocResp from 00:26:f2:f7:cf:b2 (capab=0x411 status=0 aid=3)
> [ 10.303014] wlan0: associated
> [ 10.303062] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
> [ 836.534120] wlan0: deauthenticating from 00:26:f2:f7:cf:b2 by local choice (reason=3)
> [ 836.570864] ------------[ cut here ]------------
> [ 836.570894] WARNING: at net/mac80211/agg-tx.c:685 ieee80211_start_tx_ba_cb+0x1b5/0x240 [mac80211]()

Smells like a race to me. We probably requested aggregation and then
deauthenticated, thus killing the session data. Then the driver probably
had a bug and said it started the session or something ...

johannes