我正在开发一款与BLE设备进行接口交互的Android应用程序,并最近遇到了一些奇怪的问题:当应用程序与设备断开连接后,几秒钟后似乎会有其他东西重新建立连接。
我正在逐步确定问题并专注于Bluetooth MAP和PBAP配置文件; 它们在问题点附近出现在日志中。 但我不确定这是否是根本原因,也没有找到解决方法。
该应用程序支持API 23-25。 到目前为止,我只在存在SIM卡的手机上遇到过此问题,这再次指向PBAP,因为许多手机似乎仅在使用SIM卡时支持此配置文件。 但我尚未能够在API 23上重现此问题,但现在这些测试手机没有SIM卡。
BLE设备与汽车应用程序无关,也无法处理联系人或消息。 我没有在应用程序中故意启用任何此类功能。 此外,我的应用程序与设备之间没有配对/绑定,设备也不支持配对/绑定。
大多数情况下,尝试重新连接仅发生一次,在应用程序通过设备断开连接的几秒钟后。 应用程序中的随后连接-断开连接序列具有相同的行为。 但是,我至少看到过一种情况,即重新连接(在应用程序外部)每隔几秒钟就会无限期地继续。
似乎唯一可以短期解决问题的方法是循环打开手机上的蓝牙或强制停止蓝牙共享进程。 我不认为重新连接会自己恢复,但是当用户通过我的应用程序连接/断开连接设备时,它们通常会重新出现。
我对PBAP / MAP并不十分熟悉,因此不知道它们如何启用或者如果可能的话如何禁用它们。 我不确定它们是否是罪魁祸首,但它们在重新连接的那一刻出现在日志中。
以下是关于设备断开连接和随后重新连接的日志语句。 此处的接口名称是“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 版本的怀疑度增加了。有几个未解决(或最近已解决)的 bug 与此行为有关,其中一些指向 API 版本 > 23;然而,我后来也能够在 API 23 上重现该问题。
- BLE:拥有两个挂起的直接连接并取消第二个连接无法工作
- BLE:直接连接后直接断开 / 关闭 GATT 有时没有效果,原因是竞态条件
- BLE:自 Android N 以来关闭 Gatt 对象或应用程序不会删除挂起的连接
- 在使用 BluetoothDevice#connectGatt() 时,BluetoothGatt 中存在竞态条件
我感觉这与 PBAP/MAP 档案关系不大。相反,日志中存在这些档案只是指这些档案被激活并随着任何 BLE 断开而记录。虽然没有表现出重新连接的行为,但我在玩 TI SensorTag 时也能看到类似的 PBAP/MAP 活动:这些档案再次记录了任何断开(与我的应用无关)。
受影响的设备列表
我已经能够在以下设备上以不同程度地重现此问题:
- Samsung S6 - API 23
- Samsung S7 - API 23
- Samsung S7 Edge - API 24
- Sony Xperia Z5 Compact - API 24
- Motorola Droid Turbo 2 - API 24
- Nexus 5x - API 25
- Google Pixel - API 25