USB umass external drive problem

[Daniel Eischen]

Hi,

I have an external USB SATA drive chassis but the drive is
only seen(*) if it is connected at bootup.  Regardless of
whether it was connected at bootup time or not, if it is
reconnected after the system is up it will not be seen.

(*) /dev/da0 will be created, but no entries for da0s1,
     da0s2, da0s3[a-g].  s1 is FAT16, s2 is NTFS, s2 is
     FreeBSD with partitions a-g.  s1, s2, and s3[a-g]
     entries are only created if the device is attached
     at bootup.

Before the drive was formatted as above (it was new without
any slice/partitioning), fdisk and disklabel would also be
unable to see the drive unless it was attached at bootup
time.

# uname -a
FreeBSD rigel 8.0-CURRENT FreeBSD 8.0-CURRENT #1 r191822: Wed May  6 02:44:45 EDT 2009 
root at rigel:/opt/FreeBSD/svn/obj/opt/FreeBSD/svn/src/sys/rigel  i386

Attach messages from log:

   root: Unknown USB device: vendor 0x04fc product 0x0c15 bus uhub4
   kernel: ugen4.2: <Sunplus Technology Inc.> at usbus4
   kernel: umass0: <Bulk Only Interface> on usbus4
   kernel: umass0:  SCSI over Bulk-Only; quirks = 0x0000
   kernel: umass0:3:0:-1: Attached to scbus3
   kernel: da0 at umass-sim0 bus 0 target 0 lun 0
   kernel: da0: <  > Fixed Direct Access SCSI-2 device
   kernel: da0: 40.000MB/s transfers
   kernel: da0: 55796MB (114270345 512 byte sectors: 255H 63S/T 7113C)

Full dmesg is attached.

