Ubuntu
wpasupplicant package

WiFi fails to resume after suspend; Race with wpasupplicant / wpa_cli resume?

Bug #1556357 reported by Haw Loeung on 2016-03-12

This bug report is a duplicate of: Bug #1585863: WiFi malfunction after suspend & resume stress - sudo wpa_cli scan required to fix it.. Edit Remove

302

This bug affects 63 people

Affects		Status	Importance	Assigned to	Milestone
	network-manager (Ubuntu)	Confirmed	High	Unassigned
	wpasupplicant (Ubuntu)	Confirmed	High	Unassigned

Bug Description

Hi,

I'm constantly having issues where my WiFi connection doesn't re-establish after resuming from suspend. I think it may be a race where the interface isn't ready yet and systemd-sleep calls /lib/systemd/system-sleep/wpasupplicant (which is a wrapper to wpa_cli).

I normally restart NetworkManager but then found that calling 'wpa_cli resume' works also.

Here's the logs:

| Mar 12 13:53:06 ragnar.local kernel: psmouse serio1: synaptics: quirked min/max coordinates: x [1024..5112], y [2024..4832]
| Mar 12 13:53:06 ragnar.local kernel: PM: resume of devices complete after 562.709 msecs
| Mar 12 13:53:06 ragnar.local kernel: PM: Finishing wakeup.
| Mar 12 13:53:06 ragnar.local systemd[1]: Time has been changed
| Mar 12 13:53:06 ragnar.local systemd[1066]: Time has been changed
| Mar 12 13:53:06 ragnar.local kernel: Restarting tasks ... done.
| Mar 12 13:53:06 ragnar.local systemd-sleep[29174]: System resumed.
| Mar 12 13:53:06 ragnar.local systemd-sleep[29174]: Failed to connect to non-global ctrl_ifname: (nil) error: No such file or directory
| Mar 12 13:53:06 ragnar.local systemd-sleep[29227]: /lib/systemd/system-sleep/wpasupplicant failed with error code 255.
| Mar 12 13:53:06 ragnar.local systemd[1]: Started Suspend.
| Mar 12 13:53:06 ragnar.local systemd[1]: sleep.target: Unit not needed anymore. Stopping.
| Mar 12 13:53:06 ragnar.local systemd[1]: Stopped target Sleep.
| Mar 12 13:53:06 ragnar.local systemd[1]: Reached target Suspend.
| Mar 12 13:53:06 ragnar.local systemd[1]: suspend.target: Unit is bound to inactive unit systemd-suspend.service. Stopping, too.
| Mar 12 13:53:06 ragnar.local NetworkManager[23292]: <info> wake requested (sleeping: yes enabled: yes)
| Mar 12 13:53:06 ragnar.local systemd[1]: Stopped target Suspend.
| Mar 12 13:53:06 ragnar.local NetworkManager[23292]: <info> waking up...
| Mar 12 13:53:06 ragnar.local systemd-logind[662]: Operation 'sleep' finished.
| Mar 12 13:53:06 ragnar.local kernel: IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
| Mar 12 13:53:06 ragnar.local kernel: iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
| Mar 12 13:53:06 ragnar.local NetworkManager[23292]: <info> (wlp3s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
| Mar 12 13:53:06 ragnar.local kernel: iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
| Mar 12 13:53:07 ragnar.local kernel: iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
| Mar 12 13:53:07 ragnar.local kernel: iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
| Mar 12 13:53:07 ragnar.local NetworkManager[23292]: <info> NetworkManager state is now DISCONNECTED
| Mar 12 13:53:07 ragnar.local kernel: IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
| Mar 12 13:53:07 ragnar.local wpa_supplicant[23157]: dbus: wpa_dbus_get_object_properties: failed to get object properties: (none) none
| Mar 12 13:53:07 ragnar.local wpa_supplicant[23157]: dbus: Failed to construct signal
| Mar 12 13:53:07 ragnar.local wpa_supplicant[23157]: Could not read interface p2p-dev-wlp3s0 flags: No such device
| Mar 12 13:53:07 ragnar.local NetworkManager[23292]: <info> (wlp3s0): supplicant interface state: starting -> ready
| Mar 12 13:53:07 ragnar.local NetworkManager[23292]: <info> (wlp3s0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
| Mar 12 13:53:07 ragnar.local NetworkManager[23292]: <info> Device 'wlp3s0' has no connection; scheduling activate_check in 0 seconds.
| Mar 12 13:53:07 ragnar.local kernel: IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready

Thanks,

Haw
---
ApportVersion: 2.20-0ubuntu3
Architecture: amd64
CurrentDesktop: Unity
DistroRelease: Ubuntu 16.04
IfupdownConfig:
# interfaces(5) file used by ifup(8) and ifdown(8)
auto lo
iface lo inet loopback
InstallationDate: Installed on 2014-04-24 (687 days ago)
InstallationMedia: Ubuntu 14.04 LTS "Trusty Tahr" - Release amd64 (20140417)
Package: wpasupplicant
PackageArchitecture: amd64
ProcVersionSignature: Ubuntu 4.4.0-11.26-generic 4.4.4
RfKill: Error: [Errno 2] No such file or directory
Tags: xenial
Uname: Linux 4.4.0-11-generic x86_64
UpgradeStatus: No upgrade log present (probably fresh install)
UserGroups: adm cdrom dialout dip lpadmin plugdev sudo users
_MarkForUpload: True
modified.conffile..etc.NetworkManager.NetworkManager.conf: [modified]
modified.conffile..etc.dnsmasq.d.network.manager: [modified]
mtime.conffile..etc.NetworkManager.NetworkManager.conf: 2015-09-12T15:03:20.815898
mtime.conffile..etc.dnsmasq.d.network.manager: 2014-07-29T22:12:51.096293
nmcli-con:
NAME UUID TYPE TIMESTAMP TIMESTAMP-REAL AUTOCONNECT AUTOCONNECT-PRIORITY READONLY DBUS-PATH ACTIVE DEVICE STATE ACTIVE-PATH
hld-2.4GHz deffb41a-c707-4088-a887-36d63f43a6a0 802-11-wireless 1457784347 Sat 12 Mar 2016 11:05:47 PM AEDT yes 0 no /org/freedesktop/NetworkManager/Settings/0 yes wlp3s0 activated /org/freedesktop/NetworkManager/ActiveConnection/12
nmcli-dev:
DEVICE TYPE STATE DBUS-PATH CONNECTION CON-UUID CON-PATH
wlp3s0 wifi connected /org/freedesktop/NetworkManager/Devices/0 hld-2.4GHz deffb41a-c707-4088-a887-36d63f43a6a0 /org/freedesktop/NetworkManager/ActiveConnection/12
lo loopback unmanaged /org/freedesktop/NetworkManager/Devices/1 -- -- --
nmcli-nm: Error: command ['nmcli', '-f', 'all', 'nm'] failed with exit code 2: Error: Object 'nm' is unknown, try 'nmcli help'.

See original description

Tags:

Revision history for this message

Haw Loeung (hloeung) wrote on 2016-03-12:

tags:

added: xenial

Revision history for this message

Martin Pitt (pitti) wrote on 2016-03-12:

Which Ubuntu release is that, wily or xenial? (Please use apport to file bugs).

/lib/systemd/system-sleep/wpasupplicant is a nasty hack/workaround introduced in bug 1422143; this is mirroring what we did before in/usr/lib/pm-utils/sleep.d/60_wpa_supplicant, but this really needs to be fixed properly in NM or wpa (not sure which) at some point..

no longer affects:

systemd (Ubuntu)

Revision history for this message

Martin Pitt (pitti) wrote on 2016-03-12:

Mathieu, did you see this before? Any hints what to do here?

@Haw: Can you please include the full logs before and after suspend? The timestamps indicate that this was only for the suspend part. Thanks!

Revision history for this message

Haw Loeung (hloeung) wrote on 2016-03-12:

Download full text (18.8 KiB)

@Martin, it's Xenial.

| Mar 12 17:35:25 ragnar NetworkManager[23292]: <info>  sleep requested (sleeping: no  enabled: yes)
| Mar 12 17:35:25 ragnar NetworkManager[23292]: <info>  sleeping...
| Mar 12 17:35:25 ragnar NetworkManager[23292]: <info>  (wlp3s0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
| Mar 12 17:35:26 ragnar NetworkManager[23292]: <info>  (wlp3s0): canceled DHCP transaction, DHCP client pid 8279
| Mar 12 17:35:26 ragnar NetworkManager[23292]: <info>  (wlp3s0): DHCPv4 state changed bound -> done
| Mar 12 17:35:26 ragnar kernel: [143996.969825] wlp3s0: deauthenticating from 24:a4:3c:99:31:37 by local choice (Reason: 3=DEAUTH_LEAVING)
| Mar 12 17:35:26 ragnar wpa_supplicant[23157]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=24:a4:3c:99:31:37 reason=3 locally_generated=1
| Mar 12 17:35:26 ragnar NetworkManager[23292]: <info>  NetworkManager state is now ASLEEP
| Mar 12 17:35:26 ragnar wpa_supplicant[23157]: p2p-dev-wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
| Mar 12 17:35:26 ragnar kernel: [143996.986106] cfg80211: World regulatory domain updated:
| Mar 12 17:35:26 ragnar kernel: [143996.986111] cfg80211:  DFS Master region: unset
| Mar 12 17:35:26 ragnar kernel: [143996.986113] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
| Mar 12 17:35:26 ragnar kernel: [143996.986117] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
| Mar 12 17:35:26 ragnar kernel: [143996.986120] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
| Mar 12 17:35:26 ragnar kernel: [143996.986122] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
| Mar 12 17:35:26 ragnar kernel: [143996.986125] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
| Mar 12 17:35:26 ragnar kernel: [143996.986129] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
| Mar 12 17:35:26 ragnar kernel: [143996.986133] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
| Mar 12 17:35:26 ragnar kernel: [143996.986136] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
| Mar 12 17:35:26 ragnar kernel: [143996.986139] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
| Mar 12 17:35:26 ragnar wpa_supplicant[23157]: p2p-dev-wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=AU
| Mar 12 17:35:26 ragnar kernel: [143996.990173] cfg80211: Regulatory domain changed to country: AU
| Mar 12 17:35:26 ragnar kernel: [143996.990177] cfg80211:  DFS Master region: ETSI
| Mar 12 17:35:26 ragnar kernel: [143996.990179] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
| Mar 12 17:35:26 ragnar kernel: [143996.990183] cfg80211:   (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
| Mar 12 17:35:26 ragnar kernel: [143996.990186] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 1700 mBm), (N/A)
| Mar 12 17:35:26 ragnar kernel: [143996.990189] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2400 mBm), (0 s)
| Mar 12 17:35:26 ragnar kernel: [143996.990191] cfg80211:   (5490000 KHz - 5710000 KHz @ 160000 KHz), (N/A, 2400 mBm), (0 s)
| Mar 12 17:35:26 ragnar kernel: [143996.990193] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A)
| Mar 12 17:35:26 ragnar wpa_supplicant[23157]: nl80211: deinit ifname=p2p-dev-wlp3s0 disabled_11b_rates=0
| Mar 12 17:35:26 ragnar NetworkManager[23292]: <warn>  Failed to GDBus.Error:fi.w1.wpa_supplicant1.NotConnected: This interface is not connected: disconnect.
| Mar 12 17:35:26 ragnar dbus[629]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
| Mar 12 17:35:26 ragnar systemd[1]: Starting Network Manager Script Dispatcher Service...
| Mar 12 17:35:26 ragnar dbus[629]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
| Mar 12 17:35:26 ragnar systemd[1]: Started Network Manager Script Dispatcher Service.                                                                                                                                  
| Mar 12 17:35:26 ragnar nm-dispatcher: Dispatching action 'down' for wlp3s0                                                                                                                                                                                   
| Mar 12 17:35:26 ragnar wpa_supplicant[23157]: nl80211: deinit ifname=wlp3s0 disabled_11b_rates=0                                                                                                                                                             
| Mar 12 17:35:27 ragnar systemd[1]: Reached target Sleep.                                                                                                                                                                                                     
| Mar 12 17:35:27 ragnar systemd[1]: Starting Suspend...                                                                                                                                                                                                       
| Mar 12 17:35:27 ragnar systemd-sleep[11065]: Failed to connect to non-global ctrl_ifname: (nil)  error: No such file or directory                                                                                                                            
| Mar 12 17:35:27 ragnar systemd-sleep[11069]: /lib/systemd/system-sleep/wpasupplicant failed with error code 255.                                                                                                                                             
| Mar 12 17:35:27 ragnar systemd-sleep[11065]: Suspending system...                                                                                                                                                                                            
| Mar 12 17:35:27 ragnar kernel: [143997.632123] PM: Syncing filesystems ... done.                                                                                                                                                                             
| Mar 12 17:35:27 ragnar kernel: [143997.711724] PM: Preparing system for sleep (mem)                                                                                                                                                                          
| Mar 12 23:01:54 ragnar kernel: [143997.712587] Freezing user space processes ... (elapsed 0.001 seconds) done.                                                                                                                                               
| Mar 12 23:01:54 ragnar kernel: [143997.714365] Freezing remaining freezable tasks ... (elapsed 0.030 seconds) done.                                                                                                                                          
| Mar 12 23:01:54 ragnar kernel: [143997.745010] PM: Suspending system (mem)                                                                                                                                                                                   
| Mar 12 23:01:54 ragnar kernel: [143997.745034] Suspending console(s) (use no_console_suspend to debug)                                                                                                                                                       
| Mar 12 23:01:54 ragnar kernel: [143997.875608] sd 0:0:0:0: [sda] Synchronizing SCSI cache                                                                                                                                                                    
| Mar 12 23:01:54 ragnar kernel: [143997.885211] sd 0:0:0:0: [sda] Stopping disk                                                                                                                                                                               
| Mar 12 23:01:54 ragnar kernel: [143998.220348] PM: suspend of devices complete after 475.140 msecs                                                                                                                                                           
| Mar 12 23:01:54 ragnar kernel: [143998.244328] PM: late suspend of devices complete after 23.974 msecs                                                                                                                                                       
| Mar 12 23:01:54 ragnar kernel: [143998.260430] PM: noirq suspend of devices complete after 16.095 msecs                                                                                                                                                      
| Mar 12 23:01:54 ragnar kernel: [143998.261175] ACPI: Preparing to enter system sleep state S3                                                                                                                                                                
| Mar 12 23:01:54 ragnar kernel: [143998.404256] ACPI : EC: EC stopped                                                                                                                                                                                         
| Mar 12 23:01:54 ragnar kernel: [143998.404257] PM: Saving platform NVS memory                                                                                                                                                                                
| Mar 12 23:01:54 ragnar kernel: [143998.404268] Disabling non-boot CPUs ...                                                                                                                                                                                   
| Mar 12 23:01:54 ragnar kernel: [143998.406486] Broke affinity for irq 46                                                                                                                                                                                     
| Mar 12 23:01:54 ragnar kernel: [143998.407551] smpboot: CPU 1 is now offline                                                                                                                                                                                 
| Mar 12 23:01:54 ragnar kernel: [143998.422225] Broke affinity for irq 44                                                                                                                                                                                     
| Mar 12 23:01:54 ragnar kernel: [143998.422231] Broke affinity for irq 46                                                                                                                                                                                     
| Mar 12 23:01:54 ragnar kernel: [143998.423291] smpboot: CPU 2 is now offline                                                                                                                                                                                 
| Mar 12 23:01:54 ragnar kernel: [143998.433286] Broke affinity for irq 1                                                                                                                                                                                      
| Mar 12 23:01:54 ragnar kernel: [143998.433292] Broke affinity for irq 8                                                                                                                                                                                      
| Mar 12 23:01:54 ragnar kernel: [143998.433296] Broke affinity for irq 9                                                                                                                                                                                      
| Mar 12 23:01:54 ragnar kernel: [143998.433300] Broke affinity for irq 12
| Mar 12 23:01:54 ragnar kernel: [143998.433307] Broke affinity for irq 43
| Mar 12 23:01:54 ragnar kernel: [143998.433312] Broke affinity for irq 44
| Mar 12 23:01:54 ragnar kernel: [143998.433316] Broke affinity for irq 46
| Mar 12 23:01:54 ragnar kernel: [143998.434358] smpboot: CPU 3 is now offline
| Mar 12 23:01:54 ragnar kernel: [143998.450129] ACPI: Low-level resume complete
| Mar 12 23:01:54 ragnar kernel: [143998.450186] ACPI : EC: EC started
| Mar 12 23:01:54 ragnar kernel: [143998.450186] PM: Restoring platform NVS memory
| Mar 12 23:01:54 ragnar kernel: [143998.450537] Enabling non-boot CPUs ...
| Mar 12 23:01:54 ragnar kernel: [143998.470034] x86: Booting SMP configuration:
| Mar 12 23:01:54 ragnar kernel: [143998.470035] smpboot: Booting Node 0 Processor 1 APIC 0x1
| Mar 12 23:01:54 ragnar kernel: [143998.473551]  cache: parent cpu1 should not be sleeping
| Mar 12 23:01:54 ragnar kernel: [143998.473636] CPU1 is up
| Mar 12 23:01:54 ragnar kernel: [143998.490052] smpboot: Booting Node 0 Processor 2 APIC 0x2
| Mar 12 23:01:54 ragnar kernel: [143998.493740]  cache: parent cpu2 should not be sleeping
| Mar 12 23:01:54 ragnar kernel: [143998.493828] CPU2 is up
| Mar 12 23:01:54 ragnar kernel: [143998.510082] smpboot: Booting Node 0 Processor 3 APIC 0x3
| Mar 12 23:01:54 ragnar kernel: [143998.514075]  cache: parent cpu3 should not be sleeping
| Mar 12 23:01:54 ragnar kernel: [143998.514161] CPU3 is up
| Mar 12 23:01:54 ragnar kernel: [143998.517245] ACPI: Waking up from system sleep state S3
| Mar 12 23:01:54 ragnar kernel: [143998.778588] PM: noirq resume of devices complete after 16.647 msecs
| Mar 12 23:01:54 ragnar kernel: [143998.784206] PM: early resume of devices complete after 5.595 msecs
| Mar 12 23:01:54 ragnar kernel: [143998.785037] rtc_cmos 00:02: System wakeup disabled by ACPI
| Mar 12 23:01:54 ragnar kernel: [143998.798008] sd 0:0:0:0: [sda] Starting disk
| Mar 12 23:01:54 ragnar kernel: [143999.121954] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
| Mar 12 23:01:54 ragnar kernel: [143999.134983] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
| Mar 12 23:01:54 ragnar kernel: [143999.134986] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
| Mar 12 23:01:54 ragnar kernel: [143999.137076] ata1.00: ACPI cmd ef/10:09:00:00:00:a0 (SET FEATURES) succeeded
| Mar 12 23:01:54 ragnar kernel: [143999.142831] ata1.00: supports DRM functions and may not be fully accessible
| Mar 12 23:01:54 ragnar kernel: [143999.164963] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
| Mar 12 23:01:54 ragnar kernel: [143999.164965] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
| Mar 12 23:01:54 ragnar kernel: [143999.167061] ata1.00: ACPI cmd ef/10:09:00:00:00:a0 (SET FEATURES) succeeded
| Mar 12 23:01:54 ragnar kernel: [143999.172830] ata1.00: supports DRM functions and may not be fully accessible
| Mar 12 23:01:54 ragnar kernel: [143999.177961] psmouse serio1: synaptics: queried max coordinates: x [..5112], y [..3834]
| Mar 12 23:01:54 ragnar kernel: [143999.182904] ata1.00: configured for UDMA/133
| Mar 12 23:01:54 ragnar kernel: [143999.209463] psmouse serio1: synaptics: queried min coordinates: x [1024..], y [1024..]
| Mar 12 23:01:54 ragnar kernel: [143999.209466] psmouse serio1: synaptics: quirked min/max coordinates: x [1024..5112], y [2024..4832]
| Mar 12 23:01:54 ragnar kernel: [143999.346454] PM: resume of devices complete after 562.234 msecs
| Mar 12 23:01:54 ragnar kernel: [143999.346661] PM: Finishing wakeup.
| Mar 12 23:01:54 ragnar systemd[1]: Time has been changed
| Mar 12 23:01:54 ragnar ntpd[909]: Deleting interface #9 wlp3s0, 192.168.1.39#123, interface stats: received=58, sent=71, dropped=0, active_time=11270 secs
| Mar 12 23:01:54 ragnar systemd[1066]: Time has been changed
| Mar 12 23:01:54 ragnar ntpd[909]: 192.168.1.1 local addr 192.168.1.39 -> <null>
| Mar 12 23:01:54 ragnar ntpd[909]: 203.14.0.250 local addr 192.168.1.39 -> <null>
| Mar 12 23:01:54 ragnar ntpd[909]: 203.14.0.251 local addr 192.168.1.39 -> <null>
| Mar 12 23:01:54 ragnar ntpd[909]: 203.12.160.2 local addr 192.168.1.39 -> <null>
| Mar 12 23:01:54 ragnar ntpd[909]: 203.0.178.191 local addr 192.168.1.39 -> <null>
| Mar 12 23:01:54 ragnar kernel: [143999.346663] Restarting tasks ... done.
| Mar 12 23:01:54 ragnar systemd-sleep[11065]: System resumed.
| Mar 12 23:01:54 ragnar systemd-sleep[11065]: Failed to connect to non-global ctrl_ifname: (nil)  error: No such file or directory
| Mar 12 23:01:54 ragnar systemd-sleep[11096]: /lib/systemd/system-sleep/wpasupplicant failed with error code 255.
| Mar 12 23:01:54 ragnar systemd[1]: Started Suspend.
| Mar 12 23:01:54 ragnar systemd[1]: sleep.target: Unit not needed anymore. Stopping.
| Mar 12 23:01:54 ragnar systemd[1]: Stopped target Sleep.
| Mar 12 23:01:54 ragnar systemd[1]: Reached target Suspend.
| Mar 12 23:01:54 ragnar systemd[1]: suspend.target: Unit is bound to inactive unit systemd-suspend.service. Stopping, too.
| Mar 12 23:01:54 ragnar systemd[1]: Stopped target Suspend.
| Mar 12 23:01:54 ragnar NetworkManager[23292]: <info>  wake requested (sleeping: yes  enabled: yes)
| Mar 12 23:01:54 ragnar NetworkManager[23292]: <info>  waking up...
| Mar 12 23:01:54 ragnar NetworkManager[23292]: <info>  (wlp3s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
| Mar 12 23:01:54 ragnar kernel: [143999.375795] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
| Mar 12 23:01:54 ragnar kernel: [143999.376255] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
| Mar 12 23:01:54 ragnar kernel: [143999.383076] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
| Mar 12 23:01:55 ragnar kernel: [143999.585792] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
| Mar 12 23:01:55 ragnar kernel: [143999.586402] iwlwifi 0000:03:00.0: L1 Enabled - LTR Enabled
| Mar 12 23:01:55 ragnar NetworkManager[23292]: <info>  NetworkManager state is now DISCONNECTED
| Mar 12 23:01:55 ragnar kernel: [143999.608502] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
| Mar 12 23:01:55 ragnar wpa_supplicant[23157]: dbus: wpa_dbus_get_object_properties: failed to get object properties: (none) none
| Mar 12 23:01:55 ragnar wpa_supplicant[23157]: dbus: Failed to construct signal
| Mar 12 23:01:55 ragnar wpa_supplicant[23157]: Could not read interface p2p-dev-wlp3s0 flags: No such device
| Mar 12 23:01:55 ragnar NetworkManager[23292]: <info>  (wlp3s0): supplicant interface state: starting -> ready
| Mar 12 23:01:55 ragnar NetworkManager[23292]: <info>  (wlp3s0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
| Mar 12 23:01:55 ragnar NetworkManager[23292]: <info>  Device 'wlp3s0' has no connection; scheduling activate_check in 0 seconds.
| Mar 12 23:01:55 ragnar kernel: [143999.651877] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready
| Mar 12 23:01:58 ragnar NetworkManager[23292]: <info>  (wlp3s0): supplicant interface state: ready -> inactive

Where "Mar 12 17:35:26" is about when I suspended, and "Mar 12 23:01:54" is around when I resumed.

tags:	added: apport-collected
description:	updated

Revision history for this message

Haw Loeung (hloeung) wrote on 2016-03-12: CRDA.txt

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

apport information

Revision history for this message

Haw Loeung (hloeung) wrote on 2016-03-12: Dependencies.txt

Dependencies.txt Edit (7.2 KiB, text/plain)

apport information

Revision history for this message

Haw Loeung (hloeung) wrote on 2016-03-12: IwConfig.txt

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

apport information

Revision history for this message

Launchpad Janitor (janitor) wrote on 2016-04-02:

#10

Status changed to 'Confirmed' because the bug affects multiple users.

Changed in network-manager (Ubuntu):
status:	New → Confirmed
Changed in wpasupplicant (Ubuntu):
status:	New → Confirmed

Alberto Salvia Novella (es20490446e) on 2016-04-08

Changed in wpasupplicant (Ubuntu):
importance:	Undecided → High
Changed in network-manager (Ubuntu):
importance:	Undecided → High

Christian Reis (kiko) on 2016-04-19

tags:

added: regression-release

Revision history for this message

Christian Reis (kiko) wrote on 2016-04-19:

#12

I've run into this with my x220 which was recently upgraded to Xenial as well. It is not consistent; it happens at maybe 1 out of 3 suspend/resume cycles.

Removing and reinserting the iwldvm module also fixes the issue, but kudos to Haw for noting that it doesn't seem to be a lower-level bug but rather in NM itself; as he suggests, a `sudo wpa_cli resume` triggers a reconnection.

Toggling the kill switch does not cause a reconnection to happen, nor does disabling and reenabling wifi.

The UI symptom is that the list of available networks in the dropdown does not update; either a single item or no item is displayed, and no reconnection happens (regardless of whether or not the item displayed is one which you have associated with before).

Revision history for this message

Mark Stosberg (markstos) wrote on 2016-06-01:

#13

I have this problem on a Dell XPS 2013 (Haswell) running Ubuntu 16.04. However, trying to workaround it like results in an error:

sudo wpa_cli resume

Failed to connect to non-global ctrl_ifname: (nil) error: No such file or directory.

This bug is the same as, or similar to:

No wifi connection after suspend with systemd due to missing "wpa_cli suspend"
https://bugs.launchpad.net/hundredpapercuts/+bug/1422143

Revision history for this message

Chaos Onion (chaosonion) wrote on 2016-07-23:

#14

I'm having this problem on Lenovo G410 with the latest release 16.04 fully updated as of July 22, 2016. After coming back from suspend, the wireless icon disappears and is replaced by the ethernet connection icon. Although the wifi stays connected to it's last remembered connection, the list of wireless networks also disappears from network-manager.

Revision history for this message

Stew Mat (monolit) wrote on 2016-10-30:

#15

I'm having this with my Lenovo Thinkpad T420. It happens around 1 time out of 3. Sometimes the connected wifi icon is replaced by the ethernet one, sometimes by the unconnected wifi icon.

"wpa_cli resume" fixes it.

Thanks @Haw for the workaround.

Report a bug

This report contains Public information

Everyone can see this information.

Duplicate of bug #1585863 Remove

You are

Subscribing...

Edit bug mail

Other bug subscribers

Bug attachments

Add attachment

Remote bug watches

Bug watches keep track of this bug in other bug trackers.

Ubuntuwpasupplicant package

WiFi fails to resume after suspend; Race with wpasupplicant / wpa_cli resume?

Bug Description

Other bug subscribers

Bug attachments

Remote bug watches

Ubuntu
wpasupplicant package