Dapper, Inspiron 6000, External USB Drive not available

[Kevin Collins]

First, see this post in the Ubuntu forums:
http://www.ubuntuforums.org/showthread.php?t=185531

The hardware in question:
 * SilverStone SST-MS02-B USB 2.0 External Drive Enclosure
 * 80GB Western Digitial Scorpio 2.5" SATA Hard Drive in the enclosure
 * Dell Inspiron 6000d

Ubuntu Version:
 * Dapper Drake, installed from Beta 2 text-mode install CD, and updated with apt-get nearly every day until release.
 * Kernel 2.6.15-23-686 (Ubuntu built)

Problem description:
 * The USB drive is recognized, modules are loaded to support it, but I can not access it.

Whenever I plug the external drive in, I get this series of messages in /var/log/syslog:

Jun 1 13:45:48 enterprise kernel: [4304395.996000] usb 5-7: new high speed USB device using ehci_hcd and address 9
Jun 1 13:45:51 enterprise kernel: [4304399.122000] usb 5-7: device descriptor read/64, error -110
Jun 1 13:45:52 enterprise NetworkManager: <debug info>^I[1149183952.439663] nm_hal_device_added (): New device added (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658').
Jun 1 13:45:52 enterprise NetworkManager: <debug info>^I[1149183952.471352] nm_hal_device_added (): New device added (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658_if0').
Jun 1 13:45:52 enterprise NetworkManager: <debug info>^I[1149183952.564860] nm_hal_device_added (): New device added (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658_if0_scsi_host').
Jun 1 13:45:52 enterprise NetworkManager: <debug info>^I[1149183952.627719] nm_hal_device_added (): New device added (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658_usbraw').
Jun 1 13:45:52 enterprise kernel: [4304400.282000] scsi9 : SCSI emulation for USB Mass Storage devices
Jun 1 13:45:52 enterprise kernel: [4304400.282000] usb-storage: device found at 9
Jun 1 13:45:52 enterprise kernel: [4304400.282000] usb-storage: waiting for device to settle before scanning
Jun 1 13:45:57 enterprise kernel: [4304405.527000] Vendor: WDC WD80 Model: 0VE-75HDT1 Rev: 11.0
Jun 1 13:45:57 enterprise kernel: [4304405.527000] Type: Direct-Access ANSI SCSI revision: 00
Jun 1 13:45:57 enterprise kernel: [4304405.528000] SCSI device sdb: 156301489 512-byte hdwr sectors (80026 MB)
Jun 1 13:45:57 enterprise kernel: [4304405.528000] sdb: assuming drive cache: write through
Jun 1 13:45:57 enterprise kernel: [4304405.530000] SCSI device sdb: 156301489 512-byte hdwr sectors (80026 MB)
Jun 1 13:45:57 enterprise kernel: [4304405.530000] sdb: assuming drive cache: write through
Jun 1 13:45:57 enterprise kernel: [4304405.530000] sdb: sdb1
Jun 1 13:45:57 enterprise kernel: [4304405.823000] sd 9:0:0:0: Attached scsi disk sdb
Jun 1 13:45:57 enterprise kernel: [4304405.823000] sd 9:0:0:0: Attached scsi generic sg2 type 0
Jun 1 13:45:57 enterprise kernel: [4304405.825000] usb-storage: device scan complete
Jun 1 13:45:57 enterprise NetworkManager: <debug info>^I[1149183957.982913] nm_hal_device_added (): New device added (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658_if0_scsi_host_scsi_device_lun0').
Jun 1 13:45:58 enterprise NetworkManager: <debug info>^I[1149183958.185086] nm_hal_device_added (): New device added (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658_if0_scsi_host_scsi_device_lun0_scsi_generic').

Then after a couple of seconds (about 30), I start to see these messages in the syslog:

Jun 1 13:46:28 enterprise kernel: [4304436.176000] usb 5-7: reset high speed USB device using ehci_hcd and address 9
Jun 1 13:46:31 enterprise kernel: [4304439.287000] usb 5-7: device descriptor read/64, error -110
Jun 1 13:46:46 enterprise kernel: [4304454.507000] usb 5-7: device descriptor read/64, error -110
Jun 1 13:46:46 enterprise kernel: [4304454.720000] usb 5-7: reset high speed USB device using ehci_hcd and address 9
Jun 1 13:46:49 enterprise kernel: [4304457.826000] usb 5-7: device descriptor read/64, error -110
Jun 1 13:47:05 enterprise kernel: [4304473.074000] usb 5-7: device descriptor read/64, error -110
Jun 1 13:47:05 enterprise kernel: [4304473.282000] usb 5-7: reset high speed USB device using ehci_hcd and address 9

This last series of messages repeat several times, then I get this:
Jun 1 13:47:15 enterprise kernel: [4304483.717000] usb 5-7: device not accepting address 9, error -110
Jun 1 13:47:15 enterprise kernel: [4304483.847000] usb 5-7: reset high speed USB device using ehci_hcd and address 9
Jun 1 13:47:26 enterprise NetworkManager: <debug info>^I[1149184046.433954] nm_hal_device_removed (): Device removed (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658_if0_scsi_host_scsi_device_lun0_scsi_generic').
Jun 1 13:47:26 enterprise NetworkManager: <debug info>^I[1149184046.438642] nm_hal_device_removed (): Device removed (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658_if0_scsi_host_scsi_device_lun0').
Jun 1 13:47:26 enterprise NetworkManager: <debug info>^I[1149184046.441831] nm_hal_device_removed (): Device removed (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658_if0_scsi_host').
Jun 1 13:47:26 enterprise NetworkManager: <debug info>^I[1149184046.447544] nm_hal_device_removed (): Device removed (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658_if0').
Jun 1 13:47:26 enterprise NetworkManager: <debug info>^I[1149184046.450934] nm_hal_device_removed (): Device removed (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658').
Jun 1 13:47:26 enterprise kernel: [4304494.280000] usb 5-7: device not accepting address 9, error -110
Jun 1 13:47:26 enterprise kernel: [4304494.280000] usb 5-7: USB disconnect, address 9
Jun 1 13:47:26 enterprise kernel: [4304494.280000] 9:0:0:0: scsi: Device offlined - not ready after error recovery
Jun 1 13:47:26 enterprise kernel: [4304494.284000] 9:0:0:0: SCSI error: return code = 0x10000
Jun 1 13:47:26 enterprise kernel: [4304494.284000] end_request: I/O error, dev sdb, sector 156301488
Jun 1 13:47:26 enterprise kernel: [4304494.284000] printk: 482 messages suppressed.
Jun 1 13:47:26 enterprise kernel: [4304494.284000] Buffer I/O error on device sdb, logical block 156301488
Jun 1 13:47:26 enterprise kernel: [4304494.286000] 9:0:0:0: rejecting I/O to dead device
Jun 1 13:47:26 enterprise kernel: [4304494.286000] Buffer I/O error on device sdb, logical block 156301488
Jun 1 13:47:26 enterprise kernel: [4304494.286000] 9:0:0:0: rejecting I/O to dead device
Jun 1 13:47:26 enterprise NetworkManager: <debug info>^I[1149184046.456478] nm_hal_device_removed (): Device removed (hal udi is '/org/freedesktop/Hal/devices/usb_device_ed06_4500_1966051022645658_usbraw').
Jun 1 13:47:26 enterprise kernel: [4304494.286000] Buffer I/O error on device sdb, logical block 156301488
Jun 1 13:47:26 enterprise kernel: [4304494.286000] 9:0:0:0: rejecting I/O to dead device
Jun 1 13:47:26 enterprise kernel: [4304494.286000] Buffer I/O error on device sdb, logical block 156301488
Jun 1 13:47:26 enterprise kernel: [4304494.286000] 9:0:0:0: rejecting I/O to dead device
Jun 1 13:47:26 enterprise kernel: [4304494.286000] Buffer I/O error on device sdb, logical block 156301424
Jun 1 13:47:26 enterprise kernel: [4304494.286000] Buffer I/O error on device sdb, logical block 156301425
Jun 1 13:47:26 enterprise kernel: [4304494.286000] Buffer I/O error on device sdb, logical block 156301426
Jun 1 13:47:26 enterprise kernel: [4304494.286000] Buffer I/O error on device sdb, logical block 156301427
Jun 1 13:47:26 enterprise kernel: [4304494.286000] Buffer I/O error on device sdb, logical block 156301428
Jun 1 13:47:26 enterprise kernel: [4304494.286000] Buffer I/O error on device sdb, logical block 156301429
Jun 1 13:47:26 enterprise kernel: [4304494.286000] 9:0:0:0: rejecting I/O to dead device
Jun 1 13:47:26 enterprise last message repeated 9 times
Jun 1 13:47:26 enterprise kernel: [4304494.287000] 9:0:0:0: rejecting I/O to dead device
Jun 1 13:47:26 enterprise last message repeated 19 times
Jun 1 13:47:26 enterprise kernel: [4304494.411000] usb 5-7: new high speed USB device using ehci_hcd and address 10
Jun 1 13:47:29 enterprise kernel: [4304497.528000] usb 5-7: device descriptor read/64, error -110
Jun 1 13:47:44 enterprise kernel: [4304512.752000] usb 5-7: device descriptor read/64, error -110
Jun 1 13:47:45 enterprise kernel: [4304512.961000] usb 5-7: new high speed USB device using ehci_hcd and address 11
Jun 1 13:47:48 enterprise kernel: [4304516.079000] usb 5-7: device descriptor read/64, error -110

And then the second set of messages starts all over again.

I can not mount the existing partition, I can not use cfdisk or fdisk to change the partition scheme and the drive is not available to me.

Output of "lsmod":
Module Size Used by
ehci_hcd 33800 0
uhci_hcd 35408 0
ohci_hcd 22724 0
nls_utf8 2240 0
nls_cp437 5888 0
vfat 14496 0
fat 55548 1 vfat
usb_storage 79488 0
arc4 2048 1
ieee80211_crypt_wep 5280 1
af_packet 24520 4
ipv6 286912 26
rfcomm 43604 0
hidp 33856 2
l2cap 28192 10 rfcomm,hidp
ppdev 9668 0
fglrx 391756 41
speedstep_centrino 8752 1
cpufreq_powersave 1920 0
cpufreq_stats 6688 0
cpufreq_userspace 6496 1
cpufreq_ondemand 7752 0
cpufreq_conservative 9000 0
freq_table 4928 2 speedstep_centrino,cpufreq_stats
tc1100_wmi 6884 0
video 16324 0
acpi_sbs 20172 0
battery 9988 1 acpi_sbs
i2c_acpi_ec 5120 1 acpi_sbs
i2c_core 22848 1 i2c_acpi_ec
container 4608 0
button 6704 0
pcc_acpi 12416 0
sony_acpi 5580 0
ac 5220 1 acpi_sbs
dev_acpi 11236 0
hotkey 11492 0
ext3 148104 1
jbd 65876 1 ext3
dm_mod 63256 1
md_mod 76052 0
sbp2 24996 0
parport_pc 37988 0
lp 12356 0
parport 39400 3 ppdev,parport_pc,lp
pcmcia 41948 0
ipw2200 113548 0
ieee80211 38920 1 ipw2200
ieee80211_crypt 6528 2 ieee80211_crypt_wep,ieee80211
ieee80211_1_1_13 39880 0
ieee80211_1_1_13_crypt 7040 1 ieee80211_1_1_13
sdhci 16096 0
mmc_core 31816 1 sdhci
b44 27980 0
mii 6176 1 b44
yenta_socket 30124 1
rsrc_nonstatic 14624 1 yenta_socket
pcmcia_core 45272 3 pcmcia,yenta_socket,rsrc_nonstatic
hci_usb 18004 2
bluetooth 54084 8 rfcomm,hidp,l2cap,hci_usb
snd_intel8x0 35740 1
joydev 10432 0
snd_ac97_codec 99808 1 snd_intel8x0
snd_ac97_bus 2400 1 snd_ac97_codec
tsdev 8032 0
pcspkr 2244 0
snd_pcm_oss 56448 0
snd_mixer_oss 20544 1 snd_pcm_oss
snd_pcm 96676 3 snd_intel8x0,snd_ac97_codec,snd_pcm_oss
snd_timer 26884 1 snd_pcm
psmouse 40036 0
serio_raw 7748 0
snd 60004 8 snd_intel8x0,snd_ac97_codec,snd_pcm_oss,snd_mixer_oss,snd_pcm,snd_timer
soundcore 10784 1 snd
snd_page_alloc 11304 2 snd_intel8x0,snd_pcm
intel_agp 24700 1
shpchp 49504 0
pci_hotplug 30788 1 shpchp
agpgart 36784 2 fglrx,intel_agp
sr_mod 17988 1
cdrom 41408 1 sr_mod
sg 40096 0
evdev 10176 2
reiserfs 284016 4
ide_generic 1504 0
ohci1394 37524 0
ieee1394 306520 2 sbp2,ohci1394
usbcore 137700 6 ehci_hcd,uhci_hcd,ohci_hcd,usb_storage,hci_usb
sd_mod 20448 7
ata_piix 11364 14
ahci 18468 0
libata 83376 2 ata_piix,ahci
scsi_mod 145960 7 usb_storage,sbp2,sr_mod,sg,sd_mod,ahci,libata
generic 5124 0
thermal 13768 0
processor 26344 2 speedstep_centrino,thermal
fan 4836 0
capability 4968 0
commoncap 7328 1 capability
vesafb 8636 1
fbcon 43904 72
tileblit 2784 1 fbcon
font 8320 1 fbcon
bitblit 6464 1 fbcon
softcursor 2304 1 bitblit

Output of "lspci":
0000:00:00.0 Host bridge: Intel Corporation Mobile 915GM/PM/GMS/910GML Express Processor to DRAM Controller (rev 03)
0000:00:01.0 PCI bridge: Intel Corporation Mobile 915GM/PM Express PCI Express Root Port (rev 03)
0000:00:1d.0 USB Controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) USB UHCI #1 (rev 03)
0000:00:1d.1 USB Controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) USB UHCI #2 (rev 03)
0000:00:1d.2 USB Controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) USB UHCI #3 (rev 03)
0000:00:1d.3 USB Controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) USB UHCI #4 (rev 03)
0000:00:1d.7 USB Controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) USB2 EHCI Controller (rev 03)
0000:00:1e.0 PCI bridge: Intel Corporation 82801 Mobile PCI Bridge (rev d3)
0000:00:1e.2 Multimedia audio controller: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) AC'97 Audio Controller (rev 03)
0000:00:1e.3 Modem: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) AC'97 Modem Controller (rev 03)
0000:00:1f.0 ISA bridge: Intel Corporation 82801FBM (ICH6M) LPC Interface Bridge (rev 03)
0000:00:1f.2 IDE interface: Intel Corporation 82801FBM (ICH6M) SATA Controller (rev 03)
0000:00:1f.3 SMBus: Intel Corporation 82801FB/FBM/FR/FW/FRW (ICH6 Family) SMBus Controller (rev 03)
0000:01:00.0 VGA compatible controller: ATI Technologies Inc M22 [Radeon Mobility M300]
0000:03:00.0 Ethernet controller: Broadcom Corporation BCM4401-B0 100Base-TX (rev 02)
0000:03:01.0 CardBus bridge: Ricoh Co Ltd RL5c476 II (rev b3)
0000:03:01.1 FireWire (IEEE 1394): Ricoh Co Ltd R5C552 IEEE 1394 Controller (rev 08)
0000:03:01.2 0805: Ricoh Co Ltd R5C822 SD/SDIO/MMC/MS/MSPro Host Adapter (rev 17)
0000:03:03.0 Network controller: Intel Corporation PRO/Wireless 2200BG (rev 05)

Output of "lsusb":
Bus 005 Device 001: ID 0000:0000
Bus 004 Device 001: ID 0000:0000
Bus 003 Device 001: ID 0000:0000
Bus 002 Device 003: ID 413c:8103 Dell Computer Corp. Wireless 350 Bluetooth
Bus 002 Device 001: ID 0000:0000
Bus 001 Device 001: ID 0000:0000

Any additional information available on request.

[Kevin Collins (kcollins)]

The USB drive works fine on this laptop when used with Knoppix 4.0 - kernel version 2.6.12.

I noticed that Knoppix didn't have the ehci_hcd module loaded when the drive was plugged in and functional. It did have ohci_hcd and uhci_hcd - maybe that's the key.
I'll try to remove the ehci_hcd module and see what I happens tomorrow when I return to work.

[Kevin Collins (kcollins)]

No matter which module is loaded - echi_hcd or ohci_hcd (uhci_hcd is loaded by both Dapper and Knoppix) the errors appeared in the syslog and the drive is not available for use.

I can confirm that this same probelm happens on another laptop - a Dell Inspiron 8600 - running Dapper. But is available under Windows and Knoppix on that machine as well. So it's got to be something with either Dapper's default configurations or the Vanilla Kernel.

[Kevin Collins (kcollins)]

In addition to Knoppix and Windows, I can add Fedora Core 5 to the list of OS that can access the external drive.

Also, while in Knoppix the last time, I blew away the partition information on the external drive and created one 10GB EXT3 partition. I mounted that partition and created an empty file with the command:

 "touch test.txt"

I could see and edit the file in Knoppix and Ferdora. Yet in Dapper the drive refuses access.

[Kevin Collins (kcollins)]

It must be in the kernel I can't find anything else that may be preventing the external drive from working properly.

[Kevin Collins (kcollins)]

Add Knoppix 5.0.1 to the list of OSes the drive works on.

[trollord (trollenlord)]

Thank you for taking the time to report this bug and helping to make Ubuntu better. You reported this bug a while ago and there hasn't been any activity in it recently. We were wondering is this still an issue for you? Thanks in advance.

The issue that you reported is one that should be reproducable with the live environment of the Desktop CD of the development release - Gutsy Gibbon. It would help us greatly if you could test with it so we can work on getting it fixed in the actively developed release. You can find out more about the development release at [WWW] http://www.ubuntu.com/testing/

[trollord (trollenlord)]

We are closing this bug report because it lacks the information we need to investigate the problem, as described in the previous comments. Please reopen it if you can give us the missing information, and don't hesitate to submit bug reports in the future. To reopen the bug report you can click on the current status, under the Status column, and change the Status back to "New". Thanks again!