Unable to connect to the HDX Spectrometer via USB from Linux (Windows works)

[stepandr47]

spectrometer and system information

• model: HDX Spectrometer
• operating system: Ubuntu
• python version: python3.7
• python-seabreeze version: current
• installed-via: pip3 install seabreeze

current problem

I am unable to connect to the HDX spectrometer. When I run list_devices() it displays:

This is the correct serial number for my device. I then have tried to connect to the device with from_serial_number(HDX01168) or from_first_available(). Both of these will give the below output on the first attempt:

Looking through the tickets I noticed that HDX support has been added, so I am unsure what I am doing incorrectly here.

steps to reproduce

Run below code with the HDX connected:

1. import seabreeze
2. seabreeze.use('pyseabreeze')
3. from seabreeze.spectrometer import list_devices, Spectrometer
4. list_devices()
5. spectrometer = Spectrometer.from_first_available()

It seems like other users have gotten functionality working for an HDX spectrometer so I was wondering what I am doing incorrectly in the above implmentation.

[ap--]

Hi @stepandr47

Did you install the udev rules? If not please run seabreeze_os_setup.
Also try running the code without calling list_devices() first.

If that didn't solve it, please verify that the spectrometer is working by running it on a different machine with the original spectrasuite or oceanview software.

Cheers,
Andreas 😃

[stepandr47]

Hi Andreas,

I have tried running it without list_devices() and I get the same result.

Below is the rule created by seabreeze_os_setup for my sensor:

I have verified functionality using the OceanView software with no issue. It is able to read signals successfully and varies based on the amount of light coming in as expected. Please let me know if there is any other information that would be useful for you.

Thanks for the quick reply.

[ap--]

Which version of pyusb are your running?

import usb; print(usb.__version__)

[stepandr47]

Based on the image below it is version number 1.2.1

[ap--]

So from your other issue, it seems that you have a flame-t running reliably?
Is your ubuntu machine a raspberry pi? and if so does the flame-t work reliably with the pi?
If the Flame-T works well on the same machine:

You could try testing a few different versions of pyusb: 1.1.0, 1.2.0, 1.2.1

If that doesn't work we'll have to figure out what the important difference is between the setup in https://github.com/ap--/python-seabreeze/pull/98 and yours.
So if you have access to a windows machine I would try running the hdx together with pyseabreeze there.

I can't really provide any hands-on help with debugging because I don't have an HDX...

[stepandr47]

Yeah that is correct. I am able to do everything fine with the Flame-T. I have a test setup on a Ubuntu VM and a raspberry pi. The Flame-T works with both setups, but I can't get the HDX to work on either. I don't have the HDX with me today, so I will get back to you tomorrow on testing different versions of pyusb.

[ap--]

Okay great. That's valuable information 👍
So we can at least rule out some strange installation issues.

The pyusb versions are a longshot, but it's a super quick test.

Another longshot would be:
(packet captures for the OceanFX seem to indicate that the oceanview software uses protocol version 0x0000 to trigger the default OBP protocol of the spectrometer, and since the OceanHDX is also a OBP protocol spectrometer, maybe this will have an effect.)

# run this before any other code
from seabreeze.pyseabreeze.protocol import OBPProtocol
OBPProtocol.OBP.HEADER_PROTOCOL_VERSION = 0x0000

[stepandr47]

I tried the different versions of pyusb on Ubuntu. I tried each of them with and without the OBPProtocol addition stated in your last comment. It was unable to connect in all case.

I just now tried connecting to the device on Windows and it worked without any issues. I was also able to get data from it.

Any ideas what could be causing the issue on Ubuntu? The only difference I can think of currently is the driver that gets installed when you connect it to the OceanView software.

[ap--]

Okay. This is great. At least we know now that it's some difference between the OSes that's unrelated to the pyusb version.

Hmmm 🤔

• So it could be that it's a difference between libusb handling and winusb.
• It could also be that the pyseabreeze code for opening the spectrometer doesn't work with the HDX on linux.

A simple thing I would try is to setup a virtualenv with seabreeze as root on the linux machine and run your test code as root. That rules out that it's some sort of permission issue.

Also try running your script via:

LIBUSB_DEBUG=4 PYUSB_DEBUG=debug python your_code.py

and maybe compare the debug output between linux and windows.

I'm pretty sure we'll be able to figure out what's causing this 👍

[stepandr47]

Running the script as root on Ubuntu got the same timeout error. I haven't looked through it yet but below are the outputs from linux. LIBUSB_DEBUG and PYUSB_DEBUG are not recognized on windows. During a quick search I was unable to find how to resolve this issue. One other thing worth mentioning is on windows I was only able to get it working when in a conda environment. I didn't realize my terminal was in one in the morning when I tested, but when I just tested now it won't work without being in the conda environment. Do to that I tried running the ubuntu setup in a conda environment, but it still gets the timed out error.

[stepandr47]

Linux Output:

Click to expand!

>>> spec = Spectrometer.from_first_available()
2021-09-22 17:01:49,369 DEBUG:usb.backend.libusb1:_LibUSB.__init__(<CDLL '/home/ubuntu/anaconda3/envs/seabreeze-test/lib/libusb-1.0.so.0', handle 55f934af7580 at 0x7f9d592c8700>)
[timestamp] [threadID] facility level [function call] <message>
--------------------------------------------------------------------------------
[ 0.000037] [0000b467] libusb: debug [libusb_init] created default context
[ 0.000063] [0000b467] libusb: debug [libusb_init] libusb v1.0.24.11584
[ 0.000082] [0000b467] libusb: debug [get_kernel_version] reported kernel version is 5.11.0-34-generic
[ 0.000128] [0000b467] libusb: debug [op_init] found usbfs at /dev/bus/usb
[ 0.000145] [0000b467] libusb: debug [op_init] max iso packet length is (likely) 98304 bytes
[ 0.000163] [0000b467] libusb: debug [op_init] sysfs is available
[ 0.000766] [0000b46d] libusb: debug [linux_udev_event_thread_main] udev event thread entering
[ 0.004988] [0000b467] libusb: debug [linux_get_device_address] getting address for device: usb2 detached: 0
[ 0.005054] [0000b467] libusb: debug [linux_get_device_address] scan usb2
[ 0.005169] [0000b467] libusb: debug [linux_get_device_address] bus=2 dev=1
[ 0.005181] [0000b467] libusb: debug [linux_enumerate_device] busnum 2 devaddr 1 session_id 513
[ 0.005189] [0000b467] libusb: debug [linux_enumerate_device] allocating new device for 2/1 (session 513)
[ 0.005458] [0000b467] libusb: debug [linux_get_device_address] getting address for device: 2-1 detached: 0
[ 0.005471] [0000b467] libusb: debug [linux_get_device_address] scan 2-1
[ 0.005540] [0000b467] libusb: debug [linux_get_device_address] bus=2 dev=2
[ 0.005550] [0000b467] libusb: debug [linux_enumerate_device] busnum 2 devaddr 2 session_id 514
[ 0.005557] [0000b467] libusb: debug [linux_enumerate_device] allocating new device for 2/2 (session 514)
[ 0.005663] [0000b467] libusb: debug [linux_get_parent_info] dev 0x55f9346c6000 (2-1) has parent 0x55f934ace040 (usb2) port 1
[ 0.005977] [0000b467] libusb: debug [linux_get_device_address] getting address for device: 2-2 detached: 0
[ 0.005989] [0000b467] libusb: debug [linux_get_device_address] scan 2-2
[ 0.006058] [0000b467] libusb: debug [linux_get_device_address] bus=2 dev=3
[ 0.006067] [0000b467] libusb: debug [linux_enumerate_device] busnum 2 devaddr 3 session_id 515
[ 0.006075] [0000b467] libusb: debug [linux_enumerate_device] allocating new device for 2/3 (session 515)
[ 0.006156] [0000b467] libusb: debug [linux_get_parent_info] dev 0x55f9348539d0 (2-2) has parent 0x55f934ace040 (usb2) port 2
[ 0.006363] [0000b467] libusb: debug [linux_get_device_address] getting address for device: 2-2.1 detached: 0
[ 0.006375] [0000b467] libusb: debug [linux_get_device_address] scan 2-2.1
[ 0.006443] [0000b467] libusb: debug [linux_get_device_address] bus=2 dev=12
[ 0.006453] [0000b467] libusb: debug [linux_enumerate_device] busnum 2 devaddr 12 session_id 524
[ 0.006461] [0000b467] libusb: debug [linux_enumerate_device] allocating new device for 2/12 (session 524)
[ 0.006528] [0000b467] libusb: debug [linux_get_parent_info] dev 0x55f93472a4f0 (2-2.1) has parent 0x55f9348539d0 (2-2) port 1
[ 0.006785] [0000b467] libusb: debug [linux_get_device_address] getting address for device: usb1 detached: 0
[ 0.006822] [0000b467] libusb: debug [linux_get_device_address] scan usb1
[ 0.006901] [0000b467] libusb: debug [linux_get_device_address] bus=1 dev=1
[ 0.006935] [0000b467] libusb: debug [linux_enumerate_device] busnum 1 devaddr 1 session_id 257
[ 0.006948] [0000b467] libusb: debug [linux_enumerate_device] allocating new device for 1/1 (session 257)
[ 0.007263] [0000b467] libusb: debug [linux_get_device_address] getting address for device: 1-1 detached: 0
[ 0.007303] [0000b467] libusb: debug [linux_get_device_address] scan 1-1
[ 0.007390] [0000b467] libusb: debug [linux_get_device_address] bus=1 dev=25
[ 0.007428] [0000b467] libusb: debug [linux_enumerate_device] busnum 1 devaddr 25 session_id 281
[ 0.007441] [0000b467] libusb: debug [linux_enumerate_device] allocating new device for 1/25 (session 281)
[ 0.007564] [0000b467] libusb: debug [linux_get_parent_info] dev 0x55f9348f2da0 (1-1) has parent 0x55f93483db60 (usb1) port 1
[ 0.007753] [0000b467] libusb: debug [usbi_add_event_source] add fd 6 events 1
[ 0.007801] [0000b467] libusb: debug [usbi_io_init] using timer for timeouts
[ 0.007836] [0000b467] libusb: debug [usbi_add_event_source] add fd 7 events 1
2021-09-22 17:01:49,377 INFO:usb.core:find(): using backend "usb.backend.libusb1"
2021-09-22 17:01:49,378 DEBUG:usb.backend.libusb1:_LibUSB.enumerate_devices()
[ 0.008722] [0000b467] libusb: debug [libusb_get_device_list]  
2021-09-22 17:01:49,378 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cab20>)
[ 0.009155] [0000b467] libusb: debug [libusb_get_device_descriptor]  
2021-09-22 17:01:49,379 DEBUG:usb.backend.libusb1:_LibUSB.open_device(<usb.backend.libusb1._Device object at 0x7f9d590cab20>)
[ 0.009829] [0000b467] libusb: debug [libusb_open] open 1.25
[ 0.009933] [0000b467] libusb: debug [usbi_add_event_source] add fd 8 events 4
2021-09-22 17:01:49,380 DEBUG:usb.backend.libusb1:_LibUSB.reset_device(<usb.backend.libusb1._DeviceHandle object at 0x7f9d590cad00>)
[ 0.010474] [0000b467] libusb: debug [libusb_reset_device]  
2021-09-22 17:01:49,864 DEBUG:usb.backend.libusb1:_LibUSB.close_device(<usb.backend.libusb1._DeviceHandle object at 0x7f9d590cad00>)
[ 0.494722] [0000b467] libusb: debug [libusb_close]  
[ 0.494967] [0000b467] libusb: debug [usbi_remove_event_source] remove fd 8
2021-09-22 17:01:49,865 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cad60>)
[ 0.496010] [0000b467] libusb: debug [libusb_get_device_descriptor]  
2021-09-22 17:01:49,866 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590caeb0>)
[ 0.496853] [0000b467] libusb: debug [libusb_get_device_descriptor]  
2021-09-22 17:01:49,867 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cadc0>)
[ 0.497933] [0000b467] libusb: debug [libusb_get_device_descriptor]  
2021-09-22 17:01:49,868 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590caf10>)
[ 0.499249] [0000b467] libusb: debug [libusb_get_device_descriptor]  
2021-09-22 17:01:49,869 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cae20>)
[ 0.500460] [0000b467] libusb: debug [libusb_get_device_descriptor]  
2021-09-22 17:01:49,870 DEBUG:usb.backend.libusb1:_LibUSB.open_device(<usb.backend.libusb1._Device object at 0x7f9d590cab20>)
[ 0.502201] [0000b467] libusb: debug [libusb_open] open 1.25
[ 0.502613] [0000b467] libusb: debug [usbi_add_event_source] add fd 8 events 4
2021-09-22 17:01:49,872 DEBUG:usb.backend.libusb1:_LibUSB.reset_device(<usb.backend.libusb1._DeviceHandle object at 0x7f9d64f64970>)
[ 0.503599] [0000b467] libusb: debug [libusb_reset_device]  
2021-09-22 17:01:50,352 DEBUG:usb.backend.libusb1:_LibUSB.close_device(<usb.backend.libusb1._DeviceHandle object at 0x7f9d64f64970>)
[ 0.983080] [0000b467] libusb: debug [libusb_close]  
[ 0.983156] [0000b467] libusb: debug [usbi_remove_event_source] remove fd 8
2021-09-22 17:01:50,353 INFO:usb.core:find(): using backend "usb.backend.libusb1"
2021-09-22 17:01:50,353 DEBUG:usb.backend.libusb1:_LibUSB.enumerate_devices()
[ 0.984426] [0000b467] libusb: debug [libusb_get_device_list]  
2021-09-22 17:01:50,354 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>)
[ 0.985183] [0000b467] libusb: debug [libusb_get_device_descriptor]  
2021-09-22 17:01:50,355 DEBUG:usb.backend.libusb1:_LibUSB.open_device(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>)
[ 0.986428] [0000b467] libusb: debug [libusb_open] open 1.25
[ 0.986643] [0000b467] libusb: debug [usbi_add_event_source] add fd 8 events 4
2021-09-22 17:01:50,356 DEBUG:usb.backend.libusb1:_LibUSB.is_kernel_driver_active(<usb.backend.libusb1._DeviceHandle object at 0x7f9d590caac0>, 0)
[ 0.987154] [0000b467] libusb: debug [libusb_kernel_driver_active] interface 0
2021-09-22 17:01:50,357 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0)
[ 0.988282] [0000b467] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-22 17:01:50,359 DEBUG:usb.backend.libusb1:_LibUSB.set_configuration(<usb.backend.libusb1._DeviceHandle object at 0x7f9d590caac0>, 1)
[ 0.990179] [0000b467] libusb: debug [libusb_set_configuration] configuration 1
2021-09-22 17:01:50,380 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0)
[ 1.010784] [0000b467] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-22 17:01:50,381 DEBUG:usb.backend.libusb1:_LibUSB.get_interface_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0, 0, 0)
2021-09-22 17:01:50,381 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0)
[ 1.012549] [0000b467] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-22 17:01:50,383 DEBUG:usb.backend.libusb1:_LibUSB.get_endpoint_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0, 0, 0, 0)
2021-09-22 17:01:50,383 DEBUG:usb.backend.libusb1:_LibUSB.get_interface_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0, 0, 0)
2021-09-22 17:01:50,384 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0)
[ 1.014359] [0000b467] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-22 17:01:50,384 DEBUG:usb.backend.libusb1:_LibUSB.claim_interface(<usb.backend.libusb1._DeviceHandle object at 0x7f9d590caac0>, 0)
[ 1.015289] [0000b467] libusb: debug [libusb_claim_interface] interface 0
2021-09-22 17:01:50,385 DEBUG:usb.backend.libusb1:_LibUSB.bulk_write(<usb.backend.libusb1._DeviceHandle object at 0x7f9d590caac0>, 1, 0, array('B', [193, 192, 0, 17, 0, 0, 0, 0, 1, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 20, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 197, 196, 195, 194]), 1000)
[ 1.016221] [0000b467] libusb: debug [libusb_alloc_transfer] transfer 0x55f934a68928
[ 1.016314] [0000b467] libusb: debug [libusb_submit_transfer] transfer 0x55f934a68928
[ 1.016335] [0000b467] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 1.016361] [0000b467] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 1.016509] [0000b467] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 1.016602] [0000b467] libusb: debug [handle_events] event sources modified, reallocating event data
[ 1.016633] [0000b467] libusb: debug [usbi_wait_for_events] poll() 3 fds with timeout in 60000ms
[ 1.018657] [0000b467] libusb: debug [usbi_wait_for_events] poll() returned 1
[ 1.020086] [0000b467] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=64
[ 1.020150] [0000b467] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 1.020193] [0000b467] libusb: debug [handle_bulk_completion] all URBs in transfer reaped --> complete!
[ 1.020235] [0000b467] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 1.020281] [0000b467] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f934a68928 has callback 0x7f9d590b1b90
[ 1.021691] [0000b467] libusb: debug [sync_transfer_cb] actual_length=64
[ 1.021763] [0000b467] libusb: debug [libusb_free_transfer] transfer 0x55f934a68928
2021-09-22 17:01:50,392 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0)
[ 1.023007] [0000b467] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-22 17:01:50,395 DEBUG:usb.backend.libusb1:_LibUSB.get_interface_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0, 0, 0)
2021-09-22 17:01:50,397 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0)
[ 1.027932] [0000b467] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-22 17:01:50,398 DEBUG:usb.backend.libusb1:_LibUSB.get_endpoint_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0, 0, 0, 0)
2021-09-22 17:01:50,399 DEBUG:usb.backend.libusb1:_LibUSB.get_interface_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0, 0, 0)
2021-09-22 17:01:50,399 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0)
[ 1.029856] [0000b467] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-22 17:01:50,400 DEBUG:usb.backend.libusb1:_LibUSB.get_endpoint_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 1, 0, 0, 0)
2021-09-22 17:01:50,400 DEBUG:usb.backend.libusb1:_LibUSB.get_interface_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0, 0, 0)
2021-09-22 17:01:50,400 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7f9d590cabb0>, 0)
[ 1.031228] [0000b467] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-22 17:01:50,401 DEBUG:usb.backend.libusb1:_LibUSB.bulk_read(<usb.backend.libusb1._DeviceHandle object at 0x7f9d590caac0>, 129, 0, array('B', [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]), 1000)
[ 1.032576] [0000b467] libusb: debug [libusb_alloc_transfer] transfer 0x55f934a68928
[ 1.032673] [0000b467] libusb: debug [libusb_submit_transfer] transfer 0x55f934a68928
[ 1.032716] [0000b467] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 1.032775] [0000b467] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 1.032881] [0000b467] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 1.032934] [0000b467] libusb: debug [usbi_wait_for_events] poll() 3 fds with timeout in 60000ms
[ 2.033518] [0000b467] libusb: debug [usbi_wait_for_events] poll() returned 1
[ 2.033608] [0000b467] libusb: debug [libusb_cancel_transfer] transfer 0x55f934a68928
[ 2.038056] [0000b467] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 2.038132] [0000b467] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 2.038149] [0000b467] libusb: debug [usbi_wait_for_events] poll() 3 fds with timeout in 60000ms
[ 2.038171] [0000b467] libusb: debug [usbi_wait_for_events] poll() returned 1
[ 2.038200] [0000b467] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 2.038215] [0000b467] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 2.038227] [0000b467] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 2.038239] [0000b467] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 2.038252] [0000b467] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 2.038321] [0000b467] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 2.038346] [0000b467] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f934a68928 has callback 0x7f9d590b1b90
[ 2.038359] [0000b467] libusb: debug [sync_transfer_cb] actual_length=0
[ 2.038377] [0000b467] libusb: debug [libusb_free_transfer] transfer 0x55f934a68928
Traceback (most recent call last):
  File "<stdin>", line 1, in <module>
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/spectrometers.py", line 87, in from_first_available
    for dev in list_devices():
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/spectrometers.py", line 36, in list_devices
    return list_devices._api.list_devices()
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/api.py", line 104, in list_devices
    dev.open()
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/devices.py", line 353, in open
    self._serial_number = self.get_serial_number()
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/devices.py", line 393, in get_serial_number
    serial_len = ord(protocol.query(0x00000101))
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/protocol.py", line 372, in query
    return self.receive(timeout_ms=timeout_ms)
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/protocol.py", line 313, in receive
    response = self.transport.read(size=64, timeout_ms=timeout_ms)
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/transport.py", line 250, in read
    return self._device.pyusb_device.read(endpoint, size, timeout=timeout_ms)
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/usb/core.py", line 1029, in read
    ret = fn(
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/usb/_debug.py", line 62, in do_trace
    return f(*args, **named_args)
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/usb/backend/libusb1.py", line 846, in bulk_read
    return self.__read(self.lib.libusb_bulk_transfer,
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/usb/backend/libusb1.py", line 954, in __read
    _check(retval)
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/usb/backend/libusb1.py", line 602, in _check
    raise USBTimeoutError(_strerror(ret), ret, _libusb_errno[ret])
usb.core.USBTimeoutError: [Errno 110] Operation timed out

[ap--]

To get the same output for windows, you need to set them as environment variables:

set LIBUSB_DEBUG=4
set PYUSB_DEBUG=debug
python your_code.py

(I think that's the right way for cmd.exe, powershell might be different)

[stepandr47]

Was able to get these debugs working by adding these lines to the start of the script in windows. Noticed your comment after finding this way:
import os
os.environ['PYUSB_DEBUG'] = 'debug'
os.environ['LIBUSB_DEBUG'] = '4'

Windows Output:

Click to expand!

(seabreeze-test) C:\Users\astephen\Documents>python Seabreeze_test.py
[timestamp] [threadID] facility level [function call] <message>
--------------------------------------------------------------------------------
[ 0.000376] [00001914] libusb: debug [libusb_init] created default context
[ 0.000419] [00001914] libusb: debug [libusb_init] libusb v1.0.24.11584
[ 0.000512] [00001914] libusb: debug [get_windows_version] Windows 10 64-bit
[ 0.000587] [00001914] libusb: debug [htab_create] using 1021 entries hash table
[ 0.001231] [00001914] libusb: info [winusbx_init] WinUSB DLL available (with isoch support)
[ 0.001381] [00001914] libusb: info [winusbx_init] libusbK DLL is not available
[ 0.002610] [00001914] libusb: info [windows_init] UsbDk backend is not available
[ 0.002817] [00001914] libusb: debug [usbi_add_event_source] add HANDLE 0000000000000118 events 0
[ 0.002882] [00001914] libusb: debug [usbi_io_init] using timer for timeouts
[ 0.002936] [00001914] libusb: debug [usbi_add_event_source] add HANDLE 000000000000031C events 0
[ 0.003101] [00001914] libusb: debug [libusb_get_device_list]
[ 0.003447] [000005e0] libusb: debug [windows_iocp_thread] I/O completion thread started
[ 0.015397] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [51]
[ 0.015614] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [52]
[ 0.015798] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [53]
[ 0.015976] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [54]
[ 0.016420] [00001914] libusb: debug [get_api_type] driver(s): BTHUSB
[ 0.016595] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [7]
[ 0.017077] [00001914] libusb: debug [get_api_type] driver(s): WUDFRd
[ 0.017285] [00001914] libusb: debug [get_api_type] lower filter driver(s): WinUsb
[ 0.017365] [00001914] libusb: debug [get_api_type] matched lower filter driver name against WinUSB
[ 0.017430] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [1E]
[ 0.017939] [00001914] libusb: debug [get_api_type] driver(s): WINUSB
[ 0.018248] [00001914] libusb: debug [get_api_type] matched driver name against WinUSB
[ 0.018645] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [39]
[ 0.019525] [00001914] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_8086&DEV_1C26&SUBSYS_21CF17AA&REV_04\3&B1BFB68&0&E8' bus number 1
[ 0.019740] [00001914] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_8086&DEV_1C2D&SUBSYS_21CF17AA&REV_04\3&B1BFB68&0&D0' bus number 2
[ 0.021423] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [52]
[ 0.118831] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.119842] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 25 bytes)
[ 0.123963] [00001914] libusb: debug [init_device] (bus: 1, addr: 1, depth: 1, port: 1): 'USB\VID_8087&PID_0024\5&2DF2E661&0&1'
[ 0.129739] [00001914] libusb: debug [init_root_hub] root hub 'USB\ROOT_HUB20\4&35D3A64E&0' reports 3 ports
[ 0.130799] [00001914] libusb: debug [init_device] (bus: 1, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB20\4&35D3A64E&0'
[ 0.133403] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [7]
[ 0.135772] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.144541] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 216 bytes)
[ 0.146628] [00001914] libusb: debug [init_device] (bus: 2, addr: 2, depth: 2, port: 4): 'USB\VID_0A5C&PID_217F\60D819AF2C20'
[ 0.149292] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [1E]
[ 0.151177] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.163520] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 93 bytes)
[ 0.163931] [00001914] libusb: debug [init_device] (bus: 1, addr: 2, depth: 2, port: 5): 'USB\VID_17EF&PID_1003\6&37ACA2D4&0&5'
[ 0.167231] [00001914] libusb: debug [init_root_hub] root hub 'USB\ROOT_HUB20\4&3423CC03&0' reports 3 ports
[ 0.172506] [00001914] libusb: debug [init_device] (bus: 2, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB20\4&3423CC03&0'
[ 0.175209] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [51]
[ 0.179615] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.182427] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 25 bytes)
[ 0.191102] [00001914] libusb: debug [init_device] (bus: 2, addr: 1, depth: 1, port: 1): 'USB\VID_8087&PID_0024\5&C22FC1F&0&1'
[ 0.194141] [00001914] libusb: debug [winusb_get_device_list] extra GUID: {DBBAD306-1786-4F2E-A8AB-340D45F0653F}
[ 0.195243] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [39]
[ 0.197693] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.199904] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 32 bytes)
[ 0.201162] [00001914] libusb: debug [init_device] (bus: 2, addr: 3, depth: 2, port: 2): 'USB\VID_2457&PID_2003\HDX01168'
[ 0.214280] [00001914] libusb: debug [get_api_type] driver(s): WINUSB
[ 0.218083] [00001914] libusb: debug [get_api_type] matched driver name against WinUSB
[ 0.222593] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.223412] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.225462] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.226809] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.228158] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.229783] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.231235] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.232755] [00001914] libusb: debug [libusb_open] open 2.3
[ 0.234094] [00001914] libusb: debug [libusb_reset_device]
[ 0.235334] [00001914] libusb: debug [libusb_close]
[ 0.236847] [00001914] libusb: debug [libusb_unref_device] destroy device 2.2
[ 0.237924] [00001914] libusb: debug [libusb_unref_device] destroy device 1.2
[ 0.239202] [00001914] libusb: debug [libusb_unref_device] destroy device 1.1
[ 0.240648] [00001914] libusb: debug [libusb_unref_device] destroy device 1.0
[ 0.248851] [00001914] libusb: debug [libusb_open] open 2.3
[ 0.250422] [00001914] libusb: debug [libusb_reset_device]
[ 0.251570] [00001914] libusb: debug [libusb_close]
[ 0.253029] [00001914] libusb: debug [libusb_unref_device] destroy device 2.3
[ 0.253832] [00001914] libusb: debug [libusb_unref_device] destroy device 2.1
[ 0.254979] [00001914] libusb: debug [libusb_unref_device] destroy device 2.0
[ 0.256298] [00001914] libusb: debug [libusb_get_device_list]
[ 0.274764] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [51]
[ 0.275037] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [52]
[ 0.275710] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [53]
[ 0.280018] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [54]
[ 0.280799] [00001914] libusb: debug [get_api_type] driver(s): BTHUSB
[ 0.281079] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [7]
[ 0.282013] [00001914] libusb: debug [get_api_type] driver(s): WUDFRd
[ 0.282162] [00001914] libusb: debug [get_api_type] lower filter driver(s): WinUsb
[ 0.282648] [00001914] libusb: debug [get_api_type] matched lower filter driver name against WinUSB
[ 0.283175] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [1E]
[ 0.283722] [00001914] libusb: debug [get_api_type] driver(s): WINUSB
[ 0.283891] [00001914] libusb: debug [get_api_type] matched driver name against WinUSB
[ 0.284334] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [39]
[ 0.285231] [00001914] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_8086&DEV_1C26&SUBSYS_21CF17AA&REV_04\3&B1BFB68&0&E8' bus number 1
[ 0.285345] [00001914] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_8086&DEV_1C2D&SUBSYS_21CF17AA&REV_04\3&B1BFB68&0&D0' bus number 2
[ 0.286994] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [52]
[ 0.289334] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.292574] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 25 bytes)
[ 0.293782] [00001914] libusb: debug [init_device] (bus: 1, addr: 1, depth: 1, port: 1): 'USB\VID_8087&PID_0024\5&2DF2E661&0&1'
[ 0.294595] [00001914] libusb: debug [init_root_hub] root hub 'USB\ROOT_HUB20\4&35D3A64E&0' reports 3 ports
[ 0.294855] [00001914] libusb: debug [init_device] (bus: 1, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB20\4&35D3A64E&0'
[ 0.295550] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [7]
[ 0.295988] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.296479] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 216 bytes)
[ 0.297007] [00001914] libusb: debug [init_device] (bus: 2, addr: 2, depth: 2, port: 4): 'USB\VID_0A5C&PID_217F\60D819AF2C20'
[ 0.297697] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [1E]
[ 0.298125] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.301813] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 93 bytes)
[ 0.301989] [00001914] libusb: debug [init_device] (bus: 1, addr: 2, depth: 2, port: 5): 'USB\VID_17EF&PID_1003\6&37ACA2D4&0&5'
[ 0.302791] [00001914] libusb: debug [init_root_hub] root hub 'USB\ROOT_HUB20\4&3423CC03&0' reports 3 ports
[ 0.302998] [00001914] libusb: debug [init_device] (bus: 2, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB20\4&3423CC03&0'
[ 0.303608] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [51]
[ 0.304050] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.304853] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 25 bytes)
[ 0.305049] [00001914] libusb: debug [init_device] (bus: 2, addr: 1, depth: 1, port: 1): 'USB\VID_8087&PID_0024\5&C22FC1F&0&1'
[ 0.305691] [00001914] libusb: debug [winusb_get_device_list] extra GUID: {DBBAD306-1786-4F2E-A8AB-340D45F0653F}
[ 0.305968] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [39]
[ 0.306503] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.307603] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 32 bytes)
[ 0.307697] [00001914] libusb: debug [init_device] (bus: 2, addr: 3, depth: 2, port: 2): 'USB\VID_2457&PID_2003\HDX01168'
[ 0.309930] [00001914] libusb: debug [get_api_type] driver(s): WINUSB
[ 0.313376] [00001914] libusb: debug [get_api_type] matched driver name against WinUSB
[ 0.314729] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.314951] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.315548] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.316113] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.316671] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.317237] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.317788] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.318463] [00001914] libusb: debug [libusb_open] open 2.3
[ 0.318848] [00001914] libusb: debug [libusb_kernel_driver_active] interface 0
[ 0.319370] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.319966] [00001914] libusb: debug [libusb_set_configuration] configuration 1
[ 0.320423] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F0A0A58
[ 0.320473] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F0A0A58
[ 0.320971] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.321044] [00001914] libusb: debug [libusb_claim_interface] interface 0
[ 0.321627] [00001914] libusb: debug [winusbx_claim_interface] claimed interface 0
[ 0.322091] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 01 to interface 0
[ 0.322141] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 81 to interface 0
[ 0.322710] [00001914] libusb: debug [auto_claim] auto-claimed interface 0 for control request
[ 0.323188] [00001914] libusb: debug [winusbx_submit_control_transfer] will use interface 0
[ 0.323236] [00001914] libusb: debug [windows_force_sync_completion] transfer 0000018B1F0A0A58, length 0
[ 0.323728] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.323780] [00001914] libusb: debug [handle_events] event sources modified, reallocating event data
[ 0.324275] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.324332] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.324878] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.328942] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F0A0A58 completion with errcode 0, length 0
[ 0.329672] [00001914] libusb: debug [libusb_release_interface] interface 0
[ 0.330209] [00001914] libusb: debug [auto_release] auto-released interface 0
[ 0.330971] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.331506] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F0A0A58 has callback 00007FFB04B38BF0
[ 0.331996] [00001914] libusb: debug [sync_transfer_cb] actual_length=0
[ 0.332526] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F0A0A58
[ 0.333407] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.333664] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.334078] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.334705] [00001914] libusb: debug [libusb_claim_interface] interface 0
[ 0.335196] [00001914] libusb: debug [winusbx_claim_interface] claimed interface 0
[ 0.335663] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 01 to interface 0
[ 0.336185] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 81 to interface 0
[ 0.336432] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F0A0A58
[ 0.336740] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F0A0A58
[ 0.336792] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.337302] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.337820] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.337934] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.338457] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.338069] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F0A0A58 completed, length 64
[ 0.339535] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.340048] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.340096] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F0A0A58 completion with errcode 0, length 64
[ 0.340707] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.344717] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F0A0A58 has callback 00007FFB04B38BF0
[ 0.345353] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.345908] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F0A0A58
[ 0.346558] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.347140] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.347686] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.348236] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.348868] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F0A0A58
[ 0.349343] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F0A0A58
[ 0.349899] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.351083] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.351622] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.352214] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.352767] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.352323] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F0A0A58 completed, length 64
[ 0.353850] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.354361] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.354410] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F0A0A58 completion with errcode 0, length 64
[ 0.354913] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.355437] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F0A0A58 has callback 00007FFB04B38BF0
[ 0.359178] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.359823] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F0A0A58
[ 0.360707] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F0A0A58
[ 0.361043] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F0A0A58
[ 0.361577] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.361639] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.362135] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.362255] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.362783] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.362445] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F0A0A58 completed, length 64
[ 0.363873] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.364388] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.364437] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F0A0A58 completion with errcode 0, length 64
[ 0.364943] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.365490] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F0A0A58 has callback 00007FFB04B38BF0
[ 0.366077] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.366589] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F0A0A58
[ 0.367182] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F0A0A58
[ 0.367619] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F0A0A58
[ 0.368119] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.368624] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.369143] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.369795] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.370283] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.369962] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F0A0A58 completed, length 64
[ 0.371606] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.376045] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.376827] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F0A0A58 completion with errcode 0, length 64
[ 0.377333] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.377863] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F0A0A58 has callback 00007FFB04B38BF0
[ 0.378313] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.378756] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F0A0A58
[ 0.379367] [00001914] libusb: debug [libusb_release_interface] interface 0
[ 0.379673] [00001914] libusb: debug [libusb_reset_device]
[ 0.380137] [00001914] libusb: debug [libusb_close]
[ 0.380721] [00001914] libusb: debug [libusb_unref_device] destroy device 2.2
[ 0.381026] [00001914] libusb: debug [libusb_unref_device] destroy device 1.2
[ 0.381461] [00001914] libusb: debug [libusb_unref_device] destroy device 1.1
[ 0.381896] [00001914] libusb: debug [libusb_unref_device] destroy device 1.0
[<SeaBreezeDevice HDX:HDX01168>]
[ 0.382845] [00001914] libusb: debug [libusb_open] open 2.3
[ 0.383345] [00001914] libusb: debug [libusb_reset_device]
[ 0.383733] [00001914] libusb: debug [libusb_close]
[ 0.384295] [00001914] libusb: debug [libusb_unref_device] destroy device 2.3
[ 0.384626] [00001914] libusb: debug [libusb_unref_device] destroy device 2.1
[ 0.385063] [00001914] libusb: debug [libusb_unref_device] destroy device 2.0
[ 0.385630] [00001914] libusb: debug [libusb_get_device_list]
[ 0.399061] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [51]
[ 0.399323] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [52]
[ 0.400110] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [53]
[ 0.400747] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [54]
[ 0.401678] [00001914] libusb: debug [get_api_type] driver(s): BTHUSB
[ 0.402102] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [7]
[ 0.403259] [00001914] libusb: debug [get_api_type] driver(s): WUDFRd
[ 0.403461] [00001914] libusb: debug [get_api_type] lower filter driver(s): WinUsb
[ 0.404004] [00001914] libusb: debug [get_api_type] matched lower filter driver name against WinUSB
[ 0.404575] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [1E]
[ 0.405863] [00001914] libusb: debug [get_api_type] driver(s): WINUSB
[ 0.406033] [00001914] libusb: debug [get_api_type] matched driver name against WinUSB
[ 0.406580] [00001914] libusb: debug [winusb_get_device_list] allocating new device for session [39]
[ 0.407498] [00001914] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_8086&DEV_1C26&SUBSYS_21CF17AA&REV_04\3&B1BFB68&0&E8' bus number 1
[ 0.407633] [00001914] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_8086&DEV_1C2D&SUBSYS_21CF17AA&REV_04\3&B1BFB68&0&D0' bus number 2
[ 0.409167] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [52]
[ 0.409438] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.412553] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 25 bytes)
[ 0.415098] [00001914] libusb: debug [init_device] (bus: 1, addr: 1, depth: 1, port: 1): 'USB\VID_8087&PID_0024\5&2DF2E661&0&1'
[ 0.415878] [00001914] libusb: debug [init_root_hub] root hub 'USB\ROOT_HUB20\4&35D3A64E&0' reports 3 ports
[ 0.416175] [00001914] libusb: debug [init_device] (bus: 1, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB20\4&35D3A64E&0'
[ 0.416831] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [7]
[ 0.417159] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.417515] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 216 bytes)
[ 0.417957] [00001914] libusb: debug [init_device] (bus: 2, addr: 2, depth: 2, port: 4): 'USB\VID_0A5C&PID_217F\60D819AF2C20'
[ 0.418576] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [1E]
[ 0.418879] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.422102] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 93 bytes)
[ 0.422234] [00001914] libusb: debug [init_device] (bus: 1, addr: 2, depth: 2, port: 5): 'USB\VID_17EF&PID_1003\6&37ACA2D4&0&5'
[ 0.422964] [00001914] libusb: debug [init_root_hub] root hub 'USB\ROOT_HUB20\4&3423CC03&0' reports 3 ports
[ 0.423157] [00001914] libusb: debug [init_device] (bus: 2, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB20\4&3423CC03&0'
[ 0.423768] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [51]
[ 0.424070] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.424679] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 25 bytes)
[ 0.424884] [00001914] libusb: debug [init_device] (bus: 2, addr: 1, depth: 1, port: 1): 'USB\VID_8087&PID_0024\5&C22FC1F&0&1'
[ 0.425567] [00001914] libusb: debug [winusb_get_device_list] extra GUID: {DBBAD306-1786-4F2E-A8AB-340D45F0653F}
[ 0.429005] [00001914] libusb: debug [winusb_get_device_list] found existing device for session [39]
[ 0.429794] [00001914] libusb: debug [init_device] found 1 configurations (current config: 1)
[ 0.430977] [00001914] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 32 bytes)
[ 0.431081] [00001914] libusb: debug [init_device] (bus: 2, addr: 3, depth: 2, port: 2): 'USB\VID_2457&PID_2003\HDX01168'
[ 0.432825] [00001914] libusb: debug [get_api_type] driver(s): WINUSB
[ 0.432968] [00001914] libusb: debug [get_api_type] matched driver name against WinUSB
[ 0.434059] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.434224] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.434817] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.435261] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.435698] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.436129] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.436557] [00001914] libusb: debug [libusb_get_device_descriptor]
[ 0.437036] [00001914] libusb: debug [libusb_open] open 2.3
[ 0.437325] [00001914] libusb: debug [libusb_kernel_driver_active] interface 0
[ 0.437750] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.438285] [00001914] libusb: debug [libusb_set_configuration] configuration 1
[ 0.438570] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F0A0A58
[ 0.439003] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F0A0A58
[ 0.439453] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.439924] [00001914] libusb: debug [libusb_claim_interface] interface 0
[ 0.440522] [00001914] libusb: debug [winusbx_claim_interface] claimed interface 0
[ 0.440940] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 01 to interface 0
[ 0.444497] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 81 to interface 0
[ 0.445136] [00001914] libusb: debug [auto_claim] auto-claimed interface 0 for control request
[ 0.445509] [00001914] libusb: debug [winusbx_submit_control_transfer] will use interface 0
[ 0.445954] [00001914] libusb: debug [windows_force_sync_completion] transfer 0000018B1F0A0A58, length 0
[ 0.446400] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.446839] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.447277] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.447707] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.448138] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F0A0A58 completion with errcode 0, length 0
[ 0.448579] [00001914] libusb: debug [libusb_release_interface] interface 0
[ 0.449006] [00001914] libusb: debug [auto_release] auto-released interface 0
[ 0.449439] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.449985] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F0A0A58 has callback 00007FFB04B38BF0
[ 0.450540] [00001914] libusb: debug [sync_transfer_cb] actual_length=0
[ 0.451603] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F0A0A58
[ 0.452350] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.452707] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.453154] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.453613] [00001914] libusb: debug [libusb_claim_interface] interface 0
[ 0.453997] [00001914] libusb: debug [winusbx_claim_interface] claimed interface 0
[ 0.454331] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 01 to interface 0
[ 0.454766] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 81 to interface 0
[ 0.455383] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1BF8
[ 0.455642] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1BF8
[ 0.456082] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.456637] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.460377] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.461042] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.461191] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1BF8 completed, length 64
[ 0.461445] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.462311] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.462726] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.463147] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1BF8 completion with errcode 0, length 64
[ 0.463613] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.464057] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1BF8 has callback 00007FFB04B38BF0
[ 0.464496] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.464931] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1BF8
[ 0.465488] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.466000] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.466436] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.466873] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.467357] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1E78
[ 0.467594] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1E78
[ 0.468035] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.468480] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.468905] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.469406] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.469563] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1E78 completed, length 64
[ 0.469929] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.470961] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.471715] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.472392] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1E78 completion with errcode 0, length 64
[ 0.476730] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.477394] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1E78 has callback 00007FFB04B38BF0
[ 0.477903] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.478365] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1E78
[ 0.479029] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2378
[ 0.479278] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2378
[ 0.479722] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.480167] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.480599] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.481103] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.481544] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.481190] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2378 completed, length 64
[ 0.482474] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.482914] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.483345] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2378 completion with errcode 0, length 64
[ 0.483787] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.484220] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2378 has callback 00007FFB04B38BF0
[ 0.484657] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.485088] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2378
[ 0.485624] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2878
[ 0.485979] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2878
[ 0.486412] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.486854] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.487284] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.487874] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.488094] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2878 completed, length 64
[ 0.492425] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.493563] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.493988] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.494406] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2878 completion with errcode 0, length 64
[ 0.494831] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.495246] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2878 has callback 00007FFB04B38BF0
[ 0.495665] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.496110] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2878
[ 0.496721] [00001914] libusb: debug [libusb_release_interface] interface 0
[ 0.497016] [00001914] libusb: debug [libusb_reset_device]
[ 0.497478] [00001914] libusb: debug [libusb_close]
[ 0.498055] [00001914] libusb: debug [libusb_unref_device] destroy device 2.2
[ 0.498359] [00001914] libusb: debug [libusb_unref_device] destroy device 1.2
[ 0.498789] [00001914] libusb: debug [libusb_unref_device] destroy device 1.1
[ 0.499227] [00001914] libusb: debug [libusb_unref_device] destroy device 1.0
[ 0.499743] [00001914] libusb: debug [libusb_open] open 2.3
[ 0.500198] [00001914] libusb: debug [libusb_kernel_driver_active] interface 0
[ 0.500640] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.501201] [00001914] libusb: debug [libusb_set_configuration] configuration 1
[ 0.501486] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1FB8
[ 0.501921] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1FB8
[ 0.502353] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.502798] [00001914] libusb: debug [libusb_claim_interface] interface 0
[ 0.503332] [00001914] libusb: debug [winusbx_claim_interface] claimed interface 0
[ 0.503680] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 01 to interface 0
[ 0.504112] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 81 to interface 0
[ 0.504648] [00001914] libusb: debug [auto_claim] auto-claimed interface 0 for control request
[ 0.504999] [00001914] libusb: debug [winusbx_submit_control_transfer] will use interface 0
[ 0.505433] [00001914] libusb: debug [windows_force_sync_completion] transfer 0000018B1F1E1FB8, length 0
[ 0.505869] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.506302] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.506741] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.507167] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.507616] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1FB8 completion with errcode 0, length 0
[ 0.508074] [00001914] libusb: debug [libusb_release_interface] interface 0
[ 0.508537] [00001914] libusb: debug [auto_release] auto-released interface 0
[ 0.508999] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.509463] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1FB8 has callback 00007FFB04B38BF0
[ 0.510050] [00001914] libusb: debug [sync_transfer_cb] actual_length=0
[ 0.515202] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1FB8
[ 0.516455] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.516886] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.517387] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.517960] [00001914] libusb: debug [libusb_claim_interface] interface 0
[ 0.518333] [00001914] libusb: debug [winusbx_claim_interface] claimed interface 0
[ 0.518657] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 01 to interface 0
[ 0.519090] [00001914] libusb: debug [windows_assign_endpoints] (re)assigned endpoint 81 to interface 0
[ 0.519697] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E25F8
[ 0.519946] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E25F8
[ 0.520380] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.520863] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.521321] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.521836] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.522287] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.521939] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E25F8 completed, length 64
[ 0.523227] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.523669] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.524101] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E25F8 completion with errcode 0, length 64
[ 0.524540] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.524974] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E25F8 has callback 00007FFB04B38BF0
[ 0.525486] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.528956] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E25F8
[ 0.529678] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.530337] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.530996] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.531464] [00001914] libusb: debug [libusb_get_config_descriptor] index 0
[ 0.532176] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1E78
[ 0.532548] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1E78
[ 0.532993] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.533449] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.533892] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.534406] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.534849] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.534566] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1E78 completed, length 64
[ 0.535784] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.536228] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.536658] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1E78 completion with errcode 0, length 64
[ 0.537101] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.537538] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1E78 has callback 00007FFB04B38BF0
[ 0.537967] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.538401] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1E78
[ 0.539014] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2238
[ 0.539272] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2238
[ 0.539711] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.540154] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.540587] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.541207] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.541325] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2238 completed, length 64
[ 0.545132] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.546261] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.546692] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.547129] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2238 completion with errcode 0, length 64
[ 0.547571] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.548009] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2238 has callback 00007FFB04B38BF0
[ 0.548439] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.548873] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2238
[ 0.549466] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E20F8
[ 0.549747] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E20F8
[ 0.550239] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.550877] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.551386] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.552153] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.552618] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.552316] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E20F8 completed, length 64
[ 0.553553] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.553995] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.554427] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E20F8 completion with errcode 0, length 64
[ 0.554867] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.555304] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E20F8 has callback 00007FFB04B38BF0
[ 0.555741] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.556173] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E20F8
[ 0.556936] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E24B8
[ 0.560759] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E24B8
[ 0.561517] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.562011] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.562485] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.563019] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.563189] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E24B8 completed, length 64
[ 0.563405] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.564265] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.564679] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.565094] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E24B8 completion with errcode 0, length 64
[ 0.565517] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.565934] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E24B8 has callback 00007FFB04B38BF0
[ 0.566355] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.566768] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E24B8
[ 0.567337] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1AB8
[ 0.567655] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1AB8
[ 0.568101] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.568555] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.568985] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.569476] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.569931] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.569564] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1AB8 completed, length 64
[ 0.571108] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.572414] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.576988] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1AB8 completion with errcode 0, length 64
[ 0.577712] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.578256] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1AB8 has callback 00007FFB04B38BF0
[ 0.578782] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.579486] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1AB8
[ 0.580129] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1FB8
[ 0.580525] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1FB8
[ 0.580974] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.581446] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.581896] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.582423] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.582563] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1FB8 completed, length 64
[ 0.582799] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.583658] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.584071] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.584493] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1FB8 completion with errcode 0, length 64
[ 0.584961] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.585404] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1FB8 has callback 00007FFB04B38BF0
[ 0.585848] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.586288] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1FB8
[ 0.586835] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2878
[ 0.587184] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2878
[ 0.587624] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.588069] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.588541] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.589041] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.589187] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2878 completed, length 64
[ 0.589447] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.590311] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.590861] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.591438] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2878 completion with errcode 0, length 64
[ 0.592406] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.592876] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2878 has callback 00007FFB04B38BF0
[ 0.593405] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.598174] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2878
[ 0.599009] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2878
[ 0.599777] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2878
[ 0.600252] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.600702] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.601169] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.601691] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.602137] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.601816] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2878 completed, length 64
[ 0.603053] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.603478] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.603930] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2878 completion with errcode 0, length 64
[ 0.604394] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.604857] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2878 has callback 00007FFB04B38BF0
[ 0.605301] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.605741] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2878
[ 0.606306] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1AB8
[ 0.606634] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1AB8
[ 0.607069] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.607511] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.607940] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.608431] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.608884] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.608559] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1AB8 completed, length 64
[ 0.609832] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.613805] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.614392] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1AB8 completion with errcode 0, length 64
[ 0.615123] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.615586] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1AB8 has callback 00007FFB04B38BF0
[ 0.616054] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.616474] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1AB8
[ 0.617048] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E20F8
[ 0.617405] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E20F8
[ 0.617846] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.618317] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.618763] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.619274] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.619720] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.619441] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E20F8 completed, length 64
[ 0.620655] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.621095] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.621528] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E20F8 completion with errcode 0, length 64
[ 0.621969] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.622405] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E20F8 has callback 00007FFB04B38BF0
[ 0.622839] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.623271] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E20F8
[ 0.623811] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E25F8
[ 0.624164] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E25F8
[ 0.624600] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.625046] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.625525] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.629789] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.629949] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E25F8 completed, length 64
[ 0.630365] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.631622] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.632773] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.633231] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E25F8 completion with errcode 0, length 64
[ 0.633678] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.634120] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E25F8 has callback 00007FFB04B38BF0
[ 0.634551] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.634987] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E25F8
[ 0.635631] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E25F8
[ 0.635863] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E25F8
[ 0.636302] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.636750] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.637176] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.637682] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.638125] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.637814] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E25F8 completed, length 64
[ 0.639056] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.639496] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.639929] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E25F8 completion with errcode 0, length 64
[ 0.640370] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.640804] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E25F8 has callback 00007FFB04B38BF0
[ 0.641299] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.645245] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E25F8
[ 0.645989] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2878
[ 0.646534] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2878
[ 0.646997] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.647476] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.647892] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.648378] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.648799] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.648565] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2878 completed, length 64
[ 0.649675] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.650096] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.650548] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2878 completion with errcode 0, length 64
[ 0.651090] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.651635] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2878 has callback 00007FFB04B38BF0
[ 0.652182] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.652860] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2878
[ 0.653508] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1FB8
[ 0.653730] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1FB8
[ 0.654163] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.654599] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.655026] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.655575] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.655692] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1FB8 completed, length 64
[ 0.655982] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.656921] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.660944] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.661531] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1FB8 completion with errcode 0, length 64
[ 0.662224] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.662719] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1FB8 has callback 00007FFB04B38BF0
[ 0.663194] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.663650] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1FB8
[ 0.664257] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E20F8
[ 0.664527] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E20F8
[ 0.664963] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.665426] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.665882] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.666418] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.666866] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.666565] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E20F8 completed, length 64
[ 0.667806] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.668248] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.668682] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E20F8 completion with errcode 0, length 64
[ 0.669122] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.669558] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E20F8 has callback 00007FFB04B38BF0
[ 0.669991] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.670422] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E20F8
[ 0.671108] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1D38
[ 0.671502] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1D38
[ 0.672077] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.672924] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.676835] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.677516] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.678101] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.677710] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1D38 completed, length 64
[ 0.679006] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.679429] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.679895] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1D38 completion with errcode 0, length 64
[ 0.680361] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.680814] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1D38 has callback 00007FFB04B38BF0
[ 0.681267] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.681718] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1D38
[ 0.682322] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2238
[ 0.682592] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2238
[ 0.683027] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.683489] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.683939] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.684454] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.684566] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2238 completed, length 64
[ 0.684859] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.685709] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.686125] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.686537] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2238 completion with errcode 0, length 64
[ 0.686959] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.687376] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2238 has callback 00007FFB04B38BF0
[ 0.687791] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.688406] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2238
[ 0.693643] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E25F8
[ 0.694190] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E25F8
[ 0.694703] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.695158] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.695631] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.696156] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.696605] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.696313] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E25F8 completed, length 64
[ 0.697545] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.697986] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.698418] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E25F8 completion with errcode 0, length 64
[ 0.698856] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.699292] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E25F8 has callback 00007FFB04B38BF0
[ 0.699724] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.700157] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E25F8
[ 0.700720] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1FB8
[ 0.701031] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1FB8
[ 0.701472] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.701913] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.702344] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.702834] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.703287] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.702935] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1FB8 completed, length 64
[ 0.704217] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.704656] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.705087] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1FB8 completion with errcode 0, length 64
[ 0.705524] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.705961] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1FB8 has callback 00007FFB04B38BF0
[ 0.706393] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.706824] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1FB8
[ 0.707426] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2738
[ 0.707698] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2738
[ 0.708135] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.708578] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.709010] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.709494] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.709945] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.709563] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2738 completed, length 64
[ 0.715654] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.716234] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.717256] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2738 completion with errcode 0, length 64
[ 0.717774] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.718231] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2738 has callback 00007FFB04B38BF0
[ 0.718694] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.719143] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2738
[ 0.719738] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1E78
[ 0.720025] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1E78
[ 0.720466] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.720911] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.721342] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.721852] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.722291] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.721941] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1E78 completed, length 64
[ 0.723223] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.723668] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.724097] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1E78 completion with errcode 0, length 64
[ 0.724539] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.724973] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1E78 has callback 00007FFB04B38BF0
[ 0.725407] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.726080] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1E78
[ 0.730386] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1E78
[ 0.730507] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1E78
[ 0.730997] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.731511] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.732027] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.732622] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.733287] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.732824] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1E78 completed, length 64
[ 0.734208] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.734671] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.735093] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1E78 completion with errcode 0, length 64
[ 0.735519] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.735945] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1E78 has callback 00007FFB04B38BF0
[ 0.736361] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.736777] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1E78
[ 0.737300] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E20F8
[ 0.737678] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E20F8
[ 0.738100] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.738562] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.739002] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.739508] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.739961] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.739687] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E20F8 completed, length 64
[ 0.740894] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.741450] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.745649] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E20F8 completion with errcode 0, length 64
[ 0.746379] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.746841] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E20F8 has callback 00007FFB04B38BF0
[ 0.747317] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.747769] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E20F8
[ 0.748436] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1AB8
[ 0.748650] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1AB8
[ 0.749085] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.749546] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.750016] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.750556] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.751015] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.750686] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1AB8 completed, length 64
[ 0.752182] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.753420] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.753881] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1AB8 completion with errcode 0, length 64
[ 0.754327] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.754767] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1AB8 has callback 00007FFB04B38BF0
[ 0.755203] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.755640] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1AB8
[ 0.756223] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2738
[ 0.756509] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2738
[ 0.756988] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.760777] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.761360] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.761866] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.762274] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.761951] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2738 completed, length 64
[ 0.763179] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.763647] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.764084] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2738 completion with errcode 0, length 64
[ 0.764527] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.764961] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2738 has callback 00007FFB04B38BF0
[ 0.765395] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.765829] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2738
[ 0.766474] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2878
[ 0.766708] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2878
[ 0.767148] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.767590] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.768020] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.768511] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.768964] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.768681] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2878 completed, length 64
[ 0.769896] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.770333] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.770767] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2878 completion with errcode 0, length 64
[ 0.771206] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.771706] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2878 has callback 00007FFB04B38BF0
[ 0.772248] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.772875] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2878
[ 0.777575] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2238
[ 0.778265] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2238
[ 0.778746] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.779200] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.779669] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.780199] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.780316] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2238 completed, length 64
[ 0.780581] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.781446] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.781859] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.782277] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2238 completion with errcode 0, length 64
[ 0.782736] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.783176] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2238 has callback 00007FFB04B38BF0
[ 0.783613] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.784045] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2238
[ 0.784671] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E1D38
[ 0.784919] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E1D38
[ 0.785358] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.785803] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.786231] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.786727] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.787181] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.786807] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E1D38 completed, length 64
[ 0.788111] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.788617] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.792800] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E1D38 completion with errcode 0, length 64
[ 0.794830] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.795355] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E1D38 has callback 00007FFB04B38BF0
[ 0.795780] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.796204] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E1D38
[ 0.796746] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2378
[ 0.797105] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2378
[ 0.797537] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.797962] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.798379] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.798857] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.798941] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2378 completed, length 64
[ 0.799295] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.800146] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.800560] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.800979] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2378 completion with errcode 0, length 64
[ 0.801445] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.801890] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2378 has callback 00007FFB04B38BF0
[ 0.802352] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.802771] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2378
[ 0.803398] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E25F8
[ 0.803665] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E25F8
[ 0.804110] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.804556] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 01 with interface 0
[ 0.804991] [00001914] libusb: debug [winusbx_submit_bulk_transfer] writing 64 bytes
[ 0.805482] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.805938] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.805560] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E25F8 completed, length 64
[ 0.806875] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.807312] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.807741] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E25F8 completion with errcode 0, length 64
[ 0.808182] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.808619] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E25F8 has callback 00007FFB04B38BF0
[ 0.809051] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.809482] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E25F8
[ 0.810013] [00001914] libusb: debug [libusb_alloc_transfer] transfer 0000018B1F1E2878
[ 0.810434] [00001914] libusb: debug [libusb_submit_transfer] transfer 0000018B1F1E2878
[ 0.815803] [00001914] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.816840] [00001914] libusb: debug [winusbx_submit_bulk_transfer] matched endpoint 81 with interface 0
[ 0.817312] [00001914] libusb: debug [winusbx_submit_bulk_transfer] reading 64 bytes
[ 0.817865] [00001914] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.817945] [000005e0] libusb: debug [windows_iocp_thread] transfer 0000018B1F1E2878 completed, length 64
[ 0.818263] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms
[ 0.819118] [00001914] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0
[ 0.819533] [00001914] libusb: debug [handle_event_trigger] event triggered
[ 0.819946] [00001914] libusb: debug [windows_handle_transfer_completion] handling transfer 0000018B1F1E2878 completion with errcode 0, length 64
[ 0.820376] [00001914] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.820844] [00001914] libusb: debug [usbi_handle_transfer_completion] transfer 0000018B1F1E2878 has callback 00007FFB04B38BF0
[ 0.821289] [00001914] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.821729] [00001914] libusb: debug [libusb_free_transfer] transfer 0000018B1F1E2878
HDX01168
[ 0.822713] [00001914] libusb: debug [libusb_release_interface] interface 0
[ 0.823206] [00001914] libusb: debug [libusb_reset_device]
[ 0.823649] [00001914] libusb: debug [libusb_close]
[ 0.824465] [00001914] libusb: debug [libusb_unref_device] destroy device 2.3
[ 0.824540] [00001914] libusb: debug [libusb_unref_device] destroy device 2.1
[ 0.824992] [00001914] libusb: debug [libusb_unref_device] destroy device 2.0
[ 0.825464] [00001914] libusb: debug [libusb_exit]
[ 0.825872] [00001914] libusb: debug [libusb_exit] destroying default context
[ 0.826475] [00001914] libusb: debug [usbi_remove_event_source] remove HANDLE 000000000000031C
[ 0.830112] [00001914] libusb: debug [usbi_remove_event_source] remove HANDLE 0000000000000118
[ 0.830770] [000005e0] libusb: debug [windows_iocp_thread] I/O completion thread exiting

[ap--]

Hi @stepandr47

hmm, I was hoping we'd see that the libusb version would differ between the two. As it is, it is probably a bit hard to compare. (the linux output you posted seems to be from an interactive session and the windows output lacks the pyusb debug info)

Could you save the following script on both your windows and linux machine and run it again and copy the full output of the script to this issue:

# test_open_hdx.py
import os
os.environ['LIBUSB_DEBUG'] = "4"
os.environ['PYUSB_DEBUG'] = "debug"

import seabreeze
seabreeze.use('pyseabreeze')
from seabreeze.spectrometers import Spectrometer

print("=== SPECTROMETER OPEN ===")
spec = Spectrometer.from_first_available()
print("=== SPECTROMETER STOP ===")
print(spec)

the following command should work in your linux shell AND in the windows cmd prompt:

python test_open_hdx.py > output.txt 2>&1

It should write all output to the output.txt file.

[stepandr47]

Linux Output:

2021-09-23 14:43:24,769 DEBUG:usb.backend.libusb1:_LibUSB.__init__(<CDLL '/home/ubuntu/anaconda3/envs/seabreeze-test/lib/libusb-1.0.so.0', handle 56411679e4c0 at 0x7fed8eccbdc0>)
[timestamp] [threadID] facility level [function call] <message>
--------------------------------------------------------------------------------
[ 0.000023] [0000e75a] libusb: debug [libusb_init] created default context
[ 0.000041] [0000e75a] libusb: debug [libusb_init] libusb v1.0.24.11584
[ 0.000054] [0000e75a] libusb: debug [get_kernel_version] reported kernel version is 5.11.0-34-generic
[ 0.000087] [0000e75a] libusb: debug [op_init] found usbfs at /dev/bus/usb
[ 0.000097] [0000e75a] libusb: debug [op_init] max iso packet length is (likely) 98304 bytes
[ 0.000109] [0000e75a] libusb: debug [op_init] sysfs is available
[ 0.000523] [0000e75e] libusb: debug [linux_udev_event_thread_main] udev event thread entering
[ 0.003073] [0000e75a] libusb: debug [linux_get_device_address] getting address for device: usb2 detached: 0
[ 0.003109] [0000e75a] libusb: debug [linux_get_device_address] scan usb2
[ 0.003155] [0000e75a] libusb: debug [linux_get_device_address] bus=2 dev=1
[ 0.003164] [0000e75a] libusb: debug [linux_enumerate_device] busnum 2 devaddr 1 session_id 513
[ 0.003172] [0000e75a] libusb: debug [linux_enumerate_device] allocating new device for 2/1 (session 513)
[ 0.003317] [0000e75a] libusb: debug [linux_get_device_address] getting address for device: 2-1 detached: 0
[ 0.003328] [0000e75a] libusb: debug [linux_get_device_address] scan 2-1
[ 0.003364] [0000e75a] libusb: debug [linux_get_device_address] bus=2 dev=2
[ 0.003373] [0000e75a] libusb: debug [linux_enumerate_device] busnum 2 devaddr 2 session_id 514
[ 0.003380] [0000e75a] libusb: debug [linux_enumerate_device] allocating new device for 2/2 (session 514)
[ 0.003417] [0000e75a] libusb: debug [linux_get_parent_info] dev 0x56411658ae10 (2-1) has parent 0x5641165ca140 (usb2) port 1
[ 0.003508] [0000e75a] libusb: debug [linux_get_device_address] getting address for device: 2-2 detached: 0
[ 0.003519] [0000e75a] libusb: debug [linux_get_device_address] scan 2-2
[ 0.003554] [0000e75a] libusb: debug [linux_get_device_address] bus=2 dev=3
[ 0.003563] [0000e75a] libusb: debug [linux_enumerate_device] busnum 2 devaddr 3 session_id 515
[ 0.003570] [0000e75a] libusb: debug [linux_enumerate_device] allocating new device for 2/3 (session 515)
[ 0.003611] [0000e75a] libusb: debug [linux_get_parent_info] dev 0x56411631bb20 (2-2) has parent 0x5641165ca140 (usb2) port 2
[ 0.003710] [0000e75a] libusb: debug [linux_get_device_address] getting address for device: 2-2.1 detached: 0
[ 0.003720] [0000e75a] libusb: debug [linux_get_device_address] scan 2-2.1
[ 0.003757] [0000e75a] libusb: debug [linux_get_device_address] bus=2 dev=13
[ 0.003766] [0000e75a] libusb: debug [linux_enumerate_device] busnum 2 devaddr 13 session_id 525
[ 0.003773] [0000e75a] libusb: debug [linux_enumerate_device] allocating new device for 2/13 (session 525)
[ 0.003813] [0000e75a] libusb: debug [linux_get_parent_info] dev 0x564116543fe0 (2-2.1) has parent 0x56411631bb20 (2-2) port 1
[ 0.003902] [0000e75a] libusb: debug [linux_get_device_address] getting address for device: usb1 detached: 0
[ 0.003913] [0000e75a] libusb: debug [linux_get_device_address] scan usb1
[ 0.003949] [0000e75a] libusb: debug [linux_get_device_address] bus=1 dev=1
[ 0.003958] [0000e75a] libusb: debug [linux_enumerate_device] busnum 1 devaddr 1 session_id 257
[ 0.003965] [0000e75a] libusb: debug [linux_enumerate_device] allocating new device for 1/1 (session 257)
[ 0.004083] [0000e75a] libusb: debug [linux_get_device_address] getting address for device: 1-1 detached: 0
[ 0.004093] [0000e75a] libusb: debug [linux_get_device_address] scan 1-1
[ 0.004129] [0000e75a] libusb: debug [linux_get_device_address] bus=1 dev=28
[ 0.004138] [0000e75a] libusb: debug [linux_enumerate_device] busnum 1 devaddr 28 session_id 284
[ 0.004145] [0000e75a] libusb: debug [linux_enumerate_device] allocating new device for 1/28 (session 284)
[ 0.004201] [0000e75a] libusb: debug [linux_get_parent_info] dev 0x56411660c9d0 (1-1) has parent 0x5641165440f0 (usb1) port 1
[ 0.004277] [0000e75a] libusb: debug [usbi_add_event_source] add fd 6 events 1
[ 0.004291] [0000e75a] libusb: debug [usbi_io_init] using timer for timeouts
[ 0.004298] [0000e75a] libusb: debug [usbi_add_event_source] add fd 7 events 1
2021-09-23 14:43:24,773 INFO:usb.core:find(): using backend "usb.backend.libusb1"
2021-09-23 14:43:24,774 DEBUG:usb.backend.libusb1:_LibUSB.enumerate_devices()
[ 0.004848] [0000e75a] libusb: debug [libusb_get_device_list]  
2021-09-23 14:43:24,774 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304acd0>)
[ 0.005061] [0000e75a] libusb: debug [libusb_get_device_descriptor]  
2021-09-23 14:43:24,774 DEBUG:usb.backend.libusb1:_LibUSB.open_device(<usb.backend.libusb1._Device object at 0x7fed8304acd0>)
[ 0.005365] [0000e75a] libusb: debug [libusb_open] open 1.28
[ 0.005404] [0000e75a] libusb: debug [usbi_add_event_source] add fd 8 events 4
2021-09-23 14:43:24,775 DEBUG:usb.backend.libusb1:_LibUSB.reset_device(<usb.backend.libusb1._DeviceHandle object at 0x7fed8304aeb0>)
[ 0.005526] [0000e75a] libusb: debug [libusb_reset_device]  
2021-09-23 14:43:25,246 DEBUG:usb.backend.libusb1:_LibUSB.close_device(<usb.backend.libusb1._DeviceHandle object at 0x7fed8304aeb0>)
[ 0.478259] [0000e75a] libusb: debug [libusb_close]  
[ 0.478361] [0000e75a] libusb: debug [usbi_remove_event_source] remove fd 8
2021-09-23 14:43:25,248 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304af10>)
[ 0.479477] [0000e75a] libusb: debug [libusb_get_device_descriptor]  
2021-09-23 14:43:25,249 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7fed8305e0a0>)
[ 0.480537] [0000e75a] libusb: debug [libusb_get_device_descriptor]  
2021-09-23 14:43:25,250 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304af70>)
[ 0.481675] [0000e75a] libusb: debug [libusb_get_device_descriptor]  
2021-09-23 14:43:25,251 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7fed8305e100>)
[ 0.482907] [0000e75a] libusb: debug [libusb_get_device_descriptor]  
2021-09-23 14:43:25,253 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304afd0>)
[ 0.483987] [0000e75a] libusb: debug [libusb_get_device_descriptor]  
2021-09-23 14:43:25,254 DEBUG:usb.backend.libusb1:_LibUSB.open_device(<usb.backend.libusb1._Device object at 0x7fed8304acd0>)
[ 0.485518] [0000e75a] libusb: debug [libusb_open] open 1.28
[ 0.485698] [0000e75a] libusb: debug [usbi_add_event_source] add fd 8 events 4
2021-09-23 14:43:25,255 DEBUG:usb.backend.libusb1:_LibUSB.reset_device(<usb.backend.libusb1._DeviceHandle object at 0x7fed8ee48760>)
[ 0.486583] [0000e75a] libusb: debug [libusb_reset_device]  
2021-09-23 14:43:25,733 DEBUG:usb.backend.libusb1:_LibUSB.close_device(<usb.backend.libusb1._DeviceHandle object at 0x7fed8ee48760>)
[ 0.964215] [0000e75a] libusb: debug [libusb_close]  
[ 0.964255] [0000e75a] libusb: debug [usbi_remove_event_source] remove fd 8
2021-09-23 14:43:25,734 INFO:usb.core:find(): using backend "usb.backend.libusb1"
2021-09-23 14:43:25,734 DEBUG:usb.backend.libusb1:_LibUSB.enumerate_devices()
[ 0.965012] [0000e75a] libusb: debug [libusb_get_device_list]  
2021-09-23 14:43:25,734 DEBUG:usb.backend.libusb1:_LibUSB.get_device_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>)
[ 0.965322] [0000e75a] libusb: debug [libusb_get_device_descriptor]  
2021-09-23 14:43:25,735 DEBUG:usb.backend.libusb1:_LibUSB.open_device(<usb.backend.libusb1._Device object at 0x7fed8304ad60>)
[ 0.965955] [0000e75a] libusb: debug [libusb_open] open 1.28
[ 0.966034] [0000e75a] libusb: debug [usbi_add_event_source] add fd 8 events 4
2021-09-23 14:43:25,735 DEBUG:usb.backend.libusb1:_LibUSB.is_kernel_driver_active(<usb.backend.libusb1._DeviceHandle object at 0x7fed8304ac70>, 0)
[ 0.966239] [0000e75a] libusb: debug [libusb_kernel_driver_active] interface 0
2021-09-23 14:43:25,735 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0)
[ 0.966505] [0000e75a] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-23 14:43:25,736 DEBUG:usb.backend.libusb1:_LibUSB.set_configuration(<usb.backend.libusb1._DeviceHandle object at 0x7fed8304ac70>, 1)
[ 0.967037] [0000e75a] libusb: debug [libusb_set_configuration] configuration 1
2021-09-23 14:43:25,753 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0)
[ 0.983753] [0000e75a] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-23 14:43:25,753 DEBUG:usb.backend.libusb1:_LibUSB.get_interface_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0, 0, 0)
2021-09-23 14:43:25,753 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0)
[ 0.984297] [0000e75a] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-23 14:43:25,754 DEBUG:usb.backend.libusb1:_LibUSB.get_endpoint_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0, 0, 0, 0)
2021-09-23 14:43:25,754 DEBUG:usb.backend.libusb1:_LibUSB.get_interface_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0, 0, 0)
2021-09-23 14:43:25,754 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0)
[ 0.985048] [0000e75a] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-23 14:43:25,754 DEBUG:usb.backend.libusb1:_LibUSB.claim_interface(<usb.backend.libusb1._DeviceHandle object at 0x7fed8304ac70>, 0)
[ 0.985448] [0000e75a] libusb: debug [libusb_claim_interface] interface 0
2021-09-23 14:43:25,755 DEBUG:usb.backend.libusb1:_LibUSB.bulk_write(<usb.backend.libusb1._DeviceHandle object at 0x7fed8304ac70>, 1, 0, array('B', [193, 192, 0, 17, 0, 0, 0, 0, 1, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 20, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 197, 196, 195, 194]), 1000)
[ 0.985989] [0000e75a] libusb: debug [libusb_alloc_transfer] transfer 0x56411670f858
[ 0.986018] [0000e75a] libusb: debug [libusb_submit_transfer] transfer 0x56411670f858
[ 0.986033] [0000e75a] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.986059] [0000e75a] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 0.986096] [0000e75a] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.986113] [0000e75a] libusb: debug [handle_events] event sources modified, reallocating event data
[ 0.986134] [0000e75a] libusb: debug [usbi_wait_for_events] poll() 3 fds with timeout in 60000ms
[ 0.988468] [0000e75a] libusb: debug [usbi_wait_for_events] poll() returned 1
[ 0.988524] [0000e75a] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=64
[ 0.988542] [0000e75a] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.988556] [0000e75a] libusb: debug [handle_bulk_completion] all URBs in transfer reaped --> complete!
[ 0.988571] [0000e75a] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 0.988588] [0000e75a] libusb: debug [usbi_handle_transfer_completion] transfer 0x56411670f858 has callback 0x7fed83033b90
[ 0.988604] [0000e75a] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.988621] [0000e75a] libusb: debug [libusb_free_transfer] transfer 0x56411670f858
2021-09-23 14:43:25,758 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0)
[ 0.989063] [0000e75a] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-23 14:43:25,758 DEBUG:usb.backend.libusb1:_LibUSB.get_interface_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0, 0, 0)
2021-09-23 14:43:25,759 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0)
[ 0.989620] [0000e75a] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-23 14:43:25,759 DEBUG:usb.backend.libusb1:_LibUSB.get_endpoint_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0, 0, 0, 0)
2021-09-23 14:43:25,760 DEBUG:usb.backend.libusb1:_LibUSB.get_interface_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0, 0, 0)
2021-09-23 14:43:25,760 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0)
[ 0.991089] [0000e75a] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-23 14:43:25,760 DEBUG:usb.backend.libusb1:_LibUSB.get_endpoint_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 1, 0, 0, 0)
2021-09-23 14:43:25,761 DEBUG:usb.backend.libusb1:_LibUSB.get_interface_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0, 0, 0)
2021-09-23 14:43:25,761 DEBUG:usb.backend.libusb1:_LibUSB.get_configuration_descriptor(<usb.backend.libusb1._Device object at 0x7fed8304ad60>, 0)
[ 0.991930] [0000e75a] libusb: debug [libusb_get_config_descriptor] index 0
2021-09-23 14:43:25,761 DEBUG:usb.backend.libusb1:_LibUSB.bulk_read(<usb.backend.libusb1._DeviceHandle object at 0x7fed8304ac70>, 129, 0, array('B', [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]), 1000)
[ 0.992458] [0000e75a] libusb: debug [libusb_alloc_transfer] transfer 0x5641166c80d8
[ 0.992486] [0000e75a] libusb: debug [libusb_submit_transfer] transfer 0x5641166c80d8
[ 0.992500] [0000e75a] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line)
[ 0.992525] [0000e75a] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 0.992566] [0000e75a] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.992582] [0000e75a] libusb: debug [usbi_wait_for_events] poll() 3 fds with timeout in 60000ms
[ 1.993297] [0000e75a] libusb: debug [usbi_wait_for_events] poll() returned 1
[ 1.993530] [0000e75a] libusb: debug [libusb_cancel_transfer] transfer 0x5641166c80d8
[ 1.997455] [0000e75a] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 1.997755] [0000e75a] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 1.997833] [0000e75a] libusb: debug [usbi_wait_for_events] poll() 3 fds with timeout in 60000ms
[ 1.997877] [0000e75a] libusb: debug [usbi_wait_for_events] poll() returned 1
[ 1.997917] [0000e75a] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 1.997946] [0000e75a] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 1.997972] [0000e75a] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 1.998197] [0000e75a] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 1.998255] [0000e75a] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 1.998281] [0000e75a] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer
[ 1.998311] [0000e75a] libusb: debug [usbi_handle_transfer_completion] transfer 0x5641166c80d8 has callback 0x7fed83033b90
[ 1.998338] [0000e75a] libusb: debug [sync_transfer_cb] actual_length=0
[ 1.998371] [0000e75a] libusb: debug [libusb_free_transfer] transfer 0x5641166c80d8
=== SPECTROMETER OPEN ===
Traceback (most recent call last):
  File "/home/ubuntu/Documents/test_open_hdx.py", line 11, in <module>
    spec = Spectrometer.from_first_available()
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/spectrometers.py", line 87, in from_first_available
    for dev in list_devices():
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/spectrometers.py", line 36, in list_devices
    return list_devices._api.list_devices()
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/api.py", line 104, in list_devices
    dev.open()
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/devices.py", line 353, in open
    self._serial_number = self.get_serial_number()
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/devices.py", line 393, in get_serial_number
    serial_len = ord(protocol.query(0x00000101))
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/protocol.py", line 372, in query
    return self.receive(timeout_ms=timeout_ms)
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/protocol.py", line 313, in receive
    response = self.transport.read(size=64, timeout_ms=timeout_ms)
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/seabreeze/pyseabreeze/transport.py", line 250, in read
    return self._device.pyusb_device.read(endpoint, size, timeout=timeout_ms)
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/usb/core.py", line 1029, in read
    ret = fn(
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/usb/_debug.py", line 62, in do_trace
    return f(*args, **named_args)
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/usb/backend/libusb1.py", line 846, in bulk_read
    return self.__read(self.lib.libusb_bulk_transfer,
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/usb/backend/libusb1.py", line 954, in __read
    _check(retval)
  File "/home/ubuntu/anaconda3/envs/seabreeze-test/lib/python3.9/site-packages/usb/backend/libusb1.py", line 602, in _check
    raise USBTimeoutError(_strerror(ret), ret, _libusb_errno[ret])
usb.core.USBTimeoutError: [Errno 110] Operation timed out
2021-09-23 14:43:26,794 DEBUG:usb.backend.libusb1:_LibUSB.release_interface(<usb.backend.libusb1._DeviceHandle object at 0x7fed8304ac70>, 0)
[ 2.024823] [0000e75a] libusb: debug [libusb_release_interface] interface 0
2021-09-23 14:43:26,794 DEBUG:usb.backend.libusb1:_LibUSB.close_device(<usb.backend.libusb1._DeviceHandle object at 0x7fed8304ac70>)
[ 2.025427] [0000e75a] libusb: debug [libusb_close]  
[ 2.025481] [0000e75a] libusb: debug [usbi_remove_event_source] remove fd 8
2021-09-23 14:43:26,795 DEBUG:usb.backend.libusb1:_LibUSB._finalize_object()
[ 2.025910] [0000e75a] libusb: debug [libusb_exit]  
[ 2.025953] [0000e75a] libusb: debug [libusb_exit] destroying default context
[ 2.026135] [0000e75a] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 2.026223] [0000e75a] libusb: debug [handle_events] event sources modified, reallocating event data
[ 2.026265] [0000e75a] libusb: debug [usbi_wait_for_events] poll() 2 fds with timeout in 0ms
[ 2.026299] [0000e75a] libusb: debug [usbi_wait_for_events] poll() returned 0
[ 2.026324] [0000e75a] libusb: debug [libusb_unref_device] destroy device 1.28
[ 2.026351] [0000e75a] libusb: debug [libusb_unref_device] destroy device 1.1
[ 2.026377] [0000e75a] libusb: debug [libusb_unref_device] destroy device 2.13
[ 2.026403] [0000e75a] libusb: debug [libusb_unref_device] destroy device 2.3
[ 2.026430] [0000e75a] libusb: debug [libusb_unref_device] destroy device 2.2
[ 2.026455] [0000e75a] libusb: debug [libusb_unref_device] destroy device 2.1
[ 2.026481] [0000e75a] libusb: debug [usbi_remove_event_source] remove fd 7
[ 2.026527] [0000e75a] libusb: debug [usbi_remove_event_source] remove fd 6
[ 2.026828] [0000e75e] libusb: debug [linux_udev_event_thread_main] udev event thread exiting