Sorry for responding so late on your email (its almost a year now:)).
Anyway, I still have the same problem, though in the mean time I've upgraded to
a newer kernel.
To recap the problem:
"I'm trying to get my iriver hp120 to work with EHCI. It already seem to work
with just the OHCI driver although quit slow ofcourse. Basically the whole
usb system seems to lockup whenever I start transfering files to my iriver.
The rest of the system is not locked up btw. I'm running Linux 2.6.14.5 on a
nforce motherboard with
an usb2 pci extension card."
Again, the copy process still locks up (after 20 seconds or so) and these are
the last entries I get in my kernel log:
---
Jan 2 14:44:44 einstein usb-storage: queuecommand called
Jan 2 14:44:44 einstein usb-storage: *** thread awakened.
Jan 2 14:44:44 einstein usb-storage: Command WRITE_10 (10 bytes)
Jan 2 14:44:44 einstein usb-storage: 2a 00 01 6c 74 b5 00 00 80 00
Jan 2 14:44:44 einstein usb-storage: Bulk Command S 0x43425355 T 0xabb L 65536
F 0 Trg 0 LUN 0 CL 10
Jan 2 14:44:44 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 2 14:44:44 einstein usb-storage: Status code 0; transferred 31/31
Jan 2 14:44:44 einstein usb-storage: -- transfer complete
Jan 2 14:44:44 einstein usb-storage: Bulk command transfer result=0
Jan 2 14:44:44 einstein usb-storage: usb_stor_bulk_transfer_sglist: xfer 65536
bytes, 6 entries
Jan 2 14:45:14 einstein usb-storage: command_abort called
Jan 2 14:45:14 einstein usb-storage: usb_stor_stop_transport called
Jan 2 14:45:14 einstein usb-storage: -- cancelling sg request
---
And I did finally found out how to get a stack trace, so here it is:
-----
Jan 2 14:45:38 einstein scsi_eh_0 S C038B0A7 0 781 7
974 739 (L-TLB)
Jan 2 14:45:38 einstein ddda1fac ddc530b0 c13c6160 c038b0a7 c038b0d4 ddc530b0
c13c6160 98cf1f00
Jan 2 14:45:38 einstein 98cf1f00 003d08ba ddda0000 c0122df1 98cf1f00 00000000
ddc531d8 ddc530b0
Jan 2 14:45:38 einstein c1462a30 c13c6160 990c2800 003d08ba ddda0000 c13c6160
990c2800 003d08ba
Jan 2 14:45:38 einstein Call Trace:
Jan 2 14:45:38 einstein [<c038b0a7>] schedule+0x557/0xd60
Jan 2 14:45:38 einstein [<c038b0d4>] schedule+0x584/0xd60
Jan 2 14:45:38 einstein [<c0122df1>] release_task+0xc1/0x160
Jan 2 14:45:38 einstein [<c011c895>] complete+0x45/0x60
Jan 2 14:45:38 einstein [<c02b2580>] scsi_error_handler+0x0/0x130
Jan 2 14:45:38 einstein [<c02b25d5>] scsi_error_handler+0x55/0x130
Jan 2 14:45:38 einstein [<c0138148>] kthread+0xc8/0xd0
Jan 2 14:45:38 einstein [<c0138080>] kthread+0x0/0xd0
Jan 2 14:45:38 einstein [<c0101295>] kernel_thread_helper+0x5/0x10
----
Jan 2 14:45:38 einstein scsi_eh_1 D 003D08E2 0 4654 7
3835 (L-TLB)
Jan 2 14:45:38 einstein dd7f3efc db6ba0d0 c13c61a4 003d08e2 dc4d1c00 dbba73c0
dc4d1c00 df2530af
Jan 2 14:45:38 einstein acad0000 003d08e2 dd7f2000 00000400 df801b5c 00000000
dc229198 dc229070
Jan 2 14:45:38 einstein db6ba0d0 c13c6160 acad0000 003d08e2 dd7f2000 00000028
dbba73c4 dbbc406c
Jan 2 14:45:38 einstein Call Trace:
Jan 2 14:45:38 einstein [<df2530af>] unlink1+0x2f/0x90 [usbcore]
Jan 2 14:45:38 einstein [<c038ba34>] wait_for_completion+0x94/0xd0
Jan 2 14:45:38 einstein [<c011c6b0>] default_wake_function+0x0/0x20
Jan 2 14:45:38 einstein [<df7f3289>] command_abort+0x99/0xd0 [usb_storage]
Jan 2 14:45:38 einstein [<c02b14a7>] scsi_eh_abort_cmds+0x57/0x110
Jan 2 14:45:38 einstein [<c02b2417>] scsi_unjam_host+0xb7/0x220
Jan 2 14:45:38 einstein [<c02b2580>] scsi_error_handler+0x0/0x130
Jan 2 14:45:38 einstein [<c02b2679>] scsi_error_handler+0xf9/0x130
Jan 2 14:45:38 einstein [<c0138148>] kthread+0xc8/0xd0
Jan 2 14:45:38 einstein [<c0138080>] kthread+0x0/0xd0
Jan 2 14:45:38 einstein [<c0101295>] kernel_thread_helper+0x5/0x10
----
Jan 2 14:45:38 einstein usb-storage D 003D08DB 0 4655 1
4511 (L-TLB)
Jan 2 14:45:38 einstein dd213e48 db6ba0d0 c13c61a4 003d08db df7dea51 dbba77f4
00000001 dc4d1ce0
Jan 2 14:45:38 einstein b0895400 003d08db dd212000 dbba73c0 dd213e30 00000000
db6721b8 db672090
Jan 2 14:45:38 einstein db6ba0d0 c13c6160 b0895400 003d08db dd212000 dc4d1cd0
dbba77e0 dbba73c0
Jan 2 14:45:38 einstein Call Trace:
Jan 2 14:45:38 einstein [<df7dea51>] qh_urb_transaction+0x111/0x380 [ehci_hcd]
Jan 2 14:45:38 einstein [<c038ba34>] wait_for_completion+0x94/0xd0
Jan 2 14:45:38 einstein [<c011c6b0>] default_wake_function+0x0/0x20
Jan 2 14:45:38 einstein [<df254e50>] usb_sg_wait+0x50/0x190 [usbcore]
Jan 2 14:45:38 einstein [<df7f466f>] usb_stor_bulk_transfer_sglist+0xff/0x170
[usb_storage]
Jan 2 14:45:38 einstein [<df7f472f>] usb_stor_bulk_transfer_sg+0x4f/0xa0
[usb_storage]
Jan 2 14:45:38 einstein [<df7f51c6>] usb_stor_Bulk_transport+0x1a6/0x400
[usb_storage]
Jan 2 14:45:38 einstein [<df7f47af>] usb_stor_invoke_transport+0x2f/0x460
[usb_storage]
Jan 2 14:45:38 einstein [<c012179b>] printk+0x1b/0x20
Jan 2 14:45:38 einstein [<df7f5a94>] usb_stor_control_thread+0x154/0x2b0
[usb_storage]
Jan 2 14:45:38 einstein [<c010313e>] ret_from_fork+0x6/0x14
Jan 2 14:45:38 einstein [<df7f5940>] usb_stor_control_thread+0x0/0x2b0
[usb_storage]
Jan 2 14:45:38 einstein [<df7f5940>] usb_stor_control_thread+0x0/0x2b0
[usb_storage]
Jan 2 14:45:38 einstein [<c0101295>] kernel_thread_helper+0x5/0x10
----
Jan 2 14:45:38 einstein Call Trace:
Jan 2 14:45:38 einstein [<c0296354>] generic_make_request+0x94/0x250
Jan 2 14:45:38 einstein [<c0138660>] autoremove_wake_function+0x0/0x60
Jan 2 14:45:38 einstein [<c038c128>] io_schedule+0x28/0x40
Jan 2 14:45:38 einstein [<c01680bf>] sync_buffer+0x3f/0x50
Jan 2 14:45:38 einstein [<c038c365>] __wait_on_bit+0x45/0x70
Jan 2 14:45:38 einstein [<c0168080>] sync_buffer+0x0/0x50
Jan 2 14:45:38 einstein [<c0168080>] sync_buffer+0x0/0x50
Jan 2 14:45:38 einstein [<c038c404>] out_of_line_wait_on_bit+0x74/0x90
Jan 2 14:45:38 einstein [<c01386c0>] wake_bit_function+0x0/0x60
Jan 2 14:45:38 einstein [<c01699f9>] __bread_slow+0x69/0x80
Jan 2 14:45:38 einstein [<df814067>] fat_ent_bread+0x57/0xe0 [fat]
Jan 2 14:45:38 einstein [<df814c64>] fat_alloc_clusters+0x194/0x3d0 [fat]
Jan 2 14:45:38 einstein [<df810660>] fat_bmap_cluster+0x50/0xa0 [fat]
Jan 2 14:45:38 einstein [<df815af8>] fat_add_cluster+0x28/0x80 [fat]
Jan 2 14:45:38 einstein [<df815cac>] fat_get_block+0x15c/0x1b0 [fat]
Jan 2 14:45:38 einstein [<c016a6fd>] __block_prepare_write+0x28d/0x4d0
Jan 2 14:45:38 einstein [<df815b50>] fat_get_block+0x0/0x1b0 [fat]
Jan 2 14:45:38 einstein [<c016b12f>] cont_prepare_write+0x20f/0x310
Jan 2 14:45:38 einstein [<df815b50>] fat_get_block+0x0/0x1b0 [fat]
Jan 2 14:45:38 einstein [<df815d84>] fat_prepare_write+0x34/0x40 [fat]
Jan 2 14:45:38 einstein [<df815b50>] fat_get_block+0x0/0x1b0 [fat]
Jan 2 14:45:38 einstein [<c0146aa7>] generic_file_buffered_write+0x297/0x730
Jan 2 14:45:38 einstein [<c0184e50>] inode_update_time+0x50/0xe0
Jan 2 14:45:38 einstein [<c0147201>]
__generic_file_aio_write_nolock+0x2c1/0x520
Jan 2 14:45:38 einstein [<c0145030>] file_read_actor+0x0/0x110
Jan 2 14:45:38 einstein [<c0147750>] generic_file_aio_write+0x80/0x110
Jan 2 14:45:38 einstein [<c0166b6a>] do_sync_write+0xca/0x130
Jan 2 14:45:38 einstein [<c0138660>] autoremove_wake_function+0x0/0x60
Jan 2 14:45:38 einstein [<c0166ca6>] vfs_write+0xd6/0x1b0
Jan 2 14:45:38 einstein [<c0166e4b>] sys_write+0x4b/0x80
Jan 2 14:45:38 einstein [<c0103207>] sysenter_past_esp+0x54/0x75
Thanks Again,
Sander
On Tuesday 19 April 2005 12:27, Alan Stern wrote:
> On Mon, 18 Apr 2005, Sander Jansen wrote:
> > Ok. I finally was able to compile a kernel with debug messages on.
> > So here's what I'm doing. I'm plugging in the device and hotplug
> > automatically detects it [see connect.log as attached]
> >
> > Then I mount it and start copying files. Which involves a lot of message
> > like this:
>
> <snip>
>
> > Then after so many files processed, the cp command locks up. This is what
> > usb-storage says:
> >
> > usb-storage: queuecommand called
> > usb-storage: *** thread awakened.
> > usb-storage: Command WRITE_10 (10 bytes)
> > usb-storage: 2a 00 00 a4 d7 53 00 00 80 00
> > usb-storage: Bulk Command S 0x43425355 T 0xa79 L 65536 F 0 Trg 0 LUN 0 CL
> > 10 usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
> > usb-storage: Status code 0; transferred 31/31
> > usb-storage: -- transfer complete
> > usb-storage: Bulk command transfer result=0
> > usb-storage: usb_stor_bulk_transfer_sglist: xfer 65536 bytes, 10 entries
> > usb-storage: command_abort called
> > usb-storage: usb_stor_stop_transport called
> > usb-storage: -- cancelling sg request
>
> Was that the end? There should be a bunch of stuff following, mostly
> error messages.
>
> > A couple of things I noticed:
> >
> > - I still can browse through the mounted directory. Although it lists
> > files which are not completely transferred (if I disconnect the device,
> > they're not showing up in the device itself)
>
> You're seeing data in Linux's block cache, not data from the drive.
>
> > - I cannot umount it. but when I disconnect the cable I still get these
> > messages:
> >
> > hub 3-0:1.0: state 5 ports 5 chg 0000 evt 0020
> > ehci_hcd 0000:01:07.2: GetStatus port 5 status 001002 POWER sig=se0 CSC
> > hub 3-0:1.0: port 5, status 0100, change 0001, 12 Mb/s
> > usb 3-5: USB disconnect, address 3
> > usb 3-5: usb_disable_device nuking all URBs
> >
> > - The following related processes seem to be sleeping:
> >
> > 4825 root 15 0 0 0 0 D 0.0 0.0 0:00.00 scsi_eh_1
> > 4826 root 15 0 0 0 0 D 0.0 0.0 0:00.20 usb-storage
> > 4939 root 17 0 3540 588 500 D 0.0 0.1 0:00.00 cp
>
> This sounds like a problem with the ehci-hcd driver. The transfer was
> aborted but for some reason it never finished. (This doesn't answer the
> question of why the transfer failed in the first place. It could simply
> be because of marginal hardware that isn't very reliable when running at
> high speed.)
>
> > The full log file is somewhat large. I hope I've provided everything you
> > need. Let me know If I need to turn more debugging on or not. Or send the
> > full log file.
>
> One more thing you could do is use Alt-SysRq-T to get a stack trace once
> cp has hung. The entries for usb-storage and scsi_eh_1 will be of
> interest. This usually works best when there aren't many other processes
> running at the same time, so you might want to do it in single-user mode.
>
> Also, which version of the Linux kernel are you using?
>
> Alan Stern
>
>
>
> -------------------------------------------------------
> This SF.Net email is sponsored by: New Crystal Reports XI.
> Version 11 adds new functionality designed to reduce time involved in
> creating, integrating, and deploying reporting solutions. Free runtime
> info, new features, or free trial, at:
> http://www.businessobjects.com/devxi/728
> _______________________________________________
> [email protected]
> To unsubscribe, use the last form field at:
> https://lists.sourceforge.net/lists/listinfo/linux-usb-users
Jan 1 14:05:25 einstein usb usb3: default language 0x0409
Jan 1 14:05:25 einstein usb usb3: new device strings: Mfr=3, Product=2,
SerialNumber=1
Jan 1 14:05:25 einstein usb usb3: Product: OHCI Host Controller
Jan 1 14:05:25 einstein usb usb3: Manufacturer: Linux 2.6.14-ARCH ohci_hcd
Jan 1 14:05:25 einstein usb usb3: SerialNumber: 0000:01:07.1
Jan 1 14:05:25 einstein usb usb3: hotplug
Jan 1 14:05:25 einstein usb usb3: adding 3-0:1.0 (config #1, interface 0)
Jan 1 14:05:25 einstein usb 3-0:1.0: hotplug
Jan 1 14:05:25 einstein hub 3-0:1.0: usb_probe_interface
Jan 1 14:05:25 einstein hub 3-0:1.0: usb_probe_interface - got id
Jan 1 14:05:25 einstein hub 3-0:1.0: USB hub found
Jan 1 14:05:25 einstein hub 3-0:1.0: 2 ports detected
Jan 1 14:05:25 einstein hub 3-0:1.0: standalone hub
Jan 1 14:05:25 einstein hub 3-0:1.0: no power switching (usb 1.0)
Jan 1 14:05:25 einstein hub 3-0:1.0: global over-current protection
Jan 1 14:05:25 einstein hub 3-0:1.0: power on to power good time: 30ms
Jan 1 14:05:25 einstein hub 3-0:1.0: local power source is good
Jan 1 14:05:25 einstein hub 3-0:1.0: no over-current condition exists
Jan 1 14:05:25 einstein hub 3-0:1.0: state 5 ports 2 chg 0000 evt 0000
Jan 1 14:05:31 einstein hub 1-0:1.0: state 5 ports 5 chg 0000 evt 0004
Jan 1 14:05:31 einstein ehci_hcd 0000:01:07.2: GetStatus port 2 status 001803
POWER sig=j CSC CONNECT
Jan 1 14:05:31 einstein hub 1-0:1.0: port 2, status 0501, change 0001, 480 Mb/s
Jan 1 14:05:31 einstein hub 1-0:1.0: debounce: port 2: total 100ms stable
100ms status 0x501
Jan 1 14:05:31 einstein ehci_hcd 0000:01:07.2: port 2 high speed
Jan 1 14:05:31 einstein ehci_hcd 0000:01:07.2: GetStatus port 2 status 001005
POWER sig=se0 PE CONNECT
Jan 1 14:05:31 einstein usb 1-2: new high speed USB device using ehci_hcd and
address 2
Jan 1 14:05:31 einstein ehci_hcd 0000:01:07.2: port 2 high speed
Jan 1 14:05:31 einstein ehci_hcd 0000:01:07.2: GetStatus port 2 status 001005
POWER sig=se0 PE CONNECT
Jan 1 14:05:31 einstein usb 1-2: default language 0x0409
Jan 1 14:05:31 einstein usb 1-2: new device strings: Mfr=73, Product=93,
SerialNumber=115
Jan 1 14:05:31 einstein usb 1-2: Product: iRiver iHP-100 Series
Jan 1 14:05:31 einstein usb 1-2: Manufacturer: iRiver
Jan 1 14:05:31 einstein usb 1-2: SerialNumber: 0123456789AB
Jan 1 14:05:31 einstein usb 1-2: hotplug
Jan 1 14:05:31 einstein usb 1-2: adding 1-2:2.0 (config #2, interface 0)
Jan 1 14:05:31 einstein usb 1-2:2.0: hotplug
Jan 1 14:05:31 einstein usb-storage 1-2:2.0: usb_probe_interface
Jan 1 14:05:31 einstein usb-storage 1-2:2.0: usb_probe_interface - got id
Jan 1 14:05:31 einstein usb-storage: USB Mass Storage device detected
Jan 1 14:05:31 einstein usb-storage: -- associate_dev
Jan 1 14:05:31 einstein usb-storage: Vendor: 0x1006, Product: 0x3002,
Revision: 0x0100
Jan 1 14:05:31 einstein usb-storage: Interface Subclass: 0x06, Protocol: 0x50
Jan 1 14:05:31 einstein usb-storage: Transport: Bulk
Jan 1 14:05:31 einstein usb-storage: Protocol: Transparent SCSI
Jan 1 14:05:31 einstein usb-storage: usb_stor_control_msg: rq=fe rqtype=a1
value=0000 index=00 len=1
Jan 1 14:05:31 einstein usb-storage: GetMaxLUN command result is 1, data is 0
Jan 1 14:05:31 einstein scsi1 : SCSI emulation for USB Mass Storage devices
Jan 1 14:05:31 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:31 einstein usb-storage: device found at 2
Jan 1 14:05:31 einstein usb-storage: waiting for device to settle before
scanning
Jan 1 14:05:35 einstein ath0: no IPv6 routers present
Jan 1 14:05:36 einstein usb-storage: queuecommand called
Jan 1 14:05:36 einstein usb-storage: *** thread awakened.
Jan 1 14:05:36 einstein usb-storage: Command INQUIRY (6 bytes)
Jan 1 14:05:36 einstein usb-storage: 12 00 00 00 24 00
Jan 1 14:05:36 einstein usb-storage: Bulk Command S 0x43425355 T 0x1 L 36 F
128 Trg 0 LUN 0 CL 6
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 31/31
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk command transfer result=0
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_sglist: xfer 36
bytes, 1 entries
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 36/36
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk data transfer result 0x0
Jan 1 14:05:36 einstein usb-storage: Attempting to get CSW...
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 13/13
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk status result = 0
Jan 1 14:05:36 einstein usb-storage: Bulk Status S 0x53425355 T 0x1 R 0 Stat
0x0
Jan 1 14:05:36 einstein usb-storage: scsi cmd done, result=0x0
Jan 1 14:05:36 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:36 einstein Vendor: TOSHIBA Model: MK2004GAL Rev: JC10
Jan 1 14:05:36 einstein Type: Direct-Access ANSI SCSI
revision: 00
Jan 1 14:05:36 einstein usb-storage: queuecommand called
Jan 1 14:05:36 einstein usb-storage: *** thread awakened.
Jan 1 14:05:36 einstein usb-storage: Command TEST_UNIT_READY (6 bytes)
Jan 1 14:05:36 einstein usb-storage: 00 00 00 00 00 00
Jan 1 14:05:36 einstein usb-storage: Bulk Command S 0x43425355 T 0x2 L 0 F 0
Trg 0 LUN 0 CL 6
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 31/31
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk command transfer result=0
Jan 1 14:05:36 einstein usb-storage: Attempting to get CSW...
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 13/13
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk status result = 0
Jan 1 14:05:36 einstein usb-storage: Bulk Status S 0x53425355 T 0x2 R 0 Stat
0x0
Jan 1 14:05:36 einstein usb-storage: scsi cmd done, result=0x0
Jan 1 14:05:36 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:36 einstein usb-storage: queuecommand called
Jan 1 14:05:36 einstein usb-storage: *** thread awakened.
Jan 1 14:05:36 einstein usb-storage: Command READ_CAPACITY (10 bytes)
Jan 1 14:05:36 einstein usb-storage: 25 00 00 00 00 00 00 00 00 00
Jan 1 14:05:36 einstein usb-storage: Bulk Command S 0x43425355 T 0x3 L 8 F 128
Trg 0 LUN 0 CL 10
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 31/31
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk command transfer result=0
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_sglist: xfer 8
bytes, 1 entries
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 8/8
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk data transfer result 0x0
Jan 1 14:05:36 einstein usb-storage: Attempting to get CSW...
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 13/13
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk status result = 0
Jan 1 14:05:36 einstein usb-storage: Bulk Status S 0x53425355 T 0x3 R 0 Stat
0x0
Jan 1 14:05:36 einstein usb-storage: scsi cmd done, result=0x0
Jan 1 14:05:36 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:36 einstein SCSI device sdb: 39063024 512-byte hdwr sectors (20000
MB)
Jan 1 14:05:36 einstein sdb: assuming drive cache: write through
Jan 1 14:05:36 einstein usb-storage: queuecommand called
Jan 1 14:05:36 einstein usb-storage: *** thread awakened.
Jan 1 14:05:36 einstein usb-storage: Command TEST_UNIT_READY (6 bytes)
Jan 1 14:05:36 einstein usb-storage: 00 00 00 00 00 00
Jan 1 14:05:36 einstein usb-storage: Bulk Command S 0x43425355 T 0x4 L 0 F 0
Trg 0 LUN 0 CL 6
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 31/31
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk command transfer result=0
Jan 1 14:05:36 einstein usb-storage: Attempting to get CSW...
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 13/13
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk status result = 0
Jan 1 14:05:36 einstein usb-storage: Bulk Status S 0x53425355 T 0x4 R 0 Stat
0x0
Jan 1 14:05:36 einstein usb-storage: scsi cmd done, result=0x0
Jan 1 14:05:36 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:36 einstein usb-storage: queuecommand called
Jan 1 14:05:36 einstein usb-storage: *** thread awakened.
Jan 1 14:05:36 einstein usb-storage: Command READ_CAPACITY (10 bytes)
Jan 1 14:05:36 einstein usb-storage: 25 00 00 00 00 00 00 00 00 00
Jan 1 14:05:36 einstein usb-storage: Bulk Command S 0x43425355 T 0x5 L 8 F 128
Trg 0 LUN 0 CL 10
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 31/31
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk command transfer result=0
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_sglist: xfer 8
bytes, 1 entries
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 8/8
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk data transfer result 0x0
Jan 1 14:05:36 einstein usb-storage: Attempting to get CSW...
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 13/13
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk status result = 0
Jan 1 14:05:36 einstein usb-storage: Bulk Status S 0x53425355 T 0x5 R 0 Stat
0x0
Jan 1 14:05:36 einstein usb-storage: scsi cmd done, result=0x0
Jan 1 14:05:36 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:36 einstein SCSI device sdb: 39063024 512-byte hdwr sectors (20000
MB)
Jan 1 14:05:36 einstein sdb: assuming drive cache: write through
Jan 1 14:05:36 einstein sdb:<7>usb-storage: queuecommand called
Jan 1 14:05:36 einstein usb-storage: *** thread awakened.
Jan 1 14:05:36 einstein usb-storage: Command READ_10 (10 bytes)
Jan 1 14:05:36 einstein usb-storage: 28 00 00 00 00 00 00 00 08 00
Jan 1 14:05:36 einstein usb-storage: Bulk Command S 0x43425355 T 0x6 L 4096 F
128 Trg 0 LUN 0 CL 10
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Jan 1 14:05:36 einstein usb-storage: Status code 0; transferred 31/31
Jan 1 14:05:36 einstein usb-storage: -- transfer complete
Jan 1 14:05:36 einstein usb-storage: Bulk command transfer result=0
Jan 1 14:05:36 einstein usb-storage: usb_stor_bulk_transfer_sglist: xfer 4096
bytes, 1 entries
Jan 1 14:05:37 einstein usb-storage: Status code 0; transferred 4096/4096
Jan 1 14:05:37 einstein usb-storage: -- transfer complete
Jan 1 14:05:37 einstein usb-storage: Bulk data transfer result 0x0
Jan 1 14:05:37 einstein usb-storage: Attempting to get CSW...
Jan 1 14:05:37 einstein usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Jan 1 14:05:37 einstein usb-storage: Status code 0; transferred 13/13
Jan 1 14:05:37 einstein usb-storage: -- transfer complete
Jan 1 14:05:37 einstein usb-storage: Bulk status result = 0
Jan 1 14:05:37 einstein usb-storage: Bulk Status S 0x53425355 T 0x6 R 0 Stat
0x0
Jan 1 14:05:37 einstein usb-storage: scsi cmd done, result=0x0
Jan 1 14:05:37 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:37 einstein sdb1
Jan 1 14:05:37 einstein Attached scsi disk sdb at scsi1, channel 0, id 0, lun 0
Jan 1 14:05:37 einstein usb-storage: queuecommand called
Jan 1 14:05:37 einstein usb-storage: *** thread awakened.
Jan 1 14:05:37 einstein usb-storage: Bad LUN (0:1)
Jan 1 14:05:37 einstein usb-storage: scsi cmd done, result=0x40000
Jan 1 14:05:37 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:37 einstein usb-storage: queuecommand called
Jan 1 14:05:37 einstein usb-storage: *** thread awakened.
Jan 1 14:05:37 einstein usb-storage: Bad target number (1:0)
Jan 1 14:05:37 einstein usb-storage: scsi cmd done, result=0x40000
Jan 1 14:05:37 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:37 einstein usb-storage: queuecommand called
Jan 1 14:05:37 einstein usb-storage: *** thread awakened.
Jan 1 14:05:37 einstein usb-storage: Bad target number (2:0)
Jan 1 14:05:37 einstein usb-storage: scsi cmd done, result=0x40000
Jan 1 14:05:37 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:37 einstein usb-storage: queuecommand called
Jan 1 14:05:37 einstein usb-storage: *** thread awakened.
Jan 1 14:05:37 einstein usb-storage: Bad target number (3:0)
Jan 1 14:05:37 einstein usb-storage: scsi cmd done, result=0x40000
Jan 1 14:05:37 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:37 einstein usb-storage: queuecommand called
Jan 1 14:05:37 einstein usb-storage: *** thread awakened.
Jan 1 14:05:37 einstein usb-storage: Bad target number (4:0)
Jan 1 14:05:37 einstein usb-storage: scsi cmd done, result=0x40000
Jan 1 14:05:37 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:37 einstein usb-storage: queuecommand called
Jan 1 14:05:37 einstein usb-storage: *** thread awakened.
Jan 1 14:05:37 einstein usb-storage: Bad target number (5:0)
Jan 1 14:05:37 einstein usb-storage: scsi cmd done, result=0x40000
Jan 1 14:05:37 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:37 einstein usb-storage: queuecommand called
Jan 1 14:05:37 einstein usb-storage: *** thread awakened.
Jan 1 14:05:37 einstein usb-storage: Bad target number (6:0)
Jan 1 14:05:37 einstein usb-storage: scsi cmd done, result=0x40000
Jan 1 14:05:37 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:37 einstein usb-storage: queuecommand called
Jan 1 14:05:37 einstein usb-storage: *** thread awakened.
Jan 1 14:05:37 einstein usb-storage: Bad target number (7:0)
Jan 1 14:05:37 einstein usb-storage: scsi cmd done, result=0x40000
Jan 1 14:05:37 einstein usb-storage: *** thread sleeping.
Jan 1 14:05:37 einstein usb-storage: device scan complete
