Page History

Table of Contents

Scope

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

...

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

Block diagram

• draw.io Diagram
  border true diagramName bartcorear simpleViewer false width links auto tbstyle top lbox true diagramWidth 851 height 390 revision 3

Use case diagram

• draw.io Diagram
  border true diagramName usecase-matter simpleViewer false width links auto tbstyle top lbox true diagramWidth 291 height 332 revision 2

RDK-B IoT Build

• Prelimanary

  Preliminary

  • RDK-B Banana Pi R4 - Beta Engineering Release(05 Feb 2025)
• Vendor integration
  • OpenThread & Bluetooth platform integration in BPI R4 with silabs radio
  • https://github.com/rdkcentral/meta-cmf-bananapi/tree/barton
  • Few changes from - https://github.com/rdkcentral/meta-cmf-bananapi/commits/iotyocto/
• 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 
• 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

• 
  

  • Code Block

    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

    • Code Block

      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

    •  

      Code Block

      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

    • draw.io Diagram
      border true diagramName wpan0 simpleViewer false width links auto tbstyle top lbox true diagramWidth 690 height 300 revision 1

Matter onboarding stages Barton

• draw.io Diagram
  border true diagramName MatterBarton simpleViewer false width links auto tbstyle top lbox true diagramWidth 286 height 1466 revision 1

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

      • draw.io Diagram
        border true diagramName linenoise simpleViewer false width links auto tbstyle top lbox true diagramWidth 620 height 276 revision 1

      • Note:depending on usage please include 001 patch too
  • File location

    • /home/cpokur625/BI/meta-rdk-ext/recipes-support/linenoise

Verification with matter device

• Code Block

  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

• Code Block

  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

  • Code Block

    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

• Code Block

  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

• Code Block

  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