pi@ubuntu:~$ ./chip-tool doorlock read number-of-week-day-schedules-supported-per-user 1 1 [1655346025.860793][4561:4561] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1655346025.861377][4561:4561] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1655346025.861561][4561:4561] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1655346025.861678][4561:4561] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1655346025.862112][4561:4561] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-NZki2o) [1655346025.862712][4561:4561] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655346025.862767][4561:4561] CHIP:DL: NVS set: chip-counters/reboot-count = 16 (0x10) [1655346025.863453][4561:4561] CHIP:DL: Got Ethernet interface: eth0 [1655346025.864027][4561:4561] CHIP:DL: Found the primary Ethernet interface:eth0 [1655346025.864480][4561:4561] CHIP:DL: Got WiFi interface: wlan0 [1655346025.864561][4561:4561] CHIP:DL: Failed to reset WiFi statistic counts [1655346025.864646][4561:4561] CHIP:IN: UDP::Init bind&listen port=0 [1655346025.864776][4561:4561] CHIP:IN: UDP::Init bound to port=50454 [1655346025.864804][4561:4561] CHIP:IN: UDP::Init bind&listen port=0 [1655346025.864960][4561:4561] CHIP:IN: UDP::Init bound to port=55263 [1655346025.864984][4561:4561] CHIP:IN: BLEBase::Init - setting/overriding transport [1655346025.865044][4561:4561] CHIP:IN: TransportMgr initialized [1655346025.865112][4561:4561] CHIP:FP: Initializing FabricTable from persistent storage [1655346025.865467][4561:4561] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655346025.865622][4561:4561] CHIP:FP: Loading from storage for fabric index 0x1 [1655346025.866301][4561:4561] CHIP:FP: Loading from storage for fabric index 0x2 [1655346025.866828][4561:4561] CHIP:FP: Loading from storage for fabric index 0x3 [1655346025.871071][4561:4561] CHIP:ZCL: Using ZAP configuration... [1655346025.873929][4561:4561] CHIP:DL: MDNS failed to join multicast group on wlan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655346025.877161][4561:4561] CHIP:DL: MDNS failed to join multicast group on wpan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655346025.877544][4561:4561] CHIP:CTL: System State Initialized... [1655346025.877605][4561:4561] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655346025.877670][4561:4561] CHIP:CTL: Setting attestation nonce to random value [1655346025.877735][4561:4561] CHIP:CTL: Setting CSR nonce to random value [1655346025.877810][4561:4561] CHIP:IN: UDP::Init bind&listen port=5550 [1655346025.877985][4561:4561] CHIP:IN: UDP::Init bound to port=5550 [1655346025.878016][4561:4561] CHIP:IN: UDP::Init bind&listen port=5550 [1655346025.878135][4561:4561] CHIP:IN: UDP::Init bound to port=5550 [1655346025.878161][4561:4561] CHIP:IN: TransportMgr initialized [1655346025.878236][4561:4561] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655346025.878267][4561:4561] CHIP:CTL: Setting attestation nonce to random value [1655346025.878317][4561:4561] CHIP:CTL: Setting CSR nonce to random value [1655346025.881082][4561:4561] CHIP:CTL: Generating NOC [1655346025.882017][4561:4561] CHIP:FP: Validating NOC chain [1655346025.883780][4561:4561] CHIP:FP: NOC chain validation successful [1655346025.883938][4561:4561] CHIP:FP: Added new fabric at index: 0x1, Initialized: 1 [1655346025.883966][4561:4561] CHIP:FP: Assigned compressed fabric ID: 0xA7866B26C80C3D35, node ID: 0x000000000001B669 [1655346025.890214][4561:4561] CHIP:FP: Fabric (0x1) persisted to storage. Calling OnFabricPersistedToStorage [1655346025.890275][4561:4561] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655346025.890301][4561:4561] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655346025.890323][4561:4561] CHIP:TS: Retaining current Last Known Good Time [1655346025.892039][4561:4561] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0xA7866B26C80C3D35 [1655346025.892124][4561:4561] CHIP:IN: UDP::Init bind&listen port=5550 [1655346025.892263][4561:4561] CHIP:IN: UDP::Init bound to port=5550 [1655346025.892292][4561:4561] CHIP:IN: UDP::Init bind&listen port=5550 [1655346025.892387][4561:4561] CHIP:IN: UDP::Init bound to port=5550 [1655346025.892412][4561:4561] CHIP:IN: TransportMgr initialized [1655346025.892527][4561:4561] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655346025.892567][4561:4561] CHIP:CTL: Setting attestation nonce to random value [1655346025.892628][4561:4561] CHIP:CTL: Setting CSR nonce to random value [1655346025.893630][4561:4561] CHIP:CTL: Generating NOC [1655346025.894507][4561:4561] CHIP:FP: Validating NOC chain [1655346025.896221][4561:4561] CHIP:FP: NOC chain validation successful [1655346025.896418][4561:4561] CHIP:FP: Added new fabric at index: 0x2, Initialized: 1 [1655346025.896446][4561:4561] CHIP:FP: Assigned compressed fabric ID: 0x06EE9B0E253A2288, node ID: 0x000000000001B669 [1655346025.902245][4561:4561] CHIP:FP: Fabric (0x2) persisted to storage. Calling OnFabricPersistedToStorage [1655346025.902298][4561:4561] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655346025.902323][4561:4561] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655346025.902346][4561:4561] CHIP:TS: Retaining current Last Known Good Time [1655346025.903856][4561:4561] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x06EE9B0E253A2288 [1655346025.903930][4561:4561] CHIP:IN: UDP::Init bind&listen port=5550 [1655346025.904070][4561:4561] CHIP:IN: UDP::Init bound to port=5550 [1655346025.904098][4561:4561] CHIP:IN: UDP::Init bind&listen port=5550 [1655346025.904189][4561:4561] CHIP:IN: UDP::Init bound to port=5550 [1655346025.904214][4561:4561] CHIP:IN: TransportMgr initialized [1655346025.904354][4561:4561] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655346025.904397][4561:4561] CHIP:CTL: Setting attestation nonce to random value [1655346025.904490][4561:4561] CHIP:CTL: Setting CSR nonce to random value [1655346025.905486][4561:4561] CHIP:CTL: Generating NOC [1655346025.906336][4561:4561] CHIP:FP: Validating NOC chain [1655346025.908146][4561:4561] CHIP:FP: NOC chain validation successful [1655346025.908304][4561:4561] CHIP:FP: Added new fabric at index: 0x3, Initialized: 1 [1655346025.908333][4561:4561] CHIP:FP: Assigned compressed fabric ID: 0x884B85B37DC4EE27, node ID: 0x000000000001B669 [1655346025.917961][4561:4561] CHIP:FP: Fabric (0x3) persisted to storage. Calling OnFabricPersistedToStorage [1655346025.918015][4561:4561] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655346025.918039][4561:4561] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655346025.918061][4561:4561] CHIP:TS: Retaining current Last Known Good Time [1655346025.919851][4561:4561] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x884B85B37DC4EE27 [1655346025.919940][4561:4561] CHIP:IN: UDP::Init bind&listen port=5550 [1655346025.920102][4561:4561] CHIP:IN: UDP::Init bound to port=5550 [1655346025.920136][4561:4561] CHIP:IN: UDP::Init bind&listen port=5550 [1655346025.920250][4561:4561] CHIP:IN: UDP::Init bound to port=5550 [1655346025.920279][4561:4561] CHIP:IN: TransportMgr initialized [1655346025.956684][4561:4566] CHIP:DL: CHIP task running [1655346025.956822][4561:4566] CHIP:TOO: Sending command to node 0x1 [1655346025.956866][4561:4566] CHIP:CSM: FindOrEstablishSession: PeerId = A7866B26C80C3D35:0000000000000001 [1655346025.956893][4561:4566] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1655346025.957023][4561:4566] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 1 --> 2 [1655346025.957099][4561:4566] CHIP:DIS: MINMDNS: Sending query ANY/UNICAST for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655346025.957797][4561:4566] CHIP:DIS: Warning: Attempt to mDNS broadcast failed on wlan0: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable [1655346025.958234][4561:4566] CHIP:DIS: mDNS broadcast had only partial success: 7 successes and 1 failures. [1655346025.958514][4561:4566] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655346025.958574][4561:4566] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 822FF8DD5E22EEF6.local [1655346025.958606][4561:4566] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655346025.958649][4561:4566] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655346025.958682][4561:4566] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:af47:ced6:6f88:1c68 found for 822FF8DD5E22EEF6.local [1655346025.959330][4561:4566] CHIP:DIS: Lookup clearing interface for non LL address [1655346025.959377][4561:4566] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:af47:ced6:6f88:1c68%eth0]:5540: new best score: 6 [1655346025.959407][4561:4566] CHIP:DIS: Checking node lookup status after 3 ms [1655346025.959430][4561:4566] CHIP:DIS: Keeping DNSSD lookup active [1655346025.959578][4561:4566] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655346025.959634][4561:4566] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 822FF8DD5E22EEF6.local [1655346025.959665][4561:4566] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655346025.959704][4561:4566] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655346025.959736][4561:4566] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:af47:ced6:6f88:1c68 found for 822FF8DD5E22EEF6.local [1655346025.959765][4561:4566] CHIP:DIS: Discovered node without a pending query [1655346025.960204][4561:4566] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:af47:ced6:6f88:1c68%eth0]:5540: score has not improved: 6 [1655346025.960239][4561:4566] CHIP:DIS: Checking node lookup status after 4 ms [1655346025.960261][4561:4566] CHIP:DIS: Keeping DNSSD lookup active [1655346026.157275][4561:4566] CHIP:DIS: Checking node lookup status after 201 ms [1655346026.157389][4561:4566] CHIP:CTL: Updating device address to UDP:[fd12:c411:e4a6:d11c:af47:ced6:6f88:1c68]:5540 while in state 2 [1655346026.157448][4561:4566] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 2 --> 3 [1655346026.157836][4561:4566] CHIP:IN: SecureSession[0xaaaaf1e45c50]: Allocated Type:2 LSID:24659 [1655346026.157921][4561:4566] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1655346026.159418][4561:4566] CHIP:SC: Including MRP parameters [1655346026.159854][4561:4566] CHIP:IN: Prepared unauthenticated message 0xaaaaf1e43fe8 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 45470i with MessageCounter:79444844. [1655346026.159957][4561:4566] CHIP:IN: Sending unauthenticated msg 0xaaaaf1e43fe8 with MessageCounter:79444844 to 0x0000000000000000 at monotonic time: 00000000013D6C9A msec [1655346026.160310][4561:4566] CHIP:SC: Sent Sigma1 msg [1655346026.160381][4561:4566] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 3 --> 4 [1655346026.160446][4561:4566] CHIP:DIS: Discovery does not require any more timeouts [1655346026.278444][4561:4566] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:38919627 on exchange 45470i [1655346026.278530][4561:4566] CHIP:EM: Found matching exchange: 45470i, Delegate: 0xffffa00015c0 [1655346026.278598][4561:4566] CHIP:EM: Rxd Ack; Removing MessageCounter:79444844 from Retrans Table on exchange 45470i [1655346026.278645][4561:4566] CHIP:EM: Removed CHIP MessageCounter:79444844 from RetransTable on exchange 45470i [1655346026.298088][4561:4566] CHIP:EM: Received message of type 0x33 with protocolId (0, 0) and MessageCounter:38919628 on exchange 45470i [1655346026.298169][4561:4566] CHIP:EM: Found matching exchange: 45470i, Delegate: 0xffffa00015c0 [1655346026.298221][4561:4566] CHIP:EM: CHIP MessageCounter:79444844 not in RetransTable on exchange 45470i [1655346026.298290][4561:4566] CHIP:SC: Received Sigma2Resume msg [1655346026.298458][4561:4566] CHIP:SC: Found MRP parameters in the message [1655346026.298516][4561:4566] CHIP:SC: Peer assigned session session ID 31149 [1655346026.306407][4561:4566] CHIP:SC: Sending status report. Protocol code 0, exchange 45470 [1655346026.306513][4561:4566] CHIP:EM: Piggybacking Ack for MessageCounter:38919628 on exchange: 45470i [1655346026.306591][4561:4566] CHIP:IN: Prepared unauthenticated message 0xaaaaf1e43fe8 to 0x0000000000000000 (0) of type 0x40 and protocolId (0, 0) on exchange 45470i with MessageCounter:79444845. [1655346026.306671][4561:4566] CHIP:IN: Sending unauthenticated msg 0xaaaaf1e43fe8 with MessageCounter:79444845 to 0x0000000000000000 at monotonic time: 00000000013D6D2D msec [1655346026.307098][4561:4566] CHIP:IN: SecureSession[0xaaaaf1e45c50]: Activated - Type:2 LSID:24659 [1655346026.307162][4561:4566] CHIP:IN: New secure session created for device <0000000000000001, 1>, LSID:24659 PSID:31149! [1655346026.307207][4561:4566] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 4 --> 5 [1655346026.307345][4561:4566] CHIP:TOO: cluster 0x0000_0101, attribute: 0x0000_0014, endpoint 1 [1655346026.307409][4561:4566] CHIP:TOO: Sending ReadAttribute to: [1655346026.307473][4561:4566] CHIP:DMG: SendReadRequest ReadClient[0xffffa0003cb0]: Sending Read Request [1655346026.307683][4561:4566] CHIP:IN: Prepared secure message 0xaaaaf1e44008 to 0x0000000000000001 (1) of type 0x2 and protocolId (0, 1) on exchange 45471i with MessageCounter:104210987. [1655346026.307764][4561:4566] CHIP:IN: Sending encrypted msg 0xaaaaf1e44008 with MessageCounter:104210987 to 0x0000000000000001 (1) at monotonic time: 00000000013D6D2E msec [1655346026.308047][4561:4566] CHIP:DMG: MoveToState ReadClient[0xffffa0003cb0]: Moving to [AwaitingIn] [1655346026.368533][4561:4566] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:38919629 on exchange 45470i [1655346026.368638][4561:4566] CHIP:EM: Found matching exchange: 45470i, Delegate: (nil) [1655346026.368743][4561:4566] CHIP:EM: Rxd Ack; Removing MessageCounter:79444845 from Retrans Table on exchange 45470i [1655346026.368808][4561:4566] CHIP:EM: Removed CHIP MessageCounter:79444845 from RetransTable on exchange 45470i [1655346026.393550][4561:4566] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:150912144 on exchange 45471i [1655346026.393644][4561:4566] CHIP:EM: Found matching exchange: 45471i, Delegate: 0xffffa0003cb0 [1655346026.393724][4561:4566] CHIP:EM: Rxd Ack; Removing MessageCounter:104210987 from Retrans Table on exchange 45471i [1655346026.393777][4561:4566] CHIP:EM: Removed CHIP MessageCounter:104210987 from RetransTable on exchange 45471i [1655346026.393894][4561:4566] CHIP:DMG: ReportDataMessage = [1655346026.393959][4561:4566] CHIP:DMG: { [1655346026.394009][4561:4566] CHIP:DMG: AttributeReportIBs = [1655346026.394078][4561:4566] CHIP:DMG: [ [1655346026.394136][4561:4566] CHIP:DMG: AttributeReportIB = [1655346026.394214][4561:4566] CHIP:DMG: { [1655346026.394277][4561:4566] CHIP:DMG: AttributeDataIB = [1655346026.394354][4561:4566] CHIP:DMG: { [1655346026.394465][4561:4566] CHIP:DMG: DataVersion = 0xa8257bce, [1655346026.394548][4561:4566] CHIP:DMG: AttributePathIB = [1655346026.394629][4561:4566] CHIP:DMG: { [1655346026.394710][4561:4566] CHIP:DMG: Endpoint = 0x1, [1655346026.394816][4561:4566] CHIP:DMG: Cluster = 0x101, [1655346026.394911][4561:4566] CHIP:DMG: Attribute = 0x0000_0014, [1655346026.394989][4561:4566] CHIP:DMG: } [1655346026.395059][4561:4566] CHIP:DMG: [1655346026.395157][4561:4566] CHIP:DMG: Data = 10, [1655346026.395230][4561:4566] CHIP:DMG: }, [1655346026.395304][4561:4566] CHIP:DMG: [1655346026.395393][4561:4566] CHIP:DMG: }, [1655346026.395468][4561:4566] CHIP:DMG: [1655346026.395518][4561:4566] CHIP:DMG: ], [1655346026.395602][4561:4566] CHIP:DMG: [1655346026.395657][4561:4566] CHIP:DMG: SuppressResponse = true, [1655346026.395710][4561:4566] CHIP:DMG: InteractionModelRevision = 1 [1655346026.395760][4561:4566] CHIP:DMG: } [1655346026.396061][4561:4566] CHIP:TOO: Endpoint: 1 Cluster: 0x0000_0101 Attribute 0x0000_0014 DataVersion: 2821028814 [1655346026.396195][4561:4566] CHIP:TOO: NumberOfWeekDaySchedulesSupportedPerUser: 10 [1655346026.396364][4561:4566] CHIP:EM: Sending Standalone Ack for MessageCounter:150912144 on exchange 45471i [1655346026.396506][4561:4566] CHIP:IN: Prepared secure message 0xffffb2fdd9d8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 45471i with MessageCounter:104210988. [1655346026.396610][4561:4566] CHIP:IN: Sending encrypted msg 0xffffb2fdd9d8 with MessageCounter:104210988 to 0x0000000000000001 (1) at monotonic time: 00000000013D6D87 msec [1655346026.396860][4561:4566] CHIP:EM: Flushed pending ack for MessageCounter:150912144 on exchange 45471i [1655346026.397331][4561:4561] CHIP:CTL: Shutting down the commissioner [1655346026.397754][4561:4561] CHIP:CTL: Shutting down the controller [1655346026.397836][4561:4561] CHIP:CTL: Shutting down the commissioner [1655346026.398002][4561:4561] CHIP:CTL: Shutting down the controller [1655346026.398070][4561:4561] CHIP:IN: Expiring all connections for fabric 1!! [1655346026.398120][4561:4561] CHIP:IN: SecureSession[0xaaaaf1e45c50]: MarkForRemoval Type:2 LSID:24659 [1655346026.398169][4561:4561] CHIP:IN: SecureSession[0xaaaaf1e45c50]: Released - Type:2 LSID:24659 [1655346026.398260][4561:4561] CHIP:CTL: Shutting down the commissioner [1655346026.398502][4561:4561] CHIP:CTL: Shutting down the controller [1655346026.398561][4561:4561] CHIP:IN: Expiring all connections for fabric 2!! [1655346026.398635][4561:4561] CHIP:CTL: Shutting down the commissioner [1655346026.398795][4561:4561] CHIP:CTL: Shutting down the controller [1655346026.398844][4561:4561] CHIP:IN: Expiring all connections for fabric 3!! [1655346026.398894][4561:4561] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1655346026.399822][4561:4561] CHIP:DMG: IM WH moving to [Uninitialized] [1655346026.399877][4561:4561] CHIP:DMG: IM WH moving to [Uninitialized] [1655346026.400005][4561:4561] CHIP:DMG: IM WH moving to [Uninitialized] [1655346026.400068][4561:4561] CHIP:DMG: IM WH moving to [Uninitialized] [1655346026.400106][4561:4561] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1655346026.400246][4561:4561] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1655346026.400658][4561:4561] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-z27hAs) [1655346026.401740][4561:4561] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655346026.401834][4561:4561] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1655346026.401933][4561:4561] CHIP:DL: Inet Layer shutdown [1655346026.401971][4561:4561] CHIP:DL: BLE shutdown [1655346026.402038][4561:4561] CHIP:DL: System Layer shutdown --------- pi@ubuntu:~$ ./chip-tool doorlock read number-of-total-users-supported 1 1 [1655346052.104306][4568:4568] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1655346052.104851][4568:4568] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1655346052.105023][4568:4568] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1655346052.105139][4568:4568] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1655346052.105508][4568:4568] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-Fpn3hq) [1655346052.106104][4568:4568] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655346052.106159][4568:4568] CHIP:DL: NVS set: chip-counters/reboot-count = 17 (0x11) [1655346052.106855][4568:4568] CHIP:DL: Got Ethernet interface: eth0 [1655346052.107306][4568:4568] CHIP:DL: Found the primary Ethernet interface:eth0 [1655346052.107757][4568:4568] CHIP:DL: Got WiFi interface: wlan0 [1655346052.107835][4568:4568] CHIP:DL: Failed to reset WiFi statistic counts [1655346052.107916][4568:4568] CHIP:IN: UDP::Init bind&listen port=0 [1655346052.108047][4568:4568] CHIP:IN: UDP::Init bound to port=54579 [1655346052.108075][4568:4568] CHIP:IN: UDP::Init bind&listen port=0 [1655346052.108175][4568:4568] CHIP:IN: UDP::Init bound to port=51089 [1655346052.108200][4568:4568] CHIP:IN: BLEBase::Init - setting/overriding transport [1655346052.108223][4568:4568] CHIP:IN: TransportMgr initialized [1655346052.108271][4568:4568] CHIP:FP: Initializing FabricTable from persistent storage [1655346052.108461][4568:4568] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655346052.108562][4568:4568] CHIP:FP: Loading from storage for fabric index 0x1 [1655346052.109242][4568:4568] CHIP:FP: Loading from storage for fabric index 0x2 [1655346052.109762][4568:4568] CHIP:FP: Loading from storage for fabric index 0x3 [1655346052.114058][4568:4568] CHIP:ZCL: Using ZAP configuration... [1655346052.116885][4568:4568] CHIP:DL: MDNS failed to join multicast group on wlan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655346052.119383][4568:4568] CHIP:DL: MDNS failed to join multicast group on wpan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655346052.119727][4568:4568] CHIP:CTL: System State Initialized... [1655346052.119787][4568:4568] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655346052.119849][4568:4568] CHIP:CTL: Setting attestation nonce to random value [1655346052.119911][4568:4568] CHIP:CTL: Setting CSR nonce to random value [1655346052.120072][4568:4568] CHIP:IN: UDP::Init bind&listen port=5550 [1655346052.120236][4568:4568] CHIP:IN: UDP::Init bound to port=5550 [1655346052.120266][4568:4568] CHIP:IN: UDP::Init bind&listen port=5550 [1655346052.120356][4568:4568] CHIP:IN: UDP::Init bound to port=5550 [1655346052.120381][4568:4568] CHIP:IN: TransportMgr initialized [1655346052.120490][4568:4568] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655346052.120523][4568:4568] CHIP:CTL: Setting attestation nonce to random value [1655346052.120574][4568:4568] CHIP:CTL: Setting CSR nonce to random value [1655346052.123861][4568:4568] CHIP:CTL: Generating NOC [1655346052.124779][4568:4568] CHIP:FP: Validating NOC chain [1655346052.126555][4568:4568] CHIP:FP: NOC chain validation successful [1655346052.126716][4568:4568] CHIP:FP: Added new fabric at index: 0x1, Initialized: 1 [1655346052.126746][4568:4568] CHIP:FP: Assigned compressed fabric ID: 0xA7866B26C80C3D35, node ID: 0x000000000001B669 [1655346052.133303][4568:4568] CHIP:FP: Fabric (0x1) persisted to storage. Calling OnFabricPersistedToStorage [1655346052.133361][4568:4568] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655346052.133386][4568:4568] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655346052.133407][4568:4568] CHIP:TS: Retaining current Last Known Good Time [1655346052.134942][4568:4568] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0xA7866B26C80C3D35 [1655346052.135021][4568:4568] CHIP:IN: UDP::Init bind&listen port=5550 [1655346052.135155][4568:4568] CHIP:IN: UDP::Init bound to port=5550 [1655346052.135184][4568:4568] CHIP:IN: UDP::Init bind&listen port=5550 [1655346052.135280][4568:4568] CHIP:IN: UDP::Init bound to port=5550 [1655346052.135306][4568:4568] CHIP:IN: TransportMgr initialized [1655346052.135414][4568:4568] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655346052.135512][4568:4568] CHIP:CTL: Setting attestation nonce to random value [1655346052.135576][4568:4568] CHIP:CTL: Setting CSR nonce to random value [1655346052.136513][4568:4568] CHIP:CTL: Generating NOC [1655346052.137562][4568:4568] CHIP:FP: Validating NOC chain [1655346052.139311][4568:4568] CHIP:FP: NOC chain validation successful [1655346052.139511][4568:4568] CHIP:FP: Added new fabric at index: 0x2, Initialized: 1 [1655346052.139540][4568:4568] CHIP:FP: Assigned compressed fabric ID: 0x06EE9B0E253A2288, node ID: 0x000000000001B669 [1655346052.145025][4568:4568] CHIP:FP: Fabric (0x2) persisted to storage. Calling OnFabricPersistedToStorage [1655346052.145075][4568:4568] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655346052.145099][4568:4568] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655346052.145121][4568:4568] CHIP:TS: Retaining current Last Known Good Time [1655346052.146656][4568:4568] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x06EE9B0E253A2288 [1655346052.146734][4568:4568] CHIP:IN: UDP::Init bind&listen port=5550 [1655346052.146875][4568:4568] CHIP:IN: UDP::Init bound to port=5550 [1655346052.146907][4568:4568] CHIP:IN: UDP::Init bind&listen port=5550 [1655346052.147000][4568:4568] CHIP:IN: UDP::Init bound to port=5550 [1655346052.147024][4568:4568] CHIP:IN: TransportMgr initialized [1655346052.147132][4568:4568] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655346052.147173][4568:4568] CHIP:CTL: Setting attestation nonce to random value [1655346052.147234][4568:4568] CHIP:CTL: Setting CSR nonce to random value [1655346052.148089][4568:4568] CHIP:CTL: Generating NOC [1655346052.149020][4568:4568] CHIP:FP: Validating NOC chain [1655346052.150830][4568:4568] CHIP:FP: NOC chain validation successful [1655346052.151028][4568:4568] CHIP:FP: Added new fabric at index: 0x3, Initialized: 1 [1655346052.151056][4568:4568] CHIP:FP: Assigned compressed fabric ID: 0x884B85B37DC4EE27, node ID: 0x000000000001B669 [1655346052.156643][4568:4568] CHIP:FP: Fabric (0x3) persisted to storage. Calling OnFabricPersistedToStorage [1655346052.156694][4568:4568] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655346052.156718][4568:4568] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655346052.156739][4568:4568] CHIP:TS: Retaining current Last Known Good Time [1655346052.158307][4568:4568] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x884B85B37DC4EE27 [1655346052.158382][4568:4568] CHIP:IN: UDP::Init bind&listen port=5550 [1655346052.158519][4568:4568] CHIP:IN: UDP::Init bound to port=5550 [1655346052.158547][4568:4568] CHIP:IN: UDP::Init bind&listen port=5550 [1655346052.158639][4568:4568] CHIP:IN: UDP::Init bound to port=5550 [1655346052.158663][4568:4568] CHIP:IN: TransportMgr initialized [1655346052.192904][4568:4573] CHIP:DL: CHIP task running [1655346052.193047][4568:4573] CHIP:TOO: Sending command to node 0x1 [1655346052.193089][4568:4573] CHIP:CSM: FindOrEstablishSession: PeerId = A7866B26C80C3D35:0000000000000001 [1655346052.193117][4568:4573] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1655346052.193290][4568:4573] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 1 --> 2 [1655346052.193370][4568:4573] CHIP:DIS: MINMDNS: Sending query ANY/UNICAST for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655346052.193950][4568:4573] CHIP:DIS: Warning: Attempt to mDNS broadcast failed on wlan0: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable [1655346052.194326][4568:4573] CHIP:DIS: mDNS broadcast had only partial success: 7 successes and 1 failures. [1655346052.194609][4568:4573] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655346052.194668][4568:4573] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 822FF8DD5E22EEF6.local [1655346052.194704][4568:4573] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655346052.194748][4568:4573] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655346052.194783][4568:4573] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:af47:ced6:6f88:1c68 found for 822FF8DD5E22EEF6.local [1655346052.195371][4568:4573] CHIP:DIS: Lookup clearing interface for non LL address [1655346052.195418][4568:4573] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:af47:ced6:6f88:1c68%eth0]:5540: new best score: 6 [1655346052.195444][4568:4573] CHIP:DIS: Checking node lookup status after 2 ms [1655346052.195467][4568:4573] CHIP:DIS: Keeping DNSSD lookup active [1655346052.195641][4568:4573] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655346052.195707][4568:4573] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 822FF8DD5E22EEF6.local [1655346052.195739][4568:4573] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655346052.195779][4568:4573] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655346052.195822][4568:4573] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:af47:ced6:6f88:1c68 found for 822FF8DD5E22EEF6.local [1655346052.195851][4568:4573] CHIP:DIS: Discovered node without a pending query [1655346052.196314][4568:4573] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:af47:ced6:6f88:1c68%eth0]:5540: score has not improved: 6 [1655346052.196351][4568:4573] CHIP:DIS: Checking node lookup status after 3 ms [1655346052.196374][4568:4573] CHIP:DIS: Keeping DNSSD lookup active [1655346052.394411][4568:4573] CHIP:DIS: Checking node lookup status after 201 ms [1655346052.394522][4568:4573] CHIP:CTL: Updating device address to UDP:[fd12:c411:e4a6:d11c:af47:ced6:6f88:1c68]:5540 while in state 2 [1655346052.394582][4568:4573] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 2 --> 3 [1655346052.394977][4568:4573] CHIP:IN: SecureSession[0xaaaaed76e6e0]: Allocated Type:2 LSID:33979 [1655346052.395097][4568:4573] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1655346052.396642][4568:4573] CHIP:SC: Including MRP parameters [1655346052.397122][4568:4573] CHIP:IN: Prepared unauthenticated message 0xaaaaed76c9c8 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 36474i with MessageCounter:268225530. [1655346052.397287][4568:4573] CHIP:IN: Sending unauthenticated msg 0xaaaaed76c9c8 with MessageCounter:268225530 to 0x0000000000000000 at monotonic time: 00000000013DD318 msec [1655346052.397690][4568:4573] CHIP:SC: Sent Sigma1 msg [1655346052.397773][4568:4573] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 3 --> 4 [1655346052.397874][4568:4573] CHIP:DIS: Discovery does not require any more timeouts [1655346052.517620][4568:4573] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:38919630 on exchange 36474i [1655346052.517720][4568:4573] CHIP:EM: Found matching exchange: 36474i, Delegate: 0xffff980015c0 [1655346052.517809][4568:4573] CHIP:EM: Rxd Ack; Removing MessageCounter:268225530 from Retrans Table on exchange 36474i [1655346052.517869][4568:4573] CHIP:EM: Removed CHIP MessageCounter:268225530 from RetransTable on exchange 36474i [1655346052.537477][4568:4573] CHIP:EM: Received message of type 0x33 with protocolId (0, 0) and MessageCounter:38919631 on exchange 36474i [1655346052.537570][4568:4573] CHIP:EM: Found matching exchange: 36474i, Delegate: 0xffff980015c0 [1655346052.537692][4568:4573] CHIP:EM: CHIP MessageCounter:268225530 not in RetransTable on exchange 36474i [1655346052.537972][4568:4573] CHIP:SC: Received Sigma2Resume msg [1655346052.538183][4568:4573] CHIP:SC: Found MRP parameters in the message [1655346052.538447][4568:4573] CHIP:SC: Peer assigned session session ID 31150 [1655346052.546577][4568:4573] CHIP:SC: Sending status report. Protocol code 0, exchange 36474 [1655346052.546665][4568:4573] CHIP:EM: Piggybacking Ack for MessageCounter:38919631 on exchange: 36474i [1655346052.546731][4568:4573] CHIP:IN: Prepared unauthenticated message 0xaaaaed76c9c8 to 0x0000000000000000 (0) of type 0x40 and protocolId (0, 0) on exchange 36474i with MessageCounter:268225531. [1655346052.546798][4568:4573] CHIP:IN: Sending unauthenticated msg 0xaaaaed76c9c8 with MessageCounter:268225531 to 0x0000000000000000 at monotonic time: 00000000013DD3AD msec [1655346052.547203][4568:4573] CHIP:IN: SecureSession[0xaaaaed76e6e0]: Activated - Type:2 LSID:33979 [1655346052.547265][4568:4573] CHIP:IN: New secure session created for device <0000000000000001, 1>, LSID:33979 PSID:31150! [1655346052.547308][4568:4573] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 4 --> 5 [1655346052.547427][4568:4573] CHIP:TOO: cluster 0x0000_0101, attribute: 0x0000_0011, endpoint 1 [1655346052.547488][4568:4573] CHIP:TOO: Sending ReadAttribute to: [1655346052.547535][4568:4573] CHIP:DMG: SendReadRequest ReadClient[0xffff980032a0]: Sending Read Request [1655346052.547720][4568:4573] CHIP:IN: Prepared secure message 0xaaaaed76c9e8 to 0x0000000000000001 (1) of type 0x2 and protocolId (0, 1) on exchange 36475i with MessageCounter:72589350. [1655346052.547800][4568:4573] CHIP:IN: Sending encrypted msg 0xaaaaed76c9e8 with MessageCounter:72589350 to 0x0000000000000001 (1) at monotonic time: 00000000013DD3AE msec [1655346052.548049][4568:4573] CHIP:DMG: MoveToState ReadClient[0xffff980032a0]: Moving to [AwaitingIn] [1655346052.560454][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 53.0.16.172.in-addr.arpa [1655346052.560543][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.560598][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655346052.560649][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.560770][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 53.0.16.172.in-addr.arpa [1655346052.560829][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.560889][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655346052.560944][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.561267][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.561330][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.561382][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655346052.561527][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.561584][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.561651][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655346052.561784][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.561841][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.561895][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655346052.562028][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.562098][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655346052.562150][4568:4573] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655346052.607557][4568:4573] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:38919632 on exchange 36474i [1655346052.607680][4568:4573] CHIP:EM: Found matching exchange: 36474i, Delegate: (nil) [1655346052.607781][4568:4573] CHIP:EM: Rxd Ack; Removing MessageCounter:268225531 from Retrans Table on exchange 36474i [1655346052.607850][4568:4573] CHIP:EM: Removed CHIP MessageCounter:268225531 from RetransTable on exchange 36474i [1655346052.652881][4568:4573] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:141390454 on exchange 36475i [1655346052.652987][4568:4573] CHIP:EM: Found matching exchange: 36475i, Delegate: 0xffff980032a0 [1655346052.653086][4568:4573] CHIP:EM: Rxd Ack; Removing MessageCounter:72589350 from Retrans Table on exchange 36475i [1655346052.653139][4568:4573] CHIP:EM: Removed CHIP MessageCounter:72589350 from RetransTable on exchange 36475i [1655346052.653329][4568:4573] CHIP:DMG: ReportDataMessage = [1655346052.653393][4568:4573] CHIP:DMG: { [1655346052.653452][4568:4573] CHIP:DMG: AttributeReportIBs = [1655346052.653531][4568:4573] CHIP:DMG: [ [1655346052.653599][4568:4573] CHIP:DMG: AttributeReportIB = [1655346052.653692][4568:4573] CHIP:DMG: { [1655346052.653763][4568:4573] CHIP:DMG: AttributeDataIB = [1655346052.653845][4568:4573] CHIP:DMG: { [1655346052.653917][4568:4573] CHIP:DMG: DataVersion = 0xa8257bce, [1655346052.653999][4568:4573] CHIP:DMG: AttributePathIB = [1655346052.654082][4568:4573] CHIP:DMG: { [1655346052.654171][4568:4573] CHIP:DMG: Endpoint = 0x1, [1655346052.654354][4568:4573] CHIP:DMG: Cluster = 0x101, [1655346052.654434][4568:4573] CHIP:DMG: Attribute = 0x0000_0011, [1655346052.654504][4568:4573] CHIP:DMG: } [1655346052.654584][4568:4573] CHIP:DMG: [1655346052.654659][4568:4573] CHIP:DMG: Data = 10, [1655346052.654724][4568:4573] CHIP:DMG: }, [1655346052.654795][4568:4573] CHIP:DMG: [1655346052.654854][4568:4573] CHIP:DMG: }, [1655346052.654958][4568:4573] CHIP:DMG: [1655346052.655016][4568:4573] CHIP:DMG: ], [1655346052.655083][4568:4573] CHIP:DMG: [1655346052.655135][4568:4573] CHIP:DMG: SuppressResponse = true, [1655346052.655188][4568:4573] CHIP:DMG: InteractionModelRevision = 1 [1655346052.655239][4568:4573] CHIP:DMG: } [1655346052.655542][4568:4573] CHIP:TOO: Endpoint: 1 Cluster: 0x0000_0101 Attribute 0x0000_0011 DataVersion: 2821028814 [1655346052.655642][4568:4573] CHIP:TOO: NumberOfTotalUsersSupported: 10 [1655346052.655812][4568:4573] CHIP:EM: Sending Standalone Ack for MessageCounter:141390454 on exchange 36475i [1655346052.656014][4568:4573] CHIP:IN: Prepared secure message 0xffffae5f49d8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 36475i with MessageCounter:72589351. [1655346052.656096][4568:4573] CHIP:IN: Sending encrypted msg 0xffffae5f49d8 with MessageCounter:72589351 to 0x0000000000000001 (1) at monotonic time: 00000000013DD41A msec [1655346052.656339][4568:4573] CHIP:EM: Flushed pending ack for MessageCounter:141390454 on exchange 36475i [1655346052.656775][4568:4568] CHIP:CTL: Shutting down the commissioner [1655346052.657010][4568:4568] CHIP:CTL: Shutting down the controller [1655346052.657084][4568:4568] CHIP:CTL: Shutting down the commissioner [1655346052.657309][4568:4568] CHIP:CTL: Shutting down the controller [1655346052.657380][4568:4568] CHIP:IN: Expiring all connections for fabric 1!! [1655346052.657432][4568:4568] CHIP:IN: SecureSession[0xaaaaed76e6e0]: MarkForRemoval Type:2 LSID:33979 [1655346052.657480][4568:4568] CHIP:IN: SecureSession[0xaaaaed76e6e0]: Released - Type:2 LSID:33979 [1655346052.657572][4568:4568] CHIP:CTL: Shutting down the commissioner [1655346052.657744][4568:4568] CHIP:CTL: Shutting down the controller [1655346052.657795][4568:4568] CHIP:IN: Expiring all connections for fabric 2!! [1655346052.657868][4568:4568] CHIP:CTL: Shutting down the commissioner [1655346052.658058][4568:4568] CHIP:CTL: Shutting down the controller [1655346052.658109][4568:4568] CHIP:IN: Expiring all connections for fabric 3!! [1655346052.658168][4568:4568] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1655346052.659153][4568:4568] CHIP:DMG: IM WH moving to [Uninitialized] [1655346052.659212][4568:4568] CHIP:DMG: IM WH moving to [Uninitialized] [1655346052.659255][4568:4568] CHIP:DMG: IM WH moving to [Uninitialized] [1655346052.659297][4568:4568] CHIP:DMG: IM WH moving to [Uninitialized] [1655346052.659342][4568:4568] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1655346052.659507][4568:4568] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1655346052.659894][4568:4568] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-iixSiA) [1655346052.660926][4568:4568] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655346052.661033][4568:4568] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1655346052.661087][4568:4568] CHIP:DL: Inet Layer shutdown [1655346052.661135][4568:4568] CHIP:DL: BLE shutdown [1655346052.661228][4568:4568] CHIP:DL: System Layer shutdown ---- pi@ubuntu:~$ ./chip-tool doorlock set-week-day-schedule 1 1 2 15 45 16 55 1 1 [1655347023.700152][4825:4825] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1655347023.700704][4825:4825] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1655347023.700878][4825:4825] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1655347023.701009][4825:4825] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1655347023.701421][4825:4825] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-fUAwye) [1655347023.702055][4825:4825] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655347023.702111][4825:4825] CHIP:DL: NVS set: chip-counters/reboot-count = 50 (0x32) [1655347023.702752][4825:4825] CHIP:DL: Got Ethernet interface: eth0 [1655347023.703151][4825:4825] CHIP:DL: Found the primary Ethernet interface:eth0 [1655347023.703615][4825:4825] CHIP:DL: Got WiFi interface: wlan0 [1655347023.703691][4825:4825] CHIP:DL: Failed to reset WiFi statistic counts [1655347023.703771][4825:4825] CHIP:IN: UDP::Init bind&listen port=0 [1655347023.703923][4825:4825] CHIP:IN: UDP::Init bound to port=54227 [1655347023.703959][4825:4825] CHIP:IN: UDP::Init bind&listen port=0 [1655347023.704059][4825:4825] CHIP:IN: UDP::Init bound to port=52538 [1655347023.704082][4825:4825] CHIP:IN: BLEBase::Init - setting/overriding transport [1655347023.704114][4825:4825] CHIP:IN: TransportMgr initialized [1655347023.704169][4825:4825] CHIP:FP: Initializing FabricTable from persistent storage [1655347023.704422][4825:4825] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347023.704549][4825:4825] CHIP:FP: Loading from storage for fabric index 0x1 [1655347023.705393][4825:4825] CHIP:FP: Loading from storage for fabric index 0x2 [1655347023.706103][4825:4825] CHIP:FP: Loading from storage for fabric index 0x3 [1655347023.712298][4825:4825] CHIP:ZCL: Using ZAP configuration... [1655347023.715200][4825:4825] CHIP:DL: MDNS failed to join multicast group on wlan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655347023.717336][4825:4825] CHIP:DL: MDNS failed to join multicast group on wpan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655347023.717690][4825:4825] CHIP:CTL: System State Initialized... [1655347023.717751][4825:4825] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347023.717815][4825:4825] CHIP:CTL: Setting attestation nonce to random value [1655347023.717880][4825:4825] CHIP:CTL: Setting CSR nonce to random value [1655347023.717974][4825:4825] CHIP:IN: UDP::Init bind&listen port=5550 [1655347023.718089][4825:4825] CHIP:IN: UDP::Init bound to port=5550 [1655347023.718114][4825:4825] CHIP:IN: UDP::Init bind&listen port=5550 [1655347023.718215][4825:4825] CHIP:IN: UDP::Init bound to port=5550 [1655347023.718240][4825:4825] CHIP:IN: TransportMgr initialized [1655347023.718326][4825:4825] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347023.718357][4825:4825] CHIP:CTL: Setting attestation nonce to random value [1655347023.718406][4825:4825] CHIP:CTL: Setting CSR nonce to random value [1655347023.721512][4825:4825] CHIP:CTL: Generating NOC [1655347023.722438][4825:4825] CHIP:FP: Validating NOC chain [1655347023.724214][4825:4825] CHIP:FP: NOC chain validation successful [1655347023.724420][4825:4825] CHIP:FP: Added new fabric at index: 0x1, Initialized: 1 [1655347023.724447][4825:4825] CHIP:FP: Assigned compressed fabric ID: 0xA7866B26C80C3D35, node ID: 0x000000000001B669 [1655347023.732639][4825:4825] CHIP:FP: Fabric (0x1) persisted to storage. Calling OnFabricPersistedToStorage [1655347023.732699][4825:4825] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347023.732724][4825:4825] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347023.732745][4825:4825] CHIP:TS: Retaining current Last Known Good Time [1655347023.734786][4825:4825] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0xA7866B26C80C3D35 [1655347023.734866][4825:4825] CHIP:IN: UDP::Init bind&listen port=5550 [1655347023.735005][4825:4825] CHIP:IN: UDP::Init bound to port=5550 [1655347023.735032][4825:4825] CHIP:IN: UDP::Init bind&listen port=5550 [1655347023.735157][4825:4825] CHIP:IN: UDP::Init bound to port=5550 [1655347023.735182][4825:4825] CHIP:IN: TransportMgr initialized [1655347023.735284][4825:4825] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347023.735336][4825:4825] CHIP:CTL: Setting attestation nonce to random value [1655347023.735398][4825:4825] CHIP:CTL: Setting CSR nonce to random value [1655347023.736297][4825:4825] CHIP:CTL: Generating NOC [1655347023.737128][4825:4825] CHIP:FP: Validating NOC chain [1655347023.739022][4825:4825] CHIP:FP: NOC chain validation successful [1655347023.739188][4825:4825] CHIP:FP: Added new fabric at index: 0x2, Initialized: 1 [1655347023.739230][4825:4825] CHIP:FP: Assigned compressed fabric ID: 0x06EE9B0E253A2288, node ID: 0x000000000001B669 [1655347023.746997][4825:4825] CHIP:FP: Fabric (0x2) persisted to storage. Calling OnFabricPersistedToStorage [1655347023.747052][4825:4825] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347023.747076][4825:4825] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347023.747097][4825:4825] CHIP:TS: Retaining current Last Known Good Time [1655347023.749043][4825:4825] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x06EE9B0E253A2288 [1655347023.749120][4825:4825] CHIP:IN: UDP::Init bind&listen port=5550 [1655347023.749276][4825:4825] CHIP:IN: UDP::Init bound to port=5550 [1655347023.749305][4825:4825] CHIP:IN: UDP::Init bind&listen port=5550 [1655347023.749423][4825:4825] CHIP:IN: UDP::Init bound to port=5550 [1655347023.749448][4825:4825] CHIP:IN: TransportMgr initialized [1655347023.749568][4825:4825] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347023.749621][4825:4825] CHIP:CTL: Setting attestation nonce to random value [1655347023.749678][4825:4825] CHIP:CTL: Setting CSR nonce to random value [1655347023.750578][4825:4825] CHIP:CTL: Generating NOC [1655347023.751434][4825:4825] CHIP:FP: Validating NOC chain [1655347023.753152][4825:4825] CHIP:FP: NOC chain validation successful [1655347023.753346][4825:4825] CHIP:FP: Added new fabric at index: 0x3, Initialized: 1 [1655347023.753388][4825:4825] CHIP:FP: Assigned compressed fabric ID: 0x884B85B37DC4EE27, node ID: 0x000000000001B669 [1655347023.764310][4825:4825] CHIP:FP: Fabric (0x3) persisted to storage. Calling OnFabricPersistedToStorage [1655347023.764363][4825:4825] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347023.764387][4825:4825] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347023.764408][4825:4825] CHIP:TS: Retaining current Last Known Good Time [1655347023.766482][4825:4825] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x884B85B37DC4EE27 [1655347023.766560][4825:4825] CHIP:IN: UDP::Init bind&listen port=5550 [1655347023.766699][4825:4825] CHIP:IN: UDP::Init bound to port=5550 [1655347023.766726][4825:4825] CHIP:IN: UDP::Init bind&listen port=5550 [1655347023.766846][4825:4825] CHIP:IN: UDP::Init bound to port=5550 [1655347023.766871][4825:4825] CHIP:IN: TransportMgr initialized [1655347023.815167][4825:4830] CHIP:DL: CHIP task running [1655347023.815306][4825:4830] CHIP:TOO: Sending command to node 0x1 [1655347023.815348][4825:4830] CHIP:CSM: FindOrEstablishSession: PeerId = A7866B26C80C3D35:0000000000000001 [1655347023.815375][4825:4830] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1655347023.815509][4825:4830] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 1 --> 2 [1655347023.815584][4825:4830] CHIP:DIS: MINMDNS: Sending query ANY/UNICAST for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347023.816422][4825:4830] CHIP:DIS: Warning: Attempt to mDNS broadcast failed on wlan0: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable [1655347023.816943][4825:4830] CHIP:DIS: mDNS broadcast had only partial success: 7 successes and 1 failures. [1655347023.817264][4825:4830] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347023.817332][4825:4830] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 165DF2067BB604AC.local [1655347023.817360][4825:4830] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347023.817399][4825:4830] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347023.817429][4825:4830] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:db36:1281:eb37:81e9 found for 165DF2067BB604AC.local [1655347023.818011][4825:4830] CHIP:DIS: Lookup clearing interface for non LL address [1655347023.818061][4825:4830] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9%eth0]:5540: new best score: 6 [1655347023.818088][4825:4830] CHIP:DIS: Checking node lookup status after 2 ms [1655347023.818110][4825:4830] CHIP:DIS: Keeping DNSSD lookup active [1655347023.818274][4825:4830] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347023.818328][4825:4830] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 165DF2067BB604AC.local [1655347023.818360][4825:4830] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347023.818466][4825:4830] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347023.818503][4825:4830] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:db36:1281:eb37:81e9 found for 165DF2067BB604AC.local [1655347023.818532][4825:4830] CHIP:DIS: Discovered node without a pending query [1655347023.819080][4825:4830] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9%eth0]:5540: score has not improved: 6 [1655347023.819116][4825:4830] CHIP:DIS: Checking node lookup status after 3 ms [1655347023.819138][4825:4830] CHIP:DIS: Keeping DNSSD lookup active [1655347024.016180][4825:4830] CHIP:DIS: Checking node lookup status after 200 ms [1655347024.016299][4825:4830] CHIP:CTL: Updating device address to UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9]:5540 while in state 2 [1655347024.016360][4825:4830] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 2 --> 3 [1655347024.016752][4825:4830] CHIP:IN: SecureSession[0xaaaad29baae0]: Allocated Type:2 LSID:5368 [1655347024.016841][4825:4830] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1655347024.018669][4825:4830] CHIP:SC: Including MRP parameters [1655347024.019193][4825:4830] CHIP:IN: Prepared unauthenticated message 0xaaaad29b8e88 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 61166i with MessageCounter:236574656. [1655347024.019301][4825:4830] CHIP:IN: Sending unauthenticated msg 0xaaaad29b8e88 with MessageCounter:236574656 to 0x0000000000000000 at monotonic time: 00000000014CA67E msec [1655347024.019773][4825:4830] CHIP:SC: Sent Sigma1 msg [1655347024.019853][4825:4830] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 3 --> 4 [1655347024.019921][4825:4830] CHIP:DIS: Discovery does not require any more timeouts [1655347024.136218][4825:4830] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:213740470 on exchange 61166i [1655347024.136301][4825:4830] CHIP:EM: Found matching exchange: 61166i, Delegate: 0xffff940015c0 [1655347024.136368][4825:4830] CHIP:EM: Rxd Ack; Removing MessageCounter:236574656 from Retrans Table on exchange 61166i [1655347024.136413][4825:4830] CHIP:EM: Removed CHIP MessageCounter:236574656 from RetransTable on exchange 61166i [1655347024.156055][4825:4830] CHIP:EM: Received message of type 0x33 with protocolId (0, 0) and MessageCounter:213740471 on exchange 61166i [1655347024.156142][4825:4830] CHIP:EM: Found matching exchange: 61166i, Delegate: 0xffff940015c0 [1655347024.156194][4825:4830] CHIP:EM: CHIP MessageCounter:236574656 not in RetransTable on exchange 61166i [1655347024.156268][4825:4830] CHIP:SC: Received Sigma2Resume msg [1655347024.156433][4825:4830] CHIP:SC: Found MRP parameters in the message [1655347024.156482][4825:4830] CHIP:SC: Peer assigned session session ID 18472 [1655347024.167973][4825:4830] CHIP:SC: Sending status report. Protocol code 0, exchange 61166 [1655347024.168046][4825:4830] CHIP:EM: Piggybacking Ack for MessageCounter:213740471 on exchange: 61166i [1655347024.168096][4825:4830] CHIP:IN: Prepared unauthenticated message 0xaaaad29b8e88 to 0x0000000000000000 (0) of type 0x40 and protocolId (0, 0) on exchange 61166i with MessageCounter:236574657. [1655347024.168145][4825:4830] CHIP:IN: Sending unauthenticated msg 0xaaaad29b8e88 with MessageCounter:236574657 to 0x0000000000000000 at monotonic time: 00000000014CA712 msec [1655347024.168451][4825:4830] CHIP:IN: SecureSession[0xaaaad29baae0]: Activated - Type:2 LSID:5368 [1655347024.168494][4825:4830] CHIP:IN: New secure session created for device <0000000000000001, 1>, LSID:5368 PSID:18472! [1655347024.168523][4825:4830] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 4 --> 5 [1655347024.168579][4825:4830] CHIP:TOO: Sending cluster (0x00000101) command (0x0000000B) on endpoint 1 [1655347024.168637][4825:4830] CHIP:DMG: ICR moving to [AddingComm] [1655347024.168707][4825:4830] CHIP:DMG: ICR moving to [AddedComma] [1655347024.168990][4825:4830] CHIP:IN: Prepared secure message 0xaaaad29b8ea8 to 0x0000000000000001 (1) of type 0x8 and protocolId (0, 1) on exchange 61167i with MessageCounter:162168637. [1655347024.169046][4825:4830] CHIP:IN: Sending encrypted msg 0xaaaad29b8ea8 with MessageCounter:162168637 to 0x0000000000000001 (1) at monotonic time: 00000000014CA713 msec [1655347024.169266][4825:4830] CHIP:DMG: ICR moving to [CommandSen] [1655347024.231284][4825:4830] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:213740472 on exchange 61166i [1655347024.231389][4825:4830] CHIP:EM: Found matching exchange: 61166i, Delegate: (nil) [1655347024.231487][4825:4830] CHIP:EM: Rxd Ack; Removing MessageCounter:236574657 from Retrans Table on exchange 61166i [1655347024.231553][4825:4830] CHIP:EM: Removed CHIP MessageCounter:236574657 from RetransTable on exchange 61166i [1655347024.281252][4825:4830] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:127189999 on exchange 61167i [1655347024.281336][4825:4830] CHIP:EM: Found matching exchange: 61167i, Delegate: 0xffff94007530 [1655347024.281407][4825:4830] CHIP:EM: Rxd Ack; Removing MessageCounter:162168637 from Retrans Table on exchange 61167i [1655347024.281509][4825:4830] CHIP:EM: Removed CHIP MessageCounter:162168637 from RetransTable on exchange 61167i [1655347024.281623][4825:4830] CHIP:DMG: ICR moving to [ResponseRe] [1655347024.281758][4825:4830] CHIP:DMG: InvokeResponseMessage = [1655347024.281852][4825:4830] CHIP:DMG: { [1655347024.281904][4825:4830] CHIP:DMG: suppressResponse = false, [1655347024.281952][4825:4830] CHIP:DMG: InvokeResponseIBs = [1655347024.282102][4825:4830] CHIP:DMG: [ [1655347024.282236][4825:4830] CHIP:DMG: InvokeResponseIB = [1655347024.282406][4825:4830] CHIP:DMG: { [1655347024.282466][4825:4830] CHIP:DMG: CommandStatusIB = [1655347024.282534][4825:4830] CHIP:DMG: { [1655347024.282592][4825:4830] CHIP:DMG: CommandPathIB = [1655347024.282649][4825:4830] CHIP:DMG: { [1655347024.282776][4825:4830] CHIP:DMG: EndpointId = 0x1, [1655347024.282838][4825:4830] CHIP:DMG: ClusterId = 0x101, [1655347024.282908][4825:4830] CHIP:DMG: CommandId = 0xb, [1655347024.282973][4825:4830] CHIP:DMG: }, [1655347024.283089][4825:4830] CHIP:DMG: [1655347024.283147][4825:4830] CHIP:DMG: StatusIB = [1655347024.283222][4825:4830] CHIP:DMG: { [1655347024.283291][4825:4830] CHIP:DMG: status = 0x8b (NOT_FOUND), [1655347024.283459][4825:4830] CHIP:DMG: }, [1655347024.283535][4825:4830] CHIP:DMG: [1655347024.283597][4825:4830] CHIP:DMG: }, [1655347024.283668][4825:4830] CHIP:DMG: [1655347024.283726][4825:4830] CHIP:DMG: }, [1655347024.283847][4825:4830] CHIP:DMG: [1655347024.283900][4825:4830] CHIP:DMG: ], [1655347024.283963][4825:4830] CHIP:DMG: [1655347024.284015][4825:4830] CHIP:DMG: InteractionModelRevision = 1 [1655347024.284069][4825:4830] CHIP:DMG: }, [1655347024.284237][4825:4830] CHIP:DMG: Received Command Response Status for Endpoint=1 Cluster=0x0000_0101 Command=0x0000_000B Status=0x8b [1655347024.284310][4825:4830] CHIP:TOO: Error: IM Error 0x0000058B: General error: 0x8b (NOT_FOUND) [1655347024.284386][4825:4830] CHIP:DMG: ICR moving to [AwaitingDe] [1655347024.284488][4825:4830] CHIP:EM: Sending Standalone Ack for MessageCounter:127189999 on exchange 61167i [1655347024.284693][4825:4830] CHIP:IN: Prepared secure message 0xffffa995b9d8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 61167i with MessageCounter:162168638. [1655347024.284776][4825:4830] CHIP:IN: Sending encrypted msg 0xffffa995b9d8 with MessageCounter:162168638 to 0x0000000000000001 (1) at monotonic time: 00000000014CA787 msec [1655347024.285062][4825:4830] CHIP:EM: Flushed pending ack for MessageCounter:127189999 on exchange 61167i [1655347024.285497][4825:4825] CHIP:CTL: Shutting down the commissioner [1655347024.285755][4825:4825] CHIP:CTL: Shutting down the controller [1655347024.285830][4825:4825] CHIP:CTL: Shutting down the commissioner [1655347024.286015][4825:4825] CHIP:CTL: Shutting down the controller [1655347024.286083][4825:4825] CHIP:IN: Expiring all connections for fabric 1!! [1655347024.286135][4825:4825] CHIP:IN: SecureSession[0xaaaad29baae0]: MarkForRemoval Type:2 LSID:5368 [1655347024.286184][4825:4825] CHIP:IN: SecureSession[0xaaaad29baae0]: Released - Type:2 LSID:5368 [1655347024.286275][4825:4825] CHIP:CTL: Shutting down the commissioner [1655347024.286466][4825:4825] CHIP:CTL: Shutting down the controller [1655347024.286517][4825:4825] CHIP:IN: Expiring all connections for fabric 2!! [1655347024.286611][4825:4825] CHIP:CTL: Shutting down the commissioner [1655347024.286797][4825:4825] CHIP:CTL: Shutting down the controller [1655347024.286847][4825:4825] CHIP:IN: Expiring all connections for fabric 3!! [1655347024.286934][4825:4825] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1655347024.287956][4825:4825] CHIP:DMG: IM WH moving to [Uninitialized] [1655347024.288010][4825:4825] CHIP:DMG: IM WH moving to [Uninitialized] [1655347024.288054][4825:4825] CHIP:DMG: IM WH moving to [Uninitialized] [1655347024.288096][4825:4825] CHIP:DMG: IM WH moving to [Uninitialized] [1655347024.288141][4825:4825] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1655347024.288305][4825:4825] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1655347024.288754][4825:4825] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-4aC4pR) [1655347024.289879][4825:4825] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655347024.289985][4825:4825] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1655347024.290040][4825:4825] CHIP:DL: Inet Layer shutdown [1655347024.290088][4825:4825] CHIP:DL: BLE shutdown [1655347024.290135][4825:4825] CHIP:DL: System Layer shutdown [1655347024.290369][4825:4825] CHIP:TOO: Run command failure: IM Error 0x0000058B: General error: 0x8b (NOT_FOUND) ------ pi@ubuntu:~$ ./chip-tool doorlock get-week-day-schedule 1 1 1 1 [1655347353.273158][4849:4849] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1655347353.273748][4849:4849] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1655347353.273921][4849:4849] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1655347353.274032][4849:4849] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1655347353.274377][4849:4849] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-D4mvbQ) [1655347353.274987][4849:4849] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655347353.275045][4849:4849] CHIP:DL: NVS set: chip-counters/reboot-count = 52 (0x34) [1655347353.275668][4849:4849] CHIP:DL: Got Ethernet interface: eth0 [1655347353.276224][4849:4849] CHIP:DL: Found the primary Ethernet interface:eth0 [1655347353.276656][4849:4849] CHIP:DL: Got WiFi interface: wlan0 [1655347353.276732][4849:4849] CHIP:DL: Failed to reset WiFi statistic counts [1655347353.276813][4849:4849] CHIP:IN: UDP::Init bind&listen port=0 [1655347353.276945][4849:4849] CHIP:IN: UDP::Init bound to port=42543 [1655347353.276972][4849:4849] CHIP:IN: UDP::Init bind&listen port=0 [1655347353.277068][4849:4849] CHIP:IN: UDP::Init bound to port=59027 [1655347353.277093][4849:4849] CHIP:IN: BLEBase::Init - setting/overriding transport [1655347353.277114][4849:4849] CHIP:IN: TransportMgr initialized [1655347353.277166][4849:4849] CHIP:FP: Initializing FabricTable from persistent storage [1655347353.277551][4849:4849] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347353.277707][4849:4849] CHIP:FP: Loading from storage for fabric index 0x1 [1655347353.278458][4849:4849] CHIP:FP: Loading from storage for fabric index 0x2 [1655347353.279088][4849:4849] CHIP:FP: Loading from storage for fabric index 0x3 [1655347353.284685][4849:4849] CHIP:ZCL: Using ZAP configuration... [1655347353.287462][4849:4849] CHIP:DL: MDNS failed to join multicast group on wlan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655347353.290382][4849:4849] CHIP:DL: MDNS failed to join multicast group on wpan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655347353.290740][4849:4849] CHIP:CTL: System State Initialized... [1655347353.290801][4849:4849] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347353.290864][4849:4849] CHIP:CTL: Setting attestation nonce to random value [1655347353.290927][4849:4849] CHIP:CTL: Setting CSR nonce to random value [1655347353.291005][4849:4849] CHIP:IN: UDP::Init bind&listen port=5550 [1655347353.291112][4849:4849] CHIP:IN: UDP::Init bound to port=5550 [1655347353.291141][4849:4849] CHIP:IN: UDP::Init bind&listen port=5550 [1655347353.291227][4849:4849] CHIP:IN: UDP::Init bound to port=5550 [1655347353.291251][4849:4849] CHIP:IN: TransportMgr initialized [1655347353.291327][4849:4849] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347353.291359][4849:4849] CHIP:CTL: Setting attestation nonce to random value [1655347353.291408][4849:4849] CHIP:CTL: Setting CSR nonce to random value [1655347353.294137][4849:4849] CHIP:CTL: Generating NOC [1655347353.294990][4849:4849] CHIP:FP: Validating NOC chain [1655347353.296725][4849:4849] CHIP:FP: NOC chain validation successful [1655347353.296882][4849:4849] CHIP:FP: Added new fabric at index: 0x1, Initialized: 1 [1655347353.296910][4849:4849] CHIP:FP: Assigned compressed fabric ID: 0xA7866B26C80C3D35, node ID: 0x000000000001B669 [1655347353.305078][4849:4849] CHIP:FP: Fabric (0x1) persisted to storage. Calling OnFabricPersistedToStorage [1655347353.305135][4849:4849] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347353.305160][4849:4849] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347353.305204][4849:4849] CHIP:TS: Retaining current Last Known Good Time [1655347353.307170][4849:4849] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0xA7866B26C80C3D35 [1655347353.307248][4849:4849] CHIP:IN: UDP::Init bind&listen port=5550 [1655347353.307391][4849:4849] CHIP:IN: UDP::Init bound to port=5550 [1655347353.307418][4849:4849] CHIP:IN: UDP::Init bind&listen port=5550 [1655347353.307514][4849:4849] CHIP:IN: UDP::Init bound to port=5550 [1655347353.307538][4849:4849] CHIP:IN: TransportMgr initialized [1655347353.307657][4849:4849] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347353.307697][4849:4849] CHIP:CTL: Setting attestation nonce to random value [1655347353.307757][4849:4849] CHIP:CTL: Setting CSR nonce to random value [1655347353.308622][4849:4849] CHIP:CTL: Generating NOC [1655347353.309502][4849:4849] CHIP:FP: Validating NOC chain [1655347353.311229][4849:4849] CHIP:FP: NOC chain validation successful [1655347353.311386][4849:4849] CHIP:FP: Added new fabric at index: 0x2, Initialized: 1 [1655347353.311413][4849:4849] CHIP:FP: Assigned compressed fabric ID: 0x06EE9B0E253A2288, node ID: 0x000000000001B669 [1655347353.319541][4849:4849] CHIP:FP: Fabric (0x2) persisted to storage. Calling OnFabricPersistedToStorage [1655347353.319596][4849:4849] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347353.319620][4849:4849] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347353.319642][4849:4849] CHIP:TS: Retaining current Last Known Good Time [1655347353.321580][4849:4849] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x06EE9B0E253A2288 [1655347353.321661][4849:4849] CHIP:IN: UDP::Init bind&listen port=5550 [1655347353.321803][4849:4849] CHIP:IN: UDP::Init bound to port=5550 [1655347353.321830][4849:4849] CHIP:IN: UDP::Init bind&listen port=5550 [1655347353.321924][4849:4849] CHIP:IN: UDP::Init bound to port=5550 [1655347353.321948][4849:4849] CHIP:IN: TransportMgr initialized [1655347353.322065][4849:4849] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347353.322105][4849:4849] CHIP:CTL: Setting attestation nonce to random value [1655347353.322166][4849:4849] CHIP:CTL: Setting CSR nonce to random value [1655347353.323029][4849:4849] CHIP:CTL: Generating NOC [1655347353.323881][4849:4849] CHIP:FP: Validating NOC chain [1655347353.325620][4849:4849] CHIP:FP: NOC chain validation successful [1655347353.325776][4849:4849] CHIP:FP: Added new fabric at index: 0x3, Initialized: 1 [1655347353.325804][4849:4849] CHIP:FP: Assigned compressed fabric ID: 0x884B85B37DC4EE27, node ID: 0x000000000001B669 [1655347353.333246][4849:4849] CHIP:FP: Fabric (0x3) persisted to storage. Calling OnFabricPersistedToStorage [1655347353.333296][4849:4849] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347353.333319][4849:4849] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347353.333340][4849:4849] CHIP:TS: Retaining current Last Known Good Time [1655347353.335244][4849:4849] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x884B85B37DC4EE27 [1655347353.335319][4849:4849] CHIP:IN: UDP::Init bind&listen port=5550 [1655347353.335457][4849:4849] CHIP:IN: UDP::Init bound to port=5550 [1655347353.335486][4849:4849] CHIP:IN: UDP::Init bind&listen port=5550 [1655347353.335579][4849:4849] CHIP:IN: UDP::Init bound to port=5550 [1655347353.335602][4849:4849] CHIP:IN: TransportMgr initialized [1655347353.387911][4849:4854] CHIP:DL: CHIP task running [1655347353.388048][4849:4854] CHIP:TOO: Sending command to node 0x1 [1655347353.388088][4849:4854] CHIP:CSM: FindOrEstablishSession: PeerId = A7866B26C80C3D35:0000000000000001 [1655347353.388115][4849:4854] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1655347353.388237][4849:4854] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 1 --> 2 [1655347353.388311][4849:4854] CHIP:DIS: MINMDNS: Sending query ANY/UNICAST for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347353.388963][4849:4854] CHIP:DIS: Warning: Attempt to mDNS broadcast failed on wlan0: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable [1655347353.389481][4849:4854] CHIP:DIS: mDNS broadcast had only partial success: 7 successes and 1 failures. [1655347353.389756][4849:4854] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347353.389817][4849:4854] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 165DF2067BB604AC.local [1655347353.389851][4849:4854] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347353.389895][4849:4854] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347353.389930][4849:4854] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:db36:1281:eb37:81e9 found for 165DF2067BB604AC.local [1655347353.390562][4849:4854] CHIP:DIS: Lookup clearing interface for non LL address [1655347353.390609][4849:4854] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9%eth0]:5540: new best score: 6 [1655347353.390634][4849:4854] CHIP:DIS: Checking node lookup status after 2 ms [1655347353.390655][4849:4854] CHIP:DIS: Keeping DNSSD lookup active [1655347353.390803][4849:4854] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347353.390857][4849:4854] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 165DF2067BB604AC.local [1655347353.390888][4849:4854] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347353.390927][4849:4854] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347353.390959][4849:4854] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:db36:1281:eb37:81e9 found for 165DF2067BB604AC.local [1655347353.390988][4849:4854] CHIP:DIS: Discovered node without a pending query [1655347353.391398][4849:4854] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9%eth0]:5540: score has not improved: 6 [1655347353.391431][4849:4854] CHIP:DIS: Checking node lookup status after 3 ms [1655347353.391452][4849:4854] CHIP:DIS: Keeping DNSSD lookup active [1655347353.589409][4849:4854] CHIP:DIS: Checking node lookup status after 201 ms [1655347353.589520][4849:4854] CHIP:CTL: Updating device address to UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9]:5540 while in state 2 [1655347353.589577][4849:4854] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 2 --> 3 [1655347353.589967][4849:4854] CHIP:IN: SecureSession[0xaaab14524660]: Allocated Type:2 LSID:501 [1655347353.590049][4849:4854] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1655347353.591742][4849:4854] CHIP:SC: Including MRP parameters [1655347353.592262][4849:4854] CHIP:IN: Prepared unauthenticated message 0xaaab14522998 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 65055i with MessageCounter:252929796. [1655347353.592366][4849:4854] CHIP:IN: Sending unauthenticated msg 0xaaab14522998 with MessageCounter:252929796 to 0x0000000000000000 at monotonic time: 000000000151ADE3 msec [1655347353.592721][4849:4854] CHIP:SC: Sent Sigma1 msg [1655347353.592796][4849:4854] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 3 --> 4 [1655347353.592860][4849:4854] CHIP:DIS: Discovery does not require any more timeouts [1655347353.718810][4849:4854] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:213740476 on exchange 65055i [1655347353.718896][4849:4854] CHIP:EM: Found matching exchange: 65055i, Delegate: 0xffffa00015c0 [1655347353.718968][4849:4854] CHIP:EM: Rxd Ack; Removing MessageCounter:252929796 from Retrans Table on exchange 65055i [1655347353.719014][4849:4854] CHIP:EM: Removed CHIP MessageCounter:252929796 from RetransTable on exchange 65055i [1655347353.738862][4849:4854] CHIP:EM: Received message of type 0x33 with protocolId (0, 0) and MessageCounter:213740477 on exchange 65055i [1655347353.738950][4849:4854] CHIP:EM: Found matching exchange: 65055i, Delegate: 0xffffa00015c0 [1655347353.739004][4849:4854] CHIP:EM: CHIP MessageCounter:252929796 not in RetransTable on exchange 65055i [1655347353.739154][4849:4854] CHIP:SC: Received Sigma2Resume msg [1655347353.739392][4849:4854] CHIP:SC: Found MRP parameters in the message [1655347353.739460][4849:4854] CHIP:SC: Peer assigned session session ID 18474 [1655347353.755846][4849:4854] CHIP:SC: Sending status report. Protocol code 0, exchange 65055 [1655347353.755932][4849:4854] CHIP:EM: Piggybacking Ack for MessageCounter:213740477 on exchange: 65055i [1655347353.755993][4849:4854] CHIP:IN: Prepared unauthenticated message 0xaaab14522998 to 0x0000000000000000 (0) of type 0x40 and protocolId (0, 0) on exchange 65055i with MessageCounter:252929797. [1655347353.756054][4849:4854] CHIP:IN: Sending unauthenticated msg 0xaaab14522998 with MessageCounter:252929797 to 0x0000000000000000 at monotonic time: 000000000151AE86 msec [1655347353.756416][4849:4854] CHIP:IN: SecureSession[0xaaab14524660]: Activated - Type:2 LSID:501 [1655347353.756470][4849:4854] CHIP:IN: New secure session created for device <0000000000000001, 1>, LSID:501 PSID:18474! [1655347353.756597][4849:4854] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 4 --> 5 [1655347353.756680][4849:4854] CHIP:TOO: Sending cluster (0x00000101) command (0x0000000C) on endpoint 1 [1655347353.756758][4849:4854] CHIP:DMG: ICR moving to [AddingComm] [1655347353.756835][4849:4854] CHIP:DMG: ICR moving to [AddedComma] [1655347353.757042][4849:4854] CHIP:IN: Prepared secure message 0xaaab145229b8 to 0x0000000000000001 (1) of type 0x8 and protocolId (0, 1) on exchange 65056i with MessageCounter:40689497. [1655347353.757106][4849:4854] CHIP:IN: Sending encrypted msg 0xaaab145229b8 with MessageCounter:40689497 to 0x0000000000000001 (1) at monotonic time: 000000000151AE87 msec [1655347353.757429][4849:4854] CHIP:DMG: ICR moving to [CommandSen] [1655347353.824093][4849:4854] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:213740478 on exchange 65055i [1655347353.824198][4849:4854] CHIP:EM: Found matching exchange: 65055i, Delegate: (nil) [1655347353.824298][4849:4854] CHIP:EM: Rxd Ack; Removing MessageCounter:252929797 from Retrans Table on exchange 65055i [1655347353.824436][4849:4854] CHIP:EM: Removed CHIP MessageCounter:252929797 from RetransTable on exchange 65055i [1655347353.848927][4849:4854] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:159352380 on exchange 65056i [1655347353.849018][4849:4854] CHIP:EM: Found matching exchange: 65056i, Delegate: 0xffffa0005260 [1655347353.849093][4849:4854] CHIP:EM: Rxd Ack; Removing MessageCounter:40689497 from Retrans Table on exchange 65056i [1655347353.849145][4849:4854] CHIP:EM: Removed CHIP MessageCounter:40689497 from RetransTable on exchange 65056i [1655347353.849250][4849:4854] CHIP:DMG: ICR moving to [ResponseRe] [1655347353.849356][4849:4854] CHIP:DMG: InvokeResponseMessage = [1655347353.849475][4849:4854] CHIP:DMG: { [1655347353.849534][4849:4854] CHIP:DMG: suppressResponse = false, [1655347353.849589][4849:4854] CHIP:DMG: InvokeResponseIBs = [1655347353.849694][4849:4854] CHIP:DMG: [ [1655347353.849747][4849:4854] CHIP:DMG: InvokeResponseIB = [1655347353.849851][4849:4854] CHIP:DMG: { [1655347353.849939][4849:4854] CHIP:DMG: CommandDataIB = [1655347353.850059][4849:4854] CHIP:DMG: { [1655347353.850305][4849:4854] CHIP:DMG: CommandPathIB = [1655347353.850437][4849:4854] CHIP:DMG: { [1655347353.850527][4849:4854] CHIP:DMG: EndpointId = 0x1, [1655347353.850628][4849:4854] CHIP:DMG: ClusterId = 0x101, [1655347353.850699][4849:4854] CHIP:DMG: CommandId = 0xc, [1655347353.850759][4849:4854] CHIP:DMG: }, [1655347353.850853][4849:4854] CHIP:DMG: [1655347353.850924][4849:4854] CHIP:DMG: CommandFields = [1655347353.850994][4849:4854] CHIP:DMG: { [1655347353.851141][4849:4854] CHIP:DMG: 0x0 = 1, [1655347353.851222][4849:4854] CHIP:DMG: 0x1 = 1, [1655347353.851490][4849:4854] CHIP:DMG: 0x2 = 139, [1655347353.851571][4849:4854] CHIP:DMG: }, [1655347353.851659][4849:4854] CHIP:DMG: }, [1655347353.851740][4849:4854] CHIP:DMG: [1655347353.851816][4849:4854] CHIP:DMG: }, [1655347353.851884][4849:4854] CHIP:DMG: [1655347353.851935][4849:4854] CHIP:DMG: ], [1655347353.852019][4849:4854] CHIP:DMG: [1655347353.852072][4849:4854] CHIP:DMG: InteractionModelRevision = 1 [1655347353.852127][4849:4854] CHIP:DMG: }, [1655347353.852274][4849:4854] CHIP:DMG: Received Command Response Data, Endpoint=1 Cluster=0x0000_0101 Command=0x0000_000C [1655347353.852364][4849:4854] CHIP:TOO: Endpoint: 1 Cluster: 0x0000_0101 Command 0x0000_000C [1655347353.852510][4849:4854] CHIP:TOO: GetWeekDayScheduleResponse: { [1655347353.852619][4849:4854] CHIP:TOO: weekDayIndex: 1 [1655347353.852674][4849:4854] CHIP:TOO: userIndex: 1 [1655347353.852722][4849:4854] CHIP:TOO: status: 139 [1655347353.852790][4849:4854] CHIP:TOO: } [1655347353.852865][4849:4854] CHIP:DMG: ICR moving to [AwaitingDe] [1655347353.853040][4849:4854] CHIP:EM: Sending Standalone Ack for MessageCounter:159352380 on exchange 65056i [1655347353.853176][4849:4854] CHIP:IN: Prepared secure message 0xffffb2fdd9d8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 65056i with MessageCounter:40689498. [1655347353.853295][4849:4854] CHIP:IN: Sending encrypted msg 0xffffb2fdd9d8 with MessageCounter:40689498 to 0x0000000000000001 (1) at monotonic time: 000000000151AEE8 msec [1655347353.853511][4849:4854] CHIP:EM: Flushed pending ack for MessageCounter:159352380 on exchange 65056i [1655347353.853861][4849:4849] CHIP:CTL: Shutting down the commissioner [1655347353.854131][4849:4849] CHIP:CTL: Shutting down the controller [1655347353.854193][4849:4849] CHIP:CTL: Shutting down the commissioner [1655347353.854320][4849:4849] CHIP:CTL: Shutting down the controller [1655347353.854373][4849:4849] CHIP:IN: Expiring all connections for fabric 1!! [1655347353.854411][4849:4849] CHIP:IN: SecureSession[0xaaab14524660]: MarkForRemoval Type:2 LSID:501 [1655347353.854449][4849:4849] CHIP:IN: SecureSession[0xaaab14524660]: Released - Type:2 LSID:501 [1655347353.854524][4849:4849] CHIP:CTL: Shutting down the commissioner [1655347353.854654][4849:4849] CHIP:CTL: Shutting down the controller [1655347353.854753][4849:4849] CHIP:IN: Expiring all connections for fabric 2!! [1655347353.854815][4849:4849] CHIP:CTL: Shutting down the commissioner [1655347353.854945][4849:4849] CHIP:CTL: Shutting down the controller [1655347353.854982][4849:4849] CHIP:IN: Expiring all connections for fabric 3!! [1655347353.855032][4849:4849] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1655347353.855931][4849:4849] CHIP:DMG: IM WH moving to [Uninitialized] [1655347353.855983][4849:4849] CHIP:DMG: IM WH moving to [Uninitialized] [1655347353.856017][4849:4849] CHIP:DMG: IM WH moving to [Uninitialized] [1655347353.856077][4849:4849] CHIP:DMG: IM WH moving to [Uninitialized] [1655347353.856180][4849:4849] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1655347353.856345][4849:4849] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1655347353.856757][4849:4849] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-YZylEn) [1655347353.857980][4849:4849] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655347353.858071][4849:4849] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1655347353.858113][4849:4849] CHIP:DL: Inet Layer shutdown [1655347353.858150][4849:4849] CHIP:DL: BLE shutdown [1655347353.858188][4849:4849] CHIP:DL: System Layer shutdown ------ pi@ubuntu:~$ ./chip-tool doorlock set-week-day-schedule 0 1 7 15 45 16 55 1 1 [1655347424.957002][4857:4857] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1655347424.957590][4857:4857] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1655347424.957770][4857:4857] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1655347424.957885][4857:4857] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1655347424.958323][4857:4857] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-1bWKKv) [1655347424.958925][4857:4857] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655347424.958980][4857:4857] CHIP:DL: NVS set: chip-counters/reboot-count = 53 (0x35) [1655347424.959675][4857:4857] CHIP:DL: Got Ethernet interface: eth0 [1655347424.960076][4857:4857] CHIP:DL: Found the primary Ethernet interface:eth0 [1655347424.960559][4857:4857] CHIP:DL: Got WiFi interface: wlan0 [1655347424.960635][4857:4857] CHIP:DL: Failed to reset WiFi statistic counts [1655347424.960716][4857:4857] CHIP:IN: UDP::Init bind&listen port=0 [1655347424.960848][4857:4857] CHIP:IN: UDP::Init bound to port=34519 [1655347424.960876][4857:4857] CHIP:IN: UDP::Init bind&listen port=0 [1655347424.961024][4857:4857] CHIP:IN: UDP::Init bound to port=58921 [1655347424.961053][4857:4857] CHIP:IN: BLEBase::Init - setting/overriding transport [1655347424.961076][4857:4857] CHIP:IN: TransportMgr initialized [1655347424.961131][4857:4857] CHIP:FP: Initializing FabricTable from persistent storage [1655347424.961501][4857:4857] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347424.961643][4857:4857] CHIP:FP: Loading from storage for fabric index 0x1 [1655347424.962335][4857:4857] CHIP:FP: Loading from storage for fabric index 0x2 [1655347424.962918][4857:4857] CHIP:FP: Loading from storage for fabric index 0x3 [1655347424.967831][4857:4857] CHIP:ZCL: Using ZAP configuration... [1655347424.970315][4857:4857] CHIP:DL: MDNS failed to join multicast group on wlan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655347424.972741][4857:4857] CHIP:DL: MDNS failed to join multicast group on wpan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655347424.973080][4857:4857] CHIP:CTL: System State Initialized... [1655347424.973140][4857:4857] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347424.973236][4857:4857] CHIP:CTL: Setting attestation nonce to random value [1655347424.973301][4857:4857] CHIP:CTL: Setting CSR nonce to random value [1655347424.973379][4857:4857] CHIP:IN: UDP::Init bind&listen port=5550 [1655347424.973549][4857:4857] CHIP:IN: UDP::Init bound to port=5550 [1655347424.973579][4857:4857] CHIP:IN: UDP::Init bind&listen port=5550 [1655347424.973703][4857:4857] CHIP:IN: UDP::Init bound to port=5550 [1655347424.973728][4857:4857] CHIP:IN: TransportMgr initialized [1655347424.973807][4857:4857] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347424.973839][4857:4857] CHIP:CTL: Setting attestation nonce to random value [1655347424.973889][4857:4857] CHIP:CTL: Setting CSR nonce to random value [1655347424.977003][4857:4857] CHIP:CTL: Generating NOC [1655347424.977959][4857:4857] CHIP:FP: Validating NOC chain [1655347424.979694][4857:4857] CHIP:FP: NOC chain validation successful [1655347424.979849][4857:4857] CHIP:FP: Added new fabric at index: 0x1, Initialized: 1 [1655347424.979877][4857:4857] CHIP:FP: Assigned compressed fabric ID: 0xA7866B26C80C3D35, node ID: 0x000000000001B669 [1655347424.988056][4857:4857] CHIP:FP: Fabric (0x1) persisted to storage. Calling OnFabricPersistedToStorage [1655347424.988111][4857:4857] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347424.988136][4857:4857] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347424.988158][4857:4857] CHIP:TS: Retaining current Last Known Good Time [1655347424.990518][4857:4857] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0xA7866B26C80C3D35 [1655347424.990601][4857:4857] CHIP:IN: UDP::Init bind&listen port=5550 [1655347424.990744][4857:4857] CHIP:IN: UDP::Init bound to port=5550 [1655347424.990772][4857:4857] CHIP:IN: UDP::Init bind&listen port=5550 [1655347424.990863][4857:4857] CHIP:IN: UDP::Init bound to port=5550 [1655347424.990888][4857:4857] CHIP:IN: TransportMgr initialized [1655347424.991000][4857:4857] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347424.991041][4857:4857] CHIP:CTL: Setting attestation nonce to random value [1655347424.991174][4857:4857] CHIP:CTL: Setting CSR nonce to random value [1655347424.992138][4857:4857] CHIP:CTL: Generating NOC [1655347424.993004][4857:4857] CHIP:FP: Validating NOC chain [1655347424.994745][4857:4857] CHIP:FP: NOC chain validation successful [1655347424.994903][4857:4857] CHIP:FP: Added new fabric at index: 0x2, Initialized: 1 [1655347424.994931][4857:4857] CHIP:FP: Assigned compressed fabric ID: 0x06EE9B0E253A2288, node ID: 0x000000000001B669 [1655347425.002478][4857:4857] CHIP:FP: Fabric (0x2) persisted to storage. Calling OnFabricPersistedToStorage [1655347425.002530][4857:4857] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347425.002555][4857:4857] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347425.002577][4857:4857] CHIP:TS: Retaining current Last Known Good Time [1655347425.004573][4857:4857] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x06EE9B0E253A2288 [1655347425.004652][4857:4857] CHIP:IN: UDP::Init bind&listen port=5550 [1655347425.004792][4857:4857] CHIP:IN: UDP::Init bound to port=5550 [1655347425.004821][4857:4857] CHIP:IN: UDP::Init bind&listen port=5550 [1655347425.004914][4857:4857] CHIP:IN: UDP::Init bound to port=5550 [1655347425.004939][4857:4857] CHIP:IN: TransportMgr initialized [1655347425.005111][4857:4857] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347425.005250][4857:4857] CHIP:CTL: Setting attestation nonce to random value [1655347425.005317][4857:4857] CHIP:CTL: Setting CSR nonce to random value [1655347425.006299][4857:4857] CHIP:CTL: Generating NOC [1655347425.007142][4857:4857] CHIP:FP: Validating NOC chain [1655347425.008909][4857:4857] CHIP:FP: NOC chain validation successful [1655347425.009068][4857:4857] CHIP:FP: Added new fabric at index: 0x3, Initialized: 1 [1655347425.009096][4857:4857] CHIP:FP: Assigned compressed fabric ID: 0x884B85B37DC4EE27, node ID: 0x000000000001B669 [1655347425.018251][4857:4857] CHIP:FP: Fabric (0x3) persisted to storage. Calling OnFabricPersistedToStorage [1655347425.018310][4857:4857] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347425.018334][4857:4857] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347425.018356][4857:4857] CHIP:TS: Retaining current Last Known Good Time [1655347425.020276][4857:4857] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x884B85B37DC4EE27 [1655347425.020353][4857:4857] CHIP:IN: UDP::Init bind&listen port=5550 [1655347425.020492][4857:4857] CHIP:IN: UDP::Init bound to port=5550 [1655347425.020519][4857:4857] CHIP:IN: UDP::Init bind&listen port=5550 [1655347425.020613][4857:4857] CHIP:IN: UDP::Init bound to port=5550 [1655347425.020637][4857:4857] CHIP:IN: TransportMgr initialized [1655347425.110894][4857:4862] CHIP:DL: CHIP task running [1655347425.111201][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 53.0.16.172.in-addr.arpa [1655347425.111265][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.111297][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655347425.111328][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.111411][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.111444][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.111492][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655347425.111564][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.111612][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.111643][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655347425.111713][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 53.0.16.172.in-addr.arpa [1655347425.111745][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.111776][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655347425.111816][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.111885][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.111928][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.111959][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655347425.112038][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.112070][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.8.4.7.d.2.3.8.5.0.5.f.7.7.0.6.0.0.0.0.0.0.0.0.0.0.0.0.0.8.e.f.ip6.arpa [1655347425.112100][4857:4862] CHIP:DIS: MINMDNS: received PTR record for 3.e.f.3.8.5.e.f.f.f.1.0.f.5.6.e.0.0.0.0.0.0.0.0.3.3.0.0.1.1.d.f.ip6.arpa [1655347425.112146][4857:4862] CHIP:TOO: Sending command to node 0x1 [1655347425.112205][4857:4862] CHIP:CSM: FindOrEstablishSession: PeerId = A7866B26C80C3D35:0000000000000001 [1655347425.112245][4857:4862] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1655347425.112289][4857:4862] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 1 --> 2 [1655347425.112342][4857:4862] CHIP:DIS: MINMDNS: Sending query ANY/UNICAST for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.112893][4857:4862] CHIP:DIS: Warning: Attempt to mDNS broadcast failed on wlan0: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable [1655347425.113432][4857:4862] CHIP:DIS: mDNS broadcast had only partial success: 7 successes and 1 failures. [1655347425.113702][4857:4862] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.113793][4857:4862] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655347425.113833][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655347425.113871][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655347425.113906][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655347425.113936][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655347425.113960][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.114007][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655347425.114032][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.114060][4857:4862] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.114090][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.114118][4857:4862] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.114146][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.114171][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655347425.114209][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.114236][4857:4862] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655347425.114292][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.114321][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.114345][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.114370][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.114394][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.114418][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655347425.114453][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655347425.114496][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655347425.114536][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.114562][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.114586][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.114628][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.114662][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.114687][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655347425.114717][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655347425.114744][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655347425.114769][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.114794][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.114818][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.114854][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.114878][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.114901][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655347425.114927][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655347425.114952][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.114987][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.115011][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.115036][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.115060][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.115083][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655347425.115118][4857:4862] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.115144][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.115169][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655347425.115193][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655347425.115228][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347425.115254][4857:4862] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655347425.115279][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.115310][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.115334][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.115441][4857:4862] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.115500][4857:4862] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 165DF2067BB604AC.local [1655347425.115532][4857:4862] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.115565][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347425.115594][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:db36:1281:eb37:81e9 found for 165DF2067BB604AC.local [1655347425.116154][4857:4862] CHIP:DIS: Lookup clearing interface for non LL address [1655347425.116188][4857:4862] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9%eth0]:5540: new best score: 6 [1655347425.116212][4857:4862] CHIP:DIS: Checking node lookup status after 4 ms [1655347425.116233][4857:4862] CHIP:DIS: Keeping DNSSD lookup active [1655347425.116328][4857:4862] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.116389][4857:4862] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655347425.116423][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655347425.116452][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655347425.116494][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655347425.116524][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655347425.116547][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.116574][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655347425.116607][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.116633][4857:4862] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.116656][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.116681][4857:4862] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.116707][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.116741][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655347425.116766][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.116790][4857:4862] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655347425.116844][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.116871][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.116896][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.116920][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.116944][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.116968][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655347425.117007][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655347425.117034][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655347425.117084][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.117111][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.117135][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.117160][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.117233][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.117260][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655347425.117288][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655347425.117314][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655347425.117351][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.117376][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.117400][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.117425][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.117448][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.117483][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655347425.117510][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655347425.117535][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.117560][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.117595][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.117620][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.117643][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.117667][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655347425.117693][4857:4862] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.117730][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.117755][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655347425.117779][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655347425.117802][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347425.117837][4857:4862] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655347425.117862][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.117891][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.117914][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.117994][4857:4862] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.118038][4857:4862] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 165DF2067BB604AC.local [1655347425.118081][4857:4862] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.118115][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347425.118144][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:db36:1281:eb37:81e9 found for 165DF2067BB604AC.local [1655347425.118179][4857:4862] CHIP:DIS: Discovered node without a pending query [1655347425.118622][4857:4862] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9%eth0]:5540: score has not improved: 6 [1655347425.118657][4857:4862] CHIP:DIS: Checking node lookup status after 6 ms [1655347425.118678][4857:4862] CHIP:DIS: Keeping DNSSD lookup active [1655347425.118763][4857:4862] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.118810][4857:4862] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655347425.118857][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655347425.118887][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655347425.118919][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655347425.118957][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655347425.118980][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.119007][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655347425.119039][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.119066][4857:4862] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.119088][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.119113][4857:4862] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.119139][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.119174][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655347425.119199][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.119223][4857:4862] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655347425.119276][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.119303][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.119327][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.119352][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.119375][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.119408][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655347425.119437][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655347425.119465][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655347425.119515][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.119541][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.119565][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.119590][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.119613][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.119647][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655347425.119674][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655347425.119700][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655347425.119735][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.119760][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.119784][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.119809][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.119832][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.119856][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655347425.119892][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655347425.119917][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.119941][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.119975][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.120001][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.120025][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.120048][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655347425.120075][4857:4862] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.120109][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.120134][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655347425.120158][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655347425.120181][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347425.120216][4857:4862] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655347425.120241][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.120268][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.120292][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.120370][4857:4862] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.120412][4857:4862] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655347425.120458][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655347425.120488][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655347425.120520][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655347425.120560][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655347425.120583][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.120610][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655347425.120632][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.120669][4857:4862] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.120696][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.120722][4857:4862] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.120748][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.120773][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655347425.120808][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.120832][4857:4862] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655347425.120873][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.120908][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.120932][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.120956][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.120980][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.121004][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655347425.121041][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655347425.121068][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655347425.121116][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.121143][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.121167][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.121213][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.121238][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.121261][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655347425.121289][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655347425.121325][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655347425.121352][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.121377][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.121401][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.121425][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.121458][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.121482][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655347425.121507][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655347425.121533][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.121557][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.121590][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.121615][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.121639][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.121663][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655347425.121698][4857:4862] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.121724][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.121749][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655347425.121773][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655347425.121796][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347425.121831][4857:4862] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655347425.121857][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.121885][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.121908][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.121988][4857:4862] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.122033][4857:4862] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655347425.122076][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655347425.122106][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655347425.122147][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655347425.122177][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655347425.122199][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.122226][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655347425.122259][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.122285][4857:4862] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.122307][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.122333][4857:4862] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.122368][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.122392][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655347425.122416][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.122441][4857:4862] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655347425.122491][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.122518][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.122543][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.122568][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.122601][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.122625][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655347425.122652][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655347425.122678][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655347425.122728][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.122753][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.122777][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.122801][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.122824][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.122848][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655347425.122875][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655347425.122912][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655347425.122938][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.122962][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.122986][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.123010][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.123033][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.123067][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655347425.123093][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655347425.123118][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.123142][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.123176][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.123200][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.123224][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.123247][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655347425.123273][4857:4862] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.123309][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.123333][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655347425.123357][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655347425.123381][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347425.123416][4857:4862] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655347425.123441][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.123468][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.123491][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.123569][4857:4862] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.123621][4857:4862] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655347425.123654][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655347425.123683][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655347425.123724][4857:4862] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655347425.123755][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655347425.123777][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.123804][4857:4862] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655347425.123836][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.123863][4857:4862] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.123885][4857:4862] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655347425.123911][4857:4862] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655347425.123936][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.123969][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655347425.123994][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.124018][4857:4862] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655347425.124067][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.124093][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.124118][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.124142][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.124166][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.124199][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655347425.124227][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655347425.124254][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655347425.124302][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.124329][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.124353][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.124377][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.124411][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.124436][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655347425.124463][4857:4862] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655347425.124488][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655347425.124523][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.124548][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.124572][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.124596][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.124620][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.124653][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655347425.124679][4857:4862] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655347425.124704][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655347425.124729][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655347425.124761][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655347425.124787][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655347425.124810][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655347425.124834][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655347425.124860][4857:4862] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347425.124885][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655347425.124921][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655347425.124945][4857:4862] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655347425.124969][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347425.124994][4857:4862] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655347425.125019][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125057][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.125081][4857:4862] CHIP:DIS: No delegate to report commissioning node discovery [1655347425.125251][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125281][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125352][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125378][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125435][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125472][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125541][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125568][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125626][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125652][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125720][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.125758][4857:4862] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655347425.312853][4857:4862] CHIP:DIS: Checking node lookup status after 200 ms [1655347425.312964][4857:4862] CHIP:CTL: Updating device address to UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9]:5540 while in state 2 [1655347425.313023][4857:4862] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 2 --> 3 [1655347425.313471][4857:4862] CHIP:IN: SecureSession[0xaaaad17217c0]: Allocated Type:2 LSID:19985 [1655347425.313593][4857:4862] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1655347425.315317][4857:4862] CHIP:SC: Including MRP parameters [1655347425.315864][4857:4862] CHIP:IN: Prepared unauthenticated message 0xaaaad171fa48 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 41735i with MessageCounter:17850704. [1655347425.315969][4857:4862] CHIP:IN: Sending unauthenticated msg 0xaaaad171fa48 with MessageCounter:17850704 to 0x0000000000000000 at monotonic time: 000000000152C60E msec [1655347425.316330][4857:4862] CHIP:SC: Sent Sigma1 msg [1655347425.316404][4857:4862] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 3 --> 4 [1655347425.316501][4857:4862] CHIP:DIS: Discovery does not require any more timeouts [1655347425.441466][4857:4862] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:213740479 on exchange 41735i [1655347425.441574][4857:4862] CHIP:EM: Found matching exchange: 41735i, Delegate: 0xffff84001440 [1655347425.441664][4857:4862] CHIP:EM: Rxd Ack; Removing MessageCounter:17850704 from Retrans Table on exchange 41735i [1655347425.441727][4857:4862] CHIP:EM: Removed CHIP MessageCounter:17850704 from RetransTable on exchange 41735i [1655347425.460994][4857:4862] CHIP:EM: Received message of type 0x33 with protocolId (0, 0) and MessageCounter:213740480 on exchange 41735i [1655347425.461078][4857:4862] CHIP:EM: Found matching exchange: 41735i, Delegate: 0xffff84001440 [1655347425.461130][4857:4862] CHIP:EM: CHIP MessageCounter:17850704 not in RetransTable on exchange 41735i [1655347425.461235][4857:4862] CHIP:SC: Received Sigma2Resume msg [1655347425.461412][4857:4862] CHIP:SC: Found MRP parameters in the message [1655347425.461475][4857:4862] CHIP:SC: Peer assigned session session ID 18475 [1655347425.477851][4857:4862] CHIP:SC: Sending status report. Protocol code 0, exchange 41735 [1655347425.477925][4857:4862] CHIP:EM: Piggybacking Ack for MessageCounter:213740480 on exchange: 41735i [1655347425.477976][4857:4862] CHIP:IN: Prepared unauthenticated message 0xaaaad171fa48 to 0x0000000000000000 (0) of type 0x40 and protocolId (0, 0) on exchange 41735i with MessageCounter:17850705. [1655347425.478031][4857:4862] CHIP:IN: Sending unauthenticated msg 0xaaaad171fa48 with MessageCounter:17850705 to 0x0000000000000000 at monotonic time: 000000000152C6B0 msec [1655347425.478384][4857:4862] CHIP:IN: SecureSession[0xaaaad17217c0]: Activated - Type:2 LSID:19985 [1655347425.478429][4857:4862] CHIP:IN: New secure session created for device <0000000000000001, 1>, LSID:19985 PSID:18475! [1655347425.478461][4857:4862] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 4 --> 5 [1655347425.478517][4857:4862] CHIP:TOO: Sending cluster (0x00000101) command (0x0000000B) on endpoint 1 [1655347425.478576][4857:4862] CHIP:DMG: ICR moving to [AddingComm] [1655347425.478661][4857:4862] CHIP:DMG: ICR moving to [AddedComma] [1655347425.478813][4857:4862] CHIP:IN: Prepared secure message 0xaaaad171fa68 to 0x0000000000000001 (1) of type 0x8 and protocolId (0, 1) on exchange 41736i with MessageCounter:23991264. [1655347425.478874][4857:4862] CHIP:IN: Sending encrypted msg 0xaaaad171fa68 with MessageCounter:23991264 to 0x0000000000000001 (1) at monotonic time: 000000000152C6B1 msec [1655347425.479081][4857:4862] CHIP:DMG: ICR moving to [CommandSen] [1655347425.541256][4857:4862] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:213740481 on exchange 41735i [1655347425.541351][4857:4862] CHIP:EM: Found matching exchange: 41735i, Delegate: (nil) [1655347425.541448][4857:4862] CHIP:EM: Rxd Ack; Removing MessageCounter:17850705 from Retrans Table on exchange 41735i [1655347425.541503][4857:4862] CHIP:EM: Removed CHIP MessageCounter:17850705 from RetransTable on exchange 41735i [1655347425.591754][4857:4862] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:69001002 on exchange 41736i [1655347425.591858][4857:4862] CHIP:EM: Found matching exchange: 41736i, Delegate: 0xffff84007710 [1655347425.591952][4857:4862] CHIP:EM: Rxd Ack; Removing MessageCounter:23991264 from Retrans Table on exchange 41736i [1655347425.592014][4857:4862] CHIP:EM: Removed CHIP MessageCounter:23991264 from RetransTable on exchange 41736i [1655347425.592098][4857:4862] CHIP:DMG: ICR moving to [ResponseRe] [1655347425.592206][4857:4862] CHIP:DMG: InvokeResponseMessage = [1655347425.592270][4857:4862] CHIP:DMG: { [1655347425.592336][4857:4862] CHIP:DMG: suppressResponse = false, [1655347425.592404][4857:4862] CHIP:DMG: InvokeResponseIBs = [1655347425.592489][4857:4862] CHIP:DMG: [ [1655347425.592554][4857:4862] CHIP:DMG: InvokeResponseIB = [1655347425.592640][4857:4862] CHIP:DMG: { [1655347425.592705][4857:4862] CHIP:DMG: CommandStatusIB = [1655347425.592802][4857:4862] CHIP:DMG: { [1655347425.592888][4857:4862] CHIP:DMG: CommandPathIB = [1655347425.593367][4857:4862] CHIP:DMG: { [1655347425.593472][4857:4862] CHIP:DMG: EndpointId = 0x1, [1655347425.593564][4857:4862] CHIP:DMG: ClusterId = 0x101, [1655347425.593661][4857:4862] CHIP:DMG: CommandId = 0xb, [1655347425.593745][4857:4862] CHIP:DMG: }, [1655347425.593915][4857:4862] CHIP:DMG: [1655347425.593992][4857:4862] CHIP:DMG: StatusIB = [1655347425.594082][4857:4862] CHIP:DMG: { [1655347425.594173][4857:4862] CHIP:DMG: status = 0x85 (INVALID_COMMAND), [1655347425.594261][4857:4862] CHIP:DMG: }, [1655347425.594394][4857:4862] CHIP:DMG: [1655347425.594477][4857:4862] CHIP:DMG: }, [1655347425.594566][4857:4862] CHIP:DMG: [1655347425.594636][4857:4862] CHIP:DMG: }, [1655347425.594719][4857:4862] CHIP:DMG: [1655347425.594778][4857:4862] CHIP:DMG: ], [1655347425.594853][4857:4862] CHIP:DMG: [1655347425.594963][4857:4862] CHIP:DMG: InteractionModelRevision = 1 [1655347425.595022][4857:4862] CHIP:DMG: }, [1655347425.595162][4857:4862] CHIP:DMG: Received Command Response Status for Endpoint=1 Cluster=0x0000_0101 Command=0x0000_000B Status=0x85 [1655347425.595241][4857:4862] CHIP:TOO: Error: IM Error 0x00000585: General error: 0x85 (INVALID_COMMAND) [1655347425.595335][4857:4862] CHIP:DMG: ICR moving to [AwaitingDe] [1655347425.595518][4857:4862] CHIP:EM: Sending Standalone Ack for MessageCounter:69001002 on exchange 41736i [1655347425.595683][4857:4862] CHIP:IN: Prepared secure message 0xffff977ed9d8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 41736i with MessageCounter:23991265. [1655347425.595778][4857:4862] CHIP:IN: Sending encrypted msg 0xffff977ed9d8 with MessageCounter:23991265 to 0x0000000000000001 (1) at monotonic time: 000000000152C726 msec [1655347425.596043][4857:4862] CHIP:EM: Flushed pending ack for MessageCounter:69001002 on exchange 41736i [1655347425.596529][4857:4857] CHIP:CTL: Shutting down the commissioner [1655347425.596800][4857:4857] CHIP:CTL: Shutting down the controller [1655347425.596885][4857:4857] CHIP:CTL: Shutting down the commissioner [1655347425.597079][4857:4857] CHIP:CTL: Shutting down the controller [1655347425.597155][4857:4857] CHIP:IN: Expiring all connections for fabric 1!! [1655347425.597267][4857:4857] CHIP:IN: SecureSession[0xaaaad17217c0]: MarkForRemoval Type:2 LSID:19985 [1655347425.597317][4857:4857] CHIP:IN: SecureSession[0xaaaad17217c0]: Released - Type:2 LSID:19985 [1655347425.597421][4857:4857] CHIP:CTL: Shutting down the commissioner [1655347425.597626][4857:4857] CHIP:CTL: Shutting down the controller [1655347425.597684][4857:4857] CHIP:IN: Expiring all connections for fabric 2!! [1655347425.597768][4857:4857] CHIP:CTL: Shutting down the commissioner [1655347425.597953][4857:4857] CHIP:CTL: Shutting down the controller [1655347425.598012][4857:4857] CHIP:IN: Expiring all connections for fabric 3!! [1655347425.598087][4857:4857] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1655347425.599145][4857:4857] CHIP:DMG: IM WH moving to [Uninitialized] [1655347425.599211][4857:4857] CHIP:DMG: IM WH moving to [Uninitialized] [1655347425.599264][4857:4857] CHIP:DMG: IM WH moving to [Uninitialized] [1655347425.599314][4857:4857] CHIP:DMG: IM WH moving to [Uninitialized] [1655347425.599367][4857:4857] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1655347425.599554][4857:4857] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1655347425.600015][4857:4857] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-sFhEun) [1655347425.601329][4857:4857] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655347425.601453][4857:4857] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1655347425.601515][4857:4857] CHIP:DL: Inet Layer shutdown [1655347425.601568][4857:4857] CHIP:DL: BLE shutdown [1655347425.601622][4857:4857] CHIP:DL: System Layer shutdown [1655347425.601860][4857:4857] CHIP:TOO: Run command failure: IM Error 0x00000585: General error: 0x85 (INVALID_COMMAND) ----- pi@ubuntu:~$ ./chip-tool doorlock get-week-day-schedule 0 1 1 1 [1655347485.730570][4867:4867] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1655347485.731104][4867:4867] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1655347485.731271][4867:4867] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1655347485.731385][4867:4867] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1655347485.731718][4867:4867] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-RM14Tx) [1655347485.732319][4867:4867] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655347485.732375][4867:4867] CHIP:DL: NVS set: chip-counters/reboot-count = 54 (0x36) [1655347485.732996][4867:4867] CHIP:DL: Got Ethernet interface: eth0 [1655347485.733473][4867:4867] CHIP:DL: Found the primary Ethernet interface:eth0 [1655347485.733915][4867:4867] CHIP:DL: Got WiFi interface: wlan0 [1655347485.733989][4867:4867] CHIP:DL: Failed to reset WiFi statistic counts [1655347485.734068][4867:4867] CHIP:IN: UDP::Init bind&listen port=0 [1655347485.734199][4867:4867] CHIP:IN: UDP::Init bound to port=34984 [1655347485.734227][4867:4867] CHIP:IN: UDP::Init bind&listen port=0 [1655347485.734325][4867:4867] CHIP:IN: UDP::Init bound to port=51902 [1655347485.734351][4867:4867] CHIP:IN: BLEBase::Init - setting/overriding transport [1655347485.734372][4867:4867] CHIP:IN: TransportMgr initialized [1655347485.734424][4867:4867] CHIP:FP: Initializing FabricTable from persistent storage [1655347485.734657][4867:4867] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347485.734786][4867:4867] CHIP:FP: Loading from storage for fabric index 0x1 [1655347485.735408][4867:4867] CHIP:FP: Loading from storage for fabric index 0x2 [1655347485.736039][4867:4867] CHIP:FP: Loading from storage for fabric index 0x3 [1655347485.742088][4867:4867] CHIP:ZCL: Using ZAP configuration... [1655347485.745070][4867:4867] CHIP:DL: MDNS failed to join multicast group on wlan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655347485.747715][4867:4867] CHIP:DL: MDNS failed to join multicast group on wpan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655347485.748064][4867:4867] CHIP:CTL: System State Initialized... [1655347485.748125][4867:4867] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347485.748192][4867:4867] CHIP:CTL: Setting attestation nonce to random value [1655347485.748256][4867:4867] CHIP:CTL: Setting CSR nonce to random value [1655347485.748334][4867:4867] CHIP:IN: UDP::Init bind&listen port=5550 [1655347485.748435][4867:4867] CHIP:IN: UDP::Init bound to port=5550 [1655347485.748463][4867:4867] CHIP:IN: UDP::Init bind&listen port=5550 [1655347485.748557][4867:4867] CHIP:IN: UDP::Init bound to port=5550 [1655347485.748581][4867:4867] CHIP:IN: TransportMgr initialized [1655347485.748663][4867:4867] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347485.748695][4867:4867] CHIP:CTL: Setting attestation nonce to random value [1655347485.748746][4867:4867] CHIP:CTL: Setting CSR nonce to random value [1655347485.751823][4867:4867] CHIP:CTL: Generating NOC [1655347485.752688][4867:4867] CHIP:FP: Validating NOC chain [1655347485.754454][4867:4867] CHIP:FP: NOC chain validation successful [1655347485.754615][4867:4867] CHIP:FP: Added new fabric at index: 0x1, Initialized: 1 [1655347485.754643][4867:4867] CHIP:FP: Assigned compressed fabric ID: 0xA7866B26C80C3D35, node ID: 0x000000000001B669 [1655347485.763069][4867:4867] CHIP:FP: Fabric (0x1) persisted to storage. Calling OnFabricPersistedToStorage [1655347485.763123][4867:4867] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347485.763148][4867:4867] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347485.763170][4867:4867] CHIP:TS: Retaining current Last Known Good Time [1655347485.765145][4867:4867] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0xA7866B26C80C3D35 [1655347485.765245][4867:4867] CHIP:IN: UDP::Init bind&listen port=5550 [1655347485.765392][4867:4867] CHIP:IN: UDP::Init bound to port=5550 [1655347485.765421][4867:4867] CHIP:IN: UDP::Init bind&listen port=5550 [1655347485.765512][4867:4867] CHIP:IN: UDP::Init bound to port=5550 [1655347485.765537][4867:4867] CHIP:IN: TransportMgr initialized [1655347485.765649][4867:4867] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347485.765691][4867:4867] CHIP:CTL: Setting attestation nonce to random value [1655347485.765753][4867:4867] CHIP:CTL: Setting CSR nonce to random value [1655347485.766629][4867:4867] CHIP:CTL: Generating NOC [1655347485.767484][4867:4867] CHIP:FP: Validating NOC chain [1655347485.769611][4867:4867] CHIP:FP: NOC chain validation successful [1655347485.769794][4867:4867] CHIP:FP: Added new fabric at index: 0x2, Initialized: 1 [1655347485.769823][4867:4867] CHIP:FP: Assigned compressed fabric ID: 0x06EE9B0E253A2288, node ID: 0x000000000001B669 [1655347485.817471][4867:4867] CHIP:FP: Fabric (0x2) persisted to storage. Calling OnFabricPersistedToStorage [1655347485.817575][4867:4867] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347485.817632][4867:4867] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347485.817686][4867:4867] CHIP:TS: Retaining current Last Known Good Time [1655347485.822347][4867:4867] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x06EE9B0E253A2288 [1655347485.822656][4867:4867] CHIP:IN: UDP::Init bind&listen port=5550 [1655347485.822943][4867:4867] CHIP:IN: UDP::Init bound to port=5550 [1655347485.823012][4867:4867] CHIP:IN: UDP::Init bind&listen port=5550 [1655347485.823232][4867:4867] CHIP:IN: UDP::Init bound to port=5550 [1655347485.823292][4867:4867] CHIP:IN: TransportMgr initialized [1655347485.823508][4867:4867] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655347485.823594][4867:4867] CHIP:CTL: Setting attestation nonce to random value [1655347485.823729][4867:4867] CHIP:CTL: Setting CSR nonce to random value [1655347485.824932][4867:4867] CHIP:CTL: Generating NOC [1655347485.825807][4867:4867] CHIP:FP: Validating NOC chain [1655347485.827518][4867:4867] CHIP:FP: NOC chain validation successful [1655347485.827679][4867:4867] CHIP:FP: Added new fabric at index: 0x3, Initialized: 1 [1655347485.827707][4867:4867] CHIP:FP: Assigned compressed fabric ID: 0x884B85B37DC4EE27, node ID: 0x000000000001B669 [1655347485.835342][4867:4867] CHIP:FP: Fabric (0x3) persisted to storage. Calling OnFabricPersistedToStorage [1655347485.835394][4867:4867] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655347485.835419][4867:4867] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655347485.835440][4867:4867] CHIP:TS: Retaining current Last Known Good Time [1655347485.837429][4867:4867] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x884B85B37DC4EE27 [1655347485.837505][4867:4867] CHIP:IN: UDP::Init bind&listen port=5550 [1655347485.837644][4867:4867] CHIP:IN: UDP::Init bound to port=5550 [1655347485.837672][4867:4867] CHIP:IN: UDP::Init bind&listen port=5550 [1655347485.837768][4867:4867] CHIP:IN: UDP::Init bound to port=5550 [1655347485.837792][4867:4867] CHIP:IN: TransportMgr initialized [1655347485.885639][4867:4872] CHIP:DL: CHIP task running [1655347485.885776][4867:4872] CHIP:TOO: Sending command to node 0x1 [1655347485.885815][4867:4872] CHIP:CSM: FindOrEstablishSession: PeerId = A7866B26C80C3D35:0000000000000001 [1655347485.885843][4867:4872] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1655347485.885981][4867:4872] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 1 --> 2 [1655347485.886058][4867:4872] CHIP:DIS: MINMDNS: Sending query ANY/UNICAST for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347485.886688][4867:4872] CHIP:DIS: Warning: Attempt to mDNS broadcast failed on wlan0: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable [1655347485.887238][4867:4872] CHIP:DIS: mDNS broadcast had only partial success: 7 successes and 1 failures. [1655347485.887518][4867:4872] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347485.887578][4867:4872] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 165DF2067BB604AC.local [1655347485.887611][4867:4872] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347485.887652][4867:4872] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347485.887686][4867:4872] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:db36:1281:eb37:81e9 found for 165DF2067BB604AC.local [1655347485.888312][4867:4872] CHIP:DIS: Lookup clearing interface for non LL address [1655347485.888355][4867:4872] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9%eth0]:5540: new best score: 6 [1655347485.888381][4867:4872] CHIP:DIS: Checking node lookup status after 3 ms [1655347485.888404][4867:4872] CHIP:DIS: Keeping DNSSD lookup active [1655347485.888640][4867:4872] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347485.888697][4867:4872] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 165DF2067BB604AC.local [1655347485.888731][4867:4872] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655347485.888772][4867:4872] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655347485.888807][4867:4872] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:db36:1281:eb37:81e9 found for 165DF2067BB604AC.local [1655347485.888837][4867:4872] CHIP:DIS: Discovered node without a pending query [1655347485.889311][4867:4872] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9%eth0]:5540: score has not improved: 6 [1655347485.889350][4867:4872] CHIP:DIS: Checking node lookup status after 4 ms [1655347485.889374][4867:4872] CHIP:DIS: Keeping DNSSD lookup active [1655347486.086414][4867:4872] CHIP:DIS: Checking node lookup status after 201 ms [1655347486.086528][4867:4872] CHIP:CTL: Updating device address to UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9]:5540 while in state 2 [1655347486.086587][4867:4872] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 2 --> 3 [1655347486.086978][4867:4872] CHIP:IN: SecureSession[0xaaaaf0971a70]: Allocated Type:2 LSID:59201 [1655347486.087066][4867:4872] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1655347486.088770][4867:4872] CHIP:SC: Including MRP parameters [1655347486.089384][4867:4872] CHIP:IN: Prepared unauthenticated message 0xaaaaf096fcf8 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 58541i with MessageCounter:201090579. [1655347486.089492][4867:4872] CHIP:IN: Sending unauthenticated msg 0xaaaaf096fcf8 with MessageCounter:201090579 to 0x0000000000000000 at monotonic time: 000000000153B374 msec [1655347486.089851][4867:4872] CHIP:SC: Sent Sigma1 msg [1655347486.089926][4867:4872] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 3 --> 4 [1655347486.089995][4867:4872] CHIP:DIS: Discovery does not require any more timeouts [1655347486.203807][4867:4872] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:213740482 on exchange 58541i [1655347486.203889][4867:4872] CHIP:EM: Found matching exchange: 58541i, Delegate: 0xffff700015c0 [1655347486.203957][4867:4872] CHIP:EM: Rxd Ack; Removing MessageCounter:201090579 from Retrans Table on exchange 58541i [1655347486.204002][4867:4872] CHIP:EM: Removed CHIP MessageCounter:201090579 from RetransTable on exchange 58541i [1655347486.223907][4867:4872] CHIP:EM: Received message of type 0x33 with protocolId (0, 0) and MessageCounter:213740483 on exchange 58541i [1655347486.223994][4867:4872] CHIP:EM: Found matching exchange: 58541i, Delegate: 0xffff700015c0 [1655347486.224050][4867:4872] CHIP:EM: CHIP MessageCounter:201090579 not in RetransTable on exchange 58541i [1655347486.224127][4867:4872] CHIP:SC: Received Sigma2Resume msg [1655347486.224314][4867:4872] CHIP:SC: Found MRP parameters in the message [1655347486.224449][4867:4872] CHIP:SC: Peer assigned session session ID 18476 [1655347486.240553][4867:4872] CHIP:SC: Sending status report. Protocol code 0, exchange 58541 [1655347486.240630][4867:4872] CHIP:EM: Piggybacking Ack for MessageCounter:213740483 on exchange: 58541i [1655347486.240683][4867:4872] CHIP:IN: Prepared unauthenticated message 0xaaaaf096fcf8 to 0x0000000000000000 (0) of type 0x40 and protocolId (0, 0) on exchange 58541i with MessageCounter:201090580. [1655347486.240738][4867:4872] CHIP:IN: Sending unauthenticated msg 0xaaaaf096fcf8 with MessageCounter:201090580 to 0x0000000000000000 at monotonic time: 000000000153B40B msec [1655347486.241069][4867:4872] CHIP:IN: SecureSession[0xaaaaf0971a70]: Activated - Type:2 LSID:59201 [1655347486.241120][4867:4872] CHIP:IN: New secure session created for device <0000000000000001, 1>, LSID:59201 PSID:18476! [1655347486.241152][4867:4872] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 4 --> 5 [1655347486.241240][4867:4872] CHIP:TOO: Sending cluster (0x00000101) command (0x0000000C) on endpoint 1 [1655347486.241310][4867:4872] CHIP:DMG: ICR moving to [AddingComm] [1655347486.241495][4867:4872] CHIP:DMG: ICR moving to [AddedComma] [1655347486.241696][4867:4872] CHIP:IN: Prepared secure message 0xaaaaf096fd18 to 0x0000000000000001 (1) of type 0x8 and protocolId (0, 1) on exchange 58542i with MessageCounter:139788771. [1655347486.241752][4867:4872] CHIP:IN: Sending encrypted msg 0xaaaaf096fd18 with MessageCounter:139788771 to 0x0000000000000001 (1) at monotonic time: 000000000153B40C msec [1655347486.241959][4867:4872] CHIP:DMG: ICR moving to [CommandSen] [1655347486.304094][4867:4872] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:213740484 on exchange 58541i [1655347486.304271][4867:4872] CHIP:EM: Found matching exchange: 58541i, Delegate: (nil) [1655347486.304381][4867:4872] CHIP:EM: Rxd Ack; Removing MessageCounter:201090580 from Retrans Table on exchange 58541i [1655347486.304452][4867:4872] CHIP:EM: Removed CHIP MessageCounter:201090580 from RetransTable on exchange 58541i [1655347486.334220][4867:4872] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:186285421 on exchange 58542i [1655347486.334320][4867:4872] CHIP:EM: Found matching exchange: 58542i, Delegate: 0xffff70006f40 [1655347486.334408][4867:4872] CHIP:EM: Rxd Ack; Removing MessageCounter:139788771 from Retrans Table on exchange 58542i [1655347486.334471][4867:4872] CHIP:EM: Removed CHIP MessageCounter:139788771 from RetransTable on exchange 58542i [1655347486.334640][4867:4872] CHIP:DMG: ICR moving to [ResponseRe] [1655347486.334816][4867:4872] CHIP:DMG: InvokeResponseMessage = [1655347486.334887][4867:4872] CHIP:DMG: { [1655347486.334948][4867:4872] CHIP:DMG: suppressResponse = false, [1655347486.335013][4867:4872] CHIP:DMG: InvokeResponseIBs = [1655347486.335092][4867:4872] CHIP:DMG: [ [1655347486.335154][4867:4872] CHIP:DMG: InvokeResponseIB = [1655347486.335420][4867:4872] CHIP:DMG: { [1655347486.335546][4867:4872] CHIP:DMG: CommandDataIB = [1655347486.335625][4867:4872] CHIP:DMG: { [1655347486.335697][4867:4872] CHIP:DMG: CommandPathIB = [1655347486.335774][4867:4872] CHIP:DMG: { [1655347486.335853][4867:4872] CHIP:DMG: EndpointId = 0x1, [1655347486.335936][4867:4872] CHIP:DMG: ClusterId = 0x101, [1655347486.336024][4867:4872] CHIP:DMG: CommandId = 0xc, [1655347486.336108][4867:4872] CHIP:DMG: }, [1655347486.336196][4867:4872] CHIP:DMG: [1655347486.336266][4867:4872] CHIP:DMG: CommandFields = [1655347486.336343][4867:4872] CHIP:DMG: { [1655347486.336480][4867:4872] CHIP:DMG: 0x0 = 0, [1655347486.336577][4867:4872] CHIP:DMG: 0x1 = 1, [1655347486.336664][4867:4872] CHIP:DMG: 0x2 = 133, [1655347486.336756][4867:4872] CHIP:DMG: }, [1655347486.336835][4867:4872] CHIP:DMG: }, [1655347486.336919][4867:4872] CHIP:DMG: [1655347486.336987][4867:4872] CHIP:DMG: }, [1655347486.337067][4867:4872] CHIP:DMG: [1655347486.337112][4867:4872] CHIP:DMG: ], [1655347486.337169][4867:4872] CHIP:DMG: [1655347486.337348][4867:4872] CHIP:DMG: InteractionModelRevision = 1 [1655347486.337393][4867:4872] CHIP:DMG: }, [1655347486.337508][4867:4872] CHIP:DMG: Received Command Response Data, Endpoint=1 Cluster=0x0000_0101 Command=0x0000_000C [1655347486.337592][4867:4872] CHIP:TOO: Endpoint: 1 Cluster: 0x0000_0101 Command 0x0000_000C [1655347486.337699][4867:4872] CHIP:TOO: GetWeekDayScheduleResponse: { [1655347486.337778][4867:4872] CHIP:TOO: weekDayIndex: 0 [1655347486.337824][4867:4872] CHIP:TOO: userIndex: 1 [1655347486.337867][4867:4872] CHIP:TOO: status: 133 [1655347486.337911][4867:4872] CHIP:TOO: } [1655347486.337982][4867:4872] CHIP:DMG: ICR moving to [AwaitingDe] [1655347486.338076][4867:4872] CHIP:EM: Sending Standalone Ack for MessageCounter:186285421 on exchange 58542i [1655347486.338207][4867:4872] CHIP:IN: Prepared secure message 0xffff77ffd9d8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 58542i with MessageCounter:139788772. [1655347486.338281][4867:4872] CHIP:IN: Sending encrypted msg 0xffff77ffd9d8 with MessageCounter:139788772 to 0x0000000000000001 (1) at monotonic time: 000000000153B46D msec [1655347486.338498][4867:4872] CHIP:EM: Flushed pending ack for MessageCounter:186285421 on exchange 58542i [1655347486.338839][4867:4867] CHIP:CTL: Shutting down the commissioner [1655347486.339088][4867:4867] CHIP:CTL: Shutting down the controller [1655347486.339152][4867:4867] CHIP:CTL: Shutting down the commissioner [1655347486.339391][4867:4867] CHIP:CTL: Shutting down the controller [1655347486.339450][4867:4867] CHIP:IN: Expiring all connections for fabric 1!! [1655347486.339493][4867:4867] CHIP:IN: SecureSession[0xaaaaf0971a70]: MarkForRemoval Type:2 LSID:59201 [1655347486.339535][4867:4867] CHIP:IN: SecureSession[0xaaaaf0971a70]: Released - Type:2 LSID:59201 [1655347486.339615][4867:4867] CHIP:CTL: Shutting down the commissioner [1655347486.339756][4867:4867] CHIP:CTL: Shutting down the controller [1655347486.339800][4867:4867] CHIP:IN: Expiring all connections for fabric 2!! [1655347486.339862][4867:4867] CHIP:CTL: Shutting down the commissioner [1655347486.339996][4867:4867] CHIP:CTL: Shutting down the controller [1655347486.340039][4867:4867] CHIP:IN: Expiring all connections for fabric 3!! [1655347486.340094][4867:4867] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1655347486.341032][4867:4867] CHIP:DMG: IM WH moving to [Uninitialized] [1655347486.341081][4867:4867] CHIP:DMG: IM WH moving to [Uninitialized] [1655347486.341119][4867:4867] CHIP:DMG: IM WH moving to [Uninitialized] [1655347486.341156][4867:4867] CHIP:DMG: IM WH moving to [Uninitialized] [1655347486.341232][4867:4867] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1655347486.341383][4867:4867] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1655347486.341737][4867:4867] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-3pOg3k) [1655347486.342676][4867:4867] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655347486.342767][4867:4867] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1655347486.342814][4867:4867] CHIP:DL: Inet Layer shutdown [1655347486.342854][4867:4867] CHIP:DL: BLE shutdown [1655347486.342894][4867:4867] CHIP:DL: System Layer shutdown ------ pi@ubuntu:~$ ./chip-tool doorlock clear-week-day-schedule 1 1 1 1 [1655350766.956817][5142:5142] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1655350766.957399][5142:5142] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1655350766.957580][5142:5142] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1655350766.957695][5142:5142] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1655350766.958066][5142:5142] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-wBgBpr) [1655350766.958662][5142:5142] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655350766.958717][5142:5142] CHIP:DL: NVS set: chip-counters/reboot-count = 76 (0x4C) [1655350766.959341][5142:5142] CHIP:DL: Got Ethernet interface: eth0 [1655350766.959892][5142:5142] CHIP:DL: Found the primary Ethernet interface:eth0 [1655350766.960325][5142:5142] CHIP:DL: Got WiFi interface: wlan0 [1655350766.960397][5142:5142] CHIP:DL: Failed to reset WiFi statistic counts [1655350766.960479][5142:5142] CHIP:IN: UDP::Init bind&listen port=0 [1655350766.960612][5142:5142] CHIP:IN: UDP::Init bound to port=49715 [1655350766.960640][5142:5142] CHIP:IN: UDP::Init bind&listen port=0 [1655350766.960739][5142:5142] CHIP:IN: UDP::Init bound to port=39509 [1655350766.960763][5142:5142] CHIP:IN: BLEBase::Init - setting/overriding transport [1655350766.960784][5142:5142] CHIP:IN: TransportMgr initialized [1655350766.960836][5142:5142] CHIP:FP: Initializing FabricTable from persistent storage [1655350766.961266][5142:5142] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655350766.961456][5142:5142] CHIP:FP: Loading from storage for fabric index 0x1 [1655350766.962305][5142:5142] CHIP:FP: Loading from storage for fabric index 0x2 [1655350766.963051][5142:5142] CHIP:FP: Loading from storage for fabric index 0x3 [1655350766.969830][5142:5142] CHIP:ZCL: Using ZAP configuration... [1655350766.972378][5142:5142] CHIP:DL: MDNS failed to join multicast group on wlan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655350766.974631][5142:5142] CHIP:DL: MDNS failed to join multicast group on wpan0 for address type IPv4: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found [1655350766.974980][5142:5142] CHIP:CTL: System State Initialized... [1655350766.975042][5142:5142] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655350766.975104][5142:5142] CHIP:CTL: Setting attestation nonce to random value [1655350766.975169][5142:5142] CHIP:CTL: Setting CSR nonce to random value [1655350766.975245][5142:5142] CHIP:IN: UDP::Init bind&listen port=5550 [1655350766.975352][5142:5142] CHIP:IN: UDP::Init bound to port=5550 [1655350766.975380][5142:5142] CHIP:IN: UDP::Init bind&listen port=5550 [1655350766.975464][5142:5142] CHIP:IN: UDP::Init bound to port=5550 [1655350766.975488][5142:5142] CHIP:IN: TransportMgr initialized [1655350766.975564][5142:5142] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655350766.975598][5142:5142] CHIP:CTL: Setting attestation nonce to random value [1655350766.975649][5142:5142] CHIP:CTL: Setting CSR nonce to random value [1655350766.978719][5142:5142] CHIP:CTL: Generating NOC [1655350766.979621][5142:5142] CHIP:FP: Validating NOC chain [1655350766.981513][5142:5142] CHIP:FP: NOC chain validation successful [1655350766.981689][5142:5142] CHIP:FP: Added new fabric at index: 0x1, Initialized: 1 [1655350766.981716][5142:5142] CHIP:FP: Assigned compressed fabric ID: 0xA7866B26C80C3D35, node ID: 0x000000000001B669 [1655350766.991649][5142:5142] CHIP:FP: Fabric (0x1) persisted to storage. Calling OnFabricPersistedToStorage [1655350766.991707][5142:5142] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655350766.991731][5142:5142] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655350766.991753][5142:5142] CHIP:TS: Retaining current Last Known Good Time [1655350766.994074][5142:5142] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0xA7866B26C80C3D35 [1655350766.994153][5142:5142] CHIP:IN: UDP::Init bind&listen port=5550 [1655350766.994295][5142:5142] CHIP:IN: UDP::Init bound to port=5550 [1655350766.994323][5142:5142] CHIP:IN: UDP::Init bind&listen port=5550 [1655350766.994416][5142:5142] CHIP:IN: UDP::Init bound to port=5550 [1655350766.994441][5142:5142] CHIP:IN: TransportMgr initialized [1655350766.994534][5142:5142] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655350766.994576][5142:5142] CHIP:CTL: Setting attestation nonce to random value [1655350766.994633][5142:5142] CHIP:CTL: Setting CSR nonce to random value [1655350766.995494][5142:5142] CHIP:CTL: Generating NOC [1655350766.996307][5142:5142] CHIP:FP: Validating NOC chain [1655350766.998326][5142:5142] CHIP:FP: NOC chain validation successful [1655350766.998543][5142:5142] CHIP:FP: Added new fabric at index: 0x2, Initialized: 1 [1655350766.998576][5142:5142] CHIP:FP: Assigned compressed fabric ID: 0x06EE9B0E253A2288, node ID: 0x000000000001B669 [1655350767.007835][5142:5142] CHIP:FP: Fabric (0x2) persisted to storage. Calling OnFabricPersistedToStorage [1655350767.007898][5142:5142] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655350767.007922][5142:5142] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655350767.007943][5142:5142] CHIP:TS: Retaining current Last Known Good Time [1655350767.010374][5142:5142] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x06EE9B0E253A2288 [1655350767.010459][5142:5142] CHIP:IN: UDP::Init bind&listen port=5550 [1655350767.010601][5142:5142] CHIP:IN: UDP::Init bound to port=5550 [1655350767.010629][5142:5142] CHIP:IN: UDP::Init bind&listen port=5550 [1655350767.010729][5142:5142] CHIP:IN: UDP::Init bound to port=5550 [1655350767.010755][5142:5142] CHIP:IN: TransportMgr initialized [1655350767.010872][5142:5142] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1655350767.010913][5142:5142] CHIP:CTL: Setting attestation nonce to random value [1655350767.010974][5142:5142] CHIP:CTL: Setting CSR nonce to random value [1655350767.011857][5142:5142] CHIP:CTL: Generating NOC [1655350767.012707][5142:5142] CHIP:FP: Validating NOC chain [1655350767.014509][5142:5142] CHIP:FP: NOC chain validation successful [1655350767.014674][5142:5142] CHIP:FP: Added new fabric at index: 0x3, Initialized: 1 [1655350767.014703][5142:5142] CHIP:FP: Assigned compressed fabric ID: 0x884B85B37DC4EE27, node ID: 0x000000000001B669 [1655350767.023672][5142:5142] CHIP:FP: Fabric (0x3) persisted to storage. Calling OnFabricPersistedToStorage [1655350767.023725][5142:5142] CHIP:TS: Last Known Good Time: 2022-06-16T10:33:40 [1655350767.023749][5142:5142] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1655350767.023770][5142:5142] CHIP:TS: Retaining current Last Known Good Time [1655350767.026082][5142:5142] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x884B85B37DC4EE27 [1655350767.026160][5142:5142] CHIP:IN: UDP::Init bind&listen port=5550 [1655350767.026301][5142:5142] CHIP:IN: UDP::Init bound to port=5550 [1655350767.026330][5142:5142] CHIP:IN: UDP::Init bind&listen port=5550 [1655350767.026427][5142:5142] CHIP:IN: UDP::Init bound to port=5550 [1655350767.026453][5142:5142] CHIP:IN: TransportMgr initialized [1655350767.086211][5142:5147] CHIP:DL: CHIP task running [1655350767.086481][5142:5147] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.086544][5142:5147] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655350767.086579][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655350767.086608][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655350767.086640][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655350767.086669][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655350767.086691][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.086718][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655350767.086740][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.086766][5142:5147] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.086789][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.086814][5142:5147] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.086840][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.086962][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655350767.086989][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.087013][5142:5147] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655350767.087065][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.087091][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.087131][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.087156][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.087180][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.087203][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655350767.087237][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655350767.087262][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655350767.087303][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.087328][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.087352][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.087376][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.087399][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.087423][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655350767.087449][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655350767.087475][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655350767.087496][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.087520][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.087544][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.087567][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.087590][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.087613][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655350767.087639][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655350767.087664][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.087687][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.087711][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.087732][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.087755][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.087778][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655350767.087807][5142:5147] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.087832][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.087856][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655350767.087882][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655350767.087905][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655350767.087929][5142:5147] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655350767.087953][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.087983][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.088006][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.088089][5142:5147] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.088137][5142:5147] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655350767.088169][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655350767.088198][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655350767.088228][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655350767.088256][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655350767.088278][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.088305][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655350767.088326][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.088352][5142:5147] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.088373][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.088395][5142:5147] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.088420][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.088443][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655350767.088467][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.088490][5142:5147] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655350767.088532][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.088557][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.088580][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.088604][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.088627][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.088650][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655350767.088676][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655350767.088702][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655350767.088740][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.088764][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.088788][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.088811][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.088834][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.088857][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655350767.088883][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655350767.088908][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655350767.088933][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.088957][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.088980][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.089004][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.089026][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.089049][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655350767.089074][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655350767.089098][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.089122][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.089145][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.089169][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.089226][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.089248][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655350767.089270][5142:5147] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.089294][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.089318][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655350767.089341][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655350767.089365][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655350767.089389][5142:5147] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655350767.089413][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.089442][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.089465][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.089536][5142:5147] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.089579][5142:5147] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655350767.089611][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655350767.089639][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655350767.089669][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655350767.089697][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655350767.089719][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.089744][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655350767.089766][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.089791][5142:5147] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.089814][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.089839][5142:5147] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.089863][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.089887][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655350767.089911][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.089934][5142:5147] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655350767.089976][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.090000][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.090024][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.090045][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.090067][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.090090][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655350767.090119][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655350767.090145][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655350767.090183][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.090207][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.090231][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.090255][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.090278][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.090300][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655350767.090326][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655350767.090351][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655350767.090377][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.090400][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.090424][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.090447][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.090470][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.090493][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655350767.090518][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655350767.090542][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.090566][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.090590][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.090613][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.090636][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.090658][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655350767.090684][5142:5147] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.090708][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.090732][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655350767.090755][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655350767.090778][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655350767.090803][5142:5147] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655350767.090826][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.090853][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.090875][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.090941][5142:5147] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.090982][5142:5147] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655350767.091013][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655350767.091041][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655350767.091071][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655350767.091099][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655350767.091121][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.091147][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655350767.091169][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.091194][5142:5147] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.091215][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.091240][5142:5147] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.091264][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.091287][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655350767.091311][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.091334][5142:5147] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655350767.091374][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.091398][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.091421][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.091445][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.091468][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.091490][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655350767.091517][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655350767.091542][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655350767.091580][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.091604][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.091627][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.091651][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.091674][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.091696][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655350767.091723][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655350767.091748][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655350767.091772][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.091796][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.091823][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.091847][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.091870][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.091893][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655350767.091918][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655350767.091945][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.091969][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.091993][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.092017][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.092044][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.092067][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655350767.092093][5142:5147] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.092117][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.092141][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655350767.092165][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655350767.092187][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655350767.092212][5142:5147] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655350767.092236][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.092261][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.092283][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.092346][5142:5147] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.092385][5142:5147] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655350767.092416][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655350767.092445][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655350767.092476][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655350767.092503][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655350767.092525][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.092552][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655350767.092573][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.092599][5142:5147] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.092621][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.092646][5142:5147] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.092671][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.092694][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655350767.092718][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.092742][5142:5147] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655350767.092781][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.092805][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.092829][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.092852][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.092875][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.092898][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655350767.092925][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655350767.092951][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655350767.092989][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.093013][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.093037][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.093061][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.093084][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.093107][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655350767.093133][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655350767.093158][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655350767.093199][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.093225][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.093249][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.093274][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.093297][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.093320][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655350767.093345][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655350767.093370][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.093393][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.093417][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.093440][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.093467][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.093490][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655350767.093516][5142:5147] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.093541][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.093565][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655350767.093588][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655350767.093611][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655350767.093636][5142:5147] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655350767.093660][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.093685][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.093708][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.093771][5142:5147] CHIP:DIS: MINMDNS: received SRV record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.093812][5142:5147] CHIP:DIS: MINMDNS: received SRV record for C90E80181731B112._matterc._udp.local [1655350767.093844][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance C90E80181731B112: A63E4FFB23464B65.local [1655350767.093872][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 9C2F09CED995AC40._matterc._udp.local [1655350767.093903][5142:5147] CHIP:DIS: MINMDNS: Commission SRV for instance 9C2F09CED995AC40: 3E9D692DA4D5BC31.local [1655350767.093930][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 493A07830D86D110._matterc._udp.local [1655350767.093952][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.093978][5142:5147] CHIP:DIS: MINMDNS: received SRV record for 1DE963EB082EC77B._matterc._udp.local [1655350767.094000][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.094026][5142:5147] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.094047][5142:5147] CHIP:DIS: Insufficient parsers to process all SRV entries. [1655350767.094072][5142:5147] CHIP:DIS: MINMDNS: received TXT record for OpenThread_BorderRouter._meshcop._udp.local [1655350767.094096][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.094119][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _meshcop._udp.local [1655350767.094143][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.094166][5142:5147] CHIP:DIS: MINMDNS: received TXT record for C90E80181731B112._matterc._udp.local [1655350767.094206][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.094231][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.094255][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.094278][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.094302][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.094324][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for A63E4FFB23464B65.local [1655350767.094351][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:e6b6:39d5:5cd8:786e found for A63E4FFB23464B65.local [1655350767.094376][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 9C2F09CED995AC40._matterc._udp.local [1655350767.094413][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.094438][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.094461][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.094485][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.094507][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.094529][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 3E9D692DA4D5BC31.local [1655350767.094555][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:a263:4d44:cf72:f30f found for 3E9D692DA4D5BC31.local [1655350767.094580][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 493A07830D86D110._matterc._udp.local [1655350767.094605][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.094629][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.094653][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.094677][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.094700][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.094721][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 822FF8DD5E22EEF6.local [1655350767.094747][5142:5147] CHIP:DIS: MINMDNS: received TXT record for 1DE963EB082EC77B._matterc._udp.local [1655350767.094771][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _CM._sub._matterc._udp.local [1655350767.094795][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _L3840._sub._matterc._udp.local [1655350767.094819][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _S15._sub._matterc._udp.local [1655350767.094843][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _V65521._sub._matterc._udp.local [1655350767.094866][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matterc._udp.local [1655350767.094888][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for E6D68EF7558237ED.local [1655350767.094914][5142:5147] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.094939][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _services._dns-sd._udp.local [1655350767.094963][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _IA7866B26C80C3D35._sub._matter._tcp.local [1655350767.094986][5142:5147] CHIP:DIS: MINMDNS: received PTR record for _matter._tcp.local [1655350767.095009][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655350767.095034][5142:5147] CHIP:DIS: MINMDNS: received A record for ubuntu.local [1655350767.095058][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.095083][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.095105][5142:5147] CHIP:DIS: No delegate to report commissioning node discovery [1655350767.095144][5142:5147] CHIP:TOO: Sending command to node 0x1 [1655350767.095184][5142:5147] CHIP:CSM: FindOrEstablishSession: PeerId = A7866B26C80C3D35:0000000000000001 [1655350767.095209][5142:5147] CHIP:CSM: FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1655350767.095250][5142:5147] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 1 --> 2 [1655350767.095300][5142:5147] CHIP:DIS: MINMDNS: Sending query ANY/UNICAST for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.095823][5142:5147] CHIP:DIS: Warning: Attempt to mDNS broadcast failed on wlan0: ../../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable [1655350767.096319][5142:5147] CHIP:DIS: mDNS broadcast had only partial success: 7 successes and 1 failures. [1655350767.096549][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.096578][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.096638][5142:5147] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.096685][5142:5147] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 165DF2067BB604AC.local [1655350767.096712][5142:5147] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.096750][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655350767.096779][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:db36:1281:eb37:81e9 found for 165DF2067BB604AC.local [1655350767.097437][5142:5147] CHIP:DIS: Lookup clearing interface for non LL address [1655350767.097484][5142:5147] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9%eth0]:5540: new best score: 6 [1655350767.097510][5142:5147] CHIP:DIS: Checking node lookup status after 2 ms [1655350767.097531][5142:5147] CHIP:DIS: Keeping DNSSD lookup active [1655350767.097637][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.097668][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.097727][5142:5147] CHIP:DIS: MINMDNS: received SRV record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.097774][5142:5147] CHIP:DIS: MINMDNS: Operational SRV for A7866B26C80C3D35-0000000000000001: 165DF2067BB604AC.local [1655350767.097804][5142:5147] CHIP:DIS: MINMDNS: received TXT record for A7866B26C80C3D35-0000000000000001._matter._tcp.local [1655350767.097846][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for 165DF2067BB604AC.local [1655350767.097878][5142:5147] CHIP:DIS: MINMDNS: IP address fd12:c411:e4a6:d11c:db36:1281:eb37:81e9 found for 165DF2067BB604AC.local [1655350767.097905][5142:5147] CHIP:DIS: Discovered node without a pending query [1655350767.098331][5142:5147] CHIP:DIS: UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9%eth0]:5540: score has not improved: 6 [1655350767.098367][5142:5147] CHIP:DIS: Checking node lookup status after 3 ms [1655350767.098386][5142:5147] CHIP:DIS: Keeping DNSSD lookup active [1655350767.098469][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.098497][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.098557][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.098583][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.098638][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.098664][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.098719][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.098744][5142:5147] CHIP:DIS: MINMDNS: received AAAA record for ubuntu.local [1655350767.295792][5142:5147] CHIP:DIS: Checking node lookup status after 200 ms [1655350767.295901][5142:5147] CHIP:CTL: Updating device address to UDP:[fd12:c411:e4a6:d11c:db36:1281:eb37:81e9]:5540 while in state 2 [1655350767.295959][5142:5147] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 2 --> 3 [1655350767.296349][5142:5147] CHIP:IN: SecureSession[0xaaaafcdea020]: Allocated Type:2 LSID:52980 [1655350767.296433][5142:5147] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1655350767.298314][5142:5147] CHIP:SC: Including MRP parameters [1655350767.298892][5142:5147] CHIP:IN: Prepared unauthenticated message 0xaaaafcde8468 to 0x0000000000000000 (0) of type 0x30 and protocolId (0, 0) on exchange 8856i with MessageCounter:99649401. [1655350767.298995][5142:5147] CHIP:IN: Sending unauthenticated msg 0xaaaafcde8468 with MessageCounter:99649401 to 0x0000000000000000 at monotonic time: 000000000185C4AD msec [1655350767.299343][5142:5147] CHIP:SC: Sent Sigma1 msg [1655350767.299414][5142:5147] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 3 --> 4 [1655350767.299479][5142:5147] CHIP:DIS: Discovery does not require any more timeouts [1655350767.418852][5142:5147] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:213740548 on exchange 8856i [1655350767.418938][5142:5147] CHIP:EM: Found matching exchange: 8856i, Delegate: 0xffff980015c0 [1655350767.419012][5142:5147] CHIP:EM: Rxd Ack; Removing MessageCounter:99649401 from Retrans Table on exchange 8856i [1655350767.419061][5142:5147] CHIP:EM: Removed CHIP MessageCounter:99649401 from RetransTable on exchange 8856i [1655350767.438672][5142:5147] CHIP:EM: Received message of type 0x33 with protocolId (0, 0) and MessageCounter:213740549 on exchange 8856i [1655350767.438768][5142:5147] CHIP:EM: Found matching exchange: 8856i, Delegate: 0xffff980015c0 [1655350767.438827][5142:5147] CHIP:EM: CHIP MessageCounter:99649401 not in RetransTable on exchange 8856i [1655350767.438905][5142:5147] CHIP:SC: Received Sigma2Resume msg [1655350767.439092][5142:5147] CHIP:SC: Found MRP parameters in the message [1655350767.439160][5142:5147] CHIP:SC: Peer assigned session session ID 18498 [1655350767.457164][5142:5147] CHIP:SC: Sending status report. Protocol code 0, exchange 8856 [1655350767.457258][5142:5147] CHIP:EM: Piggybacking Ack for MessageCounter:213740549 on exchange: 8856i [1655350767.457309][5142:5147] CHIP:IN: Prepared unauthenticated message 0xaaaafcde8468 to 0x0000000000000000 (0) of type 0x40 and protocolId (0, 0) on exchange 8856i with MessageCounter:99649402. [1655350767.457361][5142:5147] CHIP:IN: Sending unauthenticated msg 0xaaaafcde8468 with MessageCounter:99649402 to 0x0000000000000000 at monotonic time: 000000000185C54C msec [1655350767.457673][5142:5147] CHIP:IN: SecureSession[0xaaaafcdea020]: Activated - Type:2 LSID:52980 [1655350767.457717][5142:5147] CHIP:IN: New secure session created for device <0000000000000001, 1>, LSID:52980 PSID:18498! [1655350767.457747][5142:5147] CHIP:CTL: OperationalDeviceProxy[A7866B26C80C3D35:0000000000000001]: State change 4 --> 5 [1655350767.457801][5142:5147] CHIP:TOO: Sending cluster (0x00000101) command (0x0000000D) on endpoint 1 [1655350767.457861][5142:5147] CHIP:DMG: ICR moving to [AddingComm] [1655350767.457923][5142:5147] CHIP:DMG: ICR moving to [AddedComma] [1655350767.458042][5142:5147] CHIP:IN: Prepared secure message 0xaaaafcde8488 to 0x0000000000000001 (1) of type 0x8 and protocolId (0, 1) on exchange 8857i with MessageCounter:87752781. [1655350767.458089][5142:5147] CHIP:IN: Sending encrypted msg 0xaaaafcde8488 with MessageCounter:87752781 to 0x0000000000000001 (1) at monotonic time: 000000000185C54C msec [1655350767.458286][5142:5147] CHIP:DMG: ICR moving to [CommandSen] [1655350767.523682][5142:5147] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:213740550 on exchange 8856i [1655350767.523769][5142:5147] CHIP:EM: Found matching exchange: 8856i, Delegate: (nil) [1655350767.523855][5142:5147] CHIP:EM: Rxd Ack; Removing MessageCounter:99649402 from Retrans Table on exchange 8856i [1655350767.523902][5142:5147] CHIP:EM: Removed CHIP MessageCounter:99649402 from RetransTable on exchange 8856i [1655350767.548987][5142:5147] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:66155447 on exchange 8857i [1655350767.549068][5142:5147] CHIP:EM: Found matching exchange: 8857i, Delegate: 0xffff98008830 [1655350767.549138][5142:5147] CHIP:EM: Rxd Ack; Removing MessageCounter:87752781 from Retrans Table on exchange 8857i [1655350767.549172][5142:5147] CHIP:EM: Removed CHIP MessageCounter:87752781 from RetransTable on exchange 8857i [1655350767.549267][5142:5147] CHIP:DMG: ICR moving to [ResponseRe] [1655350767.549342][5142:5147] CHIP:DMG: InvokeResponseMessage = [1655350767.549377][5142:5147] CHIP:DMG: { [1655350767.549413][5142:5147] CHIP:DMG: suppressResponse = false, [1655350767.549487][5142:5147] CHIP:DMG: InvokeResponseIBs = [1655350767.549568][5142:5147] CHIP:DMG: [ [1655350767.549621][5142:5147] CHIP:DMG: InvokeResponseIB = [1655350767.549691][5142:5147] CHIP:DMG: { [1655350767.549788][5142:5147] CHIP:DMG: CommandStatusIB = [1655350767.549852][5142:5147] CHIP:DMG: { [1655350767.549911][5142:5147] CHIP:DMG: CommandPathIB = [1655350767.549977][5142:5147] CHIP:DMG: { [1655350767.550048][5142:5147] CHIP:DMG: EndpointId = 0x1, [1655350767.550130][5142:5147] CHIP:DMG: ClusterId = 0x101, [1655350767.550200][5142:5147] CHIP:DMG: CommandId = 0xd, [1655350767.550290][5142:5147] CHIP:DMG: }, [1655350767.550370][5142:5147] CHIP:DMG: [1655350767.550429][5142:5147] CHIP:DMG: StatusIB = [1655350767.550499][5142:5147] CHIP:DMG: { [1655350767.550586][5142:5147] CHIP:DMG: status = 0x8b (NOT_FOUND), [1655350767.550677][5142:5147] CHIP:DMG: }, [1655350767.550788][5142:5147] CHIP:DMG: [1655350767.550861][5142:5147] CHIP:DMG: }, [1655350767.550945][5142:5147] CHIP:DMG: [1655350767.551013][5142:5147] CHIP:DMG: }, [1655350767.551093][5142:5147] CHIP:DMG: [1655350767.551152][5142:5147] CHIP:DMG: ], [1655350767.551227][5142:5147] CHIP:DMG: [1655350767.551314][5142:5147] CHIP:DMG: InteractionModelRevision = 1 [1655350767.551375][5142:5147] CHIP:DMG: }, [1655350767.551513][5142:5147] CHIP:DMG: Received Command Response Status for Endpoint=1 Cluster=0x0000_0101 Command=0x0000_000D Status=0x8b [1655350767.551617][5142:5147] CHIP:TOO: Error: IM Error 0x0000058B: General error: 0x8b (NOT_FOUND) [1655350767.551711][5142:5147] CHIP:DMG: ICR moving to [AwaitingDe] [1655350767.551831][5142:5147] CHIP:EM: Sending Standalone Ack for MessageCounter:66155447 on exchange 8857i [1655350767.552016][5142:5147] CHIP:IN: Prepared secure message 0xffffa27cd9d8 to 0x0000000000000001 (1) of type 0x10 and protocolId (0, 0) on exchange 8857i with MessageCounter:87752782. [1655350767.552111][5142:5147] CHIP:IN: Sending encrypted msg 0xffffa27cd9d8 with MessageCounter:87752782 to 0x0000000000000001 (1) at monotonic time: 000000000185C5AA msec [1655350767.552399][5142:5147] CHIP:EM: Flushed pending ack for MessageCounter:66155447 on exchange 8857i [1655350767.552874][5142:5142] CHIP:CTL: Shutting down the commissioner [1655350767.553147][5142:5142] CHIP:CTL: Shutting down the controller [1655350767.553290][5142:5142] CHIP:CTL: Shutting down the commissioner [1655350767.553501][5142:5142] CHIP:CTL: Shutting down the controller [1655350767.553581][5142:5142] CHIP:IN: Expiring all connections for fabric 1!! [1655350767.553642][5142:5142] CHIP:IN: SecureSession[0xaaaafcdea020]: MarkForRemoval Type:2 LSID:52980 [1655350767.553698][5142:5142] CHIP:IN: SecureSession[0xaaaafcdea020]: Released - Type:2 LSID:52980 [1655350767.553803][5142:5142] CHIP:CTL: Shutting down the commissioner [1655350767.554001][5142:5142] CHIP:CTL: Shutting down the controller [1655350767.554059][5142:5142] CHIP:IN: Expiring all connections for fabric 2!! [1655350767.554143][5142:5142] CHIP:CTL: Shutting down the commissioner [1655350767.554334][5142:5142] CHIP:CTL: Shutting down the controller [1655350767.554395][5142:5142] CHIP:IN: Expiring all connections for fabric 3!! [1655350767.554472][5142:5142] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1655350767.555603][5142:5142] CHIP:DMG: IM WH moving to [Uninitialized] [1655350767.555671][5142:5142] CHIP:DMG: IM WH moving to [Uninitialized] [1655350767.555723][5142:5142] CHIP:DMG: IM WH moving to [Uninitialized] [1655350767.555773][5142:5142] CHIP:DMG: IM WH moving to [Uninitialized] [1655350767.555826][5142:5142] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1655350767.556018][5142:5142] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1655350767.556448][5142:5142] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-3CN3LQ) [1655350767.557658][5142:5142] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1655350767.557783][5142:5142] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1655350767.557847][5142:5142] CHIP:DL: Inet Layer shutdown [1655350767.557902][5142:5142] CHIP:DL: BLE shutdown [1655350767.557956][5142:5142] CHIP:DL: System Layer shutdown [1655350767.558196][5142:5142] CHIP:TOO: Run command failure: IM Error 0x0000058B: General error: 0x8b (NOT_FOUND)