desktop-packages team mailing list archive

Thread
Date
[Bug 1467069] Re: NM loose WIFI connection - device cannot be readied for configuration

To: desktop-packages@xxxxxxxxxxxxxxxxxxx
From: Steven Valsesia <steven.valsesia@xxxxxxxxx>
Date: Sat, 15 Aug 2015 09:17:02 -0000
Reply-to: Bug 1467069 <1467069@xxxxxxxxxxxxxxxxxx>
Sender: bounces@xxxxxxxxxxxxx
Can someone tell us more about the cause of this bug please ?

-- 
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:
  Confirmed
Status in rt2x00 package in Ubuntu:
  Confirmed

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
References

[Bug 1467069] [NEW] NM loose WIFI connection - device cannot be readied for configuration
From: Steven Valsesia, 2015-06-20