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? 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
