search

Benjamin-Dobell / Heimdall

Heimdall is a cross-platform open-source tool suite used to flash firmware (aka ROMs) onto Samsung Galaxy devices.
MIT License
2.61k stars 586 forks source link

v1.4.1 softbricking SPH-P100 tablets with the flash command "heimdall flash --KERNEL <filename>" #454

Open lsimplify opened 6 years ago

lsimplify commented 6 years ago

I bricked a SPH-P100 trying to flash the kernel partition with the boot.img file from this archive: cm-11-20140212-terenceng-p1c.zip (sha1sum is 1b80087efa191a992f2b0bdee0cfafab42d0d69f) In case it matters, this is the "Sprint firmware version" of the SPH-P100.

(I think the tablet is actually in "download mode" because it has this little graphic on the screen, so that is good.)

Command that I used was basic flashing command. Just heimdall flash --KERNEL <filename>

And I got this error: ERROR: Failed to send file part packet!

The error message made me think following this advice will prevent the error: #347

I'm following the advice and will try to flash it tomorrow, but if this doesn't fix the flash functionality, I'll apply the changes to the source code that are mentioned here: #14

I'll use the verbose option next time until we get it to work.

lsimplify commented 6 years ago

Update: ./heimdall detect --usb-log-level debug --verbose --stdout-errors cannot detect the device.

Plugging in the device doesn't cause any messages to show up in with sudo dmesg --level debug In hindsight, the tablets battery might just have been depleted from yesterday and it would have communicated with the computer if it's battery wasn't so close to dead. I'll have access to this one next week to test this hypothesis.

The graphic on the screen only shows up when connecting it to the wall. (Maybe it will detect in Windows with Odin, but I don't know right now. Tips/advice is appreciated!

I have more working SPH-P100 tablets, but I can't just willy-nilly try stuff (and brick them). I need some suggestions on how to get heimdall to not fail in the first place like it did in my first post. Suggestions should be on logical foundations.

P.S.: These are stock unrooted tablets in case that means anything in regard to why the flash command is failing.

lsimplify commented 6 years ago

I just came up with the idea of trying to flash non-critical partitions. If flashing fails, the SPH-P100 should still boot. Once flashing works, I'll try to flash the kernel partition.

lsimplify commented 6 years ago

This was unexpected... the binary with the code in #347 seems to have not done anything when I tried to flash the modem (I'll post that log in my next post in case it means anything to anyone.), and when I used the latest unmodified source, the same 'ol brick resulted. The device is still detected, but I do not understand why a command to flash the modem partition results in a brick with the yellow triangle just displaying. 

~/Heimdall/build/bin/heimdall flash --MODEM modem.bin --no-reboot --usb-log-level debug --verbose --stdout-errors
Heimdall v1.4.2
...
Initialising connection...
Detecting device...
[timestamp] [threadID] facility level [function call] <message>
--------------------------------------------------------------------------------
[ 0.006488] [00000e27] libusb: debug [libusb_get_device_list] 
[ 0.006526] [00000e27] libusb: debug [discovered_devs_append] need to increase capacity
[ 0.006539] [00000e27] libusb: debug [libusb_get_device_descriptor] 
[ 0.006546] [00000e27] libusb: debug [libusb_get_device_descriptor] 
[ 0.006550] [00000e27] libusb: debug [libusb_get_device_descriptor] 
[ 0.006558] [00000e27] libusb: debug [libusb_get_device_descriptor] 
[ 0.006568] [00000e27] libusb: debug [libusb_get_device_descriptor] 
[ 0.006578] [00000e27] libusb: debug [libusb_get_device_descriptor] 
[ 0.006587] [00000e27] libusb: debug [libusb_get_device_descriptor] 
[ 0.006597] [00000e27] libusb: debug [libusb_get_device_descriptor] 
[ 0.006608] [00000e27] libusb: debug [libusb_open] open 2.15
[ 0.006654] [00000e27] libusb: debug [usbi_add_pollfd] add fd 10 events 4
[ 0.006664] [00000e27] libusb: debug [libusb_get_device_descriptor] 
[ 0.006680] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58852630
[ 0.006690] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58852630
[ 0.006703] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.006734] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.006744] [00000e27] libusb: debug [handle_events] poll fds modified, reallocating
[ 0.006756] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.006821] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 0.006839] [00000e27] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=4
[ 0.006851] [00000e27] libusb: debug [handle_control_completion] handling completion status 0
[ 0.006864] [00000e27] libusb: debug [disarm_timerfd] 
[ 0.006876] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58852630 has callback 0x7f156b89cb30
[ 0.006889] [00000e27] libusb: debug [sync_transfer_cb] actual_length=4
[ 0.006914] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58852630
[ 0.006928] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf588530f0
[ 0.006939] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf588530f0
[ 0.006951] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.006970] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.006978] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.016805] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 0.016831] [00000e27] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=16
[ 0.016836] [00000e27] libusb: debug [handle_control_completion] handling completion status 0
[ 0.016846] [00000e27] libusb: debug [disarm_timerfd] 
[ 0.016862] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf588530f0 has callback 0x7f156b89cb30
[ 0.016867] [00000e27] libusb: debug [sync_transfer_cb] actual_length=16
[ 0.016874] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf588530f0
      Manufacturer: "SAMSUNG"
[ 0.016889] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf588523d0
[ 0.016892] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf588523d0
[ 0.016894] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.016915] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.016918] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.026807] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 0.026828] [00000e27] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=4
[ 0.026833] [00000e27] libusb: debug [handle_control_completion] handling completion status 0
[ 0.026840] [00000e27] libusb: debug [disarm_timerfd] 
[ 0.026845] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf588523d0 has callback 0x7f156b89cb30
[ 0.026849] [00000e27] libusb: debug [sync_transfer_cb] actual_length=4
[ 0.026857] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf588523d0
[ 0.026864] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58855160
[ 0.026870] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58855160
[ 0.026876] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.026891] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.026895] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.036807] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 0.036843] [00000e27] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=28
[ 0.036874] [00000e27] libusb: debug [handle_control_completion] handling completion status 0
[ 0.036884] [00000e27] libusb: debug [disarm_timerfd] 
[ 0.036893] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58855160 has callback 0x7f156b89cb30
[ 0.036899] [00000e27] libusb: debug [sync_transfer_cb] actual_length=28
[ 0.036908] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58855160
           Product: "Gadget Serial"

            length: 18
      device class: 2
               S/N: 0
           VID:PID: 04E8:6601
         bcdDevice: 021B
   iMan:iProd:iSer: 1:2:0
          nb confs: 1
[ 0.036968] [00000e27] libusb: debug [libusb_get_config_descriptor] index 0

interface[0].altsetting[0]: num endpoints = 1
   Class.SubClass.Protocol: 02.02.01
       endpoint[0].address: 83
           max packet size: 0010
          polling interval: 09

interface[1].altsetting[0]: num endpoints = 2
   Class.SubClass.Protocol: 0A.00.00
       endpoint[0].address: 81
           max packet size: 0200
          polling interval: 00
       endpoint[1].address: 02
           max packet size: 0200
          polling interval: 00
Claiming interface...
[ 0.037076] [00000e27] libusb: debug [libusb_claim_interface] interface 1
Attempt failed. Detaching driver...
[ 0.037092] [00000e27] libusb: debug [libusb_detach_kernel_driver] interface 1
Claiming interface again...
[ 0.037296] [00000e27] libusb: debug [libusb_claim_interface] interface 1
Setting up interface...
[ 0.037318] [00000e27] libusb: debug [libusb_set_interface_alt_setting] interface 1 altsetting 0

Initialising protocol...
[ 0.046888] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58855610
[ 0.046899] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58855610
[ 0.046909] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.046920] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4
[ 0.046947] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.046958] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.046964] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 0.046971] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 0.046977] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.046984] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.046990] [00000e27] libusb: debug [disarm_timerfd] 
[ 0.046997] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58855610 has callback 0x7f156b89cb30
[ 0.047003] [00000e27] libusb: debug [sync_transfer_cb] actual_length=4
[ 0.047008] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58855610
[ 0.047013] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf588590a0
[ 0.047017] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf588590a0
[ 0.047020] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.047024] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 7
[ 0.047031] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.047035] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.078448] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 0.078532] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 0.078539] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.078546] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.078560] [00000e27] libusb: debug [disarm_timerfd] 
[ 0.078571] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf588590a0 has callback 0x7f156b89cb30
[ 0.078584] [00000e27] libusb: debug [sync_transfer_cb] actual_length=4
[ 0.078594] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf588590a0
Protocol initialisation successful.

Beginning session...
[ 0.078650] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf588556f0
[ 0.078657] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf588556f0
[ 0.078660] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 0.078669] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 0.078704] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.078710] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.078753] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 0.078763] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 0.078765] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.078768] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.078770] [00000e27] libusb: debug [disarm_timerfd] 
[ 0.078774] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf588556f0 has callback 0x7f156b89cb30
[ 0.078776] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 0.078789] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf588556f0
[ 0.078799] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58852ed0
[ 0.078805] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58852ed0
[ 0.078811] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 0.078822] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 0.078838] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.078851] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.078861] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 0.078870] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 0.078876] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.078880] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.078885] [00000e27] libusb: debug [disarm_timerfd] 
[ 0.078891] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58852ed0 has callback 0x7f156b89cb30
[ 0.078898] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 0.078907] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58852ed0
[ 0.078915] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 0.078923] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 0.078926] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 0.078932] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 0.078943] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.078949] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.082758] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 0.082767] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 0.082774] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.082779] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.082783] [00000e27] libusb: debug [disarm_timerfd] 
[ 0.082792] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 0.082798] [00000e27] libusb: debug [sync_transfer_cb] actual_length=8
[ 0.082803] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0

Some devices may take up to 2 minutes to respond.
Please be patient!

Session begun.

