Bug 191951 - Cannot connect to WPA-PSK (WPA2 Personal, TKIP+AES, using passphrase of 13 ascii chars. nm-applet works)
Summary: Cannot connect to WPA-PSK (WPA2 Personal, TKIP+AES, using passphrase of 13 as...
Status: RESOLVED DUPLICATE of bug 195824
Alias: None
Product: Network Management
Classification: Miscellaneous
Component: Plasma Widget (show other bugs)
Version: unspecified
Platform: Fedora RPMs Linux
: NOR normal
Target Milestone: ---
Assignee: Will Stephenson
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2009-05-07 21:18 UTC by MartinG
Modified: 2009-07-25 21:14 UTC (History)
4 users (show)

See Also:
Latest Commit:
Version Fixed In:


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description MartinG 2009-05-07 21:18:21 UTC
Version:           0.1-0.11.20090504svn.fc11.x86_64 (using KDE 4.2.2)
OS:                Linux
Installed from:    Fedora RPMs

I have my router set to WPA2 Personal, TKIP+AES, and have a passphrase (13 chars long, pure ascii chars). It works with nm-applet, and also worked with knetworkmanager. However, it doesn't work with the plasmoid.

I have tried both using the passphrase, and the 65 chars long hex code provided by nm-applet in edit connections, but both fails.

Here is the logs, first when I try the kde plasmoid (which fails), then when I use nm-applet (successful). I've hidden my private bits:


$ cd /var/log/
$ tail -f *

Starting kde-plasma-networkmanagement-0.1-0.11.20090504svn.fc11.x86_64 here:

==> messages <==
May  7 20:55:27 localhost NetworkManager: <info>  (wlan0): bringing up device.
May  7 20:55:27 localhost kernel: Registered led device: iwl-phy0:radio       
May  7 20:55:27 localhost kernel: Registered led device: iwl-phy0:assoc       
May  7 20:55:27 localhost kernel: Registered led device: iwl-phy0:RX          
May  7 20:55:27 localhost kernel: Registered led device: iwl-phy0:TX          
May  7 20:55:27 localhost NetworkManager: <info>  (wlan0): device state change: 2 -> 3
May  7 20:55:27 localhost kernel: ADDRCONF(NETDEV_UP): wlan0: link is not ready       
May  7 20:55:27 localhost NetworkManager: <info>  (wlan0): supplicant interface state:  starting -> ready
May  7 20:55:29 localhost NetworkManager: <info>  Activation (wlan0) starting connection 'H'     
May  7 20:55:29 localhost NetworkManager: <info>  (wlan0): device state change: 3 -> 4                   
May  7 20:55:29 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) started...  
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.     
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) starting... 
May  7 20:55:30 localhost NetworkManager: <info>  (wlan0): device state change: 4 -> 5                           
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0/wireless): access point 'H' has security, but secrets are required.
May  7 20:55:30 localhost NetworkManager: <info>  (wlan0): device state change: 5 -> 6                                                         
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) complete.                                 
May  7 20:55:30 localhost NetworkManager: <WARN>  update_one_setting(): Failed to update connection secrets: 1 802-1x                          
May  7 20:55:30 localhost NetworkManager: <WARN>  real_connection_secrets_updated(): Ignoring updated secrets for setting 'ipv4'.              
May  7 20:55:30 localhost NetworkManager: <WARN>  real_connection_secrets_updated(): Ignoring updated secrets for setting '802-11-wireless'.   
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...                                
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) started...                                  
May  7 20:55:30 localhost NetworkManager: <info>  (wlan0): device state change: 6 -> 4                                                         
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...                              
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.                                   
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) starting...                               
May  7 20:55:30 localhost NetworkManager: <info>  (wlan0): device state change: 4 -> 5                                                         
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0/wireless): connection 'H' has security, and secrets exist.  No new secrets needed.                                                                                                                                          
May  7 20:55:30 localhost NetworkManager: <info>  Config: added 'ssid' value 'H'                                                            
May  7 20:55:30 localhost NetworkManager: <info>  Config: added 'scan_ssid' value '1'                                                               
May  7 20:55:30 localhost NetworkManager: <info>  Config: added 'key_mgmt' value 'WPA-PSK'                                                          
May  7 20:55:30 localhost NetworkManager: <info>  Config: added 'auth_alg' value 'OPEN'                                                             
May  7 20:55:30 localhost NetworkManager: <info>  Config: added 'psk' value '<omitted>'                                                             
May  7 20:55:30 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) complete.                                      
May  7 20:55:30 localhost NetworkManager: <info>  Config: set interface ap_scan to 1                                                                
May  7 20:55:30 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  scanning -> disconnected                                   
May  7 20:55:34 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  disconnected -> scanning                                   
May  7 20:55:45 localhost NetworkManager: <info>  wlan0: link timed out.                                                                            
May  7 20:55:50 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  scanning -> associating                                    
May  7 20:55:50 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  associating -> associated                                  
May  7 20:55:50 localhost kernel: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready                                                                
May  7 20:55:50 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  associated -> 4-way handshake                              

