← Back to team overview

desktop-packages team mailing list archive

[Bug 1467069] [NEW] NM loose WIFI connection - device cannot be readied for configuration

 

Public bug reported:

Hi there !

Since I upgraded from Ubuntu 14.10 to 15.04, I have some WIFI
disconnections. Seems to appears for no reasons... maybe after a period
of non utilization but i'm not sure.

Here some informations :

Package : network-manager-gnome 0.9.10.1-0ubuntu4


root@steven-desktop:/home/steven# /etc/init.d/network-manager status

● NetworkManager.service - Network Manager
   Loaded: loaded (/lib/systemd/system/NetworkManager.service; enabled; vendor preset: enabled)
   Active: active (running) since sam. 2015-06-20 11:15:43 CEST; 16min ago
 Main PID: 8594 (NetworkManager)
   CGroup: /system.slice/NetworkManager.service
           ├─ 812 /usr/sbin/dnsmasq --no-resolv --keep-in-foreground --no-hosts --bind-interfaces --pid-file=/run/sendsigs.omit.d/network-mana...
           ├─8594 /usr/sbin/NetworkManager --no-daemon
           └─8604 /sbin/dhclient -d -q -sf /usr/lib/NetworkManager/nm-dhcp-helper -pf /run/sendsigs.omit.d/network-manager.dhclient-wlan0.pid ...

juin 20 11:15:44 steven-desktop dhclient[8604]: bound to 192.168.1.35 -- renewal in 37987 seconds.
juin 20 11:15:44 steven-desktop NetworkManager[8594]: <info> NetworkManager state is now CONNECTED_GLOBAL
juin 20 11:15:44 steven-desktop NetworkManager[8594]: <info> Policy set '**************' (wlan0) as default for IPv4 routing and DNS.
juin 20 11:15:44 steven-desktop NetworkManager[8594]: <info> Writing DNS information to /sbin/resolvconf
juin 20 11:15:44 steven-desktop dnsmasq[812]: configuration des serveurs amonts à partir de DBus
juin 20 11:15:44 steven-desktop dnsmasq[812]: utilise le serveur de nom 192.168.1.1#53
juin 20 11:15:44 steven-desktop NetworkManager[8594]: <info> Activation (wlan0) successful, device activated.
juin 20 11:15:49 steven-desktop NetworkManager[8594]: <info> startup complete
juin 20 11:15:54 steven-desktop NetworkManager[8594]: <info> WiFi hardware radio set enabled
juin 20 11:15:54 steven-desktop NetworkManager[8594]: <info> WWAN hardware radio set enabled


Here logs when I have this problem, reported error is first with reason :  NM_DEVICE_STATE_REASON_CONFIG_FAILED 


root@steven-desktop:/home/steven# cat /var/log/syslog | grep wpa_supplicant

 Jun 20 10:49:04 steven-desktop wpa_supplicant[777]: wlan0: WPA: Group rekeying completed with 18:62:2c:93:fa:72 [GTK=TKIP]
Jun 20 11:00:55 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-DISCONNECTED bssid=18:62:2c:93:fa:72 reason=4 locally_generated=1
Jun 20 11:00:56 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SCAN-STARTED
Jun 20 11:00:57 steven-desktop wpa_supplicant[777]: wlan0: SME: Trying to authenticate with 18:62:2c:93:fa:72 (SSID='******************' freq=2437 MHz)
Jun 20 11:01:00 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SCAN-STARTED
Jun 20 11:01:01 steven-desktop wpa_supplicant[777]: wlan0: SME: Trying to authenticate with 18:62:2c:93:fa:72 (SSID='******************' freq=2437 MHz)
Jun 20 11:01:03 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SCAN-STARTED
Jun 20 11:01:04 steven-desktop wpa_supplicant[777]: wlan0: SME: Trying to authenticate with 18:62:2c:93:fa:72 (SSID='******************' freq=2437 MHz)
Jun 20 11:01:04 steven-desktop wpa_supplicant[777]: wlan0: Trying to associate with 18:62:2c:93:fa:72 (SSID='******************' freq=2437 MHz)
Jun 20 11:01:05 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="******************" auth_failures=1 duration=10
Jun 20 11:01:10 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SCAN-STARTED
Jun 20 11:01:11 steven-desktop wpa_supplicant[777]: wlan0: Reject scan trigger since one is already pending
Jun 20 11:01:11 steven-desktop wpa_supplicant[777]: wlan0: SME: Trying to authenticate with 18:62:2c:93:fa:72 (SSID='******************' freq=2437 MHz)
Jun 20 11:01:12 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="******************" auth_failures=1 duration=10
[...]


root@steven-desktop:/home/steven# cat /var/log/syslog | grep NetworkManager

Jun 20 11:00:55 steven-desktop NetworkManager[645]: <warn> Connection disconnected (reason -4)
Jun 20 11:00:55 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: completed -> disconnected
Jun 20 11:00:56 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Jun 20 11:00:57 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jun 20 11:00:58 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: authenticating -> disconnected
Jun 20 11:01:00 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Jun 20 11:01:01 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jun 20 11:01:02 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: authenticating -> disconnected
Jun 20 11:01:03 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Jun 20 11:01:04 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: scanning -> authenticating
Jun 20 11:01:04 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: authenticating -> associating
Jun 20 11:01:05 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: associating -> disconnected
Jun 20 11:01:10 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: disconnected -> scanning
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <warn> (wlan0): link timed out.
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): device state change: activated -> failed (reason 'supplicant-timeout') [100 120 11]
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> NetworkManager state is now CONNECTED_LOCAL
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> NetworkManager state is now DISCONNECTED
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <warn> Activation (wlan0) failed for connection '******************'
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): deactivating device (reason 'none') [0]
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 809
Jun 20 11:01:11 steven-desktop NetworkManager[645]: _nl_get_vtable: assertion 'vtable.handle' failed
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Writing DNS information to /sbin/resolvconf
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Auto-activating connection '******************'.
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) starting connection '******************'
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> NetworkManager state is now CONNECTING
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0/wireless): access point '******************' has security, but secrets are required.
[...]


My solution is to restart network-manager like this "service network-manager restart", but maybe network-manager is not the faulty package.

Thanks for your help  !

** Affects: network-manager-applet (Ubuntu)
     Importance: Undecided
         Status: New


** Tags: network-manage wifi

-- 
You received this bug notification because you are a member of Desktop
Packages, which is subscribed to network-manager-applet in Ubuntu.
https://bugs.launchpad.net/bugs/1467069

Title:
  NM loose WIFI connection - device cannot be readied for configuration

Status in network-manager-applet package in Ubuntu:
  New

