This post is older than 2 years and might not be relevant anymore
More Info: Consider searching for newer posts

pc-ble-driver-py: notifications and low connection interval seems to fail


Hi,

I'm developping some test suite for one of our ble products in development, and I'm encountering some unexpected behavior using pc-ble-driver-py.

The test I perfom is the following :

- I use pc-ble-driver-py with a pc10040 devkit board. This is used, as a Central, to test my device.
- My devkit and my device pairs. During pairing, I set the connection interval to the minimum (see log below)

DEBUG    pc_ble_driver_py.observers:observers.py:339 evt> conn_param_update_request conn(0)
 conn_params(
  min_conn_interval_ms(7.5)
  max_conn_interval_ms(7.5)
  slave_latency(0))

- My devkit subscribe to notifications from my device under test, and awaits for data.
- My device continuously send notifications with some data
- My devkit seem to get only the first datas, then nothing seem to disconnect (crash ?), for an unknown reason.

Here is some log from the devkit : it get the last data then I have this Target reset I do not understand.

INFO     root:BLECentral.py:283 Value received through notification on characteristic: 0x4E94 is [57, 3]
DEBUG    pc_ble_driver_py.observers:observers.py:316 evt> status code(RpcAppStatus.resetPerformed) message(Target Reset performed)
DEBUG    pc_ble_driver_py.ble_adapter:ble_adapter.py:721 RpcAppStatus.resetPerformed: Target Reset performed
DEBUG    pc_ble_driver_py.observers:observers.py:316 evt> status code(RpcAppStatus.connectionActive) message(Connection active)
DEBUG    pc_ble_driver_py.ble_adapter:ble_adapter.py:721 RpcAppStatus.connectionActive: Connection active
INFO     root:SnifferCollector.py:190 Packet read timed out.



Note : when setting a larger connection interval, things seems to works better.
Any Clue / Tip on how I could procede to understand what happens ?

Note 2:

I tried to use nRFConnect, but it have the same behavior:

2020-04-30T13:58:14.744Z DEBUG GAP_EVT_CONN_SEC_UPDATE time:2020-04-30T13:58:14.744Z connHandle:0 connSec:[secMode:[sm:1 lv:3] encrKeySize:16]
2020-04-30T13:58:14.745Z INFO Security updated, mode:1, level:3 
2020-04-30T13:58:14.779Z DEBUG   116/ 0 <-  [02 19 00 00 00 00 04 07 00 01 01 01 8f 6e 39 68 73 2d 68 1f b8 ee 40 31 af b6 6a be 42 3b fb 6f 10 a9 16 8f 43 80 a3 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 ae 67 08 aa 97 d6 19 b0 89 66 8d d9 76 03 eb b5 96 72 b6 72 cc ba 4a 48 0a a5 03 a1 af 24 0e a1 c3 fc 05 f2 16 a9 46 ec 9c 6c e6 22 a6 35 c3 f5 82 d8 3e 33 bf b8 a8 12 9f 0b 09 2b 55 42 db 4a 01 34 2a 0a ff ef fc 87 6b b5 26 74 4a c6 f7 65 79 42 34 c8 c4 ae fc fe 2e 56 61 f7 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:6 ack#:0 payload_length:117 data_integrity:1 header_checksum:ab err_code:0x0
2020-04-30T13:58:14.779Z DEBUG      119 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:14.784Z DEBUG GAP_EVT_AUTH_STATUS time:2020-04-30T13:58:14.779Z connHandle:0 authStatus:0 authStatusName:bleGapSecStatusSuccess errorSrc:0 errorSrcName:bleGapSecStatusSourceLocal bonded:true sm1Levels:[lv1:true lv2:true lv3:true lv4:false] sm2Levels:[lv1:false lv2:false lv3:false lv4:false] kdistOwn:[enc:true sign:false link:false] kdistPeer:[enc:true sign:false link:false] keyset:[keysOwn:[encKey:[encInfo:[ltk:[[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]] auth:true ltkLen:16 lesc:false] masterId:[ediv:64315 rand:[[],[],[],[],[],[],[],[]]]] idKey:[idInfo:[irk:[[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]]] idAddrInfo:[address:00:00:00:00:00:00 type:public addrIdPeer:0]] signKey:[csrk:[[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]]] pk:[pk:[[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]]]] keysPeer:[encKey:[encInfo:[ltk:[[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]] auth:true ltkLen:16 lesc:false] masterId:[ediv:51252 rand:[[],[],[],[],[],[],[],[]]]] idKey:[idInfo:[irk:[[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]]] idAddrInfo:[address:00:00:00:00:00:00 type:public addrIdPeer:0]] signKey:[csrk:[[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]]] pk:[pk:[[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]]]]]
2020-04-30T13:58:14.789Z INFO Storing bond info for device DF:3F:F5:CF:6D:85
2020-04-30T13:58:14.789Z DEBUG Bond info: {}
2020-04-30T13:58:21.638Z DEBUG      120 ->  [00 96 00 00 01 0b 00 ff ff ] type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:7 payload_length:9 data_integrity:1 header_checksum:6a err_code:0x0
2020-04-30T13:58:21.667Z DEBUG   117/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.667Z DEBUG   118/ 0 <-  [01 96 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:7 ack#:1 payload_length:6 data_integrity:1 header_checksum:c3 err_code:0x0
2020-04-30T13:58:21.667Z DEBUG      121 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.700Z DEBUG   119/ 0 <-  [02 32 00 00 00 00 00 00 00 02 00 00 00 00 08 00 0c 00 0d 00 00 00 00 12 00 0e 00 0f 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:1 payload_length:1d data_integrity:1 header_checksum:59 err_code:0x0
2020-04-30T13:58:21.700Z DEBUG      122 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.702Z DEBUG GATTC_EVT_CHAR_DISC_RSP time:2020-04-30T13:58:21.700Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 count:2 chars:[[uuid:[uuid:0 type:0 typeString:bleUuidTypeUnknown],charProps:[broadcast:false read:false writeWoResp:false write:true notify:false indicate:false authSignedWr:false],charExtProps:0,handleDecl:12,handleValue:13],[uuid:[uuid:0 type:0 typeString:bleUuidTypeUnknown],charProps:[broadcast:false read:true writeWoResp:false write:false notify:true indicate:false authSignedWr:false],charExtProps:0,handleDecl:14,handleValue:15]]
2020-04-30T13:58:21.702Z DEBUG      123 ->  [00 96 00 00 01 0f 00 ff ff ] type:     VENDOR_SPECIFIC reliable:yes seq#:1 ack#:1 payload_length:9 data_integrity:1 header_checksum:99 err_code:0x0
2020-04-30T13:58:21.734Z DEBUG   120/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.734Z DEBUG   121/ 0 <-  [01 96 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-04-30T13:58:21.734Z DEBUG      124 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.767Z DEBUG   122/ 0 <-  [02 32 00 00 00 0a 01 0f 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:2 ack#:2 payload_length:b data_integrity:1 header_checksum:70 err_code:0x0
2020-04-30T13:58:21.767Z DEBUG      125 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:3 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.767Z DEBUG GATTC_EVT_CHAR_DISC_RSP time:2020-04-30T13:58:21.767Z connHandle:0 gattStatus:266 gattStatusName:atterrAttributeNotFound errorHandle:15 count:0 chars:[]
2020-04-30T13:58:21.767Z DEBUG      126 ->  [00 9a 00 00 0c 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:2 ack#:3 payload_length:8 data_integrity:1 header_checksum:98 err_code:0x0
2020-04-30T13:58:21.800Z DEBUG   123/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:3 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.800Z DEBUG   124/ 0 <-  [01 9a 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:3 payload_length:6 data_integrity:1 header_checksum:b7 err_code:0x0
2020-04-30T13:58:21.800Z DEBUG      127 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.833Z DEBUG   125/ 0 <-  [02 36 00 00 00 00 00 00 00 0c 00 00 00 13 00 08 0d 00 b2 f3 d1 5c 94 13 07 bb 49 45 03 ca 92 4e 2c e7 ] type:     VENDOR_SPECIFIC reliable:yes seq#:4 ack#:3 payload_length:22 data_integrity:1 header_checksum:f4 err_code:0x0
2020-04-30T13:58:21.833Z DEBUG      128 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:5 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.834Z DEBUG GATTC_EVT_READ_RSP time:2020-04-30T13:58:21.833Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:12 offset:0 len:19
2020-04-30T13:58:21.834Z DEBUG      129 ->  [00 9a 00 00 0e 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:5 payload_length:8 data_integrity:1 header_checksum:87 err_code:0x0
2020-04-30T13:58:21.867Z DEBUG   126/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.867Z DEBUG   127/ 0 <-  [01 9a 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:5 ack#:4 payload_length:6 data_integrity:1 header_checksum:ad err_code:0x0
2020-04-30T13:58:21.867Z DEBUG      130 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:6 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.899Z DEBUG   128/ 0 <-  [02 36 00 00 00 00 00 00 00 0e 00 00 00 13 00 12 0f 00 b2 f3 d1 5c 94 13 07 bb 49 45 03 ca 94 4e 2c e7 ] type:     VENDOR_SPECIFIC reliable:yes seq#:6 ack#:4 payload_length:22 data_integrity:1 header_checksum:ea err_code:0x0
2020-04-30T13:58:21.900Z DEBUG      131 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.900Z DEBUG GATTC_EVT_READ_RSP time:2020-04-30T13:58:21.899Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:14 offset:0 len:19
2020-04-30T13:58:21.901Z DEBUG      132 ->  [00 9a 00 00 0f 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:4 ack#:7 payload_length:8 data_integrity:1 header_checksum:76 err_code:0x0
2020-04-30T13:58:21.933Z DEBUG   129/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:5 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.933Z DEBUG   130/ 0 <-  [01 9a 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:7 ack#:5 payload_length:6 data_integrity:1 header_checksum:a3 err_code:0x0
2020-04-30T13:58:21.933Z DEBUG      133 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.965Z DEBUG   131/ 0 <-  [02 36 00 00 00 00 00 00 00 0f 00 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:5 payload_length:f data_integrity:1 header_checksum:1a err_code:0x0
2020-04-30T13:58:21.965Z DEBUG      134 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:21.966Z DEBUG GATTC_EVT_READ_RSP time:2020-04-30T13:58:21.965Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:15 offset:0 len:0
2020-04-30T13:58:21.985Z DEBUG      135 ->  [00 97 00 00 01 0e 00 ff ff ] type:     VENDOR_SPECIFIC reliable:yes seq#:5 ack#:1 payload_length:9 data_integrity:1 header_checksum:95 err_code:0x0
2020-04-30T13:58:22.018Z DEBUG   132/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:6 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.018Z DEBUG   133/ 0 <-  [01 97 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:1 ack#:6 payload_length:6 data_integrity:1 header_checksum:a1 err_code:0x0
2020-04-30T13:58:22.018Z DEBUG      136 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.051Z DEBUG   134/ 0 <-  [02 33 00 00 00 00 00 00 00 01 00 0e 00 03 28 01 ] type:     VENDOR_SPECIFIC reliable:yes seq#:2 ack#:6 payload_length:10 data_integrity:1 header_checksum:ff err_code:0x0
2020-04-30T13:58:22.051Z DEBUG      137 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:3 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.051Z DEBUG GATTC_EVT_DESC_DISC_RSP time:2020-04-30T13:58:22.051Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 count:1 descs:[[handle:14,uuid:[uuid:10243 type:1 typeString:bleUuidTypeBle]]]
2020-04-30T13:58:22.059Z DEBUG      138 ->  [00 97 00 00 01 10 00 ff ff ] type:     VENDOR_SPECIFIC reliable:yes seq#:6 ack#:3 payload_length:9 data_integrity:1 header_checksum:84 err_code:0x0
2020-04-30T13:58:22.090Z DEBUG   135/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.090Z DEBUG   136/ 0 <-  [01 97 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:7 payload_length:6 data_integrity:1 header_checksum:97 err_code:0x0
2020-04-30T13:58:22.090Z DEBUG      139 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.122Z DEBUG   137/ 0 <-  [02 33 00 00 00 00 00 00 00 01 00 10 00 02 29 01 ] type:     VENDOR_SPECIFIC reliable:yes seq#:4 ack#:7 payload_length:10 data_integrity:1 header_checksum:f5 err_code:0x0
2020-04-30T13:58:22.122Z DEBUG      140 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:5 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.123Z DEBUG GATTC_EVT_DESC_DISC_RSP time:2020-04-30T13:58:22.122Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 count:1 descs:[[handle:16,uuid:[uuid:10498 type:1 typeString:bleUuidTypeBle]]]
2020-04-30T13:58:22.123Z DEBUG      141 ->  [00 97 00 00 01 11 00 ff ff ] type:     VENDOR_SPECIFIC reliable:yes seq#:7 ack#:5 payload_length:9 data_integrity:1 header_checksum:73 err_code:0x0
2020-04-30T13:58:22.157Z DEBUG   138/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.157Z DEBUG   139/ 0 <-  [01 97 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:5 ack#:0 payload_length:6 data_integrity:1 header_checksum:cd err_code:0x0
2020-04-30T13:58:22.157Z DEBUG      142 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:6 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.189Z DEBUG   140/ 0 <-  [02 33 00 00 00 0a 01 11 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:6 ack#:0 payload_length:b data_integrity:1 header_checksum:7c err_code:0x0
2020-04-30T13:58:22.189Z DEBUG      143 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.190Z DEBUG GATTC_EVT_DESC_DISC_RSP time:2020-04-30T13:58:22.189Z connHandle:0 gattStatus:266 gattStatusName:atterrAttributeNotFound errorHandle:17 count:0 descs:[]
2020-04-30T13:58:22.190Z DEBUG      144 ->  [00 9a 00 00 10 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:7 payload_length:8 data_integrity:1 header_checksum:7a err_code:0x0
2020-04-30T13:58:22.223Z DEBUG   141/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.223Z DEBUG   142/ 0 <-  [01 9a 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:7 ack#:1 payload_length:6 data_integrity:1 header_checksum:c3 err_code:0x0
2020-04-30T13:58:22.223Z DEBUG      145 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.255Z DEBUG   143/ 0 <-  [02 36 00 00 00 00 00 00 00 10 00 00 00 02 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:1 payload_length:11 data_integrity:1 header_checksum:19 err_code:0x0
2020-04-30T13:58:22.255Z DEBUG      146 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:22.256Z DEBUG GATTC_EVT_READ_RSP time:2020-04-30T13:58:22.255Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:16 offset:0 len:2
2020-04-30T13:58:31.090Z DEBUG      147 ->  [00 9c 00 00 01 01 00 10 00 00 00 02 00 01 01 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:1 ack#:1 payload_length:10 data_integrity:1 header_checksum:28 err_code:0x0
2020-04-30T13:58:31.122Z DEBUG   144/ 0 <-  [N/A] type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:31.122Z DEBUG   145/ 0 <-  [01 9c 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-04-30T13:58:31.122Z DEBUG      148 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:31.154Z DEBUG   146/ 0 <-  [02 38 00 00 00 00 00 00 00 10 00 01 00 00 00 00 ] type:     VENDOR_SPECIFIC reliable:yes seq#:2 ack#:2 payload_length:10 data_integrity:1 header_checksum:1f err_code:0x0
2020-04-30T13:58:31.154Z DEBUG      149 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:3 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:58:31.155Z DEBUG GATTC_EVT_WRITE_RSP time:2020-04-30T13:58:31.154Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:16 writeOp:1 offset:0 len:0
2020-04-30T13:58:31.155Z INFO Attribute value changed, handle: 0x10, value (0x): 01-00
2020-04-30T13:58:31.158Z INFO Attribute value written, handle: 0x10, value (0x): 01-00
2020-04-30T13:59:20.730Z DEBUG   147/ 0 <-  [02 39 00 00 00 00 00 00 00 0f 00 01 02 00 30 03 ] type:     VENDOR_SPECIFIC reliable:yes seq#:3 ack#:2 payload_length:10 data_integrity:1 header_checksum:1e err_code:0x0
2020-04-30T13:59:20.730Z DEBUG      150 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:4 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:59:20.730Z DEBUG GATTC_EVT_HVX time:2020-04-30T13:59:20.730Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:15 type:1 len:2
2020-04-30T13:59:20.731Z INFO Attribute value changed, handle: 0x0F, value (0x): 30-03
2020-04-30T13:59:20.762Z DEBUG   148/ 0 <-  [02 39 00 00 00 00 00 00 00 0f 00 01 02 00 31 03 ] type:     VENDOR_SPECIFIC reliable:yes seq#:4 ack#:2 payload_length:10 data_integrity:1 header_checksum:1d err_code:0x0
2020-04-30T13:59:20.762Z DEBUG      151 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:5 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:59:20.763Z DEBUG GATTC_EVT_HVX time:2020-04-30T13:59:20.762Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:15 type:1 len:2
2020-04-30T13:59:20.763Z INFO Attribute value changed, handle: 0x0F, value (0x): 31-03
2020-04-30T13:59:20.794Z DEBUG   149/ 0 <-  [02 39 00 00 00 00 00 00 00 0f 00 01 02 00 32 03 ] type:     VENDOR_SPECIFIC reliable:yes seq#:5 ack#:2 payload_length:10 data_integrity:1 header_checksum:1c err_code:0x0
2020-04-30T13:59:20.794Z DEBUG      152 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:6 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:59:20.795Z DEBUG GATTC_EVT_HVX time:2020-04-30T13:59:20.794Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:15 type:1 len:2
2020-04-30T13:59:20.795Z INFO Attribute value changed, handle: 0x0F, value (0x): 32-03
2020-04-30T13:59:20.826Z DEBUG   150/ 0 <-  [02 39 00 00 00 00 00 00 00 0f 00 01 02 00 33 03 ] type:     VENDOR_SPECIFIC reliable:yes seq#:6 ack#:2 payload_length:10 data_integrity:1 header_checksum:1b err_code:0x0
2020-04-30T13:59:20.826Z DEBUG      153 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:59:20.826Z DEBUG GATTC_EVT_HVX time:2020-04-30T13:59:20.826Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:15 type:1 len:2
2020-04-30T13:59:20.826Z INFO Attribute value changed, handle: 0x0F, value (0x): 33-03
2020-04-30T13:59:20.858Z DEBUG   151/ 0 <-  [02 39 00 00 00 00 00 00 00 0f 00 01 02 00 34 03 ] type:     VENDOR_SPECIFIC reliable:yes seq#:7 ack#:2 payload_length:10 data_integrity:1 header_checksum:1a err_code:0x0
2020-04-30T13:59:20.858Z DEBUG      154 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:59:20.859Z DEBUG GATTC_EVT_HVX time:2020-04-30T13:59:20.858Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:15 type:1 len:2
2020-04-30T13:59:20.859Z INFO Attribute value changed, handle: 0x0F, value (0x): 34-03
2020-04-30T13:59:20.890Z DEBUG   152/ 0 <-  [02 39 00 00 00 00 00 00 00 0f 00 01 02 00 35 03 ] type:     VENDOR_SPECIFIC reliable:yes seq#:0 ack#:2 payload_length:10 data_integrity:1 header_checksum:21 err_code:0x0
2020-04-30T13:59:20.890Z DEBUG      155 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:1 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:59:20.891Z DEBUG GATTC_EVT_HVX time:2020-04-30T13:59:20.890Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:15 type:1 len:2
2020-04-30T13:59:20.891Z INFO Attribute value changed, handle: 0x0F, value (0x): 35-03
2020-04-30T13:59:20.922Z DEBUG   153/ 0 <-  [02 39 00 00 00 00 00 00 00 0f 00 01 02 00 36 03 ] type:     VENDOR_SPECIFIC reliable:yes seq#:1 ack#:2 payload_length:10 data_integrity:1 header_checksum:20 err_code:0x0
2020-04-30T13:59:20.922Z DEBUG      156 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:2 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:59:20.922Z DEBUG GATTC_EVT_HVX time:2020-04-30T13:59:20.922Z connHandle:0 gattStatus:0 gattStatusName:success errorHandle:0 handle:15 type:1 len:2
2020-04-30T13:59:20.922Z INFO Attribute value changed, handle: 0x0F, value (0x): 36-03
2020-04-30T13:59:21.328Z DEBUG   154/ 0 <-  [01 7e ] type: LINK_CONTROL_PACKET reliable: no seq#:0 ack#:0 payload_length:2 data_integrity:0 err_code:0x0 [SYNC]
2020-04-30T13:59:21.328Z DEBUG State change: STATE_ACTIVE -> STATE_RESET
2020-04-30T13:59:21.328Z DEBUG      157 ->  [N/A] type:          RESERVED_5 reliable: no seq#:0 ack#:0 payload_length:0 data_integrity:0 err_code:0x0
2020-04-30T13:59:21.339Z INFO Reset performed on adapter COM8

All the best,

Parents
  • Hello,

    I suspect that your application is trying to print faster than it can be read out on the other side.

    Since you already have pc-ble-driver-py, can you search for the line "Packet read timed out" in your project? Does it appear anywhere?

    What is your peripheral? Is it an nRF DK running something? Does it have a log saying anything?

  • Thanks for your answer.

    Unfortunately, I didn't managed to reproduce the problem today, with a configuration that shall have been the same (maybe I changed the j-link emulator version, for another reason).


    I don't think I have any packet read timeout in my project, they came from the logger who uses nRFSniffer code to store some pcap data.

    My peripheral is a nRF52832, on a BL652 board.

    I'll let you know if I manage to reproduce the problem later, for now I think we can close this ticket ...

    All the best,

Reply
  • Thanks for your answer.

    Unfortunately, I didn't managed to reproduce the problem today, with a configuration that shall have been the same (maybe I changed the j-link emulator version, for another reason).


    I don't think I have any packet read timeout in my project, they came from the logger who uses nRFSniffer code to store some pcap data.

    My peripheral is a nRF52832, on a BL652 board.

    I'll let you know if I manage to reproduce the problem later, for now I think we can close this ticket ...

    All the best,

Children
No Data
Related