Bug 787471 - NetworkManager no longer auto-connects
Summary: NetworkManager no longer auto-connects
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: Fedora
Classification: Fedora
Component: NetworkManager
Version: 16
Hardware: x86_64
OS: Linux
unspecified
low
Target Milestone: ---
Assignee: Dan Williams
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2012-02-05 13:02 UTC by Piratmac
Modified: 2012-02-23 07:46 UTC (History)
2 users (show)

Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2012-02-23 07:46:10 UTC
Type: ---


Attachments (Terms of Use)

Description Piratmac 2012-02-05 13:02:00 UTC
Description of problem:
Upon startup or wake up, Networkmanager won't connect to wifi networks, even if those are market as "autoconnect" and the password is correctly filled.
If I manually ask to connect to some network, I have to enter the password again, and it creates a new network in nm-connection-editor, even though the previous one has the same name.


Version-Release number of selected component (if applicable):
kernel 3.2.2-1.fc16.x86_64
NetworkManager 1:0.9.2-1.fc16



Steps to Reproduce:
1. Connect to wifi network (via the top panel), enter password and get connected. In nm-connection-editor, check that "autoconnect" is checked.
2. Suspend or shutdown, then start again.
3. NetworkManager doesn't connect back to the network
4. Through the top panel, connect to the network. Give WPA password.
5. Open nm-connection-editor. You'll see two networks (with the same info inside)


Other infos :
- It started a few weeks ago (probably 2 or 3)
- My network is not hidden
- When I connect manually, everything works perfectly fine (good bandwith, no problems, ...)
- The prompt for the password is on a black background (like others since gnome 3). If I click "cancel", I get another prompt for password, but this one has a gray background (like classic windows). Either prompt acts the same way (both create a duplicate network and I get connected)



Thanks in advance for your help !


--------- Some dumps ----------


