> Maybe the best thing to try would be to repeat that test, but with more
> debugging output. Do you think you can insert
>
> printk(KERN_INFO "%s %d\n", __FUNCTION__, __LINE__);
>
> statements at various judicious places in the usb_physical_reset_device()
> function near the end of drivers/usb/core/hub.c? That might help pinpoint
> just where the problem strikes.
I can't see any difference in the logs -- is it possible that that
function is never called?
Now, with the disk connected directly, I can see a bit more output
though:
> Jun 5 20:53:25 ventus kernel: usb-storage: Command READ_10 (10 bytes)
> Jun 5 20:53:25 ventus kernel: usb-storage: 28 00 02 dc 0c c0 00 01 00 00
> Jun 5 20:53:25 ventus kernel: usb-storage: Bulk command S 0x43425355 T 0x136 Trg 0
> LUN 0 L 131072 F 128 CL 10
> Jun 5 20:53:25 ventus kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
> Jun 5 20:53:25 ventus kernel: usb-storage: Status code 0; transferred 31/31
> Jun 5 20:53:25 ventus kernel: usb-storage: -- transfer complete
> Jun 5 20:53:25 ventus kernel: usb-storage: Bulk command transfer result=0
> Jun 5 20:53:25 ventus kernel: usb-storage: usb_stor_bulk_transfer_sglist: xfer
> 131072 bytes, 32 entries
> Jun 5 20:53:25 ventus kernel: usb-storage: Status code 0; transferred 131072/131072
> Jun 5 20:53:25 ventus kernel: usb-storage: -- transfer complete
> Jun 5 20:53:25 ventus kernel: usb-storage: Bulk data transfer result 0x0
> Jun 5 20:53:25 ventus kernel: usb-storage: Attempting to get CSW...
> Jun 5 20:53:25 ventus kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
This is where things started breaking.
> Jun 5 20:53:55 ventus kernel: usb-storage: usb_storage_command_abort called
> Jun 5 20:53:55 ventus kernel: usb-storage: usb_stor_stop_transport called
> Jun 5 20:53:55 ventus kernel: usb-storage: -- cancelling URB
> Jun 5 20:53:55 ventus kernel: usb-storage: Status code -104; transferred 0/13
> Jun 5 20:53:55 ventus kernel: usb-storage: -- transfer cancelled
> Jun 5 20:53:55 ventus kernel: usb-storage: Bulk status result = 3
> Jun 5 20:53:55 ventus kernel: usb-storage: -- command was aborted
> Jun 5 20:53:55 ventus kernel: usb-storage: Bulk reset requested
> Jun 5 20:53:55 ventus kernel: usb-storage: usb_stor_control_msg: rq=ff rqtype=21
> value=0000 index=00 len=0
> Jun 5 20:54:15 ventus kernel: usb-storage: Timeout -- cancelling URB
> Jun 5 20:54:15 ventus kernel: usb-storage: Soft reset failed: -104
> Jun 5 20:54:15 ventus kernel: usb-storage: scsi command aborted
> Jun 5 20:54:15 ventus kernel: usb-storage: *** thread sleeping.
> Jun 5 20:54:15 ventus kernel: usb-storage: queuecommand() called
> Jun 5 20:54:15 ventus kernel: usb-storage: *** thread awakened.
> Jun 5 20:54:15 ventus kernel: usb-storage: Command TEST_UNIT_READY (6 bytes)
> Jun 5 20:54:15 ventus kernel: usb-storage: 00 00 00 00 00 00
> Jun 5 20:54:15 ventus kernel: usb-storage: Bulk command S 0x43425355 T 0x136 Trg 0
> LUN 0 L 0 F 0 CL 6
> Jun 5 20:54:15 ventus kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
> Jun 5 20:54:15 ventus kernel: usb-storage: Status code 0; transferred 31/31
> Jun 5 20:54:15 ventus kernel: usb-storage: -- transfer complete
> Jun 5 20:54:15 ventus kernel: usb-storage: Bulk command transfer result=0
> Jun 5 20:54:15 ventus kernel: usb-storage: Attempting to get CSW...
> Jun 5 20:54:15 ventus kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
> Jun 5 20:54:25 ventus kernel: usb-storage: usb_storage_command_abort called
> Jun 5 20:54:25 ventus kernel: usb-storage: usb_stor_stop_transport called
> Jun 5 20:54:25 ventus kernel: usb-storage: -- cancelling URB
> Jun 5 20:54:25 ventus kernel: usb-storage: Status code -104; transferred 0/13
> Jun 5 20:54:25 ventus kernel: usb-storage: -- transfer cancelled
> Jun 5 20:54:25 ventus kernel: usb-storage: Bulk status result = 3
> Jun 5 20:54:25 ventus kernel: usb-storage: -- command was aborted
> Jun 5 20:54:25 ventus kernel: usb-storage: Bulk reset requested
> Jun 5 20:54:25 ventus kernel: usb-storage: usb_stor_control_msg: rq=ff rqtype=21
> value=0000 index=00 len=0
> Jun 5 20:54:45 ventus kernel: usb-storage: Timeout -- cancelling URB
> Jun 5 20:54:45 ventus kernel: usb-storage: Soft reset failed: -104
> Jun 5 20:54:45 ventus kernel: usb-storage: scsi command aborted
> Jun 5 20:54:45 ventus kernel: usb-storage: *** thread sleeping.
> Jun 5 20:54:45 ventus kernel: usb-storage: usb_storage_device_reset called
> Jun 5 20:54:45 ventus kernel: usb-storage: Bulk reset requested
> Jun 5 20:54:45 ventus kernel: usb-storage: usb_stor_control_msg: rq=ff rqtype=21
> value=0000 index=00 len=0
> Jun 5 20:55:05 ventus kernel: usb-storage: Timeout -- cancelling URB
> Jun 5 20:55:05 ventus kernel: usb-storage: Soft reset failed: -104
> Jun 5 20:55:05 ventus kernel: usb-storage: usb_storage_bus_reset called
> Jun 5 20:55:05 ventus kernel: usb_physical_reset_device 1193
> Jun 5 20:55:05 ventus kernel: usb_physical_reset_device 1209
> Jun 5 20:55:05 ventus kernel: usb_physical_reset_device 1216
> Jun 5 20:55:05 ventus kernel: usb_physical_reset_device 1220
> Jun 5 20:55:05 ventus kernel: hub 5-0:0: port 1 not reset yet, waiting 10ms
> Jun 5 20:55:05 ventus kernel: hub 5-0:0: port 1 not reset yet, waiting 10ms
> Jun 5 20:55:06 ventus kernel: hub 5-0:0: port 1 not reset yet, waiting 200ms
> Jun 5 20:55:06 ventus kernel: ehci-hcd 00:09.2: GetStatus port 1 status 001002
> POWER sig=se0 CSC
> Jun 5 20:55:06 ventus kernel: usb_physical_reset_device 1225
> Jun 5 20:55:06 ventus kernel: usb_physical_reset_device 1227
> Jun 5 20:55:06 ventus kernel: usb-storage: usb_reset_device returns -19
> Jun 5 20:55:06 ventus kernel: scsi: Device offlined - not ready after error
> recovery: host 2 channel 0 id 0 lun 0
> Jun 5 20:55:06 ventus kernel: SCSI disk error : <2 0 0 0> return code = 0x6050000
> Jun 5 20:55:06 ventus kernel: end_request: I/O error, dev sda, sector 47975616
> Jun 5 20:55:06 ventus kernel: usb-storage: queuecommand() called
> Jun 5 20:55:06 ventus kernel: Buffer I/O error on device sda1, logical block 5996948
> Jun 5 20:55:06 ventus kernel: usb-storage: *** thread awakened.
> Jun 5 20:55:06 ventus kernel: usb-storage: Command WRITE_10 (10 bytes)
> Jun 5 20:55:06 ventus kernel: usb-storage: 2a 00 00 00 1b 20 00 00 58 00
> Jun 5 20:55:06 ventus kernel: usb-storage: Bulk command S 0x43425355 T 0x137 Trg 0
> LUN 0 L 45056 F 0 CL 10
> Jun 5 20:55:06 ventus kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
> Jun 5 20:55:06 ventus kernel: usb-storage: Status code -19; transferred 0/31
> Jun 5 20:55:06 ventus kernel: usb-storage: -- unknown error
> Jun 5 20:55:06 ventus kernel: usb-storage: Bulk command transfer result=3
> Jun 5 20:55:06 ventus kernel: usb-storage: -- transport indicates error, resetting
> Jun 5 20:55:06 ventus kernel: usb-storage: Bulk reset requested
> Jun 5 20:55:06 ventus kernel: usb-storage: usb_stor_control_msg: rq=ff rqtype=21
> value=0000 index=00 len=0
> Jun 5 20:55:06 ventus kernel: usb-storage: Soft reset failed: -19
> Jun 5 20:55:06 ventus kernel: usb-storage: scsi cmd done, result=0x70000
> Jun 5 20:55:06 ventus kernel: usb-storage: *** thread sleeping.
> Jun 5 20:55:06 ventus kernel: SCSI disk error : <2 0 0 0> return code = 0x70000
> Jun 5 20:55:06 ventus kernel: end_request: I/O error, dev sda, sector 6944
> Jun 5 20:55:06 ventus kernel: journal-601, buffer write failed
What follows is once again the reiserfs oops.
Hope this helps,
Andras
===========================================================================
Major Andras
e-mail: [EMAIL PROTECTED]
www: http://andras.webhop.org/
===========================================================================
-------------------------------------------------------
This SF.net email is sponsored by: Etnus, makers of TotalView, The best
thread debugger on the planet. Designed with thread debugging features
you've never dreamed of, try TotalView 6 free at www.etnus.com.
_______________________________________________
[EMAIL PROTECTED]
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel
