Package: wicd Version: 1.7.0+ds1-6 Severity: normal wicd takes time to run the postconnect scripts and report the IP address. I've had the problem with two different access points today.
The wicd.log file contains: 2011/02/22 22:47:49 :: Connecting to wireless network Etoile 1 2011/02/22 22:47:49 :: attempting to set hostname with dhclient 2011/02/22 22:47:49 :: using dhcpcd or another supported client may work better 2011/02/22 22:47:49 :: attempting to set hostname with dhclient 2011/02/22 22:47:49 :: using dhcpcd or another supported client may work better 2011/02/22 22:47:50 :: Putting interface down 2011/02/22 22:47:50 :: Releasing DHCP leases... 2011/02/22 22:47:50 :: attempting to set hostname with dhclient 2011/02/22 22:47:50 :: using dhcpcd or another supported client may work better 2011/02/22 22:47:50 :: Setting false IP... 2011/02/22 22:47:50 :: Stopping wpa_supplicant 2011/02/22 22:47:50 :: Flushing the routing table... 2011/02/22 22:47:50 :: Putting interface up... 2011/02/22 22:47:52 :: Attempting to authenticate... 2011/02/22 22:47:53 :: Running DHCP with hostname xvii 2011/02/22 22:47:53 :: attempting to set hostname with dhclient 2011/02/22 22:47:53 :: using dhcpcd or another supported client may work better 2011/02/22 22:47:53 :: Internet Systems Consortium DHCP Client 4.1.1-P1 2011/02/22 22:47:53 :: Copyright 2004-2010 Internet Systems Consortium. 2011/02/22 22:47:53 :: All rights reserved. 2011/02/22 22:47:53 :: For info, please visit https://www.isc.org/software/dhcp/ 2011/02/22 22:47:53 :: 2011/02/22 22:47:53 :: Listening on LPF/wlan0/00:21:6a:47:b3:80 2011/02/22 22:47:53 :: Sending on LPF/wlan0/00:21:6a:47:b3:80 2011/02/22 22:47:53 :: Sending on Socket/fallback 2011/02/22 22:47:55 :: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 7 2011/02/22 22:47:57 :: DHCPOFFER from 192.168.1.1 2011/02/22 22:47:57 :: DHCPREQUEST on wlan0 to 255.255.255.255 port 67 2011/02/22 22:47:57 :: DHCPACK from 192.168.1.1 2011/02/22 22:47:57 :: bound to 192.168.1.24 -- renewal in 39668 seconds. 2011/02/22 22:47:57 :: DHCP connection successful 2011/02/22 22:47:57 :: not verifying 2011/02/22 22:51:09 :: Connecting thread exiting. 2011/02/22 22:51:09 :: Sending connection attempt result Success See the problem between 22:47:57 and 22:51:09. The /var/log/syslog file contains: Feb 22 22:47:49 xvii named[1696]: received control channel command 'reconfig' Feb 22 22:47:49 xvii named[1696]: loading configuration from '/etc/bind/named.conf' Feb 22 22:47:49 xvii named[1696]: reading built-in trusted keys from file '/etc/bind/bind.keys' Feb 22 22:47:49 xvii named[1696]: using default UDP/IPv4 port range: [1024, 65535] Feb 22 22:47:49 xvii named[1696]: using default UDP/IPv6 port range: [1024, 65535] Feb 22 22:47:49 xvii named[1696]: set up managed keys zone for view _default, file 'managed-keys.bind' Feb 22 22:47:49 xvii named[1696]: reloading configuration succeeded Feb 22 22:47:49 xvii named[1696]: any newly configured zones are now loaded Feb 22 22:47:49 xvii dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1 Feb 22 22:47:49 xvii dhclient: Copyright 2004-2010 Internet Systems Consortium. Feb 22 22:47:49 xvii dhclient: All rights reserved. Feb 22 22:47:49 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/ Feb 22 22:47:49 xvii dhclient: Feb 22 22:47:49 xvii dhclient: Listening on LPF/wlan0/00:21:6a:47:b3:80 Feb 22 22:47:49 xvii dhclient: Sending on LPF/wlan0/00:21:6a:47:b3:80 Feb 22 22:47:49 xvii dhclient: Sending on Socket/fallback Feb 22 22:47:49 xvii dhclient: DHCPRELEASE on wlan0 to 192.168.1.1 port 67 Feb 22 22:47:49 xvii dhclient: send_packet: Network is unreachable Feb 22 22:47:49 xvii dhclient: send_packet: please consult README file regarding broadcast address. Feb 22 22:47:49 xvii kernel: [20782.179008] Registered led device: iwl-phy0::radio Feb 22 22:47:49 xvii kernel: [20782.179052] Registered led device: iwl-phy0::assoc Feb 22 22:47:49 xvii kernel: [20782.179093] Registered led device: iwl-phy0::RX Feb 22 22:47:49 xvii kernel: [20782.179135] Registered led device: iwl-phy0::TX Feb 22 22:47:49 xvii kernel: [20782.194811] ADDRCONF(NETDEV_UP): wlan0: link is not ready Feb 22 22:47:49 xvii named[1696]: received control channel command 'reconfig' Feb 22 22:47:49 xvii named[1696]: loading configuration from '/etc/bind/named.conf' Feb 22 22:47:49 xvii named[1696]: reading built-in trusted keys from file '/etc/bind/bind.keys' Feb 22 22:47:49 xvii named[1696]: using default UDP/IPv4 port range: [1024, 65535] Feb 22 22:47:49 xvii named[1696]: using default UDP/IPv6 port range: [1024, 65535] Feb 22 22:47:49 xvii named[1696]: set up managed keys zone for view _default, file 'managed-keys.bind' Feb 22 22:47:49 xvii named[1696]: reloading configuration succeeded Feb 22 22:47:49 xvii named[1696]: any newly configured zones are now loaded Feb 22 22:47:49 xvii dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1 Feb 22 22:47:49 xvii dhclient: Copyright 2004-2010 Internet Systems Consortium. Feb 22 22:47:49 xvii dhclient: All rights reserved. Feb 22 22:47:49 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/ Feb 22 22:47:49 xvii dhclient: Feb 22 22:47:49 xvii dhclient: Listening on LPF/eth0/00:24:e8:97:5f:73 Feb 22 22:47:49 xvii dhclient: Sending on LPF/eth0/00:24:e8:97:5f:73 Feb 22 22:47:49 xvii dhclient: Sending on Socket/fallback Feb 22 22:47:49 xvii dhclient: DHCPRELEASE on eth0 to 192.168.0.1 port 67 Feb 22 22:47:49 xvii dhclient: send_packet: Network is unreachable Feb 22 22:47:49 xvii dhclient: send_packet: please consult README file regarding broadcast address. Feb 22 22:47:50 xvii kernel: [20782.481652] e1000e 0000:00:19.0: irq 28 for MSI/MSI-X Feb 22 22:47:50 xvii kernel: [20782.536385] e1000e 0000:00:19.0: irq 28 for MSI/MSI-X Feb 22 22:47:50 xvii kernel: [20782.538005] ADDRCONF(NETDEV_UP): eth0: link is not ready Feb 22 22:47:50 xvii logger: wicd script preconnect/dhcp-conf-bind wireless Etoile 1 00:A0:C5:CA:7B:AA Feb 22 22:47:50 xvii logger: [dhcp-conf-bind] use bind: no Feb 22 22:47:50 xvii logger: wicd script preconnect/exim4-conf wireless Etoile 1 00:A0:C5:CA:7B:AA Feb 22 22:47:50 xvii logger: reconf-exim4 Feb 22 22:47:50 xvii dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1 Feb 22 22:47:50 xvii dhclient: Copyright 2004-2010 Internet Systems Consortium. Feb 22 22:47:50 xvii dhclient: All rights reserved. Feb 22 22:47:50 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/ Feb 22 22:47:50 xvii dhclient: Feb 22 22:47:50 xvii dhclient: Listening on LPF/wlan0/00:21:6a:47:b3:80 Feb 22 22:47:50 xvii dhclient: Sending on LPF/wlan0/00:21:6a:47:b3:80 Feb 22 22:47:50 xvii dhclient: Sending on Socket/fallback Feb 22 22:47:50 xvii dhclient: DHCPRELEASE on wlan0 to 192.168.1.1 port 67 Feb 22 22:47:50 xvii dhclient: send_packet: Network is unreachable Feb 22 22:47:50 xvii dhclient: send_packet: please consult README file regarding broadcast address. Feb 22 22:47:50 xvii kernel: [20782.882341] Registered led device: iwl-phy0::radio Feb 22 22:47:50 xvii kernel: [20782.882389] Registered led device: iwl-phy0::assoc Feb 22 22:47:50 xvii kernel: [20782.882434] Registered led device: iwl-phy0::RX Feb 22 22:47:50 xvii kernel: [20782.882475] Registered led device: iwl-phy0::TX Feb 22 22:47:50 xvii kernel: [20782.898706] ADDRCONF(NETDEV_UP): wlan0: link is not ready Feb 22 22:47:52 xvii kernel: [20784.985556] wlan0: direct probe to AP 00:a0:c5:ca:7b:aa (try 1) Feb 22 22:47:52 xvii kernel: [20784.993292] wlan0: deauthenticating from 00:a0:c5:ca:7b:aa by local choice (reason=3) Feb 22 22:47:52 xvii kernel: [20784.993356] wlan0: direct probe to AP 00:a0:c5:ca:7b:aa (try 1) Feb 22 22:47:52 xvii kernel: [20785.028062] wlan0: direct probe responded Feb 22 22:47:52 xvii kernel: [20785.028073] wlan0: authenticate with AP 00:a0:c5:ca:7b:aa (try 1) Feb 22 22:47:52 xvii kernel: [20785.080957] wlan0: deauthenticating from 00:a0:c5:ca:7b:aa by local choice (reason=3) Feb 22 22:47:52 xvii kernel: [20785.081063] wlan0: direct probe to AP 00:a0:c5:ca:7b:aa (try 1) Feb 22 22:47:52 xvii kernel: [20785.254704] wlan0: direct probe responded Feb 22 22:47:52 xvii kernel: [20785.254714] wlan0: authenticate with AP 00:a0:c5:ca:7b:aa (try 1) Feb 22 22:47:53 xvii kernel: [20785.364148] wlan0: authenticated Feb 22 22:47:53 xvii kernel: [20785.364233] wlan0: associate with AP 00:a0:c5:ca:7b:aa (try 1) Feb 22 22:47:53 xvii kernel: [20785.371018] wlan0: RX AssocResp from 00:a0:c5:ca:7b:aa (capab=0x411 status=0 aid=8) Feb 22 22:47:53 xvii kernel: [20785.371026] wlan0: associated Feb 22 22:47:53 xvii kernel: [20785.376079] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Feb 22 22:47:53 xvii dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1 Feb 22 22:47:53 xvii dhclient: Copyright 2004-2010 Internet Systems Consortium. Feb 22 22:47:53 xvii dhclient: All rights reserved. Feb 22 22:47:53 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/ Feb 22 22:47:53 xvii dhclient: Feb 22 22:47:53 xvii dhclient: Listening on LPF/wlan0/00:21:6a:47:b3:80 Feb 22 22:47:53 xvii dhclient: Sending on LPF/wlan0/00:21:6a:47:b3:80 Feb 22 22:47:53 xvii dhclient: Sending on Socket/fallback Feb 22 22:47:54 xvii avahi-daemon[1845]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::221:6aff:fe47:b380. Feb 22 22:47:54 xvii avahi-daemon[1845]: New relevant interface wlan0.IPv6 for mDNS. Feb 22 22:47:54 xvii avahi-daemon[1845]: Registering new address record for fe80::221:6aff:fe47:b380 on wlan0.*. Feb 22 22:47:55 xvii dhclient: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 7 Feb 22 22:47:55 xvii ntpd[27418]: Deleting interface #2 wlan0, 192.168.1.23#123, interface stats: received=177, sent=183, dropped=1, active_time=3901 secs Feb 22 22:47:55 xvii ntpd[27418]: 213.165.70.204 interface 192.168.1.23 -> (null) Feb 22 22:47:55 xvii ntpd[27418]: 188.165.211.42 interface 192.168.1.23 -> (null) Feb 22 22:47:55 xvii ntpd[27418]: 62.210.226.88 interface 192.168.1.23 -> (null) Feb 22 22:47:55 xvii ntpd[27418]: 91.121.45.45 interface 192.168.1.23 -> (null) Feb 22 22:47:57 xvii dhclient: DHCPOFFER from 192.168.1.1 Feb 22 22:47:57 xvii dhclient: DHCPREQUEST on wlan0 to 255.255.255.255 port 67 Feb 22 22:47:57 xvii dhclient: DHCPACK from 192.168.1.1 Feb 22 22:47:57 xvii avahi-daemon[1845]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.24. Feb 22 22:47:57 xvii avahi-daemon[1845]: New relevant interface wlan0.IPv4 for mDNS. Feb 22 22:47:57 xvii avahi-daemon[1845]: Registering new address record for 192.168.1.24 on wlan0.IPv4. Feb 22 22:47:57 xvii dhclient: bound to 192.168.1.24 -- renewal in 39668 seconds. Feb 22 22:47:57 xvii named[1696]: received control channel command 'reconfig' Feb 22 22:47:57 xvii named[1696]: loading configuration from '/etc/bind/named.conf' Feb 22 22:47:57 xvii named[1696]: reading built-in trusted keys from file '/etc/bind/bind.keys' Feb 22 22:47:57 xvii named[1696]: using default UDP/IPv4 port range: [1024, 65535] Feb 22 22:47:57 xvii named[1696]: using default UDP/IPv6 port range: [1024, 65535] Feb 22 22:47:57 xvii named[1696]: listening on IPv4 interface wlan0, 192.168.1.24#53 Feb 22 22:47:57 xvii named[1696]: set up managed keys zone for view _default, file 'managed-keys.bind' Feb 22 22:47:57 xvii named[1696]: reloading configuration succeeded Feb 22 22:47:57 xvii named[1696]: any newly configured zones are now loaded Feb 22 22:48:03 xvii kernel: [20795.916039] wlan0: no IPv6 routers present Feb 22 22:50:50 xvii home_net: (root) MAC address for 192.168.0.1 is unknown Feb 22 22:50:50 xvii home_net: (root) homenet = no Feb 22 22:50:50 xvii logger: restart ntpd Feb 22 22:50:50 xvii ntpd[27418]: ntpd exiting on signal 15 Feb 22 22:50:52 xvii ntpd[10324]: ntpd 4.2.6p2@1.2194-o Sun Oct 17 13:35:13 UTC 2010 (1) Feb 22 22:50:52 xvii ntpd[10325]: proto: precision = 0.768 usec Feb 22 22:50:52 xvii ntpd[10325]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Feb 22 22:50:52 xvii ntpd[10325]: Listen normally on 1 lo 127.0.0.1 UDP 123 Feb 22 22:50:52 xvii ntpd[10325]: Listen normally on 2 wlan0 192.168.1.24 UDP 123 Feb 22 22:50:52 xvii ntpd[10325]: restrict: error in address '::' on line 38. Ignoring... Feb 22 22:50:52 xvii ntpd[10325]: restrict: error in address '::1' on line 42. Ignoring... Feb 22 22:50:52 xvii logger: ntpd restarted Feb 22 22:50:52 xvii logger: wicd script postconnect/default wireless Etoile 1 00:A0:C5:CA:7B:AA Feb 22 22:50:52 xvii ntpd[10325]: ntpd exiting on signal 15 Feb 22 22:51:05 xvii ntpdate[10334]: adjust time server 81.25.192.148 offset -0.167211 sec Feb 22 22:51:09 xvii ntpd[10448]: ntpd 4.2.6p2@1.2194-o Sun Oct 17 13:35:13 UTC 2010 (1) Feb 22 22:51:09 xvii ntpd[10449]: proto: precision = 0.698 usec Feb 22 22:51:09 xvii ntpd[10449]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Feb 22 22:51:09 xvii ntpd[10449]: Listen normally on 1 lo 127.0.0.1 UDP 123 Feb 22 22:51:09 xvii ntpd[10449]: Listen normally on 2 wlan0 192.168.1.24 UDP 123 Feb 22 22:51:09 xvii ntpd[10449]: restrict: error in address '::' on line 38. Ignoring... Feb 22 22:51:09 xvii ntpd[10449]: restrict: error in address '::1' on line 42. Ignoring... Feb 22 22:51:09 xvii home_net: (root) MAC address for 192.168.0.1 is unknown Feb 22 22:51:09 xvii home_net: (root) homenet = no It's quite strange because it seems that wicd runs additional scripts, perhaps from the /etc/network/if-up.d directory, but this isn't documented. -- System Information: Debian Release: wheezy/sid APT prefers unstable APT policy: (500, 'unstable'), (500, 'testing'), (500, 'stable'), (1, 'experimental') Architecture: amd64 (x86_64) Kernel: Linux 2.6.32-5-amd64 (SMP w/2 CPU cores) Locale: LANG=POSIX, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8) Shell: /bin/sh linked to /bin/dash Versions of packages wicd depends on: ii wicd-daemon 1.7.0+ds1-6 wired and wireless network manager ii wicd-gtk [wicd-client] 1.7.0+ds1-6 wired and wireless network manager wicd recommends no packages. wicd suggests no packages. Versions of packages wicd-gtk depends on: ii python 2.6.6-3+squeeze5 interactive high-level object-orie ii python-glade2 2.17.0-4 GTK+ bindings: Glade support ii python-gtk2 2.17.0-4 Python bindings for the GTK+ widge ii wicd-daemon 1.7.0+ds1-6 wired and wireless network manager Versions of packages wicd-daemon depends on: ii adduser 3.112+nmu2 add and remove users and groups ii dbus 1.4.6-1 simple interprocess messaging syst ii debconf [debconf-2.0] 1.5.38 Debian configuration management sy ii ethtool 1:2.6.37-1 display or change Ethernet device ii iproute 20110107-2 networking and traffic control too ii iputils-ping 3:20100418-3 Tools to test the reachability of ii isc-dhcp-client [dhcp 4.1.1-P1-16 ISC DHCP client ii lsb-base 3.2-27 Linux Standard Base 3.2 init scrip ii net-tools 1.60-23 The NET-3 networking toolkit ii psmisc 22.13-1 utilities that use the proc file s ii python 2.6.6-3+squeeze5 interactive high-level object-orie ii python-dbus 0.83.1-1 simple interprocess messaging syst ii python-gobject 2.21.4+is.2.21.3-1 Python bindings for the GObject li ii python-wicd 1.7.0+ds1-6 wired and wireless network manager ii wireless-tools 30~pre9-5 Tools for manipulating Linux Wirel ii wpasupplicant 0.6.10-2.1 client support for WPA and WPA2 (I Versions of packages python-wicd depends on: ii python 2.6.6-3+squeeze5 interactive high-level object-orie ii python-support 1.0.11 automated rebuilding support for P -- debconf information: * wicd/users: vinc17 * wicd/users: vinc17 -- To UNSUBSCRIBE, email to debian-bugs-dist-requ...@lists.debian.org with a subject of "unsubscribe". Trouble? Contact listmas...@lists.debian.org
