Please keep usb mail list CCed.
On 11/14/2011 08:40 PM, Julian Sikorski wrote:
W dniu 14.11.2011 10:27, Julian Sikorski pisze:
W dniu 2011-11-14 10:24, Andiry Xu pisze:
On 11/12/2011 02:20 PM, Julian Sikorski wrote:
W dniu 11.11.2011 21:11, Julian Sikorski pisze:
W dniu 31.08.2011 22:25, Julian Sikorski pisze:
Hi,
I originally reported this problem here:
https://bugzilla.kernel.org/show_bug.cgi?id=35212
Summing up, the external hard drive will produce an io-error after
about
30 minutes of being connected. After such event, system does not
notice
if the device is re-connected upon disconnection. I am attaching the
relevant portion of /var/log/messages.
The problem happens on an up-to-date Fedora 15 x86_64 (running kernel
2.6.40.3-0.fc15.x86_64) on a Clevo P150HM laptop and Lacie Rugged USB
3.0 hard disk.
Please let me know if I can provide more information
Regards,
Julian
I have recently upgraded to Fedora 16, and I am now running kernel
3.1.0-7.fc16.x86_64. This problem is far from being gone,
unfortunately.
It will go as follows:
- you plug the drive into one of USB3 ports
- everything works fine
- suspend and resume (not sure if this is necessary)
Have you figured out if this suspend/resume step is necessary?
Not yet. I will try later today (but bear with me given the hour needed
to trigger the problem).
Due to long time needed to reproduce the problem please accept this
partial report.
I have updated the kernel to 3.1.1-1.fc16.x86_64 (which showed up in
Fedora repositories earlier today). I then rebooted and am now running
with the drive connected for more than an hour downloading something off
bittorrent. Here is the fragment of /var/log/messages from the drive
connection until now. Keep in mind that these "stalled endpoint"
messages show up every 30 minutes (12:26, 12:56 and 13:26) - maybe if
the PC was suspended before they trigger the error?
Stalled endpoint message is normal. A reset endpoint command should
bring it back into normal state.
Do you connect other full speed devices to USB3 ports?
Thanks,
Andiry
Nov 14 12:26:51 snowball2 kernel: [ 248.735018] usb 3-2: new full speed
USB device number 2 using xhci_hcd
Nov 14 12:26:51 snowball2 kernel: [ 248.735442] usb 3-2: Device not
responding to set address.
Nov 14 12:26:51 snowball2 kernel: [ 248.936230] usb 3-2: Device not
responding to set address.
Nov 14 12:26:52 snowball2 kernel: [ 249.136661] usb 3-2: device not
accepting address 2, error -71
Nov 14 12:26:52 snowball2 kernel: [ 249.289464] usb 3-2: new full speed
USB device number 3 using xhci_hcd
Nov 14 12:26:52 snowball2 kernel: [ 249.289803] usb 3-2: Device not
responding to set address.
Nov 14 12:26:52 snowball2 kernel: [ 249.490591] usb 3-2: Device not
responding to set address.
Nov 14 12:26:52 snowball2 kernel: [ 249.691060] usb 3-2: device not
accepting address 3, error -71
Nov 14 12:26:52 snowball2 kernel: [ 249.742014] hub 3-0:1.0: unable to
enumerate USB device on port 2
Nov 14 12:26:53 snowball2 kernel: [ 250.877922] usb 4-2: new SuperSpeed
USB device number 2 using xhci_hcd
Nov 14 12:26:53 snowball2 kernel: [ 250.891680] xhci_hcd 0000:02:00.0:
WARN: short transfer on control ep
Nov 14 12:26:53 snowball2 kernel: [ 250.892291] xhci_hcd 0000:02:00.0:
WARN: short transfer on control ep
Nov 14 12:26:53 snowball2 kernel: [ 250.892877] xhci_hcd 0000:02:00.0:
WARN: short transfer on control ep
Nov 14 12:26:53 snowball2 kernel: [ 250.893496] xhci_hcd 0000:02:00.0:
WARN: short transfer on control ep
Nov 14 12:26:53 snowball2 kernel: [ 250.893766] usb 4-2: New USB device
found, idVendor=059f, idProduct=103e
Nov 14 12:26:53 snowball2 kernel: [ 250.893774] usb 4-2: New USB device
strings: Mfr=2, Product=3, SerialNumber=1
Nov 14 12:26:53 snowball2 kernel: [ 250.893780] usb 4-2: Product:
Rugged USB 3
Nov 14 12:26:53 snowball2 kernel: [ 250.893785] usb 4-2: Manufacturer:
LaCie
Nov 14 12:26:53 snowball2 kernel: [ 250.893789] usb 4-2: SerialNumber:
ce0238914a4c0000000
Nov 14 12:26:53 snowball2 mtp-probe: checking bus 4, device 2:
"/sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/usb4/4-2"
Nov 14 12:26:53 snowball2 mtp-probe: bus: 4, device: 2 was not an MTP device
Nov 14 12:26:54 snowball2 kernel: [ 251.916187] usbcore: registered new
interface driver uas
Nov 14 12:26:54 snowball2 kernel: [ 251.927250] Initializing USB Mass
Storage driver...
Nov 14 12:26:54 snowball2 kernel: [ 251.927704] scsi6 : usb-storage 4-2:1.0
Nov 14 12:26:54 snowball2 kernel: [ 251.928205] usbcore: registered new
interface driver usb-storage
Nov 14 12:26:54 snowball2 kernel: [ 251.928210] USB Mass Storage
support registered.
Nov 14 12:26:55 snowball2 kernel: [ 252.936594] scsi 6:0:0:0:
Direct-Access ST950032 5AS 0002 PQ: 0 ANSI: 0
Nov 14 12:26:55 snowball2 kernel: [ 252.936887] scsi: killing requests
for dead queue
Nov 14 12:26:55 snowball2 kernel: [ 252.937145] scsi: killing requests
for dead queue
Nov 14 12:26:55 snowball2 kernel: [ 252.937293] scsi: killing requests
for dead queue
Nov 14 12:26:55 snowball2 kernel: [ 252.937545] scsi: killing requests
for dead queue
Nov 14 12:26:55 snowball2 kernel: [ 252.937737] scsi: killing requests
for dead queue
Nov 14 12:26:55 snowball2 kernel: [ 252.937886] scsi: killing requests
for dead queue
Nov 14 12:26:55 snowball2 kernel: [ 252.938034] scsi: killing requests
for dead queue
Nov 14 12:26:55 snowball2 kernel: [ 252.938161] scsi: killing requests
for dead queue
Nov 14 12:26:55 snowball2 kernel: [ 252.938975] sd 6:0:0:0: Attached
scsi generic sg2 type 0
Nov 14 12:26:55 snowball2 kernel: [ 252.939052] sd 6:0:0:0: [sdb]
976773168 512-byte logical blocks: (500 GB/465 GiB)
Nov 14 12:26:55 snowball2 kernel: [ 252.939189] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:55 snowball2 kernel: [ 252.939774] sd 6:0:0:0: [sdb] Write
Protect is off
Nov 14 12:26:55 snowball2 kernel: [ 252.939924] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:55 snowball2 kernel: [ 252.940476] sd 6:0:0:0: [sdb] No
Caching mode page present
Nov 14 12:26:55 snowball2 kernel: [ 252.940489] sd 6:0:0:0: [sdb]
Assuming drive cache: write through
Nov 14 12:26:55 snowball2 kernel: [ 252.941688] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:55 snowball2 kernel: [ 252.942389] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:55 snowball2 kernel: [ 252.942994] sd 6:0:0:0: [sdb] No
Caching mode page present
Nov 14 12:26:55 snowball2 kernel: [ 252.943002] sd 6:0:0:0: [sdb]
Assuming drive cache: write through
Nov 14 12:26:55 snowball2 kernel: [ 253.040098] sdb: sdb1 sdb2
Nov 14 12:26:55 snowball2 kernel: [ 253.041711] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:55 snowball2 kernel: [ 253.042525] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:55 snowball2 kernel: [ 253.043115] sd 6:0:0:0: [sdb] No
Caching mode page present
Nov 14 12:26:55 snowball2 kernel: [ 253.043129] sd 6:0:0:0: [sdb]
Assuming drive cache: write through
Nov 14 12:26:55 snowball2 kernel: [ 253.043140] sd 6:0:0:0: [sdb]
Attached SCSI disk
Nov 14 12:26:56 snowball2 kernel: [ 253.111205] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:56 snowball2 kernel: [ 253.112108] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:56 snowball2 kernel: [ 253.405005] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:56 snowball2 kernel: [ 253.506974] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:56 snowball2 kernel: [ 253.518140] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:56 snowball2 kernel: [ 253.555874] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:56 snowball2 kernel: [ 253.678503] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:56 snowball2 kernel: [ 253.720672] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:26:58 snowball2 ntfs-3g[2084]: Version 2011.4.12 integrated
FUSE 27
Nov 14 12:26:58 snowball2 ntfs-3g[2084]: Mounted /dev/sdb1 (Read-Write,
label "lacie", NTFS 3.1)
Nov 14 12:26:58 snowball2 ntfs-3g[2084]: Cmdline options:
rw,nosuid,nodev,uhelper=udisks,uid=500,gid=500,dmask=0077,fmask=0177
Nov 14 12:26:58 snowball2 ntfs-3g[2084]: Mount options:
rw,nosuid,nodev,uhelper=udisks,allow_other,nonempty,atime,fsname=/dev/sdb1,blkdev,blksize=512,default_permissions
Nov 14 12:26:58 snowball2 ntfs-3g[2084]: Global ownership and
permissions enforced, configuration type 1
Nov 14 12:27:22 snowball2 dbus[1181]: [system] Activating service
name='net.reactivated.Fprint' (using servicehelper)
Nov 14 12:27:22 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Activating service name='net.reactivated.Fprint' (using servicehelper)
Nov 14 12:27:22 snowball2 dbus-daemon[1181]: Launching FprintObject
Nov 14 12:27:22 snowball2 dbus[1181]: [system] Successfully activated
service 'net.reactivated.Fprint'
Nov 14 12:27:22 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Successfully activated service 'net.reactivated.Fprint'
Nov 14 12:27:22 snowball2 dbus-daemon[1181]: ** Message: D-Bus service
launched with name: net.reactivated.Fprint
Nov 14 12:27:22 snowball2 dbus-daemon[1181]: ** Message: entering main loop
Nov 14 12:27:42 snowball2 ntfs-3g[2205]: Version 2011.4.12 integrated
FUSE 27
Nov 14 12:27:42 snowball2 ntfs-3g[2205]: Mounted /dev/mapper/realcrypt1
(Read-Write, label "", NTFS 3.1)
Nov 14 12:27:42 snowball2 ntfs-3g[2205]: Cmdline options:
rw,uid=0,gid=0,umask=077,dmask=000,fmask=111
Nov 14 12:27:42 snowball2 ntfs-3g[2205]: Mount options:
rw,allow_other,nonempty,atime,fsname=/dev/mapper/realcrypt1,blkdev,blksize=4096,default_permissions
Nov 14 12:27:42 snowball2 ntfs-3g[2205]: Global ownership and
permissions enforced, configuration type 1
Nov 14 12:27:53 snowball2 dbus-daemon[1181]: ** Message: No devices in
use, exit
Nov 14 12:28:12 snowball2 kernel: [ 329.358198] process `skype' is
using obsolete setsockopt SO_BSDCOMPAT
Nov 14 12:28:14 snowball2 pulseaudio[1759]: alsa-sink.c: ALSA została
wybudzona, aby zapisać nowe dane do urządzenia, ale nie było nic do
zapisania.
Nov 14 12:28:14 snowball2 pulseaudio[1759]: alsa-sink.c: Prawdopodobnie
jest to błąd w sterowniku ALSA "snd_hda_intel". Proszę zgłosić ten
problem programistom ALSA.
Nov 14 12:28:14 snowball2 pulseaudio[1759]: alsa-sink.c: Wybudzono za
pomocą ustawienia POLLOUT - ale jednoczesne wywołanie snd_pcm_avail()
zwróciło zero lub inną wartość< min_avail.
Nov 14 12:37:43 snowball2 systemd-tmpfiles[3021]: Successfully loaded
SELinux database in 9ms 553us, size on heap is 479K.
Nov 14 12:38:36 snowball2 ntpd[1117]: 0.0.0.0 c612 02 freq_set kernel
-25.621 PPM
Nov 14 12:38:36 snowball2 ntpd[1117]: 0.0.0.0 c615 05 clock_sync
Nov 14 12:51:18 snowball2 dbus[1181]: [system] Activating service
name='org.freedesktop.PackageKit' (using servicehelper)
Nov 14 12:51:19 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Nov 14 12:51:21 snowball2 dbus[1181]: [system] Successfully activated
service 'org.freedesktop.PackageKit'
Nov 14 12:51:21 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Successfully activated service 'org.freedesktop.PackageKit'
Nov 14 12:51:21 snowball2 dbus[1181]: [system] Activating service
name='net.reactivated.Fprint' (using servicehelper)
Nov 14 12:51:21 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Activating service name='net.reactivated.Fprint' (using servicehelper)
Nov 14 12:51:21 snowball2 dbus-daemon[1181]: Launching FprintObject
Nov 14 12:51:21 snowball2 dbus[1181]: [system] Successfully activated
service 'net.reactivated.Fprint'
Nov 14 12:51:21 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Successfully activated service 'net.reactivated.Fprint'
Nov 14 12:51:21 snowball2 dbus-daemon[1181]: ** Message: D-Bus service
launched with name: net.reactivated.Fprint
Nov 14 12:51:21 snowball2 dbus-daemon[1181]: ** Message: entering main loop
Nov 14 12:51:52 snowball2 dbus-daemon[1181]: ** Message: No devices in
use, exit
Nov 14 12:56:30 snowball2 kernel: [ 2025.456413] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:56:30 snowball2 kernel: [ 2025.457321] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:56:30 snowball2 kernel: [ 2025.789154] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:56:30 snowball2 kernel: [ 2025.922752] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 12:56:30 snowball2 kernel: [ 2025.964922] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 13:01:01 snowball2 systemd-logind[1120]: New user root logged in.
Nov 14 13:01:01 snowball2 systemd-logind[1120]: New session 3 of user root.
Nov 14 13:01:02 snowball2 systemd-logind[1120]: Removed session 3.
Nov 14 13:01:02 snowball2 systemd-logind[1120]: User root logged out.
Nov 14 13:10:28 snowball2 dbus[1181]: [system] Activating service
name='net.reactivated.Fprint' (using servicehelper)
Nov 14 13:10:28 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Activating service name='net.reactivated.Fprint' (using servicehelper)
Nov 14 13:10:28 snowball2 dbus-daemon[1181]: Launching FprintObject
Nov 14 13:10:28 snowball2 dbus[1181]: [system] Successfully activated
service 'net.reactivated.Fprint'
Nov 14 13:10:28 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Successfully activated service 'net.reactivated.Fprint'
Nov 14 13:10:28 snowball2 dbus-daemon[1181]: ** Message: D-Bus service
launched with name: net.reactivated.Fprint
Nov 14 13:10:28 snowball2 dbus-daemon[1181]: ** Message: entering main loop
Nov 14 13:10:59 snowball2 dbus-daemon[1181]: ** Message: No devices in
use, exit
Nov 14 13:20:45 snowball2 dbus[1181]: [system] Activating service
name='org.freedesktop.PackageKit' (using servicehelper)
Nov 14 13:20:45 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Nov 14 13:20:45 snowball2 dbus[1181]: [system] Successfully activated
service 'org.freedesktop.PackageKit'
Nov 14 13:20:45 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Successfully activated service 'org.freedesktop.PackageKit'
Nov 14 13:26:30 snowball2 kernel: [ 3823.423450] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 13:26:30 snowball2 kernel: [ 3823.424550] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 13:26:30 snowball2 kernel: [ 3823.755151] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 13:26:30 snowball2 kernel: [ 3823.888851] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 13:26:30 snowball2 kernel: [ 3823.996842] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 14 13:31:30 snowball2 dbus[1181]: [system] Activating service
name='org.freedesktop.PackageKit' (using servicehelper)
Nov 14 13:31:30 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Activating service name='org.freedesktop.PackageKit' (using servicehelper)
Nov 14 13:31:30 snowball2 dbus[1181]: [system] Successfully activated
service 'org.freedesktop.PackageKit'
Nov 14 13:31:30 snowball2 dbus-daemon[1181]: dbus[1181]: [system]
Successfully activated service 'org.freedesktop.PackageKit'
- plug the drive again
- everything works fine again for about an hour
- BOOM
by boom, I mean the following:
Nov 11 20:25:36 snowball2 kernel: [ 9244.406224] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 11 20:25:36 snowball2 kernel: [ 9244.406818] sd 7:0:0:0: [sdc]
Sense
Key : Recovered Error [current] [descriptor]
Nov 11 20:25:36 snowball2 kernel: [ 9244.406822] Descriptor sense data
with sense descriptors (in hex):
Nov 11 20:25:36 snowball2 kernel: [ 9244.406824] 72 01 04 1d 00
00 00 0e 09 0c 00 00 00 00 00 fd
Nov 11 20:25:36 snowball2 kernel: [ 9244.406829] 00 49 00 6a
40 50
Nov 11 20:25:36 snowball2 kernel: [ 9244.406832] sd 7:0:0:0: [sdc]
ASC=0x4 ASCQ=0x1d
Nov 11 20:25:36 snowball2 kernel: [ 9244.407175] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 11 20:25:37 snowball2 kernel: [ 9244.759971] xhci_hcd 0000:02:00.0:
WARN: Stalled endpoint
Nov 11 20:25:49 snowball2 kernel: [ 9256.969686] xhci_hcd 0000:02:00.0:
xHCI host not responding to stop endpoint command.
Nov 11 20:25:49 snowball2 kernel: [ 9256.969696] xhci_hcd 0000:02:00.0:
Assuming host is dying, halting host.
Nov 11 20:25:49 snowball2 kernel: [ 9256.978610] xhci_hcd 0000:02:00.0:
HC died; cleaning up
Nov 11 20:25:49 snowball2 kernel: [ 9256.978687] usb 4-2: USB
disconnect, device number 2
Nov 11 20:25:49 snowball2 kernel: [ 9256.978810] sd 7:0:0:0: Device
offlined - not ready after error recovery
Nov 11 20:25:49 snowball2 ntfs-3g[17800]: Unmounting /dev/sdc1 (lacie)
Nov 11 20:25:49 snowball2 ntfs-3g[17800]: Failed to sync device
/dev/sdc1: Input/output error
Nov 11 20:25:49 snowball2 ntfs-3g[17800]: Failed to fsync device
/dev/sdc1: Input/output error
Nov 11 20:25:49 snowball2 ntfs-3g[17800]: Failed to close volume
/dev/sdc1: Device or resource busy
As a side question, are these recovered errors something to be
concerned
about? The drive is encrypted with truecrypt and they have been showing
since the day I bought it.
Julian
--
To unsubscribe from this list: send the line "unsubscribe linux-usb" in
the body of a message to
majordomo-u79uwXL29TY76Z2rM5mHXA@xxxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
One more thing: if you attempt to suspend after this happens, the
machine will lock up. In Fedora 15 it would just go back to gnome-shell.
Finally, I believe I never posted lspci output, here it is:
The host driver is dead and unable to perform any suspend requests.
Does this issue occur with other USB3.0 devices?
Thanks,
Andiry
I only have this one Lacie hard drive so I am afraid I cannot answer
this question.
Julian
02:00.0 USB Controller: NEC Corporation uPD720200 USB 3.0 Host
Controller (rev 03) (prog-if 30 [XHCI])
Subsystem: CLEVO/KAPOK Computer Device 5102
Flags: bus master, fast devsel, latency 0, IRQ 16
Memory at f6400000 (64-bit, non-prefetchable) [size=8K]
Capabilities: [50] Power Management version 3
Capabilities: [70] MSI: Enable- Count=1/8 Maskable- 64bit+
Capabilities: [90] MSI-X: Enable+ Count=8 Masked-
Capabilities: [a0] Express Endpoint, MSI 00
Capabilities: [100] Advanced Error Reporting
Capabilities: [140] Device Serial Number ff-ff-ff-ff-ff-ff-ff-ff
Capabilities: [150] #18
Kernel driver in use: xhci_hcd
--
To unsubscribe from this list: send the line "unsubscribe linux-usb" in
the body of a message to majordomo-u79uwXL29TY76Z2rM5mHXA@xxxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
--
To unsubscribe from this list: send the line "unsubscribe linux-usb" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
--
To unsubscribe from this list: send the line "unsubscribe linux-usb" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
