ubuntu@ubuntu:~/master/connectedhomeip/examples/chip-tool/out/debug$ ./chip-tool totalvolatileorganiccompoundsconcentrationmeasurement read cluster-revision 1 1 [1687339371.998364][5053:5053] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1687339372.013430][5053:5053] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1687339372.014001][5053:5053] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1687339372.014338][5053:5053] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1687339372.015124][5053:5053] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-dX6xWQ) [1687339372.016426][5053:5053] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1687339372.016552][5053:5053] CHIP:DL: NVS set: chip-counters/reboot-count = 51 (0x33) [1687339372.018983][5053:5053] CHIP:DL: Got Ethernet interface: eth0 [1687339372.020592][5053:5053] CHIP:DL: Found the primary Ethernet interface:eth0 [1687339372.022398][5053:5053] CHIP:DL: Got WiFi interface: wlan0 [1687339372.022564][5053:5053] CHIP:DL: Failed to reset WiFi statistic counts [1687339372.022733][5053:5053] CHIP:IN: UDP::Init bind&listen port=0 [1687339372.022989][5053:5053] CHIP:IN: UDP::Init bound to port=59960 [1687339372.023046][5053:5053] CHIP:IN: BLEBase::Init - setting/overriding transport [1687339372.023095][5053:5053] CHIP:IN: TransportMgr initialized [1687339372.023167][5053:5053] CHIP:FP: Initializing FabricTable from persistent storage [1687339372.023482][5053:5053] CHIP:TS: Last Known Good Time: 2023-06-19T05:36:12 [1687339372.027144][5053:5053] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x1B16FB350151CB67, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1687339372.032056][5053:5053] CHIP:ZCL: Using ZAP configuration... [1687339372.042383][5053:5053] CHIP:DL: Avahi client registered [1687339372.045643][5053:5053] CHIP:CTL: System State Initialized... [1687339372.045910][5053:5053] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1687339372.046004][5053:5053] CHIP:CTL: Setting attestation nonce to random value [1687339372.046104][5053:5053] CHIP:CTL: Setting CSR nonce to random value [1687339372.046239][5053:5053] CHIP:IN: UDP::Init bind&listen port=5550 [1687339372.046545][5053:5053] CHIP:IN: UDP::Init bound to port=5550 [1687339372.046711][5053:5053] CHIP:IN: TransportMgr initialized [1687339372.047300][5053:5055] CHIP:DL: CHIP task running [1687339372.047727][5053:5055] CHIP:DL: HandlePlatformSpecificBLEEvent 32784 [1687339372.048351][5053:5055] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1687339372.048476][5053:5055] CHIP:CTL: Setting attestation nonce to random value [1687339372.048860][5053:5055] CHIP:CTL: Setting CSR nonce to random value [1687339372.051124][5053:5055] CHIP:CTL: Generating NOC [1687339372.053142][5053:5055] CHIP:FP: Validating NOC chain [1687339372.057652][5053:5055] CHIP:FP: NOC chain validation successful [1687339372.058281][5053:5055] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1687339372.058359][5053:5055] CHIP:TS: Last Known Good Time: 2023-06-19T05:36:12 [1687339372.058413][5053:5055] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1687339372.058462][5053:5055] CHIP:TS: Retaining current Last Known Good Time [1687339372.069507][5053:5055] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1687339372.073938][5053:5055] CHIP:TS: Committing Last Known Good Time to storage: 2023-06-19T05:36:12 [1687339372.077932][5053:5055] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 1B16FB350151CB67) [1687339372.078038][5053:5055] CHIP:IN: UDP::Init bind&listen port=5550 [1687339372.078457][5053:5055] CHIP:IN: UDP::Init bound to port=5550 [1687339372.078519][5053:5055] CHIP:IN: TransportMgr initialized [1687339372.113080][5053:5055] CHIP:TOO: Sending command to node 0x1 [1687339372.114477][5053:5055] CHIP:CSM: FindOrEstablishSession: PeerId = [1:0000000000000001] [1687339372.114560][5053:5055] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found [1687339372.114639][5053:5055] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 1 --> 2 [1687339372.114712][5053:5055] CHIP:DIS: Resolving 1B16FB350151CB67:0000000000000001 ... [1687339372.119112][5053:5055] CHIP:DL: Avahi resolve found [1687339372.119247][5053:5055] CHIP:DIS: Node ID resolved for 1B16FB350151CB67:0000000000000001 [1687339372.119305][5053:5055] CHIP:DIS: Hostname: E45F010EBE370000 [1687339372.119370][5053:5055] CHIP:DIS: IP Address #1: fe80::e65f:1ff:fe0e:be36 [1687339372.119425][5053:5055] CHIP:DIS: Port: 5540 [1687339372.119478][5053:5055] CHIP:DIS: Mrp Interval idle: not present [1687339372.119531][5053:5055] CHIP:DIS: Mrp Interval active: not present [1687339372.119584][5053:5055] CHIP:DIS: TCP Supported: 1 [1687339372.119840][5053:5055] CHIP:DIS: UDP:[fe80::e65f:1ff:fe0e:be36%eth0]:5540: new best score: 3 [1687339372.119900][5053:5055] CHIP:DIS: Checking node lookup status after 5 ms [1687339372.119949][5053:5055] CHIP:DIS: Keeping DNSSD lookup active [1687339372.315038][5053:5055] CHIP:DIS: Checking node lookup status after 200 ms [1687339372.315346][5053:5055] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: Updating device address to UDP:[fe80::e65f:1ff:fe0e:be36%eth0]:5540 while in state 2 [1687339372.315408][5053:5055] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 2 --> 3 [1687339372.315644][5053:5055] CHIP:IN: SecureSession[0xffff7800ae80]: Allocated Type:2 LSID:137 [1687339372.315738][5053:5055] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1687339372.317648][5053:5055] CHIP:EM: <<< [E:2197i S:0 M:193775213] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) [1687339372.318024][5053:5055] CHIP:IN: (U) Sending msg 193775213 to IP address 'UDP:[fe80::e65f:1ff:fe0e:be36%eth0]:5540' [1687339372.318336][5053:5055] CHIP:SC: Sent Sigma1 msg [1687339372.318405][5053:5055] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 3 --> 4 [1687339372.326391][5053:5055] CHIP:EM: >>> [E:2197i S:0 M:106611886 (Ack:193775213)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) [1687339372.326468][5053:5055] CHIP:EM: Found matching exchange: 2197i, Delegate: 0xffff7800dd28 [1687339372.326545][5053:5055] CHIP:EM: Rxd Ack; Removing MessageCounter:193775213 from Retrans Table on exchange 2197i [1687339372.326636][5053:5055] CHIP:SC: Received Sigma2 msg [1687339372.326713][5053:5055] CHIP:SC: Peer assigned session session ID 25886 [1687339372.336692][5053:5055] CHIP:SC: Sending Sigma3 [1687339372.338047][5053:5055] CHIP:EM: <<< [E:2197i S:0 M:193775214 (Ack:106611886)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) [1687339372.338352][5053:5055] CHIP:IN: (U) Sending msg 193775214 to IP address 'UDP:[fe80::e65f:1ff:fe0e:be36%eth0]:5540' [1687339372.338649][5053:5055] CHIP:SC: Sent Sigma3 msg [1687339372.354957][5053:5055] CHIP:EM: >>> [E:2197i S:0 M:106611887 (Ack:193775214)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) [1687339372.355049][5053:5055] CHIP:EM: Found matching exchange: 2197i, Delegate: 0xffff7800dd28 [1687339372.355131][5053:5055] CHIP:EM: Rxd Ack; Removing MessageCounter:193775214 from Retrans Table on exchange 2197i [1687339372.355226][5053:5055] CHIP:SC: Success status report received. Session was established [1687339372.362554][5053:5055] CHIP:SC: SecureSession[0xffff7800ae80, LSID:137]: State change 'kEstablishing' --> 'kActive' [1687339372.362661][5053:5055] CHIP:IN: SecureSession[0xffff7800ae80]: Activated - Type:2 LSID:137 [1687339372.362907][5053:5055] CHIP:IN: New secure session activated for device <0000000000000001, 1>, LSID:137 PSID:25886! [1687339372.362975][5053:5055] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 4 --> 5 [1687339372.363102][5053:5055] CHIP:TOO: Sending ReadAttribute to: [1687339372.363249][5053:5055] CHIP:TOO: cluster 0x0000_042E, attribute: 0x0000_FFFD, endpoint 1 [1687339372.363338][5053:5055] CHIP:DMG: SendReadRequest ReadClient[0xffff78011370]: Sending Read Request [1687339372.363635][5053:5055] CHIP:EM: <<< [E:2198i S:137 M:119266952] (S) Msg TX to 1:0000000000000001 [CB67] --- Type 0001:02 (IM:ReadRequest) [1687339372.363733][5053:5055] CHIP:IN: (S) Sending msg 119266952 on secure session with LSID: 137 [1687339372.364035][5053:5055] CHIP:DMG: MoveToState ReadClient[0xffff78011370]: Moving to [AwaitingIn] [1687339372.364182][5053:5055] CHIP:EM: <<< [E:2197i S:0 M:193775215 (Ack:106611887)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) [1687339372.364449][5053:5055] CHIP:IN: (U) Sending msg 193775215 to IP address 'UDP:[fe80::e65f:1ff:fe0e:be36%eth0]:5540' [1687339372.364608][5053:5055] CHIP:EM: Flushed pending ack for MessageCounter:106611887 on exchange 2197i [1687339372.368029][5053:5055] CHIP:EM: >>> [E:2198i S:137 M:143848967 (Ack:119266952)] (S) Msg RX from 1:0000000000000001 [CB67] --- Type 0001:05 (IM:ReportData) [1687339372.368110][5053:5055] CHIP:EM: Found matching exchange: 2198i, Delegate: 0xffff78011380 [1687339372.368185][5053:5055] CHIP:EM: Rxd Ack; Removing MessageCounter:119266952 from Retrans Table on exchange 2198i [1687339372.368312][5053:5055] CHIP:DMG: ReportDataMessage = [1687339372.368374][5053:5055] CHIP:DMG: { [1687339372.368423][5053:5055] CHIP:DMG: AttributeReportIBs = [1687339372.368497][5053:5055] CHIP:DMG: [ [1687339372.368555][5053:5055] CHIP:DMG: AttributeReportIB = [1687339372.368631][5053:5055] CHIP:DMG: { [1687339372.368693][5053:5055] CHIP:DMG: AttributeDataIB = [1687339372.368764][5053:5055] CHIP:DMG: { [1687339372.368835][5053:5055] CHIP:DMG: DataVersion = 0x3c9cd304, [1687339372.368906][5053:5055] CHIP:DMG: AttributePathIB = [1687339372.368981][5053:5055] CHIP:DMG: { [1687339372.369057][5053:5055] CHIP:DMG: Endpoint = 0x1, [1687339372.369137][5053:5055] CHIP:DMG: Cluster = 0x42e, [1687339372.369217][5053:5055] CHIP:DMG: Attribute = 0x0000_FFFD, [1687339372.369293][5053:5055] CHIP:DMG: } [1687339372.369373][5053:5055] CHIP:DMG: [1687339372.369452][5053:5055] CHIP:DMG: Data = 1, [1687339372.369524][5053:5055] CHIP:DMG: }, [1687339372.369601][5053:5055] CHIP:DMG: [1687339372.369661][5053:5055] CHIP:DMG: }, [1687339372.369791][5053:5055] CHIP:DMG: [1687339372.369847][5053:5055] CHIP:DMG: ], [1687339372.370070][5053:5055] CHIP:DMG: [1687339372.370135][5053:5055] CHIP:DMG: SuppressResponse = true, [1687339372.370195][5053:5055] CHIP:DMG: InteractionModelRevision = 1 [1687339372.370249][5053:5055] CHIP:DMG: } [1687339372.370555][5053:5055] CHIP:TOO: Endpoint: 1 Cluster: 0x0000_042E Attribute 0x0000_FFFD DataVersion: 1016910596 [1687339372.370695][5053:5055] CHIP:TOO: ClusterRevision: 1 [1687339372.370960][5053:5055] CHIP:EM: <<< [E:2198i S:137 M:119266953 (Ack:143848967)] (S) Msg TX to 1:0000000000000001 [CB67] --- Type 0000:10 (SecureChannel:StandaloneAck) [1687339372.371052][5053:5055] CHIP:IN: (S) Sending msg 119266953 on secure session with LSID: 137 [1687339372.371245][5053:5055] CHIP:EM: Flushed pending ack for MessageCounter:143848967 on exchange 2198i [1687339372.371595][5053:5053] CHIP:CTL: Shutting down the commissioner [1687339372.371665][5053:5053] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1687339372.371844][5053:5053] CHIP:CTL: Shutting down the controller [1687339372.371947][5053:5053] CHIP:IN: Expiring all sessions for fabric 0x1!! [1687339372.372002][5053:5053] CHIP:IN: SecureSession[0xffff7800ae80]: MarkForEviction Type:2 LSID:137 [1687339372.372054][5053:5053] CHIP:SC: SecureSession[0xffff7800ae80, LSID:137]: State change 'kActive' --> 'kPendingEviction' [1687339372.372105][5053:5053] CHIP:IN: SecureSession[0xffff7800ae80]: Released - Type:2 LSID:137 [1687339372.372162][5053:5053] CHIP:FP: Forgetting fabric 0x1 [1687339372.372229][5053:5053] CHIP:TS: Pending Last Known Good Time: 2023-06-19T05:36:12 [1687339372.372547][5053:5053] CHIP:TS: Previous Last Known Good Time: 2023-06-19T05:36:12 [1687339372.372603][5053:5053] CHIP:TS: Reverted Last Known Good Time to previous value [1687339372.372686][5053:5053] CHIP:CTL: Shutting down the commissioner [1687339372.372738][5053:5053] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1687339372.372885][5053:5053] CHIP:CTL: Shutting down the controller [1687339372.372939][5053:5053] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1687339372.374917][5053:5053] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1687339372.375105][5053:5053] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1687339372.375206][5053:5053] CHIP:FP: Shutting down FabricTable [1687339372.375278][5053:5053] CHIP:TS: Pending Last Known Good Time: 2023-06-19T05:36:12 [1687339372.375538][5053:5053] CHIP:TS: Previous Last Known Good Time: 2023-06-19T05:36:12 [1687339372.375702][5053:5053] CHIP:TS: Reverted Last Known Good Time to previous value [1687339372.376261][5053:5053] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-ttm51X) [1687339372.377573][5053:5053] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1687339372.377758][5053:5053] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1687339372.377835][5053:5053] CHIP:DL: Inet Layer shutdown [1687339372.377962][5053:5053] CHIP:DL: BLE shutdown [1687339372.378015][5053:5053] CHIP:DL: System Layer shutdown ubuntu@ubuntu:~/master/connectedhomeip/examples/chip-tool/out/debug$