Re: disconnected from wireless network

[Dan Williams <dcbw redhat com>]

On Mon, 2008-07-21 at 15:17 +0200, Kolb Zoltán wrote:
> Hi!
> 
> Recently I upgraded from Fedora7 to Fedora9 (really it was a reinstall) 
> and started using NetworkManager. So far I had little success with 
> wireless networks.
> 
> At first NetworkManager connects to the wireless network, but after a 
> few seconds disconnects and keeps asking passphrases, then disables the 
> network. I can make it work again by unloading the hostap modules and 
> modprobing them again, but it only works for a few seconds again. 
> Several other devices connect to the AP with no problem (it is a Linksys 
> WRT54GL). Earlier with Fedora7 this wireless card (a Prism minipci 
> notebook card in an IBM T30) with this AP.

wpa_supplicant is getting disconnected from the AP and cannot reconnect
within the timeout.  Can you add "-dddt" to the end of the Exec= line
in /usr/share/dbus-1/system-services/fi.epitest.hostap.WPASupplicant.service so that it looks like this:

[D-BUS Service]
Name=fi.epitest.hostap.WPASupplicant
Exec=/usr/sbin/wpa_supplicant -c /etc/wpa_supplicant/wpa_supplicant.conf -u -f /var/log/wpa_supplicant.log -dddt
User=root

and then reboot and try connecting?  That will provide quite a bit more
information about why the driver is disconnecting you and cannot
reconnect.

Dan

