View Source

Scope

Ultimate goal is to bringup barton in RDK-B and make it available for community as IoT reference

Key insights

Platform selection
Vendor integration
OSS component support
BartonCore integration
Reference Application
Validation with Matter device

Block diagram

Use case diagram

RDK-B IoT Build

Prelimanary
- RDK-B Banana Pi R4 - Beta Engineering Release(05 Feb 2025)
Vendor integration
Comcast recipe changes
- For testing alone.we manually added changes & customized
- Add missing changes like gn
linenoise upstep
update ssid and password in reference/src/reference-network-credentials-provider.c & recompile
My yocto
- barton-exp-recipes.docx
Update image install
- +IMAGE_INSTALL_append = " parodus parodus2ccsp cpcd otbr-agent bluez5-bluetoothd bt-host-cpc-hci-bridge opkg opkg-utils xz barton"

Barton core

c-GW:~# ls /usr/lib/libbrtnDeviceServiceShared.so
/usr/lib/libbrtnDeviceServiceShared.so
root@Filogic-GW:~#
-GW:~# ls /usr/bin/brtn-ds-reference
/usr/bin/brtn-ds-reference --noZigbee
root@Filogic-GW:~#

Verification of barton in RDK-B

mkdir /nvram/icontrol
Make sure we have below services up and running
- cpcd
- otbr-agent
- bt-host-cpc-hci-bridge-attach.service
- bt-host-cpc-hci-bridge.service
mkdir -p /home/root/.brtn-ds
start this application brtn-ds-reference

