Suunto D5 USB interface

[Brendan Horan]

----- On 8 Jan, 2020, at 8:55 AM, brendanhoran brendanhoran at basstech.net wrote:
> No I have not.
> I will go read up the Install doc to get a better understanding on building it.
> Are there any debug options/flags that may help me debug this?
> Compile time options or such.
> 
So I went off and compiled Subsurface via the instructions in the INSTALL file.

I was then able to connect my D5 and everything worked as expected.
So there has to be something strange with the app image version.

I have been using the app image version for well over a year now.
With a different dive computer (also USB) with out any issue what so ever.

Not sure where I should log this bug now?


Thanks for everyones time,
Brendan


Full log for anyone interested (with LIBUSB_DEBUG=4 exported) :
$ ./subsurface -v
Subsurface v4.9.3-755-gde846bad96e5,
built with libdivecomputer v0.7.0-devel-Subsurface-NG (4eb34b1466e7dff3ee2c0dfbeeef3642c2166d8c)
built with Qt Version 5.13.2, runtime from Qt Version 5.13.2
built with libgit2 0.26.0
"validateGL(): created OpenGLContext."
"validateGL(): obtained QOpenGLFunctions."
"validateGL(): detected OpenGL version 3.0."
loading dive data from ("/home/brendan/.subsurface/brendan.xml")

File locations:

Unable to get local git directory
Cloud URL: No valid cloud credentials set.

Image filename table: /home/brendan/.subsurface/hashes
Local picture directory: /home/brendan/.subsurface/picturedata/

