Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

"Odin protocol version error!" on Samsung Galaxy S7 (SM-G930A) #370

Open
auxiliary-character opened this issue Oct 4, 2016 · 8 comments

Comments

@auxiliary-character
Copy link

This is on Arch Linux, installed from the AUR just a few hours ago (version 1.4.1.r37.gb6fe7f8-1) with the heimdall-git package.

Here's the log:

[austin@wolfram samsung-tools]$ sudo heimdall print-pit --usb-log-level debug
Heimdall v1.4.1

Copyright (c) 2010-2014 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.011193] [00000b92] libusb: debug [libusb_get_device_list] 
[ 0.011240] [00000b92] libusb: debug [discovered_devs_append] need to increase capacity
[ 0.011258] [00000b92] libusb: debug [libusb_get_device_descriptor] 
[ 0.011266] [00000b92] libusb: debug [libusb_get_device_descriptor] 
[ 0.011273] [00000b92] libusb: debug [libusb_get_device_descriptor] 
[ 0.011286] [00000b92] libusb: debug [libusb_open] open 4.82
[ 0.011329] [00000b92] libusb: debug [usbi_add_pollfd] add fd 9 events 4
[ 0.011340] [00000b92] libusb: debug [libusb_get_device_descriptor] 
[ 0.011350] [00000b92] libusb: debug [libusb_get_config_descriptor] index 0
Claiming interface...
[ 0.011380] [00000b92] libusb: debug [libusb_detach_kernel_driver] interface 1
[ 0.011396] [00000b92] libusb: debug [libusb_claim_interface] interface 1
Setting up interface...
[ 0.011447] [00000b92] libusb: debug [libusb_set_interface_alt_setting] interface 1 altsetting 0

Initialising protocol...
[ 0.011729] [00000b92] libusb: debug [libusb_alloc_transfer] transfer 0x1fd9998
[ 0.011748] [00000b92] libusb: debug [libusb_submit_transfer] transfer 0x1fd9998
[ 0.011759] [00000b92] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.011777] [00000b92] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4
[ 0.011808] [00000b92] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.011819] [00000b92] libusb: debug [handle_events] poll fds modified, reallocating
[ 0.011831] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.012041] [00000b92] libusb: debug [handle_events] poll() returned 1
[ 0.012090] [00000b92] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 0.012103] [00000b92] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.012113] [00000b92] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.012122] [00000b92] libusb: debug [disarm_timerfd] 
[ 0.012133] [00000b92] libusb: debug [usbi_handle_transfer_completion] transfer 0x1fd9998 has callback 0x7f1c25c90b90
[ 0.012142] [00000b92] libusb: debug [sync_transfer_cb] actual_length=4
[ 0.012160] [00000b92] libusb: debug [libusb_free_transfer] transfer 0x1fd9998
[ 0.012175] [00000b92] libusb: debug [libusb_alloc_transfer] transfer 0x1fd9998
[ 0.012184] [00000b92] libusb: debug [libusb_submit_transfer] transfer 0x1fd9998
[ 0.012192] [00000b92] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.012203] [00000b92] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 7
[ 0.012227] [00000b92] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.012236] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.012683] [00000b92] libusb: debug [handle_events] poll() returned 1
[ 0.012733] [00000b92] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 0.012746] [00000b92] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.012756] [00000b92] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.012765] [00000b92] libusb: debug [disarm_timerfd] 
[ 0.012776] [00000b92] libusb: debug [usbi_handle_transfer_completion] transfer 0x1fd9998 has callback 0x7f1c25c90b90
[ 0.012784] [00000b92] libusb: debug [sync_transfer_cb] actual_length=4
[ 0.012795] [00000b92] libusb: debug [libusb_free_transfer] transfer 0x1fd9998
Protocol initialisation successful.

Beginning session...
[ 0.012869] [00000b92] libusb: debug [libusb_alloc_transfer] transfer 0x1fc66b8
[ 0.012878] [00000b92] libusb: debug [libusb_submit_transfer] transfer 0x1fc66b8
[ 0.012884] [00000b92] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 0.012893] [00000b92] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 0.012910] [00000b92] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.012917] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.013595] [00000b92] libusb: debug [handle_events] poll() returned 1
[ 0.013625] [00000b92] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 0.013635] [00000b92] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.013643] [00000b92] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.013651] [00000b92] libusb: debug [disarm_timerfd] 
[ 0.013660] [00000b92] libusb: debug [usbi_handle_transfer_completion] transfer 0x1fc66b8 has callback 0x7f1c25c90b90
[ 0.013666] [00000b92] libusb: debug [sync_transfer_cb] actual_length=1024
[ 0.013675] [00000b92] libusb: debug [libusb_free_transfer] transfer 0x1fc66b8
[ 0.013684] [00000b92] libusb: debug [libusb_alloc_transfer] transfer 0x1fc66b8
[ 0.013690] [00000b92] libusb: debug [libusb_submit_transfer] transfer 0x1fc66b8
[ 0.013696] [00000b92] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 0.013704] [00000b92] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 0.013719] [00000b92] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.013727] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.056606] [00000b92] libusb: debug [handle_events] poll() returned 1
[ 0.056661] [00000b92] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 0.056670] [00000b92] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.056677] [00000b92] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.056686] [00000b92] libusb: debug [disarm_timerfd] 
[ 0.056695] [00000b92] libusb: debug [usbi_handle_transfer_completion] transfer 0x1fc66b8 has callback 0x7f1c25c90b90
[ 0.056702] [00000b92] libusb: debug [sync_transfer_cb] actual_length=0
[ 0.056711] [00000b92] libusb: debug [libusb_free_transfer] transfer 0x1fc66b8
[ 0.056725] [00000b92] libusb: debug [libusb_alloc_transfer] transfer 0x1fc66b8
[ 0.056731] [00000b92] libusb: debug [libusb_submit_transfer] transfer 0x1fc66b8
[ 0.056738] [00000b92] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 0.056747] [00000b92] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 0.056767] [00000b92] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.056774] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.056804] [00000b92] libusb: debug [handle_events] poll() returned 1
[ 3.056868] [00000b92] libusb: debug [handle_events] timerfd triggered
[ 3.056886] [00000b92] libusb: debug [libusb_cancel_transfer] transfer 0x1fc66b8
[ 3.057207] [00000b92] libusb: debug [disarm_timerfd] 
[ 3.057310] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[ 3.057334] [00000b92] libusb: debug [handle_events] poll() returned 1
[ 3.057351] [00000b92] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 3.057361] [00000b92] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 3.057370] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 3.057378] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 3.057388] [00000b92] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 3.057396] [00000b92] libusb: debug [disarm_timerfd] 
[ 3.057433] [00000b92] libusb: debug [usbi_handle_transfer_completion] transfer 0x1fc66b8 has callback 0x7f1c25c90b90
[ 3.057444] [00000b92] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.057458] [00000b92] libusb: debug [libusb_free_transfer] transfer 0x1fc66b8
[ 3.307605] [00000b92] libusb: debug [libusb_alloc_transfer] transfer 0x1fc66b8
[ 3.307677] [00000b92] libusb: debug [libusb_submit_transfer] transfer 0x1fc66b8
[ 3.307695] [00000b92] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.307714] [00000b92] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.307760] [00000b92] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.307775] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 6.307787] [00000b92] libusb: debug [handle_events] poll() returned 1
[ 6.307851] [00000b92] libusb: debug [handle_events] timerfd triggered
[ 6.307865] [00000b92] libusb: debug [libusb_cancel_transfer] transfer 0x1fc66b8
[ 6.308262] [00000b92] libusb: debug [disarm_timerfd] 
[ 6.308328] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[ 6.308349] [00000b92] libusb: debug [handle_events] poll() returned 1
[ 6.308367] [00000b92] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 6.308378] [00000b92] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 6.308387] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 6.308396] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 6.308406] [00000b92] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 6.308416] [00000b92] libusb: debug [disarm_timerfd] 
[ 6.308426] [00000b92] libusb: debug [usbi_handle_transfer_completion] transfer 0x1fc66b8 has callback 0x7f1c25c90b90
[ 6.308436] [00000b92] libusb: debug [sync_transfer_cb] actual_length=0
[ 6.308450] [00000b92] libusb: debug [libusb_free_transfer] transfer 0x1fc66b8
[ 6.808585] [00000b92] libusb: debug [libusb_alloc_transfer] transfer 0x1fc66b8
[ 6.808646] [00000b92] libusb: debug [libusb_submit_transfer] transfer 0x1fc66b8
[ 6.808659] [00000b92] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 6.808674] [00000b92] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 6.808708] [00000b92] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 6.808718] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 9.808695] [00000b92] libusb: debug [handle_events] poll() returned 1
[ 9.808769] [00000b92] libusb: debug [handle_events] timerfd triggered
[ 9.808792] [00000b92] libusb: debug [libusb_cancel_transfer] transfer 0x1fc66b8
[ 9.809176] [00000b92] libusb: debug [disarm_timerfd] 
[ 9.809205] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[ 9.809226] [00000b92] libusb: debug [handle_events] poll() returned 1
[ 9.809247] [00000b92] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 9.809264] [00000b92] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 9.809280] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 9.809295] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 9.809311] [00000b92] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 9.809328] [00000b92] libusb: debug [disarm_timerfd] 
[ 9.809349] [00000b92] libusb: debug [usbi_handle_transfer_completion] transfer 0x1fc66b8 has callback 0x7f1c25c90b90
[ 9.809366] [00000b92] libusb: debug [sync_transfer_cb] actual_length=0
[ 9.809386] [00000b92] libusb: debug [libusb_free_transfer] transfer 0x1fc66b8
[10.559545] [00000b92] libusb: debug [libusb_alloc_transfer] transfer 0x1fc66b8
[10.559611] [00000b92] libusb: debug [libusb_submit_transfer] transfer 0x1fc66b8
[10.559656] [00000b92] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[10.559676] [00000b92] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[10.559720] [00000b92] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[10.559734] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[13.559743] [00000b92] libusb: debug [handle_events] poll() returned 1
[13.559818] [00000b92] libusb: debug [handle_events] timerfd triggered
[13.559839] [00000b92] libusb: debug [libusb_cancel_transfer] transfer 0x1fc66b8
[13.560226] [00000b92] libusb: debug [disarm_timerfd] 
[13.560270] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[13.560289] [00000b92] libusb: debug [handle_events] poll() returned 1
[13.560306] [00000b92] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[13.560316] [00000b92] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[13.560325] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[13.560333] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[13.560343] [00000b92] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[13.560351] [00000b92] libusb: debug [disarm_timerfd] 
[13.560360] [00000b92] libusb: debug [usbi_handle_transfer_completion] transfer 0x1fc66b8 has callback 0x7f1c25c90b90
[13.560369] [00000b92] libusb: debug [sync_transfer_cb] actual_length=0
[13.560382] [00000b92] libusb: debug [libusb_free_transfer] transfer 0x1fc66b8
[14.560539] [00000b92] libusb: debug [libusb_alloc_transfer] transfer 0x1fc66b8
[14.560671] [00000b92] libusb: debug [libusb_submit_transfer] transfer 0x1fc66b8
[14.560687] [00000b92] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[14.560706] [00000b92] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[14.560750] [00000b92] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[14.560762] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[17.560776] [00000b92] libusb: debug [handle_events] poll() returned 1
[17.560859] [00000b92] libusb: debug [handle_events] timerfd triggered
[17.560876] [00000b92] libusb: debug [libusb_cancel_transfer] transfer 0x1fc66b8
[17.561195] [00000b92] libusb: debug [disarm_timerfd] 
[17.561250] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[17.561271] [00000b92] libusb: debug [handle_events] poll() returned 1
[17.561290] [00000b92] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[17.561303] [00000b92] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[17.561314] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[17.561323] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[17.561335] [00000b92] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[17.561346] [00000b92] libusb: debug [disarm_timerfd] 
[17.561357] [00000b92] libusb: debug [usbi_handle_transfer_completion] transfer 0x1fc66b8 has callback 0x7f1c25c90b90
[17.561368] [00000b92] libusb: debug [sync_transfer_cb] actual_length=0
[17.561383] [00000b92] libusb: debug [libusb_free_transfer] transfer 0x1fc66b8
[18.811551] [00000b92] libusb: debug [libusb_alloc_transfer] transfer 0x1fc66b8
[18.811623] [00000b92] libusb: debug [libusb_submit_transfer] transfer 0x1fc66b8
[18.811641] [00000b92] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[18.811660] [00000b92] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[18.811708] [00000b92] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[18.811723] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[21.811745] [00000b92] libusb: debug [handle_events] poll() returned 1
[21.811852] [00000b92] libusb: debug [handle_events] timerfd triggered
[21.811870] [00000b92] libusb: debug [libusb_cancel_transfer] transfer 0x1fc66b8
[21.812173] [00000b92] libusb: debug [disarm_timerfd] 
[21.812217] [00000b92] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[21.812234] [00000b92] libusb: debug [handle_events] poll() returned 1
[21.812250] [00000b92] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[21.812261] [00000b92] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[21.812270] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[21.812279] [00000b92] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[21.812289] [00000b92] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[21.812298] [00000b92] libusb: debug [disarm_timerfd] 
[21.812307] [00000b92] libusb: debug [usbi_handle_transfer_completion] transfer 0x1fc66b8 has callback 0x7f1c25c90b90
[21.812316] [00000b92] libusb: debug [sync_transfer_cb] actual_length=0
[21.812330] [00000b92] libusb: debug [libusb_free_transfer] transfer 0x1fc66b8
Releasing device interface...
[21.812373] [00000b92] libusb: debug [libusb_release_interface] interface 1