/var/log/messages, just after wake up (I was not connected)  :
13:55:20 : <info> sleep requested (sleeping: no  enabled: yes)
13:55:20 : <info> sleeping or disabling...
13:55:20 : <info> (eth0): now unmanaged
13:55:20 : <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
13:55:20 : <info> (eth0): cleaning up...
13:55:20 : <info> (eth0): taking down device.
13:55:20 : NetworkManager[868]: <info> sleep requested (sleeping: no  enabled: yes)
13:55:20 : NetworkManager[868]: <info> sleeping or disabling...
13:55:20 : NetworkManager[868]: <info> (eth0): now unmanaged
13:55:20 : NetworkManager[868]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
13:55:20 : NetworkManager[868]: <info> (eth0): cleaning up...
13:55:20 : NetworkManager[868]: <info> (eth0): taking down device.
13:55:20 : <info> (wlan0): now unmanaged
13:55:20 : <info> (wlan0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
13:55:20 : <info> (wlan0): deactivating device (reason 'sleeping') [37]
13:55:20 : NetworkManager[868]: <info> (wlan0): now unmanaged
13:55:20 : NetworkManager[868]: <info> (wlan0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
13:55:20 : NetworkManager[868]: <info> (wlan0): deactivating device (reason 'sleeping') [37]
13:55:21 : <info> (wlan0): canceled DHCP transaction, DHCP client pid 17611
13:55:21 : NetworkManager[868]: <info> (wlan0): canceled DHCP transaction, DHCP client pid 17611
13:55:21 : NetworkManager[868]: <info> (wlan0): cleaning up...
13:55:21 : <info> (wlan0): cleaning up...
13:55:21 : NetworkManager[868]: <info> (wlan0): taking down device.
13:55:21 : <info> (wlan0): taking down device.
13:55:39 : <info> radio killswitch /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/ieee80211/phy2/rfkill2 disappeared
13:55:39 : NetworkManager[868]: <info> radio killswitch /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/ieee80211/phy2/rfkill2 disappeared
13:55:40 : <info> (wlan0): driver supports SSID scans (scan_capa 0x01).
13:55:40 : <info> (wlan0): new 802.11 WiFi device (driver: 'rtl8187' ifindex: 7)
13:55:40 : <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/4
13:55:40 : NetworkManager[868]: <info> (wlan0): driver supports SSID scans (scan_capa 0x01).
13:55:40 : NetworkManager[868]: <info> (wlan0): new 802.11 WiFi device (driver: 'rtl8187' ifindex: 7)
13:55:40 : NetworkManager[868]: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/4
13:55:40 : <info> found WiFi radio killswitch rfkill3 (at /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/ieee80211/phy3/rfkill3) (driver (unknown))
13:55:40 : NetworkManager[868]: <info> found WiFi radio killswitch rfkill3 (at /sys/devices/pci0000:00/0000:00:03.3/usb1/1-2/1-2:1.0/ieee80211/phy3/rfkill3) (driver (unknown))
13:55:40 : <info> wake requested (sleeping: yes  enabled: yes)
13:55:40 : <info> waking up and re-enabling...
13:55:40 : <info> (eth0): now managed
13:55:40 : <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
13:55:40 : <info> (eth0): bringing up device.
13:55:40 : <info> (eth0): preparing device.
13:55:40 : <info> (eth0): deactivating device (reason 'managed') [2]
13:55:40 : <info> (wlan0): now managed
13:55:40 : <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
13:55:40 : NetworkManager[868]: <info> wake requested (sleeping: yes  enabled: yes)
13:55:40 : NetworkManager[868]: <info> waking up and re-enabling...
13:55:40 : NetworkManager[868]: <info> (eth0): now managed
13:55:40 : NetworkManager[868]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
13:55:40 : NetworkManager[868]: <info> (eth0): bringing up device.
13:55:40 : NetworkManager[868]: <info> (eth0): preparing device.
13:55:40 : NetworkManager[868]: <info> (eth0): deactivating device (reason 'managed') [2]
13:55:40 : NetworkManager[868]: <info> (wlan0): now managed
13:55:40 : NetworkManager[868]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
13:55:40 : NetworkManager[868]: <info> (wlan0): bringing up device.
13:55:40 : <info> (wlan0): bringing up device.
13:55:44 : <info> (wlan0): preparing device.
13:55:44 : <info> (wlan0): deactivating device (reason 'managed') [2]
13:55:44 : NetworkManager[868]: <info> (wlan0): preparing device.
13:55:44 : NetworkManager[868]: <info> (wlan0): deactivating device (reason 'managed') [2]
13:55:44 : <info> (wlan0): supplicant interface state: starting -> ready
13:55:44 : <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
13:55:44 : NetworkManager[868]: <info> (wlan0): supplicant interface state: starting -> ready
13:55:44 : NetworkManager[868]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
13:55:44 : <info> (wlan0): supplicant interface state: ready -> inactive
13:55:44 : NetworkManager[868]: <info> (wlan0): supplicant interface state: ready -> inactive

(I removed the start of the line)







After asking for connection :

13:59:06 : NetworkManager[868]:    ifcfg-rh:     read connection 'Auto MyNetwork'
13:59:06 :    ifcfg-rh:     read connection 'Auto MyNetwork'
13:59:06 : NetworkManager[868]: <info> Activation (wlan0) starting connection 'Auto MyNetwork'
13:59:06 : <info> Activation (wlan0) starting connection 'Auto MyNetwork'
13:59:06 : NetworkManager[868]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
13:59:06 : <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
13:59:06 : NetworkManager[868]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:59:06 : <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:59:06 : NetworkManager[868]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:59:06 : <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:59:06 : NetworkManager[868]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:59:06 : <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:59:06 : NetworkManager[868]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:59:06 : <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:59:06 : NetworkManager[868]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:59:06 : <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:59:06 : NetworkManager[868]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:59:06 : <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:59:06 : NetworkManager[868]: <info> Activation (wlan0/wireless): access point 'Auto MyNetwork' has security, but secrets are required.
13:59:06 : <info> Activation (wlan0/wireless): access point 'Auto MyNetwork' has security, but secrets are required.
13:59:06 : NetworkManager[868]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
13:59:06 : <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
13:59:06 : NetworkManager[868]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:59:06 : <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:59:07 :    ifcfg-rh: updating /etc/sysconfig/network-scripts/ifcfg-Auto_MyNetwork-3
13:59:07 : NetworkManager[868]:    ifcfg-rh: updating /etc/sysconfig/network-scripts/ifcfg-Auto_MyNetwork-3
13:59:16 : NetworkManager[868]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:59:16 : <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
13:59:16 : NetworkManager[868]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:59:16 : <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
13:59:16 : NetworkManager[868]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
13:59:16 : <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
13:59:16 : NetworkManager[868]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:59:16 : <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
13:59:16 : NetworkManager[868]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:59:16 : <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
13:59:16 : NetworkManager[868]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:59:16 : <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
13:59:16 : NetworkManager[868]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:59:16 : <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
13:59:16 : NetworkManager[868]: <info> Activation (wlan0/wireless): connection 'Auto MyNetwork' has security, and secrets exist.  No new secrets needed.
13:59:16 : <info> Activation (wlan0/wireless): connection 'Auto MyNetwork' has security, and secrets exist.  No new secrets needed.
13:59:16 : NetworkManager[868]: <info> Config: added 'ssid' value 'MyNetwork'
13:59:16 : <info> Config: added 'ssid' value 'MyNetwork'
13:59:16 : NetworkManager[868]: <info> Config: added 'scan_ssid' value '1'
13:59:16 : <info> Config: added 'scan_ssid' value '1'
13:59:16 : NetworkManager[868]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
13:59:16 : <info> Config: added 'key_mgmt' value 'WPA-PSK'
13:59:16 : NetworkManager[868]: <info> Config: added 'psk' value '<omitted>'
13:59:16 : <info> Config: added 'psk' value '<omitted>'
13:59:16 : NetworkManager[868]: <info> Config: added 'proto' value 'WPA RSN'
13:59:16 : <info> Config: added 'proto' value 'WPA RSN'
13:59:16 : NetworkManager[868]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:59:16 : <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
13:59:16 : NetworkManager[868]: <info> Config: set interface ap_scan to 1
13:59:16 : <info> Config: set interface ap_scan to 1
13:59:16 : <info> (wlan0): supplicant interface state: inactive -> scanning
13:59:16 : NetworkManager[868]: <info> (wlan0): supplicant interface state: inactive -> scanning
13:59:18 : <info> (wlan0): supplicant interface state: scanning -> authenticating
13:59:18 : NetworkManager[868]: <info> (wlan0): supplicant interface state: scanning -> authenticating
13:59:18 : <info> (wlan0): supplicant interface state: authenticating -> associating
13:59:18 : NetworkManager[868]: <info> (wlan0): supplicant interface state: authenticating -> associating
13:59:18 : <info> (wlan0): supplicant interface state: associating -> associated
13:59:18 : NetworkManager[868]: <info> (wlan0): supplicant interface state: associating -> associated
13:59:19 : <info> (wlan0): supplicant interface state: associated -> 4-way handshake
13:59:19 : NetworkManager[868]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
13:59:19 : <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
13:59:19 : NetworkManager[868]: <info> (wlan0): supplicant interface state: 4-way handshake -> group handshake
13:59:19 : <info> (wlan0): supplicant interface state: group handshake -> completed
13:59:19 : <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'MyNetwork'.
13:59:19 : <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
13:59:19 : NetworkManager[868]: <info> (wlan0): supplicant interface state: group handshake -> completed
13:59:19 : NetworkManager[868]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'MyNetwork'.
13:59:19 : NetworkManager[868]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
13:59:19 : <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
13:59:19 : <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
13:59:19 : NetworkManager[868]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
13:59:19 : NetworkManager[868]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
13:59:19 : <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
13:59:19 : NetworkManager[868]: <info> Activation (wlan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
13:59:19 : NetworkManager[868]: <info> dhclient started with pid 18625
13:59:19 : <info> dhclient started with pid 18625
13:59:19 : NetworkManager[868]: <info> Activation (wlan0) Beginning IP6 addrconf.
13:59:19 : <info> Activation (wlan0) Beginning IP6 addrconf.
13:59:19 : NetworkManager[868]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
13:59:19 : <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
13:59:19 : Internet Systems Consortium DHCP Client 4.2.3-P2
13:59:19 : Copyright 2004-2012 Internet Systems Consortium.
13:59:19 : All rights reserved.
13:59:19 : For info, please visit https://www.isc.org/software/dhcp/
13:59:19 : NetworkManager[868]: <info> (wlan0): DHCPv4 state changed nbi -> preinit
13:59:19 : <info> (wlan0): DHCPv4 state changed nbi -> preinit
13:59:19 : Listening on LPF/wlan0/86:92:9d:fd:4f:b7
13:59:19 : Sending on   LPF/wlan0/86:92:9d:fd:4f:b7
13:59:19 : Sending on   Socket/fallback
13:59:19 : DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 4
13:59:22 : DHCPREQUEST on wlan0 to 255.255.255.255 port 67
13:59:22 : DHCPOFFER from 192.168.1.1
13:59:22 : DHCPACK from 192.168.1.1
13:59:22 : bound to 192.168.1.62 -- renewal in 40745 seconds.
13:59:22 : <info> (wlan0): DHCPv4 state changed preinit -> bound
13:59:22 : <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
13:59:22 : NetworkManager[868]: <info> (wlan0): DHCPv4 state changed preinit -> bound
13:59:22 : NetworkManager[868]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
13:59:22 : <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
13:59:22 : <info>   address 192.168.1.62
13:59:22 : <info>   prefix 24 (255.255.255.0)
13:59:22 : <info>   gateway 192.168.1.1
13:59:22 : <info>   hostname 'PiratLand'
13:59:22 : <info>   nameserver '192.168.1.1'
13:59:22 : NetworkManager[868]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
13:59:22 : NetworkManager[868]: <info>   address 192.168.1.62
13:59:22 : NetworkManager[868]: <info>   prefix 24 (255.255.255.0)
13:59:22 : NetworkManager[868]: <info>   gateway 192.168.1.1
13:59:22 : NetworkManager[868]: <info>   hostname 'PiratLand'
13:59:22 : NetworkManager[868]: <info>   nameserver '192.168.1.1'
13:59:22 : <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
13:59:22 : NetworkManager[868]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
13:59:39 : <info> (wlan0): IP6 addrconf timed out or failed.
13:59:39 : NetworkManager[868]: <info> (wlan0): IP6 addrconf timed out or failed.
13:59:39 : <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) scheduled...
13:59:39 : <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) started...
13:59:39 : <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
13:59:39 : <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) complete.
13:59:39 : <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
13:59:39 : NetworkManager[868]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) scheduled...
13:59:39 : NetworkManager[868]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) started...
13:59:39 : NetworkManager[868]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
13:59:39 : NetworkManager[868]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) complete.
13:59:39 : NetworkManager[868]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
13:59:40 : <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
13:59:40 : NetworkManager[868]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
13:59:40 : <info> Policy set 'Auto MyNetwork' (wlan0) as default for IPv4 routing and DNS.
13:59:40 : <info> Activation (wlan0) successful, device activated.
13:59:40 : NetworkManager[868]: <info> Policy set 'Auto MyNetwork' (wlan0) as default for IPv4 routing and DNS.
13:59:40 : NetworkManager[868]: <info> Activation (wlan0) successful, device activated.
13:59:40 : <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
13:59:40 : NetworkManager[868]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.











$ nm-tool

NetworkManager Tool

State: connected (global)

- Device: eth0 -----------------------------------------------------------------
[deleted]

- Device: wlan0  [Auto MyNetwork] ----------------------------------------------
  Type:              802.11 WiFi
  Driver:            rtl8187
  State:             connected
  Default:           yes
  HW Address:        XX:XX:XX:XX:XX:XX

  Capabilities:
    Speed:           48 Mb/s

  Wireless Properties
    WEP Encryption:  yes
    WPA Encryption:  yes
    WPA2 Encryption: yes

  Wireless Access Points (* = current AP)
    *MyNetwork:      Infra, 00:17:33:37:AC:48, Freq 2422 MHz, Rate 54 Mb/s, Strength 72 WPA
    Othernetwork:    Infra, 00:17:33:A0:C1:BC, Freq 2462 MHz, Rate 54 Mb/s, Strength 65 WPA
[15 other networks]

  IPv4 Settings:
    Address:         192.168.1.21
    Prefix:          24 (255.255.255.0)
    Gateway:         192.168.1.1

    DNS:             192.168.1.1









$ nmcli -f all con list
NOM                       UUID                                   TYPE              TIMESTAMP    TIMESTAMP-REAL                     AUTOCONNECT   LECT. SEULE CHEMIN DBUS                               
Auto MyNetwork            7e8d55a2-3d06-4816-898e-43757dd5fabd   802-11-wireless   1328442276   Sun. 05 febr. 2012 12:44:36 CET    oui           non        /org/freedesktop/NetworkManager/Settings/2
Auto MyNetwork            f0b60ca1-bdd9-4d6c-972f-2629de37fd1e   802-11-wireless   1328445775   Sun. 05 febr. 2012 13:42:55 CET    oui           non        /org/freedesktop/NetworkManager/Settings/3

Comment 1 Piratmac 2012-02-23 07:46:10 UTC
Seems solved by an update, even if NetworkManager has not been updated recently.


Note You need to log in before you can comment on or make changes to this bug.