[Bug 1556357] Re: WiFi fails to resume after suspend; Race with wpasupplicant / wpa_cli resume?

[Haw Loeung]

| [hloeung at ragnar tmp]$ apt-cache policy systemd wpasupplicant
| systemd:
|   Installed: 229-2ubuntu1
|   Candidate: 229-2ubuntu1
|   Version table:
|  *** 229-2ubuntu1 500
|         500 http://archive.ubuntu.com/ubuntu xenial/main amd64 Packages
|         100 /var/lib/dpkg/status
| wpasupplicant:
|   Installed: 2.4-0ubuntu6
|   Candidate: 2.4-0ubuntu6
|   Version table:
|  *** 2.4-0ubuntu6 500
|         500 http://archive.ubuntu.com/ubuntu xenial/main amd64 Packages
|         100 /var/lib/dpkg/status


** Also affects: wpasupplicant (Ubuntu)
   Importance: Undecided
       Status: New

-- 
You received this bug notification because you are a member of Ubuntu
Foundations Bugs, which is subscribed to systemd in Ubuntu.
https://bugs.launchpad.net/bugs/1556357

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

Status in systemd 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

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/systemd/+bug/1556357/+subscriptions