
WPA has just been turned off. NetworkManager, NetworkManagerDispatcher and nm-applet
are not running. I restarted HAL just in case...



monreal@luna:~$ sudo /usr/sbin/NetworkManager --no-daemon
NetworkManager: <information>   starting...
NetworkManager: <information>   eth0: Device is fully-supported using driver 'ipw2100'.
NetworkManager: <information>   nm_device_init(): waiting for device's worker thread to start
NetworkManager: <information>   nm_device_init(): device's worker thread started, continuing.
NetworkManager: <information>   Now managing wireless (802.11) device 'eth0'.
NetworkManager: <information>   Deactivating device eth0.
NetworkManager: <information>   eth1: Device is fully-supported using driver '8139too'.
NetworkManager: <information>   nm_device_init(): waiting for device's worker thread to start
NetworkManager: <information>   nm_device_init(): device's worker thread started, continuing.
NetworkManager: <information>   Now managing wired Ethernet (802.3) device 'eth1'.
NetworkManager: <information>   Deactivating device eth1.



Now I'm starting nm-applet in another term. NM will automatically and successfully
connect to the AP "paul":



NetworkManager: <information>   Updating allowed wireless network lists.
NetworkManager: <information>   Updating VPN Connections...
NetworkManager: <information>   SWITCH: no current connection, found better connection 'eth0'.
NetworkManager: <information>   Will activate connection 'eth0/paul'.
NetworkManager: <information>   Device eth0 activation scheduled...
NetworkManager: <information>   Activation (eth0) started...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <information>   Activation (eth0/wireless): access point 'paul' is unencrypted, no key needed.
NetworkManager: <information>   SUP: sending command 'INTERFACE_ADD eth0                wext    /var/run/wpa_supplicant '
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'AP_SCAN 1'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'ADD_NETWORK'
NetworkManager: <information>   SUP: response was '0'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 ssid 7061756c'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 key_mgmt NONE'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'ENABLE_NETWORK 0'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) complete.
NetworkManager: <information>   Activation (eth0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to access point 'paul'.
NetworkManager: <information>   Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager: <information>   Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager: <information>   Activation (eth0) Beginning DHCP transaction.
NetworkManager: <information>   Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
NetworkManager: <information>   wpa_supplicant(6310): Global control interface '/var/run/wpa_supplicant-global'
NetworkManager: <information>   wpa_supplicant(6310): RX global ctrl_iface - hexdump_ascii(len=49):
NetworkManager: <information>   wpa_supplicant(6310):      49 4e 54 45 52 46 41 43 45 5f 41 44 44 20 65 74   INTERFACE_ADD et
NetworkManager: <information>   wpa_supplicant(6310):      68 30 09 09 77 65 78 74 09 2f 76 61 72 2f 72 75   h0__wext_/var/ru
NetworkManager: <information>   wpa_supplicant(6310):      6e 2f 77 70 61 5f 73 75 70 70 6c 69 63 61 6e 74   n/wpa_supplicant
NetworkManager: <information>   wpa_supplicant(6310):      09                                                _               
NetworkManager: <information>   wpa_supplicant(6310): CTRL_IFACE GLOBAL INTERFACE_ADD 'eth0             wext    /var/run/wpa_supplicant '
NetworkManager: <information>   wpa_supplicant(6310): Initializing interface 'eth0' conf 'N/A' driver 'wext' ctrl_interface '/var/run/wpa_supplicant' bridge 'N/A'
NetworkManager: <information>   wpa_supplicant(6310): Initializing interface (2) 'eth0'
NetworkManager: <information>   wpa_supplicant(6310): EAPOL: SUPP_PAE entering state DISCONNECTED
NetworkManager: <information>   wpa_supplicant(6310): EAPOL: KEY_RX entering state NO_KEY_RECEIVE
NetworkManager: <information>   DHCP daemon state is now 12 (successfully started) for interface eth0
NetworkManager: <information>   wpa_supplicant(6310): EAPOL: SUPP_BE entering state INITIALIZE
NetworkManager: <information>   wpa_supplicant(6310): EAP: EAP entering state DISABLED
NetworkManager: <information>   wpa_supplicant(6310): EAPOL: External notification - portEnabled=0
NetworkManager: <information>   wpa_supplicant(6310): EAPOL: External notification - portValid=0
NetworkManager: <information>   wpa_supplicant(6310): SIOCGIWRANGE: WE(compiled)=20 WE(source)=18 enc_capa=0xf
NetworkManager: <information>   wpa_supplicant(6310):   capabilities: key_mgmt 0xf enc 0xf
NetworkManager: <information>   wpa_supplicant(6310): WEXT: Operstate: linkmode=1, operstate=5
NetworkManager: <information>   wpa_supplicant(6310): 1
NetworkManager: <information>   wpa_supplicant(6310): wpa_driver_wext_set_wpa
NetworkManager: <information>   wpa_supplicant(6310): wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6310): wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6310): wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6310): wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6310): wpa_driver_wext_set_countermeasures
NetworkManager: <information>   wpa_supplicant(6310): wpa_driver_wext_set_drop_unencrypted
NetworkManager: <information>   wpa_supplicant(6310): Setting scan request: 0 sec 100000 usec
NetworkManager: <information>   wpa_supplicant(6310): Added interface eth0
NetworkManager: <information>   wpa_supplicant(6310): Wireless event: cmd=0x8b06 len=8
NetworkManager: <information>   wpa_supplicant(6310): RX ctrl_iface - hexdump_ascii(len=9):
NetworkManager: <information>   wpa_supplicant(6310):      41 50 5f 53 43 41 4e 20 31                        AP_SCAN 1       
NetworkManager: <information>   wpa_supplicant(6310): RTM_NEWLINK, IFLA_IFNAME: Interface 'eth0' added
NetworkManager: <information>   wpa_supplicant(6310): RX ctrl_iface - hexdump_ascii(len=11):
NetworkManager: <information>   wpa_supplicant(6310):      41 44 44 5f 4e 45 54 57 4f 52 4b                  ADD_NETWORK     
NetworkManager: <information>   wpa_supplicant(6310): CTRL_IFACE: ADD_NETWORK
NetworkManager: <information>   wpa_supplicant(6310): RX ctrl_iface - hexdump_ascii(len=27): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6310): CTRL_IFACE: SET_NETWORK id=0 name='ssid'
NetworkManager: <information>   wpa_supplicant(6310): CTRL_IFACE: value - hexdump_ascii(len=8): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6310): ssid - hexdump_ascii(len=4):
NetworkManager: <information>   wpa_supplicant(6310):      70 61 75 6c                                       paul            
NetworkManager: <information>   wpa_supplicant(6310):  - hexdump_ascii(len=27): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6310): CTRL_IFACE: SET_NETWORK id=0 name='key_mgmt'
NetworkManager: <information>   wpa_supplicant(6310): CTRL_IFACE: value - hexdump_ascii(len=4): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6310): key_mgmt: 0x4
NetworkManager: <information>   wpa_supplicant(6310): RX ctrl_iface - hexdump_ascii(len=16):
NetworkManager: <information>   wpa_supplicant(6310):      45 4e 41 42 4c 45 5f 4e 45 54 57 4f 52 4b 20 30   ENABLE_NETWORK 0
NetworkManager: <information>   wpa_supplicant(6310): CTRL_IFACE: ENABLE_NETWORK id=0
NetworkManager: <information>   wpa_supplicant(6310): Setting scan request: 0 sec 0 usec
NetworkManager: <information>   wpa_supplicant(6310): State: DISCONNECTED -> SCANNING
NetworkManager: <information>   wpa_supplicant(6310): Starting AP scan (broadcast SSID)
NetworkManager: <information>   wpa_supplicant(6310): Trying to get current scan results first without requesting a new scan to speed up initial association
NetworkManager: <information>   wpa_supplicant(6310): Received 157 bytes of scan results (1 BSSes)
NetworkManager: <information>   wpa_supplicant(6310): Scan results: 1
NetworkManager: <information>   wpa_supplicant(6310): Selecting BSS from priority group 0
NetworkManager: <information>   wpa_supplicant(6310): 0: 00:30:f1:bd:15:04 ssid='paul' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
NetworkManager: <information>   wpa_supplicant(6310):    skip - no WPA/RSN IE
NetworkManager: <information>   wpa_supplicant(6310):    selected non-WPA AP 00:30:f1:bd:15:04 ssid='paul'
NetworkManager: <information>   wpa_supplicant(6310): Trying to associate with 00:30:f1:bd:15:04 (SSID='paul' freq=0 MHz)
NetworkManager: <information>   wpa_supplicant(6310): Cancelling scan request
NetworkManager: <information>   wpa_supplicant(6310): WPA: clearing own WPA/RSN IE
NetworkManager: <information>   wpa_supplicant(6310): Automatic auth_alg selection: 0x1
NetworkManager: <information>   wpa_supplicant(6310): WPA: clearing AP WPA IE
NetworkManager: <information>   wpa_supplicant(6310): WPA: clearing AP RSN IE
NetworkManager: <information>   wpa_supplicant(6310): WPA: clearing own WPA/RSN IE
NetworkManager: <information>   wpa_supplicant(6310): No keys have been configured - skip key clearing
NetworkManager: <information>   wpa_supplicant(6310): iver_wext_set_drop_unencrypted
NetworkManager: <information>   wpa_supplicant(6310): State: SCANNING -> ASSOCIATING
NetworkManager: <information>   wpa_supplicant(6310): WEXT: Operstate: linkmode=-1, operstate=5
NetworkManager: <information>   wpa_supplicant(6310): wpa_driver_wext_associate
NetworkManager: <information>   wpa_supplicant(6310): Setting authentication timeout: 10 sec 0 usec
NetworkManager: <information>   wpa_supplicant(6310): EAPOL: External notification - portControl=ForceAuthorized
NetworkManager: <information>   wpa_supplicant(6310): CTRL_IFACE monitor attached - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61 6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 36 32 37 34 2d 31 00
NetworkManager: <information>   wpa_supplicant(6310): Wireless event: cmd=0x8b06 len=8
NetworkManager: <information>   wpa_supplicant(6310): Wireless event: cmd=0x8b15 len=20
NetworkManager: <information>   wpa_supplicant(6310): Wireless event: new AP: 00:00:00:00:00:00
NetworkManager: <information>   wpa_supplicant(6310): Added BSSID 00:30:f1:bd:15:04 into blacklist
NetworkManager: <information>   wpa_supplicant(6310): State: ASSOCIATING -> DISCONNECTED
NetworkManager: <information>   wpa_supplicant(6310): WEXT: Operstate: linkmode=-1, operstate=5
NetworkManager: <information>   wpa_supplicant(6310): EAPOL: External notification - portEnabled=0
NetworkManager: <information>   wpa_supplicant(6310): EAPOL: External notification - portValid=0
NetworkManager: <information>   wpa_supplicant(6310): CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
NetworkManager: <information>   wpa_supplicant(6310): CTRL_IFACE monitor send - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61 6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 36 32 37 34 2d 31 00
NetworkManager: <information>   wpa_supplicant(6310): RTM_NEWLINK, IFLA_IFNAME: Interface 'eth0' added
NetworkManager: <information>   DHCP daemon state is now 1 (starting) for interface eth0
NetworkManager: <information>   DHCP daemon state is now 2 (bound) for interface eth0
NetworkManager: <information>   Activation (eth0) Stage 4 of 5 (IP Configure Get) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 4 of 5 (IP Configure Get) started...
NetworkManager: <information>   Retrieved the following IP4 configuration from the DHCP daemon:
NetworkManager: <information>     address 192.168.2.110
NetworkManager: <information>     netmask 255.255.255.0
NetworkManager: <information>     broadcast 192.168.2.255
NetworkManager: <information>     gateway 192.168.2.1
NetworkManager: <information>     nameserver 192.168.2.1
NetworkManager: <information>   Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 4 of 5 (IP Configure Get) complete.
NetworkManager: <information>   Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
NetworkManager: <information>   DHCP returned name servers but system has disabled dynamic modification!
NetworkManager: <information>   Activation (eth0) successful, device activated.
NetworkManager: <debug info>    [1157315101.338616] nm_dbus_signal_filter (): NetworkManagerInfo triggered update of wireless network 'paul'
NetworkManager: <information>   Activation (eth0) Finish handler scheduled.
NetworkManager: <information>   Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
NetworkManager: <information>   wpa_supplicant(6310): 



Now NM is fully connected, the nm-applet no longer shows the connecting graphics
As a next step, I will activate WPA again. For this, I will first stop NetworkManager, 
NetworkManagerDispatcher and nm-applet to prevent them from getting confused.

I activate WPA Personal using a simple preshared passphrase on the router, then I
restart the NM apps:



monreal@luna:~$ sudo /usr/sbin/NetworkManager --no-daemon
NetworkManager: <information>   starting...
NetworkManager: <information>   eth0: Device is fully-supported using driver 'ipw2100'.
NetworkManager: <information>   nm_device_init(): waiting for device's worker thread to start
NetworkManager: <information>   nm_device_init(): device's worker thread started, continuing.
NetworkManager: <information>   Now managing wireless (802.11) device 'eth0'.
NetworkManager: <information>   Deactivating device eth0.
NetworkManager: <information>   eth1: Device is fully-supported using driver '8139too'.
NetworkManager: <information>   nm_device_init(): waiting for device's worker thread to start
NetworkManager: <information>   nm_device_init(): device's worker thread started, continuing.
NetworkManager: <information>   Now managing wired Ethernet (802.3) device 'eth1'.
NetworkManager: <information>   Deactivating device eth1.



I start nm-applet now. I thought it would connect fine like it does without WPA, but
look at this:



NetworkManager: <information>   Updating allowed wireless network lists.
NetworkManager: <information>   Updating VPN Connections...
NetworkManager: <information>   SWITCH: no current connection, found better connection 'eth0'.
NetworkManager: <information>   Will activate connection 'eth0/paul'.
NetworkManager: <information>   Device eth0 activation scheduled...
NetworkManager: <information>   Activation (eth0) started...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <information>   Activation (eth0/wireless): access point 'paul' is encrypted, but NO valid key exists.  New key needed.
NetworkManager: <information>   Activation (eth0) New wireless user key requested for network 'paul'.
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) complete.



So NM sees that it needs a key but it doesn't ask for one... even after waiting
a few minutes. The nm.applet icon just spins but NM clearly does nothing. Now I
just use "Connect to other wireless network" and input the name (paul) and WPA
Private, as well as the key. It connects just fine:



NetworkManager: <debug info>    [1157315884.207345] nm_device_802_11_wireless_get_activation_ap (): Forcing AP 'paul'
NetworkManager: <information>   User Switch: /org/freedesktop/NetworkManager/Devices/eth0 / paul
NetworkManager: <information>   Deactivating device eth0.
NetworkManager: <information>   Activation (eth0): cancelling...
NetworkManager: <information>   Activation (eth0) cancellation handler scheduled...
NetworkManager: <information>   Activation (eth0): waiting for device to cancel activation.
NetworkManager: <information>   Activation (eth0) cancellation handled.
NetworkManager: <information>   Activation (eth0): cancelled.
NetworkManager: <information>   Device eth0 activation scheduled...
NetworkManager: <information>   Activation (eth0) started...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <information>   Activation (eth0/wireless): access point 'paul' is encrypted, and a key exists.  No new key needed.
NetworkManager: <information>   SUP: sending command 'INTERFACE_ADD eth0                wext    /var/run/wpa_supplicant '
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'AP_SCAN 1'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'ADD_NETWORK'
NetworkManager: <information>   SUP: response was '0'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 ssid 7061756c'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 proto WPA'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 key_mgmt WPA-PSK'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 psk <key>'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'ENABLE_NETWORK 0'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) complete.
NetworkManager: <information>   wpa_supplicant(6749): Global control interface '/var/run/wpa_supplicant-global'
NetworkManager: <information>   wpa_supplicant(6749): RX global ctrl_iface - hexdump_ascii(len=49):
NetworkManager: <information>   wpa_supplicant(6749):      49 4e 54 45 52 46 41 43 45 5f 41 44 44 20 65 74   INTERFACE_ADD et
NetworkManager: <information>   wpa_supplicant(6749):      68 30 09 09 77 65 78 74 09 2f 76 61 72 2f 72 75   h0__wext_/var/ru
NetworkManager: <information>   wpa_supplicant(6749):      6e 2f 77 70 61 5f 73 75 70 70 6c 69 63 61 6e 74   n/wpa_supplicant
NetworkManager: <information>   wpa_supplicant(6749):      09                                                _               
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE GLOBAL INTERFACE_ADD 'eth0             wext    /var/run/wpa_supplicant '
NetworkManager: <information>   wpa_supplicant(6749): Initializing interface 'eth0' conf 'N/A' driver 'wext' ctrl_interface '/var/run/wpa_supplicant' bridge 'N/A'
NetworkManager: <information>   wpa_supplicant(6749): Initializing interface (2) 'eth0'
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: SUPP_PAE entering state DISCONNECTED
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: KEY_RX entering state NO_KEY_RECEIVE
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: SUPP_BE entering state INITIALIZE
NetworkManager: <information>   wpa_supplicant(6749): EAP: EAP entering state DISABLED
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: External notification - portEnabled=0
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: External notification - portValid=0
NetworkManager: <information>   wpa_supplicant(6749): SIOCGIWRANGE: WE(compiled)=20 WE(source)=18 enc_capa=0xf
NetworkManager: <information>   wpa_supplicant(6749):   capabilities: key_mgmt 0xf enc 0xf
NetworkManager: <information>   wpa_supplicant(6749): WEXT: Operstate: linkmode=1, operstate=5
NetworkManager: <information>   wpa_supplicant(6749): 1
NetworkManager: <information>   wpa_supplicant(6749): wpa_driver_wext_set_wpa
NetworkManager: <information>   wpa_supplicant(6749): wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6749): wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6749): wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6749): wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6749): wpa_driver_wext_set_countermeasures
NetworkManager: <information>   wpa_supplicant(6749): wpa_driver_wext_set_drop_unencrypted
NetworkManager: <information>   wpa_supplicant(6749): Setting scan request: 0 sec 100000 usec
NetworkManager: <information>   wpa_supplicant(6749): Added interface eth0
NetworkManager: <information>   wpa_supplicant(6749): Wireless event: cmd=0x8b06 len=8
NetworkManager: <information>   wpa_supplicant(6749): RX ctrl_iface - hexdump_ascii(len=9):
NetworkManager: <information>   wpa_supplicant(6749):      41 50 5f 53 43 41 4e 20 31                        AP_SCAN 1       
NetworkManager: <information>   wpa_supplicant(6749): RX ctrl_iface - hexdump_ascii(len=11):
NetworkManager: <information>   wpa_supplicant(6749):      41 44 44 5f 4e 45 54 57 4f 52 4b                  ADD_NETWORK     
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE: ADD_NETWORK
NetworkManager: <information>   wpa_supplicant(6749): RX ctrl_iface - hexdump_ascii(len=27): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE: SET_NETWORK id=0 name='ssid'
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE: value - hexdump_ascii(len=8): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): ssid - hexdump_ascii(len=4):
NetworkManager: <information>   wpa_supplicant(6749):      70 61 75 6c                                       paul            
NetworkManager: <information>   wpa_supplicant(6749): RX ctrl_iface - hexdump_ascii(len=23): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): ET_NETWORK id=0 name='proto'
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE: value - hexdump_ascii(len=3): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): proto: 0x1
NetworkManager: <information>   wpa_supplicant(6749): RX ctrl_iface - hexdump_ascii(len=30): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE: SET_NETWORK id=0 name='key_mgmt'
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE: value - hexdump_ascii(len=7): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): key_mgmt: 0x2
NetworkManager: <information>   wpa_supplicant(6749): RX ctrl_iface - hexdump_ascii(len=82): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE: SET_NETWORK id=0 name='psk'
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE: value - hexdump_ascii(len=64): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): PSK - hexdump(len=32): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): RX ctrl_iface - hexdump_ascii(len=16):
NetworkManager: <information>   wpa_supplicant(6749):      45 4e 41 42 4c 45 5f 4e 45 54 57 4f 52 4b 20 30   ENABLE_NETWORK 0
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE: ENABLE_NETWORK id=0
NetworkManager: <information>   wpa_supplicant(6749): Setting scan request: 0 sec 0 usec
NetworkManager: <information>   wpa_supplicant(6749): State: DISCONNECTED -> SCANNING
NetworkManager: <information>   wpa_supplicant(6749): Starting AP scan (broadcast SSID)
NetworkManager: <information>   wpa_supplicant(6749): Trying to get current scan results first without requesting a new scan to speed up initial association
NetworkManager: <information>   wpa_supplicant(6749): Received 253 bytes of scan results (1 BSSes)
NetworkManager: <information>   wpa_supplicant(6749): Scan results: 1
NetworkManager: <information>   wpa_supplicant(6749): Selecting BSS from priority group 0
NetworkManager: <information>   wpa_supplicant(6749): 0: 00:30:f1:bd:15:04 ssid='paul' wpa_ie_len=26 rsn_ie_len=0 caps=0x11
NetworkManager: <information>   wpa_supplicant(6749):    selected based on WPA IE
NetworkManager: <information>   wpa_supplicant(6749): ' freq=0 MHz)
NetworkManager: <information>   wpa_supplicant(6749): Cancelling scan request
NetworkManager: <information>   wpa_supplicant(6749): WPA: clearing own WPA/RSN IE
NetworkManager: <information>   wpa_supplicant(6749): Automatic auth_alg selection: 0x1
NetworkManager: <information>   wpa_supplicant(6749): WPA: using IEEE 802.11i/D3.0
NetworkManager: <information>   wpa_supplicant(6749): WPA: Selected cipher suites: group 8 pairwise 8 key_mgmt 2
NetworkManager: <information>   wpa_supplicant(6749): WPA: set AP WPA IE - hexdump(len=26): dd 18 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 00 00
NetworkManager: <information>   wpa_supplicant(6749): WPA: clearing AP RSN IE
NetworkManager: <information>   wpa_supplicant(6749): WPA: using GTK TKIP
NetworkManager: <information>   wpa_supplicant(6749): WPA: using PTK TKIP
NetworkManager: <information>   wpa_supplicant(6749): WPA: using KEY_MGMT WPA-PSK
NetworkManager: <information>   wpa_supplicant(6749): WPA: Set own WPA IE default - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
NetworkManager: <information>   wpa_supplicant(6749): No keys have been configured - skip key clearing
NetworkManager: <information>   wpa_supplicant(6749): wpa_driver_wext_set_drop_unencrypted
NetworkManager: <information>   wpa_supplicant(6749): State: SCANNING -> ASSOCIATING
NetworkManager: <information>   wpa_supplicant(6749): WEXT: Operstate: linkmode=-1, operstate=5
NetworkManager: <information>   wpa_supplicant(6749): wpa_driver_wext_associate
NetworkManager: <information>   wpa_supplicant(6749): Setting authentication timeout: 10 sec 0 usec
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: External notification - EAP success=0
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: External notification - EAP fail=0
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: External notification - portControl=Auto
NetworkManager: <information>   wpa_supplicant(6749): c 5f 36 36 31 37 2d 31 00
NetworkManager: <information>   wpa_supplicant(6749): Wireless event: cmd=0x8b06 len=8
NetworkManager: <information>   wpa_supplicant(6749): Wireless event: cmd=0x8b1a len=13
NetworkManager: <information>   wpa_supplicant(6749): Wireless event: cmd=0x8b15 len=20
NetworkManager: <information>   wpa_supplicant(6749): Wireless event: new AP: 00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6749): State: ASSOCIATING -> ASSOCIATED
NetworkManager: <information>   wpa_supplicant(6749): WEXT: Operstate: linkmode=-1, operstate=5
NetworkManager: <information>   wpa_supplicant(6749): Associated to a new BSS: BSSID=00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6749): No keys have been configured - skip key clearing
NetworkManager: <information>   wpa_supplicant(6749): Associated with 00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE monitor send - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61 6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 36 36 31 37 2d 31 00
NetworkManager: <information>   wpa_supplicant(6749): WPA: Association event - clear replay counter
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: External notification - portEnabled=0
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: External notification - portValid=0
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: External notification - EAP success=0
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: External notification - portEnabled=1
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: SUPP_PAE entering state CONNECTING
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: SUPP_BE entering state IDLE
NetworkManager: <information>   wpa_supplicant(6749): Setting authentication timeout: 10 sec 0 usec
NetworkManager: <information>   wpa_supplicant(6749): RTM_NEWLINK, IFLA_IFNAME: Interface 'eth0' added
NetworkManager: <information>   wpa_supplicant(6749): RX EAPOL from 00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6749): 0 00 00 00 00 00 00 01 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 84 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6749): Setting authentication timeout: 10 sec 0 usec
NetworkManager: <information>   wpa_supplicant(6749): IEEE 802.1X RX: version=1 type=3 length=95
NetworkManager: <information>   wpa_supplicant(6749):   EAPOL-Key type=254
NetworkManager: <information>   wpa_supplicant(6749):   key_info 0x89 (ver=1 keyidx=0 rsvd=0 Pairwise Ack)
NetworkManager: <information>   wpa_supplicant(6749):   key_length=32 key_data_length=0
NetworkManager: <information>   wpa_supplicant(6749):   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01
NetworkManager: <information>   wpa_supplicant(6749):   key_nonce - hexdump(len=32): 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 84
NetworkManager: <information>   wpa_supplicant(6749):   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6749):   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6749):   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6749):   key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6749): e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 84 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6749): State: ASSOCIATED -> 4WAY_HANDSHAKE
NetworkManager: <information>   wpa_supplicant(6749): WPA: RX message 1 of 4-Way Handshake from 00:30:f1:bd:15:04 (ver=1)
NetworkManager: <information>   wpa_supplicant(6749): WPA: Renewed SNonce - hexdump(len=32): 81 b3 3f 98 f4 92 c9 5e a9 6d 14 d6 ce 10 bf 8f ea ac d6 ed 5c 11 5a d3 a4 65 13 ba f1 fe 5c 35
NetworkManager: <information>   wpa_supplicant(6749): WPA: PMK - hexdump(len=32): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): WPA: PTK - hexdump(len=64): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): WPA: WPA IE for msg 2/4 - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
NetworkManager: <information>   wpa_supplicant(6749): WPA: Sending EAPOL-Key 2/4
NetworkManager: <information>   wpa_supplicant(6749): d 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
NetworkManager: <information>   wpa_supplicant(6749): RX EAPOL from 00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6749): RX EAPOL - hexdump(len=125): 01 03 00 79 fe 01 c9 00 20 00 00 00 00 00 00 00 02 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 84 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 8e f9 b1 47 83 af ef cf 64 d2 47 bf 61 60 28 df 00 1a dd 18 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 00 00
NetworkManager: <information>   wpa_supplicant(6749): IEEE 802.1X RX: version=1 type=3 length=121
NetworkManager: <information>   wpa_supplicant(6749):   EAPOL-Key type=254
NetworkManager: <information>   wpa_supplicant(6749):   key_info 0x1c9 (ver=1 keyidx=0 rsvd=0 Pairwise Install Ack MIC)
NetworkManager: <information>   wpa_supplicant(6749):   key_length=32 key_data_length=26
NetworkManager: <information>   wpa_supplicant(6749):   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02
NetworkManager: <information>   wpa_supplicant(6749):   key_nonce - hexdump(len=32): 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 84
NetworkManager: <information>   wpa_supplicant(6749):   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6749):   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
NetworkManager: <information>   Activation (eth0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to access point 'paul'.
NetworkManager: <information>   Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager: <information>   Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager: <information>   Activation (eth0) Beginning DHCP transaction.
NetworkManager: <information>   Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
NetworkManager: <information>   wpa_supplicant(6749): 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6749):   key_mic - hexdump(len=16): 8e f9 b1 47 83 af ef cf 64 d2 47 bf 61 60 28 df
NetworkManager: <information>   wpa_supplicant(6749): WPA: RX EAPOL-Key - hexdump(len=125): 01 03 00 79 fe 01 c9 00 20 00 00 00 00 00 00 00 02 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 84 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 8e f9 b1 47 83 af ef cf 64 d2 47 bf 61 60 28 df 00 1a dd 18 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 00 00
NetworkManager: <information>   wpa_supplicant(6749): State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
NetworkManager: <information>   wpa_supplicant(6749): WPA: RX message 3 of 4-Way Handshake from 00:30:f1:bd:15:04 (ver=1)
NetworkManager: <information>   wpa_supplicant(6749): WPA: IE KeyData - hexdump(len=26): dd 18 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 00 00
NetworkManager: <information>   wpa_supplicant(6749): WPA: Sending EAPOL-Key 4/4
NetworkManager: <information>   wpa_supplicant(6749):  00 00 00 00 46 00 03 7c 96 7a 4f 80 a9 b8 bd c2 59 28 22 a9 00 00
NetworkManager: <information>   wpa_supplicant(6749): WPA: Installing PTK to the driver.
NetworkManager: <information>   wpa_supplicant(6749): WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6749): wpa_driver_wext_set_key: alg=2 key_idx=0 set_tx=1 seq_len=6 key_len=32
NetworkManager: <information>   wpa_supplicant(6749): State: 4WAY_HANDSHAKE -> GROUP_HANDSHAKE
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: startWhen --> 0
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: SUPP_PAE entering state CONNECTING
NetworkManager: <information>   wpa_supplicant(6749): EAPOL: txStart
NetworkManager: <information>   wpa_supplicant(6749): WPA: drop TX EAPOL in non-IEEE 802.1X mode (type=1 len=0)
NetworkManager: <information>   wpa_supplicant(6749): RX EAPOL from 00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6749): RX EAPOL - hexdump(len=131): 01 03 00 7f fe 03 a1 00 20 00 00 00 00 00 00 00 04 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 83 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 79 5b 7e 63 69 e7 ce 9e 3f 03 e7 be 89 3f 75 34 00 20 39 60 4a 47 52 bc 94 60 d1 55 3d 42 be cb b7 9b 0c de 75 d9 28 7a 23 55 72 09 9c 53 dc 68 07 de
NetworkManager: <information>   wpa_supplicant(6749): IEEE 802.1X RX: version=1 type=3 length=127
NetworkManager: <information>   wpa_supplicant(6749):   EAPOL-Key type=254
NetworkManager: <information>   wpa_supplicant(6749):   key_info 0x3a1 (ver=1 keyidx=2 rsvd=0 Group Ack MIC Secure)
NetworkManager: <information>   wpa_supplicant(6749):   key_length=32 key_data_length=32
NetworkManager: <information>   wpa_supplicant(6749): ounter - hexdump(len=8): 00 00 00 00 00 00 00 04
NetworkManager: <information>   wpa_supplicant(6749):   key_nonce - hexdump(len=32): 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 83
NetworkManager: <information>   wpa_supplicant(6749):   key_iv - hexdump(len=16): 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61
NetworkManager: <information>   wpa_supplicant(6749):   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6749):   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6749):   key_mic - hexdump(len=16): 79 5b 7e 63 69 e7 ce 9e 3f 03 e7 be 89 3f 75 34
NetworkManager: <information>   wpa_supplicant(6749): WPA: RX EAPOL-Key - hexdump(len=131): 01 03 00 7f fe 03 a1 00 20 00 00 00 00 00 00 00 04 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 83 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 79 5b 7e 63 69 e7 ce 9e 3f 03 e7 be 89 3f 75 34 00 20 39 60 4a 47 52 bc 94 60 d1 55 3d 42 be cb b7 9b 0c de 75 d9 28 7a 23 55 72 09 9c 53 dc 68 07 de
NetworkManager: <information>   wpa_supplicant(6749): WPA: RX message 1 of Group Key or STAKey Handshake from 00:30:f1:bd:15:04 (ver=1)
NetworkManager: <information>   wpa_supplicant(6749): State: GROUP_HANDSHAKE -> GROUP_HANDSHAKE
NetworkManager: <information>   wpa_supplicant(6749): len=32): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6749): WPA: Installing GTK to the driver (keyidx=2 tx=0).
NetworkManager: <information>   wpa_supplicant(6749): WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
NetworkManager: <information>   DHCP daemon state is now 12 (successfully started) for interface eth0
NetworkManager: <information>   wpa_supplicant(6749): wpa_driver_wext_set_key: alg=2 key_idx=2 set_tx=0 seq_len=6 key_len=32
NetworkManager: <information>   wpa_supplicant(6749): WPA: Sending EAPOL-Key 2/2
NetworkManager: <information>   wpa_supplicant(6749): WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f fe 03 21 00 20 00 00 00 00 00 00 00 04 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 65 a0 75 4a f3 cb 2c af da db 07 c0 28 26 65 3e 00 00
NetworkManager: <information>   wpa_supplicant(6749): WPA: Key negotiation completed with 00:30:f1:bd:15:04 [PTK=TKIP GTK=TKIP]
NetworkManager: <information>   wpa_supplicant(6749): CTRL_IFACE monitor send - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61 6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 36 36 31 37 2d 31 00
NetworkManager: <information>   wpa_supplicant(6749): Cancelling authentication timeout
NetworkManager: <information>   wpa_supplicant(6749): State: GROUP_HANDSHAKE -> COMPLETED
NetworkManager: <information>   wpa_supplicant(6749): CTRL-EVENT-CONNECTED - Connection to 00:30:f1:bd:15:04 completed (auth) [id=0 id_str=]
NetworkManager: <information>   DHCP daemon state is now 1 (starting) for interface eth0
NetworkManager: <information>   DHCP daemon state is now 2 (bound) for interface eth0
NetworkManager: <information>   Activation (eth0) Stage 4 of 5 (IP Configure Get) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 4 of 5 (IP Configure Get) started...
NetworkManager: <information>   Retrieved the following IP4 configuration from the DHCP daemon:
NetworkManager: <information>     address 192.168.2.110
NetworkManager: <information>     netmask 255.255.255.0
NetworkManager: <information>     broadcast 192.168.2.255
NetworkManager: <information>     gateway 192.168.2.1
NetworkManager: <information>     nameserver 192.168.2.1
NetworkManager: <information>   Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 4 of 5 (IP Configure Get) complete.
NetworkManager: <information>   Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
NetworkManager: <information>   DHCP returned name servers but system has disabled dynamic modification!
NetworkManager: <information>   Activation (eth0) successful, device activated.
NetworkManager: <debug info>    [1157315899.309843] nm_dbus_signal_filter (): NetworkManagerInfo triggered update of wireless network 'paul'
NetworkManager: <debug info>    [1157315899.310266] nm_dbus_signal_filter (): NetworkManagerInfo triggered update of wireless network 'paul'
NetworkManager: <debug info>    [1157315899.310610] nm_dbus_signal_filter (): NetworkManagerInfo triggered update of wireless network 'paul'
NetworkManager: <debug info>    [1157315899.310926] nm_dbus_signal_filter (): NetworkManagerInfo triggered update of wireless network 'paul'
NetworkManager: <information>   Activation (eth0) Finish handler scheduled.
NetworkManager: <information>   Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.



So it is connected with WPA at this point. I kill all of NM again and restart:



monreal@luna:~$ sudo /usr/sbin/NetworkManager --no-daemon
NetworkManager: <information>   starting...
NetworkManager: <information>   eth0: Device is fully-supported using driver 'ipw2100'.
NetworkManager: <information>   nm_device_init(): waiting for device's worker thread to start
NetworkManager: <information>   nm_device_init(): device's worker thread started, continuing.
NetworkManager: <information>   Now managing wireless (802.11) device 'eth0'.
NetworkManager: <information>   Deactivating device eth0.
NetworkManager: <information>   eth1: Device is fully-supported using driver '8139too'.
NetworkManager: <information>   nm_device_init(): waiting for device's worker thread to start
NetworkManager: <information>   nm_device_init(): device's worker thread started, continuing.
NetworkManager: <information>   Now managing wired Ethernet (802.3) device 'eth1'.
NetworkManager: <information>   Deactivating device eth1.
NetworkManager: <information>   Updating allowed wireless network lists.
NetworkManager: <information>   Updating VPN Connections...
NetworkManager: <information>   SWITCH: no current connection, found better connection 'eth0'.
NetworkManager: <information>   Will activate connection 'eth0/paul'.
NetworkManager: <information>   Device eth0 activation scheduled...
NetworkManager: <information>   Activation (eth0) started...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <information>   Activation (eth0/wireless): access point 'paul' is encrypted, but NO valid key exists.  New key needed.
NetworkManager: <information>   Activation (eth0) New wireless user key requested for network 'paul'.
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) complete.
NetworkManager: <information>   Activation (eth0) New wireless user key for network 'paul' received.
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) starting...
NetworkManager: <information>   Activation (eth0/wireless): access point 'paul' is encrypted, and a key exists.  No new key needed.
NetworkManager: <information>   SUP: sending command 'INTERFACE_ADD eth0                wext    /var/run/wpa_supplicant '
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'AP_SCAN 1'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'ADD_NETWORK'
NetworkManager: <information>   SUP: response was '0'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 ssid 7061756c'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 proto WPA'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 key_mgmt WPA-PSK'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 psk <key>'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'ENABLE_NETWORK 0'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   Activation (eth0) Stage 2 of 5 (Device Configure) complete.
NetworkManager: <information>   wpa_supplicant(6978): Global control interface '/var/run/wpa_supplicant-global'
NetworkManager: <information>   wpa_supplicant(6978): RX global ctrl_iface - hexdump_ascii(len=49):
NetworkManager: <information>   wpa_supplicant(6978):      49 4e 54 45 52 46 41 43 45 5f 41 44 44 20 65 74   INTERFACE_ADD et
NetworkManager: <information>   wpa_supplicant(6978):      68 30 09 09 77 65 78 74 09 2f 76 61 72 2f 72 75   h0__wext_/var/ru
NetworkManager: <information>   wpa_supplicant(6978):      6e 2f 77 70 61 5f 73 75 70 70 6c 69 63 61 6e 74   n/wpa_supplicant
NetworkManager: <information>   wpa_supplicant(6978):      09                                                _               
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE GLOBAL INTERFACE_ADD 'eth0             wext    /var/run/wpa_supplicant '
NetworkManager: <information>   wpa_supplicant(6978): Initializing interface 'eth0' conf 'N/A' driver 'wext' ctrl_interface '/var/run/wpa_supplicant' bridge 'N/A'
NetworkManager: <information>   wpa_supplicant(6978): Initializing interface (2) 'eth0'
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: SUPP_PAE entering state DISCONNECTED
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: KEY_RX entering state NO_KEY_RECEIVE
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: SUPP_BE entering state INITIALIZE
NetworkManager: <information>   wpa_supplicant(6978): EAP: EAP entering state DISABLED
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: External notification - portEnabled=0
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: External notification - portValid=0
NetworkManager: <information>   wpa_supplicant(6978): SIOCGIWRANGE: WE(compiled)=20 WE(source)=18 enc_capa=0xf
NetworkManager: <information>   wpa_supplicant(6978):   capabilities: key_mgmt 0xf enc 0xf
NetworkManager: <information>   wpa_supplicant(6978): WEXT: Operstate: linkmode=1, operstate=5
NetworkManager: <information>   wpa_supplicant(6978): 1
NetworkManager: <information>   wpa_supplicant(6978): wpa_driver_wext_set_wpa
NetworkManager: <information>   wpa_supplicant(6978): wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6978): wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6978): wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6978): wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
NetworkManager: <information>   wpa_supplicant(6978): wpa_driver_wext_set_countermeasures
NetworkManager: <information>   wpa_supplicant(6978): wpa_driver_wext_set_drop_unencrypted
NetworkManager: <information>   wpa_supplicant(6978): Setting scan request: 0 sec 100000 usec
NetworkManager: <information>   wpa_supplicant(6978): Added interface eth0
NetworkManager: <information>   wpa_supplicant(6978): Wireless event: cmd=0x8b06 len=8
NetworkManager: <information>   wpa_supplicant(6978): RX ctrl_iface - hexdump_ascii(len=9):
NetworkManager: <information>   wpa_supplicant(6978):      41 50 5f 53 43 41 4e 20 31                        AP_SCAN 1       
NetworkManager: <information>   wpa_supplicant(6978): RX ctrl_iface - hexdump_ascii(len=11):
NetworkManager: <information>   wpa_supplicant(6978):      41 44 44 5f 4e 45 54 57 4f 52 4b                  ADD_NETWORK     
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE: ADD_NETWORK
NetworkManager: <information>   wpa_supplicant(6978): RX ctrl_iface - hexdump_ascii(len=27): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE: SET_NETWORK id=0 name='ssid'
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE: value - hexdump_ascii(len=8): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): ssid - hexdump_ascii(len=4):
NetworkManager: <information>   wpa_supplicant(6978):      70 61 75 6c                                       paul            
NetworkManager: <information>   wpa_supplicant(6978): RX ctrl_iface - hexdump_ascii(len=23): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): ET_NETWORK id=0 name='proto'
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE: value - hexdump_ascii(len=3): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): proto: 0x1
NetworkManager: <information>   wpa_supplicant(6978): RX ctrl_iface - hexdump_ascii(len=30): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE: SET_NETWORK id=0 name='key_mgmt'
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE: value - hexdump_ascii(len=7): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): key_mgmt: 0x2
NetworkManager: <information>   wpa_supplicant(6978): RX ctrl_iface - hexdump_ascii(len=82): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE: SET_NETWORK id=0 name='psk'
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE: value - hexdump_ascii(len=64): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): PSK - hexdump(len=32): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): RX ctrl_iface - hexdump_ascii(len=16):
NetworkManager: <information>   wpa_supplicant(6978):      45 4e 41 42 4c 45 5f 4e 45 54 57 4f 52 4b 20 30   ENABLE_NETWORK 0
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE: ENABLE_NETWORK id=0
NetworkManager: <information>   wpa_supplicant(6978): Setting scan request: 0 sec 0 usec
NetworkManager: <information>   wpa_supplicant(6978): State: DISCONNECTED -> SCANNING
NetworkManager: <information>   wpa_supplicant(6978): Starting AP scan (broadcast SSID)
NetworkManager: <information>   wpa_supplicant(6978): Trying to get current scan results first without requesting a new scan to speed up initial association
NetworkManager: <information>   wpa_supplicant(6978): Received 189 bytes of scan results (1 BSSes)
NetworkManager: <information>   wpa_supplicant(6978): Scan results: 1
NetworkManager: <information>   wpa_supplicant(6978): Selecting BSS from priority group 0
NetworkManager: <information>   wpa_supplicant(6978): 0: 00:30:f1:bd:15:04 ssid='paul' wpa_ie_len=26 rsn_ie_len=0 caps=0x11
NetworkManager: <information>   wpa_supplicant(6978):    selected based on WPA IE
NetworkManager: <information>   wpa_supplicant(6978): ' freq=0 MHz)
NetworkManager: <information>   wpa_supplicant(6978): Cancelling scan request
NetworkManager: <information>   wpa_supplicant(6978): WPA: clearing own WPA/RSN IE
NetworkManager: <information>   wpa_supplicant(6978): Automatic auth_alg selection: 0x1
NetworkManager: <information>   wpa_supplicant(6978): WPA: using IEEE 802.11i/D3.0
NetworkManager: <information>   wpa_supplicant(6978): WPA: Selected cipher suites: group 8 pairwise 8 key_mgmt 2
NetworkManager: <information>   wpa_supplicant(6978): WPA: set AP WPA IE - hexdump(len=26): dd 18 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 00 00
NetworkManager: <information>   wpa_supplicant(6978): WPA: clearing AP RSN IE
NetworkManager: <information>   wpa_supplicant(6978): WPA: using GTK TKIP
NetworkManager: <information>   wpa_supplicant(6978): WPA: using PTK TKIP
NetworkManager: <information>   wpa_supplicant(6978): WPA: using KEY_MGMT WPA-PSK
NetworkManager: <information>   wpa_supplicant(6978): WPA: Set own WPA IE default - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
NetworkManager: <information>   wpa_supplicant(6978): No keys have been configured - skip key clearing
NetworkManager: <information>   wpa_supplicant(6978): wpa_driver_wext_set_drop_unencrypted
NetworkManager: <information>   wpa_supplicant(6978): State: SCANNING -> ASSOCIATING
NetworkManager: <information>   wpa_supplicant(6978): WEXT: Operstate: linkmode=-1, operstate=5
NetworkManager: <information>   wpa_supplicant(6978): wpa_driver_wext_associate
NetworkManager: <information>   wpa_supplicant(6978): Setting authentication timeout: 10 sec 0 usec
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: External notification - EAP success=0
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: External notification - EAP fail=0
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: External notification - portControl=Auto
NetworkManager: <information>   wpa_supplicant(6978):  36 39 36 32 2d 31 00
NetworkManager: <information>   wpa_supplicant(6978): Wireless event: cmd=0x8b06 len=8
NetworkManager: <information>   wpa_supplicant(6978): Wireless event: cmd=0x8b1a len=13
NetworkManager: <information>   wpa_supplicant(6978): Wireless event: cmd=0x8b15 len=20
NetworkManager: <information>   wpa_supplicant(6978): Wireless event: new AP: 00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6978): State: ASSOCIATING -> ASSOCIATED
NetworkManager: <information>   wpa_supplicant(6978): WEXT: Operstate: linkmode=-1, operstate=5
NetworkManager: <information>   wpa_supplicant(6978): Associated to a new BSS: BSSID=00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6978): No keys have been configured - skip key clearing
NetworkManager: <information>   wpa_supplicant(6978): Associated with 00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE monitor send - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61 6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 36 39 36 32 2d 31 00
NetworkManager: <information>   wpa_supplicant(6978): WPA: Association event - clear replay counter
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: External notification - portEnabled=0
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: External notification - portValid=0
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: External notification - EAP success=0
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: External notification - portEnabled=1
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: SUPP_PAE entering state CONNECTING
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: SUPP_BE entering state IDLE
NetworkManager: <information>   wpa_supplicant(6978): Setting authentication timeout: 10 sec 0 usec
NetworkManager: <information>   wpa_supplicant(6978): RTM_NEWLINK, IFLA_IFNAME: Interface 'eth0' added
NetworkManager: <information>   wpa_supplicant(6978): RX EAPOL from 00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6978):  00 00 00 00 00 05 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 87 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978): Setting authentication timeout: 10 sec 0 usec
NetworkManager: <information>   wpa_supplicant(6978): IEEE 802.1X RX: version=1 type=3 length=95
NetworkManager: <information>   wpa_supplicant(6978):   EAPOL-Key type=254
NetworkManager: <information>   wpa_supplicant(6978):   key_info 0x289 (ver=1 keyidx=0 rsvd=0 Pairwise Ack Secure)
NetworkManager: <information>   wpa_supplicant(6978):   key_length=32 key_data_length=0
NetworkManager: <information>   wpa_supplicant(6978):   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 05
NetworkManager: <information>   wpa_supplicant(6978):   key_nonce - hexdump(len=32): 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 87
NetworkManager: <information>   wpa_supplicant(6978):   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978):   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978):   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978):   key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978): c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 87 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978): State: ASSOCIATED -> 4WAY_HANDSHAKE
NetworkManager: <information>   wpa_supplicant(6978): WPA: RX message 1 of 4-Way Handshake from 00:30:f1:bd:15:04 (ver=1)
NetworkManager: <information>   wpa_supplicant(6978): WPA: Renewed SNonce - hexdump(len=32): b3 b3 2c a8 09 82 d6 04 9a 30 ae da da 9a 32 ed 85 ba 86 7a 53 65 8d 5c 14 60 eb de 35 2c ff f2
NetworkManager: <information>   wpa_supplicant(6978): WPA: PMK - hexdump(len=32): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): WPA: PTK - hexdump(len=64): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): WPA: WPA IE for msg 2/4 - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
NetworkManager: <information>   wpa_supplicant(6978): WPA: Sending EAPOL-Key 2/4
NetworkManager: <information>   wpa_supplicant(6978): 18 dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
NetworkManager: <information>   wpa_supplicant(6978): RX EAPOL from 00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6978): RX EAPOL - hexdump(len=125): 01 03 00 79 fe 03 c9 00 20 00 00 00 00 00 00 00 06 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 87 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ff 81 9e 6a 89 34 d9 a9 fd 23 c4 13 a3 46 3b 9a 00 1a dd 18 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 00 00
NetworkManager: <information>   wpa_supplicant(6978): IEEE 802.1X RX: version=1 type=3 length=121
NetworkManager: <information>   wpa_supplicant(6978):   EAPOL-Key type=254
NetworkManager: <information>   wpa_supplicant(6978):   key_info 0x3c9 (ver=1 keyidx=0 rsvd=0 Pairwise Install Ack MIC Secure)
NetworkManager: <information>   wpa_supplicant(6978):   key_length=32 key_data_length=26
NetworkManager: <information>   wpa_supplicant(6978):   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 06
NetworkManager: <information>   wpa_supplicant(6978):   key_nonce - hexdump(len=32): 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 87
NetworkManager: <information>   wpa_supplicant(6978):   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978):   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
NetworkManager: <information>   Activation (eth0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to access point 'paul'.
NetworkManager: <information>   Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager: <information>   Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager: <information>   Activation (eth0) Beginning DHCP transaction.
NetworkManager: <information>   Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
NetworkManager: <information>   wpa_supplicant(6978): (len=8): 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978):   key_mic - hexdump(len=16): ff 81 9e 6a 89 34 d9 a9 fd 23 c4 13 a3 46 3b 9a
NetworkManager: <information>   wpa_supplicant(6978): WPA: RX EAPOL-Key - hexdump(len=125): 01 03 00 79 fe 03 c9 00 20 00 00 00 00 00 00 00 06 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 87 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ff 81 9e 6a 89 34 d9 a9 fd 23 c4 13 a3 46 3b 9a 00 1a dd 18 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 00 00
NetworkManager: <information>   wpa_supplicant(6978): State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
NetworkManager: <information>   wpa_supplicant(6978): WPA: RX message 3 of 4-Way Handshake from 00:30:f1:bd:15:04 (ver=1)
NetworkManager: <information>   wpa_supplicant(6978): WPA: IE KeyData - hexdump(len=26): dd 18 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 00 00
NetworkManager: <information>   wpa_supplicant(6978): WPA: Sending EAPOL-Key 4/4
NetworkManager: <information>   wpa_supplicant(6978):  00 00 00 00 00 00 00 00 00 f5 7a 99 31 d6 5a b8 1b a9 f8 2a e6 0c 8a 31 7c 00 00
NetworkManager: <information>   wpa_supplicant(6978): WPA: Installing PTK to the driver.
NetworkManager: <information>   wpa_supplicant(6978): WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978): wpa_driver_wext_set_key: alg=2 key_idx=0 set_tx=1 seq_len=6 key_len=32
NetworkManager: <information>   DHCP daemon state is now 12 (successfully started) for interface eth0
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: External notification - portValid=1
NetworkManager: <information>   wpa_supplicant(6978): State: 4WAY_HANDSHAKE -> GROUP_HANDSHAKE
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: startWhen --> 0
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: SUPP_PAE entering state CONNECTING
NetworkManager: <information>   wpa_supplicant(6978): EAPOL: txStart
NetworkManager: <information>   wpa_supplicant(6978): WPA: drop TX EAPOL in non-IEEE 802.1X mode (type=1 len=0)
NetworkManager: <information>   wpa_supplicant(6978): RX EAPOL from 00:30:f1:bd:15:04
NetworkManager: <information>   wpa_supplicant(6978): RX EAPOL - hexdump(len=131): 01 03 00 7f fe 03 a1 00 20 00 00 00 00 00 00 00 08 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 83 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 20 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 c9 bb bb 3d f8 d7 1f ff 81 68 9c 90 23 6d b9 92 00 20 e1 5b e0 e1 0f 3b 13 7b ae 7d 88 e1 a5 b2 af 71 23 9a 4f 16 20 0b 77 b9 a2 8b 2a 06 31 3b a0 9d
NetworkManager: <information>   wpa_supplicant(6978): IEEE 802.1X RX: version=1 type=3 length=127
NetworkManager: <information>   wpa_supplicant(6978):   EAPOL-Key type=254
NetworkManager: <information>   wpa_supplicant(6978):  MIC Secure)
NetworkManager: <information>   wpa_supplicant(6978):   key_length=32 key_data_length=32
NetworkManager: <information>   wpa_supplicant(6978):   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 08
NetworkManager: <information>   wpa_supplicant(6978):   key_nonce - hexdump(len=32): 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 83
NetworkManager: <information>   wpa_supplicant(6978):   key_iv - hexdump(len=16): 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61
NetworkManager: <information>   wpa_supplicant(6978):   key_rsc - hexdump(len=8): 20 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978):   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978):   key_mic - hexdump(len=16): c9 bb bb 3d f8 d7 1f ff 81 68 9c 90 23 6d b9 92
NetworkManager: <information>   wpa_supplicant(6978): WPA: RX EAPOL-Key - hexdump(len=131): 01 03 00 7f fe 03 a1 00 20 00 00 00 00 00 00 00 08 85 c2 e8 d9 c0 3e b3 34 e4 23 9e 19 55 b6 80 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 83 6a 17 05 de e8 42 22 47 ea 6c ca 2f 9f 3d 1c 61 20 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 c9 bb bb 3d f8 d7 1f ff 81 68 9c 90 23 6d b9 92 00 20 e1 5b e0 e1 0f 3b 13 7b ae 7d 88 e1 a5 b2 af 71 23 9a 4f 16 20 0b 77 b9 a2 8b 2a 06 31 3b a0 9d
NetworkManager: <information>   wpa_supplicant(6978): WPA: RX message 1 of Group Key or STAKey Handshake from 00:30:f1:bd:15:04 (ver=1)
NetworkManager: <information>   wpa_supplicant(6978): OUP_HANDSHAKE -> GROUP_HANDSHAKE
NetworkManager: <information>   wpa_supplicant(6978): WPA: Group Key - hexdump(len=32): [REMOVED]
NetworkManager: <information>   wpa_supplicant(6978): WPA: Installing GTK to the driver (keyidx=2 tx=0).
NetworkManager: <information>   wpa_supplicant(6978): WPA: RSC - hexdump(len=6): 20 00 00 00 00 00
NetworkManager: <information>   wpa_supplicant(6978): wpa_driver_wext_set_key: alg=2 key_idx=2 set_tx=0 seq_len=6 key_len=32
NetworkManager: <information>   wpa_supplicant(6978): WPA: Sending EAPOL-Key 2/2
NetworkManager: <information>   wpa_supplicant(6978): WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f fe 03 21 00 20 00 00 00 00 00 00 00 08 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 c2 3f f3 e4 e6 78 36 c2 f6 67 b1 32 51 23 3a c9 00 00
NetworkManager: <information>   wpa_supplicant(6978): WPA: Key negotiation completed with 00:30:f1:bd:15:04 [PTK=TKIP GTK=TKIP]
NetworkManager: <information>   wpa_supplicant(6978): CTRL_IFACE monitor send - hexdump(len=40): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61 6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 36 39 36 32 2d 31 00
NetworkManager: <information>   wpa_supplicant(6978): Cancelling authentication timeout
NetworkManager: <information>   wpa_supplicant(6978): State: GROUP_HANDSHAKE -> COMPLETED
NetworkManager: <information>   wpa_supplicant(6978): CTRL-EVENT-CONNECTED - Connection to 00:30:f1:bd:15:04 completed (auth) [id=0 id_str=]
NetworkManager: <information>   DHCP daemon state is now 1 (starting) for interface eth0
NetworkManager: <information>   DHCP daemon state is now 2 (bound) for interface eth0
NetworkManager: <information>   Activation (eth0) Stage 4 of 5 (IP Configure Get) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 4 of 5 (IP Configure Get) started...
NetworkManager: <information>   Retrieved the following IP4 configuration from the DHCP daemon:
NetworkManager: <information>     address 192.168.2.110
NetworkManager: <information>     netmask 255.255.255.0
NetworkManager: <information>     broadcast 192.168.2.255
NetworkManager: <information>     gateway 192.168.2.1
NetworkManager: <information>     nameserver 192.168.2.1
NetworkManager: <information>   Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
NetworkManager: <information>   Activation (eth0) Stage 4 of 5 (IP Configure Get) complete.
NetworkManager: <information>   Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
NetworkManager: <information>   DHCP returned name servers but system has disabled dynamic modification!
NetworkManager: <information>   Activation (eth0) successful, device activated.
NetworkManager: <information>   Activation (eth0) Finish handler scheduled.
NetworkManager: <information>   Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.



So now that the key is stored in the keyring, NM will connect just fine automatically
after starting the applet.

Seeing that it works I repeat this at least 10 times now and also click the "paul"
entry in the networks list a few time to make it reconnect this way. It always works
flawlessly, connection time is about 14 seconds, which is a bit longer than without
WPA but not much.
