As i was doing some sample example Light_bulb that is given in the nrf connect 1.0 but wile commissioning the light_bulb with chip-tool as controller facing issues
So. logs for the device is
D: 1025349 [IN]SecureSession[0x20003568]: Allocated Type:1 LSID:23439 I: 1025355 [DIS]Updating services using commissioning mode 1 I: 1025362 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 E: 1025372 [DIS]Failed to advertise commissionable node: 3 E: 1025377 [DIS]Failed to finalize service update: 1c I: 1025384 [DL]CHIPoBLE advertising started I: 1025389 [DL]NFC Tag emulation started I: 1029114 [DL]BLE connection established (ConnId: 0x00) I: 1029119 [DL]Current number of connections: 1/1 I: 1029123 [DL]CHIPoBLE advertising stopped I: 1029127 [DL]NFC Tag emulation stopped I: 1030907 [BLE]local and remote recv window sizes = 5 I: 1030912 [BLE]selected BTP version 4 I: 1030916 [BLE]using BTP fragment sizes rx 244 / tx 244. D: 1030998 [IN]BLE EndPoint 0x200124d8 Connection Complete I: 1031003 [DL]CHIPoBLE connection established (ConnId: 0x00, GATT MTU: 247) I: 1031089 [EM]>>> [E:28294r M:134199296] (U) Msg RX from 0:393CD4B3AB64E865 [0000] --- Type 0000:20 (Secu) I: 1031101 [EM]<<< [E:28294r M:156394311] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (Secure) I: 1031113 [IN](U) Sending msg 156394311 to IP address 'BLE' I: 1031179 [EM]>>> [E:28294r M:134199297] (U) Msg RX from 0:393CD4B3AB64E865 [0000] --- Type 0000:22 (Secu) I: 1033064 [EM]<<< [E:28294r M:156394312] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (Secure) I: 1033075 [IN](U) Sending msg 156394312 to IP address 'BLE' E: 1033081 [DL]Long dispatch time: 1903 ms, for event type 16388 I: 1033159 [EM]>>> [E:28294r M:134199298] (U) Msg RX from 0:393CD4B3AB64E865 [0000] --- Type 0000:24 (Secu) I: 1033170 [EM]<<< [E:28294r M:156394313] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (Secure) I: 1033182 [IN](U) Sending msg 156394313 to IP address 'BLE' I: 1033188 [SC]SecureSession[0x20003568]: Moving from state 'kEstablishing' --> 'kActive' D: 1033196 [IN]SecureSession[0x20003568]: Activated - Type:1 LSID:23439 D: 1033203 [IN]New secure session activated for device <FFFFFFFB00000000, 0>, LSID:23439 PSID:51082! I: 1033212 [SVR]Commissioning completed session establishment step I: 1033218 [DIS]Updating services using commissioning mode 0 I: 1033225 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 E: 1033234 [DIS]Failed to advertise extended commissionable node: 3 D: 1033240 [DIS]Scheduling extended discovery timeout in 900s E: 1033246 [DIS]Failed to finalize service update: 1c I: 1033252 [SVR]Device completed Rendezvous process I: 1033258 [EM]>>> [E:28295r M:120615681] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:R) D: 1033268 [IM]Received Read request I: 1033277 [EM]<<< [E:28295r M:84530903] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:Repo) I: 1033287 [IN](S) Sending msg 84530903 on secure session with LSID: 23439 I: 1033519 [EM]>>> [E:28296r M:120615682] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:I) I: 1033531 [FS]GeneralCommissioning: Received ArmFailSafe (60s) I: 1033538 [EM]<<< [E:28296r M:84530904] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:Invo) I: 1033549 [IN](S) Sending msg 84530904 on secure session with LSID: 23439 I: 1033609 [EM]>>> [E:28297r M:120615683] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:I) I: 1033623 [EM]<<< [E:28297r M:84530905] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:Invo) I: 1033634 [IN](S) Sending msg 84530905 on secure session with LSID: 23439 I: 1033699 [EM]>>> [E:28298r M:120615684] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:I) I: 1033711 [ZCL]OpCreds: Certificate Chain request received for PAI I: 1033720 [EM]<<< [E:28298r M:84530906] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:Invo) I: 1033731 [IN](S) Sending msg 84530906 on secure session with LSID: 23439 I: 1033969 [EM]>>> [E:28299r M:120615685] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:I) I: 1033981 [ZCL]OpCreds: Certificate Chain request received for DAC I: 1033990 [EM]<<< [E:28299r M:84530907] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:Invo) I: 1034001 [IN](S) Sending msg 84530907 on secure session with LSID: 23439 I: 1034239 [EM]>>> [E:28300r M:120615686] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:I) I: 1034251 [ZCL]OpCreds: Received an AttestationRequest command I: 1034288 [ZCL]OpCreds: AttestationRequest successful. I: 1034296 [EM]<<< [E:28300r M:84530908] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:Invo) I: 1034307 [IN](S) Sending msg 84530908 on secure session with LSID: 23439 I: 1034554 [EM]>>> [E:28301r M:120615687] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:I) I: 1034566 [ZCL]OpCreds: Received a CSRRequest command I: 1034757 [ZCL]OpCreds: AllocatePendingOperationalKey succeeded I: 1034793 [ZCL]OpCreds: CSRRequest successful. I: 1034800 [EM]<<< [E:28301r M:84530909] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:Invo) I: 1034811 [IN](S) Sending msg 84530909 on secure session with LSID: 23439 E: 1034817 [DL]Long dispatch time: 264 ms, for event type 16388 I: 1035050 [EM]>>> [E:28302r M:120615688] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:I) I: 1035061 [ZCL]OpCreds: Received an AddTrustedRootCertificate command I: 1035124 [ZCL]OpCreds: AddTrustedRootCertificate successful. I: 1035131 [EM]<<< [E:28302r M:84530910] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:Invo) I: 1035142 [IN](S) Sending msg 84530910 on secure session with LSID: 23439 E: 1035149 [DL]Long dispatch time: 101 ms, for event type 16388 I: 1035411 [EM]>>> [E:28303r M:120615689] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:I) I: 1035423 [ZCL]OpCreds: Received an AddNOC command I: 1035430 [FP]Validating NOC chain I: 1035547 [FP]NOC chain validation successful I: 1035552 [FP]Added new fabric at index: 0x1 I: 1035556 [FP]Assigned compressed fabric ID: 0xB11330A3FC78D426, node ID: 0x0000000000000001 I: 1035564 [TS]Last Known Good Time: 2023-06-23T09:32:58 I: 1035569 [TS]New proposed Last Known Good Time: 2021-01-01T00:00:00 I: 1035575 [TS]Retaining current Last Known Good Time I: 1035601 [ZCL]OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x000000000001B669 I: 1035610 [DIS]Advertise operational node B11330A3FC78D426-0000000000000001 I: 1035617 [ZCL]OpCreds: successfully created fabric index 0x1 via AddNOC I: 1035624 [EM]<<< [E:28303r M:84530911] (S) Msg TX to 1:FFFFFFFB00000000 [D426] --- Type 0001:09 (IM:Invo) I: 1035635 [IN](S) Sending msg 84530911 on secure session with LSID: 23439 E: 1035642 [DL]Long dispatch time: 234 ms, for event type 16388 I: 1035725 [EM]>>> [E:28304r M:120615690] (S) Msg RX from 1:FFFFFFFB00000000 [D426] --- Type 0001:08 (IM:I) I: 1035743 [EM]<<< [E:28304r M:84530912] (S) Msg TX to 1:FFFFFFFB00000000 [D426] --- Type 0001:09 (IM:Invo) I: 1035754 [IN](S) Sending msg 84530912 on secure session with LSID: 23439 I: 1035814 [EM]>>> [E:28305r M:120615691] (S) Msg RX from 1:FFFFFFFB00000000 [D426] --- Type 0001:08 (IM:I) I: 1064178 [DL]SRP Client was started, detected server: fd36:f93b:1aa0:ce03:52bc:82e4:351d:8e44 I: 1064191 [EM]<<< [E:28305r M:84530913] (S) Msg TX to 1:FFFFFFFB00000000 [D426] --- Type 0001:09 (IM:Invo) I: 1064202 [IN](S) Sending msg 84530913 on secure session with LSID: 23439 I: 1064209 [DIS]Advertise operational node B11330A3FC78D426-0000000000000001 I: 1064216 [SVR]Operational advertising enabled I: 1065023 [DIS]Setting operational delegate post init I: 1065032 [SWU]Stopping the watchdog timer I: 1065036 [SWU]Starting the periodic query timer, timeout: 86400 seconds I: 1065042 [DIS]Updating services using commissioning mode 0 I: 1065048 [DIS]Advertise operational node B11330A3FC78D426-0000000000000001 I: 1065055 [DL]advertising srp service: B11330A3FC78D426-0000000000000001._matter._tcp I: 1065064 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=0 I: 1065074 [DL]advertising srp service: B9F3F83F698476BA._matterc._udp E: 1065360 [DL]SRP update error: domain name or RRset is duplicated E: 1070179 [DL]SRP update error: timed out waiting on server response E: 1070722 [DL]SRP update error: domain name or RRset is duplicated E: 1075960 [DL]SRP update error: domain name or RRset is duplicated E: 1084854 [DL]SRP update error: domain name or RRset is duplicated I: 1093537 [FS]Fail-safe timer expired E: 1093540 [SVR]Failsafe timer expired D: 1093544 [IN]SecureSession[0x20003568]: MarkForEviction Type:1 LSID:23439 I: 1093551 [SC]SecureSession[0x20003568]: Moving from state 'kActive' --> 'kPendingEviction' D: 1093559 [IN]SecureSession[0x20003568]: Released - Type:1 LSID:23439 E: 1093565 [SVR]Commissioning failed (attempt 1): 32 D: 1093570 [IN]Clearing BLE pending packets. I: 1093574 [BLE]Releasing end point's BLE connection back to application. I: 1093581 [DL]Closing BLE GATT connection (ConnId 00) D: 1093588 [IN]SecureSession[0x20003568]: Allocated Type:1 LSID:23440 I: 1093595 [DIS]Updating services using commissioning mode 1 I: 1093600 [DIS]Advertise operational node B11330A3FC78D426-0000000000000001 I: 1093608 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 I: 1093618 [DL]advertising srp service: B9F3F83F698476BA._matterc._udp E: 1093624 [ZCL]OpCreds: Got FailSafeTimerExpired E: 1093629 [ZCL]OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry! D: 1093636 [IN]Expiring all sessions for fabric 0x1!! E: 1093641 [FP]Reverting pending fabric data for fabric 0x1 E: 1093647 [FP]Warning: metadata not found during delete of fabric 0x1 I: 1093658 [FP]Fabric (0x1) deleted. I: 1093662 [ZCL]OpCreds: Fabric index 0x1 was removed I: 1093667 [DIS]Updating services using commissioning mode 1 I: 1093673 [DIS]Advertise commission parameter vendorID=65521 productID=32773 discriminator=3840/15 cm=1 I: 1093683 [DL]advertising srp service: B9F3F83F698476BA._matterc._udp I: 1093689 [DL]removing srp service: B11330A3FC78D426-0000000000000001._matter._tcp I: 1093816 [TS]Pending Last Known Good Time: 2023-06-23T09:32:58 I: 1093823 [TS]Previous Last Known Good Time: 2023-06-23T09:32:58 I: 1093828 [TS]Reverted Last Known Good Time to previous value E: 1093835 [FP]Warning: metadata not found during delete of fabric 0x1 E: 1093844 [ZCL]OpCreds: failed to delete fabric at index 1: d8 I: 1093850 [NP]Reverting Thread operational dataset E: 1093860 [DL]Long dispatch time: 320 ms, for event type 32782 I: 1093866 [FS]Fail-safe cleanly disarmed I: 1093871 [DL]BLE GAP connection terminated (reason 0x16) I: 1093877 [DL]Current number of connections: 0/1 I: 1093883 [DL]CHIPoBLE advertising started I: 1093888 [DL]NFC Tag emulation started
and the logs for the chip-tool for pc is
./chip-tool-release pairing ble-thread 1 hex:0e080000000000010000000300001135060004001fffe002086fa9d405754a44f70708fd36f93b1aa0ce030510d645ad53670e4076efbce31100978b2b030f4f70656e5468726561642d393133630102913c041090de57e044a398375de7f887ec9670af0c0402a0f7f8 20202021 3840 [1687496900.819313][12710:12710] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-JyntcP) [1687496900.819519][12710:12710] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1687496900.819535][12710:12710] CHIP:DL: NVS set: chip-counters/reboot-count = 9 (0x9) [1687496900.819826][12710:12710] CHIP:DL: Got Ethernet interface: enp2s0 [1687496900.819991][12710:12710] CHIP:DL: Found the primary Ethernet interface:enp2s0 [1687496900.820172][12710:12710] CHIP:DL: Got WiFi interface: wlp0s20f3 [1687496900.821155][12710:12710] CHIP:DL: Found the primary WiFi interface:wlp0s20f3 [1687496900.821262][12710:12710] CHIP:TS: Last Known Good Time: 2023-03-02T09:28:16 [1687496900.821383][12710:12710] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xB11330A3FC78D426, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1687496900.821812][12710:12710] CHIP:ZCL: Using ZAP configuration... [1687496900.823019][12710:12710] CHIP:DL: Avahi client registered [1687496900.823352][12710:12710] CHIP:CTL: Setting attestation nonce to random value [1687496900.823363][12710:12710] CHIP:CTL: Setting CSR nonce to random value [1687496900.823789][12710:12712] CHIP:CTL: Setting attestation nonce to random value [1687496900.823803][12710:12712] CHIP:CTL: Setting CSR nonce to random value [1687496900.826169][12710:12712] CHIP:CTL: Generating NOC [1687496900.828612][12710:12712] CHIP:FP: Validating NOC chain [1687496900.837266][12710:12712] CHIP:FP: NOC chain validation successful [1687496900.837278][12710:12712] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1687496900.837282][12710:12712] CHIP:TS: Last Known Good Time: 2023-03-02T09:28:16 [1687496900.837284][12710:12712] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1687496900.837285][12710:12712] CHIP:TS: Retaining current Last Known Good Time [1687496900.837726][12710:12712] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1687496900.838003][12710:12712] CHIP:TS: Committing Last Known Good Time to storage: 2023-03-02T09:28:16 [1687496900.838478][12710:12712] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: B11330A3FC78D426) [1687496900.841153][12710:12712] CHIP:CTL: Setting thread operational dataset from parameters [1687496900.841163][12710:12712] CHIP:CTL: Setting attempt thread scan from parameters [1687496900.841174][12710:12712] CHIP:CTL: Setting attestation nonce to random value [1687496900.841186][12710:12712] CHIP:CTL: Setting CSR nonce to random value [1687496900.841190][12710:12712] CHIP:CTL: Setting PASE-only commissioning from parameters [1687496900.841194][12710:12712] CHIP:CTL: Commission called for node ID 0x0000000000000001 [1687496900.844627][12710:12711] CHIP:BLE: BLE removing known devices. [1687496900.844867][12710:12711] CHIP:BLE: BLE initiating scan. [1687496900.966241][12710:12712] CHIP:DL: Long dispatch time: 125 ms, for event type 2 [1687496900.982151][12710:12711] CHIP:BLE: New device scanned: ED:37:F1:0F:75:5A [1687496900.982175][12710:12711] CHIP:BLE: Device discriminator match. Attempting to connect. [1687496900.986963][12710:12711] CHIP:BLE: Scan complete notification without an active scan. [1687496902.457944][12710:12712] CHIP:DIS: Closing all BLE connections [1687496902.458004][12710:12712] CHIP:EM: <<< [E:28294i M:134199296] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) [1687496902.458012][12710:12712] CHIP:IN: (U) Sending msg 134199296 to IP address 'BLE' [1687496903.029800][12710:12712] CHIP:BLE: subscribe complete, ep = 0x556402315d60 [1687496903.030164][12710:12712] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4 [1687496903.030193][12710:12712] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244. [1687496903.030202][12710:12712] CHIP:BLE: local and remote recv window size = 5 [1687496903.120920][12710:12712] CHIP:EM: >>> [E:28294i M:156394311] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) [1687496903.133864][12710:12712] CHIP:EM: <<< [E:28294i M:134199297] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1) [1687496903.133872][12710:12712] CHIP:IN: (U) Sending msg 134199297 to IP address 'BLE' [1687496905.101107][12710:12712] CHIP:EM: >>> [E:28294i M:156394312] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) [1687496905.111516][12710:12712] CHIP:EM: <<< [E:28294i M:134199298] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3) [1687496905.111530][12710:12712] CHIP:IN: (U) Sending msg 134199298 to IP address 'BLE' [1687496905.189864][12710:12712] CHIP:EM: >>> [E:28294i M:156394313] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) [1687496905.189969][12710:12712] CHIP:SC: SecureSession[0x7fbd0000e800]: Moving from state 'kEstablishing' --> 'kActive' [1687496905.189984][12710:12712] CHIP:TOO: Pairing Success [1687496905.189993][12710:12712] CHIP:TOO: PASE establishment successful [1687496905.190005][12710:12712] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1687496905.190017][12710:12712] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo' [1687496905.190026][12710:12712] CHIP:CTL: Sending request for commissioning information [1687496905.190121][12710:12712] CHIP:EM: <<< [E:28295i M:120615681] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) [1687496905.190137][12710:12712] CHIP:IN: (S) Sending msg 120615681 on secure session with LSID: 51082 [1687496905.460289][12710:12712] CHIP:EM: >>> [E:28295i M:84530903] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [1687496905.460911][12710:12712] CHIP:CTL: ----- NetworkCommissioning Features: has Thread. endpointid = 0 [1687496905.460959][12710:12712] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo' [1687496905.460972][12710:12712] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe' [1687496905.460983][12710:12712] CHIP:CTL: Performing next commissioning step 'ArmFailSafe' [1687496905.460989][12710:12712] CHIP:CTL: Arming failsafe (60 seconds) [1687496905.461062][12710:12712] CHIP:EM: <<< [E:28296i M:120615682] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1687496905.461081][12710:12712] CHIP:IN: (S) Sending msg 120615682 on secure session with LSID: 51082 [1687496905.550314][12710:12712] CHIP:EM: >>> [E:28296i M:84530904] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1687496905.550504][12710:12712] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1687496905.550560][12710:12712] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1687496905.550574][12710:12712] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe' [1687496905.550583][12710:12712] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1687496905.550596][12710:12712] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory' [1687496905.550603][12710:12712] CHIP:CTL: Setting Regulatory Config [1687496905.550617][12710:12712] CHIP:CTL: Device does not support configurable regulatory location [1687496905.550689][12710:12712] CHIP:EM: <<< [E:28297i M:120615683] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1687496905.550704][12710:12712] CHIP:IN: (S) Sending msg 120615683 on secure session with LSID: 51082 [1687496905.640313][12710:12712] CHIP:EM: >>> [E:28297i M:84530905] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1687496905.640428][12710:12712] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1687496905.640450][12710:12712] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0 [1687496905.640463][12710:12712] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory' [1687496905.640469][12710:12712] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest' [1687496905.640478][12710:12712] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest' [1687496905.640484][12710:12712] CHIP:CTL: Sending request for PAI certificate [1687496905.640569][12710:12712] CHIP:EM: <<< [E:28298i M:120615684] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1687496905.640584][12710:12712] CHIP:IN: (S) Sending msg 120615684 on secure session with LSID: 51082 [1687496905.910677][12710:12712] CHIP:EM: >>> [E:28298i M:84530906] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1687496905.910836][12710:12712] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1687496905.910861][12710:12712] CHIP:CTL: Received certificate chain from the device [1687496905.910881][12710:12712] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest' [1687496905.910897][12710:12712] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1687496905.910911][12710:12712] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest' [1687496905.910928][12710:12712] CHIP:CTL: Sending request for DAC certificate [1687496905.911023][12710:12712] CHIP:EM: <<< [E:28299i M:120615685] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1687496905.911043][12710:12712] CHIP:IN: (S) Sending msg 120615685 on secure session with LSID: 51082 [1687496906.180869][12710:12712] CHIP:EM: >>> [E:28299i M:84530907] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1687496906.181011][12710:12712] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1687496906.181031][12710:12712] CHIP:CTL: Received certificate chain from the device [1687496906.181047][12710:12712] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest' [1687496906.181060][12710:12712] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1687496906.181075][12710:12712] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest' [1687496906.181087][12710:12712] CHIP:CTL: Sending Attestation Request to the device. [1687496906.181168][12710:12712] CHIP:EM: <<< [E:28300i M:120615686] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1687496906.181187][12710:12712] CHIP:IN: (S) Sending msg 120615686 on secure session with LSID: 51082 [1687496906.497127][12710:12712] CHIP:EM: >>> [E:28300i M:84530908] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1687496906.497273][12710:12712] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1687496906.497298][12710:12712] CHIP:CTL: Received Attestation Information from the device [1687496906.497318][12710:12712] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest' [1687496906.497339][12710:12712] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1687496906.497354][12710:12712] CHIP:CTL: Performing next commissioning step 'AttestationVerification' [1687496906.497363][12710:12712] CHIP:CTL: Verifying attestation [1687496906.514113][12710:12712] CHIP:CR: mbedTLS error: ERROR - Generic error [1687496906.529906][12710:12712] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device. [1687496906.529916][12710:12712] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification' [1687496906.529918][12710:12712] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest' [1687496906.529922][12710:12712] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest' [1687496906.529964][12710:12712] CHIP:EM: <<< [E:28301i M:120615687] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1687496906.529969][12710:12712] CHIP:IN: (S) Sending msg 120615687 on secure session with LSID: 51082 [1687496906.901141][12710:12712] CHIP:EM: >>> [E:28301i M:84530909] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1687496906.901268][12710:12712] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005 [1687496906.901287][12710:12712] CHIP:CTL: Received certificate signing request from the device [1687496906.901302][12710:12712] CHIP:CTL: Successfully finished commissioning step 'SendOpCertSigningRequest' [1687496906.901311][12710:12712] CHIP:CTL: Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR' [1687496906.901321][12710:12712] CHIP:CTL: Performing next commissioning step 'ValidateCSR' [1687496906.917375][12710:12712] CHIP:CTL: Successfully finished commissioning step 'ValidateCSR' [1687496906.917383][12710:12712] CHIP:CTL: Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain' [1687496906.917389][12710:12712] CHIP:CTL: Performing next commissioning step 'GenerateNOCChain' [1687496906.917394][12710:12712] CHIP:CTL: Getting certificate chain for the device from the issuer [1687496906.917419][12710:12712] CHIP:CTL: Verifying Certificate Signing Request [1687496906.925488][12710:12712] CHIP:CTL: Generating NOC [1687496906.927741][12710:12712] CHIP:CTL: Providing certificate chain to the commissioner [1687496906.927745][12710:12712] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status ../../src/controller/ExampleOperationalCredentialsIssuer.cpp:396: Success [1687496906.927747][12710:12712] CHIP:CTL: Successfully finished commissioning step 'GenerateNOCChain' [1687496906.927768][12710:12712] CHIP:CTL: Performing next commissioning step 'SendTrustedRootCert' [1687496906.927772][12710:12712] CHIP:CTL: Sending root certificate to the device [1687496906.927802][12710:12712] CHIP:EM: <<< [E:28302i M:120615688] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1687496906.927807][12710:12712] CHIP:IN: (S) Sending msg 120615688 on secure session with LSID: 51082 [1687496906.927823][12710:12712] CHIP:CTL: Sent root certificate to the device [1687496907.170136][12710:12712] CHIP:EM: >>> [E:28302i M:84530910] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1687496907.170245][12710:12712] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0 [1687496907.170260][12710:12712] CHIP:CTL: Device confirmed that it has received the root certificate [1687496907.170278][12710:12712] CHIP:CTL: Successfully finished commissioning step 'SendTrustedRootCert' [1687496907.170292][12710:12712] CHIP:CTL: Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC' [1687496907.170308][12710:12712] CHIP:CTL: Performing next commissioning step 'SendNOC' [1687496907.170415][12710:12712] CHIP:EM: <<< [E:28303i M:120615689] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1687496907.170436][12710:12712] CHIP:IN: (S) Sending msg 120615689 on secure session with LSID: 51082 [1687496907.170486][12710:12712] CHIP:CTL: Sent operational certificate to the device [1687496907.665134][12710:12712] CHIP:EM: >>> [E:28303i M:84530911] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1687496907.665242][12710:12712] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008 [1687496907.665259][12710:12712] CHIP:CTL: Device returned status 0 on receiving the NOC [1687496907.665265][12710:12712] CHIP:CTL: Operational credentials provisioned on device 0x7fbd0000ba00 [1687496907.665271][12710:12712] CHIP:TOO: Secure Pairing Success [1687496907.665275][12710:12712] CHIP:TOO: CASE establishment successful [1687496907.665285][12710:12712] CHIP:CTL: Successfully finished commissioning step 'SendNOC' [1687496907.665292][12710:12712] CHIP:CTL: No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks [1687496907.665298][12710:12712] CHIP:CTL: Commissioning stage next step: 'SendNOC' -> 'ThreadNetworkSetup' [1687496907.665306][12710:12712] CHIP:CTL: Performing next commissioning step 'ThreadNetworkSetup' [1687496907.665365][12710:12712] CHIP:EM: <<< [E:28304i M:120615690] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1687496907.665378][12710:12712] CHIP:IN: (S) Sending msg 120615690 on secure session with LSID: 51082 [1687496907.755239][12710:12712] CHIP:EM: >>> [E:28304i M:84530912] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1687496907.755335][12710:12712] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005 [1687496907.757569][12710:12712] CHIP:CTL: Received NetworkConfig response, networkingStatus=0 [1687496907.757585][12710:12712] CHIP:CTL: Successfully finished commissioning step 'ThreadNetworkSetup' [1687496907.757596][12710:12712] CHIP:CTL: Commissioning stage next step: 'ThreadNetworkSetup' -> 'ThreadNetworkEnable' [1687496907.757605][12710:12712] CHIP:CTL: Performing next commissioning step 'ThreadNetworkEnable' [1687496907.757669][12710:12712] CHIP:EM: <<< [E:28305i M:120615691] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1687496907.757684][12710:12712] CHIP:IN: (S) Sending msg 120615691 on secure session with LSID: 51082 [1687496936.240354][12710:12712] CHIP:EM: >>> [E:28305i M:84530913] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1687496936.240488][12710:12712] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0007 [1687496936.240513][12710:12712] CHIP:CTL: Received ConnectNetwork response, networkingStatus=0 [1687496936.240528][12710:12712] CHIP:CTL: Successfully finished commissioning step 'ThreadNetworkEnable' [1687496936.240540][12710:12712] CHIP:CTL: Commissioning stage next step: 'ThreadNetworkEnable' -> 'FindOperational' [1687496936.240553][12710:12712] CHIP:CTL: Performing next commissioning step 'FindOperational' [1687496936.240581][12710:12712] CHIP:DIS: Resolving B11330A3FC78D426:0000000000000001 ... [1687496936.440931][12710:12712] CHIP:DIS: Checking node lookup status after 200 ms [1687496941.244400][12710:12712] CHIP:DL: Re-trying resolve [1687496946.249386][12710:12712] CHIP:DL: Re-trying resolve [1687496951.252339][12710:12712] CHIP:DL: Re-trying resolve [1687496956.256895][12710:12712] CHIP:DL: Avahi resolve failed [1687496965.625157][12710:12711] CHIP:DL: Bluez notify CHIPoBluez connection disconnected [1687496981.248469][12710:12712] CHIP:DIS: Checking node lookup status after 45008 ms [1687496981.248516][12710:12712] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: operational discovery failed: ../../src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout [1687496981.248528][12710:12712] CHIP:CTL: Device connection failed. Error ../../src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout [1687496981.248546][12710:12712] CHIP:CTL: Error on commissioning step 'FindOperational': '../../src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout' [1687496981.248554][12710:12712] CHIP:CTL: Failed to perform commissioning step 18 [1687496981.248561][12710:12712] CHIP:CTL: Going from commissioning step 'FindOperational' with lastErr = '../../src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout' -> 'Cleanup' [1687496981.248577][12710:12712] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = '../../src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout' [1687496981.248587][12710:12712] CHIP:CTL: Successfully finished commissioning step 'Cleanup' [1687496981.248604][12710:12712] CHIP:TOO: Device commissioning Failure: ../../src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout [1687496981.248867][12710:12710] CHIP:DIS: Closing all BLE connections [1687496981.248897][12710:12710] CHIP:SC: SecureSession[0x7fbd0000e800]: Moving from state 'kActive' --> 'kPendingEviction' [1687496981.248935][12710:12710] CHIP:FP: Forgetting fabric 0x1 [1687496981.248952][12710:12710] CHIP:TS: Pending Last Known Good Time: 2023-03-02T09:28:16 [1687496981.249049][12710:12710] CHIP:TS: Previous Last Known Good Time: 2023-03-02T09:28:16 [1687496981.249057][12710:12710] CHIP:TS: Reverted Last Known Good Time to previous value [1687496981.249866][12710:12710] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1687496981.249915][12710:12710] CHIP:FP: Shutting down FabricTable [1687496981.249936][12710:12710] CHIP:TS: Pending Last Known Good Time: 2023-03-02T09:28:16 [1687496981.250021][12710:12710] CHIP:TS: Previous Last Known Good Time: 2023-03-02T09:28:16 [1687496981.250032][12710:12710] CHIP:TS: Reverted Last Known Good Time to previous value [1687496981.250245][12710:12710] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-TrOXO5) [1687496981.250709][12710:12710] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1687496981.250755][12710:12710] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1687496981.250768][12710:12710] CHIP:DL: Inet Layer shutdown [1687496981.250778][12710:12710] CHIP:DL: BLE shutdown [1687496981.251692][12710:12710] CHIP:DL: System Layer shutdown [1687496981.251973][12710:12710] CHIP:TOO: Run command failure: ../../src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeoutand I am using the nrr52840 for the light bulb and using the ncs v2.3.0 and using same version for the chip tool for the linux pc
Now what to do now?
