avrdudes / avrdude

AVRDUDE is a utility to program AVR microcontrollers
GNU General Public License v2.0
704 stars 136 forks source link

Move away from libusb-0.1 and libusb-win32 #974

Closed mcuee closed 2 years ago

mcuee commented 2 years ago

It will be good to move away from libusb-0.1 (or libusb-compat-0.1) from Linux, macOS, FreeBSD, etc. It is also good to move away from libusb-win32 under Windows.

Main affected file. dfu.h usb_libusb.c micronucleus.c pickti2.c [better move to hidapi and not libusb-1.0] usbasp.c usbtiny.c

Depending on the approaches used, it could affect more files as well like jtag3.c, jtagmkII.c and stk500v2.c.

Earlier attempts for stk500v2 and related programers.

mcuee commented 2 years ago

Right now avrdude under Windows is using avrdude-libwinusb which is good to support both libusb0.sys and WinUSB. It has a limitation though -- some issues with USB Composite Device support.

Long term solution is to use libusb-1.0 as per @mariusgreuel, but probably after libusb Windows supports get better with libusb0.sys. Ref:

mcuee commented 2 years ago

@dl8dtl mentioned below. https://github.com/avrdudes/avrdude/issues/906#issuecomment-1120433920

  1. I will recommend that avrdude to ditch the legacy libusb-0.1 API altogether (including libusb-win32) and move to libusb-1.0 API.

Unlikely to happen. libusb-0.1 "just works", and it would need volunteers for such a change, to rewrite a lot of code – and test all this in the end, on all supported platforms, with all affected programmers. In the end, doing all that work for no gain other than replacing one API by another one, i.e. there won't be any improvements for end-users by that change.

I see that usbasp already has the support of libusb-1.0 if it is present and otherwise fall back to libusb-0.1 API . So one way is to replace one by one if there are developers who are interested to create the patch for libusb-1.0 API, using the approach of usbasp.

Some candidates which may affect less users: libftdi based programmers -- it is probably good to move away from libftdi-0.1 and move to libftdi-1.0 (which is based on libusb-1.0).

It seems okay to use libusb0.sys and libusb-1.0.26 and libftdi-1.0 together with avrdude under Windows. I tested with Amontec JTAGKey-2. https://github.com/avrdudes/avrdude/issues/975#issuecomment-1149539658

There is also a concern about the libusb-1.0 support of libusb0.sys, which is the motivation of avrdude-libwinusb.

I will test out libusb0.sys and libusb-1.0.26 with usbasp later.

mcuee commented 2 years ago

usbasp test with official Windows binary which is using avrdude-libwinusb and not libusb-1.0. It supports libusb0.sys and WinUSB. It actually supports libusbk.sys as well (since libusbk.sys supports most of the driver ioctl codes from libusb0.sys)

Run log using libusbk.sys.

PS C:\work\avr\avrdude_test\avrdude-7.0_bin64> C:\libusbK-3.1.0.0-bin\bin\exe\amd64\kList.exe

Loading USB ID's maintained by Stephen J. Gowdy <linux.usb.ids@gmail.com>..

 1. USBasp (VOTI) [Connected]
    Service              : libusbK
    ClassGUID            : {ECFB0CFD-74C4-4F52-BBF7-343461CD72AC}
    DeviceID             : USB\VID_16C0&PID_05DC\5&586B51A&0&1
    DeviceInterfaceGUID  : {D32EDB66-69FC-01DC-03DD-20936E7318C2}
    SymbolicLink         : \\?\usb#vid_16c0&pid_05dc#5&586b51a&0&1#{d32edb66-69fc-01dc-03dd-20936e7318c2}
    DevicePath           : \\?\usb#vid_16c0&pid_05dc#5&586b51a&0&1#{d32edb66-69fc-01dc-03dd-20936e7318c2}
    SerialNumber         : 5&586B51A&0&1
    BusNumber            : 0
    DeviceAddress        : 1

Select device (1-1) :1

Loading driver api..
Getting descriptors..

-Device:
  bLength             :18
  bDescriptorType     :0x01
  bcdUSB              :0x0110
  bDeviceClass        :0xFF (Vendor Specific Class)
  bDeviceSubClass     :0x00
  bDeviceProtocol     :0x00
  bMaxPacketSize0     :8
  idVendor            :0x16C0 (Van Ooijen Technische Informatica)
  idProduct           :0x05DC (shared ID for use with libusb)
  bcdDevice           :0x0102
  iManufacturer       :1 (www.fischl.de)
  iProduct            :2 (USBasp)
  iSerialNumber       :0
  bNumConfigurations  :1
!End Device
-Configuration:
  bLength             :9
  bDescriptorType     :0x02
  wTotalLength        :18
  bNumInterfaces      :1
  bConfigurationValue :0x01
  iConfiguration      :0
  bmAttributes        :0x80
  MaxPower            :25 (50ma)
 -Interface:
    bLength             :9
    bDescriptorType     :0x04
    bInterfaceNumber    :0x00
    bAlternateSetting   :0x00
    bNumEndpoints       :0
    bInterfaceClass     :0x00 (Defined at Interface level)
    bInterfaceSubClass  :0x00
    bInterfaceProtocol  :0x00
    iInterface          :0
 !End Interface
!End Configuration
-MSOSV1:
  bDescriptorType     :0x00
  bLength             :00
  bMS_VendorCode      :0x00
  qwSignature         :
 -Extended Compatible IDs:
    dwLength            :0
    bcdVersion          :0x0000
    wIndex              :0
    bCount              :0
 !End Extended Compatible IDs
!End MSOSV1
-BOS:
  bDescriptorType     :0x00
  bLength             :10
  wTotalLength        :0
  bNumDeviceCapabilities:0
!End BOS

PS C:\work\avr\avrdude_test\avrdude-v7.0-windows-x64> .\avrdude.exe -c usbasp -p m328p -U flash:w:.\Blink.ino.with_bootloader.standard.hex:i -v

avrdude.exe: Version 7.0
             Copyright (c) Brian Dean, http://www.bdmicro.com/
             Copyright (c) Joerg Wunsch

             System wide configuration file is "C:/work/avr/avrdude_test/avrdude-v7.0-windows-x64/avrdude.conf"

             Using Port                    : usb
             Using Programmer              : usbasp
             AVR Part                      : ATmega328P
             Chip Erase delay              : 9000 us
             PAGEL                         : PD7
             BS2                           : PC2
             RESET disposition             : dedicated
             RETRY pulse                   : SCK
             Serial program mode           : yes
             Parallel program mode         : yes
             Timeout                       : 200
             StabDelay                     : 100
             CmdexeDelay                   : 25
             SyncLoops                     : 32
             PollIndex                     : 3
             PollValue                     : 0x53
             Memory Detail                 :

                                               Block Poll               Page                       Polled
               Memory Type Alias    Mode Delay Size  Indx Paged  Size   Size #Pages MinW  MaxW   ReadBack
               ----------- -------- ---- ----- ----- ---- ------ ------ ---- ------ ----- ----- ---------
               eeprom                 65    20     4    0 no       1024    4      0  3600  3600 0xff 0xff
               flash                  65     6   128    0 yes     32768  128    256  4500  4500 0xff 0xff
               lfuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
               hfuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
               efuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
               lock                    0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
               calibration             0     0     0    0 no          1    1      0     0     0 0x00 0x00
               signature               0     0     0    0 no          3    1      0     0     0 0x00 0x00

             Programmer Type : usbasp
             Description     : USBasp, http://www.fischl.de/usbasp/

avrdude.exe: auto set sck period (because given equals null)
avrdude.exe: warning: cannot set sck period. please check for usbasp firmware update.
avrdude.exe: AVR device initialized and ready to accept instructions

Reading | ################################################## | 100% 0.00s

avrdude.exe: Device signature = 0x1e950f (probably m328p)
avrdude.exe: NOTE: "flash" memory has been specified, an erase cycle will be performed
             To disable this feature, specify the -D option.
avrdude.exe: erasing chip
avrdude.exe: auto set sck period (because given equals null)
avrdude.exe: warning: cannot set sck period. please check for usbasp firmware update.
avrdude.exe: reading input file ".\Blink.ino.with_bootloader.standard.hex"
avrdude.exe: writing flash (32768 bytes):

Writing | ################################################## | 100% 0.65s

avrdude.exe: 32768 bytes of flash written
avrdude.exe: verifying flash memory against .\Blink.ino.with_bootloader.standard.hex:

Reading | ################################################## | 100% 0.52s

avrdude.exe: 32768 bytes of flash verified

avrdude.exe done.  Thank you.
mcuee commented 2 years ago

Testing usbasp with MSYS2 binary using libusb0.sys and libusb-1.0.26.

PS C:\work\avr\avrdude_test\avrdude-7.0_bin64> C:\libusbK-3.1.0.0-bin\bin\exe\amd64\kList.exe

Loading USB ID's maintained by Stephen J. Gowdy <linux.usb.ids@gmail.com>..

 1. USBasp (VOTI) [Connected]
    Service              : libusb0
    ClassGUID            : {EB781AAF-9C70-4523-A5DF-642A87ECA567}
    DeviceID             : USB\VID_16C0&PID_05DC\5&586B51A&0&1
    DeviceInterfaceGUID  : {B3F4C5CA-2A80-9D6A-1A90-D7BADF35524F}
    SymbolicLink         : \\?\usb#vid_16c0&pid_05dc#5&586b51a&0&1#{b3f4c5ca-2a80-9d6a-1a90-d7badf35524f}
    DevicePath           : \\.\libusb0-0001
    SerialNumber         : 5&586B51A&0&1
    BusNumber            : 0
    DeviceAddress        : 1

Select device (1-1) :1

Loading driver api..
Getting descriptors..

