Hello,

 

I am using Fedora 7 with a kernel (modified) based off of Fedora
2.6.23.15-80, madwifi drivers, and NetworkManager 0.6.6.

 

The scenario is thus:

 

1.      WPA-PSK/TKIP connection established to SSID1
2.      Numerous Supplicant state changed: 0 then 1, over and over again.
(I assume this is connection yo-yoing).
3.      Eventually the connection times out.  (ath0: link timed out.)
4.      SWITCH: found better connection SSID1 (for the same connection that
was just lost)
5.      DHCPRELEASE on the connection is started
6.      Activation started
7.      During Stage 2 configure a DHCP daemon state is now 14 event is
received.
8.      real_act_stage2_config()  :  couldn't connect to the supplicant.
9.      Device deactivated

 

I have repeated this a few times now.  I suspect that the normal exit event
from DHCP is scheduling a failure during the activation of the new
connection which somehow kills wpa_supplicant.  Am I even close on this one?
Below is the messages log I saw.  

 

Apr 30 15:04:49 localhost dhclient: DHCPREQUEST on ath0 to 192.168.2.55 port
67

Apr 30 15:04:49 localhost dhclient: DHCPACK from 192.168.2.55

Apr 30 15:04:49 localhost dhclient: bound to 192.168.2.245 -- renewal in 123
seconds.

Apr 30 15:04:49 localhost NetworkManager: <info>  DHCP daemon state is now 3
(renew) for interface ath0 

Apr 30 15:05:10 localhost NetworkManager: <info>  Supplicant state changed:
0 

Apr 30 15:05:10 localhost NetworkManager: <info>  Supplicant state changed:
0 

Apr 30 15:05:10 localhost NetworkManager: <info>  Supplicant state changed:
1 

Apr 30 15:05:22 localhost NetworkManager: <info>  Supplicant state changed:
0 

Apr 30 15:05:22 localhost NetworkManager: <info>  Supplicant state changed:
0 

Apr 30 15:05:22 localhost NetworkManager: <info>  Supplicant state changed:
1 

Apr 30 15:05:32 localhost NetworkManager: <info>  Supplicant state changed:
0 

Apr 30 15:05:32 localhost NetworkManager: <info>  Supplicant state changed:
0 

Apr 30 15:05:32 localhost NetworkManager: <info>  Supplicant state changed:
1 

Apr 30 15:05:41 localhost NetworkManager: <info>  Supplicant state changed:
0 

Apr 30 15:05:41 localhost NetworkManager: <info>  Supplicant state changed:
0 

Apr 30 15:05:41 localhost NetworkManager: <info>  Supplicant state changed:
1 

Apr 30 15:05:43 localhost NetworkManager: <info>  Supplicant state changed:
0 

Apr 30 15:06:03 localhost NetworkManager: <info>  ath0: link timed out. 

Apr 30 15:06:03 localhost NetworkManager: <info>  SWITCH: found better
connection 'ath0/GEM_RD_TEST2' than current connection 'ath0/GEM_RD_TEST2'.
same_ssid=1, have_link=0 

Apr 30 15:06:03 localhost NetworkManager: <info>  Will activate connection
'ath0/GEM_RD_TEST2'. 

Apr 30 15:06:03 localhost NetworkManager: <info>  Device ath0 activation
scheduled... 

Apr 30 15:06:03 localhost NetworkManager: <info>  Deactivating device ath0. 

Apr 30 15:06:03 localhost dhclient: wifi0: unknown hardware address type 801

Apr 30 15:06:03 localhost dhclient: wifi0: unknown hardware address type 801

Apr 30 15:06:03 localhost dhclient: DHCPRELEASE on ath0 to 192.168.2.55 port
67

Apr 30 15:06:04 localhost avahi-daemon[2669]: Withdrawing address record for
192.168.2.245 on ath0.

Apr 30 15:06:04 localhost avahi-daemon[2669]: Leaving mDNS multicast group
on interface ath0.IPv4 with address 192.168.2.245.

Apr 30 15:06:04 localhost avahi-daemon[2669]: Interface ath0.IPv4 no longer
relevant for mDNS.

Apr 30 15:06:04 localhost avahi-daemon[2669]: Withdrawing address record for
fe80::202:6fff:fe4a:c07e on ath0.

Apr 30 15:06:04 localhost avahi-daemon[2669]: Leaving mDNS multicast group
on interface ath0.IPv6 with address fe80::202:6fff:fe4a:c07e.

Apr 30 15:06:04 localhost avahi-daemon[2669]: Interface ath0.IPv6 no longer
relevant for mDNS.

Apr 30 15:06:04 localhost NetworkManager: <info>  Activation (ath0)
started... 

Apr 30 15:06:04 localhost NetworkManager: <info>  Activation (ath0) Stage 1
of 5 (Device Prepare) scheduled... 

Apr 30 15:06:04 localhost NetworkManager: <info>  Activation (ath0) Stage 1
of 5 (Device Prepare) started... 

Apr 30 15:06:04 localhost NetworkManager: <info>  DHCP daemon state is now
14 (normal exit) for interface ath0 

Apr 30 15:06:04 localhost NetworkManager: <info>  Activation (ath0) Stage 2
of 5 (Device Configure) scheduled... 

Apr 30 15:06:04 localhost NetworkManager: <info>  Activation (ath0) Stage 1
of 5 (Device Prepare) complete. 

Apr 30 15:06:04 localhost NetworkManager: <info>  Activation (ath0) Stage 2
of 5 (Device Configure) starting... 

Apr 30 15:06:04 localhost NetworkManager: <info>  Activation
(ath0/wireless): access point 'GEM_RD_TEST2' is encrypted, and a key exists.
No new key needed. 

Apr 30 15:06:04 localhost NetworkManager: <info>  DHCP daemon state is now
11 (unknown) for interface ath0 

Apr 30 15:06:04 localhost NetworkManager: <info>  DHCP daemon state is now
14 (normal exit) for interface ath0 

Apr 30 15:06:07 localhost NetworkManager: <info>  Error opening supplicant
global control interface. 

Apr 30 15:06:07 localhost NetworkManager: <WARN>  real_act_stage2_config():
Activation (ath0/wireless): couldn't connect to the supplicant. 

Apr 30 15:06:07 localhost NetworkManager: <info>  Activation (ath0) failure
scheduled... 

Apr 30 15:06:07 localhost NetworkManager: <info>  Activation (ath0) Stage 2
of 5 (Device Configure) complete. 

Apr 30 15:06:07 localhost NetworkManager: <info>  Activation (ath0) failed
for access point (GEM_RD_TEST2) 

Apr 30 15:06:07 localhost NetworkManager: <info>  Activation (ath0) failed. 

Apr 30 15:06:07 localhost NetworkManager: <info>  Deactivating device ath0. 

Apr 30 15:06:07 localhost kernel: [ 3651.599127] ADDRCONF(NETDEV_UP): ath0:
link is not ready

 

Thanks,
Phil

_______________________________________________
NetworkManager-list mailing list
[email protected]
http://mail.gnome.org/mailman/listinfo/networkmanager-list

Reply via email to