ubuntu@ubuntu:~/jun14_cntrl/connectedhomeip/examples/chip-tool/out/debug$ sudo rm -rf /tmp/chip_* ubuntu@ubuntu:~/jun14_cntrl/connectedhomeip/examples/chip-tool/out/debug$ sudo rm -rf /tmp/chip_* ubuntu@ubuntu:~/jun14_cntrl/connectedhomeip/examples/chip-tool/out/debug$ ./chip-tool payload generate-manualcode --discriminator 3840 --setup-pin-code 20202021 --version 0 --vendor-id 0xFFF1 --product-id 0x8001 --commissioning-mode 1 [1655382185.321914][36075:36075] CHIP:TOO: Manual Code: 749701123365521327694 ubuntu@ubuntu:~/jun14_cntrl/connectedhomeip/examples/chip-tool/out/debug$ ./chip-tool pairing code 1 749701123365521327694 [1655382242.672019][36079:36079] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1655382242.672313][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_kvs-xrC6nA) [1655382242.672496][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1655382242.672927][36079:36079] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1655382242.673066][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-H0fuHl) [1655382242.673195][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1655382242.673279][36079:36079] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1655382242.673400][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_config.ini-EJyGQ7) [1655382242.673597][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1655382242.673688][36079:36079] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1655382242.673847][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-hSNFIY) [1655382242.673998][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655382242.674261][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-ZaIkJS) [1655382242.674772][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1655382242.674820][36079:36079] CHIP:DL: NVS set: chip-factory/unique-id = "F786619B737587B7" [1655382242.674954][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-Gj5RpB) [1655382242.676452][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1655382242.676503][36079:36079] CHIP:DL: NVS set: chip-factory/vendor-id = 65521 (0xFFF1) [1655382242.676641][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-BQQSRW) [1655382242.678056][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1655382242.678105][36079:36079] CHIP:DL: NVS set: chip-factory/product-id = 32769 (0x8001) [1655382242.678237][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-WN3Urv) [1655382242.678589][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655382242.678632][36079:36079] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1) [1655382242.678752][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-fDEfSK) [1655382242.681333][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655382242.681385][36079:36079] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1655382242.681554][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-fcMIU7) [1655382242.683030][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655382242.683081][36079:36079] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0) [1655382242.683214][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_config.ini-ZuPQt1) [1655382242.683564][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1655382242.683608][36079:36079] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0) [1655382242.683728][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_config.ini-koFLyW) [1655382242.686398][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1655382242.686458][36079:36079] CHIP:DL: NVS set: chip-config/location-capability = 2 (0x2) [1655382242.687499][36079:36079] CHIP:DL: Got Ethernet interface: eth0 [1655382242.688229][36079:36079] CHIP:DL: Found the primary Ethernet interface:eth0 [1655382242.688992][36079:36079] CHIP:DL: Got WiFi interface: wlan0 [1655382242.689084][36079:36079] CHIP:DL: Failed to reset WiFi statistic counts [1655382242.689173][36079:36079] CHIP:IN: UDP::Init bind&listen port=0 [1655382242.689341][36079:36079] CHIP:IN: UDP::Init bound to port=42203 [1655382242.689374][36079:36079] CHIP:IN: BLEBase::Init - setting/overriding transport [1655382242.689404][36079:36079] CHIP:IN: TransportMgr initialized [1655382242.689449][36079:36079] CHIP:FP: Initializing FabricTable from persistent storage [1655382242.689505][36079:36079] CHIP:TS: Last Known Good Time: [unknown] [1655382242.689541][36079:36079] CHIP:TS: Setting Last Known Good Time to firmware build time 2022-06-14T18:16:36 [1655382242.692149][36079:36079] CHIP:ZCL: Using ZAP configuration... [1655382242.696949][36079:36079] CHIP:DL: Avahi client registered [1655382242.698257][36079:36079] CHIP:CTL: System State Initialized... [1655382242.698373][36079:36079] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655382242.698414][36079:36079] CHIP:CTL: Setting attestation nonce to random value [1655382242.698469][36079:36079] CHIP:CTL: Setting CSR nonce to random value [1655382242.698547][36079:36079] CHIP:IN: UDP::Init bind&listen port=5550 [1655382242.698695][36079:36079] CHIP:IN: UDP::Init bound to port=5550 [1655382242.698724][36079:36079] CHIP:IN: TransportMgr initialized [1655382242.698816][36079:36079] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655382242.698849][36079:36079] CHIP:CTL: Setting attestation nonce to random value [1655382242.698903][36079:36079] CHIP:CTL: Setting CSR nonce to random value [1655382242.699362][36079:36079] CHIP:CTL: Couldn't get ExampleOpCredsCAKey from storage: ../../config/PersistentStorage.cpp:108: CHIP Error 0x000000A0: Value not found in the persisted storage [1655382242.702539][36079:36079] CHIP:CTL: Couldn't get ExampleOpCredsICAKey from storage: ../../config/PersistentStorage.cpp:108: CHIP Error 0x000000A0: Value not found in the persisted storage [1655382242.703897][36079:36079] CHIP:CTL: Generating RCAC [1655382242.704995][36079:36079] CHIP:CTL: Generating ICAC [1655382242.706051][36079:36079] CHIP:CTL: Generating NOC [1655382242.707076][36079:36079] CHIP:FP: Validating NOC chain [1655382242.709370][36079:36079] CHIP:FP: NOC chain validation successful [1655382242.709593][36079:36079] CHIP:FP: Added new fabric at index: 0x1, Initialized: 1 [1655382242.709627][36079:36079] CHIP:FP: Assigned compressed fabric ID: 0x6E7E70F7DD8C6C66, node ID: 0x000000000001B669 [1655382242.713905][36079:36079] CHIP:FP: Fabric (0x1) persisted to storage. Calling OnFabricPersistedToStorage [1655382242.713968][36079:36079] CHIP:TS: Last Known Good Time: 2022-06-14T18:16:36 [1655382242.713999][36079:36079] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655382242.714027][36079:36079] CHIP:TS: Retaining current Last Known Good Time [1655382242.716410][36079:36079] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x6E7E70F7DD8C6C66 [1655382242.716485][36079:36079] CHIP:IN: UDP::Init bind&listen port=5550 [1655382242.716628][36079:36079] CHIP:IN: UDP::Init bound to port=5550 [1655382242.716656][36079:36079] CHIP:IN: TransportMgr initialized [1655382242.716743][36079:36079] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655382242.716792][36079:36079] CHIP:CTL: Setting attestation nonce to random value [1655382242.716857][36079:36079] CHIP:CTL: Setting CSR nonce to random value [1655382242.718173][36079:36079] CHIP:CTL: Generating NOC [1655382242.719078][36079:36079] CHIP:FP: Validating NOC chain [1655382242.720941][36079:36079] CHIP:FP: NOC chain validation successful [1655382242.721136][36079:36079] CHIP:FP: Added new fabric at index: 0x2, Initialized: 1 [1655382242.721165][36079:36079] CHIP:FP: Assigned compressed fabric ID: 0x928979AADB36693C, node ID: 0x000000000001B669 [1655382242.725992][36079:36079] CHIP:FP: Fabric (0x2) persisted to storage. Calling OnFabricPersistedToStorage [1655382242.726049][36079:36079] CHIP:TS: Last Known Good Time: 2022-06-14T18:16:36 [1655382242.726078][36079:36079] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655382242.726103][36079:36079] CHIP:TS: Retaining current Last Known Good Time [1655382242.729199][36079:36079] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x928979AADB36693C [1655382242.729273][36079:36079] CHIP:IN: UDP::Init bind&listen port=5550 [1655382242.729424][36079:36079] CHIP:IN: UDP::Init bound to port=5550 [1655382242.729454][36079:36079] CHIP:IN: TransportMgr initialized [1655382242.729549][36079:36079] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655382242.729603][36079:36079] CHIP:CTL: Setting attestation nonce to random value [1655382242.729672][36079:36079] CHIP:CTL: Setting CSR nonce to random value [1655382242.731005][36079:36079] CHIP:CTL: Generating NOC [1655382242.731986][36079:36079] CHIP:FP: Validating NOC chain [1655382242.734067][36079:36079] CHIP:FP: NOC chain validation successful [1655382242.734267][36079:36079] CHIP:FP: Added new fabric at index: 0x3, Initialized: 1 [1655382242.734298][36079:36079] CHIP:FP: Assigned compressed fabric ID: 0x145676BC68D548E3, node ID: 0x000000000001B669 [1655382242.740209][36079:36079] CHIP:FP: Fabric (0x3) persisted to storage. Calling OnFabricPersistedToStorage [1655382242.740263][36079:36079] CHIP:TS: Last Known Good Time: 2022-06-14T18:16:36 [1655382242.740291][36079:36079] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655382242.740315][36079:36079] CHIP:TS: Retaining current Last Known Good Time [1655382242.745978][36079:36079] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x145676BC68D548E3 [1655382242.746056][36079:36079] CHIP:IN: UDP::Init bind&listen port=5550 [1655382242.746195][36079:36079] CHIP:IN: UDP::Init bound to port=5550 [1655382242.746243][36079:36079] CHIP:IN: TransportMgr initialized [1655382242.838434][36079:36084] CHIP:DL: CHIP task running [1655382242.838705][36079:36084] CHIP:DL: HandlePlatformSpecificBLEEvent 32787 [1655382242.838783][36079:36084] CHIP:CTL: Setting attestation nonce to random value [1655382242.838941][36079:36084] CHIP:CTL: Setting CSR nonce to random value [1655382242.839057][36079:36084] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655382242.839090][36079:36084] CHIP:CTL: Starting commissioning discovery over BLE [1655382242.839133][36079:36084] CHIP:CTL: Starting commissioning discovery over DNS-SD [1655382242.840575][36079:36085] CHIP:DL: TRACE: Bluez mainloop starting Thread [1655382242.840917][36079:36082] CHIP:DL: TRACE: Bus acquired for name C-8cef [1655382242.848803][36079:36084] CHIP:DL: PlatformBlueZInit init success [1655382242.855631][36079:36082] CHIP:BLE: BLE removing known devices. [1655382242.857398][36079:36082] CHIP:BLE: BLE initiating scan. [1655382242.860625][36079:36084] CHIP:DL: Avahi browse: cache new [1655382242.860729][36079:36084] CHIP:DL: Avahi browse: cache exhausted [1655382242.951284][36079:36082] CHIP:BLE: Device 68:72:C3:7F:53:63 does not look like a CHIP device. [1655382243.344611][36079:36082] CHIP:BLE: Device 35:18:75:A8:E9:E4 does not look like a CHIP device. [1655382243.853311][36079:36084] CHIP:DL: Avahi browse: all for now [1655382243.856876][36079:36084] CHIP:DL: Avahi resolve found [1655382243.857031][36079:36084] CHIP:CTL: Discovered device to be commissioned over DNS-SD [1655382243.857472][36079:36084] CHIP:CTL: Attempting PASE connection to UDP:[fe80::e65f:1ff:fe0f:2755%eth0]:5540 [1655382243.857746][36079:36084] CHIP:IN: SecureSession[0xaaaaf701e570]: Allocated Type:1 LSID:1223 [1655382243.857804][36079:36084] CHIP:SC: Assigned local session key ID 1223 [1655382243.857932][36079:36084] CHIP:SC: Including MRP parameters in PBKDF param request [1655382243.858011][36079:36082] CHIP:BLE: New device scanned: E4:5F:01:0F:27:56 [1655382243.858066][36079:36082] CHIP:BLE: Device discriminator match. Attempting to connect. [1655382243.858030][36079:36084] CHIP:IN: Prepared unauthenticated message 0xaaaaf7021a38 to 0x0000000000000000 (0) of type 0x20 and protocolId (0, 0) on exchange 12130i with MessageCounter:2137906. [1655382243.858184][36079:36084] CHIP:IN: Sending unauthenticated msg 0xaaaaf7021a38 with MessageCounter:2137906 to 0x0000000000000000 at monotonic time: 0000000000C9F6E8 msec [1655382243.858406][36079:36084] CHIP:SC: Sent PBKDF param request [1655382243.858455][36079:36084] CHIP:CTL: Commission called for node ID 0x0000000000000001 [1655382243.861436][36079:36082] CHIP:BLE: Scan complete notification without an active scan. [1655382243.862671][36079:36084] CHIP:EM: Received message of type 0x21 with protocolId (0, 0) and MessageCounter:229930458 on exchange 12130i [1655382243.862799][36079:36084] CHIP:EM: Found matching exchange: 12130i, Delegate: 0xffff7c00c880 [1655382243.862896][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:2137906 from Retrans Table on exchange 12130i [1655382243.863251][36079:36084] CHIP:EM: Removed CHIP MessageCounter:2137906 from RetransTable on exchange 12130i [1655382243.863744][36079:36084] CHIP:SC: Received PBKDF param response [1655382243.864134][36079:36084] CHIP:SC: Peer assigned session ID 22683 [1655382243.864188][36079:36084] CHIP:SC: Found MRP parameters in the message [1655382243.872224][36079:36084] CHIP:EM: Piggybacking Ack for MessageCounter:229930458 on exchange: 12130i [1655382243.872285][36079:36084] CHIP:IN: Prepared unauthenticated message 0xaaaaf7021a38 to 0x0000000000000000 (0) of type 0x22 and protocolId (0, 0) on exchange 12130i with MessageCounter:2137907. [1655382243.872331][36079:36084] CHIP:IN: Sending unauthenticated msg 0xaaaaf7021a38 with MessageCounter:2137907 to 0x0000000000000000 at monotonic time: 0000000000C9F6F6 msec [1655382243.872528][36079:36084] CHIP:SC: Sent spake2p msg1 [1655382243.877326][36079:36084] CHIP:EM: Received message of type 0x23 with protocolId (0, 0) and MessageCounter:229930459 on exchange 12130i [1655382243.877359][36079:36084] CHIP:EM: Found matching exchange: 12130i, Delegate: 0xffff7c00c880 [1655382243.877394][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:2137907 from Retrans Table on exchange 12130i [1655382243.877419][36079:36084] CHIP:EM: Removed CHIP MessageCounter:2137907 from RetransTable on exchange 12130i [1655382243.877452][36079:36084] CHIP:SC: Received spake2p msg2 [1655382243.878781][36079:36084] CHIP:EM: Piggybacking Ack for MessageCounter:229930459 on exchange: 12130i [1655382243.878825][36079:36084] CHIP:IN: Prepared unauthenticated message 0xaaaaf7021a38 to 0x0000000000000000 (0) of type 0x24 and protocolId (0, 0) on exchange 12130i with MessageCounter:2137908. [1655382243.878862][36079:36084] CHIP:IN: Sending unauthenticated msg 0xaaaaf7021a38 with MessageCounter:2137908 to 0x0000000000000000 at monotonic time: 0000000000C9F6FD msec [1655382243.879004][36079:36084] CHIP:SC: Sent spake2p msg3 [1655382243.880770][36079:36084] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:229930460 on exchange 12130i [1655382243.880801][36079:36084] CHIP:EM: Found matching exchange: 12130i, Delegate: 0xffff7c00c880 [1655382243.880834][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:2137908 from Retrans Table on exchange 12130i [1655382243.880860][36079:36084] CHIP:EM: Removed CHIP MessageCounter:2137908 from RetransTable on exchange 12130i [1655382243.880931][36079:36084] CHIP:IN: SecureSession[0xaaaaf701e570]: Activated - Type:1 LSID:1223 [1655382243.880960][36079:36084] CHIP:IN: New secure session created for device , LSID:1223 PSID:22683! [1655382243.880988][36079:36084] CHIP:CTL: Remote device completed SPAKE2+ handshake [1655382243.881014][36079:36084] CHIP:CTL: Stopping commissioning discovery over BLE [1655382243.881037][36079:36084] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1655382243.881058][36079:36084] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655382243.881081][36079:36084] CHIP:TOO: Pairing Success [1655382243.881105][36079:36084] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1655382243.881132][36079:36084] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo' [1655382243.881154][36079:36084] CHIP:CTL: Sending request for commissioning information [1655382243.881193][36079:36084] CHIP:DMG: SendReadRequest ReadClient[0xffff7c008800]: Sending Read Request [1655382243.881313][36079:36084] CHIP:IN: Prepared secure message 0xaaaaf7021a38 to 0xFFFFFFFB00000000 (0) of type 0x2 and protocolId (0, 1) on exchange 12131i with MessageCounter:75986120. [1655382243.881352][36079:36084] CHIP:IN: Sending encrypted msg 0xaaaaf7021a38 with MessageCounter:75986120 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F6FF msec [1655382243.881491][36079:36084] CHIP:DMG: MoveToState ReadClient[0xffff7c008800]: Moving to [AwaitingIn] [1655382243.881530][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:229930460 on exchange 12130i [1655382243.881566][36079:36084] CHIP:IN: Prepared unauthenticated message 0xffff91844948 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 12130i with MessageCounter:2137909. [1655382243.881600][36079:36084] CHIP:IN: Sending unauthenticated msg 0xffff91844948 with MessageCounter:2137909 to 0x0000000000000000 at monotonic time: 0000000000C9F6FF msec [1655382243.881680][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:229930460 on exchange 12130i [1655382243.924027][36079:36084] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:62892706 on exchange 12131i [1655382243.924108][36079:36084] CHIP:EM: Found matching exchange: 12131i, Delegate: 0xffff7c008800 [1655382243.924182][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:75986120 from Retrans Table on exchange 12131i [1655382243.924234][36079:36084] CHIP:EM: Removed CHIP MessageCounter:75986120 from RetransTable on exchange 12131i [1655382243.924394][36079:36084] CHIP:DMG: ReportDataMessage = [1655382243.924448][36079:36084] CHIP:DMG: { [1655382243.924494][36079:36084] CHIP:DMG: AttributeReportIBs = [1655382243.924558][36079:36084] CHIP:DMG: [ [1655382243.924609][36079:36084] CHIP:DMG: AttributeReportIB = [1655382243.924692][36079:36084] CHIP:DMG: { [1655382243.924747][36079:36084] CHIP:DMG: AttributeDataIB = [1655382243.924812][36079:36084] CHIP:DMG: { [1655382243.924881][36079:36084] CHIP:DMG: DataVersion = 0x3193f08a, [1655382243.924948][36079:36084] CHIP:DMG: AttributePathIB = [1655382243.925023][36079:36084] CHIP:DMG: { [1655382243.925091][36079:36084] CHIP:DMG: Endpoint = 0x0, [1655382243.925166][36079:36084] CHIP:DMG: Cluster = 0x31, [1655382243.925240][36079:36084] CHIP:DMG: Attribute = 0x0000_0003, [1655382243.925315][36079:36084] CHIP:DMG: } [1655382243.925388][36079:36084] CHIP:DMG: [1655382243.925463][36079:36084] CHIP:DMG: Data = 20, [1655382243.925528][36079:36084] CHIP:DMG: }, [1655382243.925598][36079:36084] CHIP:DMG: [1655382243.925655][36079:36084] CHIP:DMG: }, [1655382243.925732][36079:36084] CHIP:DMG: [1655382243.925783][36079:36084] CHIP:DMG: AttributeReportIB = [1655382243.925898][36079:36084] CHIP:DMG: { [1655382243.925955][36079:36084] CHIP:DMG: AttributeDataIB = [1655382243.926020][36079:36084] CHIP:DMG: { [1655382243.926086][36079:36084] CHIP:DMG: DataVersion = 0x4450030a, [1655382243.926153][36079:36084] CHIP:DMG: AttributePathIB = [1655382243.926228][36079:36084] CHIP:DMG: { [1655382243.926298][36079:36084] CHIP:DMG: Endpoint = 0x0, [1655382243.926372][36079:36084] CHIP:DMG: Cluster = 0x28, [1655382243.926450][36079:36084] CHIP:DMG: Attribute = 0x0000_0004, [1655382243.926518][36079:36084] CHIP:DMG: } [1655382243.926595][36079:36084] CHIP:DMG: [1655382243.926666][36079:36084] CHIP:DMG: Data = 32769, [1655382243.926731][36079:36084] CHIP:DMG: }, [1655382243.926800][36079:36084] CHIP:DMG: [1655382243.926857][36079:36084] CHIP:DMG: }, [1655382243.926931][36079:36084] CHIP:DMG: [1655382243.926982][36079:36084] CHIP:DMG: AttributeReportIB = [1655382243.927047][36079:36084] CHIP:DMG: { [1655382243.927101][36079:36084] CHIP:DMG: AttributeDataIB = [1655382243.927165][36079:36084] CHIP:DMG: { [1655382243.927231][36079:36084] CHIP:DMG: DataVersion = 0x4450030a, [1655382243.927304][36079:36084] CHIP:DMG: AttributePathIB = [1655382243.927372][36079:36084] CHIP:DMG: { [1655382243.927442][36079:36084] CHIP:DMG: Endpoint = 0x0, [1655382243.927516][36079:36084] CHIP:DMG: Cluster = 0x28, [1655382243.927589][36079:36084] CHIP:DMG: Attribute = 0x0000_0002, [1655382243.927658][36079:36084] CHIP:DMG: } [1655382243.927730][36079:36084] CHIP:DMG: [1655382243.927799][36079:36084] CHIP:DMG: Data = 65521, [1655382243.927864][36079:36084] CHIP:DMG: }, [1655382243.927932][36079:36084] CHIP:DMG: [1655382243.928080][36079:36084] CHIP:DMG: }, [1655382243.928159][36079:36084] CHIP:DMG: [1655382243.928211][36079:36084] CHIP:DMG: AttributeReportIB = [1655382243.928281][36079:36084] CHIP:DMG: { [1655382243.928344][36079:36084] CHIP:DMG: AttributeDataIB = [1655382243.928408][36079:36084] CHIP:DMG: { [1655382243.928475][36079:36084] CHIP:DMG: DataVersion = 0xe3033d98, [1655382243.928547][36079:36084] CHIP:DMG: AttributePathIB = [1655382243.928616][36079:36084] CHIP:DMG: { [1655382243.928686][36079:36084] CHIP:DMG: Endpoint = 0x0, [1655382243.928760][36079:36084] CHIP:DMG: Cluster = 0x30, [1655382243.928833][36079:36084] CHIP:DMG: Attribute = 0x0000_0003, [1655382243.928908][36079:36084] CHIP:DMG: } [1655382243.928985][36079:36084] CHIP:DMG: [1655382243.929063][36079:36084] CHIP:DMG: Data = 2, [1655382243.929129][36079:36084] CHIP:DMG: }, [1655382243.929197][36079:36084] CHIP:DMG: [1655382243.929254][36079:36084] CHIP:DMG: }, [1655382243.929327][36079:36084] CHIP:DMG: [1655382243.929377][36079:36084] CHIP:DMG: AttributeReportIB = [1655382243.929437][36079:36084] CHIP:DMG: { [1655382243.929477][36079:36084] CHIP:DMG: AttributeDataIB = [1655382243.929522][36079:36084] CHIP:DMG: { [1655382243.929623][36079:36084] CHIP:DMG: DataVersion = 0xe3033d98, [1655382243.929676][36079:36084] CHIP:DMG: AttributePathIB = [1655382243.929724][36079:36084] CHIP:DMG: { [1655382243.929773][36079:36084] CHIP:DMG: Endpoint = 0x0, [1655382243.929838][36079:36084] CHIP:DMG: Cluster = 0x30, [1655382243.929891][36079:36084] CHIP:DMG: Attribute = 0x0000_0002, [1655382243.929943][36079:36084] CHIP:DMG: } [1655382243.929993][36079:36084] CHIP:DMG: [1655382243.930046][36079:36084] CHIP:DMG: Data = 0, [1655382243.930093][36079:36084] CHIP:DMG: }, [1655382243.930143][36079:36084] CHIP:DMG: [1655382243.930180][36079:36084] CHIP:DMG: }, [1655382243.930232][36079:36084] CHIP:DMG: [1655382243.930268][36079:36084] CHIP:DMG: AttributeReportIB = [1655382243.930317][36079:36084] CHIP:DMG: { [1655382243.930357][36079:36084] CHIP:DMG: AttributeDataIB = [1655382243.930402][36079:36084] CHIP:DMG: { [1655382243.930448][36079:36084] CHIP:DMG: DataVersion = 0xe3033d98, [1655382243.930499][36079:36084] CHIP:DMG: AttributePathIB = [1655382243.930547][36079:36084] CHIP:DMG: { [1655382243.930596][36079:36084] CHIP:DMG: Endpoint = 0x0, [1655382243.930649][36079:36084] CHIP:DMG: Cluster = 0x30, [1655382243.930704][36079:36084] CHIP:DMG: Attribute = 0x0000_0001, [1655382243.930753][36079:36084] CHIP:DMG: } [1655382243.930803][36079:36084] CHIP:DMG: [1655382243.930850][36079:36084] CHIP:DMG: Data = [1655382243.930899][36079:36084] CHIP:DMG: { [1655382243.930989][36079:36084] CHIP:DMG: 0x0 = 60, [1655382243.931047][36079:36084] CHIP:DMG: }, [1655382243.931096][36079:36084] CHIP:DMG: }, [1655382243.931146][36079:36084] CHIP:DMG: [1655382243.931186][36079:36084] CHIP:DMG: }, [1655382243.931238][36079:36084] CHIP:DMG: [1655382243.931273][36079:36084] CHIP:DMG: AttributeReportIB = [1655382243.931321][36079:36084] CHIP:DMG: { [1655382243.931361][36079:36084] CHIP:DMG: AttributeDataIB = [1655382243.931406][36079:36084] CHIP:DMG: { [1655382243.931452][36079:36084] CHIP:DMG: DataVersion = 0xe3033d98, [1655382243.931499][36079:36084] CHIP:DMG: AttributePathIB = [1655382243.931550][36079:36084] CHIP:DMG: { [1655382243.931598][36079:36084] CHIP:DMG: Endpoint = 0x0, [1655382243.931649][36079:36084] CHIP:DMG: Cluster = 0x30, [1655382243.931702][36079:36084] CHIP:DMG: Attribute = 0x0000_0000, [1655382243.931754][36079:36084] CHIP:DMG: } [1655382243.931805][36079:36084] CHIP:DMG: [1655382243.931857][36079:36084] CHIP:DMG: Data = 0, [1655382243.931903][36079:36084] CHIP:DMG: }, [1655382243.931952][36079:36084] CHIP:DMG: [1655382243.931993][36079:36084] CHIP:DMG: }, [1655382243.932046][36079:36084] CHIP:DMG: [1655382243.932082][36079:36084] CHIP:DMG: AttributeReportIB = [1655382243.932128][36079:36084] CHIP:DMG: { [1655382243.932168][36079:36084] CHIP:DMG: AttributeDataIB = [1655382243.932212][36079:36084] CHIP:DMG: { [1655382243.932258][36079:36084] CHIP:DMG: DataVersion = 0x3193f08a, [1655382243.932305][36079:36084] CHIP:DMG: AttributePathIB = [1655382243.932352][36079:36084] CHIP:DMG: { [1655382243.932402][36079:36084] CHIP:DMG: Endpoint = 0x0, [1655382243.932453][36079:36084] CHIP:DMG: Cluster = 0x31, [1655382243.932504][36079:36084] CHIP:DMG: Attribute = 0x0000_FFFC, [1655382243.932556][36079:36084] CHIP:DMG: } [1655382243.932607][36079:36084] CHIP:DMG: [1655382243.932654][36079:36084] CHIP:DMG: Data = 1, [1655382243.932703][36079:36084] CHIP:DMG: }, [1655382243.932752][36079:36084] CHIP:DMG: [1655382243.932792][36079:36084] CHIP:DMG: }, [1655382243.932838][36079:36084] CHIP:DMG: [1655382243.932873][36079:36084] CHIP:DMG: ], [1655382243.932954][36079:36084] CHIP:DMG: [1655382243.932992][36079:36084] CHIP:DMG: SuppressResponse = true, [1655382243.933029][36079:36084] CHIP:DMG: InteractionModelRevision = 1 [1655382243.933063][36079:36084] CHIP:DMG: } [1655382243.934134][36079:36084] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo' [1655382243.934183][36079:36084] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe' [1655382243.934224][36079:36084] CHIP:CTL: Performing next commissioning step 'ArmFailSafe' [1655382243.934256][36079:36084] CHIP:CTL: Arming failsafe (60 seconds) [1655382243.934331][36079:36084] CHIP:DMG: ICR moving to [AddingComm] [1655382243.934375][36079:36084] CHIP:DMG: ICR moving to [AddedComma] [1655382243.934538][36079:36084] CHIP:IN: Prepared secure message 0xaaaaf7021a38 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 12132i with MessageCounter:75986121. [1655382243.934595][36079:36084] CHIP:IN: Sending encrypted msg 0xaaaaf7021a38 with MessageCounter:75986121 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F734 msec [1655382243.934805][36079:36084] CHIP:DMG: ICR moving to [CommandSen] [1655382243.934856][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:62892706 on exchange 12131i [1655382243.934943][36079:36084] CHIP:IN: Prepared secure message 0xffff91844958 to 0xFFFFFFFB00000000 (0) of type 0x10 and protocolId (0, 0) on exchange 12131i with MessageCounter:75986122. [1655382243.935077][36079:36084] CHIP:IN: Sending encrypted msg 0xffff91844958 with MessageCounter:75986122 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F735 msec [1655382243.935184][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:62892706 on exchange 12131i [1655382243.943265][36079:36084] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:62892707 on exchange 12132i [1655382243.943312][36079:36084] CHIP:EM: Found matching exchange: 12132i, Delegate: 0xffff7c00ed80 [1655382243.943359][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:75986121 from Retrans Table on exchange 12132i [1655382243.943396][36079:36084] CHIP:EM: Removed CHIP MessageCounter:75986121 from RetransTable on exchange 12132i [1655382243.943439][36079:36084] CHIP:DMG: ICR moving to [ResponseRe] [1655382243.943515][36079:36084] CHIP:DMG: InvokeResponseMessage = [1655382243.943553][36079:36084] CHIP:DMG: { [1655382243.943605][36079:36084] CHIP:DMG: suppressResponse = false, [1655382243.943641][36079:36084] CHIP:DMG: InvokeResponseIBs = [1655382243.943702][36079:36084] CHIP:DMG: [ [1655382243.943739][36079:36084] CHIP:DMG: InvokeResponseIB = [1655382243.943802][36079:36084] CHIP:DMG: { [1655382243.943840][36079:36084] CHIP:DMG: CommandDataIB = [1655382243.943900][36079:36084] CHIP:DMG: { [1655382243.943955][36079:36084] CHIP:DMG: CommandPathIB = [1655382243.944011][36079:36084] CHIP:DMG: { [1655382243.944078][36079:36084] CHIP:DMG: EndpointId = 0x0, [1655382243.944150][36079:36084] CHIP:DMG: ClusterId = 0x30, [1655382243.944202][36079:36084] CHIP:DMG: CommandId = 0x1, [1655382243.944266][36079:36084] CHIP:DMG: }, [1655382243.944331][36079:36084] CHIP:DMG: [1655382243.944375][36079:36084] CHIP:DMG: CommandFields = [1655382243.944436][36079:36084] CHIP:DMG: { [1655382243.944503][36079:36084] CHIP:DMG: 0x0 = 0, [1655382243.944559][36079:36084] CHIP:DMG: 0x1 = "", [1655382243.944624][36079:36084] CHIP:DMG: }, [1655382243.944682][36079:36084] CHIP:DMG: }, [1655382243.944733][36079:36084] CHIP:DMG: [1655382243.944784][36079:36084] CHIP:DMG: }, [1655382243.944831][36079:36084] CHIP:DMG: [1655382243.944878][36079:36084] CHIP:DMG: ], [1655382243.944923][36079:36084] CHIP:DMG: [1655382243.944971][36079:36084] CHIP:DMG: InteractionModelRevision = 1 [1655382243.945006][36079:36084] CHIP:DMG: }, [1655382243.945103][36079:36084] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1655382243.945189][36079:36084] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1655382243.945229][36079:36084] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe' [1655382243.945275][36079:36084] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1655382243.945313][36079:36084] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory' [1655382243.945356][36079:36084] CHIP:CTL: Setting Regulatory Config [1655382243.945387][36079:36084] CHIP:CTL: No regulatory config supplied by controller, leaving as device default (0) [1655382243.945468][36079:36084] CHIP:DMG: ICR moving to [AddingComm] [1655382243.945514][36079:36084] CHIP:DMG: ICR moving to [AddedComma] [1655382243.945641][36079:36084] CHIP:IN: Prepared secure message 0xaaaaf7021a38 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 12133i with MessageCounter:75986123. [1655382243.945693][36079:36084] CHIP:IN: Sending encrypted msg 0xaaaaf7021a38 with MessageCounter:75986123 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F73F msec [1655382243.945914][36079:36084] CHIP:DMG: ICR moving to [CommandSen] [1655382243.945985][36079:36084] CHIP:DMG: ICR moving to [AwaitingDe] [1655382243.946029][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:62892707 on exchange 12132i [1655382243.946094][36079:36084] CHIP:IN: Prepared secure message 0xffff91844958 to 0xFFFFFFFB00000000 (0) of type 0x10 and protocolId (0, 0) on exchange 12132i with MessageCounter:75986124. [1655382243.946145][36079:36084] CHIP:IN: Sending encrypted msg 0xffff91844958 with MessageCounter:75986124 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F740 msec [1655382243.946257][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:62892707 on exchange 12132i [1655382243.961938][36079:36084] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:62892708 on exchange 12133i [1655382243.961986][36079:36084] CHIP:EM: Found matching exchange: 12133i, Delegate: 0xffff7c00eed0 [1655382243.962033][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:75986123 from Retrans Table on exchange 12133i [1655382243.962069][36079:36084] CHIP:EM: Removed CHIP MessageCounter:75986123 from RetransTable on exchange 12133i [1655382243.962112][36079:36084] CHIP:DMG: ICR moving to [ResponseRe] [1655382243.962184][36079:36084] CHIP:DMG: InvokeResponseMessage = [1655382243.962221][36079:36084] CHIP:DMG: { [1655382243.962257][36079:36084] CHIP:DMG: suppressResponse = false, [1655382243.962306][36079:36084] CHIP:DMG: InvokeResponseIBs = [1655382243.962352][36079:36084] CHIP:DMG: [ [1655382243.962399][36079:36084] CHIP:DMG: InvokeResponseIB = [1655382243.962463][36079:36084] CHIP:DMG: { [1655382243.962502][36079:36084] CHIP:DMG: CommandDataIB = [1655382243.962559][36079:36084] CHIP:DMG: { [1655382243.962603][36079:36084] CHIP:DMG: CommandPathIB = [1655382243.962666][36079:36084] CHIP:DMG: { [1655382243.962728][36079:36084] CHIP:DMG: EndpointId = 0x0, [1655382243.962782][36079:36084] CHIP:DMG: ClusterId = 0x30, [1655382243.962846][36079:36084] CHIP:DMG: CommandId = 0x3, [1655382243.962911][36079:36084] CHIP:DMG: }, [1655382243.962963][36079:36084] CHIP:DMG: [1655382243.963021][36079:36084] CHIP:DMG: CommandFields = [1655382243.963074][36079:36084] CHIP:DMG: { [1655382243.963142][36079:36084] CHIP:DMG: 0x0 = 0, [1655382243.963215][36079:36084] CHIP:DMG: 0x1 = "", [1655382243.963284][36079:36084] CHIP:DMG: }, [1655382243.963331][36079:36084] CHIP:DMG: }, [1655382243.963398][36079:36084] CHIP:DMG: [1655382243.963451][36079:36084] CHIP:DMG: }, [1655382243.963497][36079:36084] CHIP:DMG: [1655382243.963544][36079:36084] CHIP:DMG: ], [1655382243.963589][36079:36084] CHIP:DMG: [1655382243.963638][36079:36084] CHIP:DMG: InteractionModelRevision = 1 [1655382243.963674][36079:36084] CHIP:DMG: }, [1655382243.963770][36079:36084] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1655382243.963843][36079:36084] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0 [1655382243.963890][36079:36084] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory' [1655382243.963948][36079:36084] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest' [1655382243.963996][36079:36084] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest' [1655382243.964035][36079:36084] CHIP:CTL: Sending request for PAI certificate [1655382243.964071][36079:36084] CHIP:CTL: Sending Certificate Chain request to 0xffff7c00c830 device [1655382243.964197][36079:36084] CHIP:DMG: ICR moving to [AddingComm] [1655382243.964249][36079:36084] CHIP:DMG: ICR moving to [AddedComma] [1655382243.964382][36079:36084] CHIP:IN: Prepared secure message 0xaaaaf7021a38 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 12134i with MessageCounter:75986125. [1655382243.964464][36079:36084] CHIP:IN: Sending encrypted msg 0xaaaaf7021a38 with MessageCounter:75986125 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F752 msec [1655382243.964725][36079:36084] CHIP:DMG: ICR moving to [CommandSen] [1655382243.964813][36079:36084] CHIP:DMG: ICR moving to [AwaitingDe] [1655382243.964866][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:62892708 on exchange 12133i [1655382243.964946][36079:36084] CHIP:IN: Prepared secure message 0xffff91844958 to 0xFFFFFFFB00000000 (0) of type 0x10 and protocolId (0, 0) on exchange 12133i with MessageCounter:75986126. [1655382243.965025][36079:36084] CHIP:IN: Sending encrypted msg 0xffff91844958 with MessageCounter:75986126 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F753 msec [1655382243.965167][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:62892708 on exchange 12133i [1655382243.974247][36079:36084] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:62892709 on exchange 12134i [1655382243.974303][36079:36084] CHIP:EM: Found matching exchange: 12134i, Delegate: 0xffff7c00ed80 [1655382243.974362][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:75986125 from Retrans Table on exchange 12134i [1655382243.974407][36079:36084] CHIP:EM: Removed CHIP MessageCounter:75986125 from RetransTable on exchange 12134i [1655382243.974459][36079:36084] CHIP:DMG: ICR moving to [ResponseRe] [1655382243.974544][36079:36084] CHIP:DMG: InvokeResponseMessage = [1655382243.974589][36079:36084] CHIP:DMG: { [1655382243.974648][36079:36084] CHIP:DMG: suppressResponse = false, [1655382243.974695][36079:36084] CHIP:DMG: InvokeResponseIBs = [1655382243.974767][36079:36084] CHIP:DMG: [ [1655382243.974813][36079:36084] CHIP:DMG: InvokeResponseIB = [1655382243.974892][36079:36084] CHIP:DMG: { [1655382243.974946][36079:36084] CHIP:DMG: CommandDataIB = [1655382243.975019][36079:36084] CHIP:DMG: { [1655382243.975075][36079:36084] CHIP:DMG: CommandPathIB = [1655382243.975158][36079:36084] CHIP:DMG: { [1655382243.975234][36079:36084] CHIP:DMG: EndpointId = 0x0, [1655382243.975301][36079:36084] CHIP:DMG: ClusterId = 0x3e, [1655382243.975380][36079:36084] CHIP:DMG: CommandId = 0x3, [1655382243.975457][36079:36084] CHIP:DMG: }, [1655382243.975514][36079:36084] CHIP:DMG: [1655382243.975580][36079:36084] CHIP:DMG: CommandFields = [1655382243.975633][36079:36084] CHIP:DMG: { [1655382243.975687][36079:36084] CHIP:DMG: 0x0 = [ [1655382243.975949][36079:36084] CHIP:DMG: 0x30, 0x82, 0x1, 0xcb, 0x30, 0x82, 0x1, 0x71, 0xa0, 0x3, 0x2, 0x1, 0x2, 0x2, 0x8, 0x56, 0xad, 0x82, 0x22, 0xad, 0x94, 0x5b, 0x64, 0x30, 0xa, 0x6, 0x8, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x4, 0x3, 0x2, 0x30, 0x30, 0x31, 0x18, 0x30, 0x16, 0x6, 0x3, 0x55, 0x4, 0x3, 0xc, 0xf, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x54, 0x65, 0x73, 0x74, 0x20, 0x50, 0x41, 0x41, 0x31, 0x14, 0x30, 0x12, 0x6, 0xa, 0x2b, 0x6, 0x1, 0x4, 0x1, 0x82, 0xa2, 0x7c, 0x2, 0x1, 0xc, 0x4, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0xd, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0xf, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x6, 0x3, 0x55, 0x4, 0x3, 0xc, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x6, 0xa, 0x2b, 0x6, 0x1, 0x4, 0x1, 0x82, 0xa2, 0x7c, 0x2, 0x1, 0xc, 0x4, 0x46, 0x46, 0x46, 0x31, 0x30, 0x59, 0x30, 0x13, 0x6, 0x7, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x2, 0x1, 0x6, 0x8, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x3, 0x1, 0x7, 0x3, 0x42, 0x0, 0x4, 0x41, 0x9a, 0x93, 0x15, 0xc2, 0x17, 0x3e, 0xc, 0x8c, 0x87, 0x6d, 0x3, 0xcc, 0xfc, 0x94, 0x48, 0x52, 0x64, 0x7f, 0x7f, 0xec, 0x5e, 0x50, 0x82, 0xf4, 0x5, 0x99, 0x28, 0xec, 0xa8, 0x94, 0xc5, 0x94, 0x15, 0x13, 0x9, 0xac, 0x63, 0x1e, 0x4c, 0xb0, 0x33, 0x92, 0xaf, 0x68, 0x4b, 0xb, 0xaf, 0xb7, 0xe6, 0x5b, 0x3b, 0x81, 0x62, 0xc2, 0xf5, 0x2b, 0xf9, 0x31, 0xb8, 0xe7, 0x7a, 0xaa, 0x82, 0xa3, 0x66, 0x30, 0x64, 0x30, 0x12, 0x6, 0x3, 0x55, 0x1d, 0x13, 0x1, 0x1, 0xff, 0x4, 0x8, 0x30, 0x6, 0x1, 0x1, 0xff, 0x2, 0x1, [1655382243.976067][36079:36084] CHIP:DMG: ] [1655382243.976121][36079:36084] CHIP:DMG: }, [1655382243.976177][36079:36084] CHIP:DMG: }, [1655382243.976234][36079:36084] CHIP:DMG: [1655382243.976298][36079:36084] CHIP:DMG: }, [1655382243.976350][36079:36084] CHIP:DMG: [1655382243.976402][36079:36084] CHIP:DMG: ], [1655382243.976452][36079:36084] CHIP:DMG: [1655382243.976505][36079:36084] CHIP:DMG: InteractionModelRevision = 1 [1655382243.976544][36079:36084] CHIP:DMG: }, [1655382243.976650][36079:36084] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1655382243.976716][36079:36084] CHIP:CTL: Received certificate chain from the device [1655382243.976757][36079:36084] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest' [1655382243.976813][36079:36084] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1655382243.976855][36079:36084] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest' [1655382243.976907][36079:36084] CHIP:CTL: Sending request for DAC certificate [1655382243.976941][36079:36084] CHIP:CTL: Sending Certificate Chain request to 0xffff7c00c830 device [1655382243.977026][36079:36084] CHIP:DMG: ICR moving to [AddingComm] [1655382243.977070][36079:36084] CHIP:DMG: ICR moving to [AddedComma] [1655382243.977189][36079:36084] CHIP:IN: Prepared secure message 0xaaaaf7021a38 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 12135i with MessageCounter:75986127. [1655382243.977264][36079:36084] CHIP:IN: Sending encrypted msg 0xaaaaf7021a38 with MessageCounter:75986127 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F75F msec [1655382243.977470][36079:36084] CHIP:DMG: ICR moving to [CommandSen] [1655382243.977547][36079:36084] CHIP:DMG: ICR moving to [AwaitingDe] [1655382243.977610][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:62892709 on exchange 12134i [1655382243.977684][36079:36084] CHIP:IN: Prepared secure message 0xffff91844958 to 0xFFFFFFFB00000000 (0) of type 0x10 and protocolId (0, 0) on exchange 12134i with MessageCounter:75986128. [1655382243.977756][36079:36084] CHIP:IN: Sending encrypted msg 0xffff91844958 with MessageCounter:75986128 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F75F msec [1655382243.977918][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:62892709 on exchange 12134i [1655382243.989853][36079:36084] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:62892710 on exchange 12135i [1655382243.989903][36079:36084] CHIP:EM: Found matching exchange: 12135i, Delegate: 0xffff7c00eed0 [1655382243.989955][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:75986127 from Retrans Table on exchange 12135i [1655382243.989994][36079:36084] CHIP:EM: Removed CHIP MessageCounter:75986127 from RetransTable on exchange 12135i [1655382243.990041][36079:36084] CHIP:DMG: ICR moving to [ResponseRe] [1655382243.990120][36079:36084] CHIP:DMG: InvokeResponseMessage = [1655382243.990161][36079:36084] CHIP:DMG: { [1655382243.990214][36079:36084] CHIP:DMG: suppressResponse = false, [1655382243.990255][36079:36084] CHIP:DMG: InvokeResponseIBs = [1655382243.990319][36079:36084] CHIP:DMG: [ [1655382243.990360][36079:36084] CHIP:DMG: InvokeResponseIB = [1655382243.990430][36079:36084] CHIP:DMG: { [1655382243.990476][36079:36084] CHIP:DMG: CommandDataIB = [1655382243.990542][36079:36084] CHIP:DMG: { [1655382243.990592][36079:36084] CHIP:DMG: CommandPathIB = [1655382243.990665][36079:36084] CHIP:DMG: { [1655382243.990736][36079:36084] CHIP:DMG: EndpointId = 0x0, [1655382243.990795][36079:36084] CHIP:DMG: ClusterId = 0x3e, [1655382243.990867][36079:36084] CHIP:DMG: CommandId = 0x3, [1655382243.990935][36079:36084] CHIP:DMG: }, [1655382243.990991][36079:36084] CHIP:DMG: [1655382243.991053][36079:36084] CHIP:DMG: CommandFields = [1655382243.991133][36079:36084] CHIP:DMG: { [1655382243.991194][36079:36084] CHIP:DMG: 0x0 = [ [1655382243.991478][36079:36084] CHIP:DMG: 0x30, 0x82, 0x1, 0xe7, 0x30, 0x82, 0x1, 0x8e, 0xa0, 0x3, 0x2, 0x1, 0x2, 0x2, 0x8, 0x69, 0xcd, 0xf1, 0xd, 0xe9, 0xe5, 0x4e, 0xd1, 0x30, 0xa, 0x6, 0x8, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x4, 0x3, 0x2, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x6, 0x3, 0x55, 0x4, 0x3, 0xc, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x6, 0xa, 0x2b, 0x6, 0x1, 0x4, 0x1, 0x82, 0xa2, 0x7c, 0x2, 0x1, 0xc, 0x4, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0xd, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0xf, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x53, 0x31, 0x25, 0x30, 0x23, 0x6, 0x3, 0x55, 0x4, 0x3, 0xc, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x2f, 0x30, 0x78, 0x38, 0x30, 0x30, 0x31, 0x31, 0x14, 0x30, 0x12, 0x6, 0xa, 0x2b, 0x6, 0x1, 0x4, 0x1, 0x82, 0xa2, 0x7c, 0x2, 0x1, 0xc, 0x4, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x6, 0xa, 0x2b, 0x6, 0x1, 0x4, 0x1, 0x82, 0xa2, 0x7c, 0x2, 0x2, 0xc, 0x4, 0x38, 0x30, 0x30, 0x31, 0x30, 0x59, 0x30, 0x13, 0x6, 0x7, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x2, 0x1, 0x6, 0x8, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x3, 0x1, 0x7, 0x3, 0x42, 0x0, 0x4, 0x46, 0x3a, 0xc6, 0x93, 0x42, 0x91, 0xa, 0xe, 0x55, 0x88, 0xfc, 0x6f, 0xf5, 0x6b, 0xb6, 0x3e, 0x62, 0xec, 0xce, 0xcb, 0x14, 0x8f, 0x7d, 0x4e, 0xb0, 0x3e, 0xe5, 0x52, 0x60, 0x14, 0x15, 0x76, 0x7d, 0x16, 0xa5, 0xc6, 0x63, 0xf7, 0x93, 0xe4, 0x91, 0x23, 0x26, 0xb, 0x82, 0x97, 0xa7, 0xcd, 0x7e, 0x7c, 0xfc, 0x7 [1655382243.991609][36079:36084] CHIP:DMG: ] [1655382243.991670][36079:36084] CHIP:DMG: }, [1655382243.991732][36079:36084] CHIP:DMG: }, [1655382243.991795][36079:36084] CHIP:DMG: [1655382243.991867][36079:36084] CHIP:DMG: }, [1655382243.991925][36079:36084] CHIP:DMG: [1655382243.991984][36079:36084] CHIP:DMG: ], [1655382243.992040][36079:36084] CHIP:DMG: [1655382243.992100][36079:36084] CHIP:DMG: InteractionModelRevision = 1 [1655382243.992144][36079:36084] CHIP:DMG: }, [1655382243.992271][36079:36084] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1655382243.992345][36079:36084] CHIP:CTL: Received certificate chain from the device [1655382243.992390][36079:36084] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest' [1655382243.992436][36079:36084] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1655382243.992501][36079:36084] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest' [1655382243.992541][36079:36084] CHIP:CTL: Sending Attestation Request to the device. [1655382243.992594][36079:36084] CHIP:CTL: Sending Attestation request to 0xffff7c00c830 device [1655382243.992674][36079:36084] CHIP:DMG: ICR moving to [AddingComm] [1655382243.992743][36079:36084] CHIP:DMG: ICR moving to [AddedComma] [1655382243.992866][36079:36084] CHIP:IN: Prepared secure message 0xaaaaf7021a38 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 12136i with MessageCounter:75986129. [1655382243.992933][36079:36084] CHIP:IN: Sending encrypted msg 0xaaaaf7021a38 with MessageCounter:75986129 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F76F msec [1655382243.993158][36079:36084] CHIP:DMG: ICR moving to [CommandSen] [1655382243.993230][36079:36084] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information [1655382243.993303][36079:36084] CHIP:DMG: ICR moving to [AwaitingDe] [1655382243.993354][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:62892710 on exchange 12135i [1655382243.993434][36079:36084] CHIP:IN: Prepared secure message 0xffff91844958 to 0xFFFFFFFB00000000 (0) of type 0x10 and protocolId (0, 0) on exchange 12135i with MessageCounter:75986130. [1655382243.993514][36079:36084] CHIP:IN: Sending encrypted msg 0xffff91844958 with MessageCounter:75986130 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F76F msec [1655382243.993655][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:62892710 on exchange 12135i [1655382244.000727][36079:36084] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:62892711 on exchange 12136i [1655382244.000785][36079:36084] CHIP:EM: Found matching exchange: 12136i, Delegate: 0xffff7c00ed80 [1655382244.000844][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:75986129 from Retrans Table on exchange 12136i [1655382244.000888][36079:36084] CHIP:EM: Removed CHIP MessageCounter:75986129 from RetransTable on exchange 12136i [1655382244.000941][36079:36084] CHIP:DMG: ICR moving to [ResponseRe] [1655382244.001030][36079:36084] CHIP:DMG: InvokeResponseMessage = [1655382244.001076][36079:36084] CHIP:DMG: { [1655382244.001136][36079:36084] CHIP:DMG: suppressResponse = false, [1655382244.001182][36079:36084] CHIP:DMG: InvokeResponseIBs = [1655382244.001255][36079:36084] CHIP:DMG: [ [1655382244.001301][36079:36084] CHIP:DMG: InvokeResponseIB = [1655382244.001382][36079:36084] CHIP:DMG: { [1655382244.001435][36079:36084] CHIP:DMG: CommandDataIB = [1655382244.001509][36079:36084] CHIP:DMG: { [1655382244.001564][36079:36084] CHIP:DMG: CommandPathIB = [1655382244.001642][36079:36084] CHIP:DMG: { [1655382244.001717][36079:36084] CHIP:DMG: EndpointId = 0x0, [1655382244.001784][36079:36084] CHIP:DMG: ClusterId = 0x3e, [1655382244.001898][36079:36084] CHIP:DMG: CommandId = 0x1, [1655382244.001969][36079:36084] CHIP:DMG: }, [1655382244.002027][36079:36084] CHIP:DMG: [1655382244.002077][36079:36084] CHIP:DMG: CommandFields = [1655382244.002147][36079:36084] CHIP:DMG: { [1655382244.002202][36079:36084] CHIP:DMG: 0x0 = [ [1655382244.002473][36079:36084] CHIP:DMG: 0x15, 0x31, 0x1, 0x1d, 0x2, 0x30, 0x82, 0x2, 0x19, 0x6, 0x9, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0xd, 0x1, 0x7, 0x2, 0xa0, 0x82, 0x2, 0xa, 0x30, 0x82, 0x2, 0x6, 0x2, 0x1, 0x3, 0x31, 0xd, 0x30, 0xb, 0x6, 0x9, 0x60, 0x86, 0x48, 0x1, 0x65, 0x3, 0x4, 0x2, 0x1, 0x30, 0x82, 0x1, 0x71, 0x6, 0x9, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0xd, 0x1, 0x7, 0x1, 0xa0, 0x82, 0x1, 0x62, 0x4, 0x82, 0x1, 0x5e, 0x15, 0x24, 0x0, 0x1, 0x25, 0x1, 0xf1, 0xff, 0x36, 0x2, 0x5, 0x0, 0x80, 0x5, 0x1, 0x80, 0x5, 0x2, 0x80, 0x5, 0x3, 0x80, 0x5, 0x4, 0x80, 0x5, 0x5, 0x80, 0x5, 0x6, 0x80, 0x5, 0x7, 0x80, 0x5, 0x8, 0x80, 0x5, 0x9, 0x80, 0x5, 0xa, 0x80, 0x5, 0xb, 0x80, 0x5, 0xc, 0x80, 0x5, 0xd, 0x80, 0x5, 0xe, 0x80, 0x5, 0xf, 0x80, 0x5, 0x10, 0x80, 0x5, 0x11, 0x80, 0x5, 0x12, 0x80, 0x5, 0x13, 0x80, 0x5, 0x14, 0x80, 0x5, 0x15, 0x80, 0x5, 0x16, 0x80, 0x5, 0x17, 0x80, 0x5, 0x18, 0x80, 0x5, 0x19, 0x80, 0x5, 0x1a, 0x80, 0x5, 0x1b, 0x80, 0x5, 0x1c, 0x80, 0x5, 0x1d, 0x80, 0x5, 0x1e, 0x80, 0x5, 0x1f, 0x80, 0x5, 0x20, 0x80, 0x5, 0x21, 0x80, 0x5, 0x22, 0x80, 0x5, 0x23, 0x80, 0x5, 0x24, 0x80, 0x5, 0x25, 0x80, 0x5, 0x26, 0x80, 0x5, 0x27, 0x80, 0x5, 0x28, 0x80, 0x5, 0x29, 0x80, 0x5, 0x2a, 0x80, 0x5, 0x2b, 0x80, 0x5, 0x2c, 0x80, 0x5, 0x2d, 0x80, 0x5, 0x2e, 0x80, 0x5, 0x2f, 0x80, 0x5, 0x30, 0x80, 0x5, 0x31, 0x80, 0x5, 0x32, 0x80, 0x5, 0x33, 0x80, 0x5, 0x34, 0x80, 0x5, 0x35, 0x80, 0x5, 0x36, 0x80, 0x5, 0x37, 0x80, 0x5, 0x38, 0x80, 0x5, 0x39, 0x80, 0x5, 0x3a, 0x80, 0x5, 0x3b, 0x80, 0x5, 0x3c, 0x80, 0x5, 0x3d, 0x80, 0x5, 0x3e, 0x80, 0x5, 0x3f, 0x80, 0x5, 0x40, 0x80, 0x5, 0x41, 0x80, 0x5, 0x42, 0x80, 0x5, 0x43, 0x80, 0x5, 0x44, 0x80, 0x5, 0x45, 0x80, 0x5, 0x46, 0x80, 0x5, 0x47, 0x80, 0x5, 0x48, 0x80, 0x5, 0x49, 0x80, 0x5, 0x4a, 0x80, 0x5, 0x [1655382244.002592][36079:36084] CHIP:DMG: ] [1655382244.002647][36079:36084] CHIP:DMG: 0x1 = [ [1655382244.002737][36079:36084] CHIP:DMG: 0xbe, 0x64, 0xc3, 0x6f, 0xa4, 0x9e, 0x14, 0xbc, 0xc7, 0x60, 0x80, 0x7f, 0x2d, 0x76, 0x66, 0xf, 0xc4, 0x3e, 0x6a, 0x6a, 0xc9, 0xf1, 0xb0, 0x9, 0xce, 0xc1, 0x9e, 0xfa, 0xba, 0xd, 0xa5, 0x46, 0x28, 0x6, 0x8f, 0xe1, 0xb, 0xb8, 0xe, 0x45, 0xe4, 0xc1, 0x43, 0x10, 0xde, 0x8d, 0xc0, 0x3a, 0x6b, 0x5f, 0xb4, 0xb2, 0x53, 0x93, 0x7a, 0x90, 0x9, 0x3f, 0x7e, 0x74, 0x35, 0xd, 0x5d, 0x9a, [1655382244.002825][36079:36084] CHIP:DMG: ] [1655382244.002880][36079:36084] CHIP:DMG: }, [1655382244.002951][36079:36084] CHIP:DMG: }, [1655382244.003007][36079:36084] CHIP:DMG: [1655382244.003067][36079:36084] CHIP:DMG: }, [1655382244.003135][36079:36084] CHIP:DMG: [1655382244.003176][36079:36084] CHIP:DMG: ], [1655382244.003241][36079:36084] CHIP:DMG: [1655382244.003282][36079:36084] CHIP:DMG: InteractionModelRevision = 1 [1655382244.003334][36079:36084] CHIP:DMG: }, [1655382244.003443][36079:36084] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1655382244.003498][36079:36084] CHIP:CTL: Received Attestation Information from the device [1655382244.003554][36079:36084] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest' [1655382244.003642][36079:36084] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1655382244.003703][36079:36084] CHIP:CTL: Performing next commissioning step 'AttestationVerification' [1655382244.003740][36079:36084] CHIP:CTL: Verifying attestation [1655382244.011962][36079:36084] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device. [1655382244.012146][36079:36084] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification' [1655382244.012189][36079:36084] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest' [1655382244.012236][36079:36084] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest' [1655382244.012297][36079:36084] CHIP:CTL: Sending CSR request to 0xffff7c00c830 device [1655382244.012409][36079:36084] CHIP:DMG: ICR moving to [AddingComm] [1655382244.012461][36079:36084] CHIP:DMG: ICR moving to [AddedComma] [1655382244.012622][36079:36084] CHIP:IN: Prepared secure message 0xaaaaf7021a38 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 12137i with MessageCounter:75986131. [1655382244.012703][36079:36084] CHIP:IN: Sending encrypted msg 0xaaaaf7021a38 with MessageCounter:75986131 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F782 msec [1655382244.012959][36079:36084] CHIP:DMG: ICR moving to [CommandSen] [1655382244.013009][36079:36084] CHIP:CTL: Sent CSR request, waiting for the CSR [1655382244.013087][36079:36084] CHIP:DMG: ICR moving to [AwaitingDe] [1655382244.013135][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:62892711 on exchange 12136i [1655382244.013226][36079:36084] CHIP:IN: Prepared secure message 0xffff91844958 to 0xFFFFFFFB00000000 (0) of type 0x10 and protocolId (0, 0) on exchange 12136i with MessageCounter:75986132. [1655382244.013283][36079:36084] CHIP:IN: Sending encrypted msg 0xffff91844958 with MessageCounter:75986132 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F783 msec [1655382244.013424][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:62892711 on exchange 12136i [1655382244.017986][36079:36084] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:62892712 on exchange 12137i [1655382244.018020][36079:36084] CHIP:EM: Found matching exchange: 12137i, Delegate: 0xffff7c00eed0 [1655382244.018053][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:75986131 from Retrans Table on exchange 12137i [1655382244.018077][36079:36084] CHIP:EM: Removed CHIP MessageCounter:75986131 from RetransTable on exchange 12137i [1655382244.018106][36079:36084] CHIP:DMG: ICR moving to [ResponseRe] [1655382244.018162][36079:36084] CHIP:DMG: InvokeResponseMessage = [1655382244.018188][36079:36084] CHIP:DMG: { [1655382244.018223][36079:36084] CHIP:DMG: suppressResponse = false, [1655382244.018247][36079:36084] CHIP:DMG: InvokeResponseIBs = [1655382244.018290][36079:36084] CHIP:DMG: [ [1655382244.018314][36079:36084] CHIP:DMG: InvokeResponseIB = [1655382244.018357][36079:36084] CHIP:DMG: { [1655382244.018383][36079:36084] CHIP:DMG: CommandDataIB = [1655382244.018428][36079:36084] CHIP:DMG: { [1655382244.018466][36079:36084] CHIP:DMG: CommandPathIB = [1655382244.018501][36079:36084] CHIP:DMG: { [1655382244.018544][36079:36084] CHIP:DMG: EndpointId = 0x0, [1655382244.018592][36079:36084] CHIP:DMG: ClusterId = 0x3e, [1655382244.018627][36079:36084] CHIP:DMG: CommandId = 0x5, [1655382244.018670][36079:36084] CHIP:DMG: }, [1655382244.018713][36079:36084] CHIP:DMG: [1655382244.018744][36079:36084] CHIP:DMG: CommandFields = [1655382244.018786][36079:36084] CHIP:DMG: { [1655382244.018821][36079:36084] CHIP:DMG: 0x0 = [ [1655382244.018956][36079:36084] CHIP:DMG: 0x15, 0x30, 0x1, 0xcb, 0x30, 0x81, 0xc8, 0x30, 0x70, 0x2, 0x1, 0x0, 0x30, 0xe, 0x31, 0xc, 0x30, 0xa, 0x6, 0x3, 0x55, 0x4, 0xa, 0xc, 0x3, 0x43, 0x53, 0x52, 0x30, 0x59, 0x30, 0x13, 0x6, 0x7, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x2, 0x1, 0x6, 0x8, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x3, 0x1, 0x7, 0x3, 0x42, 0x0, 0x4, 0x72, 0x1d, 0xce, 0x96, 0x16, 0x3b, 0xb1, 0x56, 0xde, 0x6c, 0x3a, 0x20, 0xf9, 0x85, 0xb3, 0xfd, 0x29, 0x48, 0x89, 0xd2, 0xc6, 0xa7, 0x2b, 0x71, 0x6e, 0x16, 0x44, 0xb2, 0x18, 0x5c, 0xfc, 0x8c, 0x94, 0xd, 0xdf, 0x2c, 0xfb, 0xd3, 0x3b, 0x84, 0x59, 0xc7, 0xb7, 0xd2, 0xe9, 0x41, 0xe1, 0x75, 0xe5, 0xd9, 0x70, 0x53, 0xaf, 0x6e, 0xc8, 0x4, 0xf8, 0xcd, 0x3f, 0x57, 0xf4, 0x5, 0xb8, 0xab, 0xa0, 0x0, 0x30, 0xa, 0x6, 0x8, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x4, 0x3, 0x2, 0x3, 0x48, 0x0, 0x30, 0x45, 0x2, 0x20, 0xc, 0x2d, 0xdd, 0x73, 0xd4, 0x71, 0xe8, 0xa0, 0x19, 0x4a, 0x42, 0xf7, 0x81, 0x6a, 0x3c, 0xb3, 0xc9, 0xef, 0x12, 0xf0, 0x7b, 0x6f, 0x83, 0x46, 0xfd, 0xdb, 0x4e, 0xb, 0x3d, 0x43, 0x75, 0xb5, 0x2, 0x21, 0x0, 0xb4, 0xf9, 0x0, 0xf3, 0x7b, 0xa3, 0x7d, 0x69, 0x48, 0x3c, 0xcc, 0x45, 0x3c, 0xa3, 0xa6, 0x8b, 0x68, 0xc3, 0x3c, 0xde, 0x9, 0xc2, 0x98, 0xd6, 0xf7, 0x57, 0xeb, 0xec, 0xe0, 0x7f, 0x3b, 0x2d, 0x30, 0x2, 0x20, 0xa7, 0x3f, 0xda, 0xae, 0xa2, 0x2d, 0xeb, 0xe4, 0xd6, 0xea, 0xe0, 0x33, 0x4d, 0x6d, 0x1b, 0x12, 0xab, 0xd4, 0x8f, 0xda, 0x4a, 0x7f, 0xf9, 0xdd, 0x8e, 0x96, 0xf8, 0x2c, 0x7c, 0x0, 0xa7, 0x5c, 0x18, [1655382244.019022][36079:36084] CHIP:DMG: ] [1655382244.019055][36079:36084] CHIP:DMG: 0x1 = [ [1655382244.019108][36079:36084] CHIP:DMG: 0x1, 0xda, 0x13, 0x9b, 0x1d, 0x4b, 0xe0, 0xb2, 0xe3, 0x81, 0xf4, 0x84, 0x46, 0x8, 0x57, 0x56, 0x99, 0x4d, 0xa2, 0xb6, 0x4b, 0x85, 0x5a, 0x26, 0xcd, 0xd0, 0x2a, 0x9a, 0x38, 0xbf, 0x99, 0x4f, 0x27, 0x49, 0xf7, 0xd3, 0xaa, 0xb3, 0x50, 0x8a, 0xb3, 0xb7, 0x5b, 0x59, 0xce, 0x5e, 0xe3, 0xf5, 0xc2, 0x28, 0xd8, 0x45, 0xd5, 0xd4, 0x54, 0x8, 0xc1, 0xeb, 0x91, 0x2b, 0x2a, 0xab, 0x47, 0xef, [1655382244.019161][36079:36084] CHIP:DMG: ] [1655382244.019194][36079:36084] CHIP:DMG: }, [1655382244.019235][36079:36084] CHIP:DMG: }, [1655382244.019269][36079:36084] CHIP:DMG: [1655382244.019307][36079:36084] CHIP:DMG: }, [1655382244.019339][36079:36084] CHIP:DMG: [1655382244.019372][36079:36084] CHIP:DMG: ], [1655382244.019402][36079:36084] CHIP:DMG: [1655382244.019436][36079:36084] CHIP:DMG: InteractionModelRevision = 1 [1655382244.019459][36079:36084] CHIP:DMG: }, [1655382244.019539][36079:36084] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005 [1655382244.019573][36079:36084] CHIP:CTL: Received certificate signing request from the device [1655382244.019609][36079:36084] CHIP:CTL: Successfully finished commissioning step 'SendOpCertSigningRequest' [1655382244.019633][36079:36084] CHIP:CTL: Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR' [1655382244.019660][36079:36084] CHIP:CTL: Performing next commissioning step 'ValidateCSR' [1655382244.020714][36079:36084] CHIP:CTL: Successfully finished commissioning step 'ValidateCSR' [1655382244.020750][36079:36084] CHIP:CTL: Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain' [1655382244.020775][36079:36084] CHIP:CTL: Performing next commissioning step 'GenerateNOCChain' [1655382244.020798][36079:36084] CHIP:CTL: Getting certificate chain for the device from the issuer [1655382244.021000][36079:36084] CHIP:CTL: Verifying Certificate Signing Request [1655382244.021705][36079:36084] CHIP:CTL: Generating NOC [1655382244.022025][36079:36084] CHIP:CTL: Providing certificate chain to the commissioner [1655382244.022059][36079:36084] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status ../../third_party/connectedhomeip/src/controller/ExampleOperationalCredentialsIssuer.cpp:261: Success [1655382244.022086][36079:36084] CHIP:CTL: Successfully finished commissioning step 'GenerateNOCChain' [1655382244.022249][36079:36084] CHIP:CTL: Performing next commissioning step 'SendTrustedRootCert' [1655382244.022277][36079:36084] CHIP:CTL: Sending root certificate to the device [1655382244.022339][36079:36084] CHIP:DMG: ICR moving to [AddingComm] [1655382244.022466][36079:36084] CHIP:DMG: ICR moving to [AddedComma] [1655382244.022579][36079:36084] CHIP:IN: Prepared secure message 0xaaaaf7021a38 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 12138i with MessageCounter:75986133. [1655382244.022620][36079:36084] CHIP:IN: Sending encrypted msg 0xaaaaf7021a38 with MessageCounter:75986133 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F78C msec [1655382244.022805][36079:36084] CHIP:DMG: ICR moving to [CommandSen] [1655382244.022837][36079:36084] CHIP:CTL: Sent root certificate to the device [1655382244.023101][36079:36084] CHIP:DMG: ICR moving to [AwaitingDe] [1655382244.023137][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:62892712 on exchange 12137i [1655382244.023196][36079:36084] CHIP:IN: Prepared secure message 0xffff91844958 to 0xFFFFFFFB00000000 (0) of type 0x10 and protocolId (0, 0) on exchange 12137i with MessageCounter:75986134. [1655382244.023234][36079:36084] CHIP:IN: Sending encrypted msg 0xffff91844958 with MessageCounter:75986134 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F78D msec [1655382244.023345][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:62892712 on exchange 12137i [1655382244.028784][36079:36084] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:62892713 on exchange 12138i [1655382244.028828][36079:36084] CHIP:EM: Found matching exchange: 12138i, Delegate: 0xffff7c00ed80 [1655382244.028875][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:75986133 from Retrans Table on exchange 12138i [1655382244.028908][36079:36084] CHIP:EM: Removed CHIP MessageCounter:75986133 from RetransTable on exchange 12138i [1655382244.028948][36079:36084] CHIP:DMG: ICR moving to [ResponseRe] [1655382244.029022][36079:36084] CHIP:DMG: InvokeResponseMessage = [1655382244.029056][36079:36084] CHIP:DMG: { [1655382244.029100][36079:36084] CHIP:DMG: suppressResponse = false, [1655382244.029134][36079:36084] CHIP:DMG: InvokeResponseIBs = [1655382244.029188][36079:36084] CHIP:DMG: [ [1655382244.029222][36079:36084] CHIP:DMG: InvokeResponseIB = [1655382244.029281][36079:36084] CHIP:DMG: { [1655382244.029319][36079:36084] CHIP:DMG: CommandStatusIB = [1655382244.029375][36079:36084] CHIP:DMG: { [1655382244.029414][36079:36084] CHIP:DMG: CommandPathIB = [1655382244.029472][36079:36084] CHIP:DMG: { [1655382244.029530][36079:36084] CHIP:DMG: EndpointId = 0x0, [1655382244.029580][36079:36084] CHIP:DMG: ClusterId = 0x3e, [1655382244.029638][36079:36084] CHIP:DMG: CommandId = 0xb, [1655382244.029694][36079:36084] CHIP:DMG: }, [1655382244.029744][36079:36084] CHIP:DMG: [1655382244.029796][36079:36084] CHIP:DMG: StatusIB = [1655382244.029889][36079:36084] CHIP:DMG: { [1655382244.029937][36079:36084] CHIP:DMG: status = 0x00 (SUCCESS), [1655382244.029999][36079:36084] CHIP:DMG: }, [1655382244.030056][36079:36084] CHIP:DMG: [1655382244.030096][36079:36084] CHIP:DMG: }, [1655382244.030152][36079:36084] CHIP:DMG: [1655382244.030189][36079:36084] CHIP:DMG: }, [1655382244.030244][36079:36084] CHIP:DMG: [1655382244.030276][36079:36084] CHIP:DMG: ], [1655382244.030327][36079:36084] CHIP:DMG: [1655382244.030359][36079:36084] CHIP:DMG: InteractionModelRevision = 1 [1655382244.030406][36079:36084] CHIP:DMG: }, [1655382244.030493][36079:36084] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0 [1655382244.030531][36079:36084] CHIP:CTL: Device confirmed that it has received the root certificate [1655382244.030577][36079:36084] CHIP:CTL: Successfully finished commissioning step 'SendTrustedRootCert' [1655382244.030608][36079:36084] CHIP:CTL: Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC' [1655382244.030656][36079:36084] CHIP:CTL: Performing next commissioning step 'SendNOC' [1655382244.030745][36079:36084] CHIP:DMG: ICR moving to [AddingComm] [1655382244.030791][36079:36084] CHIP:DMG: ICR moving to [AddedComma] [1655382244.030950][36079:36084] CHIP:IN: Prepared secure message 0xaaaaf7021a38 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 12139i with MessageCounter:75986135. [1655382244.031010][36079:36084] CHIP:IN: Sending encrypted msg 0xaaaaf7021a38 with MessageCounter:75986135 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F795 msec [1655382244.031209][36079:36084] CHIP:DMG: ICR moving to [CommandSen] [1655382244.031249][36079:36084] CHIP:CTL: Sent operational certificate to the device [1655382244.031304][36079:36084] CHIP:DMG: ICR moving to [AwaitingDe] [1655382244.031343][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:62892713 on exchange 12138i [1655382244.031420][36079:36084] CHIP:IN: Prepared secure message 0xffff91844958 to 0xFFFFFFFB00000000 (0) of type 0x10 and protocolId (0, 0) on exchange 12138i with MessageCounter:75986136. [1655382244.031466][36079:36084] CHIP:IN: Sending encrypted msg 0xffff91844958 with MessageCounter:75986136 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F795 msec [1655382244.031572][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:62892713 on exchange 12138i [1655382244.087664][36079:36084] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:62892714 on exchange 12139i [1655382244.087724][36079:36084] CHIP:EM: Found matching exchange: 12139i, Delegate: 0xffff7c00eed0 [1655382244.087770][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:75986135 from Retrans Table on exchange 12139i [1655382244.087796][36079:36084] CHIP:EM: Removed CHIP MessageCounter:75986135 from RetransTable on exchange 12139i [1655382244.087832][36079:36084] CHIP:DMG: ICR moving to [ResponseRe] [1655382244.087883][36079:36084] CHIP:DMG: InvokeResponseMessage = [1655382244.087909][36079:36084] CHIP:DMG: { [1655382244.087933][36079:36084] CHIP:DMG: suppressResponse = false, [1655382244.087976][36079:36084] CHIP:DMG: InvokeResponseIBs = [1655382244.088008][36079:36084] CHIP:DMG: [ [1655382244.088042][36079:36084] CHIP:DMG: InvokeResponseIB = [1655382244.088079][36079:36084] CHIP:DMG: { [1655382244.088114][36079:36084] CHIP:DMG: CommandDataIB = [1655382244.088147][36079:36084] CHIP:DMG: { [1655382244.088185][36079:36084] CHIP:DMG: CommandPathIB = [1655382244.088231][36079:36084] CHIP:DMG: { [1655382244.088266][36079:36084] CHIP:DMG: EndpointId = 0x0, [1655382244.088311][36079:36084] CHIP:DMG: ClusterId = 0x3e, [1655382244.088354][36079:36084] CHIP:DMG: CommandId = 0x8, [1655382244.088388][36079:36084] CHIP:DMG: }, [1655382244.088430][36079:36084] CHIP:DMG: [1655382244.088460][36079:36084] CHIP:DMG: CommandFields = [1655382244.088501][36079:36084] CHIP:DMG: { [1655382244.088545][36079:36084] CHIP:DMG: 0x0 = 0, [1655382244.088581][36079:36084] CHIP:DMG: 0x1 = 1, [1655382244.088623][36079:36084] CHIP:DMG: }, [1655382244.088663][36079:36084] CHIP:DMG: }, [1655382244.088698][36079:36084] CHIP:DMG: [1655382244.088733][36079:36084] CHIP:DMG: }, [1655382244.088765][36079:36084] CHIP:DMG: [1655382244.088797][36079:36084] CHIP:DMG: ], [1655382244.088828][36079:36084] CHIP:DMG: [1655382244.088861][36079:36084] CHIP:DMG: InteractionModelRevision = 1 [1655382244.088885][36079:36084] CHIP:DMG: }, [1655382244.088957][36079:36084] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008 [1655382244.089004][36079:36084] CHIP:CTL: Device returned status 0 on receiving the NOC [1655382244.089036][36079:36084] CHIP:CTL: Operational credentials provisioned on device 0xffff7c00c830 [1655382244.089062][36079:36084] CHIP:TOO: Secure Pairing Success [1655382244.089089][36079:36084] CHIP:CTL: Successfully finished commissioning step 'SendNOC' [1655382244.089112][36079:36084] CHIP:CTL: Commissioning stage next step: 'SendNOC' -> 'FindOperational' [1655382244.089149][36079:36084] CHIP:CTL: Performing next commissioning step 'FindOperational' [1655382244.089172][36079:36084] CHIP:CSM: FindOrEstablishSession: PeerId = 6E7E70F7DD8C6C66:0000000000000001 [1655382244.089195][36079:36084] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1655382244.089240][36079:36084] CHIP:CTL: OperationalDeviceProxy[6E7E70F7DD8C6C66:0000000000000001]: State change 1 --> 2 [1655382244.089270][36079:36084] CHIP:DIS: Resolving 6E7E70F7DD8C6C66:0000000000000001 ... [1655382244.090343][36079:36084] CHIP:DIS: Operational node lookup already in progress. Will NOT start a new one. [1655382244.090413][36079:36084] CHIP:DMG: ICR moving to [AwaitingDe] [1655382244.090449][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:62892714 on exchange 12139i [1655382244.090536][36079:36084] CHIP:IN: Prepared secure message 0xffff91844958 to 0xFFFFFFFB00000000 (0) of type 0x10 and protocolId (0, 0) on exchange 12139i with MessageCounter:75986137. [1655382244.090595][36079:36084] CHIP:IN: Sending encrypted msg 0xffff91844958 with MessageCounter:75986137 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000C9F7D0 msec [1655382244.090743][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:62892714 on exchange 12139i [1655382244.091159][36079:36084] CHIP:DL: Avahi resolve found [1655382244.091223][36079:36084] CHIP:DIS: Node ID resolved for 6E7E70F7DD8C6C66:0000000000000001 [1655382244.091249][36079:36084] CHIP:DIS: Hostname: E45F010F27530000 [1655382244.091277][36079:36084] CHIP:DIS: IP Address #1: fe80::e65f:1ff:fe0f:2755 [1655382244.091300][36079:36084] CHIP:DIS: Port: 5540 [1655382244.091322][36079:36084] CHIP:DIS: Mrp Interval idle: 5000 ms [1655382244.091345][36079:36084] CHIP:DIS: Mrp Interval active: 300 ms [1655382244.091475][36079:36084] CHIP:DIS: UDP:[fe80::e65f:1ff:fe0f:2755%eth0]:5540: new best score: 3 [1655382244.091501][36079:36084] CHIP:DIS: Checking node lookup status after 2 ms [1655382244.091522][36079:36084] CHIP:DIS: Keeping DNSSD lookup active [1655382244.289912][36079:36084] CHIP:DIS: Checking node lookup status after 201 ms [1655382244.290176][36079:36084] CHIP:CTL: Updating device address to UDP:[fe80::e65f:1ff:fe0f:2755%eth0]:5540 while in state 2 [1655382244.290237][36079:36084] CHIP:CTL: OperationalDeviceProxy[6E7E70F7DD8C6C66:0000000000000001]: State change 2 --> 3 [1655382244.290518][36079:36084] CHIP:IN: SecureSession[0xaaaaf701e658]: Allocated Type:2 LSID:1224 [1655382244.290622][36079:36084] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1655382244.292010][36079:36084] CHIP:SC: Including MRP parameters [1655382244.292271][36079:36084] CHIP:IN: Prepared unauthenticated message 0xaaaaf7021a38 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 12140i with MessageCounter:2137910. [1655382244.292402][36079:36084] CHIP:IN: Sending unauthenticated msg 0xaaaaf7021a38 with MessageCounter:2137910 to 0x0000000000000000 at monotonic time: 0000000000C9F89A msec [1655382244.292766][36079:36084] CHIP:SC: Sent Sigma1 msg [1655382244.292834][36079:36084] CHIP:CTL: OperationalDeviceProxy[6E7E70F7DD8C6C66:0000000000000001]: State change 3 --> 4 [1655382244.292898][36079:36084] CHIP:DIS: Discovery does not require any more timeouts [1655382244.296462][36079:36084] CHIP:EM: Received message of type 0x31 with protocolId (0, 0) and MessageCounter:229930461 on exchange 12140i [1655382244.296538][36079:36084] CHIP:EM: Found matching exchange: 12140i, Delegate: 0xffff7c014a00 [1655382244.296616][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:2137910 from Retrans Table on exchange 12140i [1655382244.296675][36079:36084] CHIP:EM: Removed CHIP MessageCounter:2137910 from RetransTable on exchange 12140i [1655382244.296747][36079:36084] CHIP:SC: Received Sigma2 msg [1655382244.296842][36079:36084] CHIP:SC: Peer assigned session session ID 22684 [1655382244.304105][36079:36084] CHIP:SC: Found MRP parameters in the message [1655382244.304157][36079:36084] CHIP:SC: Sending Sigma3 [1655382244.304533][36079:36084] CHIP:EM: Piggybacking Ack for MessageCounter:229930461 on exchange: 12140i [1655382244.304593][36079:36084] CHIP:IN: Prepared unauthenticated message 0xaaaaf7021a38 to 0x0000000000000000 (0) of type 0x32 and protocolId (0, 0) on exchange 12140i with MessageCounter:2137911. [1655382244.304641][36079:36084] CHIP:IN: Sending unauthenticated msg 0xaaaaf7021a38 with MessageCounter:2137911 to 0x0000000000000000 at monotonic time: 0000000000C9F8A6 msec [1655382244.304850][36079:36084] CHIP:SC: Sent Sigma3 msg [1655382244.315052][36079:36084] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:229930462 on exchange 12140i [1655382244.315092][36079:36084] CHIP:EM: Found matching exchange: 12140i, Delegate: 0xffff7c014a00 [1655382244.315130][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:2137911 from Retrans Table on exchange 12140i [1655382244.315159][36079:36084] CHIP:EM: Removed CHIP MessageCounter:2137911 from RetransTable on exchange 12140i [1655382244.315201][36079:36084] CHIP:SC: Success status report received. Session was established [1655382244.321386][36079:36084] CHIP:IN: SecureSession[0xaaaaf701e658]: Activated - Type:2 LSID:1224 [1655382244.321458][36079:36084] CHIP:IN: New secure session created for device <0000000000000001, 1>, LSID:1224 PSID:22684! [1655382244.321507][36079:36084] CHIP:CTL: OperationalDeviceProxy[6E7E70F7DD8C6C66:0000000000000001]: State change 4 --> 5 [1655382244.321595][36079:36084] CHIP:CTL: Successfully finished commissioning step 'FindOperational' [1655382244.321642][36079:36084] CHIP:CTL: Commissioning stage next step: 'FindOperational' -> 'SendComplete' [1655382244.321690][36079:36084] CHIP:CTL: Performing next commissioning step 'SendComplete' [1655382244.321810][36079:36084] CHIP:DMG: ICR moving to [AddingComm] [1655382244.321894][36079:36084] CHIP:DMG: ICR moving to [AddedComma] [1655382244.322080][36079:36084] CHIP:IN: Prepared secure message 0xaaaaf7021a38 to 0x0000000000000001 (1) of type 0x8 and protocolId (0, 1) on exchange 12141i with MessageCounter:186697792. [1655382244.322163][36079:36084] CHIP:IN: Sending encrypted msg 0xaaaaf7021a38 with MessageCounter:186697792 to 0x0000000000000001 (1) at monotonic time: 0000000000C9F8B8 msec [1655382244.322432][36079:36084] CHIP:DMG: ICR moving to [CommandSen] [1655382244.322498][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:229930462 on exchange 12140i [1655382244.322564][36079:36084] CHIP:IN: Prepared unauthenticated message 0xffff91844948 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 12140i with MessageCounter:2137912. [1655382244.322626][36079:36084] CHIP:IN: Sending unauthenticated msg 0xffff91844948 with MessageCounter:2137912 to 0x0000000000000000 at monotonic time: 0000000000C9F8B8 msec [1655382244.322762][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:229930462 on exchange 12140i [1655382244.329610][36079:36084] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:212344476 on exchange 12141i [1655382244.329651][36079:36084] CHIP:EM: Found matching exchange: 12141i, Delegate: 0xffff7c00eed0 [1655382244.329689][36079:36084] CHIP:EM: Rxd Ack; Removing MessageCounter:186697792 from Retrans Table on exchange 12141i [1655382244.329717][36079:36084] CHIP:EM: Removed CHIP MessageCounter:186697792 from RetransTable on exchange 12141i [1655382244.329751][36079:36084] CHIP:DMG: ICR moving to [ResponseRe] [1655382244.329806][36079:36084] CHIP:DMG: InvokeResponseMessage = [1655382244.329848][36079:36084] CHIP:DMG: { [1655382244.329874][36079:36084] CHIP:DMG: suppressResponse = false, [1655382244.329903][36079:36084] CHIP:DMG: InvokeResponseIBs = [1655382244.329938][36079:36084] CHIP:DMG: [ [1655382244.329966][36079:36084] CHIP:DMG: InvokeResponseIB = [1655382244.330002][36079:36084] CHIP:DMG: { [1655382244.330031][36079:36084] CHIP:DMG: CommandDataIB = [1655382244.330068][36079:36084] CHIP:DMG: { [1655382244.330100][36079:36084] CHIP:DMG: CommandPathIB = [1655382244.330138][36079:36084] CHIP:DMG: { [1655382244.330177][36079:36084] CHIP:DMG: EndpointId = 0x0, [1655382244.330220][36079:36084] CHIP:DMG: ClusterId = 0x30, [1655382244.330262][36079:36084] CHIP:DMG: CommandId = 0x5, [1655382244.330299][36079:36084] CHIP:DMG: }, [1655382244.330338][36079:36084] CHIP:DMG: [1655382244.330373][36079:36084] CHIP:DMG: CommandFields = [1655382244.330410][36079:36084] CHIP:DMG: { [1655382244.330448][36079:36084] CHIP:DMG: 0x0 = 0, [1655382244.330493][36079:36084] CHIP:DMG: 0x1 = "", [1655382244.330532][36079:36084] CHIP:DMG: }, [1655382244.330566][36079:36084] CHIP:DMG: }, [1655382244.330604][36079:36084] CHIP:DMG: [1655382244.330633][36079:36084] CHIP:DMG: }, [1655382244.330668][36079:36084] CHIP:DMG: [1655382244.330695][36079:36084] CHIP:DMG: ], [1655382244.330729][36079:36084] CHIP:DMG: [1655382244.330756][36079:36084] CHIP:DMG: InteractionModelRevision = 1 [1655382244.330783][36079:36084] CHIP:DMG: }, [1655382244.330851][36079:36084] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0005 [1655382244.330890][36079:36084] CHIP:CTL: Received CommissioningComplete response, errorCode=0 [1655382244.330921][36079:36084] CHIP:CTL: Successfully finished commissioning step 'SendComplete' [1655382244.330947][36079:36084] CHIP:CTL: Commissioning stage next step: 'SendComplete' -> 'Cleanup' [1655382244.330976][36079:36084] CHIP:CTL: Performing next commissioning step 'Cleanup' [1655382244.331035][36079:36084] CHIP:CTL: Successfully finished commissioning step 'Cleanup' [1655382244.331066][36079:36084] CHIP:TOO: Device commissioning completed with success [1655382244.331140][36079:36084] CHIP:DMG: ICR moving to [AwaitingDe] [1655382244.331176][36079:36084] CHIP:EM: Sending Standalone Ack for MessageCounter:212344476 on exchange 12141i [1655382244.331241][36079:36084] CHIP:IN: Prepared secure message 0xffff91844958 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 12141i with MessageCounter:186697793. [1655382244.331283][36079:36084] CHIP:IN: Sending encrypted msg 0xffff91844958 with MessageCounter:186697793 to 0x0000000000000001 (1) at monotonic time: 0000000000C9F8C1 msec [1655382244.331412][36079:36084] CHIP:EM: Flushed pending ack for MessageCounter:212344476 on exchange 12141i [1655382244.331680][36079:36079] CHIP:CTL: Shutting down the commissioner [1655382244.331779][36079:36079] CHIP:CTL: Shutting down the controller [1655382244.331814][36079:36079] CHIP:CTL: Shutting down the commissioner [1655382244.331872][36079:36079] CHIP:CTL: Shutting down the controller [1655382244.331907][36079:36079] CHIP:IN: Expiring all connections for fabric 1!! [1655382244.331934][36079:36079] CHIP:IN: SecureSession[0xaaaaf701e658]: MarkForRemoval Type:2 LSID:1224 [1655382244.331961][36079:36079] CHIP:IN: SecureSession[0xaaaaf701e658]: Released - Type:2 LSID:1224 [1655382244.332009][36079:36079] CHIP:CTL: Shutting down the commissioner [1655382244.332070][36079:36079] CHIP:CTL: Shutting down the controller [1655382244.332097][36079:36079] CHIP:IN: Expiring all connections for fabric 2!! [1655382244.332136][36079:36079] CHIP:CTL: Shutting down the commissioner [1655382244.332195][36079:36079] CHIP:CTL: Shutting down the controller [1655382244.332221][36079:36079] CHIP:IN: Expiring all connections for fabric 3!! [1655382244.332255][36079:36079] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1655382244.333242][36079:36079] CHIP:DMG: IM WH moving to [Uninitialized] [1655382244.333284][36079:36079] CHIP:DMG: IM WH moving to [Uninitialized] [1655382244.333314][36079:36079] CHIP:DMG: IM WH moving to [Uninitialized] [1655382244.333338][36079:36079] CHIP:DMG: IM WH moving to [Uninitialized] [1655382244.333364][36079:36079] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1655382244.333436][36079:36079] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1655382244.333738][36079:36079] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-sn49IC) [1655382244.334519][36079:36079] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655382244.334589][36079:36079] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1655382244.334619][36079:36079] CHIP:DL: Inet Layer shutdown [1655382244.334644][36079:36079] CHIP:DL: BLE shutdown [1655382244.335539][36079:36079] CHIP:DL: System Layer shutdown [1655382244.335675][36079:36079] CHIP:IN: SecureSession[0xaaaaf701e570]: Released - Type:1 LSID:1223 ubuntu@ubuntu:~/jun14_cntrl/connectedhomeip/examples/chip-tool/out/debug$