-Device:
  bLength             :18
  bDescriptorType     :0x01
  bcdUSB              :0x0110
  bDeviceClass        :0xFF (Vendor Specific Class)
  bDeviceSubClass     :0x00
  bDeviceProtocol     :0x00
  bMaxPacketSize0     :8
  idVendor            :0x16C0 (Van Ooijen Technische Informatica)
  idProduct           :0x05DC (shared ID for use with libusb)
  bcdDevice           :0x0102
  iManufacturer       :1 (www.fischl.de)
  iProduct            :2 (USBasp)
  iSerialNumber       :0
  bNumConfigurations  :1
!End Device
-Configuration:
  bLength             :9
  bDescriptorType     :0x02
  wTotalLength        :18
  bNumInterfaces      :1
  bConfigurationValue :0x01
  iConfiguration      :0
  bmAttributes        :0x80
  MaxPower            :25 (50ma)
 -Interface:
    bLength             :9
    bDescriptorType     :0x04
    bInterfaceNumber    :0x00
    bAlternateSetting   :0x00
    bNumEndpoints       :0
    bInterfaceClass     :0x00 (Defined at Interface level)
    bInterfaceSubClass  :0x00
    bInterfaceProtocol  :0x00
    iInterface          :0
 !End Interface
!End Configuration
-MSOSV1:
  bDescriptorType     :0x00
  bLength             :00
  bMS_VendorCode      :0x00
  qwSignature         :
 -Extended Compatible IDs:
    dwLength            :0
    bcdVersion          :0x0000
    wIndex              :0
    bCount              :0
 !End Extended Compatible IDs
!End MSOSV1

PS C:\work\avr\avrdude_test\avrdude-7.0_bin64> $Env:LIBUSB_DEBUG=3
PS C:\work\avr\avrdude_test\avrdude-7.0_bin64> .\avrdude_shared.exe -c usbasp -p m328p -U flash:w:.\Blink.ino.with_bootloader.standard.hex:i -v

avrdude_shared.exe: Version 7.0
                    Copyright (c) Brian Dean, http://www.bdmicro.com/
                    Copyright (c) Joerg Wunsch

                    System wide configuration file is "C:/work/avr/avrdude_test/avrdude-7.0_bin64/avrdude.conf"

                    Using Port                    : usb
                    Using Programmer              : usbasp
libusb: info [winusbx_init] WinUSB DLL available (with isoch support)
libusb: info [windows_init] UsbDk backend is not available
libusb: warning [libusb_init] installing new context as implicit default
                    AVR Part                      : ATmega328P
                    Chip Erase delay              : 9000 us
                    PAGEL                         : PD7
                    BS2                           : PC2
                    RESET disposition             : dedicated
                    RETRY pulse                   : SCK
                    Serial program mode           : yes
                    Parallel program mode         : yes
                    Timeout                       : 200
                    StabDelay                     : 100
                    CmdexeDelay                   : 25
                    SyncLoops                     : 32
                    PollIndex                     : 3
                    PollValue                     : 0x53
                    Memory Detail                 :

                                                      Block Poll               Page                       Polled
                      Memory Type Alias    Mode Delay Size  Indx Paged  Size   Size #Pages MinW  MaxW   ReadBack
                      ----------- -------- ---- ----- ----- ---- ------ ------ ---- ------ ----- ----- ---------
                      eeprom                 65    20     4    0 no       1024    4      0  3600  3600 0xff 0xff
                      flash                  65     6   128    0 yes     32768  128    256  4500  4500 0xff 0xff
                      lfuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      hfuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      efuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      lock                    0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      calibration             0     0     0    0 no          1    1      0     0     0 0x00 0x00
                      signature               0     0     0    0 no          3    1      0     0     0 0x00 0x00

                    Programmer Type : usbasp
                    Description     : USBasp, http://www.fischl.de/usbasp/

avrdude_shared.exe: auto set sck period (because given equals null)
avrdude_shared.exe: warning: cannot set sck period. please check for usbasp firmware update.
avrdude_shared.exe: AVR device initialized and ready to accept instructions

Reading | ################################################## | 100% 0.01s

avrdude_shared.exe: Device signature = 0x1e950f (probably m328p)
avrdude_shared.exe: NOTE: "flash" memory has been specified, an erase cycle will be performed
                    To disable this feature, specify the -D option.
avrdude_shared.exe: erasing chip
avrdude_shared.exe: auto set sck period (because given equals null)
avrdude_shared.exe: warning: cannot set sck period. please check for usbasp firmware update.
avrdude_shared.exe: reading input file ".\Blink.ino.with_bootloader.standard.hex"
avrdude_shared.exe: writing flash (32768 bytes):

Writing | ################################################## | 100% 0.73s

avrdude_shared.exe: 32768 bytes of flash written
avrdude_shared.exe: verifying flash memory against .\Blink.ino.with_bootloader.standard.hex:

Reading | ################################################## | 100% 0.59s

avrdude_shared.exe: 32768 bytes of flash verified

avrdude_shared.exe done.  Thank you.
mcuee commented 2 years ago

Testing usbasp with MSYS2 binary using libusbk.sys and libusb-1.0.26.

PS C:\work\avr\avrdude_test\avrdude-7.0_bin64> C:\libusbK-3.1.0.0-bin\bin\exe\amd64\kList.exe

Loading USB ID's maintained by Stephen J. Gowdy <linux.usb.ids@gmail.com>..

 1. USBasp (VOTI) [Connected]
    Service              : libusbK
    ClassGUID            : {ECFB0CFD-74C4-4F52-BBF7-343461CD72AC}
    DeviceID             : USB\VID_16C0&PID_05DC\5&586B51A&0&1
    DeviceInterfaceGUID  : {D32EDB66-69FC-01DC-03DD-20936E7318C2}
    SymbolicLink         : \\?\usb#vid_16c0&pid_05dc#5&586b51a&0&1#{d32edb66-69fc-01dc-03dd-20936e7318c2}
    DevicePath           : \\?\usb#vid_16c0&pid_05dc#5&586b51a&0&1#{d32edb66-69fc-01dc-03dd-20936e7318c2}
    SerialNumber         : 5&586B51A&0&1
    BusNumber            : 0
    DeviceAddress        : 1

Select device (1-1) :1

Loading driver api..
Getting descriptors..

-Device:
  bLength             :18
  bDescriptorType     :0x01
  bcdUSB              :0x0110
  bDeviceClass        :0xFF (Vendor Specific Class)
  bDeviceSubClass     :0x00
  bDeviceProtocol     :0x00
  bMaxPacketSize0     :8
  idVendor            :0x16C0 (Van Ooijen Technische Informatica)
  idProduct           :0x05DC (shared ID for use with libusb)
  bcdDevice           :0x0102
  iManufacturer       :1 (www.fischl.de)
  iProduct            :2 (USBasp)
  iSerialNumber       :0
  bNumConfigurations  :1
!End Device
-Configuration:
  bLength             :9
  bDescriptorType     :0x02
  wTotalLength        :18
  bNumInterfaces      :1
  bConfigurationValue :0x01
  iConfiguration      :0
  bmAttributes        :0x80
  MaxPower            :25 (50ma)
 -Interface:
    bLength             :9
    bDescriptorType     :0x04
    bInterfaceNumber    :0x00
    bAlternateSetting   :0x00
    bNumEndpoints       :0
    bInterfaceClass     :0x00 (Defined at Interface level)
    bInterfaceSubClass  :0x00
    bInterfaceProtocol  :0x00
    iInterface          :0
 !End Interface
!End Configuration
-MSOSV1:
  bDescriptorType     :0x00
  bLength             :00
  bMS_VendorCode      :0x00
  qwSignature         :
 -Extended Compatible IDs:
    dwLength            :0
    bcdVersion          :0x0000
    wIndex              :0
    bCount              :0
 !End Extended Compatible IDs
!End MSOSV1
-BOS:
  bDescriptorType     :0x00
  bLength             :10
  wTotalLength        :0
  bNumDeviceCapabilities:0
!End BOS
PS C:\work\avr\avrdude_test\avrdude-7.0_bin64> .\avrdude_shared.exe -c usbasp -p m328p -U flash:w:.\Blink.ino.with_bootloader.standard.hex:i -v

avrdude_shared.exe: Version 7.0
                    Copyright (c) Brian Dean, http://www.bdmicro.com/
                    Copyright (c) Joerg Wunsch

                    System wide configuration file is "C:/work/avr/avrdude_test/avrdude-7.0_bin64/avrdude.conf"

                    Using Port                    : usb
                    Using Programmer              : usbasp
libusb: info [winusbx_init] WinUSB DLL available (with isoch support)
libusb: info [windows_init] UsbDk backend is not available
libusb: warning [libusb_init] installing new context as implicit default
                    AVR Part                      : ATmega328P
                    Chip Erase delay              : 9000 us
                    PAGEL                         : PD7
                    BS2                           : PC2
                    RESET disposition             : dedicated
                    RETRY pulse                   : SCK
                    Serial program mode           : yes
                    Parallel program mode         : yes
                    Timeout                       : 200
                    StabDelay                     : 100
                    CmdexeDelay                   : 25
                    SyncLoops                     : 32
                    PollIndex                     : 3
                    PollValue                     : 0x53
                    Memory Detail                 :

                                                      Block Poll               Page                       Polled
                      Memory Type Alias    Mode Delay Size  Indx Paged  Size   Size #Pages MinW  MaxW   ReadBack
                      ----------- -------- ---- ----- ----- ---- ------ ------ ---- ------ ----- ----- ---------
                      eeprom                 65    20     4    0 no       1024    4      0  3600  3600 0xff 0xff
                      flash                  65     6   128    0 yes     32768  128    256  4500  4500 0xff 0xff
                      lfuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      hfuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      efuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      lock                    0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      calibration             0     0     0    0 no          1    1      0     0     0 0x00 0x00
                      signature               0     0     0    0 no          3    1      0     0     0 0x00 0x00

                    Programmer Type : usbasp
                    Description     : USBasp, http://www.fischl.de/usbasp/

avrdude_shared.exe: auto set sck period (because given equals null)
avrdude_shared.exe: warning: cannot set sck period. please check for usbasp firmware update.
avrdude_shared.exe: AVR device initialized and ready to accept instructions

Reading | ################################################## | 100% 0.01s

avrdude_shared.exe: Device signature = 0x1e950f (probably m328p)
avrdude_shared.exe: NOTE: "flash" memory has been specified, an erase cycle will be performed
                    To disable this feature, specify the -D option.
avrdude_shared.exe: erasing chip
avrdude_shared.exe: auto set sck period (because given equals null)
avrdude_shared.exe: warning: cannot set sck period. please check for usbasp firmware update.
avrdude_shared.exe: reading input file ".\Blink.ino.with_bootloader.standard.hex"
avrdude_shared.exe: writing flash (32768 bytes):

Writing | ################################################## | 100% 0.72s

avrdude_shared.exe: 32768 bytes of flash written
avrdude_shared.exe: verifying flash memory against .\Blink.ino.with_bootloader.standard.hex:

Reading | ################################################## | 100% 0.59s

avrdude_shared.exe: 32768 bytes of flash verified

avrdude_shared.exe done.  Thank you.
mcuee commented 2 years ago

@mariusgreuel and @dioannidis

Basically my testing using the usbasp clone and Arduino Uno clone, it shows with the release of libusb-1.0.26, avrdude is okay with all three drivers: WinUSB, libusb0.sys and libusbk.sys.

The main problem of libusb0.sys with libusb-1.0 (especially in version 1.0.24) was sorted out in libusb-1.0.25 release. I believe there may be other issues with libusb0.sys and libusb-1.0, but for the purpose of avrdude, I tend to believe it works fine.

Hopefully more testing can be done by other users to check if libusb-1.0

I do not see any error messages from libusb-1.0.26 debug log.

detailed libusb-1.0.26 debug log ``` PS C:\work\avr\avrdude_test\avrdude-7.0_bin64> $Env:LIBUSB_DEBUG=4 PS C:\work\avr\avrdude_test\avrdude-7.0_bin64> .\avrdude_shared.exe -c usbasp -p m328p -U flash:w:.\Blink.ino.with_bootloader.standard.hex:i -v avrdude_shared.exe: Version 7.0 Copyright (c) Brian Dean, http://www.bdmicro.com/ Copyright (c) Joerg Wunsch System wide configuration file is "C:/work/avr/avrdude_test/avrdude-7.0_bin64/avrdude.conf" Using Port : usb Using Programmer : usbasp [timestamp] [threadID] facility level [function call] -------------------------------------------------------------------------------- [ 0.000125] [000048d8] libusb: debug [libusb_init] libusb v1.0.26.11724 [ 0.000171] [000048d8] libusb: debug [usbi_add_event_source] add HANDLE 00000000000000d8 events 0 [ 0.000247] [000048d8] libusb: debug [usbi_io_init] using timer for timeouts [ 0.000284] [000048d8] libusb: debug [usbi_add_event_source] add HANDLE 00000000000000dc events 0 [ 0.000329] [000048d8] libusb: debug [get_windows_version] Windows 11 64-bit [ 0.000359] [000048d8] libusb: debug [htab_create] using 1021 entries hash table [ 0.001873] [000048d8] libusb: info [winusbx_init] WinUSB DLL available (with isoch support) [ 0.003644] [000048d8] libusb: debug [winusbx_init] libusbK DLL found, version: 3.1.0.0 [ 0.005266] [000048d8] libusb: info [windows_init] UsbDk backend is not available [ 0.005360] [000048d8] libusb: warning [libusb_init] installing new context as implicit default [ 0.005414] [000048d8] libusb: debug [libusb_get_device_list] [ 0.005447] [00004be4] libusb: debug [windows_iocp_thread] I/O completion thread started [ 0.012309] [000048d8] libusb: debug [winusb_get_device_list] allocating new device for session [5] [ 0.012387] [000048d8] libusb: debug [winusb_get_device_list] allocating new device for session [6] [ 0.012444] [000048d8] libusb: debug [winusb_get_device_list] allocating new device for session [7] [ 0.012501] [000048d8] libusb: debug [winusb_get_device_list] allocating new device for session [8] [ 0.012892] [000048d8] libusb: debug [get_api_type] driver(s): CH341SER_A64 [ 0.012993] [000048d8] libusb: debug [winusb_get_device_list] allocating new device for session [9] [ 0.013141] [000048d8] libusb: debug [get_api_type] driver(s): WUDFRd [ 0.013202] [000048d8] libusb: debug [get_api_type] lower filter driver(s): WinUsb [ 0.013242] [000048d8] libusb: debug [get_api_type] matched lower filter driver name against WinUSB [ 0.013282] [000048d8] libusb: debug [winusb_get_device_list] allocating new device for session [21] [ 0.013424] [000048d8] libusb: debug [get_api_type] driver(s): BTHUSB [ 0.013486] [000048d8] libusb: debug [get_api_type] lower filter driver(s): ibtusb [ 0.013527] [000048d8] libusb: debug [winusb_get_device_list] allocating new device for session [35] [ 0.013709] [000048d8] libusb: debug [get_api_type] driver(s): usbccgp [ 0.013764] [000048d8] libusb: debug [get_api_type] matched driver name against Composite API [ 0.013804] [000048d8] libusb: debug [winusb_get_device_list] allocating new device for session [63] [ 0.013909] [000048d8] libusb: debug [get_api_type] driver(s): libusb0 [ 0.013965] [000048d8] libusb: debug [get_api_type] matched driver name against libusb0 [ 0.014002] [000048d8] libusb: debug [winusb_get_device_list] allocating new device for session [64] [ 0.014110] [000048d8] libusb: debug [get_api_type] driver(s): usbccgp [ 0.014165] [000048d8] libusb: debug [get_api_type] matched driver name against Composite API [ 0.014204] [000048d8] libusb: debug [winusb_get_device_list] allocating new device for session [65] [ 0.014380] [000048d8] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_8086&DEV_A0ED&SUBSYS_15451025&REV_20\3&11583659&0&A0' bus number 1 [ 0.014454] [000048d8] libusb: debug [enumerate_hcd_root_hub] assigning HCD 'PCI\VEN_8086&DEV_9A13&SUBSYS_72708086&REV_01\3&11583659&0&68' bus number 2 [ 0.015061] [000048d8] libusb: debug [winusb_get_device_list] found existing device for session [5] [ 0.015174] [000048d8] libusb: debug [init_device] found 1 configurations (current config: 1) [ 0.015225] [000048d8] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 31 bytes) [ 0.015278] [000048d8] libusb: debug [init_device] (bus: 2, addr: 1, depth: 1, port: 2): 'USB\VID_05E3&PID_0612\5&1A00B1C&0&2' [ 0.015557] [000048d8] libusb: debug [winusb_get_device_list] found existing device for session [35] [ 0.015642] [000048d8] libusb: debug [init_device] found 1 configurations (current config: 1) [ 0.015690] [000048d8] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 200 bytes) [ 0.015740] [000048d8] libusb: debug [init_device] (bus: 1, addr: 3, depth: 1, port: 10): 'USB\VID_8087&PID_0026\5&586B51A&0&10' [ 0.015834] [000048d8] libusb: debug [winusb_get_device_list] extra GUID: {16126A05-3179-4589-9DB8-952AFFD297D5} [ 0.015879] [000048d8] libusb: debug [winusb_get_device_list] found existing device for session [63] [ 0.015945] [000048d8] libusb: debug [init_device] found 1 configurations (current config: 1) [ 0.015991] [000048d8] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 512 bytes) [ 0.016038] [000048d8] libusb: debug [init_device] (bus: 1, addr: 1, depth: 1, port: 5): 'USB\VID_04F2&PID_B6DD\0001' [ 0.016118] [000048d8] libusb: debug [winusb_get_device_list] extra GUID: {20348E47-A897-468E-9D0E-56307802FC2A} [ 0.016158] [000048d8] libusb: debug [winusb_get_device_list] found existing device for session [21] [ 0.016221] [000048d8] libusb: debug [init_device] found 1 configurations (current config: 1) [ 0.016264] [000048d8] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 46 bytes) [ 0.016310] [000048d8] libusb: debug [init_device] (bus: 1, addr: 2, depth: 1, port: 7): 'USB\VID_1C7A&PID_0575\077E2F9A' [ 0.016393] [000048d8] libusb: debug [winusb_get_device_list] extra GUID: {B3F4C5CA-2A80-9D6A-1A90-D7BADF35524F} [ 0.016434] [000048d8] libusb: debug [winusb_get_device_list] found existing device for session [64] [ 0.016498] [000048d8] libusb: debug [init_device] found 1 configurations (current config: 1) [ 0.016541] [000048d8] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 18 bytes) [ 0.016587] [000048d8] libusb: debug [init_device] (bus: 1, addr: 16, depth: 1, port: 1): 'USB\VID_16C0&PID_05DC\5&586B51A&0&1' [ 0.016717] [000048d8] libusb: debug [winusb_get_device_list] found existing device for session [9] [ 0.016795] [000048d8] libusb: debug [init_device] found 1 configurations (current config: 1) [ 0.016840] [000048d8] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 39 bytes) [ 0.016889] [000048d8] libusb: debug [init_device] (bus: 1, addr: 10, depth: 2, port: 1): 'USB\VID_1A86&PID_7523\6&8694159&0&1' [ 0.017097] [000048d8] libusb: debug [init_root_hub] root hub 'USB\ROOT_HUB30\4&12C539F&0&0' reports 16 ports [ 0.017172] [000048d8] libusb: debug [init_device] (bus: 1, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB30\4&12C539F&0&0' [ 0.017328] [000048d8] libusb: debug [winusb_get_device_list] found existing device for session [8] [ 0.017533] [000048d8] libusb: debug [init_device] found 1 configurations (current config: 1) [ 0.017612] [000048d8] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 41 bytes) [ 0.017681] [000048d8] libusb: debug [init_device] (bus: 1, addr: 5, depth: 1, port: 2): 'USB\VID_05E3&PID_0610\5&586B51A&0&2' [ 0.017787] [000048d8] libusb: debug [winusb_get_device_list] found existing device for session [65] [ 0.017859] [000048d8] libusb: debug [init_device] found 1 configurations (current config: 1) [ 0.017906] [000048d8] libusb: debug [cache_config_descriptors] cached config descriptor 0 (bConfigurationValue=1, 84 bytes) [ 0.017956] [000048d8] libusb: debug [init_device] (bus: 1, addr: 4, depth: 1, port: 3): 'USB\VID_046D&PID_C52B\5&586B51A&0&3' [ 0.018156] [000048d8] libusb: debug [init_root_hub] root hub 'USB\ROOT_HUB30\4&1A63DC7C&0&0' reports 5 ports [ 0.018212] [000048d8] libusb: debug [init_device] (bus: 2, addr: 0, depth: 0, port: 0): 'USB\ROOT_HUB30\4&1A63DC7C&0&0' [ 0.018425] [000048d8] libusb: debug [winusb_get_device_list] unlisted ancestor for 'HID\SYNA7DAB&COL01\5&2F64DFEA&0&0000' (non USB HID, newly connected, etc.) - ignoring [ 0.018494] [000048d8] libusb: debug [winusb_get_device_list] setting composite interface for [65]: [ 0.018565] [000048d8] libusb: debug [set_composite_interface] interface[1] = \\?\HID#VID_046D&PID_C52B&MI_01&COL01#7&1119BFB4&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030} [ 0.018705] [000048d8] libusb: debug [winusb_get_device_list] unlisted ancestor for 'HID\INTC816\3&D2322F2&0&0000' (non USB HID, newly connected, etc.) - ignoring [ 0.018789] [000048d8] libusb: debug [winusb_get_device_list] unlisted ancestor for 'HID\SYNA7DAB&COL02\5&2F64DFEA&0&0001' (non USB HID, newly connected, etc.) - ignoring [ 0.018868] [000048d8] libusb: debug [winusb_get_device_list] unlisted ancestor for 'HID\SYNA7DAB&COL03\5&2F64DFEA&0&0002' (non USB HID, newly connected, etc.) - ignoring [ 0.018945] [000048d8] libusb: debug [winusb_get_device_list] unlisted ancestor for 'HID\SYNA7DAB&COL04\5&2F64DFEA&0&0003' (non USB HID, newly connected, etc.) - ignoring [ 0.019024] [000048d8] libusb: debug [winusb_get_device_list] unlisted ancestor for 'HID\CONVERTEDDEVICE&COL02\5&32CF90E6&0&0001' (non USB HID, newly connected, etc.) - ignoring [ 0.019093] [000048d8] libusb: debug [winusb_get_device_list] setting composite interface for [65]: [ 0.019136] [000048d8] libusb: debug [set_composite_interface] interface[1] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_01&COL02\7&1119BFB4&0&0001 [ 0.019216] [000048d8] libusb: debug [winusb_get_device_list] unlisted ancestor for 'HID\CONVERTEDDEVICE&COL03\5&32CF90E6&0&0002' (non USB HID, newly connected, etc.) - ignoring [ 0.019287] [000048d8] libusb: debug [winusb_get_device_list] setting composite interface for [65]: [ 0.019331] [000048d8] libusb: debug [set_composite_interface] interface[1] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_01&COL03\7&1119BFB4&0&0002 [ 0.019408] [000048d8] libusb: debug [winusb_get_device_list] unlisted ancestor for 'HID\10251229\3&9D5D338&0&0000' (non USB HID, newly connected, etc.) - ignoring [ 0.019486] [000048d8] libusb: debug [winusb_get_device_list] unlisted ancestor for 'HID\CONVERTEDDEVICE&COL01\5&32CF90E6&0&0000' (non USB HID, newly connected, etc.) - ignoring [ 0.019557] [000048d8] libusb: debug [winusb_get_device_list] setting composite interface for [65]: [ 0.019602] [000048d8] libusb: debug [set_composite_interface] interface[1] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_01&COL04\7&1119BFB4&0&0003 [ 0.019773] [000048d8] libusb: debug [winusb_get_device_list] setting composite interface for [65]: [ 0.019841] [000048d8] libusb: debug [set_composite_interface] interface[2] = \\?\HID#VID_046D&PID_C52B&MI_02&COL01#7&12BD7E0E&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030} [ 0.019916] [000048d8] libusb: debug [winusb_get_device_list] setting composite interface for [65]: [ 0.019962] [000048d8] libusb: debug [set_composite_interface] interface[2] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_02&COL02\7&12BD7E0E&0&0001 [ 0.020031] [000048d8] libusb: debug [winusb_get_device_list] setting composite interface for [65]: [ 0.020072] [000048d8] libusb: debug [set_composite_interface] interface[2] already set - ignoring HID collection: HID\VID_046D&PID_C52B&MI_02&COL03\7&12BD7E0E&0&0002 [ 0.020140] [000048d8] libusb: debug [winusb_get_device_list] setting composite interface for [65]: [ 0.020180] [000048d8] libusb: debug [set_composite_interface] interface[0] = \\?\HID#VID_046D&PID_C52B&MI_00#7&34F0FD76&0&0000#{4D1E55B2-F16F-11CF-88CB-001111000030}\KBD [ 0.020666] [000048d8] libusb: debug [get_api_type] driver(s): WUDFRd [ 0.020763] [000048d8] libusb: debug [get_api_type] lower filter driver(s): WinUsb [ 0.020804] [000048d8] libusb: debug [get_api_type] matched lower filter driver name against WinUSB [ 0.021230] [000048d8] libusb: debug [get_api_type] driver(s): libusb0 [ 0.021322] [000048d8] libusb: debug [get_api_type] matched driver name against libusb0 [ 0.021571] [000048d8] libusb: debug [libusb_get_device_descriptor] [ 0.021612] [000048d8] libusb: debug [libusb_get_device_descriptor] [ 0.021650] [000048d8] libusb: debug [libusb_get_device_descriptor] [ 0.021688] [000048d8] libusb: debug [libusb_get_device_descriptor] [ 0.021725] [000048d8] libusb: debug [libusb_get_device_descriptor] [ 0.021762] [000048d8] libusb: debug [libusb_open] open 1.16 [ 0.021824] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b38250 [ 0.021863] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line) [ 0.021906] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.021972] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.022025] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.022080] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.022122] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.022083] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.022225] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.022273] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.022319] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.022365] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.022562] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b38250, length 4 [ 0.022564] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfef00 for handle 00000296b5b37a50 (device 1.16) [ 0.022660] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b38250 completed, length 4 [ 0.022612] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.022751] [000048d8] libusb: debug [handle_events] event sources modified, reallocating event data [ 0.022790] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.022837] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.022880] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.022919] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b38250 completion with errcode 0, length 4 [ 0.022969] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.023009] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.023048] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.023090] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b38250 has callback 00007ffd46f28d00 [ 0.023136] [000048d8] libusb: debug [sync_transfer_cb] actual_length=4 [ 0.023175] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b38250 [ 0.023214] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b38250 [ 0.023252] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line) [ 0.023292] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.023346] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.023385] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.023430] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.023478] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.023433] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.023569] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.023616] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.023661] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.023706] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.024875] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b38250, length 28 [ 0.024919] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.024965] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.024876] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfef00 for handle 00000296b5b37a50 (device 1.16) [ 0.025066] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b38250 completed, length 28 [ 0.025110] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.025154] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.025193] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b38250 completion with errcode 0, length 28 [ 0.025241] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.025280] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.025456] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.025514] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b38250 has callback 00007ffd46f28d00 [ 0.025564] [000048d8] libusb: debug [sync_transfer_cb] actual_length=28 [ 0.025604] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b38250 [ 0.025644] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b38250 [ 0.025688] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line) [ 0.025729] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.025780] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.025820] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.025867] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.025910] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.025869] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.026004] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.026061] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.026108] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.026155] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.026576] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b38250, length 4 [ 0.026622] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.026666] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.026578] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfef00 for handle 00000296b5b37a50 (device 1.16) [ 0.026769] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b38250 completed, length 4 [ 0.026813] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.026857] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.026896] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b38250 completion with errcode 0, length 4 [ 0.026943] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.026982] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.027018] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.027060] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b38250 has callback 00007ffd46f28d00 [ 0.027106] [000048d8] libusb: debug [sync_transfer_cb] actual_length=4 [ 0.027144] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b38250 [ 0.027183] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b38250 [ 0.027221] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 1000ms (first in line) [ 0.027261] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.027312] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.027350] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.027395] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.027442] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.027398] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.027536] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.027582] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.027629] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfebf0 for handle 00000296b5b37a50 (device 1.16) [ 0.027676] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.028373] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b38250, length 14 [ 0.028416] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.028461] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.028375] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfef00 for handle 00000296b5b37a50 (device 1.16) [ 0.028564] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b38250 completed, length 14 [ 0.028608] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.028650] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.028688] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b38250 completion with errcode 0, length 14 [ 0.028736] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.028775] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.028813] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.028854] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b38250 has callback 00007ffd46f28d00 [ 0.028899] [000048d8] libusb: debug [sync_transfer_cb] actual_length=14 [ 0.028937] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b38250 [ 0.028976] [000048d8] libusb: debug [libusb_unref_device] destroy device 2.1 [ 0.029016] [000048d8] libusb: debug [libusb_unref_device] destroy device 1.3 [ 0.029054] [000048d8] libusb: debug [libusb_unref_device] destroy device 1.1 [ 0.029093] [000048d8] libusb: debug [libusb_unref_device] destroy device 1.2 [ 0.029132] [000048d8] libusb: debug [libusb_unref_device] destroy device 1.10 [ 0.029170] [000048d8] libusb: debug [libusb_unref_device] destroy device 1.5 [ 0.029209] [000048d8] libusb: debug [libusb_unref_device] destroy device 1.4 [ 0.029247] [000048d8] libusb: debug [libusb_unref_device] destroy device 2.0 AVR Part : ATmega328P Chip Erase delay : 9000 us PAGEL : PD7 BS2 : PC2 RESET disposition : dedicated RETRY pulse : SCK Serial program mode : yes Parallel program mode : yes Timeout : 200 StabDelay : 100 CmdexeDelay : 25 SyncLoops : 32 PollIndex : 3 PollValue : 0x53 Memory Detail : Block Poll Page Polled Memory Type Alias Mode Delay Size Indx Paged Size Size #Pages MinW MaxW ReadBack ----------- -------- ---- ----- ----- ---- ------ ------ ---- ------ ----- ----- --------- eeprom 65 20 4 0 no 1024 4 0 3600 3600 0xff 0xff flash 65 6 128 0 yes 32768 128 256 4500 4500 0xff 0xff lfuse 0 0 0 0 no 1 1 0 4500 4500 0x00 0x00 hfuse 0 0 0 0 no 1 1 0 4500 4500 0x00 0x00 efuse 0 0 0 0 no 1 1 0 4500 4500 0x00 0x00 lock 0 0 0 0 no 1 1 0 4500 4500 0x00 0x00 calibration 0 0 0 0 no 1 1 0 0 0 0x00 0x00 signature 0 0 0 0 no 3 1 0 0 0 0x00 0x00 Programmer Type : usbasp Description : USBasp, http://www.fischl.de/usbasp/ [ 0.089241] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.089283] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.089325] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.089388] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.089430] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.089480] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.089529] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.089483] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee70 for handle 00000296b5b37a50 (device 1.16) [ 0.089632] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee70 for handle 00000296b5b37a50 (device 1.16) [ 0.089681] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee70 for handle 00000296b5b37a50 (device 1.16) [ 0.089729] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee70 for handle 00000296b5b37a50 (device 1.16) [ 0.089776] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfefc0 for handle 00000296b5b37a50 (device 1.16) [ 0.089955] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.089956] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff180 for handle 00000296b5b37a50 (device 1.16) [ 0.090057] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.090002] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.090147] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.090196] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.090237] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.090277] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.090325] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.090365] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.090403] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.090445] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.090490] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.090529] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 avrdude_shared.exe: auto set sck period (because given equals null) [ 0.092284] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.092322] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.092362] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.092414] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.092452] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.092499] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.092547] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.092501] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee10 for handle 00000296b5b37a50 (device 1.16) [ 0.092641] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee10 for handle 00000296b5b37a50 (device 1.16) [ 0.092688] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee10 for handle 00000296b5b37a50 (device 1.16) [ 0.092735] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee10 for handle 00000296b5b37a50 (device 1.16) [ 0.092782] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfef60 for handle 00000296b5b37a50 (device 1.16) [ 0.093017] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.093061] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.093106] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.093019] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff120 for handle 00000296b5b37a50 (device 1.16) [ 0.093209] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.093253] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.093294] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.093333] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.093380] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.093420] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.093458] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.093499] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.093543] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.093582] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 avrdude_shared.exe: warning: cannot set sck period. please check for usbasp firmware update. [ 0.096075] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.096116] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.096159] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.096214] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.096253] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.096300] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.096348] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.096302] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee70 for handle 00000296b5b37a50 (device 1.16) [ 0.096443] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee70 for handle 00000296b5b37a50 (device 1.16) [ 0.096492] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee70 for handle 00000296b5b37a50 (device 1.16) [ 0.096539] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee70 for handle 00000296b5b37a50 (device 1.16) [ 0.096585] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfefc0 for handle 00000296b5b37a50 (device 1.16) [ 0.116820] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.116818] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff180 for handle 00000296b5b37a50 (device 1.16) [ 0.117358] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.117107] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.117579] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.117646] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.117695] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.117744] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.117897] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.117953] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.118004] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.118060] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.118121] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.118168] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 0.225900] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.226207] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.226549] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.227032] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.227341] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.227636] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.227843] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.227699] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee20 for handle 00000296b5b37a50 (device 1.16) [ 0.228672] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee20 for handle 00000296b5b37a50 (device 1.16) [ 0.228909] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee20 for handle 00000296b5b37a50 (device 1.16) [ 0.229317] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee20 for handle 00000296b5b37a50 (device 1.16) [ 0.229724] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfef70 for handle 00000296b5b37a50 (device 1.16) [ 0.234430] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 1 [ 0.234750] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.235143] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.234475] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff130 for handle 00000296b5b37a50 (device 1.16) [ 0.235814] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 1 [ 0.236011] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.236261] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.236462] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 1 [ 0.236633] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.236771] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.236900] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.237035] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.237186] [000048d8] libusb: debug [sync_transfer_cb] actual_length=1 [ 0.237314] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 avrdude_shared.exe: AVR device initialized and ready to accept instructions Reading | | 0% 0.00s[ 0.260303] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.260366] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.260415] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.260495] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.260568] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.260645] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.260721] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.260647] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.260821] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.260871] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.260919] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.260966] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 0.261697] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 4 [ 0.261698] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 0.261820] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 4 [ 0.261767] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.261957] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.262033] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.262076] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.262115] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 4 [ 0.262194] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.262235] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.262274] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.262341] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.262417] [000048d8] libusb: debug [sync_transfer_cb] actual_length=4 [ 0.262457] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 0.262498] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.262537] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.262576] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.262629] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.262694] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.262766] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.262840] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.262768] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.262934] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.262982] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.263029] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.263076] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 0.263777] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 4 [ 0.263846] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.263918] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.263779] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 0.264048] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 4 [ 0.264116] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.264159] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.264198] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 4 [ 0.264275] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.264315] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.264352] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.264419] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.264495] [000048d8] libusb: debug [sync_transfer_cb] actual_length=4 [ 0.264533] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 Reading | ################# | 33% 0.01s[ 0.266570] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.266617] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.266693] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.266757] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.266830] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.266907] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.266983] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.266910] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.267255] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.267313] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.267369] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 0.267424] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 0.268054] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 4 [ 0.268053] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 0.268166] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.268419] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.268241] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 4 [ 0.268579] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.268622] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.268662] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 4 [ 0.268740] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.268785] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.268826] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.268896] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.268973] [000048d8] libusb: debug [sync_transfer_cb] actual_length=4 [ 0.269013] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 Reading | ################################################## | 100% 0.01s avrdude_shared.exe: Device signature = 0x1e950f (probably m328p) avrdude_shared.exe: NOTE: "flash" memory has been specified, an erase cycle will be performed To disable this feature, specify the -D option. avrdude_shared.exe: erasing chip [ 0.280319] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.280362] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.280404] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.280465] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.280533] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.280608] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.280686] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.280611] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.280787] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.280835] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.280883] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.280930] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfef40 for handle 00000296b5b37a50 (device 1.16) [ 0.281635] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 4 [ 0.281637] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff100 for handle 00000296b5b37a50 (device 1.16) [ 0.281763] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 4 [ 0.281708] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.281904] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.281981] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.282024] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.282064] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 4 [ 0.282143] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.282184] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.282223] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.282292] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.282369] [000048d8] libusb: debug [sync_transfer_cb] actual_length=4 [ 0.282409] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 0.296086] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.296158] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.296251] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.296323] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.296416] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.296497] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.296575] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.296501] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.296671] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.296723] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.296772] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.296818] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfef40 for handle 00000296b5b37a50 (device 1.16) [ 0.297007] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.297009] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff100 for handle 00000296b5b37a50 (device 1.16) [ 0.297129] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.297080] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.297280] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.297366] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.297411] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.297453] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.297538] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.297581] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.297623] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.297698] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.297781] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.297822] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 avrdude_shared.exe: auto set sck period (because given equals null) [ 0.299881] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.300071] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.300179] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.300309] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.300394] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.300476] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.300555] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.300480] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed90 for handle 00000296b5b37a50 (device 1.16) [ 0.300759] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed90 for handle 00000296b5b37a50 (device 1.16) [ 0.300818] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed90 for handle 00000296b5b37a50 (device 1.16) [ 0.300875] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed90 for handle 00000296b5b37a50 (device 1.16) [ 0.300929] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfeee0 for handle 00000296b5b37a50 (device 1.16) [ 0.301084] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.301085] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff0a0 for handle 00000296b5b37a50 (device 1.16) [ 0.301189] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.301271] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.301358] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.301528] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.301574] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.301617] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.301697] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.301848] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.301889] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.301960] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.302037] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.302077] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 avrdude_shared.exe: warning: cannot set sck period. please check for usbasp firmware update. [ 0.304573] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.304612] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.304653] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.304711] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.304779] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.304853] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.304933] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.304856] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.305031] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.305080] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.305127] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfedf0 for handle 00000296b5b37a50 (device 1.16) [ 0.305174] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfef40 for handle 00000296b5b37a50 (device 1.16) [ 0.325404] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.325403] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff100 for handle 00000296b5b37a50 (device 1.16) [ 0.325545] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.325692] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.325615] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.325851] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.325895] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.325936] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.326051] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.326100] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.326160] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.326233] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.326319] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.326360] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 0.436298] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.436744] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.437003] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.437461] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.438220] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.439015] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.439541] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.439073] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfeda0 for handle 00000296b5b37a50 (device 1.16) [ 0.440345] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfeda0 for handle 00000296b5b37a50 (device 1.16) [ 0.440590] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfeda0 for handle 00000296b5b37a50 (device 1.16) [ 0.440950] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 1 [ 0.441743] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.442457] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.441030] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfeda0 for handle 00000296b5b37a50 (device 1.16) [ 0.443655] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfeef0 for handle 00000296b5b37a50 (device 1.16) [ 0.443897] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff0b0 for handle 00000296b5b37a50 (device 1.16) [ 0.444301] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 1 [ 0.445081] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.445295] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.445500] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 1 [ 0.445756] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.445858] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.445907] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.446017] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.446118] [000048d8] libusb: debug [sync_transfer_cb] actual_length=1 [ 0.446176] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 avrdude_shared.exe: reading input file ".\Blink.ino.with_bootloader.standard.hex" avrdude_shared.exe: writing flash (32768 bytes): Writing | | 0% 0.00s[ 0.452828] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.452884] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.452928] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.452991] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.453060] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.453136] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.453214] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.453139] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.453318] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.453366] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.453413] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.453460] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.453727] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.453728] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.453854] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.453800] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.453994] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.454069] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.454112] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.454151] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.454230] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.454270] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.454309] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.454377] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.454452] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.454491] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 0.454531] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.454569] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.454609] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.454662] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.454727] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.454799] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.454873] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.454802] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.454967] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.455015] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.455061] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.455108] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.533660] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 0.533674] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.533796] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.533903] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 0.533987] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.534160] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.534208] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.534254] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 0.534343] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.534390] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.534434] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.534510] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.534594] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 0.534638] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 Writing | #### | 8% 0.08s[ 0.536805] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.536856] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.536903] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.536970] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.537045] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.537126] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.537210] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.537129] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.537318] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.537371] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.537424] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.537475] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.537711] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.537789] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.537868] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.537713] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.538017] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.538097] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.538145] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.538188] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.538272] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.538318] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.538361] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.538436] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.538520] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.538563] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 0.538608] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.538665] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.538718] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.538820] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.538898] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.538979] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.539063] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.538981] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.539170] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.539224] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.539276] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.539328] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.621283] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 0.621369] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.622131] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 0.621888] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.623000] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.623201] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.623254] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.623300] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 0.623390] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.623440] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.623485] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.623565] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.623652] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 0.623696] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 Writing | ######## | 16% 0.17s[ 0.625898] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.625945] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.625987] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.626047] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.626117] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.626210] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.626285] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.626213] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.626393] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.626446] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.626496] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.626545] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.626712] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.626790] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.626865] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.626713] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.627010] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.627084] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.627129] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.627170] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.627250] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.627291] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.627331] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.627402] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.627480] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.627520] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 0.627561] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.627601] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.627642] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.627695] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.627764] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.627838] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.627913] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.627841] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.628016] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.628065] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.628113] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.628163] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.706550] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 0.707440] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.708190] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.706624] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.709530] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 0.710358] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.710786] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.711189] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 0.712046] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.712478] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.712917] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.713669] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.714181] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 0.714256] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 Writing | ############# | 25% 0.26s[ 0.716801] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.716857] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.716901] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.716961] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.717033] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.717110] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.717201] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.717113] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.717302] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.717352] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.717401] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.717450] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.717719] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.717832] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.717913] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.717727] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.718055] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.718128] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.718177] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.718222] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.718329] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.718398] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.718447] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.718525] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.718606] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.718648] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 0.718692] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.718735] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.718787] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.718860] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.718950] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.719044] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.719128] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.719047] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.719236] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.719282] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.719328] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.719374] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.803608] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 0.804512] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.805252] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.803683] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.806602] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 0.807425] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.807857] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.808254] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 0.809118] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.809587] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.810009] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.810793] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.811249] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 0.811402] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 Writing | ################# | 33% 0.36s[ 0.814089] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.814136] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.814178] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.814238] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.814309] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.814386] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.814477] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.814389] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.814578] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.814629] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.814678] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.814727] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.814895] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.814976] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.815050] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.814896] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.815195] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.815273] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.815318] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.815358] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.815440] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.815484] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.815524] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.815595] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.815674] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.815715] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 0.815757] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.815797] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.815838] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.815889] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.815961] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.816036] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.816110] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.816039] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.816210] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.816259] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.816308] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.816357] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.894783] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 0.894779] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.895839] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 0.895559] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.896722] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.897336] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.897662] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.897972] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 0.898622] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.898942] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.899258] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.899923] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.900558] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 0.900867] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 Writing | ##################### | 41% 0.45s[ 0.906950] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.907170] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.907431] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.907795] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.908040] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.908233] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.908484] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.908273] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.908884] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.909058] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.909267] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.909411] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.909593] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.909129] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 0.910150] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.910343] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.910155] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 0.910583] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.910636] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.910682] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 0.910774] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.910821] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.910867] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.910952] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.911031] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 0.911071] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 0.911114] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 0.911155] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 0.911197] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 0.911256] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 0.911331] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 0.911412] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 0.911638] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 0.911415] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.911824] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.911874] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.911974] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 0.912040] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 0.992217] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 0.992217] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 0.993235] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 0.992954] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 0.994120] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 0.994742] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 0.995056] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 0.995372] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 0.996005] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 0.996328] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 0.996636] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 0.997112] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 0.997732] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 0.998045] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 Writing | ######################### | 50% 0.55s[ 1.004366] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.004566] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.004715] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.004876] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.005109] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.005309] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.005552] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.005343] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.005914] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.006067] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.006368] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.006607] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.006798] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.006138] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 1.007054] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.007178] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.007058] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 1.007390] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.007436] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.007476] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 1.007556] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.007600] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.007641] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.007709] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.007787] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.007827] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 1.007868] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.007907] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.007948] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.008000] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.008067] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.008139] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.008313] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.008142] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.008532] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.008588] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.008640] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.008692] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.089429] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 1.089504] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.090286] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 1.090010] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.091166] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.091789] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.092114] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.092437] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 1.093082] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.093402] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.093718] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.094190] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.094815] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 1.095100] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 Writing | ############################# | 58% 0.64s[ 1.100213] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.100299] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.100372] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.100473] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.100594] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.100724] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.101008] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.100730] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.101526] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.101597] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.101677] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.101796] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.102038] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.101714] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 1.102468] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.102909] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.102510] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 1.103667] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.103838] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.104067] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 1.104183] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.104237] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.104286] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.104370] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.104524] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.104570] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 1.104620] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.104666] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.104714] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.104778] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.104871] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.104956] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.105037] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.104960] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.105437] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.105504] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.105563] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.105619] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.188516] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 1.189418] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.190170] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.188594] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.191683] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 1.192592] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.193057] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.193577] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 1.194121] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.194344] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.194474] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.194674] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.194905] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 1.195026] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 Writing | ################################################## | 100% 0.75s [ 1.205984] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.206176] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.206261] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.206324] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.206498] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.206667] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.206726] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.206832] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.206749] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.206930] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.207121] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.207224] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.207538] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 1.207539] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.207714] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 1.207659] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.207862] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.207942] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.207988] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.208028] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 1.208110] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.208153] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.208194] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.208265] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.208344] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.208385] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 1.208429] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.208470] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.208513] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.208576] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.208659] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.208754] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.208849] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.208757] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.208960] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.209015] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.209065] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.209115] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.286424] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 1.287379] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.288198] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.286497] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.289741] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 1.290549] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.290892] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.291217] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 1.291769] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.291989] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.292112] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.292367] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.292553] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 1.292641] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 1.292763] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.292871] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.292953] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.293063] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.293176] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.293298] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.293301] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.293427] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.293537] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.293690] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.293748] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.293797] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.294012] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 1.294092] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.294169] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.294014] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.294314] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 1.294387] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.294432] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.294472] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 1.294553] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.294594] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.294634] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.294704] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.294781] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.294821] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 1.294862] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.294902] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.294943] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.294999] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.295116] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.295202] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.295280] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.295205] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.295388] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.295438] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.295487] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.295536] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.375309] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 1.376321] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.377148] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.375385] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.378687] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 1.379623] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.380107] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.380586] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 1.381349] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.381630] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.381918] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.382439] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.382712] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 1.382810] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 1.382947] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.383042] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.383155] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.383269] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.383388] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.383520] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.383523] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.383651] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.383765] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.383960] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.384058] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.384115] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.384259] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 1.384339] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.384439] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.384260] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.384588] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 1.384661] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.384708] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.384749] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 1.384830] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.384872] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.384913] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.384983] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.385109] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.385151] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 1.385194] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.385231] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.385270] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.385322] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.385387] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.385458] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.385560] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.385461] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.385666] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.385713] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.385759] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.385804] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.467731] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 1.468735] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.469580] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.467809] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.471149] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 1.472069] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.472554] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.473025] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 1.473574] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.473853] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.474139] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.474430] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.474633] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 1.474792] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 1.474908] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.475014] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.475100] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.475193] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.475275] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.475364] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.475449] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.475367] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.475585] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.475644] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.475701] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.475757] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.475894] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 1.475974] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.476073] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.475895] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.476224] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 1.476304] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.476352] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.476396] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 1.476485] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.476531] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.476576] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.476654] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.476739] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.476783] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 1.476828] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 1.476871] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.476918] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.476975] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.477051] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.477129] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.477229] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.477133] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.477343] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.477400] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.477457] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed10 for handle 00000296b5b37a50 (device 1.16) [ 1.477513] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee60 for handle 00000296b5b37a50 (device 1.16) [ 1.558471] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 128 [ 1.558471] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff020 for handle 00000296b5b37a50 (device 1.16) [ 1.559494] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 128 [ 1.559210] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.560494] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.561125] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.561451] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.561759] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 128 [ 1.562401] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.562721] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.563153] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.563674] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 1.564327] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 1.564647] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 avrdude_shared.exe: 32768 bytes of flash written avrdude_shared.exe: verifying flash memory against .\Blink.ino.with_bootloader.standard.hex: Reading | | 0% 0.00s[ 1.575766] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.575917] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.576075] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.576246] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.576509] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.576666] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.576863] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.576669] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.577202] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.577256] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.577304] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.577351] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 1.577578] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 1.577581] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 1.577819] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.578307] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.577992] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 1.579145] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.579249] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.579392] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 1.579696] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.579961] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.580122] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.580337] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 1.580573] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.580685] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 1.580809] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.580923] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.581040] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.581239] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.581452] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.581553] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.581639] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.581558] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.582037] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.582118] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.582181] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.582239] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 1.646610] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 1.647172] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.647651] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.646670] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 1.648311] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 1.648429] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.648495] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.648550] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 1.648652] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.648714] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.648770] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.648864] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 1.648969] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 1.649023] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 Reading | #### | 8% 0.08s[ 1.651226] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.651272] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.651314] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.651373] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.651440] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.651514] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.651589] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.651516] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.651690] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.651738] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.651785] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.651831] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 1.652022] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 1.652092] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.652163] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.652023] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 1.652294] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 1.652363] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.652407] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.652445] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 1.652523] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.652562] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.652600] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.652667] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 1.652743] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.652783] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 1.652822] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.652860] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.652900] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.652952] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.653017] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.653089] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.653161] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.653091] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.653255] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.653302] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.653349] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.653396] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 1.718071] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 1.718347] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.718758] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.718079] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 1.719940] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 1.720573] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.720847] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.721073] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 1.721852] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.722260] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.722647] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.723404] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 1.724163] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 1.724540] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 Reading | ######## | 16% 0.15s[ 1.727792] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.727883] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.727941] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.728013] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.728098] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.728171] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.728247] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.728173] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.728346] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.728391] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.728434] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.728477] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 1.728710] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 1.728778] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.728845] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.728712] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 1.728969] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 1.729035] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.729074] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.729109] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 1.729180] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.729216] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.729352] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.729539] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 1.729615] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.729663] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 1.729705] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.729744] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.729784] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.729839] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.729906] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.729980] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.730051] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.729982] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.730147] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.730196] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.730243] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.730316] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 1.795132] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 1.795688] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.796454] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.795216] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 1.797705] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 1.798455] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.798861] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.799215] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 1.799503] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.799885] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.800293] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.801028] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 1.801441] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 1.801713] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 Reading | ############# | 25% 0.23s[ 1.807207] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.807260] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.807304] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.807363] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.807428] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.807500] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.807584] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.807503] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.807688] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.807739] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.807787] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.807833] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 1.808015] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 1.808088] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.808159] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.808016] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 1.808290] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 1.808362] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.808406] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.808444] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 1.808518] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.808560] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.808599] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.808667] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 1.808742] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.808781] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 1.808841] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.808880] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.808920] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.808975] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.809042] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.809115] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.809191] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.809117] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.809284] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.809331] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.809378] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.809424] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 1.873862] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 1.874520] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.875295] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.873913] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 1.876424] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 1.877128] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.877507] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.877747] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 1.878540] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.878976] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.879199] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.879901] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 1.880696] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 1.881096] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 Reading | ################# | 33% 0.31s[ 1.884406] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.884459] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.884504] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.884563] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.884632] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.884707] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.884792] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.884710] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.884892] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.884939] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.884986] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.885033] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 1.885225] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 1.885296] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.885367] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.885226] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 1.885499] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 1.885571] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.885614] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.885652] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 1.885727] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.885768] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.885807] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.885875] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 1.885950] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.885989] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 1.886029] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.886066] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.886105] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.886157] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.886221] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.886292] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.886364] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.886295] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.886456] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.886503] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.886550] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.886596] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 1.951123] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 1.951196] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 1.951989] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 1.951709] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.952863] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.953476] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.953800] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.954122] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 1.954768] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.955092] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.955405] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.955870] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 1.956487] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 1.956803] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 Reading | ##################### | 41% 0.38s[ 1.962770] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.962938] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.963110] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.963301] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.963574] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.963794] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.964076] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.963837] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.964503] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.964702] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.964931] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.965185] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 1.965439] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 1.964716] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 1.965787] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 1.966054] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 1.965792] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 1.966401] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 1.966482] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 1.966530] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 1.966628] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 1.966676] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 1.966721] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 1.966801] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 1.966894] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 1.966940] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 1.966985] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 1.967028] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 1.967073] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 1.967130] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 1.967207] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 1.967287] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 1.967541] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 1.967291] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.967777] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.967835] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.967891] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 1.967947] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.032621] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 2.032623] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.033658] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 2.033376] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.034711] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.035332] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.035651] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.035958] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 2.036606] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.036922] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.037234] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.037708] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.038323] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 2.038635] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 Reading | ######################### | 50% 0.47s[ 2.044416] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.044603] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.044774] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.044970] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.045264] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.045513] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.045814] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.045551] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.046254] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.046454] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.046774] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.047039] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.047304] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.046556] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 2.047819] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.047984] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.047860] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 2.048263] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.048306] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.048347] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 2.048427] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.048470] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.048510] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.048578] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.048655] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 2.048694] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 2.048735] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.048774] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.048894] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.048949] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.049052] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.049141] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.049266] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.049145] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.049374] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.049423] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.049471] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.049519] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.113755] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 2.113864] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.114705] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 2.114419] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.116115] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.116731] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.117128] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.117565] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 2.118350] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.118761] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.119149] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.119945] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.120447] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 2.120840] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 Reading | ############################# | 58% 0.55s[ 2.124892] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.124947] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.124992] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.125053] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.125121] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.125195] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.125279] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.125198] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.125380] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.125428] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.125474] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.125521] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.125704] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 2.125705] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.125829] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 2.125775] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.125968] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.126044] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.126086] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.126125] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 2.126202] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.126242] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.126280] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.126347] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.126422] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 2.126461] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 2.126500] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.126538] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.126577] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.126629] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.126694] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.126765] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.126838] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.126768] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.126931] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.126978] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.127024] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.127070] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.191833] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 2.191878] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.192418] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.193317] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.192682] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 2.194824] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.195061] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.195455] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 2.196232] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.196639] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.197028] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.197771] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.198512] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 2.198884] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 Reading | ################################################## | 100% 0.63s [ 2.209380] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.209454] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.209498] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.209557] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.209627] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.209702] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.209778] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.209705] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.209878] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.209926] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.209973] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.210020] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.210197] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 2.210269] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.210343] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.210199] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.210605] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 2.210676] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.210720] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.210758] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 2.210834] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.210872] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.210909] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.210976] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.211048] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 2.211084] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 2.211123] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.211159] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.211197] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.211250] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.211315] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.211387] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.211461] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.211389] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.211558] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.211604] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.211650] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.211695] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.276150] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 2.276751] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.277444] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.276204] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.278651] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 2.279295] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.279661] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.279886] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 2.280568] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.281010] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.281174] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.281645] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.282256] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 2.282648] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 2.283183] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.283562] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.283959] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.284295] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.284959] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.285575] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.286093] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.285635] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.286877] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 2.287427] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.286895] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.287923] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.288122] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.288436] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.288490] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.288539] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.288587] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 2.288659] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.288707] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.288746] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 2.288824] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.288866] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.288904] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.288972] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.289048] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 2.289087] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 2.289127] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.289165] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.289205] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.289259] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.289325] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.289397] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.289470] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.289399] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.289565] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.289613] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.289659] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.289706] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.354706] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 2.354753] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.355349] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.356333] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.355635] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 2.357875] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.358186] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.358586] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 2.359369] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.359768] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.360159] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.360903] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.361670] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 2.362070] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 2.362532] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.362742] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.363001] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.363126] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.363335] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.363427] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.363514] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.363430] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.363616] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.363665] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.363712] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.363759] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.363935] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 2.363937] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.364062] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 2.364008] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.364201] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.364273] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.364316] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.364355] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 2.364434] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.364474] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.364513] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.364580] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.364656] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 2.364694] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 2.364734] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.364772] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.364813] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.364866] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.364931] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.365002] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.365075] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.365004] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.365168] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.365215] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.365262] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.365308] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.430100] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 2.430100] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.430859] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 2.430697] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.431492] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.431981] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.432313] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.432643] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 2.433280] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.433604] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.433998] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.434466] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.435096] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 2.435411] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 2.435782] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.436084] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.436400] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.436765] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.437279] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.437761] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.438277] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.437825] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.438915] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.439418] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.439831] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.440233] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.440644] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.439077] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 0 [ 2.441364] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.441775] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.441372] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 0 [ 2.442667] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.442859] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.443029] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 0 [ 2.443195] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.443278] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.443355] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.443478] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.443557] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 2.443599] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 [ 2.443640] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b33650 [ 2.443680] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.443722] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.443875] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.443983] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.444072] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.444222] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.444076] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.444434] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.444552] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.444612] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfed40 for handle 00000296b5b37a50 (device 1.16) [ 2.444716] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee90 for handle 00000296b5b37a50 (device 1.16) [ 2.509125] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff050 for handle 00000296b5b37a50 (device 1.16) [ 2.509125] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b33650, length 128 [ 2.509921] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.510513] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.510016] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b33650 completed, length 128 [ 2.511672] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.511948] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.512265] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b33650 completion with errcode 0, length 128 [ 2.512899] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.513220] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.513531] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.514003] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b33650 has callback 00007ffd46f28d00 [ 2.514625] [000048d8] libusb: debug [sync_transfer_cb] actual_length=128 [ 2.514923] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b33650 avrdude_shared.exe: 32768 bytes of flash verified [ 2.519763] [000048d8] libusb: debug [libusb_submit_transfer] transfer 00000296b5b32c60 [ 2.520013] [000048d8] libusb: debug [add_to_flying_list] arm timer for timeout in 5000ms (first in line) [ 2.520249] [000048d8] libusb: debug [libusb_claim_interface] interface 0 [ 2.520495] [000048d8] libusb: debug [winusbx_claim_interface] claimed interface 0 [ 2.520940] [000048d8] libusb: debug [windows_assign_endpoints] no endpoints found for interface 0 [ 2.521119] [000048d8] libusb: debug [auto_claim] auto-claimed interface 0 for control request [ 2.521398] [000048d8] libusb: debug [winusbx_submit_control_transfer] will use interface 0 [ 2.521127] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee80 for handle 00000296b5b37a50 (device 1.16) [ 2.521881] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee80 for handle 00000296b5b37a50 (device 1.16) [ 2.521946] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee80 for handle 00000296b5b37a50 (device 1.16) [ 2.522049] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfee80 for handle 00000296b5b37a50 (device 1.16) [ 2.522000] [000048d8] libusb: debug [windows_force_sync_completion] transfer 00000296b5b32c60, length 0 [ 2.522290] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dfefd0 for handle 00000296b5b37a50 (device 1.16) [ 2.522832] [00004be4] libusb: debug [windows_iocp_thread] ignoring overlapped 000000cc58dff190 for handle 00000296b5b37a50 (device 1.16) [ 2.522919] [00004be4] libusb: debug [windows_iocp_thread] transfer 00000296b5b32c60 completed, length 0 [ 2.522603] [000048d8] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling [ 2.523465] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() for 2 HANDLEs with timeout in 60000ms [ 2.523760] [000048d8] libusb: debug [usbi_wait_for_events] WaitForMultipleObjects() returned 0 [ 2.524030] [000048d8] libusb: debug [handle_event_trigger] event triggered [ 2.524195] [000048d8] libusb: debug [windows_handle_transfer_completion] handling transfer 00000296b5b32c60 completion with errcode 0, length 0 [ 2.524543] [000048d8] libusb: debug [libusb_release_interface] interface 0 [ 2.524715] [000048d8] libusb: debug [auto_release] auto-released interface 0 [ 2.524873] [000048d8] libusb: debug [arm_timer_for_next_timeout] no timeouts, disarming timer [ 2.525074] [000048d8] libusb: debug [usbi_handle_transfer_completion] transfer 00000296b5b32c60 has callback 00007ffd46f28d00 [ 2.525352] [000048d8] libusb: debug [sync_transfer_cb] actual_length=0 [ 2.525572] [000048d8] libusb: debug [libusb_free_transfer] transfer 00000296b5b32c60 [ 2.525640] [000048d8] libusb: debug [libusb_close] [ 2.525700] [000048d8] libusb: debug [libusb_unref_device] destroy device 1.16 [ 2.525936] [000048d8] libusb: debug [libusb_unref_device] destroy device 1.0 [ 2.526002] [000048d8] libusb: debug [libusb_exit] [ 2.526099] [00004be4] libusb: debug [windows_iocp_thread] I/O completion thread exiting [ 2.526470] [000048d8] libusb: debug [usbi_remove_event_source] remove HANDLE 00000000000000dc [ 2.526604] [000048d8] libusb: debug [usbi_remove_event_source] remove HANDLE 00000000000000d8 avrdude_shared.exe done. Thank you. ```
mcuee commented 2 years ago

Just for compleleness, here is the run log for WinUSB with this usbasp clone.

PS C:\work\avr\avrdude_test\avrdude-7.0_bin64> C:\libusbK-3.1.0.0-bin\bin\exe\amd64\kList.exe

Loading USB ID's maintained by Stephen J. Gowdy <linux.usb.ids@gmail.com>..

 1. USBasp (VOTI) [Connected]
    Service              : WinUSB
    ClassGUID            : {88BAE032-5A81-49F0-BC3D-A4FF138216D6}
    DeviceID             : USB\VID_16C0&PID_05DC\5&586B51A&0&1
    DeviceInterfaceGUID  : {AE852F68-9D25-E08E-E595-3675C74F2D68}
    SymbolicLink         : \\?\usb#vid_16c0&pid_05dc#5&586b51a&0&1#{ae852f68-9d25-e08e-e595-3675c74f2d68}
    DevicePath           : \\?\usb#vid_16c0&pid_05dc#5&586b51a&0&1#{ae852f68-9d25-e08e-e595-3675c74f2d68}
    SerialNumber         : 5&586B51A&0&1
    BusNumber            : 0
    DeviceAddress        : 1

Select device (1-1) :1

Loading driver api..
Getting descriptors..

-Device:
  bLength             :18
  bDescriptorType     :0x01
  bcdUSB              :0x0110
  bDeviceClass        :0xFF (Vendor Specific Class)
  bDeviceSubClass     :0x00
  bDeviceProtocol     :0x00
  bMaxPacketSize0     :8
  idVendor            :0x16C0 (Van Ooijen Technische Informatica)
  idProduct           :0x05DC (shared ID for use with libusb)
  bcdDevice           :0x0102
  iManufacturer       :1 (www.fischl.de)
  iProduct            :2 (USBasp)
  iSerialNumber       :0
  bNumConfigurations  :1
!End Device
-Configuration:
  bLength             :9
  bDescriptorType     :0x02
  wTotalLength        :18
  bNumInterfaces      :1
  bConfigurationValue :0x01
  iConfiguration      :0
  bmAttributes        :0x80
  MaxPower            :25 (50ma)
 -Interface:
    bLength             :9
    bDescriptorType     :0x04
    bInterfaceNumber    :0x00
    bAlternateSetting   :0x00
    bNumEndpoints       :0
    bInterfaceClass     :0x00 (Defined at Interface level)
    bInterfaceSubClass  :0x00
    bInterfaceProtocol  :0x00
    iInterface          :0
 !End Interface
!End Configuration
-MSOSV1:
  bDescriptorType     :0x00
  bLength             :00
  bMS_VendorCode      :0x0A
  qwSignature         :
 -Extended Compatible IDs:
    dwLength            :0
    bcdVersion          :0x0000
    wIndex              :0
    bCount              :0
 !End Extended Compatible IDs
!End MSOSV1
PS C:\work\avr\avrdude_test\avrdude-7.0_bin64> .\avrdude_shared.exe -c usbasp -p m328p -U flash:w:.\Blink.ino.with_bootloader.standard.hex:i -v

avrdude_shared.exe: Version 7.0
                    Copyright (c) Brian Dean, http://www.bdmicro.com/
                    Copyright (c) Joerg Wunsch

                    System wide configuration file is "C:/work/avr/avrdude_test/avrdude-7.0_bin64/avrdude.conf"

                    Using Port                    : usb
                    Using Programmer              : usbasp
libusb: info [winusbx_init] WinUSB DLL available (with isoch support)
libusb: info [windows_init] UsbDk backend is not available
libusb: warning [libusb_init] installing new context as implicit default
                    AVR Part                      : ATmega328P
                    Chip Erase delay              : 9000 us
                    PAGEL                         : PD7
                    BS2                           : PC2
                    RESET disposition             : dedicated
                    RETRY pulse                   : SCK
                    Serial program mode           : yes
                    Parallel program mode         : yes
                    Timeout                       : 200
                    StabDelay                     : 100
                    CmdexeDelay                   : 25
                    SyncLoops                     : 32
                    PollIndex                     : 3
                    PollValue                     : 0x53
                    Memory Detail                 :

                                                      Block Poll               Page                       Polled
                      Memory Type Alias    Mode Delay Size  Indx Paged  Size   Size #Pages MinW  MaxW   ReadBack
                      ----------- -------- ---- ----- ----- ---- ------ ------ ---- ------ ----- ----- ---------
                      eeprom                 65    20     4    0 no       1024    4      0  3600  3600 0xff 0xff
                      flash                  65     6   128    0 yes     32768  128    256  4500  4500 0xff 0xff
                      lfuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      hfuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      efuse                   0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      lock                    0     0     0    0 no          1    1      0  4500  4500 0x00 0x00
                      calibration             0     0     0    0 no          1    1      0     0     0 0x00 0x00
                      signature               0     0     0    0 no          3    1      0     0     0 0x00 0x00

                    Programmer Type : usbasp
                    Description     : USBasp, http://www.fischl.de/usbasp/

avrdude_shared.exe: auto set sck period (because given equals null)
avrdude_shared.exe: warning: cannot set sck period. please check for usbasp firmware update.
avrdude_shared.exe: AVR device initialized and ready to accept instructions

Reading | ################################################## | 100% 0.01s

avrdude_shared.exe: Device signature = 0x1e950f (probably m328p)
avrdude_shared.exe: NOTE: "flash" memory has been specified, an erase cycle will be performed
                    To disable this feature, specify the -D option.
avrdude_shared.exe: erasing chip
avrdude_shared.exe: auto set sck period (because given equals null)
avrdude_shared.exe: warning: cannot set sck period. please check for usbasp firmware update.
avrdude_shared.exe: reading input file ".\Blink.ino.with_bootloader.standard.hex"
avrdude_shared.exe: writing flash (32768 bytes):

Writing | ################################################## | 100% 0.69s

avrdude_shared.exe: 32768 bytes of flash written
avrdude_shared.exe: verifying flash memory against .\Blink.ino.with_bootloader.standard.hex:

Reading | ################################################## | 100% 0.55s

avrdude_shared.exe: 32768 bytes of flash verified

avrdude_shared.exe done.  Thank you.
mcuee commented 2 years ago

@dl8dtl mentioned below. https://github.com/avrdudes/avrdude/issues/906#issuecomment-1120433920

  1. I will recommend that avrdude to ditch the legacy libusb-0.1 API altogether (including libusb-win32) and move to libusb-1.0 API.

Unlikely to happen. libusb-0.1 "just works", and it would need volunteers for such a change, to rewrite a lot of code – and test all this in the end, on all supported platforms, with all affected programmers. In the end, doing all that work for no gain other than replacing one API by another one, i.e. there won't be any improvements for end-users by that change.

I mentioned in earlier comments:

I see that usbasp already has the support of libusb-1.0 if it is present and otherwise fall back to libusb-0.1 API . So one way is to replace one by one if there are developers who are interested to create the patch for libusb-1.0 API, using the approach of usbasp.

I will close this one since it will not be a general direction as mentioned by @dl8dtl. So it will only happen if someone provides updates to the existing codes using libusb-1.0 (but with libusb-0.1 as the fallback).

The main advantage of libusb-1.0 vs libusb-0.1 API is the support of asynchronous API. But it may not bring too much speed improvement for avrdude.

mcuee commented 2 years ago

OpenOCD has done the migration from libusb-0.1 to libusb-1.0 API. current form: https://github.com/openocd-org/openocd/blob/master/src/jtag/drivers/libusb_helper.c

libusb-0.1 support was removed in March 2000. https://github.com/openocd-org/openocd/commit/8abe91cff960709ffd3ebbb9eb1dcf710dd83c1f

Before that it has both support for libusb-0.1 and libusb-1.0. https://github.com/openocd-org/openocd/tree/e7306d361b5d503cf27dfb3dc69298ddad495f88/src/jtag/drivers