[ 3.083044] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58852ed0
[ 3.083075] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58852ed0
[ 3.083105] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.083145] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.083205] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.083214] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.083353] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.083382] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.083389] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.083396] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.083403] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.083411] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58852ed0 has callback 0x7f156b89cb30
[ 3.083420] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.083436] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58852ed0
[ 3.083452] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.083462] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.083468] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.083479] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.083505] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.083524] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.083540] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.083552] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.083561] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.083569] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.083580] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.083593] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.083604] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.083611] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.083624] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58852ed0
[ 3.083633] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58852ed0
[ 3.083644] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.083657] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.083678] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.083687] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.087288] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.087302] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 3.087310] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.087322] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.087333] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.087346] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58852ed0 has callback 0x7f156b89cb30
[ 3.087357] [00000e27] libusb: debug [sync_transfer_cb] actual_length=8
[ 3.087370] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58852ed0
Downloading device's PIT file...
[ 3.087395] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.087406] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.087419] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.087431] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.087446] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.087455] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.087695] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.087707] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.087718] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.087730] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.087741] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.087753] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.087765] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.087779] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.087791] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58852ed0
[ 3.087798] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58852ed0
[ 3.087810] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.087821] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.087837] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.087851] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.087862] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.087871] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.087883] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.087894] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.087906] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.087916] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58852ed0 has callback 0x7f156b89cb30
[ 3.087928] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.087940] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58852ed0
[ 3.087954] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.087965] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.087976] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.087990] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.088005] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.088013] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.125553] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.125619] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 3.125626] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.125639] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.125646] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.125659] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.125668] [00000e27] libusb: debug [sync_transfer_cb] actual_length=8
[ 3.125683] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.125716] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58852ed0
[ 3.125723] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58852ed0
[ 3.125730] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.125739] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.125782] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.125790] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.125928] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.125976] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.125981] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.125984] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.125989] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.125995] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58852ed0 has callback 0x7f156b89cb30
[ 3.125998] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.126004] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58852ed0
[ 3.126014] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.126018] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.126023] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.126034] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.126057] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.126076] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.126083] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.126087] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.126090] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.126092] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.126095] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.126099] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.126102] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.126106] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.126118] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.126124] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.126132] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.126148] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.126168] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.126174] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.130042] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.130072] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.130078] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.130082] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.130087] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.130100] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.130115] [00000e27] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.130132] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.130147] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.130152] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.130156] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.130162] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.130178] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.130183] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.130396] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.130412] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.130417] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.130421] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.130426] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.130432] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.130437] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.130442] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.130448] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.130453] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.130457] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.130465] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.130478] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.130489] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.130499] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.130505] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.130510] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.130515] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.130521] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.130527] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.130534] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.130540] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.130548] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.130555] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.130561] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.130567] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.130574] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.130581] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.134394] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.134402] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.134409] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.134415] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.134432] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.134441] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.134447] [00000e27] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.134457] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.134466] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.134471] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.134478] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.134488] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.134500] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.134506] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.134800] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.134809] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.134816] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.134824] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.134831] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.134839] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.134846] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.134856] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.134864] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.134869] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.134873] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.134880] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.134890] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.134901] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.134911] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.134919] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.134927] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.134935] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.134942] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.134951] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.134956] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.134960] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.134967] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.134972] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.134979] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.134988] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.134999] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.135006] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.138830] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.138844] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.138852] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.138861] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.138867] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.138875] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.138884] [00000e27] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.138893] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.138903] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.138911] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.138918] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.138925] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.138940] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.138946] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.139248] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.139270] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.139279] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.139288] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.139296] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.139305] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.139315] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.139327] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.139337] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.139346] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.139356] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.139365] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.139383] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.139389] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.139399] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.139407] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.139414] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.139422] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.139429] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.139437] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.139444] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.139453] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.139464] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.139471] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.139477] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.139487] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.139501] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.139509] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.143260] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.143271] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.143279] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.143286] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.143296] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.143308] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.143316] [00000e27] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.143325] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.143335] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.143343] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.143348] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.143354] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.143366] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.143371] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.143678] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.143693] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.143697] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.143712] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.143725] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.143733] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.143742] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.143752] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.143763] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.143771] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.143778] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.143788] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.143802] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.143817] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.143827] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.143836] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.143844] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.143852] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.143860] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.143868] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.143876] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.143885] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.143896] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.143903] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.143912] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.143921] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.143934] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.143941] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.147730] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.147764] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.147780] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.147793] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.147806] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.147821] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.147829] [00000e27] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.147846] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.147867] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.147879] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.147891] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.147902] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.147931] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.147943] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.148142] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.148164] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.148173] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.148185] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.148194] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.148202] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.148215] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.148227] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.148239] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.148247] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.148255] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.148265] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.148281] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.148296] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.148305] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.148311] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.148317] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.148325] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.148333] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.148342] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.148350] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.148361] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.148377] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.148388] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.148399] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.148414] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.148437] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.148450] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.152154] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.152179] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.152184] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.152188] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.152193] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.152198] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.152202] [00000e27] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.152208] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.152223] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.152230] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.152236] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.152245] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.152273] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.152281] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.152571] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.152591] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.152597] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.152603] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.152610] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.152618] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.152625] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.152633] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.152644] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.152651] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.152658] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.152666] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.152678] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.152687] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.152691] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.152695] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.152699] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.152702] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.152705] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.152709] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.152712] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.152716] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.152721] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.152724] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.152727] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.152733] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.152747] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.152753] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.156610] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.156644] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.156653] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.156660] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.156667] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.156675] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.156679] [00000e27] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.156686] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.156699] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.156704] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.156708] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.156714] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.156730] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.156734] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.156992] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.157002] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.157006] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.157010] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.157015] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.157020] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.157023] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.157028] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.157034] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.157038] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.157045] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.157051] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.157060] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.157063] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.157080] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.157090] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.157094] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.157098] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.157102] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.157108] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.157111] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.157117] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.157125] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.157128] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.157132] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.157138] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.157148] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.157152] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.161024] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.161042] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.161049] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.161055] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.161060] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.161067] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.161071] [00000e27] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.161078] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.161089] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.161093] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.161097] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.161104] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.161119] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.161123] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.161414] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.161420] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.161425] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.161430] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.161437] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.161443] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.161447] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.161452] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.161458] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.161463] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.161468] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.161474] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.161480] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.161484] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.161492] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.161494] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.161498] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.161500] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.161503] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.161507] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.161512] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.161518] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.161523] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.161527] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.161530] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.161535] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.161543] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.161548] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.165404] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.165410] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=96
[ 3.165415] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.165420] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.165424] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.165429] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.165434] [00000e27] libusb: debug [sync_transfer_cb] actual_length=96
[ 3.165440] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.165445] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.165449] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.165453] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.165459] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1
[ 3.165466] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.165471] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.265517] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.265541] [00000e27] libusb: debug [handle_events] timerfd triggered
[ 3.265546] [00000e27] libusb: debug [libusb_cancel_transfer] transfer 0x55bf58851ea0
[ 3.265601] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.265610] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[ 3.265616] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.265623] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 3.265628] [00000e27] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 3.265634] [00000e27] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 3.265638] [00000e27] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 3.265644] [00000e27] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 3.265649] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.265655] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.265663] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.265673] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
WARNING: Empty bulk transfer after receiving packet failed. Continuing anyway...
WARNING: Empty bulk transfer after receiving packet failed. Continuing anyway...
[ 3.265713] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.265721] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.265728] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.265739] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.265753] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.265759] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.265876] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.265894] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.265903] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.265911] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.265918] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.265925] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.265931] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.265960] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.265972] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.265978] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.265985] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.265994] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.266007] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.266019] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.266029] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.266037] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.266045] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.266053] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.266060] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.266067] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.266073] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.266081] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
[ 3.266091] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf58851ea0
[ 3.266098] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf58851ea0
[ 3.266105] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.266113] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.266122] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.266127] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.269819] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.269830] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 3.269838] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.269846] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.269851] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.269859] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf58851ea0 has callback 0x7f156b89cb30
[ 3.269866] [00000e27] libusb: debug [sync_transfer_cb] actual_length=8
[ 3.269875] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf58851ea0
PIT file download successful.

