espressif / esp-matter

Espressif's SDK for Matter
Apache License 2.0
686 stars 155 forks source link

Accessories list in the Matter Bridge Apple/Google Home app is visible the empty. (CON-1379) #1120

Closed halilakpinarr closed 1 week ago

halilakpinarr commented 1 week ago

Bug Description

We are developing a Matter Bridge. We are using ESP32 as Telemetry Ethernet. (BLE: disabled, WiFi: disabled) Our problem is; when the bridge application is erased and flashed for the first time, we can create endpoints using the "app_bridge_create_bridged_device" API as in the Zigbee Bridge Example. After the first flashing, when we pair our bridge device with Apple Home, the accessory list is visible. After the first flashing, when we pair the mobile application and bridge device with Apple Home without pairing it with "idf.py monitor" or by cutting the power and returning it, the accessory list is visible the empty in the mobile application. After pairing while the accessory list is empty, we can add new dynamic endpoints with the same API "app_bridge_create_bridged_device". In this case, for example, while 10 endpoints are actually added in the mobile application, only the last endpoint we added is visible in the mobile application.

When we first flashed frimware to the device, for example, we added 10 dynamic points and then commissioned by apple home app, saw 10 accessories in the mobile application. Then, we deleted the fabric by saying "remove device" from the Apple Home application. Then, when we put the same device back into basic commissioning mode and paired it from the mobile application, although 10 endpoints were still added, only the bridge is visible in the mobile application. Again, if I add new accessories, I can only see the ones I added while they were already paired in the mobile application.

By the way, CONFIG_ESP_MATTER_ENABLE_DATA_MODEL and CONFIG_ESP_MATTER_ENABLE_MATTER_SERVER options are enabled.

Matter endpoints should normally be in the aggregator "PartsList" section and in the "PartsList" of the Root Node. Espressif "attribute::create_parts_list(cluster, NULL, 0, 0);" has a comment line in "esp_matter_cluster.cpp" indicating that this is managed internally. I think this may be the reason why we can't see the endpoints in the mobile application despite them being created.

By the way, after installing firmware on the device, we do not do anything via mfg_tool or chip-tool. Do we need to set anything?

We are waiting for your support or suggestions for a solution.

Environment

jonsmirl commented 1 week ago

Commission your bridge using chip-tool. Add the 10 accessories. Use chip-tool to verify they are there. Use chip-tool to remove the fabric. Put your accessories back. Finally use chip-tool to verify if the parts-lists are right. Using chip-tool to go through all of the same steps will let you see what is going on.

Esp code should automatically populate the parts-lists. But... the remove fabric step could be breaking that behavior (which would be bug). Another test would be to reboot the bridge right after you remove the fabric and see if that has any impact on Apple Home.

You need to narrow down whether the problem is in the bridge or Apple Home. chip-tool will let you do that.

It is also a known fact that both Google and Apple will mess up if you combine fixed device endpoints (root/aggregator are not device endpoints) with bridged ones on the same device. The spec allows this but Google/Apple don't have it implemented correctly. The symptom you will see is lots of disappearing endpoints.

Arbitrarily composed composite devices don't work on Google/Apple either. For example if you put a light and a temp sensor together in a composed device it won't work right on Google/Apple even though they support both the light and temp sensor when not composed.

halilakpinarr commented 1 week ago

Commission your bridge using chip-tool. Add the 10 accessories. Use chip-tool to verify they are there. Use chip-tool to remove the fabric. Put your accessories back. Finally use chip-tool to verify if the parts-lists are right. Using chip-tool to go through all of the same steps will let you see what is going on.

Esp code should automatically populate the parts-lists. But... the remove fabric step could be breaking that behavior (which would be bug). Another test would be to reboot the bridge right after you remove the fabric and see if that has any impact on Apple Home.

You need to narrow down whether the problem is in the bridge or Apple Home. chip-tool will let you do that.

It is also a known fact that both Google and Apple will mess up if you combine fixed device endpoints (root/aggregator are not device endpoints) with bridged ones on the same device. The spec allows this but Google/Apple don't have it implemented correctly. The symptom you will see is lots of disappearing endpoints.

Arbitrarily composed composite devices don't work on Google/Apple either. For example if you put a light and a temp sensor together in a composed device it won't work right on Google/Apple even though they support both the light and temp sensor when not composed.

@jonsmirl I commissioned the device with the chip-tool, when I get the parts lists for endpoint 0(Root node) and endpoint 1(aggregrator) device types separately(chip-tool logs below), everything seems to be fine. However, it is interesting that only the bridge device is visible in mobile applications. By the way, we do not combine the device types, I add the ESP_MATTER_ON_OFF_LIGHT_DEVICE_TYPE_ID and ESP_MATTER_DIMMABLE_LIGHT_DEVICE_TYPE_ID devices in the standard SDK, I do not add any clusters on it. I do not want to accept that the problem is in the mobile application, the reason is that I have a professionally sold Matter Bridge device and it works fine with the same Apple and Google Home applications versions, they used a raspberry pi. We are missing something, but I have not found it yet. Do you have another idea?

In the picture below, the device-type-list sections of the chip-tool and root and aggregrator endpoints appear empty, this may be our problem.

Root node endpoint partslist