root@Filogic-GW:~# brtn-ds-reference 
2025-04-10 22:52:47.089 : [zigbeeDriverCommon 112400] - DEBUG: zigbeeDriverCommonCreateDeviceDriver
2025-04-10 22:52:47.110 : [deviceDriverManager 112400] - DEBUG: Loading device driver zigbeeLight
2025-04-10 22:52:47.111 : [zigbeeDriverCommon 112400] - DEBUG: zigbeeDriverCommonCreateDeviceDriver
2025-04-10 22:52:47.111 : [deviceDriverManager 112400] - DEBUG: Loading device driver ZigBeeSensorDD
2025-04-10 22:52:47.111 : [zigbeeDriverCommon 112400] - DEBUG: zigbeeDriverCommonCreateDeviceDriver
2025-04-10 22:52:47.111 : [deviceDriverManager 112400] - DEBUG: Loading device driver zigbeeThermostat
2025-04-10 22:52:47.111 : [zigbeeDriverCommon 112400] - DEBUG: zigbeeDriverCommonCreateDeviceDriver
2025-04-10 22:52:47.111 : [deviceDriverManager 112400] - DEBUG: Loading device driver zigbeeDoorLock
2025-04-10 22:52:47.111 : [zigbeeDriverCommon 112400] - DEBUG: zigbeeDriverCommonCreateDeviceDriver
2025-04-10 22:52:47.111 : [deviceDriverManager 112400] - DEBUG: Loading device driver ZigBeePresenceDD
2025-04-10 22:52:47.111 : [zigbeeDriverCommon 112400] - DEBUG: zigbeeDriverCommonCreateDeviceDriver
2025-04-10 22:52:47.111 : [deviceDriverManager 112400] - DEBUG: Loading device driver zigbeeLightController
2025-04-10 22:52:47.111 : [zigbeeDriverCommon 112400] - DEBUG: zigbeeDriverCommonCreateDeviceDriver
2025-04-10 22:52:47.111 : [deviceDriverManager 112400] - DEBUG: Loading device driver ZigBeeWindowCDD
2025-04-10 22:52:47.111 : [deviceService 112400] - INFO: subsystem 'matter' registered
2025-04-10 22:52:47.114 : [Matter 112400] - DEBUG: MatterDriverFactory (RegisterDriver): matterDoorLock
2025-04-10 22:52:47.114 : [deviceDriverManager 112400] - DEBUG: Loading device driver matterDoorLock
2025-04-10 22:52:47.114 : [Matter 112400] - DEBUG: MatterDriverFactory (RegisterDriver): matterLight
2025-04-10 22:52:47.114 : [deviceDriverManager 112400] - DEBUG: Loading device driver matterLight
2025-04-10 22:52:47.114 : [Matter 112400] - DEBUG: MatterDriverFactory (RegisterDriver): matterWindowCovering
2025-04-10 22:52:47.114 : [deviceDriverManager 112400] - DEBUG: Loading device driver matterWindowCovering
2025-04-10 22:52:47.114 : [deviceService 112400] - INFO: subsystem 'thread' registered
2025-04-10 22:52:47.114 : [deviceService 112400] - INFO: subsystem 'zigbee' registered
2025-04-10 22:52:47.174 : [backupUtil 112400] - DEBUG: File is safe to read, /home/root/.brtn-ds/storage/devicedb/systemProperties
2025-04-10 22:52:47.175 : [device-service-property-provider 112400] - WARN: b_device_service_property_provider_get_property_as_bool: Error getting property 'zigbee.testing.fy
2025-04-10 22:52:47.175 : [deviceCommunicationWatchdog 112400] - DEBUG: commFailWatchdogThreadProc: starting up
2025-04-10 22:52:47.177 : [libdeviceDescriptors 112400] - DEBUG: deviceDescriptorsInit:  using AllowList /home/root/.brtn-ds/AllowList.xml, and DenyList /home/root/.brtn-ds/l
2025-04-10 22:52:47.177 : [deviceDriverManager 112400] - DEBUG: deviceDriverManagerInitialize
2025-04-10 22:52:47.177 : [DevInit 112400] - DEBUG: creating threadpool; workers=0
2025-04-10 22:52:47.177 : [deviceService 112400] - DEBUG: subsystemManagerInitialize
2025-04-10 22:52:47.177 : [deviceService 112400] - DEBUG: subsystemManager: checkSubsystemForMigration - Saving new matterSubsystemVersion (1)
2025-04-10 22:52:47.184 : [storage 112400] - DEBUG: storageSave: saved file /home/root/.brtn-ds/storage/devicedb/systemProperties in 7ms
2025-04-10 22:52:47.184 : [deviceService 112400] - DEBUG: subsystemManager: checkSubsystemForMigration - Saving new threadSubsystemVersion (1)
2025-04-10 22:52:47.196 : [storage 112400] - DEBUG: storageSave: saved file /home/root/.brtn-ds/storage/devicedb/systemProperties in 11ms
2025-04-10 22:52:47.196 : [Matter 112400] - DEBUG: MatterSubsystem (matterSubsystemInitialize): 
2025-04-10 22:52:47.196 : [threadSubsystem 112400] - DEBUG: initialize: 
2025-04-10 22:52:47.196 : [repeatTask 112400] - DEBUG: registering shutdown hook
2025-04-10 22:52:47.196 : [device-service-property-provider 112400] - WARN: b_device_service_property_provider_get_property_as_string: Error getting property 'barton.thread.y
2025-04-10 22:52:47.196 : [threadSubsystem 112400] - DEBUG: initialize: Using Thread network name: TestNetwork
2025-04-10 22:52:47.196 : [device-service-property-provider 112400] - WARN: b_device_service_property_provider_get_property_as_bool: Error getting property 'cpe.zigbee.repory
2025-04-10 22:52:47.196 : [device-service-property-provider 112400] - WARN: b_device_service_property_provider_get_property_as_bool: Error getting property 'cpe.diagnostics.y
2025-04-10 22:52:47.196 : [device-service-property-provider 112400] - WARN: b_device_service_property_provider_get_property_as_uint64: Error getting property 'cpe.diagnosticy
2025-04-10 22:52:47.196 : [device-service-property-provider 112400] - WARN: b_device_service_property_provider_get_property_as_uint64: Error getting property 'cpe.diagnosticy
2025-04-10 22:52:47.196 : [device-service-property-provider 112400] - WARN: b_device_service_property_provider_get_property_as_uint64: Error getting property 'cpe.diagnosticy
2025-04-10 22:52:47.196 : [device-service-property-provider 112400] - WARN: b_device_service_property_provider_get_property_as_uint64: Error getting property 'cpe.diagnosticy
2025-04-10 22:52:47.196 : [deviceDriverManager 112400] - DEBUG: deviceDriverManagerStartDeviceDrivers
2025-04-10 22:52:47.196 : [zigbeeDriverCommon 112400] - DEBUG: startup zigbeeLight
2025-04-10 22:52:47.196 : [zigbeeSubsystem 112400] - DEBUG: zigbeeSubsystemRegisterDiscoveryHandler: zigbeeLight
2025-04-10 22:52:47.196 : [zigbeeDriverCommon 112400] - DEBUG: startup ZigBeeSensorDD
2025-04-10 22:52:47.196 : [zigbeeSubsystem 112400] - DEBUG: zigbeeSubsystemRegisterDiscoveryHandler: ZigBeeSensorDD
2025-04-10 22:52:47.196 : [zigbeeDriverCommon 112400] - DEBUG: startup zigbeeThermostat
2025-04-10 22:52:47.196 : [zigbeeSubsystem 112400] - DEBUG: zigbeeSubsystemRegisterDiscoveryHandler: zigbeeThermostat
2025-04-10 22:52:47.196 : [zigbeeDriverCommon 112400] - DEBUG: startup zigbeeDoorLock
2025-04-10 22:52:47.196 : [zigbeeSubsystem 112400] - DEBUG: zigbeeSubsystemRegisterDiscoveryHandler: zigbeeDoorLock
2025-04-10 22:52:47.196 : [zigbeeDriverCommon 112400] - DEBUG: startup ZigBeePresenceDD
2025-04-10 22:52:47.196 : [zigbeeSubsystem 112400] - DEBUG: zigbeeSubsystemRegisterDiscoveryHandler: ZigBeePresenceDD
2025-04-10 22:52:47.196 : [zigbeeDriverCommon 112400] - DEBUG: startup zigbeeLightController
2025-04-10 22:52:47.196 : [zigbeeSubsystem 112400] - DEBUG: zigbeeSubsystemRegisterDiscoveryHandler: zigbeeLightController
2025-04-10 22:52:47.196 : [zigbeeDriverCommon 112400] - DEBUG: startup ZigBeeWindowCDD
2025-04-10 22:52:47.196 : [zigbeeSubsystem 112400] - DEBUG: zigbeeSubsystemRegisterDiscoveryHandler: ZigBeeWindowCDD
2025-04-10 22:52:47.196 : [MatterBaseDD 112400] - DEBUG: (Startup): 
2025-04-10 22:52:47.196 : [MatterBaseDD 112400] - DEBUG: (Startup): 
2025-04-10 22:52:47.196 : [MatterBaseDD 112400] - DEBUG: (Startup): 
2025-04-10 22:52:47.196 : [deviceService 112400] - DEBUG: subsystemManagerAllDriversStarted
2025-04-10 22:52:47.196 : [deviceService 112400] - INFO: subsystemManager: subsystemManagerIsReadyForDevices - Subsystem matter is not yet ready
2025-04-10 22:52:47.196 : [device-service-property-provider 112400] - WARN: b_device_service_property_provider_get_property_as_uint64: Error getting property 'touchscreen.sey
2025-04-10 22:52:47.197 : [threadSubsystem 112400] - DEBUG: initTaskFunc: 
2025-04-10 22:52:47.198 : [threadSubsystem 112400] - DEBUG: initializeThreadStack: 
2025-04-10 22:52:47.198 : [openThreadClient 112400] - DEBUG: Connect: 
2025-04-10 22:52:47.206 : [MatterSDK 112400] - DEBUG: [CTL] Setting attestation nonce to random value
2025-04-10 22:52:47.209 : [MatterSDK 112400] - DEBUG: [CTL] Setting CSR nonce to random value
2025-04-10 22:52:47.210 : [Matter 112400] - DEBUG: (Init): 
2025-04-10 22:52:47.211 : [storage 112400] - DEBUG: storageSave: saved file /home/root/.brtn-ds/storage/devicedb/systemProperties in 15ms
2025-04-10 22:52:47.211 : [threadSubsystem 112400] - WARN: initializeThreadStack: No network configuration loaded; a new network will be created
2025-04-10 22:52:47.211 : [Matter 112400] - DEBUG: (Init): Local node ID: 0xd8730fd2953874
2025-04-10 22:52:47.211 : [openThreadClient 112400] - DEBUG: CreateNetwork: 
2025-04-10 22:52:47.212 : [openThreadClient 112400] - DEBUG: operator(): Successfully created network TestNetwork
2025-04-10 22:52:47.212 : [device-service-property-provider 112400] - WARN: b_device_service_property_provider_get_property_as_uint64: Error getting property 'barton.matter.y
2025-04-10 22:52:47.212 : [Matter 112400] - DEBUG: (Init): Using vendor ID: 0xfff1
2025-04-10 22:52:47.213 : [threadSubsystem 112400] - ERROR: initializeThreadStack: Failed to create network, trying again in 60 seconds...
2025-04-10 22:52:47.232 : [MatterSDK 112400] - DEBUG: [DL] NVS set: chip-counters/reboot-count = 11 (0xB)
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL] Got Ethernet interface: eth1
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL] Found the primary Ethernet interface:eth1
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL] Device Configuration:
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL]   Serial Number: TEST_SN
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL]   Vendor Id: 65521 (0xFFF1)
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL]   Product Id: 32769 (0x8001)
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL]   Product Name: TEST_PRODUCT
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL]   Hardware Version: 0
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL]   Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL]   Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL]   Manufacturing Date: (not set)
2025-04-10 22:52:47.233 : [MatterSDK 112400] - DEBUG: [DL]   Device Type: 65535 (0xFFFF)
2025-04-10 22:52:47.244 : [Matter 112400] - DEBUG: (Start): 
2025-04-10 22:52:47.244 : [MatterSDK 112400] - DEBUG: [SVR] Initializing subscription resumption storage...
2025-04-10 22:52:47.256 : [MatterSDK 112400] - DEBUG: [SVR] Server initializing...
2025-04-10 22:52:47.256 : [MatterSDK 112400] - DEBUG: [TS] Last Known Good Time: 2025-04-09T22:50:10
2025-04-10 22:52:47.262 : [MatterSDK 112400] - DEBUG: [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xA7B405C82BDF3872, FabricId 0x0000000000000001, 1
2025-04-10 22:52:47.263 : [MatterSDK 112400] - DEBUG: [DMG] AccessControl: initializing
2025-04-10 22:52:47.263 : [MatterSDK 112400] - DEBUG: [DMG] barton::AccessControlDelegate::Init
2025-04-10 22:52:47.263 : [MatterSDK 112400] - DEBUG: [DMG] AccessControl: setting
2025-04-10 22:52:47.263 : [MatterSDK 112400] - DEBUG: [DMG] DefaultAclStorage: initializing
2025-04-10 22:52:47.273 : [MatterSDK 112400] - DEBUG: [DMG] DefaultAclStorage: 1 entries loaded
2025-04-10 22:52:47.273 : [MatterSDK 112400] - DEBUG: [SVR] WARNING: mTestEventTriggerDelegate is null
2025-04-10 22:52:47.314 : [MatterSDK 112400] - DEBUG: [ZCL] Using ZAP configuration...
2025-04-10 22:52:47.314 : [MatterSDK 112400] - DEBUG: [DMG] AccessControlCluster: initializing
2025-04-10 22:52:47.314 : [MatterSDK 112400] - DEBUG: [ZCL] Initiating Admin Commissioning cluster.
2025-04-10 22:52:47.317 : [tbrmd 112400] - DEBUG: Init: 
2025-04-10 22:52:47.317 : [MatterSDK 112400] - DEBUG: [SVR] Fabric already commissioned. Disabling BLE advertisement
2025-04-10 22:52:47.317 : [MatterSDK 112400] - DEBUG: [DIS] Updating services using commissioning mode 0
2025-04-10 22:52:47.327 : [MatterSDK 112400] - DEBUG: [DIS] CHIP minimal mDNS started advertising.
2025-04-10 22:52:47.345 : [MatterSDK 112400] - DEBUG: [DIS] Advertise operational node A7B405C82BDF3872-00D8730FD2953874
2025-04-10 22:52:47.345 : [MatterSDK 112400] - DEBUG: [DIS] CHIP minimal mDNS configured as 'Operational device'; instance name: A7B405C82BDF3872-00D8730FD2953874.
2025-04-10 22:52:47.360 : [MatterSDK 112400] - DEBUG: [DIS] mDNS service published: _matter._tcp
2025-04-10 22:52:47.360 : [MatterSDK 112400] - DEBUG: [DIS] Advertise commission parameter vendorID=65521 productID=32769 discriminator=0000/00 cm=0 cp=0
2025-04-10 22:52:47.360 : [MatterSDK 112400] - DEBUG: [DIS] CHIP minimal mDNS configured as 'Commissioner device'; instance name: EE7F91E4F8DE6D17.
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [DIS] mDNS service published: _matterd._udp
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [IN] CASE Server enabling CASE session setups
2025-04-10 22:52:47.377 : [MatterSDK 112400] - ERROR: [IM] WARNING ┌────────────────────────────────────────────────────
2025-04-10 22:52:47.377 : [MatterSDK 112400] - ERROR: [IM] WARNING │ Interaction Model Engine running in 'Checked' mode.
2025-04-10 22:52:47.377 : [MatterSDK 112400] - ERROR: [IM] WARNING │ This executes BOTH ember and data-model code paths.
2025-04-10 22:52:47.377 : [MatterSDK 112400] - ERROR: [IM] WARNING │ which is inefficient and consumes more flash space.
2025-04-10 22:52:47.377 : [MatterSDK 112400] - ERROR: [IM] WARNING │ This should be done for testing only.
2025-04-10 22:52:47.377 : [MatterSDK 112400] - ERROR: [IM] WARNING └────────────────────────────────────────────────────
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [SVR] Joining Multicast groups
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [SVR] Server initialization complete
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [SVR] Server Listening...
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [DL] Device Configuration:
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [DL]   Serial Number: TEST_SN
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [DL]   Vendor Id: 65521 (0xFFF1)
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [DL]   Product Id: 32769 (0x8001)
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [DL]   Product Name: TEST_PRODUCT
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [DL]   Hardware Version: 0
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [DL]   Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [DL]   Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [DL]   Manufacturing Date: (not set)
2025-04-10 22:52:47.377 : [MatterSDK 112400] - DEBUG: [DL]   Device Type: 65535 (0xFFFF)
2025-04-10 22:52:47.378 : [MatterStorage 112400] - DEBUG: (SyncGetKeyValue): key = g/gcc
2025-04-10 22:52:47.379 : [MatterStorage 112400] - DEBUG: (SyncGetKeyValue): key = g/gdc
2025-04-10 22:52:47.379 : [MatterStorage 112400] - DEBUG: (SyncSetKeyValue): key = g/gcc
2025-04-10 22:52:47.387 : [MatterStorage 112400] - DEBUG: (SyncSetKeyValue): key = g/gdc
2025-04-10 22:52:47.400 : [MatterSDK 112400] - DEBUG: [ZCL] Using ZAP configuration...
2025-04-10 22:52:47.400 : [MatterSDK 112400] - DEBUG: [DMG] AccessControlCluster: initializing
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.400 : [MatterSDK 112400] - DEBUG: [ZCL] Initiating Admin Commissioning cluster.
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.400 : [MatterSDK 112400] - ERROR: [IM] Duplicate attribute override registration failed
2025-04-10 22:52:47.401 : [MatterSDK 112400] - ERROR: [IM] WARNING ┌────────────────────────────────────────────────────
2025-04-10 22:52:47.401 : [MatterSDK 112400] - ERROR: [IM] WARNING │ Interaction Model Engine running in 'Checked' mode.
2025-04-10 22:52:47.401 : [MatterSDK 112400] - ERROR: [IM] WARNING │ This executes BOTH ember and data-model code paths.
2025-04-10 22:52:47.401 : [MatterSDK 112400] - ERROR: [IM] WARNING │ which is inefficient and consumes more flash space.
2025-04-10 22:52:47.401 : [MatterSDK 112400] - ERROR: [IM] WARNING │ This should be done for testing only.
2025-04-10 22:52:47.401 : [MatterSDK 112400] - ERROR: [IM] WARNING └────────────────────────────────────────────────────
2025-04-10 22:52:47.401 : [MatterSDK 112400] - DEBUG: [CTL] Intermediate CA is not needed
2025-04-10 22:52:47.401 : [MatterSDK 112400] - DEBUG: [FP] Validating NOC chain
2025-04-10 22:52:47.402 : [MatterSDK 112400] - DEBUG: [FP] NOC chain validation successful
2025-04-10 22:52:47.402 : [MatterSDK 112400] - DEBUG: [FP] Updated fabric at index: 0x1, Node ID: 0x00D8730FD2953874
2025-04-10 22:52:47.402 : [MatterSDK 112400] - DEBUG: [TS] Last Known Good Time: 2025-04-09T22:50:10
2025-04-10 22:52:47.403 : [MatterSDK 112400] - DEBUG: [TS] New proposed Last Known Good Time: 2025-04-09T22:50:10
2025-04-10 22:52:47.403 : [MatterSDK 112400] - DEBUG: [TS] Retaining current Last Known Good Time
2025-04-10 22:52:47.403 : [MatterStorage 112400] - DEBUG: (SyncGetKeyValue): key = g/sri
2025-04-10 22:52:47.421 : [MatterSDK 112400] - DEBUG: [FP] Metadata for Fabric 0x1 persisted to storage.
2025-04-10 22:52:47.433 : [MatterSDK 112400] - DEBUG: [TS] Committing Last Known Good Time to storage: 2025-04-09T22:50:10
2025-04-10 22:52:47.442 : [MatterSDK 112400] - DEBUG: [ZCL] OpCreds: Fabric index 0x1 was committed to storage. Compressed Fabric Id 0xA7B405C82BDF3872, FabricId 000000000001
2025-04-10 22:52:47.451 : [MatterSDK 112400] - DEBUG: [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: A7B405C82BDF3872)
2025-04-10 22:52:47.451 : [MatterStorage 112400] - DEBUG: (SyncGetKeyValue): key = f/1/g
2025-04-10 22:52:47.451 : [MatterStorage 112400] - DEBUG: (SyncGetKeyValue): key = f/1/k/0
2025-04-10 22:52:47.465 : [MatterSDK 112400] - DEBUG: [FP] Metadata for Fabric 0x1 persisted to storage.
2025-04-10 22:52:47.465 : [Matter 112400] - DEBUG: (ConfigureOTAProviderNode): 
2025-04-10 22:52:47.465 : [Matter 112400] - DEBUG: (IsAccessibleByOTARequestors): 
2025-04-10 22:52:47.465 : [MatterSDK 112400] - DEBUG: [DIS] Advertise operational node A7B405C82BDF3872-00D8730FD2953874
2025-04-10 22:52:47.465 : [MatterSDK 112400] - DEBUG: [DIS] CHIP minimal mDNS configured as 'Operational device'; instance name: A7B405C82BDF3872-00D8730FD2953874.
2025-04-10 22:52:47.481 : [MatterSDK 112400] - DEBUG: [DIS] mDNS service published: _matter._tcp
2025-04-10 22:52:47.481 : [MatterSDK 112400] - DEBUG: [SPT] InitCommissioner nodeId=0x00D8730FD2953874 fabric.fabricId=0x0000000000000001 fabricIndex=0x1
2025-04-10 22:52:47.481 : [Matter 112400] - WARN: (SetAccessRestrictionList): Setting Access Restrictions
2025-04-10 22:52:47.481 : [Matter 112400] - DEBUG: (StackThreadProc): 
2025-04-10 22:52:47.481 : [deviceService 112400] - DEBUG: onSubsystemInitialized: 'matter'
2025-04-10 22:52:47.481 : [deviceDriverManager 112400] - DEBUG: deviceDriverManagerGetDeviceDriversBySubsystem: subsystem=matter
2025-04-10 22:52:47.482 : [deviceService 112400] - DEBUG: subsystemManager: subsystemManagerGetRegisteredSubsystems - 
2025-04-10 22:52:47.482 : [deviceService 112400] - DEBUG: subsystemManager: subsystemManagerGetSubsystemStatusJson - 
2025-04-10 22:52:47.482 : [Matter 112400] - DEBUG: MatterSubsystem (getStatusJson): 
2025-04-10 22:52:47.482 : [deviceService 112400] - DEBUG: subsystemManager: subsystemManagerGetSubsystemStatusJson - 
2025-04-10 22:52:47.482 : [threadSubsystem 112400] - DEBUG: getStatusJson: 
2025-04-10 22:52:47.482 : [deviceService 112400] - INFO: subsystemManager: subsystemManagerIsReadyForDevices - Subsystem thread is not yet ready
2025-04-10 22:52:47.568 : [MatterSDK 112400] - ERROR: [DL] Disabling CHIPoBLE service due to error: src/platform/Linux/BLEManagerImpl.cpp:551: Ble Error 0x00000401: BLE adape
2025-04-10 22:52:47.568 : [MatterSDK 112400] - DEBUG: [DIS] Updating services using commissioning mode 0
2025-04-10 22:52:47.577 : [MatterSDK 112400] - DEBUG: [DIS] CHIP minimal mDNS started advertising.
2025-04-10 22:52:47.608 : [MatterSDK 112400] - DEBUG: [DIS] Advertise operational node A7B405C82BDF3872-00D8730FD2953874
2025-04-10 22:52:47.608 : [MatterSDK 112400] - DEBUG: [DIS] CHIP minimal mDNS configured as 'Operational device'; instance name: A7B405C82BDF3872-00D8730FD2953874.
2025-04-10 22:52:47.624 : [MatterSDK 112400] - DEBUG: [DIS] mDNS service published: _matter._tcp
2025-04-10 22:52:47.624 : [MatterSDK 112400] - DEBUG: [DIS] Advertise commission parameter vendorID=65521 productID=32769 discriminator=0000/00 cm=0 cp=0
2025-04-10 22:52:47.624 : [MatterSDK 112400] - DEBUG: [DIS] CHIP minimal mDNS configured as 'Commissioner device'; instance name: EE7F91E4F8DE6D17.
2025-04-10 22:52:47.639 : [MatterSDK 112400] - DEBUG: [DIS] mDNS service published: _matterd._udp
2025-04-10 22:52:47.639 : [tbrmd 112400] - DEBUG: OnMatterPlatformEventHandler: 
2025-04-10 22:52:47.639 : [Matter 112400] - DEBUG: (EventHandler): EventType=8012
2025-04-10 22:52:47.639 : [tbrmd 112400] - DEBUG: OnMatterPlatformEventHandler: 
2025-04-10 22:52:47.639 : [Matter 112400] - DEBUG: (EventHandler): EventType=8016

CLI

deviceService> help
Core:
        discoverStart|dstart <device class> [setup code] : Start discovery for a device class with optional setup code for Matter devices
        discoverStop|dstop  : Stop device discovery
        listDevices|list [-i] [device class] : list all devices, or all devices in a class. Use -i to show device IDs only
        printDevice|pd <uuid> : print information for a device
        printAllDevices|pa [device class] : print information for all devices, or all devices in a class
        readResource|rr <uri> : read the value of a resource
        Examples:
                readResource /000d6f000aae8410/r/communicationFailure

        writeResource|wr <uri> [value] : write the value of a resource
        Examples:
                writeResource /000d6f000aae8410/ep/1/r/label "Front Door"

        execResource|er <uri> [value] : execute a resource
        queryResources|qr <uri pattern> : query resources with a pattern
        Examples:
                qr */lowBatt

        readMetadata|rm <uri> : read metadata
        Examples:
                rm /000d6f000aae8410/m/lpmPolicy

        writeMetadata|wm <uri> : write metadata
        Examples:
                wm /000d6f000aae8410/m/lpmPolicy never

        queryMetadata|qm <uri pattern> : query metadata through a uri pattern
        Examples:
                qm */rejoins

        getStatus|gs  : Get the status of device service
        dumpDevice|dd <uuid> : Dump all details about a device
        dumpAllDevices|dump  : Dump all details about all devices
        removeDevice|rd <uuid> : Remove a device by uuid
        removeEndpoint|re <uri> : Remove an endpoint by uri
        getProperty|gp <key> : Get a property value
        setProperty|sp <key> [value] : Set a property value
        ddl override <path> | clearoverride | process | bypass | clearbypass : Configure and control device descriptor processing
        Examples:
                ddl override /opt/etc/AllowList.xml.override
                ddl clearoverride
                ddl process
                ddl bypass
                ddl clearbypass

Matter:
        commissionDevice|cd <setup payload> : Commission a specific device with the provided setup payload
        addMatterDevice|amd <node id> : Add a Matter device, which has already been commissioned onto the fabric, to the databaseafter locating and configuring it
        openCommissioningWindow|ocw <node id> [timeout secs] : Open the commissioning window locally (node id 0) or for a specific node id. Omit timeout for defaults
Thread:
        setNat64Enabled|sne <true|false> : Enable or disable the Thread Border Router's NAT64 system
        activateEphemeralKeyMode|aekm  : Activate ephemeral key mode and print the ePSKc
deviceService>

system status

    root@Filogic-GW:~# systemctl status otbr-agent
* otbr-agent.service - OpenThread Border Router service
     Loaded: loaded (8;;file://Filogic-GW/lib/systemd/system/otbr-agent.service/lib/systemd/system/otbr-agent.service8;;; disabled; vendor preset: disabled)
     Active: active (running) since Thu 2022-04-28 17:42:30 UTC; 2 years 11 months ago
TriggeredBy: * otbr-agent.path
   Main PID: 1856 (otbr-agent)
      Tasks: 2 (limit: 4713)
     Memory: 4.7M
     CGroup: /system.slice/otbr-agent.service
             `- 1856 otbr-agent -v -d5 -I wpan0 -B brlan0 "spinel+cpc://cpcd_0?iid=2&iid-list=0" trel://brlan0

Notice: journal has been rotated since unit was started, output may be incomplete.
root@Filogic-GW:~# 
root@Filogic-GW:~# systemctl status cpcd      
* cpcd.service - Service for the 802.15.4 co-processor communication daemon
     Loaded: loaded (8;;file://Filogic-GW/lib/systemd/system/cpcd.service/lib/systemd/system/cpcd.service8;;; enabled; vendor preset: enabled)
     Active: active (running) since Thu 2022-04-28 17:42:28 UTC; 2 years 11 months ago
   Main PID: 1767 (cpcd)
      Tasks: 5 (limit: 4713)
     Memory: 1.5M
     CGroup: /system.slice/cpcd.service
             `- 1767 /usr/bin/cpcd -c /etc/cpcd.conf

Notice: journal has been rotated since unit was started, output may be incomplete.
root@Filogic-GW:~# 

root@Filogic-GW:~# systemctl status bt-host-cpc-hci-bridge-attach.service
* bt-host-cpc-hci-bridge-attach.service - Attach Bluetooth HCI Bridge
     Loaded: loaded (8;;file://Filogic-GW/lib/systemd/system/bt-host-cpc-hci-bridge-attach.service/lib/systemd/system/bt-host-cpc-hci-bridge-attach.service8;;; enabled; vend)
     Active: active (running) since Thu 2022-04-28 17:42:30 UTC; 2 years 11 months ago
   Main PID: 1872 (btattach)
      Tasks: 1 (limit: 4713)
     Memory: 420.0K
     CGroup: /system.slice/bt-host-cpc-hci-bridge-attach.service
             `- 1872 /usr/bin/btattach -B /dev/pts/0 -S 115200

Notice: journal has been rotated since unit was started, output may be incomplete.
root@Filogic-GW:~# systemctl status bt-host-cpc-hci-bridge.service
* bt-host-cpc-hci-bridge.service - Bluetooth CPC HCI Bridge
     Loaded: loaded (8;;file://Filogic-GW/lib/systemd/system/bt-host-cpc-hci-bridge.service/lib/systemd/system/bt-host-cpc-hci-bridge.service8;;; enabled; vendor preset: ena)
     Active: active (running) since Thu 2022-04-28 17:42:28 UTC; 2 years 11 months ago
   Main PID: 1768 (bt_host_cpc_hci)
      Tasks: 2 (limit: 4713)
     Memory: 484.0K
     CGroup: /system.slice/bt-host-cpc-hci-bridge.service
             `- 1768 /usr/bin/bt_host_cpc_hci_bridge

Notice: journal has been rotated since unit was started, output may be incomplete.
root@Filogic-GW:~# igmpProxyRun: difftime is > 1000 secs, resetting to 0

Matter onboarding stages Barton

Vendor integration

Silabs radio integration
- OpenThread & Bluetooth platform integration in BPI R4 with silabs radio

OSS component support

linenoise upstep
- Revision details
  - https://github.com/antirez/linenoise/commit/d895173d679be70bcd8b23041fff3e458e1a3506
  - Recipe changes
    - Note:depending on usage please include 001 patch too
- File location
  - /home/cpokur625/BI/meta-rdk-ext/recipes-support/linenoise

Verification with matter device

deviceService> cd 03764151187
Attempting to commission device
2025-04-10 23:05:26.638 : [Matter 177014] - DEBUG: MatterSubsystem (matterSubsystemCommissionDevice): 
2025-04-10 23:05:26.638 : [MatterCommishOrch 177014] - DEBUG: (Commission): 
2025-04-10 23:05:26.638 : [Matter 177014] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = Pending
2025-04-10 23:05:26.638 : [MatterCommishOrch 177014] - DEBUG: (CommissionWorkFunc): 
2025-04-10 23:05:26.638 : [Matter 177014] - DEBUG: (GetCommissioningParams): 
2025-04-10 23:05:26.638 : [threadSubsystem 177014] - DEBUG: threadSubsystemGetNetworkInfo: 
2025-04-10 23:05:26.638 : [Matter 177014] - WARN: (GetCommissioningParams): Was unable to get thread operational dataset from the subsystem
2025-04-10 23:05:26.638 : [MatterCommishOrch 177014] - DEBUG: (CommissionWorkFunc): Calling PairDevice()
2025-04-10 23:05:26.638 : [MatterSDK 177014] - DEBUG: [CTL] Setting wifi credentials from parameters
2025-04-10 23:05:26.638 : [MatterSDK 177014] - DEBUG: [CTL] Setting attestation nonce to random value
2025-04-10 23:05:26.638 : [MatterSDK 177014] - DEBUG: [CTL] Setting CSR nonce to random value
2025-04-10 23:05:26.638 : [MatterSDK 177014] - DEBUG: [CTL] Starting commissioning discovery over BLE
2025-04-10 23:05:26.638 : [MatterSDK 177014] - DEBUG: [CTL] WiFi-PAF: has RendezvousInformationFlag::kWiFiPAF
2025-04-10 23:05:26.638 : [MatterSDK 177014] - DEBUG: [CTL] Starting commissioning discovery over DNS-SD
2025-04-10 23:05:26.639 : [Matter 177014] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = Started
2025-04-10 23:05:26.639 : [MatterSDK 177014] - DEBUG: [BLE] BLE removing known devices
2025-04-10 23:05:26.641 : [MatterSDK 177014] - DEBUG: [BLE] BLE initiating scan
2025-04-10 23:05:26.677 : [MatterCommishOrch 177014] - DEBUG: OnDiscoveredDevice: fe80::32de:4bff:fedb:8034
2025-04-10 23:05:26.677 : [Matter 177014] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = DeviceFound
2025-04-10 23:05:26.677 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:26.677 : [MatterSDK 177014] - DEBUG: [CTL] Attempting PASE connection to UDP:[fe80::32de:4bff:fedb:8034%brlan0]:5540
2025-04-10 23:05:26.678 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16090i S:0 M:149377291] (U) Msg TX from 0227AE897A7DC92D to 0:0000000000000000 [0000] [UDP:[fe80::32de:4bff)
2025-04-10 23:05:26.678 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16090i S:0 M:149377291] (U) Msg Retransmission to 0:0000000000000000 in 396ms [State:Active II:5000 AI:300 ]
2025-04-10 23:05:26.678 : [MatterSDK 177014] - DEBUG: [SC] Sent PBKDF param request [II:500ms AI:300ms AT:4000ms)
2025-04-10 23:05:26.678 : [MatterSDK 177014] - DEBUG: [CTL] Commission called for node ID 0x3F5A1FB8162C478C
2025-04-10 23:05:26.687 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16090i S:0 M:114414583 (Ack:149377291)] (U) Msg RX from 0:0000000000000000 [0000] to 0227AE897A7DC92D --- T)
2025-04-10 23:05:26.689 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16090i S:0 M:114414584 (Ack:149377291)] (U) Msg RX from 0:0000000000000000 [0000] to 0227AE897A7DC92D --- T)
2025-04-10 23:05:26.758 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16090i S:0 M:149377292 (Ack:114414584)] (U) Msg TX from 0227AE897A7DC92D to 0:0000000000000000 [0000] [UDP:)
2025-04-10 23:05:26.759 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16090i S:0 M:149377292] (U) Msg Retransmission to 0:0000000000000000 in 358ms [State:Active II:5000 AI:300 ]
2025-04-10 23:05:26.764 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16090i S:0 M:114414585 (Ack:149377292)] (U) Msg RX from 0:0000000000000000 [0000] to 0227AE897A7DC92D --- T)
2025-04-10 23:05:27.688 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16090i S:0 M:114414586 (Ack:149377292)] (U) Msg RX from 0:0000000000000000 [0000] to 0227AE897A7DC92D --- T)
2025-04-10 23:05:27.690 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16090i S:0 M:149377293 (Ack:114414586)] (U) Msg TX from 0227AE897A7DC92D to 0:0000000000000000 [0000] [UDP:)
2025-04-10 23:05:27.690 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16090i S:0 M:149377293] (U) Msg Retransmission to 0:0000000000000000 in 384ms [State:Active II:5000 AI:300 ]
2025-04-10 23:05:27.693 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.694 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.695 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.697 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.698 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.699 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.700 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.701 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.701 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.702 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.704 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.705 : [MatterSDK 177014] - DEBUG: [CTL] Discovered device to be commissioned over DNS-SD
2025-04-10 23:05:27.705 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16090i S:0 M:114414587 (Ack:149377293)] (U) Msg RX from 0:0000000000000000 [0000] to 0227AE897A7DC92D --- T)
2025-04-10 23:05:27.706 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16090i S:0 M:114414588 (Ack:149377293)] (U) Msg RX from 0:0000000000000000 [0000] to 0227AE897A7DC92D --- T)
2025-04-10 23:05:27.706 : [MatterSDK 177014] - DEBUG: [SC] SecureSession[0x7f54007d90, LSID:211]: State change 'kEstablishing' --> 'kActive'
2025-04-10 23:05:27.706 : [MatterSDK 177014] - DEBUG: [CTL] PASE session established with commissionee. Stopping discovery.
2025-04-10 23:05:27.820 : [MatterSDK 177014] - DEBUG: [BLE] Scan complete. No matching device found.
2025-04-10 23:05:27.820 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo'
2025-04-10 23:05:27.820 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'ReadCommissioningInfo'
2025-04-10 23:05:27.820 : [MatterSDK 177014] - DEBUG: [CTL] Sending read request for commissioning information
2025-04-10 23:05:27.820 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:27.820 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16091i S:211 M:118507442] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::32de:4b)
2025-04-10 23:05:27.821 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16091i S:211 M:118507442] (S) Msg Retransmission to 0:FFFFFFFB00000000 in 402ms [State:Active II:5000 AI:30]
2025-04-10 23:05:27.821 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16090i S:0 M:149377294 (Ack:114414588)] (U) Msg TX from 0227AE897A7DC92D to 0:0000000000000000 [0000] [UDP:)
2025-04-10 23:05:27.821 : [tbrmd 177014] - DEBUG: OnMatterPlatformEventHandler: 
2025-04-10 23:05:27.821 : [Matter 177014] - DEBUG: (EventHandler): EventType=8018
2025-04-10 23:05:27.862 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16091i S:211 M:201451909 (Ack:118507442)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:27.863 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='ReadCommissioningInfo' error='src/controll'
2025-04-10 23:05:27.863 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'ReadCommissioningInfo'
2025-04-10 23:05:27.863 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'ReadCommissioningInfo' -> 'ReadCommissioningInfo2'
2025-04-10 23:05:27.863 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'ReadCommissioningInfo2'
2025-04-10 23:05:27.863 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:27.863 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16092i S:211 M:118507443] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::32de:4b)
2025-04-10 23:05:27.863 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16092i S:211 M:118507443] (S) Msg Retransmission to 0:FFFFFFFB00000000 in 401ms [State:Active II:5000 AI:30]
2025-04-10 23:05:27.864 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16091i S:211 M:118507444 (Ack:201451909)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UD)
2025-04-10 23:05:27.873 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16092i S:211 M:201451910 (Ack:118507443)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:27.873 : [MatterSDK 177014] - DEBUG: [CTL] ----- NetworkCommissioning Features: has WiFi. endpointid = 0
2025-04-10 23:05:27.873 : [MatterCommishOrch 177014] - DEBUG: (OnReadCommissioningInfo): Node 0000000000000000
2025-04-10 23:05:27.873 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='ReadCommissioningInfo2' error='src/control'
2025-04-10 23:05:27.873 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'ReadCommissioningInfo2'
2025-04-10 23:05:27.873 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'ReadCommissioningInfo2' -> 'ArmFailSafe'
2025-04-10 23:05:27.873 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'ArmFailSafe'
2025-04-10 23:05:27.873 : [MatterSDK 177014] - DEBUG: [CTL] Arming failsafe (60 seconds)
2025-04-10 23:05:27.874 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16093i S:211 M:118507445] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::32de:4b)
2025-04-10 23:05:27.874 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16093i S:211 M:118507445] (S) Msg Retransmission to 0:FFFFFFFB00000000 in 401ms [State:Active II:5000 AI:30]
2025-04-10 23:05:27.875 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16092i S:211 M:118507446 (Ack:201451910)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UD)
2025-04-10 23:05:27.883 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16093i S:211 M:201451911 (Ack:118507445)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:27.883 : [MatterSDK 177014] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
2025-04-10 23:05:27.884 : [MatterSDK 177014] - DEBUG: [CTL] Received ArmFailSafe response errorCode=0
2025-04-10 23:05:27.884 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='ArmFailSafe' error='src/controller/CHIPDev'
2025-04-10 23:05:27.884 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'ArmFailSafe'
2025-04-10 23:05:27.884 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory'
2025-04-10 23:05:27.884 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'ConfigRegulatory'
2025-04-10 23:05:27.884 : [MatterSDK 177014] - DEBUG: [CTL] Setting Regulatory Config
2025-04-10 23:05:27.884 : [MatterSDK 177014] - DEBUG: [CTL] Device does not support configurable regulatory location
2025-04-10 23:05:27.884 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16094i S:211 M:118507447] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::32de:4b)
2025-04-10 23:05:27.884 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16094i S:211 M:118507447] (S) Msg Retransmission to 0:FFFFFFFB00000000 in 388ms [State:Active II:5000 AI:30]
2025-04-10 23:05:27.884 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16093i S:211 M:118507448 (Ack:201451911)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UD)
2025-04-10 23:05:27.985 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16094i S:211 M:201451912 (Ack:118507447)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:27.985 : [MatterSDK 177014] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003
2025-04-10 23:05:27.985 : [MatterSDK 177014] - DEBUG: [CTL] Received SetRegulatoryConfig response errorCode=0
2025-04-10 23:05:27.985 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='ConfigRegulatory' error='src/controller/CH'
2025-04-10 23:05:27.985 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'ConfigRegulatory'
2025-04-10 23:05:27.985 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest'
2025-04-10 23:05:27.985 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'SendPAICertificateRequest'
2025-04-10 23:05:27.985 : [MatterSDK 177014] - DEBUG: [CTL] Sending request for PAI certificate
2025-04-10 23:05:27.985 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16095i S:211 M:118507449] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::32de:4b)
2025-04-10 23:05:27.985 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16095i S:211 M:118507449] (S) Msg Retransmission to 0:FFFFFFFB00000000 in 359ms [State:Active II:5000 AI:30]
2025-04-10 23:05:27.986 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16094i S:211 M:118507450 (Ack:201451912)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UD)
2025-04-10 23:05:28.018 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16095i S:211 M:201451913 (Ack:118507449)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:28.018 : [MatterSDK 177014] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
2025-04-10 23:05:28.018 : [MatterSDK 177014] - DEBUG: [CTL] Received certificate chain from the device
2025-04-10 23:05:28.018 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='SendPAICertificateRequest' error='src/cont'
2025-04-10 23:05:28.018 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'SendPAICertificateRequest'
2025-04-10 23:05:28.018 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest'
2025-04-10 23:05:28.018 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'SendDACCertificateRequest'
2025-04-10 23:05:28.018 : [MatterSDK 177014] - DEBUG: [CTL] Sending request for DAC certificate
2025-04-10 23:05:28.019 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16096i S:211 M:118507451] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::32de:4b)
2025-04-10 23:05:28.019 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16096i S:211 M:118507451] (S) Msg Retransmission to 0:FFFFFFFB00000000 in 331ms [State:Active II:5000 AI:30]
2025-04-10 23:05:28.019 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16095i S:211 M:118507452 (Ack:201451913)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UD)
2025-04-10 23:05:28.032 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16096i S:211 M:201451914 (Ack:118507451)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:28.032 : [MatterSDK 177014] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
2025-04-10 23:05:28.032 : [MatterSDK 177014] - DEBUG: [CTL] Received certificate chain from the device
2025-04-10 23:05:28.032 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='SendDACCertificateRequest' error='src/cont'
2025-04-10 23:05:28.032 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'SendDACCertificateRequest'
2025-04-10 23:05:28.032 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest'
2025-04-10 23:05:28.032 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'SendAttestationRequest'
2025-04-10 23:05:28.032 : [MatterSDK 177014] - DEBUG: [CTL] Sending Attestation Request to the device.
2025-04-10 23:05:28.032 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16097i S:211 M:118507453] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::32de:4b)
2025-04-10 23:05:28.033 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16097i S:211 M:118507453] (S) Msg Retransmission to 0:FFFFFFFB00000000 in 349ms [State:Active II:5000 AI:30]
2025-04-10 23:05:28.033 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16096i S:211 M:118507454 (Ack:201451914)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UD)
2025-04-10 23:05:28.047 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16097i S:211 M:201451915 (Ack:118507453)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:28.237 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16097i S:211 M:201451916 (Ack:118507453)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:28.237 : [MatterSDK 177014] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001
2025-04-10 23:05:28.237 : [MatterSDK 177014] - DEBUG: [CTL] Received Attestation Information from the device
2025-04-10 23:05:28.237 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='SendAttestationRequest' error='src/control'
2025-04-10 23:05:28.237 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'SendAttestationRequest'
2025-04-10 23:05:28.237 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification'
2025-04-10 23:05:28.237 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'AttestationVerification'
2025-04-10 23:05:28.237 : [MatterSDK 177014] - DEBUG: [CTL] Verifying attestation
2025-04-10 23:05:28.252 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='AttestationVerification' error='src/contro'
2025-04-10 23:05:28.252 : [MatterSDK 177014] - ERROR: [CTL] Error on commissioning step 'AttestationVerification': 'src/controller/CHIPDeviceController.cpp:1283: CHIP Error '
2025-04-10 23:05:28.252 : [MatterSDK 177014] - ERROR: [CTL] Failed verifying attestation information. Now checking DAC chain revoked status.
2025-04-10 23:05:28.252 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'AttestationVerification' -> 'AttestationRevocationCheck'
2025-04-10 23:05:28.252 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'AttestationRevocationCheck' with completion status = 'src/controller/CHIPDevi'
2025-04-10 23:05:28.252 : [MatterSDK 177014] - DEBUG: [CTL] Verifying device's DAC chain revocation status
2025-04-10 23:05:28.252 : [MatterSDK 177014] - ERROR: [CTL] Failed in verifying 'Attestation Information' command received from the device: err 101. Look at AttestationVerifs
2025-04-10 23:05:28.252 : [MatterSDK 177014] - DEBUG: [CTL] Proceeding without changing fail-safe timer value as delegate has not set it
2025-04-10 23:05:28.252 : [MatterSDK 177014] - DEBUG: [CTL] Device attestation completed, delegating continuation to client
2025-04-10 23:05:28.252 : [Matter 177014] - DEBUG: (OnDeviceAttestationCompleted): 
2025-04-10 23:05:28.252 : [Matter 177014] - WARN: (OnDeviceAttestationCompleted): Attestation failed, but we are allowing it anyway
2025-04-10 23:05:28.252 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='AttestationRevocationCheck' error='/usr/sr'
2025-04-10 23:05:28.252 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'AttestationRevocationCheck'
2025-04-10 23:05:28.252 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'AttestationRevocationCheck' -> 'SendOpCertSigningRequest'
2025-04-10 23:05:28.252 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'SendOpCertSigningRequest'
2025-04-10 23:05:28.253 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16098i S:211 M:118507455] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::32de:4b)
2025-04-10 23:05:28.253 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16098i S:211 M:118507455] (S) Msg Retransmission to 0:FFFFFFFB00000000 in 376ms [State:Active II:5000 AI:30]
2025-04-10 23:05:28.253 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16097i S:211 M:118507456 (Ack:201451916)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UD)
2025-04-10 23:05:28.260 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16098i S:211 M:201451917 (Ack:118507455)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:28.807 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16098i S:211 M:201451918 (Ack:118507455)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:28.807 : [MatterSDK 177014] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005
2025-04-10 23:05:28.807 : [MatterSDK 177014] - DEBUG: [CTL] Received certificate signing request from the device
2025-04-10 23:05:28.807 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='SendOpCertSigningRequest' error='src/contr'
2025-04-10 23:05:28.807 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'SendOpCertSigningRequest'
2025-04-10 23:05:28.807 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR'
2025-04-10 23:05:28.807 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'ValidateCSR'
2025-04-10 23:05:28.810 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='ValidateCSR' error='src/credentials/attest'
2025-04-10 23:05:28.810 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'ValidateCSR'
2025-04-10 23:05:28.810 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain'
2025-04-10 23:05:28.810 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'GenerateNOCChain'
2025-04-10 23:05:28.810 : [MatterSDK 177014] - DEBUG: [CTL] Getting certificate chain for the device from the issuer
2025-04-10 23:05:28.813 : [MatterSDK 177014] - DEBUG: [CTL] Verifying Certificate Signing Request
2025-04-10 23:05:28.813 : [MatterStorage 177014] - DEBUG: (SyncGetKeyValue): key = comcastSelfSignedRootCA_privateKey
2025-04-10 23:05:28.813 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16098i S:211 M:118507457 (Ack:201451918)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UD)
2025-04-10 23:05:28.814 : [MatterStorage 177014] - DEBUG: (SyncGetKeyValue): key = comcastSelfSignedRootCA_certificate
2025-04-10 23:05:28.818 : [MatterSDK 177014] - DEBUG: [CTL] Received callback from the CA for NOC Chain generation. Status third_party/comcast/controller/certifier-credentias
2025-04-10 23:05:28.818 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='GenerateNOCChain' error='third_party/comca'
2025-04-10 23:05:28.818 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'GenerateNOCChain'
2025-04-10 23:05:28.819 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'SendTrustedRootCert'
2025-04-10 23:05:28.819 : [MatterSDK 177014] - DEBUG: [CTL] Sending root certificate to the device
2025-04-10 23:05:28.819 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16099i S:211 M:118507458] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::32de:4b)
2025-04-10 23:05:28.819 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16099i S:211 M:118507458] (S) Msg Retransmission to 0:FFFFFFFB00000000 in 363ms [State:Active II:5000 AI:30]
2025-04-10 23:05:28.819 : [MatterSDK 177014] - DEBUG: [CTL] Sent root certificate to the device
2025-04-10 23:05:28.825 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16099i S:211 M:201451919 (Ack:118507458)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:29.218 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16099i S:211 M:201451920 (Ack:118507458)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:29.218 : [MatterSDK 177014] - DEBUG: [DMG] Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0
2025-04-10 23:05:29.218 : [MatterSDK 177014] - DEBUG: [CTL] Device confirmed that it has received the root certificate
2025-04-10 23:05:29.218 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='SendTrustedRootCert' error='src/controller'
2025-04-10 23:05:29.218 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'SendTrustedRootCert'
2025-04-10 23:05:29.218 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC'
deviceSe[  578.403586] systemd-journald[1074]: Data hash table of /run/log/journal/9d4624db79514687a5f674609ebd66ba/system.journal has a fill level at 75.0 (5461 of 7281 ite.
2025-04-10[  578.426153] systemd-journald[1074]: /run/log/journal/9d4624db79514687a5f674609ebd66ba/system.journal: Journal header limits reached or header out-of-date, rotat.
 23:05:29.218 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'SendNOC'
2025-04-10 23:05:29.218 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16100i S:211 M:118507459] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UDP:[fe80::32de:4b)
2025-04-10 23:05:29.218 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16100i S:211 M:118507459] (S) Msg Retransmission to 0:FFFFFFFB00000000 in 374ms [State:Active II:5000 AI:30]
2025-04-10 23:05:29.219 : [MatterSDK 177014] - DEBUG: [CTL] Sent operational certificate to the device
2025-04-10 23:05:29.219 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16099i S:211 M:118507460 (Ack:201451920)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UD)
2025-04-10 23:05:29.232 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16100i S:211 M:201451921 (Ack:118507459)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:29.825 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16100i S:211 M:201451922 (Ack:118507459)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 ---)
2025-04-10 23:05:29.825 : [MatterSDK 177014] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008
2025-04-10 23:05:29.825 : [MatterSDK 177014] - DEBUG: [CTL] Device returned status 0 on receiving the NOC
2025-04-10 23:05:29.825 : [MatterSDK 177014] - DEBUG: [CTL] Operational credentials provisioned on device 0x7f540075a0
2025-04-10 23:05:29.825 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='SendNOC' error='src/controller/CHIPDeviceC'
2025-04-10 23:05:29.825 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'SendNOC'
2025-04-10 23:05:29.825 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'SendNOC' -> 'kEvictPreviousCaseSessions'
2025-04-10 23:05:29.825 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'kEvictPreviousCaseSessions'
2025-04-10 23:05:29.825 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='kEvictPreviousCaseSessions' error='src/con'
2025-04-10 23:05:29.825 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'kEvictPreviousCaseSessions'
2025-04-10 23:05:29.825 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'kEvictPreviousCaseSessions' -> 'kFindOperationalForStayActive'
2025-04-10 23:05:29.825 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'kFindOperationalForStayActive'
2025-04-10 23:05:29.826 : [MatterSDK 177014] - DEBUG: [DIS] Lookup started for A7B405C82BDF3872-3F5A1FB8162C478C
2025-04-10 23:05:29.827 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16100i S:211 M:118507461 (Ack:201451922)] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [UD)
2025-04-10 23:05:29.848 : [MatterSDK 177014] - DEBUG: [DIS] UDP:[fe80::32de:4bff:fedb:8034%brlan0]:5540: new best score: 7 (for A7B405C82BDF3872-3F5A1FB8162C478C)
2025-04-10 23:05:29.849 : [MatterSDK 177014] - DEBUG: [DIS] UDP:[fd4b:12ad:104e:599c:32de:4bff:fedb:8034%brlan0]:5540: new best score: 3 (for A7B405C82BDF3872-3F5A1FB8162C47)
2025-04-10 23:05:29.850 : [MatterSDK 177014] - DEBUG: [DIS] UDP:[fd7d:45d6:186:d26b:32de:4bff:fedb:8034%brlan0]:5540: new best score: 3 (for A7B405C82BDF3872-3F5A1FB8162C478)
2025-04-10 23:05:29.850 : [MatterSDK 177014] - DEBUG: [DIS] Checking node lookup status for A7B405C82BDF3872-3F5A1FB8162C478C after 24 ms
2025-04-10 23:05:29.850 : [MatterSDK 177014] - DEBUG: [DIS] Keeping DNSSD lookup active
2025-04-10 23:05:30.025 : [MatterSDK 177014] - DEBUG: [DIS] Checking node lookup status for A7B405C82BDF3872-3F5A1FB8162C478C after 200 ms
2025-04-10 23:05:30.026 : [MatterSDK 177014] - DEBUG: [SC] Initiating session on local FabricIndex 1 from 0x00D8730FD2953874 -> 0x3F5A1FB8162C478C
2025-04-10 23:05:30.026 : [MatterStorage 177014] - DEBUG: (SyncGetKeyValue): key = f/1/g
2025-04-10 23:05:30.026 : [MatterStorage 177014] - DEBUG: (SyncGetKeyValue): key = f/1/k/0
2025-04-10 23:05:30.026 : [MatterStorage 177014] - DEBUG: (SyncGetKeyValue): key = f/1/s/3F5A1FB8162C478C
2025-04-10 23:05:30.027 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16101i S:0 M:149377295] (U) Msg TX from 476540822FB0C115 to 0:0000000000000000 [0000] [UDP:[fe80::32de:4bff)
2025-04-10 23:05:30.027 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16101i S:0 M:149377295] (U) Msg Retransmission to 0:0000000000000000 in 5845ms [State:Idle II:5000 AI:300 A]
2025-04-10 23:05:30.027 : [MatterSDK 177014] - DEBUG: [SC] Sent Sigma1 msg to <3F5A1FB8162C478C, 1> [II:500ms AI:300ms AT:4000ms]
2025-04-10 23:05:30.032 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16101i S:0 M:114414589 (Ack:149377295)] (U) Msg RX from 0:0000000000000000 [0000] to 476540822FB0C115 --- T)
2025-04-10 23:05:30.576 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16101i S:0 M:114414590 (Ack:149377295)] (U) Msg RX from 0:0000000000000000 [0000] to 476540822FB0C115 --- T)
2025-04-10 23:05:30.576 : [MatterSDK 177014] - DEBUG: [SC] Received Sigma2 msg
2025-04-10 23:05:30.581 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16101i S:0 M:149377296 (Ack:114414590)] (U) Msg TX from 476540822FB0C115 to 0:0000000000000000 [0000] [UDP:)
2025-04-10 23:05:30.581 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16101i S:0 M:149377296] (U) Msg Retransmission to 0:0000000000000000 in 412ms [State:Active II:5000 AI:300 ]
2025-04-10 23:05:30.581 : [MatterSDK 177014] - DEBUG: [SC] Sent Sigma3 msg
2025-04-10 23:05:30.595 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16101i S:0 M:114414591 (Ack:149377296)] (U) Msg RX from 0:0000000000000000 [0000] to 476540822FB0C115 --- T)
2025-04-10 23:05:30.938 : [MatterSDK 177014] - DEBUG: [DL] Thread role changed to: leader
2025-04-10 23:05:30.941 : [tbrmd 177014] - DEBUG: OnMatterPlatformEventHandler: 
2025-04-10 23:05:30.941 : [Matter 177014] - DEBUG: (EventHandler): EventType=8001
2025-04-10 23:05:30.941 : [tbrmd 177014] - DEBUG: OnMatterPlatformEventHandler: 
2025-04-10 23:05:30.941 : [Matter 177014] - DEBUG: (EventHandler): EventType=800b
2025-04-10 23:05:31.490 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16101i S:0 M:114414592 (Ack:149377296)] (U) Msg RX from 0:0000000000000000 [0000] to 476540822FB0C115 --- T)
2025-04-10 23:05:31.490 : [MatterSDK 177014] - DEBUG: [SC] Success status report received. Session was established
2025-04-10 23:05:31.490 : [MatterStorage 177014] - DEBUG: (SyncGetKeyValue): key = g/sri
2025-04-10 23:05:31.490 : [MatterStorage 177014] - DEBUG: (SyncSetKeyValue): key = f/1/s/3F5A1FB8162C478C
2025-04-10 23:05:31.505 : [MatterStorage 177014] - DEBUG: (SyncSetKeyValue): key = g/s/cM6ELQOpl5yTZb8rmQBNpA==
2025-04-10 23:05:31.522 : [MatterStorage 177014] - DEBUG: (SyncSetKeyValue): key = g/sri
2025-04-10 23:05:31.531 : [MatterSDK 177014] - DEBUG: [SC] SecureSession[0x7f54025d30, LSID:212]: State change 'kEstablishing' --> 'kActive'
2025-04-10 23:05:31.532 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='kFindOperationalForStayActive' error='src/'
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'kFindOperationalForStayActive'
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'kFindOperationalForStayActive' -> 'ICDSendStayActive'
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'ICDSendStayActive'
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [CTL] Skipping kICDSendStayActive
2025-04-10 23:05:31.532 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='ICDSendStayActive' error='src/controller/C'
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'ICDSendStayActive'
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'ICDSendStayActive' -> 'kFindOperationalForCommissioningComplete'
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'kFindOperationalForCommissioningComplete'
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [DIS] Found an existing secure session to [1:3F5A1FB8162C478C]!
2025-04-10 23:05:31.532 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='kFindOperationalForCommissioningComplete' '
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'kFindOperationalForCommissioningComplete'
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'kFindOperationalForCommissioningComplete' -> 'SendComplete'
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'SendComplete'
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16102i S:212 M:141370255] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16102i S:212 M:141370255] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 377ms [State:Active II:5000 AI:30]
2025-04-10 23:05:31.532 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16101i S:0 M:149377297 (Ack:114414592)] (U) Msg TX from 476540822FB0C115 to 0:0000000000000000 [0000] [UDP:)
2025-04-10 23:05:31.532 : [tbrmd 177014] - DEBUG: OnMatterPlatformEventHandler: 
2025-04-10 23:05:31.532 : [Matter 177014] - DEBUG: (EventHandler): EventType=8018
2025-04-10 23:05:31.909 : [MatterSDK 177014] - DEBUG: [EM] <<1 [E:16102i S:212 M:141370255] (S) Msg Retransmission to 1:3F5A1FB8162C478C
2025-04-10 23:05:31.909 : [MatterSDK 177014] - DEBUG: [EM] ??2 [E:16102i S:212 M:141370255] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 347ms [State:Active II:5000 AI:30]
2025-04-10 23:05:31.910 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16102i S:212 M:183602762 (Ack:141370255)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:31.910 : [MatterSDK 177014] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0005
2025-04-10 23:05:31.910 : [MatterSDK 177014] - DEBUG: [CTL] Received CommissioningComplete response, errorCode=0
2025-04-10 23:05:31.910 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='SendComplete' error='src/controller/CHIPDe'
2025-04-10 23:05:31.910 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'SendComplete'
2025-04-10 23:05:31.910 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning stage next step: 'SendComplete' -> 'Cleanup'
2025-04-10 23:05:31.910 : [MatterSDK 177014] - DEBUG: [CTL] Performing next commissioning step 'Cleanup'
2025-04-10 23:05:31.910 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningStatusUpdate): Node 3f5a1fb8162c478c stageCompleted='Cleanup' error='src/controller/CHIPDeviceC'
2025-04-10 23:05:31.910 : [MatterSDK 177014] - DEBUG: [CTL] Successfully finished commissioning step 'Cleanup'
2025-04-10 23:05:31.910 : [MatterSDK 177014] - DEBUG: [SC] SecureSession[0x7f54007d90, LSID:211]: State change 'kActive' --> 'kPendingEviction'
2025-04-10 23:05:31.910 : [MatterSDK 177014] - DEBUG: [CTL] Commissioning complete for node ID 0x3F5A1FB8162C478C: success
2025-04-10 23:05:31.910 : [MatterCommishOrch 177014] - DEBUG: (OnCommissioningSuccess): Node 3f5a1fb8162c478c commissioned successfully
2025-04-10 23:05:31.910 : [Matter 177014] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = CommissionedSuccessfully
2025-04-10 23:05:31.910 : [MatterCommishOrch 177014] - DEBUG: (Commission): Done waiting for commissioning
2025-04-10 23:05:31.910 : [MatterCommishOrch 177014] - DEBUG: (Pair): Pairing device with node id 3f5a1fb8162c478c
2025-04-10 23:05:31.910 : [MatterCommishOrch 177014] - DEBUG: (SendCommissioningComplete): nodeId 3f5a1fb8162c478c
2025-04-10 23:05:31.910 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16102i S:212 M:141370256 (Ack:183602762)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:31.911 : [MatterSDK 177014] - DEBUG: [DIS] Found an existing secure session to [1:3F5A1FB8162C478C]!
2025-04-10 23:05:31.911 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16103i S:212 M:141370257] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:31.911 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16103i S:212 M:141370257] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 407ms [State:Active II:5000 AI:30]
2025-04-10 23:05:31.911 : [Matter 177014] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = DiscoveryPending

                                                                                                                                               ** (brtn-ds-reference:177014):d
2025-04-10 23:05:31.911 : [MatterSDK 177014] - DEBUG: [DIS] Found an existing secure session to [1:3F5A1FB8162C478C]!
2025-04-10 23:05:31.911 : [MatterDiscoverer 177014] - DEBUG: (OnDeviceConnectedFn): 
2025-04-10 23:05:31.911 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:31.912 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16104i S:212 M:141370258] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:31.912 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16104i S:212 M:141370258] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 361ms [State:Active II:5000 AI:30]
deviceService> 
               (brtn-ds-reference:177014): GLib-CRITICAL **: 23:05:32.244: g_hash_table_unref: assertion 'hash_table != NULL' failed
2025-04-10 23:05:32.025 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16102i S:212 M:183602763 (Ack:141370255)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.029 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16103i S:212 M:183602764 (Ack:141370257)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)

                                                                                                                                                                             d
2025-04-10 23:05:32.030 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16103i S:212 M:141370259 (Ack:183602764)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.039 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16104i S:212 M:183602765 (Ack:141370258)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.039 : [MatterDiscoverer 177014] - DEBUG: (OnAttributeData): Cluster 33, attribute 0
2025-04-10 23:05:32.039 : [MatterDiscoverer 177014] - DEBUG: (OnAttributeData): Cluster 28, attribute f
2025-04-10 23:05:32.039 : [MatterDiscoverer 177014] - DEBUG: (OnAttributeData): Cluster 28, attribute a
2025-04-10 23:05:32.039 : [MatterDiscoverer 177014] - DEBUG: (OnAttributeData): Cluster 28, attribute 9
2025-04-10 23:05:32.039 : [MatterDiscoverer 177014] - DEBUG: (OnAttributeData): Cluster 28, attribute 7
2025-04-10 23:05:32.039 : [MatterDiscoverer 177014] - DEBUG: (OnAttributeData): Cluster 28, attribute 3
2025-04-10 23:05:32.039 : [MatterDiscoverer 177014] - DEBUG: (OnAttributeData): Cluster 28, attribute 1
2025-04-10 23:05:32.039 : [MatterDiscoverer 177014] - DEBUG: (DiscoverEndpoint): endpoint 0
2025-04-10 23:05:32.039 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16105i S:212 M:141370260] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16105i S:212 M:141370260] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 377ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16106i S:212 M:141370261] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16106i S:212 M:141370261] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 409ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16107i S:212 M:141370262] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16107i S:212 M:141370262] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 378ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16108i S:212 M:141370263] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16108i S:212 M:141370263] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 334ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.040 : [MatterDiscoverer 177014] - DEBUG: (OnDone): 
2025-04-10 23:05:32.040 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16104i S:212 M:141370264 (Ack:183602765)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.054 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16105i S:212 M:183602766 (Ack:141370260)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.054 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16105i S:212 M:141370265 (Ack:183602766)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.060 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16106i S:212 M:183602767 (Ack:141370261)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.060 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16106i S:212 M:141370266 (Ack:183602767)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.063 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16107i S:212 M:183602768 (Ack:141370262)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.063 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16107i S:212 M:141370267 (Ack:183602768)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.068 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16108i S:212 M:183602769 (Ack:141370263)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.068 : [MatterDiscoverer 177014] - DEBUG: (DiscoverEndpoint): endpoint 1
2025-04-10 23:05:32.068 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:32.069 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16109i S:212 M:141370268] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:32.069 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16109i S:212 M:141370268] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 391ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.069 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:32.069 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16110i S:212 M:141370269] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:32.069 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16110i S:212 M:141370269] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 399ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.069 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:32.069 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16111i S:212 M:141370270] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:32.069 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16111i S:212 M:141370270] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 337ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.069 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:32.070 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16112i S:212 M:141370271] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:32.070 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16112i S:212 M:141370271] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 332ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.070 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16108i S:212 M:141370272 (Ack:183602769)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.081 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16109i S:212 M:183602770 (Ack:141370268)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.082 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16109i S:212 M:141370273 (Ack:183602770)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.085 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16110i S:212 M:183602771 (Ack:141370269)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.085 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16110i S:212 M:141370274 (Ack:183602771)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.087 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16111i S:212 M:183602772 (Ack:141370270)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.087 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16111i S:212 M:141370275 (Ack:183602772)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.107 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16112i S:212 M:183602773 (Ack:141370271)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.107 : [MatterCommishOrch 177014] - DEBUG: (Pair): Done waiting for discovery
2025-04-10 23:05:32.107 : [Matter 177014] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = DiscoveryCompleted
2025-04-10 23:05:32.107 : [MatterDoorLockDD 177014] - DEBUG: (ClaimDevice): 
2025-04-10 23:05:32.107 : [MatterLightDD 177014] - DEBUG: (ClaimDevice): 
2025-04-10 23:05:32.107 : [Matter 177014] - INFO: MatterDriverFactory (GetDriver): matterLight claimed the device
2025-04-10 23:05:32.107 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16112i S:212 M:141370276 (Ack:183602773)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.107 : [deviceService 177014] - DEBUG: deviceServiceDeviceFound: deviceClass=light, deviceClassVersion=3, uuid=3f5a1fb8162c478c, manufacturer=TP-Link, mod1
2025-04-10 23:05:32.107 : [device-service-property-provider 177014] - WARN: b_device_service_property_provider_get_property_as_string: Error getting property 'cpe.denylistedy
2025-04-10 23:05:32.107 : [libdeviceDescriptors 177014] - DEBUG: deviceDescriptorsGet: manufacturer=TP-Link, model=Mini Smart Wi-Fi Plug, hardwareVersion=1, firmwareVersion=1
2025-04-10 23:05:32.107 : [libdeviceDescriptors 177014] - DEBUG: no device descriptors loaded yet, attempting parse
2025-04-10 23:05:32.107 : [libdeviceDescriptorParser 177014] - WARN: Invalid/missing device descriptor list at /home/root/.brtn-ds/AllowList.xml
2025-04-10 23:05:32.107 : [libdeviceDescriptors 177014] - DEBUG: no device descriptors available.
2025-04-10 23:05:32.107 : [deviceService 177014] - DEBUG: deviceServiceDeviceFound: device added with 'neverReject'; allowing device to be paired
device discovered! uuid=3f5a1fb8162c478c, manufacturer=TP-Link, model=Mini Smart Wi-Fi Plug, hardwareVersion=1, firmwareVersion=0x00000001
2025-04-10 23:05:32.244 : [MatterBaseDD 177014] - DEBUG: (configureDevice): 
2025-04-10 23:05:32.244 : [MatterBaseDD 177014] - DEBUG: (ConnectAndExecute): 
2025-04-10 23:05:32.244 : [MatterSDK 177014] - DEBUG: [DIS] Found an existing secure session to [1:3F5A1FB8162C478C]!
2025-04-10 23:05:32.244 : [MatterBaseDD 177014] - DEBUG: (ConfigureDevice): 
2025-04-10 23:05:32.244 : [MatterBaseDD 177014] - DEBUG: (ConfigureOTARequestorCluster): 
2025-04-10 23:05:32.244 : [OTARequestorCluster 177014] - DEBUG: (SetDefaultOTAProviders): 
2025-04-10 23:05:32.245 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16113i S:212 M:141370277] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:32.245 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16113i S:212 M:141370277] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 378ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.245 : [MatterBaseDD 177014] - DEBUG: (ConfigureSubscription): 
2025-04-10 23:05:32.245 : [MatterBaseDD 177014] - DEBUG: (CalculateFinalSubscriptionIntervalSecs): 
2025-04-10 23:05:32.245 : [MatterBaseDD 177014] - DEBUG: (GetCommonClustersToSubscribeTo): 
2025-04-10 23:05:32.245 : [MatterLightDD 177014] - DEBUG: (GetClustersToSubscribeTo): 
2025-04-10 23:05:32.245 : [MatterBaseDD 177014] - INFO: (CalculateFinalSubscriptionIntervalSecs): Reducing max interval ceiling from 3600 secs to 1680 secs to line up with ct
2025-04-10 23:05:32.245 : [MatterBaseDD 177014] - DEBUG: (CalculateFinalSubscriptionIntervalSecs): Will request min interval floor of 1 seconds for the subscription on 3f5a1c
2025-04-10 23:05:32.245 : [MatterBaseDD 177014] - DEBUG: (CalculateFinalSubscriptionIntervalSecs): Will request max interval ceiling of 1680 seconds for the subscription on c
2025-04-10 23:05:32.245 : [MatterSDK 177014] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-10 23:05:32.245 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370278] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:05:32.246 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370278] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 410ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.301 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16113i S:212 M:183602774 (Ack:141370277)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.301 : [MatterCluster 177014] - DEBUG: (OnResponse): 
2025-04-10 23:05:32.301 : [MatterCluster 177014] - DEBUG: (OnResponse): 
2025-04-10 23:05:32.301 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16113i S:212 M:141370279 (Ack:183602774)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.319 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602775 (Ack:141370278)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.320 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370280 (Ack:183602775)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.320 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370280] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 350ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.332 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602776 (Ack:141370280)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.333 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370281 (Ack:183602776)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.333 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370281] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 331ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.347 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602777 (Ack:141370281)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.348 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370282 (Ack:183602777)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.348 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370282] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 400ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.366 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602778 (Ack:141370282)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.367 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370283 (Ack:183602778)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.367 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370283] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 342ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.388 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602779 (Ack:141370283)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.389 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370284 (Ack:183602779)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.390 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370284] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 353ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.409 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602780 (Ack:141370284)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.410 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370285 (Ack:183602780)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.410 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370285] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 367ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.431 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602781 (Ack:141370285)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.431 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370286 (Ack:183602781)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.432 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370286] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 410ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.443 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602782 (Ack:141370286)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.444 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370287 (Ack:183602782)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.444 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370287] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 370ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.460 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602783 (Ack:141370287)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.461 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370288 (Ack:183602783)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.461 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370288] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 379ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.477 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602784 (Ack:141370288)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.478 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370289 (Ack:183602784)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.479 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370289] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 376ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.490 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602785 (Ack:141370289)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.491 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370290 (Ack:183602785)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.491 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370290] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 346ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.509 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602786 (Ack:141370290)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.510 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370291 (Ack:183602786)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.510 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370291] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 342ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.522 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602787 (Ack:141370291)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.522 : [deviceService 177014] - DEBUG: updateResource: deviceUuid=3f5a1fb8162c478c, endpointId=(null), resourceId=firmwareVersion, newValue=0x00000001
2025-04-10 23:05:32.522 : [deviceService 177014] - DEBUG: updateResource: deviceUuid=3f5a1fb8162c478c, endpointId=(null), resourceId=firmwareVersionString, newValue=1.0.0 Bu7
2025-04-10 23:05:32.522 : [deviceService 177014] - DEBUG: updateResource: deviceUuid=3f5a1fb8162c478c, endpointId=(null), resourceId=macAddress, newValue=30de4bdb8034
2025-04-10 23:05:32.522 : [deviceService 177014] - DEBUG: updateResource: deviceUuid=3f5a1fb8162c478c, endpointId=(null), resourceId=networkType, newValue=wifi
2025-04-10 23:05:32.522 : [MatterLightDD 177014] - DEBUG: (OnOffChanged): 
2025-04-10 23:05:32.522 : [deviceService 177014] - DEBUG: updateResource: deviceUuid=3f5a1fb8162c478c, endpointId=1, resourceId=isOn, newValue=false
2025-04-10 23:05:32.523 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370292 (Ack:183602787)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.523 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16114i S:212 M:141370292] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 363ms [State:Active II:5000 AI:30]
2025-04-10 23:05:32.533 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16114i S:212 M:183602788 (Ack:141370292)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:05:32.533 : [MatterSDK 177014] - DEBUG: [DMG] SubscribeResponse is received
2025-04-10 23:05:32.533 : [MatterSDK 177014] - DEBUG: [DMG] Subscription established with SubscriptionID = 0x74308e6d MinInterval = 1s MaxInterval = 1680s Peer = 01:3F5A1FB8C
2025-04-10 23:05:32.534 : [MatterSDK 177014] - DEBUG: [DMG] Refresh LivenessCheckTime for 1693265 milliseconds with SubscriptionId = 0x74308e6d Peer = 01:3F5A1FB8162C478C
2025-04-10 23:05:32.534 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16114i S:212 M:141370293 (Ack:183602788)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:05:32.534 : [MatterBaseDD 177014] - DEBUG: (ConnectAndExecute): Processed 2 async task(s) in 288 ms
2025-04-10 23:05:32.534 : [MatterBaseDD 177014] - DEBUG: (fetchInitialResourceValues): 
2025-04-10 23:05:32.534 : [MatterBaseDD 177014] - DEBUG: (ConnectAndExecute): 
2025-04-10 23:05:32.534 : [MatterSDK 177014] - DEBUG: [DIS] Found an existing secure session to [1:3F5A1FB8162C478C]!
2025-04-10 23:05:32.534 : [MatterLightDD 177014] - DEBUG: (FetchInitialResourceValues): 
2025-04-10 23:05:32.534 : [OnOffCluster 177014] - DEBUG: (GetOnOff): 
2025-04-10 23:05:32.534 : [MatterLightDD 177014] - DEBUG: (OnOffReadComplete): 
2025-04-10 23:05:32.534 : [MatterBaseDD 177014] - DEBUG: (ConnectAndExecute): Processed 1 async task(s) in 0 ms
2025-04-10 23:05:32.534 : [initialResourceValues 177014] - DEBUG: Initial Resource Values:
2025-04-10 23:05:32.534 : [initialResourceValues 177014] - DEBUG:    firmwareUpdateStatus=NULL
2025-04-10 23:05:32.534 : [initialResourceValues 177014] - DEBUG:    dateAdded=1744326332534
2025-04-10 23:05:32.534 : [initialResourceValues 177014] - DEBUG:    manufacturer=TP-Link
2025-04-10 23:05:32.534 : [initialResourceValues 177014] - DEBUG:    model=Mini Smart Wi-Fi Plug
2025-04-10 23:05:32.534 : [initialResourceValues 177014] - DEBUG:    dateLastContacted=1744326332534
2025-04-10 23:05:32.534 : [initialResourceValues 177014] - DEBUG:    hardwareVersion=1
2025-04-10 23:05:32.534 : [initialResourceValues 177014] - DEBUG:    1/isOn=false
2025-04-10 23:05:32.534 : [initialResourceValues 177014] - DEBUG:    communicationFailure=false
2025-04-10 23:05:32.534 : [initialResourceValues 177014] - DEBUG:    firmwareVersion=0x00000001
2025-04-10 23:05:32.535 : [MatterLightDD 177014] - DEBUG: (RegisterResources): 
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/manufacturer
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/model
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/hardwareVersion
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/firmwareVersion
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/firmwareUpdateStatus
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/dateAdded
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/dateLastContacted
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/communicationFailure
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/resetToFactoryDefaults
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/serialNumber
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/firmwareVersionString
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/macAddress
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/r/networkType
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/ep/1
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/ep/1/r/label
2025-04-10 23:05:32.535 : [deviceService 177014] - DEBUG: Created URI: /3f5a1fb8162c478c/ep/1/r/isOn
2025-04-10 23:05:32.548 : [storage 177014] - DEBUG: storageSave: saved file /home/root/.brtn-ds/storage/devicedb/3f5a1fb8162c478c in 13ms
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG: device finalized:
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG: Device
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:       uuid=3f5a1fb8162c478c
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:       deviceClass=light
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:       deviceClassVersion=3
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:       uri=/3f5a1fb8162c478c
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:       managingDeviceDriver=matterLight
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:       resources:
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/manufacturer] [id=manufacturer] [endpointId=(null)] [type=com.icontk
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/model] [id=model] [endpointId=(null)] [type=com.icontrol.string] [mg
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/hardwareVersion] [id=hardwareVersion] [endpointId=(null)] [type=com1
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/firmwareVersion] [id=firmwareVersion] [endpointId=(null)] [type=com1
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/firmwareUpdateStatus] [id=firmwareUpdateStatus] [endpointId=(null)])
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/dateAdded] [id=dateAdded] [endpointId=(null)] [type=com.icontrol.da4
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/dateLastContacted] [id=dateLastContacted] [endpointId=(null)] [type4
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/communicationFailure] [id=communicationFailure] [endpointId=(null)]e
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/resetToFactoryDefaults] [id=resetToFactoryDefaults] [endpointId=(nu)
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/serialNumber] [id=serialNumber] [endpointId=(null)] [type=com.icont)
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/firmwareVersionString] [id=firmwareVersionString] [endpointId=(null7
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/macAddress] [id=macAddress] [endpointId=(null)] [type=com.icontrol.4
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Resource [uri=/3f5a1fb8162c478c/r/networkType] [id=networkType] [endpointId=(null)] [type=com.icontroi
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:       endpoints:
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:               Endpoint
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:                       id=1
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:                       uri=/3f5a1fb8162c478c/ep/1
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:                       profile=light
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:                       profileVersion=0
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:                       deviceUuid=3f5a1fb8162c478c
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:                       enabled=true
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:                       resources:
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:                               Resource [uri=/3f5a1fb8162c478c/ep/1/r/label] [id=label] [endpointId=1] [type=com.icot
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:                               Resource [uri=/3f5a1fb8162c478c/ep/1/r/isOn] [id=isOn] [endpointId=1] [type=com.iconte
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:                       metadata:
2025-04-10 23:05:32.548 : [deviceService 177014] - DEBUG:       metadata:
device added! deviceId=3f5a1fb8162c478c, uri=/3f5a1fb8162c478c, deviceClass=light, deviceClassVersion=3
endpoint added! deviceUuid=3f5a1fb8162c478c, id=1, uri=/3f5a1fb8162c478c/ep/1, profile=light, profileVersion=0
device discovery completed! uuid=3f5a1fb8162c478c, class=light
2025-04-10 23:05:32.549 : [MatterBaseDD 177014] - DEBUG: (DevicePersisted): 
2025-04-10 23:05:32.549 : [MatterDetailsStorage 177014] - ERROR: (Get): Failed to parse details metadata JSON: (null)
2025-04-10 23:05:32.549 : [deviceService 177014] - DEBUG: setMetadata: deviceUuid=3f5a1fb8162c478c, endpointId=(null), name=matterDetails, value={"vendor":"TP-Link","product}
2025-04-10 23:05:32.558 : [storage 177014] - DEBUG: storageSave: saved file /home/root/.brtn-ds/storage/devicedb/3f5a1fb8162c478c in 8ms
metadata updated: uri=/3f5a1fb8162c478c/m/matterDetails, value={"vendor":"TP-Link","product":"Mini Smart Wi-Fi Plug","hwVer":"1","swVer":"0x00000001","swStr":"1.0.0 Build 22}
2025-04-10 23:05:32.558 : [deviceCommunicationWatchdog 177014] - DEBUG: deviceCommunicationWatchdogMonitorDevice: start monitoring 3f5a1fb8162c478c with commFailTimeoutSecone
2025-04-10 23:05:32.558 : [Matter 177014] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = CommissionedSuccessfully
deviceService> 

deviceService> listDevices
3f5a1fb8162c478c: Class: light
        Endpoint 1: Profile: light, Label: Matter Light
2025-04-10 23:05

deviceService> readResource /3f5a1fb8162c478c/r/manufacturer
TP-Link
2025-04-10 23:12:22.037 : [openThreadClient 177014] - ERROR: CreateNetwork: Timed out waiting for create network response.
2025-04-10 23:12:22.037 : [threadSubsystem 177014] - ERROR: initializeThreadStack: Failed to create network, trying again in 60 seconds...
2025-04-10 23:12:25.038 : [openThreadClient 177014] - DEBUG: DBusMessageFilter: Active dataset tlvs changed
2025-04-10 23:12:25.038 : [deviceService 177014] - DEBUG: subsystemManager: subsystemManagerGetRegisteredSubsystems - 
2025-04-10 23:12:25.038 : [deviceService 177014] - DEBUG: subsystemManager: subsystemManagerGetSubsystemStatusJson - 
2025-04-10 23:12:25.038 : [threadSubsystem 177014] - DEBUG: getStatusJson: 
2025-04-10 23:12:25.038 : [deviceService 177014] - DEBUG: subsystemManager: subsystemManagerGetSubsystemStatusJson - 
2025-04-10 23:12:25.038 : [Matter 177014] - DEBUG: MatterSubsystem (getStatusJson): 
2025-04-10 23:12:30.072 : [MatterSDK 177014] - DEBUG: [DL] Thread role changed to: leader
deviceService> wr /3f5a1fb8162c478c/ep/1/r/isOn true
2025-04-10 23:13:08.348 : [MatterBaseDD 177014] - DEBUG: (writeResource): 
2025-04-10 23:13:08.348 : [MatterBaseDD 177014] - DEBUG: (ConnectAndExecute): 
2025-04-10 23:13:08.348 : [MatterSDK 177014] - DEBUG: [DIS] Found an existing secure session to [1:3F5A1FB8162C478C]!
2025-04-10 23:13:08.348 : [MatterLightDD 177014] - DEBUG: (WriteResource): isOn = true
2025-04-10 23:13:08.349 : [OnOffCluster 177014] - DEBUG: (SetOnOff): 
2025-04-10 23:13:08.349 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16115i S:212 M:141370297] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UDP:[fe80::32de:4b)
2025-04-10 23:13:08.349 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:16115i S:212 M:141370297] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 6861ms [State:Idle II:5000 AI:300]
2025-04-10 23:13:08.402 : [deviceCommunicationWatchdog 177014] - DEBUG: deviceCommunicationWatchdogPetDevice: petting 3f5a1fb8162c478c
2025-04-10 23:13:08.402 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:16115i S:212 M:183602795 (Ack:141370297)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
2025-04-10 23:13:08.402 : [MatterSDK 177014] - DEBUG: [DMG] Received Command Response Status for Endpoint=1 Cluster=0x0000_0006 Command=0x0000_0001 Status=0x0
2025-04-10 23:13:08.402 : [MatterCluster 177014] - DEBUG: (OnResponse): 
2025-04-10 23:13:08.402 : [MatterBaseDD 177014] - DEBUG: (ConnectAndExecute): Processed 1 async task(s) in 53 ms
2025-04-10 23:13:08.402 : [deviceService 177014] - DEBUG: updateResource: deviceUuid=3f5a1fb8162c478c, endpointId=1, resourceId=isOn, newValue=true
2025-04-10 23:13:08.402 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:16115i S:212 M:141370298 (Ack:183602795)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)

resourceUpdated: id=isOn, uri=/3f5a1fb8162c478c/ep/1/r/isOn, ownerId=3f5a1fb8162c478c, value=true, type=com.icontrol.boolean, mode=0x7b (rw-del-)
2025-04-10 23:13:08.469 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:65433r S:212 M:183602796] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 --- Type 0001:05 (I)
2025-04-10 23:13:08.469 : [MatterLightDD 177014] - DEBUG: (OnOffChanged): 
2025-04-10 23:13:08.469 : [deviceService 177014] - DEBUG: updateResource: deviceUuid=3f5a1fb8162c478c, endpointId=1, resourceId=isOn, newValue=true
2025-04-10 23:13:08.469 : [MatterSDK 177014] - DEBUG: [DMG] Refresh LivenessCheckTime for 1693265 milliseconds with SubscriptionId = 0x74308e6d Peer = 01:3F5A1FB8162C478C
2025-04-10 23:13:08.469 : [MatterSDK 177014] - DEBUG: [EM] <<< [E:65433r S:212 M:141370299 (Ack:183602796)] (S) Msg TX from 00D8730FD2953874 to 1:3F5A1FB8162C478C [3872] [UD)
2025-04-10 23:13:08.470 : [MatterSDK 177014] - DEBUG: [EM] ??1 [E:65433r S:212 M:141370299] (S) Msg Retransmission to 1:3F5A1FB8162C478C in 336ms [State:Active II:5000 AI:30]
2025-04-10 23:13:08.480 : [MatterSDK 177014] - DEBUG: [EM] >>> [E:65433r S:212 M:183602797 (Ack:141370299)] (S) Msg RX from 1:3F5A1FB8162C478C [3872] to 00D8730FD2953874 ---)
deviceService> 

deviceService> 

deviceService> rr /3f5a1fb8162c478c/ep/1/r/isOn
true
deviceService>

Associated wifi matter device

Filogic-GW:~# dmcli eRT getv Device.WiFi.AccessPoint.1.AssociatedDevice.
CR component name is: eRT.com.cisco.spvtg.ccsp.CR
subsystem_prefix eRT.
Execution succeed.
Parameter    1 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.MACAddress
               type:     string,    value: 30:de:4b:db:80:34 
Parameter    2 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.AuthenticationState
               type:       bool,    value: true 
Parameter    3 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.LastDataDownlinkRate
               type:       uint,    value: 0 
Parameter    4 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.LastDataUplinkRate
               type:       uint,    value: 0 
Parameter    5 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.SignalStrength
               type:        int,    value: -34 
Parameter    6 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Retransmissions
               type:       uint,    value: 0 
Parameter    7 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Active
               type:       bool,    value: true 
Parameter    8 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_OperatingStandard
               type:     string,    value: b/g 
Parameter    9 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_OperatingChannelBandwidth
               type:     string,    value: 20 MHZ 
Parameter   10 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_SNR
               type:        int,    value: 61 
Parameter   11 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_RDKCENTRAL-COM_SNR
               type:        int,    value: 61 
Parameter   12 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_InterferenceSources
               type:     string,    value:  
Parameter   13 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_DataFramesSentAck
               type:       uint,    value: 0 
Parameter   14 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_DataFramesSentNoAck
               type:       uint,    value: 0 
Parameter   15 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_BytesSent
               type:       uint,    value: 0 
Parameter   16 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_BytesReceived
               type:       uint,    value: 27008 
Parameter   17 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_RSSI
               type:        int,    value: -34 
Parameter   18 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_MinRSSI
               type:        int,    value: -34 
Parameter   19 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_MaxRSSI
               type:        int,    value: -34 
Parameter   20 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_Disassociations
               type:       uint,    value: 0 
Parameter   21 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.X_COMCAST-COM_AuthenticationFailures
               type:       uint,    value: 0 
Parameter   22 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Stats.InstantMeasurementsEnable
               type:       bool,    value: false 
Parameter   23 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Stats.BytesSent
               type:       uint,    value: 0 
Parameter   24 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Stats.BytesReceived
               type:       uint,    value: 27008 
Parameter   25 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Stats.PacketsSent
               type:       uint,    value: 4 
Parameter   26 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Stats.PacketsReceived
               type:       uint,    value: 326 
Parameter   27 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Stats.ErrorsSent
               type:       uint,    value: 0 
Parameter   28 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Stats.RetransCount
               type:       uint,    value: 0 
Parameter   29 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Stats.FailedRetransCount
               type:       uint,    value: 0 
Parameter   30 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Stats.RetryCount
               type:       uint,    value: 0 
Parameter   31 name: Device.WiFi.AccessPoint.1.AssociatedDevice.1.Stats.MultipleRetryCount
               type:       uint,    value: 0

Verification of thread

deviceService> cd 25972402765
Attempting to commission device
2025-04-14 16:19:29.441 : [Matter 111082] - DEBUG: MatterSubsystem (matterSubsystemCommissionDevice): 
2025-04-14 16:19:29.441 : [MatterCommishOrch 111082] - DEBUG: (Commission): 
2025-04-14 16:19:29.441 : [Matter 111082] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = Pending
2025-04-14 16:19:29.442 : [MatterCommishOrch 111082] - DEBUG: (CommissionWorkFunc): 
2025-04-14 16:19:29.442 : [Matter 111082] - DEBUG: (GetCommissioningParams): 
2025-04-14 16:19:29.442 : [MatterCommishOrch 111082] - DEBUG: (CommissionWorkFunc): Calling PairDevice()
2025-04-14 16:19:29.442 : [MatterSDK 111082] - DEBUG: [CTL] Setting thread operational dataset from parameters
2025-04-14 16:19:29.442 : [MatterSDK 111082] - DEBUG: [CTL] Setting wifi credentials from parameters
2025-04-14 16:19:29.442 : [MatterSDK 111082] - DEBUG: [CTL] Setting attestation nonce to random value
2025-04-14 16:19:29.442 : [MatterSDK 111082] - DEBUG: [CTL] Setting CSR nonce to random value
2025-04-14 16:19:29.442 : [MatterSDK 111082] - DEBUG: [CTL] Starting commissioning discovery over BLE
2025-04-14 16:19:29.442 : [MatterSDK 111082] - DEBUG: [CTL] WiFi-PAF: has RendezvousInformationFlag::kWiFiPAF
2025-04-14 16:19:29.442 : [MatterSDK 111082] - DEBUG: [CTL] Starting commissioning discovery over DNS-SD
deviceServi[  782.634228] Bluetooth: hci0: advertising data len corrected
2025-04-14 16:19:29.443 : [Matter 111082] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = Started
2025-04-14 16:19:29.443 : [MatterSDK 111082] - DEBUG: [BLE] BLE removing known devices
2025-04-14 16:19:29.444 : [MatterSDK 111082] - DEBUG: [BLE] BLE initiating scan
2025-04-14 16:19:29.605 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:29.605 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
devi[  782.698212] Bluetooth: hci0: advertising data len corrected
2025-04-14 16:19:29.667 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:29.667 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
deviceService> [  782.850237] Bluetooth: hci0: advertising data len corrected
2025-04-14 16:19:29.821 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:29.821 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
deviceService> [  783.220328] Bluetooth: hci0: advertising data len corrected
2025-04-14 16:19:30.195 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:30.195 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
deviceService> [  783.354340] Bluetooth: hci0: advertising data len corrected
2025-04-14 16:19:30.325 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:30.326 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
deviceService> [  783.608460] Bluetooth: hci0: advertising data len corrected
2025-04-14 16:19:30.579 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:30.579 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
deviceService> [  783.694392] Bluetooth: hci0: advertising data len corrected
2025-04-14 16:19:30.665 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:30.666 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
deviceService> [  784.096574] Bluetooth: hci0: advertising data len corrected
2025-04-14 16:19:31.067 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
deviceSe[  784.116554] Bluetooth: hci0: advertising data len corrected
2025-04-14 16:19:31.067 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:31.088 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:31.088 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
deviceService> [  784.314569] Bluetooth: hci0: advertising data len corrected
2025-04-14 16:19:31.288 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:31.288 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:31.376 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:31.376 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:31.708 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:31.708 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:31.764 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:31.764 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
deviceService> [  785.093544] systemd-journald[1091]: Data hash table of /run/log/journal/a7c511e891ac40499e2cc8c08a933b65/system.journal has a fill level at 75.0 (5461 of 7.
[  785.114681] systemd-journald[1091]: /run/log/journal/a7c511e891ac40499e2cc8c08a933b65/system.journal: Journal header limits reached or header out-of-date, rotating.
2025-04-14 16:19:32.228 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:32.228 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:32.330 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:32.330 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:32.350 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:32.350 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:32.778 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:32.778 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:33.056 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:33.056 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:33.162 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:33.162 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:33.208 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:33.208 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:33.387 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:33.387 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:33.644 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:33.645 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:33.686 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:33.686 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:33.923 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: 30:DE:4B:DB:80:34
2025-04-14 16:19:33.923 : [MatterSDK 111082] - ERROR: [BLE] Skip connection: Device discriminator does not match: 766 != 11
2025-04-14 16:19:34.181 : [MatterSDK 111082] - DEBUG: [BLE] New device scanned: F4:86:B1:95:A8:88
2025-04-14 16:19:34.181 : [MatterSDK 111082] - DEBUG: [BLE] Device discriminator match. Attempting to connect.
2025-04-14 16:19:34.574 : [MatterSDK 111082] - DEBUG: [BLE] New device connected: F4:86:B1:95:A8:88
2025-04-14 16:19:35.283 : [MatterSDK 111082] - DEBUG: [CTL] Discovered device to be commissioned over BLE
2025-04-14 16:19:35.283 : [MatterSDK 111082] - DEBUG: [CTL] Attempting PASE connection to BLE
2025-04-14 16:19:35.284 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20526i S:0 M:108489074] (U) Msg TX from B648E88C0BA1DE03 to 0:0000000000000000 [0000] [BLE] --- Type 0000:2)
2025-04-14 16:19:35.284 : [MatterSDK 111082] - DEBUG: [SC] Sent PBKDF param request [II:500ms AI:300ms AT:4000ms)
2025-04-14 16:19:35.284 : [MatterSDK 111082] - DEBUG: [CTL] Commission called for node ID 0x9490BEA86D5276B1
2025-04-14 16:19:35.611 : [MatterSDK 111082] - DEBUG: [BLE] subscribe complete, ep = 0x7faf50d528
2025-04-14 16:19:35.645 : [MatterSDK 111082] - DEBUG: [BLE] peripheral chose BTP version 4; central expected between 4 and 4
2025-04-14 16:19:35.645 : [MatterSDK 111082] - DEBUG: [BLE] using BTP fragment sizes rx 244 / tx 244.
2025-04-14 16:19:35.645 : [MatterSDK 111082] - DEBUG: [BLE] local and remote recv window size = 5
2025-04-14 16:19:35.887 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20526i S:0 M:132313596] (U) Msg RX from 0:0000000000000000 [0000] to B648E88C0BA1DE03 --- Type 0000:21 (Sec)
2025-04-14 16:19:35.933 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20526i S:0 M:108489075] (U) Msg TX from B648E88C0BA1DE03 to 0:0000000000000000 [0000] [BLE] --- Type 0000:2)
2025-04-14 16:19:36.111 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20526i S:0 M:132313597] (U) Msg RX from 0:0000000000000000 [0000] to B648E88C0BA1DE03 --- Type 0000:23 (Sec)
2025-04-14 16:19:36.113 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20526i S:0 M:108489076] (U) Msg TX from B648E88C0BA1DE03 to 0:0000000000000000 [0000] [BLE] --- Type 0000:2)
2025-04-14 16:19:36.233 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20526i S:0 M:132313598] (U) Msg RX from 0:0000000000000000 [0000] to B648E88C0BA1DE03 --- Type 0000:40 (Sec)
2025-04-14 16:19:36.233 : [MatterSDK 111082] - DEBUG: [SC] SecureSession[0x7f8c00b150, LSID:36725]: State change 'kEstablishing' --> 'kActive'
2025-04-14 16:19:36.234 : [MatterSDK 111082] - DEBUG: [CTL] PASE session established with commissionee. Stopping discovery.
2025-04-14 16:19:36.234 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo'
2025-04-14 16:19:36.234 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'ReadCommissioningInfo'
2025-04-14 16:19:36.234 : [MatterSDK 111082] - DEBUG: [CTL] Sending read request for commissioning information
2025-04-14 16:19:36.234 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-14 16:19:36.234 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20527i S:36725 M:33653352] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:36.234 : [tbrmd 111082] - DEBUG: OnMatterPlatformEventHandler: 
2025-04-14 16:19:36.234 : [Matter 111082] - DEBUG: (EventHandler): EventType=8018
2025-04-14 16:19:36.686 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20527i S:36725 M:221456125] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:05 )
2025-04-14 16:19:36.686 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='ReadCommissioningInfo' error='src/controll'
2025-04-14 16:19:36.686 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'ReadCommissioningInfo'
2025-04-14 16:19:36.686 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'ReadCommissioningInfo' -> 'ReadCommissioningInfo2'
2025-04-14 16:19:36.686 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'ReadCommissioningInfo2'
2025-04-14 16:19:36.686 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-14 16:19:36.686 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20528i S:36725 M:33653353] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:36.831 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20528i S:36725 M:221456126] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:05 )
2025-04-14 16:19:36.831 : [MatterSDK 111082] - DEBUG: [CTL] ----- NetworkCommissioning Features: has Thread. endpointid = 0
2025-04-14 16:19:36.831 : [MatterCommishOrch 111082] - DEBUG: (OnReadCommissioningInfo): Node 0000000000000000
2025-04-14 16:19:36.831 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='ReadCommissioningInfo2' error='src/control'
2025-04-14 16:19:36.831 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'ReadCommissioningInfo2'
2025-04-14 16:19:36.831 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'ReadCommissioningInfo2' -> 'ArmFailSafe'
2025-04-14 16:19:36.832 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'ArmFailSafe'
2025-04-14 16:19:36.832 : [MatterSDK 111082] - DEBUG: [CTL] Arming failsafe (60 seconds)
2025-04-14 16:19:36.832 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20529i S:36725 M:33653354] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:36.951 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20529i S:36725 M:221456127] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 )
2025-04-14 16:19:36.951 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
2025-04-14 16:19:36.951 : [MatterSDK 111082] - DEBUG: [CTL] Received ArmFailSafe response errorCode=0
2025-04-14 16:19:36.951 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='ArmFailSafe' error='src/controller/CHIPDev'
2025-04-14 16:19:36.951 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'ArmFailSafe'
2025-04-14 16:19:36.952 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory'
2025-04-14 16:19:36.952 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'ConfigRegulatory'
2025-04-14 16:19:36.952 : [MatterSDK 111082] - DEBUG: [CTL] Setting Regulatory Config
2025-04-14 16:19:36.952 : [MatterSDK 111082] - DEBUG: [CTL] Device does not support configurable regulatory location
2025-04-14 16:19:36.952 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20530i S:36725 M:33653355] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:37.113 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20530i S:36725 M:221456128] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 )
2025-04-14 16:19:37.114 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003
2025-04-14 16:19:37.114 : [MatterSDK 111082] - DEBUG: [CTL] Received SetRegulatoryConfig response errorCode=0
2025-04-14 16:19:37.114 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='ConfigRegulatory' error='src/controller/CH'
2025-04-14 16:19:37.114 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'ConfigRegulatory'
2025-04-14 16:19:37.114 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest'
2025-04-14 16:19:37.114 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'SendPAICertificateRequest'
2025-04-14 16:19:37.114 : [MatterSDK 111082] - DEBUG: [CTL] Sending request for PAI certificate
2025-04-14 16:19:37.114 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20531i S:36725 M:33653356] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:37.443 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20531i S:36725 M:221456129] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 )
2025-04-14 16:19:37.443 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
2025-04-14 16:19:37.444 : [MatterSDK 111082] - DEBUG: [CTL] Received certificate chain from the device
2025-04-14 16:19:37.444 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='SendPAICertificateRequest' error='src/cont'
2025-04-14 16:19:37.444 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'SendPAICertificateRequest'
2025-04-14 16:19:37.444 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest'
2025-04-14 16:19:37.444 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'SendDACCertificateRequest'
2025-04-14 16:19:37.444 : [MatterSDK 111082] - DEBUG: [CTL] Sending request for DAC certificate
2025-04-14 16:19:37.444 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20532i S:36725 M:33653357] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:37.738 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20532i S:36725 M:221456130] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 )
2025-04-14 16:19:37.738 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
2025-04-14 16:19:37.738 : [MatterSDK 111082] - DEBUG: [CTL] Received certificate chain from the device
2025-04-14 16:19:37.738 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='SendDACCertificateRequest' error='src/cont'
2025-04-14 16:19:37.738 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'SendDACCertificateRequest'
2025-04-14 16:19:37.738 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest'
2025-04-14 16:19:37.738 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'SendAttestationRequest'
2025-04-14 16:19:37.738 : [MatterSDK 111082] - DEBUG: [CTL] Sending Attestation Request to the device.
2025-04-14 16:19:37.738 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20533i S:36725 M:33653358] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:37.970 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20533i S:36725 M:221456131] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 )
2025-04-14 16:19:37.970 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001
2025-04-14 16:19:37.970 : [MatterSDK 111082] - DEBUG: [CTL] Received Attestation Information from the device
2025-04-14 16:19:37.970 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='SendAttestationRequest' error='src/control'
2025-04-14 16:19:37.970 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'SendAttestationRequest'
2025-04-14 16:19:37.970 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification'
2025-04-14 16:19:37.970 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'AttestationVerification'
2025-04-14 16:19:37.970 : [MatterSDK 111082] - DEBUG: [CTL] Verifying attestation
2025-04-14 16:19:37.984 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='AttestationVerification' error='src/contro'
2025-04-14 16:19:37.984 : [MatterSDK 111082] - ERROR: [CTL] Error on commissioning step 'AttestationVerification': 'src/controller/CHIPDeviceController.cpp:1283: CHIP Error '
2025-04-14 16:19:37.984 : [MatterSDK 111082] - ERROR: [CTL] Failed verifying attestation information. Now checking DAC chain revoked status.
2025-04-14 16:19:37.984 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'AttestationVerification' -> 'AttestationRevocationCheck'
2025-04-14 16:19:37.984 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'AttestationRevocationCheck' with completion status = 'src/controller/CHIPDevi'
2025-04-14 16:19:37.984 : [MatterSDK 111082] - DEBUG: [CTL] Verifying device's DAC chain revocation status
2025-04-14 16:19:37.984 : [MatterSDK 111082] - ERROR: [CTL] Failed in verifying 'Attestation Information' command received from the device: err 101. Look at AttestationVerifs
2025-04-14 16:19:37.984 : [MatterSDK 111082] - DEBUG: [CTL] Proceeding without changing fail-safe timer value as delegate has not set it
2025-04-14 16:19:37.984 : [MatterSDK 111082] - DEBUG: [CTL] Device attestation completed, delegating continuation to client
2025-04-14 16:19:37.984 : [Matter 111082] - DEBUG: (OnDeviceAttestationCompleted): 
2025-04-14 16:19:37.984 : [Matter 111082] - WARN: (OnDeviceAttestationCompleted): Attestation failed, but we are allowing it anyway
2025-04-14 16:19:37.984 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='AttestationRevocationCheck' error='/usr/sr'
2025-04-14 16:19:37.984 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'AttestationRevocationCheck'
2025-04-14 16:19:37.984 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'AttestationRevocationCheck' -> 'SendOpCertSigningRequest'
2025-04-14 16:19:37.984 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'SendOpCertSigningRequest'
2025-04-14 16:19:37.984 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20534i S:36725 M:33653359] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:38.258 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20534i S:36725 M:221456132] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 )
2025-04-14 16:19:38.258 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005
2025-04-14 16:19:38.258 : [MatterSDK 111082] - DEBUG: [CTL] Received certificate signing request from the device
2025-04-14 16:19:38.258 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='SendOpCertSigningRequest' error='src/contr'
2025-04-14 16:19:38.258 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'SendOpCertSigningRequest'
2025-04-14 16:19:38.258 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR'
2025-04-14 16:19:38.258 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'ValidateCSR'
2025-04-14 16:19:38.261 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='ValidateCSR' error='src/credentials/attest'
2025-04-14 16:19:38.261 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'ValidateCSR'
2025-04-14 16:19:38.261 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain'
2025-04-14 16:19:38.261 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'GenerateNOCChain'
2025-04-14 16:19:38.261 : [MatterSDK 111082] - DEBUG: [CTL] Getting certificate chain for the device from the issuer
2025-04-14 16:19:38.264 : [MatterSDK 111082] - DEBUG: [CTL] Verifying Certificate Signing Request
2025-04-14 16:19:38.267 : [MatterSDK 111082] - DEBUG: [CTL] Received callback from the CA for NOC Chain generation. Status third_party/comcast/controller/certifier-credentias
2025-04-14 16:19:38.267 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='GenerateNOCChain' error='third_party/comca'
2025-04-14 16:19:38.267 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'GenerateNOCChain'
2025-04-14 16:19:38.267 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'SendTrustedRootCert'
2025-04-14 16:19:38.267 : [MatterSDK 111082] - DEBUG: [CTL] Sending root certificate to the device
2025-04-14 16:19:38.267 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20535i S:36725 M:33653360] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:38.268 : [MatterSDK 111082] - DEBUG: [CTL] Sent root certificate to the device
2025-04-14 16:19:38.478 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20535i S:36725 M:221456133] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 )
2025-04-14 16:19:38.478 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0
2025-04-14 16:19:38.478 : [MatterSDK 111082] - DEBUG: [CTL] Device confirmed that it has received the root certificate
2025-04-14 16:19:38.478 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='SendTrustedRootCert' error='src/controller'
2025-04-14 16:19:38.478 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'SendTrustedRootCert'
2025-04-14 16:19:38.478 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC'
2025-04-14 16:19:38.478 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'SendNOC'
2025-04-14 16:19:38.478 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20536i S:36725 M:33653361] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:38.479 : [MatterSDK 111082] - DEBUG: [CTL] Sent operational certificate to the device
2025-04-14 16:19:38.738 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20536i S:36725 M:221456134] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 )
2025-04-14 16:19:38.738 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008
2025-04-14 16:19:38.738 : [MatterSDK 111082] - DEBUG: [CTL] Device returned status 0 on receiving the NOC
2025-04-14 16:19:38.738 : [MatterSDK 111082] - DEBUG: [CTL] Operational credentials provisioned on device 0x7f8c034a90
2025-04-14 16:19:38.738 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='SendNOC' error='src/controller/CHIPDeviceC'
2025-04-14 16:19:38.738 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'SendNOC'
2025-04-14 16:19:38.738 : [MatterSDK 111082] - DEBUG: [CTL] No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks
2025-04-14 16:19:38.738 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'SendNOC' -> 'ThreadNetworkSetup'
2025-04-14 16:19:38.738 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'ThreadNetworkSetup'
2025-04-14 16:19:38.738 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20537i S:36725 M:33653362] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:38.868 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20537i S:36725 M:221456135] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 )
2025-04-14 16:19:38.868 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005
2025-04-14 16:19:38.868 : [MatterSDK 111082] - DEBUG: [CTL] Received NetworkConfig response, networkingStatus=0
2025-04-14 16:19:38.868 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='ThreadNetworkSetup' error='src/controller/'
2025-04-14 16:19:38.868 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'ThreadNetworkSetup'
2025-04-14 16:19:38.868 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'ThreadNetworkSetup' -> 'FailsafeBeforeThreadEnable'
2025-04-14 16:19:38.868 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'FailsafeBeforeThreadEnable'
2025-04-14 16:19:38.868 : [MatterSDK 111082] - DEBUG: [CTL] Arming failsafe (77 seconds)
2025-04-14 16:19:38.868 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20538i S:36725 M:33653363] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:38.998 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20538i S:36725 M:221456136] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 )
2025-04-14 16:19:38.998 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
2025-04-14 16:19:38.998 : [MatterSDK 111082] - DEBUG: [CTL] Received ArmFailSafe response errorCode=0
2025-04-14 16:19:38.998 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='FailsafeBeforeThreadEnable' error='src/con'
2025-04-14 16:19:38.998 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'FailsafeBeforeThreadEnable'
2025-04-14 16:19:38.998 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'FailsafeBeforeThreadEnable' -> 'ThreadNetworkEnable'
2025-04-14 16:19:38.998 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'ThreadNetworkEnable'
2025-04-14 16:19:38.999 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20539i S:36725 M:33653364] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 000)
2025-04-14 16:19:40.590 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20539i S:36725 M:221456137] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 )
2025-04-14 16:19:40.591 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0007
2025-04-14 16:19:40.591 : [MatterSDK 111082] - DEBUG: [CTL] Received ConnectNetwork response, networkingStatus=0
2025-04-14 16:19:40.591 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='ThreadNetworkEnable' error='src/controller'
2025-04-14 16:19:40.591 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'ThreadNetworkEnable'
2025-04-14 16:19:40.591 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'ThreadNetworkEnable' -> 'kEvictPreviousCaseSessions'
2025-04-14 16:19:40.591 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'kEvictPreviousCaseSessions'
2025-04-14 16:19:40.591 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='kEvictPreviousCaseSessions' error='src/con'
2025-04-14 16:19:40.591 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'kEvictPreviousCaseSessions'
2025-04-14 16:19:40.591 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'kEvictPreviousCaseSessions' -> 'kFindOperationalForStayActive'
2025-04-14 16:19:40.591 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'kFindOperationalForStayActive'
2025-04-14 16:19:40.591 : [MatterSDK 111082] - DEBUG: [DIS] Lookup started for 46B0C2F0DDC8A82D-9490BEA86D5276B1
2025-04-14 16:19:40.790 : [MatterSDK 111082] - DEBUG: [DIS] Checking node lookup status for 46B0C2F0DDC8A82D-9490BEA86D5276B1 after 200 ms
deviceService> [  793.881666] systemd-journald[1091]: Data hash table of /run/log/journal/a7c511e891ac40499e2cc8c08a933b65/system.journal has a fill level at 75.0 (5461 of 7.
[  793.903695] systemd-journald[1091]: /run/log/journal/a7c511e891ac40499e2cc8c08a933b65/system.journal: Journal header limits reached or header out-of-date, rotating.
2025-04-14 16:19:42.598 : [MatterSDK 111082] - DEBUG: [DIS] UDP:[fd7d:29cc:6f19:1:bc60:5fb:5ca1:192b%dummy0]:5540: new best score: 5 (for 46B0C2F0DDC8A82D-9490BEA86D5276B1)
2025-04-14 16:19:42.598 : [MatterSDK 111082] - DEBUG: [DIS] Checking node lookup status for 46B0C2F0DDC8A82D-9490BEA86D5276B1 after 2008 ms
2025-04-14 16:19:42.598 : [MatterSDK 111082] - DEBUG: [SC] Initiating session on local FabricIndex 1 from 0xF83EDB367376F8B8 -> 0x9490BEA86D5276B1
2025-04-14 16:19:42.599 : [MatterStorage 111082] - DEBUG: (SyncGetKeyValue): key = f/1/g
2025-04-14 16:19:42.599 : [MatterStorage 111082] - DEBUG: (SyncGetKeyValue): key = f/1/k/0
2025-04-14 16:19:42.599 : [MatterStorage 111082] - DEBUG: (SyncGetKeyValue): key = f/1/s/9490BEA86D5276B1
2025-04-14 16:19:42.599 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20540i S:0 M:108489077] (U) Msg TX from 4C41B4C15F3764AE to 0:0000000000000000 [0000] [UDP:[fd7d:29cc:6f19:)
2025-04-14 16:19:42.599 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20540i S:0 M:108489077] (U) Msg Retransmission to 0:0000000000000000 in 911ms [State:Idle II:800 AI:800 AT:]
2025-04-14 16:19:42.599 : [MatterSDK 111082] - DEBUG: [SC] Sent Sigma1 msg to <9490BEA86D5276B1, 1> [II:500ms AI:300ms AT:4000ms]
2025-04-14 16:19:42.710 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20540i S:0 M:132313599 (Ack:108489077)] (U) Msg RX from 0:0000000000000000 [0000] to 4C41B4C15F3764AE --- T)
2025-04-14 16:19:42.894 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20540i S:0 M:132313600 (Ack:108489077)] (U) Msg RX from 0:0000000000000000 [0000] to 4C41B4C15F3764AE --- T)
2025-04-14 16:19:42.894 : [MatterSDK 111082] - DEBUG: [SC] Received Sigma2 msg
2025-04-14 16:19:42.899 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20540i S:0 M:108489078 (Ack:132313600)] (U) Msg TX from 4C41B4C15F3764AE to 0:0000000000000000 [0000] [UDP:)
2025-04-14 16:19:42.900 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20540i S:0 M:108489078] (U) Msg Retransmission to 0:0000000000000000 in 1021ms [State:Active II:800 AI:800 ]
2025-04-14 16:19:42.900 : [MatterSDK 111082] - DEBUG: [SC] Sent Sigma3 msg
2025-04-14 16:19:43.086 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20540i S:0 M:132313601 (Ack:108489078)] (U) Msg RX from 0:0000000000000000 [0000] to 4C41B4C15F3764AE --- T)
2025-04-14 16:19:43.184 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20540i S:0 M:132313602 (Ack:108489078)] (U) Msg RX from 0:0000000000000000 [0000] to 4C41B4C15F3764AE --- T)
2025-04-14 16:19:43.184 : [MatterSDK 111082] - DEBUG: [SC] Success status report received. Session was established
2025-04-14 16:19:43.184 : [MatterStorage 111082] - DEBUG: (SyncGetKeyValue): key = g/sri
2025-04-14 16:19:43.184 : [MatterStorage 111082] - DEBUG: (SyncSetKeyValue): key = f/1/s/9490BEA86D5276B1
2025-04-14 16:19:43.206 : [MatterStorage 111082] - DEBUG: (SyncSetKeyValue): key = g/s/Vt5CWO5UX/Arqzs6UErXVQ==
2025-04-14 16:19:43.219 : [MatterStorage 111082] - DEBUG: (SyncSetKeyValue): key = g/sri
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [SC] SecureSession[0x7f8c0357f0, LSID:36726]: State change 'kEstablishing' --> 'kActive'
2025-04-14 16:19:43.241 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='kFindOperationalForStayActive' error='src/'
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'kFindOperationalForStayActive'
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'kFindOperationalForStayActive' -> 'ICDSendStayActive'
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'ICDSendStayActive'
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [CTL] Skipping kICDSendStayActive
2025-04-14 16:19:43.241 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='ICDSendStayActive' error='src/controller/C'
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'ICDSendStayActive'
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'ICDSendStayActive' -> 'kFindOperationalForCommissioningComplete'
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'kFindOperationalForCommissioningComplete'
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [DIS] Found an existing secure session to [1:9490BEA86D5276B1]!
2025-04-14 16:19:43.241 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='kFindOperationalForCommissioningComplete' '
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'kFindOperationalForCommissioningComplete'
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'kFindOperationalForCommissioningComplete' -> 'SendComplete'
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'SendComplete'
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20541i S:36726 M:199185417] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:43.241 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20541i S:36726 M:199185417] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1036ms [State:Active II:800 AI:]
2025-04-14 16:19:43.242 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20540i S:0 M:108489079 (Ack:132313602)] (U) Msg TX from 4C41B4C15F3764AE to 0:0000000000000000 [0000] [UDP:)
2025-04-14 16:19:43.242 : [tbrmd 111082] - DEBUG: OnMatterPlatformEventHandler: 
2025-04-14 16:19:43.242 : [Matter 111082] - DEBUG: (EventHandler): EventType=8018
2025-04-14 16:19:43.396 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20541i S:36726 M:1831465 (Ack:199185417)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:43.397 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0005
2025-04-14 16:19:43.397 : [MatterSDK 111082] - DEBUG: [CTL] Received CommissioningComplete response, errorCode=0
2025-04-14 16:19:43.397 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='SendComplete' error='src/controller/CHIPDe'
2025-04-14 16:19:43.397 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'SendComplete'
2025-04-14 16:19:43.397 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning stage next step: 'SendComplete' -> 'Cleanup'
2025-04-14 16:19:43.397 : [MatterSDK 111082] - DEBUG: [CTL] Performing next commissioning step 'Cleanup'
2025-04-14 16:19:43.397 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningStatusUpdate): Node 9490bea86d5276b1 stageCompleted='Cleanup' error='src/controller/CHIPDeviceC'
2025-04-14 16:19:43.397 : [MatterSDK 111082] - DEBUG: [CTL] Successfully finished commissioning step 'Cleanup'
2025-04-14 16:19:43.397 : [MatterSDK 111082] - DEBUG: [DIS] Closing all BLE connections
2025-04-14 16:19:43.397 : [MatterSDK 111082] - DEBUG: [BLE] Auto-closing end point's BLE connection.
2025-04-14 16:19:43.397 : [MatterSDK 111082] - DEBUG: [DL] Closing BLE GATT connection (con 0x7f880f32e0)
2025-04-14 16:19:45.980 : [MatterSDK 111082] - DEBUG: [SC] SecureSession[0x7f8c00b150, LSID:36725]: State change 'kActive' --> 'kPendingEviction'
2025-04-14 16:19:45.980 : [MatterSDK 111082] - DEBUG: [CTL] Commissioning complete for node ID 0x9490BEA86D5276B1: success
2025-04-14 16:19:45.980 : [MatterCommishOrch 111082] - DEBUG: (OnCommissioningSuccess): Node 9490bea86d5276b1 commissioned successfully
2025-04-14 16:19:45.980 : [Matter 111082] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = CommissionedSuccessfully
2025-04-14 16:19:45.981 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20541i S:36726 M:199185418 (Ack:1831465)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:45.981 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20541i S:36726 M:1831465 (Ack:199185417)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:45.982 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20541i S:36726 M:199185419 (Ack:1831465)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:45.983 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20541i S:36726 M:1831465 (Ack:199185417)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:45.983 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20541i S:36726 M:199185420 (Ack:1831465)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:45.985 : [MatterCommishOrch 111082] - DEBUG: (Commission): Done waiting for commissioning
2025-04-14 16:19:45.985 : [MatterCommishOrch 111082] - DEBUG: (Pair): Pairing device with node id 9490bea86d5276b1
2025-04-14 16:19:45.985 : [MatterCommishOrch 111082] - DEBUG: (SendCommissioningComplete): nodeId 9490bea86d5276b1
2025-04-14 16:19:45.985 : [MatterSDK 111082] - DEBUG: [DIS] Found an existing secure session to [1:9490BEA86D5276B1]!
2025-04-14 16:19:45.985 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20542i S:36726 M:199185421] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:45.986 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20542i S:36726 M:199185421] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 904ms [State:Active II:800 AI:8]
2025-04-14 16:19:45.986 : [Matter 111082] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = DiscoveryPending
2025-04-14 16:19:45.987 : [MatterSDK 111082] - DEBUG: [DIS] Found an existing secure session to [1:9490BEA86D5276B1]!
2025-04-14 16:19:45.987 : [MatterDiscoverer 111082] - DEBUG: (OnDeviceConnectedFn): 
2025-04-14 16:19:45.987 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-14 16:19:45.987 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20543i S:36726 M:199185422] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:45.987 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20543i S:36726 M:199185422] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1005ms [State:Active II:800 AI:]
2025-04-14 16:19:46.130 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20542i S:36726 M:1831466 (Ack:199185421)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:46.130 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20542i S:36726 M:199185423 (Ack:1831466)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:46.269 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20543i S:36726 M:1831467 (Ack:199185422)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:46.270 : [MatterDiscoverer 111082] - DEBUG: (OnAttributeData): Cluster 33, attribute 0
2025-04-14 16:19:46.270 : [MatterDiscoverer 111082] - DEBUG: (OnAttributeData): Cluster 28, attribute f
2025-04-14 16:19:46.270 : [MatterDiscoverer 111082] - DEBUG: (OnAttributeData): Cluster 28, attribute a
2025-04-14 16:19:46.270 : [MatterDiscoverer 111082] - DEBUG: (OnAttributeData): Cluster 28, attribute 9
2025-04-14 16:19:46.270 : [MatterDiscoverer 111082] - DEBUG: (OnAttributeData): Cluster 28, attribute 7
2025-04-14 16:19:46.270 : [MatterDiscoverer 111082] - DEBUG: (OnAttributeData): Cluster 28, attribute 3
2025-04-14 16:19:46.270 : [MatterDiscoverer 111082] - DEBUG: (OnAttributeData): Cluster 28, attribute 1
2025-04-14 16:19:46.270 : [MatterDiscoverer 111082] - DEBUG: (DiscoverEndpoint): endpoint 0
2025-04-14 16:19:46.270 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-14 16:19:46.270 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20544i S:36726 M:199185424] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:46.270 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20544i S:36726 M:199185424] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 935ms [State:Active II:800 AI:8]
2025-04-14 16:19:46.270 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-14 16:19:46.270 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20545i S:36726 M:199185425] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:46.270 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20545i S:36726 M:199185425] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1012ms [State:Active II:800 AI:]
2025-04-14 16:19:46.270 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-14 16:19:46.270 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20546i S:36726 M:199185426] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:46.270 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20546i S:36726 M:199185426] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1015ms [State:Active II:800 AI:]
2025-04-14 16:19:46.270 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-14 16:19:46.270 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20547i S:36726 M:199185427] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:46.271 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20547i S:36726 M:199185427] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1010ms [State:Active II:800 AI:]
2025-04-14 16:19:46.271 : [MatterDiscoverer 111082] - DEBUG: (OnDone): 
2025-04-14 16:19:46.271 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20543i S:36726 M:199185428 (Ack:1831467)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:46.331 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20544i S:36726 M:1831468 (Ack:199185424)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:46.332 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20544i S:36726 M:199185429 (Ack:1831468)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:46.381 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20545i S:36726 M:1831469 (Ack:199185425)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:46.382 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20545i S:36726 M:199185430 (Ack:1831469)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:46.407 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20546i S:36726 M:1831470 (Ack:199185426)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:46.408 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20546i S:36726 M:199185431 (Ack:1831470)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:46.443 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20547i S:36726 M:1831471 (Ack:199185427)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:46.444 : [MatterDiscoverer 111082] - DEBUG: (DiscoverEndpoint): endpoint 1
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20548i S:36726 M:199185432] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20548i S:36726 M:199185432] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 924ms [State:Active II:800 AI:8]

                                                                                                                                                                             d
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20549i S:36726 M:199185433] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20549i S:36726 M:199185433] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 901ms [State:Active II:800 AI:8]
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20550i S:36726 M:199185434] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
deviceService> 
               (brtn-ds-reference:111082): GLib-CRITICAL **: 16:19:46.986: g_hash_table_unref: assertion 'hash_table != NULL' failed
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20550i S:36726 M:199185434] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1031ms [State:Active II:800 AI:]
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space

                                                                                                                                                      (brtn-ds-reference:1110d
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20551i S:36726 M:199185435] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:46.444 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20551i S:36726 M:199185435] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1042ms [State:Active II:800 AI:]
2025-04-14 16:19:46.445 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20547i S:36726 M:199185436 (Ack:1831471)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:46.594 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20548i S:36726 M:1831472 (Ack:199185432)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:46.594 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20548i S:36726 M:199185437 (Ack:1831472)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:46.627 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20549i S:36726 M:1831473 (Ack:199185433)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:46.628 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20549i S:36726 M:199185438 (Ack:1831473)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:46.666 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20550i S:36726 M:1831474 (Ack:199185434)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:46.666 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20550i S:36726 M:199185439 (Ack:1831474)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:46.699 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20551i S:36726 M:1831475 (Ack:199185435)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:46.700 : [MatterCommishOrch 111082] - DEBUG: (Pair): Done waiting for discovery
2025-04-14 16:19:46.700 : [Matter 111082] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = DiscoveryCompleted
2025-04-14 16:19:46.700 : [MatterDoorLockDD 111082] - DEBUG: (ClaimDevice): 
2025-04-14 16:19:46.700 : [MatterLightDD 111082] - DEBUG: (ClaimDevice): 
2025-04-14 16:19:46.700 : [Matter 111082] - INFO: MatterDriverFactory (GetDriver): matterLight claimed the device
2025-04-14 16:19:46.700 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20551i S:36726 M:199185440 (Ack:1831475)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:46.700 : [deviceService 111082] - DEBUG: deviceServiceDeviceFound: deviceClass=light, deviceClassVersion=3, uuid=9490bea86d5276b1, manufacturer=Onvis, modelb
2025-04-14 16:19:46.700 : [device-service-property-provider 111082] - WARN: b_device_service_property_provider_get_property_as_string: Error getting property 'cpe.denylistedy
2025-04-14 16:19:46.700 : [libdeviceDescriptors 111082] - DEBUG: deviceDescriptorsGet: manufacturer=Onvis, model=S4, hardwareVersion=1, firmwareVersion=0x0098bd9b
2025-04-14 16:19:46.700 : [libdeviceDescriptors 111082] - DEBUG: no device descriptors loaded yet, attempting parse
2025-04-14 16:19:46.700 : [libdeviceDescriptorParser 111082] - WARN: Invalid/missing device descriptor list at /home/root/.brtn-ds/AllowList.xml
2025-04-14 16:19:46.700 : [libdeviceDescriptors 111082] - DEBUG: no device descriptors available.
2025-04-14 16:19:46.700 : [deviceService 111082] - DEBUG: deviceServiceDeviceFound: device added with 'neverReject'; allowing device to be paired
device discovered! uuid=9490bea86d5276b1, manufacturer=Onvis, model=S4, hardwareVersion=1, firmwareVersion=0x0098bd9b
2025-04-14 16:19:46.986 : [MatterBaseDD 111082] - DEBUG: (configureDevice): 
2025-04-14 16:19:46.986 : [MatterBaseDD 111082] - DEBUG: (ConnectAndExecute): 
2025-04-14 16:19:46.986 : [MatterSDK 111082] - DEBUG: [DIS] Found an existing secure session to [1:9490BEA86D5276B1]!
2025-04-14 16:19:46.986 : [MatterBaseDD 111082] - DEBUG: (ConfigureDevice): 
2025-04-14 16:19:46.986 : [MatterBaseDD 111082] - DEBUG: (ConfigureOTARequestorCluster): 
2025-04-14 16:19:46.986 : [OTARequestorCluster 111082] - DEBUG: (SetDefaultOTAProviders): 
2025-04-14 16:19:46.986 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20552i S:36726 M:199185441] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:46.987 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20552i S:36726 M:199185441] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 953ms [State:Active II:800 AI:8]
2025-04-14 16:19:46.987 : [MatterBaseDD 111082] - DEBUG: (ConfigureSubscription): 
2025-04-14 16:19:46.987 : [MatterBaseDD 111082] - DEBUG: (CalculateFinalSubscriptionIntervalSecs): 
2025-04-14 16:19:46.987 : [MatterBaseDD 111082] - DEBUG: (GetCommonClustersToSubscribeTo): 
2025-04-14 16:19:46.987 : [MatterLightDD 111082] - DEBUG: (GetClustersToSubscribeTo): 
2025-04-14 16:19:46.987 : [MatterBaseDD 111082] - INFO: (CalculateFinalSubscriptionIntervalSecs): Reducing max interval ceiling from 3600 secs to 1680 secs to line up with ct
2025-04-14 16:19:46.987 : [MatterBaseDD 111082] - DEBUG: (CalculateFinalSubscriptionIntervalSecs): Will request min interval floor of 1 seconds for the subscription on 9490b1
2025-04-14 16:19:46.987 : [MatterBaseDD 111082] - DEBUG: (CalculateFinalSubscriptionIntervalSecs): Will request max interval ceiling of 1680 seconds for the subscription on 1
2025-04-14 16:19:46.987 : [MatterSDK 111082] - DEBUG: [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space
2025-04-14 16:19:46.987 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20553i S:36726 M:199185442] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:19:46.987 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20553i S:36726 M:199185442] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1038ms [State:Active II:800 AI:]
2025-04-14 16:19:47.086 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20552i S:36726 M:1831476 (Ack:199185441)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:47.086 : [MatterCluster 111082] - DEBUG: (OnResponse): 
2025-04-14 16:19:47.086 : [MatterCluster 111082] - DEBUG: (OnResponse): 
2025-04-14 16:19:47.086 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20552i S:36726 M:199185443 (Ack:1831476)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:47.386 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20553i S:36726 M:1831477 (Ack:199185442)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:47.387 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20553i S:36726 M:199185444 (Ack:1831477)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:47.387 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20553i S:36726 M:199185444] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 906ms [State:Active II:800 AI:8]
2025-04-14 16:19:48.240 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20553i S:36726 M:1831477 (Ack:199185442)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:48.240 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20553i S:36726 M:199185445 (Ack:1831477)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:48.293 : [MatterSDK 111082] - DEBUG: [EM] <<1 [E:20553i S:36726 M:199185444] (S) Msg Retransmission to 1:9490BEA86D5276B1
2025-04-14 16:19:48.293 : [MatterSDK 111082] - DEBUG: [EM] ??2 [E:20553i S:36726 M:199185444] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 969ms [State:Active II:800 AI:8]
2025-04-14 16:19:48.632 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20553i S:36726 M:1831478 (Ack:199185444)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:48.633 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20553i S:36726 M:199185446 (Ack:1831478)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:48.634 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20553i S:36726 M:199185446] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1010ms [State:Active II:800 AI:]
2025-04-14 16:19:48.892 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20553i S:36726 M:1831479 (Ack:199185446)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:48.893 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20553i S:36726 M:199185447 (Ack:1831479)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:48.893 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20553i S:36726 M:199185447] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 940ms [State:Active II:800 AI:8]
2025-04-14 16:19:49.258 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20553i S:36726 M:1831480 (Ack:199185447)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:49.259 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20553i S:36726 M:199185448 (Ack:1831480)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:49.259 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20553i S:36726 M:199185448] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1064ms [State:Active II:800 AI:]
2025-04-14 16:19:49.772 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20553i S:36726 M:1831481 (Ack:199185448)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:49.774 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20553i S:36726 M:199185449 (Ack:1831481)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:49.774 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20553i S:36726 M:199185449] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 968ms [State:Active II:800 AI:8]
2025-04-14 16:19:50.313 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20553i S:36726 M:1831482 (Ack:199185449)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:50.314 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=(null), resourceId=firmwareVersion, newValue=0x0098bd9b
2025-04-14 16:19:50.314 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=(null), resourceId=firmwareVersionString, newValue=1.1.11-cy
2025-04-14 16:19:50.314 : [deviceService 111082] - DEBUG: deviceServiceGetResource: deviceUuid=9490bea86d5276b1, endpointId=(null), resourceId=serialNumber
2025-04-14 16:19:50.314 : [deviceService 111082] - DEBUG: did not find the resource
2025-04-14 16:19:50.314 : [MatterDeviceDriver 111082] - WARN: Serial number changed for device 9490bea86d5276b1 from (null) to 70016cdbb45b44b3839f9c06aaad5b65
2025-04-14 16:19:50.314 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=(null), resourceId=serialNumber, newValue=70016cdbb45b44b385
2025-04-14 16:19:50.314 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=(null), resourceId=macAddress, newValue=000000000000
2025-04-14 16:19:50.314 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=(null), resourceId=networkType, newValue=thread
2025-04-14 16:19:50.314 : [MatterLightDD 111082] - DEBUG: (OnOffChanged): 
2025-04-14 16:19:50.314 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=1, resourceId=isOn, newValue=false
2025-04-14 16:19:50.315 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20553i S:36726 M:199185450 (Ack:1831482)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:50.315 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20553i S:36726 M:199185450] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1019ms [State:Active II:800 AI:]
2025-04-14 16:19:50.371 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20553i S:36726 M:1831483 (Ack:199185450)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:19:50.371 : [MatterSDK 111082] - DEBUG: [DMG] SubscribeResponse is received
2025-04-14 16:19:50.371 : [MatterSDK 111082] - DEBUG: [DMG] Subscription established with SubscriptionID = 0x518c7c20 MinInterval = 1s MaxInterval = 1680s Peer = 01:9490BEA81
2025-04-14 16:19:50.371 : [MatterSDK 111082] - DEBUG: [DMG] Refresh LivenessCheckTime for 1700310 milliseconds with SubscriptionId = 0x518c7c20 Peer = 01:9490BEA86D5276B1
2025-04-14 16:19:50.371 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20553i S:36726 M:199185451 (Ack:1831483)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)
2025-04-14 16:19:50.371 : [MatterBaseDD 111082] - DEBUG: (ConnectAndExecute): Processed 2 async task(s) in 3384 ms
2025-04-14 16:19:50.372 : [MatterBaseDD 111082] - DEBUG: (fetchInitialResourceValues): 
2025-04-14 16:19:50.372 : [MatterBaseDD 111082] - DEBUG: (ConnectAndExecute): 
2025-04-14 16:19:50.372 : [MatterSDK 111082] - DEBUG: [DIS] Found an existing secure session to [1:9490BEA86D5276B1]!
2025-04-14 16:19:50.372 : [MatterLightDD 111082] - DEBUG: (FetchInitialResourceValues): 
2025-04-14 16:19:50.372 : [OnOffCluster 111082] - DEBUG: (GetOnOff): 
2025-04-14 16:19:50.372 : [MatterLightDD 111082] - DEBUG: (OnOffReadComplete): 
2025-04-14 16:19:50.372 : [MatterBaseDD 111082] - DEBUG: (ConnectAndExecute): Processed 1 async task(s) in 0 ms
2025-04-14 16:19:50.372 : [initialResourceValues 111082] - DEBUG: Initial Resource Values:
2025-04-14 16:19:50.372 : [initialResourceValues 111082] - DEBUG:    communicationFailure=false
2025-04-14 16:19:50.372 : [initialResourceValues 111082] - DEBUG:    model=S4
2025-04-14 16:19:50.372 : [initialResourceValues 111082] - DEBUG:    dateLastContacted=1744647590372
2025-04-14 16:19:50.372 : [initialResourceValues 111082] - DEBUG:    dateAdded=1744647590372
2025-04-14 16:19:50.372 : [initialResourceValues 111082] - DEBUG:    firmwareUpdateStatus=NULL
2025-04-14 16:19:50.372 : [initialResourceValues 111082] - DEBUG:    1/isOn=false
2025-04-14 16:19:50.372 : [initialResourceValues 111082] - DEBUG:    hardwareVersion=1
2025-04-14 16:19:50.372 : [initialResourceValues 111082] - DEBUG:    firmwareVersion=0x0098bd9b
2025-04-14 16:19:50.372 : [initialResourceValues 111082] - DEBUG:    manufacturer=Onvis
2025-04-14 16:19:50.372 : [MatterLightDD 111082] - DEBUG: (RegisterResources): 
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/manufacturer
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/model
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/hardwareVersion
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/firmwareVersion
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/firmwareUpdateStatus
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/dateAdded
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/dateLastContacted
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/communicationFailure
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/resetToFactoryDefaults
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/serialNumber
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/firmwareVersionString
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/macAddress
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/r/networkType
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/ep/1
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/ep/1/r/label
2025-04-14 16:19:50.372 : [deviceService 111082] - DEBUG: Created URI: /9490bea86d5276b1/ep/1/r/isOn
2025-04-14 16:19:50.395 : [storage 111082] - DEBUG: storageSave: saved file /home/root/.brtn-ds/storage/devicedb/9490bea86d5276b1 in 22ms
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG: device finalized:
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG: Device
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:       uuid=9490bea86d5276b1
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:       deviceClass=light
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:       deviceClassVersion=3
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:       uri=/9490bea86d5276b1
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:       managingDeviceDriver=matterLight
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:       resources:
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/manufacturer] [id=manufacturer] [endpointId=(null)] [type=com.iconts
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/model] [id=model] [endpointId=(null)] [type=com.icontrol.string] [m4
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/hardwareVersion] [id=hardwareVersion] [endpointId=(null)] [type=com1
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/firmwareVersion] [id=firmwareVersion] [endpointId=(null)] [type=comb
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/firmwareUpdateStatus] [id=firmwareUpdateStatus] [endpointId=(null)])
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/dateAdded] [id=dateAdded] [endpointId=(null)] [type=com.icontrol.da2
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/dateLastContacted] [id=dateLastContacted] [endpointId=(null)] [type2
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/communicationFailure] [id=communicationFailure] [endpointId=(null)]e
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/resetToFactoryDefaults] [id=resetToFactoryDefaults] [endpointId=(nu)
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/serialNumber] [id=serialNumber] [endpointId=(null)] [type=com.icont5
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/firmwareVersionString] [id=firmwareVersionString] [endpointId=(nully
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/macAddress] [id=macAddress] [endpointId=(null)] [type=com.icontrol.0
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Resource [uri=/9490bea86d5276b1/r/networkType] [id=networkType] [endpointId=(null)] [type=com.icontrod
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:       endpoints:
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:               Endpoint
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:                       id=1
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:                       uri=/9490bea86d5276b1/ep/1
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:                       profile=light
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:                       profileVersion=0
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:                       deviceUuid=9490bea86d5276b1
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:                       enabled=true
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:                       resources:
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:                               Resource [uri=/9490bea86d5276b1/ep/1/r/label] [id=label] [endpointId=1] [type=com.icot
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:                               Resource [uri=/9490bea86d5276b1/ep/1/r/isOn] [id=isOn] [endpointId=1] [type=com.iconte
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:                       metadata:
2025-04-14 16:19:50.395 : [deviceService 111082] - DEBUG:       metadata:
device added! deviceId=9490bea86d5276b1, uri=/9490bea86d5276b1, deviceClass=light, deviceClassVersion=3
endpoint added! deviceUuid=9490bea86d5276b1, id=1, uri=/9490bea86d5276b1/ep/1, profile=light, profileVersion=0
device discovery completed! uuid=9490bea86d5276b1, class=light
2025-04-14 16:19:50.395 : [MatterBaseDD 111082] - DEBUG: (DevicePersisted): 
2025-04-14 16:19:50.396 : [MatterDetailsStorage 111082] - ERROR: (Get): Failed to parse details metadata JSON: (null)
2025-04-14 16:19:50.396 : [deviceService 111082] - DEBUG: setMetadata: deviceUuid=9490bea86d5276b1, endpointId=(null), name=matterDetails, value={"vendor":"Onvis","product":}
2025-04-14 16:19:50.404 : [storage 111082] - DEBUG: storageSave: saved file /home/root/.brtn-ds/storage/devicedb/9490bea86d5276b1 in 8ms
metadata updated: uri=/9490bea86d5276b1/m/matterDetails, value={"vendor":"Onvis","product":"S4","hwVer":"1","swVer":"0x0098bd9b","swStr":"1.1.11-c85ba1e-dirty","serialNumber}
2025-04-14 16:19:50.404 : [deviceCommunicationWatchdog 111082] - DEBUG: deviceCommunicationWatchdogMonitorDevice: start monitoring 9490bea86d5276b1 with commFailTimeoutSecone
2025-04-14 16:19:50.404 : [Matter 111082] - INFO: MatterSubsystem (onDeviceCommissioningStatusChanged): Commissioning status = CommissionedSuccessfully
deviceService> 

deviceService> listDevices
5b9021971ce8c157: Class: light
        Endpoint 1: Profile: light, Label: Matter Light
9490bea86d5276b1: Class: light
        Endpoint 1: Profile: light, Label: Matter Light
deviceService> [  822.503322] systemd-journald[1091]: Data hash table of /run/log/journal/a7c511e891ac40499e2cc8c08a933b65/system.journal has a fill level at 75.0 (5461 of 7.
[  822.524518] systemd-journald[1091]: /run/log/journal/a7c511e891ac40499e2cc8c08a933b65/system.journal: Journal header limits reached or header out-of-date, rotating.
[  822.605658] wifi0: moving STA 30:de:4b:db:80:34 to state 3
[  822.641621] ieee80211 phy0: mt7996_set_key: cipher = 0xfac04, icv_len = 8, iv_len = 8, hw_key_idx = 0, keyidx = 0, flags = 0x8, link_id = -1, keylen = 16
[  822.655425] ieee80211 phy0: mt7996_set_key: add=1, valid_links=0, active_links=0
[  822.664306] wifi0: moving STA 30:de:4b:db:80:34 to state 2
[  822.669898] ieee80211 phy0: mt7996_change_chanctx: change 1, 0x10
[  822.676000] wifi0: moving STA 30:de:4b:db:80:34 to state 1
[  822.681543] ieee80211 phy0: mt7996_mac_sta_remove_links: rem=1
[  822.687402] mt76_connac_mcu_sta_basic_tlv: link 0 addr [30:de:4b:db:80:34]
[  822.694291] ieee80211 phy0: mt7996_mcu_add_sta: link=0, newly=0, en=0
[  822.701389] wifi0: Removed STA 30:de:4b:db:80:34
[  822.706770] wifi0: Destroyed STA 30:de:4b:db:80:34


deviceService> [  823.829429] wifi0: Allocated STA 30:de:4b:db:80:34
[  823.834287] wifi0: moving STA 30:de:4b:db:80:34 to state 2
[  823.839792] wifi0: moving STA 30:de:4b:db:80:34 to state 3
[  823.845310] ieee80211 phy0: mt7996_change_chanctx: change 1, 0x10
[  823.851426] ieee80211 phy0: mt7996_mac_sta_add_links: add=1, assoc=0
[  823.857794] ieee80211 phy0: mt7996_add_link_sta: wcid=1, link_id=0, link_addr=30:de:4b:db:80:34, pri_link=0, sec_link=0
[  823.868582] ieee80211 phy0: mt7996_mac_sta_add_links: add=1, assoc=1
[  823.874956] mt76_connac_mcu_sta_basic_tlv: link 0 addr [30:de:4b:db:80:34]
[  823.881843] ieee80211 phy0: mt7996_mcu_add_sta: link=0, newly=1, en=1
[  823.889217] wifi0: Inserted STA 30:de:4b:db:80:34
[  823.937637] ieee80211 phy0: mt7996_set_key: cipher = 0xfac04, icv_len = 8, iv_len = 8, hw_key_idx = 0, keyidx = 0, flags = 0x8, link_id = -1, keylen = 16
[  823.951403] ieee80211 phy0: mt7996_set_key: add=1, valid_links=0, active_links=0
[  823.959891] wifi0: moving STA 30:de:4b:db:80:34 to state 4
rr /9490bea86d5276b1/ep/1/r/isOn
false
deviceService> 

deviceService> 

2025-04-14 16:20:13.475 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4253r S:36726 M:1831484] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- Type 0001:08 (IM)
2025-04-14 16:20:13.475 : [deviceCommunicationWatchdog 111082] - DEBUG: deviceCommunicationWatchdogPetDevice: petting 9490bea86d5276b1
2025-04-14 16:20:13.475 : [MatterSDK 111082] - ERROR: [DMG] Endpoint=0 Cluster=0x0000_0029 Command=0x0000_0000 status 0xc3 (UNSUPPORTED_CLUSTER) (no additional context)
2025-04-14 16:20:13.475 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:4253r S:36726 M:199185452 (Ack:1831484)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP)
2025-04-14 16:20:13.475 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:4253r S:36726 M:199185452] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 971ms [State:Active II:800 AI:80]
2025-04-14 16:20:13.503 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4254r S:36726 M:1831485] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- Type 0001:05 (IM)
2025-04-14 16:20:13.503 : [OTARequestorCluster 111082] - DEBUG: (OnEventDataReceived): Endpoint: 0 Cluster: 0x0000_002A Event 0x0000_0000
2025-04-14 16:20:13.503 : [MatterBaseDD 111082] - DEBUG: (OnStateTransition): 
2025-04-14 16:20:13.503 : [MatterSDK 111082] - DEBUG: [DMG] Refresh LivenessCheckTime for 1700310 milliseconds with SubscriptionId = 0x518c7c20 Peer = 01:9490BEA86D5276B1
2025-04-14 16:20:13.503 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:4254r S:36726 M:199185453 (Ack:1831485)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP)
2025-04-14 16:20:13.504 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:4254r S:36726 M:199185453] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 997ms [State:Active II:800 AI:80]
2025-04-14 16:20:13.583 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4253r S:36726 M:1831486 (Ack:199185452)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- )
2025-04-14 16:20:13.599 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4254r S:36726 M:1831487 (Ack:199185453)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- )
2025-04-14 16:20:14.513 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4255r S:36726 M:1831488] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- Type 0001:05 (IM)
2025-04-14 16:20:14.514 : [OTARequestorCluster 111082] - DEBUG: (OnEventDataReceived): Endpoint: 0 Cluster: 0x0000_002A Event 0x0000_0002
2025-04-14 16:20:14.514 : [MatterBaseDD 111082] - WARN: (OnDownloadError): Failed to download software version 0 for device 0x9490bea86d5276b1
2025-04-14 16:20:14.514 : [MatterBaseDD 111082] - WARN: (DeviceFirmwareUpdateFailed): Device 9490bea86d5276b1 failed to upgrade to version 0
2025-04-14 16:20:14.514 : [MatterSDK 111082] - DEBUG: [DMG] Refresh LivenessCheckTime for 1700310 milliseconds with SubscriptionId = 0x518c7c20 Peer = 01:9490BEA86D5276B1
2025-04-14 16:20:14.514 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=(null), resourceId=firmwareUpdateStatus, newValue=failed
2025-04-14 16:20:14.526 : [storage 111082] - DEBUG: storageSave: saved file /home/root/.brtn-ds/storage/devicedb/9490bea86d5276b1 in 11ms

