Hi all,
I'm new to this list. Please don't hurt me. m(_)m
I've got a weird problem with one of my USB storage devices (a Trekstor
MP3 player which supports USB2). When I connect it to the computer while
the ehci_hcd module is loaded, some part of the USB subsystem seems to
freeze indefinitely. For example when calling 'lsusb', it just hangs,
until I physically disconnect the MP3 player.
I've also got another usb-storage device (a card reader with 4 slots),
which gets detected fine using ehci. Four scsi disk devices are emulated.
So ehci and usb-storage can't be broken in general.
The MP3 player itself doesn't seem to be defective either; it works fine
under Windows, as well as under Linux when I force it to use uhci instead
of ehci by simply removing the ehci module altogether.
This is in vanilla kernel 2.6.14.4; but it's the same story in other
kernel versions (I also tested with a Knoppix DVD that uses 2.6.12).
I also tried 2.6.15-rc5-mm1, but this kernel actually panicked when I
connected the device. I attached the output in case it's of interest.
In the following, I'm trying to provide some information about the problem.
Please tell me whatever else you may need for identifying the cause.
---- My hardware: ----
Athlon XP, Chaintech Apogee 7VJL board, with VIA VT8235 southbridge.
lspci says about USB:
00:10.0 USB Controller: VIA Technologies, Inc. VT82xxxxx UHCI USB 1.1
Controller (rev 80)
00:10.1 USB Controller: VIA Technologies, Inc. VT82xxxxx UHCI USB 1.1
Controller (rev 80)
00:10.2 USB Controller: VIA Technologies, Inc. VT82xxxxx UHCI USB 1.1
Controller (rev 80)
00:10.3 USB Controller: VIA Technologies, Inc. USB 2.0 (rev 82)
----
The following logs were produced using vanilla 2.6.14.4 kernel, USB debug
messages enabled, as well as timing information (uptime in seconds) for
kernel printk's.
---- When connecting the MP3 player with ehci enabled: ----
[ 201.036582] hub 4-1:1.0: state 5 ports 4 chg 0000 evt 0008
[ 201.036809] hub 4-1:1.0: port 3, status 0101, change 0001, 12 Mb/s
[ 201.140406] hub 4-1:1.0: debounce: port 3: total 100ms stable 100ms status
0x101
[ 201.202391] usb 4-1.3: new high speed USB device using ehci_hcd and address 6
[ 202.201912] usb 4-1.3: hcd_unlink_urb d2dfac40 fail -16
[ 207.325971] usb 4-1.3: khubd timed out on ep0in len=9/9
[ 212.323938] usb 4-1.3: khubd timed out on ep0in len=32/32
[ 217.321914] usb 4-1.3: hcd_unlink_urb d2dfac40 fail -16
----
---- When disconnecting the MP3 player with ehci enabled: ----
[ 274.199116] usb 4-1.3: default language 0x0409
[ 274.199341] ehci_hcd 0000:00:10.3: devpath 1.3 ep0in 3strikes
[ 274.199590] ehci_hcd 0000:00:10.3: devpath 1.3 ep0in 3strikes
[ 274.199838] ehci_hcd 0000:00:10.3: devpath 1.3 ep0in 3strikes
[ 274.200088] ehci_hcd 0000:00:10.3: devpath 1.3 ep0in 3strikes
[ 274.209462] ehci_hcd 0000:00:10.3: devpath 1.3 ep0in 3strikes
[ 274.209711] ehci_hcd 0000:00:10.3: devpath 1.3 ep0in 3strikes
[ 274.209722] usb 4-1.3: new device strings: Mfr=1, Product=2, SerialNumber=3
[ 274.209753] usb 4-1.3: hotplug
[ 274.209961] ehci_hcd 0000:00:10.3: devpath 1.3 ep0out 3strikes
[ 274.209974] usb 4-1.3: can't set config #1, error -71
[ 274.210003] usb 4-1.3: hotplug
[ 274.232220] hub 4-1:1.0: state 5 ports 4 chg 0000 evt 0008
[ 274.232586] hub 4-1:1.0: port 3, status 0300, change 0003, 1.5 Mb/s
[ 274.336434] hub 4-1:1.0: debounce: port 3: total 100ms stable 100ms status
0x300
----
---- When connecting the MP3 player with ehci disabled: ----
[ 395.079856] hub 1-1:1.0: state 5 ports 4 chg 0000 evt 0008
[ 395.082829] hub 1-1:1.0: port 3, status 0101, change 0001, 12 Mb/s
[ 395.188787] hub 1-1:1.0: debounce: port 3: total 100ms stable 100ms status
0x101
[ 395.251759] usb 1-1.3: new full speed USB device using uhci_hcd and address
10
[ 396.252358] usb 1-1.3: khubd timed out on ep0in len=18/64
[ 396.356303] usb 1-1.3: default language 0x0409
[ 396.361302] usb 1-1.3: new device strings: Mfr=1, Product=2, SerialNumber=3
[ 396.361307] usb 1-1.3: Product: i.Beat organix
[ 396.361309] usb 1-1.3: Manufacturer: TrekStor
[ 396.361312] usb 1-1.3: SerialNumber: 0002F685DB12088F
[ 396.361344] usb 1-1.3: hotplug
[ 396.364324] usb 1-1.3: adding 1-1.3:1.0 (config #1, interface 0)
[ 396.364358] usb 1-1.3:1.0: hotplug
[ 396.364647] usb-storage 1-1.3:1.0: usb_probe_interface
[ 396.364651] usb-storage 1-1.3:1.0: usb_probe_interface - got id
[ 396.364654] usb-storage: USB Mass Storage device detected
[ 396.364688] usb-storage: -- associate_dev
[ 396.364691] usb-storage: Vendor: 0x066f, Product: 0x82b7, Revision: 0x1001
[ 396.364695] usb-storage: Interface Subclass: 0x06, Protocol: 0x50
[ 396.364701] usb-storage: Transport: Bulk
[ 396.364704] usb-storage: Protocol: Transparent SCSI
[ 396.364710] usb-storage: usb_stor_control_msg: rq=fe rqtype=a1 value=0000
index=00 len=1
[ 396.365313] usb-storage: GetMaxLUN command result is 1, data is 0
[ 396.383010] scsi3 : SCSI emulation for USB Mass Storage devices
[ 396.384026] usb-storage: *** thread sleeping.
[ 396.384413] usb-storage: device found at 10
[ 396.384465] usb-storage: waiting for device to settle before scanning
[ 396.386670] hub 1-1:1.0: state 5 ports 4 chg 0000 evt 0008
[ 401.381990] usb-storage: queuecommand called
[ 401.382003] usb-storage: *** thread awakened.
[ 401.382007] usb-storage: Command INQUIRY (6 bytes)
[ 401.382010] usb-storage: 12 00 00 00 24 00
[ 401.382019] usb-storage: Bulk Command S 0x43425355 T 0x1 L 36 F 128 Trg 0
LUN 0 CL 6
[ 401.382023] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.383242] usb-storage: Status code 0; transferred 31/31
[ 401.383246] usb-storage: -- transfer complete
[ 401.383249] usb-storage: Bulk command transfer result=0
[ 401.383252] usb-storage: usb_stor_bulk_transfer_sglist: xfer 36 bytes, 1
entries
[ 401.384239] usb-storage: Status code 0; transferred 36/36
[ 401.384243] usb-storage: -- transfer complete
[ 401.384246] usb-storage: Bulk data transfer result 0x0
[ 401.384248] usb-storage: Attempting to get CSW...
[ 401.384251] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.385235] usb-storage: Status code 0; transferred 13/13
[ 401.385238] usb-storage: -- transfer complete
[ 401.385241] usb-storage: Bulk status result = 0
[ 401.385244] usb-storage: Bulk Status S 0x53425355 T 0x1 R 0 Stat 0x0
[ 401.385247] usb-storage: scsi cmd done, result=0x0
[ 401.385251] usb-storage: *** thread sleeping.
[ 401.385433] Vendor: TrekStor Model: i.Beat organix Rev: 0100
[ 401.385446] Type: Direct-Access ANSI SCSI revision:
04
[ 401.386282] usb-storage: queuecommand called
[ 401.386291] usb-storage: *** thread awakened.
[ 401.386295] usb-storage: Command TEST_UNIT_READY (6 bytes)
[ 401.386297] usb-storage: 00 00 00 00 00 00
[ 401.386305] usb-storage: Bulk Command S 0x43425355 T 0x2 L 0 F 0 Trg 0 LUN 0
CL 6
[ 401.386308] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.387246] usb-storage: Status code 0; transferred 31/31
[ 401.387251] usb-storage: -- transfer complete
[ 401.387254] usb-storage: Bulk command transfer result=0
[ 401.387257] usb-storage: Attempting to get CSW...
[ 401.387260] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.388252] usb-storage: Status code 0; transferred 13/13
[ 401.388259] usb-storage: -- transfer complete
[ 401.388262] usb-storage: Bulk status result = 0
[ 401.388266] usb-storage: Bulk Status S 0x53425355 T 0x2 R 0 Stat 0x0
[ 401.388270] usb-storage: scsi cmd done, result=0x0
[ 401.388275] usb-storage: *** thread sleeping.
[ 401.388445] usb-storage: queuecommand called
[ 401.388451] usb-storage: *** thread awakened.
[ 401.388455] usb-storage: Command READ_CAPACITY (10 bytes)
[ 401.388457] usb-storage: 25 00 00 00 00 00 00 00 00 00
[ 401.388465] usb-storage: Bulk Command S 0x43425355 T 0x3 L 8 F 128 Trg 0 LUN
0 CL 10
[ 401.388469] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.389235] usb-storage: Status code 0; transferred 31/31
[ 401.389239] usb-storage: -- transfer complete
[ 401.389242] usb-storage: Bulk command transfer result=0
[ 401.389246] usb-storage: usb_stor_bulk_transfer_sglist: xfer 8 bytes, 1
entries
[ 401.390240] usb-storage: Status code 0; transferred 8/8
[ 401.390244] usb-storage: -- transfer complete
[ 401.390247] usb-storage: Bulk data transfer result 0x0
[ 401.390250] usb-storage: Attempting to get CSW...
[ 401.390253] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.391234] usb-storage: Status code 0; transferred 13/13
[ 401.391238] usb-storage: -- transfer complete
[ 401.391240] usb-storage: Bulk status result = 0
[ 401.391244] usb-storage: Bulk Status S 0x53425355 T 0x3 R 0 Stat 0x0
[ 401.391247] usb-storage: scsi cmd done, result=0x0
[ 401.391251] usb-storage: *** thread sleeping.
[ 401.391419] SCSI device sde: 1012480 512-byte hdwr sectors (518 MB)
[ 401.391446] usb-storage: queuecommand called
[ 401.391451] usb-storage: *** thread awakened.
[ 401.391455] usb-storage: Command MODE_SENSE (6 bytes)
[ 401.391457] usb-storage: 1a 00 3f 00 c0 00
[ 401.391464] usb-storage: Bulk Command S 0x43425355 T 0x4 L 192 F 128 Trg 0
LUN 0 CL 6
[ 401.391467] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.392232] usb-storage: Status code 0; transferred 31/31
[ 401.392235] usb-storage: -- transfer complete
[ 401.392238] usb-storage: Bulk command transfer result=0
[ 401.392241] usb-storage: usb_stor_bulk_transfer_sglist: xfer 192 bytes, 1
entries
[ 401.393232] usb-storage: Status code -121; transferred 4/192
[ 401.393235] usb-storage: -- short read transfer
[ 401.393238] usb-storage: Bulk data transfer result 0x1
[ 401.393240] usb-storage: Attempting to get CSW...
[ 401.393243] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.394231] usb-storage: Status code 0; transferred 13/13
[ 401.394234] usb-storage: -- transfer complete
[ 401.394237] usb-storage: Bulk status result = 0
[ 401.394240] usb-storage: Bulk Status S 0x53425355 T 0x4 R 188 Stat 0x0
[ 401.394243] usb-storage: scsi cmd done, result=0x0
[ 401.394246] usb-storage: *** thread sleeping.
[ 401.394400] sde: Write Protect is off
[ 401.394403] sde: Mode Sense: 03 00 00 00
[ 401.394406] sde: assuming drive cache: write through
[ 401.395542] usb-storage: queuecommand called
[ 401.395554] usb-storage: *** thread awakened.
[ 401.395558] usb-storage: Command TEST_UNIT_READY (6 bytes)
[ 401.395560] usb-storage: 00 00 00 00 00 00
[ 401.395568] usb-storage: Bulk Command S 0x43425355 T 0x5 L 0 F 0 Trg 0 LUN 0
CL 6
[ 401.395572] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.396236] usb-storage: Status code 0; transferred 31/31
[ 401.396240] usb-storage: -- transfer complete
[ 401.396243] usb-storage: Bulk command transfer result=0
[ 401.396245] usb-storage: Attempting to get CSW...
[ 401.396248] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.397232] usb-storage: Status code 0; transferred 13/13
[ 401.397235] usb-storage: -- transfer complete
[ 401.397238] usb-storage: Bulk status result = 0
[ 401.397241] usb-storage: Bulk Status S 0x53425355 T 0x5 R 0 Stat 0x0
[ 401.397245] usb-storage: scsi cmd done, result=0x0
[ 401.397249] usb-storage: *** thread sleeping.
[ 401.397417] usb-storage: queuecommand called
[ 401.397422] usb-storage: *** thread awakened.
[ 401.397425] usb-storage: Command ALLOW_MEDIUM_REMOVAL (6 bytes)
[ 401.397427] usb-storage: 1e 00 00 00 01 00
[ 401.397433] usb-storage: Bulk Command S 0x43425355 T 0x6 L 0 F 0 Trg 0 LUN 0
CL 6
[ 401.397436] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.398231] usb-storage: Status code 0; transferred 31/31
[ 401.398234] usb-storage: -- transfer complete
[ 401.398237] usb-storage: Bulk command transfer result=0
[ 401.398239] usb-storage: Attempting to get CSW...
[ 401.398242] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.399229] usb-storage: Status code 0; transferred 13/13
[ 401.399233] usb-storage: -- transfer complete
[ 401.399235] usb-storage: Bulk status result = 0
[ 401.399238] usb-storage: Bulk Status S 0x53425355 T 0x6 R 0 Stat 0x1
[ 401.399242] usb-storage: -- transport indicates command failure
[ 401.399245] usb-storage: Issuing auto-REQUEST_SENSE
[ 401.399249] usb-storage: Bulk Command S 0x43425355 T 0x7 L 18 F 128 Trg 0
LUN 0 CL 6
[ 401.399252] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.400229] usb-storage: Status code 0; transferred 31/31
[ 401.400233] usb-storage: -- transfer complete
[ 401.400235] usb-storage: Bulk command transfer result=0
[ 401.400238] usb-storage: usb_stor_bulk_transfer_buf: xfer 18 bytes
[ 401.401229] usb-storage: Status code 0; transferred 18/18
[ 401.401233] usb-storage: -- transfer complete
[ 401.401235] usb-storage: Bulk data transfer result 0x0
[ 401.401238] usb-storage: Attempting to get CSW...
[ 401.401240] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.402228] usb-storage: Status code 0; transferred 13/13
[ 401.402231] usb-storage: -- transfer complete
[ 401.402234] usb-storage: Bulk status result = 0
[ 401.402237] usb-storage: Bulk Status S 0x53425355 T 0x7 R 0 Stat 0x0
[ 401.402240] usb-storage: -- Result from auto-sense is 0
[ 401.402243] usb-storage: -- code: 0xf0, key: 0x5, ASC: 0x24, ASCQ: 0x0
[ 401.402246] usb-storage: (Unknown Key): (unknown ASC/ASCQ)
[ 401.402250] usb-storage: scsi cmd done, result=0x2
[ 401.402253] usb-storage: *** thread sleeping.
[ 401.402373] usb-storage: queuecommand called
[ 401.402378] usb-storage: *** thread awakened.
[ 401.402381] usb-storage: Command TEST_UNIT_READY (6 bytes)
[ 401.402383] usb-storage: 00 00 00 00 00 00
[ 401.402388] usb-storage: Bulk Command S 0x43425355 T 0x8 L 0 F 0 Trg 0 LUN 0
CL 6
[ 401.402392] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.403227] usb-storage: Status code 0; transferred 31/31
[ 401.403230] usb-storage: -- transfer complete
[ 401.403233] usb-storage: Bulk command transfer result=0
[ 401.403235] usb-storage: Attempting to get CSW...
[ 401.403237] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.404226] usb-storage: Status code 0; transferred 13/13
[ 401.404230] usb-storage: -- transfer complete
[ 401.404232] usb-storage: Bulk status result = 0
[ 401.404235] usb-storage: Bulk Status S 0x53425355 T 0x8 R 0 Stat 0x0
[ 401.404238] usb-storage: scsi cmd done, result=0x0
[ 401.404241] usb-storage: *** thread sleeping.
[ 401.404407] usb-storage: queuecommand called
[ 401.404412] usb-storage: *** thread awakened.
[ 401.404414] usb-storage: Command READ_CAPACITY (10 bytes)
[ 401.404416] usb-storage: 25 00 00 00 00 00 00 00 00 00
[ 401.404424] usb-storage: Bulk Command S 0x43425355 T 0x9 L 8 F 128 Trg 0 LUN
0 CL 10
[ 401.404427] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.405226] usb-storage: Status code 0; transferred 31/31
[ 401.405230] usb-storage: -- transfer complete
[ 401.405232] usb-storage: Bulk command transfer result=0
[ 401.405235] usb-storage: usb_stor_bulk_transfer_sglist: xfer 8 bytes, 1
entries
[ 401.406227] usb-storage: Status code 0; transferred 8/8
[ 401.406230] usb-storage: -- transfer complete
[ 401.406233] usb-storage: Bulk data transfer result 0x0
[ 401.406235] usb-storage: Attempting to get CSW...
[ 401.406238] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.407226] usb-storage: Status code 0; transferred 13/13
[ 401.407230] usb-storage: -- transfer complete
[ 401.407232] usb-storage: Bulk status result = 0
[ 401.407235] usb-storage: Bulk Status S 0x53425355 T 0x9 R 0 Stat 0x0
[ 401.407238] usb-storage: scsi cmd done, result=0x0
[ 401.407241] usb-storage: *** thread sleeping.
[ 401.407397] SCSI device sde: 1012480 512-byte hdwr sectors (518 MB)
[ 401.407407] usb-storage: queuecommand called
[ 401.407411] usb-storage: *** thread awakened.
[ 401.407414] usb-storage: Command MODE_SENSE (6 bytes)
[ 401.407416] usb-storage: 1a 00 3f 00 c0 00
[ 401.407422] usb-storage: Bulk Command S 0x43425355 T 0xa L 192 F 128 Trg 0
LUN 0 CL 6
[ 401.407425] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.408226] usb-storage: Status code 0; transferred 31/31
[ 401.408230] usb-storage: -- transfer complete
[ 401.408232] usb-storage: Bulk command transfer result=0
[ 401.408235] usb-storage: usb_stor_bulk_transfer_sglist: xfer 192 bytes, 1
entries
[ 401.409226] usb-storage: Status code -121; transferred 4/192
[ 401.409229] usb-storage: -- short read transfer
[ 401.409232] usb-storage: Bulk data transfer result 0x1
[ 401.409234] usb-storage: Attempting to get CSW...
[ 401.409237] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.410225] usb-storage: Status code 0; transferred 13/13
[ 401.410228] usb-storage: -- transfer complete
[ 401.410231] usb-storage: Bulk status result = 0
[ 401.410234] usb-storage: Bulk Status S 0x53425355 T 0xa R 188 Stat 0x0
[ 401.410237] usb-storage: scsi cmd done, result=0x0
[ 401.410240] usb-storage: *** thread sleeping.
[ 401.410392] sde: Write Protect is off
[ 401.410395] sde: Mode Sense: 03 00 00 00
[ 401.410399] sde: assuming drive cache: write through
[ 401.410403] sde:<7>usb-storage: queuecommand called
[ 401.410441] usb-storage: *** thread awakened.
[ 401.410444] usb-storage: Command READ_10 (10 bytes)
[ 401.410446] usb-storage: 28 00 00 00 00 00 00 00 08 00
[ 401.410453] usb-storage: Bulk Command S 0x43425355 T 0xb L 4096 F 128 Trg 0
LUN 0 CL 10
[ 401.410456] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.411224] usb-storage: Status code 0; transferred 31/31
[ 401.411227] usb-storage: -- transfer complete
[ 401.411230] usb-storage: Bulk command transfer result=0
[ 401.411233] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1
entries
[ 401.415232] usb-storage: Status code 0; transferred 4096/4096
[ 401.415236] usb-storage: -- transfer complete
[ 401.415238] usb-storage: Bulk data transfer result 0x0
[ 401.415240] usb-storage: Attempting to get CSW...
[ 401.415243] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.416230] usb-storage: Status code 0; transferred 13/13
[ 401.416234] usb-storage: -- transfer complete
[ 401.416236] usb-storage: Bulk status result = 0
[ 401.416239] usb-storage: Bulk Status S 0x53425355 T 0xb R 0 Stat 0x0
[ 401.416242] usb-storage: scsi cmd done, result=0x0
[ 401.416245] usb-storage: *** thread sleeping.
[ 401.416407] sde1
[ 401.417566] Attached scsi removable disk sde at scsi3, channel 0, id 0, lun 0
[ 401.418543] Attached scsi generic sg4 at scsi3, channel 0, id 0, lun 0,
type 0
[ 401.419470] usb-storage: queuecommand called
[ 401.419480] usb-storage: *** thread awakened.
[ 401.419484] usb-storage: Command (unknown command) (12 bytes)
[ 401.419487] usb-storage: a0 00 00 00 00 00 00 00 10 00 00 00
[ 401.419497] usb-storage: Bulk Command S 0x43425355 T 0xc L 4096 F 128 Trg 0
LUN 0 CL 12
[ 401.419501] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.420231] usb-storage: Status code 0; transferred 31/31
[ 401.420235] usb-storage: -- transfer complete
[ 401.420237] usb-storage: Bulk command transfer result=0
[ 401.420241] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1
entries
[ 401.421227] usb-storage: Status code -32; transferred 0/4096
[ 401.421230] usb-storage: clearing endpoint halt for pipe 0xc0008a80
[ 401.421235] usb-storage: usb_stor_control_msg: rq=01 rqtype=02 value=0000
index=81 len=0
[ 401.422230] usb-storage: usb_stor_clear_halt: result = 0
[ 401.422233] usb-storage: Bulk data transfer result 0x2
[ 401.422236] usb-storage: Attempting to get CSW...
[ 401.422239] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.423220] usb-storage: Status code 0; transferred 13/13
[ 401.423223] usb-storage: -- transfer complete
[ 401.423225] usb-storage: Bulk status result = 0
[ 401.423229] usb-storage: Bulk Status S 0x53425355 T 0xc R 4096 Stat 0x1
[ 401.423232] usb-storage: -- transport indicates command failure
[ 401.423235] usb-storage: -- unexpectedly short transfer
[ 401.423238] usb-storage: Issuing auto-REQUEST_SENSE
[ 401.423242] usb-storage: Bulk Command S 0x43425355 T 0xd L 18 F 128 Trg 0
LUN 0 CL 6
[ 401.423245] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.424219] usb-storage: Status code 0; transferred 31/31
[ 401.424222] usb-storage: -- transfer complete
[ 401.424225] usb-storage: Bulk command transfer result=0
[ 401.424228] usb-storage: usb_stor_bulk_transfer_buf: xfer 18 bytes
[ 401.425219] usb-storage: Status code 0; transferred 18/18
[ 401.425223] usb-storage: -- transfer complete
[ 401.425225] usb-storage: Bulk data transfer result 0x0
[ 401.425228] usb-storage: Attempting to get CSW...
[ 401.425230] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.426218] usb-storage: Status code 0; transferred 13/13
[ 401.426221] usb-storage: -- transfer complete
[ 401.426224] usb-storage: Bulk status result = 0
[ 401.426227] usb-storage: Bulk Status S 0x53425355 T 0xd R 0 Stat 0x0
[ 401.426230] usb-storage: -- Result from auto-sense is 0
[ 401.426232] usb-storage: -- code: 0xf0, key: 0x5, ASC: 0x20, ASCQ: 0x0
[ 401.426236] usb-storage: (Unknown Key): (unknown ASC/ASCQ)
[ 401.426240] usb-storage: scsi cmd done, result=0x2
[ 401.426243] usb-storage: *** thread sleeping.
[ 401.426431] usb-storage: queuecommand called
[ 401.426437] usb-storage: *** thread awakened.
[ 401.426440] usb-storage: Bad LUN (0:1)
[ 401.426442] usb-storage: scsi cmd done, result=0x40000
[ 401.426444] usb-storage: *** thread sleeping.
[ 401.426737] usb-storage: queuecommand called
[ 401.426742] usb-storage: *** thread awakened.
[ 401.426745] usb-storage: Bad target number (1:0)
[ 401.426747] usb-storage: scsi cmd done, result=0x40000
[ 401.426750] usb-storage: *** thread sleeping.
[ 401.426923] usb-storage: queuecommand called
[ 401.426928] usb-storage: *** thread awakened.
[ 401.426930] usb-storage: Bad target number (2:0)
[ 401.426933] usb-storage: scsi cmd done, result=0x40000
[ 401.426935] usb-storage: *** thread sleeping.
[ 401.427111] usb-storage: queuecommand called
[ 401.427117] usb-storage: *** thread awakened.
[ 401.427119] usb-storage: Bad target number (3:0)
[ 401.427122] usb-storage: scsi cmd done, result=0x40000
[ 401.427124] usb-storage: *** thread sleeping.
[ 401.427243] usb-storage: queuecommand called
[ 401.427248] usb-storage: *** thread awakened.
[ 401.427250] usb-storage: Bad target number (4:0)
[ 401.427253] usb-storage: scsi cmd done, result=0x40000
[ 401.427255] usb-storage: *** thread sleeping.
[ 401.427410] usb-storage: queuecommand called
[ 401.427415] usb-storage: *** thread awakened.
[ 401.427417] usb-storage: Bad target number (5:0)
[ 401.427419] usb-storage: scsi cmd done, result=0x40000
[ 401.427422] usb-storage: *** thread sleeping.
[ 401.427622] usb-storage: queuecommand called
[ 401.427627] usb-storage: *** thread awakened.
[ 401.427629] usb-storage: Bad target number (6:0)
[ 401.427632] usb-storage: scsi cmd done, result=0x40000
[ 401.427634] usb-storage: *** thread sleeping.
[ 401.427796] usb-storage: queuecommand called
[ 401.427801] usb-storage: *** thread awakened.
[ 401.427804] usb-storage: Bad target number (7:0)
[ 401.427806] usb-storage: scsi cmd done, result=0x40000
[ 401.427809] usb-storage: *** thread sleeping.
[ 401.427895] usb-storage: device scan complete
[ 401.531939] usb-storage: queuecommand called
[ 401.532075] usb-storage: *** thread awakened.
[ 401.532079] usb-storage: Command TEST_UNIT_READY (6 bytes)
[ 401.532082] usb-storage: 00 00 00 00 00 00
[ 401.532089] usb-storage: Bulk Command S 0x43425355 T 0xe L 0 F 0 Trg 0 LUN 0
CL 6
[ 401.532093] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.533191] usb-storage: Status code 0; transferred 31/31
[ 401.533198] usb-storage: -- transfer complete
[ 401.533201] usb-storage: Bulk command transfer result=0
[ 401.533203] usb-storage: Attempting to get CSW...
[ 401.533207] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.534175] usb-storage: Status code 0; transferred 13/13
[ 401.534179] usb-storage: -- transfer complete
[ 401.534182] usb-storage: Bulk status result = 0
[ 401.534185] usb-storage: Bulk Status S 0x53425355 T 0xe R 0 Stat 0x0
[ 401.534189] usb-storage: scsi cmd done, result=0x0
[ 401.534194] usb-storage: *** thread sleeping.
[ 401.539348] usb-storage: queuecommand called
[ 401.539521] usb-storage: *** thread awakened.
[ 401.539527] usb-storage: Command READ_10 (10 bytes)
[ 401.539529] usb-storage: 28 00 00 0f 72 38 00 00 08 00
[ 401.539540] usb-storage: Bulk Command S 0x43425355 T 0xf L 4096 F 128 Trg 0
LUN 0 CL 10
[ 401.539545] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.540180] usb-storage: Status code 0; transferred 31/31
[ 401.540184] usb-storage: -- transfer complete
[ 401.540187] usb-storage: Bulk command transfer result=0
[ 401.540191] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1
entries
[ 401.546180] usb-storage: Status code 0; transferred 4096/4096
[ 401.546184] usb-storage: -- transfer complete
[ 401.546186] usb-storage: Bulk data transfer result 0x0
[ 401.546189] usb-storage: Attempting to get CSW...
[ 401.546191] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.547177] usb-storage: Status code 0; transferred 13/13
[ 401.547180] usb-storage: -- transfer complete
[ 401.547183] usb-storage: Bulk status result = 0
[ 401.547186] usb-storage: Bulk Status S 0x53425355 T 0xf R 0 Stat 0x0
[ 401.547189] usb-storage: scsi cmd done, result=0x0
[ 401.547193] usb-storage: *** thread sleeping.
[ 401.547400] usb-storage: queuecommand called
[ 401.547455] usb-storage: *** thread awakened.
[ 401.547458] usb-storage: Command READ_10 (10 bytes)
[ 401.547461] usb-storage: 28 00 00 0f 72 f8 00 00 08 00
[ 401.547469] usb-storage: Bulk Command S 0x43425355 T 0x10 L 4096 F 128 Trg 0
LUN 0 CL 10
[ 401.547473] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.548169] usb-storage: Status code 0; transferred 31/31
[ 401.548172] usb-storage: -- transfer complete
[ 401.548174] usb-storage: Bulk command transfer result=0
[ 401.548177] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1
entries
[ 401.553175] usb-storage: Status code 0; transferred 4096/4096
[ 401.553179] usb-storage: -- transfer complete
[ 401.553181] usb-storage: Bulk data transfer result 0x0
[ 401.553183] usb-storage: Attempting to get CSW...
[ 401.553186] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.554174] usb-storage: Status code 0; transferred 13/13
[ 401.554177] usb-storage: -- transfer complete
[ 401.554180] usb-storage: Bulk status result = 0
[ 401.554183] usb-storage: Bulk Status S 0x53425355 T 0x10 R 0 Stat 0x0
[ 401.554186] usb-storage: scsi cmd done, result=0x0
[ 401.554189] usb-storage: *** thread sleeping.
[ 401.554382] usb-storage: queuecommand called
[ 401.554437] usb-storage: *** thread awakened.
[ 401.554441] usb-storage: Command READ_10 (10 bytes)
[ 401.554443] usb-storage: 28 00 00 0f 72 c0 00 00 08 00
[ 401.554451] usb-storage: Bulk Command S 0x43425355 T 0x11 L 4096 F 128 Trg 0
LUN 0 CL 10
[ 401.554455] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.555165] usb-storage: Status code 0; transferred 31/31
[ 401.555169] usb-storage: -- transfer complete
[ 401.555171] usb-storage: Bulk command transfer result=0
[ 401.555174] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1
entries
[ 401.560178] usb-storage: Status code 0; transferred 4096/4096
[ 401.560182] usb-storage: -- transfer complete
[ 401.560185] usb-storage: Bulk data transfer result 0x0
[ 401.560188] usb-storage: Attempting to get CSW...
[ 401.560191] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.561172] usb-storage: Status code 0; transferred 13/13
[ 401.561175] usb-storage: -- transfer complete
[ 401.561177] usb-storage: Bulk status result = 0
[ 401.561181] usb-storage: Bulk Status S 0x53425355 T 0x11 R 0 Stat 0x0
[ 401.561184] usb-storage: scsi cmd done, result=0x0
[ 401.561188] usb-storage: *** thread sleeping.
[ 401.561415] usb-storage: queuecommand called
[ 401.561470] usb-storage: *** thread awakened.
[ 401.561474] usb-storage: Command READ_10 (10 bytes)
[ 401.561477] usb-storage: 28 00 00 0f 72 00 00 00 08 00
[ 401.561486] usb-storage: Bulk Command S 0x43425355 T 0x12 L 4096 F 128 Trg 0
LUN 0 CL 10
[ 401.561489] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.562162] usb-storage: Status code 0; transferred 31/31
[ 401.562165] usb-storage: -- transfer complete
[ 401.562168] usb-storage: Bulk command transfer result=0
[ 401.562171] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1
entries
[ 401.567169] usb-storage: Status code 0; transferred 4096/4096
[ 401.567173] usb-storage: -- transfer complete
[ 401.567175] usb-storage: Bulk data transfer result 0x0
[ 401.567178] usb-storage: Attempting to get CSW...
[ 401.567180] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.568168] usb-storage: Status code 0; transferred 13/13
[ 401.568172] usb-storage: -- transfer complete
[ 401.568174] usb-storage: Bulk status result = 0
[ 401.568177] usb-storage: Bulk Status S 0x53425355 T 0x12 R 0 Stat 0x0
[ 401.568180] usb-storage: scsi cmd done, result=0x0
[ 401.568183] usb-storage: *** thread sleeping.
[ 401.568367] usb-storage: queuecommand called
[ 401.568421] usb-storage: *** thread awakened.
[ 401.568424] usb-storage: Command READ_10 (10 bytes)
[ 401.568427] usb-storage: 28 00 00 0f 72 f0 00 00 08 00
[ 401.568435] usb-storage: Bulk Command S 0x43425355 T 0x13 L 4096 F 128 Trg 0
LUN 0 CL 10
[ 401.568439] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.569160] usb-storage: Status code 0; transferred 31/31
[ 401.569163] usb-storage: -- transfer complete
[ 401.569165] usb-storage: Bulk command transfer result=0
[ 401.569168] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1
entries
[ 401.574168] usb-storage: Status code 0; transferred 4096/4096
[ 401.574172] usb-storage: -- transfer complete
[ 401.574174] usb-storage: Bulk data transfer result 0x0
[ 401.574177] usb-storage: Attempting to get CSW...
[ 401.574180] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.575166] usb-storage: Status code 0; transferred 13/13
[ 401.575169] usb-storage: -- transfer complete
[ 401.575171] usb-storage: Bulk status result = 0
[ 401.575175] usb-storage: Bulk Status S 0x53425355 T 0x13 R 0 Stat 0x0
[ 401.575178] usb-storage: scsi cmd done, result=0x0
[ 401.575180] usb-storage: *** thread sleeping.
[ 401.575407] usb-storage: queuecommand called
[ 401.575463] usb-storage: *** thread awakened.
[ 401.575467] usb-storage: Command READ_10 (10 bytes)
[ 401.575470] usb-storage: 28 00 00 0f 71 70 00 00 08 00
[ 401.575479] usb-storage: Bulk Command S 0x43425355 T 0x14 L 4096 F 128 Trg 0
LUN 0 CL 10
[ 401.575482] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.576156] usb-storage: Status code 0; transferred 31/31
[ 401.576159] usb-storage: -- transfer complete
[ 401.576161] usb-storage: Bulk command transfer result=0
[ 401.576165] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1
entries
[ 401.581163] usb-storage: Status code 0; transferred 4096/4096
[ 401.581167] usb-storage: -- transfer complete
[ 401.581169] usb-storage: Bulk data transfer result 0x0
[ 401.581172] usb-storage: Attempting to get CSW...
[ 401.581174] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.582163] usb-storage: Status code 0; transferred 13/13
[ 401.582166] usb-storage: -- transfer complete
[ 401.582168] usb-storage: Bulk status result = 0
[ 401.582171] usb-storage: Bulk Status S 0x53425355 T 0x14 R 0 Stat 0x0
[ 401.582174] usb-storage: scsi cmd done, result=0x0
[ 401.582177] usb-storage: *** thread sleeping.
[ 401.582384] usb-storage: queuecommand called
[ 401.582440] usb-storage: *** thread awakened.
[ 401.582444] usb-storage: Command READ_10 (10 bytes)
[ 401.582446] usb-storage: 28 00 00 00 00 38 00 00 08 00
[ 401.582455] usb-storage: Bulk Command S 0x43425355 T 0x15 L 4096 F 128 Trg 0
LUN 0 CL 10
[ 401.582458] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.583153] usb-storage: Status code 0; transferred 31/31
[ 401.583157] usb-storage: -- transfer complete
[ 401.583159] usb-storage: Bulk command transfer result=0
[ 401.583163] usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1
entries
[ 401.587161] usb-storage: Status code 0; transferred 4096/4096
[ 401.587164] usb-storage: -- transfer complete
[ 401.587167] usb-storage: Bulk data transfer result 0x0
[ 401.587169] usb-storage: Attempting to get CSW...
[ 401.587172] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.588160] usb-storage: Status code 0; transferred 13/13
[ 401.588163] usb-storage: -- transfer complete
[ 401.588166] usb-storage: Bulk status result = 0
[ 401.588169] usb-storage: Bulk Status S 0x53425355 T 0x15 R 0 Stat 0x0
[ 401.588172] usb-storage: scsi cmd done, result=0x0
[ 401.588175] usb-storage: *** thread sleeping.
[ 401.588398] usb-storage: queuecommand called
[ 401.588453] usb-storage: *** thread awakened.
[ 401.588456] usb-storage: Command READ_10 (10 bytes)
[ 401.588459] usb-storage: 28 00 00 00 00 40 00 00 40 00
[ 401.588467] usb-storage: Bulk Command S 0x43425355 T 0x16 L 32768 F 128 Trg
0 LUN 0 CL 10
[ 401.588471] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.589151] usb-storage: Status code 0; transferred 31/31
[ 401.589154] usb-storage: -- transfer complete
[ 401.589156] usb-storage: Bulk command transfer result=0
[ 401.589160] usb-storage: usb_stor_bulk_transfer_sglist: xfer 32768 bytes, 8
entries
[ 401.621150] usb-storage: Status code 0; transferred 32768/32768
[ 401.621154] usb-storage: -- transfer complete
[ 401.621157] usb-storage: Bulk data transfer result 0x0
[ 401.621159] usb-storage: Attempting to get CSW...
[ 401.621162] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.622146] usb-storage: Status code 0; transferred 13/13
[ 401.622149] usb-storage: -- transfer complete
[ 401.622152] usb-storage: Bulk status result = 0
[ 401.622155] usb-storage: Bulk Status S 0x53425355 T 0x16 R 0 Stat 0x0
[ 401.622159] usb-storage: scsi cmd done, result=0x0
[ 401.622162] usb-storage: *** thread sleeping.
[ 401.622400] usb-storage: queuecommand called
[ 401.622456] usb-storage: *** thread awakened.
[ 401.622460] usb-storage: Command READ_10 (10 bytes)
[ 401.622462] usb-storage: 28 00 00 00 02 28 00 00 28 00
[ 401.622473] usb-storage: Bulk Command S 0x43425355 T 0x17 L 20480 F 128 Trg
0 LUN 0 CL 10
[ 401.622476] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
[ 401.623137] usb-storage: Status code 0; transferred 31/31
[ 401.623141] usb-storage: -- transfer complete
[ 401.623143] usb-storage: Bulk command transfer result=0
[ 401.623147] usb-storage: usb_stor_bulk_transfer_sglist: xfer 20480 bytes, 5
entries
[ 401.643138] usb-storage: Status code 0; transferred 20480/20480
[ 401.643141] usb-storage: -- transfer complete
[ 401.643144] usb-storage: Bulk data transfer result 0x0
[ 401.643146] usb-storage: Attempting to get CSW...
[ 401.643149] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
[ 401.644142] usb-storage: Status code 0; transferred 13/13
[ 401.644146] usb-storage: -- transfer complete
[ 401.644148] usb-storage: Bulk status result = 0
[ 401.644152] usb-storage: Bulk Status S 0x53425355 T 0x17 R 0 Stat 0x0
[ 401.644155] usb-storage: scsi cmd done, result=0x0
[ 401.644158] usb-storage: *** thread sleeping.
----
---- When disconnecting the MP3 player with ehci disabled: ----
[ 542.731122] hub 1-1:1.0: state 5 ports 4 chg 0000 evt 0008
[ 542.734093] hub 1-1:1.0: port 3, status 0100, change 0001, 12 Mb/s
[ 542.735094] usb 1-1.3: USB disconnect, address 10
[ 542.735098] usb 1-1.3: usb_disable_device nuking all URBs
[ 542.735105] usb 1-1.3: unregistering interface 1-1.3:1.0
[ 542.735146] usb-storage: storage_disconnect() called
[ 542.735150] usb-storage: usb_stor_stop_transport called
[ 542.740879] usb-storage: -- usb_stor_release_resources
[ 542.740886] usb-storage: -- sending exit command to thread
[ 542.740891] usb-storage: -- dissociate_dev
[ 542.740914] usb 1-1.3:1.0: hotplug
[ 542.741214] usb 1-1.3: unregistering device
[ 542.743680] usb 1-1.3: hotplug
[ 542.743722] usb-storage: *** thread awakened.
[ 542.743725] usb-storage: -- exiting
[ 542.856057] hub 1-1:1.0: debounce: port 3: total 100ms stable 100ms status
0x100
----
---- 2.6.15-rc5-mm1 kernel panic when connecting MP3 player: ----
usb 1-1.3: new high speed USB device using ehci_hcd and address 6
usb 1-1.3: configuration #1 chosen from 1 choice
Unable to handle kernel NULL pointer dereference at virtual address 00000000
printing eip:
c015f878
*pde = 00000000
Oops: 0000 [#1]
PREEMPT
last sysfs file: /class/vc/vcsa6/dev
Modules linked in: it87 hwmon_vid eeprom lm90 hwmon i2c_isa i2c_viapro i2c_core
CPU: 0
EIP: 0060:[<c015f878>] Not tainted VLI
EFLAGS: 00010046 (2.6.15-rc5-mm1)
EIP is at kfree+0x38/0x70
eax: 00000000 ebx: 0000018d ecx: dfcf6c20 edx: 00000001
esi: 00000000 edi: 00000001 ebp: 00000202 esp: c1565e60
ds: 007b es: 007b ss: 0068
Process khubd (pid: 88, threadinfo=c1564000 task=c152b550)
Stack: <0>00000000 00000009 0000018d ffffffb9 dfa0e400 dec05c00 c03442ff
00000001
<0>80000600 00000009 00000000 00000001 00000000 00000000 00000000
00001388
<0>dec05cf4 00000001 00000001 dfcf6c20 dfa0e400 00000000 dec05c00
00000001
Call Trace:
[<c03442ff>] usb_set_configuration+0x39f/0x4a0
[<c033db1a>] usb_new_device+0xaa/0x180
[<c033ee0a>] hub_port_connect_change+0x27a/0x450
[<c0330089>] arm_lock64+0x559/0x5b0
[<c033f23a>] hub_events+0x25a/0x4c0
[<c033f4b9>] hub_thread+0x19/0xf0
[<c012fb70>] autoremove_wake_function+0x0/0x60
[<c012fb70>] autoremove_wake_function+0x0/0x60
[<c033f4a0>] hub_thread+0x0/0xf0
[<c012f586>] kthread+0xb6/0xf0
[<c012f4d0>] kthread+0x0/0xf0
[<c0101055>] kernel_thread_helper+0x5/0x10
Code: 24 08 89 74 24 0c 89 6c 24 14 85 ff 74 30 9c 5d fa 8d 87 00 00 00 40 c1
e8 0c c1 e0 05 03 05 30 50 57 c0 8b 70 18 e8 d8 5b 11 00 <8b> 1c 86 8b 03 3b 43
04 73 1d 89 7c 83 10 40 89 03 55 9d 8b 5c
----
Regards,
Andreas Klauer
-------------------------------------------------------
This SF.net email is sponsored by: Splunk Inc. Do you grep through log files
for problems? Stop! Download the new AJAX search engine that makes
searching your log files as easy as surfing the web. DOWNLOAD SPLUNK!
http://ads.osdn.com/?ad_id=7637&alloc_id=16865&op=click
_______________________________________________
Linux-usb-users@lists.sourceforge.net
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-users