-- 
DE
-------------- next part --------------
Copyright (c) 1992-2009 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
The Regents of the University of California. All rights reserved.
FreeBSD is a registered trademark of The FreeBSD Foundation.
FreeBSD 8.0-CURRENT #1 r191822: Wed May  6 02:44:45 EDT 2009
root at rigel:/opt/FreeBSD/svn/obj/opt/FreeBSD/svn/src/sys/rigel
WARNING: WITNESS option enabled, expect reduced performance.
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: Genuine Intel(R) CPU	    T2500  @ 2.00GHz (1997.34-MHz 686-class CPU)
Origin = "GenuineIntel"  Id = 0x6e8  Stepping = 8
Features=0xbfe9fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
Features2=0xc1a9<SSE3,MON,VMX,EST,TM2,xTPR,PDCM>
AMD Features=0x100000<NX>
TSC: P-state invariant
real memory  = 1073741824 (1024 MB)
avail memory = 1027928064 (980 MB)
ACPI APIC Table: <DELL   M07	>
FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
FreeBSD/SMP: 1 package(s) x 2 core(s)
cpu0 (BSP): APIC ID:  0
cpu1 (AP): APIC ID:  1
ioapic0: Changing APIC ID to 2
ioapic0 <Version 2.0> irqs 0-23 on motherboard
acpi0: <DELL M07    > on motherboard
acpi0: [ITHREAD]
acpi0: reservation of 0, 9fc00 (3) failed
acpi0: reservation of 100000, 3f5d3400 (3) failed
Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0
acpi_acad0: <AC Adapter> on acpi0
battery0: <ACPI Control Method Battery> on acpi0
acpi_lid0: <Control Method Lid Switch> on acpi0
acpi_button0: <Power Button> on acpi0
acpi_button1: <Sleep Button> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
vgapci0: <VGA-compatible display> port 0xeff8-0xefff mem 0xdff00000-0xdff7ffff,0xc0000000-0xcfffffff,0xdfec0000-0xdfefffff irq 16 at device 2.0 on pci0
agp0: <Intel 82945GM (945GM GMCH) SVGA controller> on vgapci0
agp0: detected 7932k stolen memory
agp0: aperture size is 256M
vgapci1: <VGA-compatible display> mem 0xdff80000-0xdfffffff at device 2.1 on pci0
hdac0: <Intel 82801G High Definition Audio Controller> mem 0xdfebc000-0xdfebffff irq 21 at device 27.0 on pci0
hdac0: HDA Driver Revision: 20090401_0132
hdac0: [ITHREAD]
pcib1: <ACPI PCI-PCI bridge> at device 28.0 on pci0
pci11: <ACPI PCI bus> on pcib1
pcib2: <ACPI PCI-PCI bridge> at device 28.1 on pci0
pci12: <ACPI PCI bus> on pcib2
ath0: <Atheros 5424/2424> mem 0xdfdf0000-0xdfdfffff irq 17 at device 0.0 on pci12
ath0: [ITHREAD]
ath0: AR5413 mac 10.2 RF5424 phy 6.1
pcib3: <ACPI PCI-PCI bridge> at device 28.3 on pci0
pci13: <ACPI PCI bus> on pcib3
uhci0: <UHCI (generic) USB controller> port 0xbf80-0xbf9f irq 20 at device 29.0 on pci0
uhci0: [ITHREAD]
uhci0: LegSup = 0x0000
usbus0: <UHCI (generic) USB controller> on uhci0
uhci1: <UHCI (generic) USB controller> port 0xbf60-0xbf7f irq 21 at device 29.1 on pci0
uhci1: [ITHREAD]
uhci1: LegSup = 0x0000
usbus1: <UHCI (generic) USB controller> on uhci1
uhci2: <UHCI (generic) USB controller> port 0xbf40-0xbf5f irq 22 at device 29.2 on pci0
uhci2: [ITHREAD]
uhci2: LegSup = 0x0000
usbus2: <UHCI (generic) USB controller> on uhci2
uhci3: <UHCI (generic) USB controller> port 0xbf20-0xbf3f irq 23 at device 29.3 on pci0
uhci3: [ITHREAD]
uhci3: LegSup = 0x0000
usbus3: <UHCI (generic) USB controller> on uhci3
ehci0: <Intel 82801GB/R (ICH7) USB 2.0 controller> mem 0xffa80000-0xffa803ff irq 20 at device 29.7 on pci0
ehci0: [ITHREAD]
usbus4: EHCI version 1.0
usbus4: <Intel 82801GB/R (ICH7) USB 2.0 controller> on ehci0
pcib4: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci2: <ACPI PCI bus> on pcib4
bfe0: <Broadcom BCM4401-B0 Fast Ethernet> mem 0xdf9fe000-0xdf9fffff irq 17 at device 0.0 on pci2
miibus0: <MII bus> on bfe0
bmtphy0: <BCM4401 10/100baseTX PHY> PHY 1 on miibus0
bmtphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
bfe0: Ethernet address: 00:14:22:ae:bc:98
bfe0: [ITHREAD]
fwohci0: <1394 Open Host Controller Interface> mem 0xdf9fd800-0xdf9fdfff irq 19 at device 1.0 on pci2
fwohci0: [ITHREAD]
fwohci0: OHCI version 1.10 (ROM=0)
fwohci0: No. of Isochronous channels is 4.
fwohci0: EUI64 32:4f:c0:00:29:a7:1d:41
fwohci0: Phy 1394a available S400, 1 ports.
fwohci0: Link S400, max_rec 2048 bytes.
firewire0: <IEEE1394(FireWire) bus> on fwohci0
dcons_crom0: <dcons configuration ROM> on firewire0
dcons_crom0: bus_addr 0x1088000
fwe0: <Ethernet over FireWire> on firewire0
if_fwe0: Fake Ethernet address: 32:4f:c0:a7:1d:41
fwe0: Ethernet address: 32:4f:c0:a7:1d:41
fwip0: <IP over FireWire> on firewire0
fwip0: Firewire address: 32:4f:c0:00:29:a7:1d:41 @ 0xfffe00000000, S400, maxrec 2048
sbp0: <SBP-2/SCSI over FireWire> on firewire0
fwohci0: Initiate bus reset
fwohci0: fwohci_intr_core: BUS reset
fwohci0: fwohci_intr_core: node_id=0x00000000, SelfID Count=1, CYCLEMASTER mode
pci2: <base peripheral, SD host controller> at device 1.1 (no driver attached)
pci2: <base peripheral> at device 1.2 (no driver attached)
pci2: <base peripheral> at device 1.3 (no driver attached)
pci2: <base peripheral> at device 1.4 (no driver attached)
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel ICH7M SATA300 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xbfa0-0xbfaf irq 17 at device 31.2 on pci0
ata0: <ATA channel 0> on atapci0
ata0: [ITHREAD]
ata1: <ATA channel 1> on atapci0
ata1: [ITHREAD]
pci0: <serial bus, SMBus> at device 31.3 (no driver attached)
acpi_tz0: <Thermal Zone> on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64,0x62,0x66 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
atkbd0: [ITHREAD]
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: [ITHREAD]
psm0: model Generic PS/2 mouse, device ID 0
atrtc0: <AT realtime clock> port 0x70-0x71,0x72-0x77 irq 8 on acpi0
cpu0: <ACPI CPU> on acpi0
est0: <Enhanced SpeedStep Frequency Control> on cpu0
p4tcc0: <CPU Frequency Thermal Control> on cpu0
cpu1: <ACPI CPU> on acpi0
est1: <Enhanced SpeedStep Frequency Control> on cpu1
p4tcc1: <CPU Frequency Thermal Control> on cpu1
pmtimer0 on isa0
orm0: <ISA Option ROMs> at iomem 0xc0000-0xce7ff,0xce800-0xcffff pnpid ORM0000 on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
ppc0: parallel port not found.
Timecounters tick every 1.000 msec
firewire0: 1 nodes, maxhop <= 0 cable IRM irm(0)  (me) 
firewire0: bus manager 0 
usbus0: 12Mbps Full Speed USB v1.0
usbus1: 12Mbps Full Speed USB v1.0
usbus2: 12Mbps Full Speed USB v1.0
usbus3: 12Mbps Full Speed USB v1.0
usbus4: 480Mbps High Speed USB v2.0
ugen0.1: <Intel> at usbus0
uhub0: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus0
ugen1.1: <Intel> at usbus1
uhub1: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus1
ugen2.1: <Intel> at usbus2
uhub2: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus2
ugen3.1: <Intel> at usbus3
uhub3: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus3
ugen4.1: <Intel> at usbus4
uhub4: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus4
driver bug: Unable to set devclass (devname: (null))
ad0: 305245MB <WDC WD3200BEKT-00F3T0 11.01A11> at ata0-master SATA150
acd0: DVDR <SONY DVD+/-RW DW-Q58A/UDS1> at ata1-master UDMA33
hdac0: HDA Codec #0: Sigmatel STAC9220
hdac0: HDA Codec #1: Conexant (Unknown)
pcm0: <HDA Sigmatel STAC9220 PCM #0 Analog> at cad 0 nid 1 on hdac0
GEOM_LABEL: Label for provider ad0s1 is msdosfs/DellUtility.
GEOM: ad0s3: geometry does not match label (255h,63s != 16h,63s).
GEOM_LABEL: Label for provider ad0s3a is ufsid/4a10c1c6635aa6e5.
GEOM_LABEL: Label for provider ad0s3b is ufsid/4a10c1c65bae6a31.
GEOM_LABEL: Label for provider ad0s3d is ufsid/4a10c1c739c6db92.
GEOM_LABEL: Label for provider ad0s3e is ufsid/4a10c1c793d9d987.
GEOM_LABEL: Label for provider ad0s3f is ufsid/4a10c1c7a73f81f4.
GEOM_LABEL: Label for provider ad0s3g is ufsid/4a10cf28ac6f1a67.
uhub0: 2 ports with 2 removable, self powered
uhub1: 2 ports with 2 removable, self powered
uhub2: 2 ports with 2 removable, self powered
uhub3: 2 ports with 2 removable, self powered
uhub4: 8 ports with 8 removable, self powered
acd0: FAILURE - INQUIRY ILLEGAL REQUEST asc=0x24 ascq=0x00 
(probe0:ata1:0:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 
(probe0:ata1:0:0:0): CAM Status: SCSI Status Error
(probe0:ata1:0:0:0): SCSI Status: Check Condition
(probe0:ata1:0:0:0): NOT READY asc:3a,0
(probe0:ata1:0:0:0): Medium not present
(probe0:ata1:0:0:0): Unretryable error
acd0: FAILURE - INQUIRY ILLEGAL REQUEST asc=0x24 ascq=0x00 
cd0 at ata1 bus 0 target 0 lun 0
cd0: <SONY DVD+-RW DW-Q58A UDS1> Removable CD-ROM SCSI-0 device 
cd0: 33.000MB/s transfers
cd0: Attempt to query device size failed: NOT READY, Medium not present
SMP: AP CPU #1 Launched!
WARNING: WITNESS option enabled, expect reduced performance.
Trying to mount root from ufs:/dev/ad0s3a
GEOM_LABEL: Label ufsid/4a10c1c65bae6a31 removed.
GEOM_LABEL: Label ufsid/4a10c1c6635aa6e5 removed.
GEOM_LABEL: Label for provider ad0s3a is ufsid/4a10c1c6635aa6e5.
GEOM_LABEL: Label ufsid/4a10cf28ac6f1a67 removed.
GEOM_LABEL: Label for provider ad0s3g is ufsid/4a10cf28ac6f1a67.
GEOM_LABEL: Label ufsid/4a10c1c793d9d987 removed.
GEOM_LABEL: Label for provider ad0s3e is ufsid/4a10c1c793d9d987.
GEOM_LABEL: Label ufsid/4a10c1c7a73f81f4 removed.
GEOM_LABEL: Label for provider ad0s3f is ufsid/4a10c1c7a73f81f4.
GEOM_LABEL: Label ufsid/4a10c1c739c6db92 removed.
GEOM_LABEL: Label for provider ad0s3d is ufsid/4a10c1c739c6db92.
GEOM_LABEL: Label ufsid/4a10c1c6635aa6e5 removed.
GEOM_LABEL: Label ufsid/4a10cf28ac6f1a67 removed.
GEOM_LABEL: Label ufsid/4a10c1c793d9d987 removed.
GEOM_LABEL: Label ufsid/4a10c1c7a73f81f4 removed.
GEOM_LABEL: Label ufsid/4a10c1c739c6db92 removed.
wlan0: Ethernet address: 00:14:22:ae:bc:98
bfe0: link state changed to UP
lagg0: link state changed to UP
lock order reversal:
1st 0xd810e390 bufwait (bufwait) @ /opt/FreeBSD/svn/src/sys/kern/vfs_bio.c:2555
2nd 0xc46c3a00 dirhash (dirhash) @ /opt/FreeBSD/svn/src/sys/ufs/ufs/ufs_dirhash.c:275
KDB: stack backtrace:
db_trace_self_wrapper(c09cbb51,e66c4778,c06dc535,c06ce43b,c09ce9c6,...) at db_trace_self_wrapper+0x26
kdb_backtrace(c06ce43b,c09ce9c6,c4120b50,c4124500,e66c47d4,...) at kdb_backtrace+0x29
_witness_debugger(c09ce9c6,c46c3a00,c09eec90,c4124500,c09ee91d,...) at _witness_debugger+0x25
witness_checkorder(c46c3a00,9,c09ee91d,113,0,...) at witness_checkorder+0x839
_sx_xlock(c46c3a00,0,c09ee91d,113,c4967d24,...) at _sx_xlock+0x85
ufsdirhash_acquire(d810e330,d8ae7a70,190,d8ae7a8c,e66c48a4,...) at ufsdirhash_acquire+0x35
ufsdirhash_add(c4967d24,e66c48ec,1ca8c,e66c4890,e66c4894,...) at ufsdirhash_add+0x13
ufs_direnter(c4966324,c495ba78,e66c48ec,e66c4bd4,0,...) at ufs_direnter+0x729
ufs_makeinode(e66c4bd4,0,e66c4acc,e66c4a34,c09466e5,...) at ufs_makeinode+0x4af
ufs_create(e66c4acc,e66c4ae4,0,0,e66c4ba8,...) at ufs_create+0x30
VOP_CREATE_APV(c0a7eb80,e66c4acc,2,c09c0faa,3,...) at VOP_CREATE_APV+0xa5
vn_open_cred(e66c4ba8,e66c4c5c,1b0,c47f2100,c46eec08,...) at vn_open_cred+0x19e
vn_open(e66c4ba8,e66c4c5c,1b0,c46eec08,4a10c36e,...) at vn_open+0x33
kern_openat(c46cdd80,ffffff9c,28436fc0,0,a03,...) at kern_openat+0x108
kern_open(c46cdd80,28436fc0,0,a02,1b0,...) at kern_open+0x35
open(c46cdd80,e66c4cf8,c,c09cf297,c0a5fa58,...) at open+0x30
syscall(e66c4d38) at syscall+0x2a3
Xint0x80_syscall() at Xint0x80_syscall+0x20
--- syscall (5, FreeBSD ELF32, open), eip = 0x283b8f93, esp = 0xbfbfc51c, ebp = 0xbfbfc5a8 ---

/* USB drive attached here */
Unknown USB device: vendor 0x04fc product 0x0c15 bus uhub4
ugen4.2: <Sunplus Technology Inc.> at usbus4
umass0: <Bulk Only Interface> on usbus4
umass0:  SCSI over Bulk-Only; quirks = 0x0000
umass0:3:0:-1: Attached to scbus3
da0 at umass-sim0 bus 0 target 0 lun 0
da0: <  > Fixed Direct Access SCSI-2 device 
da0: 40.000MB/s transfers
da0: 55796MB (114270345 512 byte sectors: 255H 63S/T 7113C)

/* camcontrol rescan 3 here */
(da0:umass-sim0:0:0:0): lost device
(da0:umass-sim0:0:0:0): removing device entry
da0 at umass-sim0 bus 0 target 0 lun 0
da0: < 5PJ2RV34\000\000@\000\000\004 8.03> Fixed Direct Access SCSI-2 device 
da0: 40.000MB/s transfers
da0: 55796MB (114270345 512 byte sectors: 255H 63S/T 7113C)
(da0:umass-sim0:0:0:0): Synchronize cache failed, status == 0x4, scsi status == 0x0