resourceUpdated: id=firmwareUpdateStatus, uri=/9490bea86d5276b1/r/firmwareUpdateStatus, ownerId=9490bea86d5276b1, value=failed, type=com.icontrol.firmwareVersionStatus, mode)
2025-04-14 16:20:14.527 : [OTARequestorCluster 111082] - DEBUG: (OnEventDataReceived): Endpoint: 0 Cluster: 0x0000_002A Event 0x0000_0000
2025-04-14 16:20:14.527 : [MatterBaseDD 111082] - DEBUG: (OnStateTransition): 
2025-04-14 16:20:14.527 : [MatterSDK 111082] - DEBUG: [DMG] Refresh LivenessCheckTime for 1700310 milliseconds with SubscriptionId = 0x518c7c20 Peer = 01:9490BEA86D5276B1
2025-04-14 16:20:14.527 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:4255r S:36726 M:199185454 (Ack:1831488)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP)
2025-04-14 16:20:14.527 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:4255r S:36726 M:199185454] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 905ms [State:Active II:800 AI:80]
2025-04-14 16:20:14.577 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4255r S:36726 M:1831489 (Ack:199185454)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- )
deviceService> wr /9490bea86d5276b1/ep/1/r/isOn true
2025-04-14 16:20:16.090 : [MatterBaseDD 111082] - DEBUG: (writeResource): 
2025-04-14 16:20:16.090 : [MatterBaseDD 111082] - DEBUG: (ConnectAndExecute): 
2025-04-14 16:20:16.091 : [MatterSDK 111082] - DEBUG: [DIS] Found an existing secure session to [1:9490BEA86D5276B1]!
2025-04-14 16:20:16.091 : [MatterLightDD 111082] - DEBUG: (WriteResource): isOn = true
2025-04-14 16:20:16.091 : [OnOffCluster 111082] - DEBUG: (SetOnOff): 
2025-04-14 16:20:16.091 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20554i S:36726 M:199185455] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:20:16.091 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20554i S:36726 M:199185455] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1081ms [State:Active II:800 AI:]
2025-04-14 16:20:16.283 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20554i S:36726 M:1831490 (Ack:199185455)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:20:16.284 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Status for Endpoint=1 Cluster=0x0000_0006 Command=0x0000_0001 Status=0x0
2025-04-14 16:20:16.284 : [MatterCluster 111082] - DEBUG: (OnResponse): 
2025-04-14 16:20:16.284 : [MatterBaseDD 111082] - DEBUG: (ConnectAndExecute): Processed 1 async task(s) in 191 ms
2025-04-14 16:20:16.284 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=1, resourceId=isOn, newValue=true
2025-04-14 16:20:16.284 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20554i S:36726 M:199185456 (Ack:1831490)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)

