@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 changed:
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'.
--
You received this bug notification because you are a member of Desktop
Packages, which is subscribed to network-manager in Ubuntu.
https://bugs.launchpad.net/bugs/1556357
Title:
WiFi fails to resume after suspend; Race with wpasupplicant / wpa_cli
resume?
Status in network-manager package in Ubuntu:
New
Status in wpasupplicant package in Ubuntu:
New
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'.
To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/network-manager/+bug/1556357/+subscriptions
--
Mailing list: https://launchpad.net/~desktop-packages
Post to : desktop-packages@lists.launchpad.net
Unsubscribe : https://launchpad.net/~desktop-packages
More help : https://help.launchpad.net/ListHelp
