[Bug 88746] Re: ehci_hcd module causes I/O errors in USB 2.0 devices

[filip]

For my freecom XXS drive, i think also like mentioned in the devel-list that this is a very low level bug. If i boot from my disk it resets two times until i get to the desktop. But most of the times i doesn't get there. If i look in the logs the resets are not always on the same time. The resets already accured after disk recovery before the udev rules kick in.
If i connect the drive while running a full up to date lucid (on my usb-stick cruzer here no problem) i get this:

[  626.470346] usb 1-5: new high speed USB device using ehci_hcd and address 5
Jan 27 19:17:11 lucidext kernel: [  626.621195] usb 1-5: configuration #1 chosen from 1 choice
Jan 27 19:17:11 lucidext kernel: [  626.622687] scsi7 : SCSI emulation for USB Mass Storage devices
Jan 27 19:17:16 lucidext kernel: [  631.620682] scsi 7:0:0:0: Direct-Access     Freecom  Mobile Drive XXS      PQ: 0 ANSI: 2 CCS
Jan 27 19:17:16 lucidext kernel: [  631.621505] sd 7:0:0:0: Attached scsi generic sg3 type 0
Jan 27 19:17:16 lucidext kernel: [  631.624982] sd 7:0:0:0: [sdc] 312581808 512-byte logical blocks: (160 GB/149 GiB)
Jan 27 19:17:16 lucidext kernel: [  631.625774] sd 7:0:0:0: [sdc] Write Protect is off
Jan 27 19:17:16 lucidext kernel: [  631.627691]  sdc: sdc1 sdc2 sdc3 < sdc5 sdc6 > sdc4
Jan 27 19:17:16 lucidext kernel: [  631.696161] sd 7:0:0:0: [sdc] Attached SCSI disk
Jan 27 19:17:23 lucidext kernel: [  639.130061] usb 1-5: reset high speed USB device using ehci_hcd and address 5
Jan 27 19:17:31 lucidext kernel: [  647.130060] usb 1-5: reset high speed USB device using ehci_hcd and address 5
Jan 27 19:18:02 lucidext kernel: [  678.100318] usb 1-5: USB disconnect, address 5
Jan 27 19:18:02 lucidext kernel: [  678.100343] sd 7:0:0:0: Device offlined - not ready after error recovery
Jan 27 19:18:02 lucidext kernel: [  678.100361] sd 7:0:0:0: [sdc] Unhandled error code
Jan 27 19:18:02 lucidext kernel: [  678.100365] sd 7:0:0:0: [sdc] Result: hostbyte=DID_ABORT driverbyte=DRIVER_OK
Jan 27 19:18:02 lucidext kernel: [  678.100372] sd 7:0:0:0: [sdc] CDB: Read(10): 28 00 00 00 00 00 00 00 20 00
Jan 27 19:18:02 lucidext kernel: [  678.100397] __ratelimit: 9 callbacks suppressed
Jan 27 19:18:02 lucidext kernel: [  678.101560] sd 7:0:0:0: [sdc] Unhandled error code
Jan 27 19:18:02 lucidext kernel: [  678.101564] sd 7:0:0:0: [sdc] Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Jan 27 19:18:02 lucidext kernel: [  678.101571] sd 7:0:0:0: [sdc] CDB: Read(10): 28 00 0d f8 ba 3f 00 00 02 00
Jan 27 19:18:03 lucidext kernel: [  678.400059] usb 1-5: new high speed USB device using ehci_hcd and address 6
Jan 27 19:18:03 lucidext kernel: [  678.551204] usb 1-5: configuration #1 chosen from 1 choice
Jan 27 19:18:03 lucidext kernel: [  678.553600] scsi8 : SCSI emulation for USB Mass Storage devices
Jan 27 19:18:08 lucidext kernel: [  683.592443] scsi 8:0:0:0: Direct-Access     Freecom  Mobile Drive XXS      PQ: 0 ANSI: 2 CCS
Jan 27 19:18:08 lucidext kernel: [  683.593290] sd 8:0:0:0: Attached scsi generic sg3 type 0
Jan 27 19:18:08 lucidext kernel: [  683.664169] sd 8:0:0:0: [sdc] 312581808 512-byte logical blocks: (160 GB/149 GiB)
Jan 27 19:18:08 lucidext kernel: [  683.664905] sd 8:0:0:0: [sdc] Write Protect is off
Jan 27 19:18:08 lucidext kernel: [  683.666411]  sdc: sdc1 sdc2 sdc3 < sdc5 sdc6 > sdc4
Jan 27 19:18:08 lucidext kernel: [  683.729911] sd 8:0:0:0: [sdc] Attached SCSI disk
Jan 27 19:18:15 lucidext kernel: [  691.130059] usb 1-5: reset high speed USB device using ehci_hcd and address 6
Jan 27 19:18:24 lucidext kernel: [  699.160059] usb 1-5: reset high speed USB device using ehci_hcd and address 6
Jan 27 19:18:54 lucidext kernel: [  730.070327] usb 1-5: USB disconnect, address 6
Jan 27 19:18:54 lucidext kernel: [  730.071320] sd 8:0:0:0: Device offlined - not ready after error recovery
Jan 27 19:18:54 lucidext kernel: [  730.071362] sd 8:0:0:0: [sdc] Unhandled error code
Jan 27 19:18:54 lucidext kernel: [  730.071367] sd 8:0:0:0: [sdc] Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Jan 27 19:18:54 lucidext kernel: [  730.071375] sd 8:0:0:0: [sdc] CDB: Read(10): 28 00 00 00 00 00 00 00 20 00
Jan 27 19:18:54 lucidext kernel: [  730.071603] sd 8:0:0:0: [sdc] Unhandled error code
Jan 27 19:18:54 lucidext kernel: [  730.071607] sd 8:0:0:0: [sdc] Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Jan 27 19:18:54 lucidext kernel: [  730.071614] sd 8:0:0:0: [sdc] CDB: Read(10): 28 00 0e c5 2b 01 00 00 80 00
Jan 27 19:18:55 lucidext kernel: [  730.380059] usb 1-5: new high speed USB device using ehci_hcd and address 7
Jan 27 19:18:55 lucidext kernel: [  730.531200] usb 1-5: configuration #1 chosen from 1 choice
Jan 27 19:18:55 lucidext kernel: [  730.533528] scsi9 : SCSI emulation for USB Mass Storage devices
Jan 27 19:19:00 lucidext kernel: [  735.572449] scsi 9:0:0:0: Direct-Access     Freecom  Mobile Drive XXS      PQ: 0 ANSI: 2 CCS
Jan 27 19:19:00 lucidext kernel: [  735.573295] sd 9:0:0:0: Attached scsi generic sg3 type 0
Jan 27 19:19:00 lucidext kernel: [  735.644175] sd 9:0:0:0: [sdc] 312581808 512-byte logical blocks: (160 GB/149 GiB)
Jan 27 19:19:00 lucidext kernel: [  735.644913] sd 9:0:0:0: [sdc] Write Protect is off
Jan 27 19:19:00 lucidext kernel: [  735.646419]  sdc: sdc1 sdc2 sdc3 < sdc5 sdc6 > sdc4
Jan 27 19:19:00 lucidext kernel: [  735.708791] sd 9:0:0:0: [sdc] Attached SCSI disk
Jan 27 19:19:07 lucidext kernel: [  743.130063] usb 1-5: reset high speed USB device using ehci_hcd and address 7
Jan 27 19:19:15 lucidext kernel: [  751.130065] usb 1-5: reset high speed USB device using ehci_hcd and address 7
Jan 27 19:19:47 lucidext kernel: [  782.160062] usb 1-5: reset high speed USB device using ehci_hcd and address 7

-- 
ehci_hcd module causes I/O errors in USB 2.0 devices
https://bugs.launchpad.net/bugs/88746
You received this bug notification because you are a member of Kernel
Bugs, which is subscribed to Linux.