usb/99419: external usb harddrive slow to accept

[Brendhan Horne]

>Number:         99419
>Category:       usb
>Synopsis:       external usb harddrive slow to accept
>Confidential:   no
>Severity:       non-critical
>Priority:       low
>Responsible:    freebsd-usb
>State:          open
>Quarter:        
>Keywords:       
>Date-Required:
>Class:          sw-bug
>Submitter-Id:   current-users
>Arrival-Date:   Sat Jun 24 15:00:29 GMT 2006
>Closed-Date:
>Last-Modified:
>Originator:     Brendhan Horne
>Release:        6.1-STABLE
>Organization:
>Environment:
FreeBSD Hyde.brendhanhorne.com 6.1-STABLE FreeBSD 6.1-STABLE #0: Tue May 30 10:51:22 EDT 2006     root at Hyde.bjh.homenet:/usr/obj/usr/src/sys/SCHIZO  i386

>Description:
When adding usb drive :
Western Digital 250 gig usb drive My Book
MDL: WD25001032
S/N WMANK2441962

The device takes 20 minuets to register. 
As soon as I plug it in I see:
umass0: Western Digital External HDD, rev 2.00/2.06, addr 2
Then about 20 minutes later it finally comes up with:
da0 at umass-sim0 bus 0 target 0 lun 0
da0: <WD 2500JB External 0107> Fixed Direct Access SCSI-0 device
da0: 1.000MB/s transfers
da0: 238475MB (488397168 512 byte sectors: 255H 63S/T 30401C) 

It has an external power supply. I have several external usb drives this is the only one that behaves like this. It has been newfs to UFS and still has the same problem.

Hyde# usbd -vv -d
usbd: opened /dev/usb0
usbd: reading configuration file /etc/usbd.conf
usbd: action 1: ActiveWire board, firmware download
  vndr=0x0854 prdct=0x0100 rlse=0x0000
  attach='/usr/local/bin/ezdownload -f /usr/local/share/usb/firmware/0854.0100.0_01.hex ${DEVNAME}'
usbd: action 2: Entrega Serial with UART
  vndr=0x1645 prdct=0x8001 rlse=0x0101
  attach='/usr/sbin/ezdownload -v -f /usr/share/usb/firmware/1645.8001.0101 /dev/${DEVNAME}'
usbd: action 3: Handspring Visor
  vndr=0x082d prdct=0x0100 rlse=0x0100
  devname: ugen[0-9]+
  attach='/usr/local/bin/coldsync -md -p /dev/${DEVNAME} -t usb'
usbd: action 4: USB device
usbd: 4 actions
usbd: opened /dev/usb
usbd: device-detach event at 1150769314.484222000, product 0x0901, vendor 0x1058:
  vndr=0x1058 prdct=0x0901 rlse=0x0206 clss=0x0000 subclss=0x0000 prtcl=0x0000

usbd: processing event queue on /dev/usb
usbd: device-attach event at 1150769349.219118000, External HDD, Western Digital
:
  vndr=0x1058 prdct=0x0901 rlse=0x0206 clss=0x0000 subclss=0x0000 prtcl=0x0000
  device names: umass0
  === match attempt: umass0
usbd: Found action 'USB device' for External HDD, Western Digital at umass0
usbd: action 0: USB device
usbd: Setting DEVNAME='umass0'
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0               <--about here it came up with the da0 part. I was able to mount it. But I was still getting the same message in the debug screen.
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb


After I unmounted it I got this:
usbd: device-detach event at 1150770674.963814000, product 0x0901, vendor 0x1058:
  vndr=0x1058 prdct=0x0901 rlse=0x0206 clss=0x0000 subclss=0x0000 prtcl=0x0000

usbd: doing timeout discovery on /dev/usb0
usbd: processing event queue due to timeout on /dev/usb
and on and on 
>How-To-Repeat:
Plug in usb hard drive.
>Fix:

>Release-Note:
>Audit-Trail:
>Unformatted: