On Tuesday 13 April 2004 21:03, Alan Stern wrote:

I read about the "scsiglue.c" file on this mailinglist and decided to do some 
testing.
With a "max_sectors" value of 16 I got the fastest transferrate, so my slow 
USB problem is solved. (Although it is not a real solution.)
The transferrate improved from 12,9kB/s to 218,8kB/s. That's a big 
difference. ;-)

Is the sectorsize something that is negotiated when the USB device is plugged 
in?

> > > uhci_hcd 0000:00:1d.0: uhci_result_control: failed with status 440000

> You probably don't have to worry about that particular error.  Most likely
> it just means something went wrong when the driver tried to retrieve the
> Vendor, Product, or Serial Number string from the device.

> To determine the reason your transfers are so slow, we need to see a lot
> more of your usb-storage debugging log.

Before changing scsiglue.c the logfiles went like this:

When I was lucky the transfer was only really slow. I could see the "activity 
light" light up once, then 5 seconds nothing, one little blink, 5 seconds 
nothing, ...
This behaviour is reflected when you look at the timestamp of the "*** thread 
awakened." messages.

When I was not so lucky, my camera would lock up and the batteries needed to 
be removed to bring it back to life. I think this is what happened when you 
investigate these logfiles further.

After changing the scsiglue.c file, the accesslight is flashing really fast 
during transfers.


