BLE logs OSTC3

Jan Mulder jlmulder at xs4all.nl
Mon Sep 18 08:24:50 PDT 2017 

On IRC, Dirk asked me for some logs of successful BLE download sessions.

Attached are 2 files:
1) the console output (with -vv) of a download session on Arch Linux.
2) The standard developer log from Subsurface-mobile on Android.

Both self built. And 2) on a very recent Android 7.1.2 LineageOS 
distribution on Android security patch level of September 5, 2017. So 
all patches related to the recent BlueBourne vulnerability.

Further notice: 1) did load 0 dives, which is correct as the logbook was 
up to date. and 2) did download 1 dive, which is also correct as my 
cloud storage was 1 dive behind.

And a small bug recently introduced (on mobile). See the following 
logging fragment:

"28.459: Dive 1: Sun Sep 10, 2017 10:59 AM"
"31.119: Dive 2: Mon Sep 4, 2017 8:32 PM"
"31.127: Already downloaded dive at Mon Sep 4, 2017 8:32 PM"
"31.240: No new dives downloaded from dive computer"
Finishing the thread  dives downloaded 1
1 dive downloaded

The logging on 31.127 is correct. But on 31.240 is not correct. This 
flashes also briefly over the screen.

--jan
-------------- next part --------------
A non-text attachment was scrubbed...
Name: android-subsurface.log
Type: text/x-log
Size: 8719 bytes
Desc: not available
URL: <http://lists.subsurface-divelog.org/pipermail/subsurface/attachments/20170918/544e70af/attachment.bin>
-------------- next part --------------
Starting download from  BT
Starting the thread 0
qt.bluetooth.bluez: Bluez 5 detected.
qt.bluetooth.bluez: Enabling GATT request timeout behavior 20000
qt_ble_open( 00:80:25:4A:0F:C3 )
qt.bluetooth.bluez: Missing CAP_NET_ADMIN permission. Cannot determine whether a found address is of random or public type.
qt.bluetooth.bluez: addresstypeToUse: "Public"
qt.bluetooth.bluez: No settings found for peer device.
qt.bluetooth.bluez: HCI event triggered, type: e
qt.bluetooth.bluez: HCI event triggered, type: e
qt.bluetooth.bluez: HCI event triggered, type: e
qt.bluetooth.bluez: HCI event triggered, type: e
qt.bluetooth.bluez: HCI event triggered, type: f
qt.bluetooth.bluez: HCI event triggered, type: f
qt.bluetooth.bluez: Current l2cp sec level: 1
qt.bluetooth.bluez: Exchanging MTU
connected to the controller for device 00:80:25:4A:0F:C3
  .. discovering services
qt.bluetooth.bluez: Sending read_by_group_type request, startHandle: 1 endHandle: ffff 2800
qt.bluetooth.bluez: HCI event triggered, type: f
qt.bluetooth.bluez: Received size: 3 data: "031700"
qt.bluetooth.bluez: Server MTU: 23 resulting mtu: 23
qt.bluetooth.bluez: Received size: 20 data: "1106010005000018060006000118070009000a18"
qt.bluetooth.bluez: Found uuid: "{00001800-0000-1000-8000-00805f9b34fb}" start handle: 1 end handle: 5
Found service "{00001800-0000-1000-8000-00805f9b34fb}"
qt.bluetooth.bluez: Found uuid: "{00001801-0000-1000-8000-00805f9b34fb}" start handle: 6 end handle: 6
Found service "{00001801-0000-1000-8000-00805f9b34fb}"
qt.bluetooth.bluez: Found uuid: "{0000180a-0000-1000-8000-00805f9b34fb}" start handle: 7 end handle: 9
Found service "{0000180a-0000-1000-8000-00805f9b34fb}"
qt.bluetooth.bluez: Sending read_by_group_type request, startHandle: a endHandle: ffff 2800
qt.bluetooth.bluez: Received size: 8 data: "11060a001600fbfe"
qt.bluetooth.bluez: Found uuid: "{0000fefb-0000-1000-8000-00805f9b34fb}" start handle: a end handle: 16
Found service "{0000fefb-0000-1000-8000-00805f9b34fb}"
 .. created service object QLowEnergyService(0x7fff48018e10)
