Heimdall 1.4.0 Ubuntu 12.04 64 bit with libusbx 1.0.16 two runs of: ./heimdall print-pit --verbose --no-reboot --usb-log-level debug

heimdall_first_run.log

Heimdall v1.4.0

Copyright (c) 2010-2013, 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.003005] [00001805] libusbx: debug [libusb_get_device_list] 
[ 0.003029] [00001805] libusbx: debug [discovered_devs_append] need to increase capacity
[ 0.003038] [00001805] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003043] [00001805] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003047] [00001805] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003050] [00001805] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003054] [00001805] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003058] [00001805] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003061] [00001805] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003065] [00001805] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003068] [00001805] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003072] [00001805] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003078] [00001805] libusbx: debug [libusb_open] open 3.9
[ 0.003100] [00001805] libusbx: debug [op_open] getcap not available
[ 0.003106] [00001805] libusbx: debug [usbi_add_pollfd] add fd 9 events 4
[ 0.003121] [00001805] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003129] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.003153] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.003159] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.003242] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.003269] [00001805] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=4
[ 0.003275] [00001805] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.003281] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.003287] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.003292] [00001805] libusbx: debug [sync_transfer_cb] actual_length=4
[ 0.003307] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.003320] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.003327] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.003414] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.003436] [00001805] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=16
[ 0.003444] [00001805] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.003451] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.003458] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.003464] [00001805] libusbx: debug [sync_transfer_cb] actual_length=16
      Manufacturer: "SAMSUNG"
[ 0.003478] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.003491] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.003497] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.003580] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.003593] [00001805] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=4
[ 0.003600] [00001805] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.003605] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.003610] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.003614] [00001805] libusbx: debug [sync_transfer_cb] actual_length=4
[ 0.003631] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.003641] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.003646] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.003726] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.003733] [00001805] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=28
[ 0.003738] [00001805] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.003742] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.003747] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.003751] [00001805] libusbx: debug [sync_transfer_cb] actual_length=28
           Product: "Gadget Serial"

            length: 18
      device class: 2
               S/N: 0
           VID:PID: 04E8:685D
         bcdDevice: 021B
   iMan:iProd:iSer: 1:2:0
          nb confs: 1
[ 0.003781] [00001805] libusbx: 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.003831] [00001805] libusbx: debug [libusb_claim_interface] interface 1
Attempt failed. Detaching driver...
[ 0.003841] [00001805] libusbx: debug [libusb_detach_kernel_driver] interface 1
Claiming interface again...
[ 0.003966] [00001805] libusbx: debug [libusb_claim_interface] interface 1
Setting up interface...
[ 0.003984] [00001805] libusbx: debug [libusb_set_interface_alt_setting] interface 1 altsetting 0

Initialising protocol...
[ 0.004129] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004139] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004144] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004185] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.004192] [00001805] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=0
[ 0.004197] [00001805] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.004201] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.004206] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.004210] [00001805] libusbx: debug [sync_transfer_cb] actual_length=0
[ 0.004217] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004225] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004230] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004302] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.004309] [00001805] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=7
[ 0.004314] [00001805] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.004318] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.004322] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.004327] [00001805] libusbx: debug [sync_transfer_cb] actual_length=7
[ 0.004333] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004340] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004345] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004386] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.004393] [00001805] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=0
[ 0.004404] [00001805] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.004409] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.004414] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.004418] [00001805] libusbx: debug [sync_transfer_cb] actual_length=0
[ 0.004424] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004432] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004437] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004676] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.004689] [00001805] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=0
[ 0.004694] [00001805] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.004699] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.004704] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.004708] [00001805] libusbx: debug [sync_transfer_cb] actual_length=0
[ 0.004716] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004726] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004731] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004804] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.004811] [00001805] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=7
[ 0.004816] [00001805] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.004820] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.004825] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.004829] [00001805] libusbx: debug [sync_transfer_cb] actual_length=7
[ 0.004835] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004843] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004847] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004888] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.004895] [00001805] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=0
[ 0.004900] [00001805] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.004904] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.004908] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.004913] [00001805] libusbx: debug [sync_transfer_cb] actual_length=0
[ 0.004920] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004926] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4
[ 0.004934] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004939] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004951] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.004956] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 0.004961] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.004965] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.004969] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.004973] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.004977] [00001805] libusbx: debug [sync_transfer_cb] actual_length=4
[ 0.004983] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004988] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 7
[ 0.004995] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.005006] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.006724] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.006731] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 0.006736] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.006740] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.006745] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.006749] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd60608 has callback 0x7f80213baf30
[ 0.006753] [00001805] libusbx: debug [sync_transfer_cb] actual_length=4
Protocol initialisation successful.

Beginning session...
[ 0.006775] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 0.006781] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 0.006789] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.006794] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.006855] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.006867] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 0.006874] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.006880] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.006886] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.006892] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 0.006898] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 0.006907] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 0.006915] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 0.006923] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.006928] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.009699] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 0.009723] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 0.009731] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.009738] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.009743] [00001805] libusbx: debug [disarm_timerfd] 
[ 0.009749] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 0.009754] [00001805] libusbx: debug [sync_transfer_cb] actual_length=8

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

[ 3.009844] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.009878] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.009898] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.009905] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.009992] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.010002] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.010008] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.010013] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.010019] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.010025] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.010034] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.010044] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.010060] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.010069] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.010074] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.016903] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.016926] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 3.016934] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.016940] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.016946] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.016952] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.016957] [00001805] libusbx: debug [sync_transfer_cb] actual_length=8
Session begun.

Downloading device's PIT file...
[ 3.016988] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.016995] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.017006] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.017012] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.017144] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.017166] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.017174] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.017181] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.017187] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.017193] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.017198] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.017208] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.017215] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.017226] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.017232] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.026778] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.026801] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 3.026810] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.026816] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.026822] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.026828] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.026833] [00001805] libusbx: debug [sync_transfer_cb] actual_length=8
[ 3.026849] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.026857] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.026868] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.026874] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.027004] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.027026] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.027034] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.027041] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.027047] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.027052] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.027057] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.027069] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.027084] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.027096] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.027102] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.029849] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.029873] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.029881] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.029887] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.029893] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.029899] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.029903] [00001805] libusbx: debug [sync_transfer_cb] actual_length=500
[ 3.029924] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.029931] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.029943] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.029949] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.030080] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.030101] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.030110] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.030117] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.030122] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.030128] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.030133] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.030144] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.030151] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.030162] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.030168] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.032913] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.032936] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.032945] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.032951] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.032956] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.032962] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.032967] [00001805] libusbx: debug [sync_transfer_cb] actual_length=500
[ 3.032979] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.032986] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.032998] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.033003] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.033135] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.033157] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.033165] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.033172] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.033178] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.033183] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.033188] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.033208] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.033215] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.033227] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.033232] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.035973] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.035995] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.036003] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.036010] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.036016] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.036022] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.036027] [00001805] libusbx: debug [sync_transfer_cb] actual_length=500
[ 3.036039] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.036046] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.036058] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.036063] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.036196] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.036219] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.036227] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.036234] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.036239] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.036245] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.036250] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.036261] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.036268] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.036280] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.036285] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.039033] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.039055] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.039064] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.039070] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.039076] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.039082] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.039087] [00001805] libusbx: debug [sync_transfer_cb] actual_length=500
[ 3.039098] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.039105] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.039117] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.039123] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.039255] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.039276] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.039285] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.039292] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.039297] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.039303] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.039308] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.039327] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.039335] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.039346] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.039352] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.042100] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.042124] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.042132] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.042139] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.042144] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.042149] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.042154] [00001805] libusbx: debug [sync_transfer_cb] actual_length=500
[ 3.042166] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.042173] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.042185] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.042190] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.042320] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.042342] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.042351] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.042358] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.042363] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.042369] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.042374] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.042385] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.042391] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.042403] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.042408] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.045163] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.045186] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.045195] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.045202] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.045207] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.045213] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.045218] [00001805] libusbx: debug [sync_transfer_cb] actual_length=500
[ 3.045230] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.045237] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.045249] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.045254] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.045381] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.045403] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.045411] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.045418] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.045423] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.045429] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.045443] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.045455] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.045462] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.045474] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.045479] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.048221] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.048244] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.048253] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.048259] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.048265] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.048271] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.048276] [00001805] libusbx: debug [sync_transfer_cb] actual_length=500
[ 3.048288] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.048295] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.048307] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.048312] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.048443] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.048465] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.048473] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.048480] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.048486] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.048492] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.048497] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.048508] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.048515] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.048526] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.048531] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.051267] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.051289] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=500
[ 3.051297] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.051304] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.051310] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.051315] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.051320] [00001805] libusbx: debug [sync_transfer_cb] actual_length=500
[ 3.051332] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.051339] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.051351] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.051356] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.051490] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.051512] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.051520] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.051527] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.051533] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.051539] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.051553] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.051565] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.051572] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 500
[ 3.051584] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.051589] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.054278] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.054301] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=96
[ 3.054310] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.054316] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.054322] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.054327] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.054332] [00001805] libusbx: debug [sync_transfer_cb] actual_length=96
[ 3.054344] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.054351] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.054363] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.054368] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.054498] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.054520] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.054528] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.054535] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.054541] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.054546] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.054551] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.054562] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.054569] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.054580] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.054586] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.057298] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.057321] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 3.057329] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.057336] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.057341] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.057347] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.057352] [00001805] libusbx: debug [sync_transfer_cb] actual_length=8
PIT file download successful.

Entry Count: 15
Unknown 1: 0
Unknown 2: 0
Unknown 3: 0
Unknown 4: 0
Unknown 5: 0
Unknown 6: 0
Unknown 7: 0
Unknown 8: 0


--- Entry #0 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 0
Attributes: 0 (Read-Only)
Update Attributes: 0
Partition Block Size/Offset: 0
Partition Block Count: 0
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: GANG
Flash Filename: emmc.img
FOTA Filename: 


--- Entry #1 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 1
Attributes: 0 (Read-Only)
Update Attributes: 0
Partition Block Size/Offset: 0
Partition Block Count: 0
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: BOOT
Flash Filename: boot.bin
FOTA Filename: 


--- Entry #2 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 4
Attributes: 1 (Read/Write)
Update Attributes: 0
Partition Block Size/Offset: 8192
Partition Block Count: 40960
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: EFS
Flash Filename: efs.img
FOTA Filename: 


--- Entry #3 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 2
Attributes: 0 (Read-Only)
Update Attributes: 0
Partition Block Size/Offset: 49152
Partition Block Count: 2560
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: SBL1
Flash Filename: Sbl.bin
FOTA Filename: 


--- Entry #4 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 3
Attributes: 0 (Read-Only)
Update Attributes: 0
Partition Block Size/Offset: 53248
Partition Block Count: 2560
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: SBL2
Flash Filename: 
FOTA Filename: 


--- Entry #5 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 5
Attributes: 0 (Read-Only)
Update Attributes: 0
Partition Block Size/Offset: 57344
Partition Block Count: 16384
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: PARAM
Flash Filename: param.lfs
FOTA Filename: 


--- Entry #6 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 6
Attributes: 0 (Read-Only)
Update Attributes: 0
Partition Block Size/Offset: 73728
Partition Block Count: 16384
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: KERNEL
Flash Filename: zImage
FOTA Filename: 


--- Entry #7 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 7
Attributes: 0 (Read-Only)
Update Attributes: 0
Partition Block Size/Offset: 90112
Partition Block Count: 16384
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: RECOVERY
Flash Filename: 
FOTA Filename: 


--- Entry #8 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 8
Attributes: 1 (Read/Write)
Update Attributes: 0
Partition Block Size/Offset: 106496
Partition Block Count: 204800
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: CACHE
Flash Filename: cache.img
FOTA Filename: 


--- Entry #9 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 9
Attributes: 0 (Read-Only)
Update Attributes: 0
Partition Block Size/Offset: 311296
Partition Block Count: 32768
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: MODEM
Flash Filename: modem.bin
FOTA Filename: 


--- Entry #10 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 10
Attributes: 1 (Read/Write)
Update Attributes: 0
Partition Block Size/Offset: 344064
Partition Block Count: 1048576
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: FACTORYFS
Flash Filename: factoryfs.img
FOTA Filename: 


--- Entry #11 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 11
Attributes: 1 (Read/Write)
Update Attributes: 0
Partition Block Size/Offset: 1392640
Partition Block Count: 4194304
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: DATAFS
Flash Filename: data.img
FOTA Filename: 


--- Entry #12 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 12
Attributes: 2 (STL Read-Only)
Update Attributes: 0
Partition Block Size/Offset: 5586944
Partition Block Count: 24133632
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: UMS
Flash Filename: 
FOTA Filename: 


--- Entry #13 ---
Binary Type: 0 (AP)
Device Type: 2 (MMC)
Identifier: 13
Attributes: 1 (Read/Write)
Update Attributes: 0
Partition Block Size/Offset: 29720576
Partition Block Count: 1048576
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: HIDDEN
Flash Filename: hidden.img
FOTA Filename: 


--- Entry #14 ---
Binary Type: 1 (CP)
Device Type: 1 (File/FAT)
Identifier: 9
Attributes: 0 (Read-Only)
Update Attributes: 0
Partition Block Size/Offset: 0
Partition Block Count: 0
File Offset (Obsolete): 0
File Size (Obsolete): 0
Partition Name: 
Flash Filename: 
FOTA Filename: 

Ending session...
[ 3.058117] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.058124] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 3.058136] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.058146] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.058271] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 3.058293] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 3.058302] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.058309] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.058314] [00001805] libusbx: debug [disarm_timerfd] 
[ 3.058320] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 3.058325] [00001805] libusbx: debug [sync_transfer_cb] actual_length=1024
[ 3.058335] [00001805] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.058341] [00001805] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.058353] [00001805] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.058358] [00001805] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 4.280897] [00001805] libusbx: debug [handle_events] poll() returned 1
[ 4.280925] [00001805] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=8
[ 4.280933] [00001805] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 4.280939] [00001805] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 4.280944] [00001805] libusbx: debug [disarm_timerfd] 
[ 4.280950] [00001805] libusbx: debug [usbi_handle_transfer_completion] transfer 0xd5c918 has callback 0x7f80213baf30
[ 4.280955] [00001805] libusbx: debug [sync_transfer_cb] actual_length=8
Releasing device interface...
[ 4.280970] [00001805] libusbx: debug [libusb_release_interface] interface 1
Re-attaching kernel driver...
[ 4.280993] [00001805] libusbx: debug [libusb_attach_kernel_driver] interface 1

[ 4.281016] [00001805] libusbx: debug [libusb_close] 
[ 4.281026] [00001805] libusbx: debug [usbi_remove_pollfd] remove fd 9
[ 4.281037] [00001805] libusbx: debug [libusb_exit] 
[ 4.281041] [00001805] libusbx: debug [libusb_exit] destroying default context

heimdall_second_run.log

Heimdall v1.4.0

Copyright (c) 2010-2013, 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.002933] [0000180b] libusbx: debug [libusb_get_device_list] 
[ 0.002957] [0000180b] libusbx: debug [discovered_devs_append] need to increase capacity
[ 0.002967] [0000180b] libusbx: debug [libusb_get_device_descriptor] 
[ 0.002972] [0000180b] libusbx: debug [libusb_get_device_descriptor] 
[ 0.002976] [0000180b] libusbx: debug [libusb_get_device_descriptor] 
[ 0.002980] [0000180b] libusbx: debug [libusb_get_device_descriptor] 
[ 0.002984] [0000180b] libusbx: debug [libusb_get_device_descriptor] 
[ 0.002988] [0000180b] libusbx: debug [libusb_get_device_descriptor] 
[ 0.002991] [0000180b] libusbx: debug [libusb_get_device_descriptor] 
[ 0.002995] [0000180b] libusbx: debug [libusb_get_device_descriptor] 
[ 0.002999] [0000180b] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003002] [0000180b] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003008] [0000180b] libusbx: debug [libusb_open] open 3.9
[ 0.003031] [0000180b] libusbx: debug [op_open] getcap not available
[ 0.003037] [0000180b] libusbx: debug [usbi_add_pollfd] add fd 9 events 4
[ 0.003052] [0000180b] libusbx: debug [libusb_get_device_descriptor] 
[ 0.003061] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.003083] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.003089] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.003169] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 0.003195] [0000180b] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=4
[ 0.003202] [0000180b] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.003208] [0000180b] libusbx: debug [disarm_timerfd] 
[ 0.003214] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 0.003218] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=4
[ 0.003231] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.003244] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.003250] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.003329] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 0.003336] [0000180b] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=16
[ 0.003341] [0000180b] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.003346] [0000180b] libusbx: debug [disarm_timerfd] 
[ 0.003350] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 0.003355] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=16
      Manufacturer: "SAMSUNG"
[ 0.003366] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.003375] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.003380] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.003459] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 0.003466] [0000180b] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=4
[ 0.003470] [0000180b] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.003475] [0000180b] libusbx: debug [disarm_timerfd] 
[ 0.003479] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 0.003483] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=4
[ 0.003501] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.003510] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.003515] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.003583] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 0.003608] [0000180b] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=28
[ 0.003618] [0000180b] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.003624] [0000180b] libusbx: debug [disarm_timerfd] 
[ 0.003630] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 0.003634] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=28
           Product: "Gadget Serial"

            length: 18
      device class: 2
               S/N: 0
           VID:PID: 04E8:685D
         bcdDevice: 021B
   iMan:iProd:iSer: 1:2:0
          nb confs: 1
[ 0.003667] [0000180b] libusbx: 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.003719] [0000180b] libusbx: debug [libusb_claim_interface] interface 1
Setting up interface...
[ 0.003737] [0000180b] libusbx: debug [libusb_set_interface_alt_setting] interface 1 altsetting 0

Initialising protocol...
[ 0.003891] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.003904] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.003909] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.003950] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 0.003957] [0000180b] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=0
[ 0.003962] [0000180b] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.003966] [0000180b] libusbx: debug [disarm_timerfd] 
[ 0.003973] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 0.003978] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=0
[ 0.003985] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.003994] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.003999] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004091] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 0.004102] [0000180b] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=7
[ 0.004109] [0000180b] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.004115] [0000180b] libusbx: debug [disarm_timerfd] 
[ 0.004122] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 0.004128] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=7
[ 0.004137] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004149] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004155] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004219] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 0.004231] [0000180b] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=0
[ 0.004237] [0000180b] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.004243] [0000180b] libusbx: debug [disarm_timerfd] 
[ 0.004250] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 0.004264] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=0
[ 0.004274] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004285] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004290] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004355] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 0.004367] [0000180b] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=0
[ 0.004374] [0000180b] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.004380] [0000180b] libusbx: debug [disarm_timerfd] 
[ 0.004386] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 0.004392] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=0
[ 0.004401] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004413] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004419] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004495] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 0.004502] [0000180b] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=7
[ 0.004507] [0000180b] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.004511] [0000180b] libusbx: debug [disarm_timerfd] 
[ 0.004516] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 0.004520] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=7
[ 0.004526] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004534] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004539] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004578] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 0.004585] [0000180b] libusbx: debug [reap_for_handle] urb type=2 status=0 transferred=0
[ 0.004589] [0000180b] libusbx: debug [handle_control_completion] handling completion status 0
[ 0.004594] [0000180b] libusbx: debug [disarm_timerfd] 
[ 0.004598] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 0.004602] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=0
[ 0.004609] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004615] [0000180b] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4
[ 0.004624] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004629] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 0.004641] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 0.004646] [0000180b] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 0.004651] [0000180b] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.004655] [0000180b] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.004659] [0000180b] libusbx: debug [disarm_timerfd] 
[ 0.004664] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 0.004668] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=4
[ 0.004674] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004679] [0000180b] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 7
[ 0.004685] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004690] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 1.004687] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 1.004736] [0000180b] libusbx: debug [handle_events] timerfd triggered
[ 1.004747] [0000180b] libusbx: debug [libusb_cancel_transfer] 
[ 1.004788] [0000180b] libusbx: debug [disarm_timerfd] 
[ 1.004798] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 1.004804] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 1.004812] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 1.004818] [0000180b] libusbx: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 1.004824] [0000180b] libusbx: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 1.004828] [0000180b] libusbx: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 1.004833] [0000180b] libusbx: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 1.004837] [0000180b] libusbx: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 1.004841] [0000180b] libusbx: debug [disarm_timerfd] 
[ 1.004845] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 1.004850] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=0
ERROR: Failed to receive handshake response. Retrying...
[ 1.504947] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 1.504977] [0000180b] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4
[ 1.504997] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 1.505003] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 1.505023] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 1.505030] [0000180b] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 1.505035] [0000180b] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 1.505040] [0000180b] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 1.505045] [0000180b] libusbx: debug [disarm_timerfd] 
[ 1.505049] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 1.505054] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=4
[ 1.505062] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 1.505067] [0000180b] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 7
[ 1.505075] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 1.505080] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 2.505075] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 2.505109] [0000180b] libusbx: debug [handle_events] timerfd triggered
[ 2.505116] [0000180b] libusbx: debug [libusb_cancel_transfer] 
[ 2.505156] [0000180b] libusbx: debug [disarm_timerfd] 
[ 2.505166] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 2.505172] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 2.505179] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 2.505186] [0000180b] libusbx: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 2.505191] [0000180b] libusbx: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 2.505196] [0000180b] libusbx: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 2.505200] [0000180b] libusbx: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 2.505204] [0000180b] libusbx: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 2.505208] [0000180b] libusbx: debug [disarm_timerfd] 
[ 2.505212] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 2.505217] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=0
ERROR: Failed to receive handshake response. Retrying...
[ 3.255313] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 3.255363] [0000180b] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4
[ 3.255385] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.255391] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 3.255411] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 3.255418] [0000180b] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 3.255423] [0000180b] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 3.255428] [0000180b] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 3.255432] [0000180b] libusbx: debug [disarm_timerfd] 
[ 3.255437] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 3.255442] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=4
[ 3.255449] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 3.255455] [0000180b] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 7
[ 3.255462] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.255467] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 4.255460] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 4.255492] [0000180b] libusbx: debug [handle_events] timerfd triggered
[ 4.255499] [0000180b] libusbx: debug [libusb_cancel_transfer] 
[ 4.255532] [0000180b] libusbx: debug [disarm_timerfd] 
[ 4.255543] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 4.255549] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 4.255556] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 4.255562] [0000180b] libusbx: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 4.255567] [0000180b] libusbx: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 4.255572] [0000180b] libusbx: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 4.255576] [0000180b] libusbx: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 4.255580] [0000180b] libusbx: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 4.255585] [0000180b] libusbx: debug [disarm_timerfd] 
[ 4.255589] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 4.255594] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=0
ERROR: Failed to receive handshake response. Retrying...
[ 5.255672] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 5.255694] [0000180b] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4
[ 5.255706] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 5.255710] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 5.255726] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 5.255730] [0000180b] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 5.255732] [0000180b] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 5.255735] [0000180b] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 5.255737] [0000180b] libusbx: debug [disarm_timerfd] 
[ 5.255740] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 5.255742] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=4
[ 5.255746] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 5.255749] [0000180b] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 7
[ 5.255752] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 5.255761] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 6.255772] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 6.255797] [0000180b] libusbx: debug [handle_events] timerfd triggered
[ 6.255807] [0000180b] libusbx: debug [libusb_cancel_transfer] 
[ 6.255851] [0000180b] libusbx: debug [disarm_timerfd] 
[ 6.255867] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 6.255875] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 6.255884] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 6.255893] [0000180b] libusbx: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 6.255900] [0000180b] libusbx: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 6.255907] [0000180b] libusbx: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 6.255912] [0000180b] libusbx: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 6.255917] [0000180b] libusbx: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 6.255921] [0000180b] libusbx: debug [disarm_timerfd] 
[ 6.255926] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 6.255931] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=0
ERROR: Failed to receive handshake response. Retrying...
[ 7.506027] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 7.506067] [0000180b] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4
[ 7.506089] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 7.506096] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 7.506128] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 7.506151] [0000180b] libusbx: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 7.506157] [0000180b] libusbx: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 7.506162] [0000180b] libusbx: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 7.506168] [0000180b] libusbx: debug [disarm_timerfd] 
[ 7.506180] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 7.506182] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=4
[ 7.506186] [0000180b] libusbx: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 7.506189] [0000180b] libusbx: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 7
[ 7.506206] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 7.506212] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 8.506204] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 8.506225] [0000180b] libusbx: debug [handle_events] timerfd triggered
[ 8.506233] [0000180b] libusbx: debug [libusb_cancel_transfer] 
[ 8.506275] [0000180b] libusbx: debug [disarm_timerfd] 
[ 8.506286] [0000180b] libusbx: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 8.506292] [0000180b] libusbx: debug [handle_events] poll() 4 fds with timeout in 60000ms
[ 8.506299] [0000180b] libusbx: debug [handle_events] poll() returned 1
[ 8.506306] [0000180b] libusbx: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 8.506311] [0000180b] libusbx: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 8.506315] [0000180b] libusbx: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 8.506319] [0000180b] libusbx: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 8.506323] [0000180b] libusbx: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 8.506328] [0000180b] libusbx: debug [disarm_timerfd] 
[ 8.506332] [0000180b] libusbx: debug [usbi_handle_transfer_completion] transfer 0x12485a8 has callback 0x7f4aed4a6f30
[ 8.506348] [0000180b] libusbx: debug [sync_transfer_cb] actual_length=0
ERROR: Failed to receive handshake response.
ERROR: Protocol initialisation failed!

Releasing device interface...
[ 8.506378] [0000180b] libusbx: debug [libusb_release_interface] interface 1

[ 8.506401] [0000180b] libusbx: debug [libusb_close] 
[ 8.506411] [0000180b] libusbx: debug [usbi_remove_pollfd] remove fd 9
[ 8.506421] [0000180b] libusbx: debug [libusb_exit] 
[ 8.506426] [0000180b] libusbx: debug [libusb_exit] destroying default context