Debian Bug report logs - #637267
suspend takes time due to /usr/share/wicd/daemon/suspend.py

version graph

Package: wicd; Maintainer for wicd is David Paleino <dapal@debian.org>; Source for wicd is src:wicd.

Reported by: Vincent Lefevre <vincent@vinc17.net>

Date: Wed, 10 Aug 2011 00:39:13 UTC

Severity: important

Found in versions wicd/1.7.2.4-2, wicd/1.7.0+ds1-6

Reply or subscribe to this bug.

Toggle useless messages

View this report as an mbox folder, status mbox, maintainer mbox


Report forwarded to debian-bugs-dist@lists.debian.org, David Paleino <dapal@debian.org>:
Bug#637267; Package wicd. (Wed, 10 Aug 2011 00:39:15 GMT) Full text and rfc822 format available.

Acknowledgement sent to Vincent Lefevre <vincent@vinc17.net>:
New Bug report received and forwarded. Copy sent to David Paleino <dapal@debian.org>. (Wed, 10 Aug 2011 00:39:16 GMT) Full text and rfc822 format available.

Message #5 received at submit@bugs.debian.org (full text, mbox):

From: Vincent Lefevre <vincent@vinc17.net>
To: Debian Bug Tracking System <submit@bugs.debian.org>
Subject: suspend fails because of wicd and dhclient
Date: Wed, 10 Aug 2011 02:38:24 +0200
Package: wicd
Version: 1.7.0+ds1-6
Severity: normal

When using wicd with a wifi connection using the DHCP client dhclient,
making my Debian laptop DELL Latitude E6400 sleep (suspend) sometimes
fail. The wicd option "Automatically connect to this network" may be
the cause. The logs show that after initiating the suspend procedure,
wicd starts dhclient every 10 seconds.

