Fedora 7 0.6.6-1 reconnect issue?



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



[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]