Bug#409349: Bug #409349, similar symptoms, different kernel location cited in error messages.
I've recently begun seeing this, nearly daily, since October 16th.
Beginning October 16, 2009, I've been seeing the same symptoms of (1)
USB connection light on the hub goes out, (2) syslog and dmesg messages
as cited below, (3) some processes hanging on exit, (4) system load
rises, and (5) during system shutdown and reboot, those hung processes
are called out on the console screen.
I've run "testing" for years, updating roughly daily. Currently this is
squeeze, with the last month or so on the 2.6.30-2-486.
I've been running apcupsd since September 6, 2009, well before
experiencing the problem.
I upgraded to 2.6.30-2-486 on October 12, 2009, four days before this
problem, but given the daily problem (at different times of day though),
the kernel upgrade is probably not it.
While I've been composing this message, the problem has recurred!
Stopping and starting apcupsd did NOT resolve things.
Disconnecting and reconnecting the USB-connected UPS didn't help.
System load is soaring now and it looks like I'll have to reboot again. :-(
(I usually go 10-30 days between reboots before this problem, not 20 to
30 hours! Perhaps I should revert to the old kernel, just to see if the
previous kernel is similarly afflicted.)
Here are dmesg excerpts from the instance, yesterday. Today's is
following the pattern, right down to the copy of "screen" I just exited
not cleanly exiting. I'll add an excerpt from today's failure to the
end of things here.
[ 5.952538] usb 1-1: New USB device found, idVendor=050d, idProduct=0234
[ 5.952552] usb 1-1: New USB device strings: Mfr=0, Product=0,
SerialNumber=0
[ 5.952817] usb 1-1: configuration #1 chosen from 1 choice
[ 5.952954] hub 1-1:1.0: USB hub found
[ 5.953116] hub 1-1:1.0: 4 ports detected
[ 6.224175] usb 1-1.1: new low speed USB device using ehci_hcd and
address 3
[ 6.481945] usb 1-1.1: New USB device found, idVendor=051d,
idProduct=0002
[ 6.481960] usb 1-1.1: New USB device strings: Mfr=3, Product=1,
SerialNumber
=2
[ 6.481972] usb 1-1.1: Product: Back-UPS RS 1500 FW:8.g9 .D USB FW:g9
[ 6.481982] usb 1-1.1: Manufacturer: American Power Conversion
[ 6.481990] usb 1-1.1: SerialNumber: JB0550031350
[ 6.482280] usb 1-1.1: configuration #1 chosen from 1 choice
[ 6.581967] usbcore: registered new interface driver hiddev
[ 7.655017] generic-usb 0003:051D:0002.0001: hiddev96,hidraw0: USB
HID v1.10
Device [American Power Conversion Back-UPS RS 1500 FW:8.g9 .D USB FW:g9
] on usb
-0000:00:10.3-1.1/input0
[ 7.655081] usbcore: registered new interface driver usbhid
[ 7.655092] usbhid: v2.6:USB HID core driver
*** hours later ***
[ 7746.876222] usb 1-1.1: reset low speed USB device using ehci_hcd and
address 3
[27906.876241] usb 1-1.1: reset low speed USB device using ehci_hcd and
address 3
[62107.076218] usb 1-1.1: reset low speed USB device using ehci_hcd and
address 3
[98040.872229] usb 1-1.1: reset low speed USB device using ehci_hcd and
address 3
*** after many hours of the above intermittent messages, things change ***
[102901.876216] usb 1-1.1: reset low speed USB device using ehci_hcd and
address 3
[103026.068186] usb 1-1.1: reset low speed USB device using ehci_hcd and
address 3
[103273.276315] usb 1-1.1: reset low speed USB device using ehci_hcd and
address 3
[103521.072257] usb 1-1.1: reset low speed USB device using ehci_hcd and
address 3
[103536.332233] usb 1-1.1: device descriptor read/64, error -110
[103551.696322] usb 1-1.1: device descriptor read/64, error -110
[103551.800358]
/build/buildd-linux-2.6_2.6.30-8-i386-Tm0Lt9/linux-2.6-2.6.30/debian/build/source_i386_none/drivers/hid/usbhid/hid-core.c:
usb_submit_urb(ctrl) failed
[103551.840721]
/build/buildd-linux-2.6_2.6.30-8-i386-Tm0Lt9/linux-2.6-2.6.30/debian/build/source_i386_none/drivers/hid/usbhid/hid-core.c:
usb_submit_urb(ctrl) failed
[103551.860883]
/build/buildd-linux-2.6_2.6.30-8-i386-Tm0Lt9/linux-2.6-2.6.30/debian/build/source_i386_none/drivers/hid/usbhid/hid-core.c:
usb_submit_urb(ctrl) failed
*** and later still ***
[104580.274392]
/build/buildd-linux-2.6_2.6.30-8-i386-Tm0Lt9/linux-2.6-2.6.30/de
bian/build/source_i386_none/drivers/hid/usbhid/hid-core.c:
usb_submit_urb(ctrl) failed
[104580.294554]
/build/buildd-linux-2.6_2.6.30-8-i386-Tm0Lt9/linux-2.6-2.6.30/debian/build/source_i386_none/drivers/hid/usbhid/hid-core.c:
usb_submit_urb(ctrl) failed
[104580.314732]
/build/buildd-linux-2.6_2.6.30-8-i386-Tm0Lt9/linux-2.6-2.6.30/debian/build/source_i386_none/drivers/hid/usbhid/hid-core.c:
usb_submit_urb(ctrl) failed
[104580.334912] generic-usb 0003:051D:0002.0001: control queue full
[104580.375237] generic-usb 0003:051D:0002.0001: control queue full
[104580.395388] generic-usb 0003:051D:0002.0001: control queue full
[104580.415573] generic-usb 0003:051D:0002.0001: control queue full
[104640.472154] generic-usb 0003:051D:0002.0001: control queue full
[104640.512395] generic-usb 0003:051D:0002.0001: control queue full
[104640.552639] generic-usb 0003:051D:0002.0001: control queue full
[104640.633104] generic-usb 0003:051D:0002.0001: control queue full
******* The updates applied over the 3 days before the problem symptoms
emerged ******
***[on 20091014]***
The following packages have been kept back:
sysv-rc
The following packages will be upgraded:
gnome-codec-install gnumeric gnumeric-common libkeyutils1
libparams-validate-perl libpci3 libpda-pilot-perl libperl5.10 libpisock9
libpisync1 libschroedinger-1.0-0 libusb-1.0-0 pciutils perl perl-base
perl-doc perl-modules pilot-link python-support spamassassin spamc
***[on 20091015]***
The following packages have been kept back:
consolekit sysv-rc
The following packages will be upgraded:
file gnome-volume-manager libck-connector0 libglibmm-2.4-1c2a libidl0
libmagic1 liborbit2 libpam-ck-connector libpangomm-1.4-1 libwavpack1
traceroute
*** and also ***
The following extra packages will be installed:
libeggdbus-1-0 libpolkit-gobject-1-0
The following NEW packages will be installed:
libeggdbus-1-0 libpolkit-gobject-1-0
The following packages will be upgraded:
consolekit
*** and also ***
The following packages will be REMOVED:
libbluetooth2 postgresql-doc-8.3
***[on 20091016]***
The following packages have been kept back:
sysv-rc
The following packages will be upgraded:
gappletviewer-4.3 gcalctool gcj-4.3 gcj-4.3-base gij-4.3 gok gtk2-engines
gtk2-engines-pixbuf libbonoboui2-0 libbonoboui2-common libgail-common
libgail-dbg libgail18 libgcj9-0 libgcj9-0-awt libgcj9-dbg libgcj9-dev
libgcj9-jar libgcj9-src libgtk2-perl libgtk2.0-0 libgtk2.0-0-dbg
libgtk2.0-bin libgtk2.0-common libgtkmm-2.4-1c2a libmetacity-private0
metacity metacity-common
*** and also ***
The following NEW packages will be installed:
insserv
The following packages will be upgraded:
sysv-rc
*****************************
*** Today's failure, excerpt related to blocked task ***
[61774.061876] generic-usb 0003:051D:0002.0001: control queue full
[61801.312193] INFO: task screen:2601 blocked for more than 120 seconds.
[61801.312209] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
disables this
message.
[61801.312221] screen D 00000287 0 2601 2600
[61801.312239] dfd3b800 00000082 00000000 00000287 dfd3b9b4 45b9a180
0000380e f
8423785
[61801.312263] f6c2bd38 f6c2bdc8 f1ee68a0 f70c6c00 dfe11eb0 7fffffff
00000000 00000002
[61801.312286] c0305b7c c0305c87 f7329009 e02a6244 c0188f34 f6c92894
00000001 00002331
[61801.312308] Call Trace:
[61801.312408] [<f8423785>] ? journal_stop+0x200/0x20c [jbd]
[61801.312429] [<c0305b7c>] ? schedule+0x5/0x13
[61801.312444] [<c0305c87>] ? schedule_timeout+0x14/0xb3
[61801.312468] [<c0188f34>] ? __d_lookup+0x93/0xc1
[61801.312485] [<c030574a>] ? wait_for_common+0x9e/0xf2
[61801.312507] [<c011a218>] ? default_wake_function+0x0/0x8
[61801.312526] [<c012a0e1>] ? flush_cpu_workqueue+0x55/0x5d
[61801.312542] [<c012a2a1>] ? wq_barrier_func+0x0/0x8
[61801.312563] [<c0242c82>] ? tty_ldisc_release+0x33/0x187
[61801.312578] [<c023e136>] ? do_tty_hangup+0x2d9/0x2f8
[61801.312593] [<c023f2e4>] ? tty_release_dev+0x35f/0x382
[61801.312613] [<c018aae6>] ? notify_change+0x15b/0x289
[61801.312628] [<c023f30e>] ? tty_release+0x7/0xa
[61801.312647] [<c017d384>] ? __fput+0xa4/0x152
[61801.312661] [<c017ac1e>] ? filp_close+0x4e/0x54
[61801.312675] [<c017ac70>] ? sys_close+0x4c/0x7a
[61801.312696] [<c0102ff5>] ? syscall_call+0x7/0xb
[61834.120120] generic-usb 0003:051D:0002.0001: control queue full
*****************************
Reply to: