nRF Connect sends ATT Find Information Request with Starting Handle being greater than Ending Handle

[Torsten Robitzki]

Hi,

first of all: Thank you very much for providing such a great tools as the nRF Connect Desktop tool!

I've installed the tool today and discovered, that the nRF Connect sends ATT Find Information Request with Starting Handle being greater than Ending Handle during discovery. I don't know if this is intentional or not, but according to the specs: "the Starting Handle parameter shall be less than or equal to the Ending Handle parameter.", this could be a bug.

The version I've used is v2.6.1 for OS/X and the firmware on the nRF52840 Dongle is "Connectivity firmware version: ble-connectivity 0.1.0+Aug-14-2018-15-12-51. SoftDevice API version: 3. Baud rate: 115200."

I can give you sniffs if you like to investigate into this.

Kind regards,

Torsten

[Marjeris Romero]

Hi Torsten,

I am not sure I understood your question. Is this happening when performing a characteristic descriptor discovery with the nrf52840 dongle? What are you connecting to?

Maybe you can attach the log file of the nRF Connect app? You can open the whole log file by pushing the log icon in the above the log window.

Best Regards,

Marjeris

[Torsten Robitzki]

Hi Marjeris,

sorry for being so unclear! I don't have a question. I think I've stumbled over a Bug in the wonderful nRF Connect Application. Please find attached a protocol of the discovery process between the nRF Connect App (as a central) and a nrf52 as peripheral.

The nRFConnect is sending an "ATT Send Find Information Request" (frame 190 at 5.26) with a starting handle of 0x0009 and an ending handle of 0x0008. According to the Bluetooth Core Spec, this is not allowed.

I don't know if this is intentional or a Bug in the nRF Connect App, but I thought it could be worth reporting this. But feel free to ignore this report.

The discovery happens directly after connecting the nRF Connect App, using a nrf52840 dongle, with a nrf52832 eval board.

Best Regards,

Torsten

Fullscreen nrf_connect.log Download

2019-02-11T10:11:18.989Z INFO Application data folder: /Users/todi/Library/Application Support/nrfconnect/pc-nrfconnect-ble
2019-02-11T10:11:19.169Z DEBUG App pc-nrfconnect-ble v2.2.0 official
2019-02-11T10:11:19.169Z DEBUG App path: /Users/todi/.nrfconnect-apps/node_modules/pc-nrfconnect-ble
2019-02-11T10:11:19.169Z DEBUG nRFConnect 2.6.1 is supported by the app (^2.4.0)
2019-02-11T10:11:19.169Z DEBUG nRFConnect path: /Applications/nRF Connect.app/Contents/Resources/app.asar
2019-02-11T10:11:19.169Z DEBUG HomeDir: /Users/todi
2019-02-11T10:11:19.169Z DEBUG TmpDir: /var/folders/0g/2y6ypy_14ydbs2qncvff348m0000gn/T/
2019-02-11T10:11:19.217Z VERBOSE Could not fetch serial number for serial port at /dev/tty.SOC
2019-02-11T10:11:19.217Z VERBOSE Could not fetch serial number for serial port at /dev/tty.MALS
2019-02-11T10:11:19.217Z VERBOSE Could not fetch serial number for serial port at /dev/tty.Bluetooth-Incoming-Port
2019-02-11T10:11:19.217Z VERBOSE Could not fetch serial number for serial port at /dev/tty.iPhone-WirelessiAPv2
2019-02-11T10:11:20.925Z INFO Validating connectivity firmware for device with serial number E4ED2FADDEC4...
2019-02-11T10:11:20.932Z INFO Connectivity firmware is valid.
2019-02-11T10:11:20.932Z INFO Connectivity firmware version: ble-connectivity 0.1.0+Aug-14-2018-15-12-51. SoftDevice API version: 3. Baud rate: 115200.
2019-02-11T10:11:20.934Z INFO Opening adapter connected to /dev/tty.usbmodem1452
2019-02-11T10:11:21.456Z DEBUG State change: STATE_START -> STATE_FAILED
...

2019-02-12T15:20:26.548Z INFO Connecting to device
2019-02-12T15:20:26.552Z DEBUG     2230 ->  [00 8c 01 02 cc f4 8f f1 0f c0 01 01 a0 00 50 00 14 00 01 06 00 06 00 00 00 90 01 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:3 payload_length:1b data_integrity:1 header_checksum:66 err_code:0
2019-02-12T15:20:26.552Z DEBUG  2228/ 0 <-  []
               type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:26.552Z DEBUG  2229/ 0 <-  [01 8c 00 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:4 payload_length:6 data_integrity:1 header_checksum:af err_code:0
2019-02-12T15:20:26.552Z DEBUG     2231 ->  []
               type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.056Z DEBUG GAP_EVT_CONNECTED time:2019-02-12T15:20:27.055Z connHandle:0 peerAddr:[address:C0:0F:F1:8F:F4:CC type:randomStatic addrIdPeer:0] role:central connParams:[minConnInterval:7_5 maxConnInterval:7_5 slaveLatency:0 connSupTimeout:4000]
2019-02-12T15:20:27.061Z INFO Connected to device C0:0F:F1:8F:F4:CC
2019-02-12T15:20:27.071Z DEBUG  2230/ 0 <-  [02 10 00 00 00 02 cc f4 8f f1 0f c0 02 06 00 06 00 00 00 90 01 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:4 ack#:4 payload_length:15 data_integrity:1 header_checksum:bd err_code:0
2019-02-12T15:20:27.071Z DEBUG     2232 ->  []
               type:                 ACK reliable: no seq#:0 ack#:5 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.072Z DEBUG     2233 ->  [00 94 00 00 01 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:4 ack#:5 payload_length:7 data_integrity:1 header_checksum:96 err_code:0
2019-02-12T15:20:27.075Z DEBUG  2231/ 0 <-  []
               type:                 ACK reliable: no seq#:0 ack#:5 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.075Z DEBUG  2232/ 0 <-  [01 94 00 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:5 ack#:5 payload_length:6 data_integrity:1 header_checksum:a5 err_code:0
2019-02-12T15:20:27.075Z DEBUG     2234 ->  []
               type:                 ACK reliable: no seq#:0 ack#:6 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.088Z DEBUG GATTC_EVT_PRIM_SRVC_DISC_RSP time:2019-02-12T15:20:27.086Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 count:1 services:[[uuid:[uuid:27105 type:2 typeString:bleUuidTypeVendorBegin],handleRange:[startHandle:1 endHandle:3]]]
2019-02-12T15:20:27.088Z DEBUG  2233/ 0 <-  [02 30 00 00 00 00 00 00 00 01 00 e1 69 02 01 00 03 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:6 ack#:5 payload_length:12 data_integrity:1 header_checksum:e3 err_code:0
2019-02-12T15:20:27.088Z DEBUG     2235 ->  []
               type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.089Z DEBUG     2236 ->  [00 94 00 00 04 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:5 ack#:7 payload_length:7 data_integrity:1 header_checksum:85 err_code:0
2019-02-12T15:20:27.089Z DEBUG  2234/ 0 <-  []
               type:                 ACK reliable: no seq#:0 ack#:6 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.089Z DEBUG  2235/ 0 <-  [01 94 00 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:7 ack#:6 payload_length:6 data_integrity:1 header_checksum:9b err_code:0
2019-02-12T15:20:27.089Z DEBUG     2237 ->  []
               type:                 ACK reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.102Z DEBUG  2236/ 0 <-  [02 30 00 00 00 00 00 00 00 01 00 00 18 01 04 00 08 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:6 payload_length:12 data_integrity:1 header_checksum:e1 err_code:0
2019-02-12T15:20:27.102Z DEBUG     2238 ->  []
               type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.103Z DEBUG GATTC_EVT_PRIM_SRVC_DISC_RSP time:2019-02-12T15:20:27.101Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 count:1 services:[[uuid:[uuid:6144 type:1 typeString:bleUuidTypeBle],handleRange:[startHandle:4 endHandle:8]]]
2019-02-12T15:20:27.103Z DEBUG     2239 ->  [00 94 00 00 09 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:6 ack#:1 payload_length:7 data_integrity:1 header_checksum:b4 err_code:0
2019-02-12T15:20:27.104Z DEBUG  2237/ 0 <-  []
               type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.104Z DEBUG  2238/ 0 <-  [01 94 00 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:1 ack#:7 payload_length:6 data_integrity:1 header_checksum:99 err_code:0
2019-02-12T15:20:27.104Z DEBUG     2240 ->  []
               type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.116Z DEBUG  2239/ 0 <-  [02 30 00 00 00 0a 01 09 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:2 ack#:7 payload_length:b data_integrity:1 header_checksum:48 err_code:0
2019-02-12T15:20:27.117Z DEBUG     2241 ->  []
               type:                 ACK reliable: no seq#:0 ack#:3 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.117Z DEBUG GATTC_EVT_PRIM_SRVC_DISC_RSP time:2019-02-12T15:20:27.116Z connHandle:0 gattStatus:266 gattStatusName:atterrAttributeNotFound errorHandle:9 count:0 services:[]
2019-02-12T15:20:27.123Z DEBUG     2242 ->  [00 96 00 00 01 04 00 08 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:7 ack#:3 payload_length:9 data_integrity:1 header_checksum:83 err_code:0
2019-02-12T15:20:27.123Z DEBUG  2240/ 0 <-  []
               type:                 ACK reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.124Z DEBUG  2241/ 0 <-  [01 96 00 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:0 payload_length:6 data_integrity:1 header_checksum:cf err_code:0
2019-02-12T15:20:27.124Z DEBUG     2243 ->  []
               type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.141Z DEBUG GATTC_EVT_CHAR_DISC_RSP time:2019-02-12T15:20:27.139Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 count:2 chars:[[uuid:[uuid:10752 type:1 typeString:bleUuidTypeBle],charProps:[broadcast:false read:true writeWoResp:false write:false notify:false indicate:false authSignedWr:false],charExtProps:0,handleDecl:5,handleValue:6],[uuid:[uuid:10753 type:1 typeString:bleUuidTypeBle],charProps:[broadcast:false read:true writeWoResp:false write:false notify:false indicate:false authSignedWr:false],charExtProps:0,handleDecl:7,handleValue:8]]
2019-02-12T15:20:27.141Z DEBUG  2242/ 0 <-  [02 32 00 00 00 00 00 00 00 02 00 00 2a 01 02 00 05 00 06 00 01 2a 01 02 00 07 00 08 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:4 ack#:0 payload_length:1d data_integrity:1 header_checksum:5d err_code:0
2019-02-12T15:20:27.141Z DEBUG     2244 ->  []
               type:                 ACK reliable: no seq#:0 ack#:5 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.141Z DEBUG     2245 ->  [00 9a 00 00 06 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:5 payload_length:8 data_integrity:1 header_checksum:8a err_code:0
2019-02-12T15:20:27.142Z DEBUG  2243/ 0 <-  []
               type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.142Z DEBUG  2244/ 0 <-  [01 9a 00 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:5 ack#:1 payload_length:6 data_integrity:1 header_checksum:c5 err_code:0
2019-02-12T15:20:27.142Z DEBUG     2246 ->  []
               type:                 ACK reliable: no seq#:0 ack#:6 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.154Z DEBUG GATTC_EVT_READ_RSP time:2019-02-12T15:20:27.154Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:6 offset:0 len:14
2019-02-12T15:20:27.155Z DEBUG  2245/ 0 <-  [02 36 00 00 00 00 00 00 00 06 00 00 00 0e 00 42 6c 75 65 74 6f 65 2d 53 65 72 76 65 72 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:6 ack#:1 payload_length:1d data_integrity:1 header_checksum:53 err_code:0
2019-02-12T15:20:27.155Z DEBUG     2247 ->  []
               type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.155Z DEBUG     2248 ->  [00 9a 00 00 08 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:1 ack#:7 payload_length:8 data_integrity:1 header_checksum:79 err_code:0
2019-02-12T15:20:27.155Z DEBUG  2246/ 0 <-  []
               type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.155Z DEBUG  2247/ 0 <-  [01 9a 00 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:7 ack#:2 payload_length:6 data_integrity:1 header_checksum:bb err_code:0
2019-02-12T15:20:27.155Z DEBUG     2249 ->  []
               type:                 ACK reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.169Z DEBUG GATTC_EVT_READ_RSP time:2019-02-12T15:20:27.169Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:8 offset:0 len:2
2019-02-12T15:20:27.175Z DEBUG  2248/ 0 <-  [02 36 00 00 00 00 00 00 00 08 00 00 00 02 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:2 payload_length:11 data_integrity:1 header_checksum:11 err_code:0
2019-02-12T15:20:27.175Z DEBUG     2250 ->  []
               type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.175Z DEBUG     2251 ->  [00 97 00 00 01 07 00 08 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:2 ack#:1 payload_length:9 data_integrity:1 header_checksum:98 err_code:0
2019-02-12T15:20:27.176Z DEBUG  2249/ 0 <-  []
               type:                 ACK reliable: no seq#:0 ack#:3 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.176Z DEBUG  2250/ 0 <-  [01 97 00 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:1 ack#:3 payload_length:6 data_integrity:1 header_checksum:b9 err_code:0
2019-02-12T15:20:27.176Z DEBUG     2252 ->  []
               type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.194Z DEBUG GATTC_EVT_DESC_DISC_RSP time:2019-02-12T15:20:27.191Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 count:2 descs:[[handle:7,uuid:[uuid:10243 type:1 typeString:bleUuidTypeBle]],[handle:8,uuid:[uuid:10753 type:1 typeString:bleUuidTypeBle]]]
2019-02-12T15:20:27.197Z DEBUG  2251/ 0 <-  [02 33 00 00 00 00 00 00 00 02 00 07 00 03 28 01 08 00 01 2a 01 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:2 ack#:3 payload_length:15 data_integrity:1 header_checksum:c7 err_code:0
2019-02-12T15:20:27.198Z DEBUG     2253 ->  []
               type:                 ACK reliable: no seq#:0 ack#:3 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.198Z DEBUG     2254 ->  [00 97 00 00 01 09 00 08 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:3 payload_length:9 data_integrity:1 header_checksum:87 err_code:0
2019-02-12T15:20:27.198Z DEBUG  2252/ 0 <-  []
               type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.198Z DEBUG  2253/ 0 <-  [01 97 00 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:4 payload_length:6 data_integrity:1 header_checksum:af err_code:0
2019-02-12T15:20:27.198Z DEBUG     2255 ->  []
               type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.223Z DEBUG GATTC_EVT_DESC_DISC_RSP time:2019-02-12T15:20:27.222Z connHandle:0 gattStatus:257 gattStatusName:atterrInvalidHandle errorHandle:9 count:0 descs:[]
2019-02-12T15:20:27.226Z DEBUG  2254/ 0 <-  [02 33 00 00 00 01 01 09 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:4 ack#:4 payload_length:b data_integrity:1 header_checksum:5e err_code:0
2019-02-12T15:20:27.226Z DEBUG     2256 ->  []
               type:                 ACK reliable: no seq#:0 ack#:5 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.226Z DEBUG     2257 ->  [00 9a 00 00 06 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:4 ack#:5 payload_length:8 data_integrity:1 header_checksum:86 err_code:0
2019-02-12T15:20:27.226Z DEBUG  2255/ 0 <-  []
               type:                 ACK reliable: no seq#:0 ack#:5 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.226Z DEBUG  2256/ 0 <-  [01 9a 00 00 00 00 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:5 ack#:5 payload_length:6 data_integrity:1 header_checksum:a5 err_code:0
2019-02-12T15:20:27.226Z DEBUG     2258 ->  []
               type:                 ACK reliable: no seq#:0 ack#:6 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.237Z DEBUG  2257/ 0 <-  [02 36 00 00 00 00 00 00 00 06 00 00 00 0e 00 42 6c 75 65 74 6f 65 2d 53 65 72 76 65 72 ]
               type:     VENDOR_SPECIFIC reliable:yes seq#:6 ack#:5 payload_length:1d data_integrity:1 header_checksum:33 err_code:0
2019-02-12T15:20:27.237Z DEBUG     2259 ->  []
               type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0
2019-02-12T15:20:27.238Z DEBUG GATTC_EVT_READ_RSP time:2019-02-12T15:20:27.237Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:6 offset:0 len:14
2019-02-12T15:20:27.238Z INFO Attribute value read, handle: 0x06, value (0x): 42-6C-75-65-74-6F-65-2D-53-65-72-76-65-72

5658.2.pcapng

[Marjeris Romero]

Hi,

Sorry for the late reply. I have given the feedback to our developers. Will keep you updated.

Best Regards,

Marjeris