Nrfutil not finding dfu target

[Michal]

Hi,

I am trying to nrfutil to execute dfu over BLE using nrf52 dk and nrfutil. I have no problems when I try to use nrf_connect mobile app, but I want to use nrfutil desktop app. I want to use finding device by name, but doesn't seem to work. I run nrfutl with multiple -v to see more logs and I was suprised to see that it finds my device - TEST_A - but still doesn't connect to it and fails with "not found". Please have a look at the logs.

C:\Users\THINK\biol\repos\audinor\audinor>nrfutil -v -v -v dfu ble -ic NRF52 -pkg _release/receiver_nrfA_release_v0.10.0_dfuimg.zip -p COM6 -n "TEST_A" -f
Board already flashed with connectivity firmware.
2021-04-08 11:47:51,147 Using connectivity board at serial port: COM6
2021-04-08 11:47:51,174 Sending Application image.
2021-04-08 11:47:54,624 Successfully opened COM6. Baud rate: 1000000. Flow control: none. Parity: none.
2021-04-08 11:47:54,624 evt> severity(20) message(Successfully opened COM6. Baud rate: 1000000. Flow control: none. Parity: none.)
2021-04-08 11:47:54,627 RpcAppStatus.resetPerformed: Target Reset performed
2021-04-08 11:47:54,632 evt> status code(RpcAppStatus.resetPerformed) message(Target Reset performed)
2021-04-08 11:47:55,326 RpcAppStatus.connectionActive: Connection active
2021-04-08 11:47:55,326 evt> status code(RpcAppStatus.connectionActive) message(Connection active)
2021-04-08 11:47:56,133 BLE: Scanning for TEST_A
2021-04-08 11:47:56,932 evt> adv_report conn(65535)
 peer_addr(7D:7B:CF:31:E1:BB)
 rssi(-45)
 adv_type(BLEGapAdvType.non_connectable_undirected)
2021-04-08 11:47:56,933 Received advertisement report, address: 0x7D7BCF31E1BB, device_name:
2021-04-08 11:47:57,372 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-33)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:47:57,373 Received advertisement report, address: 0xD8FE54471CE3, device_name: TEST_A
2021-04-08 11:47:57,418 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-32)
 adv_type(None)
2021-04-08 11:47:57,419 Received advertisement report, address: 0xD8FE54471CE3, device_name:
2021-04-08 11:47:57,464 evt> adv_report conn(65535)
 peer_addr(5F:43:9F:32:B0:61)
 rssi(-76)
 adv_type(BLEGapAdvType.non_connectable_undirected)
2021-04-08 11:47:57,465 Received advertisement report, address: 0x5F439F32B061, device_name:
2021-04-08 11:47:57,509 evt> adv_report conn(65535)
 peer_addr(35:11:6E:0F:25:0F)
 rssi(-88)
 adv_type(BLEGapAdvType.non_connectable_undirected)
2021-04-08 11:47:57,510 Received advertisement report, address: 0x35116E0F250F, device_name:
2021-04-08 11:47:57,556 evt> adv_report conn(65535)
 peer_addr(64:93:21:69:7F:03)
 rssi(-77)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:47:57,557 Received advertisement report, address: 0x649321697F03, device_name:
2021-04-08 11:47:57,602 evt> adv_report conn(65535)
 peer_addr(73:22:50:13:57:F0)
 rssi(-69)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:47:57,603 Received advertisement report, address: 0x7322501357F0, device_name:
2021-04-08 11:47:57,649 evt> adv_report conn(65535)
 peer_addr(73:22:50:13:57:F0)
 rssi(-68)
 adv_type(None)
2021-04-08 11:47:57,650 Received advertisement report, address: 0x7322501357F0, device_name:
2021-04-08 11:47:57,696 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-33)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:47:57,697 Received advertisement report, address: 0xD8FE54471CE3, device_name: TEST_A
2021-04-08 11:47:57,743 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-32)
 adv_type(None)
2021-04-08 11:47:57,744 Received advertisement report, address: 0xD8FE54471CE3, device_name:
2021-04-08 11:47:57,789 evt> adv_report conn(65535)
 peer_addr(F8:3F:51:2D:7D:B0)
 rssi(-89)
 adv_type(BLEGapAdvType.non_connectable_undirected)
