Re: Problems with p55-express and sleeping usb-hds

From: Alexander Holler
Date: Fri Nov 13 2009 - 21:51:14 EST


Hello,

Am 14.11.2009 00:35, schrieb Alexander Holler:
Am 14.11.2009 00:30, schrieb Oliver Neukum:
Am Freitag, 13. November 2009 23:30:14 schrieb Alexander Holler:
(And here comes the problem)

Nov 13 19:47:34 krabat kernel: [ 2655.981980] usb 9-7: reset high speed
USB device using ehci_hcd and address 3
Nov 13 19:47:39 krabat kernel: [ 2661.120397] usb 9-7: device firmware
changed

You cut too early. This shows a failed reset but not the cause.

There are no relevant messages in the log before:

--------
Nov 13 19:06:23 krabat kernel: [ 190.790549] EXT4-fs (dm-1): mounted
filesystem with ordered data mode
Nov 13 19:08:05 krabat ntpd[3381]: synchronized to 192.168.207.1, stratum 2
Nov 13 19:08:05 krabat ntpd[3381]: kernel time sync status change 2001
Nov 13 19:47:34 krabat kernel: [ 2655.981980] usb 9-7: reset high speed
USB device using ehci_hcd and address 3
----------------------------------------------------

it might be that my assumption is totally wrong an the usb-disconnect is unrelated to the time the usb-drives are going to sleep. What I have done inbetween that mount and the disconnect was a rsync -c -n (dry-run) between two of those usb-discs (I'm using them for backups). Both drives are containing the same files, some large (up to 5-10 gb) and small files. Looking at the leds of those usb-drives I just assumed the problem happens when one of those was inactive for some time (because rsync computes the hash of one of the large files on the other drive).
In the excerpt of the log I've posted before, I've cut out the disconnect of the other drive, which has happend almost at the same time (and with the same consequences) as the one shown. In fact I've cut out one line from the log (sorry for that):
---------
Nov 13 19:08:05 krabat ntpd[3381]: kernel time sync status change 2001
Nov 13 19:47:34 krabat kernel: [ 2655.981980] usb 9-7: reset high speed USB device using ehci_hcd and address 3
Nov 13 19:47:35 krabat kernel: [ 2656.233278] usb 9-1: reset high speed USB device using ehci_hcd and address 4
Nov 13 19:47:39 krabat kernel: [ 2661.120397] usb 9-7: device firmware changed
Nov 13 19:47:39 krabat kernel: [ 2661.120489] usb 9-7: USB disconnect, address 3
---------
Afterwards the messages for the second drive where the same as for the one I've posted.

This is reproducible, at least through starting the rsync. I will turn on some debugging knobs and will see if I can see some more hints in the resulting logs.

Kind regards,

Alexander Holler
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/