Android BLE 意外并反复重新连接到外围设备

Question

我正在开发一个与 BLE 设备交互的 Android 应用程序，最近偶然发现了一些奇怪的行为:当应用程序与设备断开连接时，几秒钟后似乎有其他东西建立了连接。

我正在更全面地描述问题，并一直专注于蓝牙 MAP 和 PBAP 配置文件；它们出现在问题点周围的日志中。但是，我不确定这是否是根本原因，也没有找到解决方法。

该应用支持 API 23-25。迄今为止，我只在带有 SIM 卡的手机中遇到过这个问题，这再次指向 PBAP，因为许多手机似乎只使用 SIM 卡支持此配置文件。我还不能在 API 23 上重现，但目前这些测试手机没有 SIM 卡。

BLE 设备与汽车应用无关，也不具备处理联系人或消息传递的能力。我没有故意在应用程序中启用任何这些。此外，我的应用和设备之间没有配对/绑定(bind)，设备也不支持配对/绑定(bind)。

在大多数情况下，重新连接尝试发生一次，在设备通过应用断开连接后几秒钟。应用程序中的后续连接-断开序列具有相同的行为。但是，我至少在一个实例中看到，重新连接(在应用程序之外)每隔几秒就会无限期地继续。

似乎可以在短期内解决问题的唯一方法是在手机上循环蓝牙，或强制停止蓝牙共享过程。我不相信重新连接会自行恢复，但是一旦用户通过我的应用与设备连接或断开连接，它们就会重新出现。

我对 PBAP/MAP 不是很熟悉，所以我不知道它们是如何启用的，或者如果可能的话，如何禁用它们。我不确定它们是否是罪魁祸首，但它们会在重新连接时出现在日志中。

以下是断开连接点和随后的重新连接点周围的日志语句。这里的接口(interface)名称是“Foo04”，MAC = B0:B4:48:E8:FA:04。

