$ ./out/all-clusters-app/chip-all-clusters-app --wifi --discriminator 2222 [1708101548.888130][12429:12429] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1708101548.888578][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-4Yh8S8) [1708101548.888879][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101548.889648][12429:12429] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1708101548.889684][12429:12429] CHIP:DL: ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1708101548.895793][12429:12429] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1708101548.896129][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-GSL6tY) [1708101548.896417][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1708101548.896560][12429:12429] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1708101548.896709][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_config.ini-jLba9w) [1708101548.896914][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1708101548.897029][12429:12429] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1708101548.897169][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-CcOpvr) [1708101548.897385][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1708101548.897695][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-wFtFXW) [1708101548.898364][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1708101548.898482][12429:12429] CHIP:DL: NVS set: chip-factory/unique-id = "82F405E3496EB8B3" [1708101548.898687][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-GC4c2S) [1708101548.899370][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1708101548.899443][12429:12429] CHIP:DL: NVS set: chip-factory/vendor-id = 65521 (0xFFF1) [1708101548.899611][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-npcjwX) [1708101548.900265][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1708101548.900338][12429:12429] CHIP:DL: NVS set: chip-factory/product-id = 32769 (0x8001) [1708101548.900506][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-QmHO21) [1708101548.901075][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1708101548.901144][12429:12429] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1) [1708101548.901306][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-ENQ81J) [1708101548.901900][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1708101548.901970][12429:12429] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1708101548.902133][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-wzySgB) [1708101548.902877][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1708101548.902951][12429:12429] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0) [1708101548.903110][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_config.ini-qHSouf) [1708101548.903679][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1708101548.903749][12429:12429] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0) [1708101548.903905][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_config.ini-ZwY1J0) [1708101548.904526][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1708101548.904597][12429:12429] CHIP:DL: NVS set: chip-config/location-capability = 2 (0x2) [1708101548.905308][12429:12429] CHIP:DL: Got Ethernet interface: eth0 [1708101548.905700][12429:12429] CHIP:DL: Found the primary Ethernet interface:eth0 [1708101548.906121][12429:12429] CHIP:DL: Got WiFi interface: wlan0 [1708101548.906189][12429:12429] CHIP:DL: Failed to reset WiFi statistic counts [1708101548.906229][12429:12429] CHIP:SPT: *** WARNING: Using temporary passcode 20202021 due to no neither --passcode or --spake2p-verifier-base64 given on command line. This is temporary and will disappear. Please update your scripts to explicitly configure onboarding credentials. *** [1708101548.906257][12429:12429] CHIP:SPT: PASE PBKDF iterations set to 1000 [1708101548.906284][12429:12429] CHIP:SPT: LinuxCommissionableDataProvider didn't get a PASE salt, generating one. [1708101548.913189][12429:12429] CHIP:DL: Device Configuration: [1708101548.913246][12429:12429] CHIP:DL: Serial Number: TEST_SN [1708101548.913279][12429:12429] CHIP:DL: Vendor Id: 65521 (0xFFF1) [1708101548.913313][12429:12429] CHIP:DL: Product Id: 32769 (0x8001) [1708101548.913334][12429:12429] CHIP:DL: Product Name: TEST_PRODUCT [1708101548.913364][12429:12429] CHIP:DL: Hardware Version: 0 [1708101548.913385][12429:12429] CHIP:DL: Setup Pin Code (0 for UNKNOWN/ERROR): 20202021 [1708101548.913404][12429:12429] CHIP:DL: Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 2222 (0x8AE) [1708101548.913431][12429:12429] CHIP:DL: Manufacturing Date: (not set) [1708101548.913451][12429:12429] CHIP:DL: Device Type: 65535 (0xFFFF) [1708101548.913470][12429:12429] CHIP:-: ==== Onboarding payload for Standard Commissioning Flow ==== [1708101548.913520][12429:12429] CHIP:SVR: SetupQRCode: [MT:-24J0E.N16750648G00] [1708101548.913547][12429:12429] CHIP:SVR: Copy/paste the below URL in a browser to see the QR Code: [1708101548.913566][12429:12429] CHIP:SVR: https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A-24J0E.N16750648G00 [1708101548.913601][12429:12429] CHIP:SVR: Manual pairing code: [20054912334] [1708101548.913713][12429:12430] CHIP:DL: wpa_supplicant: Start WiFi management [1708101548.922068][12429:12430] CHIP:DL: wpa_supplicant: connected to wpa_supplicant proxy [1708101548.923692][12429:12430] CHIP:DL: wpa_supplicant: can't find interface wlan0: GDBus.Error:org.freedesktop.DBus.Error.AccessDenied: Rejected send message, 2 matched rules; type="method_call", sender=":1.152" (uid=1000 pid=12429 comm="./out/all-clusters-app/chip-all-clusters-app --wif" label="unconfined") interface="fi.w1.wpa_supplicant1" member="GetInterface" error name="(unset)" requested_reply="0" destination=":1.6" (uid=0 pid=810 comm="/sbin/wpa_supplicant -u -s -O /run/wpa_supplicant " label="unconfined") [1708101548.923758][12429:12430] CHIP:DL: wpa_supplicant: try to create interface wlan0 [1708101548.925241][12429:12430] CHIP:DL: wpa_supplicant: failed to create interface wlan0: GDBus.Error:org.freedesktop.DBus.Error.AccessDenied: Rejected send message, 2 matched rules; type="method_call", sender=":1.152" (uid=1000 pid=12429 comm="./out/all-clusters-app/chip-all-clusters-app --wif" label="unconfined") interface="fi.w1.wpa_supplicant1" member="CreateInterface" error name="(unset)" requested_reply="0" destination=":1.6" (uid=0 pid=810 comm="/sbin/wpa_supplicant -u -s -O /run/wpa_supplicant " label="unconfined") [1708101549.416176][12429:12429] CHIP:-: Wi-Fi Management taking too long to start - device configuration will be reset. [1708101549.416367][12429:12429] CHIP:SVR: Initializing subscription resumption storage... [1708101549.416854][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-wcD68B) [1708101549.418241][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101549.418754][12429:12429] CHIP:SVR: Server initializing... [1708101549.418835][12429:12429] CHIP:FP: Initializing FabricTable from persistent storage [1708101549.418949][12429:12429] CHIP:TS: Last Known Good Time: [unknown] [1708101549.419016][12429:12429] CHIP:TS: Setting Last Known Good Time to firmware build time 2023-10-14T01:16:48 [1708101549.419393][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-VnC4WS) [1708101549.420720][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101549.421183][12429:12429] CHIP:DMG: AccessControl: initializing [1708101549.421247][12429:12429] CHIP:DMG: Examples::AccessControlDelegate::Init [1708101549.421324][12429:12429] CHIP:DMG: AccessControl: setting [1708101549.421377][12429:12429] CHIP:DMG: DefaultAclStorage: initializing [1708101549.421423][12429:12429] CHIP:DMG: DefaultAclStorage: 0 entries loaded [1708101549.421488][12429:12429] CHIP:IN: UDP::Init bind&listen port=5540 [1708101549.421758][12429:12429] CHIP:IN: UDP::Init bound to port=5540 [1708101549.421814][12429:12429] CHIP:IN: BLEBase::Init - setting/overriding transport [1708101549.421863][12429:12429] CHIP:IN: TransportMgr initialized [1708101549.422449][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-HpneGG) [1708101549.423822][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101549.424483][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-3kL3Te) [1708101549.425879][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101549.432016][12429:12429] CHIP:DL: Avahi client registered [1708101549.432391][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-XRmR8O) [1708101549.433228][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101549.433318][12429:12429] CHIP:ZCL: Using ZAP configuration... [1708101549.433559][12429:12429] CHIP:DMG: Failed to read stored attribute (0, 0x0000_0028, 0x0000_0005: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.433610][12429:12429] CHIP:DMG: Failed to read stored attribute (0, 0x0000_0028, 0x0000_0010: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.433658][12429:12429] CHIP:DMG: Failed to read stored attribute (0, 0x0000_002B, 0x0000_0000: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.433700][12429:12429] CHIP:DMG: Failed to read stored attribute (0, 0x0000_002C, 0x0000_0000: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.433740][12429:12429] CHIP:DMG: Failed to read stored attribute (0, 0x0000_002C, 0x0000_0001: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.433782][12429:12429] CHIP:DMG: Failed to read stored attribute (0, 0x0000_002D, 0x0000_0000: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.433868][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0006, 0x0000_0000: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.433910][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0006, 0x0000_4003: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.433952][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0008, 0x0000_0000: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.433998][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0008, 0x0000_4000: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434063][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0050, 0x0000_0003: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434103][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0050, 0x0000_0004: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434142][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0050, 0x0000_0005: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434199][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_005C, 0x0000_0000: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434238][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_005C, 0x0000_0001: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434278][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_005C, 0x0000_0002: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434317][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_005C, 0x0000_0003: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434357][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_005C, 0x0000_0004: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434426][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_005C, 0x0000_0006: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434468][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_005C, 0x0000_0007: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434535][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0080, 0x0000_0005: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434581][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0081, 0x0000_0001: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434626][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0081, 0x0000_0008: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434684][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0003: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434724][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0004: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434764][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0005: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434804][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0006: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434844][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0007: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434884][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0008: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434924][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0009: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.434969][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_000E: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435009][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_000F: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435049][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0010: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435089][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0011: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435129][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0012: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435170][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0013: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435209][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0102, 0x0000_0017: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435353][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0300, 0x0000_0000: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435393][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0300, 0x0000_0001: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435434][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0300, 0x0000_0003: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435474][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0300, 0x0000_0004: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435517][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0300, 0x0000_0007: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435624][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0300, 0x0000_4000: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435665][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0300, 0x0000_4001: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435706][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0300, 0x0000_4002: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435747][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0300, 0x0000_4003: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435788][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0300, 0x0000_4004: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.435841][12429:12429] CHIP:DMG: Failed to read stored attribute (1, 0x0000_0300, 0x0000_4010: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.436169][12429:12429] CHIP:DMG: AccessControlCluster: initializing [1708101549.436192][12429:12429] CHIP:ZCL: Initiating Admin Commissioning cluster. [1708101549.436225][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0030 update version to 5bde7300 [1708101549.436302][12429:12429] CHIP:ZCL: GeneralDiagnostics: OnDeviceReboot [1708101549.436331][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0033 update version to 91f0688a [1708101549.436438][12429:12429] CHIP:EVL: LogEvent event number: 0x0000000000000000 priority: 2, endpoint id: 0x0 cluster id: 0x0000_0033 event id: 0x3 Epoch timestamp: 0x0000018DB2C93D7C [1708101549.436722][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-9Qi2CN) [1708101549.437594][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101549.437808][12429:12429] CHIP:EVL: LogEvent event number: 0x0000000000000001 priority: 1, endpoint id: 0x0 cluster id: 0x0000_0038 event id: 0x0 Epoch timestamp: 0x0000018DB2C93D7D [1708101549.437851][12429:12429] CHIP:ZCL: Emit DSTTableEmpty event [ep=0] [1708101549.438020][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0003 update version to d179b096 [1708101549.438057][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0004 update version to 79bd66f5 [1708101549.438087][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0004 update version to 79bd66f6 [1708101549.438145][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0003 update version to 4f3f32de [1708101549.438178][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0004 update version to 60ed859f [1708101549.438206][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0004 update version to 60ed85a0 [1708101549.438243][12429:12429] CHIP:ZCL: Endpoint 1 On/off already set to new value [1708101549.438533][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-ELAgns) [1708101549.439443][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101549.439538][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0008 update version to a81606d1 [1708101549.439647][12429:12429] CHIP:ZCL: ModeBase: Unable to load the CurrentMode from the KVS. Assuming 0 [1708101549.439696][12429:12429] CHIP:ZCL: ModeBase: Unable to load the StartUpMode from the KVS. Assuming null [1708101549.439737][12429:12429] CHIP:ZCL: ModeBase: Unable to load the OnMode from the KVS. Assuming null [1708101549.439961][12429:12429] CHIP:ZCL: ModeBase: Unable to load the CurrentMode from the KVS. Assuming 0 [1708101549.440008][12429:12429] CHIP:ZCL: ModeBase: Unable to load the StartUpMode from the KVS. Assuming null [1708101549.440049][12429:12429] CHIP:ZCL: ModeBase: Unable to load the OnMode from the KVS. Assuming null [1708101549.440104][12429:12429] CHIP:ZCL: ModeBase: Unable to load the CurrentMode from the KVS. Assuming 0 [1708101549.440146][12429:12429] CHIP:ZCL: ModeBase: Unable to load the StartUpMode from the KVS. Assuming null [1708101549.440186][12429:12429] CHIP:ZCL: ModeBase: Unable to load the OnMode from the KVS. Assuming null [1708101549.440241][12429:12429] CHIP:ZCL: ModeBase: Unable to load the CurrentMode from the KVS. Assuming 0 [1708101549.440283][12429:12429] CHIP:ZCL: ModeBase: Unable to load the StartUpMode from the KVS. Assuming null [1708101549.440323][12429:12429] CHIP:ZCL: ModeBase: Unable to load the OnMode from the KVS. Assuming null [1708101549.440373][12429:12429] CHIP:ZCL: ModeBase: Unable to load the CurrentMode from the KVS. Assuming 0 [1708101549.440414][12429:12429] CHIP:ZCL: ModeBase: Unable to load the StartUpMode from the KVS. Assuming null [1708101549.440453][12429:12429] CHIP:ZCL: ModeBase: Unable to load the OnMode from the KVS. Assuming null [1708101549.440507][12429:12429] CHIP:ZCL: ModeBase: Unable to load the CurrentMode from the KVS. Assuming 0 [1708101549.440548][12429:12429] CHIP:ZCL: ModeBase: Unable to load the StartUpMode from the KVS. Assuming null [1708101549.440591][12429:12429] CHIP:ZCL: ModeBase: Unable to load the OnMode from the KVS. Assuming null [1708101549.440647][12429:12429] CHIP:ZCL: ModeBase: Unable to load the CurrentMode from the KVS. Assuming 0 [1708101549.440689][12429:12429] CHIP:ZCL: ModeBase: Unable to load the StartUpMode from the KVS. Assuming null [1708101549.440729][12429:12429] CHIP:ZCL: ModeBase: Unable to load the OnMode from the KVS. Assuming null [1708101549.440813][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0062 update version to 773d94e4 [1708101549.440854][12429:12429] CHIP:ZCL: ResourceMonitoring: Init [1708101549.440900][12429:12429] CHIP:ZCL: ResourceMonitoring: Unable to load the LastChangedTime from the KVS. Assuming null [1708101549.440929][12429:12429] CHIP:ZCL: ResourceMonitoring: calling mDelegate->Init() [1708101549.440952][12429:12429] CHIP:ZCL: HepaFilterMonitoringDelegate::Init() [1708101549.440977][12429:12429] CHIP:ZCL: ResourceMonitoring: Init [1708101549.441017][12429:12429] CHIP:ZCL: ResourceMonitoring: Unable to load the LastChangedTime from the KVS. Assuming null [1708101549.441045][12429:12429] CHIP:ZCL: ResourceMonitoring: calling mDelegate->Init() [1708101549.441069][12429:12429] CHIP:ZCL: ActivatedCarbonFilterMonitoringDelegate::Init() [1708101549.441110][12429:12429] CHIP:SVR: mPowerMode updated to 2 [1708101549.441138][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0090 update version to 2dea7ca1 [1708101549.441213][12429:12429] CHIP:SVR: EVSE: Unable to restore persisted ChargingEnabledUntil value [1708101549.441257][12429:12429] CHIP:SVR: EVSE: Unable to restore persisted DischargingEnabledUntil value [1708101549.441298][12429:12429] CHIP:SVR: EVSE: Unable to restore persisted UserMaximumChargeCurrent value [1708101549.441338][12429:12429] CHIP:SVR: EVSE: Unable to restore persisted RandomizationDelayWindow value [1708101549.441379][12429:12429] CHIP:SVR: EVSE: Unable to restore persisted ApproximateEVEfficiency value [1708101549.441444][12429:12429] CHIP:ZCL: ModeBase: Unable to load the CurrentMode from the KVS. Assuming 0 [1708101549.441487][12429:12429] CHIP:ZCL: ModeBase: Unable to load the StartUpMode from the KVS. Assuming null [1708101549.441527][12429:12429] CHIP:ZCL: ModeBase: Unable to load the OnMode from the KVS. Assuming null [1708101549.441584][12429:12429] CHIP:ZCL: ModeBase: Unable to load the CurrentMode from the KVS. Assuming 0 [1708101549.441626][12429:12429] CHIP:ZCL: ModeBase: Unable to load the StartUpMode from the KVS. Assuming null [1708101549.441667][12429:12429] CHIP:ZCL: ModeBase: Unable to load the OnMode from the KVS. Assuming null [1708101549.441921][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-J41oTk) [1708101549.442937][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101549.443041][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0102 update version to f3fd2639 [1708101549.443076][12429:12429] CHIP:ZCL: WC POST ATTRIBUTE=7 [1708101549.443112][12429:12429] CHIP:ZCL: ConfigStatus 0x1B Operational=1 OnlineReserved=1 [1708101549.443137][12429:12429] CHIP:ZCL: Lift(PA=1 Encoder=0 Reversed=0) Tilt(PA=1 Encoder=0) [1708101549.443164][12429:12429] CHIP:ZCL: Initialize PCC Server Cluster [EP:1] [1708101549.443513][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-8MLGFx) [1708101549.444393][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101549.444484][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0300 update version to f816313e [1708101549.444527][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0300 update version to f816313f [1708101549.444823][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-10ip4D) [1708101549.445725][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101549.445818][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0300 update version to f8163140 [1708101549.445872][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0406 update version to e67c547c [1708101549.445909][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0406 update version to e67c547d [1708101549.445949][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040C update version to 1770713 [1708101549.445978][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040C update version to 1770714 [1708101549.446006][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040C update version to 1770715 [1708101549.446041][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040C update version to 1770716 [1708101549.446167][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040C update version to 1770717 [1708101549.446199][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040C update version to 1770718 [1708101549.446226][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040C update version to 1770719 [1708101549.446252][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040C update version to 177071a [1708101549.446283][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040D update version to e7e664ad [1708101549.446311][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040D update version to e7e664ae [1708101549.446339][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040D update version to e7e664af [1708101549.446366][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040D update version to e7e664b0 [1708101549.446413][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040D update version to e7e664b1 [1708101549.446443][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040D update version to e7e664b2 [1708101549.446468][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040D update version to e7e664b3 [1708101549.446494][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_040D update version to e7e664b4 [1708101549.446525][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0413 update version to b79b453b [1708101549.446555][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0413 update version to b79b453c [1708101549.446582][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0413 update version to b79b453d [1708101549.446609][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0413 update version to b79b453e [1708101549.446635][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0413 update version to b79b453f [1708101549.446662][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0413 update version to b79b4540 [1708101549.446689][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0413 update version to b79b4541 [1708101549.446715][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0413 update version to b79b4542 [1708101549.446749][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0415 update version to 3d4a263f [1708101549.446777][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0415 update version to 3d4a2640 [1708101549.446806][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0415 update version to 3d4a2641 [1708101549.446833][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0415 update version to 3d4a2642 [1708101549.446859][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0415 update version to 3d4a2643 [1708101549.446886][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0415 update version to 3d4a2644 [1708101549.446912][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0415 update version to 3d4a2645 [1708101549.446939][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_0415 update version to 3d4a2646 [1708101549.446969][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042A update version to 3b8c70de [1708101549.446997][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042A update version to 3b8c70df [1708101549.447024][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042A update version to 3b8c70e0 [1708101549.447051][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042A update version to 3b8c70e1 [1708101549.447082][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042A update version to 3b8c70e2 [1708101549.447109][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042A update version to 3b8c70e3 [1708101549.447135][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042A update version to 3b8c70e4 [1708101549.447162][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042A update version to 3b8c70e5 [1708101549.447192][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042B update version to a7ef699b [1708101549.447219][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042B update version to a7ef699c [1708101549.447246][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042B update version to a7ef699d [1708101549.447273][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042B update version to a7ef699e [1708101549.447295][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042B update version to a7ef699f [1708101549.447317][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042B update version to a7ef69a0 [1708101549.447338][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042B update version to a7ef69a1 [1708101549.447359][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042B update version to a7ef69a2 [1708101549.447383][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042C update version to 502e83d7 [1708101549.447405][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042C update version to 502e83d8 [1708101549.447435][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042C update version to 502e83d9 [1708101549.447457][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042C update version to 502e83da [1708101549.447482][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042C update version to 502e83db [1708101549.447504][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042C update version to 502e83dc [1708101549.447525][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042C update version to 502e83dd [1708101549.447546][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042C update version to 502e83de [1708101549.447571][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042D update version to 94b7825b [1708101549.447593][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042D update version to 94b7825c [1708101549.447615][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042D update version to 94b7825d [1708101549.447637][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042D update version to 94b7825e [1708101549.447658][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042D update version to 94b7825f [1708101549.447679][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042D update version to 94b78260 [1708101549.447701][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042D update version to 94b78261 [1708101549.447722][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042D update version to 94b78262 [1708101549.447751][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042E update version to e9f31b86 [1708101549.447773][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042E update version to e9f31b87 [1708101549.447795][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042E update version to e9f31b88 [1708101549.447817][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042E update version to e9f31b89 [1708101549.447838][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042E update version to e9f31b8a [1708101549.447860][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042E update version to e9f31b8b [1708101549.447881][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042E update version to e9f31b8c [1708101549.447902][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042E update version to e9f31b8d [1708101549.447926][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042F update version to 2e1fdd9c [1708101549.447950][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042F update version to 2e1fdd9d [1708101549.447971][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042F update version to 2e1fdd9e [1708101549.447993][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042F update version to 2e1fdd9f [1708101549.448014][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042F update version to 2e1fdda0 [1708101549.448036][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042F update version to 2e1fdda1 [1708101549.448057][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042F update version to 2e1fdda2 [1708101549.448078][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_042F update version to 2e1fdda3 [1708101549.448098][12429:12429] CHIP:-: Setting LowPower default delegate to global manager [1708101549.448128][12429:12429] CHIP:DMG: Endpoint 2, Cluster 0x0000_0004 update version to 86da7cc3 [1708101549.448152][12429:12429] CHIP:DMG: Endpoint 2, Cluster 0x0000_0004 update version to 86da7cc4 [1708101549.448182][12429:12429] CHIP:DMG: Endpoint 2, Cluster 0x0000_0062 update version to 6df60d90 [1708101549.448211][12429:12429] CHIP:DMG: Endpoint 2, Cluster 0x0000_0406 update version to b284cb3a [1708101549.448234][12429:12429] CHIP:DMG: Endpoint 2, Cluster 0x0000_0406 update version to b284cb3b [1708101549.448386][12429:12429] CHIP:IN: SecureSession[0xaaab16edaa50]: Allocated Type:1 LSID:62109 [1708101549.448426][12429:12429] CHIP:SC: Assigned local session key ID 62109 [1708101549.448449][12429:12429] CHIP:SC: Waiting for PBKDF param request [1708101549.448492][12429:12429] CHIP:DIS: Updating services using commissioning mode 1 [1708101549.448924][12429:12429] CHIP:DL: Using wifi MAC for hostname [1708101549.449022][12429:12429] CHIP:DIS: Advertise commission parameter vendorID=65521 productID=32769 discriminator=2222/08 cm=1 cp=0 [1708101549.449120][12429:12429] CHIP:DL: PublishService 5B5CAF568CB954F7._matterc._udp5540.-1 [1708101549.451253][12429:12429] CHIP:DL: Using addresses from interface id=0 name= [1708101549.459890][12429:12429] CHIP:DIS: mDNS service published: _matterc._udp; instance name: 5B5CAF568CB954F7 [1708101549.459958][12429:12429] CHIP:DIS: Updating services using commissioning mode 1 [1708101549.461633][12429:12429] CHIP:DL: Using wifi MAC for hostname [1708101549.461775][12429:12429] CHIP:DIS: Advertise commission parameter vendorID=65521 productID=32769 discriminator=2222/08 cm=1 cp=0 [1708101549.461875][12429:12429] CHIP:DL: PublishService 5B5CAF568CB954F7._matterc._udp5540.-1 [1708101549.463746][12429:12429] CHIP:DL: Using addresses from interface id=0 name= [1708101549.475213][12429:12429] CHIP:DIS: mDNS service published: _matterc._udp; instance name: 5B5CAF568CB954F7 [1708101549.475296][12429:12429] CHIP:IN: CASE Server enabling CASE session setups [1708101549.475446][12429:12429] CHIP:IN: SecureSession[0xaaab16edb860]: Allocated Type:2 LSID:62110 [1708101549.475506][12429:12429] CHIP:SC: Allocated SecureSession (0xaaab16edb860) - waiting for Sigma1 msg [1708101549.475547][12429:12429] CHIP:SVR: Joining Multicast groups [1708101549.475589][12429:12429] CHIP:ZCL: Emitting StartUp event [1708101549.475680][12429:12429] CHIP:EVL: LogEvent event number: 0x0000000000000002 priority: 2, endpoint id: 0x0 cluster id: 0x0000_0028 event id: 0x0 Epoch timestamp: 0x0000018DB2C93DA3 [1708101549.475726][12429:12429] CHIP:SVR: Server initialization complete [1708101549.475777][12429:12429] CHIP:SVR: Server Listening... [1708101549.475807][12429:12429] CHIP:DL: Device Configuration: [1708101549.475874][12429:12429] CHIP:DL: Serial Number: TEST_SN [1708101549.475949][12429:12429] CHIP:DL: Vendor Id: 65521 (0xFFF1) [1708101549.476001][12429:12429] CHIP:DL: Product Id: 32769 (0x8001) [1708101549.476033][12429:12429] CHIP:DL: Product Name: TEST_PRODUCT [1708101549.476076][12429:12429] CHIP:DL: Hardware Version: 0 [1708101549.476108][12429:12429] CHIP:DL: Setup Pin Code (0 for UNKNOWN/ERROR): 20202021 [1708101549.476136][12429:12429] CHIP:DL: Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 2222 (0x8AE) [1708101549.476178][12429:12429] CHIP:DL: Manufacturing Date: (not set) [1708101549.476209][12429:12429] CHIP:DL: Device Type: 65535 (0xFFFF) [1708101549.476256][12429:12429] CHIP:SVR: SetupQRCode: [MT:-24J0E.N16750648G00] [1708101549.476293][12429:12429] CHIP:SVR: Copy/paste the below URL in a browser to see the QR Code: [1708101549.476321][12429:12429] CHIP:SVR: https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A-24J0E.N16750648G00 [1708101549.476363][12429:12429] CHIP:SVR: Manual pairing code: [20054912334] [1708101549.476412][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_001D update version to 696584f1 [1708101549.477017][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_005D update version to a4c5e7db [1708101549.477086][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_005D update version to a4c5e7dc [1708101549.477132][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_005D update version to a4c5e7dd [1708101549.477178][12429:12429] CHIP:DMG: Endpoint 1, Cluster 0x0000_005D update version to a4c5e7de [1708101549.477297][12429:12429] CHIP:EVL: LogEvent event number: 0x0000000000000003 priority: 1, endpoint id: 0x1 cluster id: 0x0000_005D event id: 0x0 Epoch timestamp: 0x0000018DB2C93DA5 [1708101549.477845][12429:12430] CHIP:DL: CREATE service object at /chipoble/308d/service [1708101549.478677][12429:12430] CHIP:DL: Create characteristic object at /chipoble/308d/service/c1 [1708101549.479138][12429:12430] CHIP:DL: Create characteristic object at /chipoble/308d/service/c2 [1708101549.479321][12429:12430] CHIP:DL: CHIP BTP C1 /chipoble/308d/service [1708101549.479357][12429:12430] CHIP:DL: CHIP BTP C2 /chipoble/308d/service [1708101549.479385][12429:12430] CHIP:DL: CHIP_ENABLE_ADDITIONAL_DATA_ADVERTISING is FALSE [1708101549.489466][12429:12429] CHIP:DL: BlueZ integration init success [1708101549.490073][12429:12429] CHIP:SVR: Cannot load binding table: src/platform/Linux/KeyValueStoreManagerImpl.cpp:53: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101549.490136][12429:12429] CHIP:DL: HandlePlatformSpecificBLEEvent 32786 [1708101549.490280][12429:12429] CHIP:DIS: Updating services using commissioning mode 1 [1708101549.493413][12429:12429] CHIP:DL: Using wifi MAC for hostname [1708101549.493574][12429:12429] CHIP:DIS: Advertise commission parameter vendorID=65521 productID=32769 discriminator=2222/08 cm=1 cp=0 [1708101549.493694][12429:12429] CHIP:DL: PublishService 5B5CAF568CB954F7._matterc._udp5540.-1 [1708101549.495883][12429:12429] CHIP:DL: Using addresses from interface id=0 name= [1708101549.500340][12429:12430] CHIP:DL: BluezPeripheralRegisterAppDone done [1708101549.508608][12429:12429] CHIP:DIS: mDNS service published: _matterc._udp; instance name: 5B5CAF568CB954F7 [1708101549.508685][12429:12429] CHIP:DL: HandlePlatformSpecificBLEEvent 32790 [1708101549.509508][12429:12429] CHIP:IM: No subscriptions to resume [1708101549.509560][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0038 update version to 42180fe6 [1708101549.509598][12429:12429] CHIP:DL: HandlePlatformSpecificBLEEvent 16392 [1708101549.509739][12429:12430] CHIP:DL: Create BLE adv object at /chipoble/308d/advertising [1708101549.510895][12429:12429] CHIP:DL: SET service data to {'0xFFF6': <[byte 0x00, 0xae, 0x08, 0xf1, 0xff, 0x01, 0x80, 0x00]>} [1708101549.524164][12429:12430] CHIP:DL: RegisterAdvertisement complete [1708101549.524345][12429:12429] CHIP:DL: HandlePlatformSpecificBLEEvent 16393 [1708101550.501393][12429:12429] CHIP:DL: Avahi group established [1708101555.941595][12429:12430] CHIP:DL: BLE device connected: conn 0xffffa4008870, device DC:A6:32:8D:70:74, path /org/bluez/hci0/dev_DC_A6_32_8D_70_74 [1708101557.732283][12429:12430] CHIP:DL: BluezCharacteristicAcquireWrite is called, conn: 0xffffa4008870 [1708101557.733883][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101557.734073][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101557.734257][12429:12429] CHIP:BLE: local and remote recv window sizes = 6 [1708101557.734353][12429:12429] CHIP:BLE: selected BTP version 4 [1708101557.734433][12429:12429] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244. [1708101557.830703][12429:12430] CHIP:DL: CHIPoBLE subscribe received [1708101557.831244][12429:12429] CHIP:IN: BLE EndPoint 0xaaaae97ca2d0 Connection Complete [1708101557.831336][12429:12429] CHIP:DL: HandlePlatformSpecificBLEEvent 32774 [1708101557.831379][12429:12429] CHIP:DL: Receive kCHIPoBLEConnectionEstablished [1708101557.927782][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101557.928386][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101557.929064][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101557.929514][12429:12429] CHIP:EM: >>> [E:47753r S:0 M:5231169] (U) Msg RX from 0:B7A0522662434371 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) [1708101557.929622][12429:12429] CHIP:EM: Handling via exchange: 47753r, Delegate: 0xaaaae97c8de8 [1708101557.929700][12429:12429] CHIP:SC: Received PBKDF param request [1708101557.929789][12429:12429] CHIP:SC: Peer assigned session ID 63037 [1708101557.929852][12429:12429] CHIP:SC: Found MRP parameters in the message [1708101557.930352][12429:12429] CHIP:EM: <<< [E:47753r S:0 M:81241172] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) [1708101557.931336][12429:12429] CHIP:SC: Sent PBKDF param response [1708101557.931430][12429:12429] CHIP:SVR: Commissioning session establishment step started [1708101558.025032][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101558.025584][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101558.026683][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101558.027101][12429:12429] CHIP:EM: >>> [E:47753r S:0 M:5231170] (U) Msg RX from 0:B7A0522662434371 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1) [1708101558.027184][12429:12429] CHIP:EM: Found matching exchange: 47753r, Delegate: 0xaaaae97c8de8 [1708101558.027280][12429:12429] CHIP:SC: Received spake2p msg1 [1708101558.032659][12429:12429] CHIP:EM: <<< [E:47753r S:0 M:81241173] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:23 (SecureChannel:PASE_Pake2) [1708101558.033306][12429:12429] CHIP:SC: Sent spake2p msg2 [1708101558.122466][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101558.122783][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101558.123642][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101558.124034][12429:12429] CHIP:EM: >>> [E:47753r S:0 M:5231171] (U) Msg RX from 0:B7A0522662434371 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3) [1708101558.124120][12429:12429] CHIP:EM: Found matching exchange: 47753r, Delegate: 0xaaaae97c8de8 [1708101558.124217][12429:12429] CHIP:SC: Received spake2p msg3 [1708101558.124496][12429:12429] CHIP:SC: Sending status report. Protocol code 0, exchange 47753 [1708101558.124622][12429:12429] CHIP:EM: <<< [E:47753r S:0 M:81241174] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:40 (SecureChannel:StatusReport) [1708101558.125651][12429:12429] CHIP:SC: SecureSession[0xaaab16edaa50, LSID:62109]: State change 'kEstablishing' --> 'kActive' [1708101558.125763][12429:12429] CHIP:IN: SecureSession[0xaaab16edaa50]: Activated - Type:1 LSID:62109 [1708101558.125831][12429:12429] CHIP:IN: New secure session activated for device , LSID:62109 PSID:63037! [1708101558.125929][12429:12429] CHIP:SVR: Commissioning completed session establishment step [1708101558.126052][12429:12429] CHIP:DIS: Updating services using commissioning mode 0 [1708101558.128592][12429:12429] CHIP:SVR: Device completed Rendezvous process [1708101558.133444][12429:12430] CHIP:DL: UnregisterAdvertisement complete [1708101558.133673][12429:12429] CHIP:DL: HandlePlatformSpecificBLEEvent 16394 [1708101558.133733][12429:12429] CHIP:DL: CHIPoBLE advertising stopped [1708101558.219740][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101558.221125][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101558.222241][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101558.222872][12429:12429] CHIP:EM: >>> [E:47754r S:62109 M:156344650] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) [1708101558.222987][12429:12429] CHIP:EM: Handling via exchange: 47754r, Delegate: 0xaaaae97c1968 [1708101558.223052][12429:12429] CHIP:IM: Received Read request [1708101558.223179][12429:12429] CHIP:DMG: ReadRequestMessage = [1708101558.223237][12429:12429] CHIP:DMG: { [1708101558.223287][12429:12429] CHIP:DMG: AttributePathIBs = [1708101558.223347][12429:12429] CHIP:DMG: [ [1708101558.223403][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.223467][12429:12429] CHIP:DMG: { [1708101558.223531][12429:12429] CHIP:DMG: Cluster = 0x31, [1708101558.223666][12429:12429] CHIP:DMG: Attribute = 0x0000_FFFC, [1708101558.223737][12429:12429] CHIP:DMG: } [1708101558.223806][12429:12429] CHIP:DMG: [1708101558.223866][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.223930][12429:12429] CHIP:DMG: { [1708101558.223998][12429:12429] CHIP:DMG: Endpoint = 0x0, [1708101558.224077][12429:12429] CHIP:DMG: Cluster = 0x30, [1708101558.224146][12429:12429] CHIP:DMG: Attribute = 0x0000_0000, [1708101558.224210][12429:12429] CHIP:DMG: } [1708101558.224286][12429:12429] CHIP:DMG: [1708101558.224361][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.224438][12429:12429] CHIP:DMG: { [1708101558.224505][12429:12429] CHIP:DMG: Endpoint = 0x0, [1708101558.224587][12429:12429] CHIP:DMG: Cluster = 0x30, [1708101558.224656][12429:12429] CHIP:DMG: Attribute = 0x0000_0001, [1708101558.224720][12429:12429] CHIP:DMG: } [1708101558.224788][12429:12429] CHIP:DMG: [1708101558.224848][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.224911][12429:12429] CHIP:DMG: { [1708101558.224985][12429:12429] CHIP:DMG: Endpoint = 0x0, [1708101558.225058][12429:12429] CHIP:DMG: Cluster = 0x30, [1708101558.225126][12429:12429] CHIP:DMG: Attribute = 0x0000_0002, [1708101558.225200][12429:12429] CHIP:DMG: } [1708101558.225271][12429:12429] CHIP:DMG: [1708101558.225330][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.225392][12429:12429] CHIP:DMG: { [1708101558.225455][12429:12429] CHIP:DMG: Endpoint = 0x0, [1708101558.225523][12429:12429] CHIP:DMG: Cluster = 0x30, [1708101558.225590][12429:12429] CHIP:DMG: Attribute = 0x0000_0003, [1708101558.225653][12429:12429] CHIP:DMG: } [1708101558.225719][12429:12429] CHIP:DMG: [1708101558.225779][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.225841][12429:12429] CHIP:DMG: { [1708101558.225904][12429:12429] CHIP:DMG: Endpoint = 0x0, [1708101558.225994][12429:12429] CHIP:DMG: Cluster = 0x28, [1708101558.226063][12429:12429] CHIP:DMG: Attribute = 0x0000_0002, [1708101558.226147][12429:12429] CHIP:DMG: } [1708101558.226217][12429:12429] CHIP:DMG: [1708101558.226276][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.226342][12429:12429] CHIP:DMG: { [1708101558.226462][12429:12429] CHIP:DMG: Endpoint = 0x0, [1708101558.226547][12429:12429] CHIP:DMG: Cluster = 0x28, [1708101558.226614][12429:12429] CHIP:DMG: Attribute = 0x0000_0004, [1708101558.226679][12429:12429] CHIP:DMG: } [1708101558.226747][12429:12429] CHIP:DMG: [1708101558.226806][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.226868][12429:12429] CHIP:DMG: { [1708101558.226936][12429:12429] CHIP:DMG: Cluster = 0x31, [1708101558.227003][12429:12429] CHIP:DMG: Attribute = 0x0000_0003, [1708101558.227065][12429:12429] CHIP:DMG: } [1708101558.227130][12429:12429] CHIP:DMG: [1708101558.227189][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.227250][12429:12429] CHIP:DMG: { [1708101558.227312][12429:12429] CHIP:DMG: Endpoint = 0x0, [1708101558.227381][12429:12429] CHIP:DMG: Cluster = 0x38, [1708101558.227444][12429:12429] CHIP:DMG: } [1708101558.227510][12429:12429] CHIP:DMG: [1708101558.227569][12429:12429] CHIP:DMG: ], [1708101558.227665][12429:12429] CHIP:DMG: [1708101558.227725][12429:12429] CHIP:DMG: isFabricFiltered = false, [1708101558.227783][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101558.227837][12429:12429] CHIP:DMG: }, [1708101558.228585][12429:12429] CHIP:DMG: IM RH moving to [CanStartReporting] [1708101558.228836][12429:12429] CHIP:DMG: Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000 [1708101558.228932][12429:12429] CHIP:DMG: Cluster 38, Attribute 0 is dirty [1708101558.228983][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_0000 (expanded=1) [1708101558.229045][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.229101][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.229204][12429:12429] CHIP:DMG: Cluster 38, Attribute 1 is dirty [1708101558.229257][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_0001 (expanded=1) [1708101558.229311][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.229364][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.229441][12429:12429] CHIP:DMG: Cluster 38, Attribute 2 is dirty [1708101558.229492][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_0002 (expanded=1) [1708101558.229547][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.229599][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.229682][12429:12429] CHIP:DMG: Cluster 38, Attribute 3 is dirty [1708101558.229734][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_0003 (expanded=1) [1708101558.229788][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.229839][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.229914][12429:12429] CHIP:DMG: Cluster 38, Attribute 4 is dirty [1708101558.229965][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_0004 (expanded=1) [1708101558.230018][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.230070][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.230229][12429:12429] CHIP:DMG: Cluster 38, Attribute 5 is dirty [1708101558.230287][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_0005 (expanded=1) [1708101558.230341][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.230374][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.230495][12429:12429] CHIP:DMG: Cluster 38, Attribute 6 is dirty [1708101558.230523][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_0006 (expanded=1) [1708101558.230551][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.230575][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.230613][12429:12429] CHIP:DMG: Cluster 38, Attribute 7 is dirty [1708101558.230638][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_0007 (expanded=1) [1708101558.230665][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.230691][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.230731][12429:12429] CHIP:DMG: Cluster 38, Attribute 8 is dirty [1708101558.230757][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_0008 (expanded=1) [1708101558.230784][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.230809][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.230849][12429:12429] CHIP:DMG: Cluster 38, Attribute a is dirty [1708101558.230874][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_000A (expanded=1) [1708101558.230901][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.230927][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.230965][12429:12429] CHIP:DMG: Cluster 38, Attribute b is dirty [1708101558.230991][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_000B (expanded=1) [1708101558.231017][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.231043][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.231079][12429:12429] CHIP:DMG: Cluster 38, Attribute c is dirty [1708101558.231104][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_000C (expanded=1) [1708101558.231130][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.231156][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.231196][12429:12429] CHIP:DMG: Cluster 38, Attribute fffc is dirty [1708101558.231222][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) [1708101558.231248][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.231274][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.231314][12429:12429] CHIP:DMG: Cluster 38, Attribute fffd is dirty [1708101558.231341][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_FFFD (expanded=1) [1708101558.231367][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.231393][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.231431][12429:12429] CHIP:DMG: Cluster 38, Attribute fff8 is dirty [1708101558.231457][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_FFF8 (expanded=1) [1708101558.231483][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.231508][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.231550][12429:12429] CHIP:DMG: Cluster 38, Attribute fff9 is dirty [1708101558.231575][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_FFF9 (expanded=1) [1708101558.231601][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.231626][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.231665][12429:12429] CHIP:DMG: Cluster 38, Attribute fffb is dirty [1708101558.231690][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0038 Endpoint=0 AttributeId=0x0000_FFFB (expanded=1) [1708101558.231716][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=v [1708101558.231741][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.231790][12429:12429] CHIP:DMG: Cluster 31, Attribute 3 is dirty [1708101558.231816][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_0003 (expanded=1) [1708101558.231842][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0031 e=0 p=v [1708101558.231867][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.231907][12429:12429] CHIP:DMG: Cluster 28, Attribute 4 is dirty [1708101558.231932][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0004 (expanded=0) [1708101558.231960][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0028 e=0 p=v [1708101558.231985][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.232071][12429:12429] CHIP:DMG: Cluster 28, Attribute 2 is dirty [1708101558.232100][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0028 Endpoint=0 AttributeId=0x0000_0002 (expanded=0) [1708101558.232129][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0028 e=0 p=v [1708101558.232155][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.232208][12429:12429] CHIP:DMG: Cluster 30, Attribute 3 is dirty [1708101558.232236][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0003 (expanded=0) [1708101558.232264][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v [1708101558.232290][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.232350][12429:12429] CHIP:DMG: Cluster 30, Attribute 2 is dirty [1708101558.232379][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0002 (expanded=0) [1708101558.232406][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v [1708101558.232433][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.232485][12429:12429] CHIP:DMG: Cluster 30, Attribute 1 is dirty [1708101558.232513][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0001 (expanded=0) [1708101558.232541][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v [1708101558.232567][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.232606][12429:12429] CHIP:DMG: Cluster 30, Attribute 0 is dirty [1708101558.232632][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0000 (expanded=0) [1708101558.232658][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v [1708101558.232684][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.232725][12429:12429] CHIP:DMG: Cluster 31, Attribute fffc is dirty [1708101558.232751][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0031 Endpoint=0 AttributeId=0x0000_FFFC (expanded=1) [1708101558.232777][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0031 e=0 p=v [1708101558.232803][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.232851][12429:12429] CHIP:DMG: Sending report (payload has 737 bytes)... [1708101558.233023][12429:12429] CHIP:EM: <<< [E:47754r S:62109 M:138661794] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) [1708101558.233342][12429:12429] CHIP:DMG: OnReportConfirm: NumReports = 0 [1708101558.233381][12429:12429] CHIP:DMG: ReportsInFlight = 0 with readHandler 0, RE has no more messages [1708101558.233406][12429:12429] CHIP:DMG: IM RH moving to [AwaitingDestruction] [1708101558.234274][12429:12429] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1708101558.414333][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101558.511811][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101558.609201][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101558.706640][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101558.707334][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101558.708344][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101558.708803][12429:12429] CHIP:EM: >>> [E:47755r S:62109 M:156344651] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) [1708101558.708899][12429:12429] CHIP:EM: Handling via exchange: 47755r, Delegate: 0xaaaae97c1968 [1708101558.708954][12429:12429] CHIP:IM: Received Read request [1708101558.709047][12429:12429] CHIP:DMG: ReadRequestMessage = [1708101558.709096][12429:12429] CHIP:DMG: { [1708101558.709152][12429:12429] CHIP:DMG: AttributePathIBs = [1708101558.709206][12429:12429] CHIP:DMG: [ [1708101558.709254][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.709309][12429:12429] CHIP:DMG: { [1708101558.709365][12429:12429] CHIP:DMG: Endpoint = 0x0, [1708101558.709432][12429:12429] CHIP:DMG: Cluster = 0x30, [1708101558.709491][12429:12429] CHIP:DMG: Attribute = 0x0000_0004, [1708101558.709599][12429:12429] CHIP:DMG: } [1708101558.709668][12429:12429] CHIP:DMG: [1708101558.709721][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.709860][12429:12429] CHIP:DMG: { [1708101558.710064][12429:12429] CHIP:DMG: Endpoint = 0x0, [1708101558.710129][12429:12429] CHIP:DMG: Cluster = 0x46, [1708101558.710197][12429:12429] CHIP:DMG: Attribute = 0x0000_FFFC, [1708101558.710296][12429:12429] CHIP:DMG: } [1708101558.710364][12429:12429] CHIP:DMG: [1708101558.710467][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.710527][12429:12429] CHIP:DMG: { [1708101558.710579][12429:12429] CHIP:DMG: Endpoint = 0x0, [1708101558.710634][12429:12429] CHIP:DMG: Cluster = 0x46, [1708101558.710693][12429:12429] CHIP:DMG: Attribute = 0x0000_0006, [1708101558.710746][12429:12429] CHIP:DMG: } [1708101558.710812][12429:12429] CHIP:DMG: [1708101558.710865][12429:12429] CHIP:DMG: AttributePathIB = [1708101558.710914][12429:12429] CHIP:DMG: { [1708101558.710968][12429:12429] CHIP:DMG: Endpoint = 0x0, [1708101558.711022][12429:12429] CHIP:DMG: Cluster = 0x46, [1708101558.711115][12429:12429] CHIP:DMG: Attribute = 0x0000_0007, [1708101558.711167][12429:12429] CHIP:DMG: } [1708101558.711221][12429:12429] CHIP:DMG: [1708101558.711268][12429:12429] CHIP:DMG: ], [1708101558.711324][12429:12429] CHIP:DMG: [1708101558.711369][12429:12429] CHIP:DMG: isFabricFiltered = false, [1708101558.711413][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101558.711497][12429:12429] CHIP:DMG: }, [1708101558.711853][12429:12429] CHIP:DMG: IM RH moving to [CanStartReporting] [1708101558.712052][12429:12429] CHIP:DMG: Building Reports for ReadHandler with LastReportGeneration = 0x0000000000000000 DirtyGeneration = 0x0000000000000000 [1708101558.712108][12429:12429] CHIP:DMG: Cluster 46, Attribute 7 is dirty [1708101558.712145][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0046 Endpoint=0 AttributeId=0x0000_0007 (expanded=0) [1708101558.712213][12429:12429] CHIP:DMG: Cluster 46, Attribute 6 is dirty [1708101558.712253][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0046 Endpoint=0 AttributeId=0x0000_0006 (expanded=0) [1708101558.712307][12429:12429] CHIP:DMG: Cluster 46, Attribute fffc is dirty [1708101558.712345][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0046 Endpoint=0 AttributeId=0x0000_FFFC (expanded=0) [1708101558.712400][12429:12429] CHIP:DMG: Cluster 30, Attribute 4 is dirty [1708101558.712448][12429:12429] CHIP:DMG: Reading attribute: Cluster=0x0000_0030 Endpoint=0 AttributeId=0x0000_0004 (expanded=0) [1708101558.712492][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=v [1708101558.712533][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.712618][12429:12429] CHIP:DMG: Sending report (payload has 105 bytes)... [1708101558.712793][12429:12429] CHIP:EM: <<< [E:47755r S:62109 M:138661795] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData) [1708101558.713205][12429:12429] CHIP:DMG: OnReportConfirm: NumReports = 0 [1708101558.713256][12429:12429] CHIP:DMG: ReportsInFlight = 0 with readHandler 0, RE has no more messages [1708101558.713295][12429:12429] CHIP:DMG: IM RH moving to [AwaitingDestruction] [1708101558.714615][12429:12429] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1708101558.804871][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101558.805318][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101558.806262][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101558.806900][12429:12429] CHIP:EM: >>> [E:47756r S:62109 M:156344652] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101558.807068][12429:12429] CHIP:EM: Handling via exchange: 47756r, Delegate: 0xaaaae97c1968 [1708101558.807205][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101558.807265][12429:12429] CHIP:DMG: { [1708101558.807317][12429:12429] CHIP:DMG: suppressResponse = false, [1708101558.807378][12429:12429] CHIP:DMG: timedRequest = false, [1708101558.807435][12429:12429] CHIP:DMG: InvokeRequests = [1708101558.807508][12429:12429] CHIP:DMG: [ [1708101558.807564][12429:12429] CHIP:DMG: CommandDataIB = [1708101558.807628][12429:12429] CHIP:DMG: { [1708101558.807748][12429:12429] CHIP:DMG: CommandPathIB = [1708101558.807831][12429:12429] CHIP:DMG: { [1708101558.807961][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101558.808085][12429:12429] CHIP:DMG: ClusterId = 0x30, [1708101558.808207][12429:12429] CHIP:DMG: CommandId = 0x0, [1708101558.808284][12429:12429] CHIP:DMG: }, [1708101558.808404][12429:12429] CHIP:DMG: [1708101558.808469][12429:12429] CHIP:DMG: CommandFields = [1708101558.808589][12429:12429] CHIP:DMG: { [1708101558.808725][12429:12429] CHIP:DMG: 0x0 = 60, [1708101558.808846][12429:12429] CHIP:DMG: 0x1 = 4, [1708101558.808927][12429:12429] CHIP:DMG: }, [1708101558.809038][12429:12429] CHIP:DMG: }, [1708101558.809115][12429:12429] CHIP:DMG: [1708101558.809216][12429:12429] CHIP:DMG: ], [1708101558.809289][12429:12429] CHIP:DMG: [1708101558.809393][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101558.809495][12429:12429] CHIP:DMG: }, [1708101558.809855][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=a [1708101558.809970][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.810038][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0000 [1708101558.810242][12429:12429] CHIP:FS: GeneralCommissioning: Received ArmFailSafe (60s) [1708101558.810380][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0030 update version to 5bde7301 [1708101558.810527][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101558.810584][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101558.810651][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101558.810797][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101558.810918][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101558.811370][12429:12429] CHIP:EM: <<< [E:47756r S:62109 M:138661796] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101558.811907][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101558.811978][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101558.902451][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101558.903086][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101558.903925][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101558.904457][12429:12429] CHIP:EM: >>> [E:47757r S:62109 M:156344653] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101558.904561][12429:12429] CHIP:EM: Handling via exchange: 47757r, Delegate: 0xaaaae97c1968 [1708101558.904694][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101558.904753][12429:12429] CHIP:DMG: { [1708101558.904805][12429:12429] CHIP:DMG: suppressResponse = false, [1708101558.904865][12429:12429] CHIP:DMG: timedRequest = false, [1708101558.904922][12429:12429] CHIP:DMG: InvokeRequests = [1708101558.904995][12429:12429] CHIP:DMG: [ [1708101558.905052][12429:12429] CHIP:DMG: CommandDataIB = [1708101558.905116][12429:12429] CHIP:DMG: { [1708101558.905175][12429:12429] CHIP:DMG: CommandPathIB = [1708101558.905251][12429:12429] CHIP:DMG: { [1708101558.905317][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101558.905384][12429:12429] CHIP:DMG: ClusterId = 0x30, [1708101558.905448][12429:12429] CHIP:DMG: CommandId = 0x2, [1708101558.905509][12429:12429] CHIP:DMG: }, [1708101558.905574][12429:12429] CHIP:DMG: [1708101558.905636][12429:12429] CHIP:DMG: CommandFields = [1708101558.905703][12429:12429] CHIP:DMG: { [1708101558.905775][12429:12429] CHIP:DMG: 0x0 = 0, [1708101558.905934][12429:12429] CHIP:DMG: 0x1 = "XX" (2 chars), [1708101558.906028][12429:12429] CHIP:DMG: 0x2 = 5, [1708101558.906161][12429:12429] CHIP:DMG: }, [1708101558.906296][12429:12429] CHIP:DMG: }, [1708101558.906377][12429:12429] CHIP:DMG: [1708101558.906565][12429:12429] CHIP:DMG: ], [1708101558.906655][12429:12429] CHIP:DMG: [1708101558.906712][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101558.906768][12429:12429] CHIP:DMG: }, [1708101558.907051][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=a [1708101558.907118][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101558.907180][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0002 [1708101558.907812][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_config.ini-Ulk97x) [1708101558.909363][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1708101558.909536][12429:12429] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0) [1708101558.909998][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_config.ini-c4zUfU) [1708101558.911730][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1708101558.911901][12429:12429] CHIP:DL: NVS set: chip-config/country-code = "XX" [1708101558.911987][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0030 update version to 5bde7302 [1708101558.912093][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101558.912150][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101558.912213][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101558.912282][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101558.912399][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101558.912720][12429:12429] CHIP:EM: <<< [E:47757r S:62109 M:138661797] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101558.913167][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101558.913204][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101559.000179][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101559.000414][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101559.001307][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101559.001778][12429:12429] CHIP:EM: >>> [E:47758r S:62109 M:156344654] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101559.001884][12429:12429] CHIP:EM: Handling via exchange: 47758r, Delegate: 0xaaaae97c1968 [1708101559.002054][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101559.002114][12429:12429] CHIP:DMG: { [1708101559.002166][12429:12429] CHIP:DMG: suppressResponse = false, [1708101559.002229][12429:12429] CHIP:DMG: timedRequest = false, [1708101559.002286][12429:12429] CHIP:DMG: InvokeRequests = [1708101559.002358][12429:12429] CHIP:DMG: [ [1708101559.002471][12429:12429] CHIP:DMG: CommandDataIB = [1708101559.002545][12429:12429] CHIP:DMG: { [1708101559.002614][12429:12429] CHIP:DMG: CommandPathIB = [1708101559.002680][12429:12429] CHIP:DMG: { [1708101559.002745][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101559.002812][12429:12429] CHIP:DMG: ClusterId = 0x38, [1708101559.002879][12429:12429] CHIP:DMG: CommandId = 0x0, [1708101559.002946][12429:12429] CHIP:DMG: }, [1708101559.003018][12429:12429] CHIP:DMG: [1708101559.003080][12429:12429] CHIP:DMG: CommandFields = [1708101559.003222][12429:12429] CHIP:DMG: { [1708101559.003309][12429:12429] CHIP:DMG: 0x0 = 761416758953038, [1708101559.003396][12429:12429] CHIP:DMG: 0x1 = 2, [1708101559.003470][12429:12429] CHIP:DMG: }, [1708101559.003550][12429:12429] CHIP:DMG: }, [1708101559.003626][12429:12429] CHIP:DMG: [1708101559.003683][12429:12429] CHIP:DMG: ], [1708101559.003756][12429:12429] CHIP:DMG: [1708101559.003811][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101559.003882][12429:12429] CHIP:DMG: }, [1708101559.004151][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0038 e=0 p=a [1708101559.004217][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101559.004275][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_0038 Command=0x0000_0000 [1708101559.004396][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101559.004459][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101559.004522][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101559.004582][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101559.004673][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101559.004897][12429:12429] CHIP:EM: <<< [E:47758r S:62109 M:138661798] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101559.005458][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101559.005523][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101559.097205][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101559.097676][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101559.098312][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101559.098698][12429:12429] CHIP:EM: >>> [E:47759r S:62109 M:156344655] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101559.098778][12429:12429] CHIP:EM: Handling via exchange: 47759r, Delegate: 0xaaaae97c1968 [1708101559.098877][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101559.098921][12429:12429] CHIP:DMG: { [1708101559.098959][12429:12429] CHIP:DMG: suppressResponse = false, [1708101559.099005][12429:12429] CHIP:DMG: timedRequest = false, [1708101559.099049][12429:12429] CHIP:DMG: InvokeRequests = [1708101559.099103][12429:12429] CHIP:DMG: [ [1708101559.099145][12429:12429] CHIP:DMG: CommandDataIB = [1708101559.099193][12429:12429] CHIP:DMG: { [1708101559.099238][12429:12429] CHIP:DMG: CommandPathIB = [1708101559.099296][12429:12429] CHIP:DMG: { [1708101559.099346][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101559.099397][12429:12429] CHIP:DMG: ClusterId = 0x3e, [1708101559.099446][12429:12429] CHIP:DMG: CommandId = 0x2, [1708101559.099493][12429:12429] CHIP:DMG: }, [1708101559.099555][12429:12429] CHIP:DMG: [1708101559.099604][12429:12429] CHIP:DMG: CommandFields = [1708101559.099655][12429:12429] CHIP:DMG: { [1708101559.099708][12429:12429] CHIP:DMG: 0x0 = 2, [1708101559.099771][12429:12429] CHIP:DMG: }, [1708101559.099821][12429:12429] CHIP:DMG: }, [1708101559.099880][12429:12429] CHIP:DMG: [1708101559.099935][12429:12429] CHIP:DMG: ], [1708101559.099989][12429:12429] CHIP:DMG: [1708101559.100032][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101559.100079][12429:12429] CHIP:DMG: }, [1708101559.100284][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a [1708101559.100337][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101559.100383][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002 [1708101559.100449][12429:12429] CHIP:ZCL: OpCreds: Certificate Chain request received for PAI [1708101559.100522][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101559.100565][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101559.100613][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101559.100662][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101559.100734][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101559.100949][12429:12429] CHIP:EM: <<< [E:47759r S:62109 M:138661799] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101559.101382][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101559.101430][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101559.243227][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101559.340383][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101559.438122][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101559.438321][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101559.439145][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101559.439621][12429:12429] CHIP:EM: >>> [E:47760r S:62109 M:156344656] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101559.439728][12429:12429] CHIP:EM: Handling via exchange: 47760r, Delegate: 0xaaaae97c1968 [1708101559.439855][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101559.439912][12429:12429] CHIP:DMG: { [1708101559.439966][12429:12429] CHIP:DMG: suppressResponse = false, [1708101559.440021][12429:12429] CHIP:DMG: timedRequest = false, [1708101559.440083][12429:12429] CHIP:DMG: InvokeRequests = [1708101559.440155][12429:12429] CHIP:DMG: [ [1708101559.440319][12429:12429] CHIP:DMG: CommandDataIB = [1708101559.440390][12429:12429] CHIP:DMG: { [1708101559.440450][12429:12429] CHIP:DMG: CommandPathIB = [1708101559.440518][12429:12429] CHIP:DMG: { [1708101559.440589][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101559.440671][12429:12429] CHIP:DMG: ClusterId = 0x3e, [1708101559.440744][12429:12429] CHIP:DMG: CommandId = 0x2, [1708101559.440812][12429:12429] CHIP:DMG: }, [1708101559.440884][12429:12429] CHIP:DMG: [1708101559.440948][12429:12429] CHIP:DMG: CommandFields = [1708101559.441016][12429:12429] CHIP:DMG: { [1708101559.441087][12429:12429] CHIP:DMG: 0x0 = 1, [1708101559.441160][12429:12429] CHIP:DMG: }, [1708101559.441223][12429:12429] CHIP:DMG: }, [1708101559.441466][12429:12429] CHIP:DMG: [1708101559.441614][12429:12429] CHIP:DMG: ], [1708101559.441692][12429:12429] CHIP:DMG: [1708101559.441748][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101559.441869][12429:12429] CHIP:DMG: }, [1708101559.442139][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a [1708101559.442206][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101559.442269][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002 [1708101559.442353][12429:12429] CHIP:ZCL: OpCreds: Certificate Chain request received for DAC [1708101559.442552][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101559.442611][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101559.442674][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101559.442740][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101559.442833][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101559.443111][12429:12429] CHIP:EM: <<< [E:47760r S:62109 M:138661800] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101559.443678][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101559.443752][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101559.584284][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101559.681522][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101559.779479][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101559.780124][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101559.781013][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101559.781491][12429:12429] CHIP:EM: >>> [E:47761r S:62109 M:156344657] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101559.781593][12429:12429] CHIP:EM: Handling via exchange: 47761r, Delegate: 0xaaaae97c1968 [1708101559.781725][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101559.781784][12429:12429] CHIP:DMG: { [1708101559.781835][12429:12429] CHIP:DMG: suppressResponse = false, [1708101559.781895][12429:12429] CHIP:DMG: timedRequest = false, [1708101559.781952][12429:12429] CHIP:DMG: InvokeRequests = [1708101559.782030][12429:12429] CHIP:DMG: [ [1708101559.782086][12429:12429] CHIP:DMG: CommandDataIB = [1708101559.782149][12429:12429] CHIP:DMG: { [1708101559.782235][12429:12429] CHIP:DMG: CommandPathIB = [1708101559.782304][12429:12429] CHIP:DMG: { [1708101559.782373][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101559.782522][12429:12429] CHIP:DMG: ClusterId = 0x3e, [1708101559.782590][12429:12429] CHIP:DMG: CommandId = 0x0, [1708101559.782653][12429:12429] CHIP:DMG: }, [1708101559.782719][12429:12429] CHIP:DMG: [1708101559.782776][12429:12429] CHIP:DMG: CommandFields = [1708101559.782837][12429:12429] CHIP:DMG: { [1708101559.782902][12429:12429] CHIP:DMG: 0x0 = [ [1708101559.783007][12429:12429] CHIP:DMG: 0xf2, 0x67, 0xb4, 0x6f, 0x9e, 0x8e, 0x6c, 0x05, 0xa1, 0x4e, 0xdb, 0xf6, 0x3b, 0x1a, 0x77, 0xcf, 0x32, 0x10, 0xeb, 0x11, 0xbd, 0x47, 0x1a, 0x38, 0xca, 0x78, 0x9f, 0xd5, 0x36, 0x20, 0x53, 0xa5, [1708101559.783096][12429:12429] CHIP:DMG: ] (32 bytes) [1708101559.783192][12429:12429] CHIP:DMG: }, [1708101559.783267][12429:12429] CHIP:DMG: }, [1708101559.783334][12429:12429] CHIP:DMG: [1708101559.783385][12429:12429] CHIP:DMG: ], [1708101559.783451][12429:12429] CHIP:DMG: [1708101559.783501][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101559.783551][12429:12429] CHIP:DMG: }, [1708101559.783817][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a [1708101559.783877][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101559.783933][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0000 [1708101559.784017][12429:12429] CHIP:ZCL: OpCreds: Received an AttestationRequest command [1708101559.784997][12429:12429] CHIP:ZCL: OpCreds: AttestationRequest successful. [1708101559.785117][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101559.785173][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101559.785237][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101559.785301][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101559.785406][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101559.785699][12429:12429] CHIP:EM: <<< [E:47761r S:62109 M:138661801] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101559.786303][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101559.786369][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101559.925623][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101560.023243][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101560.218188][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101560.218896][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101560.219913][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101560.220381][12429:12429] CHIP:EM: >>> [E:47762r S:62109 M:156344658] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101560.220515][12429:12429] CHIP:EM: Handling via exchange: 47762r, Delegate: 0xaaaae97c1968 [1708101560.220651][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101560.220712][12429:12429] CHIP:DMG: { [1708101560.220765][12429:12429] CHIP:DMG: suppressResponse = false, [1708101560.220827][12429:12429] CHIP:DMG: timedRequest = false, [1708101560.220885][12429:12429] CHIP:DMG: InvokeRequests = [1708101560.220986][12429:12429] CHIP:DMG: [ [1708101560.221046][12429:12429] CHIP:DMG: CommandDataIB = [1708101560.221199][12429:12429] CHIP:DMG: { [1708101560.221271][12429:12429] CHIP:DMG: CommandPathIB = [1708101560.221398][12429:12429] CHIP:DMG: { [1708101560.221469][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101560.221670][12429:12429] CHIP:DMG: ClusterId = 0x3e, [1708101560.221750][12429:12429] CHIP:DMG: CommandId = 0x4, [1708101560.221870][12429:12429] CHIP:DMG: }, [1708101560.221950][12429:12429] CHIP:DMG: [1708101560.222068][12429:12429] CHIP:DMG: CommandFields = [1708101560.222201][12429:12429] CHIP:DMG: { [1708101560.222281][12429:12429] CHIP:DMG: 0x0 = [ [1708101560.222378][12429:12429] CHIP:DMG: 0x76, 0x21, 0x5a, 0x48, 0xf6, 0xeb, 0xe3, 0xcc, 0xae, 0x5c, 0x28, 0x99, 0x5a, 0x13, 0xdd, 0x50, 0x51, 0x31, 0x56, 0xe3, 0x54, 0x00, 0x3f, 0x4f, 0x0d, 0x2a, 0x81, 0xcf, 0x74, 0x26, 0x30, 0x05, [1708101560.222586][12429:12429] CHIP:DMG: ] (32 bytes) [1708101560.222767][12429:12429] CHIP:DMG: }, [1708101560.222840][12429:12429] CHIP:DMG: }, [1708101560.222959][12429:12429] CHIP:DMG: [1708101560.223023][12429:12429] CHIP:DMG: ], [1708101560.223095][12429:12429] CHIP:DMG: [1708101560.223193][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101560.223253][12429:12429] CHIP:DMG: }, [1708101560.223523][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a [1708101560.223589][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101560.223651][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0004 [1708101560.223737][12429:12429] CHIP:ZCL: OpCreds: Received a CSRRequest command [1708101560.223798][12429:12429] CHIP:ZCL: OpCreds: Finding fabric with fabricIndex 0x0 [1708101560.227149][12429:12429] CHIP:ZCL: OpCreds: AllocatePendingOperationalKey succeeded [1708101560.228005][12429:12429] CHIP:ZCL: OpCreds: CSRRequest successful. [1708101560.228186][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101560.228246][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101560.228310][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101560.228412][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101560.228526][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101560.228883][12429:12429] CHIP:EM: <<< [E:47762r S:62109 M:138661802] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101560.229443][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101560.229517][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101560.364377][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101560.461828][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101560.464407][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101560.466083][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101560.558956][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101560.560390][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101560.560888][12429:12429] CHIP:EM: >>> [E:47763r S:62109 M:156344659] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101560.560998][12429:12429] CHIP:EM: Handling via exchange: 47763r, Delegate: 0xaaaae97c1968 [1708101560.561133][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101560.561193][12429:12429] CHIP:DMG: { [1708101560.561248][12429:12429] CHIP:DMG: suppressResponse = false, [1708101560.561309][12429:12429] CHIP:DMG: timedRequest = false, [1708101560.561391][12429:12429] CHIP:DMG: InvokeRequests = [1708101560.561469][12429:12429] CHIP:DMG: [ [1708101560.561548][12429:12429] CHIP:DMG: CommandDataIB = [1708101560.561617][12429:12429] CHIP:DMG: { [1708101560.561771][12429:12429] CHIP:DMG: CommandPathIB = [1708101560.561881][12429:12429] CHIP:DMG: { [1708101560.561957][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101560.562057][12429:12429] CHIP:DMG: ClusterId = 0x3e, [1708101560.562129][12429:12429] CHIP:DMG: CommandId = 0xb, [1708101560.562220][12429:12429] CHIP:DMG: }, [1708101560.562295][12429:12429] CHIP:DMG: [1708101560.562379][12429:12429] CHIP:DMG: CommandFields = [1708101560.562495][12429:12429] CHIP:DMG: { [1708101560.562585][12429:12429] CHIP:DMG: 0x0 = [ [1708101560.562844][12429:12429] CHIP:DMG: 0x15, 0x30, 0x01, 0x01, 0x01, 0x24, 0x02, 0x01, 0x37, 0x03, 0x24, 0x14, 0x01, 0x18, 0x26, 0x04, 0x80, 0x22, 0x81, 0x27, 0x26, 0x05, 0x80, 0x25, 0x4d, 0x3a, 0x37, 0x06, 0x24, 0x14, 0x01, 0x18, 0x24, 0x07, 0x01, 0x24, 0x08, 0x01, 0x30, 0x09, 0x41, 0x04, 0x9e, 0x1e, 0xc1, 0xdc, 0x20, 0x2f, 0xcc, 0x5e, 0xa2, 0x19, 0x55, 0x30, 0xdf, 0x44, 0x62, 0xce, 0xfd, 0x58, 0x20, 0xc6, 0x19, 0xa0, 0x3e, 0xb6, 0xbf, 0x24, 0x17, 0xab, 0x31, 0xd0, 0xf9, 0x9d, 0x86, 0x5d, 0x2d, 0x49, 0x1c, 0x1d, 0x63, 0xb1, 0x48, 0x32, 0x19, 0xff, 0x4a, 0x29, 0x74, 0x9d, 0xf0, 0x27, 0x09, 0xa4, 0x5c, 0xf1, 0xf0, 0xfb, 0xa1, 0xe4, 0xd5, 0xf3, 0x70, 0xaf, 0xf6, 0xb6, 0x37, 0x0a, 0x35, 0x01, 0x29, 0x01, 0x18, 0x24, 0x02, 0x60, 0x30, 0x04, 0x14, 0x58, 0x9c, 0xf6, 0x24, 0x44, 0xb0, 0x55, 0xb9, 0x33, 0xfc, 0x31, 0xe1, 0x93, 0x5d, 0x1a, 0x8d, 0xee, 0xad, 0xfe, 0xa1, 0x30, 0x05, 0x14, 0x58, 0x9c, 0xf6, 0x24, 0x44, 0xb0, 0x55, 0xb9, 0x33, 0xfc, 0x31, 0xe1, 0x93, 0x5d, 0x1a, 0x8d, 0xee, 0xad, 0xfe, 0xa1, 0x18, 0x30, 0x0b, 0x40, 0xb4, 0xed, 0xe0, 0xad, 0x05, 0xf5, 0x5d, 0x95, 0x72, 0xc9, 0x04, 0x27, 0xd6, 0x36, 0x40, 0x12, 0x67, 0x75, 0x89, 0xd2, 0xcf, 0x3e, 0x9c, 0x3f, 0x44, 0x6e, 0x8a, 0xcb, 0x97, 0xc5, 0x9a, 0x29, 0xb3, 0xfa, 0xdc, 0x5c, 0xb8, 0x7c, 0x65, 0xc2, 0x2d, 0x7d, 0xdf, 0x18, 0x87, 0x5a, 0x03, 0x8d, 0xa4, 0x21, 0x2a, 0x26, 0x37, 0xe7, 0x9d, 0xbd, 0xd2, 0x4b, 0xa4, 0x19, 0xe6, 0x3f, 0x84, 0xd1, 0x18, [1708101560.563058][12429:12429] CHIP:DMG: ] (231 bytes) [1708101560.563146][12429:12429] CHIP:DMG: }, [1708101560.563244][12429:12429] CHIP:DMG: }, [1708101560.563321][12429:12429] CHIP:DMG: [1708101560.563379][12429:12429] CHIP:DMG: ], [1708101560.563449][12429:12429] CHIP:DMG: [1708101560.563534][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101560.563584][12429:12429] CHIP:DMG: }, [1708101560.563853][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a [1708101560.563914][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101560.563970][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B [1708101560.564050][12429:12429] CHIP:ZCL: OpCreds: Received an AddTrustedRootCertificate command [1708101560.566551][12429:12429] CHIP:ZCL: OpCreds: AddTrustedRootCertificate successful. [1708101560.566668][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101560.566727][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101560.566791][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101560.566852][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101560.566957][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101560.567220][12429:12429] CHIP:EM: <<< [E:47763r S:62109 M:138661803] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101560.567868][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101560.567970][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101560.657125][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101560.703981][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101560.705310][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101560.805087][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101560.807453][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101560.900694][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101560.902362][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101560.902951][12429:12429] CHIP:EM: >>> [E:47764r S:62109 M:156344660] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101560.903058][12429:12429] CHIP:EM: Handling via exchange: 47764r, Delegate: 0xaaaae97c1968 [1708101560.903200][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101560.903258][12429:12429] CHIP:DMG: { [1708101560.903311][12429:12429] CHIP:DMG: suppressResponse = false, [1708101560.903372][12429:12429] CHIP:DMG: timedRequest = false, [1708101560.903453][12429:12429] CHIP:DMG: InvokeRequests = [1708101560.903535][12429:12429] CHIP:DMG: [ [1708101560.903593][12429:12429] CHIP:DMG: CommandDataIB = [1708101560.903681][12429:12429] CHIP:DMG: { [1708101560.903740][12429:12429] CHIP:DMG: CommandPathIB = [1708101560.903849][12429:12429] CHIP:DMG: { [1708101560.903922][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101560.904028][12429:12429] CHIP:DMG: ClusterId = 0x3e, [1708101560.904132][12429:12429] CHIP:DMG: CommandId = 0x6, [1708101560.904213][12429:12429] CHIP:DMG: }, [1708101560.904307][12429:12429] CHIP:DMG: [1708101560.904371][12429:12429] CHIP:DMG: CommandFields = [1708101560.904460][12429:12429] CHIP:DMG: { [1708101560.904532][12429:12429] CHIP:DMG: 0x0 = [ [1708101560.904812][12429:12429] CHIP:DMG: 0x15, 0x30, 0x01, 0x01, 0x01, 0x24, 0x02, 0x01, 0x37, 0x03, 0x24, 0x13, 0x02, 0x18, 0x26, 0x04, 0x80, 0x22, 0x81, 0x27, 0x26, 0x05, 0x80, 0x25, 0x4d, 0x3a, 0x37, 0x06, 0x24, 0x15, 0x01, 0x24, 0x11, 0x50, 0x18, 0x24, 0x07, 0x01, 0x24, 0x08, 0x01, 0x30, 0x09, 0x41, 0x04, 0x19, 0x90, 0xaa, 0x23, 0xa6, 0x93, 0x76, 0x55, 0xc1, 0x7a, 0x48, 0x5e, 0x70, 0xe8, 0x20, 0x38, 0x1c, 0x26, 0xa1, 0xaa, 0x39, 0x1d, 0x62, 0x74, 0x50, 0x91, 0x03, 0x3f, 0xff, 0x5e, 0x22, 0x27, 0x60, 0x8d, 0x93, 0x78, 0x1a, 0x5a, 0x5d, 0x2b, 0x05, 0x1d, 0x47, 0x0d, 0xf2, 0x46, 0x50, 0xb8, 0xaf, 0xa7, 0x8b, 0xe2, 0x11, 0x45, 0xed, 0x60, 0x77, 0x8a, 0x8f, 0xaf, 0xb7, 0xe5, 0x78, 0xa2, 0x37, 0x0a, 0x35, 0x01, 0x28, 0x01, 0x18, 0x24, 0x02, 0x01, 0x36, 0x03, 0x04, 0x02, 0x04, 0x01, 0x18, 0x30, 0x04, 0x14, 0x71, 0x22, 0xd0, 0xb6, 0xb0, 0xf9, 0xe1, 0xa2, 0xb8, 0x4d, 0x2a, 0x1b, 0x44, 0xef, 0xf7, 0xfa, 0x55, 0x7f, 0xbd, 0x70, 0x30, 0x05, 0x14, 0x77, 0x55, 0xfa, 0x6d, 0x37, 0xd4, 0xf3, 0x2a, 0xbf, 0xf5, 0xc3, 0xbc, 0xc9, 0x2c, 0x31, 0xee, 0x33, 0x9a, 0x37, 0xc0, 0x18, 0x30, 0x0b, 0x40, 0x9e, 0x96, 0xcd, 0x84, 0xfb, 0xee, 0x19, 0x58, 0xd0, 0x79, 0x6f, 0x7d, 0xe1, 0x7d, 0x4d, 0x17, 0x6e, 0x86, 0x87, 0xea, 0xd1, 0xbb, 0xfc, 0x6f, 0x53, 0x7e, 0xc1, 0x8d, 0xf0, 0xf5, 0x39, 0x12, 0x58, 0x64, 0x85, 0x4d, 0x53, 0x00, 0xf2, 0xd5, 0x67, 0xfb, 0xff, 0x40, 0xe3, 0xd7, 0x4b, 0x99, 0xef, 0x27, 0x7e, 0x0d, 0xe0, 0xef, 0x0c, 0xed, 0x2e, 0x60, 0xaa, 0x59, 0xe3, 0x23, 0x7d, 0x35, 0x18, [1708101560.905003][12429:12429] CHIP:DMG: ] (241 bytes) [1708101560.905088][12429:12429] CHIP:DMG: 0x1 = [ [1708101560.905384][12429:12429] CHIP:DMG: 0x15, 0x30, 0x01, 0x01, 0x01, 0x24, 0x02, 0x01, 0x37, 0x03, 0x24, 0x14, 0x01, 0x18, 0x26, 0x04, 0x80, 0x22, 0x81, 0x27, 0x26, 0x05, 0x80, 0x25, 0x4d, 0x3a, 0x37, 0x06, 0x24, 0x13, 0x02, 0x18, 0x24, 0x07, 0x01, 0x24, 0x08, 0x01, 0x30, 0x09, 0x41, 0x04, 0x95, 0x74, 0x58, 0x1e, 0x93, 0x15, 0xf4, 0x6f, 0x61, 0x3b, 0x7a, 0x05, 0x53, 0x77, 0x5e, 0x17, 0x2b, 0xec, 0x90, 0x4c, 0xe1, 0x8b, 0x53, 0x83, 0x82, 0x7c, 0xff, 0xcf, 0xf6, 0x8f, 0xc4, 0x70, 0xe6, 0x34, 0xdd, 0x47, 0xb0, 0x5a, 0xd9, 0x04, 0x8f, 0x28, 0x06, 0xc2, 0x38, 0xb3, 0xf5, 0xda, 0x08, 0x69, 0xd9, 0x78, 0x62, 0xea, 0xc0, 0x01, 0x35, 0x72, 0x97, 0xab, 0xd7, 0x57, 0x1a, 0x78, 0x37, 0x0a, 0x35, 0x01, 0x29, 0x01, 0x18, 0x24, 0x02, 0x60, 0x30, 0x04, 0x14, 0x77, 0x55, 0xfa, 0x6d, 0x37, 0xd4, 0xf3, 0x2a, 0xbf, 0xf5, 0xc3, 0xbc, 0xc9, 0x2c, 0x31, 0xee, 0x33, 0x9a, 0x37, 0xc0, 0x30, 0x05, 0x14, 0x58, 0x9c, 0xf6, 0x24, 0x44, 0xb0, 0x55, 0xb9, 0x33, 0xfc, 0x31, 0xe1, 0x93, 0x5d, 0x1a, 0x8d, 0xee, 0xad, 0xfe, 0xa1, 0x18, 0x30, 0x0b, 0x40, 0xc6, 0xc1, 0x8b, 0x54, 0x0a, 0x6e, 0xa6, 0x8a, 0x1e, 0xdd, 0x58, 0x2b, 0x29, 0xe8, 0xf9, 0xee, 0x24, 0x1b, 0xfd, 0xac, 0xe5, 0xbe, 0x9f, 0xd4, 0xd3, 0xaa, 0x3f, 0xe9, 0x73, 0xb4, 0x2c, 0x01, 0xe8, 0x69, 0xea, 0x7f, 0x25, 0x80, 0x39, 0x93, 0xb4, 0x3f, 0x4a, 0xa7, 0x46, 0x0b, 0x13, 0x61, 0x2d, 0x6b, 0xcf, 0xe8, 0x4a, 0x12, 0x40, 0xc0, 0x48, 0x46, 0x8b, 0xc3, 0x0d, 0xc1, 0x50, 0xa2, 0x18, [1708101560.905572][12429:12429] CHIP:DMG: ] (231 bytes) [1708101560.905710][12429:12429] CHIP:DMG: 0x2 = [ [1708101560.905807][12429:12429] CHIP:DMG: 0x74, 0x65, 0x6d, 0x70, 0x6f, 0x72, 0x61, 0x72, 0x79, 0x20, 0x69, 0x70, 0x6b, 0x20, 0x30, 0x31, [1708101560.905940][12429:12429] CHIP:DMG: ] (16 bytes) [1708101560.906026][12429:12429] CHIP:DMG: 0x3 = 112233, [1708101560.906131][12429:12429] CHIP:DMG: 0x4 = 65521, [1708101560.906216][12429:12429] CHIP:DMG: }, [1708101560.906316][12429:12429] CHIP:DMG: }, [1708101560.906451][12429:12429] CHIP:DMG: [1708101560.906538][12429:12429] CHIP:DMG: ], [1708101560.906618][12429:12429] CHIP:DMG: [1708101560.906698][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101560.906754][12429:12429] CHIP:DMG: }, [1708101560.907111][12429:12429] CHIP:DMG: AccessControl: checking f=0 a=p s=0xFFFFFFFB00000000 t= c=0x0000_003E e=0 p=a [1708101560.907198][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101560.907261][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0006 [1708101560.907369][12429:12429] CHIP:ZCL: OpCreds: Received an AddNOC command [1708101560.907962][12429:12429] CHIP:FP: Validating NOC chain [1708101560.912758][12429:12429] CHIP:FP: NOC chain validation successful [1708101560.912860][12429:12429] CHIP:FP: Added new fabric at index: 0x1 [1708101560.912911][12429:12429] CHIP:FP: Assigned compressed fabric ID: 0x40158D25F58F891F, node ID: 0x0000000000000050 [1708101560.912973][12429:12429] CHIP:TS: Last Known Good Time: 2023-10-14T01:16:48 [1708101560.913027][12429:12429] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1708101560.913076][12429:12429] CHIP:TS: Retaining current Last Known Good Time [1708101560.913140][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_003E update version to f8695b27 [1708101560.913228][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_003E update version to f8695b28 [1708101560.913976][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-dej4qw) [1708101560.915093][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101560.915451][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-f0yrR2) [1708101560.916372][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101560.916641][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-NpxRm3) [1708101560.917555][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101560.917662][12429:12429] CHIP:DMG: AccessControl: validating f=1 p=a a=c s=1 t=0 [1708101560.917692][12429:12429] CHIP:DMG: validating subject 0x000000000001B669 [1708101560.917926][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-0IPIUW) [1708101560.918938][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101560.919111][12429:12429] CHIP:EVL: LogEvent event number: 0x0000000000000004 priority: 1, endpoint id: 0x0 cluster id: 0x0000_001F event id: 0x0 Epoch timestamp: 0x0000018DB2C96A57 [1708101560.919166][12429:12429] CHIP:ZCL: OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x000000000001B669 [1708101560.919671][12429:12429] CHIP:DL: Using wifi MAC for hostname [1708101560.919714][12429:12429] CHIP:DIS: Advertise operational node 40158D25F58F891F-0000000000000050 [1708101560.919789][12429:12429] CHIP:DL: PublishService 40158D25F58F891F-0000000000000050._matter._tcp5540.-1 [1708101560.921641][12429:12429] CHIP:DL: Using addresses from interface id=0 name= [1708101560.927519][12429:12429] CHIP:DIS: mDNS service published: _matter._tcp; instance name: 40158D25F58F891F-0000000000000050 [1708101560.927577][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_003E update version to f8695b29 [1708101560.927605][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_003E update version to f8695b2a [1708101560.927658][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101560.927684][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101560.927713][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101560.927745][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101560.927768][12429:12429] CHIP:ZCL: OpCreds: successfully created fabric index 0x1 via AddNOC [1708101560.927851][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101560.928014][12429:12429] CHIP:EM: <<< [E:47764r S:62109 M:138661804] (S) Msg TX to 1:FFFFFFFB00000000 [891F] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101560.928357][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101560.928392][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101560.998565][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101560.999300][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101561.000105][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101561.000528][12429:12429] CHIP:EM: >>> [E:47765r S:62109 M:156344661] (S) Msg RX from 1:FFFFFFFB00000000 [891F] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101561.000620][12429:12429] CHIP:EM: Handling via exchange: 47765r, Delegate: 0xaaaae97c1968 [1708101561.000737][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101561.000788][12429:12429] CHIP:DMG: { [1708101561.000833][12429:12429] CHIP:DMG: suppressResponse = false, [1708101561.000885][12429:12429] CHIP:DMG: timedRequest = false, [1708101561.000935][12429:12429] CHIP:DMG: InvokeRequests = [1708101561.001056][12429:12429] CHIP:DMG: [ [1708101561.001109][12429:12429] CHIP:DMG: CommandDataIB = [1708101561.001166][12429:12429] CHIP:DMG: { [1708101561.001217][12429:12429] CHIP:DMG: CommandPathIB = [1708101561.001276][12429:12429] CHIP:DMG: { [1708101561.001335][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101561.001397][12429:12429] CHIP:DMG: ClusterId = 0x31, [1708101561.001487][12429:12429] CHIP:DMG: CommandId = 0x2, [1708101561.001553][12429:12429] CHIP:DMG: }, [1708101561.001617][12429:12429] CHIP:DMG: [1708101561.001672][12429:12429] CHIP:DMG: CommandFields = [1708101561.001731][12429:12429] CHIP:DMG: { [1708101561.001786][12429:12429] CHIP:DMG: 0x0 = [ [1708101561.001846][12429:12429] CHIP:DMG: 0x50, 0x61, 0x72, 0x74, 0x79, 0x67, 0x75, 0x79, 0x73, [1708101561.001911][12429:12429] CHIP:DMG: ] (9 bytes) [1708101561.001967][12429:12429] CHIP:DMG: 0x1 = [ [1708101561.002028][12429:12429] CHIP:DMG: 0x49, 0x6e, 0x76, 0x61, 0x6c, 0x69, 0x64, 0x24, 0x31, 0x32, 0x33, [1708101561.002092][12429:12429] CHIP:DMG: ] (11 bytes) [1708101561.002151][12429:12429] CHIP:DMG: 0x2 = 23, [1708101561.002208][12429:12429] CHIP:DMG: }, [1708101561.002258][12429:12429] CHIP:DMG: }, [1708101561.002317][12429:12429] CHIP:DMG: [1708101561.002361][12429:12429] CHIP:DMG: ], [1708101561.002494][12429:12429] CHIP:DMG: [1708101561.002544][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101561.002609][12429:12429] CHIP:DMG: }, [1708101561.002860][12429:12429] CHIP:DMG: AccessControl: checking f=1 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0031 e=0 p=a [1708101561.002921][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101561.002975][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0002 [1708101561.003112][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101561.003167][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101561.003218][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101561.003277][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101561.003339][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0030 update version to 5bde7303 [1708101561.003426][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0031 update version to 976ac3a0 [1708101561.003516][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101561.003714][12429:12429] CHIP:EM: <<< [E:47765r S:62109 M:138661805] (S) Msg TX to 1:FFFFFFFB00000000 [891F] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101561.004252][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101561.004311][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101561.096027][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101561.096657][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101561.097700][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101561.098529][12429:12429] CHIP:EM: >>> [E:47766r S:62109 M:156344662] (S) Msg RX from 1:FFFFFFFB00000000 [891F] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101561.098634][12429:12429] CHIP:EM: Handling via exchange: 47766r, Delegate: 0xaaaae97c1968 [1708101561.098765][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101561.098824][12429:12429] CHIP:DMG: { [1708101561.098875][12429:12429] CHIP:DMG: suppressResponse = false, [1708101561.098937][12429:12429] CHIP:DMG: timedRequest = false, [1708101561.099004][12429:12429] CHIP:DMG: InvokeRequests = [1708101561.099092][12429:12429] CHIP:DMG: [ [1708101561.099150][12429:12429] CHIP:DMG: CommandDataIB = [1708101561.099215][12429:12429] CHIP:DMG: { [1708101561.099274][12429:12429] CHIP:DMG: CommandPathIB = [1708101561.099342][12429:12429] CHIP:DMG: { [1708101561.099418][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101561.099487][12429:12429] CHIP:DMG: ClusterId = 0x30, [1708101561.099554][12429:12429] CHIP:DMG: CommandId = 0x0, [1708101561.099615][12429:12429] CHIP:DMG: }, [1708101561.099681][12429:12429] CHIP:DMG: [1708101561.099737][12429:12429] CHIP:DMG: CommandFields = [1708101561.099798][12429:12429] CHIP:DMG: { [1708101561.099864][12429:12429] CHIP:DMG: 0x0 = 69, [1708101561.099931][12429:12429] CHIP:DMG: 0x1 = 25, [1708101561.099997][12429:12429] CHIP:DMG: }, [1708101561.100054][12429:12429] CHIP:DMG: }, [1708101561.100122][12429:12429] CHIP:DMG: [1708101561.100252][12429:12429] CHIP:DMG: ], [1708101561.100333][12429:12429] CHIP:DMG: [1708101561.100391][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101561.100446][12429:12429] CHIP:DMG: }, [1708101561.100711][12429:12429] CHIP:DMG: AccessControl: checking f=1 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0030 e=0 p=a [1708101561.100779][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101561.100851][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0000 [1708101561.100939][12429:12429] CHIP:FS: GeneralCommissioning: Received ArmFailSafe (69s) [1708101561.101071][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0030 update version to 5bde7304 [1708101561.101168][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101561.101223][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101561.101302][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101561.101368][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101561.101461][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101561.101681][12429:12429] CHIP:EM: <<< [E:47766r S:62109 M:138661806] (S) Msg TX to 1:FFFFFFFB00000000 [891F] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101561.102210][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101561.102276][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101561.193603][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101561.194039][12429:12430] CHIP:DL: C1 WriteHandlerCallback MTU: 517 [1708101561.194836][12429:12430] CHIP:BLE: Write request received debug 0xffffa4008870 [1708101561.195256][12429:12429] CHIP:EM: >>> [E:47767r S:62109 M:156344663] (S) Msg RX from 1:FFFFFFFB00000000 [891F] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101561.195351][12429:12429] CHIP:EM: Handling via exchange: 47767r, Delegate: 0xaaaae97c1968 [1708101561.195523][12429:12429] CHIP:DMG: InvokeRequestMessage = [1708101561.195579][12429:12429] CHIP:DMG: { [1708101561.195624][12429:12429] CHIP:DMG: suppressResponse = false, [1708101561.195677][12429:12429] CHIP:DMG: timedRequest = false, [1708101561.195727][12429:12429] CHIP:DMG: InvokeRequests = [1708101561.195790][12429:12429] CHIP:DMG: [ [1708101561.195849][12429:12429] CHIP:DMG: CommandDataIB = [1708101561.195951][12429:12429] CHIP:DMG: { [1708101561.196006][12429:12429] CHIP:DMG: CommandPathIB = [1708101561.196095][12429:12429] CHIP:DMG: { [1708101561.196209][12429:12429] CHIP:DMG: EndpointId = 0x0, [1708101561.196276][12429:12429] CHIP:DMG: ClusterId = 0x31, [1708101561.196339][12429:12429] CHIP:DMG: CommandId = 0x6, [1708101561.196438][12429:12429] CHIP:DMG: }, [1708101561.196508][12429:12429] CHIP:DMG: [1708101561.196565][12429:12429] CHIP:DMG: CommandFields = [1708101561.196623][12429:12429] CHIP:DMG: { [1708101561.196758][12429:12429] CHIP:DMG: 0x0 = [ [1708101561.196825][12429:12429] CHIP:DMG: 0x50, 0x61, 0x72, 0x74, 0x79, 0x67, 0x75, 0x79, 0x73, [1708101561.196945][12429:12429] CHIP:DMG: ] (9 bytes) [1708101561.197053][12429:12429] CHIP:DMG: 0x1 = 27, [1708101561.197121][12429:12429] CHIP:DMG: }, [1708101561.197215][12429:12429] CHIP:DMG: }, [1708101561.197284][12429:12429] CHIP:DMG: [1708101561.197373][12429:12429] CHIP:DMG: ], [1708101561.197443][12429:12429] CHIP:DMG: [1708101561.197491][12429:12429] CHIP:DMG: InteractionModelRevision = 11 [1708101561.197578][12429:12429] CHIP:DMG: }, [1708101561.197901][12429:12429] CHIP:DMG: AccessControl: checking f=1 a=p s=0xFFFFFFFB00000000 t= c=0x0000_0031 e=0 p=a [1708101561.197998][12429:12429] CHIP:DMG: AccessControl: implicit admin (PASE) [1708101561.198056][12429:12429] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0006 [1708101561.198184][12429:12429] CHIP:NP: LinuxWiFiDriver: ConnectNetwork 'Partyguys' [1708101561.198253][12429:12429] CHIP:NP: Failed to connect to WiFi network: src/platform/Linux/ConnectivityManagerImpl.cpp:1106: CHIP Error 0x00000003: Incorrect state [1708101561.198309][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0031 update version to 976ac3a1 [1708101561.198360][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0031 update version to 976ac3a2 [1708101561.198472][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0031 update version to 976ac3a3 [1708101561.198598][12429:12429] CHIP:DMG: Command handler moving to [NewRespons] [1708101561.198649][12429:12429] CHIP:DMG: Command handler moving to [ Preparing] [1708101561.198745][12429:12429] CHIP:DMG: Command handler moving to [AddingComm] [1708101561.198807][12429:12429] CHIP:DMG: Command handler moving to [AddedComma] [1708101561.198853][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 1 [1708101561.198973][12429:12429] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0 [1708101561.199305][12429:12429] CHIP:EM: <<< [E:47767r S:62109 M:138661807] (S) Msg TX to 1:FFFFFFFB00000000 [891F] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101561.199824][12429:12429] CHIP:DMG: Command response sender moving to [AllInvokeR] [1708101561.199924][12429:12429] CHIP:DMG: Command handler moving to [AwaitingDe] [1708101561.291043][12429:12430] CHIP:BLE: Indication confirmation, 0xffffa4008870 [1708101561.682888][12429:12429] CHIP:DL: Avahi group established [1708101563.973537][12429:12430] CHIP:DL: Bluez disconnected [1708101563.973677][12429:12430] CHIP:DL: Bluez notify CHIPoBluez connection disconnected [1708101563.973929][12429:12429] CHIP:IN: Clearing BLE pending packets. [1708101630.167455][12429:12429] CHIP:FS: Fail-safe timer expired [1708101630.167576][12429:12429] CHIP:DL: HandlePlatformSpecificBLEEvent 32784 [1708101630.167638][12429:12429] CHIP:SVR: Failsafe timer expired [1708101630.167689][12429:12429] CHIP:IN: SecureSession[0xaaab16edaa50]: MarkForEviction Type:1 LSID:62109 [1708101630.167744][12429:12429] CHIP:SC: SecureSession[0xaaab16edaa50, LSID:62109]: State change 'kActive' --> 'kPendingEviction' [1708101630.167808][12429:12429] CHIP:IN: SecureSession[0xaaab16edaa50]: Released - Type:1 LSID:62109 [1708101630.167889][12429:12429] CHIP:SVR: Commissioning failed (attempt 1): src/app/server/CommissioningWindowManager.cpp:82: CHIP Error 0x00000032: Timeout [1708101630.168067][12429:12429] CHIP:IN: SecureSession[0xaaab16edaa50]: Allocated Type:1 LSID:62111 [1708101630.168143][12429:12429] CHIP:SC: Assigned local session key ID 62111 [1708101630.168200][12429:12429] CHIP:SC: Waiting for PBKDF param request [1708101630.168288][12429:12429] CHIP:DIS: Updating services using commissioning mode 1 [1708101630.171652][12429:12429] CHIP:DL: Using wifi MAC for hostname [1708101630.171763][12429:12429] CHIP:DIS: Advertise operational node 40158D25F58F891F-0000000000000050 [1708101630.171906][12429:12429] CHIP:DL: PublishService 40158D25F58F891F-0000000000000050._matter._tcp5540.-1 [1708101630.175117][12429:12429] CHIP:DL: Using addresses from interface id=0 name= [1708101630.185094][12429:12429] CHIP:DIS: mDNS service published: _matter._tcp; instance name: 40158D25F58F891F-0000000000000050 [1708101630.185712][12429:12429] CHIP:DL: Using wifi MAC for hostname [1708101630.185835][12429:12429] CHIP:DIS: Advertise commission parameter vendorID=65521 productID=32769 discriminator=2222/08 cm=1 cp=0 [1708101630.185949][12429:12429] CHIP:DL: PublishService 5B5CAF568CB954F7._matterc._udp5540.-1 [1708101630.187788][12429:12429] CHIP:DL: Using addresses from interface id=0 name= [1708101630.197921][12429:12429] CHIP:DIS: mDNS service published: _matterc._udp; instance name: 5B5CAF568CB954F7 [1708101630.197985][12429:12429] CHIP:ZCL: Failsafe timeout, tell platform driver to revert network credentials. [1708101630.198021][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0031 update version to 976ac3a4 [1708101630.198053][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0031 update version to 976ac3a5 [1708101630.198081][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0031 update version to 976ac3a6 [1708101630.198108][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0031 update version to 976ac3a7 [1708101630.198135][12429:12429] CHIP:ZCL: OpCreds: Got FailSafeTimerExpired [1708101630.198157][12429:12429] CHIP:ZCL: OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry! [1708101630.198179][12429:12429] CHIP:IN: Expiring all sessions for fabric 0x1!! [1708101630.198229][12429:12429] CHIP:FP: Reverting pending fabric data for fabric 0x1 [1708101630.198329][12429:12429] CHIP:EVL: LogEvent event number: 0x0000000000000005 priority: 1, endpoint id: 0x0 cluster id: 0x0000_0028 event id: 0x2 Epoch timestamp: 0x0000018DB2CA78F6 [1708101630.198381][12429:12429] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1708101630.198463][12429:12429] CHIP:FP: Warning: metadata not found during delete of fabric 0x1 [1708101630.198887][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-XIZ5ca) [1708101630.200072][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101630.200167][12429:12429] CHIP:FP: Fabric (0x1) deleted. [1708101630.200203][12429:12429] CHIP:ZCL: UserLabel: Last Fabric index 0x1 was removed [1708101630.200357][12429:12429] CHIP:ZCL: OpCreds: Fabric index 0x1 was removed [1708101630.200397][12429:12429] CHIP:DIS: Updating services using commissioning mode 1 [1708101630.202929][12429:12429] CHIP:DL: Using wifi MAC for hostname [1708101630.203065][12429:12429] CHIP:DIS: Advertise commission parameter vendorID=65521 productID=32769 discriminator=2222/08 cm=1 cp=0 [1708101630.203157][12429:12429] CHIP:DL: PublishService 5B5CAF568CB954F7._matterc._udp5540.-1 [1708101630.204745][12429:12429] CHIP:DL: Using addresses from interface id=0 name= [1708101630.214430][12429:12429] CHIP:DIS: mDNS service published: _matterc._udp; instance name: 5B5CAF568CB954F7 [1708101630.214670][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_003E update version to f8695b2b [1708101630.214707][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_003E update version to f8695b2c [1708101630.215994][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-jg9QTc) [1708101630.217181][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101630.218430][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-VXR3Ed) [1708101630.219577][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101630.220089][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-F59Qju) [1708101630.221185][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101630.221726][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-QFHYvB) [1708101630.222846][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101630.223110][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-koIilB) [1708101630.224239][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101630.224580][12429:12429] CHIP:DL: writing settings to file (/tmp/chip_kvs-ZBMZqt) [1708101630.225652][12429:12429] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1708101630.225792][12429:12429] CHIP:TS: Pending Last Known Good Time: 2023-10-14T01:16:48 [1708101630.226012][12429:12429] CHIP:TS: Previous Last Known Good Time: 2023-10-14T01:16:48 [1708101630.226049][12429:12429] CHIP:TS: Reverted Last Known Good Time to previous value [1708101630.226144][12429:12429] CHIP:EVL: LogEvent event number: 0x0000000000000006 priority: 1, endpoint id: 0x0 cluster id: 0x0000_0028 event id: 0x2 Epoch timestamp: 0x0000018DB2CA7912 [1708101630.226192][12429:12429] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1708101630.226235][12429:12429] CHIP:FP: Warning: metadata not found during delete of fabric 0x1 [1708101630.226334][12429:12429] CHIP:ZCL: OpCreds: failed to delete fabric at index 1: src/credentials/FabricTable.cpp:991: CHIP Error 0x000000D8: The item referenced in the function call was not found [1708101630.226380][12429:12429] CHIP:DMG: Endpoint 0, Cluster 0x0000_0030 update version to 5bde7305 [1708101630.226466][12429:12429] CHIP:FS: Fail-safe cleanly disarmed [1708101630.226646][12429:12429] CHIP:DL: SET service data to {'0xFFF6': <[byte 0x00, 0xae, 0x08, 0xf1, 0xff, 0x01, 0x80, 0x00]>} [1708101630.237904][12429:12430] CHIP:DL: RegisterAdvertisement complete [1708101630.238054][12429:12429] CHIP:DL: HandlePlatformSpecificBLEEvent 16393 [1708101630.975037][12429:12429] CHIP:DL: Avahi group established [1708101660.267571][12429:12429] CHIP:DL: bleAdv Timeout : Start slow advertisement [1708101660.267949][12429:12429] CHIP:DL: SET service data to {'0xFFF6': <[byte 0x00, 0xae, 0x08, 0xf1, 0xff, 0x01, 0x80, 0x00]>}