resourceUpdated: id=isOn, uri=/9490bea86d5276b1/ep/1/r/isOn, ownerId=9490bea86d5276b1, value=true, type=com.icontrol.boolean, mode=0x7b (rw-del-)
2025-04-14 16:20:16.366 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4256r S:36726 M:1831491] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- Type 0001:05 (IM)
2025-04-14 16:20:16.366 : [MatterLightDD 111082] - DEBUG: (OnOffChanged): 
2025-04-14 16:20:16.366 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=1, resourceId=isOn, newValue=true
2025-04-14 16:20:16.367 : [MatterSDK 111082] - DEBUG: [DMG] Refresh LivenessCheckTime for 1700310 milliseconds with SubscriptionId = 0x518c7c20 Peer = 01:9490BEA86D5276B1
2025-04-14 16:20:16.367 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:4256r S:36726 M:199185457 (Ack:1831491)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP)
2025-04-14 16:20:16.367 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:4256r S:36726 M:199185457] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1019ms [State:Active II:800 AI:8]
2025-04-14 16:20:16.419 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4256r S:36726 M:1831492 (Ack:199185457)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- )
deviceService> 

deviceService> wr /9490bea86d5276b1/ep/1/r/isOn false
2025-04-14 16:20:19.912 : [MatterBaseDD 111082] - DEBUG: (writeResource): 
2025-04-14 16:20:19.912 : [MatterBaseDD 111082] - DEBUG: (ConnectAndExecute): 
2025-04-14 16:20:19.912 : [MatterSDK 111082] - DEBUG: [DIS] Found an existing secure session to [1:9490BEA86D5276B1]!
2025-04-14 16:20:19.912 : [MatterLightDD 111082] - DEBUG: (WriteResource): isOn = false
2025-04-14 16:20:19.912 : [OnOffCluster 111082] - DEBUG: (SetOnOff): 
2025-04-14 16:20:19.912 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20555i S:36726 M:199185458] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:20:19.913 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20555i S:36726 M:199185458] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 950ms [State:Active II:800 AI:8]
2025-04-14 16:20:20.075 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20555i S:36726 M:1831493 (Ack:199185458)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:20:20.075 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Status for Endpoint=1 Cluster=0x0000_0006 Command=0x0000_0000 Status=0x0
2025-04-14 16:20:20.075 : [MatterCluster 111082] - DEBUG: (OnResponse): 
2025-04-14 16:20:20.075 : [MatterBaseDD 111082] - DEBUG: (ConnectAndExecute): Processed 1 async task(s) in 162 ms
2025-04-14 16:20:20.075 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=1, resourceId=isOn, newValue=false
2025-04-14 16:20:20.075 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20555i S:36726 M:199185459 (Ack:1831493)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)