03-31 14:27:44.305 D/RxBle#Radio(14105):  STARTED RxBleRadioOperationDisconnect(186827491)
03-31 14:27:44.319 D/BluetoothManager(14105): getConnectionState()
03-31 14:27:44.320 D/BluetoothManager(14105): getConnectedDevices
03-31 14:27:44.332 D/BluetoothGatt(14105): cancelOpen() - device: B0:B4:48:E8:FA:04
03-31 14:27:44.334 D/BtGatt.GattService(13168): clientDisconnect() - address=B0:B4:48:E8:FA:04, connId=5
03-31 14:27:44.339 E/bt_btif (13168): bta_gattc_mark_bg_conn unable to find the bg connection mask for: b0:b4:48:e8:fa:04
03-31 14:27:44.340 D/BtGatt.GattService(13168): onDisconnected() - clientIf=5, connId=5, address=B0:B4:48:E8:FA:04
03-31 14:27:44.341 D/BluetoothGatt(14105): onClientConnectionState() - status=0 clientIf=5 device=B0:B4:48:E8:FA:04
03-31 14:27:44.342 D/RxBle#BluetoothGatt(14105): onConnectionStateChange newState=0 status=0
03-31 14:27:44.345 D/RxBle#Radio(14105): FINISHED RxBleRadioOperationDisconnect(186827491)
03-31 14:27:44.352 D/BluetoothGatt(14105): close()
03-31 14:27:44.352 D/BluetoothGatt(14105): unregisterApp() - mClientIf=5
03-31 14:27:44.354 D/BtGatt.GattService(13168): unregisterClient() - clientIf=5
03-31 14:27:45.376 W/bt_l2cap(13168): l2cble_process_conn_update_evt: Error status: 22
03-31 14:27:45.377 W/bt_btif (13168): bta_gattc_conn_cback() - cif=3 connected=0 conn_id=3 reason=0x0016
03-31 14:27:45.377 W/bt_btif (13168): bta_gattc_conn_cback() - cif=4 connected=0 conn_id=4 reason=0x0016
03-31 14:27:45.377 I/bt_btm_sec(13168): btm_sec_disconnected clearing pending flag handle:13 reason:22
03-31 14:27:45.381 E/BluetoothRemoteDevices(13168): state12newState1
03-31 14:27:45.393 D/BluetoothMapService(13168): onReceive
03-31 14:27:45.393 D/BluetoothMapService(13168): onReceive: android.bluetooth.device.action.ACL_DISCONNECTED
03-31 14:27:45.402 D/BluetoothPbapReceiver(13168): PbapReceiver onReceive action = 
03-31 14:27:45.404 D/BluetoothPbapReceiver(13168): Calling start service with action = null
03-31 14:27:45.405 I/TrustAgent.Tracker(15208): [BluetoothConnectionTracker] Bluetooth disconnect broadast for Foo04 B0:B4:48:E8:FA:04
03-31 14:27:46.407 W/bt_smp  (13168): smp_br_connect_callback is called on unexpected transport 2
03-31 14:27:46.408 W/bt_btif (13168): bta_dm_acl_change info: 0x0
03-31 14:27:46.408 I/bt_bta_dm(13168): bta_dm_gatt_disc_result service_id_uuid_len=2 
03-31 14:27:46.408 I/bt_bta_dm(13168): bta_dm_gatt_disc_result service_id_uuid_len=2 
03-31 14:27:46.408 D/bt_btif_dm(13168): remote version info [b0:b4:48:e8:fa:04]: 0, 0, 0
03-31 14:27:46.408 I/bt_bta_dm(13168): bta_dm_gatt_disc_result service_id_uuid_len=2 
03-31 14:27:46.408 I/bt_bta_dm(13168): bta_dm_gatt_disc_result service_id_uuid_len=16 
03-31 14:27:46.408 I/bt_bta_dm(13168): bta_dm_gatt_disc_result service_id_uuid_len=2 
03-31 14:27:46.412 E/BluetoothRemoteDevices(13168): state12newState0
03-31 14:27:46.457 I/TrustAgent.Tracker(15208): [BluetoothConnectionTracker] Bluetooth connect broadast for Foo04 B0:B4:48:E8:FA:04
03-31 14:27:47.317 I/WCNSS_FILTER(13194): ibs_msm_serial_clock_vote: vote UART CLK OFF using UART driver's ioctl()
03-31 14:27:48.421 I/WCNSS_FILTER(13194): ibs_msm_serial_clock_vote: vote UART CLK ON using UART driver's ioctl()
03-31 14:27:48.483 W/bt_btif (13168): bta_gattc_conn_cback() - cif=3 connected=0 conn_id=3 reason=0x0016
03-31 14:27:48.483 W/bt_btif (13168): bta_gattc_conn_cback() - cif=4 connected=0 conn_id=4 reason=0x0016
03-31 14:27:48.483 I/bt_btm_sec(13168): btm_sec_disconnected clearing pending flag handle:14 reason:22
03-31 14:27:48.488 E/BluetoothRemoteDevices(13168): state12newState1
03-31 14:27:48.506 D/BluetoothMapService(13168): onReceive
03-31 14:27:48.506 D/BluetoothMapService(13168): onReceive: android.bluetooth.device.action.ACL_DISCONNECTED
03-31 14:27:48.524 D/BluetoothPbapReceiver(13168): PbapReceiver onReceive action = android.bluetooth.device.action.ACL_DISCONNECTED
03-31 14:27:48.527 D/BluetoothPbapReceiver(13168): Calling start service with action = null
03-31 14:27:48.530 I/TrustAgent.Tracker(15208): [BluetoothConnectionTracker] Bluetooth disconnect broadast for Foo04 B0:B4:48:E8:FA:04
03-31 14:27:49.430 I/WCNSS_FILTER(13194): ibs_msm_serial_clock_vote: vote UART CLK OFF using UART driver's ioctl()

进一步调查

我在 Android BLE ACL_DISCONNECTED sometimes delayed 中发布了一个相关问题.

我发现问题的设备的一个共同点是存在 SIM 卡，但另一个是 API 24 或 25。我还不能在 API 23 设备上重现，或者无论如何的版本，没有实际安装 SIM 卡的版本。

经过进一步调查，我不太怀疑 SIM 卡，更多的是 API 版本。有几个突出的(或最近修复的)错误具有相关行为，其中一些指向 API 版本 > 23；但是，我随后能够在 API 23 上重现。

• BLE: Having two pending direct connections and cancelling the second one does not work
• BLE: Disconnecting/closing GATT directly after connect sometimes has no effect due to race conditions
• BLE: Closing gatt object or app does not remove pending connection since Android N
• Race condition in BluetoothGatt when using BluetoothDevice#connectGatt()

我觉得这与 PBAP/MAP 配置文件无关。相反，它们在日志中的存在仅表明这些配置文件在任何 BLE 断开连接时被激活。虽然没有表现出重新连接行为，但在与 TI SensorTag 混淆时，我能够看到类似的 PBAP/MAP 激活:这些配置文件再次记录了任何断开连接(与我的应用程序无关)。

受影响设备列表

我已经能够在以下设备上不同程度地重现此问题:

• 三星 S6 - API 23
• 三星 S7 - API 23
• 三星 S7 Edge - API 24
• 索尼 Xperia Z5 Compact - API 24
• 摩托罗拉 Droid Turbo 2 - API 24
• Nexus 5x - API 25
• Google 像素 - API 25

Answer 1

经过大量调查，我能够确定问题的根本原因:Spotify。

在 Android 设备上安装 Spotify 足以证明这种异常行为；用户无需登录或启动过 Spotify 应用程序。在所有情况下卸载或强制停止应用程序都可以解决问题。

Spotify 似乎有一项服务，可以注册与任何蓝牙外围设备的断开连接。当系统通知 Spotify 时，该服务立即连接到刚刚断开的外围设备——我没有费心去描述来自 Spotify 的通信。约 5 秒后，连接断开；然而，由于 Spotify 收到蓝牙断开事件的通知，该服务再次尝试与外围设备连接。这实际上是一个无限循环，只能通过强制停止 Spotify 或在 Android 设备上循环蓝牙来中止。

为了进行调查，我开发了一个简单的应用程序，它会通知并报告蓝牙连接和断开连接(ACL_CONNECTED、ACL_DISCONNECTED)事件。我在我的 Android 设备上使用了 BLE 扫描仪，并与各种蓝牙外围设备连接/断开连接。我的测试应用程序将显示我与外围设备的初始交互，然后是无限的连接流然后断开连接事件。同样，这将一直持续到 Spotify 被强制停止。

以下是示例记录...

04-10 19:56:24.109  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_CONNECTED
04-10 19:56:32.057  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_DISCONNECTED
04-10 19:56:34.197  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_CONNECTED
04-10 19:56:40.396  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_DISCONNECTED
04-10 19:56:43.857  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_CONNECTED
04-10 19:56:49.962  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_DISCONNECTED
04-10 19:56:51.130  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_CONNECTED
04-10 19:57:17.348  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_DISCONNECTED
04-10 19:57:17.927  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_CONNECTED
04-10 19:57:37.621  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_DISCONNECTED
04-10 19:57:38.157  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_CONNECTED
04-10 19:57:44.364  D/BluetoothConnectionMoni: onReceive: deviceName=System05 deviceAddr=B0:B4:48:E8:D7:03 action=ACL_DISCONNECTED
...

很难确定 Spotify 是根本原因。

我的第一个迹象是通过查看开发人员选项 -> 运行服务并注意到 Spotify 定期弹出与外围连接/断开相关。

不过，最后，它归结为消除过程:一旦异常行为开始，我有选择地检查已安装应用程序列表，强制停止可能对蓝牙感兴趣的应用程序，最终看到问题停止当我停止 Spotify 时立即。

几周前我已经向 Spotify 提供了一份详细的错误报告，但我还没有收到他们的回复。