Ubuntu 8.10 was unable to authenticate and connect to wireless access point at fist session logon.

[eridano]

After power on, start and logon process, automatically Ubuntu try to authenticate and connect to the defined wireless network; in the first aptempt it fails and ask me to verify wireless credential data. If I click on connect the connection establish.

Ivan Cella
<email address hidden>

--messages from daemon.log
Nov 12 09:46:12 my-laptop NetworkManager: <info> (ath0): device state change: 1 -> 2
Nov 12 09:46:12 my-laptop NetworkManager: <info> (ath0): bringing up device.
Nov 12 09:46:12 my-laptop NetworkManager: <info> (ath0): preparing device.
Nov 12 09:46:12 my-laptop NetworkManager: <info> (ath0): deactivating device (reason: 2).
Nov 12 09:46:12 my-laptop NetworkManager: <info> (ath0): device state change: 2 -> 3
Nov 12 09:46:12 my-laptop nm-system-settings: Adding default connection 'Auto eth0' for /org/freedesktop/Hal/devices/net_00_15_58_29_fc_96
Nov 12 09:46:12 my-laptop NetworkManager: <info> (ath0): supplicant interface state change: 1 -> 2.
Nov 12 09:46:16 my-laptop gdmgreeter[5382]: Gtk-WARNING: Unable to locate theme engine in module_path: "ubuntulooks",
Nov 12 09:46:37 my-laptop x-session-manager[5405]: WARNING: Unable to find provider 'gnome-wm' of required component 'windowmanager'
Nov 12 09:46:47 my-laptop x-session-manager[5405]: WARNING: Application 'gnome-wm.desktop' failed to register before timeout
Nov 12 09:46:52 my-laptop hald: mounted /dev/sdb1 on behalf of uid 1000
Nov 12 09:46:57 my-laptop x-session-manager[5405]: WARNING: Application 'libcanberra-login-sound.desktop' failed to register before timeout
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) starting connection 'Auto Alice-1234'
Nov 12 09:46:59 my-laptop NetworkManager: <info> (ath0): device state change: 3 -> 4
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) scheduled...
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) started...
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) scheduled...
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) complete.
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) starting...
Nov 12 09:46:59 my-laptop NetworkManager: <info> (ath0): device state change: 4 -> 5
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0/wireless): access point 'Auto Alice-1234' has security, but secrets are required.
Nov 12 09:46:59 my-laptop NetworkManager: <info> (ath0): device state change: 5 -> 6
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) complete.
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) scheduled...
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) started...
Nov 12 09:46:59 my-laptop NetworkManager: <info> (ath0): device state change: 6 -> 4
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) scheduled...
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) complete.
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) starting...
Nov 12 09:46:59 my-laptop NetworkManager: <info> (ath0): device state change: 4 -> 5
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0/wireless): connection 'Auto Alice-1234' has security, and secrets exist. No new secrets needed.
Nov 12 09:46:59 my-laptop NetworkManager: <info> Config: added 'ssid' value 'Alice-1234'
Nov 12 09:46:59 my-laptop NetworkManager: <info> Config: added 'scan_ssid' value '1'
Nov 12 09:46:59 my-laptop NetworkManager: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Nov 12 09:46:59 my-laptop NetworkManager: <info> Config: added 'psk' value '<omitted>'
Nov 12 09:46:59 my-laptop NetworkManager: <info> Config: added 'proto' value 'WPA RSN'
Nov 12 09:46:59 my-laptop NetworkManager: <info> Config: added 'pairwise' value 'TKIP CCMP'
Nov 12 09:46:59 my-laptop NetworkManager: <info> Config: added 'group' value 'WEP40 WEP104 TKIP CCMP'
Nov 12 09:46:59 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) complete.
Nov 12 09:46:59 my-laptop NetworkManager: <info> Config: set interface ap_scan to 1
Nov 12 09:46:59 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 1 -> 2
Nov 12 09:47:13 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 2 -> 3
Nov 12 09:47:23 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 3 -> 0
Nov 12 09:47:23 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 0 -> 2
Nov 12 09:47:23 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 2 -> 3
Nov 12 09:47:24 my-laptop NetworkManager: <info> Activation (ath0/wireless): association took too long.
Nov 12 09:47:24 my-laptop NetworkManager: <info> (ath0): device state change: 5 -> 6
Nov 12 09:47:24 my-laptop NetworkManager: <info> Activation (ath0/wireless): asking for new secrets
Nov 12 09:47:24 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 3 -> 0
Nov 12 09:47:39 my-laptop NetworkManager: <info> ath0: link timed out.
Nov 12 09:47:43 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 0 -> 2
Nov 12 09:48:09 my-laptop avahi-daemon[4811]: Registering new address record for fe80::216:ceff:fe62:b2b on ath0.*.
Nov 12 09:52:24 my-laptop NetworkManager: <WARN> get_secrets_cb(): Couldn't get connection secrets: Message did not receive a reply (timeout by message bus).
Nov 12 09:52:24 my-laptop NetworkManager: <info> (ath0): device state change: 6 -> 9
Nov 12 09:52:24 my-laptop NetworkManager: <info> Activation (ath0) failed for access point (Alice-1234)
Nov 12 09:52:24 my-laptop NetworkManager: <info> Marking connection 'Auto Alice-1234' invalid.
Nov 12 09:52:24 my-laptop NetworkManager: <info> Activation (ath0) failed.
Nov 12 09:52:24 my-laptop NetworkManager: <info> (ath0): device state change: 9 -> 3
Nov 12 09:52:24 my-laptop NetworkManager: <info> (ath0): deactivating device (reason: 0).
Nov 12 09:52:24 my-laptop NetworkManager: <info> Activation (ath0) starting connection 'Auto Alice-1234'
Nov 12 09:52:24 my-laptop NetworkManager: <info> (ath0): device state change: 3 -> 4
Nov 12 09:52:24 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) scheduled...
Nov 12 09:52:24 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) started...
Nov 12 09:52:24 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) scheduled...
Nov 12 09:52:24 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) complete.
Nov 12 09:52:24 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) starting...
Nov 12 09:52:24 my-laptop NetworkManager: <info> (ath0): device state change: 4 -> 5
Nov 12 09:52:24 my-laptop NetworkManager: <info> Activation (ath0/wireless): access point 'Auto Alice-1234' has security, but secrets are required.
Nov 12 09:52:24 my-laptop NetworkManager: <info> (ath0): device state change: 5 -> 6
Nov 12 09:52:24 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) complete.
Nov 12 09:52:24 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 2 -> 0
Nov 12 09:52:25 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) scheduled...
Nov 12 09:52:25 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) started...
Nov 12 09:52:25 my-laptop NetworkManager: <info> (ath0): device state change: 6 -> 4
Nov 12 09:52:25 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) scheduled...
Nov 12 09:52:25 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) complete.
Nov 12 09:52:25 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) starting...
Nov 12 09:52:25 my-laptop NetworkManager: <info> (ath0): device state change: 4 -> 5
Nov 12 09:52:25 my-laptop NetworkManager: <info> Activation (ath0/wireless): connection 'Auto Alice-1234' has security, and secrets exist. No new secrets needed.
Nov 12 09:52:25 my-laptop NetworkManager: <info> Config: added 'ssid' value 'Alice-1234'
Nov 12 09:52:25 my-laptop NetworkManager: <info> Config: added 'scan_ssid' value '1'
Nov 12 09:52:25 my-laptop NetworkManager: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Nov 12 09:52:25 my-laptop NetworkManager: <info> Config: added 'psk' value '<omitted>'
Nov 12 09:52:25 my-laptop NetworkManager: <info> Config: added 'proto' value 'WPA RSN'
Nov 12 09:52:25 my-laptop NetworkManager: <info> Config: added 'pairwise' value 'TKIP CCMP'
Nov 12 09:52:25 my-laptop NetworkManager: <info> Config: added 'group' value 'WEP40 WEP104 TKIP CCMP'
Nov 12 09:52:25 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) complete.
Nov 12 09:52:25 my-laptop NetworkManager: <info> Config: set interface ap_scan to 1
Nov 12 09:52:25 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 0 -> 2
Nov 12 09:52:35 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 2 -> 3
Nov 12 09:52:45 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 3 -> 0
Nov 12 09:52:45 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 0 -> 2
Nov 12 09:52:45 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 2 -> 3
Nov 12 09:52:50 my-laptop NetworkManager: <info> Activation (ath0/wireless): association took too long.
Nov 12 09:52:50 my-laptop NetworkManager: <info> (ath0): device state change: 5 -> 6
Nov 12 09:52:50 my-laptop NetworkManager: <info> Activation (ath0/wireless): asking for new secrets
Nov 12 09:52:50 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 3 -> 0
Nov 12 09:53:05 my-laptop NetworkManager: <info> ath0: link timed out.
Nov 12 09:53:13 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) scheduled...
Nov 12 09:53:13 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) started...
Nov 12 09:53:13 my-laptop NetworkManager: <info> (ath0): device state change: 6 -> 4
Nov 12 09:53:13 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) scheduled...
Nov 12 09:53:13 my-laptop NetworkManager: <info> Activation (ath0) Stage 1 of 5 (Device Prepare) complete.
Nov 12 09:53:13 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) starting...
Nov 12 09:53:13 my-laptop NetworkManager: <info> (ath0): device state change: 4 -> 5
Nov 12 09:53:13 my-laptop NetworkManager: <info> Activation (ath0/wireless): connection 'Auto Alice-1234' has security, and secrets exist. No new secrets needed.
Nov 12 09:53:13 my-laptop NetworkManager: <info> Config: added 'ssid' value 'Alice-1234'
Nov 12 09:53:13 my-laptop NetworkManager: <info> Config: added 'scan_ssid' value '1'
Nov 12 09:53:13 my-laptop NetworkManager: <info> Config: added 'key_mgmt' value 'WPA-PSK'
Nov 12 09:53:13 my-laptop NetworkManager: <info> Config: added 'psk' value '<omitted>'
Nov 12 09:53:13 my-laptop NetworkManager: <info> Config: added 'proto' value 'WPA RSN'
Nov 12 09:53:13 my-laptop NetworkManager: <info> Config: added 'pairwise' value 'TKIP CCMP'
Nov 12 09:53:13 my-laptop NetworkManager: <info> Config: added 'group' value 'WEP40 WEP104 TKIP CCMP'
Nov 12 09:53:13 my-laptop NetworkManager: <info> Activation (ath0) Stage 2 of 5 (Device Configure) complete.
Nov 12 09:53:13 my-laptop NetworkManager: <info> Config: set interface ap_scan to 1
Nov 12 09:53:13 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 0 -> 2
Nov 12 09:53:23 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 2 -> 3
Nov 12 09:53:23 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 3 -> 4
Nov 12 09:53:23 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 4 -> 5
Nov 12 09:53:23 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 5 -> 6
Nov 12 09:53:23 my-laptop NetworkManager: <info> (ath0): supplicant connection state change: 6 -> 7
Nov 12 09:53:23 my-laptop NetworkManager: <info> Activation (ath0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'Alice-1234'.
Nov 12 09:53:23 my-laptop NetworkManager: <info> Activation (ath0) Stage 3 of 5 (IP Configure Start) scheduled.
Nov 12 09:53:23 my-laptop NetworkManager: <info> Activation (ath0) Stage 3 of 5 (IP Configure Start) started...
Nov 12 09:53:23 my-laptop NetworkManager: <info> (ath0): device state change: 5 -> 7
Nov 12 09:53:23 my-laptop NetworkManager: <info> Activation (ath0) Beginning DHCP transaction.
Nov 12 09:53:23 my-laptop NetworkManager: <info> dhclient started with pid 5991
Nov 12 09:53:23 my-laptop NetworkManager: <info> Activation (ath0) Stage 3 of 5 (IP Configure Start) complete.
Nov 12 09:53:23 my-laptop dhclient: Internet Systems Consortium DHCP Client V3.1.1
Nov 12 09:53:23 my-laptop dhclient: Copyright 2004-2008 Internet Systems Consortium.
Nov 12 09:53:23 my-laptop dhclient: All rights reserved.
Nov 12 09:53:23 my-laptop dhclient: For info, please visit http://www.isc.org/sw/dhcp/
Nov 12 09:53:23 my-laptop dhclient:
Nov 12 09:53:23 my-laptop dhclient: wifi0: unknown hardware address type 801
Nov 12 09:53:23 my-laptop NetworkManager: <info> DHCP: device ath0 state changed (null) -> preinit
Nov 12 09:53:23 my-laptop dhclient: wifi0: unknown hardware address type 801
Nov 12 09:53:23 my-laptop dhclient: Listening on LPF/ath0/00:16:ce:62:0b:2b
Nov 12 09:53:23 my-laptop dhclient: Sending on LPF/ath0/00:16:ce:62:0b:2b
Nov 12 09:53:23 my-laptop dhclient: Sending on Socket/fallback
Nov 12 09:53:27 my-laptop dhclient: DHCPDISCOVER on ath0 to 255.255.255.255 port 67 interval 6
Nov 12 09:53:29 my-laptop dhclient: DHCPOFFER of 192.168.1.156 from 192.168.1.1
Nov 12 09:53:29 my-laptop dhclient: DHCPREQUEST of 192.168.1.156 on ath0 to 255.255.255.255 port 67
Nov 12 09:53:29 my-laptop dhclient: DHCPACK of 192.168.1.156 from 192.168.1.1
Nov 12 09:53:29 my-laptop NetworkManager: <info> DHCP: device ath0 state changed preinit -> bound
Nov 12 09:53:29 my-laptop NetworkManager: <info> Activation (ath0) Stage 4 of 5 (IP Configure Get) scheduled...
Nov 12 09:53:29 my-laptop NetworkManager: <info> Activation (ath0) Stage 4 of 5 (IP Configure Get) started...
Nov 12 09:53:29 my-laptop NetworkManager: <info> address 192.168.1.156
Nov 12 09:53:29 my-laptop NetworkManager: <info> prefix 24 (255.255.255.0)
Nov 12 09:53:29 my-laptop NetworkManager: <info> gateway 192.168.1.1
Nov 12 09:53:29 my-laptop NetworkManager: <info> hostname 'my-laptop'
Nov 12 09:53:29 my-laptop NetworkManager: <info> nameserver '192.168.1.1'
Nov 12 09:53:29 my-laptop NetworkManager: <info> domain name 'homenet.telecomitalia.it'
Nov 12 09:53:29 my-laptop NetworkManager: <info> Activation (ath0) Stage 5 of 5 (IP Configure Commit) scheduled...
Nov 12 09:53:29 my-laptop NetworkManager: <info> Activation (ath0) Stage 4 of 5 (IP Configure Get) complete.
Nov 12 09:53:29 my-laptop NetworkManager: <info> Activation (ath0) Stage 5 of 5 (IP Configure Commit) started...
Nov 12 09:53:29 my-laptop avahi-daemon[4811]: Joining mDNS multicast group on interface ath0.IPv4 with address 192.168.1.156.
Nov 12 09:53:29 my-laptop avahi-daemon[4811]: New relevant interface ath0.IPv4 for mDNS.
Nov 12 09:53:29 my-laptop avahi-daemon[4811]: Registering new address record for 192.168.1.156 on ath0.IPv4.
Nov 12 09:53:29 my-laptop dhclient: bound to 192.168.1.156 -- renewal in 8581 seconds.
Nov 12 09:53:30 my-laptop NetworkManager: <info> (ath0): device state change: 7 -> 8
Nov 12 09:53:30 my-laptop NetworkManager: <info> Policy set 'Auto Alice-1234' (ath0) as default for routing and DNS.
Nov 12 09:53:30 my-laptop NetworkManager: <info> Activation (ath0) successful, device activated.
Nov 12 09:53:30 my-laptop NetworkManager: <info> Activation (ath0) Stage 5 of 5 (IP Configure Commit) complete.
Nov 12 09:53:31 my-laptop ntpdate[6046]: can't find host ntp.ubuntu.com
Nov 12 09:53:31 my-laptop ntpdate[6046]: no servers can be used, exiting

[Kenneth Venken (kenneth-venken)]

Thank you for taking the time to report this bug and helping to make Ubuntu better. This bug did not have a package associated with it, which is important for ensuring that it gets looked at by the proper developers. You can learn more about finding the right package at https://wiki.ubuntu.com/Bugs/FindRightPackage . I have classified this bug as a bug in network-manager
For future reference you might be interested to know that a lot of applications have bug reporting functionality built in to them. This can be accessed via the Report a Problem option in the Help menu for the application with which you are having an issue. You can learn more about this feature at https://wiki.ubuntu.com/ReportingBugs.

[Pedro Villavicencio (pedro)]

Thank you for taking the time to report this bug and helping to make Ubuntu better. You reported this bug a while ago and there hasn't been any activity in it recently. We were wondering is this still an issue for you? Could you try to reproduce the same with Ubuntu 8.10 or 9.04? Thanks in advance.

[Pedro Villavicencio (pedro)]

We are closing this bug report because it lacks the information we need to investigate the problem, as described in the previous comments. Please reopen it if you can give us the missing information, and don't hesitate to submit bug reports in the future. To reopen the bug report you can click on the current status, under the Status column, and change the Status back to New. Thanks again!.