2021-04-08 11:47:57,790 Received advertisement report, address: 0xF83F512D7DB0, device_name:
2021-04-08 11:47:57,835 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-33)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:47:57,836 Received advertisement report, address: 0xD8FE54471CE3, device_name: TEST_A
2021-04-08 11:47:58,282 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-32)
 adv_type(None)
2021-04-08 11:47:58,283 Received advertisement report, address: 0xD8FE54471CE3, device_name:
2021-04-08 11:47:58,720 evt> adv_report conn(65535)
 peer_addr(7D:7B:CF:31:E1:BB)
 rssi(-45)
 adv_type(BLEGapAdvType.non_connectable_undirected)
2021-04-08 11:47:58,721 Received advertisement report, address: 0x7D7BCF31E1BB, device_name:
2021-04-08 11:47:59,163 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-32)
 adv_type(None)
2021-04-08 11:47:59,163 Received advertisement report, address: 0xD8FE54471CE3, device_name:
2021-04-08 11:47:59,210 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-33)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:47:59,211 Received advertisement report, address: 0xD8FE54471CE3, device_name: TEST_A
2021-04-08 11:47:59,257 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-31)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:47:59,258 Received advertisement report, address: 0xD8FE54471CE3, device_name: TEST_A
2021-04-08 11:47:59,304 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-30)
 adv_type(None)
2021-04-08 11:47:59,304 Received advertisement report, address: 0xD8FE54471CE3, device_name:
2021-04-08 11:47:59,351 evt> adv_report conn(65535)
 peer_addr(5F:43:9F:32:B0:61)
 rssi(-78)
 adv_type(BLEGapAdvType.non_connectable_undirected)
2021-04-08 11:47:59,352 Received advertisement report, address: 0x5F439F32B061, device_name:
2021-04-08 11:47:59,398 evt> adv_report conn(65535)
 peer_addr(57:5F:B9:D0:A3:3B)
 rssi(-87)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:47:59,399 Received advertisement report, address: 0x575FB9D0A33B, device_name:
2021-04-08 11:47:59,429 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-30)
 adv_type(None)
2021-04-08 11:47:59,430 Received advertisement report, address: 0xD8FE54471CE3, device_name:
2021-04-08 11:47:59,475 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-31)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:47:59,476 Received advertisement report, address: 0xD8FE54471CE3, device_name: TEST_A
2021-04-08 11:47:59,522 evt> adv_report conn(65535)
 peer_addr(49:A5:96:83:DB:6E)
 rssi(-88)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:47:59,523 Received advertisement report, address: 0x49A59683DB6E, device_name:
2021-04-08 11:47:59,957 evt> adv_report conn(65535)
 peer_addr(35:11:6E:0F:25:0F)
 rssi(-88)
 adv_type(BLEGapAdvType.non_connectable_undirected)
2021-04-08 11:47:59,958 Received advertisement report, address: 0x35116E0F250F, device_name:
2021-04-08 11:48:00,004 evt> adv_report conn(65535)
 peer_addr(73:22:50:13:57:F0)
 rssi(-67)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:48:00,005 Received advertisement report, address: 0x7322501357F0, device_name:
2021-04-08 11:48:00,051 evt> adv_report conn(65535)
 peer_addr(73:22:50:13:57:F0)
 rssi(-66)
 adv_type(None)
2021-04-08 11:48:00,052 Received advertisement report, address: 0x7322501357F0, device_name:
2021-04-08 11:48:00,488 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-33)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:48:00,489 Received advertisement report, address: 0xD8FE54471CE3, device_name: TEST_A
2021-04-08 11:48:00,535 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-34)
 adv_type(None)
2021-04-08 11:48:00,536 Received advertisement report, address: 0xD8FE54471CE3, device_name:
2021-04-08 11:48:00,583 evt> adv_report conn(65535)
 peer_addr(64:93:21:69:7F:03)
 rssi(-72)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:48:00,584 Received advertisement report, address: 0x649321697F03, device_name:
