nRF Connect Desktop BLE app v2.4.0 with nRF52840 dongle does not scan devices (times out)

[sagar448]

Hi,

I am using nRF Connect for Desktop v3.4.0 and BLE app v2.4.0. I flashed the latest connectivity firmware ("ble-connectivity 4.1.1+May-07-2019-15-12-19. SoftDevice API version: 5") onto an nRF52840 dongle. When I start scanning I do not see any devices in the sidebar. After some time the log message states that the device scan timed out ("Scanning timed out on adapter"). I can make the dongle advertise with the app, so I know that the app is definitely able to control the dongle. I'm on Mac OSX Catalina.

Regards.

[Vidar Berg]

Hi,

I tested with the same version of the app on MacOS 10.15.2, but did not manage to replicate it here, unfortunately. Are you sure you have advertising BLE devices nearby? Maybe you can try the nRF connect app on Android or iOS to see if it gives the same result. 

Regards,

Vidar

[sagar448]

Thank you for your reply. I'll give my steps in detail:

I have turned on my macbook's Bluetooth for advertising.
I have turned on native Bluetooth 4.2 advertising on an Android phone.
I can see the macbook advertising on my Android phone.
I can see the phone's advertising on my macbook.

I went to the dongle's settings in the nRF Connect for Desktop BLE App and turned on advertising.
I went to the phone's nRF Connect for Mobile and can see the dongle's LE Legacy packets. I know it is advertising.

I went back to the nRF Connect for Desktop BLE App and clicked on Start Scan. I cannot see the MacBook address or phone address on the Desktop BLE App. Nothing shows up.

I started a scannable advertiser on the nRF Connect for Mobile app. On the nRF Desktop BLE App that doesn't show up either.

I am in an environment where there are around a dozen BLE devices nearby (smart TVs, phones etc) and they are all visible on the nRF Connect for Mobile app.

I flashed a beacon on a DK and that is not visible in the Desktop BLE app either.

I'm on OSX 10.15.5. Desktop BLE App v2.4.0 and nRF Connect for Desktop v3.4.0

[Vidar Berg]

Thanks for the additional details. We still haven't had any luck with replicating the issue on our end (the developer has tried it too). Do you mind uploading the log file as well so I can add it to the bug report? As you maybe have noticed, there is a separate button in the "Log" view to open the log file.

[sagar448]

Thank you for your reply.

Please see the attached log.

