Hi,
I'm using nrf52840 as matter bulb and I'm using raspberry Pi 4 and nrf52840 dongle as OTBR, when i try to connect chip-tool mobile than i get pairing issue.
: 527 [DL]CHIPoBLE advertising started I: 531 [ZCL]Cluster OnOff: attribute OnOff set to 0 I: 536 [ZCL]Cluster LevelControl: attribute CurrentLevel set to 254 I: 543 [DL]NFC Tag emulation started I: 30531 [DL]CHIPoBLE advertising mode changed to slow I: 30538 [DL]CHIPoBLE advertising started I: 49611 [DL]BLE connection established (ConnId: 0x00) I: 49616 [DL]Current number of connections: 1/1 I: 49621 [DL]CHIPoBLE advertising stopped I: 49624 [DL]NFC Tag emulation stopped D: 50437 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 50445 [BLE]local and remote recv window sizes = 5 I: 50449 [BLE]selected BTP version 4 I: 50453 [BLE]using BTP fragment sizes rx 244 / tx 244. D: 50492 [DL]ConnId: 0x00, New CCCD value: 0x0002 D: 50497 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 6) D: 50504 [IN]BLE EndPoint 0x2000a728 Connection Complete I: 50509 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 247) D: 50605 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 50680 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 50688 [EM]>>> [E:64087r S:0 M:116645379] (U) Msg RX from 0:F8B5377FD9DE9B1C [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) I: 50701 [EM]<<< [E:64087r S:0 M:49533507] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) I: 50713 [IN](U) Sending msg 49533507 to IP address 'BLE' D: 50719 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 140) D: 50830 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 50868 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 50875 [EM]>>> [E:64087r S:0 M:116645380] (U) Msg RX from 0:F8B5377FD9DE9B1C [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1) I: 52605 [EM]<<< [E:64087r S:0 M:49533508] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) I: 52616 [IN](U) Sending msg 49533508 to IP address 'BLE' D: 52621 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 132) E: 52629 [DL]Long dispatch time: 1761 ms, for event type 16388 D: 52712 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 52758 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 52765 [EM]>>> [E:64087r S:0 M:116645381] (U) Msg RX from 0:F8B5377FD9DE9B1C [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3) I: 52777 [EM]<<< [E:64087r S:0 M:49533509] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I: 52789 [IN](U) Sending msg 49533509 to IP address 'BLE' D: 52794 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 35) I: 52803 [SC]SecureSession[0x20003320]: Moving from state 'kEstablishing' --> 'kActive' D: 52811 [IN]SecureSession[0x20003320]: Activated - Type:1 LSID:54407 D: 52817 [IN]New secure session activated for device <FFFFFFFB00000000, 0>, LSID:54407 PSID:52488! I: 52826 [SVR]Commissioning completed session establishment step I: 52832 [DIS]Updating services using commissioning mode 0 E: 52838 [DIS]Failed to remove advertised services: 3 D: 52842 [DL]Using Thread extended MAC for hostname. I: 52849 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 E: 52858 [DIS]Failed to advertise extended commissionable node: 3 D: 52864 [DIS]Scheduling extended discovery timeout in 900s E: 52869 [DIS]Failed to finalize service update: 3 D: 52874 [DL]CHIPoBLE advertising set to off I: 52878 [SVR]Device completed Rendezvous process E: 52883 [DL]Long dispatch time: 124 ms, for event type 16388 D: 52888 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 52896 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 52904 [EM]>>> [E:64088r S:54407 M:151427545] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) D: 52915 [IM]Received Read request I: 52925 [EM]<<< [E:64088r S:54407 M:187674411] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I: 52935 [IN](S) Sending msg 187674411 on secure session with LSID: 54407 D: 52942 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 53027 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 53035 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 21) D: 53118 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 53126 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 53134 [EM]>>> [E:64089r S:54407 M:151427546] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 53146 [FS]GeneralCommissioning: Received ArmFailSafe (30s) I: 53153 [EM]<<< [E:64089r S:54407 M:187674412] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 53165 [IN](S) Sending msg 187674412 on secure session with LSID: 54407 D: 53171 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 53253 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 53261 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 53269 [EM]>>> [E:64090r S:54407 M:151427547] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 53283 [EM]<<< [E:64090r S:54407 M:187674413] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 53294 [IN](S) Sending msg 187674413 on secure session with LSID: 54407 D: 53301 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 53388 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 53396 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 53404 [EM]>>> [E:64091r S:54407 M:151427548] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 53416 [ZCL]OpCreds: Certificate Chain request received for PAI I: 53425 [EM]<<< [E:64091r S:54407 M:187674414] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 53437 [IN](S) Sending msg 187674414 on secure session with LSID: 54407 D: 53444 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 53522 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 53530 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 53612 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 53620 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 48) D: 53703 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 53711 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 53719 [EM]>>> [E:64092r S:54407 M:151427549] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 53731 [ZCL]OpCreds: Certificate Chain request received for DAC I: 53740 [EM]<<< [E:64092r S:54407 M:187674415] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 53752 [IN](S) Sending msg 187674415 on secure session with LSID: 54407 D: 53759 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 53837 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 53845 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 53927 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 53935 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 77) D: 54017 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 54026 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 54034 [EM]>>> [E:64093r S:54407 M:151427550] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 54046 [ZCL]OpCreds: Received an AttestationRequest command I: 54086 [ZCL]OpCreds: AttestationRequest successful. I: 54095 [EM]<<< [E:64093r S:54407 M:187674416] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 54106 [IN](S) Sending msg 187674416 on secure session with LSID: 54407 D: 54113 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 54197 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 54205 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) D: 54287 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 54295 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 235) D: 54377 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 54468 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 54476 [EM]>>> [E:64094r S:54407 M:151427551] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 54488 [ZCL]OpCreds: Received a CSRRequest command I: 54669 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded I: 54709 [ZCL]OpCreds: CSRRequest successful. I: 54715 [EM]<<< [E:64094r S:54407 M:187674417] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 54727 [IN](S) Sending msg 187674417 on secure session with LSID: 54407 D: 54734 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 244) E: 54741 [DL]Long dispatch time: 273 ms, for event type 16388 D: 54827 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 54835 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 138) D: 54917 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 55008 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 55098 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 55107 [EM]>>> [E:64095r S:54407 M:151427552] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 55119 [ZCL]OpCreds: Received an AddTrustedRootCertificate command I: 55190 [ZCL]OpCreds: AddTrustedRootCertificate successful. I: 55196 [EM]<<< [E:64095r S:54407 M:187674418] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I: 55208 [IN](S) Sending msg 187674418 on secure session with LSID: 54407 D: 55214 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 68) E: 55222 [DL]Long dispatch time: 124 ms, for event type 16388 D: 55277 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 55286 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 55368 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 55377 [EM]>>> [E:64096r S:54407 M:151427553] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I: 55390 [ZCL]OpCreds: Received an AddNOC command I: 55398 [FP]Validating NOC chain I: 55466 [FP]NOC chain validation successful I: 55470 [FP]Added new fabric at index: 0x1 I: 55474 [FP]Assigned compressed fabric ID: 0x1C7CAC8170E013F3, node ID: 0x0000000000000009 I: 55482 [TS]Last Known Good Time: 2023-06-05T10:38:53 I: 55487 [TS]New proposed Last Known Good Time: 2021-06-10T00:00:00 I: 55493 [TS]Retaining current Last Known Good Time I: 55636 [ZCL]OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x000000000001B669 D: 55645 [DL]Using Thread extended MAC for hostname. I: 55650 [DIS]Advertise operational node 1C7CAC8170E013F3-0000000000000009 I: 55657 [ZCL]OpCreds: successfully created fabric index 0x1 via AddNOC I: 55664 [EM]<<< [E:64096r S:54407 M:187674419] (S) Msg TX to 1:FFFFFFFB00000000 [13F3] --- Type 0001:09 (IM:InvokeCommandResponse) I: 55676 [IN](S) Sending msg 187674419 on secure session with LSID: 54407 D: 55682 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) E: 55690 [DL]Long dispatch time: 322 ms, for event type 16388 D: 55772 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 55818 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 55826 [EM]>>> [E:64097r S:54407 M:151427554] (S) Msg RX from 1:FFFFFFFB00000000 [13F3] --- Type 0001:08 (IM:InvokeCommandRequest) I: 55844 [EM]<<< [E:64097r S:54407 M:187674420] (S) Msg TX to 1:FFFFFFFB00000000 [13F3] --- Type 0001:09 (IM:InvokeCommandResponse) I: 55856 [IN](S) Sending msg 187674420 on secure session with LSID: 54407 D: 55863 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 71) D: 55953 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 55961 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 55969 [EM]>>> [E:64098r S:54407 M:151427555] (S) Msg RX from 1:FFFFFFFB00000000 [13F3] --- Type 0001:08 (IM:InvokeCommandRequest) D: 56014 [DL]OpenThread State Changed (Flags: 0x1006c100) D: 56019 [DL] Network Name: OpenThread D: 56023 [DL] PAN Id: 0x1234 D: 56026 [DL] Extended PAN Id: 0x1111111122222222 D: 56031 [DL] Channel: 15 D: 56034 [DL] Mesh Prefix: fdde:ad00:beef::/64 D: 56040 [DL]OpenThread State Changed (Flags: 0x0100103d) D: 56045 [DL] Device Role: DETACHED D: 56049 [DL] Thread Unicast Addresses: D: 56053 [DL] fdde:ad00:beef::ff:fe00:7405/64 valid rloc D: 56059 [DL] fdde:ad00:beef:0:5d90:4464:a3ee:9016/64 valid D: 56065 [DL] fe80::fc34:1502:b36a:c157/64 valid preferred D: 58468 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 58563 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 60990 [DL]OpenThread State Changed (Flags: 0x10000040) D: 61082 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 63590 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 63692 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 66212 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 68719 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 68822 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 71342 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 73849 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 73952 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 76472 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) D: 78980 [DL]Sending indication for CHIPoBLE TX characteristic (ConnId 00, len 3) D: 79127 [DL]Indication for CHIPoBLE TX characteristic done (ConnId 0x00, result 0x00) D: 81647 [DL]Write request received for CHIPoBLE RX characteristic (ConnId 0x00) I: 83153 [FS]Fail-safe timer expired E: 83156 [SVR]Failsafe timer expired D: 83159 [IN]SecureSession[0x20003320]: MarkForEviction Type:1 LSID:54407 I: 83166 [SC]SecureSession[0x20003320]: Moving from state 'kActive' --> 'kPendingEviction' D: 83174 [IN]SecureSession[0x20003320]: Released - Type:1 LSID:54407 E: 83180 [SVR]Commissioning failed (attempt 1): 32 D: 83185 [IN]Clearing BLE pending packets. I: 83189 [BLE]Releasing end point's BLE connection back to application. I: 83195 [DL]Closing BLE GATT connection (ConnId 00) D: 83203 [IN]SecureSession[0x20003320]: Allocated Type:1 LSID:54409 D: 83209 [DL]CHIPoBLE advertising set to on I: 83213 [DIS]Updating services using commissioning mode 1 E: 83218 [DIS]Failed to remove advertised services: 3 D: 83224 [DL]Using Thread extended MAC for hostname. I: 83228 [DIS]Advertise operational node 1C7CAC8170E013F3-0000000000000009 E: 83235 [DIS]Failed to advertise operational node: 3 D: 83240 [DL]Using Thread extended MAC for hostname. I: 83246 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 E: 83255 [DIS]Failed to advertise commissionable node: 3 E: 83260 [DIS]Failed to finalize service update: 3 E: 83264 [ZCL]OpCreds: Got FailSafeTimerExpired E: 83269 [ZCL]OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry! D: 83275 [IN]Expiring all sessions for fabric 0x1!! E: 83280 [FP]Reverting pending fabric data for fabric 0x1 E: 83286 [FP]Warning: metadata not found during delete of fabric 0x1 I: 83346 [FP]Fabric (0x1) deleted. I: 83349 [ZCL]OpCreds: Fabric index 0x1 was removed I: 83354 [DIS]Updating services using commissioning mode 1 E: 83359 [DIS]Failed to remove advertised services: 3 D: 83364 [DL]Using Thread extended MAC for hostname. I: 83370 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 E: 83379 [DIS]Failed to advertise commissionable node: 3 E: 83384 [DIS]Failed to finalize service update: 3 I: 83495 [TS]Pending Last Known Good Time: 2023-06-05T10:38:53 I: 83501 [TS]Previous Last Known Good Time: 2023-06-05T10:38:53 I: 83507 [TS]Reverted Last Known Good Time to previous value E: 83514 [FP]Warning: metadata not found during delete of fabric 0x1 E: 83523 [ZCL]OpCreds: failed to delete fabric at index 1: d8 I: 83529 [NP]Reverting Thread operational dataset E: 83542 [DMG]Failed to send command response: 3 E: 83547 [DL]Long dispatch time: 391 ms, for event type 32782 I: 83552 [FS]Fail-safe cleanly disarmed D: 83556 [DL]OpenThread State Changed (Flags: 0x11002006) D: 83561 [DL] Device Role: DISABLED D: 83564 [DL] Thread Unicast Addresses: D: 83569 [DL]OpenThread State Changed (Flags: 0x10000000) I: 83575 [DL]BLE GAP connection terminated (reason 0x16) I: 83581 [DL]Current number of connections: 0/1 I: 83587 [DL]CHIPoBLE advertising started I: 83592 [DL]NFC Tag emulation started I: 113591 [DL]CHIPoBLE advertising mode changed to slow I: 113599 [DL]CHIPoBLE advertising started
this are matter device logs.
