[virt-tools-list] USB redirection bug with remote viewer

Mon Jul 1 13:42:26 UTC 2019

Getting logs from windows is a bitch!

Anyway, here is the part of the log when I click on USB redirection and
choose the Logitech camera.

On Mon, Jul 1, 2019 at 4:20 PM Victor Toso <victortoso at redhat.com> wrote:

> Hi,
>
> On Mon, Jul 01, 2019 at 04:08:17PM +0300, Hetz Ben Hamo wrote:
> > On Mon, Jul 1, 2019 at 1:46 PM Victor Toso <victortoso at redhat.com>
> wrote:
> >
> > > Hi,
> > >
> > > On Sun, Jun 30, 2019 at 12:42:05PM +0300, Hetz Ben Hamo wrote:
> > > > Hi,
> > > >
> > > > I'm trying remote viewer 8.0 on Windows 10 (1903 and
> earlier).(desktop
> > > > client is Windows, VM is Windows)
> > > >
> > > > Trying to redirect a USB device gives error, few devices gives
> different
> > > > errors:
> > > >
> > > > * Trying to connect Logitech HD 920 gives error -5
> > > > * Trying to connect Sandisk Ultra disk on key gives error -12
> > >
> > > Without logs it is a bit hard to fully understand. If this errors
> > > are coming from libusb, -5 would be 'not found' and -12 'not
> > > supported'.
> > >
> > > For redirect USB from windows client you need UsbDK installed,
> > > which version do you have?
> > >
> >
> > If I'm not mistaken, the Windows remote viewer already includes USBDK.
>
> Not sure what is your installer but remote-viewer is one thing
> and USBDk is another. remote-viewer uses USBDk but can be run if
> USBDk is not installed. It should have been installed indeed
> otherwise you wouldn't be able to select any USB device.
>
> > Anyway, I tried to install the latest (1.0.21) - still same problem.
> Trying
> > to select other devices on the list, btw, gives other errors.
> >
> > Regarding logs - where is the Windows client store those logs? Looking
> > at C:\Program Files\VirtViewer v8.0-256 - I don't see any log files.
>
>
> Sorry, I should have said that earlier.  You should run from
> console/terminal with --debug and --spice-debug
>
> Cheers,
> Victor
>
> > > > This only happens on Windows. I tried the same thing with Fedora 30
> on my
> > > > laptop and it works well.
> > > >
> > > > Should I open a bug on Bugzilla?
> > >
> > > Cheers,
> > > Victor
> > >
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://listman.redhat.com/archives/virt-tools-list/attachments/20190701/6aa7aa00/attachment.htm>
-------------- next part --------------
(remote-viewer.exe:11984): GSpice-DEBUG: 16:34:39.700: ../../src/usbutil.c:238 loading C:\Users\Public\Documents\hwdata\usb.ids success: no
(remote-viewer.exe:11984): GSpice-DEBUG: 16:34:39.712: ../../src/usbutil.c:238 loading C:\Program Files\VirtViewer v8.0-256\share\hwdata\usb.ids success: yes
(remote-viewer.exe:11984): GSpice-DEBUG: 16:34:39.725: ../../src/usb-device-manager.c:1964 Auto-connect disabled, no hider setup needed
(remote-viewer.exe:11984): GSpice-DEBUG: 16:34:58.486: ../../src/usb-device-manager.c:1391 connecting device 00000000045DA800
[timestamp] [threadID] facility level [function call] <message>
--------------------------------------------------------------------------------
[54.360604] [00001960] libusb: debug [libusb_get_device_list]
[54.366618] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [60]
[54.366618] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [61]
[54.366618] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [62]
[54.366618] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [63]
[54.366618] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [64]
[54.367610] [00001960] libusb: debug [get_api_type] driver(s): usbccgp
[54.367610] [00001960] libusb: debug [get_api_type] matched driver name against Composite API
[54.367610] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [4E]
[54.367610] [00001960] libusb: debug [get_api_type] driver(s): usbccgp
[54.367610] [00001960] libusb: debug [get_api_type] matched driver name against Composite API
[54.367610] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [4F]
[54.367610] [00001960] libusb: debug [get_api_type] driver(s): usbccgp
[54.367610] [00001960] libusb: debug [get_api_type] matched driver name against Composite API
[54.367610] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [50]
[54.368619] [00001960] libusb: debug [get_api_type] driver(s): HidUsb
[54.368619] [00001960] libusb: debug [get_api_type] matched driver name against HID API
[54.368619] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [51]
[54.369613] [00001960] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_10DE&DEV_1AD8&SUBSYS_12B010DE&REV_A1\4&1C3D25BB&0&0219' bus number 1
[54.375618] [00001960] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_1022&DEV_145F&SUBSYS_7B791462&REV_00\4&C93BEE2&0&0339' bus number 2
[54.377620] [00001960] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_1022&DEV_43D0&SUBSYS_11421B21&REV_01\4&288C77C7&0&000B' bus number 3
[54.378621] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [60]
[54.378621] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.379630] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 31 bytes)
[54.379630] [00001960] libusb: debug [init_device] (bus: 2, addr: 1, depth: 1, port: 5): 'USB\VID_0451&PID_8041\6&1325015&0&5'
[54.380622] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [50]
[54.381623] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.381623] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 3452 bytes)
[54.382624] [00001960] libusb: debug [init_device] (bus: 2, addr: 5, depth: 2, port: 1): 'USB\VID_046D&PID_082D\6F6F5D0F'
[54.383625] [00001960] libusb: debug [init_device] (bus: 2, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB30\5&2F2F304D&0&0'
[54.383625] [00001960] libusb: debug [init_device] (bus: 3, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB30\5&1171EDA9&0&0'
[54.384626] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [4F]
[54.389639] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.390632] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 84 bytes)
[54.390632] [00001960] libusb: debug [init_device] (bus: 2, addr: 6, depth: 2, port: 4): 'USB\VID_046D&PID_C52B\7&EF51FF9&0&4'
[54.391633] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [51]
[54.392633] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.392633] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 34 bytes)
[54.393635] [00001960] libusb: debug [init_device] (bus: 2, addr: 3, depth: 1, port: 3): 'USB\VID_413C&PID_2003\6&1325015&0&3'
[54.394635] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [61]
[54.394635] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.395637] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 41 bytes)
[54.395637] [00001960] libusb: debug [init_device] (bus: 2, addr: 2, depth: 1, port: 1): 'USB\VID_0451&PID_8043\MSFT201D0C0871E678'
[54.396637] [00001960] libusb: debug [init_device] (bus: 1, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB30\5&7979EB2&0&0'
[54.397638] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [4E]
[54.402651] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.403644] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 1191 bytes)
[54.404644] [00001960] libusb: debug [init_device] (bus: 2, addr: 4, depth: 1, port: 4): 'USB\VID_08BB&PID_2902\6&1325015&0&4'
[54.404644] [00001960] libusb: debug [discovered_devs_append] need to increase capacity
[54.405646] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.405646] [00001960] libusb: debug [set_composite_interface] interface[1] = \\.\HID#VID_046D&PID_C52B&MI_01&COL01#9&AC3FF13&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030}
[54.406647] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.407647] [00001960] libusb: debug [set_composite_interface] interface[1] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_01&COL03\9&AC3FF13&0&0002
[54.407647] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.408648] [00001960] libusb: debug [set_composite_interface] interface[2] = \\.\HID#VID_046D&PID_C52B&MI_02&COL01#9&2E9B3CD5&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030}
[54.408648] [00001960] libusb: debug [winusb_get_device_list] setting HID interface for [51]:
[54.409649] [00001960] libusb: debug [set_hid_interface] interface[0] = \\.\HID#VID_413C&PID_2003#7&3B8A5012&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030}#KBD
[54.410650] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.415655] [00001960] libusb: debug [set_composite_interface] interface[2] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_02&COL02\9&2E9B3CD5&0&0001
[54.416656] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.417656] [00001960] libusb: debug [set_composite_interface] interface[2] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_02&COL03\9&2E9B3CD5&0&0002
[54.417656] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.418657] [00001960] libusb: debug [set_composite_interface] interface[1] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_01&COL04\9&AC3FF13&0&0003
[54.418657] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4E]:
[54.419658] [00001960] libusb: debug [set_composite_interface] interface[3] = \\.\HID#VID_08BB&PID_2902&MI_03#8&25D25CD2&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030}
[54.420659] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.420659] [00001960] libusb: debug [set_composite_interface] interface[0] = \\.\HID#VID_046D&PID_C52B&MI_00#9&22878B58&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030}#KBD
[54.421660] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.422661] [00001960] libusb: debug [set_composite_interface] interface[1] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_01&COL02\9&AC3FF13&0&0001
[54.423662] [00001960] libusb: debug [libusb_unref_device] destroy device 2.1
[54.427674] [00001960] libusb: debug [libusb_unref_device] destroy device 3.0
[54.428667] [00001960] libusb: debug [libusb_unref_device] destroy device 2.6
[54.429668] [00001960] libusb: debug [libusb_unref_device] destroy device 2.3
[54.429668] [00001960] libusb: debug [libusb_unref_device] destroy device 1.0
[54.430669] [00001960] libusb: debug [libusb_unref_device] destroy device 2.4
(remote-viewer.exe:11984): GSpice-DEBUG: 16:34:58.557: ../../src/channel-usbredir.c:419 usbredir-9:3: connecting device 046d:082d (00000000045DA800) to channel 0000000002E23580
[54.431669] [000019d4] libusb: debug [libusb_open] open 2.5
[54.432670] [000019d4] libusb: debug [libusb_open] open 2.5 returns -5
[54.433671] [000019d4] libusb: debug [libusb_unref_device] destroy device 2.5
[54.433671] [000019d4] libusb: debug [libusb_unref_device] destroy device 2.2
[54.434672] [000019d4] libusb: debug [libusb_unref_device] destroy device 2.0
[54.436683] [00001960] libusb: debug [libusb_get_device_list]
[54.445690] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [60]
[54.445690] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [61]
[54.446691] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [62]
[54.447684] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [63]
[54.447684] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [64]
[54.448685] [00001960] libusb: debug [get_api_type] driver(s): usbccgp
[54.448685] [00001960] libusb: debug [get_api_type] matched driver name against Composite API
[54.449686] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [4E]
[54.453690] [00001960] libusb: debug [get_api_type] driver(s): usbccgp
[54.454690] [00001960] libusb: debug [get_api_type] matched driver name against Composite API
[54.454690] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [4F]
[54.455692] [00001960] libusb: debug [get_api_type] driver(s): usbccgp
[54.456692] [00001960] libusb: debug [get_api_type] matched driver name against Composite API
[54.456692] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [50]
[54.457693] [00001960] libusb: debug [get_api_type] driver(s): HidUsb
[54.458694] [00001960] libusb: debug [get_api_type] matched driver name against HID API
[54.458694] [00001960] libusb: debug [winusb_get_device_list] allocating new device for session [51]
[54.459695] [00001960] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_10DE&DEV_1AD8&SUBSYS_12B010DE&REV_A1\4&1C3D25BB&0&0219' bus number 1
[54.459695] [00001960] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_1022&DEV_145F&SUBSYS_7B791462&REV_00\4&C93BEE2&0&0339' bus number 2
[54.460696] [00001960] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_1022&DEV_43D0&SUBSYS_11421B21&REV_01\4&288C77C7&0&000B' bus number 3
[54.462698] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [60]
[54.466710] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.467703] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 31 bytes)
[54.468703] [00001960] libusb: debug [init_device] (bus: 2, addr: 1, depth: 1, port: 5): 'USB\VID_0451&PID_8041\6&1325015&0&5'
[54.469704] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [50]
[54.469704] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.470705] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 3452 bytes)
[54.470705] [00001960] libusb: debug [init_device] (bus: 2, addr: 5, depth: 2, port: 1): 'USB\VID_046D&PID_082D\6F6F5D0F'
[54.471706] [00001960] libusb: debug [init_device] (bus: 2, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB30\5&2F2F304D&0&0'
[54.472707] [00001960] libusb: debug [init_device] (bus: 3, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB30\5&1171EDA9&0&0'
[54.472707] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [4F]
[54.473708] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.473708] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 84 bytes)
[54.474709] [00001960] libusb: debug [init_device] (bus: 2, addr: 6, depth: 2, port: 4): 'USB\VID_046D&PID_C52B\7&EF51FF9&0&4'
[54.475710] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [51]
[54.480723] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.482716] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 34 bytes)
[54.482716] [00001960] libusb: debug [init_device] (bus: 2, addr: 3, depth: 1, port: 3): 'USB\VID_413C&PID_2003\6&1325015&0&3'
[54.483717] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [61]
[54.484718] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.484718] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 41 bytes)
[54.485719] [00001960] libusb: debug [init_device] (bus: 2, addr: 2, depth: 1, port: 1): 'USB\VID_0451&PID_8043\MSFT201D0C0871E678'
[54.486720] [00001960] libusb: debug [init_device] (bus: 1, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB30\5&7979EB2&0&0'
[54.486720] [00001960] libusb: debug [winusb_get_device_list] found existing device for session [4E]
[54.487721] [00001960] libusb: debug [init_device] found 1 configurations (active conf: 1)
[54.487721] [00001960] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 1191 bytes)
[54.488722] [00001960] libusb: debug [init_device] (bus: 2, addr: 4, depth: 1, port: 4): 'USB\VID_08BB&PID_2902\6&1325015&0&4'
[54.493727] [00001960] libusb: debug [discovered_devs_append] need to increase capacity
[54.494728] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.494728] [00001960] libusb: debug [set_composite_interface] interface[1] = \\.\HID#VID_046D&PID_C52B&MI_01&COL01#9&AC3FF13&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030}
[54.495728] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.496729] [00001960] libusb: debug [set_composite_interface] interface[1] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_01&COL03\9&AC3FF13&0&0002
[54.496729] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.497730] [00001960] libusb: debug [set_composite_interface] interface[2] = \\.\HID#VID_046D&PID_C52B&MI_02&COL01#9&2E9B3CD5&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030}
[54.497730] [00001960] libusb: debug [winusb_get_device_list] setting HID interface for [51]:
[54.498731] [00001960] libusb: debug [set_hid_interface] interface[0] = \\.\HID#VID_413C&PID_2003#7&3B8A5012&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030}#KBD
[54.499732] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.499732] [00001960] libusb: debug [set_composite_interface] interface[2] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_02&COL02\9&2E9B3CD5&0&0001
[54.500733] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.500733] [00001960] libusb: debug [set_composite_interface] interface[2] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_02&COL03\9&2E9B3CD5&0&0002
[54.506747] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.506747] [00001960] libusb: debug [set_composite_interface] interface[1] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_01&COL04\9&AC3FF13&0&0003
[54.507740] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4E]:
[54.508740] [00001960] libusb: debug [set_composite_interface] interface[3] = \\.\HID#VID_08BB&PID_2902&MI_03#8&25D25CD2&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030}
[54.508740] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.509741] [00001960] libusb: debug [set_composite_interface] interface[0] = \\.\HID#VID_046D&PID_C52B&MI_00#9&22878B58&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030}#KBD
[54.509741] [00001960] libusb: debug [winusb_get_device_list] setting composite interface for [4F]:
[54.510742] [00001960] libusb: debug [set_composite_interface] interface[1] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_01&COL02\9&AC3FF13&0&0001
[54.511743] [00001960] libusb: debug [libusb_get_device_descriptor]
[54.511743] [00001960] libusb: debug [libusb_get_device_descriptor]
[54.512744] [00001960] libusb: debug [libusb_get_device_descriptor]
[54.513745] [00001960] libusb: debug [libusb_get_device_descriptor]
[54.513745] [00001960] libusb: debug [libusb_get_device_descriptor]
[54.514745] [00001960] libusb: debug [libusb_get_device_descriptor]
[54.519751] [00001960] libusb: debug [libusb_get_device_descriptor]
[54.520760] [00001960] libusb: debug [libusb_get_device_descriptor]
[54.521752] [00001960] libusb: debug [libusb_get_device_descriptor]
[54.521752] [00001960] libusb: debug [libusb_unref_device] destroy device 2.1
[54.522753] [00001960] libusb: debug [libusb_unref_device] destroy device 2.5
[54.523754] [00001960] libusb: debug [libusb_unref_device] destroy device 3.0
[54.523754] [00001960] libusb: debug [libusb_unref_device] destroy device 2.6
[54.524755] [00001960] libusb: debug [libusb_unref_device] destroy device 2.3
[54.525756] [00001960] libusb: debug [libusb_unref_device] destroy device 2.2
[54.525756] [00001960] libusb: debug [libusb_unref_device] destroy device 1.0
[54.526760] [00001960] libusb: debug [libusb_unref_device] destroy device 2.4
[54.526760] [00001960] libusb: debug [libusb_unref_device] destroy device 2.0

(remote-viewer.exe:11984): Gtk-WARNING **: 16:34:58.667: Could not load a pixbuf from /org/gtk/libgtk/theme/Adwaita/assets/check-symbolic.svg.
This may indicate that pixbuf loaders or the mime database could not be found.