[21.812423] [00000b92] libusb: debug [libusb_close] 
[21.812443] [00000b92] libusb: debug [usbi_remove_pollfd] remove fd 9
[21.812470] [00000b92] libusb: debug [libusb_exit] 
[21.812480] [00000b92] libusb: debug [libusb_exit] destroying default context

If there's anything else I can provide, let me know.

@charliegreen
Copy link

I'm having a similar issue, on Debian 9.2, with heimdall v1.4.2 (built from commit 9bcc42d, which at the time I post this is HEAD). I'm also using a Samsung Galaxy S7 SM-G930A, and getting the error message "Odin protocol version error!" on it. Here's my log:

$ ./bin/heimdall version
v1.4.2
$ ./bin/heimdall print-pit --usb-log-level debug
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.003532] [00000b3c] libusb: debug [libusb_get_device_list] 
[ 0.003549] [00000b3c] libusb: debug [libusb_get_device_descriptor] 
[ 0.003551] [00000b3c] libusb: debug [libusb_get_device_descriptor] 
[ 0.003552] [00000b3c] libusb: debug [libusb_get_device_descriptor] 
[ 0.003554] [00000b3c] libusb: debug [libusb_get_device_descriptor] 
[ 0.003556] [00000b3c] libusb: debug [libusb_get_device_descriptor] 
[ 0.003559] [00000b3c] libusb: debug [libusb_open] open 1.13
[ 0.003589] [00000b3c] libusb: debug [usbi_add_pollfd] add fd 9 events 4
[ 0.003593] [00000b3c] libusb: debug [libusb_get_device_descriptor] 
[ 0.003596] [00000b3c] libusb: debug [libusb_get_config_descriptor] index 0
Claiming interface...
[ 0.003609] [00000b3c] libusb: debug [libusb_claim_interface] interface 1
Setting up interface...
[ 0.003629] [00000b3c] libusb: debug [libusb_set_interface_alt_setting] interface 1 altsetting 0

