ubuntu@ubuntu:~/master/connectedhomeip/examples/chip-tool/out/debug$ ./chip-tool formaldehydeconcentrationmeasurement read cluster-revision 1 1 [1687332961.571749][4163:4163] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1687332961.586945][4163:4163] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1687332961.587442][4163:4163] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1687332961.587755][4163:4163] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1687332961.588531][4163:4163] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-U3k38Y) [1687332961.589884][4163:4163] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1687332961.590023][4163:4163] CHIP:DL: NVS set: chip-counters/reboot-count = 103 (0x67) [1687332961.592454][4163:4163] CHIP:DL: Got Ethernet interface: eth0 [1687332961.594199][4163:4163] CHIP:DL: Found the primary Ethernet interface:eth0 [1687332961.595987][4163:4163] CHIP:DL: Got WiFi interface: wlan0 [1687332961.596163][4163:4163] CHIP:DL: Failed to reset WiFi statistic counts [1687332961.596338][4163:4163] CHIP:IN: UDP::Init bind&listen port=0 [1687332961.596602][4163:4163] CHIP:IN: UDP::Init bound to port=48040 [1687332961.596659][4163:4163] CHIP:IN: BLEBase::Init - setting/overriding transport [1687332961.596709][4163:4163] CHIP:IN: TransportMgr initialized [1687332961.596785][4163:4163] CHIP:FP: Initializing FabricTable from persistent storage [1687332961.597114][4163:4163] CHIP:TS: Last Known Good Time: 2023-06-19T05:36:12 [1687332961.600781][4163:4163] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x14EA8FF3ECFE2998, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1687332961.605741][4163:4163] CHIP:ZCL: Using ZAP configuration... [1687332961.615639][4163:4163] CHIP:DL: Avahi client registered [1687332961.618481][4163:4163] CHIP:CTL: System State Initialized... [1687332961.618666][4163:4163] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1687332961.618757][4163:4163] CHIP:CTL: Setting attestation nonce to random value [1687332961.618846][4163:4163] CHIP:CTL: Setting CSR nonce to random value [1687332961.618975][4163:4163] CHIP:IN: UDP::Init bind&listen port=5550 [1687332961.619246][4163:4163] CHIP:IN: UDP::Init bound to port=5550 [1687332961.619300][4163:4163] CHIP:IN: TransportMgr initialized [1687332961.619828][4163:4165] CHIP:DL: CHIP task running [1687332961.620250][4163:4165] CHIP:DL: HandlePlatformSpecificBLEEvent 32784 [1687332961.620877][4163:4165] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1687332961.620988][4163:4165] CHIP:CTL: Setting attestation nonce to random value [1687332961.621390][4163:4165] CHIP:CTL: Setting CSR nonce to random value [1687332961.623525][4163:4165] CHIP:CTL: Generating NOC [1687332961.625585][4163:4165] CHIP:FP: Validating NOC chain [1687332961.630321][4163:4165] CHIP:FP: NOC chain validation successful [1687332961.630713][4163:4165] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1687332961.630819][4163:4165] CHIP:TS: Last Known Good Time: 2023-06-19T05:36:12 [1687332961.630872][4163:4165] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1687332961.630921][4163:4165] CHIP:TS: Retaining current Last Known Good Time [1687332961.640996][4163:4165] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1687332961.644895][4163:4165] CHIP:TS: Committing Last Known Good Time to storage: 2023-06-19T05:36:12 [1687332961.648592][4163:4165] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 14EA8FF3ECFE2998) [1687332961.648696][4163:4165] CHIP:IN: UDP::Init bind&listen port=5550 [1687332961.648952][4163:4165] CHIP:IN: UDP::Init bound to port=5550 [1687332961.649008][4163:4165] CHIP:IN: TransportMgr initialized [1687332961.681957][4163:4165] CHIP:TOO: Sending command to node 0x1 [1687332961.683080][4163:4165] CHIP:CSM: FindOrEstablishSession: PeerId = [1:0000000000000001] [1687332961.683156][4163:4165] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found [1687332961.683234][4163:4165] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 1 --> 2 [1687332961.683307][4163:4165] CHIP:DIS: Resolving 14EA8FF3ECFE2998:0000000000000001 ... [1687332961.687680][4163:4165] CHIP:DL: Avahi resolve found [1687332961.687808][4163:4165] CHIP:DIS: Node ID resolved for 14EA8FF3ECFE2998:0000000000000001 [1687332961.687866][4163:4165] CHIP:DIS: Hostname: E45F010EBE370000 [1687332961.687930][4163:4165] CHIP:DIS: IP Address #1: fe80::e65f:1ff:fe0e:be36 [1687332961.687986][4163:4165] CHIP:DIS: Port: 5540 [1687332961.688038][4163:4165] CHIP:DIS: Mrp Interval idle: not present [1687332961.688087][4163:4165] CHIP:DIS: Mrp Interval active: not present [1687332961.688136][4163:4165] CHIP:DIS: TCP Supported: 1 [1687332961.688398][4163:4165] CHIP:DIS: UDP:[fe80::e65f:1ff:fe0e:be36%eth0]:5540: new best score: 3 [1687332961.688456][4163:4165] CHIP:DIS: Checking node lookup status after 5 ms [1687332961.688505][4163:4165] CHIP:DIS: Keeping DNSSD lookup active [1687332961.883427][4163:4165] CHIP:DIS: Checking node lookup status after 200 ms [1687332961.883725][4163:4165] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: Updating device address to UDP:[fe80::e65f:1ff:fe0e:be36%eth0]:5540 while in state 2 [1687332961.883789][4163:4165] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 2 --> 3 [1687332961.884021][4163:4165] CHIP:IN: SecureSession[0xffff7400ae80]: Allocated Type:2 LSID:50159 [1687332961.884115][4163:4165] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1687332961.886337][4163:4165] CHIP:EM: <<< [E:26629i S:0 M:262437736] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) [1687332961.886642][4163:4165] CHIP:IN: (U) Sending msg 262437736 to IP address 'UDP:[fe80::e65f:1ff:fe0e:be36%eth0]:5540' [1687332961.886943][4163:4165] CHIP:SC: Sent Sigma1 msg [1687332961.887012][4163:4165] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 3 --> 4 [1687332961.895039][4163:4165] CHIP:EM: >>> [E:26629i S:0 M:142689796 (Ack:262437736)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) [1687332961.895119][4163:4165] CHIP:EM: Found matching exchange: 26629i, Delegate: 0xffff7400dd28 [1687332961.895196][4163:4165] CHIP:EM: Rxd Ack; Removing MessageCounter:262437736 from Retrans Table on exchange 26629i [1687332961.895287][4163:4165] CHIP:SC: Received Sigma2 msg [1687332961.895366][4163:4165] CHIP:SC: Peer assigned session session ID 38162 [1687332961.905346][4163:4165] CHIP:SC: Sending Sigma3 [1687332961.906720][4163:4165] CHIP:EM: <<< [E:26629i S:0 M:262437737 (Ack:142689796)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) [1687332961.907033][4163:4165] CHIP:IN: (U) Sending msg 262437737 to IP address 'UDP:[fe80::e65f:1ff:fe0e:be36%eth0]:5540' [1687332961.907332][4163:4165] CHIP:SC: Sent Sigma3 msg [1687332961.923946][4163:4165] CHIP:EM: >>> [E:26629i S:0 M:142689797 (Ack:262437737)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) [1687332961.924026][4163:4165] CHIP:EM: Found matching exchange: 26629i, Delegate: 0xffff7400dd28 [1687332961.924101][4163:4165] CHIP:EM: Rxd Ack; Removing MessageCounter:262437737 from Retrans Table on exchange 26629i [1687332961.924194][4163:4165] CHIP:SC: Success status report received. Session was established [1687332961.931500][4163:4165] CHIP:SC: SecureSession[0xffff7400ae80, LSID:50159]: State change 'kEstablishing' --> 'kActive' [1687332961.931600][4163:4165] CHIP:IN: SecureSession[0xffff7400ae80]: Activated - Type:2 LSID:50159 [1687332961.931652][4163:4165] CHIP:IN: New secure session activated for device <0000000000000001, 1>, LSID:50159 PSID:38162! [1687332961.931715][4163:4165] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 4 --> 5 [1687332961.931839][4163:4165] CHIP:TOO: Sending ReadAttribute to: [1687332961.931985][4163:4165] CHIP:TOO: cluster 0x0000_042B, attribute: 0x0000_FFFD, endpoint 1 [1687332961.932077][4163:4165] CHIP:DMG: SendReadRequest ReadClient[0xffff74011370]: Sending Read Request [1687332961.932367][4163:4165] CHIP:EM: <<< [E:26630i S:50159 M:19996956] (S) Msg TX to 1:0000000000000001 [2998] --- Type 0001:02 (IM:ReadRequest) [1687332961.932467][4163:4165] CHIP:IN: (S) Sending msg 19996956 on secure session with LSID: 50159 [1687332961.932771][4163:4165] CHIP:DMG: MoveToState ReadClient[0xffff74011370]: Moving to [AwaitingIn] [1687332961.932921][4163:4165] CHIP:EM: <<< [E:26629i S:0 M:262437738 (Ack:142689797)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) [1687332961.933191][4163:4165] CHIP:IN: (U) Sending msg 262437738 to IP address 'UDP:[fe80::e65f:1ff:fe0e:be36%eth0]:5540' [1687332961.933355][4163:4165] CHIP:EM: Flushed pending ack for MessageCounter:142689797 on exchange 26629i [1687332961.936353][4163:4165] CHIP:EM: >>> [E:26630i S:50159 M:189057754 (Ack:19996956)] (S) Msg RX from 1:0000000000000001 [2998] --- Type 0001:05 (IM:ReportData) [1687332961.936432][4163:4165] CHIP:EM: Found matching exchange: 26630i, Delegate: 0xffff74011380 [1687332961.936508][4163:4165] CHIP:EM: Rxd Ack; Removing MessageCounter:19996956 from Retrans Table on exchange 26630i [1687332961.936637][4163:4165] CHIP:DMG: ReportDataMessage = [1687332961.936699][4163:4165] CHIP:DMG: { [1687332961.936749][4163:4165] CHIP:DMG: AttributeReportIBs = [1687332961.936829][4163:4165] CHIP:DMG: [ [1687332961.936886][4163:4165] CHIP:DMG: AttributeReportIB = [1687332961.936964][4163:4165] CHIP:DMG: { [1687332961.937025][4163:4165] CHIP:DMG: AttributeDataIB = [1687332961.937096][4163:4165] CHIP:DMG: { [1687332961.937170][4163:4165] CHIP:DMG: DataVersion = 0x25ddd9e6, [1687332961.937245][4163:4165] CHIP:DMG: AttributePathIB = [1687332961.937320][4163:4165] CHIP:DMG: { [1687332961.937400][4163:4165] CHIP:DMG: Endpoint = 0x1, [1687332961.937481][4163:4165] CHIP:DMG: Cluster = 0x42b, [1687332961.937561][4163:4165] CHIP:DMG: Attribute = 0x0000_FFFD, [1687332961.937637][4163:4165] CHIP:DMG: } [1687332961.937751][4163:4165] CHIP:DMG: [1687332961.937832][4163:4165] CHIP:DMG: Data = 1, [1687332961.937902][4163:4165] CHIP:DMG: }, [1687332961.937980][4163:4165] CHIP:DMG: [1687332961.938041][4163:4165] CHIP:DMG: }, [1687332961.938114][4163:4165] CHIP:DMG: [1687332961.938169][4163:4165] CHIP:DMG: ], [1687332961.938240][4163:4165] CHIP:DMG: [1687332961.938298][4163:4165] CHIP:DMG: SuppressResponse = true, [1687332961.938356][4163:4165] CHIP:DMG: InteractionModelRevision = 1 [1687332961.938411][4163:4165] CHIP:DMG: } [1687332961.938713][4163:4165] CHIP:TOO: Endpoint: 1 Cluster: 0x0000_042B Attribute 0x0000_FFFD DataVersion: 635296230 [1687332961.938854][4163:4165] CHIP:TOO: ClusterRevision: 1 [1687332961.939137][4163:4165] CHIP:EM: <<< [E:26630i S:50159 M:19996957 (Ack:189057754)] (S) Msg TX to 1:0000000000000001 [2998] --- Type 0000:10 (SecureChannel:StandaloneAck) [1687332961.939224][4163:4165] CHIP:IN: (S) Sending msg 19996957 on secure session with LSID: 50159 [1687332961.939421][4163:4165] CHIP:EM: Flushed pending ack for MessageCounter:189057754 on exchange 26630i [1687332961.939770][4163:4163] CHIP:CTL: Shutting down the commissioner [1687332961.939840][4163:4163] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1687332961.940020][4163:4163] CHIP:CTL: Shutting down the controller [1687332961.940124][4163:4163] CHIP:IN: Expiring all sessions for fabric 0x1!! [1687332961.940177][4163:4163] CHIP:IN: SecureSession[0xffff7400ae80]: MarkForEviction Type:2 LSID:50159 [1687332961.940230][4163:4163] CHIP:SC: SecureSession[0xffff7400ae80, LSID:50159]: State change 'kActive' --> 'kPendingEviction' [1687332961.940283][4163:4163] CHIP:IN: SecureSession[0xffff7400ae80]: Released - Type:2 LSID:50159 [1687332961.940340][4163:4163] CHIP:FP: Forgetting fabric 0x1 [1687332961.940409][4163:4163] CHIP:TS: Pending Last Known Good Time: 2023-06-19T05:36:12 [1687332961.940714][4163:4163] CHIP:TS: Previous Last Known Good Time: 2023-06-19T05:36:12 [1687332961.940771][4163:4163] CHIP:TS: Reverted Last Known Good Time to previous value [1687332961.940854][4163:4163] CHIP:CTL: Shutting down the commissioner [1687332961.940906][4163:4163] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1687332961.941050][4163:4163] CHIP:CTL: Shutting down the controller [1687332961.941105][4163:4163] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1687332961.943162][4163:4163] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1687332961.943353][4163:4163] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1687332961.943457][4163:4163] CHIP:FP: Shutting down FabricTable [1687332961.943528][4163:4163] CHIP:TS: Pending Last Known Good Time: 2023-06-19T05:36:12 [1687332961.943792][4163:4163] CHIP:TS: Previous Last Known Good Time: 2023-06-19T05:36:12 [1687332961.943974][4163:4163] CHIP:TS: Reverted Last Known Good Time to previous value [1687332961.944637][4163:4163] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-B8dMdH) [1687332961.946012][4163:4163] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1687332961.946149][4163:4163] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1687332961.946210][4163:4163] CHIP:DL: Inet Layer shutdown [1687332961.946259][4163:4163] CHIP:DL: BLE shutdown [1687332961.946309][4163:4163] CHIP:DL: System Layer shutdown ubuntu@ubuntu:~/master/connectedhomeip/examples/chip-tool/out/debug$