Hi,
I have an external hard drive (well, actually a case for an internal
one, but this may not be relevant) that connects to the PC via USB3.
However, I've been experiencing erratic behaviour with it. So far
there have been two issues.
One is that if I boot with the cable plugged in already, then it fails
to mount the volume (or, indeed, report that it even exists). However,
if I either remove the cable and replug it into another USB3 socket or
if I plug it in only after it's done booting, then everything is fine
and I can see and mount the volume. I'll attach the dmesg errors about
it.
The other issue is rather peculiar. When I get it mounted, doing
certain I/O tasks (I'm testing with btrfs send/receive) results in the
device disconnecting and unmounting (the key error seems to be "xHCI
xhci_drop_endpoint called with disabled ep ffff8801aced5d40"). When
it's connected through a USB2 port, I also get errors ("reset
high-speed USB device number 6 using ehci-pci"), but they don't result
in unmounting the volume. And if I do large file copying instead of
btrfs send/receive, then everything works fine even when connected
through USB3. I'll also attach the error logs in question.
I also tested this with a separate USB2 cable, and the results are the
same as when I use the USB3 cable but connect it to a USB2 port.
I'm currently using kernel 3.10.11.
Any ideas about what could be causing this behaviour with USB3?
[ 0.921676] xhci_hcd 0000:00:14.0: setting latency timer to 64
[ 0.921680] xhci_hcd 0000:00:14.0: xHCI Host Controller
[ 0.921687] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus
number 3
[ 0.921771] xhci_hcd 0000:00:14.0: cache line size of 64 is not supported
[ 0.921794] xhci_hcd 0000:00:14.0: irq 43 for MSI/MSI-X
[ 0.921843] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002
[ 0.921845] usb usb3: New USB device strings: Mfr=3, Product=2,
SerialNumber=1
[ 0.921846] usb usb3: Product: xHCI Host Controller
[ 0.921848] usb usb3: Manufacturer: Linux 3.11.10-7-desktop xhci_hcd
[ 0.921849] usb usb3: SerialNumber: 0000:00:14.0
[ 0.922074] xHCI xhci_add_endpoint called for root hub
[ 0.922076] xHCI xhci_check_bandwidth called for root hub
[ 0.922099] hub 3-0:1.0: USB hub found
[ 0.922106] hub 3-0:1.0: 4 ports detected
[ 0.923854] xhci_hcd 0000:00:14.0: xHCI Host Controller
[ 0.923858] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus
number 4
[ 0.923876] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003
[ 0.923878] usb usb4: New USB device strings: Mfr=3, Product=2,
SerialNumber=1
[ 0.923880] usb usb4: Product: xHCI Host Controller
[ 0.923881] usb usb4: Manufacturer: Linux 3.11.10-7-desktop xhci_hcd
[ 0.923882] usb usb4: SerialNumber: 0000:00:14.0
[ 0.923956] xHCI xhci_add_endpoint called for root hub
[ 0.923957] xHCI xhci_check_bandwidth called for root hub
[ 0.923977] hub 4-0:1.0: USB hub found
[ 0.923983] hub 4-0:1.0: 4 ports detected
[ 1.294519] usb 3-4: new high-speed USB device number 2 using xhci_hcd
[ 6.294532] xhci_hcd 0000:00:14.0: Timeout while waiting for address device
command
[ 11.495571] xhci_hcd 0000:00:14.0: Timeout while waiting for address device
command
[ 11.696552] usb 3-4: device not accepting address 2, error -62
[ 11.849577] usb 3-4: new high-speed USB device number 3 using xhci_hcd
[ 16.849611] xhci_hcd 0000:00:14.0: Timeout while waiting for address device
command
[ 22.050578] xhci_hcd 0000:00:14.0: Timeout while waiting for address device
command
[ 22.251554] usb 3-4: device not accepting address 3, error -62
[ 22.404569] usb 3-4: new high-speed USB device number 4 using xhci_hcd
[ 27.404495] xhci_hcd 0000:00:14.0: Timeout while waiting for address device
command
[ 32.605405] xhci_hcd 0000:00:14.0: Timeout while waiting for address device
command
[ 32.806420] usb 3-4: device not accepting address 4, error -62
[ 32.959422] usb 3-4: new high-speed USB device number 5 using xhci_hcd
[ 37.959351] xhci_hcd 0000:00:14.0: Timeout while waiting for address device
command
[ 43.160272] xhci_hcd 0000:00:14.0: Timeout while waiting for address device
command
[ 43.361278] usb 3-4: device not accepting address 5, error -62
[ 43.361321] hub 3-0:1.0: unable to enumerate USB device on port 4
13:01:31 kernel: usb 4-4: new SuperSpeed USB device number 2 using xhci_hcd
13:01:31 kernel: usb 4-4: New USB device found, idVendor=05e3, idProduct=0731
13:01:31 kernel: usb 4-4: New USB device strings: Mfr=3, Product=4,
SerialNumber=5
13:01:31 kernel: usb 4-4: Product: USB to S-ATA
13:01:31 kernel: usb 4-4: Manufacturer: Genesyslogic
13:01:31 kernel: usb 4-4: SerialNumber: 0000000000000497
13:01:32 mtp-probe[9781]: checking bus 4, device 2:
"/sys/devices/pci0000:00/0000:00:14.0/usb4/4-4"
13:01:32 mtp-probe[9781]: bus: 4, device: 2 was not an MTP device
13:01:32 kernel: usb-storage 4-4:1.0: USB Mass Storage device detected
13:01:32 kernel: scsi6 : usb-storage 4-4:1.0
13:01:32 kernel: usbcore: registered new interface driver usb-storage
13:01:33 kernel: scsi 6:0:0:0: Direct-Access WDC WD32 00BEVT-26ZCT0 12.0
PQ: 0 ANSI: 0
13:01:33 kernel: sd 6:0:0:0: Attached scsi generic sg3 type 0
13:01:33 kernel: sd 6:0:0:0: [sdc] 625142444 512-byte logical blocks: (320
GB/298 GiB)
13:01:33 kernel: sd 6:0:0:0: [sdc] Write Protect is off
13:01:33 kernel: sd 6:0:0:0: [sdc] Mode Sense: 03 00 00 00
13:01:33 kernel: sd 6:0:0:0: [sdc] No Caching mode page found
13:01:33 kernel: sd 6:0:0:0: [sdc] Assuming drive cache: write through
13:01:33 kernel: sd 6:0:0:0: [sdc] No Caching mode page found
13:01:33 kernel: sd 6:0:0:0: [sdc] Assuming drive cache: write through
13:01:33 kernel: sdc: sdc1 sdc2
13:01:33 kernel: sd 6:0:0:0: [sdc] No Caching mode page found
13:01:33 kernel: sd 6:0:0:0: [sdc] Assuming drive cache: write through
13:01:33 kernel: sd 6:0:0:0: [sdc] Attached SCSI disk
13:01:34 kernel: device label ExtBackup devid 1 transid 236 /dev/sdc1
13:01:43 kernel: btrfs: disk space caching is enabled
13:01:44 udisksd[1157]: Mounted /dev/sdc1 at /run/media/dainius/ExtBackup on
behalf of uid 1000
13:12:03 sudo[10153]: dainius : TTY=pts/1 ;
PWD=/var/run/media/dainius/ExtBackup/snapshots/root ; USER=root ;
COMMAND=/sbin/btrfs receive -v
/var/run/media/dainius/ExtBackup/snapshots/root/snapshot/
13:12:51 kernel: usb 4-4: reset SuperSpeed USB device number 2 using xhci_hcd
13:12:51 kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801aced5d40
13:12:51 kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801aced5d80
13:13:03 kernel: usb 4-4: reset SuperSpeed USB device number 2 using xhci_hcd
13:13:03 kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801aced5d40
13:13:03 kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801aced5d80
13:13:10 kernel: usb 4-4: reset SuperSpeed USB device number 2 using xhci_hcd
13:13:10 kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801aced5d40
13:13:10 kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801aced5d80
13:13:59 kernel: usb 4-4: Disable of device-initiated U1 failed.
13:14:14 kernel: usb 4-4: Disable of device-initiated U2 failed.
13:14:14 udisksd[1157]: Cleaning up mount point /run/media/dainius/ExtBackup
(device 8:33 no longer exist)
13:14:14 kernel: xhci_hcd 0000:00:14.0: Timeout while waiting for address
device command
13:14:14 kernel: xhci_hcd 0000:00:14.0: Timeout while waiting for address
device command
13:14:14 kernel: usb 4-4: device not accepting address 2, error -62
13:14:14 kernel: usb 4-4: USB disconnect, device number 2
13:14:16 kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801aced5d40
13:14:16 kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with
disabled ep ffff8801aced5d80
Bal 16 13:56:16 mahobay kernel: usb 1-1.2: new high-speed USB device number 6
using ehci-pci
Bal 16 13:56:16 mahobay kernel: usb 1-1.2: New USB device found, idVendor=05e3,
idProduct=0731
Bal 16 13:56:16 mahobay kernel: usb 1-1.2: New USB device strings: Mfr=3,
Product=4, SerialNumber=5
Bal 16 13:56:16 mahobay kernel: usb 1-1.2: Product: USB to S-ATA
Bal 16 13:56:16 mahobay kernel: usb 1-1.2: Manufacturer: Genesyslogic
Bal 16 13:56:16 mahobay kernel: usb 1-1.2: SerialNumber: 0000000000000497
Bal 16 13:56:16 mahobay kernel: usb-storage 1-1.2:1.0: USB Mass Storage device
detected
Bal 16 13:56:16 mahobay kernel: scsi9 : usb-storage 1-1.2:1.0
Bal 16 13:56:16 mahobay mtp-probe[10846]: checking bus 1, device 6:
"/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2"
Bal 16 13:56:16 mahobay mtp-probe[10846]: bus: 1, device: 6 was not an MTP
device
Bal 16 13:56:20 mahobay kernel: scsi 9:0:0:0: Direct-Access WDC WD32
00BEVT-26ZCT0 12.0 PQ: 0 ANSI: 0
Bal 16 13:56:20 mahobay kernel: sd 9:0:0:0: Attached scsi generic sg3 type 0
Bal 16 13:56:20 mahobay kernel: sd 9:0:0:0: [sdd] 625142444 512-byte logical
blocks: (320 GB/298 GiB)
Bal 16 13:56:20 mahobay kernel: sd 9:0:0:0: [sdd] Write Protect is off
Bal 16 13:56:20 mahobay kernel: sd 9:0:0:0: [sdd] Mode Sense: 03 00 00 00
Bal 16 13:56:20 mahobay kernel: sd 9:0:0:0: [sdd] No Caching mode page found
Bal 16 13:56:20 mahobay kernel: sd 9:0:0:0: [sdd] Assuming drive cache: write
through
Bal 16 13:56:20 mahobay kernel: sd 9:0:0:0: [sdd] No Caching mode page found
Bal 16 13:56:20 mahobay kernel: sd 9:0:0:0: [sdd] Assuming drive cache: write
through
Bal 16 13:56:20 mahobay kernel: sdd: sdd1 sdd2
Bal 16 13:56:20 mahobay kernel: sd 9:0:0:0: [sdd] No Caching mode page found
Bal 16 13:56:20 mahobay kernel: sd 9:0:0:0: [sdd] Assuming drive cache: write
through
Bal 16 13:56:20 mahobay kernel: sd 9:0:0:0: [sdd] Attached SCSI disk
Bal 16 13:56:20 mahobay kernel: device label ExtBackup devid 1 transid 239
/dev/sdd1
Bal 16 13:57:20 mahobay udisksd[1157]: Mounted /dev/sdd1 at
/run/media/dainius/ExtBackup on behalf of uid 1000
Bal 16 13:58:49 mahobay sudo[11034]: dainius : TTY=pts/1 ;
PWD=/var/run/media/dainius/ExtBackup/snapshots ; USER=root ;
COMMAND=/sbin/btrfs receive -v /var/run/media/dainius/ExtBackup/snapshots/root/
Bal 16 13:59:38 mahobay kernel: could not find root 9
Bal 16 13:59:38 mahobay kernel: could not find root 9
Bal 16 13:59:38 mahobay kernel: could not find root 9
Bal 16 13:59:38 mahobay kernel: could not find root 9
Bal 16 13:59:51 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:00:22 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:01:21 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:01:27 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:02:21 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:02:53 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:05:22 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:07:15 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:09:02 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:09:08 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:09:15 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:10:19 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:12:34 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:12:39 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:12:50 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:14:11 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:14:51 mahobay kernel: usb 1-1.2: reset high-speed USB device number 6
using ehci-pci
Bal 16 14:24:21 mahobay udisksd[1157]: Cleaning up mount point
/run/media/dainius/ExtBackup (device 8:49 is not mounted)
Bal 16 14:24:22 mahobay udisksd[1157]: Unmounted /dev/sdd1 on behalf of uid 1000