Starting download from  /dev/ttyUSB0
downloading all dives
[timestamp] [threadID] facility level [function call] <message>
--------------------------------------------------------------------------------
[ 0.000016] [000095bf] libusb: debug [libusb_init] created default context
[ 0.000102] [000095bf] libusb: debug [libusb_init] libusb v1.0.21.11156
[ 0.000218] [000095bf] libusb: debug [find_usbfs_path] found usbfs at /dev/bus/usb
[ 0.000280] [000095bf] libusb: debug [op_init] bulk continuation flag supported
[ 0.000308] [000095bf] libusb: debug [op_init] zero length packet flag supported
[ 0.000371] [000095bf] libusb: debug [op_init] sysfs can relate devices
[ 0.000391] [000095bf] libusb: debug [op_init] sysfs has complete descriptors
[ 0.000848] [000095c0] libusb: debug [linux_udev_event_thread_main] udev event thread entering.
[ 0.004491] [000095bf] libusb: debug [linux_get_device_address] getting address for device: usb1 detached: 0
[ 0.004521] [000095bf] libusb: debug [linux_get_device_address] scan usb1
[ 0.004659] [000095bf] libusb: debug [linux_get_device_address] bus=1 dev=1
[ 0.004677] [000095bf] libusb: debug [linux_enumerate_device] busnum 1 devaddr 1 session_id 257
[ 0.004694] [000095bf] libusb: debug [linux_enumerate_device] allocating new device for 1/1 (session 257)
[ 0.004962] [000095bf] libusb: debug [linux_get_device_address] getting address for device: 1-2 detached: 0
[ 0.004977] [000095bf] libusb: debug [linux_get_device_address] scan 1-2
[ 0.005033] [000095bf] libusb: debug [linux_get_device_address] bus=1 dev=6
[ 0.005042] [000095bf] libusb: debug [linux_enumerate_device] busnum 1 devaddr 6 session_id 262
[ 0.005052] [000095bf] libusb: debug [linux_enumerate_device] allocating new device for 1/6 (session 262)
[ 0.005142] [000095bf] libusb: debug [linux_get_parent_info] Dev 0x7f31400222c0 (1-2) has parent 0x7f31400223c0 (usb1) port 2
[ 0.005288] [000095bf] libusb: debug [linux_get_device_address] getting address for device: 1-8 detached: 0
[ 0.005300] [000095bf] libusb: debug [linux_get_device_address] scan 1-8
[ 0.005353] [000095bf] libusb: debug [linux_get_device_address] bus=1 dev=4
[ 0.005363] [000095bf] libusb: debug [linux_enumerate_device] busnum 1 devaddr 4 session_id 260
[ 0.005372] [000095bf] libusb: debug [linux_enumerate_device] allocating new device for 1/4 (session 260)
[ 0.005426] [000095bf] libusb: debug [linux_get_parent_info] Dev 0x7f3140022db0 (1-8) has parent 0x7f31400223c0 (usb1) port 8
[ 0.005569] [000095bf] libusb: debug [linux_get_device_address] getting address for device: 1-9 detached: 0
[ 0.005580] [000095bf] libusb: debug [linux_get_device_address] scan 1-9
[ 0.005633] [000095bf] libusb: debug [linux_get_device_address] bus=1 dev=5
[ 0.005642] [000095bf] libusb: debug [linux_enumerate_device] busnum 1 devaddr 5 session_id 261
[ 0.005652] [000095bf] libusb: debug [linux_enumerate_device] allocating new device for 1/5 (session 261)
[ 0.005711] [000095bf] libusb: debug [linux_get_parent_info] Dev 0x7f3140022ec0 (1-9) has parent 0x7f31400223c0 (usb1) port 9
[ 0.005850] [000095bf] libusb: debug [linux_get_device_address] getting address for device: usb2 detached: 0
[ 0.005862] [000095bf] libusb: debug [linux_get_device_address] scan usb2
[ 0.005920] [000095bf] libusb: debug [linux_get_device_address] bus=2 dev=1
[ 0.005931] [000095bf] libusb: debug [linux_enumerate_device] busnum 2 devaddr 1 session_id 513
[ 0.005941] [000095bf] libusb: debug [linux_enumerate_device] allocating new device for 2/1 (session 513)
[ 0.006163] [000095bf] libusb: debug [usbi_add_pollfd] add fd 20 events 1
[ 0.006206] [000095bf] libusb: debug [usbi_io_init] using timerfd for timeouts
[ 0.006218] [000095bf] libusb: debug [usbi_add_pollfd] add fd 22 events 1
[ 0.006233] [000095bf] libusb: debug [libusb_get_device_list] 
[ 0.006282] [000095bf] libusb: debug [libusb_get_device_descriptor] 
[ 0.006302] [000095bf] libusb: debug [libusb_get_device_descriptor] 
[ 0.006315] [000095bf] libusb: debug [libusb_get_device_descriptor] 
[ 0.006329] [000095bf] libusb: debug [libusb_get_device_descriptor] 
[ 0.006453] [000095bf] libusb: debug [libusb_open] open 1.6
[ 0.006507] [000095bf] libusb: debug [usbi_add_pollfd] add fd 23 events 4
[ 0.006528] [000095bf] libusb: debug [libusb_claim_interface] interface 0
[ 0.010462] [000095bf] libusb: debug [libusb_alloc_transfer] transfer 0x7f314001d060
[ 0.010505] [000095bf] libusb: debug [libusb_submit_transfer] transfer 0x7f314001d060
[ 0.010530] [000095bf] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 0.010590] [000095bf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.010613] [000095bf] libusb: debug [handle_events] poll fds modified, reallocating
[ 0.010638] [000095bf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.018564] [000095bf] libusb: debug [handle_events] poll() returned 1
[ 0.018617] [000095bf] libusb: debug [reap_for_handle] urb type=1 status=0 transferred=64
[ 0.018639] [000095bf] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.018654] [000095bf] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.018700] [000095bf] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f314001d060 has callback 0x7f316c512460
[ 0.018718] [000095bf] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.018755] [000095bf] libusb: debug [libusb_free_transfer] transfer 0x7f314001d060
[ 0.018780] [000095bf] libusb: debug [libusb_alloc_transfer] transfer 0x7f3140021cf0
[ 0.018793] [000095bf] libusb: debug [libusb_submit_transfer] transfer 0x7f3140021cf0
[ 0.018806] [000095bf] libusb: debug [add_to_flying_list] arm timerfd for timeout in 5000ms (first in line)
[ 0.018842] [000095bf] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 0.018884] [000095bf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.018900] [000095bf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.030550] [000095bf] libusb: debug [handle_events] poll() returned 1
[ 0.030600] [000095bf] libusb: debug [reap_for_handle] urb type=1 status=0 transferred=64
[ 0.030647] [000095bf] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.030667] [000095bf] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.030685] [000095bf] libusb: debug [disarm_timerfd] 
[ 0.030706] [000095bf] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f3140021cf0 has callback 0x7f316c512460
[ 0.030724] [000095bf] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.030746] [000095bf] libusb: debug [libusb_free_transfer] transfer 0x7f3140021cf0
INFO: dc_device_open error value of 0
[ 0.030859] [000095bf] libusb: debug [libusb_alloc_transfer] transfer 0x7f3140021dd0
[ 0.030880] [000095bf] libusb: debug [libusb_submit_transfer] transfer 0x7f3140021dd0
[ 0.030898] [000095bf] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 0.030943] [000095bf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.030960] [000095bf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.031549] [000095bf] libusb: debug [handle_events] poll() returned 1
[ 0.031596] [000095bf] libusb: debug [reap_for_handle] urb type=1 status=0 transferred=64
[ 0.031615] [000095bf] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.031634] [000095bf] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.031653] [000095bf] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f3140021dd0 has callback 0x7f316c512460
[ 0.031671] [000095bf] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.031693] [000095bf] libusb: debug [libusb_free_transfer] transfer 0x7f3140021dd0
[ 0.031716] [000095bf] libusb: debug [libusb_alloc_transfer] transfer 0x7f314001ed40
[ 0.031731] [000095bf] libusb: debug [libusb_submit_transfer] transfer 0x7f314001ed40
[ 0.031748] [000095bf] libusb: debug [add_to_flying_list] arm timerfd for timeout in 5000ms (first in line)
[ 0.031773] [000095bf] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 0.031810] [000095bf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.031827] [000095bf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.036541] [000095bf] libusb: debug [handle_events] poll() returned 1
[ 0.036579] [000095bf] libusb: debug [reap_for_handle] urb type=1 status=0 transferred=64
[ 0.036596] [000095bf] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.036612] [000095bf] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.036624] [000095bf] libusb: debug [disarm_timerfd] 
[ 0.036638] [000095bf] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f314001ed40 has callback 0x7f316c512460
[ 0.036650] [000095bf] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.036664] [000095bf] libusb: debug [libusb_free_transfer] transfer 0x7f314001ed40
[ 0.036687] [000095bf] libusb: debug [libusb_alloc_transfer] transfer 0x7f314001ee20
[ 0.036700] [000095bf] libusb: debug [libusb_submit_transfer] transfer 0x7f314001ee20
[ 0.036712] [000095bf] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 0.036754] [000095bf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.036769] [000095bf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.037592] [000095bf] libusb: debug [handle_events] poll() returned 1
[ 0.037632] [000095bf] libusb: debug [reap_for_handle] urb type=1 status=0 transferred=64
[ 0.037643] [000095bf] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.037654] [000095bf] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.037665] [000095bf] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f314001ee20 has callback 0x7f316c512460
[ 0.037675] [000095bf] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.037688] [000095bf] libusb: debug [libusb_free_transfer] transfer 0x7f314001ee20
[ 0.037705] [000095bf] libusb: debug [libusb_alloc_transfer] transfer 0x7f314001b2c0
[ 0.037714] [000095bf] libusb: debug [libusb_submit_transfer] transfer 0x7f314001b2c0
[ 0.037723] [000095bf] libusb: debug [add_to_flying_list] arm timerfd for timeout in 5000ms (first in line)
[ 0.037740] [000095bf] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 0.037771] [000095bf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.037781] [000095bf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.038557] [000095bf] libusb: debug [handle_events] poll() returned 1
[ 0.038597] [000095bf] libusb: debug [reap_for_handle] urb type=1 status=0 transferred=64
[ 0.038605] [000095bf] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.038613] [000095bf] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.038622] [000095bf] libusb: debug [disarm_timerfd] 
[ 0.038632] [000095bf] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f314001b2c0 has callback 0x7f316c512460
[ 0.038640] [000095bf] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.038651] [000095bf] libusb: debug [libusb_free_transfer] transfer 0x7f314001b2c0
[ 0.038667] [000095bf] libusb: debug [libusb_alloc_transfer] transfer 0x7f314001b3a0
[ 0.038675] [000095bf] libusb: debug [libusb_submit_transfer] transfer 0x7f314001b3a0
[ 0.038683] [000095bf] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 0.038706] [000095bf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.038714] [000095bf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.039524] [000095bf] libusb: debug [handle_events] poll() returned 1
[ 0.039545] [000095bf] libusb: debug [reap_for_handle] urb type=1 status=0 transferred=64
[ 0.039552] [000095bf] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.039559] [000095bf] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.039565] [000095bf] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f314001b3a0 has callback 0x7f316c512460
[ 0.039572] [000095bf] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.039580] [000095bf] libusb: debug [libusb_free_transfer] transfer 0x7f314001b3a0
[ 0.039590] [000095bf] libusb: debug [libusb_alloc_transfer] transfer 0x7f314001b3a0
[ 0.039596] [000095bf] libusb: debug [libusb_submit_transfer] transfer 0x7f314001b3a0
[ 0.039602] [000095bf] libusb: debug [add_to_flying_list] arm timerfd for timeout in 5000ms (first in line)
[ 0.039612] [000095bf] libusb: debug [submit_bulk_transfer] need 1 urbs for new transfer with length 64
[ 0.039628] [000095bf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.039634] [000095bf] libusb: debug [handle_events] poll() 3 fds with timeout in 60000ms
[ 0.040554] [000095bf] libusb: debug [handle_events] poll() returned 1
[ 0.040573] [000095bf] libusb: debug [reap_for_handle] urb type=1 status=0 transferred=64
[ 0.040583] [000095bf] libusb: debug [handle_bulk_completion] handling completion status 0 of bulk urb 1/1
[ 0.040591] [000095bf] libusb: debug [handle_bulk_completion] last URB in transfer --> complete!
[ 0.040600] [000095bf] libusb: debug [disarm_timerfd] 
[ 0.040609] [000095bf] libusb: debug [usbi_handle_transfer_completion] transfer 0x7f314001b3a0 has callback 0x7f316c512460
[ 0.040618] [000095bf] libusb: debug [sync_transfer_cb] actual_length=64
[ 0.040633] [000095bf] libusb: debug [libusb_free_transfer] transfer 0x7f314001b3a0
[ 0.040667] [000095bf] libusb: debug [libusb_release_interface] interface 0
[ 0.041464] [000095bf] libusb: debug [libusb_close] 
[ 0.041486] [000095bf] libusb: debug [usbi_remove_pollfd] remove fd 23
[ 0.041500] [000095bf] libusb: debug [libusb_exit] 
[ 0.041504] [000095bf] libusb: debug [libusb_exit] destroying default context
[ 0.041513] [000095bf] libusb: debug [libusb_handle_events_timeout_completed] doing our own event handling
[ 0.041517] [000095bf] libusb: debug [handle_events] poll fds modified, reallocating
[ 0.041530] [000095bf] libusb: debug [handle_events] poll() 2 fds with timeout in 0ms
[ 0.041542] [000095bf] libusb: debug [handle_events] poll() returned 0
[ 0.041550] [000095bf] libusb: debug [libusb_unref_device] destroy device 2.1
[ 0.041557] [000095bf] libusb: debug [libusb_unref_device] destroy device 1.5
[ 0.041564] [000095bf] libusb: debug [libusb_unref_device] destroy device 1.4
[ 0.041570] [000095bf] libusb: debug [libusb_unref_device] destroy device 1.6
[ 0.041576] [000095bf] libusb: debug [libusb_unref_device] destroy device 1.1
[ 0.041583] [000095bf] libusb: debug [usbi_remove_pollfd] remove fd 20
[ 0.041605] [000095bf] libusb: debug [usbi_remove_pollfd] remove fd 22
[ 0.041647] [000095c0] libusb: debug [linux_udev_event_thread_main] udev event thread exiting
Finishing download thread: 0 dives downloaded