qt.bluetooth.bluez: Sending read_by_type request, startHandle: a endHandle: 16 type: 2802 packet: "080a0016000228"
qt.bluetooth.bluez: Sending read_by_group_type request, startHandle: 17 endHandle: ffff 2800
qt.bluetooth.bluez: Received size: 5 data: "01080a000a"
qt.bluetooth.bluez: Error1: "no attribute in given range found" last command: 8 handle: a
qt.bluetooth.bluez: Sending read_by_type request, startHandle: a endHandle: 16 type: 2803 packet: "080a0016000328"
qt.bluetooth.bluez: Received size: 22 data: "11141700ffff30303031565245534f495245544d5453"
qt.bluetooth.bluez: Found uuid: "{53544d54-4552-494f-5345-525631303030}" start handle: 17 end handle: ffff
Found service "{53544d54-4552-494f-5345-525631303030}"
qt.bluetooth.bluez: Sending read_by_group_type request, startHandle: 1 endHandle: ffff 2801
qt.bluetooth.bluez: Received size: 23 data: "09150b00040c0000000025800000800010000001000000"
qt.bluetooth.bluez: Found handle: b properties: QFlags(0x4) value handle: c uuid: "{00000001-0000-1000-8000-008025000000}"
qt.bluetooth.bluez: Sending read_by_type request, startHandle: c endHandle: 16 type: 2803 packet: "080c0016000328"
qt.bluetooth.bluez: Received size: 5 data: "011001000a"
qt.bluetooth.bluez: Error1: "no attribute in given range found" last command: 10 handle: 1
 .. done discovering services
 .. discovering details
qt.bluetooth.bluez: Received size: 23 data: "09150e00100f0000000025800000800010000002000000"
qt.bluetooth.bluez: Found handle: e properties: QFlags(0x10) value handle: f uuid: "{00000002-0000-1000-8000-008025000000}"
qt.bluetooth.bluez: Sending read_by_type request, startHandle: f endHandle: 16 type: 2803 packet: "080f0016000328"
qt.bluetooth.bluez: Received size: 23 data: "0915110008120000000025800000800010000003000000"
qt.bluetooth.bluez: Found handle: 11 properties: QFlags(0x8) value handle: 12 uuid: "{00000003-0000-1000-8000-008025000000}"
qt.bluetooth.bluez: Sending read_by_type request, startHandle: 12 endHandle: 16 type: 2803 packet: "08120016000328"
qt.bluetooth.bluez: Received size: 23 data: "0915140020150000000025800000800010000004000000"
qt.bluetooth.bluez: Found handle: 14 properties: QFlags(0x20) value handle: 15 uuid: "{00000004-0000-1000-8000-008025000000}"
qt.bluetooth.bluez: Sending read_by_type request, startHandle: 15 endHandle: 16 type: 2803 packet: "08150016000328"
qt.bluetooth.bluez: Received size: 5 data: "010815000a"
qt.bluetooth.bluez: Error1: "no attribute in given range found" last command: 8 handle: 15
qt.bluetooth.bluez: Reading all characteristic values for "{0000fefb-0000-1000-8000-00805f9b34fb}"
qt.bluetooth.bluez: Discovering descriptor values for "{0000fefb-0000-1000-8000-00805f9b34fb}"
qt.bluetooth.bluez: Sending find_info request (b, e, 11, 14) b
qt.bluetooth.bluez: Received size: 6 data: "05010b000328"
qt.bluetooth.bluez: Suppressing primary/characteristic 2803
qt.bluetooth.bluez: Sending find_info request (b, e, 11, 14) c
qt.bluetooth.bluez: Received size: 20 data: "05020c0000000025800000800010000001000000"
qt.bluetooth.bluez: Suppressing char handle c
qt.bluetooth.bluez: Sending find_info request (b, e, 11, 14) d
qt.bluetooth.bluez: Received size: 6 data: "05010d000229"
qt.bluetooth.bluez: Descriptor found, uuid: "{00002902-0000-1000-8000-00805f9b34fb}" descriptor handle: d
qt.bluetooth.bluez: Sending find_info request (e, 11, 14) e
qt.bluetooth.bluez: Received size: 6 data: "05010e000328"
qt.bluetooth.bluez: Suppressing primary/characteristic 2803
qt.bluetooth.bluez: Sending find_info request (e, 11, 14) f
qt.bluetooth.bluez: Received size: 20 data: "05020f0000000025800000800010000002000000"
qt.bluetooth.bluez: Suppressing char handle f
qt.bluetooth.bluez: Sending find_info request (e, 11, 14) 10
qt.bluetooth.bluez: Received size: 6 data: "050110000229"
qt.bluetooth.bluez: Descriptor found, uuid: "{00002902-0000-1000-8000-00805f9b34fb}" descriptor handle: 10
qt.bluetooth.bluez: Sending find_info request (11, 14) 11
qt.bluetooth.bluez: Received size: 6 data: "050111000328"
qt.bluetooth.bluez: Suppressing primary/characteristic 2803
qt.bluetooth.bluez: Sending find_info request (11, 14) 12
qt.bluetooth.bluez: Received size: 20 data: "0502120000000025800000800010000003000000"
qt.bluetooth.bluez: Suppressing char handle 12
qt.bluetooth.bluez: Sending find_info request (11, 14) 13
qt.bluetooth.bluez: Received size: 6 data: "050113000229"
qt.bluetooth.bluez: Descriptor found, uuid: "{00002902-0000-1000-8000-00805f9b34fb}" descriptor handle: 13
qt.bluetooth.bluez: Sending find_info request (14) 14
qt.bluetooth.bluez: Received size: 6 data: "050114000328"
qt.bluetooth.bluez: Suppressing primary/characteristic 2803
qt.bluetooth.bluez: Sending find_info request (14) 15
qt.bluetooth.bluez: Received size: 20 data: "0502150000000025800000800010000004000000"
qt.bluetooth.bluez: Suppressing char handle 15
qt.bluetooth.bluez: Sending find_info request (14) 16
qt.bluetooth.bluez: Received size: 6 data: "050116000229"
qt.bluetooth.bluez: Descriptor found, uuid: "{00002902-0000-1000-8000-00805f9b34fb}" descriptor handle: 16
qt.bluetooth.bluez: Reading all descriptor values for "{0000fefb-0000-1000-8000-00805f9b34fb}"
qt.bluetooth.bluez: Received size: 3 data: "0b0000"
qt.bluetooth.bluez: Received size: 3 data: "0b0000"
qt.bluetooth.bluez: Received size: 3 data: "0b0000"
qt.bluetooth.bluez: Received size: 3 data: "0b0000"
    "{00000001-0000-1000-8000-008025000000}"
    "{00000002-0000-1000-8000-008025000000}"
    "{00000003-0000-1000-8000-008025000000}"
    "{00000004-0000-1000-8000-008025000000}"
 .. enabling notifications