Uploading MODEM
[ 3.269909] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf5885bc30
[ 3.269914] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf5885bc30
[ 3.269920] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.269928] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.269955] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.269962] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.270236] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.270247] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.270252] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.270257] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.270264] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.270272] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf5885bc30 has callback 0x7f156b89cb30
[ 3.270279] [00000e27] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.270286] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf5885bc30
[ 3.270295] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf5885bc30
[ 3.270303] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf5885bc30
[ 3.270310] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.270317] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.270330] [00000e27] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.270341] [00000e27] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.270351] [00000e27] libusb: debug [handle_events] poll() returned 1
[ 3.270357] [00000e27] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.270363] [00000e27] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.270368] [00000e27] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.270374] [00000e27] libusb: debug [disarm_timerfd] 
[ 3.270382] [00000e27] libusb: debug [usbi_handle_transfer_completion] transfer 0x55bf5885bc30 has callback 0x7f156b89cb30
[ 3.270388] [00000e27] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.270394] [00000e27] libusb: debug [libusb_free_transfer] transfer 0x55bf5885bc30
[ 3.270416] [00000e27] libusb: debug [libusb_alloc_transfer] transfer 0x55bf5885bc30
[ 3.270421] [00000e27] libusb: debug [libusb_submit_transfer] transfer 0x55bf5885bc30
[ 3.270428] [00000e27] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.270436] [00000e27] libusb: debug [submit_bulk_transfer] need 1 urbs
lsimplify commented 6 years ago

This was the output from the command when #347 was applied. (It didn't work. The phone started booting during the flashing.)

$ ~/Heimdall/build/bin/heimdall flash --MODEM modem.bin --resume --no-reboot --usb-log-level debug --verbose --stdout-errors

Heimdall v1.4.2

Copyright (c) 2010-2017 Benjamin Dobell, Glass Echidna
http://www.glassechidna.com.au/

This software is provided free of charge. Copying and redistribution is
encouraged.

If you appreciate this software and you would like to support future
development please consider donating:
http://www.glassechidna.com.au/donate/

Initialising connection...
Detecting device...
[timestamp] [threadID] facility level [function call] <message>
--------------------------------------------------------------------------------
[ 0.006568] [00000c7c] libusb: debug [libusb_get_device_list] 
[ 0.006605] [00000c7c] libusb: debug [discovered_devs_append] need to increase capacity
[ 0.006624] [00000c7c] libusb: debug [libusb_get_device_descriptor] 
[ 0.006639] [00000c7c] libusb: debug [libusb_get_device_descriptor] 
[ 0.006649] [00000c7c] libusb: debug [libusb_get_device_descriptor] 
[ 0.006658] [00000c7c] libusb: debug [libusb_get_device_descriptor] 
[ 0.006687] [00000c7c] libusb: debug [libusb_get_device_descriptor] 
[ 0.006700] [00000c7c] libusb: debug [libusb_get_device_descriptor] 
[ 0.006710] [00000c7c] libusb: debug [libusb_get_device_descriptor] 
[ 0.006719] [00000c7c] libusb: debug [libusb_get_device_descriptor] 
[ 0.006731] [00000c7c] libusb: debug [libusb_open] open 2.10
[ 0.006782] [00000c7c] libusb: debug [usbi_add_pollfd] add fd 10 events 4
[ 0.006793] [00000c7c] libusb: debug [libusb_get_device_descriptor] 
[ 0.006808] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b4280
[ 0.006820] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b4280
[ 0.006834] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.006870] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.006881] [00000c7c] libusb: debug [handle_events] poll fds modified, reallocating
[ 0.006894] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.016902] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 0.016925] [00000c7c] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=4
[ 0.016929] [00000c7c] libusb: debug [handle_control_completion] handling completion status 0
[ 0.016934] [00000c7c] libusb: debug [disarm_timerfd] 
[ 0.016949] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b4280 has callback 0x7ff7c63bab30
[ 0.016953] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=4
[ 0.016984] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b4280
[ 0.016998] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b3320
[ 0.017001] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b3320
[ 0.017010] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.017037] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.017042] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.017136] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 0.017158] [00000c7c] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=16
[ 0.017165] [00000c7c] libusb: debug [handle_control_completion] handling completion status 0
[ 0.017171] [00000c7c] libusb: debug [disarm_timerfd] 
[ 0.017177] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b3320 has callback 0x7ff7c63bab30
[ 0.017181] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=16
[ 0.017193] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b3320
      Manufacturer: "SAMSUNG"
[ 0.017213] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864a9180
[ 0.017217] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864a9180
[ 0.017221] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.017237] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.017241] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.027132] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 0.027157] [00000c7c] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=4
[ 0.027161] [00000c7c] libusb: debug [handle_control_completion] handling completion status 0
[ 0.027168] [00000c7c] libusb: debug [disarm_timerfd] 
[ 0.027176] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864a9180 has callback 0x7ff7c63bab30
[ 0.027182] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=4
[ 0.027189] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864a9180
[ 0.027197] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864a8460
[ 0.027202] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864a8460
[ 0.027207] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.027222] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.027226] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.037143] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 0.037185] [00000c7c] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=28
[ 0.037191] [00000c7c] libusb: debug [handle_control_completion] handling completion status 0
[ 0.037199] [00000c7c] libusb: debug [disarm_timerfd] 
[ 0.037214] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864a8460 has callback 0x7ff7c63bab30
[ 0.037234] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=28
[ 0.037246] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864a8460
           Product: "Gadget Serial"

            length: 18
      device class: 2
               S/N: 0
           VID:PID: 04E8:6601
         bcdDevice: 021B
   iMan:iProd:iSer: 1:2:0
          nb confs: 1
[ 0.037319] [00000c7c] libusb: debug [libusb_get_config_descriptor] index 0

interface[0].altsetting[0]: num endpoints = 1
   Class.SubClass.Protocol: 02.02.01
       endpoint[0].address: 83
           max packet size: 0010
          polling interval: 09

interface[1].altsetting[0]: num endpoints = 2
   Class.SubClass.Protocol: 0A.00.00
       endpoint[0].address: 81
           max packet size: 0200
          polling interval: 00
       endpoint[1].address: 02
           max packet size: 0200
          polling interval: 00
Claiming interface...
[ 0.037438] [00000c7c] libusb: debug [libusb_claim_interface] interface 1
Setting up interface...
[ 0.037473] [00000c7c] libusb: debug [libusb_set_interface_alt_setting] interface 1 altsetting 0

Beginning session...
[ 0.047228] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864a8f60
[ 0.047244] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864a8f60
[ 0.047252] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 0.047261] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 0.047281] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.047286] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.057200] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 0.057238] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 0.057246] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.057254] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.057263] [00000c7c] libusb: debug [disarm_timerfd] 
[ 0.057273] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864a8f60 has callback 0x7ff7c63bab30
[ 0.057282] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 0.057292] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864a8f60
[ 0.057305] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1260
[ 0.057312] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1260
[ 0.057318] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 0.057327] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 0.057361] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.057376] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.057388] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 0.057401] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 0.057408] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.057422] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.057430] [00000c7c] libusb: debug [disarm_timerfd] 
[ 0.057437] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1260 has callback 0x7ff7c63bab30
[ 0.057444] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 0.057451] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1260
[ 0.057461] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 0.057468] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 0.057477] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 0.057487] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 0.057500] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.057508] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.061086] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 0.061099] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 0.061105] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.061113] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.061118] [00000c7c] libusb: debug [disarm_timerfd] 
[ 0.061127] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 0.061133] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=8
[ 0.061141] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340

Some devices may take up to 2 minutes to respond.
Please be patient!

Session begun.

[ 3.061470] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.061509] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.061524] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.061557] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.061627] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.061643] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.061755] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.061775] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.061782] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.061788] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.061794] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.061801] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.061811] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.061823] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.061838] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.061845] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.061851] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.061860] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.061883] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.061903] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.061918] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.061929] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.061938] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.061951] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.061959] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.061971] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.061982] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.061996] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.062011] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.062020] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.062031] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.062043] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.062061] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.062069] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.065807] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.065858] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 3.065882] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.065901] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.065937] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.065960] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.065982] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=8
[ 3.066016] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
Downloading device's PIT file...
[ 3.066085] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.066099] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.066108] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.066133] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.066193] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.066208] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.066301] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.066315] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.066327] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.066339] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.066350] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.066361] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.066373] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.066388] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.066400] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.066407] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.066418] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.066432] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.066451] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.066468] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.066483] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.066493] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.066501] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.066507] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.066514] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.066525] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.066537] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.066551] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.066564] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.066572] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.066577] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.066587] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.066602] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.066612] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.104127] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.104192] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 3.104198] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.104204] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.104211] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.104219] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.104227] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=8
[ 3.104246] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.104270] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.104276] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.104285] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.104296] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.104329] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.104337] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.104537] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.104566] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.104569] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.104573] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.104578] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.104584] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.104591] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.104600] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.104611] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.104617] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.104623] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.104631] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.104650] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.104666] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.104672] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.104678] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.104686] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.104691] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.104697] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.104704] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.104718] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.104722] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.104738] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.104745] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.104748] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.104755] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.104771] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.104778] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.108500] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.108509] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.108513] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.108518] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.108523] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.108529] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.108533] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.108539] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.108546] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.108550] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.108554] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.108560] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.108571] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.108574] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.108909] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.108914] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.108916] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.108918] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.108920] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.108923] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.108925] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.108928] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.108931] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.108934] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.108937] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.108943] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.108947] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.108951] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.108959] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.108963] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.108968] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.108972] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.108977] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.108982] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.108985] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.108990] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.108997] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.109001] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.109006] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.109011] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.109018] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.109022] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.112934] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.112943] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.112949] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.112954] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.112960] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.112966] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.112972] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.112978] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.112986] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.112991] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.112997] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.113005] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.113015] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.113019] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.113319] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.113324] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.113327] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.113330] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.113332] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.113335] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.113337] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.113340] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.113344] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.113347] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.113355] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.113360] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.113367] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.113376] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.113382] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.113388] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.113394] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.113400] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.113403] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.113409] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.113414] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.113420] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.113426] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.113432] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.113438] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.113443] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.113452] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.113457] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.117313] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.117319] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.117322] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.117325] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.117328] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.117331] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.117334] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.117338] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.117342] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.117346] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.117352] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.117359] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.117370] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.117375] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.117729] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.117736] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.117742] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.117748] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.117754] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.117760] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.117767] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.117774] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.117781] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.117788] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.117794] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.117802] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.117810] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.117821] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.117829] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.117834] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.117841] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.117844] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.117847] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.117851] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.117858] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.117866] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.117873] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.117878] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.117881] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.117887] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.117894] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.117899] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.121753] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.121760] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.121767] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.121773] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.121779] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.121786] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.121792] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.121799] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.121807] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.121813] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.121820] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.121829] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.121838] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.121844] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.122162] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.122170] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.122174] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.122179] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.122182] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.122187] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.122193] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.122198] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.122204] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.122209] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.122215] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.122223] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.122235] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.122246] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.122256] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.122264] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.122270] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.122278] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.122284] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.122291] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.122299] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.122307] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.122315] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.122322] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.122329] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.122337] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.122349] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.122355] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.126207] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.126232] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.126240] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.126247] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.126258] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.126271] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.126278] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.126289] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.126303] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.126308] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.126315] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.126324] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.126348] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.126353] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.126578] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.126590] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.126598] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.126605] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.126612] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.126620] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.126627] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.126636] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.126644] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.126651] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.126658] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.126694] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.126705] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.126717] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.126724] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.126730] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.126736] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.126742] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.126747] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.126753] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.126758] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.126765] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.126774] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.126778] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.126784] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.126790] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.126801] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.126805] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.130597] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.130635] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.130641] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.130647] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.130654] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.130662] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.130682] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.130690] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.130701] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.130705] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.130711] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.130719] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.130739] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.130744] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.131006] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.131019] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.131026] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.131033] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.131039] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.131047] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.131053] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.131060] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.131068] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.131074] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.131079] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.131087] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.131099] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.131108] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.131115] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.131120] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.131126] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.131131] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.131136] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.131142] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.131148] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.131154] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.131161] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.131166] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.131172] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.131179] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.131189] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.131193] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.135022] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.135042] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.135048] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.135053] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.135059] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.135065] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.135071] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.135078] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.135088] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.135092] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.135097] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.135104] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.135119] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.135123] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.135415] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.135421] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.135427] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.135432] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.135438] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.135444] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.135449] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.135455] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.135461] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.135469] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.135473] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.135479] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.135487] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.135498] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.135505] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.135511] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.135517] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.135521] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.135526] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.135532] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.135537] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.135542] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.135549] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.135555] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.135559] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.135566] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.135574] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.135579] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.139515] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.139598] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.139612] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.139623] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.139633] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.139645] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.139654] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=500
[ 3.139667] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.139693] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.139697] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.139704] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.139715] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.139747] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.139753] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.139864] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.139877] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.139881] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.139884] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.139888] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.139892] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.139895] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.139900] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.139906] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.139911] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.139916] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.139921] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.139936] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.139951] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.139963] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.139971] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.139979] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.139983] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.139991] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.139997] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.140003] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.140010] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.140020] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.140026] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.140029] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.140035] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.140046] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.140051] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.143841] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.143851] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=96
[ 3.143857] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.143863] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.143869] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.143874] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.143879] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=96
[ 3.143895] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.143901] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.143905] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.143909] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.143915] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1
[ 3.143923] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.143927] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.244007] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.244039] [00000c7c] libusb: debug [handle_events] timerfd triggered
[ 3.244051] [00000c7c] libusb: debug [libusb_cancel_transfer] transfer 0x55d8864b1340
[ 3.244119] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.244140] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[ 3.244163] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.244176] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 3.244185] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 3.244193] [00000c7c] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 3.244200] [00000c7c] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 3.244208] [00000c7c] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 3.244216] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.244222] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.244229] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.244240] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
WARNING: Empty bulk transfer after receiving packet failed. Continuing anyway...
WARNING: Empty bulk transfer after receiving packet failed. Continuing anyway...
[ 3.244278] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.244285] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.244293] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.244306] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.244327] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.244333] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.244425] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.244436] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.244444] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.244452] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.244461] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.244470] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.244478] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.244487] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.244497] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.244505] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.244513] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.244525] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.244537] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.244549] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.244555] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.244561] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.244567] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.244575] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.244582] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.244590] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.244599] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.244609] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
[ 3.244618] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864b1340
[ 3.244623] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864b1340
[ 3.244629] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.244638] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.244652] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.244660] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.248395] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.248408] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 3.248418] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.248426] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.248434] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.248443] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864b1340 has callback 0x7ff7c63bab30
[ 3.248451] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=8
[ 3.248462] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864b1340
PIT file download successful.

