Hi,
Andrew Morton wrote:
Running 2.6.7-rc2-mm2, I connected up the Sony digital camera.
dmesg says:
From two days ago:
usb 4-2: new full speed USB device using address 2 Initializing USB Mass Storage driver... scsi2 : SCSI emulation for USB Mass Storage devices Vendor: Sony Model: Sony DSC Rev: 3.28 Type: Direct-Access ANSI SCSI revision: 02 SCSI device sdc: 31680 512-byte hdwr sectors (16 MB) sdc: assuming Write Enabled sdc: assuming drive cache: write through sdc: sdc1 Attached scsi removable disk sdc at scsi2, channel 0, id 0, lun 0 USB Mass Storage device found at 2 usbcore: registered new driver usb-storage USB Mass Storage support registered. inserting floppy driver for 2.6.7-rc2-mm2 Floppy drive(s): fd0 is 1.44M FDC 0 is a post-1991 82077 usb 4-2: USB disconnect, address 2
But today it hung:
usb 4-2: new full speed USB device using address 3 scsi3 : SCSI emulation for USB Mass Storage devices
Ok. I investigated further. For me, the problem is that usb-storage only works the first time on every reboot. I'm testing with a USB2 compactflash reader.
I am also running 2.6.7-rc2-mm2.
Here are logs with usb debug & usb storage debug:
I plug the device in:
Jun 7 13:04:39 dsd ehci_hcd 0000:00:02.2: GetStatus port 1 status 001803 POWER sig=j CSC CONNECT
Jun 7 13:04:39 dsd hub 1-0:1.0: port 1, status 0501, change 0001, 480 Mb/s
Jun 7 13:04:39 dsd hub 1-0:1.0: debounce: port 1: delay 100ms stable 4 status 0x501
Jun 7 13:04:39 dsd hub 1-0:1.0: port 1 not reset yet, waiting 50ms
Jun 7 13:04:39 dsd ehci_hcd 0000:00:02.2: port 1 high speed
Jun 7 13:04:39 dsd ehci_hcd 0000:00:02.2: GetStatus port 1 status 001005 POWER sig=se0 PE CONNECT
Jun 7 13:04:39 dsd usb 1-1: new high speed USB device using address 2
Jun 7 13:04:39 dsd ehci_hcd 0000:00:02.2: devpath 1 ep0out 3strikes
Jun 7 13:04:39 dsd usb 1-1: new device strings: Mfr=2, Product=3, SerialNumber=4
Jun 7 13:04:39 dsd usb 1-1: default language 0x0409
Jun 7 13:04:39 dsd usb 1-1: Product: zMate CF USB2.0
Jun 7 13:04:39 dsd usb 1-1: Manufacturer: Dane-Elec
Jun 7 13:04:39 dsd usb 1-1: SerialNumber: 03214
Jun 7 13:04:39 dsd usb 1-1: hotplug
Jun 7 13:04:39 dsd usb 1-1: adding 1-1:1.0 (config #1, interface 0)
Jun 7 13:04:39 dsd usb 1-1:1.0: hotplug
Jun 7 13:04:39 dsd Initializing USB Mass Storage driver...
Jun 7 13:04:39 dsd usb-storage 1-1:1.0: usb_probe_interface
Jun 7 13:04:39 dsd usb-storage 1-1:1.0: usb_probe_interface - got id
Jun 7 13:04:39 dsd usb-storage: USB Mass Storage device detected
Jun 7 13:04:39 dsd usb-storage: altsetting is 0, id_index is 93
Jun 7 13:04:39 dsd usb-storage: -- associate_dev
Jun 7 13:04:40 dsd usb-storage: Transport: Bulk
Jun 7 13:04:40 dsd usb-storage: Protocol: Transparent SCSI
Jun 7 13:04:40 dsd usb-storage: Endpoints: In: 0xd535bf94 Out: 0xd535bfa8 Int: 0x00000000 (Period 0)
Jun 7 13:04:40 dsd usb-storage: usb_stor_control_msg: rq=fe rqtype=a1 value=0000 index=00 len=1
Jun 7 13:04:40 dsd usb-storage: usb_stor_control_msg: rq=01 rqtype=02 value=0000 index=81 len=0
Jun 7 13:04:40 dsd usb-storage: usb_stor_clear_halt: result = 0
Jun 7 13:04:40 dsd usb-storage: usb_stor_control_msg: rq=01 rqtype=02 value=0000 index=02 len=0
Jun 7 13:04:40 dsd usb-storage: usb_stor_clear_halt: result = 0
Jun 7 13:04:40 dsd usb-storage: GetMaxLUN command result is -32, data is 0
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd scsi2 : SCSI emulation for USB Mass Storage devices
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Command INQUIRY (6 bytes)
Jun 7 13:04:40 dsd usb-storage: 12 00 00 00 24 00
Jun 7 13:04:40 dsd usb-storage: Bulk Command S 0x43425355 T 0xfec L 36 F 128 Trg 0 LUN 0 CL 6
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 31/31
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk command transfer result=0
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 36 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 36/36
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk data transfer result 0x0
Jun 7 13:04:40 dsd usb-storage: Attempting to get CSW...
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 13/13
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk status result = 0
Jun 7 13:04:40 dsd usb-storage: Bulk Status S 0x53425355 T 0xfec R 0 Stat 0x0
Jun 7 13:04:40 dsd usb-storage: Fixing INQUIRY data to show SCSI rev 2 - was 0
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x0
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Command INQUIRY (6 bytes)
Jun 7 13:04:40 dsd usb-storage: 12 00 00 00 25 00
Jun 7 13:04:40 dsd usb-storage: Bulk Command S 0x43425355 T 0xfed L 37 F 128 Trg 0 LUN 0 CL 6
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 31/31
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk command transfer result=0
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 37 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 36/37
Jun 7 13:04:40 dsd usb-storage: -- short transfer
Jun 7 13:04:40 dsd usb-storage: Bulk data transfer result 0x1
Jun 7 13:04:40 dsd usb-storage: Attempting to get CSW...
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jun 7 13:04:40 dsd usb-storage: Status code -32; transferred 0/13
Jun 7 13:04:40 dsd usb-storage: clearing endpoint halt for pipe 0xc0008280
Jun 7 13:04:40 dsd usb-storage: usb_stor_control_msg: rq=01 rqtype=02 value=0000 index=81 len=0
Jun 7 13:04:40 dsd usb-storage: usb_stor_clear_halt: result = 0
Jun 7 13:04:40 dsd usb-storage: Attempting to get CSW (2nd try)...
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 13/13
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk status result = 0
Jun 7 13:04:40 dsd usb-storage: Bulk Status S 0x53425355 T 0xfed R 1 Stat 0x0
Jun 7 13:04:40 dsd usb-storage: Fixing INQUIRY data to show SCSI rev 2 - was 0
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x0
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd Vendor: Generic Model: STORAGE DEVICE Rev: 1.01
Jun 7 13:04:40 dsd Type: Direct-Access ANSI SCSI revision: 02
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Command TEST_UNIT_READY (6 bytes)
Jun 7 13:04:40 dsd usb-storage: 00 00 00 00 00 00
Jun 7 13:04:40 dsd usb-storage: Bulk Command S 0x43425355 T 0xfee L 0 F 0 Trg 0 LUN 0 CL 6
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 31/31
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk command transfer result=0
Jun 7 13:04:40 dsd usb-storage: Attempting to get CSW...
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 13/13
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk status result = 0
Jun 7 13:04:40 dsd usb-storage: Bulk Status S 0x53425355 T 0xfee R 0 Stat 0x0
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x0
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Command READ_CAPACITY (10 bytes)
Jun 7 13:04:40 dsd usb-storage: 25 00 00 00 00 00 00 00 00 00
Jun 7 13:04:40 dsd usb-storage: Bulk Command S 0x43425355 T 0xfef L 8 F 128 Trg 0 LUN 0 CL 10
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 31/31
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk command transfer result=0
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 8 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 8/8
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk data transfer result 0x0
Jun 7 13:04:40 dsd usb-storage: Attempting to get CSW...
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 13/13
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk status result = 0
Jun 7 13:04:40 dsd usb-storage: Bulk Status S 0x53425355 T 0xfef R 0 Stat 0x0
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x0
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd SCSI device sdb: 63488 512-byte hdwr sectors (33 MB)
Jun 7 13:04:40 dsd sdb: assuming Write Enabled
Jun 7 13:04:40 dsd sdb: assuming drive cache: write through
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Command TEST_UNIT_READY (6 bytes)
Jun 7 13:04:40 dsd usb-storage: 00 00 00 00 00 00
Jun 7 13:04:40 dsd usb-storage: Bulk Command S 0x43425355 T 0xff0 L 0 F 0 Trg 0 LUN 0 CL 6
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 31/31
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk command transfer result=0
Jun 7 13:04:40 dsd usb-storage: Attempting to get CSW...
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 13/13
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk status result = 0
Jun 7 13:04:40 dsd usb-storage: Bulk Status S 0x53425355 T 0xff0 R 0 Stat 0x0
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x0
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Command ALLOW_MEDIUM_REMOVAL (6 bytes)
Jun 7 13:04:40 dsd usb-storage: 1e 00 00 00 01 00
Jun 7 13:04:40 dsd usb-storage: Bulk Command S 0x43425355 T 0xff1 L 0 F 0 Trg 0 LUN 0 CL 6
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 31/31
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk command transfer result=0
Jun 7 13:04:40 dsd usb-storage: Attempting to get CSW...
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 13/13
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk status result = 0
Jun 7 13:04:40 dsd usb-storage: Bulk Status S 0x53425355 T 0xff1 R 0 Stat 0x0
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x0
Jun 7 13:04:40 dsd sdb:<7>usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Command READ_10 (10 bytes)
Jun 7 13:04:40 dsd usb-storage: 28 00 00 00 00 00 00 00 08 00
Jun 7 13:04:40 dsd usb-storage: Bulk Command S 0x43425355 T 0xff2 L 4096 F 128 Trg 0 LUN 0 CL 10
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 31/31
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk command transfer result=0
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096 bytes, 1 entries
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 4096/4096
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk data transfer result 0x0
Jun 7 13:04:40 dsd usb-storage: Attempting to get CSW...
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 13/13
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk status result = 0
Jun 7 13:04:40 dsd usb-storage: Bulk Status S 0x53425355 T 0xff2 R 0 Stat 0x0
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x0
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd sdb1
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Command ALLOW_MEDIUM_REMOVAL (6 bytes)
Jun 7 13:04:40 dsd usb-storage: 1e 00 00 00 00 00
Jun 7 13:04:40 dsd usb-storage: Bulk Command S 0x43425355 T 0xff3 L 0 F 0 Trg 0 LUN 0 CL 6
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 31/31
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk command transfer result=0
Jun 7 13:04:40 dsd usb-storage: Attempting to get CSW...
Jun 7 13:04:40 dsd usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jun 7 13:04:40 dsd usb-storage: Status code 0; transferred 13/13
Jun 7 13:04:40 dsd usb-storage: -- transfer complete
Jun 7 13:04:40 dsd usb-storage: Bulk status result = 0
Jun 7 13:04:40 dsd usb-storage: Bulk Status S 0x53425355 T 0xff3 R 0 Stat 0x0
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x0
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd Attached scsi removable disk sdb at scsi2, channel 0, id 0, lun 0
Jun 7 13:04:40 dsd Attached scsi generic sg1 at scsi2, channel 0, id 0, lun 0, type 0
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Bad target number (1:0)
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x40000
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Bad target number (2:0)
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x40000
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Bad target number (3:0)
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x40000
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Bad target number (4:0)
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x40000
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Bad target number (5:0)
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x40000
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Bad target number (6:0)
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x40000
Jun 7 13:04:40 dsd usb-storage: queuecommand called
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd usb-storage: *** thread awakened.
Jun 7 13:04:40 dsd usb-storage: Bad target number (7:0)
Jun 7 13:04:40 dsd usb-storage: scsi cmd done, result=0x40000
Jun 7 13:04:40 dsd usb-storage: *** thread sleeping.
Jun 7 13:04:40 dsd USB Mass Storage device found at 2
Jun 7 13:04:40 dsd usbcore: registered new driver usb-storage
Jun 7 13:04:40 dsd USB Mass Storage support registered.
Jun 7 13:04:41 dsd scsi.agent[9334]: disk at /devices/pci0000:00/0000:00:02.2/usb1/1-1/1-1:1.0/host2/2:0:0:0
Jun 7 13:05:00 dsd CRON[9381]: (root) CMD (test -x /usr/sbin/run-crons && /usr/sbin/run-crons )
All is fine up to here. At this point, I can mount and copy files, etc, but I have not done so in this instance. I now unplugged it straight away, as shown below:
Jun 7 13:05:10 dsd hub 1-0:1.0: port 1, status 0100, change 0001, 12 Mb/s Jun 7 13:05:10 dsd usb 1-1: USB disconnect, address 2 Jun 7 13:05:10 dsd usb 1-1: usb_disable_device nuking all URBs Jun 7 13:05:10 dsd usb 1-1: unregistering interface 1-1:1.0 Jun 7 13:05:10 dsd usb-storage: storage_disconnect() called Jun 7 13:05:10 dsd usb-storage: usb_stor_stop_transport called Jun 7 13:05:10 dsd usb-storage: -- dissociate_dev Jun 7 13:05:10 dsd usb-storage: -- sending exit command to thread Jun 7 13:05:10 dsd usb-storage: *** thread awakened. Jun 7 13:05:10 dsd usb-storage: -- exit command received Jun 7 13:05:10 dsd usb-storage: -- usb_stor_release_resources finished Jun 7 13:05:10 dsd usb 1-1:1.0: hotplug Jun 7 13:05:10 dsd usb 1-1: unregistering device Jun 7 13:05:10 dsd usb 1-1: hotplug
I plug the device back in:
Jun 7 13:05:23 dsd ehci_hcd 0000:00:02.2: GetStatus port 1 status 001803 POWER sig=j CSC CONNECT
Jun 7 13:05:23 dsd hub 1-0:1.0: port 1, status 0501, change 0001, 480 Mb/s
Jun 7 13:05:24 dsd hub 1-0:1.0: debounce: port 1: delay 100ms stable 4 status 0x501
Jun 7 13:05:24 dsd hub 1-0:1.0: port 1 not reset yet, waiting 50ms
Jun 7 13:05:24 dsd ehci_hcd 0000:00:02.2: port 1 high speed
Jun 7 13:05:24 dsd ehci_hcd 0000:00:02.2: GetStatus port 1 status 001005 POWER sig=se0 PE CONNECT
Jun 7 13:05:24 dsd usb 1-1: new high speed USB device using address 3
Jun 7 13:05:24 dsd ehci_hcd 0000:00:02.2: devpath 1 ep0out 3strikes
Jun 7 13:05:24 dsd usb 1-1: new device strings: Mfr=2, Product=3, SerialNumber=4
Jun 7 13:05:24 dsd usb 1-1: default language 0x0409
Jun 7 13:05:24 dsd usb 1-1: Product: zMate CF USB2.0
Jun 7 13:05:24 dsd usb 1-1: Manufacturer: Dane-Elec
Jun 7 13:05:24 dsd usb 1-1: SerialNumber: 03214
Jun 7 13:05:24 dsd usb 1-1: hotplug
Jun 7 13:05:24 dsd usb 1-1: adding 1-1:1.0 (config #1, interface 0)
Jun 7 13:05:24 dsd usb 1-1:1.0: hotplug
Jun 7 13:05:24 dsd usb-storage 1-1:1.0: usb_probe_interface
Jun 7 13:05:24 dsd usb-storage 1-1:1.0: usb_probe_interface - got id
Jun 7 13:05:24 dsd usb-storage: USB Mass Storage device detected
Jun 7 13:05:24 dsd usb-storage: altsetting is 0, id_index is 93
Jun 7 13:05:24 dsd usb-storage: -- associate_dev
Jun 7 13:05:24 dsd usb-storage: Transport: Bulk
Jun 7 13:05:24 dsd usb-storage: Protocol: Transparent SCSI
Jun 7 13:05:24 dsd usb-storage: Endpoints: In: 0xde53b3d4 Out: 0xde53b3e8 Int: 0x00000000 (Period 0)
Jun 7 13:05:24 dsd usb-storage: usb_stor_control_msg: rq=fe rqtype=a1 value=0000 index=00 len=1
Jun 7 13:05:24 dsd usb-storage: usb_stor_control_msg: rq=01 rqtype=02 value=0000 index=81 len=0
Jun 7 13:05:24 dsd usb-storage: usb_stor_clear_halt: result = 0
Jun 7 13:05:24 dsd usb-storage: usb_stor_control_msg: rq=01 rqtype=02 value=0000 index=02 len=0
Jun 7 13:05:24 dsd usb-storage: usb_stor_clear_halt: result = 0
Jun 7 13:05:24 dsd usb-storage: GetMaxLUN command result is -32, data is 85
Jun 7 13:05:24 dsd usb-storage: *** thread sleeping.
Jun 7 13:05:24 dsd scsi3 : SCSI emulation for USB Mass Storage devices
Jun 7 13:05:24 dsd usb-storage: queuecommand called
Jun 7 13:05:24 dsd usb-storage: *** thread awakened.
Jun 7 13:05:24 dsd usb-storage: Command INQUIRY (6 bytes)
Jun 7 13:05:24 dsd usb-storage: 12 00 00 00 24 00
Jun 7 13:05:30 dsd usb-storage: command_abort called
Jun 7 13:05:30 dsd usb-storage: usb_stor_stop_transport called
I traced the code path from when the final inquiry command was recieved and I found:
1. usb.c:usb_stor_control_thread calls us->proto_handler, which is set to point at protocol.c:usb_stor_transparent_scsi_command
2. usb_stor_transparent_scsi_command calls transport.c:usb_stor_invoke_transport
3. usb_stor_invoke_transport makes the following call: down(&(us->pusb_dev->serialize)); And code execution freezes here.
I don't have the knowledge to debug this further, but hopefully this will be useful to someone who does.
Daniel
------------------------------------------------------- This SF.Net email is sponsored by the new InstallShield X.
From Windows to Linux, servers to mobile, InstallShield X is the one
installation-authoring solution that does it all. Learn more and evaluate today! http://www.installshield.com/Dev2Dev/0504 _______________________________________________ [EMAIL PROTECTED] To unsubscribe, use the last form field at: https://lists.sourceforge.net/lists/listinfo/linux-usb-devel