Re: networkmanager fails to associate (ipw3945)

[dragoran <drago01 gmail com>]

Dan Williams wrote:
> On Thu, 2007-02-08 at 10:29 +0100, dragoran wrote:
>   
> > I recompiled ieee and ipw3945 and it now works with wpa_supplicant 
> > without any problems (using the wext driver).
> > but nm keeps asking me for the passphrase and never connects.
> >     
>
> Hmm; can you get some logs of NM during the connection attempt?  They
> should get dumped to /var/log/messages.  Basically, it may be the case
> that NM isn't passing the same options to wpa_supplicant as your
> testcase is using, but we don't know what those options are.
>
>   
nm output to syslog is attached (note: ignore selinux messages I did run 
setenforce 0 before doing this test)
> Dan
>
>
>
>   

Feb  8 13:48:35 localhost NetworkManager: <information>	User Switch: /org/freedesktop/NetworkManager/Devices/eth1 / mynet 
Feb  8 13:48:35 localhost NetworkManager: <information>	Deactivating device eth1. 
Feb  8 13:48:35 localhost kernel: audit(1170938915.752:18): avc:  denied  { unlink } for  pid=2641 comm="NetworkManager" name="eth1" dev=sda2 ino=7898869 scontext=system_u:system_r:NetworkManager_t:s0 tcontext=user_u:object_r:var_run_t:s0 tclass=sock_file
Feb  8 13:48:35 localhost dhcdbd: message_handler: message handler not found under /com/redhat/dhcp/eth1 for sub-path eth1.dbus.get.reason
Feb  8 13:48:35 localhost NetworkManager: <information>	Device eth1 activation scheduled... 
Feb  8 13:48:35 localhost NetworkManager: <information>	Activation (eth1) started... 
Feb  8 13:48:35 localhost NetworkManager: <information>	Activation (eth1) Stage 1 of 5 (Device Prepare) scheduled... 
Feb  8 13:48:35 localhost NetworkManager: <information>	Activation (eth1) Stage 1 of 5 (Device Prepare) started... 
Feb  8 13:48:35 localhost NetworkManager: <information>	Activation (eth1) Stage 2 of 5 (Device Configure) scheduled... 
Feb  8 13:48:35 localhost NetworkManager: <information>	Activation (eth1) Stage 1 of 5 (Device Prepare) complete. 
Feb  8 13:48:35 localhost NetworkManager: <information>	Activation (eth1) Stage 2 of 5 (Device Configure) starting... 
Feb  8 13:48:35 localhost NetworkManager: <information>	Activation (eth1/wireless): access point 'mynet' is encrypted, but NO valid key exists.  New key needed. 
Feb  8 13:48:35 localhost NetworkManager: <information>	Activation (eth1) New wireless user key requested for network 'mynet'. 
Feb  8 13:48:35 localhost NetworkManager: <information>	Activation (eth1) Stage 2 of 5 (Device Configure) complete. 
Feb  8 13:48:49 localhost NetworkManager: <information>	Activation (eth1) New wireless user key for network 'mynet' received. 
Feb  8 13:48:49 localhost NetworkManager: <information>	Activation (eth1) Stage 1 of 5 (Device Prepare) scheduled... 
Feb  8 13:48:49 localhost NetworkManager: <information>	Activation (eth1) Stage 1 of 5 (Device Prepare) started... 
Feb  8 13:48:49 localhost NetworkManager: <information>	Activation (eth1) Stage 2 of 5 (Device Configure) scheduled... 
Feb  8 13:48:49 localhost NetworkManager: <information>	Activation (eth1) Stage 1 of 5 (Device Prepare) complete. 
Feb  8 13:48:49 localhost NetworkManager: <information>	Activation (eth1) Stage 2 of 5 (Device Configure) starting... 
Feb  8 13:48:49 localhost NetworkManager: <information>	Activation (eth1/wireless): access point 'mynet' is encrypted, and a key exists.  No new key needed. 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: sending command 'INTERFACE_ADD eth1		wext	/var/run/wpa_supplicant	' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: response was 'OK' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: sending command 'AP_SCAN 1' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: response was 'OK' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: sending command 'ADD_NETWORK' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: response was '0' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: sending command 'SET_NETWORK 0 ssid 6d796e6574' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: response was 'OK' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: sending command 'SET_NETWORK 0 proto WPA' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: response was 'OK' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: sending command 'SET_NETWORK 0 key_mgmt WPA-PSK' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: response was 'OK' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: sending command 'SET_NETWORK 0 psk <key>' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: response was 'OK' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: sending command 'SET_NETWORK 0 pairwise TKIP' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: response was 'OK' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: sending command 'SET_NETWORK 0 group TKIP' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: response was 'OK' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: sending command 'ENABLE_NETWORK 0' 
Feb  8 13:48:49 localhost NetworkManager: <information>	SUP: response was 'OK' 
Feb  8 13:48:49 localhost NetworkManager: <information>	Activation (eth1) Stage 2 of 5 (Device Configure) complete. 
Feb  8 13:48:57 localhost kernel: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
Feb  8 13:48:59 localhost avahi-daemon[2565]: New relevant interface eth1.IPv6 for mDNS.
Feb  8 13:48:59 localhost avahi-daemon[2565]: Joining mDNS multicast group on interface eth1.IPv6 with address fe80::218:deff:fe05:f79e.
Feb  8 13:48:59 localhost avahi-daemon[2565]: Registering new address record for fe80::218:deff:fe05:f79e on eth1.
Feb  8 13:49:00 localhost NetworkManager: <information>	Activation (eth1/wireless): disconnected during association, asking for new key. 
Feb  8 13:49:00 localhost NetworkManager: <information>	Activation (eth1) New wireless user key requested for network 'mynet'. 
Feb  8 13:49:12 localhost NetworkManager: <information>	Activation (eth1) New wireless user key request for network 'mynet' was canceled. 
Feb  8 13:49:12 localhost NetworkManager: <information>	Deactivating device eth1. 
Feb  8 13:49:12 localhost NetworkManager: <information>	Activation (eth1): cancelling... 
Feb  8 13:49:12 localhost NetworkManager: <information>	Activation (eth1) cancellation handler scheduled... 
Feb  8 13:49:12 localhost NetworkManager: <information>	Activation (eth1): waiting for device to cancel activation. 
Feb  8 13:49:12 localhost NetworkManager: <information>	Activation (eth1) cancellation handled. 
Feb  8 13:49:12 localhost NetworkManager: <information>	Activation (eth1): cancelled. 
Feb  8 13:49:12 localhost avahi-daemon[2565]: Interface eth1.IPv6 no longer relevant for mDNS.
Feb  8 13:49:12 localhost avahi-daemon[2565]: Leaving mDNS multicast group on interface eth1.IPv6 with address fe80::218:deff:fe05:f79e.
Feb  8 13:49:12 localhost avahi-daemon[2565]: Withdrawing address record for fe80::218:deff:fe05:f79e on eth1.