Uploading MODEM
[ 3.248508] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[ 3.248517] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[ 3.248525] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.248535] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.248548] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.248555] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.248806] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.248815] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.248820] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.248829] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.248837] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.248846] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864adb70 has callback 0x7ff7c63bab30
[ 3.248854] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.248864] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
[ 3.248872] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[ 3.248878] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[ 3.248886] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.248895] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.248907] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.248920] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.248931] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.248938] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.248944] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.248951] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.248960] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.248967] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864adb70 has callback 0x7ff7c63bab30
[ 3.248976] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.248985] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
[ 3.249009] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[ 3.249015] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[ 3.249018] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.249025] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.249035] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.249041] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.252786] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.252798] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 3.252806] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.252815] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.252823] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.252832] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864adb70 has callback 0x7ff7c63bab30
[ 3.252841] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=8
[ 3.252848] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
0%[ 3.252867] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[ 3.252875] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[ 3.252882] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.252892] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.252905] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.252911] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.253241] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.253267] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.253280] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.253293] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.253301] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.253311] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864adb70 has callback 0x7ff7c63bab30
[ 3.253319] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 3.253329] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
[ 3.253343] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[ 3.253353] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[ 3.253364] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 3.253377] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 3.253393] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.253406] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.253418] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.253428] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 3.253439] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.253450] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.253459] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.253468] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864adb70 has callback 0x7ff7c63bab30
[ 3.253475] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.253486] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
[ 3.253543] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[ 3.253551] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[ 3.253562] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.253575] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.253589] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.253597] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.257191] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 3.257205] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 3.257215] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.257224] [00000c7c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.257233] [00000c7c] libusb: debug [disarm_timerfd] 
[ 3.257243] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864adb70 has callback 0x7ff7c63bab30
[ 3.257254] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=8
[ 3.257265] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
[ 3.257514] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[ 3.257524] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[ 3.257534] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.257546] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 131072
[ 3.257605] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.257613] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 6.257581] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 6.257622] [00000c7c] libusb: debug [handle_events] timerfd triggered
[ 6.257632] [00000c7c] libusb: debug [libusb_cancel_transfer] transfer 0x55d8864adb70
[ 6.257690] [00000c7c] libusb: debug [disarm_timerfd] 
[ 6.257718] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[ 6.257736] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 6.257774] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 6.257792] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 6.257804] [00000c7c] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 6.257829] [00000c7c] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 6.257859] [00000c7c] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 6.257926] [00000c7c] libusb: debug [disarm_timerfd] 
[ 6.257961] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864adb70 has callback 0x7ff7c63bab30
[ 6.257978] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 6.257999] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
ERROR: libusb error -7 whilst sending bulk transfer.ERROR: libusb error -7 whilst sending bulk transfer. Retrying...
 Retrying...
[ 6.508253] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[ 6.508299] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[ 6.508310] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 6.508325] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 131072
[ 6.508493] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 6.508522] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 9.508348] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 9.508382] [00000c7c] libusb: debug [handle_events] timerfd triggered
[ 9.508390] [00000c7c] libusb: debug [libusb_cancel_transfer] transfer 0x55d8864adb70
[ 9.508450] [00000c7c] libusb: debug [disarm_timerfd] 
[ 9.508465] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[ 9.508481] [00000c7c] libusb: debug [handle_events] poll() returned 1
[ 9.508504] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 9.508515] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 9.508530] [00000c7c] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 9.508544] [00000c7c] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 9.508567] [00000c7c] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 9.508583] [00000c7c] libusb: debug [disarm_timerfd] 
[ 9.508596] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864adb70 has callback 0x7ff7c63bab30
[ 9.508610] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[ 9.508628] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
ERROR: libusb error -7 whilst sending bulk transfer.ERROR: libusb error -7 whilst sending bulk transfer. Retrying...
 Retrying...
[10.008858] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[10.008908] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[10.008924] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[10.008956] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 131072
[10.009110] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[10.009126] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[13.009017] [00000c7c] libusb: debug [handle_events] poll() returned 1
[13.009057] [00000c7c] libusb: debug [handle_events] timerfd triggered
[13.009066] [00000c7c] libusb: debug [libusb_cancel_transfer] transfer 0x55d8864adb70
[13.009131] [00000c7c] libusb: debug [disarm_timerfd] 
[13.009149] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[13.009161] [00000c7c] libusb: debug [handle_events] poll() returned 1
[13.009183] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[13.009196] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[13.009204] [00000c7c] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[13.009222] [00000c7c] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[13.009254] [00000c7c] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[13.009281] [00000c7c] libusb: debug [disarm_timerfd] 
[13.009308] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864adb70 has callback 0x7ff7c63bab30
[13.009329] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[13.009350] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
ERROR: libusb error -7 whilst sending bulk transfer.ERROR: libusb error -7 whilst sending bulk transfer. Retrying...
 Retrying...
[13.759567] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[13.759617] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[13.759633] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[13.759666] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 131072
[13.759812] [00000c7c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[13.759829] [00000c7c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[16.041062] [00000c7c] libusb: debug [handle_events] poll() returned 1
[16.041114] [00000c7c] libusb: debug [reap_for_handle] urb type=3 status=-71 transferred=0
[16.041123] [00000c7c] libusb: debug [handle_bulk_completion] handling completion status -71 of bulk urb 1/1
[16.041132] [00000c7c] libusb: debug [handle_bulk_completion] low level error -71
[16.041148] [00000c7c] libusb: debug [disarm_timerfd] 
[16.041197] [00000c7c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55d8864adb70 has callback 0x7ff7c63bab30
[16.041231] [00000c7c] libusb: debug [sync_transfer_cb] actual_length=0
[16.041261] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
ERROR: libusb error -1 whilst sending bulk transfer.ERROR: libusb error -1 whilst sending bulk transfer. Retrying...
 Retrying...
[16.048028] [00000c7d] libusb: debug [linux_get_device_address] getting address for device: 2-2 detached: 1
[16.048081] [00000c7d] libusb: debug [udev_hotplug_event] udev hotplug event. action: remove.
[17.041539] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[17.041581] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[17.041591] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[17.041603] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 131072
[17.041617] [00000c7c] libusb: debug [submit_bulk_transfer] first URB failed, easy peasy
[17.041635] [00000c7c] libusb: debug [disarm_timerfd] 
[17.041670] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
ERROR: libusb error -4 whilst sending bulk transfer.ERROR: libusb error -4 whilst sending bulk transfer. Retrying...
 Retrying...
[18.291847] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864adb70
[18.291883] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864adb70
[18.291896] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[18.291917] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 131072
[18.291936] [00000c7c] libusb: debug [submit_bulk_transfer] first URB failed, easy peasy
[18.291950] [00000c7c] libusb: debug [disarm_timerfd] 
[18.291964] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864adb70
ERROR: libusb error -4 whilst sending bulk transfer.ERROR: libusb error -4 whilst sending bulk transfer.

ERROR: Failed to send file part packet!
ERROR: Failed to send file part packet!
ERROR: MODEM upload failed!

ERROR: MODEM upload failed!

Ending session...
[18.292166] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864ab760
[18.292180] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864ab760
[18.292193] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[18.292209] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[18.292223] [00000c7c] libusb: debug [submit_bulk_transfer] first URB failed, easy peasy
[18.292235] [00000c7c] libusb: debug [disarm_timerfd] 
[18.292247] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864ab760
ERROR: libusb error -4 whilst sending bulk transfer.ERROR: libusb error -4 whilst sending bulk transfer. Retrying...
 Retrying...
[18.542441] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864ab760
[18.542482] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864ab760
[18.542491] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[18.542504] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[18.542517] [00000c7c] libusb: debug [submit_bulk_transfer] first URB failed, easy peasy
[18.542537] [00000c7c] libusb: debug [disarm_timerfd] 
[18.542567] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864ab760
ERROR: libusb error -4 whilst sending bulk transfer.ERROR: libusb error -4 whilst sending bulk transfer. Retrying...
 Retrying...
[19.042774] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864ab760
[19.042812] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864ab760
[19.042820] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[19.042832] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[19.042850] [00000c7c] libusb: debug [submit_bulk_transfer] first URB failed, easy peasy
[19.042877] [00000c7c] libusb: debug [disarm_timerfd] 
[19.042893] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864ab760
ERROR: libusb error -4 whilst sending bulk transfer.ERROR: libusb error -4 whilst sending bulk transfer. Retrying...
 Retrying...
[19.793066] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864ab760
[19.793102] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864ab760
[19.793114] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[19.793138] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[19.793161] [00000c7c] libusb: debug [submit_bulk_transfer] first URB failed, easy peasy
[19.793186] [00000c7c] libusb: debug [disarm_timerfd] 
[19.793200] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864ab760
ERROR: libusb error -4 whilst sending bulk transfer.ERROR: libusb error -4 whilst sending bulk transfer. Retrying...
 Retrying...
[20.242113] [00000c7d] libusb: debug [linux_get_device_address] getting address for device: 2-2 detached: 0
[20.242133] [00000c7d] libusb: debug [linux_get_device_address] scan 2-2
[20.242927] [00000c7d] libusb: debug [linux_get_device_address] bus=2 dev=11
[20.242940] [00000c7d] libusb: debug [udev_hotplug_event] udev hotplug event. action: add.
[20.242945] [00000c7d] libusb: debug [linux_enumerate_device] busnum 2 devaddr 11 session_id 523
[20.242950] [00000c7d] libusb: debug [linux_enumerate_device] allocating new device for 2/11 (session 523)
[20.242982] [00000c7d] libusb: debug [linux_get_parent_info] Dev 0x7ff7c0002980 (2-2) has parent 0x55d8864b1af0 (usb2) port 2
[20.488438] [00000c7d] libusb: debug [linux_get_device_address] getting address for device: 2-2 detached: 1
[20.488492] [00000c7d] libusb: debug [udev_hotplug_event] udev hotplug event. action: remove.
[20.793406] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864ab760
[20.793444] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864ab760
[20.793452] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[20.793464] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[20.793474] [00000c7c] libusb: debug [submit_bulk_transfer] first URB failed, easy peasy
[20.793485] [00000c7c] libusb: debug [disarm_timerfd] 
[20.793494] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864ab760
ERROR: libusb error -4 whilst sending bulk transfer.ERROR: libusb error -4 whilst sending bulk transfer. Retrying...
 Retrying...
[22.043693] [00000c7c] libusb: debug [libusb_alloc_transfer] transfer 0x55d8864ab760
[22.043735] [00000c7c] libusb: debug [libusb_submit_transfer] transfer 0x55d8864ab760
[22.043745] [00000c7c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[22.043758] [00000c7c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[22.043771] [00000c7c] libusb: debug [submit_bulk_transfer] first URB failed, easy peasy
[22.043785] [00000c7c] libusb: debug [disarm_timerfd] 
[22.043813] [00000c7c] libusb: debug [libusb_free_transfer] transfer 0x55d8864ab760
ERROR: libusb error -4 whilst sending bulk transfer.ERROR: libusb error -4 whilst sending bulk transfer.

ERROR: Failed to send end session packet!
ERROR: Failed to send end session packet!
Releasing device interface...
[22.043925] [00000c7c] libusb: debug [libusb_release_interface] interface 1

[22.043971] [00000c7c] libusb: debug [libusb_close] 
[22.043997] [00000c7c] libusb: debug [usbi_remove_pollfd] remove fd 10
[22.044043] [00000c7c] libusb: debug [libusb_exit] 
[22.044055] [00000c7c] libusb: debug [libusb_exit] destroying default context
JonnyTech commented 6 years ago

My experience is that the --no-reboot and --resume commands only work on a very few devices. Try flashing in one go, one command per reboot such as this example:

heimdall flash --FACTORYFS factoryfs.rfs --CACHE cache.rfs --DBDATA dbdata.rfs --IBL+PBL boot.bin --SBL Sbl.bin --PARAM param.lfs --KERNEL zImage --MODEM modem.bin

Flash one or more partitions then reboot back to download mode and run another command and repeat until done. One single heimdall command per session.

lsimplify commented 6 years ago

@JonnyTech The first time I tried flashing the recovery partition I did not use --no-reboot or --resume. My command is in the original post: heimdall flash --KERNEL <filename> It softbricked without those two options. This is the basis of my bug report.

Also, when sending one partition fails, I really don't see how sending those eight partitions will result in zero failures.

I'm wondering if this issue is a bug of Heimdall, or an artifact of the fact that the Samsung software is actively preventing firmware modifications.

I might just try to flash back rooted stock firmware with Odin and proceed using ROM Manager.

lsimplify commented 6 years ago

One more piece of info for whomever it may concern: Somewhere above I mentioned flashing a modem file, well here is the archive where I got the modem file from: http://www.mediafire.com/file/g9wrj3gg86ws60c/Sprint_EA24_modem.zip And the actual sha1 sum of the modem.bin file in the zip archive is: b3a0cf1c0b687b1cddd3a4ad717832b8e486b465

mahdi-ln commented 3 years ago

Same problem. it stuck a little at 0% and then ERROR: Failed to send file part packet!

$ sudo ./heimdall  flash --RECOVERY '/TWRP-3.3.1-trhpltexx-20190610.img'
[sudo] password for m: 
Heimdall v1.4.2

.
.
.

Uploading RECOVERY
0%
ERROR: Failed to send file part packet!
ERROR: RECOVERY upload failed!

Ending session...
ERROR: Failed to receive session end confirmation!
Releasing device interface...
amo13 commented 3 years ago

On a few older samsung devices the recovery partition was not RECOVERY, but Kernel2, so try sudo ./heimdall flash --Kernel2 twrp.img Otherwise, was does the pit file information say? You should be able to see an overview of the present partitions and this way find out which one is the recovery partition.

mahdi-ln commented 3 years ago

On a few older samsung devices the recovery partition was not RECOVERY, but Kernel2, so try sudo ./heimdall flash --Kernel2 twrp.img Otherwise, was does the pit file information say? You should be able to see an overview of the present partitions and this way find out which one is the recovery partition.

ERROR: Partition "Kernel2" does not exist in the specified PIT. PIT output attached here: https://github.com/Benjamin-Dobell/Heimdall/issues/488 I found RECOVERY partition in list