Android-DFU-Library: DFU fails, because scanner cannot find the device (MAC address)

DFU Bootloader version:

• SDK version: [SDK 15.3]
• Bonding used: [no]
• Library version: [2.0.1, 2.0.3]

Device information:

• Device: [Lenovo TB-X505L]
• OS: [Android 10]

Describe the bug After enabling DFU mode, the DFU process cannot be completed due to the following reason:

2022-06-13 11:00:51.500 27088-27515/com.myapp.app.dev I/DfuImpl: Scanning for new address finished with: null

This issue only occurs with the Lenovo TB-X505L device.

Logs

2022-06-13 11:00:38.622 27088-27482/com.myapp.app.dev V/FA: onActivityCreated
2022-06-13 11:00:38.664 27088-27483/com.myapp.app.dev V/FA: Activity paused, time: 4397817
2022-06-13 11:00:38.675 27088-27483/com.myapp.app.dev V/FA: Activity resumed, time: 4397910
2022-06-13 11:00:38.764 27088-27088/com.myapp.app.dev I/DfuBaseService: DFU service created. Version: debug
2022-06-13 11:00:38.773 27088-27515/com.myapp.app.dev I/DfuBaseService: Starting DFU service in foreground
2022-06-13 11:00:38.799 27088-27515/com.myapp.app.dev I/DfuBaseService: Connecting to the device...
2022-06-13 11:00:38.800 27088-27515/com.myapp.app.dev D/BluetoothGatt: connect() - device: FF:03:A6:82:AB:EC, auto: false
2022-06-13 11:00:38.800 27088-27515/com.myapp.app.dev D/BluetoothGatt: registerApp()
2022-06-13 11:00:38.800 27088-27515/com.myapp.app.dev D/BluetoothGatt: registerApp() - UUID=717aff8c-6523-4a08-999b-935811fe301a
2022-06-13 11:00:38.805 27088-27161/com.myapp.app.dev D/BluetoothGatt: onClientRegistered() - status=0 clientIf=6
2022-06-13 11:00:38.810 27088-27161/com.myapp.app.dev D/BluetoothGatt: onClientConnectionState() - status=0 clientIf=6 device=FF:03:A6:82:AB:EC
2022-06-13 11:00:38.810 27088-27161/com.myapp.app.dev I/DfuBaseService: Connected to GATT server
2022-06-13 11:00:38.813 27088-27161/com.myapp.app.dev D/BluetoothGatt: discoverServices() - device: FF:03:A6:82:AB:EC
2022-06-13 11:00:38.814 27088-27161/com.myapp.app.dev I/DfuBaseService: Attempting to start service discovery... succeed
2022-06-13 11:00:38.815 27088-27214/com.myapp.app.dev D/BluetoothGatt: onClientConnectionState() - status=0 clientIf=6 device=FF:03:A6:82:AB:EC
2022-06-13 11:00:38.815 27088-27214/com.myapp.app.dev I/DfuBaseService: Connected to GATT server
2022-06-13 11:00:38.816 27088-27214/com.myapp.app.dev D/BluetoothGatt: discoverServices() - device: FF:03:A6:82:AB:EC
2022-06-13 11:00:38.818 27088-27214/com.myapp.app.dev I/DfuBaseService: Attempting to start service discovery... succeed
2022-06-13 11:00:39.580 27088-27214/com.myapp.app.dev D/BluetoothGatt: onConnectionUpdated() - Device=FF:03:A6:82:AB:EC interval=6 latency=0 timeout=500 status=0
2022-06-13 11:00:40.097 27088-27214/com.myapp.app.dev D/BluetoothGatt: onSearchComplete() = Device=FF:03:A6:82:AB:EC Status=0
2022-06-13 11:00:40.097 27088-27214/com.myapp.app.dev I/DfuBaseService: Services discovered
2022-06-13 11:00:40.099 27088-27515/com.myapp.app.dev I/DfuImpl: Buttonless service without bond sharing found -> SDK 13 or newer
2022-06-13 11:00:40.109 27088-27088/com.myapp.app.dev D/MY_DfuRunningService: Connected
2022-06-13 11:00:40.119 27088-27088/com.myapp.app.dev D/MY_DfuRunningService: ProcessStarting
2022-06-13 11:00:40.164 27088-27214/com.myapp.app.dev D/BluetoothGatt: onConnectionUpdated() - Device=FF:03:A6:82:AB:EC interval=96 latency=0 timeout=400 status=0
2022-06-13 11:00:41.102 27088-27515/com.myapp.app.dev I/DfuImpl: Enabling indications...
2022-06-13 11:00:41.102 27088-27515/com.myapp.app.dev D/BluetoothGatt: setCharacteristicNotification() - uuid: 0b177506-24f2-4343-b580-123c836b01e5 enable: true
2022-06-13 11:00:42.262 27088-27515/com.myapp.app.dev I/DfuImpl: Sending Enter Bootloader (Op Code = 1)
2022-06-13 11:00:42.264 27088-27088/com.myapp.app.dev D/MY_DfuRunningService: EnablingDfuMode
2022-06-13 11:00:43.793 27088-27483/com.myapp.app.dev V/FA: Inactivity, disconnecting from the service
2022-06-13 11:00:46.475 27088-27214/com.myapp.app.dev D/BluetoothGatt: onClientConnectionState() - status=8 clientIf=6 device=FF:03:A6:82:AB:EC
2022-06-13 11:00:46.475 27088-27214/com.myapp.app.dev W/DfuBaseService: Target device disconnected with status: 8
2022-06-13 11:00:46.476 27088-27515/com.myapp.app.dev I/DfuImpl: Device disconnected before receiving notification
2022-06-13 11:00:46.476 27088-27515/com.myapp.app.dev D/BluetoothGatt: refresh() - device: FF:03:A6:82:AB:EC
2022-06-13 11:00:46.478 27088-27515/com.myapp.app.dev I/DfuBaseService: Refreshing result: true
2022-06-13 11:00:46.478 27088-27515/com.myapp.app.dev I/DfuBaseService: Cleaning up...
2022-06-13 11:00:46.478 27088-27515/com.myapp.app.dev D/BluetoothGatt: cancelOpen() - device: FF:03:A6:82:AB:EC
2022-06-13 11:00:46.480 27088-27515/com.myapp.app.dev D/BluetoothGatt: close()
2022-06-13 11:00:46.480 27088-27515/com.myapp.app.dev D/BluetoothGatt: unregisterApp() - mClientIf=6
2022-06-13 11:00:46.482 27088-27515/com.myapp.app.dev I/DfuImpl: Restarting to bootloader mode
2022-06-13 11:00:46.496 27088-27515/com.myapp.app.dev D/BluetoothAdapter: isLeEnabled(): ON
2022-06-13 11:00:46.498 27088-27088/com.myapp.app.dev I/DfuBaseService: Action received: android.bluetooth.device.action.ACL_DISCONNECTED
2022-06-13 11:00:46.502 27088-27214/com.myapp.app.dev D/BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=6 mScannerId=0
2022-06-13 11:00:51.494 27088-27515/com.myapp.app.dev D/BluetoothAdapter: isLeEnabled(): ON
2022-06-13 11:00:51.500 27088-27515/com.myapp.app.dev I/DfuImpl: Scanning for new address finished with: null
2022-06-13 11:00:51.523 27088-27515/com.myapp.app.dev I/DfuBaseService: Starting DFU service in foreground
2022-06-13 11:00:53.535 27088-27515/com.myapp.app.dev I/DfuBaseService: Connecting to the device...
2022-06-13 11:00:53.537 27088-27515/com.myapp.app.dev D/BluetoothGatt: connect() - device: FF:03:A6:82:AB:EC, auto: false
2022-06-13 11:00:53.537 27088-27515/com.myapp.app.dev D/BluetoothGatt: registerApp()
2022-06-13 11:00:53.537 27088-27515/com.myapp.app.dev D/BluetoothGatt: registerApp() - UUID=72e2ef1f-e6b8-47b1-b81c-9547720c7b67
2022-06-13 11:00:53.541 27088-27214/com.myapp.app.dev D/BluetoothGatt: onClientRegistered() - status=0 clientIf=6
2022-06-13 11:00:53.542 27088-27088/com.myapp.app.dev D/MY_DfuRunningService: Connecting
2022-06-13 11:01:23.559 27088-27214/com.myapp.app.dev D/BluetoothGatt: onClientConnectionState() - status=133 clientIf=6 device=FF:03:A6:82:AB:EC
2022-06-13 11:01:23.559 27088-27214/com.myapp.app.dev E/DfuBaseService: Connection state change error: 133 newState: 0
2022-06-13 11:01:23.559 27088-27515/com.myapp.app.dev I/DfuBaseService: Connection error after: 30026 ms
2022-06-13 11:01:23.559 27088-27515/com.myapp.app.dev E/DfuBaseService: Device not reachable. Check if the device with address FF:03:A6:82:AB:EC is in range, is advertising and is connectable
2022-06-13 11:01:23.560 27088-27515/com.myapp.app.dev I/DfuBaseService: Attempt: 1
2022-06-13 11:01:23.560 27088-27515/com.myapp.app.dev D/BluetoothGatt: refresh() - device: FF:03:A6:82:AB:EC
2022-06-13 11:01:23.563 27088-27515/com.myapp.app.dev I/DfuBaseService: Refreshing result: true
2022-06-13 11:01:23.563 27088-27515/com.myapp.app.dev I/DfuBaseService: Cleaning up...
2022-06-13 11:01:23.563 27088-27515/com.myapp.app.dev D/BluetoothGatt: cancelOpen() - device: FF:03:A6:82:AB:EC
2022-06-13 11:01:23.565 27088-27515/com.myapp.app.dev D/BluetoothGatt: close()
2022-06-13 11:01:23.565 27088-27515/com.myapp.app.dev D/BluetoothGatt: unregisterApp() - mClientIf=6
2022-06-13 11:01:23.567 27088-27515/com.myapp.app.dev I/DfuBaseService: Restarting the service
2022-06-13 11:01:23.584 27088-27515/com.myapp.app.dev I/DfuBaseService: Starting DFU service in foreground
2022-06-13 11:01:25.594 27088-27515/com.myapp.app.dev I/DfuBaseService: Connecting to the device...
2022-06-13 11:01:25.596 27088-27515/com.myapp.app.dev D/BluetoothGatt: connect() - device: FF:03:A6:82:AB:EC, auto: false
2022-06-13 11:01:25.596 27088-27515/com.myapp.app.dev D/BluetoothGatt: registerApp()
2022-06-13 11:01:25.596 27088-27515/com.myapp.app.dev D/BluetoothGatt: registerApp() - UUID=6a35cafc-2253-4533-8061-70edde516640
2022-06-13 11:01:25.600 27088-27214/com.myapp.app.dev D/BluetoothGatt: onClientRegistered() - status=0 clientIf=6
2022-06-13 11:01:55.609 27088-27214/com.myapp.app.dev D/BluetoothGatt: onClientConnectionState() - status=133 clientIf=6 device=FF:03:A6:82:AB:EC
2022-06-13 11:01:55.609 27088-27214/com.myapp.app.dev E/DfuBaseService: Connection state change error: 133 newState: 0
2022-06-13 11:01:55.610 27088-27515/com.myapp.app.dev I/DfuBaseService: Connection error after: 30017 ms
2022-06-13 11:01:55.610 27088-27515/com.myapp.app.dev E/DfuBaseService: Device not reachable. Check if the device with address FF:03:A6:82:AB:EC is in range, is advertising and is connectable
2022-06-13 11:01:55.611 27088-27515/com.myapp.app.dev I/DfuBaseService: Attempt: 2
2022-06-13 11:01:55.611 27088-27515/com.myapp.app.dev D/BluetoothGatt: refresh() - device: FF:03:A6:82:AB:EC
2022-06-13 11:01:55.613 27088-27515/com.myapp.app.dev I/DfuBaseService: Refreshing result: true
2022-06-13 11:01:55.613 27088-27515/com.myapp.app.dev I/DfuBaseService: Cleaning up...
2022-06-13 11:01:55.613 27088-27515/com.myapp.app.dev D/BluetoothGatt: cancelOpen() - device: FF:03:A6:82:AB:EC
2022-06-13 11:01:55.617 27088-27515/com.myapp.app.dev D/BluetoothGatt: close()
2022-06-13 11:01:55.617 27088-27515/com.myapp.app.dev D/BluetoothGatt: unregisterApp() - mClientIf=6
2022-06-13 11:01:55.618 27088-27515/com.myapp.app.dev I/DfuBaseService: Restarting the service
2022-06-13 11:01:55.633 27088-27515/com.myapp.app.dev I/DfuBaseService: Starting DFU service in foreground
2022-06-13 11:01:57.644 27088-27515/com.myapp.app.dev I/DfuBaseService: Connecting to the device...
2022-06-13 11:01:57.646 27088-27515/com.myapp.app.dev D/BluetoothGatt: connect() - device: FF:03:A6:82:AB:EC, auto: false
2022-06-13 11:01:57.646 27088-27515/com.myapp.app.dev D/BluetoothGatt: registerApp()
2022-06-13 11:01:57.646 27088-27515/com.myapp.app.dev D/BluetoothGatt: registerApp() - UUID=6afb1bb3-f8e8-432c-ab2b-59602a5b0ef6
2022-06-13 11:01:57.652 27088-27214/com.myapp.app.dev D/BluetoothGatt: onClientRegistered() - status=0 clientIf=6
2022-06-13 11:02:27.661 27088-27214/com.myapp.app.dev D/BluetoothGatt: onClientConnectionState() - status=133 clientIf=6 device=FF:03:A6:82:AB:EC
2022-06-13 11:02:27.662 27088-27214/com.myapp.app.dev E/DfuBaseService: Connection state change error: 133 newState: 0
2022-06-13 11:02:27.662 27088-27515/com.myapp.app.dev I/DfuBaseService: Connection error after: 30020 ms
2022-06-13 11:02:27.662 27088-27515/com.myapp.app.dev E/DfuBaseService: Device not reachable. Check if the device with address FF:03:A6:82:AB:EC is in range, is advertising and is connectable
2022-06-13 11:02:27.662 27088-27515/com.myapp.app.dev I/DfuBaseService: Attempt: 3
2022-06-13 11:02:27.664 27088-27515/com.myapp.app.dev D/BluetoothGatt: refresh() - device: FF:03:A6:82:AB:EC
2022-06-13 11:02:27.666 27088-27515/com.myapp.app.dev I/DfuBaseService: Refreshing result: true
2022-06-13 11:02:27.666 27088-27515/com.myapp.app.dev I/DfuBaseService: Cleaning up...
2022-06-13 11:02:27.666 27088-27515/com.myapp.app.dev D/BluetoothGatt: cancelOpen() - device: FF:03:A6:82:AB:EC
2022-06-13 11:02:27.668 27088-27515/com.myapp.app.dev D/BluetoothGatt: close()
2022-06-13 11:02:27.668 27088-27515/com.myapp.app.dev D/BluetoothGatt: unregisterApp() - mClientIf=6
2022-06-13 11:02:28.280 27088-27088/com.myapp.app.dev D/MY_DfuRunningService: Error: GATT ERROR
2022-06-13 11:02:28.323 27088-27088/com.myapp.app.dev I/DfuBaseService: DFU service destroyed
2022-06-13 11:02:30.333 27088-27548/com.myapp.app.dev V/FA: Recording user engagement, ms: 111647
2022-06-13 11:02:30.442 27088-27548/com.myapp.app.dev V/FA: Connecting to remote service
2022-06-13 11:02:30.460 27088-27548/com.myapp.app.dev V/FA: Connection attempt already in progress
2022-06-13 11:02:30.460 27088-27548/com.myapp.app.dev V/FA: Activity paused, time: 4509564
2022-06-13 11:02:30.461 27088-27548/com.myapp.app.dev V/FA: Activity resumed, time: 4509599
2022-06-13 11:02:30.578 27088-27548/com.myapp.app.dev V/FA: Connection attempt already in progress
2022-06-13 11:02:30.580 27088-27548/com.myapp.app.dev V/FA: Connection attempt already in progress
2022-06-13 11:02:30.580 27088-27548/com.myapp.app.dev D/FA: Connected to remote service
2022-06-13 11:02:30.582 27088-27548/com.myapp.app.dev V/FA: Processing queued up service tasks: 4