Re: PROBLEM: hotplugging an USB memorystick fails

[Alan Stern <stern@xxxxxxxxxxxxxxxxxxx>]

On Wed, 17 Oct 2007, I Mouwen wrote:

> Here's the kernel log with debugging enabled. This is when I insert
> the memory stick after booting.

...
> Oct 17 21:13:40 casper kernel: usb-storage: Command INQUIRY (6 bytes)
> Oct 17 21:13:40 casper kernel: usb-storage:  12 00 00 00 24 00
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk Command S 0x43425355
> T 0x1 L 36 F 128 Trg 0 LUN 0 CL 6
> Oct 17 21:13:40 casper kernel: usb-storage:
> usb_stor_bulk_transfer_buf: xfer 31 bytes
> Oct 17 21:13:40 casper kernel: usb-storage: Status code 0; transferred 31/31
> Oct 17 21:13:40 casper kernel: usb-storage: -- transfer complete
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk command transfer result=0
> Oct 17 21:13:40 casper kernel: usb-storage:
> usb_stor_bulk_transfer_sglist: xfer 36 bytes, 1 entries
> Oct 17 21:13:40 casper kernel: usb-storage: Status code 0; transferred 36/36
> Oct 17 21:13:40 casper kernel: usb-storage: -- transfer complete
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk data transfer result 0x0
> Oct 17 21:13:40 casper kernel: usb-storage: Attempting to get CSW...
> Oct 17 21:13:40 casper kernel: usb-storage:
> usb_stor_bulk_transfer_buf: xfer 13 bytes
> Oct 17 21:13:40 casper kernel: usb-storage: Status code 0; transferred 13/13
> Oct 17 21:13:40 casper kernel: usb-storage: -- transfer complete
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk status result = 0
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk Status S 0x53425355 T
> 0x1 R 0 Stat 0x0
> Oct 17 21:13:40 casper kernel: usb-storage: scsi cmd done, result=0x0
> Oct 17 21:13:40 casper kernel: usb-storage: *** thread sleeping.
> Oct 17 21:13:40 casper kernel: scsi scan: INQUIRY result too short (5), using 36

That's not a terribly bad error.  It merely means the device included a
bad length byte in its INQUIRY data.  However it is surprising that
this message did not appear in the insert-before-boot log.

> Oct 17 21:13:40 casper kernel: scsi 4:0:0:0: Direct-Access     USB
>  2.0 Flash Drive  1.00 PQ: 0 ANSI: 0

You see the data did get transferred correctly in spite of the bad 
length byte.

...
> Oct 17 21:13:40 casper kernel: usb-storage: Command TEST_UNIT_READY (6 bytes)
> Oct 17 21:13:40 casper kernel: usb-storage:  00 00 00 00 00 00
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk Command S 0x43425355
> T 0x2 L 0 F 0 Trg 0 LUN 0 CL 6
> Oct 17 21:13:40 casper kernel: usb-storage:
> usb_stor_bulk_transfer_buf: xfer 31 bytes
> Oct 17 21:13:40 casper kernel: usb-storage: Status code 0; transferred 31/31
> Oct 17 21:13:40 casper kernel: usb-storage: -- transfer complete
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk command transfer result=0
> Oct 17 21:13:40 casper kernel: usb-storage: Attempting to get CSW...
> Oct 17 21:13:40 casper kernel: usb-storage:
> usb_stor_bulk_transfer_buf: xfer 13 bytes
> Oct 17 21:13:40 casper kernel: usb-storage: Status code 0; transferred 13/13
> Oct 17 21:13:40 casper kernel: usb-storage: -- transfer complete
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk status result = 0
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk Status S 0x53425355 T
> 0x2 R 0 Stat 0x1
> Oct 17 21:13:40 casper kernel: usb-storage: -- transport indicates
> command failure
> Oct 17 21:13:40 casper kernel: usb-storage: Issuing auto-REQUEST_SENSE
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk Command S 0x43425355
> T 0x3 L 18 F 128 Trg 0 LUN 0 CL 6
> Oct 17 21:13:40 casper kernel: usb-storage:
> usb_stor_bulk_transfer_buf: xfer 31 bytes
> Oct 17 21:13:40 casper kernel: usb-storage: Status code 0; transferred 31/31
> Oct 17 21:13:40 casper kernel: usb-storage: -- transfer complete
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk command transfer result=0
> Oct 17 21:13:40 casper kernel: usb-storage:
> usb_stor_bulk_transfer_buf: xfer 18 bytes
> Oct 17 21:13:40 casper kernel: usb-storage: Status code 0; transferred 18/18
> Oct 17 21:13:40 casper kernel: usb-storage: -- transfer complete
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk data transfer result 0x0
> Oct 17 21:13:40 casper kernel: usb-storage: Attempting to get CSW...
> Oct 17 21:13:40 casper kernel: usb-storage:
> usb_stor_bulk_transfer_buf: xfer 13 bytes
> Oct 17 21:13:40 casper kernel: usb-storage: Status code 0; transferred 13/13
> Oct 17 21:13:40 casper kernel: usb-storage: -- transfer complete
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk status result = 0
> Oct 17 21:13:40 casper kernel: usb-storage: Bulk Status S 0x53425355 T
> 0x3 R 0 Stat 0x0
> Oct 17 21:13:40 casper kernel: usb-storage: -- Result from auto-sense is 0
> Oct 17 21:13:40 casper kernel: usb-storage: -- code: 0x70, key: 0x6,
> ASC: 0x3a, ASCQ: 0x0
> Oct 17 21:13:40 casper kernel: usb-storage: Unit Attention: Medium not present
> Oct 17 21:13:40 casper kernel: usb-storage: scsi cmd done, result=0x2

Again, normal enough -- except that this device doesn't have removable 
media!  It may trying to indicate that something else is wrong.  For 
instance, it may need more than the usual 5-second delay.

> Oct 17 21:13:40 casper kernel: usb-storage: *** thread sleeping.
> Oct 17 21:13:40 casper kernel: sd 4:0:0:0: [sda] Attached SCSI disk
> Oct 17 21:13:40 casper kernel: usb-storage: queuecommand called
> Oct 17 21:13:40 casper kernel: usb-storage: *** thread awakened.
> Oct 17 21:13:40 casper kernel: usb-storage: Command INQUIRY (6 bytes)
> Oct 17 21:13:40 casper kernel: usb-storage:  12 00 00 00 fe 00

This and the following commands look very bizarre.  They resemble 
something that might be produced by a several-year-old version of HAL
or some other drive-monitoring program.

Alan Stern


-------------------------------------------------------------------------
This SF.net email is sponsored by: Splunk Inc.
Still grepping through log files to find problems?  Stop.
Now Search log events and configuration files using AJAX and a browser.
Download your FREE copy of Splunk now >> http://get.splunk.com/
_______________________________________________
linux-usb-devel@xxxxxxxxxxxxxxxxxxxxx
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel