VPN doesn't start for a long time or until I change USB from charging to Transfer Files or MTP

[softwarecreations]

This might not be relevant in the latest version, I'm running version 1.0(2016/09/19) from F-Droid. I'm running LineageOS 15.1 Android Oreo 8.1.0 Kernel 3.4.113.

If I leave the phone and wait for the VPN popups, it takes a VERY long time I started every time with my phone connected via USB, phone screen permanently on and phone in high-performance mode. When I start simple-rt from my Linux desktop (firewall disabled), initially nothing happens. If I just leave the phone for few minutes eventually the popups come and I accept them as per the screenshots. Then I get the key icon at the top.

Making the VPN popup happen immediately I can make it connect immediately by going into the USB menu of my phone in settings, then changing USB from charging only to either File transfer mode or MTP mode.

Then I have a problem that seems the same as this https://github.com/vvviperrr/SimpleRT/issues/49

I ran it in debug mode. I let it run for 75 seconds but it seemed to be going around in circles, so I stopped it with Ctrl-C. Here's the beginning of the log.

debug mode enabled
starting network
configuring:
local interface: eth0
virtual interface: tun0
network: 10.1.1.0
address: 10.1.1.1
netmask: 24
nameserver: 1.1.1.1
tun0 interface configured!
SimpleRT started!
[timestamp] [threadID] facility level [function call] <message>
--------------------------------------------------------------------------------
[ 0.115760] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.115793] [00000e5f] libusb: debug [handle_events] poll fds modified, reallocating
[ 0.115820] [00000e5f] libusb: debug [handle_events] poll() 2 fds with timeout in 60000ms
[26.043422] [00000e60] libusb: debug [linux_get_device_address] getting address for device: 3-4 detached: 0
[26.043430] [00000e60] libusb: debug [linux_get_device_address] scan 3-4
[26.045753] [00000e60] libusb: debug [linux_get_device_address] getting address for device: 3-4 detached: 1
[26.045763] [00000e60] libusb: debug [udev_hotplug_event] udev hotplug event. action: remove.
[26.045790] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.045797] [00000e5f] libusb: debug [handle_events] caught a fish on the event pipe
[26.045799] [00000e5f] libusb: debug [handle_events] hotplug message received
[26.045802] [00000e5f] libusb: debug [libusb_unref_device] destroy device 3.37
[26.045806] [00000e5f] libusb: debug [handle_events] poll() 2 fds with timeout in 0ms
[26.045809] [00000e5f] libusb: debug [handle_events] poll() returned 0
[26.045814] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.045815] [00000e5f] libusb: debug [handle_events] poll() 2 fds with timeout in 60000ms
[26.545174] [00000e60] libusb: debug [linux_get_device_address] getting address for device: 3-4 detached: 0
[26.545187] [00000e60] libusb: debug [linux_get_device_address] scan 3-4
[26.545233] [00000e60] libusb: debug [linux_get_device_address] bus=3 dev=38
[26.545236] [00000e60] libusb: debug [udev_hotplug_event] udev hotplug event. action: add.
[26.545239] [00000e60] libusb: debug [linux_enumerate_device] busnum 3 devaddr 38 session_id 806
[26.545243] [00000e60] libusb: debug [linux_enumerate_device] allocating new device for 3/38 (session 806)
[26.545266] [00000e60] libusb: debug [linux_get_parent_info] Dev 0x7f67c00036f0 (3-4) has parent 0x55c4455532e0 (usb3) port 4
[26.545285] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.545288] [00000e5f] libusb: debug [handle_events] caught a fish on the event pipe
[26.545290] [00000e5f] libusb: debug [handle_events] hotplug message received
[26.545317] [00000e5f] libusb: debug [handle_events] poll() 2 fds with timeout in 0ms
[26.545322] [00000e5f] libusb: debug [handle_events] poll() returned 0
[26.545325] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.545327] [00000e5f] libusb: debug [handle_events] poll() 2 fds with timeout in 60000ms
[26.545410] [00000f2b] libusb: debug [libusb_open] open 3.38
[26.545448] [00000f2b] libusb: debug [usbi_add_pollfd] add fd 11 events 4
[26.545460] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.545464] [00000e5f] libusb: debug [handle_events] caught a fish on the event pipe
[26.545473] [00000e5f] libusb: debug [handle_events] someone updated the poll fds
[26.545477] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.545481] [00000e5f] libusb: debug [handle_events] poll fds modified, reallocating
[26.545485] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[26.545493] [00000f2b] libusb: debug [libusb_get_device_descriptor] 
[26.545497] [00000f2b] libusb: debug [libusb_kernel_driver_active] interface 0
Kernel driver is not active!
[26.545504] [00000f2b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b8000c20
[26.545508] [00000f2b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b8000c20
[26.545518] [00000f2b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[26.545595] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.545607] [00000e5f] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=2
[26.545610] [00000e5f] libusb: debug [handle_control_completion] handling completion status 0
[26.545613] [00000e5f] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f67b8000c20 has callback 0x7f67c813cb30
[26.545616] [00000e5f] libusb: debug [sync_transfer_cb] actual_length=2
[26.545623] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.545627] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[26.545666] [00000f2b] libusb: debug [libusb_free_transfer] transfer 0x7f67b8000c20
Device supports AOA 2.0!
Sending identification to the device
 sending manufacturer: Konstantin Menyaev
[26.545685] [00000f2b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b8000d60
[26.545688] [00000f2b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b8000d60
[26.545697] [00000f2b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[26.545767] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.545778] [00000e5f] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=19
[26.545781] [00000e5f] libusb: debug [handle_control_completion] handling completion status 0
[26.545785] [00000e5f] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f67b8000d60 has callback 0x7f67c813cb30
[26.545787] [00000e5f] libusb: debug [sync_transfer_cb] actual_length=19
[26.545794] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.545796] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[26.545803] [00000f2b] libusb: debug [libusb_free_transfer] transfer 0x7f67b8000d60
 sending model: SimpleRT
[26.545809] [00000f2b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b8000e70
[26.545811] [00000f2b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b8000e70
[26.545818] [00000f2b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[26.545888] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.545896] [00000e5f] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=9
[26.545899] [00000e5f] libusb: debug [handle_control_completion] handling completion status 0
[26.545901] [00000e5f] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f67b8000e70 has callback 0x7f67c813cb30
[26.545904] [00000e5f] libusb: debug [sync_transfer_cb] actual_length=9
[26.545909] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.545911] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[26.545917] [00000f2b] libusb: debug [libusb_free_transfer] transfer 0x7f67b8000e70
 sending description: Simple Reverse Tethering
[26.545922] [00000f2b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b8000f50
[26.545924] [00000f2b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b8000f50
[26.545929] [00000f2b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[26.546109] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.546120] [00000e5f] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=25
[26.546123] [00000e5f] libusb: debug [handle_control_completion] handling completion status 0
[26.546126] [00000e5f] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f67b8000f50 has callback 0x7f67c813cb30
[26.546129] [00000e5f] libusb: debug [sync_transfer_cb] actual_length=25
[26.546135] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.546137] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[26.546262] [00000f2b] libusb: debug [libusb_free_transfer] transfer 0x7f67b8000f50
 sending version: 1.0
[26.546277] [00000f2b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b8001030
[26.546280] [00000f2b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b8001030
[26.546287] [00000f2b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[26.546348] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.546356] [00000e5f] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=4
[26.546358] [00000e5f] libusb: debug [handle_control_completion] handling completion status 0
[26.546361] [00000e5f] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f67b8001030 has callback 0x7f67c813cb30
[26.546364] [00000e5f] libusb: debug [sync_transfer_cb] actual_length=4
[26.546370] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.546372] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[26.546379] [00000f2b] libusb: debug [libusb_free_transfer] transfer 0x7f67b8001030
 sending url: https://github.com/vvviperrr/SimpleRT
[26.546385] [00000f2b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b8001110
[26.546387] [00000f2b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b8001110
[26.546394] [00000f2b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[26.546455] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.546461] [00000e5f] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=38
[26.546463] [00000e5f] libusb: debug [handle_control_completion] handling completion status 0
[26.546466] [00000e5f] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f67b8001110 has callback 0x7f67c813cb30
[26.546468] [00000e5f] libusb: debug [sync_transfer_cb] actual_length=38
[26.546473] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.546475] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[26.546480] [00000f2b] libusb: debug [libusb_free_transfer] transfer 0x7f67b8001110
 sending serial number: 10.1.1.2,1.1.1.1
[26.546485] [00000f2b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b8001230
[26.546487] [00000f2b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b8001230
[26.546492] [00000f2b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[26.546553] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.546559] [00000e5f] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=17
[26.546561] [00000e5f] libusb: debug [handle_control_completion] handling completion status 0
[26.546564] [00000e5f] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f67b8001230 has callback 0x7f67c813cb30
[26.546566] [00000e5f] libusb: debug [sync_transfer_cb] actual_length=17
[26.546571] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.546574] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[26.546584] [00000f2b] libusb: debug [libusb_free_transfer] transfer 0x7f67b8001230
 sending command: start accessory
[26.546597] [00000f2b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b8001310
[26.546600] [00000f2b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b8001310
[26.546607] [00000f2b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[26.546791] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.546799] [00000e5f] libusb: debug [reap_for_handle] urb type=2 status=0 transferred=0
[26.546801] [00000e5f] libusb: debug [handle_control_completion] handling completion status 0
[26.546804] [00000e5f] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f67b8001310 has callback 0x7f67c813cb30
[26.546807] [00000e5f] libusb: debug [sync_transfer_cb] actual_length=0
[26.546812] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.546814] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[26.547376] [00000f2b] libusb: debug [libusb_free_transfer] transfer 0x7f67b8001310
Accessory was inited successfully!
[26.547387] [00000f2b] libusb: debug [libusb_close] 
[26.547397] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.547399] [00000e5f] libusb: debug [handle_events] caught a fish on the event pipe
[26.547406] [00000e5f] libusb: debug [handle_events] someone is closing a device
[26.547409] [00000e5f] libusb: debug [libusb_try_lock_events] someone else is closing a device
[26.547411] [00000e5f] libusb: debug [libusb_event_handler_active] someone else is closing a device
[26.547413] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[26.547418] [00000f2b] libusb: debug [usbi_remove_pollfd] remove fd 11
[26.547429] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.547432] [00000e5f] libusb: debug [handle_events] poll fds modified, reallocating
[26.547436] [00000e5f] libusb: debug [handle_events] poll() 2 fds with timeout in 60000ms
[26.557535] [00000e60] libusb: debug [linux_get_device_address] getting address for device: 3-4 detached: 0
[26.557544] [00000e60] libusb: debug [linux_get_device_address] scan 3-4
[26.557569] [00000e60] libusb: debug [linux_get_device_address] bus=3 dev=38
[26.557571] [00000e60] libusb: debug [udev_hotplug_event] udev hotplug event. action: bind.
[26.557572] [00000e60] libusb: error [udev_hotplug_event] ignoring udev action bind
[26.755359] [00000e60] libusb: debug [linux_get_device_address] getting address for device: 3-4 detached: 0
[26.755394] [00000e60] libusb: debug [linux_get_device_address] scan 3-4
[26.760327] [00000e60] libusb: debug [linux_get_device_address] getting address for device: 3-4 detached: 1
[26.760340] [00000e60] libusb: debug [udev_hotplug_event] udev hotplug event. action: remove.
[26.760364] [00000e5f] libusb: debug [handle_events] poll() returned 1
[26.760373] [00000e5f] libusb: debug [handle_events] caught a fish on the event pipe
[26.760375] [00000e5f] libusb: debug [handle_events] hotplug message received
[26.760380] [00000e5f] libusb: debug [libusb_unref_device] destroy device 3.38
[26.760382] [00000e5f] libusb: debug [handle_events] poll() 2 fds with timeout in 0ms
[26.760385] [00000e5f] libusb: debug [handle_events] poll() returned 0
[26.760388] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[26.760390] [00000e5f] libusb: debug [handle_events] poll() 2 fds with timeout in 60000ms
[27.262723] [00000e60] libusb: debug [linux_get_device_address] getting address for device: 3-4 detached: 0
[27.262734] [00000e60] libusb: debug [linux_get_device_address] scan 3-4
[27.262766] [00000e60] libusb: debug [linux_get_device_address] bus=3 dev=39
[27.262769] [00000e60] libusb: debug [udev_hotplug_event] udev hotplug event. action: add.
[27.262771] [00000e60] libusb: debug [linux_enumerate_device] busnum 3 devaddr 39 session_id 807
[27.262774] [00000e60] libusb: debug [linux_enumerate_device] allocating new device for 3/39 (session 807)
[27.262798] [00000e60] libusb: debug [linux_get_parent_info] Dev 0x7f67c0004790 (3-4) has parent 0x55c4455532e0 (usb3) port 4
[27.263140] [00000e5f] libusb: debug [handle_events] poll() returned 1
[27.263149] [00000e5f] libusb: debug [handle_events] caught a fish on the event pipe
[27.263151] [00000e5f] libusb: debug [handle_events] hotplug message received
[27.263171] [00000e5f] libusb: debug [handle_events] poll() 2 fds with timeout in 0ms
[27.263175] [00000e5f] libusb: debug [handle_events] poll() returned 0
[27.263180] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[27.263183] [00000e5f] libusb: debug [handle_events] poll() 2 fds with timeout in 60000ms
[27.266088] [00000f4b] libusb: debug [libusb_open] open 3.39
[27.266109] [00000f4b] libusb: debug [usbi_add_pollfd] add fd 11 events 4
[27.266116] [00000f4b] libusb: debug [libusb_get_device_descriptor] 
Found accessory 18d1:2d01
[27.266121] [00000f4b] libusb: debug [libusb_get_config_descriptor] index 0
[27.266126] [00000f4b] libusb: debug [libusb_claim_interface] interface 0
accessory connected!
[27.266150] [00000e5f] libusb: debug [handle_events] poll() returned 1
[27.266156] [00000e5f] libusb: debug [handle_events] caught a fish on the event pipe
[27.266153] [00000f4b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b8000de0
[27.266162] [00000f4b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b8000de0
[27.266165] [00000f4b] libusb: debug [add_to_flying_list] arm timerfd for timeout in 200ms (first in line)
[27.266171] [00000f4b] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4096
[27.266158] [00000e5f] libusb: debug [handle_events] someone updated the poll fds
[27.266192] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[27.266194] [00000e5f] libusb: debug [handle_events] poll fds modified, reallocating
[27.266198] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[27.266203] [00000f4b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[27.267462] [00000e60] libusb: debug [linux_get_device_address] getting address for device: 3-4 detached: 0
[27.267471] [00000e60] libusb: debug [linux_get_device_address] scan 3-4
[27.267501] [00000e60] libusb: debug [linux_get_device_address] bus=3 dev=39
[27.267503] [00000e60] libusb: debug [udev_hotplug_event] udev hotplug event. action: bind.
[27.267506] [00000e60] libusb: error [udev_hotplug_event] ignoring udev action bind
[27.466210] [00000e5f] libusb: debug [handle_events] poll() returned 1
[27.466232] [00000e5f] libusb: debug [handle_events] timerfd triggered
[27.466238] [00000e5f] libusb: debug [libusb_cancel_transfer] transfer 0x7f67b8000de0
[27.466318] [00000e5f] libusb: debug [disarm_timerfd] 
[27.466339] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[27.466348] [00000e5f] libusb: debug [handle_events] poll() returned 1
[27.466356] [00000e5f] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[27.466364] [00000e5f] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[27.466370] [00000e5f] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[27.466376] [00000e5f] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[27.466383] [00000e5f] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[27.466390] [00000e5f] libusb: debug [disarm_timerfd] 
[27.466398] [00000e5f] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f67b8000de0 has callback 0x7f67c813cb30
[27.466405] [00000e5f] libusb: debug [sync_transfer_cb] actual_length=0
[27.466420] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[27.466426] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[27.466450] [00000f4b] libusb: debug [libusb_free_transfer] transfer 0x7f67b8000de0
[27.466473] [00000f4b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b8000f00
[27.466478] [00000f4b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b8000f00
[27.466486] [00000f4b] libusb: debug [add_to_flying_list] arm timerfd for timeout in 200ms (first in line)
[27.466496] [00000f4b] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4096
[27.466514] [00000f4b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[27.666511] [00000e5f] libusb: debug [handle_events] poll() returned 1
[27.666583] [00000e5f] libusb: debug [handle_events] timerfd triggered
[27.666592] [00000e5f] libusb: debug [libusb_cancel_transfer] transfer 0x7f67b8000f00
[27.666651] [00000e5f] libusb: debug [disarm_timerfd] 
[27.666669] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[27.666679] [00000e5f] libusb: debug [handle_events] poll() returned 1
[27.666687] [00000e5f] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[27.666694] [00000e5f] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[27.666700] [00000e5f] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[27.666706] [00000e5f] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[27.666712] [00000e5f] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[27.666718] [00000e5f] libusb: debug [disarm_timerfd] 
[27.666724] [00000e5f] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f67b8000f00 has callback 0x7f67c813cb30
[27.666731] [00000e5f] libusb: debug [sync_transfer_cb] actual_length=0
[27.666747] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[27.666753] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[27.666779] [00000f4b] libusb: debug [libusb_free_transfer] transfer 0x7f67b8000f00
[27.666800] [00000f4b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b8000fe0
[27.666807] [00000f4b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b8000fe0
[27.666814] [00000f4b] libusb: debug [add_to_flying_list] arm timerfd for timeout in 200ms (first in line)
[27.666824] [00000f4b] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4096
[27.666841] [00000f4b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[27.866875] [00000e5f] libusb: debug [handle_events] poll() returned 1
[27.866909] [00000e5f] libusb: debug [handle_events] timerfd triggered
[27.866919] [00000e5f] libusb: debug [libusb_cancel_transfer] transfer 0x7f67b8000fe0
[27.867001] [00000e5f] libusb: debug [disarm_timerfd] 
[27.867022] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[27.867034] [00000e5f] libusb: debug [handle_events] poll() returned 1
[27.867049] [00000e5f] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[27.867061] [00000e5f] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[27.867070] [00000e5f] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[27.867079] [00000e5f] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[27.867091] [00000e5f] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[27.867105] [00000e5f] libusb: debug [disarm_timerfd] 
[27.867115] [00000e5f] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f67b8000fe0 has callback 0x7f67c813cb30
[27.867127] [00000e5f] libusb: debug [sync_transfer_cb] actual_length=0
[27.867149] [00000e5f] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[27.867160] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[27.867189] [00000f4b] libusb: debug [libusb_free_transfer] transfer 0x7f67b8000fe0
[27.867226] [00000f4b] libusb: debug [libusb_alloc_transfer] transfer 0x7f67b80010c0
[27.867237] [00000f4b] libusb: debug [libusb_submit_transfer] transfer 0x7f67b80010c0
[27.867249] [00000f4b] libusb: debug [add_to_flying_list] arm timerfd for timeout in 200ms (first in line)
[27.867266] [00000f4b] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 4096
[27.867295] [00000f4b] libusb: debug [libusb_handle_events_timeout_completed] another thread is doing event handling
[28.067360] [00000e5f] libusb: debug [handle_events] poll() returned 1
[28.067385] [00000e5f] libusb: debug [handle_events] timerfd triggered
[28.067393] [00000e5f] libusb: debug [libusb_cancel_transfer] transfer 0x7f67b80010c0
[28.067454] [00000e5f] libusb: debug [disarm_timerfd] 
[28.067469] [00000e5f] libusb: debug [handle_events] poll() 3 fds with timeout in 0ms
[28.067478] [00000e5f] libusb: debug [handle_events] poll() returned 1
[28.067490] [00000e5f] libusb: debug [reap_for_handle] urb type=3 status=-2 transferred=0
[28.067498] [00000e5f] libusb: debug [handle_bulk_completion] handling completion status -2 of bulk urb 1/1
[28.067505] [00000e5f] libusb: debug [handle_bulk_completion] abnormal reap: urb status -2
[28.067510] [00000e5f] libusb: debug [handle_bulk_completion] abnormal reap: last URB handled, reporting
[28.067517] [00000e5f] libusb: debug [usbi_handle_transfer_cancellation] detected timeout cancellation
[28.067527] [00000e5f] libusb: debug [disarm_timerfd] 

[robinpaulson]

I'd like to hear your experiences with the v1.1.1, released to f-droid this week.