==> wpa_supplicant.log <==
Trying to associate with xx:xx:xx:xx:xx:xx (SSID='H' freq=2452 MHz)
Associated with xx:xx:xx:xx:xx:xx                                          

==> messages <==
May  7 20:55:52 localhost avahi-daemon[2464]: Registering new address record for yyyy::yyy:yyyy:yyyy:yyyy on wlan0.*.
May  7 20:55:53 localhost ntpd[2246]: Listening on interface #19 wlan0, yyyy::yyy:yyyy:yyyy:yyyy#123 Enabled         
May  7 20:55:54 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  4-way handshake -> disconnected
May  7 20:55:54 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  disconnected -> scanning       
May  7 20:55:55 localhost NetworkManager: <info>  Activation (wlan0/wireless): association took too long.               
May  7 20:55:55 localhost NetworkManager: <info>  (wlan0): device state change: 5 -> 6                                  
May  7 20:55:55 localhost NetworkManager: <info>  Activation (wlan0/wireless): asking for new secrets                   
May  7 20:55:55 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  scanning -> disconnected       

==> wpa_supplicant.log <==
WPA: 4-Way Handshake failed - pre-shared key may be incorrect
CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys     

==> messages <==
May  7 20:55:55 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  disconnected -> associated
May  7 20:55:55 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  associated -> disconnected

==> wpa_supplicant.log <==
No network configuration found for the current AP
CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
Authentication with 00:00:00:00:00:00 timed out.        

==> messages <==
May  7 20:56:10 localhost NetworkManager: <info>  wlan0: link timed out.
May  7 20:56:18 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  disconnected -> scanning
May  7 20:57:15 localhost NetworkManager: <info>  (wlan0): device state change: 6 -> 3                           
May  7 20:57:15 localhost NetworkManager: <info>  (wlan0): deactivating device (reason: 38).                     





**********************************************************************
removed the plasmoid, and started nm-applet:
**********************************************************************

==> messages <==
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) starting connection 'Auto H'
May  7 20:57:31 localhost NetworkManager: <info>  (wlan0): device state change: 3 -> 4                   
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) started...  
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.     
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) starting... 
May  7 20:57:31 localhost NetworkManager: <info>  (wlan0): device state change: 4 -> 5                           
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0/wireless): access point 'Auto H' has security, but secrets are required.
May  7 20:57:31 localhost NetworkManager: <info>  (wlan0): device state change: 5 -> 6                                                              
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) complete.                                      
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...                                     
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) started...                                       
May  7 20:57:31 localhost NetworkManager: <info>  (wlan0): device state change: 6 -> 4                                                              
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...                                   
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.                                        
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) starting...                                    
May  7 20:57:31 localhost NetworkManager: <info>  (wlan0): device state change: 4 -> 5                                                              
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0/wireless): connection 'Auto H' has security, and secrets exist.  No new secrets needed.                                                                                                                                     
May  7 20:57:31 localhost NetworkManager: <info>  Config: added 'ssid' value 'H'                                                            
May  7 20:57:31 localhost NetworkManager: <info>  Config: added 'scan_ssid' value '1'                                                               
May  7 20:57:31 localhost NetworkManager: <info>  Config: added 'key_mgmt' value 'WPA-PSK'                                                          
May  7 20:57:31 localhost NetworkManager: <info>  Config: added 'psk' value '<omitted>'                                                             
May  7 20:57:31 localhost NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) complete.                                      
May  7 20:57:31 localhost NetworkManager: <info>  Config: set interface ap_scan to 1                                                                
May  7 20:57:31 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  disconnected -> scanning                                   
May  7 20:57:34 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  scanning -> associating                                    
May  7 20:57:34 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  associating -> associated                                  
May  7 20:57:34 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  associated -> 4-way handshake                              
May  7 20:57:34 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  4-way handshake -> group handshake                         
May  7 20:57:34 localhost NetworkManager: <info>  (wlan0): supplicant connection state:  group handshake -> completed                               
May  7 20:57:34 localhost NetworkManager: <info>  Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'H'.                                                                                                                                   
May  7 20:57:34 localhost NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.                                   
May  7 20:57:34 localhost NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...                                   
May  7 20:57:34 localhost NetworkManager: <info>  (wlan0): device state change: 5 -> 7                                                              
May  7 20:57:34 localhost NetworkManager: <info>  Activation (wlan0) Beginning DHCP transaction.                                                    
May  7 20:57:34 localhost NetworkManager: <info>  dhclient started with pid 10802
May  7 20:57:34 localhost NetworkManager: <info>  Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
May  7 20:57:34 localhost dhclient: Internet Systems Consortium DHCP Client 4.1.0
May  7 20:57:34 localhost dhclient: Copyright 2004-2008 Internet Systems Consortium.
May  7 20:57:34 localhost dhclient: All rights reserved.
May  7 20:57:34 localhost dhclient: For info, please visit http://www.isc.org/sw/dhcp/
May  7 20:57:34 localhost dhclient:
May  7 20:57:34 localhost NetworkManager: <info>  DHCP: device wlan0 state changed normal exit -> preinit
May  7 20:57:34 localhost dhclient: Listening on LPF/wlan0/00:16:ea:e3:60:d4
May  7 20:57:34 localhost dhclient: Sending on   LPF/wlan0/00:16:ea:e3:60:d4
May  7 20:57:34 localhost dhclient: Sending on   Socket/fallback
May  7 20:57:35 localhost dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 3
May  7 20:57:35 localhost dhclient: DHCPOFFER from 192.168.3.1
May  7 20:57:35 localhost dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67
May  7 20:57:35 localhost dhclient: DHCPACK from 192.168.3.1
May  7 20:57:35 localhost NetworkManager: <info>  DHCP: device wlan0 state changed preinit -> bound
May  7 20:57:35 localhost NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP Configure Get) scheduled...
May  7 20:57:35 localhost NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP Configure Get) started...
May  7 20:57:35 localhost NetworkManager: <info>    address 192.168.3.14
May  7 20:57:35 localhost NetworkManager: <info>    prefix 24 (255.255.255.0)
May  7 20:57:35 localhost NetworkManager: <info>    gateway 192.168.3.1
May  7 20:57:35 localhost NetworkManager: <info>    hostname 'myhostname'
May  7 20:57:35 localhost NetworkManager: <info>    nameserver '192.168.3.1'
May  7 20:57:35 localhost NetworkManager: <info>    domain name 'h'
May  7 20:57:35 localhost NetworkManager: <info>  Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
May  7 20:57:35 localhost NetworkManager: <info>  Activation (wlan0) Stage 4 of 5 (IP Configure Get) complete.
May  7 20:57:35 localhost NetworkManager: <info>  Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
May  7 20:57:35 localhost avahi-daemon[2464]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.14.
May  7 20:57:35 localhost avahi-daemon[2464]: New relevant interface wlan0.IPv4 for mDNS.
May  7 20:57:35 localhost avahi-daemon[2464]: Registering new address record for 192.168.3.14 on wlan0.IPv4.
May  7 20:57:35 localhost dhclient: bound to 192.168.3.14 -- renewal in 32937 seconds.

==> wpa_supplicant.log <==
Trying to associate with xx:xx:xx:xx:xx:xx (SSID='H' freq=2452 MHz)
Associated with xx:xx:xx:xx:xx:xx
WPA: Key negotiation completed with xx:xx:xx:xx:xx:xx [PTK=CCMP GTK=TKIP]
CTRL-EVENT-CONNECTED - Connection to xx:xx:xx:xx:xx:xx completed (auth) [id=0 id_str=]

==> messages <==
May  7 20:57:36 localhost NetworkManager: <info>  (wlan0): device state change: 7 -> 8
May  7 20:57:36 localhost dnsmasq[2578]: reading /etc/resolv.conf
May  7 20:57:36 localhost dnsmasq[2578]: using nameserver 192.168.3.1#53
May  7 20:57:36 localhost NetworkManager: <info>  Policy set 'Auto H' (wlan0) as default for routing and DNS.
May  7 20:57:36 localhost NetworkManager: <info>  Activation (wlan0) successful, device activated.
May  7 20:57:36 localhost NetworkManager: <info>  Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
May  7 20:57:36 localhost ntpd[2246]: Listening on interface #20 wlan0, 192.168.3.14#123 Enabled
Comment 1 MartinG 2009-05-07 21:55:53 UTC
Btw, my router is a Linksys WRT-54G, Firmware Version : v4.30.1, HyperWRT 2.1b1 +tofu13c. It works with knetworkmanager CentOS 5.3 on a Dell, Windows XP and Fedora Rawhide using nm-applet.
Comment 2 Kevin Kofler 2009-05-10 19:42:28 UTC
Looks like the applet only tries WPA 1 and not WPA 2 (or RSN as NM calls it).
Comment 3 Kevin Kofler 2009-05-10 19:47:50 UTC
Actually, it doesn't look like it. It was just a guess, probably a wrong one. The log looks more like the key is bad for some reason.
Comment 4 Will Stephenson 2009-07-25 21:14:58 UTC
Duping to later bug which correctly identified the cause.

*** This bug has been marked as a duplicate of bug 195824 ***