Bluetooth key exchange task won't die
After failing to add a contact on the Sony Xperia Tipo (Android 4.0.4), the Bluetooth key exchange task continued to make failed connection attempts every two seconds. I can't tell whether this was related to the initial failure and/or the failure of subsequent attempts to add a contact.
04-04 16:36:42.279 2133-2133/org.briarproject I/DroidtoothPlugin: Scan mode: None
04-04 16:36:42.539 2133-2133/org.briarproject I/DroidtoothPlugin: Scan mode: Connectable
04-04 16:36:42.539 2133-2133/org.briarproject I/DroidtoothPlugin: Scan mode: Connectable
04-04 16:36:42.549 2133-2133/org.briarproject I/DroidtoothPlugin: Scan mode: Connectable
04-04 16:36:42.649 2133-2133/org.briarproject I/DroidtoothPlugin: Bluetooth enabled
04-04 16:36:42.649 2133-11695/org.briarproject I/DroidtoothPlugin: Local address D0:51:62:52:53:62
04-04 16:36:42.799 2133-2133/org.briarproject I/ShowQrCodeFragment: Bluetooth enabled
04-04 16:36:43.369 2133-11695/org.briarproject I/InsecureBluetooth: Listening with new API
04-04 16:36:43.389 2133-12290/org.briarproject I/KeyAgreementConnector: Starting BQP listeners
04-04 16:36:43.389 2133-12290/org.briarproject I/DroidtoothPlugin: Key agreement UUID b32e3352-044d-30c0-b542-e81c88fff7d1
04-04 16:36:43.389 2133-12290/org.briarproject I/InsecureBluetooth: Listening with new API
04-04 16:36:43.439 2133-11695/org.briarproject I/DroidtoothPlugin: Socket bound
04-04 16:36:43.449 2133-11694/org.briarproject I/PollerImpl: Polling DroidtoothPlugin
04-04 16:36:55.729 2133-11870/org.briarproject I/DroidtoothPlugin: bt: Incoming connection
04-04 16:36:55.729 2133-11870/org.briarproject I/KeyAgreementConnector: bt: Waiting for connection
04-04 16:36:56.739 2133-11870/org.briarproject I/KeyAgreementConnector: bt: Waiting for connection
04-04 16:36:57.099 2133-12293/org.briarproject I/QrCodeDecoder: Decoding barcode took 206 ms
04-04 16:36:57.099 2133-2133/org.briarproject I/ShowQrCodeFragment: Got result from decoder
04-04 16:36:57.099 2133-2133/org.briarproject I/ShowQrCodeFragment: Releasing camera
04-04 16:36:57.419 2133-2133/org.briarproject I/CameraView: Surface destroyed
04-04 16:36:57.439 2133-12297/org.briarproject I/KeyAgreementConnector: Starting outgoing BQP connections
04-04 16:36:57.439 2133-11694/org.briarproject I/DroidtoothPlugin: Connecting to key agreement UUID a76f5a79-4148-326f-a831-b450cd7378a9
04-04 16:36:57.449 2133-11694/org.briarproject I/InsecureBluetooth: Creating socket with new API
04-04 16:36:57.449 2133-11694/org.briarproject I/DroidtoothPlugin: Connecting to 64:A7:69:F6:98:D1
04-04 16:36:57.699 2133-11694/org.briarproject I/DroidtoothPlugin: Connected to 64:A7:69:F6:98:D1
04-04 16:36:57.699 2133-11694/org.briarproject I/KeyAgreementConnector: bt: Outgoing connection
04-04 16:36:57.699 2133-12297/org.briarproject I/KeyAgreementConnector: Stopping BQP listeners
04-04 16:36:57.699 2133-12297/org.briarproject I/KeyAgreementConnector: Cancelled task
04-04 16:36:57.699 2133-12297/org.briarproject I/KeyAgreementTaskImpl: Starting BQP protocol
04-04 16:37:00.439 2133-12297/org.briarproject I/Sec1KeyParser: Parsing public key took 1610 ms
04-04 16:37:02.339 2133-12297/org.briarproject I/CryptoComponentImpl: Deriving shared secret took 1899 ms
04-04 16:37:06.109 2133-12297/org.briarproject I/KeyAgreementTaskImpl: Finished BQP protocol
04-04 16:37:17.559 2133-11870/org.briarproject I/PollerImpl: Polling DroidtoothPlugin
04-04 16:38:13.959 2133-12294/org.briarproject I/ShowQrCodeFragment: Opening camera
04-04 16:38:13.959 2133-12347/org.briarproject I/KeyAgreementConnector: Starting BQP listeners
04-04 16:38:13.959 2133-12347/org.briarproject I/DroidtoothPlugin: Key agreement UUID 03cdea53-5012-378e-b2fe-2625aa2bc46f
04-04 16:38:13.959 2133-12347/org.briarproject I/InsecureBluetooth: Listening with new API
04-04 16:38:14.809 2133-2133/org.briarproject I/CameraView: Display orientation 90 degrees
04-04 16:38:14.809 2133-2133/org.briarproject I/CameraView: Scene modes: [auto, night, snow, sports]
04-04 16:38:14.809 2133-2133/org.briarproject I/CameraView: Focus modes: [infinity, fixed]
04-04 16:38:14.809 2133-2133/org.briarproject I/CameraView: Setting focus mode to fixed
04-04 16:38:14.809 2133-2133/org.briarproject I/CameraView: Size 640x480, stretch 2.1440537, pixels 307200, score 143280.0
04-04 16:38:14.809 2133-2133/org.briarproject I/CameraView: Size 640x368, stretch 2.7965918, pixels 235520, score 84216.8
04-04 16:38:14.819 2133-2133/org.briarproject I/CameraView: Size 512x288, stretch 2.8587382, pixels 147456, score 51580.8
04-04 16:38:14.819 2133-2133/org.briarproject I/CameraView: Size 384x288, stretch 2.1440537, pixels 110592, score 51580.797
04-04 16:38:14.819 2133-2133/org.briarproject I/CameraView: Size 352x288, stretch 1.9653825, pixels 101376, score 51580.8
04-04 16:38:14.819 2133-2133/org.briarproject I/CameraView: Size 320x240, stretch 2.1440537, pixels 76800, score 35820.0
04-04 16:38:14.819 2133-2133/org.briarproject I/CameraView: Size 176x144, stretch 1.9653825, pixels 25344, score 12895.2
04-04 16:38:14.819 2133-2133/org.briarproject I/CameraView: Best size 640x480
04-04 16:38:27.389 2133-2133/org.briarproject I/ShowQrCodeFragment: Releasing camera
04-04 16:38:27.839 2133-12365/org.briarproject I/KeyAgreementConnector: Stopping BQP listeners
04-04 16:38:30.579 2133-12363/org.briarproject I/ChooseIdentityFragment: Loading authors took 2 ms
04-04 16:38:31.629 2133-12369/org.briarproject I/KeyAgreementConnector: Starting BQP listeners
04-04 16:38:31.649 2133-12369/org.briarproject I/DroidtoothPlugin: Key agreement UUID bafa97a2-46a6-3b19-a2a2-ea62fb7db10f
04-04 16:38:31.649 2133-12369/org.briarproject I/InsecureBluetooth: Listening with new API
04-04 16:38:31.649 2133-12226/org.briarproject I/ShowQrCodeFragment: Opening camera
04-04 16:38:31.679 2133-2133/org.briarproject I/CameraView: Surface created
04-04 16:38:31.679 2133-2133/org.briarproject I/CameraView: Surface changed: 320x199
04-04 16:38:32.359 2133-2133/org.briarproject I/CameraView: Display orientation 90 degrees
04-04 16:38:32.369 2133-2133/org.briarproject I/CameraView: Scene modes: [auto, night, snow, sports]
04-04 16:38:32.369 2133-2133/org.briarproject I/CameraView: Focus modes: [infinity, fixed]
04-04 16:38:32.369 2133-2133/org.briarproject I/CameraView: Setting focus mode to fixed
04-04 16:38:32.369 2133-2133/org.briarproject I/CameraView: Size 640x480, stretch 2.1440537, pixels 307200, score 143280.0
04-04 16:38:32.369 2133-2133/org.briarproject I/CameraView: Size 640x368, stretch 2.7965918, pixels 235520, score 84216.8
04-04 16:38:32.369 2133-2133/org.briarproject I/CameraView: Size 512x288, stretch 2.8587382, pixels 147456, score 51580.8
04-04 16:38:32.369 2133-2133/org.briarproject I/CameraView: Size 384x288, stretch 2.1440537, pixels 110592, score 51580.797
04-04 16:38:32.369 2133-2133/org.briarproject I/CameraView: Size 352x288, stretch 1.9653825, pixels 101376, score 51580.8
04-04 16:38:32.369 2133-2133/org.briarproject I/CameraView: Size 320x240, stretch 2.1440537, pixels 76800, score 35820.0
04-04 16:38:32.369 2133-2133/org.briarproject I/CameraView: Size 176x144, stretch 1.9653825, pixels 25344, score 12895.2
04-04 16:38:32.369 2133-2133/org.briarproject I/CameraView: Best size 640x480
04-04 16:38:55.719 2133-11870/org.briarproject I/DroidtoothPlugin: bt: Incoming connection
04-04 16:38:55.719 2133-11870/org.briarproject I/KeyAgreementConnector: bt: Data available
04-04 16:38:56.739 2133-11870/org.briarproject I/PollerImpl: Polling TorPlugin
04-04 16:39:02.229 2133-12293/org.briarproject I/QrCodeDecoder: Decoding barcode took 178 ms
04-04 16:39:02.229 2133-2133/org.briarproject I/ShowQrCodeFragment: Got result from decoder
04-04 16:39:02.229 2133-2133/org.briarproject I/ShowQrCodeFragment: Releasing camera
04-04 16:39:02.559 2133-2133/org.briarproject I/CameraView: Surface destroyed
04-04 16:39:02.579 2133-12385/org.briarproject I/KeyAgreementConnector: Starting outgoing BQP connections
04-04 16:39:02.579 2133-11870/org.briarproject I/DroidtoothPlugin: Connecting to key agreement UUID 9b9de3f9-dcf4-3eb1-a134-4db19a63cb74
04-04 16:39:02.579 2133-11870/org.briarproject I/InsecureBluetooth: Creating socket with new API
04-04 16:39:02.579 2133-11870/org.briarproject I/DroidtoothPlugin: Connecting to 64:A7:69:F6:98:D1
04-04 16:39:02.589 2133-12385/org.briarproject I/KeyAgreementConnector: Stopping BQP listeners
04-04 16:39:02.589 2133-12385/org.briarproject I/KeyAgreementConnector: Cancelled task
04-04 16:39:02.589 2133-12385/org.briarproject I/KeyAgreementTaskImpl: Starting BQP protocol
04-04 16:39:02.599 2133-11870/org.briarproject I/DroidtoothPlugin: Failed to connect to 64:A7:69:F6:98:D1
04-04 16:39:04.299 2133-12385/org.briarproject I/Sec1KeyParser: Parsing public key took 1705 ms
04-04 16:39:04.679 2133-11870/org.briarproject I/DroidtoothPlugin: Connecting to key agreement UUID 9b9de3f9-dcf4-3eb1-a134-4db19a63cb74
04-04 16:39:04.679 2133-11870/org.briarproject I/InsecureBluetooth: Creating socket with new API
04-04 16:39:04.679 2133-11870/org.briarproject I/DroidtoothPlugin: Connecting to 64:A7:69:F6:98:D1
04-04 16:39:04.839 2133-11870/org.briarproject I/DroidtoothPlugin: Failed to connect to 64:A7:69:F6:98:D1
04-04 16:39:06.839 2133-11870/org.briarproject I/DroidtoothPlugin: Connecting to key agreement UUID 9b9de3f9-dcf4-3eb1-a134-4db19a63cb74
04-04 16:39:06.839 2133-11870/org.briarproject I/InsecureBluetooth: Creating socket with new API
04-04 16:39:06.839 2133-11870/org.briarproject I/DroidtoothPlugin: Connecting to 64:A7:69:F6:98:D1
04-04 16:39:07.059 2133-11870/org.briarproject I/DroidtoothPlugin: Failed to connect to 64:A7:69:F6:98:D1
04-04 16:39:07.169 2133-12385/org.briarproject I/CryptoComponentImpl: Deriving shared secret took 2869 ms
04-04 16:39:07.199 2133-12385/org.briarproject I/KeyAgreementTaskImpl: Finished BQP protocol
04-04 16:39:09.059 2133-11870/org.briarproject I/DroidtoothPlugin: Connecting to key agreement UUID 9b9de3f9-dcf4-3eb1-a134-4db19a63cb74
04-04 16:39:09.059 2133-11870/org.briarproject I/InsecureBluetooth: Creating socket with new API
04-04 16:39:09.059 2133-11870/org.briarproject I/DroidtoothPlugin: Connecting to 64:A7:69:F6:98:D1
04-04 16:39:09.179 2133-11870/org.briarproject I/DroidtoothPlugin: Failed to connect to 64:A7:69:F6:98:D1
The connection attempts continued every 2 seconds, indefinitely:
04-04 16:42:19.949 2133-11870/org.briarproject I/DroidtoothPlugin: Connecting to key agreement UUID 9b9de3f9-dcf4-3eb1-a134-4db19a63cb74
04-04 16:42:19.949 2133-11870/org.briarproject I/InsecureBluetooth: Creating socket with new API
04-04 16:42:19.949 2133-11870/org.briarproject I/DroidtoothPlugin: Connecting to 64:A7:69:F6:98:D1
04-04 16:42:20.119 2133-11870/org.briarproject I/DroidtoothPlugin: Failed to connect to 64:A7:69:F6:98:D1
Manually disabling Bluetooth finally killed the task:
04-04 16:44:26.599 2133-2133/org.briarproject I/DroidtoothPlugin: Scan mode: None
04-04 16:44:26.619 2133-2133/org.briarproject I/DroidtoothPlugin: Scan mode: None
04-04 16:44:28.489 2133-2133/org.briarproject I/DroidtoothPlugin: Bluetooth disabled
04-04 16:44:28.489 2133-11695/org.briarproject I/DroidtoothPlugin: java.io.IOException: Operation Canceled