> See the attached log (from /var/log/messages). Any suggestions?
> 
> 
> plain text document attachment (nm.log)
> Jul 21 13:49:04 kolibri kernel: wifi0: NIC: id=0x8013 v1.0.0
> Jul 21 13:49:04 kolibri kernel: wifi0: PRI: id=0x15 v1.1.1
> Jul 21 13:49:04 kolibri kernel: wifi0: STA: id=0x1f v1.7.4
> Jul 21 13:49:04 kolibri kernel: wifi0: Intersil Prism2.5 PCI: mem=0xf8000000, irq=9
> Jul 21 13:49:04 kolibri kernel: udev: renamed network interface wlan0 to eth1
> Jul 21 13:49:04 kolibri NetworkManager: <info>  eth1: Device is fully-supported using driver 'hostap_pci'.
> Jul 21 13:49:04 kolibri NetworkManager: <info>  eth1: driver supports SSID scans (scan_capa 0x01).
> Jul 21 13:49:04 kolibri NetworkManager: <info>  Found new wireless (802.11) device 'eth1'.
> Jul 21 13:49:04 kolibri NetworkManager: <info>  (eth1): exported as /org/freedesktop/Hal/devices/net_00_05_3c_09_5e_d5
> Jul 21 13:49:08 kolibri NetworkManager: <info>  (eth1): device state change: 1 -> 2
> Jul 21 13:49:08 kolibri NetworkManager: <info>  (eth1): bringing up device.
> Jul 21 13:49:08 kolibri NetworkManager: <info>  (eth1): preparing device.
> Jul 21 13:49:08 kolibri NetworkManager: <info>  (eth1): deactivating device.
> Jul 21 13:49:08 kolibri NetworkManager: <info>  (eth1): supplicant interface state change: 1 -> 2.
> Jul 21 13:49:10 kolibri avahi-daemon[2237]: Registering new address record for fe80::205:3cff:fe09:5ed5 on eth1.*.
> Jul 21 13:49:23 kolibri NetworkManager: <info>  (eth1): device state change: 2 -> 3
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) starting connection 'Bitlab'
> Jul 21 13:49:37 kolibri NetworkManager: <info>  (eth1): device state change: 3 -> 4
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) scheduled...
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) started...
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) scheduled...
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) complete.
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) starting...
> Jul 21 13:49:37 kolibri NetworkManager: <info>  (eth1): device state change: 4 -> 5
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1/wireless): access point 'Bitlab' has security, but secrets are required.
> Jul 21 13:49:37 kolibri NetworkManager: <info>  (eth1): device state change: 5 -> 6
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) complete.
> Jul 21 13:49:37 kolibri NetworkManager: Missing or invalid key management
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) scheduled...
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) started...
> Jul 21 13:49:37 kolibri NetworkManager: <info>  (eth1): device state change: 6 -> 4
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) scheduled...
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) complete.
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) starting...
> Jul 21 13:49:37 kolibri NetworkManager: <info>  (eth1): device state change: 4 -> 5
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1/wireless): connection 'Bitlab' has security, and secrets exist.  No new secrets needed.
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Config: added 'ssid' value 'bitlab'
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Config: added 'scan_ssid' value '1'
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Config: added 'key_mgmt' value 'WPA-PSK'
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Config: added 'psk' value '<omitted>'
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Config: added 'proto' value 'WPA RSN'
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Config: added 'pairwise' value 'TKIP CCMP'
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Config: added 'group' value 'WEP40 WEP104 TKIP CCMP'
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) complete.
> Jul 21 13:49:37 kolibri NetworkManager: <info>  Config: set interface ap_scan to 1
> Jul 21 13:49:37 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 1 -> 2
> Jul 21 13:49:42 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 2 -> 3
> Jul 21 13:49:42 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 3 -> 5
> Jul 21 13:49:43 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 5 -> 6
> Jul 21 13:49:43 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 6 -> 7
> Jul 21 13:49:43 kolibri NetworkManager: <info>  Activation (eth1/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'bitlab'.
> Jul 21 13:49:43 kolibri NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure Start) scheduled.
> Jul 21 13:49:43 kolibri NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure Start) started...
> Jul 21 13:49:43 kolibri NetworkManager: <info>  (eth1): device state change: 5 -> 7
> Jul 21 13:49:43 kolibri NetworkManager: <info>  Activation (eth1) Beginning DHCP transaction.
> Jul 21 13:49:43 kolibri NetworkManager: <info>  dhclient started with pid 4083
> Jul 21 13:49:43 kolibri NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure Start) complete.
> Jul 21 13:49:43 kolibri dhclient: Internet Systems Consortium DHCP Client 4.0.0
> Jul 21 13:49:43 kolibri dhclient: Copyright 2004-2007 Internet Systems Consortium.
> Jul 21 13:49:43 kolibri dhclient: All rights reserved.
> Jul 21 13:49:43 kolibri dhclient: For info, please visit http://www.isc.org/sw/dhcp/
> Jul 21 13:49:43 kolibri dhclient: 
> Jul 21 13:49:43 kolibri NetworkManager: <info>  DHCP: device eth1 state changed normal exit -> preinit
> Jul 21 13:49:43 kolibri dhclient: Listening on LPF/eth1/00:05:3c:09:5e:d5
> Jul 21 13:49:43 kolibri dhclient: Sending on   LPF/eth1/00:05:3c:09:5e:d5
> Jul 21 13:49:43 kolibri dhclient: Sending on   Socket/fallback
> Jul 21 13:49:43 kolibri dhclient: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 7
> Jul 21 13:49:43 kolibri dhclient: DHCPOFFER from 172.31.10.9
> Jul 21 13:49:43 kolibri dhclient: DHCPREQUEST on eth1 to 255.255.255.255 port 67
> Jul 21 13:49:43 kolibri dhclient: DHCPACK from 172.31.10.9
> Jul 21 13:49:43 kolibri dhclient: bound to 172.31.2.2 -- renewal in 67665 seconds.
> Jul 21 13:49:43 kolibri NetworkManager: <info>  DHCP: device eth1 state changed preinit -> bound
> Jul 21 13:49:43 kolibri NetworkManager: <info>  Activation (eth1) Stage 4 of 5 (IP Configure Get) scheduled...
> Jul 21 13:49:43 kolibri NetworkManager: <info>  Activation (eth1) Stage 4 of 5 (IP Configure Get) started...
> Jul 21 13:49:43 kolibri NetworkManager: <info>    address 172.31.2.2
> Jul 21 13:49:43 kolibri NetworkManager: <info>    netmask 255.255.240.0
> Jul 21 13:49:43 kolibri NetworkManager: <info>    gateway 172.31.10.101
> Jul 21 13:49:43 kolibri NetworkManager: <info>    nameserver '172.31.10.9'
> Jul 21 13:49:43 kolibri NetworkManager: <info>    nameserver '172.31.20.32'
> Jul 21 13:49:43 kolibri NetworkManager: <info>    domain name 'polygon.hu'
> Jul 21 13:49:43 kolibri NetworkManager: <info>  Activation (eth1) Stage 5 of 5 (IP Configure Commit) scheduled...
> Jul 21 13:49:43 kolibri NetworkManager: <info>  Activation (eth1) Stage 4 of 5 (IP Configure Get) complete.
> Jul 21 13:49:43 kolibri NetworkManager: <info>  Activation (eth1) Stage 5 of 5 (IP Configure Commit) started...
> Jul 21 13:49:43 kolibri avahi-daemon[2237]: Joining mDNS multicast group on interface eth1.IPv4 with address 172.31.2.2.
> Jul 21 13:49:43 kolibri avahi-daemon[2237]: New relevant interface eth1.IPv4 for mDNS.
> Jul 21 13:49:43 kolibri avahi-daemon[2237]: Registering new address record for 172.31.2.2 on eth1.IPv4.
> Jul 21 13:49:44 kolibri NetworkManager: <info>  Policy set (eth0) as default device for routing and DNS.
> Jul 21 13:49:44 kolibri NetworkManager: <info>  (eth1): device state change: 7 -> 8
> Jul 21 13:49:44 kolibri NetworkManager: <info>  Activation (eth1) successful, device activated.
> Jul 21 13:49:44 kolibri NetworkManager: <info>  Activation (eth1) Stage 5 of 5 (IP Configure Commit) complete.
> Jul 21 13:49:44 kolibri nm-dispatcher.action: Script '/etc/NetworkManager/dispatcher.d/05-netfs' exited with error status 1.
> Jul 21 13:49:49 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 7 -> 3
> Jul 21 13:49:52 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 3 -> 5
> Jul 21 13:49:52 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 5 -> 6
> Jul 21 13:49:52 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 6 -> 7
> Jul 21 13:50:29 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 7 -> 3
> Jul 21 13:50:49 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 3 -> 0
> Jul 21 13:50:49 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 0 -> 2
> Jul 21 13:50:54 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 2 -> 3
> Jul 21 13:51:04 kolibri NetworkManager: <info>  (eth1): device state change: 8 -> 3
> Jul 21 13:51:04 kolibri NetworkManager: <info>  (eth1): deactivating device.
> Jul 21 13:51:04 kolibri NetworkManager: <info>  eth1: canceled DHCP transaction, dhclient pid 4083
> Jul 21 13:51:04 kolibri avahi-daemon[2237]: Withdrawing address record for 172.31.2.2 on eth1.
> Jul 21 13:51:04 kolibri avahi-daemon[2237]: Leaving mDNS multicast group on interface eth1.IPv4 with address 172.31.2.2.
> Jul 21 13:51:04 kolibri avahi-daemon[2237]: Interface eth1.IPv4 no longer relevant for mDNS.
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Activation (eth1) starting connection 'Bitlab'
> Jul 21 13:51:04 kolibri NetworkManager: <info>  (eth1): device state change: 3 -> 4
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) scheduled...
> Jul 21 13:51:04 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 3 -> 0
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) started...
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) scheduled...
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) complete.
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) starting...
> Jul 21 13:51:04 kolibri NetworkManager: <info>  (eth1): device state change: 4 -> 5
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Activation (eth1/wireless): connection 'Bitlab' has security, and secrets exist.  No new secrets needed.
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Config: added 'ssid' value 'bitlab'
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Config: added 'scan_ssid' value '1'
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Config: added 'key_mgmt' value 'WPA-PSK'
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Config: added 'psk' value '<omitted>'
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Config: added 'proto' value 'WPA RSN'
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Config: added 'pairwise' value 'TKIP CCMP'
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Config: added 'group' value 'WEP40 WEP104 TKIP CCMP'
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) complete.
> Jul 21 13:51:04 kolibri NetworkManager: <info>  Config: set interface ap_scan to 1
> Jul 21 13:51:04 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 0 -> 2
> Jul 21 13:51:09 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 2 -> 3
> Jul 21 13:51:29 kolibri NetworkManager: <info>  Activation (eth1/wireless): association took too long.
> Jul 21 13:51:29 kolibri NetworkManager: <info>  (eth1): device state change: 5 -> 6
> Jul 21 13:51:29 kolibri NetworkManager: <info>  Activation (eth1/wireless): asking for new secrets
> Jul 21 13:51:29 kolibri NetworkManager: <info>  (eth1): supplicant connection state change: 3 -> 0
> Jul 21 13:51:33 kolibri NetworkManager: <WARN>  get_secrets_cb(): Couldn't get connection secrets: applet-device-wireless.c.1299 (get_secrets_dialog_response_cb): canceled.
> Jul 21 13:51:33 kolibri NetworkManager: <info>  (eth1): device state change: 6 -> 9
> Jul 21 13:51:33 kolibri NetworkManager: <info>  Activation (eth1) failed for access point (bitlab)
> Jul 21 13:51:33 kolibri NetworkManager: <info>  Marking connection 'Bitlab' invalid.
> Jul 21 13:51:33 kolibri NetworkManager: <info>  Activation (eth1) failed.
> Jul 21 13:51:33 kolibri NetworkManager: <info>  (eth1): device state change: 9 -> 3
> Jul 21 13:51:33 kolibri NetworkManager: <info>  (eth1): deactivating device.
> 
> _______________________________________________
> NetworkManager-list mailing list
> NetworkManager-list gnome org
> http://mail.gnome.org/mailman/listinfo/networkmanager-list