2020-06-16T16:08:03.967Z INFO Application data folder: /Users/RedactedUser/Library/Application Support/nrfconnect/pc-nrfconnect-ble
2020-06-16T16:08:04.041Z DEBUG App pc-nrfconnect-ble v2.4.0 official
2020-06-16T16:08:04.041Z DEBUG App path: /Users/RedactedUser/.nrfconnect-apps/node_modules/pc-nrfconnect-ble
2020-06-16T16:08:04.041Z DEBUG nRFConnect 3.4.0 is supported by the app (^3.4.0)
2020-06-16T16:08:04.041Z DEBUG nRFConnect path: /Applications/nRF Connect.app/Contents/Resources/app.asar
2020-06-16T16:08:04.041Z DEBUG HomeDir: /Users/RedactedUser
2020-06-16T16:08:04.041Z DEBUG TmpDir: /var/folders/53/248sn0110b3_q02b8tf4cvc80000gp/T/
2020-06-16T16:08:04.092Z VERBOSE Could not fetch serial number for serial port at /dev/tty.URT0
2020-06-16T16:08:04.092Z VERBOSE Could not fetch serial number for serial port at /dev/tty.Bluetooth-Incoming-Port
2020-06-16T16:08:26.873Z INFO Device setup completed
2020-06-16T16:08:26.874Z INFO Connectivity firmware version: ble-connectivity 4.1.1+May-07-2019-15-12-19. SoftDevice API version: 5. Baud rate: 1000000.
2020-06-16T16:08:26.881Z INFO Opening adapter connected to /dev/tty.usbmodemREDACTED2
2020-06-16T16:08:27.825Z INFO Successfully opened /dev/tty.usbmodemREDACTED2. Baud rate: 1000000. Flow control: none. Parity: none.
2020-06-16T16:08:27.826Z DEBUG State change: STATE_START -> STATE_RESET
2020-06-16T16:08:27.826Z DEBUG        1 ->  [N/A] type:          RESERVED_5 reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:27.827Z INFO Reset performed on adapter /dev/tty.usbmodemREDACTED2
2020-06-16T16:08:27.893Z DEBUG     1/ 0 <-  [01 7e ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:2 data_integrity:0 err_code:0x0 [SYNC]
2020-06-16T16:08:28.130Z DEBUG State change: STATE_RESET -> STATE_UNINITIALIZED
2020-06-16T16:08:28.130Z DEBUG        2 ->  [01 7e ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:2 data_integrity:0 err_code:0x0 [SYNC]
2020-06-16T16:08:28.130Z DEBUG     2/ 0 <-  [02 7d ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:2 data_integrity:0 err_code:0x0 [SYNC_RESP]
2020-06-16T16:08:28.131Z DEBUG State change: STATE_UNINITIALIZED -> STATE_INITIALIZED
2020-06-16T16:08:28.131Z DEBUG        3 ->  [03 fc 11 ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:3 data_integrity:0 err_code:0x0 [CONFIG [ sliding-window-size:1 out-of-frame:0 data-integrity-check-type:1 version-number:0 ]]
2020-06-16T16:08:28.383Z DEBUG        4 ->  [03 fc 11 ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:3 data_integrity:0 err_code:0x0 [CONFIG [ sliding-window-size:1 out-of-frame:0 data-integrity-check-type:1 version-number:0 ]]
2020-06-16T16:08:28.530Z DEBUG     3/ 0 <-  [01 7e ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:2 data_integrity:0 err_code:0x0 [SYNC]
2020-06-16T16:08:28.531Z DEBUG        5 ->  [02 7d ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:2 data_integrity:0 err_code:0x0 [SYNC_RESP]
2020-06-16T16:08:28.637Z DEBUG        6 ->  [03 fc 11 ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:3 data_integrity:0 err_code:0x0 [CONFIG [ sliding-window-size:1 out-of-frame:0 data-integrity-check-type:1 version-number:0 ]]
2020-06-16T16:08:28.638Z DEBUG     4/ 0 <-  [03 fc 11 ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:3 data_integrity:0 err_code:0x0 [CONFIG [ sliding-window-size:1 out-of-frame:0 data-integrity-check-type:1 version-number:0 ]]
2020-06-16T16:08:28.638Z DEBUG        7 ->  [04 7b 11 ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:3 data_integrity:0 err_code:0x0 [CONFIG_RESP [ sliding-window-size:1 out-of-frame:0 data-integrity-check-type:1 version-number:0 ]]
2020-06-16T16:08:28.892Z DEBUG        8 ->  [03 fc 11 ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:3 data_integrity:0 err_code:0x0 [CONFIG [ sliding-window-size:1 out-of-frame:0 data-integrity-check-type:1 version-number:0 ]]
2020-06-16T16:08:28.893Z DEBUG     5/ 0 <-  [04 7b 11 ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:3 data_integrity:0 err_code:0x0 [CONFIG_RESP [ sliding-window-size:1 out-of-frame:0 data-integrity-check-type:1 version-number:0 ]]
2020-06-16T16:08:28.893Z DEBUG State change: STATE_INITIALIZED -> STATE_ACTIVE
2020-06-16T16:08:28.903Z INFO Adapter connected to /dev/tty.usbmodemREDACTED2 opened
2020-06-16T16:08:28.904Z DEBUG        9 ->  [00 69 20 00 00 00 01 01 08 a0 01 ] type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:0 payload_length:b data_integrity:1 header_checksum:82 err_code:0x0
2020-06-16T16:08:28.904Z DEBUG     6/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.904Z DEBUG     7/ 0 <-  [01 69 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:1 payload_length:6 data_integrity:1 header_checksum:ca err_code:0x0
2020-06-16T16:08:28.904Z DEBUG       10 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.904Z DEBUG       11 ->  [00 69 23 00 00 00 01 01 f7 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:1 ack#:1 payload_length:a data_integrity:1 header_checksum:89 err_code:0x0
2020-06-16T16:08:28.904Z DEBUG     8/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.904Z DEBUG     9/ 0 <-  [01 69 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:1 ack#:2 payload_length:6 data_integrity:1 header_checksum:c1 err_code:0x0
2020-06-16T16:08:28.904Z DEBUG       12 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.904Z DEBUG       13 ->  [00 69 01 00 00 00 01 0a ] type:     VENDOR_SPECIFIC reliable:yes seq#:2 ack#:2 payload_length:8 data_integrity:1 header_checksum:a0 err_code:0x0
2020-06-16T16:08:28.905Z DEBUG    10/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:3 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.905Z DEBUG    11/ 0 <-  [01 69 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:2 ack#:3 payload_length:6 data_integrity:1 header_checksum:b8 err_code:0x0
2020-06-16T16:08:28.905Z DEBUG       14 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:3 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.905Z DEBUG       15 ->  [00 69 40 00 00 00 01 01 07 01 ] type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:3 payload_length:a data_integrity:1 header_checksum:77 err_code:0x0
2020-06-16T16:08:28.905Z DEBUG    12/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.905Z DEBUG    13/ 0 <-  [01 69 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:4 payload_length:6 data_integrity:1 header_checksum:af err_code:0x0
2020-06-16T16:08:28.905Z DEBUG       16 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.905Z DEBUG       17 ->  [00 69 a0 00 00 00 01 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:4 ack#:4 payload_length:8 data_integrity:1 header_checksum:8e err_code:0x0
2020-06-16T16:08:28.905Z DEBUG    14/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:5 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.906Z DEBUG    15/ 0 <-  [01 69 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:4 ack#:5 payload_length:6 data_integrity:1 header_checksum:a6 err_code:0x0
2020-06-16T16:08:28.906Z DEBUG       18 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:5 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.906Z DEBUG       19 ->  [00 69 a1 00 00 00 01 80 05 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:5 ack#:5 payload_length:b data_integrity:1 header_checksum:55 err_code:0x0
2020-06-16T16:08:28.906Z DEBUG    16/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:6 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.906Z DEBUG    17/ 0 <-  [01 69 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:5 ack#:6 payload_length:6 data_integrity:1 header_checksum:9d err_code:0x0
2020-06-16T16:08:28.906Z DEBUG       20 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:6 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.906Z DEBUG       21 ->  [00 60 ] type:     VENDOR_SPECIFIC reliable:yes seq#:6 ack#:6 payload_length:2 data_integrity:1 header_checksum:dc err_code:0x0
2020-06-16T16:08:28.906Z DEBUG    18/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.906Z DEBUG    19/ 0 <-  [01 60 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:6 ack#:7 payload_length:6 data_integrity:1 header_checksum:94 err_code:0x0
2020-06-16T16:08:28.906Z DEBUG       22 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.909Z DEBUG       23 ->  [00 65 01 ] type:     VENDOR_SPECIFIC reliable:yes seq#:7 ack#:7 payload_length:3 data_integrity:1 header_checksum:c3 err_code:0x0
2020-06-16T16:08:28.909Z DEBUG    20/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.910Z DEBUG    21/ 0 <-  [01 65 00 00 00 00 09 59 00 a5 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:7 ack#:0 payload_length:b data_integrity:1 header_checksum:7b err_code:0x0
2020-06-16T16:08:28.910Z DEBUG       24 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.910Z DEBUG       25 ->  [00 7d 01 f8 00 01 ] type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:0 payload_length:6 data_integrity:1 header_checksum:d2 err_code:0x0
2020-06-16T16:08:28.910Z DEBUG    22/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.911Z DEBUG    23/ 0 <-  [01 7d 00 00 00 00 01 05 00 6e 52 46 35 78 ] type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:1 payload_length:e data_integrity:1 header_checksum:4a err_code:0x0
2020-06-16T16:08:28.911Z DEBUG       26 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.911Z DEBUG       27 ->  [00 6d 01 ] type:     VENDOR_SPECIFIC reliable:yes seq#:1 ack#:1 payload_length:3 data_integrity:1 header_checksum:f9 err_code:0x0
2020-06-16T16:08:28.916Z DEBUG SoftDevice BLE stack enabled.
2020-06-16T16:08:28.916Z DEBUG    24/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:28.916Z DEBUG    25/ 0 <-  [01 6d 00 00 00 00 02 a9 77 08 bd 90 e4 ] type:     VENDOR_SPECIFIC reliable:yes seq#:1 ack#:2 payload_length:d data_integrity:1 header_checksum:51 err_code:0x0
2020-06-16T16:08:28.916Z DEBUG       28 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:34.116Z DEBUG       29 ->  [00 8a 01 01 a0 00 20 00 3c 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:2 ack#:2 payload_length:a data_integrity:1 header_checksum:80 err_code:0x0
2020-06-16T16:08:34.116Z DEBUG    26/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:3 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:34.117Z DEBUG    27/ 0 <-  [01 8a 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:2 ack#:3 payload_length:6 data_integrity:1 header_checksum:b8 err_code:0x0
2020-06-16T16:08:34.117Z DEBUG       30 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:3 payload_length:0 data_integrity:0 err_code:0x0
2020-06-16T16:08:34.120Z INFO Scan started
2020-06-16T16:09:34.120Z DEBUG GAP_EVT_TIMEOUT time:2020-06-16T16:09:34.115Z connHandle:65535 src:1 srcName:bleGapTimeoutSrcScan
2020-06-16T16:09:34.125Z INFO Scanning timed out on adapter /dev/tty.usbmodemREDACTED2
2020-06-16T16:09:34.126Z DEBUG    28/ 0 <-  [02 1b 00 ff ff 01 ] type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:3 payload_length:6 data_integrity:1 header_checksum:b7 err_code:0x0
2020-06-16T16:09:34.126Z DEBUG       31 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0x0

[Vidar Berg]

I don't see anything wrong in the log you posted, but have added to the bug internal report so the dev team can take a look at it. I will update the ticket as soon as I know more.

[sagar448]

Thank you. At this point I'm not ruling out a hardware fault on the dongle, even though it is broadcasting fine.