USB Problems with HDD on Freebsd 8

Wed Jun 6 17:30:56 UTC 2012

At Wed, 6 Jun 2012 17:52:38 +0200,
Hans Petter Selasky wrote:
> 
> On Wednesday 06 June 2012 15:35:04 Momchil Ivanov wrote:
> > Hi,
> > 
> > I have some troubles using my new USB HDD, it seems do die when
> > I try to write some things on it with rsync, otherwise it works
> > ok. Here is the log when it crashed and I tried to reinsert the USB
> > cable several times, but it does not seem to get attached:
> > 
> > ugen2.6: <Hitachi GST> at usbus2 (disconnected)
> > umass0: at uhub9, port 2, addr 6 (disconnected)
> > (da0:umass-sim0:0:0:0): lost device
> > (da0:umass-sim0:0:0:0): Invalidating pack
> > GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839091200,
> > length=131072)] g_vfs_done():ufs/storage[READ(offset=902839091200,
> > length=131072)]error = 6 GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839091200, length=16384)] GEOM_ELI:
> > g_eli_read_done() failed da0.eli[READ(offset=902839107584, length=131072)]
> > g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839091200,
> > length=16384)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839107584, length=131072)]
> > g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839091200,
> > length=16384)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839107584, length=131072)]
> > g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839091200,
> > length=16384)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839107584, length=131072)]
> > g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839091200,
> > length=16384)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839107584, length=131072)]
> > g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839091200,
> > length=16384)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839107584, length=131072)]
> > g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839091200,
> > length=16384)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839107584, length=131072)]
> > g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839091200,
> > length=16384)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839107584, length=131072)]
> > g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839091200,
> > length=16384)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839107584, length=131072)]
> > g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839091200,
> > length=16384)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839107584, length=131072)]
> > g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839091200,
> > length=16384)] g_vfs_done():ufs/storage[READ(offset=902839091200,
> > length=16384)]error = 6 GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839107584, length=131072)] GEOM_ELI:
> > g_eli_read_done() failed da0.eli[READ(offset=902839091200, length=16384)]
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839107584,
> > length=131072)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839091200, length=16384)]
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839107584,
> > length=131072)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839091200, length=16384)]
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=131072)]error =
> > 6 g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839107584,
> > length=81920)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839189504, length=131072)] GEOM_ELI:
> > g_eli_read_done() failed da0.eli[READ(offset=902839091200, length=16384)]
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=81920)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839189504, length=131072)]error =
> > 6 g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839107584,
> > length=81920)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839189504, length=131072)] GEOM_ELI:
> > g_eli_read_done() failed da0.eli[READ(offset=902839091200, length=16384)]
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=81920)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839189504, length=131072)]error =
> > 6 g_vfs_done():ufs/storage[READ(offset=902839091200, length=16384)]error =
> > 6 GEOM_ELI: g_eli_read_done() failed da0.eli[READ(offset=902839107584,
> > length=81920)] GEOM_ELI: g_eli_read_done() failed
> > da0.eli[READ(offset=902839189504, length=65536)]
> > g_vfs_done():ufs/storage[READ(offset=902839107584, length=81920)]error = 6
> > g_vfs_done():ufs/storage[READ(offset=902839189504, length=65536)]error = 6
> > (da0:umass-sim0:0:0:0): Synchronize cache failed, status == 0xa, scsi
> > status == 0x0 (da0:umass-sim0:0:0:0): removing device entry
> > usb_alloc_device: set address 5 failed (USB_ERR_TIMEOUT, ignored)
> > usbd_req_re_enumerate: addr=5, set address failed! (USB_ERR_STALLED,
> > ignored) pid 2695 (zsh), uid 1005 inumber 116230002 on /mnt/storage: out
> > of inodes Device storage went missing before all of the data could be
> > written to it; expect data loss. usbd_req_re_enumerate: addr=5, set
> > address failed! (USB_ERR_TIMEOUT, ignored) ugen2.5: <Unknown> at usbus2
> > (disconnected)
> > uhub_reattach_port: could not allocate new device
> > usb_alloc_device: set address 5 failed (USB_ERR_TIMEOUT, ignored)
> > usbd_req_re_enumerate: addr=5, set address failed! (USB_ERR_STALLED,
> > ignored) usbd_req_re_enumerate: addr=5, set address failed!
> > (USB_ERR_TIMEOUT, ignored) ugen2.5: <Unknown> at usbus2 (disconnected)
> > uhub_reattach_port: could not allocate new device
> > usb_alloc_device: set address 5 failed (USB_ERR_TIMEOUT, ignored)
> > usbd_req_re_enumerate: addr=5, set address failed! (USB_ERR_TIMEOUT,
> > ignored) usbd_req_re_enumerate: addr=5, set address failed!
> > (USB_ERR_TIMEOUT, ignored) ugen2.5: <Unknown> at usbus2 (disconnected)
> > uhub_reattach_port: could not allocate new device
> > usb_alloc_device: set address 5 failed (USB_ERR_TIMEOUT, ignored)
> > usbd_req_re_enumerate: addr=5, set address failed! (USB_ERR_TIMEOUT,
> > ignored) usbd_req_re_enumerate: addr=5, set address failed!
> > (USB_ERR_TIMEOUT, ignored) ugen2.5: <Unknown> at usbus2 (disconnected)
> > uhub_reattach_port: could not allocate new device
> > usb_alloc_device: set address 5 failed (USB_ERR_TIMEOUT, ignored)
> > usbd_req_re_enumerate: addr=5, set address failed! (USB_ERR_TIMEOUT,
> > ignored) usbd_req_re_enumerate: addr=5, set address failed!
> > (USB_ERR_TIMEOUT, ignored) ugen2.5: <Unknown> at usbus2 (disconnected)
> > uhub_reattach_port: could not allocate new device
> > usb_alloc_device: set address 5 failed (USB_ERR_TIMEOUT, ignored)
> > usbd_req_re_enumerate: addr=5, set address failed! (USB_ERR_TIMEOUT,
> > ignored) usbd_req_re_enumerate: addr=5, set address failed!
> > (USB_ERR_TIMEOUT, ignored) ugen2.5: <Unknown> at usbus2 (disconnected)
> > uhub_reattach_port: could not allocate new device
> > 
> > 
> > I am using FreeBSD 8.2-STABLE i386 as of Tue Dec 27 17:01:47 CET
> > 2011. Hope somebody can help me with this issue, I haven't seen it
> > with my other USB HDDs.
> > 
> > Note: Please CC me since I am not subscribed.
> 
> Hi,
> 
> Is your HDD self-powered or USB powered?
> 
> If a USB reset doesn't bring the device back, its firmware is most likely 
> crashed and there is not much we can do about it, except throttling the data 
> traffic on the BULK endpoints. Currently there is no userland option to enable 
> throttling.
> 
> Try:
> 
> usbconfig -d X.Y reset
> 
> --HPS

It's self powered, I will try USB reset if it happens with other data,
for the time being I am manually rsync-ing sub directories. The thing
is that it's reproducible if I try to rsync a certain folder. I will
check if I can pin it down to a certain file. Would be a pitty if it's
a firmware bug since I spent some money on the device.

Regards,
Momchil