VMware Cloud Community
Lagus1
Contributor
Contributor
‎12-06-2016 04:52 AM

BUG: ESXi 6.5: USB passthrough error

Hi!

There seem to be a fair number of discussions on this topic:

ESXi 6.5 USB Devices attached to VMs Issues?‌

ESXi 6.5 issue with USB Dymo Labelwriter

and also my case ...

Both my devices worked in ESXi 6.0 U2 and now have major troubles in 6.5.

So I would like to collect all issue on this on one thread for easier troubleshooting.

My issue is with one USB hard drive (1058:1230 Western Digital Technologies, Inc.) connected to a USB 3.0 port.

I'm running HPE official image 6.5 on an Proliant Microserver Gen8

Uname -a: VMkernel host.domain 6.5.0 #1 SMP Release build-4564106 Oct 26 2016 22:24:57 x86_64 x86_64 x86_64 ESXi

At the same time I got another USB 3.0 HDD connected and working fine (174c:5136 ASMedia Technology Inc. ASM1053 SATA 6Gb/s bridge)

The WD disk works for a bit and then remounts as RO on the guest (Ubuntu server 16.04)

dmesg on the host gives me:

2016-12-06T12:26:37.740Z cpu1:65907)WARNING: usb_non_isoc_callback:1019: transfer error [20]: endpt = 0x82, nframes = 1, timeout = 5000, interval = 4, max_packet_size = 64, max_frame_size = 64: Failure


2016-12-06T12:26:42.687Z cpu0:65907)WARNING: usb_non_isoc_callback:1019: transfer error [20]: endpt = 0x82, nframes = 1, timeout = 5000, interval = 4, max_packet_size = 64, max_frame_size = 64: Failure


2016-12-06T12:26:47.632Z cpu0:65907)WARNING: usb_non_isoc_callback:1019: transfer error [20]: endpt = 0x82, nframes = 1, timeout = 5000, interval = 4, max_packet_size = 64, max_frame_size = 64: Failure


2016-12-06T12:26:52.578Z cpu0:65907)WARNING: usb_non_isoc_callback:1019: transfer error [20]: endpt = 0x82, nframes = 1, timeout = 5000, interval = 4, max_packet_size = 64, max_frame_size = 64: Failure


2016-12-06T12:26:57.523Z cpu1:65907)WARNING: usb_non_isoc_callback:1019: transfer error [20]: endpt = 0x82, nframes = 1, timeout = 5000, interval = 4, max_packet_size = 64, max_frame_size = 64: Failure


2016-12-06T12:27:02.469Z cpu0:65907)WARNING: usb_non_isoc_callback:1019: transfer error [20]: endpt = 0x82, nframes = 1, timeout = 5000, interval = 4, max_packet_size = 64, max_frame_size = 64: Failure


2016-12-06T12:27:07.414Z cpu1:65907)WARNING: usb_non_isoc_callback:1019: transfer error [20]: endpt = 0x82, nframes = 1, timeout = 5000, interval = 4, max_packet_size = 64, max_frame_size = 64: Failure


2016-12-06T12:27:12.358Z cpu1:65907)WARNING: usb_non_isoc_callback:1019: transfer error [20]: endpt = 0x82, nframes = 1, timeout = 5000, interval = 4, max_packet_size = 64, max_frame_size = 64: Failure


2016-12-06T12:27:17.303Z cpu0:65907)WARNING: usb_non_isoc_callback:1019: transfer error [20]: endpt = 0x82, nframes = 1, timeout = 5000, interval = 4, max_packet_size = 64, max_frame_size = 64: Failure


2016-12-06T12:27:22.248Z cpu1:65907)WARNING: usb_non_isoc_callback:1019: transfer error [20]: endpt = 0x82, nframes = 1, timeout = 5000, interval = 4, max_packet_size = 64, max_frame_size = 64: Failure


2016-12-06T12:27:27.193Z cpu0:65907)WARNING: usb_non_isoc_callback:1019: transfer error [20]: endpt = 0x82, nframes = 1, timeout = 5000, interval = 4, max_packet_size = 64, max_frame_size = 64: Failure

dmesg on the guest gives me:

[  99.527766] EXT4-fs (sde1): mounted filesystem with ordered data mode. Opts: (null)


[  123.468593] nfsd: last server has exited, flushing export cache


[  123.560971] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory


[  123.560997] NFSD: starting 90-second grace period (net ffffffff81ef4680)


[ 7218.809569] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[ 7224.313609] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[12341.802506] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[12341.853564] sd 34:0:0:0: [sde] tag#0 FAILED Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK


[12341.853571] sd 34:0:0:0: [sde] tag#0 CDB: Read(10) 28 00 14 b0 01 21 00 00 01 00


[12341.853574] blk_update_request: I/O error, dev sde, sector 2776631560


[12347.314556] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[12347.359728] sd 34:0:0:0: [sde] tag#0 FAILED Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK


[12347.359757] sd 34:0:0:0: [sde] tag#0 CDB: Read(10) 28 00 14 b0 01 22 00 00 01 00


[12347.359764] blk_update_request: I/O error, dev sde, sector 2776631568


[16045.316261] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[16045.363161] sd 34:0:0:0: [sde] tag#0 FAILED Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK


[16045.363170] sd 34:0:0:0: [sde] tag#0 CDB: Read(10) 28 00 12 a0 01 21 00 00 01 00


[16045.363173] blk_update_request: I/O error, dev sde, sector 2499807496


[16050.820375] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[16050.866836] sd 34:0:0:0: [sde] tag#0 FAILED Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK


[16050.866860] sd 34:0:0:0: [sde] tag#0 CDB: Read(10) 28 00 12 a0 01 22 00 00 01 00


[16050.866868] blk_update_request: I/O error, dev sde, sector 2499807504


[19823.990199] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[19824.036778] sd 34:0:0:0: [sde] tag#0 FAILED Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK


[19824.036787] sd 34:0:0:0: [sde] tag#0 CDB: Read(10) 28 00 32 c0 01 21 00 00 01 00


[19824.036790] blk_update_request: I/O error, dev sde, sector 6811552008


[19829.498360] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[19829.543041] sd 34:0:0:0: [sde] tag#0 FAILED Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK


[19829.543049] sd 34:0:0:0: [sde] tag#0 CDB: Read(10) 28 00 32 c0 01 22 00 00 01 00


[19829.543052] blk_update_request: I/O error, dev sde, sector 6811552016


[24522.979739] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[24528.487780] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[28229.805561] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[28235.325512] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[31912.173703] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[31917.685669] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[35448.001446] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[35453.513178] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[43226.520440] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[43228.096443] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[43229.684458] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[43231.264510] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[43232.844421] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[43234.420503] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[43234.465907] sd 34:0:0:0: [sde] tag#0 FAILED Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK


[43234.465935] sd 34:0:0:0: [sde] tag#0 CDB: Write(10) 2a 00 1d 18 87 f2 00 00 02 00


[43234.465950] blk_update_request: I/O error, dev sde, sector 3905175440


[43234.466067] Aborting journal on device sde1-8.


[43235.992595] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[63700.742992] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[63706.250942] usb 4-2: reset SuperSpeed USB device number 3 using xhci_hcd


[63707.212829] EXT4-fs error (device sde1): ext4_journal_check_start:56: Detected aborted journal


[63707.214711] EXT4-fs (sde1): Remounting filesystem read-only







[root@host:~] lsusb -v -d 1058:1230






Bus 002 Device 002: ID 1058:1230 Western Digital Technologies, Inc.


Device Descriptor:


  bLength                18


  bDescriptorType         1


  bcdUSB               3.00


  bDeviceClass            0 (Defined at Interface level)


  bDeviceSubClass         0


  bDeviceProtocol         0


  bMaxPacketSize0         9


  idVendor           0x1058 Western Digital Technologies, Inc.


  idProduct          0x1230


  bcdDevice           10.65


  iManufacturer           2 Western Digital


  iProduct                3 My Book 1230


  iSerial                 1 574343344543584B44384C50


  bNumConfigurations      1


  Configuration Descriptor:


    bLength                 9


    bDescriptorType         2


    wTotalLength           44


    bNumInterfaces          1


    bConfigurationValue     1


    iConfiguration          0


    bmAttributes         0xc0


      Self Powered


    MaxPower                2mA


    Interface Descriptor:


      bLength                 9


      bDescriptorType         4


      bInterfaceNumber        0


      bAlternateSetting       0


      bNumEndpoints           2


      bInterfaceClass         8 Mass Storage


      bInterfaceSubClass      6 SCSI


      bInterfaceProtocol     80 Bulk-Only


      iInterface              0


      Endpoint Descriptor:


        bLength                 7


        bDescriptorType         5


        bEndpointAddress     0x81  EP 1 IN


        bmAttributes            2


          Transfer Type            Bulk


          Synch Type               None


          Usage Type               Data


        wMaxPacketSize     0x0400  1x 1024 bytes


        bInterval               0


        bMaxBurst              15


      Endpoint Descriptor:


        bLength                 7


        bDescriptorType         5


        bEndpointAddress     0x02  EP 2 OUT


        bmAttributes            2


          Transfer Type            Bulk


          Synch Type               None


          Usage Type               Data


        wMaxPacketSize     0x0400  1x 1024 bytes


        bInterval               0


        bMaxBurst              15


Binary Object Store Descriptor:


  bLength                 5


  bDescriptorType        15


  wTotalLength           22


  bNumDeviceCaps          2


  USB 2.0 Extension Device Capability:


    bLength                 7


    bDescriptorType        16


    bDevCapabilityType      2


    bmAttributes   0x0000f41e


      Link Power Management (LPM) Supported


  SuperSpeed USB Device Capability:


    bLength                10


    bDescriptorType        16


    bDevCapabilityType      3


    bmAttributes         0x00


    wSpeedsSupported   0x000e


      Device can operate at Full Speed (12Mbps)


      Device can operate at High Speed (480Mbps)


      Device can operate at SuperSpeed (5Gbps)


    bFunctionalitySupport   1


      Lowest fully-functional device speed is Full Speed (12Mbps)


    bU1DevExitLat          10 micro seconds


    bU2DevExitLat        2047 micro seconds


Device Status:     0x0001


  Self Powered

Who have any pointers to what can be done to have this working again as it seems to be affecting quite a few of us with USB peripherals like HDD's card readers and printers...

I already tried to downgrade to the previous version of xhci vib's but turned out to be too many dependencies...

Many many thanks for any help on this topic!

0 Kudos
5 Replies
Lagus1
Contributor
Contributor
‎12-09-2016 10:51 AM

No one?

0 Kudos
Lagus1
Contributor
Contributor
‎01-12-2017 01:26 PM

I'm coming back to my own unanswered questions while googling for an answer... Smiley Wink

Still have the same error but now after a CPU change with a USB 2 device that was previously working.

Anyone any ideas?

0 Kudos
Lagus1
Contributor
Contributor
‎01-12-2017 02:01 PM

Some more logs from this error that plays out the same way as the firs one explained

Device that worked previously but not causing troubles is a USB 2 Harddrive:

Bus 001 Device 004: ID 059f:1014 LaCie, Ltd

I appreciate all help!

Thanks!

In dmesg on the ESXi host:

2017-01-12T21:21:11.287Z cpu0:65969)WARNING: usb_non_isoc_callback:1019: transfer error [22]: endpt = 0x81, nframes = 1, timeout = 5000, interval = 0, max_packet_size = 1024, max_frame_size = 16384: Broken pipe


2017-01-12T21:30:41.554Z cpu1:67872 opID=a36bb1cd)World: 12230: VC opID 00a18053 maps to vmkernel opID a36bb1cd


2017-01-12T21:30:41.554Z cpu1:67872 opID=a36bb1cd)FSS: 6214: Conflict between buffered and unbuffered open (file 'tulippa0-flat.vmdk'):flags 0x4008, requested flags 0x40001


--


2017-01-12T21:40:15.459Z cpu2:65959)DMA: 691: DMA Engine 'vmhba33' destroyed.


2017-01-12T21:40:22.832Z cpu5:287846)WARNING: udev_do_control:690: standard request (0x8) error, err 20: Broken pipe


2017-01-12T21:40:22.832Z cpu5:287846)WARNING: FreeBSDCharIoctl:1778: ioctl failed: dev = usb0104, cmd = 0xc0105500, err = -32: Failure


2017-01-12T21:40:48.250Z cpu5:65963)usbd_setup_device_desc:1987: getting device descriptor at addr 4 failed, USB_ERR_TIMEOUT






2017-01-12T21:40:55.362Z cpu4:65963)usbd_setup_device_desc:1987: getting device descriptor at addr 4 failed, USB_ERR_TIMEOUT






2017-01-12T21:41:01.952Z cpu4:65963)usbd_setup_device_desc:1987: getting device descriptor at addr 4 failed, USB_ERR_TIMEOUT






2017-01-12T21:41:09.043Z cpu5:65963)usbd_setup_device_desc:1987: getting device descriptor at addr 4 failed, USB_ERR_TIMEOUT






2017-01-12T21:41:09.043Z cpu5:65963)uhub_explore_handle_re_enumerate:543: Failed to reset device usb0104, error=USB_ERR_TIMEOUT






2017-01-12T21:41:09.658Z cpu4:287504)WARNING: udev_reset_device:2214: Failed to set config index to 0: Bad parameter


2017-01-12T21:41:09.658Z cpu4:287504)WARNING: FreeBSDCharIoctl:1778: ioctl failed: dev = usb0104, cmd = 0x5514, err = -22: Failure


2017-01-12T21:41:12.264Z cpu1:287504)WARNING: udev_submit_urb:1555: usbd_do_request_flag error USB_ERR_TIMEOUT: Failure


2017-01-12T21:41:14.633Z cpu7:287504)WARNING: udev_submit_urb:1555: usbd_do_request_flag error USB_ERR_TIMEOUT: Failure


2017-01-12T21:41:16.770Z cpu7:287504)WARNING: udev_submit_urb:1555: usbd_do_request_flag error USB_ERR_TIMEOUT: Failure


2017-01-12T21:41:17.184Z cpu2:67963 opID=ae0e124d)World: 12230: VC opID 00a182d6 maps to vmkernel opID ae0e124d


2017-01-12T21:41:17.184Z cpu2:67963 opID=ae0e124d)FSS: 6214: Conflict between buffered and unbuffered open (file 'tulippa0-flat.vmdk'):flags 0x4008, requested flags 0x40001


2017-01-12T21:41:28.521Z cpu5:65963)usbd_setup_device_desc:1987: getting device descriptor at addr 4 failed, USB_ERR_TIMEOUT






2017-01-12T21:41:35.612Z cpu4:65963)usbd_setup_device_desc:1987: getting device descriptor at addr 4 failed, USB_ERR_TIMEOUT






2017-01-12T21:41:42.200Z cpu2:65963)usbd_setup_device_desc:1987: getting device descriptor at addr 4 failed, USB_ERR_TIMEOUT






2017-01-12T21:41:49.298Z cpu5:65963)usbd_setup_device_desc:1987: getting device descriptor at addr 4 failed, USB_ERR_TIMEOUT






2017-01-12T21:41:49.298Z cpu5:65963)uhub_explore_handle_re_enumerate:543: Failed to reset device usb0104, error=USB_ERR_TIMEOUT






2017-01-12T21:41:54.316Z cpu6:287504)WARNING: udev_reset_device:2214: Failed to set config index to 255: Bad parameter


2017-01-12T21:41:54.316Z cpu6:287504)WARNING: FreeBSDCharIoctl:1778: ioctl failed: dev = usb0104, cmd = 0x5514, err = -22: Failure


2017-01-12T21:41:56.934Z cpu3:287504)WARNING: udev_submit_urb:1555: usbd_do_request_flag error USB_ERR_TIMEOUT: Failure


2017-01-12T21:42:00.034Z cpu6:287892)WARNING: usb_read:2654: Failed to get config descriptor 0 at bus 0, err 20: I/O error


2017-01-12T21:42:00.034Z cpu6:287892)WARNING: FreeBSDCharRead:1876: Failed to read 8 byte(s) from usb0104 [errno 19]: No such target on adapter


2017-01-12T21:42:02.122Z cpu4:287504)WARNING: udev_submit_urb:1555: usbd_do_request_flag error USB_ERR_TIMEOUT: Failure


2017-01-12T21:42:04.344Z cpu4:287504)WARNING: udev_submit_urb:1555: usbd_do_request_flag error USB_ERR_TIMEOUT: Failure

And then dmesg on the guest (Ubuntu 16.04 Virtual Server)

[   10.760719] systemd[1]: Mounted FUSE Control File System.


[   10.827155] systemd[1]: Started Set console keymap.


[   10.881814] systemd[1]: Started Journal Service.


[   11.808449] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro


[   11.899758] systemd-journald[520]: Received request to flush runtime journal from PID 1


[   12.605090] piix4_smbus 0000:00:07.3: SMBus Host Controller not enabled!


[   12.606715] vmw_vmci 0000:00:07.7: Found VMCI PCI device at 0x11080, irq 16


[   12.606775] vmw_vmci 0000:00:07.7: Using capabilities 0xc


[   12.607205] Guest personality initialized and is active


[   12.607242] VMCI host device registered (name=vmci, major=10, minor=55)


[   12.607243] Initialized host personality


[   12.636790] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4


[   12.867225] intel_rapl: no valid rapl domains found in package 0


[   14.125396] ppdev: user-space parallel port driver


[   14.203473] Adding 1046524k swap on /dev/sda5.  Priority:-1 extents:1 across:1046524k SSFS


[   15.470596] floppy0: no floppy controllers found


[   15.470701] work still pending


[   15.565562] zavl: module license 'CDDL' taints kernel.


[   15.565568] Disabling lock debugging due to kernel taint


[   15.586586] SPL: Loaded module v0.6.5.6-0ubuntu4


[   15.818963] ZFS: Loaded module v0.6.5.6-0ubuntu15, ZFS pool version 5000, ZFS filesystem version 5


[   16.850539] SPL: The /etc/hostid file is not found.


[   16.850545] SPL: using hostid 0x00000000


[   18.726994] audit: type=1400 audit(1484257299.143:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default" pid=1910 comm="apparmor_parser"


[   18.727005] audit: type=1400 audit(1484257299.143:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-cgns" pid=1910 comm="apparmor_parser"


[   18.727013] audit: type=1400 audit(1484257299.143:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-with-mounting" pid=1910 comm="apparmor_parser"


[   18.727019] audit: type=1400 audit(1484257299.143:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lxc-container-default-with-nesting" pid=1910 comm="apparmor_parser"


[   18.912506] audit: type=1400 audit(1484257299.327:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/sbin/dhclient" pid=1911 comm="apparmor_parser"


[   18.912519] audit: type=1400 audit(1484257299.327:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=1911 comm="apparmor_parser"


[   18.912525] audit: type=1400 audit(1484257299.327:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=1911 comm="apparmor_parser"


[   18.912531] audit: type=1400 audit(1484257299.327:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=1911 comm="apparmor_parser"


[   18.936280] audit: type=1400 audit(1484257299.351:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/lxc-start" pid=1912 comm="apparmor_parser"


[   18.971614] audit: type=1400 audit(1484257299.387:11): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/lxd/lxd-bridge-proxy" pid=1913 comm="apparmor_parser"


[   19.388380] cgroup: new mount options do not match the existing superblock, will be ignored


[   19.435417] vmxnet3 0000:03:00.0 ens160: intr type 3, mode 0, 2 vectors allocated


[   19.436238] vmxnet3 0000:03:00.0 ens160: NIC Link is Up 10000 Mbps


[   20.527212] NET: Registered protocol family 40


[   53.101277] random: nonblocking pool is initialized


[  274.532510] usb 3-2: new high-speed USB device number 5 using xhci_hcd


[  318.671001] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 35s! [kworker/0:4:503]


[  318.671059] Modules linked in: vmw_vsock_vmci_transport vsock zfs(PO) zunicode(PO) zcommon(PO) znvpair(PO) spl(O) zavl(PO) ppdev vmw_balloon coretemp joydev input_leds serio_raw shpchp vmw_vmci i2c_piix4 nfit 8250_fintek parport_pc parport mac_hid ib_iser rdma_cm iw_cm ib_cm ib_sa ib_mad ib_core ib_addr iscsi_tcp libiscsi_tcp libiscsi scsi_transport_iscsi autofs4 btrfs raid10 raid456 async_raid6_recov async_memcpy async_pq async_xor async_tx xor raid6_pq libcrc32c raid1 raid0 multipath linear hid_generic usbhid hid crct10dif_pclmul crc32_pclmul ghash_clmulni_intel aesni_intel aes_x86_64 lrw gf128mul glue_helper ablk_helper cryptd vmwgfx ttm psmouse drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops mptspi mptscsih drm mptbase ahci vmxnet3 libahci scsi_transport_spi pata_acpi fjes


[  318.671126] CPU: 0 PID: 503 Comm: kworker/0:4 Tainted: P           O    4.4.0-59-generic #80-Ubuntu


[  318.671128] Hardware name: VMware, Inc. VMware Virtual Platform/440BX Desktop Reference Platform, BIOS 6.00 04/05/2016


[  318.671139] Workqueue: usb_hub_wq hub_event


[  318.671141] task: ffff8800353bd400 ti: ffff88003762c000 task.ti: ffff88003762c000


[  318.671144] RIP: 0010:[<ffffffff81837f35>]  [<ffffffff81837f35>] _raw_spin_unlock_irqrestore+0x15/0x20


[  318.671151] RSP: 0018:ffff88003762f8f8  EFLAGS: 00000283


[  318.671153] RAX: 0000000000200e03 RBX: 0000000000000000 RCX: 0000000000000e11


[  318.671154] RDX: 0000000000000000 RSI: 0000000000000283 RDI: 0000000000000283


[  318.671156] RBP: ffff88003762f8f8 R08: 0000000000200e03 R09: 0000000000000001


[  318.671157] R10: 0000000000000001 R11: 0000000000000283 R12: 0000000000000000


[  318.671159] R13: ffff88003b566000 R14: ffff88003b566000 R15: ffff88003b5662b4


[  318.671161] FS:  0000000000000000(0000) GS:ffff88003c600000(0000) knlGS:0000000000000000


[  318.671163] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b


[  318.671165] CR2: 00007fecf2a05b24 CR3: 000000003974a000 CR4: 00000000001406f0


[  318.671243] Stack:


[  318.671247]  ffff88003762f988 ffffffff8166cc6d ffff880039053080 ffff88003b566268


[  318.671250]  0000000100020202 ffff880039b00004 ffff880000000000 ffff880000000004


[  318.671252]  0000000000000008 0000000000000004 ffff88003a95a7a0 ffff88003f807d00


[  318.671255] Call Trace:


[  318.671266]  [<ffffffff8166cc6d>] xhci_hub_control+0xcd/0x13e0


[  318.671272]  [<ffffffff8161dc26>] usb_hcd_submit_urb+0x3b6/0xb50


[  318.671276]  [<ffffffff81837f35>] ? _raw_spin_unlock_irqrestore+0x15/0x20


[  318.671280]  [<ffffffff8165ea23>] ? xhci_urb_enqueue+0x2a3/0x700


[  318.671286]  [<ffffffff810b2bfc>] ? __enqueue_entity+0x6c/0x70


[  318.671289]  [<ffffffff810b99d7>] ? put_prev_entity+0x97/0x7d0


[  318.671292]  [<ffffffff8161f543>] usb_submit_urb.part.6+0x2f3/0x560


[  318.671295]  [<ffffffff8161f812>] usb_submit_urb+0x62/0x70


[  318.671297]  [<ffffffff8161feee>] usb_start_wait_urb+0x6e/0x170


[  318.671300]  [<ffffffff816200cc>] usb_control_msg+0xdc/0x130


[  318.671303]  [<ffffffff81612c88>] set_port_feature+0x48/0x50


[  318.671306]  [<ffffffff81614b0c>] hub_port_reset+0xac/0x660


[  318.671309]  [<ffffffff81615501>] hub_port_init+0x441/0xbd0


[  318.671314]  [<ffffffff81566985>] ? pm_runtime_set_autosuspend_delay+0x45/0x60


[  318.671318]  [<ffffffff81618c90>] hub_port_connect+0x200/0x9c0


[  318.671322]  [<ffffffff81619b21>] hub_event+0x6d1/0xb10


[  318.671324]  [<ffffffff810b9975>] ? put_prev_entity+0x35/0x7d0


[  318.671329]  [<ffffffff8109a575>] process_one_work+0x165/0x480


[  318.671333]  [<ffffffff8109a8db>] worker_thread+0x4b/0x4c0


[  318.671336]  [<ffffffff8109a890>] ? process_one_work+0x480/0x480


[  318.671339]  [<ffffffff8109a890>] ? process_one_work+0x480/0x480


[  318.671342]  [<ffffffff810a0c08>] kthread+0xd8/0xf0


[  318.671345]  [<ffffffff810a0b30>] ? kthread_create_on_node+0x1e0/0x1e0


[  318.671349]  [<ffffffff8183888f>] ret_from_fork+0x3f/0x70


[  318.671351]  [<ffffffff810a0b30>] ? kthread_create_on_node+0x1e0/0x1e0


[  318.671353] Code: 1f 44 00 00 eb c6 31 c0 eb ca e8 97 91 84 ff 90 90 90 90 90 90 90 0f 1f 44 00 00 55 48 89 e5 c6 07 00 0f 1f 40 00 48 89 f7 57 9d <0f> 1f 44 00 00 5d c3 0f 1f 40 00 0f 1f 44 00 00 55 48 89 e5 c6 


[  319.085927] usb 3-2: device descriptor read/64, error -71


[  366.210233] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 35s! [kworker/0:4:503]


[  366.210290] Modules linked in: vmw_vsock_vmci_transport vsock zfs(PO) zunicode(PO) zcommon(PO) znvpair(PO) spl(O) zavl(PO) ppdev vmw_balloon coretemp joydev input_leds serio_raw shpchp vmw_vmci i2c_piix4 nfit 8250_fintek parport_pc parport mac_hid ib_iser rdma_cm iw_cm ib_cm ib_sa ib_mad ib_core ib_addr iscsi_tcp libiscsi_tcp libiscsi scsi_transport_iscsi autofs4 btrfs raid10 raid456 async_raid6_recov async_memcpy async_pq async_xor async_tx xor raid6_pq libcrc32c raid1 raid0 multipath linear hid_generic usbhid hid crct10dif_pclmul crc32_pclmul ghash_clmulni_intel aesni_intel aes_x86_64 lrw gf128mul glue_helper ablk_helper cryptd vmwgfx ttm psmouse drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops mptspi mptscsih drm mptbase ahci vmxnet3 libahci scsi_transport_spi pata_acpi fjes


[  366.210356] CPU: 0 PID: 503 Comm: kworker/0:4 Tainted: P           O L  4.4.0-59-generic #80-Ubuntu


[  366.210358] Hardware name: VMware, Inc. VMware Virtual Platform/440BX Desktop Reference Platform, BIOS 6.00 04/05/2016


[  366.210369] Workqueue: usb_hub_wq hub_event


[  366.210372] task: ffff8800353bd400 ti: ffff88003762c000 task.ti: ffff88003762c000


[  366.210374] RIP: 0010:[<ffffffff81837f35>]  [<ffffffff81837f35>] _raw_spin_unlock_irqrestore+0x15/0x20


[  366.210381] RSP: 0018:ffff88003762f8f8  EFLAGS: 00000283


[  366.210383] RAX: 0000000000200e03 RBX: 0000000000000000 RCX: 0000000000000e11


[  366.210384] RDX: 0000000000000000 RSI: 0000000000000283 RDI: 0000000000000283


[  366.210386] RBP: ffff88003762f8f8 R08: 0000000000200e03 R09: 0000000000000001


[  366.210387] R10: 0000000000000001 R11: 0000000000000283 R12: 0000000000000000


[  366.210389] R13: ffff88003b566000 R14: ffff88003b566000 R15: ffff88003b5662b4


[  366.210391] FS:  0000000000000000(0000) GS:ffff88003c600000(0000) knlGS:0000000000000000


[  366.210393] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b


[  366.210394] CR2: 00007f7c8d1aab24 CR3: 000000003974a000 CR4: 00000000001406f0


[  366.210465] Stack:


[  366.210468]  ffff88003762f988 ffffffff8166cc6d ffff880039053080 ffff88003b566268


[  366.210471]  0000000100020202 ffff880039b00004 ffff880000000000 ffff880000000004


[  366.210473]  0000000000000008 0000000000000004 ffff88003a95a7a0 ffff88003f807d00


[  366.210476] Call Trace:


[  366.210485]  [<ffffffff8166cc6d>] xhci_hub_control+0xcd/0x13e0


[  366.210490]  [<ffffffff8161dc26>] usb_hcd_submit_urb+0x3b6/0xb50


[  366.210493]  [<ffffffff81837f35>] ? _raw_spin_unlock_irqrestore+0x15/0x20


[  366.210496]  [<ffffffff8165ea23>] ? xhci_urb_enqueue+0x2a3/0x700


[  366.210503]  [<ffffffff810b2bfc>] ? __enqueue_entity+0x6c/0x70


[  366.210505]  [<ffffffff810b99d7>] ? put_prev_entity+0x97/0x7d0


[  366.210508]  [<ffffffff8161f543>] usb_submit_urb.part.6+0x2f3/0x560


[  366.210511]  [<ffffffff8161f812>] usb_submit_urb+0x62/0x70


[  366.210513]  [<ffffffff8161feee>] usb_start_wait_urb+0x6e/0x170


[  366.210516]  [<ffffffff816200cc>] usb_control_msg+0xdc/0x130


[  366.210519]  [<ffffffff81612c88>] set_port_feature+0x48/0x50


[  366.210522]  [<ffffffff81614b0c>] hub_port_reset+0xac/0x660


[  366.210525]  [<ffffffff816154ec>] ? hub_port_init+0x42c/0xbd0


[  366.210528]  [<ffffffff81615501>] hub_port_init+0x441/0xbd0


[  366.210533]  [<ffffffff81566985>] ? pm_runtime_set_autosuspend_delay+0x45/0x60


[  366.210536]  [<ffffffff81618c90>] hub_port_connect+0x200/0x9c0


[  366.210540]  [<ffffffff81619b21>] hub_event+0x6d1/0xb10


[  366.210542]  [<ffffffff810b9975>] ? put_prev_entity+0x35/0x7d0


[  366.210547]  [<ffffffff8109a575>] process_one_work+0x165/0x480


[  366.210550]  [<ffffffff8109a8db>] worker_thread+0x4b/0x4c0


[  366.210553]  [<ffffffff8109a890>] ? process_one_work+0x480/0x480


[  366.210556]  [<ffffffff8109a890>] ? process_one_work+0x480/0x480


[  366.210558]  [<ffffffff810a0c08>] kthread+0xd8/0xf0


[  366.210561]  [<ffffffff810a0b30>] ? kthread_create_on_node+0x1e0/0x1e0


[  366.210565]  [<ffffffff8183888f>] ret_from_fork+0x3f/0x70


[  366.210567]  [<ffffffff810a0b30>] ? kthread_create_on_node+0x1e0/0x1e0


[  366.210569] Code: 1f 44 00 00 eb c6 31 c0 eb ca e8 97 91 84 ff 90 90 90 90 90 90 90 0f 1f 44 00 00 55 48 89 e5 c6 07 00 0f 1f 40 00 48 89 f7 57 9d <0f> 1f 44 00 00 5d c3 0f 1f 40 00 0f 1f 44 00 00 55 48 89 e5 c6 


[  366.545698] usb 3-2: device descriptor read/64, error -71


[  405.167570] usb 3-2: new high-speed USB device number 6 using xhci_hcd


[  449.248942] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 37s! [kworker/0:4:503]


[  449.249000] Modules linked in: vmw_vsock_vmci_transport vsock zfs(PO) zunicode(PO) zcommon(PO) znvpair(PO) spl(O) zavl(PO) ppdev vmw_balloon coretemp joydev input_leds serio_raw shpchp vmw_vmci i2c_piix4 nfit 8250_fintek parport_pc parport mac_hid ib_iser rdma_cm iw_cm ib_cm ib_sa ib_mad ib_core ib_addr iscsi_tcp libiscsi_tcp libiscsi scsi_transport_iscsi autofs4 btrfs raid10 raid456 async_raid6_recov async_memcpy async_pq async_xor async_tx xor raid6_pq libcrc32c raid1 raid0 multipath linear hid_generic usbhid hid crct10dif_pclmul crc32_pclmul ghash_clmulni_intel aesni_intel aes_x86_64 lrw gf128mul glue_helper ablk_helper cryptd vmwgfx ttm psmouse drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops mptspi mptscsih drm mptbase ahci vmxnet3 libahci scsi_transport_spi pata_acpi fjes


[  449.249065] CPU: 0 PID: 503 Comm: kworker/0:4 Tainted: P           O L  4.4.0-59-generic #80-Ubuntu


[  449.249067] Hardware name: VMware, Inc. VMware Virtual Platform/440BX Desktop Reference Platform, BIOS 6.00 04/05/2016


[  449.249078] Workqueue: usb_hub_wq hub_event


[  449.249080] task: ffff8800353bd400 ti: ffff88003762c000 task.ti: ffff88003762c000


[  449.249082] RIP: 0010:[<ffffffff81837f35>]  [<ffffffff81837f35>] _raw_spin_unlock_irqrestore+0x15/0x20


[  449.249089] RSP: 0018:ffff88003762f8f8  EFLAGS: 00000283


[  449.249224] RAX: 0000000000200e03 RBX: 0000000000000000 RCX: 0000000000000e11


[  449.249228] RDX: 0000000000000000 RSI: 0000000000000283 RDI: 0000000000000283


[  449.249229] RBP: ffff88003762f8f8 R08: 0000000000200e03 R09: 0000000000000001


[  449.249231] R10: 0000000000000001 R11: 0000000000000283 R12: 0000000000000000


[  449.249232] R13: ffff88003b566000 R14: ffff88003b566000 R15: ffff88003b5662b4


[  449.249235] FS:  0000000000000000(0000) GS:ffff88003c600000(0000) knlGS:0000000000000000


[  449.249236] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b


[  449.249238] CR2: 00007f7fea2dbb24 CR3: 000000003974a000 CR4: 00000000001406f0


[  449.249327] Stack:


[  449.249329]  ffff88003762f988 ffffffff8166cc6d ffff88003762fa68 ffff88003b566268


[  449.249332]  0000000100023220 0000000000000004 0000000000000000 ffff880000000004


[  449.249334]  0000000000000008 0000000000000004 ffff88003a95a7a0 0000000000002303


[  449.249337] Call Trace:


[  449.249346]  [<ffffffff8166cc6d>] xhci_hub_control+0xcd/0x13e0


[  449.249352]  [<ffffffff8161dc26>] usb_hcd_submit_urb+0x3b6/0xb50


[  449.249356]  [<ffffffff81837f35>] ? _raw_spin_unlock_irqrestore+0x15/0x20


[  449.249361]  [<ffffffff810b99d7>] ? put_prev_entity+0x97/0x7d0


[  449.249363]  [<ffffffff810ba9bc>] ? set_next_entity+0x9c/0xb0


[  449.249366]  [<ffffffff8161f543>] usb_submit_urb.part.6+0x2f3/0x560


[  449.249368]  [<ffffffff8161f812>] usb_submit_urb+0x62/0x70


[  449.249371]  [<ffffffff8161feee>] usb_start_wait_urb+0x6e/0x170


[  449.249373]  [<ffffffff816200cc>] usb_control_msg+0xdc/0x130


[  449.249377]  [<ffffffff81612c88>] set_port_feature+0x48/0x50


[  449.249380]  [<ffffffff81614b0c>] hub_port_reset+0xac/0x660


[  449.249383]  [<ffffffff816154ec>] ? hub_port_init+0x42c/0xbd0


[  449.249386]  [<ffffffff81615501>] hub_port_init+0x441/0xbd0


[  449.249390]  [<ffffffff81566985>] ? pm_runtime_set_autosuspend_delay+0x45/0x60


[  449.249394]  [<ffffffff81618c90>] hub_port_connect+0x200/0x9c0


[  449.249397]  [<ffffffff81619b21>] hub_event+0x6d1/0xb10


[  449.249400]  [<ffffffff810b9975>] ? put_prev_entity+0x35/0x7d0


[  449.249405]  [<ffffffff8109a575>] process_one_work+0x165/0x480


[  449.249408]  [<ffffffff8109a8db>] worker_thread+0x4b/0x4c0


[  449.249411]  [<ffffffff8109a890>] ? process_one_work+0x480/0x480


[  449.249414]  [<ffffffff8109a890>] ? process_one_work+0x480/0x480


[  449.249416]  [<ffffffff810a0c08>] kthread+0xd8/0xf0


[  449.249419]  [<ffffffff810a0b30>] ? kthread_create_on_node+0x1e0/0x1e0


[  449.249423]  [<ffffffff8183888f>] ret_from_fork+0x3f/0x70


[  449.249426]  [<ffffffff810a0b30>] ? kthread_create_on_node+0x1e0/0x1e0


[  449.249427] Code: 1f 44 00 00 eb c6 31 c0 eb ca e8 97 91 84 ff 90 90 90 90 90 90 90 0f 1f 44 00 00 55 48 89 e5 c6 07 00 0f 1f 40 00 48 89 f7 57 9d <0f> 1f 44 00 00 5d c3 0f 1f 40 00 0f 1f 44 00 00 55 48 89 e5 c6 


[  449.598958] usb 3-2: device descriptor read/64, error -71


[  493.626834] usb 3-2: device descriptor read/64, error -71


[  531.246670] usb 3-2: new high-speed USB device number 7 using xhci_hcd


[  537.582034] usb 3-2: device descriptor read/8, error -71


[  544.010926] usb 3-2: device descriptor read/8, error -71


[  581.647018] usb 3-2: new high-speed USB device number 8 using xhci_hcd


[  587.975316] usb 3-2: device descriptor read/8, error -71


[  594.409432] usb 3-2: device descriptor read/8, error -71


[  594.511187] usb usb3-port2: unable to enumerate USB device
0 Kudos
MarcHuppert
Enthusiast
Enthusiast
‎02-15-2017 10:49 AM

You need to disable the new vmkusb module and load the older legacy USB drivers with this command:

esxcli system module set -m=vmkusb -e=FALSE



You must reboot your ESXi host to apply it. It works for me

VCDX #181, VSP, VTSP, VCA, VCP-DCV(2+3+4+5+6+6.5+6.7+2019), VCP-DT, VCP-NV, VCAP(DCA4+5+DCD4+5), VCIX-NV, VCIX-DCV, VCI, vExpert, vEpxert NSX, vExpert VSAN and VCDX
0 Kudos
geogherkins
Enthusiast
Enthusiast
‎03-07-2017 06:33 PM

New Dell-customized ESXi 6.5 install on PowerEdge T320.

Guest Win2008r2 VM needs USB connected device, but no go. Quite a head-scratcher, why I can't connect a device. A Google search landed here.

Seemed like a long shot, but tried the "esxcli system module set -m=vmkusb -e=FALSE" and the host restart recommended here and KB https://kb.vmware.com/selfservice/microsites/search.do?language=en_US&cmd=displayKC&externalId=21476....

USB still not working!

A "lsusb" command comes up empty. What am I doing wrong?

0 Kudos