resourceUpdated: id=isOn, uri=/9490bea86d5276b1/ep/1/r/isOn, ownerId=9490bea86d5276b1, value=false, type=com.icontrol.boolean, mode=0x7b (rw-del-)
2025-04-14 16:20:20.149 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4257r S:36726 M:1831494] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- Type 0001:05 (IM)
2025-04-14 16:20:20.149 : [MatterLightDD 111082] - DEBUG: (OnOffChanged): 
2025-04-14 16:20:20.149 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=1, resourceId=isOn, newValue=false
2025-04-14 16:20:20.150 : [MatterSDK 111082] - DEBUG: [DMG] Refresh LivenessCheckTime for 1700310 milliseconds with SubscriptionId = 0x518c7c20 Peer = 01:9490BEA86D5276B1
2025-04-14 16:20:20.150 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:4257r S:36726 M:199185460 (Ack:1831494)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP)
2025-04-14 16:20:20.150 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:4257r S:36726 M:199185460] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1062ms [State:Active II:800 AI:8]
2025-04-14 16:20:20.199 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4257r S:36726 M:1831495 (Ack:199185460)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- )
deviceService> 

deviceService> wr /9490bea86d5276b1/ep/1/r/isOn true
2025-04-14 16:20:24.028 : [MatterBaseDD 111082] - DEBUG: (writeResource): 
2025-04-14 16:20:24.028 : [MatterBaseDD 111082] - DEBUG: (ConnectAndExecute): 
2025-04-14 16:20:24.028 : [MatterSDK 111082] - DEBUG: [DIS] Found an existing secure session to [1:9490BEA86D5276B1]!
2025-04-14 16:20:24.028 : [MatterLightDD 111082] - DEBUG: (WriteResource): isOn = true
2025-04-14 16:20:24.029 : [OnOffCluster 111082] - DEBUG: (SetOnOff): 
2025-04-14 16:20:24.029 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20556i S:36726 M:199185461] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP:[fd7d:29cc:6)
2025-04-14 16:20:24.029 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:20556i S:36726 M:199185461] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 1030ms [State:Active II:800 AI:]
2025-04-14 16:20:24.218 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:20556i S:36726 M:1831496 (Ack:199185461)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 ---)
2025-04-14 16:20:24.218 : [MatterSDK 111082] - DEBUG: [DMG] Received Command Response Status for Endpoint=1 Cluster=0x0000_0006 Command=0x0000_0001 Status=0x0
2025-04-14 16:20:24.218 : [MatterCluster 111082] - DEBUG: (OnResponse): 
2025-04-14 16:20:24.218 : [MatterBaseDD 111082] - DEBUG: (ConnectAndExecute): Processed 1 async task(s) in 188 ms
2025-04-14 16:20:24.218 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=1, resourceId=isOn, newValue=true
2025-04-14 16:20:24.218 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:20556i S:36726 M:199185462 (Ack:1831496)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UD)

