[1673882836.541431][71934:71934] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1673882836.543052][71934:71934] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1673882836.543085][71934:71934] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1673882836.543103][71934:71934] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1673882836.543168][71934:71934] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-jeNt4Z) [1673882836.543275][71934:71934] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1673882836.543284][71934:71934] CHIP:DL: NVS set: chip-counters/reboot-count = 56 (0x38) [1673882836.543382][71934:71934] CHIP:DL: Got Ethernet interface: enp0s31f6 [1673882836.543435][71934:71934] CHIP:DL: Found the primary Ethernet interface:enp0s31f6 [1673882836.543489][71934:71934] CHIP:DL: Got WiFi interface: wlp0s20f3 [1673882836.545353][71934:71934] CHIP:DL: Found the primary WiFi interface:wlp0s20f3 [1673882836.545394][71934:71934] CHIP:IN: UDP::Init bind&listen port=0 [1673882836.545415][71934:71934] CHIP:IN: UDP::Init bound to port=53224 [1673882836.545420][71934:71934] CHIP:IN: UDP::Init bind&listen port=0 [1673882836.545433][71934:71934] CHIP:IN: UDP::Init bound to port=38101 [1673882836.545437][71934:71934] CHIP:IN: BLEBase::Init - setting/overriding transport [1673882836.545441][71934:71934] CHIP:IN: TransportMgr initialized [1673882836.545448][71934:71934] CHIP:FP: Initializing FabricTable from persistent storage [1673882836.545487][71934:71934] CHIP:TS: Last Known Good Time: 2022-10-28T16:00:45 [1673882836.545861][71934:71934] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x70D5C1F6C73FD2A3, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1673882836.546228][71934:71934] CHIP:ZCL: Using ZAP configuration... [1673882836.546393][71934:71934] CHIP:DL: MDNS failed to join multicast group on enp0s31f6 for address type IPv4: examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:764: Inet Error 0x00000110: Address not found [1673882836.546555][71934:71934] CHIP:CTL: System State Initialized... [1673882836.546568][71934:71934] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1673882836.546580][71934:71934] CHIP:CTL: Setting attestation nonce to random value [1673882836.546588][71934:71934] CHIP:CTL: Setting CSR nonce to random value [1673882836.546599][71934:71934] CHIP:IN: UDP::Init bind&listen port=5550 [1673882836.546613][71934:71934] CHIP:IN: UDP::Init bound to port=5550 [1673882836.546618][71934:71934] CHIP:IN: UDP::Init bind&listen port=5550 [1673882836.546628][71934:71934] CHIP:IN: UDP::Init bound to port=5550 [1673882836.546632][71934:71934] CHIP:IN: TransportMgr initialized [1673882836.546727][71934:71939] CHIP:DL: CHIP task running [1673882836.546802][71934:71939] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1673882836.546811][71934:71939] CHIP:CTL: Setting attestation nonce to random value [1673882836.546834][71934:71939] CHIP:CTL: Setting CSR nonce to random value [1673882836.547028][71934:71939] CHIP:CTL: Generating NOC [1673882836.547225][71934:71939] CHIP:FP: Validating NOC chain [1673882836.547560][71934:71939] CHIP:FP: NOC chain validation successful [1673882836.547594][71934:71939] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1673882836.547599][71934:71939] CHIP:TS: Last Known Good Time: 2022-10-28T16:00:45 [1673882836.547603][71934:71939] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1673882836.547607][71934:71939] CHIP:TS: Retaining current Last Known Good Time [1673882836.548431][71934:71939] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1673882836.548698][71934:71939] CHIP:TS: Committing Last Known Good Time to storage: 2022-10-28T16:00:45 [1673882836.548917][71934:71939] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x0000000000000000 [1673882836.548925][71934:71939] CHIP:IN: UDP::Init bind&listen port=5550 [1673882836.548940][71934:71939] CHIP:IN: UDP::Init bound to port=5550 [1673882836.548956][71934:71939] CHIP:IN: UDP::Init bind&listen port=5550 [1673882836.548969][71934:71939] CHIP:IN: UDP::Init bound to port=5550 [1673882836.548973][71934:71939] CHIP:IN: TransportMgr initialized [1673882836.550665][71934:71939] CHIP:TOO: Sending command to group 0x101 [1673882836.550671][71934:71939] CHIP:TOO: Sending cluster (0x00000006) command (0x00000000) on Group 257 [1673882836.550681][71934:71939] CHIP:DMG: ICR moving to [AddingComm] [1673882836.550691][71934:71939] CHIP:DMG: ICR moving to [AddedComma] [1673882836.550885][71934:71939] CHIP:EM: <<< [E:16679i M:55000] (G) Msg TX to 1:FFFFFFFFFFFF0101 [D2A3] --- Type 0001:08 (IM:InvokeCommandRequest) [1673882836.550896][71934:71939] CHIP:IN: (G) Sending msg 55000 to Multicast IPV6 address 'UDP:[ff35:40:fd00::100:101]:5540' [1673882836.551061][71934:71939] CHIP:IN: Interface wlp0s20f3 has a link local address [1673882836.551095][71934:71939] CHIP:IN: Successfully send Multicast message on interface wlp0s20f3 [1673882836.551103][71934:71939] CHIP:DMG: ICR moving to [CommandSen] [1673882836.551107][71934:71939] CHIP:DMG: ICR moving to [AwaitingDe] [1673882836.551275][71934:71934] CHIP:CTL: Shutting down the commissioner [1673882836.551297][71934:71934] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1673882836.551312][71934:71934] CHIP:CTL: Shutting down the controller [1673882836.551317][71934:71934] CHIP:IN: Expiring all sessions for fabric 0x1!! [1673882836.551321][71934:71934] CHIP:FP: Forgetting fabric 0x1 [1673882836.551327][71934:71934] CHIP:TS: Pending Last Known Good Time: 2022-10-28T16:00:45 [1673882836.551348][71934:71934] CHIP:TS: Previous Last Known Good Time: 2022-10-28T16:00:45 [1673882836.551352][71934:71934] CHIP:TS: Reverted Last Known Good Time to previous value [1673882836.551359][71934:71934] CHIP:CTL: Shutting down the commissioner [1673882836.551363][71934:71934] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1673882836.551374][71934:71934] CHIP:CTL: Shutting down the controller [1673882836.551378][71934:71934] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1673882836.551430][71934:71934] CHIP:DMG: IM WH moving to [Uninitialized] [1673882836.551435][71934:71934] CHIP:DMG: IM WH moving to [Uninitialized] [1673882836.551438][71934:71934] CHIP:DMG: IM WH moving to [Uninitialized] [1673882836.551441][71934:71934] CHIP:DMG: IM WH moving to [Uninitialized] [1673882836.551446][71934:71934] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1673882836.551457][71934:71934] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1673882836.551494][71934:71934] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-8MUzFN) [1673882836.551571][71934:71934] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1673882836.551582][71934:71934] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1673882836.551587][71934:71934] CHIP:DL: Inet Layer shutdown [1673882836.551592][71934:71934] CHIP:DL: BLE shutdown [1673882836.551597][71934:71934] CHIP:DL: System Layer shutdown