Wireless: network detected, but no connection

Mon Nov 9 11:25:57 UTC 2009

wicd & static IP gives the same behavior: connecting and disconnecting a
second later. 
Here the log:

2009/11/09 12:00:00 :: Connecting to wireless network OpenNetwork
2009/11/09 12:00:00 :: Putting interface down
2009/11/09 12:00:00 :: Releasing DHCP leases...
2009/11/09 12:00:00 :: Setting false IP...
2009/11/09 12:00:00 :: Stopping wpa_supplicant
2009/11/09 12:00:00 :: Flushing the routing table...
2009/11/09 12:00:00 :: Putting interface up...
2009/11/09 12:00:01 :: Setting static IP : 192.168.10.32
2009/11/09 12:00:01 :: Setting default gateway : 192.168.10.1
2009/11/09 12:00:01 :: Verifying AP association
2009/11/09 12:00:02 :: Connecting thread exiting.
2009/11/09 12:00:02 :: Sending connection attempt result Success

For information, here is the log from a laptop (ubuntu 9.10) that
successfully connects to the same network (using NM):

Nov  9 12:13:00 verde-laptop NetworkManager: <info>  (wlan0): device
state change: 8 -> 3 (reason 0)
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  (wlan0):
deactivating device (reason: 0).
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  (wlan0): canceled
DHCP transaction, dhcp client pid 2090
Nov  9 12:13:00 verde-laptop NetworkManager: <WARN>  check_one_route():
(wlan0) error -34 returned from rtnl_route_del(): Sucess#012
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Activation (wlan0)
starting connection 'Auto OpenNetwork'
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  (wlan0): device
state change: 3 -> 4 (reason 0)
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 1 of 5 (Device Prepare) scheduled...
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 1 of 5 (Device Prepare) started...
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 2 of 5 (Device Configure) scheduled...
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 1 of 5 (Device Prepare) complete.
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 2 of 5 (Device Configure) starting...
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  (wlan0): device
state change: 4 -> 5 (reason 0)
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Activation
(wlan0/wireless): connection 'Auto OpenNetwork' requires no security.
No secrets needed.
Nov  9 12:13:00 verde-laptop avahi-daemon[636]: Withdrawing address
record for 192.168.10.12 on wlan0.
Nov  9 12:13:00 verde-laptop wpa_supplicant[693]:
CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
Nov  9 12:13:00 verde-laptop avahi-daemon[636]: Leaving mDNS multicast
group on interface wlan0.IPv4 with address 192.168.10.12.
Nov  9 12:13:00 verde-laptop avahi-daemon[636]: Interface wlan0.IPv4 no
longer relevant for mDNS.
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Config: added
'ssid' value 'OpenNetwork'
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Config: added
'scan_ssid' value '1'
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Config: added
'key_mgmt' value 'NONE'
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 2 of 5 (Device Configure) complete.
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  (wlan0): supplicant
connection state:  completed -> disconnected
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  Config: set
interface ap_scan to 1
Nov  9 12:13:00 verde-laptop wpa_supplicant[693]: Failed to initiate AP
scan.
Nov  9 12:13:00 verde-laptop NetworkManager: <info>  (wlan0): supplicant
connection state:  disconnected -> scanning
Nov  9 12:13:11 verde-laptop wpa_supplicant[693]:
CTRL-EVENT-SCAN-RESULTS 
Nov  9 12:13:11 verde-laptop wpa_supplicant[693]: Trying to associate
with 00:a0:c5:5d:36:ce (SSID='OpenNetwork' freq=2452 MHz)
Nov  9 12:13:11 verde-laptop wpa_supplicant[693]: Association request to
the driver failed
Nov  9 12:13:11 verde-laptop NetworkManager: <info>  (wlan0): supplicant
connection state:  scanning -> associating
Nov  9 12:13:11 verde-laptop wpa_supplicant[693]: Associated with
00:a0:c5:5d:36:ce
Nov  9 12:13:11 verde-laptop wpa_supplicant[693]: CTRL-EVENT-CONNECTED -
Connection to 00:a0:c5:5d:36:ce completed (reauth) [id=0 id_str=]
Nov  9 12:13:11 verde-laptop NetworkManager: <info>  (wlan0): supplicant
connection state:  associating -> associated
Nov  9 12:13:11 verde-laptop NetworkManager: <info>  (wlan0): supplicant
connection state:  associated -> completed
Nov  9 12:13:11 verde-laptop NetworkManager: <info>  Activation
(wlan0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected
to wireless network 'OpenNetwork'.
Nov  9 12:13:11 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 3 of 5 (IP Configure Start) scheduled.
Nov  9 12:13:11 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 3 of 5 (IP Configure Start) started...
Nov  9 12:13:11 verde-laptop NetworkManager: <info>  (wlan0): device
state change: 5 -> 7 (reason 0)
Nov  9 12:13:11 verde-laptop NetworkManager: <info>  Activation (wlan0)
Beginning DHCP transaction (timeout in 45 seconds)
Nov  9 12:13:11 verde-laptop dhclient: Internet Systems Consortium DHCP
Client V3.1.2
Nov  9 12:13:11 verde-laptop dhclient: Copyright 2004-2008 Internet
Systems Consortium.
Nov  9 12:13:11 verde-laptop dhclient: All rights reserved.
Nov  9 12:13:11 verde-laptop dhclient: For info, please visit
http://www.isc.org/sw/dhcp/
Nov  9 12:13:11 verde-laptop dhclient: 
Nov  9 12:13:12 verde-laptop NetworkManager: <info>  dhclient started
with pid 2219
Nov  9 12:13:12 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 4 of 5 (IP6 Configure Get) scheduled...
Nov  9 12:13:12 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 3 of 5 (IP Configure Start) complete.
Nov  9 12:13:12 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 4 of 5 (IP6 Configure Get) started...
Nov  9 12:13:12 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 4 of 5 (IP6 Configure Get) complete.
Nov  9 12:13:12 verde-laptop dhclient: Listening on
LPF/wlan0/00:90:4b:8f:a8:34
Nov  9 12:13:12 verde-laptop dhclient: Sending on
LPF/wlan0/00:90:4b:8f:a8:34
Nov  9 12:13:12 verde-laptop dhclient: Sending on   Socket/fallback
Nov  9 12:13:12 verde-laptop NetworkManager: <info>  DHCP: device wlan0
state changed normal exit -> preinit
Nov  9 12:13:13 verde-laptop ntpd[2201]: sendto(129.132.2.21) (fd=21):
Invalid argument
Nov  9 12:13:13 verde-laptop wpa_supplicant[693]:
CTRL-EVENT-SCAN-RESULTS 
Nov  9 12:13:15 verde-laptop dhclient: DHCPREQUEST of 192.168.10.12 on
wlan0 to 255.255.255.255 port 67
Nov  9 12:13:15 verde-laptop dhclient: DHCPACK of 192.168.10.12 from
192.168.10.1
Nov  9 12:13:15 verde-laptop NetworkManager: <info>  DHCP: device wlan0
state changed preinit -> reboot
Nov  9 12:13:15 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 4 of 5 (IP4 Configure Get) scheduled...
Nov  9 12:13:15 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 4 of 5 (IP4 Configure Get) started...
Nov  9 12:13:15 verde-laptop NetworkManager: <info>    address
192.168.10.12
Nov  9 12:13:15 verde-laptop NetworkManager: <info>    prefix 24
(255.255.255.0)
Nov  9 12:13:15 verde-laptop NetworkManager: <info>    gateway
192.168.10.1
Nov  9 12:13:15 verde-laptop NetworkManager: <info>    nameserver
'192.168.10.1'
Nov  9 12:13:15 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 5 of 5 (IP Configure Commit) scheduled...
Nov  9 12:13:15 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 4 of 5 (IP4 Configure Get) complete.
Nov  9 12:13:15 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 5 of 5 (IP Configure Commit) started...
Nov  9 12:13:15 verde-laptop avahi-daemon[636]: Joining mDNS multicast
group on interface wlan0.IPv4 with address 192.168.10.12.
Nov  9 12:13:15 verde-laptop avahi-daemon[636]: New relevant interface
wlan0.IPv4 for mDNS.
Nov  9 12:13:15 verde-laptop avahi-daemon[636]: Registering new address
record for 192.168.10.12 on wlan0.IPv4.
Nov  9 12:13:15 verde-laptop dhclient: bound to 192.168.10.12 -- renewal
in 41029 seconds.
Nov  9 12:13:15 verde-laptop ntpd[2201]: sendto(91.189.94.4) (fd=21):
Network is unreachable
Nov  9 12:13:16 verde-laptop NetworkManager: <info>  (wlan0): device
state change: 7 -> 8 (reason 0)
Nov  9 12:13:16 verde-laptop NetworkManager: <info>  Policy set 'Auto
OpenNetwork' (wlan0) as default for routing and DNS.
Nov  9 12:13:16 verde-laptop NetworkManager: <info>  Activation (wlan0)
successful, device activated.
Nov  9 12:13:16 verde-laptop NetworkManager: <info>  Activation (wlan0)
Stage 5 of 5 (IP Configure Commit) complete.
Nov  9 12:13:16 verde-laptop ntpd[2201]: ntpd exiting on signal 15
Nov  9 12:13:17 verde-laptop ntpdate[2301]: adjust time server
129.132.2.21 offset -0.000258 sec
Nov  9 12:13:17 verde-laptop ntpd[2329]: ntpd 4.2.4p6 at 1.1549-o Thu Oct
22 21:58:37 UTC 2009 (1)
Nov  9 12:13:17 verde-laptop ntpd[2330]: precision = 1.000 usec
Nov  9 12:13:17 verde-laptop ntpd[2330]: Listening on interface #0
wildcard, 0.0.0.0#123 Disabled
Nov  9 12:13:17 verde-laptop ntpd[2330]: Listening on interface #1
wildcard, ::#123 Disabled
Nov  9 12:13:17 verde-laptop ntpd[2330]: Listening on interface #2
lo, ::1#123 Enabled
Nov  9 12:13:17 verde-laptop ntpd[2330]: Listening on interface #3
wlan0, fe80::290:4bff:fe8f:a834#123 Enabled
Nov  9 12:13:17 verde-laptop ntpd[2330]: Listening on interface #4 lo,
127.0.0.1#123 Enabled
Nov  9 12:13:17 verde-laptop ntpd[2330]: Listening on interface #5
wlan0, 192.168.10.12#123 Enabled
Nov  9 12:13:17 verde-laptop ntpd[2330]: kernel time sync status 2040
Nov  9 12:13:17 verde-laptop ntpd[2330]: frequency initialized -33.295
PPM from /var/lib/ntp/ntp.drift