Initialising protocol...
[ 0.003924] [00000b3c] libusb: debug [libusb_alloc_transfer] transfer 0x55f02cdb2020
[ 0.003929] [00000b3c] libusb: debug [libusb_submit_transfer] transfer 0x55f02cdb2020
[ 0.003931] [00000b3c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.003936] [00000b3c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4
[ 0.003946] [00000b3c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.003951] [00000b3c] libusb: debug [handle_events] poll fds modified, reallocating
[ 0.003962] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.003981] [00000b3c] libusb: debug [handle_events] poll() returned 1
[ 0.003986] [00000b3c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 0.003990] [00000b3c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.003994] [00000b3c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.003999] [00000b3c] libusb: debug [disarm_timerfd] 
[ 0.004002] [00000b3c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f02cdb2020 has callback 0x7f408cd59ff0
[ 0.004004] [00000b3c] libusb: debug [sync_transfer_cb] actual_length=4
[ 0.004015] [00000b3c] libusb: debug [libusb_free_transfer] transfer 0x55f02cdb2020
[ 0.004021] [00000b3c] libusb: debug [libusb_alloc_transfer] transfer 0x55f02cdb2020
[ 0.004025] [00000b3c] libusb: debug [libusb_submit_transfer] transfer 0x55f02cdb2020
[ 0.004028] [00000b3c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 1000ms (first in line)
[ 0.004032] [00000b3c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 7
[ 0.004038] [00000b3c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004042] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.004870] [00000b3c] libusb: debug [handle_events] poll() returned 1
[ 0.004876] [00000b3c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=4
[ 0.004879] [00000b3c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.004883] [00000b3c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.004886] [00000b3c] libusb: debug [disarm_timerfd] 
[ 0.004891] [00000b3c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f02cdb2020 has callback 0x7f408cd59ff0
[ 0.004895] [00000b3c] libusb: debug [sync_transfer_cb] actual_length=4
[ 0.004899] [00000b3c] libusb: debug [libusb_free_transfer] transfer 0x55f02cdb2020
Protocol initialisation successful.

Beginning session...
[ 0.004925] [00000b3c] libusb: debug [libusb_alloc_transfer] transfer 0x55f02cdb9d40
[ 0.004928] [00000b3c] libusb: debug [libusb_submit_transfer] transfer 0x55f02cdb9d40
[ 0.004931] [00000b3c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 0.004934] [00000b3c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 1024
[ 0.004941] [00000b3c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.004945] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.005867] [00000b3c] libusb: debug [handle_events] poll() returned 1
[ 0.005871] [00000b3c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=1024
[ 0.005875] [00000b3c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.005880] [00000b3c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.005884] [00000b3c] libusb: debug [disarm_timerfd] 
[ 0.005889] [00000b3c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f02cdb9d40 has callback 0x7f408cd59ff0
[ 0.005893] [00000b3c] libusb: debug [sync_transfer_cb] actual_length=1024
[ 0.005898] [00000b3c] libusb: debug [libusb_free_transfer] transfer 0x55f02cdb9d40
[ 0.005903] [00000b3c] libusb: debug [libusb_alloc_transfer] transfer 0x55f02cdb9d40
[ 0.005907] [00000b3c] libusb: debug [libusb_submit_transfer] transfer 0x55f02cdb9d40
[ 0.005911] [00000b3c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 100ms (first in line)
[ 0.005916] [00000b3c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 0
[ 0.005923] [00000b3c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.005928] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.049005] [00000b3c] libusb: debug [handle_events] poll() returned 1
[ 0.049019] [00000b3c] libusb: debug [reap_for_handle] urb type=3 status=0 transferred=0
[ 0.049021] [00000b3c] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.049023] [00000b3c] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.049025] [00000b3c] libusb: debug [disarm_timerfd] 
[ 0.049028] [00000b3c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f02cdb9d40 has callback 0x7f408cd59ff0
[ 0.049030] [00000b3c] libusb: debug [sync_transfer_cb] actual_length=0
[ 0.049032] [00000b3c] libusb: debug [libusb_free_transfer] transfer 0x55f02cdb9d40
[ 0.049037] [00000b3c] libusb: debug [libusb_alloc_transfer] transfer 0x55f02cdb9d40
[ 0.049039] [00000b3c] libusb: debug [libusb_submit_transfer] transfer 0x55f02cdb9d40
[ 0.049040] [00000b3c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 0.049043] [00000b3c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 0.049050] [00000b3c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.049053] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 3.049116] [00000b3c] libusb: debug [handle_events] poll() returned 1
[ 3.049155] [00000b3c] libusb: debug [handle_events] timerfd triggered
[ 3.049166] [00000b3c] libusb: debug [libusb_cancel_transfer] transfer 0x55f02cdb9d40
[ 3.049224] [00000b3c] libusb: debug [disarm_timerfd] 
[ 3.049238] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[ 3.049248] [00000b3c] libusb: debug [handle_events] poll() returned 1
[ 3.049258] [00000b3c] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 3.049267] [00000b3c] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 3.049274] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 3.049285] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 3.049299] [00000b3c] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 3.049311] [00000b3c] libusb: debug [disarm_timerfd] 
[ 3.049319] [00000b3c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f02cdb9d40 has callback 0x7f408cd59ff0
[ 3.049332] [00000b3c] libusb: debug [sync_transfer_cb] actual_length=0
[ 3.049347] [00000b3c] libusb: debug [libusb_free_transfer] transfer 0x55f02cdb9d40
[ 3.299510] [00000b3c] libusb: debug [libusb_alloc_transfer] transfer 0x55f02cdb9d40
[ 3.299544] [00000b3c] libusb: debug [libusb_submit_transfer] transfer 0x55f02cdb9d40
[ 3.299552] [00000b3c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 3.299563] [00000b3c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 3.299592] [00000b3c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 3.299601] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 6.299648] [00000b3c] libusb: debug [handle_events] poll() returned 1
[ 6.299696] [00000b3c] libusb: debug [handle_events] timerfd triggered
[ 6.299704] [00000b3c] libusb: debug [libusb_cancel_transfer] transfer 0x55f02cdb9d40
[ 6.299763] [00000b3c] libusb: debug [disarm_timerfd] 
[ 6.299778] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[ 6.299791] [00000b3c] libusb: debug [handle_events] poll() returned 1
[ 6.299804] [00000b3c] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 6.299813] [00000b3c] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 6.299825] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 6.299833] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 6.299845] [00000b3c] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 6.299858] [00000b3c] libusb: debug [disarm_timerfd] 
[ 6.299866] [00000b3c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f02cdb9d40 has callback 0x7f408cd59ff0
[ 6.299875] [00000b3c] libusb: debug [sync_transfer_cb] actual_length=0
[ 6.299897] [00000b3c] libusb: debug [libusb_free_transfer] transfer 0x55f02cdb9d40
[ 6.800056] [00000b3c] libusb: debug [libusb_alloc_transfer] transfer 0x55f02cdb9d40
[ 6.800094] [00000b3c] libusb: debug [libusb_submit_transfer] transfer 0x55f02cdb9d40
[ 6.800105] [00000b3c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[ 6.800117] [00000b3c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[ 6.800145] [00000b3c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 6.800159] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 9.800206] [00000b3c] libusb: debug [handle_events] poll() returned 1
[ 9.800254] [00000b3c] libusb: debug [handle_events] timerfd triggered
[ 9.800262] [00000b3c] libusb: debug [libusb_cancel_transfer] transfer 0x55f02cdb9d40
[ 9.800324] [00000b3c] libusb: debug [disarm_timerfd] 
[ 9.800338] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[ 9.800348] [00000b3c] libusb: debug [handle_events] poll() returned 1
[ 9.800360] [00000b3c] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[ 9.800368] [00000b3c] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[ 9.800375] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[ 9.800379] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[ 9.800386] [00000b3c] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[ 9.800393] [00000b3c] libusb: debug [disarm_timerfd] 
[ 9.800398] [00000b3c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f02cdb9d40 has callback 0x7f408cd59ff0
[ 9.800406] [00000b3c] libusb: debug [sync_transfer_cb] actual_length=0
[ 9.800420] [00000b3c] libusb: debug [libusb_free_transfer] transfer 0x55f02cdb9d40
[10.550600] [00000b3c] libusb: debug [libusb_alloc_transfer] transfer 0x55f02cdb9d40
[10.550634] [00000b3c] libusb: debug [libusb_submit_transfer] transfer 0x55f02cdb9d40
[10.550642] [00000b3c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[10.550654] [00000b3c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[10.550683] [00000b3c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[10.550695] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[13.550731] [00000b3c] libusb: debug [handle_events] poll() returned 1
[13.550766] [00000b3c] libusb: debug [handle_events] timerfd triggered
[13.550773] [00000b3c] libusb: debug [libusb_cancel_transfer] transfer 0x55f02cdb9d40
[13.550833] [00000b3c] libusb: debug [disarm_timerfd] 
[13.550849] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[13.550858] [00000b3c] libusb: debug [handle_events] poll() returned 1
[13.550880] [00000b3c] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[13.550888] [00000b3c] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[13.550901] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[13.550911] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[13.550922] [00000b3c] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[13.550932] [00000b3c] libusb: debug [disarm_timerfd] 
[13.550946] [00000b3c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f02cdb9d40 has callback 0x7f408cd59ff0
[13.550955] [00000b3c] libusb: debug [sync_transfer_cb] actual_length=0
[13.550969] [00000b3c] libusb: debug [libusb_free_transfer] transfer 0x55f02cdb9d40
[14.551147] [00000b3c] libusb: debug [libusb_alloc_transfer] transfer 0x55f02cdb9d40
[14.551180] [00000b3c] libusb: debug [libusb_submit_transfer] transfer 0x55f02cdb9d40
[14.551187] [00000b3c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[14.551199] [00000b3c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[14.551228] [00000b3c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[14.551240] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[17.551294] [00000b3c] libusb: debug [handle_events] poll() returned 1
[17.551326] [00000b3c] libusb: debug [handle_events] timerfd triggered
[17.551334] [00000b3c] libusb: debug [libusb_cancel_transfer] transfer 0x55f02cdb9d40
[17.551433] [00000b3c] libusb: debug [disarm_timerfd] 
[17.551449] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[17.551460] [00000b3c] libusb: debug [handle_events] poll() returned 1
[17.551472] [00000b3c] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[17.551481] [00000b3c] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[17.551491] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[17.551499] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[17.551508] [00000b3c] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[17.551518] [00000b3c] libusb: debug [disarm_timerfd] 
[17.551529] [00000b3c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f02cdb9d40 has callback 0x7f408cd59ff0
[17.551542] [00000b3c] libusb: debug [sync_transfer_cb] actual_length=0
[17.551556] [00000b3c] libusb: debug [libusb_free_transfer] transfer 0x55f02cdb9d40
[18.801715] [00000b3c] libusb: debug [libusb_alloc_transfer] transfer 0x55f02cdb9d40
[18.801748] [00000b3c] libusb: debug [libusb_submit_transfer] transfer 0x55f02cdb9d40
[18.801755] [00000b3c] libusb: debug [add_to_flying_list] arm timerfd for timeout in 3000ms (first in line)
[18.801769] [00000b3c] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 8
[18.801806] [00000b3c] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[18.801819] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[21.801852] [00000b3c] libusb: debug [handle_events] poll() returned 1
[21.801897] [00000b3c] libusb: debug [handle_events] timerfd triggered
[21.801905] [00000b3c] libusb: debug [libusb_cancel_transfer] transfer 0x55f02cdb9d40
[21.801964] [00000b3c] libusb: debug [disarm_timerfd] 
[21.801978] [00000b3c] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[21.801991] [00000b3c] libusb: debug [handle_events] poll() returned 1
[21.802007] [00000b3c] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[21.802019] [00000b3c] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[21.802027] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[21.802037] [00000b3c] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[21.802048] [00000b3c] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[21.802059] [00000b3c] libusb: debug [disarm_timerfd] 
[21.802071] [00000b3c] libusb: debug [usbi_handle_transfer_completion] transfer 0x55f02cdb9d40 has callback 0x7f408cd59ff0
[21.802082] [00000b3c] libusb: debug [sync_transfer_cb] actual_length=0
[21.802103] [00000b3c] libusb: debug [libusb_free_transfer] transfer 0x55f02cdb9d40
Releasing device interface...
[21.802143] [00000b3c] libusb: debug [libusb_release_interface] interface 1

[21.802182] [00000b3c] libusb: debug [libusb_close] 
[21.802203] [00000b3c] libusb: debug [usbi_remove_pollfd] remove fd 9
[21.802230] [00000b3c] libusb: debug [libusb_exit] 
[21.802240] [00000b3c] libusb: debug [libusb_exit] destroying default context
$

Please let me know if there's any other information I have that may be useful!

@Chris-Slade
Copy link

Having the same issue on a Galaxy S7 G930A. Running Manjaro Linux on a x86_64 laptop (kernel version 4.14.14-1-MANJARO), with libusb version 1.0.21-2. Tried both the Heimdall versions in the AUR (git and non-git), and compiled from source. In all cases print-pit would fail with ERROR: libusb error -7 whilst receiving bulk transfer, and Download mode on the phone would print Odin protocol version error!.

@ghost
Copy link

ghost commented Mar 15, 2018

Same exact problem but on Windows. Phone says Odio protocl version error. Heimdall says libusbx: error [init_device] device '\\.\USB#VID_1532&PID_0504&MI_03#7&124BE690&0&0003' is no longer connected!

@Blefish
Copy link

Blefish commented Mar 16, 2018

I have a patch for this, is @Benjamin-Dobell still maintaining this project?

@Benjamin-Dobell
Copy link
Owner

Benjamin-Dobell commented Mar 16, 2018

@Blefish Yes, and PRs are welcome. They just need to be thorough. Fixes for one device tend to break things for others.

@Blefish
Copy link

Blefish commented Mar 23, 2018

There is a patch available here, can anyone confirm whether it works? #459

@anonveni98
Copy link

Can i install twrp with heimdall as i tried to install twrp with herolte odin 3.14 but it failed. After googling I found that there is a problem with the SM-G930A. If it shows Qualcomm secure boot enabled then we can't install twrp. So odin failed to install twrp and shows an error SECURE CHECK FAIL : Recovery
Thanks in advance.

@DaddyW123
Copy link

Can i install twrp with heimdall as i tried to install twrp with herolte odin 3.14 but it failed. After googling I found that there is a problem with the SM-G930A. If it shows Qualcomm secure boot enabled then we can't install twrp. So odin failed to install twrp and shows an error SECURE CHECK FAIL : Recovery Thanks in advance.

Did you ever find a resolution for this? I am trying to flash an S10 with Lineage, and I'm getting the "Odin protocol version error!", and mine too is saying "Qualcomm secureboot : enabled". Which I have a feeling is causing my problem.
This device was already rather bricked because using the stock recovery, I did a "wipe and restore to factory defaults", but that process didn't work right because it just boot loops into recovery now. So I cannot boot in to the stock android to turn on developer mode. I was basically just hoping that I could salvage this phone but I have a feeling it's going to have to go to e-waste.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

7 participants