usb/105186: USB 2.0/ehci on FreeBSD 6.2-PRE/AMD64 crashes box

[Khairil Yusof]

On Tue, 2007-08-14 at 17:01 +0200, Hans Petter Selasky wrote:

> Hi,
> 
> You can turn on more debugging in "umass" by:
> 
> sysctl hw.usb.umass.debug=-1
> 
> With /usr/src/sys/dev/ata/ata-usb.c:
> 
> Change the following variable manually to "5".
> 
> static const int atausbdebug = 0;
> 
> Then recompile the atausb module or kernel.
> 
> When it hangs the debug output is of interest.

These messages come up repeatedly until the file system operations
hang. 

dmesg:

afd0: begin_transaction WRITE_BIG
atausb0: BBB transfer 6
atausb0: atausb_t_bbb_data_write_callback: max_bulk=131072, ata_bytecount=16384
atausb0: atausb_t_bbb_data_write_callback: max_bulk=131072, ata_bytecount=0
atausb0: BBB transfer 8
atausb0: atausb_t_bbb_status_callback: depreciated unlock!
afd0: end_transaction WRITE_BIG
afd0: begin_transaction WRITE_BIG
atausb0: BBB transfer 6
atausb0: atausb_t_bbb_data_write_callback: max_bulk=131072, ata_bytecount=12288
atausb0: atausb_t_bbb_data_write_callback: max_bulk=131072, ata_bytecount=0
atausb0: BBB transfer 8
atausb0: atausb_t_bbb_status_callback: depreciated unlock!
afd0: end_transaction WRITE_BIG
afd0: begin_transaction WRITE_BIG
atausb0: BBB transfer 6
atausb0: atausb_t_bbb_data_write_callback: max_bulk=131072, ata_bytecount=4096
atausb0: atausb_t_bbb_data_write_callback: max_bulk=131072, ata_bytecount=0
atausb0: BBB transfer 8
atausb0: atausb_t_bbb_status_callback: depreciated unlock!
afd0: end_transaction WRITE_BIG

/var/log/messages

ug 16 14:08:13 gambit kernel: afd0: FAILURE - WRITE_BIG ABORTED COMMAND asc=0x00 ascq=0x00
Aug 16 14:08:13 gambit kernel: afd0: begin_transaction WRITE_BIG
Aug 16 14:08:13 gambit kernel: g_vfs_done():afd0s1d[WRITE(offset=117597388800, length=16384)]error = 5
Aug 16 14:08:13 gambit kernel: atausb0: BBB transfer 6
Aug 16 14:08:13 gambit kernel: atausb0: atausb_t_bbb_data_write_callback: max_bulk=131072, ata_bytecount=6144
Aug 16 14:09:18 gambit kernel: atausb0: BBB transfer 7
Aug 16 14:09:18 gambit kernel: atausb0: BBB transfer 8
Aug 16 14:09:18 gambit kernel: atausb0: CSWSTATUS_FAILED
Aug 16 14:09:18 gambit kernel: atausb0: atausb_t_bbb_status_callback: depreciated unlock!
Aug 16 14:09:18 gambit kernel: afd0: end_transaction WRITE_BIG
Aug 16 14:09:18 gambit kernel: afd0: begin_transaction REQUEST_SENSE
Aug 16 14:09:18 gambit kernel: atausb0: BBB transfer 4
Aug 16 14:09:18 gambit kernel: atausb0: atausb_t_bbb_data_read_callback: max_bulk=131072, ata_bytecount=18
Aug 16 14:09:18 gambit kernel: atausb0: atausb_t_bbb_data_read_callback: max_bulk=131072, ata_bytecount=0
Aug 16 14:09:18 gambit kernel: atausb0: BBB transfer 8
Aug 16 14:09:18 gambit kernel: atausb0: atausb_t_bbb_status_callback: depreciated unlock!
Aug 16 14:09:18 gambit kernel: afd0: end_transaction WRITE_BIG
Aug 16 14:09:18 gambit kernel: afd0: FAILURE - WRITE_BIG ABORTED COMMAND asc=0x00 ascq=0x00
Aug 16 14:09:18 gambit kernel: afd0: begin_transaction WRITE_BIG
Aug 16 14:09:18 gambit kernel: g_vfs_done():afd0s1d[WRITE(offset=117598726144, length=6144)]error = 5
Aug 16 14:09:18 gambit kernel: atausb0: BBB transfer 6
Aug 16 14:09:18 gambit kernel: atausb0: atausb_t_bbb_data_write_callback: max_bulk=131072, ata_bytecount=32768
Aug 16 14:09:40 gambit kernel: atausb0: atausb_t_bbb_data_write_callback: max_bulk=131072, ata_bytecount=0
Aug 16 14:09:40 gambit kernel: atausb0: BBB transfer 8
Aug 16 14:09:40 gambit kernel: atausb0: atausb_t_bbb_status_callback: depreciated unlock!
Aug 16 14:09:40 gambit kernel: afd0: end_transaction WRITE_BIG
Aug 16 14:09:40 gambit kernel: afd0: begin_transaction WRITE_BIG
Aug 16 14:09:40 gambit kernel: atausb0: BBB transfer 6
Aug 16 14:09:40 gambit kernel: atausb0: atausb_t_bbb_data_write_callback: max_bulk=131072, ata_bytecount=49152