chip-tool descriptor read parts-list 0x7283 0x0 [1729056319.669] [10407:10407] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1729056319.670] [10407:10407] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1729056319.670] [10407:10407] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1729056319.672] [10407:10407] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1729056319.672] [10407:10407] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1729056319.672] [10407:10407] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1729056319.672] [10407:10407] [DL] wrote settings to /tmp/chip_counters.ini [1729056319.672] [10407:10407] [DL] NVS set: chip-counters/reboot-count = 8 (0x8) [1729056319.672] [10407:10407] [DL] Got Ethernet interface: enp2s0 [1729056319.672] [10407:10407] [DL] Found the primary Ethernet interface:enp2s0 [1729056319.673] [10407:10407] [DL] Got WiFi interface: wlo1 [1729056319.674] [10407:10407] [DL] Found the primary WiFi interface:wlo1 [1729056319.674] [10407:10407] [IN] UDP::Init bind&listen port=0 [1729056319.674] [10407:10407] [IN] UDP::Init bound to port=52579 [1729056319.674] [10407:10407] [IN] BLEBase::Init - setting/overriding transport [1729056319.674] [10407:10407] [IN] TransportMgr initialized [1729056319.674] [10407:10407] [FP] Initializing FabricTable from persistent storage [1729056319.674] [10407:10407] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1729056319.675] [10407:10407] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xFB874A35140CD896, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1729056319.675] [10407:10407] [ZCL] Using ZAP configuration... [1729056319.676] [10407:10407] [IM] WARNING ┌──────────────────────────────────────────────────── [1729056319.676] [10407:10407] [IM] WARNING │ Interaction Model Engine running in 'Checked' mode. [1729056319.676] [10407:10407] [IM] WARNING │ This executes BOTH ember and data-model code paths. [1729056319.676] [10407:10407] [IM] WARNING │ which is inefficient and consumes more flash space. [1729056319.676] [10407:10407] [IM] WARNING │ This should be done for testing only. [1729056319.676] [10407:10407] [IM] WARNING └──────────────────────────────────────────────────── [1729056319.676] [10407:10407] [CTL] System State Initialized... [1729056319.676] [10407:10407] [CTL] Setting attestation nonce to random value [1729056319.676] [10407:10407] [CTL] Setting CSR nonce to random value [1729056319.676] [10407:10409] [DL] CHIP task running [1729056319.676] [10407:10409] [DL] HandlePlatformSpecificBLEEvent 32786 [1729056319.676] [10407:10409] [CTL] Setting attestation nonce to random value [1729056319.676] [10407:10409] [CTL] Setting CSR nonce to random value [1729056319.676] [10407:10409] [CTL] Generating NOC [1729056319.676] [10407:10409] [FP] Validating NOC chain [1729056319.677] [10407:10409] [FP] NOC chain validation successful [1729056319.677] [10407:10409] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1729056319.677] [10407:10409] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1729056319.677] [10407:10409] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00 [1729056319.677] [10407:10409] [TS] Retaining current Last Known Good Time [1729056319.678] [10407:10409] [FP] Metadata for Fabric 0x1 persisted to storage. [1729056319.678] [10407:10409] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1729056319.678] [10407:10409] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: FB874A35140CD896) [1729056319.681] [10407:10409] [TOO] Sending command to node 0x7283 [1729056319.682] [10407:10409] [CSM] FindOrEstablishSession: PeerId = [1:0000000000007283] [1729056319.682] [10407:10409] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1729056319.682] [10407:10409] [DIS] OperationalSessionSetup[1:0000000000007283]: State change 1 --> 2 [1729056319.682] [10407:10409] [DIS] Lookup started for FB874A35140CD896-0000000000007283 [1729056319.882] [10407:10409] [DIS] Checking node lookup status for FB874A35140CD896-0000000000007283 after 200 ms [1729056320.539] [10407:10409] [DIS] UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540: new best score: 7 (for FB874A35140CD896-0000000000007283) [1729056320.540] [10407:10409] [DIS] Lookup clearing interface for non LL address [1729056320.540] [10407:10409] [DIS] UDP:[fd77:53ba:dfa5:b64b:e665:b8ff:fe7e:fbef%wlo1]:5540: new best score: 5 (for FB874A35140CD896-0000000000007283) [1729056320.540] [10407:10409] [DIS] Checking node lookup status for FB874A35140CD896-0000000000007283 after 858 ms [1729056320.540] [10407:10409] [DIS] OperationalSessionSetup[1:0000000000007283]: Updating device address to UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540 while in state 2 [1729056320.540] [10407:10409] [DIS] OperationalSessionSetup[1:0000000000007283]: State change 2 --> 3 [1729056320.540] [10407:10409] [IN] SecureSession[0x7f2ddc00bd60]: Allocated Type:2 LSID:43768 [1729056320.540] [10407:10409] [SC] Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000007283 [1729056320.540] [10407:10409] [EM] <<< [E:52010i S:0 M:182358479] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) [1729056320.540] [10407:10409] [SC] Sent Sigma1 msg to <0000000000007283, 1> [1729056320.540] [10407:10409] [DIS] OperationalSessionSetup[1:0000000000007283]: State change 3 --> 4 [1729056320.552] [10407:10409] [EM] >>> [E:52010i S:0 M:31262493 (Ack:182358479)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1729056320.552] [10407:10409] [EM] Found matching exchange: 52010i, Delegate: 0x7f2ddc00ba78 [1729056320.552] [10407:10409] [EM] Rxd Ack; Removing MessageCounter:182358479 from Retrans Table on exchange 52010i [1729056320.943] [10407:10409] [EM] >>> [E:52010i S:0 M:31262494 (Ack:182358479)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:752) [1729056320.943] [10407:10409] [EM] Found matching exchange: 52010i, Delegate: 0x7f2ddc00ba78 [1729056320.943] [10407:10409] [EM] CHIP MessageCounter:182358479 not in RetransTable on exchange 52010i [1729056320.943] [10407:10409] [SC] Received Sigma2 msg [1729056320.943] [10407:10409] [SC] Peer assigned session session ID 5412 [1729056320.943] [10407:10409] [SC] Found MRP parameters in the message [1729056320.943] [10407:10409] [SC] Sending Sigma3 [1729056320.944] [10407:10409] [EM] <<< [E:52010i S:0 M:182358480 (Ack:31262494)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598) [1729056320.944] [10407:10409] [SC] Sent Sigma3 msg [1729056320.955] [10407:10409] [EM] >>> [E:52010i S:0 M:31262495 (Ack:182358480)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1729056320.955] [10407:10409] [EM] Found matching exchange: 52010i, Delegate: 0x7f2ddc00ba78 [1729056320.955] [10407:10409] [EM] Rxd Ack; Removing MessageCounter:182358480 from Retrans Table on exchange 52010i [1729056321.659] [10407:10409] [EM] >>> [E:52010i S:0 M:31262496 (Ack:182358480)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) (B:34) [1729056321.659] [10407:10409] [EM] Found matching exchange: 52010i, Delegate: 0x7f2ddc00ba78 [1729056321.659] [10407:10409] [EM] CHIP MessageCounter:182358480 not in RetransTable on exchange 52010i [1729056321.659] [10407:10409] [SC] Success status report received. Session was established [1729056321.660] [10407:10409] [SC] SecureSession[0x7f2ddc00bd60, LSID:43768]: State change 'kEstablishing' --> 'kActive' [1729056321.660] [10407:10409] [IN] SecureSession[0x7f2ddc00bd60]: Activated - Type:2 LSID:43768 [1729056321.660] [10407:10409] [IN] New secure session activated for device <0000000000007283, 1>, LSID:43768 PSID:5412! [1729056321.660] [10407:10409] [DIS] OperationalSessionSetup[1:0000000000007283]: State change 4 --> 5 [1729056321.660] [10407:10409] [TOO] Sending ReadAttribute to: [1729056321.660] [10407:10409] [TOO] cluster 0x0000_001D, attribute: 0x0000_0003, endpoint 0 [1729056321.660] [10407:10409] [DMG] SendReadRequest ReadClient[0x7f2ddc00a170]: Sending Read Request [1729056321.660] [10407:10409] [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space [1729056321.660] [10407:10409] [EM] <<< [E:52011i S:43768 M:118953719] (S) Msg TX to 1:0000000000007283 [D896] [UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540] --- Type 0001:02 (IM:ReadRequest) (B:51) [1729056321.660] [10407:10409] [DMG] MoveToState ReadClient[0x7f2ddc00a170]: Moving to [AwaitingIn] [1729056321.660] [10407:10409] [EM] <<< [E:52010i S:0 M:182358481 (Ack:31262496)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1729056321.660] [10407:10409] [EM] Flushed pending ack for MessageCounter:31262496 on exchange 52010i [1729056321.660] [10407:10409] [DL] HandlePlatformSpecificBLEEvent 32792 [1729056321.681] [10407:10409] [EM] >>> [E:52011i S:43768 M:126560505 (Ack:118953719)] (S) Msg RX from 1:0000000000007283 [D896] --- Type 0001:05 (IM:ReportData) (B:80) [1729056321.681] [10407:10409] [EM] Found matching exchange: 52011i, Delegate: 0x7f2ddc00a180 [1729056321.681] [10407:10409] [EM] Rxd Ack; Removing MessageCounter:118953719 from Retrans Table on exchange 52011i [1729056321.681] [10407:10409] [DMG] ReportDataMessage = [1729056321.681] [10407:10409] [DMG] { [1729056321.681] [10407:10409] [DMG] AttributeReportIBs = [1729056321.681] [10407:10409] [DMG] [ [1729056321.681] [10407:10409] [DMG] AttributeReportIB = [1729056321.681] [10407:10409] [DMG] { [1729056321.681] [10407:10409] [DMG] AttributeDataIB = [1729056321.681] [10407:10409] [DMG] { [1729056321.681] [10407:10409] [DMG] DataVersion = 0x8656309, [1729056321.681] [10407:10409] [DMG] AttributePathIB = [1729056321.681] [10407:10409] [DMG] { [1729056321.681] [10407:10409] [DMG] Endpoint = 0x0, [1729056321.681] [10407:10409] [DMG] Cluster = 0x1d, [1729056321.681] [10407:10409] [DMG] Attribute = 0x0000_0003, [1729056321.681] [10407:10409] [DMG] } [1729056321.681] [10407:10409] [DMG]
[1729056321.681] [10407:10409] [DMG] Data = [ [1729056321.681] [10407:10409] [DMG] 1 (unsigned), 2 (unsigned), 3 (unsigned), 4 (unsigned), 5 (unsigned), [1729056321.681] [10407:10409] [DMG] ], [1729056321.681] [10407:10409] [DMG] }, [1729056321.681] [10407:10409] [DMG]
[1729056321.681] [10407:10409] [DMG] }, [1729056321.681] [10407:10409] [DMG]
[1729056321.681] [10407:10409] [DMG] ], [1729056321.681] [10407:10409] [DMG]
[1729056321.681] [10407:10409] [DMG] SuppressResponse = true, [1729056321.681] [10407:10409] [DMG] InteractionModelRevision = 11 [1729056321.681] [10407:10409] [DMG] } [1729056321.681] [10407:10409] [TOO] Endpoint: 0 Cluster: 0x0000_001D Attribute 0x0000_0003 DataVersion: 140862217 [1729056321.681] [10407:10409] [TOO] PartsList: 5 entries

[1729056321.682] [10407:10409] [EM] <<< [E:52011i S:43768 M:118953720 (Ack:126560505)] (S) Msg TX to 1:0000000000007283 [D896] [UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1729056321.682] [10407:10409] [EM] Flushed pending ack for MessageCounter:126560505 on exchange 52011i [1729056321.682] [10407:10407] [CTL] Shutting down the commissioner [1729056321.682] [10407:10407] [CTL] Shutting down the controller [1729056321.682] [10407:10407] [IN] Expiring all sessions for fabric 0x1!! [1729056321.682] [10407:10407] [IN] SecureSession[0x7f2ddc00bd60]: MarkForEviction Type:2 LSID:43768 [1729056321.682] [10407:10407] [SC] SecureSession[0x7f2ddc00bd60, LSID:43768]: State change 'kActive' --> 'kPendingEviction' [1729056321.682] [10407:10407] [IN] SecureSession[0x7f2ddc00bd60]: Released - Type:2 LSID:43768 [1729056321.682] [10407:10407] [FP] Forgetting fabric 0x1 [1729056321.682] [10407:10407] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1729056321.682] [10407:10407] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1729056321.682] [10407:10407] [TS] Reverted Last Known Good Time to previous value [1729056321.682] [10407:10407] [CTL] Shutting down the commissioner [1729056321.682] [10407:10407] [CTL] Shutting down the controller [1729056321.682] [10407:10407] [CTL] Shutting down the System State, this will teardown the CHIP Stack [1729056321.682] [10407:10407] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1729056321.682] [10407:10407] [FP] Shutting down FabricTable [1729056321.682] [10407:10407] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1729056321.682] [10407:10407] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1729056321.682] [10407:10407] [TS] Reverted Last Known Good Time to previous value [1729056321.682] [10407:10407] [DL] wrote settings to /tmp/chip_counters.ini [1729056321.682] [10407:10407] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0) [1729056321.682] [10407:10407] [DL] Inet Layer shutdown [1729056321.682] [10407:10407] [DL] BLE Layer shutdown [1729056321.682] [10407:10407] [DL] System Layer shutdown

Aggregrator endpoint partslist