qt.bluetooth.bluez: Writing descriptor 16 (size: 5 )
qt.bluetooth.bluez: Writing descriptor 10 (size: 5 )
qt.bluetooth.bluez: Writing characteristic 11 (size: 4 with response: true signed: false )
qt.bluetooth.bluez: Received size: 1 data: "13"
BLE write completed
qt.bluetooth.bluez: Received size: 1 data: "13"
BLE write completed
qt.bluetooth.bluez: Received size: 1 data: "13"
INFO: dc_deveice_open error value of 0
qt.bluetooth.bluez: Received size: 4 data: "1d150017"
qt.bluetooth.bluez: Change indication for handle 15
ignore packet from "{00000004-0000-1000-8000-008025000000}" "17"
qt.bluetooth.bluez: Writing characteristic b (size: 4 with response: false signed: false )
qt.bluetooth.bluez: Received size: 5 data: "1b0f00bb4d"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Writing characteristic b (size: 4 with response: false signed: false )
qt.bluetooth.bluez: Received size: 10 data: "1b0f0060001a0000004d"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Writing characteristic b (size: 4 with response: false signed: false )
qt.bluetooth.bluez: Received size: 23 data: "1b0f0069f213020f4857204f5354430000000000000000"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f000000000000000000000000000000000000000000"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f000000000000000000000000000000000000000000"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 9 data: "1b0f0000000000004d"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Writing characteristic b (size: 4 with response: false signed: false )
qt.bluetooth.bluez: Received size: 23 data: "1b0f006d7c240010060e1405bb04370002010024f52b00"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f001006171337f70443001a0200247c05001006190e"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f0007aa000300260300242423001006190e122b0935"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f000000040024b82c0010061c140f9e094400180500"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f00240c2d001007010c0f4209450008060024b03400"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f001007020e1ed40551002f07002405360010070514"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f0003c5095300390800246632001007080b2f09094e"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f00000409002456280010070c140693093d001c0a00"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f0024d42c001007100e2e96094500000b0024033200"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f00100713140c36094d00190c0024ce2c0010071d0c"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f000766094400310d0024f4280010071f0a20bf053e"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f0000180e0024df260010071f0d32b1093a00280f00"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f0024cd24001008011427d20837002a1000242f2900"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f001008060a1435093c001f110024e91d001008060d"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f0000af062c000d1200241a200010080914270b052c"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f000002130024d02b0010080a132533094300081400"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f0024fc270010080d0d1e9c0c3d0000150024040800"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f001008111013d50208000d160024070f0010081110"
qt.bluetooth.bluez: Change notification for handle f
qt.bluetooth.bluez: Received size: 23 data: "1b0f0024590413002f170024be5f001008120b249e0f98"
qt.bluetooth.bluez: Change notification for handle f
turning off further BT debug output
Finishing the thread  dives downloaded 0

More information about the subsurface mailing list