ubuntu@ubuntu:~/JUl18_cntr/connectedhomeip/examples/chip-tool/out/debug$ ./chip-tool unitlocalization write temperature-unit 3 1 0 [1658152257.629367][9435:9435] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1658152257.629936][9435:9435] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1658152257.630104][9435:9435] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1658152257.630217][9435:9435] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1658152257.630602][9435:9435] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-d5jasG) [1658152257.631316][9435:9435] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658152257.631376][9435:9435] CHIP:DL: NVS set: chip-counters/reboot-count = 28 (0x1C) [1658152257.632412][9435:9435] CHIP:DL: Got Ethernet interface: eth0 [1658152257.633028][9435:9435] CHIP:DL: Found the primary Ethernet interface:eth0 [1658152257.633641][9435:9435] CHIP:DL: Got WiFi interface: wlan0 [1658152257.633713][9435:9435] CHIP:DL: Failed to reset WiFi statistic counts [1658152257.633803][9435:9435] CHIP:IN: UDP::Init bind&listen port=0 [1658152257.633943][9435:9435] CHIP:IN: UDP::Init bound to port=53555 [1658152257.633970][9435:9435] CHIP:IN: BLEBase::Init - setting/overriding transport [1658152257.633993][9435:9435] CHIP:IN: TransportMgr initialized [1658152257.634029][9435:9435] CHIP:FP: Initializing FabricTable from persistent storage [1658152257.634236][9435:9435] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152257.634915][9435:9435] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x46B1F7A569FCEA56, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152257.635375][9435:9435] CHIP:FP: Fabric index 0x2 was retrieved from storage. Compressed FabricId 0xC0C9CFC9F5A9E5F8, FabricId 0x0000000000000002, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152257.635828][9435:9435] CHIP:FP: Fabric index 0x3 was retrieved from storage. Compressed FabricId 0x5E6B20D4A8CCF1A2, FabricId 0x0000000000000003, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152257.639130][9435:9435] CHIP:ZCL: Using ZAP configuration... [1658152257.643386][9435:9435] CHIP:DL: Avahi client registered [1658152257.644547][9435:9435] CHIP:CTL: System State Initialized... [1658152257.644632][9435:9435] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152257.644669][9435:9435] CHIP:CTL: Setting attestation nonce to random value [1658152257.644717][9435:9435] CHIP:CTL: Setting CSR nonce to random value [1658152257.644788][9435:9435] CHIP:IN: UDP::Init bind&listen port=5550 [1658152257.644925][9435:9435] CHIP:IN: UDP::Init bound to port=5550 [1658152257.644950][9435:9435] CHIP:IN: TransportMgr initialized [1658152257.645019][9435:9435] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152257.645047][9435:9435] CHIP:CTL: Setting attestation nonce to random value [1658152257.645094][9435:9435] CHIP:CTL: Setting CSR nonce to random value [1658152257.647957][9435:9435] CHIP:CTL: Generating NOC [1658152257.649163][9435:9435] CHIP:FP: Validating NOC chain [1658152257.650939][9435:9435] CHIP:FP: NOC chain validation successful [1658152257.651146][9435:9435] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1658152257.651190][9435:9435] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152257.651221][9435:9435] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152257.651247][9435:9435] CHIP:TS: Retaining current Last Known Good Time [1658152257.659641][9435:9435] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1658152257.662988][9435:9435] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152257.665694][9435:9435] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x0000000000000000 [1658152257.665764][9435:9435] CHIP:IN: UDP::Init bind&listen port=5550 [1658152257.665893][9435:9435] CHIP:IN: UDP::Init bound to port=5550 [1658152257.665921][9435:9435] CHIP:IN: TransportMgr initialized [1658152257.666034][9435:9435] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152257.666073][9435:9435] CHIP:CTL: Setting attestation nonce to random value [1658152257.666136][9435:9435] CHIP:CTL: Setting CSR nonce to random value [1658152257.667123][9435:9435] CHIP:CTL: Generating NOC [1658152257.668360][9435:9435] CHIP:FP: Validating NOC chain [1658152257.670128][9435:9435] CHIP:FP: NOC chain validation successful [1658152257.670365][9435:9435] CHIP:FP: Updated fabric at index: 0x2, Node ID: 0x000000000001B669 [1658152257.670402][9435:9435] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152257.670425][9435:9435] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152257.670447][9435:9435] CHIP:TS: Retaining current Last Known Good Time [1658152257.674549][9435:9435] CHIP:FP: Metadata for Fabric 0x2 persisted to storage. [1658152257.677542][9435:9435] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152257.680337][9435:9435] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x0000000000000000 [1658152257.680408][9435:9435] CHIP:IN: UDP::Init bind&listen port=5550 [1658152257.680547][9435:9435] CHIP:IN: UDP::Init bound to port=5550 [1658152257.680578][9435:9435] CHIP:IN: TransportMgr initialized [1658152257.680697][9435:9435] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152257.680740][9435:9435] CHIP:CTL: Setting attestation nonce to random value [1658152257.680803][9435:9435] CHIP:CTL: Setting CSR nonce to random value [1658152257.681747][9435:9435] CHIP:CTL: Generating NOC [1658152257.683005][9435:9435] CHIP:FP: Validating NOC chain [1658152257.684782][9435:9435] CHIP:FP: NOC chain validation successful [1658152257.684973][9435:9435] CHIP:FP: Updated fabric at index: 0x3, Node ID: 0x000000000001B669 [1658152257.685009][9435:9435] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152257.685032][9435:9435] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152257.685053][9435:9435] CHIP:TS: Retaining current Last Known Good Time [1658152257.688116][9435:9435] CHIP:FP: Metadata for Fabric 0x3 persisted to storage. [1658152257.691095][9435:9435] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152257.693974][9435:9435] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x0000000000000000 [1658152257.694054][9435:9435] CHIP:IN: UDP::Init bind&listen port=5550 [1658152257.694186][9435:9435] CHIP:IN: UDP::Init bound to port=5550 [1658152257.694215][9435:9435] CHIP:IN: TransportMgr initialized [1658152257.730884][9435:9440] CHIP:DL: CHIP task running [1658152257.731134][9435:9440] CHIP:DL: HandlePlatformSpecificBLEEvent 32784 [1658152257.731169][9435:9440] CHIP:TOO: Sending command to node 0x1 [1658152257.731208][9435:9440] CHIP:CSM: FindOrEstablishSession: PeerId = 46B1F7A569FCEA56:0000000000000001 [1658152257.731236][9435:9440] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1658152257.731276][9435:9440] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 1 --> 2 [1658152257.731403][9435:9440] CHIP:DIS: Resolving 46B1F7A569FCEA56:0000000000000001 ... [1658152257.840905][9435:9440] CHIP:DL: Avahi resolve found [1658152257.841056][9435:9440] CHIP:DIS: Node ID resolved for 46B1F7A569FCEA56:0000000000000001 [1658152257.841121][9435:9440] CHIP:DIS: Hostname: E45F0149AE290000 [1658152257.841188][9435:9440] CHIP:DIS: IP Address #1: fe80::e65f:1ff:fe49:ae29 [1658152257.841246][9435:9440] CHIP:DIS: Port: 5540 [1658152257.841301][9435:9440] CHIP:DIS: Mrp Interval idle: 5000 ms [1658152257.841357][9435:9440] CHIP:DIS: Mrp Interval active: 300 ms [1658152257.841824][9435:9440] CHIP:DIS: UDP:[fe80::e65f:1ff:fe49:ae29%eth0]:5540: new best score: 3 [1658152257.841890][9435:9440] CHIP:DIS: Checking node lookup status after 111 ms [1658152257.841943][9435:9440] CHIP:DIS: Keeping DNSSD lookup active [1658152257.931929][9435:9440] CHIP:DIS: Checking node lookup status after 201 ms [1658152257.932212][9435:9440] CHIP:CTL: Updating device address to UDP:[fe80::e65f:1ff:fe49:ae29%eth0]:5540 while in state 2 [1658152257.932276][9435:9440] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 2 --> 3 [1658152257.932656][9435:9440] CHIP:IN: SecureSession[0xffff9c001930]: Allocated Type:2 LSID:64519 [1658152257.932747][9435:9440] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1658152257.934340][9435:9440] CHIP:SC: Including MRP parameters [1658152257.934711][9435:9440] CHIP:IN: Prepared unauthenticated message 0xffff9c003138 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 5002i with MessageCounter:91891846. [1658152257.934878][9435:9440] CHIP:IN: Sending unauthenticated msg 0xffff9c003138 with MessageCounter:91891846 to 0x0000000000000000 at monotonic time: 0000000001C6C498 msec [1658152257.935235][9435:9440] CHIP:SC: Sent Sigma1 msg [1658152257.935305][9435:9440] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 3 --> 4 [1658152257.941511][9435:9440] CHIP:EM: Received message of type 0x31 with protocolId (0, 0) and MessageCounter:10140289 on exchange 5002i [1658152257.941587][9435:9440] CHIP:EM: Found matching exchange: 5002i, Delegate: 0xffff9c0011a0 [1658152257.941665][9435:9440] CHIP:EM: Rxd Ack; Removing MessageCounter:91891846 from Retrans Table on exchange 5002i [1658152257.941722][9435:9440] CHIP:EM: Removed CHIP MessageCounter:91891846 from RetransTable on exchange 5002i [1658152257.941796][9435:9440] CHIP:SC: Received Sigma2 msg [1658152257.941905][9435:9440] CHIP:SC: Peer assigned session session ID 62968 [1658152257.947877][9435:9440] CHIP:SC: Found MRP parameters in the message [1658152257.947946][9435:9440] CHIP:SC: Sending Sigma3 [1658152257.948721][9435:9440] CHIP:EM: Piggybacking Ack for MessageCounter:10140289 on exchange: 5002i [1658152257.948794][9435:9440] CHIP:IN: Prepared unauthenticated message 0xffff9c002d48 to 0x0000000000000000 (0) of type 0x32 and protocolId (0, 0) on exchange 5002i with MessageCounter:91891847. [1658152257.948855][9435:9440] CHIP:IN: Sending unauthenticated msg 0xffff9c002d48 with MessageCounter:91891847 to 0x0000000000000000 at monotonic time: 0000000001C6C4A6 msec [1658152257.949068][9435:9440] CHIP:SC: Sent Sigma3 msg [1658152257.960968][9435:9440] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:10140290 on exchange 5002i [1658152257.961037][9435:9440] CHIP:EM: Found matching exchange: 5002i, Delegate: 0xffff9c0011a0 [1658152257.961085][9435:9440] CHIP:EM: Rxd Ack; Removing MessageCounter:91891847 from Retrans Table on exchange 5002i [1658152257.961118][9435:9440] CHIP:EM: Removed CHIP MessageCounter:91891847 from RetransTable on exchange 5002i [1658152257.961168][9435:9440] CHIP:SC: Success status report received. Session was established [1658152257.967166][9435:9440] CHIP:SC: SecureSession[0xffff9c001930]: Moving from state 'kEstablishing' --> 'kActive' [1658152257.967225][9435:9440] CHIP:IN: SecureSession[0xffff9c001930]: Activated - Type:2 LSID:64519 [1658152257.967253][9435:9440] CHIP:IN: New secure session activated for device <0000000000000001, 1>, LSID:64519 PSID:62968! [1658152257.967285][9435:9440] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 4 --> 5 [1658152257.967394][9435:9440] CHIP:TOO: cluster 0x0000_002D, attribute: 0x0000_0000, endpoint 0 [1658152257.967473][9435:9440] CHIP:DMG: WriteClient moving to [AddAttribu] [1658152257.967599][9435:9440] CHIP:IN: Prepared secure message 0xffff9c005878 to 0x0000000000000001 (1) of type 0x6 and protocolId (0, 1) on exchange 5003i with MessageCounter:206454688. [1658152257.967645][9435:9440] CHIP:IN: Sending encrypted msg 0xffff9c005878 with MessageCounter:206454688 to 0x0000000000000001 (1) at monotonic time: 0000000001C6C4B8 msec [1658152257.967943][9435:9440] CHIP:DMG: WriteClient moving to [AwaitingRe] [1658152257.967997][9435:9440] CHIP:EM: Sending Standalone Ack for MessageCounter:10140290 on exchange 5002i [1658152257.968043][9435:9440] CHIP:IN: Prepared unauthenticated message 0xffffa3ffd9d8 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 5002i with MessageCounter:91891848. [1658152257.968083][9435:9440] CHIP:IN: Sending unauthenticated msg 0xffffa3ffd9d8 with MessageCounter:91891848 to 0x0000000000000000 at monotonic time: 0000000001C6C4B9 msec [1658152257.968173][9435:9440] CHIP:EM: Flushed pending ack for MessageCounter:10140290 on exchange 5002i [1658152257.971111][9435:9440] CHIP:EM: Received message of type 0x7 with protocolId (0, 1) and MessageCounter:150947466 on exchange 5003i [1658152257.971164][9435:9440] CHIP:EM: Found matching exchange: 5003i, Delegate: 0xffff9c007680 [1658152257.971202][9435:9440] CHIP:EM: Rxd Ack; Removing MessageCounter:206454688 from Retrans Table on exchange 5003i [1658152257.971227][9435:9440] CHIP:EM: Removed CHIP MessageCounter:206454688 from RetransTable on exchange 5003i [1658152257.971259][9435:9440] CHIP:DMG: WriteClient moving to [ResponseRe] [1658152257.971311][9435:9440] CHIP:DMG: WriteResponseMessage = [1658152257.971337][9435:9440] CHIP:DMG: { [1658152257.971359][9435:9440] CHIP:DMG: AttributeStatusIBs = [1658152257.971390][9435:9440] CHIP:DMG: [ [1658152257.971414][9435:9440] CHIP:DMG: AttributeStatusIB = [1658152257.971447][9435:9440] CHIP:DMG: { [1658152257.971472][9435:9440] CHIP:DMG: AttributePathIB = [1658152257.971505][9435:9440] CHIP:DMG: { [1658152257.971537][9435:9440] CHIP:DMG: Endpoint = 0x0, [1658152257.971574][9435:9440] CHIP:DMG: Cluster = 0x2d, [1658152257.971611][9435:9440] CHIP:DMG: Attribute = 0x0000_0000, [1658152257.971642][9435:9440] CHIP:DMG: } [1658152257.971677][9435:9440] CHIP:DMG: [1658152257.971706][9435:9440] CHIP:DMG: StatusIB = [1658152257.971737][9435:9440] CHIP:DMG: { [1658152257.971767][9435:9440] CHIP:DMG: status = 0x00 (SUCCESS), [1658152257.971801][9435:9440] CHIP:DMG: }, [1658152257.971831][9435:9440] CHIP:DMG: [1658152257.971856][9435:9440] CHIP:DMG: }, [1658152257.971882][9435:9440] CHIP:DMG: [1658152257.971905][9435:9440] CHIP:DMG: ], [1658152257.971934][9435:9440] CHIP:DMG: [1658152257.971958][9435:9440] CHIP:DMG: InteractionModelRevision = 1 [1658152257.971982][9435:9440] CHIP:DMG: } [1658152257.972053][9435:9440] CHIP:DMG: WriteClient moving to [AwaitingDe] [1658152257.972123][9435:9440] CHIP:EM: Sending Standalone Ack for MessageCounter:150947466 on exchange 5003i [1658152257.972205][9435:9440] CHIP:IN: Prepared secure message 0xffffa3ffd9e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 5003i with MessageCounter:206454689. [1658152257.972252][9435:9440] CHIP:IN: Sending encrypted msg 0xffffa3ffd9e8 with MessageCounter:206454689 to 0x0000000000000001 (1) at monotonic time: 0000000001C6C4BD msec [1658152257.972368][9435:9440] CHIP:EM: Flushed pending ack for MessageCounter:150947466 on exchange 5003i [1658152257.972594][9435:9435] CHIP:CTL: Shutting down the commissioner [1658152257.972635][9435:9435] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152257.972721][9435:9435] CHIP:CTL: Shutting down the controller [1658152257.972757][9435:9435] CHIP:IN: Expiring all sessions for fabric 0x1!! [1658152257.972781][9435:9435] CHIP:IN: SecureSession[0xffff9c001930]: MarkForEviction Type:2 LSID:64519 [1658152257.972804][9435:9435] CHIP:SC: SecureSession[0xffff9c001930]: Moving from state 'kActive' --> 'kPendingEviction' [1658152257.972827][9435:9435] CHIP:IN: SecureSession[0xffff9c001930]: Released - Type:2 LSID:64519 [1658152257.972853][9435:9435] CHIP:FP: Forgetting fabric 0x1 [1658152257.972887][9435:9435] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152257.973092][9435:9435] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152257.973121][9435:9435] CHIP:TS: Reverted Last Known Good Time to previous value [1658152257.973169][9435:9435] CHIP:CTL: Shutting down the commissioner [1658152257.973192][9435:9435] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152257.973263][9435:9435] CHIP:CTL: Shutting down the controller [1658152257.973289][9435:9435] CHIP:IN: Expiring all sessions for fabric 0x2!! [1658152257.973309][9435:9435] CHIP:FP: Forgetting fabric 0x2 [1658152257.973337][9435:9435] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152257.973485][9435:9435] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152257.973511][9435:9435] CHIP:TS: Reverted Last Known Good Time to previous value [1658152257.973546][9435:9435] CHIP:CTL: Shutting down the commissioner [1658152257.973568][9435:9435] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152257.973627][9435:9435] CHIP:CTL: Shutting down the controller [1658152257.973653][9435:9435] CHIP:IN: Expiring all sessions for fabric 0x3!! [1658152257.973674][9435:9435] CHIP:FP: Forgetting fabric 0x3 [1658152257.973700][9435:9435] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152257.973837][9435:9435] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152257.973862][9435:9435] CHIP:TS: Reverted Last Known Good Time to previous value [1658152257.973895][9435:9435] CHIP:CTL: Shutting down the commissioner [1658152257.973917][9435:9435] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152257.973975][9435:9435] CHIP:CTL: Shutting down the controller [1658152257.973999][9435:9435] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1658152257.975271][9435:9435] CHIP:DMG: IM WH moving to [Uninitialized] [1658152257.975344][9435:9435] CHIP:DMG: IM WH moving to [Uninitialized] [1658152257.975362][9435:9435] CHIP:DMG: IM WH moving to [Uninitialized] [1658152257.975378][9435:9435] CHIP:DMG: IM WH moving to [Uninitialized] [1658152257.975396][9435:9435] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1658152257.975578][9435:9435] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1658152257.975867][9435:9435] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-DJsfYR) [1658152257.976605][9435:9435] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658152257.976678][9435:9435] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1658152257.976707][9435:9435] CHIP:DL: Inet Layer shutdown [1658152257.976790][9435:9435] CHIP:DL: BLE shutdown [1658152257.976815][9435:9435] CHIP:DL: System Layer shutdown ubuntu@ubuntu:~/JUl18_cntr/connectedhomeip/examples/chip-tool/out/debug$ ./chip-tool unitlocalization write temperature-unit 4 1 0 [1658152261.585464][9441:9441] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1658152261.586029][9441:9441] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1658152261.586192][9441:9441] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1658152261.586307][9441:9441] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1658152261.586678][9441:9441] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-0c6MKG) [1658152261.587381][9441:9441] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658152261.587437][9441:9441] CHIP:DL: NVS set: chip-counters/reboot-count = 29 (0x1D) [1658152261.588483][9441:9441] CHIP:DL: Got Ethernet interface: eth0 [1658152261.589096][9441:9441] CHIP:DL: Found the primary Ethernet interface:eth0 [1658152261.589714][9441:9441] CHIP:DL: Got WiFi interface: wlan0 [1658152261.589793][9441:9441] CHIP:DL: Failed to reset WiFi statistic counts [1658152261.589888][9441:9441] CHIP:IN: UDP::Init bind&listen port=0 [1658152261.590031][9441:9441] CHIP:IN: UDP::Init bound to port=58033 [1658152261.590059][9441:9441] CHIP:IN: BLEBase::Init - setting/overriding transport [1658152261.590078][9441:9441] CHIP:IN: TransportMgr initialized [1658152261.590110][9441:9441] CHIP:FP: Initializing FabricTable from persistent storage [1658152261.590340][9441:9441] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152261.591057][9441:9441] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x46B1F7A569FCEA56, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152261.591531][9441:9441] CHIP:FP: Fabric index 0x2 was retrieved from storage. Compressed FabricId 0xC0C9CFC9F5A9E5F8, FabricId 0x0000000000000002, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152261.592001][9441:9441] CHIP:FP: Fabric index 0x3 was retrieved from storage. Compressed FabricId 0x5E6B20D4A8CCF1A2, FabricId 0x0000000000000003, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152261.595377][9441:9441] CHIP:ZCL: Using ZAP configuration... [1658152261.599677][9441:9441] CHIP:DL: Avahi client registered [1658152261.600842][9441:9441] CHIP:CTL: System State Initialized... [1658152261.600928][9441:9441] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152261.600967][9441:9441] CHIP:CTL: Setting attestation nonce to random value [1658152261.601016][9441:9441] CHIP:CTL: Setting CSR nonce to random value [1658152261.601089][9441:9441] CHIP:IN: UDP::Init bind&listen port=5550 [1658152261.601238][9441:9441] CHIP:IN: UDP::Init bound to port=5550 [1658152261.601268][9441:9441] CHIP:IN: TransportMgr initialized [1658152261.601354][9441:9441] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152261.601391][9441:9441] CHIP:CTL: Setting attestation nonce to random value [1658152261.601445][9441:9441] CHIP:CTL: Setting CSR nonce to random value [1658152261.604377][9441:9441] CHIP:CTL: Generating NOC [1658152261.605677][9441:9441] CHIP:FP: Validating NOC chain [1658152261.607873][9441:9441] CHIP:FP: NOC chain validation successful [1658152261.608106][9441:9441] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1658152261.608155][9441:9441] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152261.608185][9441:9441] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152261.608211][9441:9441] CHIP:TS: Retaining current Last Known Good Time [1658152261.617601][9441:9441] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1658152261.621078][9441:9441] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152261.624161][9441:9441] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x0000000000000000 [1658152261.624233][9441:9441] CHIP:IN: UDP::Init bind&listen port=5550 [1658152261.624367][9441:9441] CHIP:IN: UDP::Init bound to port=5550 [1658152261.624396][9441:9441] CHIP:IN: TransportMgr initialized [1658152261.624516][9441:9441] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152261.624559][9441:9441] CHIP:CTL: Setting attestation nonce to random value [1658152261.624622][9441:9441] CHIP:CTL: Setting CSR nonce to random value [1658152261.625685][9441:9441] CHIP:CTL: Generating NOC [1658152261.626957][9441:9441] CHIP:FP: Validating NOC chain [1658152261.628763][9441:9441] CHIP:FP: NOC chain validation successful [1658152261.628954][9441:9441] CHIP:FP: Updated fabric at index: 0x2, Node ID: 0x000000000001B669 [1658152261.628994][9441:9441] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152261.629017][9441:9441] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152261.629038][9441:9441] CHIP:TS: Retaining current Last Known Good Time [1658152261.633302][9441:9441] CHIP:FP: Metadata for Fabric 0x2 persisted to storage. [1658152261.636482][9441:9441] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152261.639578][9441:9441] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x0000000000000000 [1658152261.639649][9441:9441] CHIP:IN: UDP::Init bind&listen port=5550 [1658152261.639786][9441:9441] CHIP:IN: UDP::Init bound to port=5550 [1658152261.639816][9441:9441] CHIP:IN: TransportMgr initialized [1658152261.639943][9441:9441] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152261.639987][9441:9441] CHIP:CTL: Setting attestation nonce to random value [1658152261.640050][9441:9441] CHIP:CTL: Setting CSR nonce to random value [1658152261.641117][9441:9441] CHIP:CTL: Generating NOC [1658152261.642342][9441:9441] CHIP:FP: Validating NOC chain [1658152261.644229][9441:9441] CHIP:FP: NOC chain validation successful [1658152261.644423][9441:9441] CHIP:FP: Updated fabric at index: 0x3, Node ID: 0x000000000001B669 [1658152261.644465][9441:9441] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152261.644489][9441:9441] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152261.644510][9441:9441] CHIP:TS: Retaining current Last Known Good Time [1658152261.647734][9441:9441] CHIP:FP: Metadata for Fabric 0x3 persisted to storage. [1658152261.650883][9441:9441] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152261.653968][9441:9441] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x0000000000000000 [1658152261.654039][9441:9441] CHIP:IN: UDP::Init bind&listen port=5550 [1658152261.654175][9441:9441] CHIP:IN: UDP::Init bound to port=5550 [1658152261.654200][9441:9441] CHIP:IN: TransportMgr initialized [1658152261.691909][9441:9446] CHIP:DL: CHIP task running [1658152261.692125][9441:9446] CHIP:DL: HandlePlatformSpecificBLEEvent 32784 [1658152261.692159][9441:9446] CHIP:TOO: Sending command to node 0x1 [1658152261.692200][9441:9446] CHIP:CSM: FindOrEstablishSession: PeerId = 46B1F7A569FCEA56:0000000000000001 [1658152261.692228][9441:9446] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1658152261.692267][9441:9446] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 1 --> 2 [1658152261.692299][9441:9446] CHIP:DIS: Resolving 46B1F7A569FCEA56:0000000000000001 ... [1658152261.694178][9441:9446] CHIP:DL: Avahi resolve found [1658152261.694262][9441:9446] CHIP:DIS: Node ID resolved for 46B1F7A569FCEA56:0000000000000001 [1658152261.694291][9441:9446] CHIP:DIS: Hostname: E45F0149AE290000 [1658152261.694322][9441:9446] CHIP:DIS: IP Address #1: fe80::e65f:1ff:fe49:ae29 [1658152261.694347][9441:9446] CHIP:DIS: Port: 5540 [1658152261.694371][9441:9446] CHIP:DIS: Mrp Interval idle: 5000 ms [1658152261.694395][9441:9446] CHIP:DIS: Mrp Interval active: 300 ms [1658152261.694659][9441:9446] CHIP:DIS: UDP:[fe80::e65f:1ff:fe49:ae29%eth0]:5540: new best score: 3 [1658152261.694691][9441:9446] CHIP:DIS: Checking node lookup status after 2 ms [1658152261.694713][9441:9446] CHIP:DIS: Keeping DNSSD lookup active [1658152261.893141][9441:9446] CHIP:DIS: Checking node lookup status after 201 ms [1658152261.893447][9441:9446] CHIP:CTL: Updating device address to UDP:[fe80::e65f:1ff:fe49:ae29%eth0]:5540 while in state 2 [1658152261.893510][9441:9446] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 2 --> 3 [1658152261.893894][9441:9446] CHIP:IN: SecureSession[0xffff98001930]: Allocated Type:2 LSID:18839 [1658152261.893983][9441:9446] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1658152261.895727][9441:9446] CHIP:SC: Including MRP parameters [1658152261.896102][9441:9446] CHIP:IN: Prepared unauthenticated message 0xffff98003138 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 22402i with MessageCounter:241442624. [1658152261.896206][9441:9446] CHIP:IN: Sending unauthenticated msg 0xffff98003138 with MessageCounter:241442624 to 0x0000000000000000 at monotonic time: 0000000001C6D411 msec [1658152261.896540][9441:9446] CHIP:SC: Sent Sigma1 msg [1658152261.896608][9441:9446] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 3 --> 4 [1658152261.902323][9441:9446] CHIP:EM: Received message of type 0x31 with protocolId (0, 0) and MessageCounter:10140291 on exchange 22402i [1658152261.902401][9441:9446] CHIP:EM: Found matching exchange: 22402i, Delegate: 0xffff980011a0 [1658152261.902477][9441:9446] CHIP:EM: Rxd Ack; Removing MessageCounter:241442624 from Retrans Table on exchange 22402i [1658152261.902535][9441:9446] CHIP:EM: Removed CHIP MessageCounter:241442624 from RetransTable on exchange 22402i [1658152261.902610][9441:9446] CHIP:SC: Received Sigma2 msg [1658152261.902715][9441:9446] CHIP:SC: Peer assigned session session ID 62969 [1658152261.908506][9441:9446] CHIP:SC: Found MRP parameters in the message [1658152261.908599][9441:9446] CHIP:SC: Sending Sigma3 [1658152261.909447][9441:9446] CHIP:EM: Piggybacking Ack for MessageCounter:10140291 on exchange: 22402i [1658152261.909536][9441:9446] CHIP:IN: Prepared unauthenticated message 0xffff98002d48 to 0x0000000000000000 (0) of type 0x32 and protocolId (0, 0) on exchange 22402i with MessageCounter:241442625. [1658152261.909599][9441:9446] CHIP:IN: Sending unauthenticated msg 0xffff98002d48 with MessageCounter:241442625 to 0x0000000000000000 at monotonic time: 0000000001C6D41E msec [1658152261.909832][9441:9446] CHIP:SC: Sent Sigma3 msg [1658152261.922122][9441:9446] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:10140292 on exchange 22402i [1658152261.922182][9441:9446] CHIP:EM: Found matching exchange: 22402i, Delegate: 0xffff980011a0 [1658152261.922223][9441:9446] CHIP:EM: Rxd Ack; Removing MessageCounter:241442625 from Retrans Table on exchange 22402i [1658152261.922250][9441:9446] CHIP:EM: Removed CHIP MessageCounter:241442625 from RetransTable on exchange 22402i [1658152261.922292][9441:9446] CHIP:SC: Success status report received. Session was established [1658152261.926926][9441:9446] CHIP:SC: SecureSession[0xffff98001930]: Moving from state 'kEstablishing' --> 'kActive' [1658152261.926987][9441:9446] CHIP:IN: SecureSession[0xffff98001930]: Activated - Type:2 LSID:18839 [1658152261.927011][9441:9446] CHIP:IN: New secure session activated for device <0000000000000001, 1>, LSID:18839 PSID:62969! [1658152261.927043][9441:9446] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 4 --> 5 [1658152261.927151][9441:9446] CHIP:TOO: cluster 0x0000_002D, attribute: 0x0000_0000, endpoint 0 [1658152261.927230][9441:9446] CHIP:DMG: WriteClient moving to [AddAttribu] [1658152261.927358][9441:9446] CHIP:IN: Prepared secure message 0xffff98005878 to 0x0000000000000001 (1) of type 0x6 and protocolId (0, 1) on exchange 22403i with MessageCounter:79007637. [1658152261.927407][9441:9446] CHIP:IN: Sending encrypted msg 0xffff98005878 with MessageCounter:79007637 to 0x0000000000000001 (1) at monotonic time: 0000000001C6D430 msec [1658152261.927608][9441:9446] CHIP:DMG: WriteClient moving to [AwaitingRe] [1658152261.927661][9441:9446] CHIP:EM: Sending Standalone Ack for MessageCounter:10140292 on exchange 22402i [1658152261.927709][9441:9446] CHIP:IN: Prepared unauthenticated message 0xffffa27cd9d8 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 22402i with MessageCounter:241442626. [1658152261.927749][9441:9446] CHIP:IN: Sending unauthenticated msg 0xffffa27cd9d8 with MessageCounter:241442626 to 0x0000000000000000 at monotonic time: 0000000001C6D430 msec [1658152261.927843][9441:9446] CHIP:EM: Flushed pending ack for MessageCounter:10140292 on exchange 22402i [1658152261.930699][9441:9446] CHIP:EM: Received message of type 0x7 with protocolId (0, 1) and MessageCounter:173856229 on exchange 22403i [1658152261.930743][9441:9446] CHIP:EM: Found matching exchange: 22403i, Delegate: 0xffff98007680 [1658152261.930778][9441:9446] CHIP:EM: Rxd Ack; Removing MessageCounter:79007637 from Retrans Table on exchange 22403i [1658152261.930837][9441:9446] CHIP:EM: Removed CHIP MessageCounter:79007637 from RetransTable on exchange 22403i [1658152261.930869][9441:9446] CHIP:DMG: WriteClient moving to [ResponseRe] [1658152261.930920][9441:9446] CHIP:DMG: WriteResponseMessage = [1658152261.930946][9441:9446] CHIP:DMG: { [1658152261.930968][9441:9446] CHIP:DMG: AttributeStatusIBs = [1658152261.930998][9441:9446] CHIP:DMG: [ [1658152261.931022][9441:9446] CHIP:DMG: AttributeStatusIB = [1658152261.931053][9441:9446] CHIP:DMG: { [1658152261.931081][9441:9446] CHIP:DMG: AttributePathIB = [1658152261.931117][9441:9446] CHIP:DMG: { [1658152261.931150][9441:9446] CHIP:DMG: Endpoint = 0x0, [1658152261.931188][9441:9446] CHIP:DMG: Cluster = 0x2d, [1658152261.931220][9441:9446] CHIP:DMG: Attribute = 0x0000_0000, [1658152261.931254][9441:9446] CHIP:DMG: } [1658152261.931294][9441:9446] CHIP:DMG: [1658152261.931328][9441:9446] CHIP:DMG: StatusIB = [1658152261.931362][9441:9446] CHIP:DMG: { [1658152261.931397][9441:9446] CHIP:DMG: status = 0x00 (SUCCESS), [1658152261.931432][9441:9446] CHIP:DMG: }, [1658152261.931466][9441:9446] CHIP:DMG: [1658152261.931493][9441:9446] CHIP:DMG: }, [1658152261.931524][9441:9446] CHIP:DMG: [1658152261.931548][9441:9446] CHIP:DMG: ], [1658152261.931576][9441:9446] CHIP:DMG: [1658152261.931599][9441:9446] CHIP:DMG: InteractionModelRevision = 1 [1658152261.931624][9441:9446] CHIP:DMG: } [1658152261.931695][9441:9446] CHIP:DMG: WriteClient moving to [AwaitingDe] [1658152261.931761][9441:9446] CHIP:EM: Sending Standalone Ack for MessageCounter:173856229 on exchange 22403i [1658152261.931834][9441:9446] CHIP:IN: Prepared secure message 0xffffa27cd9e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 22403i with MessageCounter:79007638. [1658152261.931876][9441:9446] CHIP:IN: Sending encrypted msg 0xffffa27cd9e8 with MessageCounter:79007638 to 0x0000000000000001 (1) at monotonic time: 0000000001C6D435 msec [1658152261.931996][9441:9446] CHIP:EM: Flushed pending ack for MessageCounter:173856229 on exchange 22403i [1658152261.932216][9441:9441] CHIP:CTL: Shutting down the commissioner [1658152261.932257][9441:9441] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152261.932346][9441:9441] CHIP:CTL: Shutting down the controller [1658152261.932382][9441:9441] CHIP:IN: Expiring all sessions for fabric 0x1!! [1658152261.932406][9441:9441] CHIP:IN: SecureSession[0xffff98001930]: MarkForEviction Type:2 LSID:18839 [1658152261.932429][9441:9441] CHIP:SC: SecureSession[0xffff98001930]: Moving from state 'kActive' --> 'kPendingEviction' [1658152261.932451][9441:9441] CHIP:IN: SecureSession[0xffff98001930]: Released - Type:2 LSID:18839 [1658152261.932476][9441:9441] CHIP:FP: Forgetting fabric 0x1 [1658152261.932508][9441:9441] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152261.932703][9441:9441] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152261.932731][9441:9441] CHIP:TS: Reverted Last Known Good Time to previous value [1658152261.932777][9441:9441] CHIP:CTL: Shutting down the commissioner [1658152261.932801][9441:9441] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152261.932866][9441:9441] CHIP:CTL: Shutting down the controller [1658152261.932890][9441:9441] CHIP:IN: Expiring all sessions for fabric 0x2!! [1658152261.932910][9441:9441] CHIP:FP: Forgetting fabric 0x2 [1658152261.932935][9441:9441] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152261.933067][9441:9441] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152261.933093][9441:9441] CHIP:TS: Reverted Last Known Good Time to previous value [1658152261.933125][9441:9441] CHIP:CTL: Shutting down the commissioner [1658152261.933148][9441:9441] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152261.933227][9441:9441] CHIP:CTL: Shutting down the controller [1658152261.933250][9441:9441] CHIP:IN: Expiring all sessions for fabric 0x3!! [1658152261.933271][9441:9441] CHIP:FP: Forgetting fabric 0x3 [1658152261.933296][9441:9441] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152261.933430][9441:9441] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152261.933457][9441:9441] CHIP:TS: Reverted Last Known Good Time to previous value [1658152261.933490][9441:9441] CHIP:CTL: Shutting down the commissioner [1658152261.933513][9441:9441] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152261.933570][9441:9441] CHIP:CTL: Shutting down the controller [1658152261.933593][9441:9441] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1658152261.934673][9441:9441] CHIP:DMG: IM WH moving to [Uninitialized] [1658152261.934733][9441:9441] CHIP:DMG: IM WH moving to [Uninitialized] [1658152261.934830][9441:9441] CHIP:DMG: IM WH moving to [Uninitialized] [1658152261.934879][9441:9441] CHIP:DMG: IM WH moving to [Uninitialized] [1658152261.934927][9441:9441] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1658152261.935305][9441:9441] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1658152261.935747][9441:9441] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-dCD9Uz) [1658152261.936480][9441:9441] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658152261.936552][9441:9441] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1658152261.936581][9441:9441] CHIP:DL: Inet Layer shutdown [1658152261.936603][9441:9441] CHIP:DL: BLE shutdown [1658152261.936625][9441:9441] CHIP:DL: System Layer shutdown ubuntu@ubuntu:~/JUl18_cntr/connectedhomeip/examples/chip-tool/out/debug$ ./chip-tool unitlocalization write temperature-unit 60 1 0 [1658152265.913879][9448:9448] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1658152265.914427][9448:9448] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1658152265.914596][9448:9448] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1658152265.914717][9448:9448] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1658152265.915120][9448:9448] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-WfXIpF) [1658152265.915817][9448:9448] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658152265.915873][9448:9448] CHIP:DL: NVS set: chip-counters/reboot-count = 30 (0x1E) [1658152265.916810][9448:9448] CHIP:DL: Got Ethernet interface: eth0 [1658152265.917426][9448:9448] CHIP:DL: Found the primary Ethernet interface:eth0 [1658152265.918054][9448:9448] CHIP:DL: Got WiFi interface: wlan0 [1658152265.918129][9448:9448] CHIP:DL: Failed to reset WiFi statistic counts [1658152265.918220][9448:9448] CHIP:IN: UDP::Init bind&listen port=0 [1658152265.918362][9448:9448] CHIP:IN: UDP::Init bound to port=38834 [1658152265.918389][9448:9448] CHIP:IN: BLEBase::Init - setting/overriding transport [1658152265.918412][9448:9448] CHIP:IN: TransportMgr initialized [1658152265.918447][9448:9448] CHIP:FP: Initializing FabricTable from persistent storage [1658152265.918660][9448:9448] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152265.919349][9448:9448] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x46B1F7A569FCEA56, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152265.919802][9448:9448] CHIP:FP: Fabric index 0x2 was retrieved from storage. Compressed FabricId 0xC0C9CFC9F5A9E5F8, FabricId 0x0000000000000002, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152265.920250][9448:9448] CHIP:FP: Fabric index 0x3 was retrieved from storage. Compressed FabricId 0x5E6B20D4A8CCF1A2, FabricId 0x0000000000000003, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152265.923511][9448:9448] CHIP:ZCL: Using ZAP configuration... [1658152265.927617][9448:9448] CHIP:DL: Avahi client registered [1658152265.928835][9448:9448] CHIP:CTL: System State Initialized... [1658152265.928924][9448:9448] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152265.928962][9448:9448] CHIP:CTL: Setting attestation nonce to random value [1658152265.929010][9448:9448] CHIP:CTL: Setting CSR nonce to random value [1658152265.929080][9448:9448] CHIP:IN: UDP::Init bind&listen port=5550 [1658152265.929216][9448:9448] CHIP:IN: UDP::Init bound to port=5550 [1658152265.929241][9448:9448] CHIP:IN: TransportMgr initialized [1658152265.929310][9448:9448] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152265.929337][9448:9448] CHIP:CTL: Setting attestation nonce to random value [1658152265.929383][9448:9448] CHIP:CTL: Setting CSR nonce to random value [1658152265.932309][9448:9448] CHIP:CTL: Generating NOC [1658152265.933681][9448:9448] CHIP:FP: Validating NOC chain [1658152265.935775][9448:9448] CHIP:FP: NOC chain validation successful [1658152265.936004][9448:9448] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1658152265.936047][9448:9448] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152265.936076][9448:9448] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152265.936102][9448:9448] CHIP:TS: Retaining current Last Known Good Time [1658152265.943877][9448:9448] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1658152265.946816][9448:9448] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152265.949244][9448:9448] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x0000000000000000 [1658152265.949302][9448:9448] CHIP:IN: UDP::Init bind&listen port=5550 [1658152265.949422][9448:9448] CHIP:IN: UDP::Init bound to port=5550 [1658152265.949463][9448:9448] CHIP:IN: TransportMgr initialized [1658152265.949581][9448:9448] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152265.949620][9448:9448] CHIP:CTL: Setting attestation nonce to random value [1658152265.949683][9448:9448] CHIP:CTL: Setting CSR nonce to random value [1658152265.950604][9448:9448] CHIP:CTL: Generating NOC [1658152265.951792][9448:9448] CHIP:FP: Validating NOC chain [1658152265.953529][9448:9448] CHIP:FP: NOC chain validation successful [1658152265.953706][9448:9448] CHIP:FP: Updated fabric at index: 0x2, Node ID: 0x000000000001B669 [1658152265.953739][9448:9448] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152265.953762][9448:9448] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152265.953783][9448:9448] CHIP:TS: Retaining current Last Known Good Time [1658152265.956612][9448:9448] CHIP:FP: Metadata for Fabric 0x2 persisted to storage. [1658152265.959146][9448:9448] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152265.961524][9448:9448] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x0000000000000000 [1658152265.961582][9448:9448] CHIP:IN: UDP::Init bind&listen port=5550 [1658152265.961705][9448:9448] CHIP:IN: UDP::Init bound to port=5550 [1658152265.961744][9448:9448] CHIP:IN: TransportMgr initialized [1658152265.961858][9448:9448] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152265.961897][9448:9448] CHIP:CTL: Setting attestation nonce to random value [1658152265.961959][9448:9448] CHIP:CTL: Setting CSR nonce to random value [1658152265.962900][9448:9448] CHIP:CTL: Generating NOC [1658152265.964067][9448:9448] CHIP:FP: Validating NOC chain [1658152265.965782][9448:9448] CHIP:FP: NOC chain validation successful [1658152265.965996][9448:9448] CHIP:FP: Updated fabric at index: 0x3, Node ID: 0x000000000001B669 [1658152265.966029][9448:9448] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152265.966053][9448:9448] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152265.966073][9448:9448] CHIP:TS: Retaining current Last Known Good Time [1658152265.968880][9448:9448] CHIP:FP: Metadata for Fabric 0x3 persisted to storage. [1658152265.971431][9448:9448] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152265.973810][9448:9448] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x0000000000000000 [1658152265.973868][9448:9448] CHIP:IN: UDP::Init bind&listen port=5550 [1658152265.973991][9448:9448] CHIP:IN: UDP::Init bound to port=5550 [1658152265.974032][9448:9448] CHIP:IN: TransportMgr initialized [1658152266.009652][9448:9453] CHIP:DL: CHIP task running [1658152266.009883][9448:9453] CHIP:DL: HandlePlatformSpecificBLEEvent 32784 [1658152266.009919][9448:9453] CHIP:TOO: Sending command to node 0x1 [1658152266.009955][9448:9453] CHIP:CSM: FindOrEstablishSession: PeerId = 46B1F7A569FCEA56:0000000000000001 [1658152266.009981][9448:9453] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1658152266.010019][9448:9453] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 1 --> 2 [1658152266.010049][9448:9453] CHIP:DIS: Resolving 46B1F7A569FCEA56:0000000000000001 ... [1658152266.011946][9448:9453] CHIP:DL: Avahi resolve found [1658152266.012029][9448:9453] CHIP:DIS: Node ID resolved for 46B1F7A569FCEA56:0000000000000001 [1658152266.012074][9448:9453] CHIP:DIS: Hostname: E45F0149AE290000 [1658152266.012125][9448:9453] CHIP:DIS: IP Address #1: fe80::e65f:1ff:fe49:ae29 [1658152266.012154][9448:9453] CHIP:DIS: Port: 5540 [1658152266.012178][9448:9453] CHIP:DIS: Mrp Interval idle: 5000 ms [1658152266.012202][9448:9453] CHIP:DIS: Mrp Interval active: 300 ms [1658152266.012476][9448:9453] CHIP:DIS: UDP:[fe80::e65f:1ff:fe49:ae29%eth0]:5540: new best score: 3 [1658152266.012511][9448:9453] CHIP:DIS: Checking node lookup status after 2 ms [1658152266.012534][9448:9453] CHIP:DIS: Keeping DNSSD lookup active [1658152266.210869][9448:9453] CHIP:DIS: Checking node lookup status after 201 ms [1658152266.211061][9448:9453] CHIP:CTL: Updating device address to UDP:[fe80::e65f:1ff:fe49:ae29%eth0]:5540 while in state 2 [1658152266.211088][9448:9453] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 2 --> 3 [1658152266.211284][9448:9453] CHIP:IN: SecureSession[0xffff88001930]: Allocated Type:2 LSID:9302 [1658152266.211326][9448:9453] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1658152266.212112][9448:9453] CHIP:SC: Including MRP parameters [1658152266.212290][9448:9453] CHIP:IN: Prepared unauthenticated message 0xffff88003138 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 8793i with MessageCounter:195977164. [1658152266.212339][9448:9453] CHIP:IN: Sending unauthenticated msg 0xffff88003138 with MessageCounter:195977164 to 0x0000000000000000 at monotonic time: 0000000001C6E4ED msec [1658152266.212513][9448:9453] CHIP:SC: Sent Sigma1 msg [1658152266.212543][9448:9453] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 3 --> 4 [1658152266.215760][9448:9453] CHIP:EM: Received message of type 0x31 with protocolId (0, 0) and MessageCounter:10140293 on exchange 8793i [1658152266.215809][9448:9453] CHIP:EM: Found matching exchange: 8793i, Delegate: 0xffff880011a0 [1658152266.215852][9448:9453] CHIP:EM: Rxd Ack; Removing MessageCounter:195977164 from Retrans Table on exchange 8793i [1658152266.215882][9448:9453] CHIP:EM: Removed CHIP MessageCounter:195977164 from RetransTable on exchange 8793i [1658152266.215923][9448:9453] CHIP:SC: Received Sigma2 msg [1658152266.215989][9448:9453] CHIP:SC: Peer assigned session session ID 62970 [1658152266.220030][9448:9453] CHIP:SC: Found MRP parameters in the message [1658152266.220086][9448:9453] CHIP:SC: Sending Sigma3 [1658152266.220732][9448:9453] CHIP:EM: Piggybacking Ack for MessageCounter:10140293 on exchange: 8793i [1658152266.220796][9448:9453] CHIP:IN: Prepared unauthenticated message 0xffff88002d48 to 0x0000000000000000 (0) of type 0x32 and protocolId (0, 0) on exchange 8793i with MessageCounter:195977165. [1658152266.220845][9448:9453] CHIP:IN: Sending unauthenticated msg 0xffff88002d48 with MessageCounter:195977165 to 0x0000000000000000 at monotonic time: 0000000001C6E4F6 msec [1658152266.221037][9448:9453] CHIP:SC: Sent Sigma3 msg [1658152266.229589][9448:9453] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:10140294 on exchange 8793i [1658152266.229630][9448:9453] CHIP:EM: Found matching exchange: 8793i, Delegate: 0xffff880011a0 [1658152266.229668][9448:9453] CHIP:EM: Rxd Ack; Removing MessageCounter:195977165 from Retrans Table on exchange 8793i [1658152266.229698][9448:9453] CHIP:EM: Removed CHIP MessageCounter:195977165 from RetransTable on exchange 8793i [1658152266.229740][9448:9453] CHIP:SC: Success status report received. Session was established [1658152266.235873][9448:9453] CHIP:SC: SecureSession[0xffff88001930]: Moving from state 'kEstablishing' --> 'kActive' [1658152266.235958][9448:9453] CHIP:IN: SecureSession[0xffff88001930]: Activated - Type:2 LSID:9302 [1658152266.236000][9448:9453] CHIP:IN: New secure session activated for device <0000000000000001, 1>, LSID:9302 PSID:62970! [1658152266.236053][9448:9453] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 4 --> 5 [1658152266.236203][9448:9453] CHIP:TOO: cluster 0x0000_002D, attribute: 0x0000_0000, endpoint 0 [1658152266.236313][9448:9453] CHIP:DMG: WriteClient moving to [AddAttribu] [1658152266.236480][9448:9453] CHIP:IN: Prepared secure message 0xffff88005878 to 0x0000000000000001 (1) of type 0x6 and protocolId (0, 1) on exchange 8794i with MessageCounter:26695748. [1658152266.236553][9448:9453] CHIP:IN: Sending encrypted msg 0xffff88005878 with MessageCounter:26695748 to 0x0000000000000001 (1) at monotonic time: 0000000001C6E505 msec [1658152266.236829][9448:9453] CHIP:DMG: WriteClient moving to [AwaitingRe] [1658152266.236896][9448:9453] CHIP:EM: Sending Standalone Ack for MessageCounter:10140294 on exchange 8793i [1658152266.236962][9448:9453] CHIP:IN: Prepared unauthenticated message 0xffff9d1699d8 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 8793i with MessageCounter:195977166. [1658152266.237026][9448:9453] CHIP:IN: Sending unauthenticated msg 0xffff9d1699d8 with MessageCounter:195977166 to 0x0000000000000000 at monotonic time: 0000000001C6E506 msec [1658152266.237151][9448:9453] CHIP:EM: Flushed pending ack for MessageCounter:10140294 on exchange 8793i [1658152266.240309][9448:9453] CHIP:EM: Received message of type 0x7 with protocolId (0, 1) and MessageCounter:266575275 on exchange 8794i [1658152266.240374][9448:9453] CHIP:EM: Found matching exchange: 8794i, Delegate: 0xffff88007680 [1658152266.240432][9448:9453] CHIP:EM: Rxd Ack; Removing MessageCounter:26695748 from Retrans Table on exchange 8794i [1658152266.240477][9448:9453] CHIP:EM: Removed CHIP MessageCounter:26695748 from RetransTable on exchange 8794i [1658152266.240529][9448:9453] CHIP:DMG: WriteClient moving to [ResponseRe] [1658152266.240607][9448:9453] CHIP:DMG: WriteResponseMessage = [1658152266.240653][9448:9453] CHIP:DMG: { [1658152266.240693][9448:9453] CHIP:DMG: AttributeStatusIBs = [1658152266.240750][9448:9453] CHIP:DMG: [ [1658152266.240794][9448:9453] CHIP:DMG: AttributeStatusIB = [1658152266.240864][9448:9453] CHIP:DMG: { [1658152266.240912][9448:9453] CHIP:DMG: AttributePathIB = [1658152266.240968][9448:9453] CHIP:DMG: { [1658152266.241033][9448:9453] CHIP:DMG: Endpoint = 0x0, [1658152266.241097][9448:9453] CHIP:DMG: Cluster = 0x2d, [1658152266.241164][9448:9453] CHIP:DMG: Attribute = 0x0000_0000, [1658152266.241227][9448:9453] CHIP:DMG: } [1658152266.241290][9448:9453] CHIP:DMG: [1658152266.241346][9448:9453] CHIP:DMG: StatusIB = [1658152266.241402][9448:9453] CHIP:DMG: { [1658152266.241459][9448:9453] CHIP:DMG: status = 0x00 (SUCCESS), [1658152266.241515][9448:9453] CHIP:DMG: }, [1658152266.241570][9448:9453] CHIP:DMG: [1658152266.241620][9448:9453] CHIP:DMG: }, [1658152266.241673][9448:9453] CHIP:DMG: [1658152266.241716][9448:9453] CHIP:DMG: ], [1658152266.241770][9448:9453] CHIP:DMG: [1658152266.241813][9448:9453] CHIP:DMG: InteractionModelRevision = 1 [1658152266.241855][9448:9453] CHIP:DMG: } [1658152266.241971][9448:9453] CHIP:DMG: WriteClient moving to [AwaitingDe] [1658152266.242062][9448:9453] CHIP:EM: Sending Standalone Ack for MessageCounter:266575275 on exchange 8794i [1658152266.242257][9448:9453] CHIP:IN: Prepared secure message 0xffff9d1699e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 8794i with MessageCounter:26695749. [1658152266.242325][9448:9453] CHIP:IN: Sending encrypted msg 0xffff9d1699e8 with MessageCounter:26695749 to 0x0000000000000001 (1) at monotonic time: 0000000001C6E50B msec [1658152266.242492][9448:9453] CHIP:EM: Flushed pending ack for MessageCounter:266575275 on exchange 8794i [1658152266.242756][9448:9448] CHIP:CTL: Shutting down the commissioner [1658152266.242853][9448:9448] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152266.242986][9448:9448] CHIP:CTL: Shutting down the controller [1658152266.243041][9448:9448] CHIP:IN: Expiring all sessions for fabric 0x1!! [1658152266.243083][9448:9448] CHIP:IN: SecureSession[0xffff88001930]: MarkForEviction Type:2 LSID:9302 [1658152266.243124][9448:9448] CHIP:SC: SecureSession[0xffff88001930]: Moving from state 'kActive' --> 'kPendingEviction' [1658152266.243164][9448:9448] CHIP:IN: SecureSession[0xffff88001930]: Released - Type:2 LSID:9302 [1658152266.243208][9448:9448] CHIP:FP: Forgetting fabric 0x1 [1658152266.243263][9448:9448] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152266.243560][9448:9448] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152266.243590][9448:9448] CHIP:TS: Reverted Last Known Good Time to previous value [1658152266.243637][9448:9448] CHIP:CTL: Shutting down the commissioner [1658152266.243662][9448:9448] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152266.243729][9448:9448] CHIP:CTL: Shutting down the controller [1658152266.243755][9448:9448] CHIP:IN: Expiring all sessions for fabric 0x2!! [1658152266.243777][9448:9448] CHIP:FP: Forgetting fabric 0x2 [1658152266.243805][9448:9448] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152266.243945][9448:9448] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152266.243971][9448:9448] CHIP:TS: Reverted Last Known Good Time to previous value [1658152266.244005][9448:9448] CHIP:CTL: Shutting down the commissioner [1658152266.244030][9448:9448] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152266.244085][9448:9448] CHIP:CTL: Shutting down the controller [1658152266.244111][9448:9448] CHIP:IN: Expiring all sessions for fabric 0x3!! [1658152266.244133][9448:9448] CHIP:FP: Forgetting fabric 0x3 [1658152266.244159][9448:9448] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152266.244295][9448:9448] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152266.244321][9448:9448] CHIP:TS: Reverted Last Known Good Time to previous value [1658152266.244355][9448:9448] CHIP:CTL: Shutting down the commissioner [1658152266.244379][9448:9448] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152266.244437][9448:9448] CHIP:CTL: Shutting down the controller [1658152266.244462][9448:9448] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1658152266.245463][9448:9448] CHIP:DMG: IM WH moving to [Uninitialized] [1658152266.245503][9448:9448] CHIP:DMG: IM WH moving to [Uninitialized] [1658152266.245526][9448:9448] CHIP:DMG: IM WH moving to [Uninitialized] [1658152266.245550][9448:9448] CHIP:DMG: IM WH moving to [Uninitialized] [1658152266.245574][9448:9448] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1658152266.245651][9448:9448] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1658152266.245950][9448:9448] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-h7r22h) [1658152266.246704][9448:9448] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658152266.246773][9448:9448] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1658152266.246843][9448:9448] CHIP:DL: Inet Layer shutdown [1658152266.246869][9448:9448] CHIP:DL: BLE shutdown [1658152266.246894][9448:9448] CHIP:DL: System Layer shutdown ubuntu@ubuntu:~/JUl18_cntr/connectedhomeip/examples/chip-tool/out/debug$ ./chip-tool unitlocalization write temperature-unit 255 1 0 [1658152270.446255][9455:9455] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1658152270.447153][9455:9455] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1658152270.447357][9455:9455] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1658152270.447487][9455:9455] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1658152270.447856][9455:9455] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-FhM8BL) [1658152270.448548][9455:9455] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658152270.448617][9455:9455] CHIP:DL: NVS set: chip-counters/reboot-count = 31 (0x1F) [1658152270.449565][9455:9455] CHIP:DL: Got Ethernet interface: eth0 [1658152270.450234][9455:9455] CHIP:DL: Found the primary Ethernet interface:eth0 [1658152270.450924][9455:9455] CHIP:DL: Got WiFi interface: wlan0 [1658152270.451008][9455:9455] CHIP:DL: Failed to reset WiFi statistic counts [1658152270.451107][9455:9455] CHIP:IN: UDP::Init bind&listen port=0 [1658152270.451253][9455:9455] CHIP:IN: UDP::Init bound to port=46793 [1658152270.451282][9455:9455] CHIP:IN: BLEBase::Init - setting/overriding transport [1658152270.451304][9455:9455] CHIP:IN: TransportMgr initialized [1658152270.451339][9455:9455] CHIP:FP: Initializing FabricTable from persistent storage [1658152270.451556][9455:9455] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152270.452169][9455:9455] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x46B1F7A569FCEA56, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152270.452617][9455:9455] CHIP:FP: Fabric index 0x2 was retrieved from storage. Compressed FabricId 0xC0C9CFC9F5A9E5F8, FabricId 0x0000000000000002, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152270.453073][9455:9455] CHIP:FP: Fabric index 0x3 was retrieved from storage. Compressed FabricId 0x5E6B20D4A8CCF1A2, FabricId 0x0000000000000003, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152270.456539][9455:9455] CHIP:ZCL: Using ZAP configuration... [1658152270.460639][9455:9455] CHIP:DL: Avahi client registered [1658152270.461794][9455:9455] CHIP:CTL: System State Initialized... [1658152270.461881][9455:9455] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152270.461919][9455:9455] CHIP:CTL: Setting attestation nonce to random value [1658152270.461967][9455:9455] CHIP:CTL: Setting CSR nonce to random value [1658152270.462039][9455:9455] CHIP:IN: UDP::Init bind&listen port=5550 [1658152270.462179][9455:9455] CHIP:IN: UDP::Init bound to port=5550 [1658152270.462203][9455:9455] CHIP:IN: TransportMgr initialized [1658152270.462282][9455:9455] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152270.462310][9455:9455] CHIP:CTL: Setting attestation nonce to random value [1658152270.462357][9455:9455] CHIP:CTL: Setting CSR nonce to random value [1658152270.465189][9455:9455] CHIP:CTL: Generating NOC [1658152270.466394][9455:9455] CHIP:FP: Validating NOC chain [1658152270.468406][9455:9455] CHIP:FP: NOC chain validation successful [1658152270.468625][9455:9455] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1658152270.468669][9455:9455] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152270.468701][9455:9455] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152270.468730][9455:9455] CHIP:TS: Retaining current Last Known Good Time [1658152270.477174][9455:9455] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1658152270.479994][9455:9455] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152270.482291][9455:9455] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x0000000000000000 [1658152270.482346][9455:9455] CHIP:IN: UDP::Init bind&listen port=5550 [1658152270.482467][9455:9455] CHIP:IN: UDP::Init bound to port=5550 [1658152270.482491][9455:9455] CHIP:IN: TransportMgr initialized [1658152270.482600][9455:9455] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152270.482639][9455:9455] CHIP:CTL: Setting attestation nonce to random value [1658152270.482701][9455:9455] CHIP:CTL: Setting CSR nonce to random value [1658152270.483589][9455:9455] CHIP:CTL: Generating NOC [1658152270.484683][9455:9455] CHIP:FP: Validating NOC chain [1658152270.486395][9455:9455] CHIP:FP: NOC chain validation successful [1658152270.486552][9455:9455] CHIP:FP: Updated fabric at index: 0x2, Node ID: 0x000000000001B669 [1658152270.486584][9455:9455] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152270.486607][9455:9455] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152270.486628][9455:9455] CHIP:TS: Retaining current Last Known Good Time [1658152270.489404][9455:9455] CHIP:FP: Metadata for Fabric 0x2 persisted to storage. [1658152270.491840][9455:9455] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152270.494131][9455:9455] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x0000000000000000 [1658152270.494187][9455:9455] CHIP:IN: UDP::Init bind&listen port=5550 [1658152270.494307][9455:9455] CHIP:IN: UDP::Init bound to port=5550 [1658152270.494332][9455:9455] CHIP:IN: TransportMgr initialized [1658152270.494418][9455:9455] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152270.494456][9455:9455] CHIP:CTL: Setting attestation nonce to random value [1658152270.494515][9455:9455] CHIP:CTL: Setting CSR nonce to random value [1658152270.495469][9455:9455] CHIP:CTL: Generating NOC [1658152270.496569][9455:9455] CHIP:FP: Validating NOC chain [1658152270.498243][9455:9455] CHIP:FP: NOC chain validation successful [1658152270.498434][9455:9455] CHIP:FP: Updated fabric at index: 0x3, Node ID: 0x000000000001B669 [1658152270.498466][9455:9455] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152270.498489][9455:9455] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152270.498509][9455:9455] CHIP:TS: Retaining current Last Known Good Time [1658152270.501203][9455:9455] CHIP:FP: Metadata for Fabric 0x3 persisted to storage. [1658152270.503762][9455:9455] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152270.506071][9455:9455] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x0000000000000000 [1658152270.506125][9455:9455] CHIP:IN: UDP::Init bind&listen port=5550 [1658152270.506247][9455:9455] CHIP:IN: UDP::Init bound to port=5550 [1658152270.506272][9455:9455] CHIP:IN: TransportMgr initialized [1658152270.542095][9455:9460] CHIP:DL: CHIP task running [1658152270.542318][9455:9460] CHIP:DL: HandlePlatformSpecificBLEEvent 32784 [1658152270.542350][9455:9460] CHIP:TOO: Sending command to node 0x1 [1658152270.542384][9455:9460] CHIP:CSM: FindOrEstablishSession: PeerId = 46B1F7A569FCEA56:0000000000000001 [1658152270.542410][9455:9460] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1658152270.542447][9455:9460] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 1 --> 2 [1658152270.542477][9455:9460] CHIP:DIS: Resolving 46B1F7A569FCEA56:0000000000000001 ... [1658152270.544217][9455:9460] CHIP:DL: Avahi resolve found [1658152270.544293][9455:9460] CHIP:DIS: Node ID resolved for 46B1F7A569FCEA56:0000000000000001 [1658152270.544322][9455:9460] CHIP:DIS: Hostname: E45F0149AE290000 [1658152270.544356][9455:9460] CHIP:DIS: IP Address #1: fe80::e65f:1ff:fe49:ae29 [1658152270.544383][9455:9460] CHIP:DIS: Port: 5540 [1658152270.544407][9455:9460] CHIP:DIS: Mrp Interval idle: 5000 ms [1658152270.544431][9455:9460] CHIP:DIS: Mrp Interval active: 300 ms [1658152270.544689][9455:9460] CHIP:DIS: UDP:[fe80::e65f:1ff:fe49:ae29%eth0]:5540: new best score: 3 [1658152270.544716][9455:9460] CHIP:DIS: Checking node lookup status after 2 ms [1658152270.544737][9455:9460] CHIP:DIS: Keeping DNSSD lookup active [1658152270.742934][9455:9460] CHIP:DIS: Checking node lookup status after 201 ms [1658152270.743114][9455:9460] CHIP:CTL: Updating device address to UDP:[fe80::e65f:1ff:fe49:ae29%eth0]:5540 while in state 2 [1658152270.743144][9455:9460] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 2 --> 3 [1658152270.743345][9455:9460] CHIP:IN: SecureSession[0xffff80001930]: Allocated Type:2 LSID:5986 [1658152270.743393][9455:9460] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1658152270.744222][9455:9460] CHIP:SC: Including MRP parameters [1658152270.744423][9455:9460] CHIP:IN: Prepared unauthenticated message 0xffff80003128 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 59470i with MessageCounter:81139090. [1658152270.744480][9455:9460] CHIP:IN: Sending unauthenticated msg 0xffff80003128 with MessageCounter:81139090 to 0x0000000000000000 at monotonic time: 0000000001C6F6A1 msec [1658152270.744671][9455:9460] CHIP:SC: Sent Sigma1 msg [1658152270.744707][9455:9460] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 3 --> 4 [1658152270.751106][9455:9460] CHIP:EM: Received message of type 0x31 with protocolId (0, 0) and MessageCounter:10140295 on exchange 59470i [1658152270.751161][9455:9460] CHIP:EM: Found matching exchange: 59470i, Delegate: 0xffff800011a0 [1658152270.751201][9455:9460] CHIP:EM: Rxd Ack; Removing MessageCounter:81139090 from Retrans Table on exchange 59470i [1658152270.751227][9455:9460] CHIP:EM: Removed CHIP MessageCounter:81139090 from RetransTable on exchange 59470i [1658152270.751264][9455:9460] CHIP:SC: Received Sigma2 msg [1658152270.751327][9455:9460] CHIP:SC: Peer assigned session session ID 62971 [1658152270.754663][9455:9460] CHIP:SC: Found MRP parameters in the message [1658152270.754780][9455:9460] CHIP:SC: Sending Sigma3 [1658152270.755515][9455:9460] CHIP:EM: Piggybacking Ack for MessageCounter:10140295 on exchange: 59470i [1658152270.755623][9455:9460] CHIP:IN: Prepared unauthenticated message 0xffff80002d38 to 0x0000000000000000 (0) of type 0x32 and protocolId (0, 0) on exchange 59470i with MessageCounter:81139091. [1658152270.755676][9455:9460] CHIP:IN: Sending unauthenticated msg 0xffff80002d38 with MessageCounter:81139091 to 0x0000000000000000 at monotonic time: 0000000001C6F6AC msec [1658152270.755890][9455:9460] CHIP:SC: Sent Sigma3 msg [1658152270.764428][9455:9460] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:10140296 on exchange 59470i [1658152270.764480][9455:9460] CHIP:EM: Found matching exchange: 59470i, Delegate: 0xffff800011a0 [1658152270.764519][9455:9460] CHIP:EM: Rxd Ack; Removing MessageCounter:81139091 from Retrans Table on exchange 59470i [1658152270.764543][9455:9460] CHIP:EM: Removed CHIP MessageCounter:81139091 from RetransTable on exchange 59470i [1658152270.764582][9455:9460] CHIP:SC: Success status report received. Session was established [1658152270.769474][9455:9460] CHIP:SC: SecureSession[0xffff80001930]: Moving from state 'kEstablishing' --> 'kActive' [1658152270.769535][9455:9460] CHIP:IN: SecureSession[0xffff80001930]: Activated - Type:2 LSID:5986 [1658152270.769567][9455:9460] CHIP:IN: New secure session activated for device <0000000000000001, 1>, LSID:5986 PSID:62971! [1658152270.769606][9455:9460] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 4 --> 5 [1658152270.769724][9455:9460] CHIP:TOO: cluster 0x0000_002D, attribute: 0x0000_0000, endpoint 0 [1658152270.769809][9455:9460] CHIP:DMG: WriteClient moving to [AddAttribu] [1658152270.769947][9455:9460] CHIP:IN: Prepared secure message 0xffff80005868 to 0x0000000000000001 (1) of type 0x6 and protocolId (0, 1) on exchange 59471i with MessageCounter:66389980. [1658152270.770004][9455:9460] CHIP:IN: Sending encrypted msg 0xffff80005868 with MessageCounter:66389980 to 0x0000000000000001 (1) at monotonic time: 0000000001C6F6BB msec [1658152270.770217][9455:9460] CHIP:DMG: WriteClient moving to [AwaitingRe] [1658152270.770266][9455:9460] CHIP:EM: Sending Standalone Ack for MessageCounter:10140296 on exchange 59470i [1658152270.770314][9455:9460] CHIP:IN: Prepared unauthenticated message 0xffff94a569d8 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 59470i with MessageCounter:81139092. [1658152270.770360][9455:9460] CHIP:IN: Sending unauthenticated msg 0xffff94a569d8 with MessageCounter:81139092 to 0x0000000000000000 at monotonic time: 0000000001C6F6BB msec [1658152270.770454][9455:9460] CHIP:EM: Flushed pending ack for MessageCounter:10140296 on exchange 59470i [1658152270.773607][9455:9460] CHIP:EM: Received message of type 0x7 with protocolId (0, 1) and MessageCounter:130570819 on exchange 59471i [1658152270.773653][9455:9460] CHIP:EM: Found matching exchange: 59471i, Delegate: 0xffff80007670 [1658152270.773696][9455:9460] CHIP:EM: Rxd Ack; Removing MessageCounter:66389980 from Retrans Table on exchange 59471i [1658152270.773728][9455:9460] CHIP:EM: Removed CHIP MessageCounter:66389980 from RetransTable on exchange 59471i [1658152270.773768][9455:9460] CHIP:DMG: WriteClient moving to [ResponseRe] [1658152270.773828][9455:9460] CHIP:DMG: WriteResponseMessage = [1658152270.773862][9455:9460] CHIP:DMG: { [1658152270.773891][9455:9460] CHIP:DMG: AttributeStatusIBs = [1658152270.773932][9455:9460] CHIP:DMG: [ [1658152270.773964][9455:9460] CHIP:DMG: AttributeStatusIB = [1658152270.774000][9455:9460] CHIP:DMG: { [1658152270.774034][9455:9460] CHIP:DMG: AttributePathIB = [1658152270.774082][9455:9460] CHIP:DMG: { [1658152270.774124][9455:9460] CHIP:DMG: Endpoint = 0x0, [1658152270.774168][9455:9460] CHIP:DMG: Cluster = 0x2d, [1658152270.774212][9455:9460] CHIP:DMG: Attribute = 0x0000_0000, [1658152270.774254][9455:9460] CHIP:DMG: } [1658152270.774299][9455:9460] CHIP:DMG: [1658152270.774337][9455:9460] CHIP:DMG: StatusIB = [1658152270.774377][9455:9460] CHIP:DMG: { [1658152270.774417][9455:9460] CHIP:DMG: status = 0x00 (SUCCESS), [1658152270.774458][9455:9460] CHIP:DMG: }, [1658152270.774498][9455:9460] CHIP:DMG: [1658152270.774531][9455:9460] CHIP:DMG: }, [1658152270.774570][9455:9460] CHIP:DMG: [1658152270.774601][9455:9460] CHIP:DMG: ], [1658152270.774640][9455:9460] CHIP:DMG: [1658152270.774671][9455:9460] CHIP:DMG: InteractionModelRevision = 1 [1658152270.774702][9455:9460] CHIP:DMG: } [1658152270.774813][9455:9460] CHIP:DMG: WriteClient moving to [AwaitingDe] [1658152270.774887][9455:9460] CHIP:EM: Sending Standalone Ack for MessageCounter:130570819 on exchange 59471i [1658152270.774959][9455:9460] CHIP:IN: Prepared secure message 0xffff94a569e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 59471i with MessageCounter:66389981. [1658152270.775008][9455:9460] CHIP:IN: Sending encrypted msg 0xffff94a569e8 with MessageCounter:66389981 to 0x0000000000000001 (1) at monotonic time: 0000000001C6F6C0 msec [1658152270.775135][9455:9460] CHIP:EM: Flushed pending ack for MessageCounter:130570819 on exchange 59471i [1658152270.775365][9455:9455] CHIP:CTL: Shutting down the commissioner [1658152270.775406][9455:9455] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152270.775504][9455:9455] CHIP:CTL: Shutting down the controller [1658152270.775545][9455:9455] CHIP:IN: Expiring all sessions for fabric 0x1!! [1658152270.775576][9455:9455] CHIP:IN: SecureSession[0xffff80001930]: MarkForEviction Type:2 LSID:5986 [1658152270.775605][9455:9455] CHIP:SC: SecureSession[0xffff80001930]: Moving from state 'kActive' --> 'kPendingEviction' [1658152270.775635][9455:9455] CHIP:IN: SecureSession[0xffff80001930]: Released - Type:2 LSID:5986 [1658152270.775667][9455:9455] CHIP:FP: Forgetting fabric 0x1 [1658152270.775707][9455:9455] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152270.775928][9455:9455] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152270.775961][9455:9455] CHIP:TS: Reverted Last Known Good Time to previous value [1658152270.776011][9455:9455] CHIP:CTL: Shutting down the commissioner [1658152270.776041][9455:9455] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152270.776112][9455:9455] CHIP:CTL: Shutting down the controller [1658152270.776143][9455:9455] CHIP:IN: Expiring all sessions for fabric 0x2!! [1658152270.776252][9455:9455] CHIP:FP: Forgetting fabric 0x2 [1658152270.776287][9455:9455] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152270.776452][9455:9455] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152270.776484][9455:9455] CHIP:TS: Reverted Last Known Good Time to previous value [1658152270.776525][9455:9455] CHIP:CTL: Shutting down the commissioner [1658152270.776555][9455:9455] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152270.776622][9455:9455] CHIP:CTL: Shutting down the controller [1658152270.776652][9455:9455] CHIP:IN: Expiring all sessions for fabric 0x3!! [1658152270.776680][9455:9455] CHIP:FP: Forgetting fabric 0x3 [1658152270.776711][9455:9455] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152270.776866][9455:9455] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152270.776898][9455:9455] CHIP:TS: Reverted Last Known Good Time to previous value [1658152270.776938][9455:9455] CHIP:CTL: Shutting down the commissioner [1658152270.776967][9455:9455] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152270.777035][9455:9455] CHIP:CTL: Shutting down the controller [1658152270.777066][9455:9455] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1658152270.778172][9455:9455] CHIP:DMG: IM WH moving to [Uninitialized] [1658152270.778215][9455:9455] CHIP:DMG: IM WH moving to [Uninitialized] [1658152270.778240][9455:9455] CHIP:DMG: IM WH moving to [Uninitialized] [1658152270.778266][9455:9455] CHIP:DMG: IM WH moving to [Uninitialized] [1658152270.778294][9455:9455] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1658152270.778375][9455:9455] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1658152270.778690][9455:9455] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-ex4zI0) [1658152270.779506][9455:9455] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658152270.779581][9455:9455] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1658152270.779616][9455:9455] CHIP:DL: Inet Layer shutdown [1658152270.779642][9455:9455] CHIP:DL: BLE shutdown [1658152270.779669][9455:9455] CHIP:DL: System Layer shutdown ubuntu@ubuntu:~/JUl18_cntr/connectedhomeip/examples/chip-tool/out/debug$ ./chip-tool unitlocalization write temperature-unit 254 1 0 [1658152274.002889][9461:9461] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1658152274.003445][9461:9461] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1658152274.003609][9461:9461] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1658152274.003717][9461:9461] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1658152274.004071][9461:9461] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-7uhcIg) [1658152274.004700][9461:9461] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658152274.004751][9461:9461] CHIP:DL: NVS set: chip-counters/reboot-count = 32 (0x20) [1658152274.005626][9461:9461] CHIP:DL: Got Ethernet interface: eth0 [1658152274.006206][9461:9461] CHIP:DL: Found the primary Ethernet interface:eth0 [1658152274.006808][9461:9461] CHIP:DL: Got WiFi interface: wlan0 [1658152274.006882][9461:9461] CHIP:DL: Failed to reset WiFi statistic counts [1658152274.006970][9461:9461] CHIP:IN: UDP::Init bind&listen port=0 [1658152274.007104][9461:9461] CHIP:IN: UDP::Init bound to port=40863 [1658152274.007129][9461:9461] CHIP:IN: BLEBase::Init - setting/overriding transport [1658152274.007152][9461:9461] CHIP:IN: TransportMgr initialized [1658152274.007185][9461:9461] CHIP:FP: Initializing FabricTable from persistent storage [1658152274.007388][9461:9461] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152274.008017][9461:9461] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x46B1F7A569FCEA56, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152274.008448][9461:9461] CHIP:FP: Fabric index 0x2 was retrieved from storage. Compressed FabricId 0xC0C9CFC9F5A9E5F8, FabricId 0x0000000000000002, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152274.008870][9461:9461] CHIP:FP: Fabric index 0x3 was retrieved from storage. Compressed FabricId 0x5E6B20D4A8CCF1A2, FabricId 0x0000000000000003, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658152274.011899][9461:9461] CHIP:ZCL: Using ZAP configuration... [1658152274.016207][9461:9461] CHIP:DL: Avahi client registered [1658152274.017434][9461:9461] CHIP:CTL: System State Initialized... [1658152274.017529][9461:9461] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152274.017568][9461:9461] CHIP:CTL: Setting attestation nonce to random value [1658152274.017618][9461:9461] CHIP:CTL: Setting CSR nonce to random value [1658152274.017693][9461:9461] CHIP:IN: UDP::Init bind&listen port=5550 [1658152274.017836][9461:9461] CHIP:IN: UDP::Init bound to port=5550 [1658152274.017863][9461:9461] CHIP:IN: TransportMgr initialized [1658152274.017936][9461:9461] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152274.017967][9461:9461] CHIP:CTL: Setting attestation nonce to random value [1658152274.018018][9461:9461] CHIP:CTL: Setting CSR nonce to random value [1658152274.021052][9461:9461] CHIP:CTL: Generating NOC [1658152274.022325][9461:9461] CHIP:FP: Validating NOC chain [1658152274.024742][9461:9461] CHIP:FP: NOC chain validation successful [1658152274.024956][9461:9461] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1658152274.024997][9461:9461] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152274.025026][9461:9461] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152274.025052][9461:9461] CHIP:TS: Retaining current Last Known Good Time [1658152274.033062][9461:9461] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1658152274.035876][9461:9461] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152274.038360][9461:9461] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x0000000000000000 [1658152274.038420][9461:9461] CHIP:IN: UDP::Init bind&listen port=5550 [1658152274.038547][9461:9461] CHIP:IN: UDP::Init bound to port=5550 [1658152274.038572][9461:9461] CHIP:IN: TransportMgr initialized [1658152274.038677][9461:9461] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152274.038715][9461:9461] CHIP:CTL: Setting attestation nonce to random value [1658152274.038778][9461:9461] CHIP:CTL: Setting CSR nonce to random value [1658152274.039742][9461:9461] CHIP:CTL: Generating NOC [1658152274.041027][9461:9461] CHIP:FP: Validating NOC chain [1658152274.042859][9461:9461] CHIP:FP: NOC chain validation successful [1658152274.043028][9461:9461] CHIP:FP: Updated fabric at index: 0x2, Node ID: 0x000000000001B669 [1658152274.043060][9461:9461] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152274.043083][9461:9461] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152274.043104][9461:9461] CHIP:TS: Retaining current Last Known Good Time [1658152274.048620][9461:9461] CHIP:FP: Metadata for Fabric 0x2 persisted to storage. [1658152274.051176][9461:9461] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152274.053936][9461:9461] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x0000000000000000 [1658152274.054008][9461:9461] CHIP:IN: UDP::Init bind&listen port=5550 [1658152274.054136][9461:9461] CHIP:IN: UDP::Init bound to port=5550 [1658152274.054163][9461:9461] CHIP:IN: TransportMgr initialized [1658152274.054290][9461:9461] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152274.054333][9461:9461] CHIP:CTL: Setting attestation nonce to random value [1658152274.054397][9461:9461] CHIP:CTL: Setting CSR nonce to random value [1658152274.055450][9461:9461] CHIP:CTL: Generating NOC [1658152274.056792][9461:9461] CHIP:FP: Validating NOC chain [1658152274.059061][9461:9461] CHIP:FP: NOC chain validation successful [1658152274.059287][9461:9461] CHIP:FP: Updated fabric at index: 0x3, Node ID: 0x000000000001B669 [1658152274.059331][9461:9461] CHIP:TS: Last Known Good Time: 2022-07-18T10:26:20 [1658152274.059358][9461:9461] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658152274.059382][9461:9461] CHIP:TS: Retaining current Last Known Good Time [1658152274.062827][9461:9461] CHIP:FP: Metadata for Fabric 0x3 persisted to storage. [1658152274.065912][9461:9461] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T10:26:20 [1658152274.068902][9461:9461] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x0000000000000000 [1658152274.068976][9461:9461] CHIP:IN: UDP::Init bind&listen port=5550 [1658152274.069111][9461:9461] CHIP:IN: UDP::Init bound to port=5550 [1658152274.069140][9461:9461] CHIP:IN: TransportMgr initialized [1658152274.107958][9461:9466] CHIP:DL: CHIP task running [1658152274.108169][9461:9466] CHIP:DL: HandlePlatformSpecificBLEEvent 32784 [1658152274.108203][9461:9466] CHIP:TOO: Sending command to node 0x1 [1658152274.108262][9461:9466] CHIP:CSM: FindOrEstablishSession: PeerId = 46B1F7A569FCEA56:0000000000000001 [1658152274.108289][9461:9466] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1658152274.108326][9461:9466] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 1 --> 2 [1658152274.108364][9461:9466] CHIP:DIS: Resolving 46B1F7A569FCEA56:0000000000000001 ... [1658152274.110155][9461:9466] CHIP:DL: Avahi resolve found [1658152274.110233][9461:9466] CHIP:DIS: Node ID resolved for 46B1F7A569FCEA56:0000000000000001 [1658152274.110260][9461:9466] CHIP:DIS: Hostname: E45F0149AE290000 [1658152274.110288][9461:9466] CHIP:DIS: IP Address #1: fe80::e65f:1ff:fe49:ae29 [1658152274.110311][9461:9466] CHIP:DIS: Port: 5540 [1658152274.110333][9461:9466] CHIP:DIS: Mrp Interval idle: 5000 ms [1658152274.110356][9461:9466] CHIP:DIS: Mrp Interval active: 300 ms [1658152274.110638][9461:9466] CHIP:DIS: UDP:[fe80::e65f:1ff:fe49:ae29%eth0]:5540: new best score: 3 [1658152274.110666][9461:9466] CHIP:DIS: Checking node lookup status after 2 ms [1658152274.110687][9461:9466] CHIP:DIS: Keeping DNSSD lookup active [1658152274.308176][9461:9466] CHIP:DIS: Checking node lookup status after 200 ms [1658152274.308477][9461:9466] CHIP:CTL: Updating device address to UDP:[fe80::e65f:1ff:fe49:ae29%eth0]:5540 while in state 2 [1658152274.308541][9461:9466] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 2 --> 3 [1658152274.308975][9461:9466] CHIP:IN: SecureSession[0xffff78001930]: Allocated Type:2 LSID:38939 [1658152274.309063][9461:9466] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1658152274.310693][9461:9466] CHIP:SC: Including MRP parameters [1658152274.311149][9461:9466] CHIP:IN: Prepared unauthenticated message 0xffff78003138 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 65487i with MessageCounter:28721619. [1658152274.311253][9461:9466] CHIP:IN: Sending unauthenticated msg 0xffff78003138 with MessageCounter:28721619 to 0x0000000000000000 at monotonic time: 0000000001C70490 msec [1658152274.311611][9461:9466] CHIP:SC: Sent Sigma1 msg [1658152274.311680][9461:9466] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 3 --> 4 [1658152274.317433][9461:9466] CHIP:EM: Received message of type 0x31 with protocolId (0, 0) and MessageCounter:10140297 on exchange 65487i [1658152274.317507][9461:9466] CHIP:EM: Found matching exchange: 65487i, Delegate: 0xffff780011a0 [1658152274.317583][9461:9466] CHIP:EM: Rxd Ack; Removing MessageCounter:28721619 from Retrans Table on exchange 65487i [1658152274.317642][9461:9466] CHIP:EM: Removed CHIP MessageCounter:28721619 from RetransTable on exchange 65487i [1658152274.317718][9461:9466] CHIP:SC: Received Sigma2 msg [1658152274.317850][9461:9466] CHIP:SC: Peer assigned session session ID 62972 [1658152274.325154][9461:9466] CHIP:SC: Found MRP parameters in the message [1658152274.325213][9461:9466] CHIP:SC: Sending Sigma3 [1658152274.325880][9461:9466] CHIP:EM: Piggybacking Ack for MessageCounter:10140297 on exchange: 65487i [1658152274.325943][9461:9466] CHIP:IN: Prepared unauthenticated message 0xffff78002d48 to 0x0000000000000000 (0) of type 0x32 and protocolId (0, 0) on exchange 65487i with MessageCounter:28721620. [1658152274.325994][9461:9466] CHIP:IN: Sending unauthenticated msg 0xffff78002d48 with MessageCounter:28721620 to 0x0000000000000000 at monotonic time: 0000000001C7049F msec [1658152274.326212][9461:9466] CHIP:SC: Sent Sigma3 msg [1658152274.338533][9461:9466] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:10140298 on exchange 65487i [1658152274.338573][9461:9466] CHIP:EM: Found matching exchange: 65487i, Delegate: 0xffff780011a0 [1658152274.338612][9461:9466] CHIP:EM: Rxd Ack; Removing MessageCounter:28721620 from Retrans Table on exchange 65487i [1658152274.338641][9461:9466] CHIP:EM: Removed CHIP MessageCounter:28721620 from RetransTable on exchange 65487i [1658152274.338683][9461:9466] CHIP:SC: Success status report received. Session was established [1658152274.345304][9461:9466] CHIP:SC: SecureSession[0xffff78001930]: Moving from state 'kEstablishing' --> 'kActive' [1658152274.345384][9461:9466] CHIP:IN: SecureSession[0xffff78001930]: Activated - Type:2 LSID:38939 [1658152274.345423][9461:9466] CHIP:IN: New secure session activated for device <0000000000000001, 1>, LSID:38939 PSID:62972! [1658152274.345474][9461:9466] CHIP:CTL: OperationalDeviceProxy[46B1F7A569FCEA56:0000000000000001]: State change 4 --> 5 [1658152274.345625][9461:9466] CHIP:TOO: cluster 0x0000_002D, attribute: 0x0000_0000, endpoint 0 [1658152274.345731][9461:9466] CHIP:DMG: WriteClient moving to [AddAttribu] [1658152274.345899][9461:9466] CHIP:IN: Prepared secure message 0xffff78005878 to 0x0000000000000001 (1) of type 0x6 and protocolId (0, 1) on exchange 65488i with MessageCounter:159003598. [1658152274.345976][9461:9466] CHIP:IN: Sending encrypted msg 0xffff78005878 with MessageCounter:159003598 to 0x0000000000000001 (1) at monotonic time: 0000000001C704B3 msec [1658152274.346302][9461:9466] CHIP:DMG: WriteClient moving to [AwaitingRe] [1658152274.346375][9461:9466] CHIP:EM: Sending Standalone Ack for MessageCounter:10140298 on exchange 65487i [1658152274.346444][9461:9466] CHIP:IN: Prepared unauthenticated message 0xffff827cd9d8 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 65487i with MessageCounter:28721621. [1658152274.346508][9461:9466] CHIP:IN: Sending unauthenticated msg 0xffff827cd9d8 with MessageCounter:28721621 to 0x0000000000000000 at monotonic time: 0000000001C704B3 msec [1658152274.346671][9461:9466] CHIP:EM: Flushed pending ack for MessageCounter:10140298 on exchange 65487i [1658152274.350471][9461:9466] CHIP:EM: Received message of type 0x7 with protocolId (0, 1) and MessageCounter:204427657 on exchange 65488i [1658152274.350538][9461:9466] CHIP:EM: Found matching exchange: 65488i, Delegate: 0xffff78007680 [1658152274.350596][9461:9466] CHIP:EM: Rxd Ack; Removing MessageCounter:159003598 from Retrans Table on exchange 65488i [1658152274.350641][9461:9466] CHIP:EM: Removed CHIP MessageCounter:159003598 from RetransTable on exchange 65488i [1658152274.350694][9461:9466] CHIP:DMG: WriteClient moving to [ResponseRe] [1658152274.350829][9461:9466] CHIP:DMG: WriteResponseMessage = [1658152274.350878][9461:9466] CHIP:DMG: { [1658152274.350918][9461:9466] CHIP:DMG: AttributeStatusIBs = [1658152274.350993][9461:9466] CHIP:DMG: [ [1658152274.351052][9461:9466] CHIP:DMG: AttributeStatusIB = [1658152274.351110][9461:9466] CHIP:DMG: { [1658152274.351172][9461:9466] CHIP:DMG: AttributePathIB = [1658152274.351234][9461:9466] CHIP:DMG: { [1658152274.351314][9461:9466] CHIP:DMG: Endpoint = 0x0, [1658152274.351405][9461:9466] CHIP:DMG: Cluster = 0x2d, [1658152274.351469][9461:9466] CHIP:DMG: Attribute = 0x0000_0000, [1658152274.351553][9461:9466] CHIP:DMG: } [1658152274.351634][9461:9466] CHIP:DMG: [1658152274.351691][9461:9466] CHIP:DMG: StatusIB = [1658152274.351753][9461:9466] CHIP:DMG: { [1658152274.351792][9461:9466] CHIP:DMG: status = 0x00 (SUCCESS), [1658152274.351849][9461:9466] CHIP:DMG: }, [1658152274.351886][9461:9466] CHIP:DMG: [1658152274.351928][9461:9466] CHIP:DMG: }, [1658152274.351964][9461:9466] CHIP:DMG: [1658152274.352004][9461:9466] CHIP:DMG: ], [1658152274.352041][9461:9466] CHIP:DMG: [1658152274.352082][9461:9466] CHIP:DMG: InteractionModelRevision = 1 [1658152274.352110][9461:9466] CHIP:DMG: } [1658152274.352221][9461:9466] CHIP:DMG: WriteClient moving to [AwaitingDe] [1658152274.352306][9461:9466] CHIP:EM: Sending Standalone Ack for MessageCounter:204427657 on exchange 65488i [1658152274.352416][9461:9466] CHIP:IN: Prepared secure message 0xffff827cd9e8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 65488i with MessageCounter:159003599. [1658152274.352479][9461:9466] CHIP:IN: Sending encrypted msg 0xffff827cd9e8 with MessageCounter:159003599 to 0x0000000000000001 (1) at monotonic time: 0000000001C704B9 msec [1658152274.352629][9461:9466] CHIP:EM: Flushed pending ack for MessageCounter:204427657 on exchange 65488i [1658152274.352894][9461:9461] CHIP:CTL: Shutting down the commissioner [1658152274.352937][9461:9461] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152274.353036][9461:9461] CHIP:CTL: Shutting down the controller [1658152274.353080][9461:9461] CHIP:IN: Expiring all sessions for fabric 0x1!! [1658152274.353109][9461:9461] CHIP:IN: SecureSession[0xffff78001930]: MarkForEviction Type:2 LSID:38939 [1658152274.353138][9461:9461] CHIP:SC: SecureSession[0xffff78001930]: Moving from state 'kActive' --> 'kPendingEviction' [1658152274.353165][9461:9461] CHIP:IN: SecureSession[0xffff78001930]: Released - Type:2 LSID:38939 [1658152274.353195][9461:9461] CHIP:FP: Forgetting fabric 0x1 [1658152274.353236][9461:9461] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152274.353460][9461:9461] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152274.353495][9461:9461] CHIP:TS: Reverted Last Known Good Time to previous value [1658152274.353548][9461:9461] CHIP:CTL: Shutting down the commissioner [1658152274.353577][9461:9461] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152274.353652][9461:9461] CHIP:CTL: Shutting down the controller [1658152274.353682][9461:9461] CHIP:IN: Expiring all sessions for fabric 0x2!! [1658152274.353708][9461:9461] CHIP:FP: Forgetting fabric 0x2 [1658152274.353740][9461:9461] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152274.353899][9461:9461] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152274.353933][9461:9461] CHIP:TS: Reverted Last Known Good Time to previous value [1658152274.353976][9461:9461] CHIP:CTL: Shutting down the commissioner [1658152274.354003][9461:9461] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152274.354069][9461:9461] CHIP:CTL: Shutting down the controller [1658152274.354099][9461:9461] CHIP:IN: Expiring all sessions for fabric 0x3!! [1658152274.354124][9461:9461] CHIP:FP: Forgetting fabric 0x3 [1658152274.354155][9461:9461] CHIP:TS: Pending Last Known Good Time: 2022-07-18T10:26:20 [1658152274.354311][9461:9461] CHIP:TS: Previous Last Known Good Time: 2022-07-18T10:26:20 [1658152274.354341][9461:9461] CHIP:TS: Reverted Last Known Good Time to previous value [1658152274.354380][9461:9461] CHIP:CTL: Shutting down the commissioner [1658152274.354407][9461:9461] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658152274.354470][9461:9461] CHIP:CTL: Shutting down the controller [1658152274.354498][9461:9461] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1658152274.355668][9461:9461] CHIP:DMG: IM WH moving to [Uninitialized] [1658152274.355721][9461:9461] CHIP:DMG: IM WH moving to [Uninitialized] [1658152274.355772][9461:9461] CHIP:DMG: IM WH moving to [Uninitialized] [1658152274.355800][9461:9461] CHIP:DMG: IM WH moving to [Uninitialized] [1658152274.355827][9461:9461] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1658152274.355914][9461:9461] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1658152274.356253][9461:9461] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-Zc0xlA) [1658152274.357096][9461:9461] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658152274.357183][9461:9461] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1658152274.357218][9461:9461] CHIP:DL: Inet Layer shutdown [1658152274.357245][9461:9461] CHIP:DL: BLE shutdown [1658152274.357273][9461:9461] CHIP:DL: System Layer shutdown ubuntu@ubuntu:~/JUl18_cntr/connectedhomeip/examples/chip-tool/out/debug$ ./chip-tool unitlocalization write temperature-unit 256 1 0 [1658152278.864293][9468:9468] CHIP:TOO: InitArgs: Invalid argument attribute-values: 256 Usage: ./chip-tool unitlocalization write attribute-name attribute-values destination-id endpoint-id-ignored-for-group-commands [--paa-trust-store-path] [--commissioner-name] [--commissioner-nodeid] [--trace_file] [--trace_log] [--trace_decode] [--ble-adapter] [--timedInteractionTimeoutMs] [--data-version] [--suppressResponse] [--repeat-count] [--repeat-delay-ms] [--timeout] attribute-name: The attribute name to write. attribute-values: Comma-separated list of attribute values to write. destination-id: 64-bit node or group identifier. Group identifiers are detected by being in the 0xFFFF'FFFF'FFFF'xxxx range. endpoint-id-ignored-for-group-commands: Endpoint the command is targeted at. [--paa-trust-store-path]: Path to directory holding PAA certificate information. Can be absolute or relative to the current working directory. [--commissioner-name]: Name of fabric to use. Valid values are "alpha", "beta", "gamma", and integers greater than or equal to 4. [--commissioner-nodeid]: The node id to use for chip-tool. If not provided, kTestControllerNodeId (112233, 0x1B669) will be used. [--timedInteractionTimeoutMs]: If provided, do a timed write with the given timed interaction timeout. [--data-version]: Comma-separated list of data versions for the clusters being written. [1658152278.864618][9468:9468] CHIP:TOO: Run command failure: ../../commands/common/Commands.cpp:144: Error 0x0000002F ubuntu@ubuntu:~/JUl18_cntr/connectedhomeip/examples/chip-tool/out/debug$