Ubuntu
bcmwl package

wireless doesn't associate after waking from suspend

Bug #1289884 reported by Joe Barnett on 2014-03-08

This bug report is a duplicate of: Bug #1252121: missing PrepareForSleep signal after resuming, causing networking to stay disabled. Edit Remove

This bug affects 6 people

Affects		Status	Importance	Assigned to	Milestone
	bcmwl (Ubuntu)	Confirmed	High	Unassigned
	linux (Ubuntu)	Confirmed	High	Unassigned

Bug Description

on a macbook pro retina, the wireless often doesn't work after waking from suspend. sometimes combinations of unloading and reloading kernel modules and restarting network-manager works; other times i need to reboot before I can reconnect to the wireless.

here's syslog after a network-manager restart during one of the times i needed to reboot.

Mar 8 13:38:42 leafy ModemManager[833]: <info> Caught signal, shutting down...
Mar 8 13:38:42 leafy ModemManager[833]: <info> ModemManager is shut down
Mar 8 13:38:42 leafy ModemManager[4223]: <info> ModemManager (version 1.0.0) starting...
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> NetworkManager (version 0.9.8.8) is starting...
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> Read config file /etc/NetworkManager/NetworkManager.conf
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> WEXT support is enabled
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> VPN: loaded org.freedesktop.NetworkManager.pptp
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> DNS: loaded plugin dnsmasq
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ifupdown: init!
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ifupdown: update_system_hostname
Mar 8 13:38:42 leafy NetworkManager[4230]: interface-parser: parsing file /etc/network/interfaces
Mar 8 13:38:42 leafy NetworkManager[4230]: interface-parser: finished parsing file /etc/network/interf
aces
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPluginIfupdown: management mode: unmanaged
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:0
0/0000:00:1c.2/0000:03:00.0/net/wlan0, iface: wlan0)
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00
/0000:00:1c.2/0000:03:00.0/net/wlan0, iface: wlan0): no ifupdown configuration found.
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/n
et/lo, iface: lo)
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/ne
t/lo, iface: lo): no ifupdown configuration found.
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ifupdown: end _init.
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> Loaded plugin ifupdown: (C) 2008 Canonical Ltd. To report
bugs please use the NetworkManager mailing list.
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc. To r
eport bugs please use the NetworkManager mailing list.
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ofono: Acquired D-Bus service com.canonical.NMOfono
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ofono: init!
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ofono: end _init.
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> Loaded plugin (null): (null)
Mar 8 13:38:42 leafy NetworkManager[4230]: Ifupdown: get unmanaged devices count: 0
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ifupdown: (13606448) ... get_connections.
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ifupdown: (13606448) ... get_connections (managed=fal
se): return empty list.
Mar 8 13:38:42 leafy NetworkManager[4230]: keyfile: parsing HKGJ3 ...
Mar 8 13:38:42 leafy NetworkManager[4230]: keyfile: read connection 'HKGJ3'
Mar 8 13:38:42 leafy NetworkManager[4230]: keyfile: parsing Marin Secure ...
Mar 8 13:38:42 leafy NetworkManager[4230]: keyfile: read connection 'Marin Secure'
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ofono: (67124512) ... get_connections.
Mar 8 13:38:42 leafy NetworkManager[4230]: SCPlugin-Ofono: (67124512) connections count: 0
Mar 8 13:38:42 leafy NetworkManager[4230]: Ifupdown: get unmanaged devices count: 0
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> monitoring kernel firmware directory '/lib/firmware'.
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> rfkill3: found WiFi radio killswitch (at /sys/devices/pci0
000:00/0000:00:1c.2/0000:03:00.0/ieee80211/phy0/rfkill3) (driver wl)
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> rfkill4: found WiFi radio killswitch (at /sys/devices/pci0
000:00/0000:00:1c.2/0000:03:00.0/net/wlan0/rfkill4) (driver wl)
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> WiFi enabled by radio killswitch; enabled by state file
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> WWAN enabled by radio killswitch; enabled by state file
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> WiMAX enabled by radio killswitch; enabled by state file
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> Networking is enabled by state file
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0): using nl80211 for WiFi device control
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0): new 802.11 WiFi device (driver: 'wl' ifindex: 3)
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devic
es/0
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0): device state change: unmanaged -> unavailable (re
ason 'managed') [10 20 2]
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0): bringing up device.
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0): preparing device.
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0): deactivating device (reason 'managed') [2]
Mar 8 13:38:42 leafy NetworkManager[4230]: <warn> /sys/devices/virtual/net/lo: couldn't determine device dri
ver; ignoring...
Mar 8 13:38:42 leafy NetworkManager[4230]: <warn> /sys/devices/virtual/net/lo: couldn't determine device dri
ver; ignoring...
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> urfkill disappeared from the bus
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> ModemManager available in the bus
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0) supports 1 scan SSIDs
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0): supplicant interface state: starting -> ready
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0): device state change: unavailable -> disconnected
(reason 'supplicant-available') [20 30 42]
Mar 8 13:38:42 leafy NetworkManager[4230]: <warn> Trying to remove a non-existant call id.
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0): supplicant interface state: ready -> disconnected
Mar 8 13:38:42 leafy NetworkManager[4230]: <info> (wlan0) supports 1 scan SSIDs
Mar 8 13:38:43 leafy avahi-daemon[731]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe
80::7a31:c1ff:febb:af0c.
Mar 8 13:38:43 leafy avahi-daemon[731]: New relevant interface wlan0.IPv6 for mDNS.
Mar 8 13:38:43 leafy avahi-daemon[731]: Registering new address record for fe80::7a31:c1ff:febb:af0c on wlan
0.*.
Mar 8 13:38:44 leafy ModemManager[4223]: <warn> Couldn't find support for device at '/sys/devices/pci0000:0
0/0000:00:1c.2/0000:03:00.0': not supported by any plugin
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> (wlan0): supplicant interface state: disconnected -> inact
ive
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Auto-activating connection 'HKGJ3'.
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Activation (wlan0) starting connection 'HKGJ3'
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> (wlan0): device state change: disconnected -> prepare (rea
son 'none') [30 40 0]
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> NetworkManager state is now CONNECTING
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled
...
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started..
.
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) schedul
ed...
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) startin
g...
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> (wlan0): device state change: prepare -> config (reason 'n
one') [40 50 0]
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Activation (wlan0/wireless): connection 'HKGJ3' has securi
ty, and secrets exist. No new secrets needed.
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Config: added 'ssid' value 'HKGJ3'
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Config: added 'scan_ssid' value '1'
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Config: added 'key_mgmt' value 'NONE'
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Config: added 'auth_alg' value 'OPEN'
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Config: added 'wep_key0' value '<omitted>'
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Config: added 'wep_tx_keyidx' value '0'
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complet
e.
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> Config: set interface ap_scan to 1
Mar 8 13:38:52 leafy NetworkManager[4230]: <info> WiFi hardware radio set enabled
Mar 8 13:39:02 leafy wpa_supplicant[947]: wlan0: Trying to associate with 00:26:62:5a:bd:a6 (SSID='HKGJ3' freq=2462 MHz)
Mar 8 13:39:02 leafy NetworkManager[4230]: <info> (wlan0): supplicant interface state: inactive -> associating
Mar 8 13:39:02 leafy kernel: [ 301.876221] cfg80211: Calling CRDA for country: US
Mar 8 13:39:02 leafy kernel: [ 301.878188] cfg80211: Regulatory domain changed to country: US
Mar 8 13:39:02 leafy kernel: [ 301.878189] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Mar 8 13:39:02 leafy kernel: [ 301.878191] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
Mar 8 13:39:02 leafy kernel: [ 301.878192] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
Mar 8 13:39:02 leafy kernel: [ 301.878192] cfg80211: (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 8 13:39:02 leafy kernel: [ 301.878193] cfg80211: (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 8 13:39:02 leafy kernel: [ 301.878194] cfg80211: (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 8 13:39:02 leafy kernel: [ 301.878195] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
Mar 8 13:39:02 leafy kernel: [ 301.878196] cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4000 mBm)
Mar 8 13:39:12 leafy wpa_supplicant[947]: wlan0: Authentication with 00:26:62:5a:bd:a6 timed out.
Mar 8 13:39:12 leafy wpa_supplicant[947]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:26:62:5a:bd:a6 reason=3 locally_generated=1
Mar 8 13:39:12 leafy wpa_supplicant[947]: wlan0: Trying to associate with 00:26:62:5a:bd:a6 (SSID='HKGJ3' freq=2462 MHz)
Mar 8 13:39:12 leafy NetworkManager[4230]: <warn> Connection disconnected (reason -3)
Mar 8 13:39:12 leafy kernel: [ 311.653148] cfg80211: Calling CRDA to update world regulatory domain
Mar 8 13:39:12 leafy kernel: [ 311.655002] cfg80211: World regulatory domain updated:
Mar 8 13:39:12 leafy kernel: [ 311.655004] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Mar 8 13:39:12 leafy kernel: [ 311.655005] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 8 13:39:12 leafy kernel: [ 311.655006] cfg80211: (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 8 13:39:12 leafy kernel: [ 311.655007] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Mar 8 13:39:12 leafy kernel: [ 311.655008] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 8 13:39:12 leafy kernel: [ 311.655008] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 8 13:39:12 leafy kernel: [ 311.768353] ------------[ cut here ]------------
Mar 8 13:39:12 leafy kernel: [ 311.768374] WARNING: CPU: 0 PID: 3975 at /build/buildd/linux-3.13.0/net/wireless/sme.c:662 __cfg80211_connect_result+0x3db/0x420 [cfg80211]()
Mar 8 13:39:12 leafy kernel: [ 311.768375] Modules linked in: lib80211_crypt_tkip wl(POF) lib80211 cfg80211 pci_stub vboxpci(OF) vboxnetadp(OF) vboxnetflt(OF) vboxdrv(OF) dm_crypt snd_hda_codec_hdmi rfcomm bnep x86_pkg_temp_thermal intel_powerclamp parport_pc coretemp ppdev kvm_intel joydev nvidia(POF) snd_hda_codec_cirrus btusb bluetooth snd_hda_intel applesmc kvm bcm5974 snd_hda_codec input_polldev crct10dif_pclmul crc32_pclmul snd_hwdep snd_pcm ghash_clmulni_intel snd_page_alloc snd_seq_midi aesni_intel aes_x86_64 snd_seq_midi_event lrw gf128mul snd_rawmidi glue_helper ablk_helper cryptd snd_seq snd_seq_device mei_me snd_timer lpc_ich mei snd drm soundcore apple_gmux binfmt_misc apple_bl mac_hid nls_iso8859_1 lp parport ahci usb_storage libahci hid_generic hid_apple hid_logitech_dj usbhid hid video [last unloaded: lib80211]
Mar 8 13:39:12 leafy kernel: [ 311.768426] CPU: 0 PID: 3975 Comm: kworker/u16:5 Tainted: PF O 3.13.0-16-generic #36-Ubuntu
Mar 8 13:39:12 leafy kernel: [ 311.768428] Hardware name: Apple Inc. MacBookPro11,3/Mac-2BD1B31983FE1663, BIOS MBP112.88Z.0138.B02.1310181745 10/18/2013
Mar 8 13:39:12 leafy kernel: [ 311.768435] Workqueue: cfg80211 cfg80211_event_work [cfg80211]
Mar 8 13:39:12 leafy kernel: [ 311.768437] 0000000000000009 ffff880079747ca8 ffffffff81710b65 0000000000000000
Mar 8 13:39:12 leafy kernel: [ 311.768441] ffff880079747ce0 ffffffff810662cd 0000000000000000 0000000000000000
Mar 8 13:39:12 leafy kernel: [ 311.768444] ffff88046728a418 ffff880467211800 ffff880468aac000 ffff880079747cf0
Mar 8 13:39:12 leafy kernel: [ 311.768447] Call Trace:
Mar 8 13:39:12 leafy kernel: [ 311.768453] [<ffffffff81710b65>] dump_stack+0x45/0x56
Mar 8 13:39:12 leafy kernel: [ 311.768457] [<ffffffff810662cd>] warn_slowpath_common+0x7d/0xa0
Mar 8 13:39:12 leafy kernel: [ 311.768460] [<ffffffff810663aa>] warn_slowpath_null+0x1a/0x20
Mar 8 13:39:12 leafy kernel: [ 311.768470] [<ffffffffa01632fb>] __cfg80211_connect_result+0x3db/0x420 [cfg80211]
Mar 8 13:39:12 leafy kernel: [ 311.768475] [<ffffffff8101b763>] ? native_sched_clock+0x13/0x80
Mar 8 13:39:12 leafy kernel: [ 311.768478] [<ffffffff8101b7d9>] ? sched_clock+0x9/0x10
Mar 8 13:39:12 leafy kernel: [ 311.768486] [<ffffffffa01415a4>] cfg80211_process_wdev_events+0x194/0x1c0 [cfg80211]
Mar 8 13:39:12 leafy kernel: [ 311.768494] [<ffffffffa0141610>] cfg80211_process_rdev_events+0x40/0x90 [cfg80211]
Mar 8 13:39:12 leafy kernel: [ 311.768500] [<ffffffffa013d01e>] cfg80211_event_work+0x1e/0x30 [cfg80211]
Mar 8 13:39:12 leafy kernel: [ 311.768506] [<ffffffff81082482>] process_one_work+0x182/0x450
Mar 8 13:39:12 leafy kernel: [ 311.768509] [<ffffffff81083221>] worker_thread+0x121/0x410
Mar 8 13:39:12 leafy kernel: [ 311.768512] [<ffffffff81083100>] ? rescuer_thread+0x3e0/0x3e0
Mar 8 13:39:12 leafy kernel: [ 311.768515] [<ffffffff81089eb2>] kthread+0xd2/0xf0
Mar 8 13:39:12 leafy kernel: [ 311.768518] [<ffffffff81089de0>] ? kthread_create_on_node+0x190/0x190
Mar 8 13:39:12 leafy kernel: [ 311.768522] [<ffffffff817214bc>] ret_from_fork+0x7c/0xb0
Mar 8 13:39:12 leafy kernel: [ 311.768525] [<ffffffff81089de0>] ? kthread_create_on_node+0x190/0x190
Mar 8 13:39:12 leafy kernel: [ 311.768527] ---[ end trace fd5add861d6b4628 ]---
Mar 8 13:39:17 leafy NetworkManager[4230]: <warn> Activation (wlan0/wireless): association took too long.
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
Mar 8 13:39:17 leafy NetworkManager[4230]: <warn> Activation (wlan0/wireless): asking for new secrets
Mar 8 13:39:17 leafy wpa_supplicant[947]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:26:62:5a:bd:a6 reason=3 locally_generated=1
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 8 13:39:17 leafy NetworkManager[4230]: <warn> Connection disconnected (reason -3)
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> (wlan0): supplicant interface state: associating -> disconnected
Mar 8 13:39:17 leafy NetworkManager[4230]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Activation (wlan0/wireless): connection 'HKGJ3' has security, and secrets exist. No new secrets needed.
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Config: added 'ssid' value 'HKGJ3'
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Config: added 'scan_ssid' value '1'
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Config: added 'key_mgmt' value 'NONE'
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Config: added 'auth_alg' value 'OPEN'
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Config: added 'wep_key0' value '<omitted>'
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Config: added 'wep_tx_keyidx' value '0'
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> Config: set interface ap_scan to 1
Mar 8 13:39:17 leafy NetworkManager[4230]: <info> (wlan0): supplicant interface state: disconnected -> scanning

ProblemType: Bug
DistroRelease: Ubuntu 14.04
Package: bcmwl-kernel-source 6.30.223.141+bdcom-0ubuntu2
ProcVersionSignature: Ubuntu 3.13.0-16.36-generic 3.13.5
Uname: Linux 3.13.0-16-generic x86_64
NonfreeKernelModules: wl nvidia
ApportVersion: 2.13.3-0ubuntu1
Architecture: amd64
CurrentDesktop: GNOME
Date: Sat Mar 8 13:46:29 2014
InstallationDate: Installed on 2014-02-24 (12 days ago)
InstallationMedia: Ubuntu-GNOME 14.04 "Trusty Tahr" - Alpha amd64 (20140218)
ProcEnviron:
TERM=xterm
PATH=(custom, no user)
XDG_RUNTIME_DIR=<set>
LANG=en_US.UTF-8
SHELL=/bin/bash
SourcePackage: bcmwl
UpgradeStatus: No upgrade log present (probably fresh install)
---
ApportVersion: 2.13.3-0ubuntu1
Architecture: amd64
AudioDevicesInUse:
USER PID ACCESS COMMAND
/dev/snd/controlC1: jbarnett 2543 F.... pulseaudio
/dev/snd/controlC0: jbarnett 2543 F.... pulseaudio
CurrentDesktop: GNOME
DistroRelease: Ubuntu 14.04
HibernationDevice: RESUME=UUID=948280a0-a059-468d-8484-f0d317f46e6d
InstallationDate: Installed on 2014-02-24 (14 days ago)
InstallationMedia: Ubuntu-GNOME 14.04 "Trusty Tahr" - Alpha amd64 (20140218)
MachineType: Apple Inc. MacBookPro11,3
NonfreeKernelModules: nvidia wl
Package: linux (not installed)
ProcEnviron:
TERM=xterm
PATH=(custom, no user)
XDG_RUNTIME_DIR=<set>
LANG=en_US.UTF-8
SHELL=/bin/bash
ProcFB: 0 EFI VGA
ProcKernelCmdLine: BOOT_IMAGE=/boot/vmlinuz-3.13.0-16-generic.efi.signed root=UUID=135fd833-3282-4f3b-8802-0a27ec2765b9 ro quiet splash vt.handoff=7
ProcVersionSignature: Ubuntu 3.13.0-16.36-generic 3.13.5
RelatedPackageVersions:
linux-restricted-modules-3.13.0-16-generic N/A
linux-backports-modules-3.13.0-16-generic N/A
linux-firmware 1.126
Tags: trusty
Uname: Linux 3.13.0-16-generic x86_64
UpgradeStatus: No upgrade log present (probably fresh install)
UserGroups: adm audio cdrom dip lpadmin plugdev sambashare sudo
_MarkForUpload: True
dmi.bios.date: 10/18/2013
dmi.bios.vendor: Apple Inc.
dmi.bios.version: MBP112.88Z.0138.B02.1310181745
dmi.board.asset.tag: Base Board Asset Tag#
dmi.board.name: Mac-2BD1B31983FE1663
dmi.board.vendor: Apple Inc.
dmi.board.version: MacBookPro11,3
dmi.chassis.type: 10
dmi.chassis.vendor: Apple Inc.
dmi.chassis.version: Mac-2BD1B31983FE1663
dmi.modalias: dmi:bvnAppleInc.:bvrMBP112.88Z.0138.B02.1310181745:bd10/18/2013:svnAppleInc.:pnMacBookPro11,3:pvr1.0:rvnAppleInc.:rnMac-2BD1B31983FE1663:rvrMacBookPro11,3:cvnAppleInc.:ct10:cvrMac-2BD1B31983FE1663:
dmi.product.name: MacBookPro11,3
dmi.product.version: 1.0
dmi.sys.vendor: Apple Inc.

See original description

Tags:

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-08:

Dependencies.txt Edit (3.4 KiB, text/plain; charset="utf-8")

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11:

adding linux as stack trace references cfg80211 module code

Revision history for this message

Brad Figg (brad-figg) wrote on 2014-03-11: Missing required logs.

This bug is missing log files that will aid in diagnosing the problem. From a terminal window please run:

apport-collect 1289884

and then change the status of the bug to 'Confirmed'.

If, due to the nature of the issue you have encountered, you are unable to run this command, please add a comment stating that fact and change the bug status to 'Confirmed'.

This change has been made by an automated script, maintained by the Ubuntu Kernel Team.

Changed in linux (Ubuntu):
status:	New → Incomplete

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: AlsaInfo.txt

AlsaInfo.txt Edit (43.7 KiB, text/plain)

apport information

tags:	added: apport-collected
description:	updated

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: BootDmesg.txt

BootDmesg.txt Edit (119.8 KiB, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: CRDA.txt

CRDA.txt Edit (235 bytes, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: CurrentDmesg.txt

CurrentDmesg.txt Edit (12.4 KiB, text/plain)

apport information

Changed in linux (Ubuntu):
status:	Incomplete → Confirmed

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: IwConfig.txt

IwConfig.txt Edit (272 bytes, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: Lspci.txt

Lspci.txt Edit (11.1 KiB, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: Lsusb.txt

#10

Lsusb.txt Edit (513 bytes, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: ProcCpuinfo.txt

#11

ProcCpuinfo.txt Edit (8.0 KiB, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: ProcInterrupts.txt

#12

ProcInterrupts.txt Edit (3.8 KiB, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: ProcModules.txt

#13

ProcModules.txt Edit (4.4 KiB, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: PulseList.txt

#14

PulseList.txt Edit (26.1 KiB, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: RfKill.txt

#15

RfKill.txt Edit (174 bytes, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: UdevDb.txt

#16

UdevDb.txt Edit (164.7 KiB, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: UdevLog.txt

#17

UdevLog.txt Edit (397.8 KiB, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-11: WifiSyslog.txt

#18

WifiSyslog.txt Edit (16.2 KiB, text/plain)

apport information

Revision history for this message

Joe Barnett (thejoe) wrote on 2014-03-13:

#19

Download full text (25.5 KiB)

here is syslog after a resume where networking didn't resume immediately, but a network-manager restart caused resulted in a successfull connection:

Mar 13 08:10:48 leafy kernel: [64782.772111] Freezing user space processes ... (elapsed 0.015 seconds) done.
Mar 13 08:10:48 leafy kernel: [64782.787976] Freezing remaining freezable tasks ... (elapsed 0.120 seconds) done.
Mar 13 08:10:48 leafy kernel: [64782.908611] PM: Entering mem sleep
Mar 13 08:10:48 leafy kernel: [64782.908650] Suspending console(s) (use no_console_suspend to debug)
Mar 13 08:10:48 leafy kernel: [64782.908816] sd 1:0:0:0: [sda] Synchronizing SCSI cache
Mar 13 08:10:48 leafy kernel: [64782.909373] sd 1:0:0:0: [sda] Stopping disk
Mar 13 08:10:48 leafy kernel: [64782.950546] apple-gmux 00:07: System wakeup disabled by ACPI
Mar 13 08:10:48 leafy kernel: [64783.462757] PM: suspend of devices complete after 554.317 msecs
Mar 13 08:10:48 leafy kernel: [64783.462955] PM: late suspend of devices complete after 0.196 msecs
Mar 13 08:10:48 leafy kernel: [64783.466676] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
Mar 13 08:10:48 leafy kernel: [64783.470726] PM: noirq suspend of devices complete after 7.771 msecs
Mar 13 08:10:48 leafy kernel: [64783.480900] ACPI: Preparing to enter system sleep state S3
Mar 13 08:10:48 leafy kernel: [64823.456618] PM: Saving platform NVS memory
Mar 13 08:10:48 leafy kernel: [64823.457107] Disabling non-boot CPUs ...
Mar 13 08:10:48 leafy kernel: [64823.458754] kvm: disabling virtualization on CPU1
Mar 13 08:10:48 leafy kernel: [64823.560234] smpboot: CPU 1 is now offline
Mar 13 08:10:48 leafy kernel: [64823.562404] kvm: disabling virtualization on CPU2
Mar 13 08:10:48 leafy kernel: [64823.664191] smpboot: CPU 2 is now offline
Mar 13 08:10:48 leafy kernel: [64823.665268] Broke affinity for irq 18
Mar 13 08:10:48 leafy kernel: [64823.666324] kvm: disabling virtualization on CPU3
Mar 13 08:10:48 leafy kernel: [64823.768143] smpboot: CPU 3 is now offline
Mar 13 08:10:48 leafy kernel: [64823.770272] kvm: disabling virtualization on CPU4
Mar 13 08:10:48 leafy kernel: [64823.872080] smpboot: CPU 4 is now offline
Mar 13 08:10:48 leafy kernel: [64823.873028] Broke affinity for irq 45
Mar 13 08:10:48 leafy kernel: [64823.873033] Broke affinity for irq 46
Mar 13 08:10:48 leafy kernel: [64823.873038] Broke affinity for irq 47
Mar 13 08:10:48 leafy kernel: [64823.873043] Broke affinity for irq 49
Mar 13 08:10:48 leafy kernel: [64823.874069] kvm: disabling virtualization on CPU5
Mar 13 08:10:48 leafy kernel: [64823.976008] smpboot: CPU 5 is now offline
Mar 13 08:10:48 leafy kernel: [64823.977954] kvm: disabling virtualization on CPU6
Mar 13 08:10:48 leafy kernel: [64824.079954] smpboot: CPU 6 is now offline
Mar 13 08:10:48 leafy kernel: [64824.081984] kvm: disabling virtualization on CPU7
Mar 13 08:10:48 leafy kernel: [64824.183883] smpboot: CPU 7 is now offline
Mar 13 08:10:48 leafy kernel: [64824.216900] ACPI: Low-level resume complete
Mar 13 08:10:48 leafy kernel: [64824.216948] PM: Restoring platform NVS memory
Mar 13 08:10:48 leafy kernel: [64824.217411] Enabling non-boot CPUs ...
Mar 13 08:10:48 leafy kernel: [64824.217459] x86: Booting SMP configuration:
Mar 13 08:10:48 leafy kernel: [64824.217461] smpboot: Booting Node 0 Processor 1 APIC 0x2
Mar 13 08:10:48 leafy kernel: [64824.237159] kvm: enabling virtualization on CPU1
Mar 13 08:10:48 leafy kernel: [64824.241219] CPU1 is up
Mar 13 08:10:48 leafy kernel: [64824.241241] smpboot: Booting Node 0 Processor 2 APIC 0x4
Mar 13 08:10:48 leafy kernel: [64824.260528] kvm: enabling virtualization on CPU2
Mar 13 08:10:48 leafy kernel: [64824.264068] CPU2 is up
Mar 13 08:10:48 leafy kernel: [64824.264089] smpboot: Booting Node 0 Processor 3 APIC 0x6
Mar 13 08:10:48 leafy kernel: [64824.282884] kvm: enabling virtualization on CPU3
Mar 13 08:10:48 leafy kernel: [64824.286231] CPU3 is up
Mar 13 08:10:48 leafy kernel: [64824.286254] smpboot: Booting Node 0 Processor 4 APIC 0x1
Mar 13 08:10:48 leafy kernel: [64824.301980] kvm: enabling virtualization on CPU4
Mar 13 08:10:48 leafy kernel: [64824.304220] CPU4 is up
Mar 13 08:10:48 leafy kernel: [64824.304231] smpboot: Booting Node 0 Processor 5 APIC 0x3
Mar 13 08:10:48 leafy kernel: [64824.320133] kvm: enabling virtualization on CPU5
Mar 13 08:10:48 leafy kernel: [64824.322718] CPU5 is up
Mar 13 08:10:48 leafy kernel: [64824.322732] smpboot: Booting Node 0 Processor 6 APIC 0x5
Mar 13 08:10:48 leafy kernel: [64824.338588] kvm: enabling virtualization on CPU6
Mar 13 08:10:48 leafy kernel: [64824.341164] CPU6 is up
Mar 13 08:10:48 leafy kernel: [64824.341178] smpboot: Booting Node 0 Processor 7 APIC 0x7
Mar 13 08:10:48 leafy kernel: [64824.357024] kvm: enabling virtualization on CPU7
Mar 13 08:10:48 leafy kernel: [64824.359597] CPU7 is up
Mar 13 08:10:48 leafy kernel: [64824.437129] ACPI: Waking up from system sleep state S3
Mar 13 08:10:48 leafy kernel: [64826.487493] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
Mar 13 08:10:48 leafy kernel: [64826.543687] PM: noirq resume of devices complete after 60.146 msecs
Mar 13 08:10:48 leafy kernel: [64826.544129] PM: early resume of devices complete after 0.388 msecs
Mar 13 08:10:48 leafy kernel: [64826.544298] mei_me 0000:00:16.0: irq 51 for MSI/MSI-X
Mar 13 08:10:48 leafy kernel: [64826.544532] snd_hda_intel 0000:00:1b.0: irq 52 for MSI/MSI-X
Mar 13 08:10:48 leafy kernel: [64826.891360] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Mar 13 08:10:48 leafy kernel: [64826.891694] ata1.00: unexpected _GTF length (8)
Mar 13 08:10:48 leafy kernel: [64826.892547] ata1.00: unexpected _GTF length (8)
Mar 13 08:10:48 leafy kernel: [64826.892878] ata1.00: configured for UDMA/133
Mar 13 08:10:48 leafy kernel: [64826.893019] sd 1:0:0:0: [sda] Starting disk
Mar 13 08:10:48 leafy kernel: [64829.485900] pciehp 0000:00:1c.0:pcie04: Link Training Error occurs 
Mar 13 08:10:48 leafy kernel: [64829.485902] pciehp 0000:00:1c.0:pcie04: Failed to check link status
Mar 13 08:10:48 leafy kernel: [64829.590708] PM: resume of devices complete after 3048.269 msecs
Mar 13 08:10:48 leafy kernel: [64829.591467] PM: Finishing wakeup.
Mar 13 08:10:48 leafy rtkit-daemon[1991]: The canary thread is apparently starving. Taking action.
Mar 13 08:10:48 leafy rtkit-daemon[1991]: Demoting known real-time threads.
Mar 13 08:10:48 leafy acpid: input device has been disconnected, fd 20
Mar 13 08:10:48 leafy rtkit-daemon[1991]: Successfully demoted thread 2614 of process 2543 (n/a).
Mar 13 08:10:48 leafy rtkit-daemon[1991]: Successfully demoted thread 2613 of process 2543 (n/a).
Mar 13 08:10:48 leafy rtkit-daemon[1991]: Successfully demoted thread 2612 of process 2543 (n/a).
Mar 13 08:10:48 leafy rtkit-daemon[1991]: Successfully demoted thread 2543 of process 2543 (n/a).
Mar 13 08:10:48 leafy rtkit-daemon[1991]: Demoted 4 threads.
Mar 13 08:10:48 leafy kernel: [64829.591470] Restarting tasks ... done.
Mar 13 08:10:49 leafy kernel: [64829.770388] usb 1-5: new low-speed USB device number 13 using xhci_hcd
Mar 13 08:10:49 leafy kernel: [64829.814981] usb 1-5: New USB device found, idVendor=045e, idProduct=00db
Mar 13 08:10:49 leafy kernel: [64829.814983] usb 1-5: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Mar 13 08:10:49 leafy kernel: [64829.814985] usb 1-5: Product: Natural® Ergonomic Keyboard 4000
Mar 13 08:10:49 leafy kernel: [64829.814986] usb 1-5: Manufacturer: Microsoft
Mar 13 08:10:49 leafy kernel: [64829.815053] usb 1-5: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes
Mar 13 08:10:49 leafy kernel: [64829.815055] usb 1-5: ep 0x82 - rounding interval to 64 microframes, ep desc says 80 microframes
Mar 13 08:10:49 leafy kernel: [64829.822877] input: Microsoft Natural® Ergonomic Keyboard 4000 as /devices/pci0000:00/0000:00:14.0/usb1/1-5/1-5:1.0/input/input26
Mar 13 08:10:49 leafy kernel: [64829.822975] microsoft 0003:045E:00DB.0013: input,hidraw0: USB HID v1.11 Keyboard [Microsoft Natural® Ergonomic Keyboard 4000] on usb-0000:00:14.0-5/input0
Mar 13 08:10:49 leafy kernel: [64829.833707] input: Microsoft Natural® Ergonomic Keyboard 4000 as /devices/pci0000:00/0000:00:14.0/usb1/1-5/1-5:1.1/input/input27
Mar 13 08:10:49 leafy kernel: [64829.833782] microsoft 0003:045E:00DB.0014: input,hidraw3: USB HID v1.11 Device [Microsoft Natural® Ergonomic Keyboard 4000] on usb-0000:00:14.0-5/input1
Mar 13 08:10:49 leafy acpid: client connected from 1241[0:0]
Mar 13 08:10:49 leafy acpid: 1 client rule loaded
Mar 13 08:10:49 leafy mtp-probe: checking bus 1, device 13: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-5"
Mar 13 08:10:49 leafy mtp-probe: bus: 1, device: 13 was not an MTP device

# sudo service network-manager restart

Mar 13 08:11:47 leafy NetworkManager[27962]: <info> caught signal 15, shutting down normally.
Mar 13 08:11:47 leafy dnsmasq[28015]: exiting on receipt of SIGTERM
Mar 13 08:11:47 leafy NetworkManager[27962]: <info> Removing DNS information from /sbin/resolvconf
Mar 13 08:11:47 leafy kernel: [64887.727624] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Mar 13 08:11:47 leafy NetworkManager[27962]: <info> exiting (success)
Mar 13 08:11:47 leafy ModemManager[27955]: <info>  Caught signal, shutting down...
Mar 13 08:11:47 leafy ModemManager[27955]: <info>  ModemManager is shut down
Mar 13 08:11:47 leafy ModemManager[4612]: <info>  ModemManager (version 1.0.0) starting...
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> NetworkManager (version 0.9.8.8) is starting...
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> Read config file /etc/NetworkManager/NetworkManager.conf
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> WEXT support is enabled
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> VPN: loaded org.freedesktop.NetworkManager.pptp
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> DNS: loaded plugin dnsmasq
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ifupdown: init!
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ifupdown: update_system_hostname
Mar 13 08:11:47 leafy NetworkManager[4619]:       interface-parser: parsing file /etc/network/interfaces
Mar 13 08:11:47 leafy NetworkManager[4619]:       interface-parser: finished parsing file /etc/network/interfaces
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPluginIfupdown: management mode: unmanaged
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1c.2/0000:03:00.0/net/wlan0, iface: wlan0)
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1c.2/0000:03:00.0/net/wlan0, iface: wlan0): no ifupdown configuration found.
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/lo, iface: lo)
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ifupdown: end _init.
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> Loaded plugin ifupdown: (C) 2008 Canonical Ltd.  To report bugs please use the NetworkManager mailing list.
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ofono: Acquired D-Bus service com.canonical.NMOfono
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ofono: init!
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ofono: end _init.
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> Loaded plugin (null): (null)
Mar 13 08:11:47 leafy NetworkManager[4619]:    Ifupdown: get unmanaged devices count: 0
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ifupdown: (20364832) ... get_connections.
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ifupdown: (20364832) ... get_connections (managed=false): return empty list.
Mar 13 08:11:47 leafy NetworkManager[4619]:    keyfile: parsing HKGJ3 ... 
Mar 13 08:11:47 leafy NetworkManager[4619]:    keyfile:     read connection 'HKGJ3'
Mar 13 08:11:47 leafy NetworkManager[4619]:    keyfile: parsing Marin GUEST ... 
Mar 13 08:11:47 leafy NetworkManager[4619]:    keyfile:     read connection 'Marin GUEST'
Mar 13 08:11:47 leafy NetworkManager[4619]:    keyfile: parsing Marin Secure ... 
Mar 13 08:11:47 leafy NetworkManager[4619]:    keyfile:     read connection 'Marin Secure'
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ofono: (268451104) ... get_connections.
Mar 13 08:11:47 leafy NetworkManager[4619]:    SCPlugin-Ofono: (268451104) connections count: 0
Mar 13 08:11:47 leafy NetworkManager[4619]:    Ifupdown: get unmanaged devices count: 0
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> monitoring kernel firmware directory '/lib/firmware'.
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> rfkill1: found WiFi radio killswitch (at /sys/devices/pci0000:00/0000:00:1c.2/0000:03:00.0/ieee80211/phy0/rfkill1) (driver wl)
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> rfkill2: found WiFi radio killswitch (at /sys/devices/pci0000:00/0000:00:1c.2/0000:03:00.0/net/wlan0/rfkill2) (driver wl)
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> WiFi enabled by radio killswitch; enabled by state file
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> WWAN enabled by radio killswitch; enabled by state file
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> WiMAX enabled by radio killswitch; enabled by state file
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> Networking is enabled by state file
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0): using nl80211 for WiFi device control
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0): new 802.11 WiFi device (driver: 'wl' ifindex: 2)
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/0
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0): bringing up device.
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0): preparing device.
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0): deactivating device (reason 'managed') [2]
Mar 13 08:11:47 leafy NetworkManager[4619]: <warn> /sys/devices/virtual/net/lo: couldn't determine device driver; ignoring...
Mar 13 08:11:47 leafy NetworkManager[4619]: <warn> /sys/devices/virtual/net/lo: couldn't determine device driver; ignoring...
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> urfkill disappeared from the bus
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> ModemManager available in the bus
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0) supports 1 scan SSIDs
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0): supplicant interface state: starting -> ready
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Mar 13 08:11:47 leafy NetworkManager[4619]: <warn> Trying to remove a non-existant call id.
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0): supplicant interface state: ready -> disconnected
Mar 13 08:11:47 leafy NetworkManager[4619]: <info> (wlan0) supports 1 scan SSIDs
Mar 13 08:11:47 leafy wpa_supplicant[982]: wlan0: Reject scan trigger since one is already pending
Mar 13 08:11:47 leafy wpa_supplicant[982]: wlan0: CTRL-EVENT-SCAN-STARTED 
Mar 13 08:11:49 leafy avahi-daemon[768]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::7a31:c1ff:febb:af0c.
Mar 13 08:11:49 leafy avahi-daemon[768]: New relevant interface wlan0.IPv6 for mDNS.
Mar 13 08:11:49 leafy avahi-daemon[768]: Registering new address record for fe80::7a31:c1ff:febb:af0c on wlan0.*.
Mar 13 08:11:49 leafy ModemManager[4612]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.2/0000:03:00.0': not supported by any plugin
Mar 13 08:11:51 leafy kernel: [64892.188644] ERROR @wl_inform_single_bss : cfg80211_inform_bss_frame error
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> (wlan0): supplicant interface state: disconnected -> inactive
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Auto-activating connection 'Marin GUEST'.
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) starting connection 'Marin GUEST'
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> NetworkManager state is now CONNECTING
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0/wireless): connection 'Marin GUEST' has security, and secrets exist.  No new secrets needed.
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Config: added 'ssid' value 'Marin GUEST'
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Config: added 'scan_ssid' value '1'
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Config: added 'psk' value '<omitted>'
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Config: set interface ap_scan to 1
Mar 13 08:11:51 leafy wpa_supplicant[982]: wlan0: Trying to associate with 06:18:0a:21:5b:46 (SSID='Marin GUEST' freq=2412 MHz)
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> (wlan0): supplicant interface state: inactive -> associating
Mar 13 08:11:51 leafy wpa_supplicant[982]: wlan0: Associated with 06:18:0a:21:5b:46
Mar 13 08:11:51 leafy kernel: [64892.367977] cfg80211: Calling CRDA for country: US
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> (wlan0): supplicant interface state: associating -> associated
Mar 13 08:11:51 leafy kernel: [64892.371129] cfg80211: Regulatory domain changed to country: US
Mar 13 08:11:51 leafy kernel: [64892.371133] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Mar 13 08:11:51 leafy kernel: [64892.371137] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
Mar 13 08:11:51 leafy kernel: [64892.371139] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
Mar 13 08:11:51 leafy kernel: [64892.371141] cfg80211:   (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 13 08:11:51 leafy kernel: [64892.371143] cfg80211:   (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 13 08:11:51 leafy kernel: [64892.371145] cfg80211:   (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Mar 13 08:11:51 leafy kernel: [64892.371148] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
Mar 13 08:11:51 leafy kernel: [64892.371150] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4000 mBm)
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
Mar 13 08:11:51 leafy wpa_supplicant[982]: wlan0: WPA: Key negotiation completed with 06:18:0a:21:5b:46 [PTK=CCMP GTK=TKIP]
Mar 13 08:11:51 leafy wpa_supplicant[982]: wlan0: CTRL-EVENT-CONNECTED - Connection to 06:18:0a:21:5b:46 completed [id=0 id_str=]
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'Marin GUEST'.
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> dhclient started with pid 4684
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Beginning IP6 addrconf.
Mar 13 08:11:51 leafy avahi-daemon[768]: Withdrawing address record for fe80::7a31:c1ff:febb:af0c on wlan0.
Mar 13 08:11:51 leafy avahi-daemon[768]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::7a31:c1ff:febb:af0c.
Mar 13 08:11:51 leafy avahi-daemon[768]: Interface wlan0.IPv6 no longer relevant for mDNS.
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
Mar 13 08:11:51 leafy dhclient: Internet Systems Consortium DHCP Client 4.2.4
Mar 13 08:11:51 leafy dhclient: Copyright 2004-2012 Internet Systems Consortium.
Mar 13 08:11:51 leafy dhclient: All rights reserved.
Mar 13 08:11:51 leafy dhclient: For info, please visit https://www.isc.org/software/dhcp/
Mar 13 08:11:51 leafy dhclient: 
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
Mar 13 08:11:51 leafy dhclient: Listening on LPF/wlan0/78:31:c1:bb:af:0c
Mar 13 08:11:51 leafy dhclient: Sending on   LPF/wlan0/78:31:c1:bb:af:0c
Mar 13 08:11:51 leafy dhclient: Sending on   Socket/fallback
Mar 13 08:11:51 leafy dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 3 (xid=0x383aa9ff)
Mar 13 08:11:51 leafy dhclient: DHCPREQUEST of 10.95.26.108 on wlan0 to 255.255.255.255 port 67 (xid=0x383aa9ff)
Mar 13 08:11:51 leafy dhclient: DHCPOFFER of 10.95.26.108 from 10.128.128.128
Mar 13 08:11:51 leafy dhclient: DHCPACK of 10.95.26.108 from 10.128.128.128
Mar 13 08:11:51 leafy dhclient: bound to 10.95.26.108 -- renewal in 33613 seconds.
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> (wlan0): DHCPv4 state changed preinit -> bound
Mar 13 08:11:51 leafy NetworkManager[4619]: <info>   address 10.95.26.108
Mar 13 08:11:51 leafy NetworkManager[4619]: <info>   prefix 8 (255.0.0.0)
Mar 13 08:11:51 leafy NetworkManager[4619]: <info>   gateway 10.128.128.128
Mar 13 08:11:51 leafy NetworkManager[4619]: <info>   nameserver '10.128.128.128'
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Mar 13 08:11:51 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) started...
Mar 13 08:11:51 leafy avahi-daemon[768]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.95.26.108.
Mar 13 08:11:51 leafy avahi-daemon[768]: New relevant interface wlan0.IPv4 for mDNS.
Mar 13 08:11:51 leafy avahi-daemon[768]: Registering new address record for 10.95.26.108 on wlan0.IPv4.
Mar 13 08:11:52 leafy NetworkManager[4619]: <info> (wlan0): device state change: ip-config -> secondaries (reason 'none') [70 90 0]
Mar 13 08:11:52 leafy NetworkManager[4619]: <info> Activation (wlan0) Stage 5 of 5 (IPv4 Commit) complete.
Mar 13 08:11:52 leafy NetworkManager[4619]: <info> (wlan0): device state change: secondaries -> activated (reason 'none') [90 100 0]
Mar 13 08:11:52 leafy NetworkManager[4619]: <info> NetworkManager state is now CONNECTED_GLOBAL
Mar 13 08:11:52 leafy NetworkManager[4619]: <info> Policy set 'Marin GUEST' (wlan0) as default for IPv4 routing and DNS.
Mar 13 08:11:52 leafy NetworkManager[4619]: <info> DNS: starting dnsmasq...
Mar 13 08:11:52 leafy NetworkManager[4619]: <warn> dnsmasq not available on the bus, can't update servers.
Mar 13 08:11:52 leafy NetworkManager[4619]: <error> [1394723512.804650] [nm-dns-dnsmasq.c:400] update(): dnsmasq owner not found on bus: Could not get owner of name 'org.freedesktop.NetworkManager.dnsmasq': no such name
Mar 13 08:11:52 leafy NetworkManager[4619]: <warn> DNS: plugin dnsmasq update failed
Mar 13 08:11:52 leafy NetworkManager[4619]: <info> Writing DNS information to /sbin/resolvconf
Mar 13 08:11:52 leafy dnsmasq[4701]: started, version 2.68 cache disabled
Mar 13 08:11:52 leafy dnsmasq[4701]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth
Mar 13 08:11:52 leafy dnsmasq[4701]: DBus support enabled: connected to system bus
Mar 13 08:11:52 leafy dnsmasq[4701]: warning: no upstream servers configured
Mar 13 08:11:52 leafy NetworkManager[4619]: <info> Activation (wlan0) successful, device activated.
Mar 13 08:11:52 leafy dbus[662]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Mar 13 08:11:52 leafy NetworkManager[4619]: <warn> dnsmasq appeared on DBus: :1.548
Mar 13 08:11:52 leafy NetworkManager[4619]: <info> Writing DNS information to /sbin/resolvconf
Mar 13 08:11:52 leafy dnsmasq[4701]: setting upstream servers from DBus
Mar 13 08:11:52 leafy dnsmasq[4701]: using nameserver 10.128.128.128#53
Mar 13 08:11:52 leafy dbus[662]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 13 08:11:53 leafy avahi-daemon[768]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::7a31:c1ff:febb:af0c.
Mar 13 08:11:53 leafy avahi-daemon[768]: New relevant interface wlan0.IPv6 for mDNS.
Mar 13 08:11:53 leafy avahi-daemon[768]: Registering new address record for fe80::7a31:c1ff:febb:af0c on wlan0.*.
Mar 13 08:11:53 leafy whoopsie[1121]: message repeated 3 times: [ offline]
Mar 13 08:11:53 leafy whoopsie[1121]: online

Revision history for this message

Alberto Salvia Novella (es20490446e) wrote on 2014-03-24:

#20

It affects an essential hardware component (disk controller, built-in networking, video card, keyboard, mouse).

Changed in bcmwl (Ubuntu):
status:	New → Confirmed
Changed in linux (Ubuntu):
importance:	Undecided → High
Changed in bcmwl (Ubuntu):
importance:	Undecided → High

Revision history for this message

hippich (hippich) wrote on 2014-07-13:

#21

I am not sure this is duplicate of #1252121. In my case even after running sudo nmcli nm sleep false it unable to connect to wifi network. Having similar traceback as OP

Revision history for this message

Dennis-martin-herbers (dennis-martin-herbers) wrote on 2015-01-25:

#22

Can confirm with 14.04/14.10/15.04, Thinkpad X121e, Intel WiFi 7260 N.

Network is "avaible" after suspend but often fails to associate and to therefore to connect. This problem does _NOT_ exist with Debian/testing or Fedora 21.

Revision history for this message

Dennis-martin-herbers (dennis-martin-herbers) wrote on 2015-01-25:

#23

Can confirm using "pm-suspend" works ALWAYS, while Suspend from Unity menu is broken.

Revision history for this message

Dennis-martin-herbers (dennis-martin-herbers) wrote on 2015-01-25:

#24

I tracked down the issue where WiFi would fail after suspend in that it doesn't associate anymore (tries to connect but fails), and it's a bug in wpa-supplicant that was fixed in the current version (Ubuntu uses 2.1, newest is 2.3). Replacing Ubuntu's wpa-supplicant 2.1 with Debian's wpa-supplicant 2.3 and rebooting immediately fixed the issue.

https://packages.debian.org/sid/wpasupplicant