Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Debug log no longer shows key/pairing/boding information #158

Open
ikarus23 opened this issue May 11, 2020 · 0 comments
Open

Debug log no longer shows key/pairing/boding information #158

ikarus23 opened this issue May 11, 2020 · 0 comments

Comments

@ikarus23
Copy link

Important information like keys are no longer part of the log file. E.g. After bonding, my log is now:

2020-05-11T14:27:50.174Z INFO Security updated, mode:1, level:2 
2020-05-11T14:27:50.185Z DEBUG   169/ 0 <-  [02 1a 00 00 00 21 10 ] type:     VENDOR_SPECIFIC reliable:yes seq#:5 ack#:7 payload_length:7 data_integrity:1 header_checksum:85 err_code:0x0
2020-05-11T14:27:50.185Z DEBUG      173 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:6 payload_length:0 data_integrity:0 err_code:0x0
2020-05-11T14:27:50.198Z DEBUG   170/ 0 <-  [02 19 00 00 00 00 04 03 00 01 01 01 e2 cb dd 44 55 6f 19 38 8e ff 0b 79 58 93 c6 fc 40 1d ae 87 40 e1 57 30 ed 0c d2 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 c0 a5 80 02 96 02 83 d8 60 35 ed 82 b7 76 f2 ef 10 82 4d a1 cb 8a a7 ce c5 80 0c 5b 41 5e ed bf 0a 2a f1 ea 39 6a c2 e7 45 1f 4b 94 06 03 33 a9 00 4c 04 b7 86 e5 00 46 63 d3 4e e0 94 d1 1e eb 01 41 56 49 14 a2 81 28 66 31 26 7a d6 b9 ee 30 0d 40 5f 10 a8 7f 27 29 74 cd 14 39 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#:7 payload_length:117 data_integrity:1 header_checksum:73 err_code:0x0
2020-05-11T14:27:50.198Z DEBUG      174 ->  [N/A] type:                 ACK reliable: no seq#:0 ack#:7 payload_length:0 data_integrity:0 err_code:0x0
2020-05-11T14:27:50.206Z DEBUG GAP_EVT_AUTH_STATUS time:2020-05-11T14:27:50.197Z connHandle:0 authStatus:0 authStatusName:bleGapSecStatusSuccess errorSrc:0 errorSrcName:bleGapSecStatusSourceLocal bonded:true sm1Levels:[lv1:true lv2:true lv3:false 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:false ltkLen:16 lesc:false] masterId:[ediv:44573 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:false ltkLen:16 lesc:false] masterId:[ediv:4191 rand:[[],[],[],[],[],[],[],[]]]] idKey:[idInfo:[irk:[[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]]] idAddrInfo:[address:00:00:00:00:00:00 type:public addrIdPeer:0]] signKey:[csrk:[[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]]] pk:[pk:[[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[],[]]]]]
2020-05-11T14:27:50.213Z INFO Storing bond info for device 28:11:A5:78:06:F9
2020-05-11T14:27:50.213Z DEBUG Bond info: {}

Earlier, all information were in there. Unfortunately I don't have a old log. But it was something like this (taken from https://devzone.nordicsemi.com/f/nordic-q-a/47277/how-ble_gap_sec_keyset_t-keyset-is-handled-in-pc-nrfconnect-ble-with-pc-ble-driver-js)

2019-05-14T13:12:28.791Z DEBUG Bond info: [["CB:76:12:5C:09:9D",{"keys_own":{"enc_key":{"enc_info":{"ltk":[202,20,206,74,234,248,52,226,180,232,21,75,73,235,111,76],"auth":true,"ltk_len":16,"lesc":true},"master_id":{"ediv":0,"rand":[0,0,0,0,0,0,0,0]}},"id_key":{"id_info":{"irk":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0]},"id_addr_info":{"address":"00:00:00:00:00:00","type":"BLE_GAP_ADDR_TYPE_PUBLIC","addr_id_peer":0}},"sign_key":{"csrk":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0]},"pk":{"pk":[116,158,103,26,253,159,228,93,217,135,141,150,109,73,64,179,141,180,171,25,163,38,7,30,184,123,172,149,20,83,205,90,217,208,204,139,82,70,136,18,87,119,112,10,152,58,171,228,3,14,207,17,98,2,27,124,232,225,181,174,35,136,138,203]}},"keys_peer":{"enc_key":{"enc_info":{"ltk":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0],"auth":false,"ltk_len":0,"lesc":false},"master_id":{"ediv":0,"rand":[0,0,0,0,0,0,0,0]}},"id_key":{"id_info":{"irk":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0]},"id_addr_info":{"address":"00:00:00:00:00:00","type":"BLE_GAP_ADDR_TYPE_PUBLIC","addr_id_peer":0}},"sign_key":{"csrk":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0]},"pk":{"pk":[176,131,11,186,219,18,116,201,71,45,4,50,251,207,105,86,201,205,19,128,18,194,225,47,9,181,216,35,160,200,132,60,21,182,1,35,122,26,33,233,16,108,16,159,147,102,151,176,134,46,107,145,60,8,96,11,156,212,245,82,225,249,53,191]}}}]]

It is not specific to my system. Other newer logs out there in the internet show no key infos too (taken from https://devzone.nordicsemi.com/f/nordic-q-a/60880/pc-ble-driver-py-notifications-and-low-connection-interval-seems-to-fail)

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: {}
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

1 participant