Apr 11 22:18:55 paranoid usb-storage: *** thread awakened.
Apr 11 22:18:55 paranoid usb-storage: Command READ_10 (10 bytes)
Apr 11 22:18:55 paranoid usb-storage:  28 00 00 00 84 4f 00 00 80 00
Apr 11 22:18:55 paranoid usb-storage: Bulk Command S 0x43425355 T 0x45 L 65536 
F 128 Trg 0 LUN 0 CL 10
Apr 11 22:18:55 paranoid usb-storage: usb_stor_bulk_transfer_buf: xfer 31 
bytes
Apr 11 22:18:55 paranoid usb-storage: Status code 0; transferred 31/31
Apr 11 22:18:55 paranoid usb-storage: -- transfer complete
Apr 11 22:18:55 paranoid usb-storage: Bulk command transfer result=0
Apr 11 22:18:55 paranoid usb-storage: usb_stor_bulk_transfer_sglist: xfer 
65536 bytes, 16 entries
Apr 11 22:18:59 paranoid usb-storage: Status code 0; transferred 65536/65536
Apr 11 22:18:59 paranoid usb-storage: -- transfer complete
Apr 11 22:18:59 paranoid usb-storage: Bulk data transfer result 0x0
Apr 11 22:18:59 paranoid usb-storage: Attempting to get CSW...
Apr 11 22:18:59 paranoid usb-storage: usb_stor_bulk_transfer_buf: xfer 13 
bytes
Apr 11 22:18:59 paranoid usb-storage: Status code 0; transferred 13/13
Apr 11 22:18:59 paranoid usb-storage: -- transfer complete
Apr 11 22:18:59 paranoid usb-storage: Bulk status result = 0
Apr 11 22:18:59 paranoid usb-storage: Bulk Status S 0x53425355 T 0x45 R 0 Stat 
0x0
Apr 11 22:18:59 paranoid usb-storage: scsi cmd done, result=0x0
Apr 11 22:18:59 paranoid usb-storage: *** thread sleeping.
Apr 11 22:18:59 paranoid usb-storage: queuecommand called
Apr 11 22:18:59 paranoid usb-storage: *** thread awakened.
Apr 11 22:18:59 paranoid usb-storage: Command READ_10 (10 bytes)
Apr 11 22:18:59 paranoid usb-storage:  28 00 00 00 84 cf 00 00 80 00
Apr 11 22:18:59 paranoid usb-storage: Bulk Command S 0x43425355 T 0x46 L 65536 
F 128 Trg 0 LUN 0 CL 10
Apr 11 22:18:59 paranoid usb-storage: usb_stor_bulk_transfer_buf: xfer 31 
bytes
Apr 11 22:18:59 paranoid usb-storage: Status code 0; transferred 31/31
Apr 11 22:18:59 paranoid usb-storage: -- transfer complete
Apr 11 22:18:59 paranoid usb-storage: Bulk command transfer result=0
Apr 11 22:18:59 paranoid usb-storage: usb_stor_bulk_transfer_sglist: xfer 
65536 bytes, 16 entries
Apr 11 22:19:04 paranoid usb-storage: Status code 0; transferred 65536/65536
Apr 11 22:19:04 paranoid usb-storage: -- transfer complete
Apr 11 22:19:04 paranoid usb-storage: Bulk data transfer result 0x0
Apr 11 22:19:04 paranoid usb-storage: Attempting to get CSW...
Apr 11 22:19:04 paranoid usb-storage: usb_stor_bulk_transfer_buf: xfer 13 
bytes
Apr 11 22:19:04 paranoid usb-storage: Status code 0; transferred 13/13
Apr 11 22:19:04 paranoid usb-storage: -- transfer complete
Apr 11 22:19:04 paranoid usb-storage: Bulk status result = 0
Apr 11 22:19:04 paranoid usb-storage: Bulk Status S 0x53425355 T 0x46 R 0 Stat 
0x0
Apr 11 22:19:04 paranoid usb-storage: scsi cmd done, result=0x0
Apr 11 22:19:04 paranoid usb-storage: *** thread sleeping.
Apr 11 22:19:04 paranoid usb-storage: queuecommand called
Apr 11 22:19:04 paranoid usb-storage: *** thread awakened.
Apr 11 22:19:04 paranoid usb-storage: Command READ_10 (10 bytes)
Apr 11 22:19:04 paranoid usb-storage:  28 00 00 00 85 4f 00 00 80 00
Apr 11 22:19:04 paranoid usb-storage: Bulk Command S 0x43425355 T 0x47 L 65536 
F 128 Trg 0 LUN 0 CL 10
Apr 11 22:19:04 paranoid usb-storage: usb_stor_bulk_transfer_buf: xfer 31 
bytes
Apr 11 22:19:04 paranoid usb 2-1: control timeout on ep0in
Apr 11 22:19:09 paranoid usb 2-1: control timeout on ep0in
Apr 11 22:19:14 paranoid usb 2-1: control timeout on ep0in
Apr 11 22:19:19 paranoid usb 2-1: control timeout on ep0in
Apr 11 22:19:24 paranoid usb 2-1: control timeout on ep0in
Apr 11 22:19:29 paranoid usb 2-1: control timeout on ep0in
Apr 11 22:19:34 paranoid usb-storage: command_abort called
Apr 11 22:19:34 paranoid usb-storage: usb_stor_stop_transport called
Apr 11 22:19:34 paranoid usb-storage: -- cancelling URB
Apr 11 22:19:34 paranoid usb-storage: Status code -104; transferred 16/31
Apr 11 22:19:34 paranoid usb-storage: -- transfer cancelled
Apr 11 22:19:34 paranoid usb-storage: Bulk command transfer result=4
Apr 11 22:19:34 paranoid usb-storage: -- command was aborted
Apr 11 22:19:34 paranoid usb-storage: usb_stor_Bulk_reset called
Apr 11 22:19:34 paranoid usb-storage: usb_stor_control_msg: rq=ff rqtype=21 
value=0000 index=00 len=0
Apr 11 22:19:34 paranoid usb 2-1: control timeout on ep0in
Apr 11 22:19:39 paranoid usb 2-1: control timeout on ep0in
Apr 11 22:19:44 paranoid usb 2-1: control timeout on ep0in
Apr 11 22:19:49 paranoid usb 2-1: control timeout on ep0in
Apr 11 22:19:54 paranoid usb-storage: Timeout -- cancelling URB
Apr 11 22:19:54 paranoid usb-storage: Soft reset failed: -104
Apr 11 22:19:54 paranoid usb-storage: scsi command aborted
Apr 11 22:19:54 paranoid usb-storage: *** thread sleeping.
Apr 11 22:19:54 paranoid usb-storage: queuecommand called
Apr 11 22:19:54 paranoid usb-storage: *** thread awakened.
Apr 11 22:19:54 paranoid usb-storage: Command TEST_UNIT_READY (6 bytes)
Apr 11 22:19:54 paranoid usb-storage:  00 00 00 00 00 00
Apr 11 22:19:54 paranoid usb-storage: Bulk Command S 0x43425355 T 0x47 L 0 F 0 
Trg 0 LUN 0 CL 6
Apr 11 22:19:54 paranoid usb-storage: usb_stor_bulk_transfer_buf: xfer 31 
bytes
Apr 11 22:20:04 paranoid usb-storage: command_abort called
Apr 11 22:20:04 paranoid usb-storage: usb_stor_stop_transport called
Apr 11 22:20:04 paranoid usb-storage: -- cancelling URB
Apr 11 22:20:04 paranoid usb-storage: Status code -104; transferred 0/31
Apr 11 22:20:04 paranoid usb-storage: -- transfer cancelled
Apr 11 22:20:04 paranoid usb-storage: Bulk command transfer result=4
Apr 11 22:20:04 paranoid usb-storage: -- command was aborted
Apr 11 22:20:04 paranoid usb-storage: usb_stor_Bulk_reset called
Apr 11 22:20:04 paranoid usb-storage: usb_stor_control_msg: rq=ff rqtype=21 
value=0000 index=00 len=0
Apr 11 22:20:24 paranoid usb-storage: Timeout -- cancelling URB
Apr 11 22:20:24 paranoid usb-storage: Soft reset failed: -104
Apr 11 22:20:24 paranoid usb-storage: scsi command aborted
Apr 11 22:20:24 paranoid usb-storage: *** thread sleeping.
Apr 11 22:20:24 paranoid usb-storage: device_reset called
Apr 11 22:20:24 paranoid usb-storage: usb_stor_Bulk_reset called
Apr 11 22:20:24 paranoid usb-storage: usb_stor_control_msg: rq=ff rqtype=21 
value=0000 index=00 len=0
Apr 11 22:20:44 paranoid usb-storage: Timeout -- cancelling URB
Apr 11 22:20:44 paranoid usb-storage: Soft reset failed: -104
Apr 11 22:20:44 paranoid usb-storage: bus_reset called
Apr 11 22:20:44 paranoid uhci_hcd 0000:00:1d.0: port 1 portsc 0288
Apr 11 22:20:44 paranoid hub 2-0:1.0: port 1 enable change, status 110
Apr 11 22:20:49 paranoid usb 2-1: control timeout on ep0out
Apr 11 22:20:49 paranoid drivers/usb/core/hub.c: USB device not accepting new 
address (error=-110)
Apr 11 22:20:49 paranoid usb-storage: usb_reset_device returns -110
Apr 11 22:20:49 paranoid scsi: Device offlined - not ready after error 
recovery: host 0 channel 0 id 0 lun 0
Apr 11 22:20:49 paranoid SCSI error : <0 0 0 0> return code = 0x50000
Apr 11 22:20:49 paranoid end_request: I/O error, dev sda, sector 34127
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34095
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34096
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34097
...
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34220
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34221
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34222
Apr 11 22:20:49 paranoid usb-storage: queuecommand called
Apr 11 22:20:49 paranoid usb-storage: *** thread awakened.
Apr 11 22:20:49 paranoid usb-storage: Command READ_10 (10 bytes)
Apr 11 22:20:49 paranoid usb-storage:  28 00 00 00 85 cf 00 00 58 00
Apr 11 22:20:49 paranoid usb-storage: Bulk Command S 0x43425355 T 0x48 L 45056 
F 128 Trg 0 LUN 0 CL 10
Apr 11 22:20:49 paranoid usb-storage: usb_stor_bulk_transfer_buf: xfer 31 
bytes
Apr 11 22:20:49 paranoid usb-storage: Status code -19; transferred 0/31
Apr 11 22:20:49 paranoid usb-storage: -- unknown error
Apr 11 22:20:49 paranoid usb-storage: Bulk command transfer result=4
Apr 11 22:20:49 paranoid usb-storage: -- transport indicates error, resetting
Apr 11 22:20:49 paranoid usb-storage: usb_stor_Bulk_reset called
Apr 11 22:20:49 paranoid usb-storage: usb_stor_control_msg: rq=ff rqtype=21 
value=0000 index=00 len=0
Apr 11 22:20:49 paranoid uhci_hcd 0000:00:1d.0: uhci_result_control: failed 
with status 440000
Apr 11 22:20:49 paranoid [efb71240] link (2fb711e2) element (27f8e080)
Apr 11 22:20:49 paranoid 0: [e7f8e080] link (27f8e0c0) e0 Stalled CRC/Timeo 
Length=7 MaxLen=7 DT0 EndPt=0 Dev=2, PID=2d(SETUP) (buf=2fb6a020)
Apr 11 22:20:49 paranoid 1: [e7f8e0c0] link (00000001) e3 IOC Active Length=0 
MaxLen=7ff DT1 EndPt=0 Dev=2, PID=69(IN) (buf=00000000)
Apr 11 22:20:49 paranoid
Apr 11 22:20:49 paranoid usb-storage: Soft reset failed: -71
Apr 11 22:20:49 paranoid usb-storage: scsi cmd done, result=0x70000
Apr 11 22:20:49 paranoid usb-storage: *** thread sleeping.
Apr 11 22:20:49 paranoid SCSI error : <0 0 0 0> return code = 0x70000
Apr 11 22:20:49 paranoid end_request: I/O error, dev sda, sector 34255
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34223
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34224
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34225
...
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34308
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34309
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 34310
Apr 11 22:20:49 paranoid scsi0 (0:0): rejecting I/O to offline device
Apr 11 22:20:49 paranoid FAT: bread(block 34) in fat_access failed
Apr 11 22:20:49 paranoid scsi0 (0:0): rejecting I/O to offline device
Apr 11 22:20:49 paranoid FAT: bread(block 34) in fat_access failed
Apr 11 22:20:49 paranoid scsi0 (0:0): rejecting I/O to offline device
Apr 11 22:20:49 paranoid FAT: bread(block 34) in fat_access failed
...
Apr 11 22:20:49 paranoid scsi0 (0:0): rejecting I/O to offline device
Apr 11 22:20:49 paranoid FAT: bread(block 36) in fat_access failed
Apr 11 22:20:49 paranoid scsi0 (0:0): rejecting I/O to offline device
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 36579
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 36580
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 36581
Apr 11 22:20:49 paranoid Buffer I/O error on device sda1, logical block 36582
Apr 11 22:20:49 paranoid buffer layer error at fs/buffer.c:1309
Apr 11 22:20:49 paranoid Call Trace:
Apr 11 22:20:49 paranoid [<c0152d63>] __bread_slow+0x7c/0xa0
Apr 11 22:20:49 paranoid [<c0153072>] __bread+0x3d/0x41
Apr 11 22:20:49 paranoid [<c01aa729>] fat__get_entry+0xb5/0x194
Apr 11 22:20:49 paranoid [<c01a7778>] fat_get_short_entry+0x8f/0x9a
Apr 11 22:20:49 paranoid [<c01a7923>] fat_scan+0x56/0x8e
Apr 11 22:20:49 paranoid [<c01aac62>] msdos_find+0x73/0xad
Apr 11 22:20:49 paranoid [<c01aae15>] msdos_lookup+0x6e/0x127
Apr 11 22:20:49 paranoid [<c01677f1>] d_lookup+0x23/0x46
Apr 11 22:20:49 paranoid [<c015d6c3>] real_lookup+0xdc/0xfe
Apr 11 22:20:49 paranoid [<c015d934>] do_lookup+0x96/0xa1
Apr 11 22:20:49 paranoid [<c015de12>] link_path_walk+0x4d3/0x953
Apr 11 22:20:49 paranoid [<c015e754>] __user_walk+0x49/0x5e
Apr 11 22:20:49 paranoid [<c015979b>] vfs_stat+0x1f/0x5b
Apr 11 22:20:49 paranoid [<c025e28d>] tty_write+0x19e/0x2a8
Apr 11 22:20:49 paranoid [<c016681e>] dput+0x22/0x212
Apr 11 22:20:49 paranoid [<c0159eeb>] sys_stat64+0x1b/0x39
Apr 11 22:20:49 paranoid [<c014fc7c>] filp_close+0x59/0x86
Apr 11 22:20:49 paranoid [<c014fd0c>] sys_close+0x63/0x96
Apr 11 22:20:49 paranoid [<c010727f>] syscall_call+0x7/0xb
Apr 11 22:20:49 paranoid
Apr 11 22:20:49 paranoid buffer layer error at fs/buffer.c:2705
Apr 11 22:20:49 paranoid Call Trace:
Apr 11 22:20:49 paranoid [<c0154ff7>] submit_bh+0x162/0x1d6
Apr 11 22:20:49 paranoid [<c010763a>] show_trace+0x46/0x92
Apr 11 22:20:49 paranoid [<c010727f>] syscall_call+0x7/0xb
Apr 11 22:20:49 paranoid [<c0152d1f>] __bread_slow+0x38/0xa0
Apr 11 22:20:49 paranoid [<c0153072>] __bread+0x3d/0x41
Apr 11 22:20:49 paranoid [<c01aa729>] fat__get_entry+0xb5/0x194
Apr 11 22:20:49 paranoid [<c01a7778>] fat_get_short_entry+0x8f/0x9a
Apr 11 22:20:49 paranoid [<c01a7923>] fat_scan+0x56/0x8e
Apr 11 22:20:49 paranoid [<c01aac62>] msdos_find+0x73/0xad
Apr 11 22:20:49 paranoid [<c01aae15>] msdos_lookup+0x6e/0x127
Apr 11 22:20:49 paranoid [<c01677f1>] d_lookup+0x23/0x46
Apr 11 22:20:49 paranoid [<c015d6c3>] real_lookup+0xdc/0xfe
Apr 11 22:20:49 paranoid [<c015d934>] do_lookup+0x96/0xa1
Apr 11 22:20:49 paranoid [<c015de12>] link_path_walk+0x4d3/0x953
Apr 11 22:20:49 paranoid [<c015e754>] __user_walk+0x49/0x5e
Apr 11 22:20:49 paranoid [<c015979b>] vfs_stat+0x1f/0x5b
Apr 11 22:20:49 paranoid [<c025e28d>] tty_write+0x19e/0x2a8
Apr 11 22:20:49 paranoid [<c016681e>] dput+0x22/0x212
Apr 11 22:20:49 paranoid [<c0159eeb>] sys_stat64+0x1b/0x39
Apr 11 22:20:49 paranoid [<c014fc7c>] filp_close+0x59/0x86
Apr 11 22:20:49 paranoid [<c014fd0c>] sys_close+0x63/0x96
Apr 11 22:20:49 paranoid [<c010727f>] syscall_call+0x7/0xb
...

-- 
Johan Braeken
http://www.knudde.be


-------------------------------------------------------
This SF.Net email is sponsored by: IBM Linux Tutorials
Free Linux tutorial presented by Daniel Robbins, President and CEO of
GenToo technologies. Learn everything from fundamentals to system
administration.http://ads.osdn.com/?ad_id=1470&alloc_id=3638&op=click
_______________________________________________
[EMAIL PROTECTED]
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel

Reply via email to