pi@ubuntu:~$ ./chip-tool binding read binding 1 1 [1666833978.944319][1941:1941] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1666833978.949767][1941:1941] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1666833978.950233][1941:1941] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1666833978.950368][1941:1941] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1666833978.950775][1941:1941] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-MTx3t3) [1666833978.951446][1941:1941] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1666833978.951518][1941:1941] CHIP:DL: NVS set: chip-counters/reboot-count = 13 (0xD) [1666833978.952473][1941:1941] CHIP:DL: Got Ethernet interface: eth0 [1666833978.953140][1941:1941] CHIP:DL: Found the primary Ethernet interface:eth0 [1666833978.953707][1941:1941] CHIP:DL: Got WiFi interface: wlan0 [1666833978.953797][1941:1941] CHIP:DL: Failed to reset WiFi statistic counts [1666833978.953913][1941:1941] CHIP:IN: UDP::Init bind&listen port=0 [1666833978.954091][1941:1941] CHIP:IN: UDP::Init bound to port=39687 [1666833978.954143][1941:1941] CHIP:IN: BLEBase::Init - setting/overriding transport [1666833978.954171][1941:1941] CHIP:IN: TransportMgr initialized [1666833978.954213][1941:1941] CHIP:FP: Initializing FabricTable from persistent storage [1666833978.954519][1941:1941] CHIP:TS: Last Known Good Time: 2023-02-02T22:46:27 [1666833978.956868][1941:1941] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xAC8528230FB2F420, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1666833978.960158][1941:1941] CHIP:ZCL: Using ZAP configuration... [1666833978.961517][1941:1941] CHIP:CTL: System State Initialized... [1666833978.961644][1941:1941] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1666833978.961712][1941:1941] CHIP:CTL: Setting attestation nonce to random value [1666833978.961777][1941:1941] CHIP:CTL: Setting CSR nonce to random value [1666833978.961859][1941:1941] CHIP:IN: UDP::Init bind&listen port=5550 [1666833978.961976][1941:1941] CHIP:IN: UDP::Init bound to port=5550 [1666833978.962039][1941:1941] CHIP:IN: TransportMgr initialized [1666833978.962387][1941:1946] CHIP:DL: CHIP task running [1666833978.962997][1941:1946] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1666833978.963044][1941:1946] CHIP:CTL: Setting attestation nonce to random value [1666833978.963221][1941:1946] CHIP:CTL: Setting CSR nonce to random value [1666833978.964315][1941:1946] CHIP:CTL: Generating NOC [1666833978.965453][1941:1946] CHIP:FP: Validating NOC chain [1666833978.967103][1941:1946] CHIP:FP: NOC chain validation successful [1666833978.967254][1941:1946] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1666833978.967285][1941:1946] CHIP:TS: Last Known Good Time: 2023-02-02T22:46:27 [1666833978.967306][1941:1946] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1666833978.967324][1941:1946] CHIP:TS: Retaining current Last Known Good Time [1666833978.973129][1941:1946] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1666833978.975391][1941:1946] CHIP:TS: Committing Last Known Good Time to storage: 2023-02-02T22:46:27 [1666833978.977409][1941:1946] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x0000000000000000 [1666833978.977466][1941:1946] CHIP:IN: UDP::Init bind&listen port=5550 [1666833978.977588][1941:1946] CHIP:IN: UDP::Init bound to port=5550 [1666833978.977614][1941:1946] CHIP:IN: TransportMgr initialized [1666833978.989104][1941:1946] CHIP:TOO: Sending command to node 0x1 [1666833978.990290][1941:1946] CHIP:CSM: FindOrEstablishSession: PeerId = [1:0000000000000001] [1666833978.990323][1941:1946] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found [1666833978.990354][1941:1946] CHIP:CTL: OperationalSessionSetup[1:0000000000000001]: State change 1 --> 2 [1666833978.991645][1941:1946] CHIP:DIS: Lookup clearing interface for non LL address [1666833978.991685][1941:1946] CHIP:DIS: UDP:[fd11:22::588a:7bbe:ad63:48e2%eth0]:5540: new best score: 6 [1666833978.991713][1941:1946] CHIP:DIS: Checking node lookup status after 1 ms [1666833978.991732][1941:1946] CHIP:DIS: Keeping DNSSD lookup active [1666833978.992292][1941:1946] CHIP:DIS: SRV record already actively processed. [1666833978.992369][1941:1946] CHIP:DIS: SRV record already actively processed. [1666833978.993529][1941:1946] CHIP:DIS: UDP:[fd11:22::588a:7bbe:ad63:48e2%eth0]:5540: score has not improved: 6 [1666833978.993571][1941:1946] CHIP:DIS: Checking node lookup status after 3 ms [1666833978.993591][1941:1946] CHIP:DIS: Keeping DNSSD lookup active [1666833979.190852][1941:1946] CHIP:DIS: Checking node lookup status after 200 ms [1666833979.190969][1941:1946] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: Updating device address to UDP:[fd11:22::588a:7bbe:ad63:48e2]:5540 while in state 2 [1666833979.191032][1941:1946] CHIP:CTL: OperationalSessionSetup[1:0000000000000001]: State change 2 --> 3 [1666833979.191270][1941:1946] CHIP:IN: SecureSession[0xffffa400b040]: Allocated Type:2 LSID:41498 [1666833979.191362][1941:1946] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1666833979.193443][1941:1946] CHIP:SC: Including MRP parameters [1666833979.193918][1941:1946] CHIP:EM: <<< [E:60766i M:34718962] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) [1666833979.194119][1941:1946] CHIP:IN: (U) Sending msg 34718962 to IP address 'UDP:[fd11:22::588a:7bbe:ad63:48e2]:5540' [1666833979.194444][1941:1946] CHIP:SC: Sent Sigma1 msg [1666833979.194519][1941:1946] CHIP:CTL: OperationalSessionSetup[1:0000000000000001]: State change 3 --> 4 [1666833979.309186][1941:1946] CHIP:EM: >>> [E:60766i M:151222299 (Ack:34718962)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) [1666833979.309266][1941:1946] CHIP:EM: Found matching exchange: 60766i, Delegate: 0xffffa400ec88 [1666833979.309332][1941:1946] CHIP:EM: Rxd Ack; Removing MessageCounter:34718962 from Retrans Table on exchange 60766i [1666833979.458903][1941:1946] CHIP:EM: >>> [E:60766i M:151222300 (Ack:34718962)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) [1666833979.458998][1941:1946] CHIP:EM: Found matching exchange: 60766i, Delegate: 0xffffa400ec88 [1666833979.459053][1941:1946] CHIP:EM: CHIP MessageCounter:34718962 not in RetransTable on exchange 60766i [1666833979.459141][1941:1946] CHIP:SC: Received Sigma2 msg [1666833979.459216][1941:1946] CHIP:SC: Peer assigned session session ID 34165 [1666833979.469485][1941:1946] CHIP:SC: Found MRP parameters in the message [1666833979.469787][1941:1946] CHIP:SC: Sending Sigma3 [1666833979.471446][1941:1946] CHIP:EM: <<< [E:60766i M:34718963 (Ack:151222300)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) [1666833979.471784][1941:1946] CHIP:IN: (U) Sending msg 34718963 to IP address 'UDP:[fd11:22::588a:7bbe:ad63:48e2%wpan0]:5540' [1666833979.472115][1941:1946] CHIP:SC: Sent Sigma3 msg [1666833979.818837][1941:1946] CHIP:IN: Received a duplicate message with MessageCounter:151222300 on exchange 60766i [1666833979.818939][1941:1946] CHIP:EM: >>> [E:60766i M:151222300 (Ack:34718962)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) [1666833979.819005][1941:1946] CHIP:EM: Found matching exchange: 60766i, Delegate: 0xffffa400ec88 [1666833979.819061][1941:1946] CHIP:EM: Forcing tx of solitary ack for duplicate MessageCounter:151222300 on exchange 60766i [1666833979.819149][1941:1946] CHIP:EM: <<< [E:60766i M:34718964 (Ack:151222300)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) [1666833979.819384][1941:1946] CHIP:IN: (U) Sending msg 34718964 to IP address 'UDP:[fd11:22::588a:7bbe:ad63:48e2%wpan0]:5540' [1666833979.828962][1941:1946] CHIP:EM: >>> [E:60766i M:151222301 (Ack:34718963)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) [1666833979.829059][1941:1946] CHIP:EM: Found matching exchange: 60766i, Delegate: 0xffffa400ec88 [1666833979.829201][1941:1946] CHIP:EM: Rxd Ack; Removing MessageCounter:34718963 from Retrans Table on exchange 60766i [1666833979.838942][1941:1946] CHIP:EM: >>> [E:60766i M:151222302 (Ack:34718963)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) [1666833979.839015][1941:1946] CHIP:EM: Found matching exchange: 60766i, Delegate: 0xffffa400ec88 [1666833979.839058][1941:1946] CHIP:EM: CHIP MessageCounter:34718963 not in RetransTable on exchange 60766i [1666833979.839134][1941:1946] CHIP:SC: Success status report received. Session was established [1666833979.844817][1941:1946] CHIP:SC: SecureSession[0xffffa400b040]: Moving from state 'kEstablishing' --> 'kActive' [1666833979.844895][1941:1946] CHIP:IN: SecureSession[0xffffa400b040]: Activated - Type:2 LSID:41498 [1666833979.844936][1941:1946] CHIP:IN: New secure session activated for device <0000000000000001, 1>, LSID:41498 PSID:34165! [1666833979.844983][1941:1946] CHIP:CTL: OperationalSessionSetup[1:0000000000000001]: State change 4 --> 5 [1666833979.845098][1941:1946] CHIP:TOO: cluster 0x0000_001E, attribute: 0x0000_0000, endpoint 1 [1666833979.845164][1941:1946] CHIP:TOO: Sending ReadAttribute to: [1666833979.845212][1941:1946] CHIP:DMG: SendReadRequest ReadClient[0xffffa4002ef0]: Sending Read Request [1666833979.845417][1941:1946] CHIP:EM: <<< [E:60767i M:126167758] (S) Msg TX to 1:0000000000000001 [F420] --- Type 0001:02 (IM:ReadRequest) [1666833979.845486][1941:1946] CHIP:IN: (S) Sending msg 126167758 on secure session with LSID: 41498 [1666833979.845737][1941:1946] CHIP:DMG: MoveToState ReadClient[0xffffa4002ef0]: Moving to [AwaitingIn] [1666833979.845863][1941:1946] CHIP:EM: <<< [E:60766i M:34718965 (Ack:151222302)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) [1666833979.846112][1941:1946] CHIP:IN: (U) Sending msg 34718965 to IP address 'UDP:[fd11:22::588a:7bbe:ad63:48e2%wpan0]:5540' [1666833979.846282][1941:1946] CHIP:EM: Flushed pending ack for MessageCounter:151222302 on exchange 60766i [1666833979.983919][1941:1946] CHIP:EM: >>> [E:60767i M:31351866 (Ack:126167758)] (S) Msg RX from 1:0000000000000001 [F420] --- Type 0001:05 (IM:ReportData) [1666833979.984016][1941:1946] CHIP:EM: Found matching exchange: 60767i, Delegate: 0xffffa4002f00 [1666833979.984097][1941:1946] CHIP:EM: Rxd Ack; Removing MessageCounter:126167758 from Retrans Table on exchange 60767i [1666833979.984377][1941:1946] CHIP:DMG: ReportDataMessage = [1666833979.984440][1941:1946] CHIP:DMG: { [1666833979.984488][1941:1946] CHIP:DMG: AttributeReportIBs = [1666833979.984558][1941:1946] CHIP:DMG: [ [1666833979.984613][1941:1946] CHIP:DMG: AttributeReportIB = [1666833979.984691][1941:1946] CHIP:DMG: { [1666833979.984751][1941:1946] CHIP:DMG: AttributeDataIB = [1666833979.984832][1941:1946] CHIP:DMG: { [1666833979.984962][1941:1946] CHIP:DMG: DataVersion = 0x1f15277e, [1666833979.985104][1941:1946] CHIP:DMG: AttributePathIB = [1666833979.985597][1941:1946] CHIP:DMG: { [1666833979.985734][1941:1946] CHIP:DMG: Endpoint = 0x1, [1666833979.985870][1941:1946] CHIP:DMG: Cluster = 0x1e, [1666833979.986296][1941:1946] CHIP:DMG: Attribute = 0x0000_0000, [1666833979.986425][1941:1946] CHIP:DMG: } [1666833979.986505][1941:1946] CHIP:DMG: [1666833979.986886][1941:1946] CHIP:DMG: Data = [ [1666833979.986962][1941:1946] CHIP:DMG: [1666833979.987048][1941:1946] CHIP:DMG: ], [1666833979.987126][1941:1946] CHIP:DMG: }, [1666833979.987203][1941:1946] CHIP:DMG: [1666833979.987264][1941:1946] CHIP:DMG: }, [1666833979.987359][1941:1946] CHIP:DMG: [1666833979.987416][1941:1946] CHIP:DMG: AttributeReportIB = [1666833979.987497][1941:1946] CHIP:DMG: { [1666833979.987559][1941:1946] CHIP:DMG: AttributeDataIB = [1666833979.987635][1941:1946] CHIP:DMG: { [1666833979.987712][1941:1946] CHIP:DMG: DataVersion = 0x1f15277e, [1666833979.987782][1941:1946] CHIP:DMG: AttributePathIB = [1666833979.987855][1941:1946] CHIP:DMG: { [1666833979.987937][1941:1946] CHIP:DMG: Endpoint = 0x1, [1666833979.988017][1941:1946] CHIP:DMG: Cluster = 0x1e, [1666833979.988096][1941:1946] CHIP:DMG: Attribute = 0x0000_0000, [1666833979.988171][1941:1946] CHIP:DMG: ListIndex = Null, [1666833979.988242][1941:1946] CHIP:DMG: } [1666833979.988318][1941:1946] CHIP:DMG: [1666833979.988395][1941:1946] CHIP:DMG: Data = [1666833979.988466][1941:1946] CHIP:DMG: { [1666833979.988587][1941:1946] CHIP:DMG: 0x1 = 2, [1666833979.988666][1941:1946] CHIP:DMG: 0x3 = 1, [1666833979.988744][1941:1946] CHIP:DMG: 0x4 = 6, [1666833979.988827][1941:1946] CHIP:DMG: 0xfe = 1, [1666833979.988900][1941:1946] CHIP:DMG: }, [1666833979.988965][1941:1946] CHIP:DMG: }, [1666833979.989046][1941:1946] CHIP:DMG: [1666833979.989106][1941:1946] CHIP:DMG: }, [1666833979.989201][1941:1946] CHIP:DMG: [1666833979.989259][1941:1946] CHIP:DMG: AttributeReportIB = [1666833979.989335][1941:1946] CHIP:DMG: { [1666833979.989393][1941:1946] CHIP:DMG: AttributeDataIB = [1666833979.989461][1941:1946] CHIP:DMG: { [1666833979.989526][1941:1946] CHIP:DMG: DataVersion = 0x1f15277e, [1666833979.989595][1941:1946] CHIP:DMG: AttributePathIB = [1666833979.989666][1941:1946] CHIP:DMG: { [1666833979.989736][1941:1946] CHIP:DMG: Endpoint = 0x1, [1666833979.989812][1941:1946] CHIP:DMG: Cluster = 0x1e, [1666833979.989890][1941:1946] CHIP:DMG: Attribute = 0x0000_0000, [1666833979.989971][1941:1946] CHIP:DMG: ListIndex = Null, [1666833979.990084][1941:1946] CHIP:DMG: } [1666833979.990166][1941:1946] CHIP:DMG: [1666833979.990230][1941:1946] CHIP:DMG: Data = [1666833979.990301][1941:1946] CHIP:DMG: { [1666833979.990376][1941:1946] CHIP:DMG: 0x1 = 3, [1666833979.990454][1941:1946] CHIP:DMG: 0x3 = 1, [1666833979.990530][1941:1946] CHIP:DMG: 0x4 = 6, [1666833979.990605][1941:1946] CHIP:DMG: 0xfe = 1, [1666833979.990688][1941:1946] CHIP:DMG: }, [1666833979.990761][1941:1946] CHIP:DMG: }, [1666833979.990841][1941:1946] CHIP:DMG: [1666833979.990901][1941:1946] CHIP:DMG: }, [1666833979.990995][1941:1946] CHIP:DMG: [1666833979.991048][1941:1946] CHIP:DMG: AttributeReportIB = [1666833979.991127][1941:1946] CHIP:DMG: { [1666833979.991188][1941:1946] CHIP:DMG: AttributeDataIB = [1666833979.991250][1941:1946] CHIP:DMG: { [1666833979.991320][1941:1946] CHIP:DMG: DataVersion = 0x1f15277e, [1666833979.991395][1941:1946] CHIP:DMG: AttributePathIB = [1666833979.991463][1941:1946] CHIP:DMG: { [1666833979.991535][1941:1946] CHIP:DMG: Endpoint = 0x1, [1666833979.991611][1941:1946] CHIP:DMG: Cluster = 0x1e, [1666833979.991688][1941:1946] CHIP:DMG: Attribute = 0x0000_0000, [1666833979.991764][1941:1946] CHIP:DMG: ListIndex = Null, [1666833979.991835][1941:1946] CHIP:DMG: } [1666833979.991912][1941:1946] CHIP:DMG: [1666833979.991982][1941:1946] CHIP:DMG: Data = [1666833979.992054][1941:1946] CHIP:DMG: { [1666833979.992128][1941:1946] CHIP:DMG: 0x1 = 4, [1666833979.992204][1941:1946] CHIP:DMG: 0x3 = 1, [1666833979.992280][1941:1946] CHIP:DMG: 0x4 = 6, [1666833979.992355][1941:1946] CHIP:DMG: 0xfe = 1, [1666833979.992436][1941:1946] CHIP:DMG: }, [1666833979.992504][1941:1946] CHIP:DMG: }, [1666833979.992581][1941:1946] CHIP:DMG: [1666833979.992643][1941:1946] CHIP:DMG: }, [1666833979.992737][1941:1946] CHIP:DMG: [1666833979.992790][1941:1946] CHIP:DMG: AttributeReportIB = [1666833979.992868][1941:1946] CHIP:DMG: { [1666833979.992929][1941:1946] CHIP:DMG: AttributeDataIB = [1666833979.992997][1941:1946] CHIP:DMG: { [1666833979.993204][1941:1946] CHIP:DMG: DataVersion = 0x1f15277e, [1666833979.993277][1941:1946] CHIP:DMG: AttributePathIB = [1666833979.993355][1941:1946] CHIP:DMG: { [1666833979.993429][1941:1946] CHIP:DMG: Endpoint = 0x1, [1666833979.993505][1941:1946] CHIP:DMG: Cluster = 0x1e, [1666833979.993582][1941:1946] CHIP:DMG: Attribute = 0x0000_0000, [1666833979.993664][1941:1946] CHIP:DMG: ListIndex = Null, [1666833979.993734][1941:1946] CHIP:DMG: } [1666833979.993809][1941:1946] CHIP:DMG: [1666833979.993878][1941:1946] CHIP:DMG: Data = [1666833979.993948][1941:1946] CHIP:DMG: { [1666833979.994055][1941:1946] CHIP:DMG: 0x1 = 5, [1666833979.994136][1941:1946] CHIP:DMG: 0x3 = 1, [1666833979.994218][1941:1946] CHIP:DMG: 0x4 = 6, [1666833979.994295][1941:1946] CHIP:DMG: 0xfe = 1, [1666833979.994369][1941:1946] CHIP:DMG: }, [1666833979.994436][1941:1946] CHIP:DMG: }, [1666833979.994515][1941:1946] CHIP:DMG: [1666833979.994574][1941:1946] CHIP:DMG: }, [1666833979.994671][1941:1946] CHIP:DMG: [1666833979.994724][1941:1946] CHIP:DMG: AttributeReportIB = [1666833979.994803][1941:1946] CHIP:DMG: { [1666833979.994860][1941:1946] CHIP:DMG: AttributeDataIB = [1666833979.994931][1941:1946] CHIP:DMG: { [1666833979.995007][1941:1946] CHIP:DMG: DataVersion = 0x1f15277e, [1666833979.995069][1941:1946] CHIP:DMG: AttributePathIB = [1666833979.995141][1941:1946] CHIP:DMG: { [1666833979.995213][1941:1946] CHIP:DMG: Endpoint = 0x1, [1666833979.995269][1941:1946] CHIP:DMG: Cluster = 0x1e, [1666833979.995301][1941:1946] CHIP:DMG: Attribute = 0x0000_0000, [1666833979.995332][1941:1946] CHIP:DMG: ListIndex = Null, [1666833979.995424][1941:1946] CHIP:DMG: } [1666833979.995458][1941:1946] CHIP:DMG: [1666833979.995487][1941:1946] CHIP:DMG: Data = [1666833979.995519][1941:1946] CHIP:DMG: { [1666833979.995549][1941:1946] CHIP:DMG: 0x1 = 6, [1666833979.995581][1941:1946] CHIP:DMG: 0x3 = 1, [1666833979.995613][1941:1946] CHIP:DMG: 0x4 = 6, [1666833979.995644][1941:1946] CHIP:DMG: 0xfe = 1, [1666833979.995675][1941:1946] CHIP:DMG: }, [1666833979.995703][1941:1946] CHIP:DMG: }, [1666833979.995739][1941:1946] CHIP:DMG: [1666833979.995764][1941:1946] CHIP:DMG: }, [1666833979.995806][1941:1946] CHIP:DMG: [1666833979.995830][1941:1946] CHIP:DMG: AttributeReportIB = [1666833979.995862][1941:1946] CHIP:DMG: { [1666833979.995886][1941:1946] CHIP:DMG: AttributeDataIB = [1666833979.995915][1941:1946] CHIP:DMG: { [1666833979.995944][1941:1946] CHIP:DMG: DataVersion = 0x1f15277e, [1666833979.995973][1941:1946] CHIP:DMG: AttributePathIB = [1666833979.996002][1941:1946] CHIP:DMG: { [1666833979.996034][1941:1946] CHIP:DMG: Endpoint = 0x1, [1666833979.996066][1941:1946] CHIP:DMG: Cluster = 0x1e, [1666833979.996098][1941:1946] CHIP:DMG: Attribute = 0x0000_0000, [1666833979.996131][1941:1946] CHIP:DMG: ListIndex = Null, [1666833979.996159][1941:1946] CHIP:DMG: } [1666833979.996193][1941:1946] CHIP:DMG: [1666833979.996222][1941:1946] CHIP:DMG: Data = [1666833979.996253][1941:1946] CHIP:DMG: { [1666833979.996286][1941:1946] CHIP:DMG: 0x1 = 7, [1666833979.996318][1941:1946] CHIP:DMG: 0x3 = 1, [1666833979.996347][1941:1946] CHIP:DMG: 0x4 = 6, [1666833979.996381][1941:1946] CHIP:DMG: 0xfe = 1, [1666833979.996411][1941:1946] CHIP:DMG: }, [1666833979.996439][1941:1946] CHIP:DMG: }, [1666833979.996474][1941:1946] CHIP:DMG: [1666833979.996498][1941:1946] CHIP:DMG: }, [1666833979.996536][1941:1946] CHIP:DMG: [1666833979.996558][1941:1946] CHIP:DMG: AttributeReportIB = [1666833979.996591][1941:1946] CHIP:DMG: { [1666833979.996614][1941:1946] CHIP:DMG: AttributeDataIB = [1666833979.996642][1941:1946] CHIP:DMG: { [1666833979.996671][1941:1946] CHIP:DMG: DataVersion = 0x1f15277e, [1666833979.996699][1941:1946] CHIP:DMG: AttributePathIB = [1666833979.996729][1941:1946] CHIP:DMG: { [1666833979.996760][1941:1946] CHIP:DMG: Endpoint = 0x1, [1666833979.996793][1941:1946] CHIP:DMG: Cluster = 0x1e, [1666833979.996825][1941:1946] CHIP:DMG: Attribute = 0x0000_0000, [1666833979.996855][1941:1946] CHIP:DMG: ListIndex = Null, [1666833979.996885][1941:1946] CHIP:DMG: } [1666833979.996914][1941:1946] CHIP:DMG: [1666833979.996943][1941:1946] CHIP:DMG: Data = [1666833979.996972][1941:1946] CHIP:DMG: { [1666833979.997002][1941:1946] CHIP:DMG: 0x1 = 8, [1666833979.997037][1941:1946] CHIP:DMG: 0x3 = 1, [1666833979.997069][1941:1946] CHIP:DMG: 0x4 = 6, [1666833979.997100][1941:1946] CHIP:DMG: 0xfe = 1, [1666833979.997131][1941:1946] CHIP:DMG: }, [1666833979.997158][1941:1946] CHIP:DMG: }, [1666833979.997191][1941:1946] CHIP:DMG: [1666833979.997216][1941:1946] CHIP:DMG: }, [1666833979.997255][1941:1946] CHIP:DMG: [1666833979.997277][1941:1946] CHIP:DMG: AttributeReportIB = [1666833979.997310][1941:1946] CHIP:DMG: { [1666833979.997335][1941:1946] CHIP:DMG: AttributeDataIB = [1666833979.997365][1941:1946] CHIP:DMG: { [1666833979.997396][1941:1946] CHIP:DMG: DataVersion = 0x1f15277e, [1666833979.997425][1941:1946] CHIP:DMG: AttributePathIB = [1666833979.997453][1941:1946] CHIP:DMG: { [1666833979.997483][1941:1946] CHIP:DMG: Endpoint = 0x1, [1666833979.997514][1941:1946] CHIP:DMG: Cluster = 0x1e, [1666833979.997547][1941:1946] CHIP:DMG: Attribute = 0x0000_0000, [1666833979.997578][1941:1946] CHIP:DMG: ListIndex = Null, [1666833979.997610][1941:1946] CHIP:DMG: } [1666833979.997639][1941:1946] CHIP:DMG: [1666833979.997670][1941:1946] CHIP:DMG: Data = [1666833979.997699][1941:1946] CHIP:DMG: { [1666833979.997730][1941:1946] CHIP:DMG: 0x1 = 9, [1666833979.997762][1941:1946] CHIP:DMG: 0x3 = 1, [1666833979.997793][1941:1946] CHIP:DMG: 0x4 = 6, [1666833979.997825][1941:1946] CHIP:DMG: 0xfe = 1, [1666833979.997859][1941:1946] CHIP:DMG: }, [1666833979.997889][1941:1946] CHIP:DMG: }, [1666833979.997922][1941:1946] CHIP:DMG: [1666833979.997947][1941:1946] CHIP:DMG: }, [1666833979.997986][1941:1946] CHIP:DMG: [1666833979.998028][1941:1946] CHIP:DMG: AttributeReportIB = [1666833979.998061][1941:1946] CHIP:DMG: { [1666833979.998086][1941:1946] CHIP:DMG: AttributeDataIB = [1666833979.998113][1941:1946] CHIP:DMG: { [1666833979.998142][1941:1946] CHIP:DMG: DataVersion = 0x1f15277e, [1666833979.998170][1941:1946] CHIP:DMG: AttributePathIB = [1666833979.998199][1941:1946] CHIP:DMG: { [1666833979.998229][1941:1946] CHIP:DMG: Endpoint = 0x1, [1666833979.998260][1941:1946] CHIP:DMG: Cluster = 0x1e, [1666833979.998292][1941:1946] CHIP:DMG: Attribute = 0x0000_0000, [1666833979.998323][1941:1946] CHIP:DMG: ListIndex = Null, [1666833979.998352][1941:1946] CHIP:DMG: } [1666833979.998381][1941:1946] CHIP:DMG: [1666833979.998410][1941:1946] CHIP:DMG: Data = [1666833979.998438][1941:1946] CHIP:DMG: { [1666833979.998469][1941:1946] CHIP:DMG: 0x1 = 10, [1666833979.998503][1941:1946] CHIP:DMG: 0x3 = 1, [1666833979.998535][1941:1946] CHIP:DMG: 0x4 = 6, [1666833979.998565][1941:1946] CHIP:DMG: 0xfe = 1, [1666833979.998596][1941:1946] CHIP:DMG: }, [1666833979.998626][1941:1946] CHIP:DMG: }, [1666833979.998659][1941:1946] CHIP:DMG: [1666833979.998683][1941:1946] CHIP:DMG: }, [1666833979.998723][1941:1946] CHIP:DMG: [1666833979.998745][1941:1946] CHIP:DMG: AttributeReportIB = [1666833979.998777][1941:1946] CHIP:DMG: { [1666833979.998803][1941:1946] CHIP:DMG: AttributeDataIB = [1666833979.998831][1941:1946] CHIP:DMG: { [1666833979.998862][1941:1946] CHIP:DMG: DataVersion = 0x1f15277e, [1666833979.998890][1941:1946] CHIP:DMG: AttributePathIB = [1666833979.998921][1941:1946] CHIP:DMG: { [1666833979.998951][1941:1946] CHIP:DMG: Endpoint = 0x1, [1666833979.998982][1941:1946] CHIP:DMG: Cluster = 0x1e, [1666833979.999014][1941:1946] CHIP:DMG: Attribute = 0x0000_0000, [1666833979.999044][1941:1946] CHIP:DMG: ListIndex = Null, [1666833979.999073][1941:1946] CHIP:DMG: } [1666833979.999099][1941:1946] CHIP:DMG: [1666833979.999127][1941:1946] CHIP:DMG: Data = [1666833979.999156][1941:1946] CHIP:DMG: { [1666833979.999186][1941:1946] CHIP:DMG: 0x1 = 11, [1666833979.999218][1941:1946] CHIP:DMG: 0x3 = 1, [1666833979.999249][1941:1946] CHIP:DMG: 0x4 = 6, [1666833979.999283][1941:1946] CHIP:DMG: 0xfe = 1, [1666833979.999314][1941:1946] CHIP:DMG: }, [1666833979.999342][1941:1946] CHIP:DMG: }, [1666833979.999374][1941:1946] CHIP:DMG: [1666833979.999401][1941:1946] CHIP:DMG: }, [1666833979.999431][1941:1946] CHIP:DMG: [1666833979.999452][1941:1946] CHIP:DMG: ], [1666833979.999546][1941:1946] CHIP:DMG: [1666833979.999569][1941:1946] CHIP:DMG: SuppressResponse = true, [1666833979.999592][1941:1946] CHIP:DMG: InteractionModelRevision = 1 [1666833979.999617][1941:1946] CHIP:DMG: } [1666833980.000632][1941:1946] CHIP:TOO: Endpoint: 1 Cluster: 0x0000_001E Attribute 0x0000_0000 DataVersion: 521480062 [1666833980.000726][1941:1946] CHIP:TOO: Binding: 10 entries [1666833980.000773][1941:1946] CHIP:TOO: [1]: { [1666833980.000806][1941:1946] CHIP:TOO: Node: 2 [1666833980.000829][1941:1946] CHIP:TOO: Endpoint: 1 [1666833980.000850][1941:1946] CHIP:TOO: Cluster: 6 [1666833980.000869][1941:1946] CHIP:TOO: FabricIndex: 1 [1666833980.000889][1941:1946] CHIP:TOO: } [1666833980.000916][1941:1946] CHIP:TOO: [2]: { [1666833980.000938][1941:1946] CHIP:TOO: Node: 3 [1666833980.000958][1941:1946] CHIP:TOO: Endpoint: 1 [1666833980.000978][1941:1946] CHIP:TOO: Cluster: 6 [1666833980.000997][1941:1946] CHIP:TOO: FabricIndex: 1 [1666833980.001017][1941:1946] CHIP:TOO: } [1666833980.001045][1941:1946] CHIP:TOO: [3]: { [1666833980.001066][1941:1946] CHIP:TOO: Node: 4 [1666833980.001085][1941:1946] CHIP:TOO: Endpoint: 1 [1666833980.001105][1941:1946] CHIP:TOO: Cluster: 6 [1666833980.001124][1941:1946] CHIP:TOO: FabricIndex: 1 [1666833980.001143][1941:1946] CHIP:TOO: } [1666833980.001171][1941:1946] CHIP:TOO: [4]: { [1666833980.001192][1941:1946] CHIP:TOO: Node: 5 [1666833980.001212][1941:1946] CHIP:TOO: Endpoint: 1 [1666833980.001231][1941:1946] CHIP:TOO: Cluster: 6 [1666833980.001251][1941:1946] CHIP:TOO: FabricIndex: 1 [1666833980.001270][1941:1946] CHIP:TOO: } [1666833980.001296][1941:1946] CHIP:TOO: [5]: { [1666833980.001316][1941:1946] CHIP:TOO: Node: 6 [1666833980.001336][1941:1946] CHIP:TOO: Endpoint: 1 [1666833980.001356][1941:1946] CHIP:TOO: Cluster: 6 [1666833980.001376][1941:1946] CHIP:TOO: FabricIndex: 1 [1666833980.001395][1941:1946] CHIP:TOO: } [1666833980.001422][1941:1946] CHIP:TOO: [6]: { [1666833980.001443][1941:1946] CHIP:TOO: Node: 7 [1666833980.001463][1941:1946] CHIP:TOO: Endpoint: 1 [1666833980.001482][1941:1946] CHIP:TOO: Cluster: 6 [1666833980.001501][1941:1946] CHIP:TOO: FabricIndex: 1 [1666833980.001520][1941:1946] CHIP:TOO: } [1666833980.001546][1941:1946] CHIP:TOO: [7]: { [1666833980.001567][1941:1946] CHIP:TOO: Node: 8 [1666833980.001586][1941:1946] CHIP:TOO: Endpoint: 1 [1666833980.001609][1941:1946] CHIP:TOO: Cluster: 6 [1666833980.001628][1941:1946] CHIP:TOO: FabricIndex: 1 [1666833980.001647][1941:1946] CHIP:TOO: } [1666833980.001673][1941:1946] CHIP:TOO: [8]: { [1666833980.001694][1941:1946] CHIP:TOO: Node: 9 [1666833980.001714][1941:1946] CHIP:TOO: Endpoint: 1 [1666833980.001733][1941:1946] CHIP:TOO: Cluster: 6 [1666833980.001753][1941:1946] CHIP:TOO: FabricIndex: 1 [1666833980.001772][1941:1946] CHIP:TOO: } [1666833980.001798][1941:1946] CHIP:TOO: [9]: { [1666833980.001819][1941:1946] CHIP:TOO: Node: 10 [1666833980.001841][1941:1946] CHIP:TOO: Endpoint: 1 [1666833980.001861][1941:1946] CHIP:TOO: Cluster: 6 [1666833980.001880][1941:1946] CHIP:TOO: FabricIndex: 1 [1666833980.001899][1941:1946] CHIP:TOO: } [1666833980.001926][1941:1946] CHIP:TOO: [10]: { [1666833980.001947][1941:1946] CHIP:TOO: Node: 11 [1666833980.001966][1941:1946] CHIP:TOO: Endpoint: 1 [1666833980.001986][1941:1946] CHIP:TOO: Cluster: 6 [1666833980.002022][1941:1946] CHIP:TOO: FabricIndex: 1 [1666833980.002042][1941:1946] CHIP:TOO: } [1666833980.002266][1941:1946] CHIP:EM: <<< [E:60767i M:126167759 (Ack:31351866)] (S) Msg TX to 1:0000000000000001 [F420] --- Type 0000:10 (SecureChannel:StandaloneAck) [1666833980.002309][1941:1946] CHIP:IN: (S) Sending msg 126167759 on secure session with LSID: 41498 [1666833980.002444][1941:1946] CHIP:EM: Flushed pending ack for MessageCounter:31351866 on exchange 60767i [1666833980.002670][1941:1941] CHIP:CTL: Shutting down the commissioner [1666833980.002708][1941:1941] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1666833980.002856][1941:1941] CHIP:CTL: Shutting down the controller [1666833980.002890][1941:1941] CHIP:IN: Expiring all sessions for fabric 0x1!! [1666833980.002911][1941:1941] CHIP:IN: SecureSession[0xffffa400b040]: MarkForEviction Type:2 LSID:41498 [1666833980.002933][1941:1941] CHIP:SC: SecureSession[0xffffa400b040]: Moving from state 'kActive' --> 'kPendingEviction' [1666833980.002954][1941:1941] CHIP:IN: SecureSession[0xffffa400b040]: Released - Type:2 LSID:41498 [1666833980.002977][1941:1941] CHIP:FP: Forgetting fabric 0x1 [1666833980.003008][1941:1941] CHIP:TS: Pending Last Known Good Time: 2023-02-02T22:46:27 [1666833980.003211][1941:1941] CHIP:TS: Previous Last Known Good Time: 2023-02-02T22:46:27 [1666833980.003237][1941:1941] CHIP:TS: Reverted Last Known Good Time to previous value [1666833980.003284][1941:1941] CHIP:CTL: Shutting down the commissioner [1666833980.003305][1941:1941] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1666833980.003433][1941:1941] CHIP:CTL: Shutting down the controller [1666833980.003458][1941:1941] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1666833980.003729][1941:1941] CHIP:DMG: IM WH moving to [Uninitialized] [1666833980.003754][1941:1941] CHIP:DMG: IM WH moving to [Uninitialized] [1666833980.003772][1941:1941] CHIP:DMG: IM WH moving to [Uninitialized] [1666833980.003790][1941:1941] CHIP:DMG: IM WH moving to [Uninitialized] [1666833980.003839][1941:1941] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1666833980.003899][1941:1941] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1666833980.004254][1941:1941] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-YqyaoU) [1666833980.004945][1941:1941] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1666833980.005005][1941:1941] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1666833980.005038][1941:1941] CHIP:DL: Inet Layer shutdown [1666833980.005059][1941:1941] CHIP:DL: BLE shutdown [1666833980.005078][1941:1941] CHIP:DL: System Layer shutdown pi@ubuntu:~$