2021-04-08 11:48:00,631 evt> adv_report conn(65535)
 peer_addr(64:93:21:69:7F:03)
 rssi(-71)
 adv_type(None)
2021-04-08 11:48:00,631 Received advertisement report, address: 0x649321697F03, device_name:
2021-04-08 11:48:00,678 evt> adv_report conn(65535)
 peer_addr(77:17:7E:73:57:51)
 rssi(-91)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:48:00,679 Received advertisement report, address: 0x77177E735751, device_name:
2021-04-08 11:48:00,725 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-31)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:48:00,725 Received advertisement report, address: 0xD8FE54471CE3, device_name: TEST_A
2021-04-08 11:48:00,770 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-31)
 adv_type(None)
2021-04-08 11:48:00,770 Received advertisement report, address: 0xD8FE54471CE3, device_name:
2021-04-08 11:48:00,817 evt> adv_report conn(65535)
 peer_addr(7D:7B:CF:31:E1:BB)
 rssi(-37)
 adv_type(BLEGapAdvType.non_connectable_undirected)
2021-04-08 11:48:00,818 Received advertisement report, address: 0x7D7BCF31E1BB, device_name:
2021-04-08 11:48:00,862 evt> adv_report conn(65535)
 peer_addr(7D:7B:CF:31:E1:BB)
 rssi(-38)
 adv_type(BLEGapAdvType.non_connectable_undirected)
2021-04-08 11:48:00,863 Received advertisement report, address: 0x7D7BCF31E1BB, device_name:
2021-04-08 11:48:01,301 evt> adv_report conn(65535)
 peer_addr(D8:FE:54:47:1C:E3)
 rssi(-31)
 adv_type(BLEGapAdvType.connectable_undirected)
2021-04-08 11:48:01,302 Received advertisement report, address: 0xD8FE54471CE3, device_name: TEST_A
Traceback (most recent call last):
  File "nordicsemi\__main__.py", line 1537, in <module>
  File "site-packages\click\core.py", line 764, in __call__
  File "site-packages\click\core.py", line 717, in main
  File "site-packages\click\core.py", line 1137, in invoke
  File "site-packages\click\core.py", line 1137, in invoke
  File "site-packages\click\core.py", line 956, in invoke
  File "site-packages\click\core.py", line 555, in invoke
  File "nordicsemi\__main__.py", line 1199, in ble
  File "nordicsemi\dfu\dfu.py", line 127, in dfu_send_images
  File "nordicsemi\dfu\dfu.py", line 88, in _dfu_send_image
  File "nordicsemi\dfu\dfu_transport_ble.py", line 476, in open
  File "nordicsemi\dfu\dfu_transport_ble.py", line 153, in connect
  File "nordicsemi\dfu\dfu_transport_ble.py", line 263, in verify_stable_connection
Exception: Connection Failure - Device not found!
[14072] Failed to execute script __main__

Michal

[Michal]

Hi Einar,

I am running on Windows 10 64bit. Yes it worked when I tried to use address instead of name.

[Einar Thorsrud]

I see. I tested this now using nrfutil 6.1.0 and the nRF52 DK (also tested nRF52840 dongle) and with that both works for me. (I did experience a few times over quite a few tests that nrfutil did not manage to connect before it timed out though.)

Does this consistently fail for you, or just occasionally? Which nrfutil version do you use?

[Michal]

I am using nrfutil 6.0.1. Tested with nrf52 dk and nrf52840 dk. Fails every single time, but when using address it works on each attempt.

[Einar Thorsrud]

I see. I do not see any reason it should have changed, but what if you update to the latest nrfutil? And which Python version do you use (I use 3.7.2)?

[Michal]

I updated to nrfutil 6.1.0. Python version 3.7.4. I still get the same behaviour. I tested with different name but still not connecting. Also I notice that updating using nrfutil is much much longer in compare to using mobile app on my iOS device. Why is that? Is there any way to optimize it?

[Einar Thorsrud]

Regarding time to update that probably mostly liked by the low throughput of the pc-ble-driver and serialization scheme. There is not much optimization that can be done here as there are fundamental limits in the way it is designed. There have also been a few nrfutil versions with very slow DFU performance but that is fixed in >= 6.0.1 by this PR.