For the first two times, the screen was cleared with only one message
on it (I always get a message, so that I think it's unrelated), but
the hard disk and the screen were still on. The only thing I could do
was to switch off the machine. Concerning the second time, Fn-F1 (to
put the machine into sleep) didn't seem to have any effect, except
that the wicd client was not responding any more: its window was just
grey. Immediately after I killed its window, the screen got cleared,
as described above.

For the third time, the screen wasn't cleared, and I had the idea to
disable the radio (wifi...) with the switch that is on the right side
of my laptop, and the suspend procedure could terminate as expected.

/var/log/wicd/wicd.log (logs of the wicd daemon):

2011/08/09 04:52:58 :: attempting to set hostname with dhclient
2011/08/09 04:52:58 :: using dhcpcd or another supported client may work better
2011/08/09 04:53:24 :: attempting to set hostname with dhclient
2011/08/09 04:53:24 :: using dhcpcd or another supported client may work better
2011/08/09 04:53:35 :: attempting to set hostname with dhclient
2011/08/09 04:53:35 :: using dhcpcd or another supported client may work better
2011/08/09 04:53:45 :: attempting to set hostname with dhclient
2011/08/09 04:53:45 :: using dhcpcd or another supported client may work better
2011/08/09 04:53:55 :: attempting to set hostname with dhclient
2011/08/09 04:53:55 :: using dhcpcd or another supported client may work better

The kernel and dhclient logs from the /var/log/syslog file:

Aug  9 04:52:59 xvii dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Aug  9 04:52:59 xvii dhclient: Copyright 2004-2010 Internet Systems Consortium.
Aug  9 04:52:59 xvii dhclient: All rights reserved.
Aug  9 04:52:59 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/
Aug  9 04:52:59 xvii dhclient: 
Aug  9 04:52:59 xvii dhclient: Listening on LPF/wlan0/00:21:6a:47:b3:80
Aug  9 04:52:59 xvii dhclient: Sending on   LPF/wlan0/00:21:6a:47:b3:80
Aug  9 04:52:59 xvii dhclient: Sending on   Socket/fallback
Aug  9 04:52:59 xvii dhclient: DHCPRELEASE on wlan0 to 192.168.0.1 port 67
Aug  9 04:52:59 xvii kernel: [61476.509155] wlan0: deauthenticating from 00:1f:33:89:73:4e by local choice (reason=3)
Aug  9 04:52:59 xvii kernel: [61476.538578] cfg80211: Calling CRDA to update world regulatory domain
Aug  9 04:52:59 xvii kernel: [61476.656275] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug  9 04:53:02 xvii kernel: [61479.780263] wlan0: authenticate with 00:1f:33:89:73:4e (try 1)
Aug  9 04:53:02 xvii kernel: [61479.783028] wlan0: authenticated
Aug  9 04:53:02 xvii kernel: [61479.807677] wlan0: associate with 00:1f:33:89:73:4e (try 1)
Aug  9 04:53:02 xvii kernel: [61479.811356] wlan0: RX AssocResp from 00:1f:33:89:73:4e (capab=0x411 status=0 aid=1)
Aug  9 04:53:02 xvii kernel: [61479.811365] wlan0: associated
Aug  9 04:53:02 xvii kernel: [61479.819541] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Aug  9 04:53:09 xvii kernel: [61486.991308] wlan0: deauthenticating from 00:1f:33:89:73:4e by local choice (reason=3)
Aug  9 04:53:09 xvii kernel: [61487.021997] cfg80211: Calling CRDA to update world regulatory domain
Aug  9 04:53:24 xvii dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Aug  9 04:53:24 xvii dhclient: Copyright 2004-2010 Internet Systems Consortium.
Aug  9 04:53:24 xvii dhclient: All rights reserved.
Aug  9 04:53:24 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/
Aug  9 04:53:24 xvii dhclient: 
Aug  9 04:53:24 xvii dhclient: Listening on LPF/wlan0/00:21:6a:47:b3:80
Aug  9 04:53:24 xvii dhclient: Sending on   LPF/wlan0/00:21:6a:47:b3:80
Aug  9 04:53:24 xvii dhclient: Sending on   Socket/fallback
Aug  9 04:53:24 xvii dhclient: DHCPRELEASE on wlan0 to 192.168.0.1 port 67
Aug  9 04:53:24 xvii dhclient: send_packet: Network is unreachable
Aug  9 04:53:24 xvii dhclient: send_packet: please consult README file regarding broadcast address.
Aug  9 04:53:24 xvii kernel: [61502.020551] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug  9 04:53:25 xvii kernel: [61502.199112] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug  9 04:53:35 xvii dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Aug  9 04:53:35 xvii dhclient: Copyright 2004-2010 Internet Systems Consortium.
Aug  9 04:53:35 xvii dhclient: All rights reserved.
Aug  9 04:53:35 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/
Aug  9 04:53:35 xvii dhclient: 
Aug  9 04:53:35 xvii dhclient: Listening on LPF/eth0/00:24:e8:97:5f:73
Aug  9 04:53:35 xvii dhclient: Sending on   LPF/eth0/00:24:e8:97:5f:73
Aug  9 04:53:35 xvii dhclient: Sending on   Socket/fallback
Aug  9 04:53:35 xvii dhclient: DHCPRELEASE on eth0 to 192.168.0.1 port 67
Aug  9 04:53:35 xvii dhclient: send_packet: Network is unreachable
Aug  9 04:53:35 xvii dhclient: send_packet: please consult README file regarding broadcast address.
Aug  9 04:53:35 xvii kernel: [61512.584909] e1000e 0000:00:19.0: irq 44 for MSI/MSI-X
Aug  9 04:53:35 xvii kernel: [61512.640416] e1000e 0000:00:19.0: irq 44 for MSI/MSI-X
Aug  9 04:53:35 xvii kernel: [61512.641803] ADDRCONF(NETDEV_UP): eth0: link is not ready
Aug  9 04:53:45 xvii dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Aug  9 04:53:45 xvii dhclient: Copyright 2004-2010 Internet Systems Consortium.
Aug  9 04:53:45 xvii dhclient: All rights reserved.
Aug  9 04:53:45 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/
Aug  9 04:53:45 xvii dhclient: 
Aug  9 04:53:45 xvii dhclient: Listening on LPF/wlan0/00:21:6a:47:b3:80
Aug  9 04:53:45 xvii dhclient: Sending on   LPF/wlan0/00:21:6a:47:b3:80
Aug  9 04:53:45 xvii dhclient: Sending on   Socket/fallback
Aug  9 04:53:45 xvii dhclient: DHCPRELEASE on wlan0 to 192.168.0.1 port 67
Aug  9 04:53:45 xvii dhclient: send_packet: Network is unreachable
Aug  9 04:53:45 xvii dhclient: send_packet: please consult README file regarding broadcast address.
Aug  9 04:53:45 xvii kernel: [61523.051304] ADDRCONF(NETDEV_UP): wlan0: link is not ready
Aug  9 04:53:55 xvii dhclient: Internet Systems Consortium DHCP Client 4.1.1-P1
Aug  9 04:53:55 xvii dhclient: Copyright 2004-2010 Internet Systems Consortium.
Aug  9 04:53:55 xvii dhclient: All rights reserved.
Aug  9 04:53:55 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/
Aug  9 04:53:55 xvii dhclient: 
Aug  9 04:53:56 xvii dhclient: Listening on LPF/eth0/00:24:e8:97:5f:73
Aug  9 04:53:56 xvii dhclient: Sending on   LPF/eth0/00:24:e8:97:5f:73
Aug  9 04:53:56 xvii dhclient: Sending on   Socket/fallback
Aug  9 04:53:56 xvii dhclient: DHCPRELEASE on eth0 to 192.168.0.1 port 67
Aug  9 04:53:56 xvii dhclient: send_packet: Network is unreachable
Aug  9 04:53:56 xvii dhclient: send_packet: please consult README file regarding broadcast address.
Aug  9 04:53:56 xvii kernel: [61533.435317] e1000e 0000:00:19.0: irq 44 for MSI/MSI-X
Aug  9 04:53:56 xvii kernel: [61533.488409] e1000e 0000:00:19.0: irq 44 for MSI/MSI-X
Aug  9 04:53:56 xvii kernel: [61533.489776] ADDRCONF(NETDEV_UP): eth0: link is not ready
Aug  9 04:54:03 xvii kernel: [61541.086891] iwlagn 0000:0c:00.0: RF_KILL bit toggled to disable radio.
Aug  9 04:54:03 xvii kernel: [61541.086913] keyboard: can't emulate rawmode for keycode 240
Aug  9 04:54:03 xvii kernel: [61541.086943] keyboard: can't emulate rawmode for keycode 240
Aug  9 04:54:03 xvii kernel: [61541.104141] usb 3-1: USB disconnect, device number 2
Aug  9 04:54:03 xvii kernel: [61541.104149] usb 3-1.1: USB disconnect, device number 3
Aug  9 04:54:03 xvii kernel: [61541.104258] iwlagn 0000:0c:00.0: Not sending command - RF KILL
Aug  9 04:54:03 xvii kernel: [61541.104267] iwlagn 0000:0c:00.0: Error sending REPLY_RXON: enqueue_hcmd failed: -5
Aug  9 04:54:03 xvii kernel: [61541.104275] iwlagn 0000:0c:00.0: Error clearing ASSOC_MSK on BSS (-5)
Aug  9 04:54:03 xvii kernel: [61541.104829] btusb_intr_complete: hci0 urb ffff880119c762c0 failed to resubmit (19)
Aug  9 04:54:03 xvii kernel: [61541.104847] btusb_bulk_complete: hci0 urb ffff880119c76440 failed to resubmit (19)
Aug  9 04:54:03 xvii kernel: [61541.105831] btusb_bulk_complete: hci0 urb ffff880119c76380 failed to resubmit (19)
Aug  9 04:54:03 xvii kernel: [61541.140120] usb 3-1.2: USB disconnect, device number 4
Aug  9 04:54:03 xvii kernel: [61541.140207] usb 3-1.3: USB disconnect, device number 5
Aug  9 04:54:03 xvii kernel: [61541.140269] btusb_send_frame: hci0 urb ffff8801182460c0 submission failed
Aug  9 04:54:06 xvii kernel: [61543.880890] PM: Syncing filesystems ... done.
Aug  9 04:54:06 xvii kernel: [61543.925182] PM: Preparing system for mem sleep

$ uname -a
Linux xvii 3.0.0-1-amd64 #1 SMP Sun Jul 24 02:24:44 UTC 2011 x86_64 GNU/Linux

-- 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 3.0.0-1-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.7-2     interactive high-level object-orie
ii  python-glade2                2.24.0-2    GTK+ bindings: Glade support
ii  python-gtk2                  2.24.0-2    Python bindings for the GTK+ widge
ii  wicd-daemon                  1.7.0+ds1-6 wired and wireless network manager

Versions of packages wicd-gtk recommends:
ii  gksu                          2.0.2-5    graphical frontend to su
ii  python-notify                 0.1.1-3    Python bindings for libnotify
ii  sudo                          1.7.4p6-1  Provide limited super user privile

Versions of packages wicd-daemon depends on:
ii  adduser                  3.113           add and remove users and groups
ii  dbus                     1.4.14-1        simple interprocess messaging syst
ii  debconf [debconf-2.0]    1.5.41          Debian configuration management sy
ii  ethtool                  1:2.6.39-1      display or change Ethernet device 
ii  iproute                  20110629-1      networking and traffic control too
ii  iputils-ping             3:20101006-1+b1 Tools to test the reachability of 
ii  isc-dhcp-client [dhcp3-c 4.1.1-P1-17     ISC DHCP client
ii  lsb-base                 3.2-27          Linux Standard Base 3.2 init scrip
ii  net-tools                1.60-24.1       The NET-3 networking toolkit
ii  psmisc                   22.14-1         utilities that use the proc file s
ii  python                   2.6.7-2         interactive high-level object-orie
ii  python-dbus              0.84.0-2        simple interprocess messaging syst
ii  python-gobject           2.28.6-4        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.7.3-3.1       client support for WPA and WPA2 (I

Versions of packages wicd-daemon recommends:
ii  wicd-gtk [wicd-client]       1.7.0+ds1-6 wired and wireless network manager

Versions of packages wicd-daemon suggests:
ii  pm-utils                      1.4.1-8    utilities and scripts for power ma

Versions of packages python-wicd depends on:
ii  python                        2.6.7-2    interactive high-level object-orie
ii  python-support                1.0.14     automated rebuilding support for P

-- debconf information excluded




Information forwarded to debian-bugs-dist@lists.debian.org, David Paleino <dapal@debian.org>:
Bug#637267; Package wicd. (Sat, 04 Aug 2012 12:33:03 GMT) Full text and rfc822 format available.

Acknowledgement sent to Vincent Lefevre <vincent@vinc17.net>:
Extra info received and forwarded to list. Copy sent to David Paleino <dapal@debian.org>. (Sat, 04 Aug 2012 12:33:03 GMT) Full text and rfc822 format available.

Message #10 received at 637267@bugs.debian.org (full text, mbox):

From: Vincent Lefevre <vincent@vinc17.net>
To: 637267@bugs.debian.org
Subject: Re: suspend fails because of wicd and dhclient
Date: Sat, 4 Aug 2012 14:30:31 +0200
severity 637267 important
found 637267 1.7.2.4-2
thanks

On 2011-08-10 02:38:24 +0200, Vincent Lefevre wrote:
> When using wicd with a wifi connection using the DHCP client dhclient,
> making my Debian laptop DELL Latitude E6400 sleep (suspend) sometimes
> fail.

This bug still occurs, even when there's no wifi connection and the
RF_KILL bit is set (radio disabled, so that wifi is not possible).
The suspend doesn't fail, but is just very slow (several dozens of
seconds, which is unacceptable).

It seems to occur only when there is no Ethernet cable plugged in
(perhaps the wicd daemon can detect it and doesn't try to do anything
in such a case?).

After a Fn-F1 to put the machine into sleep, as I was seeing that
it was not put into sleep yet, I did a ps and found that
/usr/lib/pm-utils/sleep.d/55wicd was running, with a child
/usr/share/wicd/daemon/suspend.py; the 55wicd script has:

wicd_suspend()
{
        # Put wifi interface down
        /usr/share/wicd/daemon/suspend.py 1>/dev/null 2>/dev/null
        RETVAL=$?
}

The /var/log/syslog file shows that dhclient is started several
times to do a DHCPRELEASE on wlan0 (even though wlan0 isn't up),
e.g.

Aug  4 13:55:32 xvii dhclient: Internet Systems Consortium DHCP Client 4.2.4
Aug  4 13:55:32 xvii dhclient: Copyright 2004-2012 Internet Systems Consortium.
Aug  4 13:55:32 xvii dhclient: All rights reserved.
Aug  4 13:55:32 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/
Aug  4 13:55:32 xvii dhclient: 
Aug  4 13:55:32 xvii dhclient: Listening on LPF/wlan0/00:21:6a:47:b3:80
Aug  4 13:55:32 xvii dhclient: Sending on   LPF/wlan0/00:21:6a:47:b3:80
Aug  4 13:55:32 xvii dhclient: Sending on   Socket/fallback
Aug  4 13:55:32 xvii dhclient: DHCPRELEASE on wlan0 to 192.168.0.1 port 67
Aug  4 13:55:32 xvii dhclient: send_packet: Network is unreachable
Aug  4 13:55:32 xvii dhclient: send_packet: please consult README file regarding broadcast address.
Aug  4 13:55:32 xvii dhclient: dhclient.c:2268: Failed to send 300 byte long packet over fallback interface.

and some daemons were reconfigured, again, several times (this may be
a consequence of /etc/network/if-down.d/ scripts), before the machine
entered sleep:

Aug  4 13:54:52 xvii acpid: received input layer event "button/sleep SBTN 00000080 00000000"
Aug  4 13:54:52 xvii acpid: rule from 2860[0:0] matched
Aug  4 13:54:52 xvii acpid: notifying client 2860[0:0]
Aug  4 13:54:52 xvii acpid: rule from /etc/acpi/events/sleepbtn matched
Aug  4 13:54:52 xvii acpid: executing action "/etc/acpi/sleep_suspend.sh sleep"
[...]
Aug  4 13:54:55 xvii named[2748]: received control channel command 'reconfig'
Aug  4 13:54:55 xvii postfix/master[4183]: reload -- version 2.9.3, configuration /etc/postfix
[...]
Aug  4 13:55:07 xvii named[2748]: received control channel command 'reconfig'
Aug  4 13:55:07 xvii postfix/master[4183]: reload -- version 2.9.3, configuration /etc/postfix
Aug  4 13:55:07 xvii dhclient: Internet Systems Consortium DHCP Client 4.2.4
Aug  4 13:55:07 xvii dhclient: Copyright 2004-2012 Internet Systems Consortium.
Aug  4 13:55:07 xvii dhclient: All rights reserved.
Aug  4 13:55:07 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/
Aug  4 13:55:07 xvii dhclient: 
Aug  4 13:55:07 xvii dhclient: Listening on LPF/wlan0/00:21:6a:47:b3:80
Aug  4 13:55:07 xvii dhclient: Sending on   LPF/wlan0/00:21:6a:47:b3:80
Aug  4 13:55:07 xvii dhclient: Sending on   Socket/fallback
Aug  4 13:55:08 xvii dhclient: DHCPRELEASE on wlan0 to 192.168.0.1 port 67
Aug  4 13:55:08 xvii dhclient: send_packet: Network is unreachable
Aug  4 13:55:08 xvii dhclient: send_packet: please consult README file regarding broadcast address.
Aug  4 13:55:08 xvii dhclient: dhclient.c:2268: Failed to send 300 byte long packet over fallback interface.
[...]
Aug  4 13:55:20 xvii named[2748]: received control channel command 'reconfig'
[...]
Aug  4 13:55:20 xvii postfix/master[4183]: reload -- version 2.9.3, configuration /etc/postfix
[...]
Aug  4 13:55:32 xvii named[2748]: received control channel command 'reconfig'
[...]
Aug  4 13:55:32 xvii postfix/master[4183]: reload -- version 2.9.3, configuration /etc/postfix
Aug  4 13:55:32 xvii dhclient: Internet Systems Consortium DHCP Client 4.2.4
Aug  4 13:55:32 xvii dhclient: Copyright 2004-2012 Internet Systems Consortium.
Aug  4 13:55:32 xvii dhclient: All rights reserved.
Aug  4 13:55:32 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/
Aug  4 13:55:32 xvii dhclient: 
Aug  4 13:55:32 xvii dhclient: Listening on LPF/wlan0/00:21:6a:47:b3:80
Aug  4 13:55:32 xvii dhclient: Sending on   LPF/wlan0/00:21:6a:47:b3:80
Aug  4 13:55:32 xvii dhclient: Sending on   Socket/fallback
Aug  4 13:55:32 xvii dhclient: DHCPRELEASE on wlan0 to 192.168.0.1 port 67
Aug  4 13:55:32 xvii dhclient: send_packet: Network is unreachable
Aug  4 13:55:32 xvii dhclient: send_packet: please consult README file regarding broadcast address.
Aug  4 13:55:32 xvii dhclient: dhclient.c:2268: Failed to send 300 byte long packet over fallback interface.
[...]
Aug  4 13:55:44 xvii named[2748]: received control channel command 'reconfig'
[...]
Aug  4 13:55:45 xvii postfix/master[4183]: reload -- version 2.9.3, configuration /etc/postfix
[...]
Aug  4 13:55:57 xvii kernel: [32338.056083] PM: Syncing filesystems ... done.
Aug  4 13:55:57 xvii kernel: [32338.079064] PM: Preparing system for mem sleep

-- 
Vincent Lefèvre <vincent@vinc17.net> - Web: <http://www.vinc17.net/>
100% accessible validated (X)HTML - Blog: <http://www.vinc17.net/blog/>
Work: CR INRIA - computer arithmetic / AriC project (LIP, ENS-Lyon)



Severity set to 'important' from 'normal' Request was from Vincent Lefevre <vincent@vinc17.net> to control@bugs.debian.org. (Sat, 04 Aug 2012 12:33:05 GMT) Full text and rfc822 format available.

Marked as found in versions wicd/1.7.2.4-2. Request was from Vincent Lefevre <vincent@vinc17.net> to control@bugs.debian.org. (Sat, 04 Aug 2012 12:33:05 GMT) Full text and rfc822 format available.

Changed Bug title to 'suspend takes time due to /usr/share/wicd/daemon/suspend.py' from 'suspend fails because of wicd and dhclient' Request was from Vincent Lefevre <vincent@vinc17.net> to control@bugs.debian.org. (Sat, 04 Aug 2012 12:51:02 GMT) Full text and rfc822 format available.

Send a report that this bug log contains spam.


Debian bug tracking system administrator <owner@bugs.debian.org>. Last modified: Mon Apr 21 16:51:15 2014; Machine Name: beach.debian.org

Debian Bug tracking system
Copyright (C) 1999 Darren O. Benham, 1997,2003 nCipher Corporation Ltd, 1994-97 Ian Jackson.