Bug description:
  Hi there !

  Since I upgraded from Ubuntu 14.10 to 15.04, I have some WIFI
  disconnections. Seems to appears for no reasons... maybe after a
  period of non utilization but i'm not sure.

  Here some informations :

  Package : network-manager-gnome 0.9.10.1-0ubuntu4

  
  root@steven-desktop:/home/steven# /etc/init.d/network-manager status

  ● NetworkManager.service - Network Manager
     Loaded: loaded (/lib/systemd/system/NetworkManager.service; enabled; vendor preset: enabled)
     Active: active (running) since sam. 2015-06-20 11:15:43 CEST; 16min ago
   Main PID: 8594 (NetworkManager)
     CGroup: /system.slice/NetworkManager.service
             ├─ 812 /usr/sbin/dnsmasq --no-resolv --keep-in-foreground --no-hosts --bind-interfaces --pid-file=/run/sendsigs.omit.d/network-mana...
             ├─8594 /usr/sbin/NetworkManager --no-daemon
             └─8604 /sbin/dhclient -d -q -sf /usr/lib/NetworkManager/nm-dhcp-helper -pf /run/sendsigs.omit.d/network-manager.dhclient-wlan0.pid ...

  juin 20 11:15:44 steven-desktop dhclient[8604]: bound to 192.168.1.35 -- renewal in 37987 seconds.
  juin 20 11:15:44 steven-desktop NetworkManager[8594]: <info> NetworkManager state is now CONNECTED_GLOBAL
  juin 20 11:15:44 steven-desktop NetworkManager[8594]: <info> Policy set '**************' (wlan0) as default for IPv4 routing and DNS.
  juin 20 11:15:44 steven-desktop NetworkManager[8594]: <info> Writing DNS information to /sbin/resolvconf
  juin 20 11:15:44 steven-desktop dnsmasq[812]: configuration des serveurs amonts à partir de DBus
  juin 20 11:15:44 steven-desktop dnsmasq[812]: utilise le serveur de nom 192.168.1.1#53
  juin 20 11:15:44 steven-desktop NetworkManager[8594]: <info> Activation (wlan0) successful, device activated.
  juin 20 11:15:49 steven-desktop NetworkManager[8594]: <info> startup complete
  juin 20 11:15:54 steven-desktop NetworkManager[8594]: <info> WiFi hardware radio set enabled
  juin 20 11:15:54 steven-desktop NetworkManager[8594]: <info> WWAN hardware radio set enabled

  
  Here logs when I have this problem, reported error is first with reason :  NM_DEVICE_STATE_REASON_CONFIG_FAILED 

  
  root@steven-desktop:/home/steven# cat /var/log/syslog | grep wpa_supplicant

   Jun 20 10:49:04 steven-desktop wpa_supplicant[777]: wlan0: WPA: Group rekeying completed with 18:62:2c:93:fa:72 [GTK=TKIP]
  Jun 20 11:00:55 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-DISCONNECTED bssid=18:62:2c:93:fa:72 reason=4 locally_generated=1
  Jun 20 11:00:56 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SCAN-STARTED
  Jun 20 11:00:57 steven-desktop wpa_supplicant[777]: wlan0: SME: Trying to authenticate with 18:62:2c:93:fa:72 (SSID='******************' freq=2437 MHz)
  Jun 20 11:01:00 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SCAN-STARTED
  Jun 20 11:01:01 steven-desktop wpa_supplicant[777]: wlan0: SME: Trying to authenticate with 18:62:2c:93:fa:72 (SSID='******************' freq=2437 MHz)
  Jun 20 11:01:03 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SCAN-STARTED
  Jun 20 11:01:04 steven-desktop wpa_supplicant[777]: wlan0: SME: Trying to authenticate with 18:62:2c:93:fa:72 (SSID='******************' freq=2437 MHz)
  Jun 20 11:01:04 steven-desktop wpa_supplicant[777]: wlan0: Trying to associate with 18:62:2c:93:fa:72 (SSID='******************' freq=2437 MHz)
  Jun 20 11:01:05 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="******************" auth_failures=1 duration=10
  Jun 20 11:01:10 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SCAN-STARTED
  Jun 20 11:01:11 steven-desktop wpa_supplicant[777]: wlan0: Reject scan trigger since one is already pending
  Jun 20 11:01:11 steven-desktop wpa_supplicant[777]: wlan0: SME: Trying to authenticate with 18:62:2c:93:fa:72 (SSID='******************' freq=2437 MHz)
  Jun 20 11:01:12 steven-desktop wpa_supplicant[777]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="******************" auth_failures=1 duration=10
  [...]

  
  root@steven-desktop:/home/steven# cat /var/log/syslog | grep NetworkManager

  Jun 20 11:00:55 steven-desktop NetworkManager[645]: <warn> Connection disconnected (reason -4)
  Jun 20 11:00:55 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: completed -> disconnected
  Jun 20 11:00:56 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: disconnected -> scanning
  Jun 20 11:00:57 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: scanning -> authenticating
  Jun 20 11:00:58 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: authenticating -> disconnected
  Jun 20 11:01:00 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: disconnected -> scanning
  Jun 20 11:01:01 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: scanning -> authenticating
  Jun 20 11:01:02 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: authenticating -> disconnected
  Jun 20 11:01:03 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: disconnected -> scanning
  Jun 20 11:01:04 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: scanning -> authenticating
  Jun 20 11:01:04 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: authenticating -> associating
  Jun 20 11:01:05 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: associating -> disconnected
  Jun 20 11:01:10 steven-desktop NetworkManager[645]: <info> (wlan0): supplicant interface state: disconnected -> scanning
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <warn> (wlan0): link timed out.
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): device state change: activated -> failed (reason 'supplicant-timeout') [100 120 11]
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> NetworkManager state is now CONNECTED_LOCAL
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> NetworkManager state is now DISCONNECTED
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <warn> Activation (wlan0) failed for connection '******************'
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): device state change: failed -> disconnected (reason 'none') [120 30 0]
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): deactivating device (reason 'none') [0]
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 809
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: _nl_get_vtable: assertion 'vtable.handle' failed
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Writing DNS information to /sbin/resolvconf
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Auto-activating connection '******************'.
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) starting connection '******************'
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> NetworkManager state is now CONNECTING
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
  Jun 20 11:01:11 steven-desktop NetworkManager[645]: <info> Activation (wlan0/wireless): access point '******************' has security, but secrets are required.
  [...]

  
  My solution is to restart network-manager like this "service network-manager restart", but maybe network-manager is not the faulty package.

  Thanks for your help  !

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/network-manager-applet/+bug/1467069/+subscriptions


Follow ups

References