resourceUpdated: id=isOn, uri=/9490bea86d5276b1/ep/1/r/isOn, ownerId=9490bea86d5276b1, value=true, type=com.icontrol.boolean, mode=0x7b (rw-del-)
2025-04-14 16:20:24.308 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4258r S:36726 M:1831497] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- Type 0001:05 (IM)
2025-04-14 16:20:24.308 : [MatterLightDD 111082] - DEBUG: (OnOffChanged): 
2025-04-14 16:20:24.309 : [deviceService 111082] - DEBUG: updateResource: deviceUuid=9490bea86d5276b1, endpointId=1, resourceId=isOn, newValue=true
2025-04-14 16:20:24.309 : [MatterSDK 111082] - DEBUG: [DMG] Refresh LivenessCheckTime for 1700310 milliseconds with SubscriptionId = 0x518c7c20 Peer = 01:9490BEA86D5276B1
2025-04-14 16:20:24.309 : [MatterSDK 111082] - DEBUG: [EM] <<< [E:4258r S:36726 M:199185463 (Ack:1831497)] (S) Msg TX from F83EDB367376F8B8 to 1:9490BEA86D5276B1 [A82D] [UDP)
2025-04-14 16:20:24.309 : [MatterSDK 111082] - DEBUG: [EM] ??1 [E:4258r S:36726 M:199185463] (S) Msg Retransmission to 1:9490BEA86D5276B1 in 918ms [State:Active II:800 AI:80]
2025-04-14 16:20:24.360 : [MatterSDK 111082] - DEBUG: [EM] >>> [E:4258r S:36726 M:1831498 (Ack:199185463)] (S) Msg RX from 1:9490BEA86D5276B1 [A82D] to F83EDB367376F8B8 --- )
deviceService> [  837.633837] systemd-journald[1091]: Data hash table of /run/log/journal/a7c511e891ac40499e2cc8c08a933b65/system.journal has a fill level at 75.0 (5462 of 7.
[  837.654990] systemd-journald[1091]: /run/log/journal/a7c511e891ac40499e2cc8c08a933b65/system.journal: Journal header limits reached or header out-of-date, rotating.


deviceService> rr /9490bea86d5276b1/r/manufacturer
Onvis

deviceService> listDevices
5b9021971ce8c157: Class: light
        Endpoint 1: Profile: light, Label: Matter Light
9490bea86d5276b1: Class: light
        Endpoint 1: Profile: light, Label: Matter Light
deviceService> pa
5b9021971ce8c157: Matter Light, Class: light
        /5b9021971ce8c157/r/communicationFailure = false
        /5b9021971ce8c157/r/dateAdded = 1744647248521
        /5b9021971ce8c157/r/dateLastContacted = 1744647270855
        /5b9021971ce8c157/r/firmwareUpdateStatus = (null)
        /5b9021971ce8c157/r/firmwareVersion = 0x00000001
        /5b9021971ce8c157/r/firmwareVersionString = 1.0.0 Build 220930 Rel.143947
        /5b9021971ce8c157/r/hardwareVersion = 1
        /5b9021971ce8c157/r/macAddress = 30de4bdb8034
        /5b9021971ce8c157/r/manufacturer = TP-Link
        /5b9021971ce8c157/r/model = Mini Smart Wi-Fi Plug
        /5b9021971ce8c157/r/networkType = wifi
        /5b9021971ce8c157/r/resetToFactoryDefaults = (null)
        /5b9021971ce8c157/r/serialNumber = (null)
        Endpoint 1: Profile: light
                /5b9021971ce8c157/ep/1/r/isOn = true
                /5b9021971ce8c157/ep/1/r/label = Matter Light
9490bea86d5276b1: Matter Light, Class: light
        /9490bea86d5276b1/r/communicationFailure = false
        /9490bea86d5276b1/r/dateAdded = 1744647590372
        /9490bea86d5276b1/r/dateLastContacted = 1744647613475
        /9490bea86d5276b1/r/firmwareUpdateStatus = failed
        /9490bea86d5276b1/r/firmwareVersion = 0x0098bd9b
        /9490bea86d5276b1/r/firmwareVersionString = 1.1.11-c85ba1e-dirty
        /9490bea86d5276b1/r/hardwareVersion = 1
        /9490bea86d5276b1/r/macAddress = 000000000000
        /9490bea86d5276b1/r/manufacturer = Onvis
        /9490bea86d5276b1/r/model = S4
        /9490bea86d5276b1/r/networkType = thread
        /9490bea86d5276b1/r/resetToFactoryDefaults = (null)
        /9490bea86d5276b1/r/serialNumber = 70016cdbb45b44b3839f9c06aaad5b65
        Endpoint 1: Profile: light
                /9490bea86d5276b1/ep/1/r/isOn = true
                /9490bea86d5276b1/ep/1/r/label = Matter Light
deviceService> 

deviceService> rr /5b9021971ce8c157/ep/1/r/isOn
true
deviceService> rr /9490bea86d5276b1/ep/1/r/isOn
true

Logging

deviceService> gs
Device Service Status:
{
        "supportedDeviceClasses":       ["light", "sensor", "thermostat", "doorLock", "presence", "lightController", "windowCovering"],
        "discoveryRunning":     false,
        "discoveringDeviceClasses":     [],
        "discoveryTimeoutSeconds":      0,
        "findingOrphanedDevices":       false,
        "isReadyForDeviceOperation":    true,
        "isReadyForPairing":    true,
        "subsystems":   [{
                        "networkName":  "TestNetwork",
                        "networkKey":   "UPOB6rmLSnUaayy2g+TY2A==",
                        "role": "unknown",
                        "activeDataset":        "DggAAAAAAAEAAAADAAAZSgMAAA01BgAEAAAAQAIIZht8G4qtpqUHCP3yaqviw5x6BRBQ84HquYtKdRprLLaD5NjYAwtUZXN0TmV0d29yawECfCcEELOdyKXw0n2a,
                        "pendingDataset":       "",
                        "channel":      25,
                        "panId":        "7c27",
                        "extendedPanId":        "661b7c1b8aada6a5",
                        "nat64Enabled": true,
                        "borderAgentId":        "V7uuWxSXsDXojNZAi4xqlQ==",
                        "threadVersion":        "5",
                        "interfaceUp":  true,
                        "ready":        true,
                        "__name":       "thread"
                }, {
                        "ready":        true,
                        "nodeId":       "f83edb367376f8b8",
                        "ipk":  "D4CKDkiMQPBFF5upwYO6kQ==",
                        "__name":       "matter"
                }]
}
deviceService> 

//------------------
deviceService> dump
/2a63de336194874e
        deviceClass=light
        deviceClassVersion=3
        managingDeviceDriver=matterLight
        resources:
                /2a63de336194874e/r/manufacturer
                        value=TP-Link
                        ownerId=2a63de336194874e
                        type=com.icontrol.string
                        mode=0x1 (r------)
                /2a63de336194874e/r/model
                        value=Mini Smart Wi-Fi Plug
                        ownerId=2a63de336194874e
                        type=com.icontrol.string
                        mode=0x21 (r---e--)
                /2a63de336194874e/r/hardwareVersion
                        value=1
                        ownerId=2a63de336194874e
                        type=com.icontrol.version
                        mode=0x1 (r------)
                /2a63de336194874e/r/firmwareVersion
                        value=0x00000001
                        ownerId=2a63de336194874e
                        type=com.icontrol.version
                        mode=0x39 (r--de--)
                /2a63de336194874e/r/firmwareUpdateStatus
                        value=(null)
                        ownerId=2a63de336194874e
                        type=com.icontrol.firmwareVersionStatus
                        mode=0x39 (r--de--)
                /2a63de336194874e/r/dateAdded
                        value=1744644845171
                        ownerId=2a63de336194874e
                        type=com.icontrol.dateTime
                        mode=0x1 (r------)
                /2a63de336194874e/r/dateLastContacted
                        value=1744664466026
                        ownerId=2a63de336194874e
                        type=com.icontrol.dateTime
                        mode=0x59 (r--d-l-)
                /2a63de336194874e/r/communicationFailure
                        value=false
                        ownerId=2a63de336194874e
                        type=com.icontrol.trouble
                        mode=0x39 (r--de--)
                /2a63de336194874e/r/resetToFactoryDefaults
                        value=(null)
                        ownerId=2a63de336194874e
                        type=com.icontrol.resetToFactoryOperation
                        mode=0x4 (--x----)
                /2a63de336194874e/r/serialNumber
                        value=(null)
                        ownerId=2a63de336194874e
                        type=com.icontrol.serialNumber
                        mode=0x1 (r------)
                /2a63de336194874e/r/firmwareVersionString
                        value=1.0.0 Build 220930 Rel.143947
                        ownerId=2a63de336194874e
                        type=com.icontrol.string
                        mode=0x39 (r--de--)
                /2a63de336194874e/r/macAddress
                        value=30de4bdb8034
                        ownerId=2a63de336194874e
                        type=com.icontrol.macAddress
                        mode=0x1 (r------)
                /2a63de336194874e/r/networkType
                        value=wifi
                        ownerId=2a63de336194874e
                        type=com.icontrol.networkType
                        mode=0x1 (r------)
        endpoints:
                /2a63de336194874e/ep/1
                        profile=light
                        profileVersion=0
                        ownerId=2a63de336194874e
                        resources:
                                /2a63de336194874e/ep/1/r/label
                                        value=Matter Light
                                        ownerId=2a63de336194874e
                                        type=com.icontrol.label
                                        mode=0x3b (rw-de--)
                                /2a63de336194874e/ep/1/r/isOn
                                        value=true
                                        ownerId=2a63de336194874e
                                        type=com.icontrol.boolean
                                        mode=0x7b (rw-del-)
        metadata:
                        {
        "vendor":       "TP-Link",
        "product":      "Mini Smart Wi-Fi Plug",
        "hwVer":        "1",
        "swVer":        "0x00000001",
        "swStr":        "1.0.0 Build 220930 Rel.143947",
        "serialNumber": null,
        "macAddress":   "30de4bdb8034",
        "networkType":  "wifi",
        "endpoints":    [{
                        "endpointId":   0,
                        "deviceTypes":  [{
                                        "type": 22
                                }],
                        "servers":      [29, 31, 40, 42, 48, 49, 51, 60, 62, 63],
                        "clients":      [41],
                        "parts":        [1]
                }, {
                        "endpointId":   1,
                        "deviceTypes":  [{
                                        "type": 266
                                }],
                        "servers":      [3, 4, 5, 6, 29],
                        "clients":      [],
                        "parts":        []
                }]
}
/fdddc46b3881fe3c
        deviceClass=light
        deviceClassVersion=3
        managingDeviceDriver=matterLight
        resources:
                /fdddc46b3881fe3c/r/manufacturer
                        value=Onvis
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.string
                        mode=0x1 (r------)
                /fdddc46b3881fe3c/r/model
                        value=S4
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.string
                        mode=0x21 (r---e--)
                /fdddc46b3881fe3c/r/hardwareVersion
                        value=1
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.version
                        mode=0x1 (r------)
                /fdddc46b3881fe3c/r/firmwareVersion
                        value=0x0098bd9b
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.version
                        mode=0x39 (r--de--)
                /fdddc46b3881fe3c/r/firmwareUpdateStatus
                        value=failed
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.firmwareVersionStatus
                        mode=0x39 (r--de--)
                /fdddc46b3881fe3c/r/dateAdded
                        value=1744663050010
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.dateTime
                        mode=0x1 (r------)
                /fdddc46b3881fe3c/r/dateLastContacted
                        value=1744664452372
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.dateTime
                        mode=0x59 (r--d-l-)
                /fdddc46b3881fe3c/r/communicationFailure
                        value=false
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.trouble
                        mode=0x39 (r--de--)
                /fdddc46b3881fe3c/r/resetToFactoryDefaults
                        value=(null)
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.resetToFactoryOperation
                        mode=0x4 (--x----)
                /fdddc46b3881fe3c/r/serialNumber
                        value=70016cdbb45b44b3839f9c06aaad5b65
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.serialNumber
                        mode=0x1 (r------)
                /fdddc46b3881fe3c/r/firmwareVersionString
                        value=1.1.11-c85ba1e-dirty
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.string
                        mode=0x39 (r--de--)
                /fdddc46b3881fe3c/r/macAddress
                        value=000000000000
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.macAddress
                        mode=0x1 (r------)
                /fdddc46b3881fe3c/r/networkType
                        value=thread
                        ownerId=fdddc46b3881fe3c
                        type=com.icontrol.networkType
                        mode=0x1 (r------)
        endpoints:
                /fdddc46b3881fe3c/ep/1
                        profile=light
                        profileVersion=0
                        ownerId=fdddc46b3881fe3c
                        resources:
                                /fdddc46b3881fe3c/ep/1/r/label
                                        value=Matter Light
                                        ownerId=fdddc46b3881fe3c
                                        type=com.icontrol.label
                                        mode=0x3b (rw-de--)
                                /fdddc46b3881fe3c/ep/1/r/isOn
                                        value=true
                                        ownerId=fdddc46b3881fe3c
                                        type=com.icontrol.boolean
                                        mode=0x7b (rw-del-)
        metadata:
                        {
        "vendor":       "Onvis",
        "product":      "S4",
        "hwVer":        "1",
        "swVer":        "0x0098bd9b",
        "swStr":        "1.1.11-c85ba1e-dirty",
        "serialNumber": "70016cdbb45b44b3839f9c06aaad5b65",
        "macAddress":   "000000000000",
        "networkType":  "thread",
        "endpoints":    [{
                        "endpointId":   0,
                        "deviceTypes":  [{
                                        "type": 22
                                }],
                        "servers":      [29, 31, 40, 48, 49, 51, 60, 62, 63, 53, 42],
                        "clients":      [41],
                        "parts":        [1]
                }, {
                        "endpointId":   1,
                        "deviceTypes":  [{
                                        "type": 266
                                }],
                        "servers":      [29, 3, 4, 5, 6],
                        "clients":      [],
                        "parts":        []
                }]
}
deviceService>

IoT devices used

Thread smart plug
- https://www.amazon.com/dp/B0CWTKW9BB?ref=ppx_yo2ov_dt_b_fed_asin_title&th=1
Wifi smart plug
- TP-Link tapo matter smart plug - TAPO P125M
- https://www.tp-link.com/us/home-networking/smart-plug/tapo-p125m/

Known issues

Need to enable firewall to allow mDNS,As of now we bypassed firewall

Demo video (April 10/14 2025)

Matter over wifi demo
- barton-Matter-IoT-Demo.mp4
Matter over thread demo
- barton-IoT-Matter-with-wifi-thread.mp4
- Working image (Will be shared on request to License members)
  - 1barton.wic.bz2

Scope

Key insights

Block diagram

Use case diagram

RDK-B IoT Build

Prelimanary

Barton core

Verification of barton in RDK-B

CLI

Matter onboarding stages Barton

Vendor integration

Silabs radio integration

OSS component support

linenoise upstep

Verification with matter device

Associated wifi matter device

Verification of thread

Logging

IoT devices used

Thread smart plug

Wifi smart plug

Known issues

Demo video (April 10/14 2025)

Matter over wifi demo

Matter over thread demo

Working image (Will be shared on request to License members)