Hi Arend, Thanks for the quick reply. On Tue, Jan 22, 2013 at 10:09 PM, Arend van Spriel <arend@xxxxxxxxxxxx> wrote: > Always too bad when getting a trace like this. It would be good to have > a bit more context so could you provide 10-20 lines before and after > this WARNING. Nothing interesting happens before the warning. After the warning occurs my network (as expected?) stops working, I therefore manually disconnect and reconnect it (in NetworkManager), which you can see in the log below. It then (mostly?) works as normal. Cheers, Tom -- Logs begin at Tue 2013-01-22 18:57:40 CET, end at Tue 2013-01-22 22:30:45 CET. -- Jan 22 19:07:04 toms-air kernel: ------------[ cut here ]------------ Jan 22 19:07:04 toms-air kernel: WARNING: at drivers/net/wireless/brcm80211/brcmsmac/main.c:7539 brcms_c_wait_for_tx_completion+0xab/0xc0 [brcmsmac]() Jan 22 19:07:04 toms-air kernel: Hardware name: MacBookAir5,1 Jan 22 19:07:04 toms-air kernel: Modules linked in: efivars hid_generic mousedev hid_apple usbhid bcm5974 brcmsmac cordic mac80211 snd_hda_codec_hdmi snd_hda_codec_cirrus brcmutil i915 cfg80211 kvm_intel kvm rfkill i2c_algo_bit snd_hda_intel intel_agp intel_gtt snd_hda_codec drm_kms_helper snd_hwdep aesni_intel aes_x86_64 snd_pcm xhci_hcd ehci_pci applesmc ehci_hcd input_polldev drm microcode led_class snd_page_alloc snd_timer usbcore snd bcma agpgart soundcore usb_common battery acpi_cpufreq mperf ac evdev processor Jan 22 19:07:04 toms-air kernel: Pid: 84, comm: kworker/u:7 Not tainted 3.8.0-rc4-TEG #2 Jan 22 19:07:04 toms-air kernel: Call Trace: Jan 22 19:07:04 toms-air kernel: [<ffffffff81043e9f>] warn_slowpath_common+0x7f/0xc0 Jan 22 19:07:04 toms-air kernel: [<ffffffff81043efa>] warn_slowpath_null+0x1a/0x20 Jan 22 19:07:04 toms-air kernel: [<ffffffffa04ca32b>] brcms_c_wait_for_tx_completion+0xab/0xc0 [brcmsmac] Jan 22 19:07:04 toms-air kernel: [<ffffffffa04bd8ab>] brcms_ops_flush+0x3b/0x60 [brcmsmac] Jan 22 19:07:04 toms-air kernel: [<ffffffffa04317b3>] ieee80211_scan_work+0x1b3/0x630 [mac80211] Jan 22 19:07:04 toms-air kernel: [<ffffffff810615d2>] process_one_work+0x132/0x4f0 Jan 22 19:07:04 toms-air kernel: [<ffffffff81078cd1>] ? get_parent_ip+0x11/0x50 Jan 22 19:07:04 toms-air kernel: [<ffffffffa0431600>] ? ieee80211_run_deferred_scan+0x90/0x90 [mac80211] Jan 22 19:07:04 toms-air kernel: [<ffffffff81062cfd>] worker_thread+0x15d/0x450 Jan 22 19:07:04 toms-air kernel: [<ffffffff81062ba0>] ? busy_worker_rebind_fn+0x100/0x100 Jan 22 19:07:04 toms-air kernel: [<ffffffff81068d00>] kthread+0xc0/0xd0 Jan 22 19:07:04 toms-air kernel: [<ffffffff815f0000>] ? register_cpu+0x4e/0xc5 Jan 22 19:07:04 toms-air kernel: [<ffffffff81068c40>] ? kthread_create_on_node+0x130/0x130 Jan 22 19:07:04 toms-air kernel: [<ffffffff81602d1c>] ret_from_fork+0x7c/0xb0 Jan 22 19:07:04 toms-air kernel: [<ffffffff81068c40>] ? kthread_create_on_node+0x130/0x130 Jan 22 19:07:04 toms-air kernel: ---[ end trace 2264c0238d7b0ba5 ]--- Jan 22 19:07:05 toms-air kernel: brcmsmac bcma0:0: brcms_c_ampdu_dotxstatus_complete: Pkt tx suppressed, illegal channel possibly 157 Jan 22 19:07:05 toms-air kernel: brcmsmac bcma0:0: brcms_c_ampdu_dotxstatus_complete: Pkt tx suppressed, illegal channel possibly 157 Jan 22 19:24:59 toms-air kernel: brcmsmac bcma0:0: brcms_c_ampdu_dotxstatus_complete: Pkt tx suppressed, illegal channel possibly 1 Jan 22 19:24:59 toms-air kernel: brcmsmac bcma0:0: brcms_c_ampdu_dotxstatus_complete: Pkt tx suppressed, illegal channel possibly 1 Jan 22 19:24:59 toms-air kernel: brcmsmac bcma0:0: brcms_c_ampdu_dotxstatus_complete: Pkt tx suppressed, illegal channel possibly 1 Jan 22 19:24:59 toms-air kernel: brcmsmac bcma0:0: brcms_c_ampdu_dotxstatus_complete: Pkt tx suppressed, illegal channel possibly 1 Jan 22 19:47:20 toms-air kernel: ALSA sound/pci/hda/hda_intel.c:2300 0000:00:1b.0: Unstable LPIB (131024 >= 8192); disabling LPIB delay counting Jan 22 20:31:58 toms-air NetworkManager[254]: <info> (wlp2s0): device state change: activated -> disconnected (reason 'user-requested') [100 30 39] Jan 22 20:31:58 toms-air NetworkManager[254]: <info> (wlp2s0): deactivating device (reason 'user-requested') [39] Jan 22 20:31:58 toms-air NetworkManager[254]: <info> (wlp2s0): canceled DHCP transaction, DHCP client pid 296 Jan 22 20:31:58 toms-air kernel: wlp2s0: deauthenticating from f4:ca:e5:d9:f7:e0 by local choice (reason=3) Jan 22 20:31:58 toms-air kernel: brcmsmac bcma0:0: brcmsmac: brcms_ops_bss_info_changed: disassociated Jan 22 20:31:58 toms-air kernel: brcmsmac bcma0:0: brcms_ops_bss_info_changed: arp filtering: enabled false, count 1 (implement) Jan 22 20:31:58 toms-air kernel: brcmsmac bcma0:0: brcms_ops_bss_info_changed: qos enabled: false (implement) Jan 22 20:31:58 toms-air NetworkManager[254]: <info> (wlp2s0): supplicant interface state: completed -> disconnected Jan 22 20:31:58 toms-air kernel: cfg80211: Calling CRDA to update world regulatory domain Jan 22 20:31:58 toms-air dbus-daemon[260]: dbus[260]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper) Jan 22 20:31:58 toms-air dbus[260]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper) Jan 22 20:31:58 toms-air dbus-daemon[260]: dbus[260]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' Jan 22 20:31:58 toms-air dbus[260]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) starting connection 'tomicko' Jan 22 20:32:00 toms-air NetworkManager[254]: <info> (wlp2s0): device state change: disconnected -> prepare (reason 'none') [30 40 0] Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) scheduled... Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) started... Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) scheduled... Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) complete. Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) starting... Jan 22 20:32:00 toms-air NetworkManager[254]: <info> (wlp2s0): device state change: prepare -> config (reason 'none') [40 50 0] Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0/wireless): access point 'tomicko' has security, but secrets are required. Jan 22 20:32:00 toms-air NetworkManager[254]: <info> (wlp2s0): device state change: config -> need-auth (reason 'none') [50 60 0] Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) complete. Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) scheduled... Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) started... Jan 22 20:32:00 toms-air NetworkManager[254]: <info> (wlp2s0): device state change: need-auth -> prepare (reason 'none') [60 40 0] Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) scheduled... Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 1 of 5 (Device Prepare) complete. Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) starting... Jan 22 20:32:00 toms-air NetworkManager[254]: <info> (wlp2s0): device state change: prepare -> config (reason 'none') [40 50 0] Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0/wireless): connection 'tomicko' has security, and secrets exist. No new secrets needed. Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Config: added 'ssid' value 'tomicko' Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Config: added 'scan_ssid' value '1' Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Config: added 'key_mgmt' value 'WPA-PSK' Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Config: added 'auth_alg' value 'OPEN' Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Config: added 'psk' value '<omitted>' Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 2 of 5 (Device Configure) complete. Jan 22 20:32:00 toms-air NetworkManager[254]: <info> Config: set interface ap_scan to 1 Jan 22 20:32:00 toms-air NetworkManager[254]: <info> (wlp2s0): supplicant interface state: disconnected -> scanning Jan 22 20:32:04 toms-air kernel: wlp2s0: authenticate with f4:ca:e5:d9:f7:e0 Jan 22 20:32:04 toms-air kernel: wlp2s0: send auth to f4:ca:e5:d9:f7:e0 (try 1/3) Jan 22 20:32:04 toms-air NetworkManager[254]: <info> (wlp2s0): supplicant interface state: scanning -> authenticating Jan 22 20:32:04 toms-air kernel: wlp2s0: authenticated Jan 22 20:32:04 toms-air kernel: wlp2s0: associate with f4:ca:e5:d9:f7:e0 (try 1/3) Jan 22 20:32:04 toms-air kernel: wlp2s0: RX AssocResp from f4:ca:e5:d9:f7:e0 (capab=0x411 status=0 aid=1) Jan 22 20:32:04 toms-air kernel: brcmsmac bcma0:0: brcmsmac: brcms_ops_bss_info_changed: associated Jan 22 20:32:04 toms-air kernel: brcmsmac bcma0:0: brcms_ops_bss_info_changed: arp filtering: enabled true, count 0 (implement) Jan 22 20:32:04 toms-air NetworkManager[254]: <info> (wlp2s0): supplicant interface state: authenticating -> associating Jan 22 20:32:04 toms-air kernel: brcmsmac bcma0:0: brcms_ops_bss_info_changed: qos enabled: true (implement) Jan 22 20:32:04 toms-air kernel: wlp2s0: associated Jan 22 20:32:05 toms-air NetworkManager[254]: <info> (wlp2s0): supplicant interface state: associating -> 4-way handshake Jan 22 20:32:05 toms-air NetworkManager[254]: <info> (wlp2s0): supplicant interface state: 4-way handshake -> group handshake Jan 22 20:32:05 toms-air NetworkManager[254]: <info> (wlp2s0): supplicant interface state: group handshake -> completed Jan 22 20:32:05 toms-air NetworkManager[254]: <info> Activation (wlp2s0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'tomicko'. Jan 22 20:32:05 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 3 of 5 (IP Configure Start) scheduled. Jan 22 20:32:05 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 3 of 5 (IP Configure Start) started... Jan 22 20:32:05 toms-air NetworkManager[254]: <info> (wlp2s0): device state change: config -> ip-config (reason 'none') [50 70 0] Jan 22 20:32:05 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Beginning DHCPv4 transaction (timeout in 45 seconds) Jan 22 20:32:05 toms-air NetworkManager[254]: <info> dhclient started with pid 11660 Jan 22 20:32:05 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Beginning IP6 addrconf. Jan 22 20:32:05 toms-air avahi-daemon[259]: Withdrawing address record for fe80::9afe:94ff:fe3f:c618 on wlp2s0. Jan 22 20:32:05 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 3 of 5 (IP Configure Start) complete. Jan 22 20:32:05 toms-air dhclient[11660]: Internet Systems Consortium DHCP Client 4.2.4-P2 Jan 22 20:32:05 toms-air dhclient[11660]: Copyright 2004-2012 Internet Systems Consortium. Jan 22 20:32:05 toms-air dhclient[11660]: All rights reserved. Jan 22 20:32:05 toms-air dhclient[11660]: For info, please visit https://www.isc.org/software/dhcp/ Jan 22 20:32:05 toms-air dhclient[11660]: Jan 22 20:32:05 toms-air NetworkManager[254]: <info> (wlp2s0): DHCPv4 state changed nbi -> preinit Jan 22 20:32:05 toms-air dhclient[11660]: Listening on LPF/wlp2s0/98:fe:94:3f:c6:18 Jan 22 20:32:05 toms-air dhclient[11660]: Sending on LPF/wlp2s0/98:fe:94:3f:c6:18 Jan 22 20:32:05 toms-air dhclient[11660]: Sending on Socket/fallback Jan 22 20:32:05 toms-air dhclient[11660]: DHCPREQUEST on wlp2s0 to 255.255.255.255 port 67 Jan 22 20:32:05 toms-air dhclient[11660]: DHCPACK from 192.168.1.254 Jan 22 20:32:05 toms-air NetworkManager[254]: <info> (wlp2s0): DHCPv4 state changed preinit -> reboot Jan 22 20:32:05 toms-air NetworkManager[254]: <info> address 192.168.1.14 Jan 22 20:32:05 toms-air NetworkManager[254]: <info> prefix 24 (255.255.255.0) Jan 22 20:32:05 toms-air NetworkManager[254]: <info> gateway 192.168.1.254 Jan 22 20:32:05 toms-air NetworkManager[254]: <info> nameserver '8.8.4.4' Jan 22 20:32:05 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 5 of 5 (IPv4 Configure Commit) scheduled... Jan 22 20:32:05 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 5 of 5 (IPv4 Commit) started... Jan 22 20:32:05 toms-air dhclient[11660]: bound to 192.168.1.14 -- renewal in 20390 seconds. Jan 22 20:32:05 toms-air kernel: brcmsmac bcma0:0: brcms_ops_bss_info_changed: arp filtering: enabled true, count 1 (implement) Jan 22 20:32:06 toms-air NetworkManager[254]: <info> (wlp2s0): device state change: ip-config -> activated (reason 'none') [70 100 0] Jan 22 20:32:06 toms-air NetworkManager[254]: <info> Policy set 'tomicko' (wlp2s0) as default for IPv4 routing and DNS. Jan 22 20:32:06 toms-air NetworkManager[254]: <info> Activation (wlp2s0) successful, device activated. Jan 22 20:32:06 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 5 of 5 (IPv4 Commit) complete. Jan 22 20:32:07 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 5 of 5 (IPv6 Commit) scheduled... Jan 22 20:32:07 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 5 of 5 (IPv6 Commit) started... Jan 22 20:32:08 toms-air NetworkManager[254]: <info> Policy set 'tomicko' (wlp2s0) as default for IPv6 routing and DNS. Jan 22 20:32:08 toms-air NetworkManager[254]: <info> Activation (wlp2s0) Stage 5 of 5 (IPv6 Commit) complete. Jan 22 20:37:58 toms-air kernel: brcmsmac bcma0:0: brcms_c_ampdu_dotxstatus_complete: Pkt tx suppressed, illegal channel possibly 165 -- To unsubscribe from this list: send the line "unsubscribe linux-wireless" in the body of a message to majordomo@xxxxxxxxxxxxxxx More majordomo info at http://vger.kernel.org/majordomo-info.html
