wordpress wp-content权限,上海优化排名网站,用软件做seo网站关键词推广,wordpress用户权限插件前言
主要是记录一下学习过程#xff0c;梳理下思路#xff0c;抛转~
官方的开发环境#xff0c;基于Linux版本#xff0c;官方的环境是基于树莓派环境的#xff0c;原理其实也比较明了#xff0c;目的也比较明确#xff0c;就是达到Linux 主机和wifi 路由在同一局域网…前言
主要是记录一下学习过程梳理下思路抛转~
官方的开发环境基于Linux版本官方的环境是基于树莓派环境的原理其实也比较明了目的也比较明确就是达到Linux 主机和wifi 路由在同一局域网借助蓝牙的能力将支持matter协议的wifi 设备添加到网络中后面就是直接局域网控制就OK了折腾了几天终于跑通了。
原理
蓝牙配网功能其实比较常见粗略理解了流程就俩个核心安全的传输信息给待配网设备拿到ip。所以配网过程就涉及一些安全证书、加密传输等知识有了方向就可以往下深入理解了
环境
VMware Ubuntu 22.04蓝牙 BT5.0 (实验环境)wifi 路由器
准备
chip-tool
配网控制需要编译chip-tool过程参考之前一篇 【Matter】基于Ubuntu 22.04 编译chip-tool工具
蓝牙模块
蓝牙模块由于使用虚拟机所以就购买了蓝牙模块BT5.0的免驱支持Ubuntu这一部分的验证还得继续研究包括BT4.0也可以用吗具体使用了哪些能力
过程
蓝牙功能确认
首先确保蓝牙是可用的具体测试办法参考一下蓝牙的测试办法常用的工具有bluetoothctl、blueman 测试工作当然不止上面一条搜索一下工具怎么用支持哪些命令 设备触发进入配网状态 我手上拿到的是一款wifi灯开关五次设备就开始发送蓝牙广播处于配网状态 执行命令进行配网 贴上配网成功的log可以参考排查问题
配网 xingxing-virtual-machine:~/work/connectedhomeip$ out/x86/chip-tool pairing ble-wifi 1235 AX3000_7076 1234567890 32878964 384 --bypass-attestation-verifier true [1692185956.271842][4873:4873] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1692185956.271897][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_kvs-FBKRft) [1692185956.271934][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1692185956.273551][4873:4873] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1692185956.273622][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-4X5Xz5) [1692185956.273652][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1692185956.273693][4873:4873] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1692185956.273713][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_config.ini-8OTMTn) [1692185956.273733][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1692185956.273769][4873:4873] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1692185956.273789][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-jzasl7) [1692185956.273808][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1692185956.276499][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-On38Ak) [1692185956.276675][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1692185956.276700][4873:4873] CHIP:DL: NVS set: chip-factory/unique-id “2A04CD881AD7A36E” [1692185956.276731][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-CO6MK2) [1692185956.276891][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1692185956.276914][4873:4873] CHIP:DL: NVS set: chip-factory/vendor-id 65521 (0xFFF1) [1692185956.276941][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-zee7Ut) [1692185956.277213][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1692185956.277243][4873:4873] CHIP:DL: NVS set: chip-factory/product-id 32769 (0x8001) [1692185956.277274][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-xcIxzX) [1692185956.277397][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1692185956.277419][4873:4873] CHIP:DL: NVS set: chip-counters/reboot-count 1 (0x1) [1692185956.277448][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-PO8rUc) [1692185956.277598][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1692185956.277625][4873:4873] CHIP:DL: NVS set: chip-counters/total-operational-hours 0 (0x0) [1692185956.277654][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-LnLY5b) [1692185956.277954][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1692185956.277982][4873:4873] CHIP:DL: NVS set: chip-counters/boot-reason 0 (0x0) [1692185956.278011][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_config.ini-P5JRau) [1692185956.278130][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1692185956.278153][4873:4873] CHIP:DL: NVS set: chip-config/regulatory-location 0 (0x0) [1692185956.278181][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_config.ini-iHudLZ) [1692185956.278526][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1692185956.278554][4873:4873] CHIP:DL: NVS set: chip-config/location-capability 2 (0x2) [1692185956.278763][4873:4873] CHIP:DL: Got Ethernet interface: ens33 [1692185956.278886][4873:4873] CHIP:DL: Found the primary Ethernet interface:ens33 [1692185956.279025][4873:4873] CHIP:DL: Failed to get WiFi interface [1692185956.279030][4873:4873] CHIP:DL: Failed to reset WiFi statistic counts [1692185956.280200][4873:4873] CHIP:IN: UDP::Init bindlisten port0 [1692185956.280243][4873:4873] CHIP:IN: UDP::Init bound to port41284 [1692185956.280249][4873:4873] CHIP:IN: UDP::Init bindlisten port0 [1692185956.280261][4873:4873] CHIP:IN: UDP::Init bound to port35885 [1692185956.280286][4873:4873] CHIP:IN: BLEBase::Init - setting/overriding transport [1692185956.280289][4873:4873] CHIP:IN: TransportMgr initialized [1692185956.280302][4873:4873] CHIP:FP: Initializing FabricTable from persistent storage [1692185956.280311][4873:4873] CHIP:TS: Last Known Good Time: [unknown] [1692185956.280335][4873:4873] CHIP:TS: Setting Last Known Good Time to firmware build time 2023-08-10T09:33:31 [1692185956.281166][4873:4873] CHIP:ZCL: Using ZAP configuration… [1692185956.282879][4873:4873] CHIP:CTL: System State Initialized… [1692185956.282942][4873:4873] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1692185956.282966][4873:4873] CHIP:CTL: Setting attestation nonce to random value [1692185956.283010][4873:4873] CHIP:CTL: Setting CSR nonce to random value [1692185956.283038][4873:4873] CHIP:IN: UDP::Init bindlisten port5550 [1692185956.283084][4873:4873] CHIP:IN: UDP::Init bound to port5550 [1692185956.283110][4873:4873] CHIP:IN: UDP::Init bindlisten port5550 [1692185956.283125][4873:4873] CHIP:IN: UDP::Init bound to port5550 [1692185956.283127][4873:4873] CHIP:IN: TransportMgr initialized [1692185956.283229][4873:4875] CHIP:DL: CHIP task running [1692185956.283277][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 32786 [1692185956.283557][4873:4875] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1692185956.283611][4873:4875] CHIP:CTL: Setting attestation nonce to random value [1692185956.283657][4873:4875] CHIP:CTL: Setting CSR nonce to random value [1692185956.283732][4873:4875] CHIP:CTL: Couldn’t get ExampleOpCredsCAKey from storage: src/controller/ExamplePersistentStorage.cpp:99: CHIP Error 0x000000A0: Value not found in the persisted storage [1692185956.292418][4873:4875] CHIP:CTL: Couldn’t get ExampleOpCredsICAKey from storage: src/controller/ExamplePersistentStorage.cpp:99: CHIP Error 0x000000A0: Value not found in the persisted storage [1692185956.293132][4873:4875] CHIP:CTL: Generating RCAC [1692185956.295878][4873:4875] CHIP:CTL: Generating ICAC [1692185956.296815][4873:4875] CHIP:CTL: Generating NOC [1692185956.297231][4873:4875] CHIP:FP: Validating NOC chain [1692185956.305477][4873:4875] CHIP:FP: NOC chain validation successful [1692185956.307030][4873:4875] CHIP:FP: Added new fabric at index: 0x1 [1692185956.307073][4873:4875] CHIP:FP: Assigned compressed fabric ID: 0x83349DF93C94F36C, node ID: 0x000000000001B669 [1692185956.307090][4873:4875] CHIP:TS: Last Known Good Time: 2023-08-10T09:33:31 [1692185956.307094][4873:4875] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1692185956.307098][4873:4875] CHIP:TS: Retaining current Last Known Good Time [1692185956.308968][4873:4875] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1692185956.309807][4873:4875] CHIP:TS: Committing Last Known Good Time to storage: 2023-08-10T09:33:31 [1692185956.310766][4873:4875] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 83349DF93C94F36C) [1692185956.310844][4873:4875] CHIP:IN: UDP::Init bindlisten port5550 [1692185956.310887][4873:4875] CHIP:IN: UDP::Init bound to port5550 [1692185956.310921][4873:4875] CHIP:IN: UDP::Init bindlisten port5550 [1692185956.310948][4873:4875] CHIP:IN: UDP::Init bound to port5550 [1692185956.310951][4873:4875] CHIP:IN: TransportMgr initialized [1692185956.329567][4873:4875] CHIP:CTL: Setting wifi credentials from parameters [1692185956.330210][4873:4875] CHIP:CTL: Setting attestation nonce to random value [1692185956.330229][4873:4875] CHIP:CTL: Setting CSR nonce to random value [1692185956.330238][4873:4875] CHIP:CTL: Commission called for node ID 0x00000000000004D3 [1692185956.334397][4873:4874] CHIP:DL: TRACE: Bus acquired for name C-1309 [1692185956.338779][4873:4875] CHIP:DL: PlatformBlueZInit init success [1692185956.341588][4873:4874] CHIP:BLE: BLE removing known devices. [1692185956.342100][4873:4874] CHIP:BLE: BLE initiating scan. [1692185956.523113][4873:4875] CHIP:DL: Long dispatch time: 192 ms, for event type 2 [1692185956.577156][4873:4874] CHIP:BLE: Device CC:81:2A:DF:69:B4 does not look like a CHIP device. [1692185956.581106][4873:4874] CHIP:BLE: Device 34:BE:12:F3:5B:C9 does not look like a CHIP device. [1692185956.581340][4873:4874] CHIP:BLE: Device C4:67:B5:83:5D:F5 does not look like a CHIP device. [1692185956.581694][4873:4874] CHIP:BLE: Device D1:00:FD:87:EB:FC does not look like a CHIP device. [1692185956.582002][4873:4874] CHIP:BLE: Device 74:C9:31:37:0F:F9 does not look like a CHIP device. [1692185956.614006][4873:4874] CHIP:BLE: New device scanned: 53:0B:77:A5:C5:EA [1692185956.614053][4873:4874] CHIP:BLE: Device discriminator match. Attempting to connect. [1692185956.908258][4873:4874] CHIP:DL: ConnectDevice complete [1692185958.216567][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0008 [1692185958.216637][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.216662][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0008 [1692185958.216686][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.216709][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0008 [1692185958.216732][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.216756][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0008 [1692185958.216778][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.216801][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0008 [1692185958.216823][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.216846][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0008 [1692185958.216868][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.216892][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.216914][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.216938][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0008 [1692185958.216961][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.216983][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0008 [1692185958.217006][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217028][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0008 [1692185958.217050][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217075][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217114][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217141][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217164][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217188][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217210][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217234][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217257][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217280][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217302][4873:4874] CHIP:DL: Char1 /org/bluez/hci0/dev_53_0B_77_A5_C5_EA/service0010 [1692185958.217327][4873:4874] CHIP:DL: New BLE connection: conn 0x7ff3cc05a340, device 53:0B:77:A5:C5:EA, path /org/bluez/hci0/dev_53_0B_77_A5_C5_EA [1692185958.217473][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 [1692185958.217504][4873:4875] CHIP:DIS: Closing all BLE connections [1692185958.217527][4873:4875] CHIP:IN: BleConnectionComplete: endPoint 0x55e6f68af380 [1692185958.218883][4873:4875] CHIP:IN: SecureSession[0x7ff3c4014a00]: Allocated Type:1 LSID:63028 [1692185958.218909][4873:4875] CHIP:SC: Assigned local session key ID 63028 [1692185958.218935][4873:4875] CHIP:EM: [E:54442i S:0 M:99666382] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:20 (SecureChannel:PBKDFParamRequest) [1692185958.218967][4873:4875] CHIP:IN: (U) Sending msg 99666382 to IP address ‘BLE’ [1692185958.218973][4873:4875] CHIP:IN: Message appended to BLE send queue [1692185958.218976][4873:4875] CHIP:SC: Sent PBKDF param request [1692185958.797253][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185958.892163][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185958.892272][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1692185958.892280][4873:4875] CHIP:BLE: subscribe complete, ep 0x55e6f68af380 [1692185958.892284][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185958.892288][4873:4875] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4 [1692185958.892291][4873:4875] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244. [1692185958.892293][4873:4875] CHIP:BLE: local and remote recv window size 5 [1692185958.892507][4873:4875] CHIP:IN: BLE EndPoint 0x55e6f68af380 Connection Complete [1692185959.027742][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185959.027935][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185959.028004][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185959.028810][4873:4875] CHIP:EM: [E:54442i S:0 M:54954233] (U) Msg RX from 0:0000000000000000 [0000] — Type 0000:21 (SecureChannel:PBKDFParamResponse) [1692185959.028861][4873:4875] CHIP:EM: Found matching exchange: 54442i, Delegate: 0x7ff3c4033d70 [1692185959.028893][4873:4875] CHIP:SC: Received PBKDF param response [1692185959.028926][4873:4875] CHIP:SC: Peer assigned session ID 20315 [1692185959.056237][4873:4875] CHIP:EM: [E:54442i S:0 M:99666383] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:22 (SecureChannel:PASE_Pake1) [1692185959.056339][4873:4875] CHIP:IN: (U) Sending msg 99666383 to IP address ‘BLE’ [1692185959.057515][4873:4875] CHIP:SC: Sent spake2p msg1 [1692185959.118983][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185960.695770][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185960.695876][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185960.695940][4873:4875] CHIP:EM: [E:54442i S:0 M:54954234] (U) Msg RX from 0:0000000000000000 [0000] — Type 0000:23 (SecureChannel:PASE_Pake2) [1692185960.695968][4873:4875] CHIP:EM: Found matching exchange: 54442i, Delegate: 0x7ff3c4033d70 [1692185960.695977][4873:4875] CHIP:SC: Received spake2p msg2 [1692185960.696399][4873:4875] CHIP:EM: [E:54442i S:0 M:99666384] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:24 (SecureChannel:PASE_Pake3) [1692185960.696433][4873:4875] CHIP:IN: (U) Sending msg 99666384 to IP address ‘BLE’ [1692185960.696534][4873:4875] CHIP:SC: Sent spake2p msg3 [1692185960.783897][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185960.783978][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185960.784031][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185960.784062][4873:4875] CHIP:EM: [E:54442i S:0 M:54954235] (U) Msg RX from 0:0000000000000000 [0000] — Type 0000:40 (SecureChannel:StatusReport) [1692185960.784089][4873:4875] CHIP:EM: Found matching exchange: 54442i, Delegate: 0x7ff3c4033d70 [1692185960.784156][4873:4875] CHIP:SC: SecureSession[0x7ff3c4014a00, LSID:63028]: State change ‘kEstablishing’ -- ‘kActive’ [1692185960.784181][4873:4875] CHIP:IN: SecureSession[0x7ff3c4014a00]: Activated - Type:1 LSID:63028 [1692185960.784185][4873:4875] CHIP:IN: New secure session activated for device FFFFFFFB00000000, 0, LSID:63028 PSID:20315! [1692185960.784190][4873:4875] CHIP:CTL: Remote device completed SPAKE2 handshake [1692185960.784192][4873:4875] CHIP:TOO: Pairing Success [1692185960.784194][4873:4875] CHIP:TOO: PASE establishment successful [1692185960.784198][4873:4875] CHIP:CTL: Commissioning stage next step: ‘SecurePairing’ - ‘ReadCommissioningInfo’ [1692185960.784201][4873:4875] CHIP:CTL: Performing next commissioning step ‘ReadCommissioningInfo’ [1692185960.784203][4873:4875] CHIP:CTL: Sending request for commissioning information [1692185960.784223][4873:4875] CHIP:DMG: SendReadRequest ReadClient[0x7ff3c403cdc0]: Sending Read Request [1692185960.784285][4873:4875] CHIP:EM: [E:54443i S:63028 M:42671701] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:02 (IM:ReadRequest) [1692185960.784312][4873:4875] CHIP:IN: (S) Sending msg 42671701 on secure session with LSID: 63028 [1692185960.784432][4873:4875] CHIP:DMG: MoveToState ReadClient[0x7ff3c403cdc0]: Moving to [AwaitingIn] [1692185961.099387][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185961.147425][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185961.147516][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185961.324506][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185961.324595][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185961.324662][4873:4875] CHIP:EM: [E:54443i S:63028 M:127198689] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:05 (IM:ReportData) [1692185961.324690][4873:4875] CHIP:EM: Found matching exchange: 54443i, Delegate: 0x7ff3c403cdd0 [1692185961.324717][4873:4875] CHIP:DMG: ReportDataMessage [1692185961.324740][4873:4875] CHIP:DMG: { [1692185961.324744][4873:4875] CHIP:DMG: AttributeReportIBs [1692185961.324749][4873:4875] CHIP:DMG: [ [1692185961.324751][4873:4875] CHIP:DMG: AttributeReportIB [1692185961.324755][4873:4875] CHIP:DMG: { [1692185961.324757][4873:4875] CHIP:DMG: AttributeDataIB [1692185961.324760][4873:4875] CHIP:DMG: { [1692185961.324763][4873:4875] CHIP:DMG: DataVersion 0x81c5d8b0, [1692185961.324786][4873:4875] CHIP:DMG: AttributePathIB [1692185961.324791][4873:4875] CHIP:DMG: { [1692185961.324795][4873:4875] CHIP:DMG: Endpoint 0x0, [1692185961.324798][4873:4875] CHIP:DMG: Cluster 0x31, [1692185961.324800][4873:4875] CHIP:DMG: Attribute 0x0000_0003, [1692185961.324803][4873:4875] CHIP:DMG: } [1692185961.324806][4873:4875] CHIP:DMG: [1692185961.324828][4873:4875] CHIP:DMG: Data 20, [1692185961.324832][4873:4875] CHIP:DMG: }, [1692185961.324836][4873:4875] CHIP:DMG: [1692185961.324839][4873:4875] CHIP:DMG: }, [1692185961.324845][4873:4875] CHIP:DMG: [1692185961.324847][4873:4875] CHIP:DMG: AttributeReportIB [1692185961.324850][4873:4875] CHIP:DMG: { [1692185961.324853][4873:4875] CHIP:DMG: AttributeDataIB [1692185961.324855][4873:4875] CHIP:DMG: { [1692185961.324858][4873:4875] CHIP:DMG: DataVersion 0x57be975d, [1692185961.324860][4873:4875] CHIP:DMG: AttributePathIB [1692185961.324881][4873:4875] CHIP:DMG: { [1692185961.324885][4873:4875] CHIP:DMG: Endpoint 0x0, [1692185961.324888][4873:4875] CHIP:DMG: Cluster 0x28, [1692185961.324892][4873:4875] CHIP:DMG: Attribute 0x0000_0004, [1692185961.324894][4873:4875] CHIP:DMG: } [1692185961.324897][4873:4875] CHIP:DMG: [1692185961.324900][4873:4875] CHIP:DMG: Data 53250, [1692185961.324903][4873:4875] CHIP:DMG: }, [1692185961.324906][4873:4875] CHIP:DMG: [1692185961.324908][4873:4875] CHIP:DMG: }, [1692185961.324913][4873:4875] CHIP:DMG: [1692185961.324934][4873:4875] CHIP:DMG: AttributeReportIB [1692185961.324940][4873:4875] CHIP:DMG: { [1692185961.324942][4873:4875] CHIP:DMG: AttributeDataIB [1692185961.324945][4873:4875] CHIP:DMG: { [1692185961.324948][4873:4875] CHIP:DMG: DataVersion 0x57be975d, [1692185961.324950][4873:4875] CHIP:DMG: AttributePathIB [1692185961.324953][4873:4875] CHIP:DMG: { [1692185961.324955][4873:4875] CHIP:DMG: Endpoint 0x0, [1692185961.324976][4873:4875] CHIP:DMG: Cluster 0x28, [1692185961.324981][4873:4875] CHIP:DMG: Attribute 0x0000_0002, [1692185961.324984][4873:4875] CHIP:DMG: } [1692185961.324988][4873:4875] CHIP:DMG: [1692185961.324991][4873:4875] CHIP:DMG: Data 5020, [1692185961.324993][4873:4875] CHIP:DMG: }, [1692185961.324996][4873:4875] CHIP:DMG: [1692185961.324999][4873:4875] CHIP:DMG: }, [1692185961.325004][4873:4875] CHIP:DMG: [1692185961.325006][4873:4875] CHIP:DMG: AttributeReportIB [1692185961.325009][4873:4875] CHIP:DMG: { [1692185961.325035][4873:4875] CHIP:DMG: AttributeDataIB [1692185961.325041][4873:4875] CHIP:DMG: { [1692185961.325045][4873:4875] CHIP:DMG: DataVersion 0x37caace1, [1692185961.325048][4873:4875] CHIP:DMG: AttributePathIB [1692185961.325052][4873:4875] CHIP:DMG: { [1692185961.325054][4873:4875] CHIP:DMG: Endpoint 0x0, [1692185961.325057][4873:4875] CHIP:DMG: Cluster 0x30, [1692185961.325060][4873:4875] CHIP:DMG: Attribute 0x0000_0003, [1692185961.325062][4873:4875] CHIP:DMG: } [1692185961.325087][4873:4875] CHIP:DMG: [1692185961.325091][4873:4875] CHIP:DMG: Data 0, [1692185961.325094][4873:4875] CHIP:DMG: }, [1692185961.325098][4873:4875] CHIP:DMG: [1692185961.325100][4873:4875] CHIP:DMG: }, [1692185961.325105][4873:4875] CHIP:DMG: [1692185961.325107][4873:4875] CHIP:DMG: AttributeReportIB [1692185961.325111][4873:4875] CHIP:DMG: { [1692185961.325131][4873:4875] CHIP:DMG: AttributeDataIB [1692185961.325136][4873:4875] CHIP:DMG: { [1692185961.325139][4873:4875] CHIP:DMG: DataVersion 0x37caace1, [1692185961.325142][4873:4875] CHIP:DMG: AttributePathIB [1692185961.325144][4873:4875] CHIP:DMG: { [1692185961.325147][4873:4875] CHIP:DMG: Endpoint 0x0, [1692185961.325150][4873:4875] CHIP:DMG: Cluster 0x30, [1692185961.325153][4873:4875] CHIP:DMG: Attribute 0x0000_0002, [1692185961.325155][4873:4875] CHIP:DMG: } [1692185961.325177][4873:4875] CHIP:DMG: [1692185961.325181][4873:4875] CHIP:DMG: Data 0, [1692185961.325184][4873:4875] CHIP:DMG: }, [1692185961.325187][4873:4875] CHIP:DMG: [1692185961.325190][4873:4875] CHIP:DMG: }, [1692185961.325195][4873:4875] CHIP:DMG: [1692185961.325197][4873:4875] CHIP:DMG: AttributeReportIB [1692185961.325220][4873:4875] CHIP:DMG: { [1692185961.325224][4873:4875] CHIP:DMG: AttributeDataIB [1692185961.325227][4873:4875] CHIP:DMG: { [1692185961.325229][4873:4875] CHIP:DMG: DataVersion 0x37caace1, [1692185961.325231][4873:4875] CHIP:DMG: AttributePathIB [1692185961.325234][4873:4875] CHIP:DMG: { [1692185961.325236][4873:4875] CHIP:DMG: Endpoint 0x0, [1692185961.325240][4873:4875] CHIP:DMG: Cluster 0x30, [1692185961.325261][4873:4875] CHIP:DMG: Attribute 0x0000_0001, [1692185961.325265][4873:4875] CHIP:DMG: } [1692185961.325269][4873:4875] CHIP:DMG: [1692185961.325272][4873:4875] CHIP:DMG: Data [1692185961.325275][4873:4875] CHIP:DMG: { [1692185961.325278][4873:4875] CHIP:DMG: 0x0 60, [1692185961.325280][4873:4875] CHIP:DMG: 0x1 900, [1692185961.325302][4873:4875] CHIP:DMG: }, [1692185961.325306][4873:4875] CHIP:DMG: }, [1692185961.325310][4873:4875] CHIP:DMG: [1692185961.325313][4873:4875] CHIP:DMG: }, [1692185961.325318][4873:4875] CHIP:DMG: [1692185961.325321][4873:4875] CHIP:DMG: AttributeReportIB [1692185961.325324][4873:4875] CHIP:DMG: { [1692185961.325326][4873:4875] CHIP:DMG: AttributeDataIB [1692185961.325329][4873:4875] CHIP:DMG: { [1692185961.325331][4873:4875] CHIP:DMG: DataVersion 0x37caace1, [1692185961.325352][4873:4875] CHIP:DMG: AttributePathIB [1692185961.325357][4873:4875] CHIP:DMG: { [1692185961.325360][4873:4875] CHIP:DMG: Endpoint 0x0, [1692185961.325363][4873:4875] CHIP:DMG: Cluster 0x30, [1692185961.325366][4873:4875] CHIP:DMG: Attribute 0x0000_0000, [1692185961.325369][4873:4875] CHIP:DMG: } [1692185961.325372][4873:4875] CHIP:DMG: [1692185961.325374][4873:4875] CHIP:DMG: Data 0, [1692185961.325377][4873:4875] CHIP:DMG: }, [1692185961.325381][4873:4875] CHIP:DMG: [1692185961.325400][4873:4875] CHIP:DMG: }, [1692185961.325408][4873:4875] CHIP:DMG: [1692185961.325411][4873:4875] CHIP:DMG: AttributeReportIB [1692185961.325415][4873:4875] CHIP:DMG: { [1692185961.325417][4873:4875] CHIP:DMG: AttributeDataIB [1692185961.325419][4873:4875] CHIP:DMG: { [1692185961.325422][4873:4875] CHIP:DMG: DataVersion 0x81c5d8b0, [1692185961.325424][4873:4875] CHIP:DMG: AttributePathIB [1692185961.325427][4873:4875] CHIP:DMG: { [1692185961.325430][4873:4875] CHIP:DMG: Endpoint 0x0, [1692185961.325451][4873:4875] CHIP:DMG: Cluster 0x31, [1692185961.325455][4873:4875] CHIP:DMG: Attribute 0x0000_FFFC, [1692185961.325458][4873:4875] CHIP:DMG: } [1692185961.325462][4873:4875] CHIP:DMG: [1692185961.325465][4873:4875] CHIP:DMG: Data 1, [1692185961.325467][4873:4875] CHIP:DMG: }, [1692185961.325471][4873:4875] CHIP:DMG: [1692185961.325474][4873:4875] CHIP:DMG: }, [1692185961.325477][4873:4875] CHIP:DMG: [1692185961.325479][4873:4875] CHIP:DMG: ], [1692185961.325510][4873:4875] CHIP:DMG: [1692185961.325514][4873:4875] CHIP:DMG: SuppressResponse true, [1692185961.325517][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185961.325519][4873:4875] CHIP:DMG: } [1692185961.325737][4873:4875] CHIP:CTL: ----- NetworkCommissioning Features: has WiFi. endpointid 0 [1692185961.325777][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘ReadCommissioningInfo’ [1692185961.326680][4873:4875] CHIP:CTL: Commissioning stage next step: ‘ReadCommissioningInfo’ - ‘ArmFailSafe’ [1692185961.326704][4873:4875] CHIP:CTL: Performing next commissioning step ‘ArmFailSafe’ [1692185961.326710][4873:4875] CHIP:CTL: Arming failsafe (60 seconds) [1692185961.326732][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185961.327616][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185961.327665][4873:4875] CHIP:EM: [E:54444i S:63028 M:42671702] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest) [1692185961.327674][4873:4875] CHIP:IN: (S) Sending msg 42671702 on secure session with LSID: 63028 [1692185961.327822][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185961.503802][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185961.503928][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185961.503963][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185961.504101][4873:4875] CHIP:EM: [E:54444i S:63028 M:127198690] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse) [1692185961.504130][4873:4875] CHIP:EM: Found matching exchange: 54444i, Delegate: 0x55e6f92eee88 [1692185961.504138][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185961.504151][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185961.504153][4873:4875] CHIP:DMG: { [1692185961.504155][4873:4875] CHIP:DMG: suppressResponse false, [1692185961.504178][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185961.504185][4873:4875] CHIP:DMG: [ [1692185961.504187][4873:4875] CHIP:DMG: InvokeResponseIB [1692185961.504191][4873:4875] CHIP:DMG: { [1692185961.504194][4873:4875] CHIP:DMG: CommandDataIB [1692185961.504197][4873:4875] CHIP:DMG: { [1692185961.504199][4873:4875] CHIP:DMG: CommandPathIB [1692185961.504298][4873:4875] CHIP:DMG: { [1692185961.504303][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185961.504306][4873:4875] CHIP:DMG: ClusterId 0x30, [1692185961.504309][4873:4875] CHIP:DMG: CommandId 0x1, [1692185961.504311][4873:4875] CHIP:DMG: }, [1692185961.504314][4873:4875] CHIP:DMG: [1692185961.504317][4873:4875] CHIP:DMG: CommandFields [1692185961.504320][4873:4875] CHIP:DMG: { [1692185961.504377][4873:4875] CHIP:DMG: 0x0 0, [1692185961.504382][4873:4875] CHIP:DMG: 0x1 “” (0 chars), [1692185961.504404][4873:4875] CHIP:DMG: }, [1692185961.504408][4873:4875] CHIP:DMG: }, [1692185961.504413][4873:4875] CHIP:DMG: [1692185961.504415][4873:4875] CHIP:DMG: }, [1692185961.504418][4873:4875] CHIP:DMG: [1692185961.504420][4873:4875] CHIP:DMG: ], [1692185961.504425][4873:4875] CHIP:DMG: [1692185961.504446][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185961.504449][4873:4875] CHIP:DMG: }, [1692185961.504464][4873:4875] CHIP:DMG: Received Command Response Data, Endpoint0 Cluster0x0000_0030 Command0x0000_0001 [1692185961.504472][4873:4875] CHIP:CTL: Received ArmFailSafe response errorCode0 [1692185961.504498][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘ArmFailSafe’ [1692185961.504503][4873:4875] CHIP:CTL: Commissioning stage next step: ‘ArmFailSafe’ - ‘ConfigRegulatory’ [1692185961.504507][4873:4875] CHIP:CTL: Performing next commissioning step ‘ConfigRegulatory’ [1692185961.504509][4873:4875] CHIP:CTL: Setting Regulatory Config [1692185961.504511][4873:4875] CHIP:CTL: Device does not support configurable regulatory location [1692185961.504525][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185961.511973][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185961.512062][4873:4875] CHIP:EM: [E:54445i S:63028 M:42671703] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest) [1692185961.512128][4873:4875] CHIP:IN: (S) Sending msg 42671703 on secure session with LSID: 63028 [1692185961.512287][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185961.512328][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185961.595824][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185961.595951][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185961.595992][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185961.596056][4873:4875] CHIP:EM: [E:54445i S:63028 M:127198691] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse) [1692185961.596120][4873:4875] CHIP:EM: Found matching exchange: 54445i, Delegate: 0x7ff3c4030648 [1692185961.596130][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185961.596146][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185961.596152][4873:4875] CHIP:DMG: { [1692185961.596158][4873:4875] CHIP:DMG: suppressResponse false, [1692185961.596167][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185961.596178][4873:4875] CHIP:DMG: [ [1692185961.596186][4873:4875] CHIP:DMG: InvokeResponseIB [1692185961.596198][4873:4875] CHIP:DMG: { [1692185961.596208][4873:4875] CHIP:DMG: CommandDataIB [1692185961.596220][4873:4875] CHIP:DMG: { [1692185961.596231][4873:4875] CHIP:DMG: CommandPathIB [1692185961.596244][4873:4875] CHIP:DMG: { [1692185961.596258][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185961.596272][4873:4875] CHIP:DMG: ClusterId 0x30, [1692185961.596286][4873:4875] CHIP:DMG: CommandId 0x3, [1692185961.596300][4873:4875] CHIP:DMG: }, [1692185961.596313][4873:4875] CHIP:DMG: [1692185961.596325][4873:4875] CHIP:DMG: CommandFields [1692185961.596338][4873:4875] CHIP:DMG: { [1692185961.596351][4873:4875] CHIP:DMG: 0x0 0, [1692185961.596366][4873:4875] CHIP:DMG: 0x1 “” (0 chars), [1692185961.596380][4873:4875] CHIP:DMG: }, [1692185961.596392][4873:4875] CHIP:DMG: }, [1692185961.596405][4873:4875] CHIP:DMG: [1692185961.596415][4873:4875] CHIP:DMG: }, [1692185961.596427][4873:4875] CHIP:DMG: [1692185961.596435][4873:4875] CHIP:DMG: ], [1692185961.596445][4873:4875] CHIP:DMG: [1692185961.596452][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185961.596460][4873:4875] CHIP:DMG: }, [1692185961.596477][4873:4875] CHIP:DMG: Received Command Response Data, Endpoint0 Cluster0x0000_0030 Command0x0000_0003 [1692185961.596488][4873:4875] CHIP:CTL: Received SetRegulatoryConfig response errorCode0 [1692185961.596498][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘ConfigRegulatory’ [1692185961.596504][4873:4875] CHIP:CTL: Commissioning stage next step: ‘ConfigRegulatory’ - ‘SendPAICertificateRequest’ [1692185961.596512][4873:4875] CHIP:CTL: Performing next commissioning step ‘SendPAICertificateRequest’ [1692185961.596517][4873:4875] CHIP:CTL: Sending request for PAI certificate [1692185961.596523][4873:4875] CHIP:CTL: Sending Certificate Chain request to 0x7ff3c4033d20 device [1692185961.596549][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185961.596563][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185961.596585][4873:4875] CHIP:EM: [E:54446i S:63028 M:42671704] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest) [1692185961.596596][4873:4875] CHIP:IN: (S) Sending msg 42671704 on secure session with LSID: 63028 [1692185961.596718][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185961.596738][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185961.682966][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185961.731685][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185961.731786][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185961.867669][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185961.867745][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185961.955717][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185961.955789][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185961.955841][4873:4875] CHIP:EM: [E:54446i S:63028 M:127198692] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse) [1692185961.955853][4873:4875] CHIP:EM: Found matching exchange: 54446i, Delegate: 0x55e6f92eee88 [1692185961.955863][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185961.955891][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185961.955906][4873:4875] CHIP:DMG: { [1692185961.955917][4873:4875] CHIP:DMG: suppressResponse false, [1692185961.955929][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185961.955949][4873:4875] CHIP:DMG: [ [1692185961.955958][4873:4875] CHIP:DMG: InvokeResponseIB [1692185961.955970][4873:4875] CHIP:DMG: { [1692185961.955980][4873:4875] CHIP:DMG: CommandDataIB [1692185961.955992][4873:4875] CHIP:DMG: { [1692185961.956004][4873:4875] CHIP:DMG: CommandPathIB [1692185961.956018][4873:4875] CHIP:DMG: { [1692185961.956031][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185961.956046][4873:4875] CHIP:DMG: ClusterId 0x3e, [1692185961.956061][4873:4875] CHIP:DMG: CommandId 0x3, [1692185961.956075][4873:4875] CHIP:DMG: }, [1692185961.956088][4873:4875] CHIP:DMG: [1692185961.956100][4873:4875] CHIP:DMG: CommandFields [1692185961.956113][4873:4875] CHIP:DMG: { [1692185961.956127][4873:4875] CHIP:DMG: 0x0 [ [1692185961.956160][4873:4875] CHIP:DMG: 0x30, 0x82, 0x01, 0xd6, 0x30, 0x82, 0x01, 0x7b, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x11, 0x00, 0x96, 0x49, 0x0b, 0xc1, 0xe5, 0x95, 0x86, 0xa6, 0x74, 0xb6, 0x21, 0xd5, 0x5c, 0xdb, 0x95, 0x9f, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x3a, 0x31, 0x22, 0x30, 0x20, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x19, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x55, 0x61, 0x73, 0x63, 0x65, 0x6e, 0x74, 0x20, 0x50, 0x41, 0x41, 0x20, 0x30, 0x78, 0x31, 0x34, 0x30, 0x30, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x31, 0x34, 0x30, 0x30, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x33, 0x30, 0x32, 0x32, 0x34, 0x30, 0x32, 0x31, 0x34, 0x30, 0x36, 0x5a, 0x18, 0x0f, 0x32, 0x31, 0x32, 0x32, 0x30, 0x31, 0x33, 0x31, 0x30, 0x33, 0x31, 0x34, 0x30, 0x36, 0x5a, 0x30, 0x34, 0x31, 0x1c, 0x30, 0x1a, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x13, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x55, 0x61, 0x73, 0x63, 0x65, 0x6e, 0x74, 0x20, 0x20, 0x50, 0x41, 0x49, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x31, 0x34, 0x30, 0x30, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x62, 0x70, 0x65, 0xf0, 0x76, 0xd5, 0xb4, 0xd3, 0x85, 0xb3, 0xeb, 0x43, 0x0e, 0xee, 0x9f, 0x9b, 0x15, 0x0a, 0xc3, 0xd9, 0xf2, 0x63, 0x6d, 0xc2, 0x26, 0xd4, 0xed, 0xe4, 0x58, 0x71, 0x83, 0xbf, 0xe1, 0xca, 0xa2, 0x1a, 0xe8, 0x02, 0x7a, 0x4f, 0xe1, 0xb8, 0xc8, 0x15, 0xe5, 0x12, 0x40, 0x6a, 0x9e, 0x9e, 0xb6, 0x91, 0x23, 0xff, 0xe4, 0x79, 0xd0, 0x3a, 0x99, 0xfb, 0x5c, 0xb5, 0x02, 0x68, 0x [1692185961.956187][4873:4875] CHIP:DMG: ] (474 bytes) [1692185961.956202][4873:4875] CHIP:DMG: }, [1692185961.956215][4873:4875] CHIP:DMG: }, [1692185961.956228][4873:4875] CHIP:DMG: [1692185961.956237][4873:4875] CHIP:DMG: }, [1692185961.956249][4873:4875] CHIP:DMG: [1692185961.956257][4873:4875] CHIP:DMG: ], [1692185961.956268][4873:4875] CHIP:DMG: [1692185961.956275][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185961.956283][4873:4875] CHIP:DMG: }, [1692185961.956300][4873:4875] CHIP:DMG: Received Command Response Data, Endpoint0 Cluster0x0000_003E Command0x0000_0003 [1692185961.956311][4873:4875] CHIP:CTL: Received certificate chain from the device [1692185961.956322][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘SendPAICertificateRequest’ [1692185961.956330][4873:4875] CHIP:CTL: Commissioning stage next step: ‘SendPAICertificateRequest’ - ‘SendDACCertificateRequest’ [1692185961.956337][4873:4875] CHIP:CTL: Performing next commissioning step ‘SendDACCertificateRequest’ [1692185961.956343][4873:4875] CHIP:CTL: Sending request for DAC certificate [1692185961.956348][4873:4875] CHIP:CTL: Sending Certificate Chain request to 0x7ff3c4033d20 device [1692185961.956363][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185961.956371][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185961.956393][4873:4875] CHIP:EM: [E:54447i S:63028 M:42671705] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest) [1692185961.956404][4873:4875] CHIP:IN: (S) Sending msg 42671705 on secure session with LSID: 63028 [1692185961.956533][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185961.956553][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185962.042853][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185962.091403][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185962.091738][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185962.227590][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185962.227689][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185962.316063][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185962.316159][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185962.316235][4873:4875] CHIP:EM: [E:54447i S:63028 M:127198693] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse) [1692185962.316263][4873:4875] CHIP:EM: Found matching exchange: 54447i, Delegate: 0x7ff3cc02baa8 [1692185962.316272][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185962.316364][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185962.316388][4873:4875] CHIP:DMG: { [1692185962.316392][4873:4875] CHIP:DMG: suppressResponse false, [1692185962.316395][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185962.316399][4873:4875] CHIP:DMG: [ [1692185962.316401][4873:4875] CHIP:DMG: InvokeResponseIB [1692185962.316406][4873:4875] CHIP:DMG: { [1692185962.316408][4873:4875] CHIP:DMG: CommandDataIB [1692185962.316411][4873:4875] CHIP:DMG: { [1692185962.316432][4873:4875] CHIP:DMG: CommandPathIB [1692185962.316437][4873:4875] CHIP:DMG: { [1692185962.316440][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185962.316443][4873:4875] CHIP:DMG: ClusterId 0x3e, [1692185962.316446][4873:4875] CHIP:DMG: CommandId 0x3, [1692185962.316448][4873:4875] CHIP:DMG: }, [1692185962.316451][4873:4875] CHIP:DMG: [1692185962.316454][4873:4875] CHIP:DMG: CommandFields [1692185962.316457][4873:4875] CHIP:DMG: { [1692185962.316459][4873:4875] CHIP:DMG: 0x0 [ [1692185962.316481][4873:4875] CHIP:DMG: 0x30, 0x82, 0x01, 0xe1, 0x30, 0x82, 0x01, 0x86, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x11, 0x00, 0xf9, 0xc8, 0xd3, 0x87, 0xb9, 0x87, 0x2d, 0x5c, 0x50, 0x80, 0x74, 0xf6, 0xa8, 0x66, 0x75, 0xef, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x34, 0x31, 0x1c, 0x30, 0x1a, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x13, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x55, 0x61, 0x73, 0x63, 0x65, 0x6e, 0x74, 0x20, 0x20, 0x50, 0x41, 0x49, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x31, 0x34, 0x30, 0x30, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x33, 0x30, 0x34, 0x32, 0x34, 0x30, 0x37, 0x31, 0x32, 0x30, 0x37, 0x5a, 0x18, 0x0f, 0x32, 0x31, 0x30, 0x33, 0x30, 0x34, 0x30, 0x35, 0x30, 0x38, 0x31, 0x32, 0x30, 0x37, 0x5a, 0x30, 0x4b, 0x31, 0x1d, 0x30, 0x1b, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x14, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x54, 0x65, 0x73, 0x74, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x30, 0x30, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x31, 0x34, 0x30, 0x30, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x30, 0x33, 0x45, 0x43, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x69, 0x40, 0xa5, 0x0e, 0x22, 0x92, 0xb2, 0xac, 0xa8, 0x3b, 0xf3, 0x1e, 0x34, 0x4d, 0xb5, 0xce, 0xdf, 0x92, 0xda, 0x33, 0x63, 0xee, 0xbf, 0x17, 0x4c, 0x7b, 0xc0, 0x93, 0x6d, 0xca, 0x60, 0x23, 0xf9, 0x9f, 0x95, 0x3a, 0xa8, 0xca, 0xa6, 0x98, 0xd6, 0xc8, 0xe4, 0x60, 0x3d, 0xb5, 0xdd, 0x [1692185962.316511][4873:4875] CHIP:DMG: ] (485 bytes) [1692185962.316514][4873:4875] CHIP:DMG: }, [1692185962.316517][4873:4875] CHIP:DMG: }, [1692185962.316521][4873:4875] CHIP:DMG: [1692185962.316523][4873:4875] CHIP:DMG: }, [1692185962.316526][4873:4875] CHIP:DMG: [1692185962.316547][4873:4875] CHIP:DMG: ], [1692185962.316553][4873:4875] CHIP:DMG: [1692185962.316556][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185962.316558][4873:4875] CHIP:DMG: }, [1692185962.316572][4873:4875] CHIP:DMG: Received Command Response Data, Endpoint0 Cluster0x0000_003E Command0x0000_0003 [1692185962.316597][4873:4875] CHIP:CTL: Received certificate chain from the device [1692185962.316605][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘SendDACCertificateRequest’ [1692185962.316610][4873:4875] CHIP:CTL: Commissioning stage next step: ‘SendDACCertificateRequest’ - ‘SendAttestationRequest’ [1692185962.316614][4873:4875] CHIP:CTL: Performing next commissioning step ‘SendAttestationRequest’ [1692185962.316616][4873:4875] CHIP:CTL: Sending Attestation Request to the device. [1692185962.316638][4873:4875] CHIP:CTL: Sending Attestation request to 0x7ff3c4033d20 device [1692185962.316652][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185962.316657][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185962.316676][4873:4875] CHIP:EM: [E:54448i S:63028 M:42671706] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest) [1692185962.316703][4873:4875] CHIP:IN: (S) Sending msg 42671706 on secure session with LSID: 63028 [1692185962.316827][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185962.316853][4873:4875] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information [1692185962.316865][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185962.495303][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185962.719314][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185962.719420][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185962.899181][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185962.899274][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185962.899367][4873:4875] CHIP:EM: [E:54448i S:63028 M:127198694] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse) [1692185962.899400][4873:4875] CHIP:EM: Found matching exchange: 54448i, Delegate: 0x7ff3cc05b388 [1692185962.899410][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185962.899421][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185962.899522][4873:4875] CHIP:DMG: { [1692185962.899527][4873:4875] CHIP:DMG: suppressResponse false, [1692185962.899529][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185962.899534][4873:4875] CHIP:DMG: [ [1692185962.899536][4873:4875] CHIP:DMG: InvokeResponseIB [1692185962.899541][4873:4875] CHIP:DMG: { [1692185962.899543][4873:4875] CHIP:DMG: CommandDataIB [1692185962.899546][4873:4875] CHIP:DMG: { [1692185962.899567][4873:4875] CHIP:DMG: CommandPathIB [1692185962.899572][4873:4875] CHIP:DMG: { [1692185962.899575][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185962.899578][4873:4875] CHIP:DMG: ClusterId 0x3e, [1692185962.899581][4873:4875] CHIP:DMG: CommandId 0x1, [1692185962.899583][4873:4875] CHIP:DMG: }, [1692185962.899587][4873:4875] CHIP:DMG: [1692185962.899589][4873:4875] CHIP:DMG: CommandFields [1692185962.899611][4873:4875] CHIP:DMG: { [1692185962.899615][4873:4875] CHIP:DMG: 0x0 [ [1692185962.899637][4873:4875] CHIP:DMG: 0x15, 0x31, 0x01, 0x0d, 0x01, 0x30, 0x82, 0x01, 0x09, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x02, 0xa0, 0x81, 0xfb, 0x30, 0x81, 0xf8, 0x02, 0x01, 0x03, 0x31, 0x0d, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x65, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x01, 0xa0, 0x58, 0x04, 0x56, 0x15, 0x24, 0x00, 0x01, 0x25, 0x01, 0x9c, 0x13, 0x36, 0x02, 0x05, 0x02, 0xd0, 0x18, 0x25, 0x03, 0x0d, 0x01, 0x2c, 0x04, 0x13, 0x43, 0x53, 0x41, 0x32, 0x33, 0x32, 0x39, 0x31, 0x4d, 0x41, 0x54, 0x34, 0x30, 0x38, 0x30, 0x34, 0x2d, 0x32, 0x34, 0x24, 0x05, 0x00, 0x24, 0x06, 0x00, 0x24, 0x07, 0x01, 0x24, 0x08, 0x02, 0x25, 0x09, 0x00, 0x14, 0x25, 0x0a, 0xec, 0x03, 0x36, 0x0b, 0x10, 0x14, 0x30, 0x83, 0x79, 0x89, 0x64, 0xe0, 0x93, 0xa8, 0xb2, 0x59, 0xfe, 0xb6, 0xc3, 0x78, 0x09, 0x6e, 0x25, 0x68, 0x4e, 0x9a, 0x18, 0x18, 0x31, 0x7d, 0x30, 0x7b, 0x02, 0x01, 0x03, 0x80, 0x14, 0xf6, 0x86, 0x03, 0xa3, 0x69, 0x2e, 0x98, 0x10, 0x72, 0x41, 0x9e, 0xa1, 0xe1, 0xab, 0x38, 0x54, 0xbd, 0x77, 0x95, 0xd3, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x04, 0x47, 0x30, 0x45, 0x02, 0x21, 0x00, 0xf2, 0x7b, 0x8f, 0x37, 0xb9, 0x6e, 0x43, 0x42, 0x22, 0x85, 0xed, 0x26, 0xcf, 0x3d, 0x60, 0x38, 0xff, 0xc7, 0x74, 0xff, 0xb9, 0x41, 0xae, 0xd1, 0x1f, 0xeb, 0x39, 0x20, 0x78, 0x64, 0xbf, 0xa9, 0x02, 0x20, 0x01, 0xe1, 0x4a, 0xd0, 0xc6, 0xde, 0xb4, 0x31, 0x22, 0xea, 0x02, 0xa6, 0x9b, 0x40, 0x0b, 0x89, 0x56, 0x3c, 0x15, 0xe8, 0xa6, 0x98, 0x5e, 0xe8, 0x65, 0x3e, 0xca, 0xab, 0x42, 0xe9, 0x2e, 0x7e, 0x30, 0x02, 0x20, 0x0e, 0xbf, 0xac, 0x72, 0x5c, 0x11, 0x [1692185962.899666][4873:4875] CHIP:DMG: ] (313 bytes) [1692185962.899671][4873:4875] CHIP:DMG: 0x1 [ [1692185962.899677][4873:4875] CHIP:DMG: 0x63, 0xd7, 0xed, 0x1a, 0x24, 0x7c, 0xb5, 0x77, 0x18, 0xb8, 0x0d, 0xf3, 0x8c, 0xc4, 0x95, 0x4f, 0x89, 0xd7, 0x18, 0x8c, 0xa7, 0x31, 0x4e, 0x72, 0x25, 0xd0, 0x0c, 0x0e, 0x94, 0x29, 0x9a, 0x2e, 0x6c, 0xb9, 0x84, 0x58, 0x2b, 0x60, 0x34, 0xc5, 0x54, 0x73, 0x09, 0x1c, 0xcb, 0x1c, 0x15, 0x3c, 0x2b, 0xa4, 0x75, 0xfe, 0x25, 0x78, 0x02, 0xd2, 0x02, 0x21, 0x5d, 0x85, 0xf8, 0x45, 0x1e, 0x30, [1692185962.899682][4873:4875] CHIP:DMG: ] (64 bytes) [1692185962.899684][4873:4875] CHIP:DMG: }, [1692185962.899687][4873:4875] CHIP:DMG: }, [1692185962.899709][4873:4875] CHIP:DMG: [1692185962.899713][4873:4875] CHIP:DMG: }, [1692185962.899717][4873:4875] CHIP:DMG: [1692185962.899720][4873:4875] CHIP:DMG: ], [1692185962.899724][4873:4875] CHIP:DMG: [1692185962.899726][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185962.899728][4873:4875] CHIP:DMG: }, [1692185962.899743][4873:4875] CHIP:DMG: Received Command Response Data, Endpoint0 Cluster0x0000_003E Command0x0000_0001 [1692185962.899769][4873:4875] CHIP:CTL: Received Attestation Information from the device [1692185962.899777][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘SendAttestationRequest’ [1692185962.899781][4873:4875] CHIP:CTL: AutoCommissioner setting attestationElements buffer size 313/313 [1692185962.899794][4873:4875] CHIP:CTL: Commissioning stage next step: ‘SendAttestationRequest’ - ‘AttestationVerification’ [1692185962.899818][4873:4875] CHIP:CTL: Performing next commissioning step ‘AttestationVerification’ [1692185962.899822][4873:4875] CHIP:CTL: Verifying attestation [1692185962.915229][4873:4875] CHIP:CTL: Failed in verifying ‘Attestation Information’ command received from the device: err 101. Look at AttestationVerificationResult enum to understand the errors [1692185962.915283][4873:4875] CHIP:CTL: Proceeding without changing fail-safe timer value as delegate has not set it [1692185962.915288][4873:4875] CHIP:CTL: Device attestation completed, delegating continuation to client [1692185962.915292][4873:4875] CHIP:CTL: Continuing commissioning after attestation failure for device ID 0x00000000000004D3 [1692185962.915295][4873:4875] CHIP:CTL: Overriding attestation failure per client and continuing commissioning [1692185962.915300][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘AttestationVerification’ [1692185962.915312][4873:4875] CHIP:CTL: Commissioning stage next step: ‘AttestationVerification’ - ‘SendOpCertSigningRequest’ [1692185962.915344][4873:4875] CHIP:CTL: Performing next commissioning step ‘SendOpCertSigningRequest’ [1692185962.915350][4873:4875] CHIP:CTL: Sending CSR request to 0x7ff3c4033d20 device [1692185962.915373][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185962.915400][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185962.915438][4873:4875] CHIP:EM: [E:54449i S:63028 M:42671707] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest) [1692185962.915467][4873:4875] CHIP:IN: (S) Sending msg 42671707 on secure session with LSID: 63028 [1692185962.915637][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185962.915664][4873:4875] CHIP:CTL: Sent CSR request, waiting for the CSR [1692185962.915679][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185963.034822][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185963.936117][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185963.936212][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185964.024694][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185964.024788][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185964.024856][4873:4875] CHIP:EM: [E:54449i S:63028 M:127198695] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse) [1692185964.024884][4873:4875] CHIP:EM: Found matching exchange: 54449i, Delegate: 0x7ff3cc05b4e8 [1692185964.024893][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185964.024905][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185964.024914][4873:4875] CHIP:DMG: { [1692185964.024922][4873:4875] CHIP:DMG: suppressResponse false, [1692185964.024931][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185964.024942][4873:4875] CHIP:DMG: [ [1692185964.024951][4873:4875] CHIP:DMG: InvokeResponseIB [1692185964.024963][4873:4875] CHIP:DMG: { [1692185964.024972][4873:4875] CHIP:DMG: CommandDataIB [1692185964.024984][4873:4875] CHIP:DMG: { [1692185964.024995][4873:4875] CHIP:DMG: CommandPathIB [1692185964.025008][4873:4875] CHIP:DMG: { [1692185964.025021][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185964.025035][4873:4875] CHIP:DMG: ClusterId 0x3e, [1692185964.025050][4873:4875] CHIP:DMG: CommandId 0x5, [1692185964.025063][4873:4875] CHIP:DMG: }, [1692185964.025077][4873:4875] CHIP:DMG: [1692185964.025088][4873:4875] CHIP:DMG: CommandFields [1692185964.025101][4873:4875] CHIP:DMG: { [1692185964.025114][4873:4875] CHIP:DMG: 0x0 [ [1692185964.025144][4873:4875] CHIP:DMG: 0x15, 0x30, 0x01, 0xce, 0x30, 0x81, 0xcb, 0x30, 0x70, 0x02, 0x01, 0x00, 0x30, 0x0e, 0x31, 0x0c, 0x30, 0x0a, 0x06, 0x03, 0x55, 0x04, 0x0a, 0x0c, 0x03, 0x43, 0x53, 0x52, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x53, 0x73, 0xd2, 0x85, 0xe0, 0xbb, 0x86, 0x84, 0x48, 0x7b, 0x42, 0x87, 0x78, 0x6f, 0x4a, 0x22, 0xb4, 0xaf, 0x22, 0xb2, 0x04, 0xab, 0xc4, 0x6d, 0x61, 0x9a, 0xc9, 0x4d, 0x8a, 0xea, 0xf4, 0xff, 0x79, 0x3d, 0x08, 0xf5, 0x7a, 0xb5, 0xa1, 0x91, 0x99, 0xf3, 0x19, 0x1f, 0x51, 0xa2, 0x75, 0x06, 0xd3, 0xfe, 0xeb, 0x6f, 0x09, 0xce, 0x71, 0xa9, 0x7c, 0x28, 0xf8, 0x58, 0xb4, 0xab, 0xc5, 0x4a, 0xa0, 0x00, 0x30, 0x0c, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x05, 0x00, 0x03, 0x49, 0x00, 0x30, 0x46, 0x02, 0x21, 0x00, 0xb7, 0x89, 0x15, 0x31, 0xf8, 0x13, 0x30, 0x92, 0x05, 0x4e, 0xbb, 0xb0, 0x91, 0x50, 0x99, 0x6f, 0x5b, 0x02, 0xd1, 0xe8, 0x16, 0x2d, 0x28, 0x16, 0xb6, 0x09, 0x4d, 0x37, 0x13, 0x76, 0xe2, 0x8d, 0x02, 0x21, 0x00, 0xe8, 0x86, 0x92, 0xbc, 0x39, 0x88, 0x05, 0xff, 0xf4, 0x1e, 0xe1, 0x83, 0x74, 0x72, 0xbb, 0x04, 0x05, 0x08, 0x4e, 0x27, 0x45, 0xb6, 0xe9, 0x23, 0x0a, 0x67, 0x0a, 0xfa, 0x1e, 0x48, 0x67, 0x3f, 0x30, 0x02, 0x20, 0x68, 0xe9, 0xcb, 0x0f, 0x97, 0x45, 0x83, 0x42, 0xd3, 0x8e, 0xce, 0x34, 0x02, 0x0b, 0x1b, 0x1a, 0x5c, 0x63, 0x1c, 0x8c, 0x55, 0xbd, 0xef, 0x0e, 0x01, 0xd7, 0x35, 0x86, 0xee, 0x81, 0x93, 0xe5, 0x18, [1692185964.025170][4873:4875] CHIP:DMG: ] (246 bytes) [1692185964.025184][4873:4875] CHIP:DMG: 0x1 [ [1692185964.025202][4873:4875] CHIP:DMG: 0x32, 0x67, 0xf0, 0x1a, 0x2d, 0x58, 0xd2, 0x3e, 0xed, 0xa5, 0x45, 0x34, 0x8b, 0xb8, 0x53, 0x44, 0xe9, 0x7d, 0x59, 0x92, 0xc0, 0xe1, 0x89, 0x59, 0x21, 0xfd, 0x05, 0x60, 0x9d, 0x10, 0xa0, 0x12, 0xea, 0xe1, 0x15, 0x69, 0x61, 0x2b, 0x42, 0x5c, 0xec, 0x66, 0xf9, 0xcf, 0xc6, 0x26, 0xbc, 0x97, 0xba, 0x96, 0xf1, 0x12, 0x2a, 0x2b, 0x0d, 0x34, 0x04, 0x48, 0xde, 0xc1, 0xa1, 0x69, 0x51, 0x81, [1692185964.025219][4873:4875] CHIP:DMG: ] (64 bytes) [1692185964.025252][4873:4875] CHIP:DMG: }, [1692185964.025271][4873:4875] CHIP:DMG: }, [1692185964.025291][4873:4875] CHIP:DMG: [1692185964.025302][4873:4875] CHIP:DMG: }, [1692185964.025314][4873:4875] CHIP:DMG: [1692185964.025322][4873:4875] CHIP:DMG: ], [1692185964.025332][4873:4875] CHIP:DMG: [1692185964.025339][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185964.025348][4873:4875] CHIP:DMG: }, [1692185964.025365][4873:4875] CHIP:DMG: Received Command Response Data, Endpoint0 Cluster0x0000_003E Command0x0000_0005 [1692185964.025476][4873:4875] CHIP:CTL: Received certificate signing request from the device [1692185964.025493][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘SendOpCertSigningRequest’ [1692185964.025500][4873:4875] CHIP:CTL: Commissioning stage next step: ‘SendOpCertSigningRequest’ - ‘ValidateCSR’ [1692185964.025508][4873:4875] CHIP:CTL: Performing next commissioning step ‘ValidateCSR’ [1692185964.026815][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘ValidateCSR’ [1692185964.026850][4873:4875] CHIP:CTL: Commissioning stage next step: ‘ValidateCSR’ - ‘GenerateNOCChain’ [1692185964.026859][4873:4875] CHIP:CTL: Performing next commissioning step ‘GenerateNOCChain’ [1692185964.026866][4873:4875] CHIP:CTL: Getting certificate chain for the device from the issuer [1692185964.028007][4873:4875] CHIP:CTL: Verifying Certificate Signing Request [1692185964.028636][4873:4875] CHIP:CTL: Generating NOC [1692185964.028736][4873:4875] CHIP:CTL: Providing certificate chain to the commissioner [1692185964.028767][4873:4875] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status src/controller/ExampleOperationalCredentialsIssuer.cpp:396: Success [1692185964.028777][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘GenerateNOCChain’ [1692185964.028818][4873:4875] CHIP:CTL: Performing next commissioning step ‘SendTrustedRootCert’ [1692185964.028844][4873:4875] CHIP:CTL: Sending root certificate to the device [1692185964.028862][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185964.028871][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185964.028896][4873:4875] CHIP:EM: [E:54450i S:63028 M:42671708] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest) [1692185964.028927][4873:4875] CHIP:IN: (S) Sending msg 42671708 on secure session with LSID: 63028 [1692185964.029137][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185964.029175][4873:4875] CHIP:CTL: Sent root certificate to the device [1692185964.029299][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185964.203322][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185964.287613][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185964.923773][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185964.923863][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185964.923937][4873:4875] CHIP:EM: [E:54450i S:63028 M:127198696] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse) [1692185964.923964][4873:4875] CHIP:EM: Found matching exchange: 54450i, Delegate: 0x7ff3cc05b388 [1692185964.923972][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185964.923984][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185964.923986][4873:4875] CHIP:DMG: { [1692185964.923999][4873:4875] CHIP:DMG: suppressResponse false, [1692185964.924008][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185964.924019][4873:4875] CHIP:DMG: [ [1692185964.924027][4873:4875] CHIP:DMG: InvokeResponseIB [1692185964.924039][4873:4875] CHIP:DMG: { [1692185964.924048][4873:4875] CHIP:DMG: CommandStatusIB [1692185964.924060][4873:4875] CHIP:DMG: { [1692185964.924071][4873:4875] CHIP:DMG: CommandPathIB [1692185964.924084][4873:4875] CHIP:DMG: { [1692185964.924097][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185964.924111][4873:4875] CHIP:DMG: ClusterId 0x3e, [1692185964.924125][4873:4875] CHIP:DMG: CommandId 0xb, [1692185964.924138][4873:4875] CHIP:DMG: }, [1692185964.924152][4873:4875] CHIP:DMG: [1692185964.924163][4873:4875] CHIP:DMG: StatusIB [1692185964.924176][4873:4875] CHIP:DMG: { [1692185964.924215][4873:4875] CHIP:DMG: status 0x00 (SUCCESS), [1692185964.924231][4873:4875] CHIP:DMG: }, [1692185964.924244][4873:4875] CHIP:DMG: [1692185964.924255][4873:4875] CHIP:DMG: }, [1692185964.924290][4873:4875] CHIP:DMG: [1692185964.924304][4873:4875] CHIP:DMG: }, [1692185964.924315][4873:4875] CHIP:DMG: [1692185964.924323][4873:4875] CHIP:DMG: ], [1692185964.924333][4873:4875] CHIP:DMG: [1692185964.924340][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185964.924371][4873:4875] CHIP:DMG: }, [1692185964.924390][4873:4875] CHIP:DMG: Received Command Response Status for Endpoint0 Cluster0x0000_003E Command0x0000_000B Status0x0 [1692185964.924400][4873:4875] CHIP:CTL: Device confirmed that it has received the root certificate [1692185964.924409][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘SendTrustedRootCert’ [1692185964.924416][4873:4875] CHIP:CTL: Commissioning stage next step: ‘SendTrustedRootCert’ - ‘SendNOC’ [1692185964.924424][4873:4875] CHIP:CTL: Performing next commissioning step ‘SendNOC’ [1692185964.924442][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185964.924477][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185964.924515][4873:4875] CHIP:EM: [E:54451i S:63028 M:42671709] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest) [1692185964.924549][4873:4875] CHIP:IN: (S) Sending msg 42671709 on secure session with LSID: 63028 [1692185964.924649][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185964.924661][4873:4875] CHIP:CTL: Sent operational certificate to the device [1692185964.924674][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185965.103633][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185965.327197][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185965.419090][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185966.524861][4873:4875] CHIP:BLE: BLE scan error: src/platform/Linux/bluez/ChipDeviceScanner.cpp:175: CHIP Error 0x00000032: Timeout [1692185966.772011][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185966.772146][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185966.772238][4873:4875] CHIP:EM: [E:54451i S:63028 M:127198697] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse) [1692185966.772274][4873:4875] CHIP:EM: Found matching exchange: 54451i, Delegate: 0x7ff3cc05b4e8 [1692185966.772282][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185966.772294][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185966.772317][4873:4875] CHIP:DMG: { [1692185966.772321][4873:4875] CHIP:DMG: suppressResponse false, [1692185966.772324][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185966.772329][4873:4875] CHIP:DMG: [ [1692185966.772331][4873:4875] CHIP:DMG: InvokeResponseIB [1692185966.772335][4873:4875] CHIP:DMG: { [1692185966.772338][4873:4875] CHIP:DMG: CommandDataIB [1692185966.772340][4873:4875] CHIP:DMG: { [1692185966.772342][4873:4875] CHIP:DMG: CommandPathIB [1692185966.772345][4873:4875] CHIP:DMG: { [1692185966.772348][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185966.772351][4873:4875] CHIP:DMG: ClusterId 0x3e, [1692185966.772373][4873:4875] CHIP:DMG: CommandId 0x8, [1692185966.772377][4873:4875] CHIP:DMG: }, [1692185966.772381][4873:4875] CHIP:DMG: [1692185966.772384][4873:4875] CHIP:DMG: CommandFields [1692185966.772386][4873:4875] CHIP:DMG: { [1692185966.772389][4873:4875] CHIP:DMG: 0x0 0, [1692185966.772392][4873:4875] CHIP:DMG: 0x1 1, [1692185966.772395][4873:4875] CHIP:DMG: }, [1692185966.772397][4873:4875] CHIP:DMG: }, [1692185966.772401][4873:4875] CHIP:DMG: [1692185966.772403][4873:4875] CHIP:DMG: }, [1692185966.772407][4873:4875] CHIP:DMG: [1692185966.772428][4873:4875] CHIP:DMG: ], [1692185966.772434][4873:4875] CHIP:DMG: [1692185966.772437][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185966.772439][4873:4875] CHIP:DMG: }, [1692185966.772452][4873:4875] CHIP:DMG: Received Command Response Data, Endpoint0 Cluster0x0000_003E Command0x0000_0008 [1692185966.772480][4873:4875] CHIP:CTL: Device returned status 0 on receiving the NOC [1692185966.772485][4873:4875] CHIP:CTL: Operational credentials provisioned on device 0x7ff3c4033d20 [1692185966.772488][4873:4875] CHIP:TOO: Secure Pairing Success [1692185966.772490][4873:4875] CHIP:TOO: CASE establishment successful [1692185966.772496][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘SendNOC’ [1692185966.772499][4873:4875] CHIP:CTL: No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks [1692185966.772521][4873:4875] CHIP:CTL: Commissioning stage next step: ‘SendNOC’ - ‘WiFiNetworkSetup’ [1692185966.772527][4873:4875] CHIP:CTL: Performing next commissioning step ‘WiFiNetworkSetup’ [1692185966.772541][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185966.772546][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185966.772585][4873:4875] CHIP:EM: [E:54452i S:63028 M:42671710] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest) [1692185966.772593][4873:4875] CHIP:IN: (S) Sending msg 42671710 on secure session with LSID: 63028 [1692185966.772762][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185966.772802][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185966.859375][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185966.859971][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185966.860044][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185966.860117][4873:4875] CHIP:EM: [E:54452i S:63028 M:127198698] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse) [1692185966.860146][4873:4875] CHIP:EM: Found matching exchange: 54452i, Delegate: 0x7ff3cc05b388 [1692185966.860153][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185966.860165][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185966.860167][4873:4875] CHIP:DMG: { [1692185966.860190][4873:4875] CHIP:DMG: suppressResponse false, [1692185966.860194][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185966.860199][4873:4875] CHIP:DMG: [ [1692185966.860201][4873:4875] CHIP:DMG: InvokeResponseIB [1692185966.860205][4873:4875] CHIP:DMG: { [1692185966.860207][4873:4875] CHIP:DMG: CommandDataIB [1692185966.860210][4873:4875] CHIP:DMG: { [1692185966.860231][4873:4875] CHIP:DMG: CommandPathIB [1692185966.860236][4873:4875] CHIP:DMG: { [1692185966.860240][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185966.860243][4873:4875] CHIP:DMG: ClusterId 0x31, [1692185966.860246][4873:4875] CHIP:DMG: CommandId 0x5, [1692185966.860248][4873:4875] CHIP:DMG: }, [1692185966.860251][4873:4875] CHIP:DMG: [1692185966.860254][4873:4875] CHIP:DMG: CommandFields [1692185966.860257][4873:4875] CHIP:DMG: { [1692185966.860260][4873:4875] CHIP:DMG: 0x0 0, [1692185966.860262][4873:4875] CHIP:DMG: 0x2 0, [1692185966.860283][4873:4875] CHIP:DMG: }, [1692185966.860287][4873:4875] CHIP:DMG: }, [1692185966.860291][4873:4875] CHIP:DMG: [1692185966.860294][4873:4875] CHIP:DMG: }, [1692185966.860298][4873:4875] CHIP:DMG: [1692185966.860300][4873:4875] CHIP:DMG: ], [1692185966.860304][4873:4875] CHIP:DMG: [1692185966.860306][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185966.860309][4873:4875] CHIP:DMG: }, [1692185966.860322][4873:4875] CHIP:DMG: Received Command Response Data, Endpoint0 Cluster0x0000_0031 Command0x0000_0005 [1692185966.868132][4873:4875] CHIP:CTL: Received NetworkConfig response, networkingStatus0 [1692185966.868174][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘WiFiNetworkSetup’ [1692185966.868180][4873:4875] CHIP:CTL: Commissioning stage next step: ‘WiFiNetworkSetup’ - ‘FailsafeBeforeWiFiEnable’ [1692185966.868186][4873:4875] CHIP:CTL: Performing next commissioning step ‘FailsafeBeforeWiFiEnable’ [1692185966.868196][4873:4875] CHIP:CTL: Arming failsafe (66 seconds) [1692185966.868213][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185966.868240][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185966.868285][4873:4875] CHIP:EM: [E:54453i S:63028 M:42671711] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest) [1692185966.868315][4873:4875] CHIP:IN: (S) Sending msg 42671711 on secure session with LSID: 63028 [1692185966.868485][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185966.868522][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185966.947342][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185966.947858][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185966.947923][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185966.947993][4873:4875] CHIP:EM: [E:54453i S:63028 M:127198699] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse) [1692185966.948021][4873:4875] CHIP:EM: Found matching exchange: 54453i, Delegate: 0x7ff3cc05b4e8 [1692185966.948028][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185966.948040][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185966.948043][4873:4875] CHIP:DMG: { [1692185966.948065][4873:4875] CHIP:DMG: suppressResponse false, [1692185966.948069][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185966.948074][4873:4875] CHIP:DMG: [ [1692185966.948076][4873:4875] CHIP:DMG: InvokeResponseIB [1692185966.948081][4873:4875] CHIP:DMG: { [1692185966.948083][4873:4875] CHIP:DMG: CommandDataIB [1692185966.948086][4873:4875] CHIP:DMG: { [1692185966.948106][4873:4875] CHIP:DMG: CommandPathIB [1692185966.948112][4873:4875] CHIP:DMG: { [1692185966.948115][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185966.948118][4873:4875] CHIP:DMG: ClusterId 0x30, [1692185966.948121][4873:4875] CHIP:DMG: CommandId 0x1, [1692185966.948123][4873:4875] CHIP:DMG: }, [1692185966.948127][4873:4875] CHIP:DMG: [1692185966.948129][4873:4875] CHIP:DMG: CommandFields [1692185966.948132][4873:4875] CHIP:DMG: { [1692185966.948135][4873:4875] CHIP:DMG: 0x0 0, [1692185966.948139][4873:4875] CHIP:DMG: 0x1 “” (0 chars), [1692185966.948160][4873:4875] CHIP:DMG: }, [1692185966.948163][4873:4875] CHIP:DMG: }, [1692185966.948168][4873:4875] CHIP:DMG: [1692185966.948170][4873:4875] CHIP:DMG: }, [1692185966.948174][4873:4875] CHIP:DMG: [1692185966.948176][4873:4875] CHIP:DMG: ], [1692185966.948180][4873:4875] CHIP:DMG: [1692185966.948183][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185966.948185][4873:4875] CHIP:DMG: }, [1692185966.948199][4873:4875] CHIP:DMG: Received Command Response Data, Endpoint0 Cluster0x0000_0030 Command0x0000_0001 [1692185966.948226][4873:4875] CHIP:CTL: Received ArmFailSafe response errorCode0 [1692185966.948234][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘FailsafeBeforeWiFiEnable’ [1692185966.948237][4873:4875] CHIP:CTL: Commissioning stage next step: ‘FailsafeBeforeWiFiEnable’ - ‘WiFiNetworkEnable’ [1692185966.948240][4873:4875] CHIP:CTL: Setting wifi connection time min 20 [1692185966.948244][4873:4875] CHIP:CTL: Performing next commissioning step ‘WiFiNetworkEnable’ [1692185966.948278][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185966.948285][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185966.948304][4873:4875] CHIP:EM: [E:54454i S:63028 M:42671712] (S) Msg TX to 0:FFFFFFFB00000000 [0000] — Type 0001:08 (IM:InvokeCommandRequest) [1692185966.948331][4873:4875] CHIP:IN: (S) Sending msg 42671712 on secure session with LSID: 63028 [1692185966.948448][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185966.948483][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185967.039293][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185969.951785][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185969.951877][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185971.539523][4873:4874] CHIP:DL: Indication received, conn 0x7ff3cc05a340 [1692185971.539622][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1692185971.539707][4873:4875] CHIP:EM: [E:54454i S:63028 M:127198700] (S) Msg RX from 0:FFFFFFFB00000000 [0000] — Type 0001:09 (IM:InvokeCommandResponse) [1692185971.539736][4873:4875] CHIP:EM: Found matching exchange: 54454i, Delegate: 0x7ff3cc05b388 [1692185971.539750][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185971.539761][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185971.539764][4873:4875] CHIP:DMG: { [1692185971.539766][4873:4875] CHIP:DMG: suppressResponse false, [1692185971.539768][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185971.539773][4873:4875] CHIP:DMG: [ [1692185971.539775][4873:4875] CHIP:DMG: InvokeResponseIB [1692185971.539779][4873:4875] CHIP:DMG: { [1692185971.539782][4873:4875] CHIP:DMG: CommandDataIB [1692185971.539794][4873:4875] CHIP:DMG: { [1692185971.539829][4873:4875] CHIP:DMG: CommandPathIB [1692185971.539836][4873:4875] CHIP:DMG: { [1692185971.539841][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185971.539845][4873:4875] CHIP:DMG: ClusterId 0x31, [1692185971.539848][4873:4875] CHIP:DMG: CommandId 0x7, [1692185971.539851][4873:4875] CHIP:DMG: }, [1692185971.539854][4873:4875] CHIP:DMG: [1692185971.539857][4873:4875] CHIP:DMG: CommandFields [1692185971.539860][4873:4875] CHIP:DMG: { [1692185971.539863][4873:4875] CHIP:DMG: 0x0 0, [1692185971.539866][4873:4875] CHIP:DMG: 0x2 NULL [1692185971.539868][4873:4875] CHIP:DMG: }, [1692185971.539871][4873:4875] CHIP:DMG: }, [1692185971.539874][4873:4875] CHIP:DMG: [1692185971.539876][4873:4875] CHIP:DMG: }, [1692185971.539880][4873:4875] CHIP:DMG: [1692185971.539882][4873:4875] CHIP:DMG: ], [1692185971.539886][4873:4875] CHIP:DMG: [1692185971.539888][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185971.539890][4873:4875] CHIP:DMG: }, [1692185971.539903][4873:4875] CHIP:DMG: Received Command Response Data, Endpoint0 Cluster0x0000_0031 Command0x0000_0007 [1692185971.540053][4873:4875] CHIP:CTL: Received ConnectNetwork response, networkingStatus0 [1692185971.540062][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘WiFiNetworkEnable’ [1692185971.540122][4873:4875] CHIP:CTL: Commissioning stage next step: ‘WiFiNetworkEnable’ - ‘FindOperational’ [1692185971.540129][4873:4875] CHIP:CTL: Performing next commissioning step ‘FindOperational’ [1692185971.540131][4873:4875] CHIP:CSM: FindOrEstablishSession: PeerId [1:00000000000004D3] [1692185971.540135][4873:4875] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found [1692185971.540177][4873:4875] CHIP:DIS: OperationalSessionSetup[1:00000000000004D3]: State change 1 -- 2 [1692185971.541015][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185971.741981][4873:4875] CHIP:DIS: Checking node lookup status after 201 ms [1692185971.894016][4873:4875] CHIP:DIS: UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540: new best score: 7 [1692185971.894064][4873:4875] CHIP:DIS: UDP:192.168.0.4%ens33:5540: score has not improved: 2 [1692185971.894073][4873:4875] CHIP:DIS: UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540: score has not improved: 7 [1692185971.894079][4873:4875] CHIP:DIS: UDP:192.168.0.4%ens33:5540: score has not improved: 2 [1692185971.894081][4873:4875] CHIP:DIS: Checking node lookup status after 353 ms [1692185971.894089][4873:4875] CHIP:DIS: OperationalSessionSetup[1:00000000000004D3]: Updating device address to UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540 while in state 2 [1692185971.894093][4873:4875] CHIP:DIS: OperationalSessionSetup[1:00000000000004D3]: State change 2 -- 3 [1692185971.894130][4873:4875] CHIP:IN: SecureSession[0x7ff3c403b2d0]: Allocated Type:2 LSID:63029 [1692185971.894138][4873:4875] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 - 0x00000000000004D3 [1692185971.894383][4873:4875] CHIP:EM: [E:54455i S:0 M:99666385] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:30 (SecureChannel:CASE_Sigma1) [1692185971.894422][4873:4875] CHIP:IN: (U) Sending msg 99666385 to IP address ‘UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540’ [1692185971.894640][4873:4875] CHIP:SC: Sent Sigma1 msg [1692185971.894662][4873:4875] CHIP:DIS: OperationalSessionSetup[1:00000000000004D3]: State change 3 -- 4 [1692185972.095871][4873:4875] CHIP:EM: [E:54455i S:0 M:54954236 (Ack:99666385)] (U) Msg RX from 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck) [1692185972.095953][4873:4875] CHIP:EM: Found matching exchange: 54455i, Delegate: 0x7ff3c403b038 [1692185972.095983][4873:4875] CHIP:EM: Rxd Ack; Removing MessageCounter:99666385 from Retrans Table on exchange 54455i [1692185973.075746][4873:4875] CHIP:EM: [E:54455i S:0 M:54954237 (Ack:99666385)] (U) Msg RX from 0:0000000000000000 [0000] — Type 0000:31 (SecureChannel:CASE_Sigma2) [1692185973.075779][4873:4875] CHIP:EM: Found matching exchange: 54455i, Delegate: 0x7ff3c403b038 [1692185973.075785][4873:4875] CHIP:EM: CHIP MessageCounter:99666385 not in RetransTable on exchange 54455i [1692185973.075797][4873:4875] CHIP:SC: Received Sigma2 msg [1692185973.075804][4873:4875] CHIP:SC: Peer assigned session session ID 20316 [1692185973.076768][4873:4875] CHIP:SC: Sending Sigma3 [1692185973.076908][4873:4875] CHIP:EM: [E:54455i S:0 M:99666386 (Ack:54954237)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:32 (SecureChannel:CASE_Sigma3) [1692185973.076966][4873:4875] CHIP:IN: (U) Sending msg 99666386 to IP address ‘UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540’ [1692185973.077065][4873:4875] CHIP:SC: Sent Sigma3 msg [1692185973.092635][4873:4875] CHIP:EM: [E:54455i S:0 M:54954238 (Ack:99666386)] (U) Msg RX from 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck) [1692185973.092664][4873:4875] CHIP:EM: Found matching exchange: 54455i, Delegate: 0x7ff3c403b038 [1692185973.092675][4873:4875] CHIP:EM: Rxd Ack; Removing MessageCounter:99666386 from Retrans Table on exchange 54455i [1692185973.115867][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1692185975.077496][4873:4875] CHIP:EM: [E:54455i S:0 M:54954239 (Ack:99666386)] (U) Msg RX from 0:0000000000000000 [0000] — Type 0000:40 (SecureChannel:StatusReport) [1692185975.077544][4873:4875] CHIP:EM: Found matching exchange: 54455i, Delegate: 0x7ff3c403b038 [1692185975.077554][4873:4875] CHIP:EM: CHIP MessageCounter:99666386 not in RetransTable on exchange 54455i [1692185975.077569][4873:4875] CHIP:SC: Success status report received. Session was established [1692185975.078860][4873:4875] CHIP:SC: SecureSession[0x7ff3c403b2d0, LSID:63029]: State change ‘kEstablishing’ -- ‘kActive’ [1692185975.078897][4873:4875] CHIP:IN: SecureSession[0x7ff3c403b2d0]: Activated - Type:2 LSID:63029 [1692185975.078905][4873:4875] CHIP:IN: New secure session activated for device 00000000000004D3, 1, LSID:63029 PSID:20316! [1692185975.078914][4873:4875] CHIP:DIS: OperationalSessionSetup[1:00000000000004D3]: State change 4 -- 5 [1692185975.078939][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘FindOperational’ [1692185975.079023][4873:4875] CHIP:CTL: Commissioning stage next step: ‘FindOperational’ - ‘SendComplete’ [1692185975.079037][4873:4875] CHIP:CTL: Performing next commissioning step ‘SendComplete’ [1692185975.079055][4873:4875] CHIP:DMG: ICR moving to [AddingComm] [1692185975.079064][4873:4875] CHIP:DMG: ICR moving to [AddedComma] [1692185975.079092][4873:4875] CHIP:EM: [E:54456i S:63029 M:191952918] (S) Msg TX to 1:00000000000004D3 [F36C] — Type 0001:08 (IM:InvokeCommandRequest) [1692185975.079104][4873:4875] CHIP:IN: (S) Sending msg 191952918 on secure session with LSID: 63029 [1692185975.079207][4873:4875] CHIP:DMG: ICR moving to [CommandSen] [1692185975.079275][4873:4875] CHIP:EM: [E:54455i S:0 M:99666387 (Ack:54954239)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck) [1692185975.079305][4873:4875] CHIP:IN: (U) Sending msg 99666387 to IP address ‘UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540’ [1692185975.079367][4873:4875] CHIP:EM: Flushed pending ack for MessageCounter:54954239 on exchange 54455i [1692185975.260487][4873:4875] CHIP:EM: [E:54456i S:63029 M:154166066 (Ack:191952918)] (S) Msg RX from 1:00000000000004D3 [F36C] — Type 0001:09 (IM:InvokeCommandResponse) [1692185975.260535][4873:4875] CHIP:EM: Found matching exchange: 54456i, Delegate: 0x7ff3cc05b4e8 [1692185975.260549][4873:4875] CHIP:EM: Rxd Ack; Removing MessageCounter:191952918 from Retrans Table on exchange 54456i [1692185975.260561][4873:4875] CHIP:DMG: ICR moving to [ResponseRe] [1692185975.260577][4873:4875] CHIP:DMG: InvokeResponseMessage [1692185975.260583][4873:4875] CHIP:DMG: { [1692185975.260589][4873:4875] CHIP:DMG: suppressResponse false, [1692185975.260598][4873:4875] CHIP:DMG: InvokeResponseIBs [1692185975.260609][4873:4875] CHIP:DMG: [ [1692185975.260617][4873:4875] CHIP:DMG: InvokeResponseIB [1692185975.260657][4873:4875] CHIP:DMG: { [1692185975.260669][4873:4875] CHIP:DMG: CommandDataIB [1692185975.260681][4873:4875] CHIP:DMG: { [1692185975.260692][4873:4875] CHIP:DMG: CommandPathIB [1692185975.260706][4873:4875] CHIP:DMG: { [1692185975.260719][4873:4875] CHIP:DMG: EndpointId 0x0, [1692185975.260758][4873:4875] CHIP:DMG: ClusterId 0x30, [1692185975.260774][4873:4875] CHIP:DMG: CommandId 0x5, [1692185975.260788][4873:4875] CHIP:DMG: }, [1692185975.260802][4873:4875] CHIP:DMG: [1692185975.260839][4873:4875] CHIP:DMG: CommandFields [1692185975.260854][4873:4875] CHIP:DMG: { [1692185975.260868][4873:4875] CHIP:DMG: 0x0 0, [1692185975.260882][4873:4875] CHIP:DMG: 0x1 “” (0 chars), [1692185975.260920][4873:4875] CHIP:DMG: }, [1692185975.260934][4873:4875] CHIP:DMG: }, [1692185975.260947][4873:4875] CHIP:DMG: [1692185975.260957][4873:4875] CHIP:DMG: }, [1692185975.260990][4873:4875] CHIP:DMG: [1692185975.261002][4873:4875] CHIP:DMG: ], [1692185975.261013][4873:4875] CHIP:DMG: [1692185975.261020][4873:4875] CHIP:DMG: InteractionModelRevision 1 [1692185975.261029][4873:4875] CHIP:DMG: }, [1692185975.261046][4873:4875] CHIP:DMG: Received Command Response Data, Endpoint0 Cluster0x0000_0030 Command0x0000_0005 [1692185975.261067][4873:4875] CHIP:CTL: Received CommissioningComplete response, errorCode0 [1692185975.261104][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘SendComplete’ [1692185975.261113][4873:4875] CHIP:CTL: Commissioning stage next step: ‘SendComplete’ - ‘Cleanup’ [1692185975.261122][4873:4875] CHIP:CTL: Performing next commissioning step ‘Cleanup’ [1692185975.261130][4873:4875] CHIP:DIS: Closing all BLE connections [1692185975.261137][4873:4875] CHIP:IN: Clearing BLE pending packets. [1692185975.261893][4873:4875] CHIP:BLE: Auto-closing end point’s BLE connection. [1692185975.261924][4873:4875] CHIP:DL: Closing BLE GATT connection (con 0x7ff3cc05a340) [1692185975.261944][4873:4874] CHIP:DL: BluezDisconnect peer53:0B:77:A5:C5:EA [1692185977.204691][4873:4874] CHIP:DL: Bluez disconnected [1692185977.204727][4873:4874] CHIP:DL: Bluez notify CHIPoBluez connection disconnected [1692185977.205427][4873:4875] CHIP:IN: SecureSession[0x7ff3c4014a00]: MarkForEviction Type:1 LSID:63028 [1692185977.205456][4873:4875] CHIP:SC: SecureSession[0x7ff3c4014a00, LSID:63028]: State change ‘kActive’ -- ‘kPendingEviction’ [1692185977.205482][4873:4875] CHIP:IN: SecureSession[0x7ff3c4014a00]: Released - Type:1 LSID:63028 [1692185977.205498][4873:4875] CHIP:CTL: Successfully finished commissioning step ‘Cleanup’ [1692185977.205505][4873:4875] CHIP:TOO: Device commissioning completed with success [1692185977.205538][4873:4875] CHIP:DMG: ICR moving to [AwaitingDe] [1692185977.205574][4873:4875] CHIP:EM: [E:54456i S:63029 M:191952919 (Ack:154166066)] (S) Msg TX to 1:00000000000004D3 [F36C] — Type 0000:10 (SecureChannel:StandaloneAck) [1692185977.205582][4873:4875] CHIP:IN: (S) Sending msg 191952919 on secure session with LSID: 63029 [1692185977.206233][4873:4875] CHIP:EM: Flushed pending ack for MessageCounter:154166066 on exchange 54456i [1692185977.206251][4873:4875] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1692185977.206254][4873:4875] CHIP:BLE: no endpoint for unsub complete [1692185977.206346][4873:4873] CHIP:CTL: Shutting down the commissioner [1692185977.206349][4873:4873] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1692185977.206373][4873:4873] CHIP:CTL: Shutting down the controller [1692185977.206377][4873:4873] CHIP:IN: Expiring all sessions for fabric 0x1!! [1692185977.206380][4873:4873] CHIP:IN: SecureSession[0x7ff3c403b2d0]: MarkForEviction Type:2 LSID:63029 [1692185977.206383][4873:4873] CHIP:SC: SecureSession[0x7ff3c403b2d0, LSID:63029]: State change ‘kActive’ -- ‘kPendingEviction’ [1692185977.206385][4873:4873] CHIP:IN: SecureSession[0x7ff3c403b2d0]: Released - Type:2 LSID:63029 [1692185977.206389][4873:4873] CHIP:FP: Forgetting fabric 0x1 [1692185977.206394][4873:4873] CHIP:TS: Pending Last Known Good Time: 2023-08-10T09:33:31 [1692185977.206496][4873:4873] CHIP:TS: Previous Last Known Good Time: 2023-08-10T09:33:31 [1692185977.206707][4873:4873] CHIP:TS: Reverted Last Known Good Time to previous value [1692185977.206752][4873:4873] CHIP:CTL: Shutting down the commissioner [1692185977.206757][4873:4873] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1692185977.206777][4873:4873] CHIP:CTL: Shutting down the controller [1692185977.206803][4873:4873] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1692185977.206859][4873:4873] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1692185977.206913][4873:4873] CHIP:FP: Shutting down FabricTable [1692185977.206921][4873:4873] CHIP:TS: Pending Last Known Good Time: 2023-08-10T09:33:31 [1692185977.206988][4873:4873] CHIP:TS: Previous Last Known Good Time: 2023-08-10T09:33:31 [1692185977.207012][4873:4873] CHIP:TS: Reverted Last Known Good Time to previous value [1692185977.207102][4873:4873] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-cCgX6R) [1692185977.207749][4873:4873] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1692185977.207825][4873:4873] CHIP:DL: NVS set: chip-counters/total-operational-hours 0 (0x0) [1692185977.207835][4873:4873] CHIP:DL: Inet Layer shutdown [1692185977.207840][4873:4873] CHIP:DL: BLE shutdown [1692185977.208511][4873:4873] CHIP:DL: System Layer shutdown 控制 xingxing-virtual-machine:~/work/connectedhomeip$ out/x86/chip-tool onoff toggle 1235 1 [1692186248.638330][5152:5152] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1692186248.640268][5152:5152] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1692186248.640387][5152:5152] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1692186248.640436][5152:5152] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1692186248.640742][5152:5152] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-pQbxaV) [1692186248.641139][5152:5152] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1692186248.641182][5152:5152] CHIP:DL: NVS set: chip-counters/reboot-count 3 (0x3) [1692186248.641934][5152:5152] CHIP:DL: Got Ethernet interface: ens33 [1692186248.642169][5152:5152] CHIP:DL: Found the primary Ethernet interface:ens33 [1692186248.642324][5152:5152] CHIP:DL: Failed to get WiFi interface [1692186248.642346][5152:5152] CHIP:DL: Failed to reset WiFi statistic counts [1692186248.642402][5152:5152] CHIP:IN: UDP::Init bindlisten port0 [1692186248.642438][5152:5152] CHIP:IN: UDP::Init bound to port59263 [1692186248.642442][5152:5152] CHIP:IN: UDP::Init bindlisten port0 [1692186248.642463][5152:5152] CHIP:IN: UDP::Init bound to port51875 [1692186248.642491][5152:5152] CHIP:IN: BLEBase::Init - setting/overriding transport [1692186248.642495][5152:5152] CHIP:IN: TransportMgr initialized [1692186248.642549][5152:5152] CHIP:FP: Initializing FabricTable from persistent storage [1692186248.642784][5152:5152] CHIP:TS: Last Known Good Time: 2023-08-10T09:33:31 [1692186248.643388][5152:5152] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x83349DF93C94F36C, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1692186248.644716][5152:5152] CHIP:ZCL: Using ZAP configuration… [1692186248.645945][5152:5152] CHIP:CTL: System State Initialized… [1692186248.646021][5152:5152] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1692186248.646065][5152:5152] CHIP:CTL: Setting attestation nonce to random value [1692186248.646092][5152:5152] CHIP:CTL: Setting CSR nonce to random value [1692186248.646155][5152:5152] CHIP:IN: UDP::Init bindlisten port5550 [1692186248.646179][5152:5152] CHIP:IN: UDP::Init bound to port5550 [1692186248.646206][5152:5152] CHIP:IN: UDP::Init bindlisten port5550 [1692186248.646220][5152:5152] CHIP:IN: UDP::Init bound to port5550 [1692186248.646222][5152:5152] CHIP:IN: TransportMgr initialized [1692186248.646394][5152:5154] CHIP:DL: CHIP task running [1692186248.646448][5152:5154] CHIP:DL: HandlePlatformSpecificBLEEvent 32786 [1692186248.646575][5152:5154] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1692186248.646613][5152:5154] CHIP:CTL: Setting attestation nonce to random value [1692186248.646660][5152:5154] CHIP:CTL: Setting CSR nonce to random value [1692186248.646905][5152:5154] CHIP:CTL: Generating NOC [1692186248.647158][5152:5154] CHIP:FP: Validating NOC chain [1692186248.647564][5152:5154] CHIP:FP: NOC chain validation successful [1692186248.647630][5152:5154] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1692186248.647658][5152:5154] CHIP:TS: Last Known Good Time: 2023-08-10T09:33:31 [1692186248.647682][5152:5154] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1692186248.647704][5152:5154] CHIP:TS: Retaining current Last Known Good Time [1692186248.661664][5152:5154] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1692186248.662647][5152:5154] CHIP:TS: Committing Last Known Good Time to storage: 2023-08-10T09:33:31 [1692186248.663361][5152:5154] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 83349DF93C94F36C) [1692186248.663425][5152:5154] CHIP:IN: UDP::Init bindlisten port5550 [1692186248.663486][5152:5154] CHIP:IN: UDP::Init bound to port5550 [1692186248.663536][5152:5154] CHIP:IN: UDP::Init bindlisten port5550 [1692186248.663592][5152:5154] CHIP:IN: UDP::Init bound to port5550 [1692186248.663628][5152:5154] CHIP:IN: TransportMgr initialized [1692186248.669311][5152:5154] CHIP:TOO: Sending command to node 0x4d3 [1692186248.669474][5152:5154] CHIP:CSM: FindOrEstablishSession: PeerId [1:00000000000004D3] [1692186248.669511][5152:5154] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found [1692186248.669569][5152:5154] CHIP:DIS: OperationalSessionSetup[1:00000000000004D3]: State change 1 -- 2 [1692186248.689496][5152:5154] CHIP:DIS: UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540: new best score: 7 [1692186248.689738][5152:5154] CHIP:DIS: UDP:[fdfe:7ca8:9deb:8:3a73:eaff:fee2:5722%ens33]:5540: score has not improved: 5 [1692186248.689784][5152:5154] CHIP:DIS: UDP:192.168.0.4%ens33:5540: score has not improved: 2 [1692186248.689813][5152:5154] CHIP:DIS: Checking node lookup status after 20 ms [1692186248.689839][5152:5154] CHIP:DIS: Keeping DNSSD lookup active [1692186248.691329][5152:5154] CHIP:DIS: UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540: score has not improved: 7 [1692186248.691831][5152:5154] CHIP:DIS: UDP:[fdfe:7ca8:9deb:8:3a73:eaff:fee2:5722%ens33]:5540: score has not improved: 5 [1692186248.691904][5152:5154] CHIP:DIS: UDP:192.168.0.4%ens33:5540: score has not improved: 2 [1692186248.691936][5152:5154] CHIP:DIS: Checking node lookup status after 22 ms [1692186248.691963][5152:5154] CHIP:DIS: Keeping DNSSD lookup active [1692186248.870680][5152:5154] CHIP:DIS: Checking node lookup status after 201 ms [1692186248.870728][5152:5154] CHIP:DIS: OperationalSessionSetup[1:00000000000004D3]: Updating device address to UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540 while in state 2 [1692186248.870764][5152:5154] CHIP:DIS: OperationalSessionSetup[1:00000000000004D3]: State change 2 -- 3 [1692186248.870822][5152:5154] CHIP:IN: SecureSession[0x7f824400b0e0]: Allocated Type:2 LSID:7890 [1692186248.870832][5152:5154] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 - 0x00000000000004D3 [1692186248.871076][5152:5154] CHIP:EM: [E:40461i S:0 M:187945024] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:30 (SecureChannel:CASE_Sigma1) [1692186248.871116][5152:5154] CHIP:IN: (U) Sending msg 187945024 to IP address ‘UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540’ [1692186248.871231][5152:5154] CHIP:SC: Sent Sigma1 msg [1692186248.871250][5152:5154] CHIP:DIS: OperationalSessionSetup[1:00000000000004D3]: State change 3 -- 4 [1692186248.920854][5152:5154] CHIP:EM: [E:40461i S:0 M:54954244 (Ack:187945024)] (U) Msg RX from 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck) [1692186248.920886][5152:5154] CHIP:EM: Found matching exchange: 40461i, Delegate: 0x7f824400f658 [1692186248.920896][5152:5154] CHIP:EM: Rxd Ack; Removing MessageCounter:187945024 from Retrans Table on exchange 40461i [1692186249.877324][5152:5154] CHIP:EM: [E:40461i S:0 M:54954245 (Ack:187945024)] (U) Msg RX from 0:0000000000000000 [0000] — Type 0000:31 (SecureChannel:CASE_Sigma2) [1692186249.877357][5152:5154] CHIP:EM: Found matching exchange: 40461i, Delegate: 0x7f824400f658 [1692186249.877363][5152:5154] CHIP:EM: CHIP MessageCounter:187945024 not in RetransTable on exchange 40461i [1692186249.877374][5152:5154] CHIP:SC: Received Sigma2 msg [1692186249.877382][5152:5154] CHIP:SC: Peer assigned session session ID 20318 [1692186249.878257][5152:5154] CHIP:SC: Sending Sigma3 [1692186249.878395][5152:5154] CHIP:EM: [E:40461i S:0 M:187945025 (Ack:54954245)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:32 (SecureChannel:CASE_Sigma3) [1692186249.878450][5152:5154] CHIP:IN: (U) Sending msg 187945025 to IP address ‘UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540’ [1692186249.878683][5152:5154] CHIP:SC: Sent Sigma3 msg [1692186249.913281][5152:5154] CHIP:EM: [E:40461i S:0 M:54954246 (Ack:187945025)] (U) Msg RX from 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck) [1692186249.913312][5152:5154] CHIP:EM: Found matching exchange: 40461i, Delegate: 0x7f824400f658 [1692186249.913322][5152:5154] CHIP:EM: Rxd Ack; Removing MessageCounter:187945025 from Retrans Table on exchange 40461i [1692186251.846430][5152:5154] CHIP:EM: [E:40461i S:0 M:54954247 (Ack:187945025)] (U) Msg RX from 0:0000000000000000 [0000] — Type 0000:40 (SecureChannel:StatusReport) [1692186251.846463][5152:5154] CHIP:EM: Found matching exchange: 40461i, Delegate: 0x7f824400f658 [1692186251.846469][5152:5154] CHIP:EM: CHIP MessageCounter:187945025 not in RetransTable on exchange 40461i [1692186251.846479][5152:5154] CHIP:SC: Success status report received. Session was established [1692186251.847805][5152:5154] CHIP:SC: SecureSession[0x7f824400b0e0, LSID:7890]: State change ‘kEstablishing’ -- ‘kActive’ [1692186251.847829][5152:5154] CHIP:IN: SecureSession[0x7f824400b0e0]: Activated - Type:2 LSID:7890 [1692186251.847834][5152:5154] CHIP:IN: New secure session activated for device 00000000000004D3, 1, LSID:7890 PSID:20318! [1692186251.847838][5152:5154] CHIP:DIS: OperationalSessionSetup[1:00000000000004D3]: State change 4 -- 5 [1692186251.847855][5152:5154] CHIP:TOO: Sending cluster (0x00000006) command (0x00000002) on endpoint 1 [1692186251.847867][5152:5154] CHIP:DMG: ICR moving to [AddingComm] [1692186251.847892][5152:5154] CHIP:DMG: ICR moving to [AddedComma] [1692186251.847921][5152:5154] CHIP:EM: [E:40462i S:7890 M:236175787] (S) Msg TX to 1:00000000000004D3 [F36C] — Type 0001:08 (IM:InvokeCommandRequest) [1692186251.847928][5152:5154] CHIP:IN: (S) Sending msg 236175787 on secure session with LSID: 7890 [1692186251.848027][5152:5154] CHIP:DMG: ICR moving to [CommandSen] [1692186251.848063][5152:5154] CHIP:EM: [E:40461i S:0 M:187945026 (Ack:54954247)] (U) Msg TX to 0:0000000000000000 [0000] — Type 0000:10 (SecureChannel:StandaloneAck) [1692186251.848088][5152:5154] CHIP:IN: (U) Sending msg 187945026 to IP address ‘UDP:[fe80::3a73:eaff:fee2:5722%ens33]:5540’ [1692186251.848135][5152:5154] CHIP:EM: Flushed pending ack for MessageCounter:54954247 on exchange 40461i [1692186251.864103][5152:5154] CHIP:EM: [E:40462i S:7890 M:77602851 (Ack:236175787)] (S) Msg RX from 1:00000000000004D3 [F36C] — Type 0001:09 (IM:InvokeCommandResponse) [1692186251.864131][5152:5154] CHIP:EM: Found matching exchange: 40462i, Delegate: 0x7f824400a368 [1692186251.864140][5152:5154] CHIP:EM: Rxd Ack; Removing MessageCounter:236175787 from Retrans Table on exchange 40462i [1692186251.864147][5152:5154] CHIP:DMG: ICR moving to [ResponseRe] [1692186251.864157][5152:5154] CHIP:DMG: InvokeResponseMessage [1692186251.864181][5152:5154] CHIP:DMG: { [1692186251.864185][5152:5154] CHIP:DMG: suppressResponse false, [1692186251.864188][5152:5154] CHIP:DMG: InvokeResponseIBs [1692186251.864192][5152:5154] CHIP:DMG: [ [1692186251.864195][5152:5154] CHIP:DMG: InvokeResponseIB [1692186251.864199][5152:5154] CHIP:DMG: { [1692186251.864201][5152:5154] CHIP:DMG: CommandStatusIB [1692186251.864204][5152:5154] CHIP:DMG: { [1692186251.864206][5152:5154] CHIP:DMG: CommandPathIB [1692186251.864210][5152:5154] CHIP:DMG: { [1692186251.864212][5152:5154] CHIP:DMG: EndpointId 0x1, [1692186251.864215][5152:5154] CHIP:DMG: ClusterId 0x6, [1692186251.864219][5152:5154] CHIP:DMG: CommandId 0x2, [1692186251.864240][5152:5154] CHIP:DMG: }, [1692186251.864247][5152:5154] CHIP:DMG: [1692186251.864250][5152:5154] CHIP:DMG: StatusIB [1692186251.864253][5152:5154] CHIP:DMG: { [1692186251.864256][5152:5154] CHIP:DMG: status 0x00 (SUCCESS), [1692186251.864258][5152:5154] CHIP:DMG: }, [1692186251.864261][5152:5154] CHIP:DMG: [1692186251.864263][5152:5154] CHIP:DMG: }, [1692186251.864267][5152:5154] CHIP:DMG: [1692186251.864288][5152:5154] CHIP:DMG: }, [1692186251.864294][5152:5154] CHIP:DMG: [1692186251.864296][5152:5154] CHIP:DMG: ], [1692186251.864300][5152:5154] CHIP:DMG: [1692186251.864302][5152:5154] CHIP:DMG: InteractionModelRevision 1 [1692186251.864304][5152:5154] CHIP:DMG: }, [1692186251.864316][5152:5154] CHIP:DMG: Received Command Response Status for Endpoint1 Cluster0x0000_0006 Command0x0000_0002 Status0x0 [1692186251.864324][5152:5154] CHIP:DMG: ICR moving to [AwaitingDe] [1692186251.864369][5152:5154] CHIP:EM: [E:40462i S:7890 M:236175788 (Ack:77602851)] (S) Msg TX to 1:00000000000004D3 [F36C] — Type 0000:10 (SecureChannel:StandaloneAck) [1692186251.864377][5152:5154] CHIP:IN: (S) Sending msg 236175788 on secure session with LSID: 7890 [1692186251.864457][5152:5154] CHIP:EM: Flushed pending ack for MessageCounter:77602851 on exchange 40462i [1692186251.864637][5152:5152] CHIP:CTL: Shutting down the commissioner [1692186251.864650][5152:5152] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1692186251.864680][5152:5152] CHIP:CTL: Shutting down the controller [1692186251.864688][5152:5152] CHIP:IN: Expiring all sessions for fabric 0x1!! [1692186251.864695][5152:5152] CHIP:IN: SecureSession[0x7f824400b0e0]: MarkForEviction Type:2 LSID:7890 [1692186251.864701][5152:5152] CHIP:SC: SecureSession[0x7f824400b0e0, LSID:7890]: State change ‘kActive’ -- ‘kPendingEviction’ [1692186251.864707][5152:5152] CHIP:IN: SecureSession[0x7f824400b0e0]: Released - Type:2 LSID:7890 [1692186251.864714][5152:5152] CHIP:FP: Forgetting fabric 0x1 [1692186251.864723][5152:5152] CHIP:TS: Pending Last Known Good Time: 2023-08-10T09:33:31 [1692186251.864762][5152:5152] CHIP:TS: Previous Last Known Good Time: 2023-08-10T09:33:31 [1692186251.864769][5152:5152] CHIP:TS: Reverted Last Known Good Time to previous value [1692186251.864782][5152:5152] CHIP:CTL: Shutting down the commissioner [1692186251.864788][5152:5152] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1692186251.864802][5152:5152] CHIP:CTL: Shutting down the controller [1692186251.864808][5152:5152] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1692186251.864838][5152:5152] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1692186251.864858][5152:5152] CHIP:FP: Shutting down FabricTable [1692186251.864865][5152:5152] CHIP:TS: Pending Last Known Good Time: 2023-08-10T09:33:31 [1692186251.864888][5152:5152] CHIP:TS: Previous Last Known Good Time: 2023-08-10T09:33:31 [1692186251.864895][5152:5152] CHIP:TS: Reverted Last Known Good Time to previous value [1692186251.864960][5152:5152] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-9wImOH) [1692186251.865159][5152:5152] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1692186251.865175][5152:5152] CHIP:DL: NVS set: chip-counters/total-operational-hours 0 (0x0) [1692186251.865182][5152:5152] CHIP:DL: Inet Layer shutdown [1692186251.865188][5152:5152] CHIP:DL: BLE shutdown [1692186251.865194][5152:5152] CHIP:DL: System Layer shutdown 问题排查
整个过程看似很顺利实际情况是在WiFINetworkSetup 这一步每次都报错研究代码加上理解流程才大概理解到这一步是通过DNS-SD服务请求ip地址一直请求不到。 由于编译使用的是NAT上网使用的PC机代理网络所以可以说虚拟机是不能直接跟路由器建立起关系的可以猜测到DNS-SD服务很难拿到结果简单点直接改成桥接网络 相当于路由器下连的就是虚拟机这样跟路由器请求获取分配的ip请求到以后就可以给到入网的灯设备了经过验证确实可行
感悟
一些术语以及英文缩写需要自己整理知道要干什么通过看代码验证自己的思路
