USB Errors with 13.0-U2 I thought were gone with 13.0-U3, but not...

Andrew Ostrom

Explorer
Joined
Jul 28, 2017
Messages
57
I am just posting this to save others some time and possible frustration. With some past update, could have been 13.0-U2 maybe 13.0-U1, my system started showing a bazillion (+/- a jillion or two) USB timeout errors every time it booted, and often even after that. I did a little searching and couldn't find anyone else reporting this, and my server still ran fine, so I never bothered to report it. Also, I wondered if I had a hardware issue, or if it was TrueNAS.

Anyway - I just installed the TrueNAS 13.0-U3 update and all the USB error messages are GONE. So - thank you to whoever fixed whatever was wrong.
 

Andrew Ostrom

Explorer
Joined
Jul 28, 2017
Messages
57
OK, this is weird - it's back after a reboot. Here's what I get, and this repeats endlessly. Any help would be appreciated.

Code:
Nov  4 16:35:24 freenas xhci0: Resetting controller
Nov  4 16:35:24 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:35:26 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:35:44 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:35:45 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:36:03 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:36:04 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:36:22 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:36:24 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:36:42 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:36:42 freenas ugen2.3: <Unknown > at usbus2 (disconnected)
Nov  4 16:36:42 freenas uhub_reattach_port: could not allocate new device
Nov  4 16:36:42 freenas uhub1: at usbus2, port 1, addr 1 (disconnected)
Nov  4 16:36:42 freenas ugen2.2: <VIA Labs, Inc. USB3.0 Hub> at usbus2 (disconnected)
Nov  4 16:36:42 freenas uhub3: at uhub1, port 2, addr 1 (disconnected)
Nov  4 16:36:44 freenas uhub3: detached
Nov  4 16:36:44 freenas uhub1: detached
Nov  4 16:36:44 freenas uhub1 numa-domain 1 on usbus2
Nov  4 16:36:44 freenas uhub1: <(0x1b21) XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus2
Nov  4 16:36:45 freenas uhub1: 4 ports with 4 removable, self powered
Nov  4 16:36:45 freenas ugen2.2: <VIA Labs, Inc. USB3.0 Hub> at usbus2
Nov  4 16:36:45 freenas uhub3 numa-domain 1 on uhub1
Nov  4 16:36:45 freenas uhub3: <VIA Labs, Inc. USB3.0 Hub, class 9/0, rev 3.00/91.05, addr 1> on usbus2
Nov  4 16:36:47 freenas uhub3: 4 ports with 4 removable, self powered
Nov  4 16:36:47 freenas usb_alloc_device: set address 3 failed (USB_ERR_TIMEOUT, ignored)
Nov  4 16:37:05 freenas xhci0: Resetting controller
Nov  4 16:37:05 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:37:07 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:37:24 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:37:26 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:37:44 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:37:45 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:38:03 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:38:05 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:38:23 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:38:23 freenas ugen2.3: <Unknown > at usbus2 (disconnected)
Nov  4 16:38:23 freenas uhub_reattach_port: could not allocate new device
Nov  4 16:38:23 freenas uhub1: at usbus2, port 1, addr 1 (disconnected)
Nov  4 16:38:23 freenas ugen2.2: <VIA Labs, Inc. USB3.0 Hub> at usbus2 (disconnected)
Nov  4 16:38:23 freenas uhub3: at uhub1, port 2, addr 1 (disconnected)
Nov  4 16:38:25 freenas uhub3: detached
Nov  4 16:38:25 freenas uhub1: detached
Nov  4 16:38:25 freenas uhub1 numa-domain 1 on usbus2
Nov  4 16:38:25 freenas uhub1: <(0x1b21) XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus2
Nov  4 16:38:26 freenas uhub1: 4 ports with 4 removable, self powered
Nov  4 16:38:26 freenas ugen2.2: <VIA Labs, Inc. USB3.0 Hub> at usbus2
Nov  4 16:38:26 freenas uhub3 numa-domain 1 on uhub1
Nov  4 16:38:26 freenas uhub3: <VIA Labs, Inc. USB3.0 Hub, class 9/0, rev 3.00/91.05, addr 1> on usbus2
Nov  4 16:38:28 freenas uhub3: 4 ports with 4 removable, self powered
Nov  4 16:38:28 freenas usb_alloc_device: set address 3 failed (USB_ERR_TIMEOUT, ignored)
Nov  4 16:38:46 freenas xhci0: Resetting controller
 

rogerh

Guru
Joined
Apr 18, 2014
Messages
1,111
OK, this is weird - it's back after a reboot. Here's what I get, and this repeats endlessly. Any help would be appreciated.

Code:
Nov  4 16:35:24 freenas xhci0: Resetting controller
Nov  4 16:35:24 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:35:26 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:35:44 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:35:45 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:36:03 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:36:04 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:36:22 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:36:24 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:36:42 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:36:42 freenas ugen2.3: <Unknown > at usbus2 (disconnected)
Nov  4 16:36:42 freenas uhub_reattach_port: could not allocate new device
Nov  4 16:36:42 freenas uhub1: at usbus2, port 1, addr 1 (disconnected)
Nov  4 16:36:42 freenas ugen2.2: <VIA Labs, Inc. USB3.0 Hub> at usbus2 (disconnected)
Nov  4 16:36:42 freenas uhub3: at uhub1, port 2, addr 1 (disconnected)
Nov  4 16:36:44 freenas uhub3: detached
Nov  4 16:36:44 freenas uhub1: detached
Nov  4 16:36:44 freenas uhub1 numa-domain 1 on usbus2
Nov  4 16:36:44 freenas uhub1: <(0x1b21) XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus2
Nov  4 16:36:45 freenas uhub1: 4 ports with 4 removable, self powered
Nov  4 16:36:45 freenas ugen2.2: <VIA Labs, Inc. USB3.0 Hub> at usbus2
Nov  4 16:36:45 freenas uhub3 numa-domain 1 on uhub1
Nov  4 16:36:45 freenas uhub3: <VIA Labs, Inc. USB3.0 Hub, class 9/0, rev 3.00/91.05, addr 1> on usbus2
Nov  4 16:36:47 freenas uhub3: 4 ports with 4 removable, self powered
Nov  4 16:36:47 freenas usb_alloc_device: set address 3 failed (USB_ERR_TIMEOUT, ignored)
Nov  4 16:37:05 freenas xhci0: Resetting controller
Nov  4 16:37:05 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:37:07 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:37:24 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:37:26 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:37:44 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:37:45 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:38:03 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:38:05 freenas usbd_req_re_enumerate: addr=3, set address failed! (USB_ERR_TIMEOUT, ignored)
Nov  4 16:38:23 freenas usbd_setup_device_desc: getting device descriptor at addr 3 failed, USB_ERR_TIMEOUT
Nov  4 16:38:23 freenas ugen2.3: <Unknown > at usbus2 (disconnected)
Nov  4 16:38:23 freenas uhub_reattach_port: could not allocate new device
Nov  4 16:38:23 freenas uhub1: at usbus2, port 1, addr 1 (disconnected)
Nov  4 16:38:23 freenas ugen2.2: <VIA Labs, Inc. USB3.0 Hub> at usbus2 (disconnected)
Nov  4 16:38:23 freenas uhub3: at uhub1, port 2, addr 1 (disconnected)
Nov  4 16:38:25 freenas uhub3: detached
Nov  4 16:38:25 freenas uhub1: detached
Nov  4 16:38:25 freenas uhub1 numa-domain 1 on usbus2
Nov  4 16:38:25 freenas uhub1: <(0x1b21) XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus2
Nov  4 16:38:26 freenas uhub1: 4 ports with 4 removable, self powered
Nov  4 16:38:26 freenas ugen2.2: <VIA Labs, Inc. USB3.0 Hub> at usbus2
Nov  4 16:38:26 freenas uhub3 numa-domain 1 on uhub1
Nov  4 16:38:26 freenas uhub3: <VIA Labs, Inc. USB3.0 Hub, class 9/0, rev 3.00/91.05, addr 1> on usbus2
Nov  4 16:38:28 freenas uhub3: 4 ports with 4 removable, self powered
Nov  4 16:38:28 freenas usb_alloc_device: set address 3 failed (USB_ERR_TIMEOUT, ignored)
Nov  4 16:38:46 freenas xhci0: Resetting controller

I was getting runs of continuous samba log messages about a Time Machine share closing connections. This was producing tens of GiB a day of log messages. gzipping and rotating the log files (on a remote machine, not Truenas) helped. But setting Log Level in the SAMBA Service configuration to "Minimum" instead of "Normal" seems to have helped even more.

I don't know if it is a good idea for SAMBA to produce hundreds of identical log messages per second, but it seems too vague to report as a bug.
 

Andrew Ostrom

Explorer
Joined
Jul 28, 2017
Messages
57
Does anyone have any ideas if this is a hardware issue, or a TrueNAS problem? The only USB device is a keyboard and it works fine.

Thanks.
 

joeschmuck

Old Man
Moderator
Joined
May 28, 2011
Messages
10,996

joeschmuck

Old Man
Moderator
Joined
May 28, 2011
Messages
10,996

Andrew Ostrom

Explorer
Joined
Jul 28, 2017
Messages
57
Do you have a USB Hub?
I do not have anything external to the SuperMicro motherboard other than the keyboard. I had my UPS plugged in, but I disconnected it.
 

joeschmuck

Old Man
Moderator
Joined
May 28, 2011
Messages
10,996
It very well might be related to the FreeBSD problem I linked above, which would mean it's not a TrueNAS problem directly. You could submit a bug report and reference the link, maybe it will help, maybe it's some other cause. You may need to roll back to 13.0-U2.
 
Top