chip-tool descriptor read parts-list 0x7283 0x1 [1729056329.532] [10529:10529] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1729056329.532] [10529:10529] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1729056329.532] [10529:10529] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1729056329.534] [10529:10529] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1729056329.534] [10529:10529] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1729056329.534] [10529:10529] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1729056329.534] [10529:10529] [DL] wrote settings to /tmp/chip_counters.ini [1729056329.534] [10529:10529] [DL] NVS set: chip-counters/reboot-count = 9 (0x9) [1729056329.534] [10529:10529] [DL] Got Ethernet interface: enp2s0 [1729056329.535] [10529:10529] [DL] Found the primary Ethernet interface:enp2s0 [1729056329.535] [10529:10529] [DL] Got WiFi interface: wlo1 [1729056329.536] [10529:10529] [DL] Found the primary WiFi interface:wlo1 [1729056329.536] [10529:10529] [IN] UDP::Init bind&listen port=0 [1729056329.536] [10529:10529] [IN] UDP::Init bound to port=55584 [1729056329.536] [10529:10529] [IN] BLEBase::Init - setting/overriding transport [1729056329.537] [10529:10529] [IN] TransportMgr initialized [1729056329.537] [10529:10529] [FP] Initializing FabricTable from persistent storage [1729056329.537] [10529:10529] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1729056329.537] [10529:10529] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xFB874A35140CD896, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1729056329.538] [10529:10529] [ZCL] Using ZAP configuration... [1729056329.538] [10529:10529] [IM] WARNING ┌──────────────────────────────────────────────────── [1729056329.538] [10529:10529] [IM] WARNING │ Interaction Model Engine running in 'Checked' mode. [1729056329.538] [10529:10529] [IM] WARNING │ This executes BOTH ember and data-model code paths. [1729056329.538] [10529:10529] [IM] WARNING │ which is inefficient and consumes more flash space. [1729056329.538] [10529:10529] [IM] WARNING │ This should be done for testing only. [1729056329.538] [10529:10529] [IM] WARNING └──────────────────────────────────────────────────── [1729056329.538] [10529:10529] [CTL] System State Initialized... [1729056329.538] [10529:10529] [CTL] Setting attestation nonce to random value [1729056329.538] [10529:10529] [CTL] Setting CSR nonce to random value [1729056329.539] [10529:10531] [DL] CHIP task running [1729056329.539] [10529:10531] [DL] HandlePlatformSpecificBLEEvent 32786 [1729056329.539] [10529:10531] [CTL] Setting attestation nonce to random value [1729056329.539] [10529:10531] [CTL] Setting CSR nonce to random value [1729056329.539] [10529:10531] [CTL] Generating NOC [1729056329.539] [10529:10531] [FP] Validating NOC chain [1729056329.540] [10529:10531] [FP] NOC chain validation successful [1729056329.540] [10529:10531] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1729056329.540] [10529:10531] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1729056329.540] [10529:10531] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00 [1729056329.540] [10529:10531] [TS] Retaining current Last Known Good Time [1729056329.542] [10529:10531] [FP] Metadata for Fabric 0x1 persisted to storage. [1729056329.542] [10529:10531] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1729056329.542] [10529:10531] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: FB874A35140CD896) [1729056329.547] [10529:10531] [TOO] Sending command to node 0x7283 [1729056329.548] [10529:10531] [CSM] FindOrEstablishSession: PeerId = [1:0000000000007283] [1729056329.548] [10529:10531] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1729056329.548] [10529:10531] [DIS] OperationalSessionSetup[1:0000000000007283]: State change 1 --> 2 [1729056329.548] [10529:10531] [DIS] Lookup started for FB874A35140CD896-0000000000007283 [1729056329.553] [10529:10531] [DIS] UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540: new best score: 7 (for FB874A35140CD896-0000000000007283) [1729056329.553] [10529:10531] [DIS] Lookup clearing interface for non LL address [1729056329.553] [10529:10531] [DIS] UDP:[fd77:53ba:dfa5:b64b:e665:b8ff:fe7e:fbef%wlo1]:5540: new best score: 5 (for FB874A35140CD896-0000000000007283) [1729056329.553] [10529:10531] [DIS] Checking node lookup status for FB874A35140CD896-0000000000007283 after 6 ms [1729056329.553] [10529:10531] [DIS] Keeping DNSSD lookup active [1729056329.748] [10529:10531] [DIS] Checking node lookup status for FB874A35140CD896-0000000000007283 after 200 ms [1729056329.748] [10529:10531] [DIS] OperationalSessionSetup[1:0000000000007283]: Updating device address to UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540 while in state 2 [1729056329.748] [10529:10531] [DIS] OperationalSessionSetup[1:0000000000007283]: State change 2 --> 3 [1729056329.748] [10529:10531] [IN] SecureSession[0x75a34400bd60]: Allocated Type:2 LSID:28671 [1729056329.748] [10529:10531] [SC] Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000007283 [1729056329.748] [10529:10531] [EM] <<< [E:8898i S:0 M:1360350] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) [1729056329.748] [10529:10531] [SC] Sent Sigma1 msg to <0000000000007283, 1> [1729056329.748] [10529:10531] [DIS] OperationalSessionSetup[1:0000000000007283]: State change 3 --> 4 [1729056329.759] [10529:10531] [EM] >>> [E:8898i S:0 M:31262497 (Ack:1360350)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1729056329.759] [10529:10531] [EM] Found matching exchange: 8898i, Delegate: 0x75a34400ba78 [1729056329.759] [10529:10531] [EM] Rxd Ack; Removing MessageCounter:1360350 from Retrans Table on exchange 8898i [1729056330.159] [10529:10531] [EM] >>> [E:8898i S:0 M:31262498 (Ack:1360350)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:752) [1729056330.159] [10529:10531] [EM] Found matching exchange: 8898i, Delegate: 0x75a34400ba78 [1729056330.159] [10529:10531] [EM] CHIP MessageCounter:1360350 not in RetransTable on exchange 8898i [1729056330.159] [10529:10531] [SC] Received Sigma2 msg [1729056330.159] [10529:10531] [SC] Peer assigned session session ID 5414 [1729056330.160] [10529:10531] [SC] Found MRP parameters in the message [1729056330.160] [10529:10531] [SC] Sending Sigma3 [1729056330.160] [10529:10531] [EM] <<< [E:8898i S:0 M:1360351 (Ack:31262498)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598) [1729056330.160] [10529:10531] [SC] Sent Sigma3 msg [1729056330.171] [10529:10531] [EM] >>> [E:8898i S:0 M:31262499 (Ack:1360351)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1729056330.171] [10529:10531] [EM] Found matching exchange: 8898i, Delegate: 0x75a34400ba78 [1729056330.171] [10529:10531] [EM] Rxd Ack; Removing MessageCounter:1360351 from Retrans Table on exchange 8898i [1729056330.978] [10529:10531] [EM] >>> [E:8898i S:0 M:31262500 (Ack:1360351)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) (B:34) [1729056330.978] [10529:10531] [EM] Found matching exchange: 8898i, Delegate: 0x75a34400ba78 [1729056330.978] [10529:10531] [EM] CHIP MessageCounter:1360351 not in RetransTable on exchange 8898i [1729056330.978] [10529:10531] [SC] Success status report received. Session was established [1729056330.982] [10529:10531] [SC] SecureSession[0x75a34400bd60, LSID:28671]: State change 'kEstablishing' --> 'kActive' [1729056330.982] [10529:10531] [IN] SecureSession[0x75a34400bd60]: Activated - Type:2 LSID:28671 [1729056330.982] [10529:10531] [IN] New secure session activated for device <0000000000007283, 1>, LSID:28671 PSID:5414! [1729056330.983] [10529:10531] [DIS] OperationalSessionSetup[1:0000000000007283]: State change 4 --> 5 [1729056330.983] [10529:10531] [TOO] Sending ReadAttribute to: [1729056330.983] [10529:10531] [TOO] cluster 0x0000_001D, attribute: 0x0000_0003, endpoint 1 [1729056330.983] [10529:10531] [DMG] SendReadRequest ReadClient[0x75a34400a170]: Sending Read Request [1729056330.983] [10529:10531] [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space [1729056330.983] [10529:10531] [EM] <<< [E:8899i S:28671 M:203665380] (S) Msg TX to 1:0000000000007283 [D896] [UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540] --- Type 0001:02 (IM:ReadRequest) (B:51) [1729056330.983] [10529:10531] [DMG] MoveToState ReadClient[0x75a34400a170]: Moving to [AwaitingIn] [1729056330.983] [10529:10531] [EM] <<< [E:8898i S:0 M:1360352 (Ack:31262500)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1729056330.983] [10529:10531] [EM] Flushed pending ack for MessageCounter:31262500 on exchange 8898i [1729056330.983] [10529:10531] [DL] HandlePlatformSpecificBLEEvent 32792 [1729056331.003] [10529:10531] [EM] >>> [E:8899i S:28671 M:244358615 (Ack:203665380)] (S) Msg RX from 1:0000000000007283 [D896] --- Type 0001:05 (IM:ReportData) (B:78) [1729056331.003] [10529:10531] [EM] Found matching exchange: 8899i, Delegate: 0x75a34400a180 [1729056331.003] [10529:10531] [EM] Rxd Ack; Removing MessageCounter:203665380 from Retrans Table on exchange 8899i [1729056331.003] [10529:10531] [DMG] ReportDataMessage = [1729056331.003] [10529:10531] [DMG] { [1729056331.003] [10529:10531] [DMG] AttributeReportIBs = [1729056331.004] [10529:10531] [DMG] [ [1729056331.004] [10529:10531] [DMG] AttributeReportIB = [1729056331.004] [10529:10531] [DMG] { [1729056331.004] [10529:10531] [DMG] AttributeDataIB = [1729056331.004] [10529:10531] [DMG] { [1729056331.004] [10529:10531] [DMG] DataVersion = 0x25c05bf1, [1729056331.004] [10529:10531] [DMG] AttributePathIB = [1729056331.004] [10529:10531] [DMG] { [1729056331.004] [10529:10531] [DMG] Endpoint = 0x1, [1729056331.004] [10529:10531] [DMG] Cluster = 0x1d, [1729056331.004] [10529:10531] [DMG] Attribute = 0x0000_0003, [1729056331.004] [10529:10531] [DMG] } [1729056331.004] [10529:10531] [DMG]
[1729056331.004] [10529:10531] [DMG] Data = [ [1729056331.004] [10529:10531] [DMG] 2 (unsigned), 3 (unsigned), 4 (unsigned), 5 (unsigned), [1729056331.004] [10529:10531] [DMG] ], [1729056331.004] [10529:10531] [DMG] }, [1729056331.004] [10529:10531] [DMG]
[1729056331.004] [10529:10531] [DMG] }, [1729056331.004] [10529:10531] [DMG]
[1729056331.004] [10529:10531] [DMG] ], [1729056331.004] [10529:10531] [DMG]
[1729056331.004] [10529:10531] [DMG] SuppressResponse = true, [1729056331.004] [10529:10531] [DMG] InteractionModelRevision = 11 [1729056331.004] [10529:10531] [DMG] } [1729056331.004] [10529:10531] [TOO] Endpoint: 1 Cluster: 0x0000_001D Attribute 0x0000_0003 DataVersion: 633363441 [1729056331.004] [10529:10531] [TOO] PartsList: 4 entries

[1729056331.004] [10529:10531] [EM] <<< [E:8899i S:28671 M:203665381 (Ack:244358615)] (S) Msg TX to 1:0000000000007283 [D896] [UDP:[fe80::e665:b8ff:fe7e:fbef%wlo1]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1729056331.004] [10529:10531] [EM] Flushed pending ack for MessageCounter:244358615 on exchange 8899i [1729056331.004] [10529:10529] [CTL] Shutting down the commissioner [1729056331.004] [10529:10529] [CTL] Shutting down the controller [1729056331.004] [10529:10529] [IN] Expiring all sessions for fabric 0x1!! [1729056331.004] [10529:10529] [IN] SecureSession[0x75a34400bd60]: MarkForEviction Type:2 LSID:28671 [1729056331.004] [10529:10529] [SC] SecureSession[0x75a34400bd60, LSID:28671]: State change 'kActive' --> 'kPendingEviction' [1729056331.004] [10529:10529] [IN] SecureSession[0x75a34400bd60]: Released - Type:2 LSID:28671 [1729056331.005] [10529:10529] [FP] Forgetting fabric 0x1 [1729056331.005] [10529:10529] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1729056331.005] [10529:10529] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1729056331.005] [10529:10529] [TS] Reverted Last Known Good Time to previous value [1729056331.005] [10529:10529] [CTL] Shutting down the commissioner [1729056331.005] [10529:10529] [CTL] Shutting down the controller [1729056331.005] [10529:10529] [CTL] Shutting down the System State, this will teardown the CHIP Stack [1729056331.005] [10529:10529] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1729056331.005] [10529:10529] [FP] Shutting down FabricTable [1729056331.005] [10529:10529] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1729056331.005] [10529:10529] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1729056331.005] [10529:10529] [TS] Reverted Last Known Good Time to previous value [1729056331.005] [10529:10529] [DL] wrote settings to /tmp/chip_counters.ini [1729056331.005] [10529:10529] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0) [1729056331.005] [10529:10529] [DL] Inet Layer shutdown [1729056331.005] [10529:10529] [DL] BLE Layer shutdown [1729056331.005] [10529:10529] [DL] System Layer shutdow

Device Logs(Pairing Apple TV + Apple Home App - commissioning successfully but accessories list is empty only shown bridge device.)

I (940379) app_main: Commissioning session started I (940389) chip[EM]: >>> [E:52605r S:5416 M:187313220] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) (B:51) I (940389) app_main: Commissioning window closed I (940399) chip[EM]: <<< [E:52605r S:5416 M:6348526 (Ack:187313220)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:80) I (940409) chip[EM]: >>> [E:52605r S:5416 M:187313221 (Ack:6348526)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (940409) chip[EM]: >>> [E:52606r S:5416 M:187313222] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) (B:51) I (940419) chip[EM]: <<< [E:52606r S:5416 M:6348527 (Ack:187313222)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:88) I (940429) chip[EM]: >>> [E:52606r S:5416 M:187313223 (Ack:6348527)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (940439) chip[EM]: >>> [E:52607r S:5416 M:187313224] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) (B:52) I (940439) chip[EM]: <<< [E:52607r S:5416 M:6348528 (Ack:187313224)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:71) I (940459) chip[EM]: >>> [E:52607r S:5416 M:187313225 (Ack:6348528)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (940459) chip[EM]: >>> [E:52608r S:5416 M:187313226] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) (B:51) I (940469) chip[EM]: <<< [E:52608r S:5416 M:6348529 (Ack:187313226)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:77) I (940479) chip[EM]: >>> [E:52608r S:5416 M:187313227 (Ack:6348529)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (940489) chip[EM]: >>> [E:52609r S:5416 M:187313228] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) (B:131) I (940489) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000031's Attribute 0x00000003 is 0 ** I (940499) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 ** I (940499) chip[EM]: <<< [E:52609r S:5416 M:6348530 (Ack:187313228)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:262) I (940509) chip[EM]: >>> [E:52610r S:5416 M:187313229] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) (B:106) I (940519) chip[EM]: >>> [E:52609r S:5416 M:187313230 (Ack:6348530)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) E (940519) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0002 err = 5c3 E (940519) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0001 err = 5c3 E (940529) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0000 err = 5c3 E (940529) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0007 err = 5c3 E (940529) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0046, attributeId: 0x0000_0006 err = 5c3 I (940529) chip[EM]: <<< [E:52610r S:5416 M:6348531 (Ack:187313229)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:184) I (940539) chip[EM]: >>> [E:52611r S:5416 M:187313231] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) (B:65) I (940549) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030 I (940549) chip[FS]: GeneralCommissioning: Received ArmFailSafe (60s) I (940549) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 ** I (940549) esp_matter_attribute: ** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 4 ** I (940559) knx_bridge: ---------------------------ATTR-START--------------------- I (940559) knx_bridge: Knx Address -> 65535 I (940559) knx_bridge: PRE_UPDATE I (940559) knx_bridge: GeneralCommissioning I (940559) chip[EM]: <<< [E:52611r S:5416 M:6348532 (Ack:187313231)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) I (940569) chip[EM]: >>> [E:52610r S:5416 M:187313232 (Ack:6348531)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (940569) chip[EM]: >>> [E:52612r S:5416 M:187313233] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) (B:70) I (940579) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x00000030 I (940579) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0) I (940589) chip[DL]: NVS set: chip-config/country-code = "TR" I (940589) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 4 ** I (940589) esp_matter_attribute: ** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 5 ** I (940589) knx_bridge: ---------------------------ATTR-START--------------------- I (940589) knx_bridge: Knx Address -> 65535 I (940589) knx_bridge: PRE_UPDATE I (940589) knx_bridge: GeneralCommissioning I (940599) chip[EM]: <<< [E:52612r S:5416 M:6348533 (Ack:187313233)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) I (940609) chip[EM]: >>> [E:52611r S:5416 M:187313234 (Ack:6348532)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (940609) chip[EM]: >>> [E:52613r S:5416 M:187313235] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) (B:62) I (940619) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E I (940619) chip[ZCL]: OpCreds: Certificate Chain request received for PAI I (940619) chip[EM]: <<< [E:52613r S:5416 M:6348534 (Ack:187313235)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:531) I (940629) chip[EM]: >>> [E:52612r S:5416 M:187313236 (Ack:6348533)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (940639) chip[EM]: >>> [E:52614r S:5416 M:187313237] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) (B:62) I (940639) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E I (940639) chip[ZCL]: OpCreds: Certificate Chain request received for DAC I (940649) chip[EM]: <<< [E:52614r S:5416 M:6348535 (Ack:187313237)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:561) I (940649) chip[EM]: >>> [E:52613r S:5416 M:187313238 (Ack:6348534)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (940659) chip[EM]: >>> [E:52615r S:5416 M:187313239] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) (B:94) I (940659) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x0000003E I (940669) chip[ZCL]: OpCreds: Received an AttestationRequest command I (940669) chip[EM]: <<< [E:52615r S:5416 M:6348536 (Ack:187313239)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (940739) chip[ZCL]: OpCreds: AttestationRequest successful. I (940739) chip[EM]: <<< [E:52615r S:5416 M:6348537 (Ack:187313239)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:718) I (940749) chip[EM]: >>> [E:52614r S:5416 M:187313240 (Ack:6348535)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (940769) chip[EM]: >>> [E:52616r S:5416 M:187313241] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) (B:65) I (940769) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030 I (940769) chip[FS]: GeneralCommissioning: Received ArmFailSafe (60s) I (940769) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 5 ** I (940779) esp_matter_attribute: ** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 14 ** I (940779) knx_bridge: ---------------------------ATTR-START--------------------- I (940779) knx_bridge: Knx Address -> 65535 I (940779) knx_bridge: PRE_UPDATE I (940779) knx_bridge: GeneralCommissioning I (940779) chip[EM]: <<< [E:52616r S:5416 M:6348538 (Ack:187313241)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) I (940789) chip[EM]: >>> [E:52615r S:5416 M:187313242 (Ack:6348537)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (940799) chip[EM]: >>> [E:52616r S:5416 M:187313243 (Ack:6348538)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (944119) chip[EM]: >>> [E:52617r S:5416 M:187313244] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) (B:94) I (944119) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x0000003E I (944119) chip[ZCL]: OpCreds: Received a CSRRequest command I (944129) chip[EM]: <<< [E:52617r S:5416 M:6348539 (Ack:187313244)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (944249) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded I (944319) chip[ZCL]: OpCreds: CSRRequest successful. I (944319) chip[EM]: <<< [E:52617r S:5416 M:6348540 (Ack:187313244)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:378) I (944339) chip[EM]: >>> [E:52617r S:5416 M:187313245 (Ack:6348540)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (944339) chip[EM]: >>> [E:52618r S:5416 M:187313246] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) (B:308) I (944349) esp_matter_command: Received command 0x0000000B for endpoint 0x0000's cluster 0x0000003E I (944349) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command I (944349) chip[EM]: <<< [E:52618r S:5416 M:6348541 (Ack:187313246)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (944569) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful. I (944579) chip[EM]: <<< [E:52618r S:5416 M:6348542 (Ack:187313246)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67) I (944639) chip[EM]: >>> [E:52619r S:5416 M:187313247] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) (B:347) I (944649) esp_matter_command: Received command 0x00000006 for endpoint 0x0000's cluster 0x0000003E I (944649) chip[ZCL]: OpCreds: Received an AddNOC command I (944649) chip[EM]: <<< [E:52619r S:5416 M:6348543 (Ack:187313247)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (944659) chip[FP]: Validating NOC chain I (944869) chip[FP]: NOC chain validation successful I (944869) chip[FP]: Added new fabric at index: 0x4 I (944879) chip[FP]: Assigned compressed fabric ID: 0x7B6F0E7F28D92409, node ID: 0x0000000055EB15C4 I (944879) chip[TS]: Last Known Good Time: 2024-10-15T09:21:27 I (944879) chip[TS]: New proposed Last Known Good Time: 2024-10-16T05:40:38 I (944879) chip[TS]: Updating pending Last Known Good Time to 2024-10-16T05:40:38 I (944909) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x4 CASE Admin Subject 0x00000000F040A290 I (944909) chip[DIS]: Advertise operational node 7B6F0E7F28D92409-0000000055EB15C4 I (944909) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7B6F0E7F28D92409-0000000055EB15C4. I (944919) chip[DIS]: mDNS service published: _matter._tcp I (944919) chip[ZCL]: OpCreds: successfully created fabric index 0x4 via AddNOC I (944919) chip[EM]: <<< [E:52619r S:5416 M:6348544 (Ack:187313247)] (S) Msg TX to 4:FFFFFFFB00000000 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) I (944929) chip[EM]: >>> [E:52618r S:5416 M:187313248 (Ack:6348542)] (S) Msg RX from 4:FFFFFFFB00000000 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (944939) app_main: Fabric is updated I (944959) chip[EM]: >>> [E:52619r S:5416 M:187313249 (Ack:6348544)] (S) Msg RX from 4:FFFFFFFB00000000 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (945159) chip[EM]: >>> [E:52620r S:0 M:43632631] (U) Msg RX from 0:6C5072B3656D1035 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) I (945159) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3ffb77b0 I (945169) chip[EM]: <<< [E:52620r S:0 M:31262509 (Ack:43632631)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) I (945169) chip[SC]: Received Sigma1 msg I (945179) chip[SC]: CASE matched destination ID: fabricIndex 4, NodeID 0x0000000055EB15C4 I (945449) chip[EM]: <<< [E:52620r S:0 M:31262510 (Ack:43632631)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:529) I (945459) chip[SC]: Sent Sigma2 msg I (945569) chip[EM]: >>> [E:52620r S:0 M:43632632 (Ack:31262510)] (U) Msg RX from 0:6C5072B3656D1035 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:373) I (945579) chip[EM]: <<< [E:52620r S:0 M:31262511 (Ack:43632632)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) I (945579) chip[SC]: Received Sigma3 msg E (945589) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c. Falling back to Last Known Good UTC Time I (946039) chip[EM]: <<< [E:52620r S:0 M:31262512 (Ack:43632632)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:40 (SecureChannel:StatusReport) (B:34) I (946039) chip[SC]: SecureSession[0x3ffb5d80, LSID:5417]: State change 'kEstablishing' --> 'kActive' I (946039) chip[IN]: CASE Session established to peer: <00000000F040A290, 4> E (946049) app_main: Invalid Event Type 32792 I (946079) chip[EM]: >>> [E:52621r S:5417 M:138480446] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:08 (IM:InvokeCommandRequest) (B:59) I (946089) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x00000030 I (946089) chip[FS]: GeneralCommissioning: Received CommissioningComplete I (946099) chip[FP]: Metadata for Fabric 0x4 persisted to storage. I (946109) chip[TS]: Committing Last Known Good Time to storage: 2024-10-16T05:40:38 I (946119) chip[ZCL]: OpCreds: Fabric index 0x4 was committed to storage. Compressed Fabric Id 0x7B6F0E7F28D92409, FabricId 0000000054388E78, NodeId 0000000055EB15C4, VendorId 0x1349 I (946119) chip[FS]: GeneralCommissioning: Successfully commited pending fabric data I (946119) chip[FS]: Fail-safe cleanly disarmed I (946119) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 14 ** I (946129) esp_matter_attribute: ** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 ** I (946129) knx_bridge: ---------------------------ATTR-START--------------------- I (946129) knx_bridge: Knx Address -> 65535 I (946129) knx_bridge: PRE_UPDATE I (946129) knx_bridge: GeneralCommissioning I (946129) chip[EM]: <<< [E:52621r S:5417 M:176189816 (Ack:138480446)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) I (946139) chip[EM]: >>> [E:52620r S:0 M:43632633 (Ack:31262512)] (U) Msg RX from 0:6C5072B3656D1035 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) I (946139) app_main: Fabric is committed I (946149) chip[SVR]: Commissioning completed successfully I (946149) chip[DIS]: Updating services using commissioning mode 0 I (946149) chip[DIS]: CHIP minimal mDNS started advertising. I (946159) chip[DIS]: Advertise operational node 7B6F0E7F28D92409-0000000055EB15C4 I (946159) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7B6F0E7F28D92409-0000000055EB15C4. I (946169) chip[DIS]: mDNS service published: _matter._tcp I (946169) chip[SC]: SecureSession[0x3ffb60e0, LSID:5416]: State change 'kActive' --> 'kPendingEviction' I (946169) app_main: Commissioning complete I (946169) esp_matter_core: Commissioning Complete I (946179) chip[EM]: >>> [E:52621r S:5417 M:138480447 (Ack:176189816)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946179) chip[EM]: >>> [E:52622r S:5417 M:138480448] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946179) app_main: Commissioning window closed I (946189) chip[EM]: <<< [E:52622r S:5417 M:176189817 (Ack:138480448)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:162) I (946199) chip[EM]: >>> [E:52622r S:5417 M:138480449 (Ack:176189817)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946249) chip[EM]: >>> [E:52623r S:5417 M:138480450] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946249) chip[EM]: <<< [E:52623r S:5417 M:176189818 (Ack:138480450)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:73) I (946259) chip[EM]: >>> [E:52623r S:5417 M:138480451 (Ack:176189818)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946269) chip[EM]: >>> [E:52624r S:5417 M:138480452] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946279) chip[EM]: <<< [E:52624r S:5417 M:176189819 (Ack:138480452)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:80) I (946289) chip[EM]: >>> [E:52624r S:5417 M:138480453 (Ack:176189819)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946289) chip[EM]: >>> [E:52625r S:5417 M:138480454] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946299) chip[EM]: <<< [E:52625r S:5417 M:176189820 (Ack:138480454)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:78) I (946309) chip[EM]: >>> [E:52625r S:5417 M:138480455 (Ack:176189820)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946309) chip[EM]: >>> [E:52626r S:5417 M:138480456] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946319) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0000 err = 5c3 I (946319) chip[EM]: <<< [E:52626r S:5417 M:176189821 (Ack:138480456)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946329) chip[EM]: >>> [E:52626r S:5417 M:138480457 (Ack:176189821)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946339) chip[EM]: >>> [E:52627r S:5417 M:138480458] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946339) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0000 err = 5c3 I (946349) chip[EM]: <<< [E:52627r S:5417 M:176189822 (Ack:138480458)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946359) chip[EM]: >>> [E:52627r S:5417 M:138480459 (Ack:176189822)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946359) chip[EM]: >>> [E:52628r S:5417 M:138480460] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946369) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0000 err = 5c3 I (946369) chip[EM]: <<< [E:52628r S:5417 M:176189823 (Ack:138480460)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946389) chip[EM]: >>> [E:52628r S:5417 M:138480461 (Ack:176189823)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946389) chip[EM]: >>> [E:52629r S:5417 M:138480462] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946399) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0000 err = 5c3 I (946399) chip[EM]: <<< [E:52629r S:5417 M:176189824 (Ack:138480462)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946409) chip[EM]: >>> [E:52629r S:5417 M:138480463 (Ack:176189824)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946419) chip[EM]: >>> [E:52630r S:5417 M:138480464] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946419) chip[EM]: <<< [E:52630r S:5417 M:176189825 (Ack:138480464)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:78) I (946439) chip[EM]: >>> [E:52630r S:5417 M:138480465 (Ack:176189825)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946439) chip[EM]: >>> [E:52631r S:5417 M:138480466] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946439) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0003 err = 5c3 I (946449) chip[EM]: <<< [E:52631r S:5417 M:176189826 (Ack:138480466)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946459) chip[EM]: >>> [E:52631r S:5417 M:138480467 (Ack:176189826)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946459) chip[EM]: >>> [E:52632r S:5417 M:138480468] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946469) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0001 err = 5c3 I (946469) chip[EM]: <<< [E:52632r S:5417 M:176189827 (Ack:138480468)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946479) chip[EM]: >>> [E:52632r S:5417 M:138480469 (Ack:176189827)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946489) chip[EM]: >>> [E:52633r S:5417 M:138480470] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946489) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0003 err = 5c3 I (946499) chip[EM]: <<< [E:52633r S:5417 M:176189828 (Ack:138480470)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946509) chip[EM]: >>> [E:52633r S:5417 M:138480471 (Ack:176189828)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946509) chip[EM]: >>> [E:52634r S:5417 M:138480472] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946519) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0001 err = 5c3 I (946519) chip[EM]: <<< [E:52634r S:5417 M:176189829 (Ack:138480472)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946529) chip[EM]: >>> [E:52634r S:5417 M:138480473 (Ack:176189829)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946529) chip[EM]: >>> [E:52635r S:5417 M:138480474] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946539) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0003 err = 5c3 I (946539) chip[EM]: <<< [E:52635r S:5417 M:176189830 (Ack:138480474)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946549) chip[EM]: >>> [E:52635r S:5417 M:138480475 (Ack:176189830)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946559) chip[EM]: >>> [E:52636r S:5417 M:138480476] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946559) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0001 err = 5c3 I (946569) chip[EM]: <<< [E:52636r S:5417 M:176189831 (Ack:138480476)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946579) chip[EM]: >>> [E:52636r S:5417 M:138480477 (Ack:176189831)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946579) chip[EM]: >>> [E:52637r S:5417 M:138480478] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946589) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0003 err = 5c3 I (946589) chip[EM]: <<< [E:52637r S:5417 M:176189832 (Ack:138480478)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946599) chip[EM]: >>> [E:52637r S:5417 M:138480479 (Ack:176189832)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946609) chip[EM]: >>> [E:52638r S:5417 M:138480480] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (946609) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0001 err = 5c3 I (946609) chip[EM]: <<< [E:52638r S:5417 M:176189833 (Ack:138480480)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946619) chip[EM]: >>> [E:52638r S:5417 M:138480481 (Ack:176189833)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946629) chip[EM]: >>> [E:52639r S:5417 M:138480482] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946639) chip[EM]: <<< [E:52639r S:5417 M:176189834 (Ack:138480482)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:80) I (946649) chip[EM]: >>> [E:52639r S:5417 M:138480483 (Ack:176189834)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946649) chip[EM]: >>> [E:52640r S:5417 M:138480484] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946659) chip[EM]: >>> [E:52641r S:5417 M:138480485] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946659) chip[EM]: >>> [E:52642r S:5417 M:138480486] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946669) chip[EM]: >>> [E:52643r S:5417 M:138480487] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946669) chip[EM]: >>> [E:52644r S:5417 M:138480488] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946679) chip[EM]: >>> [E:52645r S:5417 M:138480489] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946679) chip[EM]: <<< [E:52645r S:5417 M:176189835 (Ack:138480489)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:01 (IM:StatusResponse) (B:42) I (946689) chip[EM]: <<< [E:52640r S:5417 M:176189836 (Ack:138480484)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:88) I (946699) chip[EM]: <<< [E:52641r S:5417 M:176189837 (Ack:138480485)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:72) E (946709) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0001 err = 5c3 I (946719) chip[EM]: <<< [E:52642r S:5417 M:176189838 (Ack:138480486)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) E (946729) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0001 err = 5c3 I (946729) chip[EM]: <<< [E:52643r S:5417 M:176189839 (Ack:138480487)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) E (946739) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0001 err = 5c3 I (946739) chip[EM]: <<< [E:52644r S:5417 M:176189840 (Ack:138480488)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (946749) chip[EM]: >>> [E:52645r S:5417 M:138480490 (Ack:176189835)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (946759) chip[EM]: <<< [E:52645r S:5417 M:176189841 (Ack:138480490)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) E (946759) chip[EM]: Dropping unexpected message of type 0x1 with protocolId (0, 1) and MessageCounter:138480490 on exchange 52645r I (946769) chip[EM]: >>> [E:52640r S:5417 M:138480491 (Ack:176189836)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946769) chip[EM]: >>> [E:52641r S:5417 M:138480492 (Ack:176189837)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946769) chip[EM]: >>> [E:52642r S:5417 M:138480493 (Ack:176189838)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946779) chip[EM]: >>> [E:52643r S:5417 M:138480494 (Ack:176189839)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946779) chip[EM]: >>> [E:52644r S:5417 M:138480495 (Ack:176189840)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946789) chip[EM]: >>> [E:52646r S:5417 M:138480496] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (946789) chip[EM]: <<< [E:52646r S:5417 M:176189842 (Ack:138480496)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:162) I (946799) chip[EM]: >>> [E:52646r S:5417 M:138480497 (Ack:176189842)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946849) chip[EM]: >>> [E:52647r S:5417 M:138480498] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:08 (IM:InvokeCommandRequest) (B:65) I (946849) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030 I (946849) chip[FS]: GeneralCommissioning: Received ArmFailSafe (30s) I (946849) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 ** I (946859) chip[EM]: <<< [E:52647r S:5417 M:176189843 (Ack:138480498)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) I (946869) chip[EM]: >>> [E:52647r S:5417 M:138480499 (Ack:176189843)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (946869) chip[EM]: >>> [E:52648r S:5417 M:138480500] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:08 (IM:InvokeCommandRequest) (B:94) I (946879) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x0000003E I (946879) chip[ZCL]: OpCreds: Received a CSRRequest command I (946879) chip[EM]: <<< [E:52648r S:5417 M:176189844 (Ack:138480500)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (947009) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded I (947069) chip[ZCL]: OpCreds: CSRRequest successful. I (947079) chip[EM]: <<< [E:52648r S:5417 M:176189845 (Ack:138480500)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:378) I (947089) chip[EM]: >>> [E:52648r S:5417 M:138480501 (Ack:176189845)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (947089) chip[EM]: >>> [E:52649r S:5417 M:138480502] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:08 (IM:InvokeCommandRequest) (B:316) I (947099) esp_matter_command: Received command 0x0000000B for endpoint 0x0000's cluster 0x0000003E I (947099) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command I (947099) chip[EM]: <<< [E:52649r S:5417 M:176189846 (Ack:138480502)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (947319) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful. I (947319) chip[EM]: <<< [E:52649r S:5417 M:176189847 (Ack:138480502)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:67) I (947329) chip[EM]: >>> [E:52649r S:5417 M:138480503 (Ack:176189847)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (947339) chip[EM]: >>> [E:52650r S:5417 M:138480504] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:08 (IM:InvokeCommandRequest) (B:349) I (947339) esp_matter_command: Received command 0x00000006 for endpoint 0x0000's cluster 0x0000003E I (947349) chip[ZCL]: OpCreds: Received an AddNOC command I (947349) chip[EM]: <<< [E:52650r S:5417 M:176189848 (Ack:138480504)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (947359) chip[FP]: Validating NOC chain I (947569) chip[FP]: NOC chain validation successful I (947569) chip[FP]: Added new fabric at index: 0x5 I (947569) chip[FP]: Assigned compressed fabric ID: 0x1ED462C166FB9D76, node ID: 0x0000000073BC4610 I (947569) chip[TS]: Last Known Good Time: 2024-10-16T05:40:38 I (947579) chip[TS]: New proposed Last Known Good Time: 2024-10-16T05:40:41 I (947579) chip[TS]: Updating pending Last Known Good Time to 2024-10-16T05:40:41 I (947599) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x5 CASE Admin Subject 0x000000007C888F0D I (947599) chip[DIS]: Advertise operational node 7B6F0E7F28D92409-0000000055EB15C4 I (947599) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7B6F0E7F28D92409-0000000055EB15C4. I (947609) chip[DIS]: mDNS service published: _matter._tcp I (947609) chip[DIS]: Advertise operational node 1ED462C166FB9D76-0000000073BC4610 I (947609) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 1ED462C166FB9D76-0000000073BC4610. I (947619) chip[DIS]: mDNS service published: _matter._tcp I (947619) chip[ZCL]: OpCreds: successfully created fabric index 0x5 via AddNOC I (947629) chip[EM]: <<< [E:52650r S:5417 M:176189849 (Ack:138480504)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) I (947629) app_main: Fabric is updated I (947719) chip[EM]: >>> [E:52650r S:5417 M:138480505 (Ack:176189849)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (947929) chip[EM]: >>> [E:52651r S:0 M:43632634] (U) Msg RX from 0:B06D5AE9AC11BA4F [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) I (947929) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3ffb77b0 I (947929) chip[EM]: <<< [E:52651r S:0 M:31262513 (Ack:43632634)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) I (947939) chip[SC]: Received Sigma1 msg I (947949) chip[SC]: CASE matched destination ID: fabricIndex 5, NodeID 0x0000000073BC4610 I (948219) chip[EM]: <<< [E:52651r S:0 M:31262514 (Ack:43632634)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:534) I (948229) chip[SC]: Sent Sigma2 msg I (948339) chip[EM]: >>> [E:52651r S:0 M:43632635 (Ack:31262514)] (U) Msg RX from 0:B06D5AE9AC11BA4F [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:378) I (948339) chip[EM]: <<< [E:52651r S:0 M:31262515 (Ack:43632635)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) I (948349) chip[SC]: Received Sigma3 msg E (948349) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c. Falling back to Last Known Good UTC Time I (948799) chip[EM]: <<< [E:52651r S:0 M:31262516 (Ack:43632635)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0000:40 (SecureChannel:StatusReport) (B:34) I (948799) chip[SC]: SecureSession[0x3ffb5e58, LSID:5418]: State change 'kEstablishing' --> 'kActive' I (948799) chip[IN]: CASE Session established to peer: <000000007C888F0D, 5> E (948809) app_main: Invalid Event Type 32792 I (948849) chip[EM]: >>> [E:52652r S:5418 M:217375488] (S) Msg RX from 5:000000007C888F0D [9D76] --- Type 0001:08 (IM:InvokeCommandRequest) (B:59) I (948849) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x00000030 I (948859) chip[FS]: GeneralCommissioning: Received CommissioningComplete I (948859) chip[FP]: Metadata for Fabric 0x5 persisted to storage. I (948869) chip[TS]: Committing Last Known Good Time to storage: 2024-10-16T05:40:41 I (948889) chip[ZCL]: OpCreds: Fabric index 0x5 was committed to storage. Compressed Fabric Id 0x1ED462C166FB9D76, FabricId 00000000DCE5496B, NodeId 0000000073BC4610, VendorId 0x1384 I (948889) chip[FS]: GeneralCommissioning: Successfully commited pending fabric data I (948889) chip[FS]: Fail-safe cleanly disarmed I (948899) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 ** I (948899) chip[EM]: <<< [E:52652r S:5418 M:226075871 (Ack:217375488)] (S) Msg TX to 5:000000007C888F0D [9D76] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) I (948909) chip[EM]: >>> [E:52651r S:0 M:43632636 (Ack:31262516)] (U) Msg RX from 0:B06D5AE9AC11BA4F [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) I (948909) app_main: Fabric is committed I (948909) app_main: Commissioning complete I (948909) esp_matter_core: Commissioning Complete I (948919) chip[EM]: >>> [E:52652r S:5418 M:217375489 (Ack:226075871)] (S) Msg RX from 5:000000007C888F0D [9D76] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (948919) chip[EM]: >>> [E:52653r S:5418 M:217375490] (S) Msg RX from 5:000000007C888F0D [9D76] --- Type 0001:02 (IM:ReadRequest) (B:51) E (948919) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_0028, attributeId: 0x0000_000F err = 586 I (948929) chip[EM]: <<< [E:52653r S:5418 M:226075872 (Ack:217375490)] (S) Msg TX to 5:000000007C888F0D [9D76] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (948939) chip[EM]: >>> [E:52653r S:5418 M:217375491 (Ack:226075872)] (S) Msg RX from 5:000000007C888F0D [9D76] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (949099) chip[EM]: >>> [E:52654r S:5417 M:138480506] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (949109) chip[EM]: <<< [E:52654r S:5417 M:176189850 (Ack:138480506)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:80) I (949119) chip[EM]: >>> [E:52654r S:5417 M:138480507 (Ack:176189850)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (949119) chip[EM]: >>> [E:52655r S:5417 M:138480508] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (949129) chip[EM]: <<< [E:52655r S:5417 M:176189851 (Ack:138480508)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:88) I (949139) chip[EM]: >>> [E:52655r S:5417 M:138480509 (Ack:176189851)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (949149) chip[EM]: >>> [E:52656r S:5417 M:138480510] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (949149) chip[EM]: <<< [E:52656r S:5417 M:176189852 (Ack:138480510)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:72) I (949169) chip[EM]: >>> [E:52656r S:5417 M:138480511 (Ack:176189852)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (949169) chip[EM]: >>> [E:52657r S:5417 M:138480512] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) E (949169) chip[DMG]: Fail to retrieve data, roll back and encode status on clusterId: 0x0000_001D, attributeId: 0x0000_0001 err = 5c3 I (949179) chip[EM]: <<< [E:52657r S:5417 M:176189853 (Ack:138480512)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::1426:7DCC:8242:68F%en1]:54313] --- Type 0001:05 (IM:ReportData) (B:67) I (949189) chip[EM]: >>> [E:52657r S:5417 M:138480513 (Ack:176189853)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (949459) chip[EM]: >>> [E:30173r S:0 M:197182203] (U) Msg RX from 0:8369401F5BBAD87F [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) I (949459) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3ffb77b0 I (949459) chip[EM]: <<< [E:30173r S:0 M:31262517 (Ack:197182203)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) I (949459) chip[SC]: Received Sigma1 msg I (949469) chip[SC]: CASE matched destination ID: fabricIndex 4, NodeID 0x0000000055EB15C4 I (949749) chip[EM]: <<< [E:30173r S:0 M:31262518 (Ack:197182203)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:529) I (949759) chip[SC]: Sent Sigma2 msg I (949759) chip[EM]: >>> [E:30173r S:0 M:197182204 (Ack:31262518)] (U) Msg RX from 0:8369401F5BBAD87F [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:373) I (949769) chip[EM]: <<< [E:30173r S:0 M:31262519 (Ack:197182204)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) I (949769) chip[SC]: Received Sigma3 msg E (949779) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c. Falling back to Last Known Good UTC Time I (950239) chip[EM]: <<< [E:30173r S:0 M:31262520 (Ack:197182204)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0000:40 (SecureChannel:StatusReport) (B:34) I (950239) chip[SC]: SecureSession[0x3ffb5f30, LSID:5419]: State change 'kEstablishing' --> 'kActive' I (950239) chip[IN]: CASE Session established to peer: <00000000F040A290, 4> E (950249) app_main: Invalid Event Type 32792 I (950309) chip[EM]: >>> [E:30174r S:5419 M:196640819] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (950309) chip[EM]: >>> [E:30175r S:5419 M:196640820] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:03 (IM:SubscribeRequest) (B:58) I (950319) chip[DMG]: Final negotiated min/max parameters: Min = 0s, Max = 600s I (950329) chip[EM]: >>> [E:30173r S:0 M:197182205 (Ack:31262520)] (U) Msg RX from 0:8369401F5BBAD87F [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) I (950339) chip[EM]: <<< [E:30174r S:5419 M:253023426 (Ack:196640819)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:91) I (950349) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x0000001F's Attribute 0x0000FFFC is 0 ** I (950359) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000028's Attribute 0x0000FFFC is 0 ** I (950359) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000028's Attribute 0x0000FFFD is 3 ** I (950359) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000028's Attribute 0x00000005 is EAE KNX Matter Bridge ** I (950369) chip[EM]: <<< [E:30175r S:5419 M:253023427 (Ack:196640820)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:1195) I (950379) chip[EM]: >>> [E:30174r S:5419 M:196640821 (Ack:253023426)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (950379) chip[EM]: >>> [E:30176r S:5419 M:196640822] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:06 (IM:WriteRequest) (B:105) I (950409) chip[EM]: <<< [E:30176r S:5419 M:253023428 (Ack:196640822)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:07 (IM:WriteResponse) (B:84) I (950409) chip[EM]: >>> [E:30175r S:5419 M:196640823 (Ack:253023427)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (950419) chip[IM]: Received status response, status is 0x00 I (950419) chip[EM]: >>> [E:30176r S:5419 M:196640824 (Ack:253023428)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (950429) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x0000FFFC is 0 ** I (950429) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x0000FFFD is 1 ** I (950429) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 ** I (950439) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000031's Attribute 0x00000002 is 0 ** I (950439) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x00000031's Attribute 0x00000003 is 0 ** I (950449) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x0000003C's Attribute 0x0000FFFC is 0 ** I (950449) chip[EM]: <<< [E:30175r S:5419 M:253023429 (Ack:196640823)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:1207) I (950459) chip[EM]: >>> [E:30175r S:5419 M:196640825 (Ack:253023429)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (950469) chip[IM]: Received status response, status is 0x00 I (950469) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x0000003C's Attribute 0x0000FFFD is 1 ** I (950469) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x0000003E's Attribute 0x0000FFFC is 0 ** I (950489) chip[EM]: <<< [E:30175r S:5419 M:253023430 (Ack:196640825)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:1189) I (950499) chip[EM]: >>> [E:30175r S:5419 M:196640826 (Ack:253023430)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (950499) chip[IM]: Received status response, status is 0x00 I (950509) esp_matter_attribute: ** R : Endpoint 0x0000's Cluster 0x0000003E's Attribute 0x0000FFFD is 1 ** I (950519) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000039's Attribute 0x0000FFFC is 0 ** I (950519) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000039's Attribute 0x0000FFFD is 2 ** I (950529) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000039's Attribute 0x00000011 is 1 ** I (950529) chip[EM]: <<< [E:30175r S:5419 M:253023431 (Ack:196640826)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:1204) I (950539) chip[EM]: >>> [E:30175r S:5419 M:196640827 (Ack:253023431)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (950549) chip[IM]: Received status response, status is 0x00 I (950549) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x0000FFFC is 0 ** I (950549) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x0000FFFD is 4 ** I (950549) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x00000000 is 0 ** I (950549) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x00000001 is 0 ** I (950559) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x0000FFFC is 1 ** I (950559) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x0000FFFD is 4 ** I (950559) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x00000000 is 128 ** I (950559) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950569) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000062's Attribute 0x0000FFFC is 0 ** I (950569) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950569) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000062's Attribute 0x0000FFFD is 1 ** I (950569) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950569) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950579) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950579) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000006's Attribute 0x0000FFFC is 1 ** I (950579) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000006's Attribute 0x0000FFFD is 6 ** I (950589) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000006's Attribute 0x00000000 is 0 ** I (950589) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000006's Attribute 0x00004000 is 1 ** I (950589) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000006's Attribute 0x00004001 is 0 ** I (950589) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000006's Attribute 0x00004002 is 0 ** I (950589) esp_matter_attribute: ** R : Endpoint 0x0002's Cluster 0x00000006's Attribute 0x00004003 is 0 ** I (950599) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000039's Attribute 0x0000FFFC is 0 ** I (950599) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000039's Attribute 0x0000FFFD is 2 ** I (950599) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000039's Attribute 0x00000011 is 1 ** I (950609) chip[EM]: <<< [E:30175r S:5419 M:253023432 (Ack:196640827)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:1208) I (950619) chip[EM]: >>> [E:30175r S:5419 M:196640828 (Ack:253023432)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (950619) chip[IM]: Received status response, status is 0x00 I (950619) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000003's Attribute 0x0000FFFC is 0 ** I (950629) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000003's Attribute 0x0000FFFD is 4 ** I (950629) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000003's Attribute 0x00000000 is 0 ** I (950629) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000003's Attribute 0x00000001 is 0 ** I (950629) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000004's Attribute 0x0000FFFC is 1 ** I (950639) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000004's Attribute 0x0000FFFD is 4 ** I (950639) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000004's Attribute 0x00000000 is 128 ** I (950639) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950639) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000062's Attribute 0x0000FFFC is 0 ** I (950639) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950649) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000062's Attribute 0x0000FFFD is 1 ** I (950649) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950649) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950649) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950659) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000006's Attribute 0x0000FFFC is 1 ** I (950659) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000006's Attribute 0x0000FFFD is 6 ** I (950659) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000006's Attribute 0x00000000 is 0 ** I (950659) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000006's Attribute 0x00004000 is 1 ** I (950669) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000006's Attribute 0x00004001 is 0 ** I (950669) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000006's Attribute 0x00004002 is 0 ** I (950669) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000006's Attribute 0x00004003 is 0 ** I (950669) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000008's Attribute 0x0000FFFC is 3 ** I (950679) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000008's Attribute 0x0000FFFD is 5 ** I (950679) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000008's Attribute 0x00000000 is 1 ** I (950679) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000008's Attribute 0x00000011 is null ** I (950679) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000008's Attribute 0x0000000F is 0 ** I (950679) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000008's Attribute 0x00000001 is 0 ** I (950689) chip[EM]: <<< [E:30175r S:5419 M:253023433 (Ack:196640828)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:1206) I (950699) chip[EM]: >>> [E:30175r S:5419 M:196640829 (Ack:253023433)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (950699) chip[IM]: Received status response, status is 0x00 I (950709) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000008's Attribute 0x00000001 is 0 ** I (950709) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000008's Attribute 0x00000002 is 1 ** I (950709) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000008's Attribute 0x00000003 is 254 ** I (950709) esp_matter_attribute: ** R : Endpoint 0x0003's Cluster 0x00000008's Attribute 0x00004000 is 0 ** I (950719) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000039's Attribute 0x0000FFFC is 0 ** I (950719) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000039's Attribute 0x0000FFFD is 2 ** I (950719) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000039's Attribute 0x00000011 is 1 ** I (950719) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000003's Attribute 0x0000FFFC is 0 ** I (950729) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000003's Attribute 0x0000FFFD is 4 ** I (950729) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000003's Attribute 0x00000000 is 0 ** I (950729) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000003's Attribute 0x00000001 is 0 ** I (950729) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000004's Attribute 0x0000FFFC is 1 ** I (950729) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000004's Attribute 0x0000FFFD is 4 ** I (950739) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000004's Attribute 0x00000000 is 128 ** I (950739) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950739) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000062's Attribute 0x0000FFFC is 0 ** I (950739) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950739) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000062's Attribute 0x0000FFFD is 1 ** I (950749) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950749) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950749) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950759) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000006's Attribute 0x0000FFFC is 1 ** I (950759) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000006's Attribute 0x0000FFFD is 6 ** I (950759) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000006's Attribute 0x00000000 is 0 ** I (950769) chip[EM]: <<< [E:30175r S:5419 M:253023434 (Ack:196640829)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:1186) I (950779) chip[EM]: >>> [E:30175r S:5419 M:196640830 (Ack:253023434)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (950779) chip[IM]: Received status response, status is 0x00 I (950779) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000006's Attribute 0x00000000 is 0 ** I (950779) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000006's Attribute 0x00004000 is 1 ** I (950789) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000006's Attribute 0x00004001 is 0 ** I (950789) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000006's Attribute 0x00004002 is 0 ** I (950789) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000006's Attribute 0x00004003 is 0 ** I (950789) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000008's Attribute 0x0000FFFC is 3 ** I (950799) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000008's Attribute 0x0000FFFD is 5 ** I (950799) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000008's Attribute 0x00000000 is 1 ** I (950799) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000008's Attribute 0x00000011 is null ** I (950799) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000008's Attribute 0x0000000F is 0 ** I (950799) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000008's Attribute 0x00000001 is 0 ** I (950809) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000008's Attribute 0x00000002 is 1 ** I (950809) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000008's Attribute 0x00000003 is 254 ** I (950809) esp_matter_attribute: ** R : Endpoint 0x0004's Cluster 0x00000008's Attribute 0x00004000 is 0 ** I (950809) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000039's Attribute 0x0000FFFC is 0 ** I (950819) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000039's Attribute 0x0000FFFD is 2 ** I (950819) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000039's Attribute 0x00000011 is 1 ** I (950819) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000003's Attribute 0x0000FFFC is 0 ** I (950819) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000003's Attribute 0x0000FFFD is 4 ** I (950829) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000003's Attribute 0x00000000 is 0 ** I (950829) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000003's Attribute 0x00000001 is 0 ** I (950829) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000004's Attribute 0x0000FFFC is 1 ** I (950829) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000004's Attribute 0x0000FFFD is 4 ** I (950839) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000004's Attribute 0x00000000 is 128 ** I (950839) chip[EM]: <<< [E:30175r S:5419 M:253023435 (Ack:196640830)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:1208) I (950849) chip[EM]: >>> [E:30175r S:5419 M:196640831 (Ack:253023435)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (950849) chip[IM]: Received status response, status is 0x00 I (950859) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950859) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000062's Attribute 0x0000FFFC is 0 ** I (950859) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950859) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000062's Attribute 0x0000FFFD is 1 ** I (950869) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950869) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950869) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000062's Attribute 0x00000001 is 16 ** I (950869) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000006's Attribute 0x0000FFFC is 1 ** I (950879) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000006's Attribute 0x0000FFFD is 6 ** I (950879) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000006's Attribute 0x00000000 is 0 ** I (950879) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000006's Attribute 0x00004000 is 1 ** I (950879) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000006's Attribute 0x00004001 is 0 ** I (950889) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000006's Attribute 0x00004002 is 0 ** I (950889) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000006's Attribute 0x00004003 is 0 ** I (950889) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000008's Attribute 0x0000FFFC is 3 ** I (950889) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000008's Attribute 0x0000FFFD is 5 ** I (950889) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000008's Attribute 0x00000000 is 1 ** I (950899) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000008's Attribute 0x00000011 is null ** I (950899) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000008's Attribute 0x0000000F is 0 ** I (950899) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000008's Attribute 0x00000001 is 0 ** I (950899) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000008's Attribute 0x00000002 is 1 ** I (950899) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000008's Attribute 0x00000003 is 254 ** I (950909) esp_matter_attribute: ** R : Endpoint 0x0005's Cluster 0x00000008's Attribute 0x00004000 is 0 ** I (950919) chip[EM]: <<< [E:30175r S:5419 M:253023436 (Ack:196640831)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:1207) I (950919) chip[EM]: >>> [E:30175r S:5419 M:196640832 (Ack:253023436)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (950929) chip[IM]: Received status response, status is 0x00 I (950929) chip[EM]: <<< [E:30175r S:5419 M:253023437 (Ack:196640832)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:249) I (950939) chip[EM]: >>> [E:30175r S:5419 M:196640833 (Ack:253023437)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (950949) chip[IM]: Received status response, status is 0x00 I (950949) chip[EM]: <<< [E:30175r S:5419 M:253023438 (Ack:196640833)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:04 (IM:SubscribeResponse) (B:49) I (950949) chip[DMG]: Registered a ReadHandler that will schedule a report between system Timestamp: 0x00000000000E7D78 and system Timestamp 0x000000000017A538. I (950959) chip[EM]: >>> [E:30177r S:5419 M:196640834] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:06 (IM:WriteRequest) (B:89) I (950959) chip[EM]: <<< [E:30177r S:5419 M:253023439 (Ack:196640834)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:07 (IM:WriteResponse) (B:84) I (950969) chip[EM]: >>> [E:30175r S:5419 M:196640835 (Ack:253023438)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (950979) chip[EM]: <<< [E:56460i S:5419 M:253023440] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:105) I (950989) chip[EM]: >>> [E:30177r S:5419 M:196640836 (Ack:253023439)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (950989) chip[EM]: >>> [E:30178r S:5419 M:196640837] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:08 (IM:InvokeCommandRequest) (B:66) I (950999) esp_matter_command: Received command 0x00000009 for endpoint 0x0000's cluster 0x0000003E I (950999) chip[ZCL]: OpCreds: Received an UpdateFabricLabel command I (951009) chip[FP]: Metadata for Fabric 0x4 persisted to storage. I (951009) chip[EM]: <<< [E:30178r S:5419 M:253023441 (Ack:196640837)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) I (951019) chip[EM]: >>> [E:56460i S:5419 M:196640838 (Ack:253023440)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (951029) chip[IM]: Received status response, status is 0x00 I (951029) chip[EM]: <<< [E:56460i S:5419 M:253023442 (Ack:196640838)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (951029) chip[EM]: >>> [E:30179r S:5419 M:196640839] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (951039) chip[EM]: >>> [E:30178r S:5419 M:196640840 (Ack:253023441)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (951039) chip[EM]: >>> [E:30180r S:5419 M:196640841] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (951049) chip[EM]: <<< [E:30179r S:5419 M:253023443 (Ack:196640839)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:258) I (951069) chip[EM]: <<< [E:56461i S:5419 M:253023444] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:258) I (951079) chip[EM]: <<< [E:30180r S:5419 M:253023445 (Ack:196640841)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:258) I (951099) chip[EM]: >>> [E:30181r S:5419 M:196640842] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:08 (IM:InvokeCommandRequest) (B:66) I (951099) esp_matter_command: Received command 0x00000009 for endpoint 0x0000's cluster 0x0000003E I (951099) chip[ZCL]: OpCreds: Received an UpdateFabricLabel command I (951109) chip[FP]: Metadata for Fabric 0x4 persisted to storage. I (951109) chip[EM]: <<< [E:30181r S:5419 M:253023446 (Ack:196640842)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) I (951119) chip[EM]: >>> [E:30179r S:5419 M:196640843 (Ack:253023443)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (951119) chip[EM]: >>> [E:56461i S:5419 M:196640844 (Ack:253023444)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (951129) chip[IM]: Received status response, status is 0x00 I (951129) chip[EM]: <<< [E:56461i S:5419 M:253023447 (Ack:196640844)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (951139) chip[EM]: >>> [E:30180r S:5419 M:196640845 (Ack:253023445)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (951139) chip[EM]: >>> [E:30181r S:5419 M:196640846 (Ack:253023446)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (951149) chip[EM]: <<< [E:56462i S:5419 M:253023448] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:258) I (951159) chip[EM]: >>> [E:56462i S:5419 M:196640847 (Ack:253023448)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:01 (IM:StatusResponse) (B:42) I (951159) chip[IM]: Received status response, status is 0x00 I (951169) chip[EM]: <<< [E:56462i S:5419 M:253023449 (Ack:196640847)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (951169) chip[EM]: >>> [E:30182r S:5419 M:196640848] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (951179) chip[EM]: >>> [E:30183r S:5419 M:196640849] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0001:02 (IM:ReadRequest) (B:51) I (951189) chip[EM]: <<< [E:30182r S:5419 M:253023450 (Ack:196640848)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:258) I (951199) chip[EM]: <<< [E:30183r S:5419 M:253023451 (Ack:196640849)] (S) Msg TX to 4:00000000F040A290 [2409] [UDP:[FE80::83B:F58:88F:2CF4%en1]:62836] --- Type 0001:05 (IM:ReportData) (B:258) I (951219) chip[EM]: >>> [E:30182r S:5419 M:196640850 (Ack:253023450)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) I (951219) chip[EM]: >>> [E:30183r S:5419 M:196640851 (Ack:253023451)] (S) Msg RX from 4:00000000F040A290 [2409] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34)

Screenshot from 2024-10-16 08-44-28

chip-tool device type list is empty

Screenshot from 2024-10-16 09-18-52

halilakpinarr commented 1 week ago

When the device is reset, the descriptor cluster is not created, when you create the descriptor cluster manually, the problem is solved. I think this should be in the SDK.