Other Parts Discussed in Thread: CC2652R7
我在边界路由上面执行下面这条命令配对不上，请问我要如何操作。
现在RCP已经搭建好，matter Device也正常的，我想用chiptool工具执行配对不行。
This thread has been locked.
If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.
我在边界路由上面执行下面这条命令配对不上，请问我要如何操作。
现在RCP已经搭建好，matter Device也正常的，我想用chiptool工具执行配对不行。
您好，
感谢您的提问。我们有几个问题想要咨询您：
期待您的回复。
我确认OenThread边界路由已经正常运行，下面我给出我的chiptool的日志和Matter Device的串口日志
ubuntu@raspberrypi:~/matter/out$ ls
args.gn build.ninja build.ninja.d build.ninja.stamp chip-tool chip-tool-utils.a chip-tool.map gen lib liblogging-stdio.a obj python relative_path_transformations.json toolchain.ninja
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$ ./chip-tool
[1689126501.963905][6142:6142] CHIP:TOO: Missing cluster name
Usage:
./chip-tool cluster_name command_name [param1 param2 ...]
+-------------------------------------------------------------------------------------+
| Clusters: |
+-------------------------------------------------------------------------------------+
| * accesscontrol |
| * accountlogin |
| * actions |
| * administratorcommissioning |
| * any |
| * applicationbasic |
| * applicationlauncher |
| * audiooutput |
| * ballastconfiguration |
| * barriercontrol |
| * basicinformation |
| * binaryinputbasic |
| * binding |
| * booleanstate |
| * bridgeddevicebasic |
| * channel |
| * clientmonitoring |
| * colorcontrol |
| * contentlauncher |
| * descriptor |
| * diagnosticlogs |
| * discover |
| * doorlock |
| * electricalmeasurement |
| * ethernetnetworkdiagnostics |
| * fancontrol |
| * faultinjection |
| * fixedlabel |
| * flowmeasurement |
| * generalcommissioning |
| * generaldiagnostics |
| * groupkeymanagement |
| * groupsettings |
| * groups |
| * identify |
| * illuminancemeasurement |
| * keypadinput |
| * levelcontrol |
| * localizationconfiguration |
| * lowpower |
| * mediainput |
| * mediaplayback |
| * modeselect |
| * networkcommissioning |
| * occupancysensing |
| * onoff |
| * onoffswitchconfiguration |
| * operationalcredentials |
| * otasoftwareupdateprovider |
| * otasoftwareupdaterequestor |
| * pairing |
| * payload |
| * powersource |
| * powersourceconfiguration |
| * pressuremeasurement |
| * proxyconfiguration |
| * proxydiscovery |
| * proxyvalid |
| * pulsewidthmodulation |
| * pumpconfigurationandcontrol |
| * relativehumiditymeasurement |
| * scenes |
| * softwarediagnostics |
| * subscriptions |
| * switch |
| * targetnavigator |
| * temperaturemeasurement |
| * tests |
| * thermostat |
| * thermostatuserinterfaceconfiguration |
| * threadnetworkdiagnostics |
| * timeformatlocalization |
| * timesynchronization |
| * unitlocalization |
| * unittesting |
| * userlabel |
| * wakeonlan |
| * wifinetworkdiagnostics |
| * windowcovering |
| * interactive |
| * storage |
+-------------------------------------------------------------------------------------+
[1689126501.965290][6142:6142] CHIP:TOO: Run command failure: ../examples/chip-tool/commands/common/Commands.cpp:78: Error 0x0000002F
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$ cd ..
ubuntu@raspberrypi:~/matter$
ubuntu@raspberrypi:~/matter$ ls
BUILD.gn CONTRIBUTING.md NOTICE README_MATTER.md README_WIFI.md REVIEWERS.md build config docs gn_build.sh lgtm.yml scripts third_party
CODE_OF_CONDUCT.md LICENSE README.md README_THREAD.md RELEASE_NOTES.md 'Texas Instruments Matter GitHub_1.0.x_manifest.html' build_overrides credentials examples integrations out src zzz_generated
ubuntu@raspberrypi:~/matter$
ubuntu@raspberrypi:~/matter$
ubuntu@raspberrypi:~/matter$ cd ..
ubuntu@raspberrypi:~$
ubuntu@raspberrypi:~$
ubuntu@raspberrypi:~$ ls
connectedhomeip matter ot-br-posix
ubuntu@raspberrypi:~$ cd matter/
ubuntu@raspberrypi:~/matter$
ubuntu@raspberrypi:~/matter$ ls
BUILD.gn CONTRIBUTING.md NOTICE README_MATTER.md README_WIFI.md REVIEWERS.md build config docs gn_build.sh lgtm.yml scripts third_party
CODE_OF_CONDUCT.md LICENSE README.md README_THREAD.md RELEASE_NOTES.md 'Texas Instruments Matter GitHub_1.0.x_manifest.html' build_overrides credentials examples integrations out src zzz_generated
ubuntu@raspberrypi:~/matter$
ubuntu@raspberrypi:~/matter$
ubuntu@raspberrypi:~/matter$ cd out
ubuntu@raspberrypi:~/matter/out$ ls
args.gn build.ninja build.ninja.d build.ninja.stamp chip-tool chip-tool-utils.a chip-tool.map gen lib liblogging-stdio.a obj python relative_path_transformations.json toolchain.ninja
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$ cd ~
ubuntu@raspberrypi:~$ sudo ot-ctl dataset active -x
[sudo] password for ubuntu:
000300000f35060004001fffe00208dead00beef00cafe0708fde2f55f8122e830051000112233445566778899aabbccddeeff030f4f70656e5468726561642d37386237010212340410104810e2315100afd6bc9215a6bfac530c0402a0f7f80e080000000000010000
Done
ubuntu@raspberrypi:~$
ubuntu@raspberrypi:~$
ubuntu@raspberrypi:~$
ubuntu@raspberrypi:~$ ls
connectedhomeip matter ot-br-posix
ubuntu@raspberrypi:~$
ubuntu@raspberrypi:~$
ubuntu@raspberrypi:~$ cd matter/
ubuntu@raspberrypi:~/matter$
ubuntu@raspberrypi:~/matter$ ls
BUILD.gn CONTRIBUTING.md NOTICE README_MATTER.md README_WIFI.md REVIEWERS.md build config docs gn_build.sh lgtm.yml scripts third_party
CODE_OF_CONDUCT.md LICENSE README.md README_THREAD.md RELEASE_NOTES.md 'Texas Instruments Matter GitHub_1.0.x_manifest.html' build_overrides credentials examples integrations out src zzz_generated
ubuntu@raspberrypi:~/matter$
ubuntu@raspberrypi:~/matter$
ubuntu@raspberrypi:~/matter$ cd out/
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$ ls
args.gn build.ninja build.ninja.d build.ninja.stamp chip-tool chip-tool-utils.a chip-tool.map gen lib liblogging-stdio.a obj python relative_path_transformations.json toolchain.ninja
ubuntu@raspberrypi:~/matter/out$
ubuntu@raspberrypi:~/matter/out$ ./chip-tool pairing ble-thread 1 000300000f35060004001fffe00208dead00beef00cafe0708fde2f55f8122e830051000112233445566778899aabbccddeeff030f4f70656e5468726561642d37386237010212340410104810e2315100afd6bc9215a6bfac530c0402a0f7f80e080000000000010000 20202021 3840
[1689139858.904812][10800:10800] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
[1689139858.905125][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_kvs-XcUmQc)
[1689139858.905317][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1689139858.956389][10800:10800] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini
[1689139858.956876][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-NS429B)
[1689139858.957131][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
[1689139858.957291][10800:10800] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini
[1689139858.957464][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_config.ini-x6zM3m)
[1689139858.957638][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1689139858.957759][10800:10800] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini
[1689139858.957943][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-GP6GH8)
[1689139858.958132][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1689139858.958451][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-PMgKOz)
[1689139858.959067][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
[1689139858.959134][10800:10800] CHIP:DL: NVS set: chip-factory/unique-id = "4009E09BD06345CA"
[1689139858.959306][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-9J7xFU)
[1689139858.959928][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
[1689139858.959997][10800:10800] CHIP:DL: NVS set: chip-factory/vendor-id = 65521 (0xFFF1)
[1689139858.960166][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-QJSHLl)
[1689139858.960761][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
[1689139858.960829][10800:10800] CHIP:DL: NVS set: chip-factory/product-id = 32769 (0x8001)
[1689139858.960997][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-OVIjDJ)
[1689139858.961488][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1689139858.961552][10800:10800] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1)
[1689139858.961712][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-9Pe51e)
[1689139858.962484][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1689139858.962550][10800:10800] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1689139858.962782][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-A2M8aw)
[1689139858.963339][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1689139858.963404][10800:10800] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0)
[1689139858.963565][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_config.ini-q2oc2F)
[1689139858.964107][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1689139858.964168][10800:10800] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0)
[1689139858.964329][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_config.ini-kW4x99)
[1689139858.964939][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1689139858.965007][10800:10800] CHIP:DL: NVS set: chip-config/location-capability = 2 (0x2)
[1689139858.965809][10800:10800] CHIP:DL: Got Ethernet interface: eth0
[1689139858.966389][10800:10800] CHIP:DL: Found the primary Ethernet interface:eth0
[1689139858.967012][10800:10800] CHIP:DL: Got WiFi interface: wlan0
[1689139858.967087][10800:10800] CHIP:DL: Failed to reset WiFi statistic counts
[1689139858.967204][10800:10800] CHIP:IN: UDP::Init bind&listen port=0
[1689139858.967318][10800:10800] CHIP:IN: UDP::Init bound to port=55610
[1689139858.967342][10800:10800] CHIP:IN: UDP::Init bind&listen port=0
[1689139858.967436][10800:10800] CHIP:IN: UDP::Init bound to port=43630
[1689139858.967459][10800:10800] CHIP:IN: BLEBase::Init - setting/overriding transport
[1689139858.967478][10800:10800] CHIP:IN: TransportMgr initialized
[1689139858.967508][10800:10800] CHIP:FP: Initializing FabricTable from persistent storage
[1689139858.967553][10800:10800] CHIP:TS: Last Known Good Time: [unknown]
[1689139858.967581][10800:10800] CHIP:TS: Setting Last Known Good Time to firmware build time 2023-07-12T09:42:48
[1689139858.971126][10800:10800] CHIP:ZCL: Using ZAP configuration...
[1689139858.973966][10800:10800] CHIP:DL: MDNS failed to join multicast group on wpan0 for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:764: Inet Error 0x00000110: Address not found
[1689139858.974288][10800:10800] CHIP:CTL: System State Initialized...
[1689139858.974343][10800:10800] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1689139858.974422][10800:10800] CHIP:CTL: Setting attestation nonce to random value
[1689139858.974475][10800:10800] CHIP:CTL: Setting CSR nonce to random value
[1689139858.974537][10800:10800] CHIP:IN: UDP::Init bind&listen port=5550
[1689139858.974629][10800:10800] CHIP:IN: UDP::Init bound to port=5550
[1689139858.974654][10800:10800] CHIP:IN: UDP::Init bind&listen port=5550
[1689139858.974741][10800:10800] CHIP:IN: UDP::Init bound to port=5550
[1689139858.974764][10800:10800] CHIP:IN: TransportMgr initialized
[1689139858.975047][10800:10802] CHIP:DL: CHIP task running
[1689139858.975684][10800:10802] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1689139858.975733][10800:10802] CHIP:CTL: Setting attestation nonce to random value
[1689139858.975913][10800:10802] CHIP:CTL: Setting CSR nonce to random value
[1689139858.976121][10800:10802] CHIP:CTL: Couldn't get ExampleOpCredsCAKey from storage: ../examples/chip-tool/third_party/connectedhomeip/src/controller/ExamplePersistentStorage.cpp:93: CHIP Error 0x000000A0: Value not found in the persisted storage
[1689139858.980427][10800:10802] CHIP:CTL: Couldn't get ExampleOpCredsICAKey from storage: ../examples/chip-tool/third_party/connectedhomeip/src/controller/ExamplePersistentStorage.cpp:93: CHIP Error 0x000000A0: Value not found in the persisted storage
[1689139858.981810][10800:10802] CHIP:CTL: Generating RCAC
[1689139859.001274][10800:10802] CHIP:CTL: Generating ICAC
[1689139859.003065][10800:10802] CHIP:CTL: Generating NOC
[1689139859.004434][10800:10802] CHIP:FP: Validating NOC chain
[1689139859.009869][10800:10802] CHIP:FP: NOC chain validation successful
[1689139859.010179][10800:10802] CHIP:FP: Added new fabric at index: 0x1
[1689139859.010284][10800:10802] CHIP:FP: Assigned compressed fabric ID: 0xB85C55CBB67A0454, node ID: 0x000000000001B669
[1689139859.010316][10800:10802] CHIP:TS: Last Known Good Time: 2023-07-12T09:42:48
[1689139859.010339][10800:10802] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00
[1689139859.010360][10800:10802] CHIP:TS: Retaining current Last Known Good Time
[1689139859.012020][10800:10802] CHIP:FP: Metadata for Fabric 0x1 persisted to storage.
[1689139859.014309][10800:10802] CHIP:TS: Committing Last Known Good Time to storage: 2023-07-12T09:42:48
[1689139859.020911][10800:10802] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: B85C55CBB67A0454)
[1689139859.020975][10800:10802] CHIP:IN: UDP::Init bind&listen port=5550
[1689139859.021119][10800:10802] CHIP:IN: UDP::Init bound to port=5550
[1689139859.021152][10800:10802] CHIP:IN: UDP::Init bind&listen port=5550
[1689139859.021280][10800:10802] CHIP:IN: UDP::Init bound to port=5550
[1689139859.021310][10800:10802] CHIP:IN: TransportMgr initialized
[1689139859.053533][10800:10802] CHIP:CTL: Setting thread operational dataset from parameters
[1689139859.053586][10800:10802] CHIP:CTL: Setting attempt thread scan from parameters
[1689139859.053613][10800:10802] CHIP:CTL: Setting attestation nonce to random value
[1689139859.053676][10800:10802] CHIP:CTL: Setting CSR nonce to random value
[1689139859.053717][10800:10802] CHIP:CTL: Setting PASE-only commissioning from parameters
[1689139859.053745][10800:10802] CHIP:CTL: Commission called for node ID 0x0000000000000001
[1689139859.059434][10800:10801] CHIP:DL: TRACE: Bus acquired for name C-2a30
[1689139859.069151][10800:10802] CHIP:DL: PlatformBlueZInit init success
[1689139859.075467][10800:10801] CHIP:BLE: BLE removing known devices.
[1689139859.077241][10800:10801] CHIP:BLE: BLE initiating scan.
[1689139859.085590][10800:10801] CHIP:BLE: Device 03:03:92:77:C5:A7 does not look like a CHIP device.
[1689139859.111142][10800:10801] CHIP:BLE: Device F2:49:1C:4E:E1:44 does not look like a CHIP device.
[1689139859.113510][10800:10801] CHIP:BLE: Device 3F:DF:A9:A4:0C:D8 does not look like a CHIP device.
[1689139859.115651][10800:10801] CHIP:BLE: Device 19:EB:64:8F:CB:B3 does not look like a CHIP device.
[1689139859.120362][10800:10801] CHIP:BLE: Device 18:E6:CC:61:60:DD does not look like a CHIP device.
[1689139859.134365][10800:10801] CHIP:BLE: Device 69:7F:AF:12:A3:5A does not look like a CHIP device.
[1689139859.153198][10800:10801] CHIP:BLE: Device 2C:C1:EB:EF:93:13 does not look like a CHIP device.
[1689139859.163091][10800:10801] CHIP:BLE: Device 24:A7:D0:E2:35:28 does not look like a CHIP device.
[1689139859.185214][10800:10801] CHIP:BLE: Device 0D:56:32:DC:D1:73 does not look like a CHIP device.
[1689139859.205468][10800:10801] CHIP:BLE: Device 53:10:59:59:18:0C does not look like a CHIP device.
[1689139859.210914][10800:10801] CHIP:BLE: Device 50:EC:82:A0:FF:B2 does not look like a CHIP device.
[1689139859.238535][10800:10801] CHIP:BLE: New device scanned: 75:19:7E:C1:BD:91
[1689139859.238614][10800:10801] CHIP:BLE: Device discriminator match. Attempting to connect.
[1689139859.243421][10800:10801] CHIP:BLE: Scan complete notification without an active scan.
[1689139859.768354][10800:10801] CHIP:DL: ConnectDevice complete
[1689139861.012149][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012198][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012225][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012245][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012272][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012292][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012319][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012339][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012362][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012382][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012404][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012423][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012448][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012468][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012490][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012510][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012532][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012552][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012577][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012597][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012644][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012664][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012692][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012712][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012735][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012754][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012776][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012796][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012820][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012840][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012862][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012882][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012904][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012923][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012947][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.012967][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.012989][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.013009][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.013031][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.013050][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.013079][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.013098][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.013120][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.013140][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.013162][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.013182][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.013208][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.013228][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.013250][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.013269][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.013291][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
[1689139861.013311][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.013336][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.013356][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
[1689139861.013383][10800:10801] CHIP:DL: New BLE connection 0xffffb405e080, device 75:19:7E:C1:BD:91, path /org/bluez/hci0/dev_75_19_7E_C1_BD_91
[1689139861.013527][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16385
[1689139861.013562][10800:10802] CHIP:DIS: Closing all BLE connections
[1689139861.013614][10800:10802] CHIP:IN: BleConnectionComplete: endPoint 0xaaaac174c038
[1689139861.013760][10800:10802] CHIP:IN: SecureSession[0xffffac014f10]: Allocated Type:1 LSID:56629
[1689139861.013793][10800:10802] CHIP:SC: Assigned local session key ID 56629
[1689139861.013889][10800:10802] CHIP:EM: <<< [E:60471i M:199606471] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
[1689139861.013938][10800:10802] CHIP:IN: (U) Sending msg 199606471 to IP address 'BLE'
[1689139861.013968][10800:10802] CHIP:IN: Message appended to BLE send queue
[1689139861.013991][10800:10802] CHIP:SC: Sent PBKDF param request
[1689139861.375578][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139861.473877][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16388
[1689139861.473955][10800:10802] CHIP:BLE: subscribe complete, ep = 0xaaaac174c038
[1689139861.476785][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139861.477079][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139861.477150][10800:10802] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4
[1689139861.477203][10800:10802] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244.
[1689139861.477251][10800:10802] CHIP:BLE: local and remote recv window size = 6
[1689139861.477439][10800:10802] CHIP:IN: BLE EndPoint 0xaaaac174c038 Connection Complete
[1689139861.570488][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139861.574061][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139861.574341][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139861.574549][10800:10802] CHIP:EM: >>> [E:60471i M:20804041] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
[1689139861.574624][10800:10802] CHIP:EM: Found matching exchange: 60471i, Delegate: 0xffffac034aa0
[1689139861.574704][10800:10802] CHIP:SC: Received PBKDF param response
[1689139861.574789][10800:10802] CHIP:SC: Peer assigned session ID 8216
[1689139861.586902][10800:10802] CHIP:EM: <<< [E:60471i M:199606472] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
[1689139861.586967][10800:10802] CHIP:IN: (U) Sending msg 199606472 to IP address 'BLE'
[1689139861.587074][10800:10802] CHIP:SC: Sent spake2p msg1
[1689139861.667840][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139866.838965][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139866.839284][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139866.839525][10800:10802] CHIP:EM: >>> [E:60471i M:20804042] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
[1689139866.839601][10800:10802] CHIP:EM: Found matching exchange: 60471i, Delegate: 0xffffac034aa0
[1689139866.839689][10800:10802] CHIP:SC: Received spake2p msg2
[1689139866.843013][10800:10802] CHIP:EM: <<< [E:60471i M:199606473] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
[1689139866.843120][10800:10802] CHIP:IN: (U) Sending msg 199606473 to IP address 'BLE'
[1689139866.843293][10800:10802] CHIP:SC: Sent spake2p msg3
[1689139866.932721][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139866.985069][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139866.985367][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139866.985601][10800:10802] CHIP:EM: >>> [E:60471i M:20804043] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
[1689139866.985676][10800:10802] CHIP:EM: Found matching exchange: 60471i, Delegate: 0xffffac034aa0
[1689139866.986080][10800:10802] CHIP:SC: SecureSession[0xffffac014f10]: Moving from state 'kEstablishing' --> 'kActive'
[1689139866.986143][10800:10802] CHIP:IN: SecureSession[0xffffac014f10]: Activated - Type:1 LSID:56629
[1689139866.986193][10800:10802] CHIP:IN: New secure session activated for device <FFFFFFFB00000000, 0>, LSID:56629 PSID:8216!
[1689139866.986254][10800:10802] CHIP:CTL: Remote device completed SPAKE2+ handshake
[1689139866.986304][10800:10802] CHIP:TOO: Pairing Success
[1689139866.986349][10800:10802] CHIP:TOO: PASE establishment successful
[1689139866.986400][10800:10802] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo'
[1689139866.986456][10800:10802] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo'
[1689139866.986526][10800:10802] CHIP:CTL: Sending request for commissioning information
[1689139866.986604][10800:10802] CHIP:DMG: SendReadRequest ReadClient[0xffffac03e000]: Sending Read Request
[1689139866.986925][10800:10802] CHIP:EM: <<< [E:60472i M:147197894] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
[1689139866.987013][10800:10802] CHIP:IN: (S) Sending msg 147197894 on secure session with LSID: 56629
[1689139866.987193][10800:10802] CHIP:DMG: MoveToState ReadClient[0xffffac03e000]: Moving to [AwaitingIn]
[1689139867.079051][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139867.082846][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139867.083129][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139867.179984][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139867.180281][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139867.180579][10800:10802] CHIP:EM: >>> [E:60472i M:256335160] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
[1689139867.180707][10800:10802] CHIP:EM: Found matching exchange: 60472i, Delegate: 0xffffac03e010
[1689139867.180935][10800:10802] CHIP:DMG: ReportDataMessage =
[1689139867.180995][10800:10802] CHIP:DMG: {
[1689139867.181068][10800:10802] CHIP:DMG: AttributeReportIBs =
[1689139867.181144][10800:10802] CHIP:DMG: [
[1689139867.181201][10800:10802] CHIP:DMG: AttributeReportIB =
[1689139867.181277][10800:10802] CHIP:DMG: {
[1689139867.181361][10800:10802] CHIP:DMG: AttributeDataIB =
[1689139867.181438][10800:10802] CHIP:DMG: {
[1689139867.181534][10800:10802] CHIP:DMG: DataVersion = 0x287c0f6,
[1689139867.181609][10800:10802] CHIP:DMG: AttributePathIB =
[1689139867.181684][10800:10802] CHIP:DMG: {
[1689139867.181782][10800:10802] CHIP:DMG: Endpoint = 0x0,
[1689139867.181864][10800:10802] CHIP:DMG: Cluster = 0x31,
[1689139867.181961][10800:10802] CHIP:DMG: Attribute = 0x0000_0003,
[1689139867.182037][10800:10802] CHIP:DMG: }
[1689139867.182135][10800:10802] CHIP:DMG:
[1689139867.182215][10800:10802] CHIP:DMG: Data = 20,
[1689139867.182307][10800:10802] CHIP:DMG: },
[1689139867.182381][10800:10802] CHIP:DMG:
[1689139867.182441][10800:10802] CHIP:DMG: },
[1689139867.182548][10800:10802] CHIP:DMG:
[1689139867.182605][10800:10802] CHIP:DMG: AttributeReportIB =
[1689139867.182700][10800:10802] CHIP:DMG: {
[1689139867.182762][10800:10802] CHIP:DMG: AttributeDataIB =
[1689139867.182856][10800:10802] CHIP:DMG: {
[1689139867.182932][10800:10802] CHIP:DMG: DataVersion = 0xd3bb39b0,
[1689139867.183024][10800:10802] CHIP:DMG: AttributePathIB =
[1689139867.183100][10800:10802] CHIP:DMG: {
[1689139867.183171][10800:10802] CHIP:DMG: Endpoint = 0x0,
[1689139867.183271][10800:10802] CHIP:DMG: Cluster = 0x28,
[1689139867.183350][10800:10802] CHIP:DMG: Attribute = 0x0000_0004,
[1689139867.183452][10800:10802] CHIP:DMG: }
[1689139867.183530][10800:10802] CHIP:DMG:
[1689139867.183629][10800:10802] CHIP:DMG: Data = 32774,
[1689139867.183701][10800:10802] CHIP:DMG: },
[1689139867.183795][10800:10802] CHIP:DMG:
[1689139867.183856][10800:10802] CHIP:DMG: },
[1689139867.183941][10800:10802] CHIP:DMG:
[1689139867.184016][10800:10802] CHIP:DMG: AttributeReportIB =
[1689139867.184092][10800:10802] CHIP:DMG: {
[1689139867.184172][10800:10802] CHIP:DMG: AttributeDataIB =
[1689139867.184242][10800:10802] CHIP:DMG: {
[1689139867.184290][10800:10802] CHIP:DMG: DataVersion = 0xd3bb39b0,
[1689139867.184365][10800:10802] CHIP:DMG: AttributePathIB =
[1689139867.184417][10800:10802] CHIP:DMG: {
[1689139867.184482][10800:10802] CHIP:DMG: Endpoint = 0x0,
[1689139867.184535][10800:10802] CHIP:DMG: Cluster = 0x28,
[1689139867.184620][10800:10802] CHIP:DMG: Attribute = 0x0000_0002,
[1689139867.184674][10800:10802] CHIP:DMG: }
[1689139867.184740][10800:10802] CHIP:DMG:
[1689139867.184793][10800:10802] CHIP:DMG: Data = 65521,
[1689139867.184854][10800:10802] CHIP:DMG: },
[1689139867.184905][10800:10802] CHIP:DMG:
[1689139867.184945][10800:10802] CHIP:DMG: },
[1689139867.185014][10800:10802] CHIP:DMG:
[1689139867.185053][10800:10802] CHIP:DMG: AttributeReportIB =
[1689139867.185118][10800:10802] CHIP:DMG: {
[1689139867.185159][10800:10802] CHIP:DMG: AttributeDataIB =
[1689139867.185203][10800:10802] CHIP:DMG: {
[1689139867.185263][10800:10802] CHIP:DMG: DataVersion = 0xf328d5ef,
[1689139867.185308][10800:10802] CHIP:DMG: AttributePathIB =
[1689139867.185369][10800:10802] CHIP:DMG: {
[1689139867.185419][10800:10802] CHIP:DMG: Endpoint = 0x0,
[1689139867.185486][10800:10802] CHIP:DMG: Cluster = 0x30,
[1689139867.185540][10800:10802] CHIP:DMG: Attribute = 0x0000_0003,
[1689139867.185605][10800:10802] CHIP:DMG: }
[1689139867.185657][10800:10802] CHIP:DMG:
[1689139867.185709][10800:10802] CHIP:DMG: Data = 0,
[1689139867.185768][10800:10802] CHIP:DMG: },
[1689139867.185818][10800:10802] CHIP:DMG:
[1689139867.185877][10800:10802] CHIP:DMG: },
[1689139867.185934][10800:10802] CHIP:DMG:
[1689139867.185987][10800:10802] CHIP:DMG: AttributeReportIB =
[1689139867.186037][10800:10802] CHIP:DMG: {
[1689139867.186080][10800:10802] CHIP:DMG: AttributeDataIB =
[1689139867.186140][10800:10802] CHIP:DMG: {
[1689139867.186186][10800:10802] CHIP:DMG: DataVersion = 0xf328d5ef,
[1689139867.186247][10800:10802] CHIP:DMG: AttributePathIB =
[1689139867.186297][10800:10802] CHIP:DMG: {
[1689139867.186361][10800:10802] CHIP:DMG: Endpoint = 0x0,
[1689139867.186415][10800:10802] CHIP:DMG: Cluster = 0x30,
[1689139867.186482][10800:10802] CHIP:DMG: Attribute = 0x0000_0002,
[1689139867.186530][10800:10802] CHIP:DMG: }
[1689139867.186584][10800:10802] CHIP:DMG:
[1689139867.186650][10800:10802] CHIP:DMG: Data = 0,
[1689139867.186696][10800:10802] CHIP:DMG: },
[1689139867.186758][10800:10802] CHIP:DMG:
[1689139867.186799][10800:10802] CHIP:DMG: },
[1689139867.186871][10800:10802] CHIP:DMG:
[1689139867.186910][10800:10802] CHIP:DMG: AttributeReportIB =
[1689139867.186961][10800:10802] CHIP:DMG: {
[1689139867.187016][10800:10802] CHIP:DMG: AttributeDataIB =
[1689139867.187062][10800:10802] CHIP:DMG: {
[1689139867.187123][10800:10802] CHIP:DMG: DataVersion = 0xf328d5ef,
[1689139867.187171][10800:10802] CHIP:DMG: AttributePathIB =
[1689139867.187234][10800:10802] CHIP:DMG: {
[1689139867.187285][10800:10802] CHIP:DMG: Endpoint = 0x0,
[1689139867.187337][10800:10802] CHIP:DMG: Cluster = 0x30,
[1689139867.187404][10800:10802] CHIP:DMG: Attribute = 0x0000_0001,
[1689139867.187454][10800:10802] CHIP:DMG: }
[1689139867.187518][10800:10802] CHIP:DMG:
[1689139867.187567][10800:10802] CHIP:DMG: Data =
[1689139867.187628][10800:10802] CHIP:DMG: {
[1689139867.187718][10800:10802] CHIP:DMG: 0x0 = 60,
[1689139867.187787][10800:10802] CHIP:DMG: 0x1 = 900,
[1689139867.187840][10800:10802] CHIP:DMG: },
[1689139867.187900][10800:10802] CHIP:DMG: },
[1689139867.187952][10800:10802] CHIP:DMG:
[1689139867.188005][10800:10802] CHIP:DMG: },
[1689139867.188062][10800:10802] CHIP:DMG:
[1689139867.188117][10800:10802] CHIP:DMG: AttributeReportIB =
[1689139867.188168][10800:10802] CHIP:DMG: {
[1689139867.188208][10800:10802] CHIP:DMG: AttributeDataIB =
[1689139867.188268][10800:10802] CHIP:DMG: {
[1689139867.188314][10800:10802] CHIP:DMG: DataVersion = 0xf328d5ef,
[1689139867.188374][10800:10802] CHIP:DMG: AttributePathIB =
[1689139867.188424][10800:10802] CHIP:DMG: {
[1689139867.188487][10800:10802] CHIP:DMG: Endpoint = 0x0,
[1689139867.188541][10800:10802] CHIP:DMG: Cluster = 0x30,
[1689139867.188667][10800:10802] CHIP:DMG: Attribute = 0x0000_0000,
[1689139867.188723][10800:10802] CHIP:DMG: }
[1689139867.188795][10800:10802] CHIP:DMG:
[1689139867.188847][10800:10802] CHIP:DMG: Data = 0,
[1689139867.188905][10800:10802] CHIP:DMG: },
[1689139867.188954][10800:10802] CHIP:DMG:
[1689139867.189008][10800:10802] CHIP:DMG: },
[1689139867.189065][10800:10802] CHIP:DMG:
[1689139867.189106][10800:10802] CHIP:DMG: AttributeReportIB =
[1689139867.189171][10800:10802] CHIP:DMG: {
[1689139867.189211][10800:10802] CHIP:DMG: AttributeDataIB =
[1689139867.189275][10800:10802] CHIP:DMG: {
[1689139867.189323][10800:10802] CHIP:DMG: DataVersion = 0x287c0f6,
[1689139867.189385][10800:10802] CHIP:DMG: AttributePathIB =
[1689139867.189436][10800:10802] CHIP:DMG: {
[1689139867.189499][10800:10802] CHIP:DMG: Endpoint = 0x0,
[1689139867.189553][10800:10802] CHIP:DMG: Cluster = 0x31,
[1689139867.189606][10800:10802] CHIP:DMG: Attribute = 0x0000_FFFC,
[1689139867.189671][10800:10802] CHIP:DMG: }
[1689139867.189720][10800:10802] CHIP:DMG:
[1689139867.189785][10800:10802] CHIP:DMG: Data = 2,
[1689139867.189837][10800:10802] CHIP:DMG: },
[1689139867.189900][10800:10802] CHIP:DMG:
[1689139867.189941][10800:10802] CHIP:DMG: },
[1689139867.189988][10800:10802] CHIP:DMG:
[1689139867.190039][10800:10802] CHIP:DMG: ],
[1689139867.190145][10800:10802] CHIP:DMG:
[1689139867.190185][10800:10802] CHIP:DMG: SuppressResponse = true,
[1689139867.190240][10800:10802] CHIP:DMG: InteractionModelRevision = 1
[1689139867.190278][10800:10802] CHIP:DMG: }
[1689139867.191465][10800:10802] CHIP:CTL: ----- NetworkCommissioning Features: has Thread. endpointid = 0
[1689139867.191558][10800:10802] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo'
[1689139867.191605][10800:10802] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe'
[1689139867.191648][10800:10802] CHIP:CTL: Performing next commissioning step 'ArmFailSafe'
[1689139867.191682][10800:10802] CHIP:CTL: Arming failsafe (60 seconds)
[1689139867.191781][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
[1689139867.191826][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
[1689139867.192003][10800:10802] CHIP:EM: <<< [E:60473i M:147197895] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1689139867.192062][10800:10802] CHIP:IN: (S) Sending msg 147197895 on secure session with LSID: 56629
[1689139867.192207][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
[1689139867.276786][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139867.280516][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139867.280796][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139867.281077][10800:10802] CHIP:EM: >>> [E:60473i M:256335161] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1689139867.281145][10800:10802] CHIP:EM: Found matching exchange: 60473i, Delegate: 0xffffb4060ec8
[1689139867.281240][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
[1689139867.281328][10800:10802] CHIP:DMG: InvokeResponseMessage =
[1689139867.281374][10800:10802] CHIP:DMG: {
[1689139867.281420][10800:10802] CHIP:DMG: suppressResponse = false,
[1689139867.281469][10800:10802] CHIP:DMG: InvokeResponseIBs =
[1689139867.281552][10800:10802] CHIP:DMG: [
[1689139867.281602][10800:10802] CHIP:DMG: InvokeResponseIB =
[1689139867.281668][10800:10802] CHIP:DMG: {
[1689139867.281738][10800:10802] CHIP:DMG: CommandDataIB =
[1689139867.281804][10800:10802] CHIP:DMG: {
[1689139867.281878][10800:10802] CHIP:DMG: CommandPathIB =
[1689139867.281943][10800:10802] CHIP:DMG: {
[1689139867.282026][10800:10802] CHIP:DMG: EndpointId = 0x0,
[1689139867.282095][10800:10802] CHIP:DMG: ClusterId = 0x30,
[1689139867.282184][10800:10802] CHIP:DMG: CommandId = 0x1,
[1689139867.282249][10800:10802] CHIP:DMG: },
[1689139867.282316][10800:10802] CHIP:DMG:
[1689139867.282393][10800:10802] CHIP:DMG: CommandFields =
[1689139867.282456][10800:10802] CHIP:DMG: {
[1689139867.282540][10800:10802] CHIP:DMG: 0x0 = 0,
[1689139867.282616][10800:10802] CHIP:DMG: 0x1 = "" (0 chars),
[1689139867.282707][10800:10802] CHIP:DMG: },
[1689139867.282767][10800:10802] CHIP:DMG: },
[1689139867.282850][10800:10802] CHIP:DMG:
[1689139867.282902][10800:10802] CHIP:DMG: },
[1689139867.282981][10800:10802] CHIP:DMG:
[1689139867.283030][10800:10802] CHIP:DMG: ],
[1689139867.283091][10800:10802] CHIP:DMG:
[1689139867.283157][10800:10802] CHIP:DMG: InteractionModelRevision = 1
[1689139867.283205][10800:10802] CHIP:DMG: },
[1689139867.283342][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1689139867.283409][10800:10802] CHIP:CTL: Received ArmFailSafe response errorCode=0
[1689139867.283482][10800:10802] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe'
[1689139867.283526][10800:10802] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory'
[1689139867.283578][10800:10802] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory'
[1689139867.283640][10800:10802] CHIP:CTL: Setting Regulatory Config
[1689139867.283679][10800:10802] CHIP:CTL: Device does not support configurable regulatory location
[1689139867.283798][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
[1689139867.283857][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
[1689139867.284032][10800:10802] CHIP:EM: <<< [E:60474i M:147197896] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1689139867.284130][10800:10802] CHIP:IN: (S) Sending msg 147197896 on secure session with LSID: 56629
[1689139867.284298][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
[1689139867.284418][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
[1689139867.374134][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139867.377947][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139867.378237][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139867.378548][10800:10802] CHIP:EM: >>> [E:60474i M:256335162] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1689139867.378627][10800:10802] CHIP:EM: Found matching exchange: 60474i, Delegate: 0xffffb4061248
[1689139867.378708][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
[1689139867.378803][10800:10802] CHIP:DMG: InvokeResponseMessage =
[1689139867.378886][10800:10802] CHIP:DMG: {
[1689139867.378940][10800:10802] CHIP:DMG: suppressResponse = false,
[1689139867.378997][10800:10802] CHIP:DMG: InvokeResponseIBs =
[1689139867.379091][10800:10802] CHIP:DMG: [
[1689139867.379148][10800:10802] CHIP:DMG: InvokeResponseIB =
[1689139867.379245][10800:10802] CHIP:DMG: {
[1689139867.379307][10800:10802] CHIP:DMG: CommandDataIB =
[1689139867.379378][10800:10802] CHIP:DMG: {
[1689139867.379462][10800:10802] CHIP:DMG: CommandPathIB =
[1689139867.379535][10800:10802] CHIP:DMG: {
[1689139867.379635][10800:10802] CHIP:DMG: EndpointId = 0x0,
[1689139867.379716][10800:10802] CHIP:DMG: ClusterId = 0x30,
[1689139867.379815][10800:10802] CHIP:DMG: CommandId = 0x3,
[1689139867.379892][10800:10802] CHIP:DMG: },
[1689139867.379969][10800:10802] CHIP:DMG:
[1689139867.380056][10800:10802] CHIP:DMG: CommandFields =
[1689139867.380130][10800:10802] CHIP:DMG: {
[1689139867.380231][10800:10802] CHIP:DMG: 0x0 = 0,
[1689139867.380315][10800:10802] CHIP:DMG: 0x1 = "" (0 chars),
[1689139867.380421][10800:10802] CHIP:DMG: },
[1689139867.380494][10800:10802] CHIP:DMG: },
[1689139867.380598][10800:10802] CHIP:DMG:
[1689139867.380709][10800:10802] CHIP:DMG: },
[1689139867.380802][10800:10802] CHIP:DMG:
[1689139867.380859][10800:10802] CHIP:DMG: ],
[1689139867.380931][10800:10802] CHIP:DMG:
[1689139867.381008][10800:10802] CHIP:DMG: InteractionModelRevision = 1
[1689139867.381063][10800:10802] CHIP:DMG: },
[1689139867.381219][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003
[1689139867.381298][10800:10802] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0
[1689139867.381382][10800:10802] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory'
[1689139867.381434][10800:10802] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest'
[1689139867.381493][10800:10802] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest'
[1689139867.381562][10800:10802] CHIP:CTL: Sending request for PAI certificate
[1689139867.381608][10800:10802] CHIP:CTL: Sending Certificate Chain request to 0xffffac034a50 device
[1689139867.381719][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
[1689139867.381894][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
[1689139867.382096][10800:10802] CHIP:EM: <<< [E:60475i M:147197897] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1689139867.382214][10800:10802] CHIP:IN: (S) Sending msg 147197897 on secure session with LSID: 56629
[1689139867.382413][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
[1689139867.382555][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
[1689139867.471686][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139867.475382][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139867.475661][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139867.572535][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139867.572881][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139867.669962][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139867.670251][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139867.670576][10800:10802] CHIP:EM: >>> [E:60475i M:256335163] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1689139867.670654][10800:10802] CHIP:EM: Found matching exchange: 60475i, Delegate: 0xffffb4060ec8
[1689139867.670764][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
[1689139867.670863][10800:10802] CHIP:DMG: InvokeResponseMessage =
[1689139867.670917][10800:10802] CHIP:DMG: {
[1689139867.670971][10800:10802] CHIP:DMG: suppressResponse = false,
[1689139867.671028][10800:10802] CHIP:DMG: InvokeResponseIBs =
[1689139867.671125][10800:10802] CHIP:DMG: [
[1689139867.671183][10800:10802] CHIP:DMG: InvokeResponseIB =
[1689139867.671283][10800:10802] CHIP:DMG: {
[1689139867.671345][10800:10802] CHIP:DMG: CommandDataIB =
[1689139867.671437][10800:10802] CHIP:DMG: {
[1689139867.671506][10800:10802] CHIP:DMG: CommandPathIB =
[1689139867.671580][10800:10802] CHIP:DMG: {
[1689139867.671680][10800:10802] CHIP:DMG: EndpointId = 0x0,
[1689139867.671760][10800:10802] CHIP:DMG: ClusterId = 0x3e,
[1689139867.671854][10800:10802] CHIP:DMG: CommandId = 0x3,
[1689139867.671926][10800:10802] CHIP:DMG: },
[1689139867.672003][10800:10802] CHIP:DMG:
[1689139867.672091][10800:10802] CHIP:DMG: CommandFields =
[1689139867.672166][10800:10802] CHIP:DMG: {
[1689139867.672262][10800:10802] CHIP:DMG: 0x0 = [
[1689139867.672678][10800:10802] CHIP:DMG: 0x30, 0x82, 0x01, 0xdc, 0x30, 0x82, 0x01, 0x81, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x7f, 0x7e, 0xf3, 0xdb, 0x08, 0xa3, 0x8f, 0x68, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x30, 0x31, 0x18, 0x30, 0x16, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x0f, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x54, 0x65, 0x73, 0x74, 0x20, 0x50, 0x41, 0x41, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x31, 0x30, 0x36, 0x32, 0x38, 0x31, 0x34, 0x32, 0x33, 0x34, 0x33, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x4d, 0x31, 0x1f, 0x30, 0x1d, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x16, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x65, 0x6c, 0x6f, 0x70, 0x6d, 0x65, 0x6e, 0x74, 0x20, 0x50, 0x41, 0x49, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x36, 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, 0x42, 0x93, 0x55, 0x77, 0x35, 0x35, 0x15, 0xce, 0xfa, 0x8f, 0x0e, 0x30, 0xe2, 0x34, 0x7e, 0x90, 0xee, 0xd6, 0xfd, 0x51, 0x5b, 0xe8, 0x82, 0xd1, 0xbc, 0xba, 0x74, 0x83, 0xd7, 0xff, 0x6e, 0xf4, 0xde, 0xdf, 0x98, 0xf7, 0xf7, 0x4f, 0x17, 0x42, 0x1d, 0xe5, 0x45, 0x0c, 0xff, 0xfb, 0x3e, 0x7f, 0x6d, 0x4f, 0x62, 0x28, 0x53, 0x41, 0x14, 0xfb, 0xb8, 0x5c, 0x
[1689139867.672856][10800:10802] CHIP:DMG: ] (480 bytes)
[1689139867.672937][10800:10802] CHIP:DMG: },
[1689139867.673008][10800:10802] CHIP:DMG: },
[1689139867.673086][10800:10802] CHIP:DMG:
[1689139867.673146][10800:10802] CHIP:DMG: },
[1689139867.673217][10800:10802] CHIP:DMG:
[1689139867.673301][10800:10802] CHIP:DMG: ],
[1689139867.673375][10800:10802] CHIP:DMG:
[1689139867.673431][10800:10802] CHIP:DMG: InteractionModelRevision = 1
[1689139867.673508][10800:10802] CHIP:DMG: },
[1689139867.673669][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1689139867.673744][10800:10802] CHIP:CTL: Received certificate chain from the device
[1689139867.673807][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest'
[1689139867.673867][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest'
[1689139867.673950][10800:10802] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest'
[1689139867.674001][10800:10802] CHIP:CTL: Sending request for DAC certificate
[1689139867.674047][10800:10802] CHIP:CTL: Sending Certificate Chain request to 0xffffac034a50 device
[1689139867.674183][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
[1689139867.674249][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
[1689139867.674459][10800:10802] CHIP:EM: <<< [E:60476i M:147197898] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1689139867.674569][10800:10802] CHIP:IN: (S) Sending msg 147197898 on secure session with LSID: 56629
[1689139867.674770][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
[1689139867.674912][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
[1689139867.763751][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139867.767889][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139867.768167][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139867.865038][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139867.865326][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139867.962507][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139867.962807][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139867.963145][10800:10802] CHIP:EM: >>> [E:60476i M:256335164] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1689139867.963222][10800:10802] CHIP:EM: Found matching exchange: 60476i, Delegate: 0xffffb4061248
[1689139867.963332][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
[1689139867.963434][10800:10802] CHIP:DMG: InvokeResponseMessage =
[1689139867.963490][10800:10802] CHIP:DMG: {
[1689139867.963545][10800:10802] CHIP:DMG: suppressResponse = false,
[1689139867.963602][10800:10802] CHIP:DMG: InvokeResponseIBs =
[1689139867.963701][10800:10802] CHIP:DMG: [
[1689139867.963761][10800:10802] CHIP:DMG: InvokeResponseIB =
[1689139867.963860][10800:10802] CHIP:DMG: {
[1689139867.963923][10800:10802] CHIP:DMG: CommandDataIB =
[1689139867.963994][10800:10802] CHIP:DMG: {
[1689139867.964080][10800:10802] CHIP:DMG: CommandPathIB =
[1689139867.964156][10800:10802] CHIP:DMG: {
[1689139867.964252][10800:10802] CHIP:DMG: EndpointId = 0x0,
[1689139867.964333][10800:10802] CHIP:DMG: ClusterId = 0x3e,
[1689139867.964432][10800:10802] CHIP:DMG: CommandId = 0x3,
[1689139867.964508][10800:10802] CHIP:DMG: },
[1689139867.964711][10800:10802] CHIP:DMG:
[1689139867.964785][10800:10802] CHIP:DMG: CommandFields =
[1689139867.964881][10800:10802] CHIP:DMG: {
[1689139867.964959][10800:10802] CHIP:DMG: 0x0 = [
[1689139867.965357][10800:10802] CHIP:DMG: 0x30, 0x82, 0x01, 0xf7, 0x30, 0x82, 0x01, 0x9d, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x46, 0x88, 0xeb, 0x94, 0xad, 0x32, 0xb2, 0xe4, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x4d, 0x31, 0x1f, 0x30, 0x1d, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x16, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x65, 0x6c, 0x6f, 0x70, 0x6d, 0x65, 0x6e, 0x74, 0x20, 0x50, 0x41, 0x49, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x36, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x31, 0x30, 0x36, 0x32, 0x38, 0x31, 0x34, 0x32, 0x33, 0x34, 0x33, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x52, 0x31, 0x24, 0x30, 0x22, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1b, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x65, 0x6c, 0x6f, 0x70, 0x6d, 0x65, 0x6e, 0x74, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x30, 0x30, 0x30, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x36, 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, 0xc5, 0x65, 0xfd, 0xad, 0xfd, 0x16, 0xdd, 0x62, 0xe4, 0x3f, 0x19, 0x60, 0xb9, 0x93, 0xbb, 0x57, 0x2c, 0xfd, 0xd8, 0x1f, 0x6d, 0x71, 0x67, 0x67, 0x
[1689139867.965534][10800:10802] CHIP:DMG: ] (507 bytes)
[1689139867.965613][10800:10802] CHIP:DMG: },
[1689139867.965683][10800:10802] CHIP:DMG: },
[1689139867.965762][10800:10802] CHIP:DMG:
[1689139867.965842][10800:10802] CHIP:DMG: },
[1689139867.965916][10800:10802] CHIP:DMG:
[1689139867.965972][10800:10802] CHIP:DMG: ],
[1689139867.966044][10800:10802] CHIP:DMG:
[1689139867.966128][10800:10802] CHIP:DMG: InteractionModelRevision = 1
[1689139867.966183][10800:10802] CHIP:DMG: },
[1689139867.966340][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1689139867.966415][10800:10802] CHIP:CTL: Received certificate chain from the device
[1689139867.966498][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest'
[1689139867.966559][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest'
[1689139867.966622][10800:10802] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest'
[1689139867.966693][10800:10802] CHIP:CTL: Sending Attestation Request to the device.
[1689139867.966741][10800:10802] CHIP:CTL: Sending Attestation request to 0xffffac034a50 device
[1689139867.966879][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
[1689139867.966948][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
[1689139867.967163][10800:10802] CHIP:EM: <<< [E:60477i M:147197899] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1689139867.967279][10800:10802] CHIP:IN: (S) Sending msg 147197899 on secure session with LSID: 56629
[1689139867.967480][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
[1689139867.967551][10800:10802] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information
[1689139867.967669][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
[1689139868.056690][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139868.206321][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139868.206619][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139868.303822][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139868.304110][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139868.401367][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139868.401653][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139868.402008][10800:10802] CHIP:EM: >>> [E:60477i M:256335165] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1689139868.402086][10800:10802] CHIP:EM: Found matching exchange: 60477i, Delegate: 0xffffb4060ec8
[1689139868.402203][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
[1689139868.402305][10800:10802] CHIP:DMG: InvokeResponseMessage =
[1689139868.402359][10800:10802] CHIP:DMG: {
[1689139868.402413][10800:10802] CHIP:DMG: suppressResponse = false,
[1689139868.402495][10800:10802] CHIP:DMG: InvokeResponseIBs =
[1689139868.402572][10800:10802] CHIP:DMG: [
[1689139868.402629][10800:10802] CHIP:DMG: InvokeResponseIB =
[1689139868.402730][10800:10802] CHIP:DMG: {
[1689139868.402791][10800:10802] CHIP:DMG: CommandDataIB =
[1689139868.402881][10800:10802] CHIP:DMG: {
[1689139868.402949][10800:10802] CHIP:DMG: CommandPathIB =
[1689139868.403043][10800:10802] CHIP:DMG: {
[1689139868.403122][10800:10802] CHIP:DMG: EndpointId = 0x0,
[1689139868.403202][10800:10802] CHIP:DMG: ClusterId = 0x3e,
[1689139868.403303][10800:10802] CHIP:DMG: CommandId = 0x1,
[1689139868.403376][10800:10802] CHIP:DMG: },
[1689139868.403475][10800:10802] CHIP:DMG:
[1689139868.403544][10800:10802] CHIP:DMG: CommandFields =
[1689139868.403637][10800:10802] CHIP:DMG: {
[1689139868.403714][10800:10802] CHIP:DMG: 0x0 = [
[1689139868.404103][10800:10802] CHIP:DMG: 0x15, 0x31, 0x01, 0x1d, 0x02, 0x30, 0x82, 0x02, 0x19, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x02, 0xa0, 0x82, 0x02, 0x0a, 0x30, 0x82, 0x02, 0x06, 0x02, 0x01, 0x03, 0x31, 0x0d, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x82, 0x01, 0x71, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x01, 0xa0, 0x82, 0x01, 0x62, 0x04, 0x82, 0x01, 0x5e, 0x15, 0x24, 0x00, 0x01, 0x25, 0x01, 0xf1, 0xff, 0x36, 0x02, 0x05, 0x00, 0x80, 0x05, 0x01, 0x80, 0x05, 0x02, 0x80, 0x05, 0x03, 0x80, 0x05, 0x04, 0x80, 0x05, 0x05, 0x80, 0x05, 0x06, 0x80, 0x05, 0x07, 0x80, 0x05, 0x08, 0x80, 0x05, 0x09, 0x80, 0x05, 0x0a, 0x80, 0x05, 0x0b, 0x80, 0x05, 0x0c, 0x80, 0x05, 0x0d, 0x80, 0x05, 0x0e, 0x80, 0x05, 0x0f, 0x80, 0x05, 0x10, 0x80, 0x05, 0x11, 0x80, 0x05, 0x12, 0x80, 0x05, 0x13, 0x80, 0x05, 0x14, 0x80, 0x05, 0x15, 0x80, 0x05, 0x16, 0x80, 0x05, 0x17, 0x80, 0x05, 0x18, 0x80, 0x05, 0x19, 0x80, 0x05, 0x1a, 0x80, 0x05, 0x1b, 0x80, 0x05, 0x1c, 0x80, 0x05, 0x1d, 0x80, 0x05, 0x1e, 0x80, 0x05, 0x1f, 0x80, 0x05, 0x20, 0x80, 0x05, 0x21, 0x80, 0x05, 0x22, 0x80, 0x05, 0x23, 0x80, 0x05, 0x24, 0x80, 0x05, 0x25, 0x80, 0x05, 0x26, 0x80, 0x05, 0x27, 0x80, 0x05, 0x28, 0x80, 0x05, 0x29, 0x80, 0x05, 0x2a, 0x80, 0x05, 0x2b, 0x80, 0x05, 0x2c, 0x80, 0x05, 0x2d, 0x80, 0x05, 0x2e, 0x80, 0x05, 0x2f, 0x80, 0x05, 0x30, 0x80, 0x05, 0x31, 0x80, 0x05, 0x32, 0x80, 0x05, 0x33, 0x80, 0x05, 0x34, 0x80, 0x05, 0x35, 0x80, 0x05, 0x36, 0x80, 0x05, 0x37, 0x80, 0x05, 0x38, 0x80, 0x05, 0x39, 0x80, 0x05, 0x3a, 0x80, 0x05, 0x3b, 0x80, 0x05, 0x3c, 0x80, 0x05, 0x3d, 0x80, 0x05, 0x3e, 0x80, 0x05, 0x3f, 0x80, 0x05, 0x40, 0x80, 0x05, 0x41, 0x80, 0x05, 0x42, 0x80, 0x05, 0x43, 0x80, 0x
[1689139868.404302][10800:10802] CHIP:DMG: ] (585 bytes)
[1689139868.404382][10800:10802] CHIP:DMG: 0x1 = [
[1689139868.404514][10800:10802] CHIP:DMG: 0xb9, 0x3a, 0x86, 0x97, 0xff, 0x10, 0xff, 0x43, 0x0c, 0x49, 0x6a, 0x04, 0xee, 0xe4, 0x63, 0xc4, 0x83, 0x80, 0x06, 0x04, 0x82, 0x4c, 0xc5, 0x12, 0x0a, 0x1d, 0xb9, 0xdd, 0x59, 0x44, 0x0a, 0x5e, 0x3a, 0x82, 0x5e, 0x7f, 0x01, 0xb1, 0x8f, 0xf7, 0x5a, 0x86, 0x54, 0xa5, 0x3e, 0xc4, 0xd9, 0xe7, 0x59, 0xd8, 0x6d, 0xae, 0x74, 0x70, 0xb4, 0x66, 0x3e, 0xd1, 0xd0, 0x38, 0xf1, 0xfe, 0xde, 0x77,
[1689139868.404671][10800:10802] CHIP:DMG: ] (64 bytes)
[1689139868.404752][10800:10802] CHIP:DMG: },
[1689139868.404821][10800:10802] CHIP:DMG: },
[1689139868.404927][10800:10802] CHIP:DMG:
[1689139868.404988][10800:10802] CHIP:DMG: },
[1689139868.405060][10800:10802] CHIP:DMG:
[1689139868.405138][10800:10802] CHIP:DMG: ],
[1689139868.405211][10800:10802] CHIP:DMG:
[1689139868.405286][10800:10802] CHIP:DMG: InteractionModelRevision = 1
[1689139868.405343][10800:10802] CHIP:DMG: },
[1689139868.405503][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001
[1689139868.405580][10800:10802] CHIP:CTL: Received Attestation Information from the device
[1689139868.405665][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest'
[1689139868.405723][10800:10802] CHIP:CTL: AutoCommissioner setting attestationElements buffer size 585/585
[1689139868.405850][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification'
[1689139868.405916][10800:10802] CHIP:CTL: Performing next commissioning step 'AttestationVerification'
[1689139868.405965][10800:10802] CHIP:CTL: Verifying attestation
[1689139868.443997][10800:10802] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device.
[1689139868.444080][10800:10802] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification'
[1689139868.444101][10800:10802] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest'
[1689139868.444132][10800:10802] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest'
[1689139868.444154][10800:10802] CHIP:CTL: Sending CSR request to 0xffffac034a50 device
[1689139868.444227][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
[1689139868.444259][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
[1689139868.444404][10800:10802] CHIP:EM: <<< [E:60478i M:147197900] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1689139868.444456][10800:10802] CHIP:IN: (S) Sending msg 147197900 on secure session with LSID: 56629
[1689139868.444568][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
[1689139868.444645][10800:10802] CHIP:CTL: Sent CSR request, waiting for the CSR
[1689139868.444734][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
[1689139868.544347][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139869.815289][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139869.815594][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139869.912680][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139869.912979][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139869.913253][10800:10802] CHIP:EM: >>> [E:60478i M:256335166] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1689139869.913333][10800:10802] CHIP:EM: Found matching exchange: 60478i, Delegate: 0xffffb4061248
[1689139869.913420][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
[1689139869.913522][10800:10802] CHIP:DMG: InvokeResponseMessage =
[1689139869.913579][10800:10802] CHIP:DMG: {
[1689139869.913633][10800:10802] CHIP:DMG: suppressResponse = false,
[1689139869.913690][10800:10802] CHIP:DMG: InvokeResponseIBs =
[1689139869.913766][10800:10802] CHIP:DMG: [
[1689139869.913822][10800:10802] CHIP:DMG: InvokeResponseIB =
[1689139869.913901][10800:10802] CHIP:DMG: {
[1689139869.913961][10800:10802] CHIP:DMG: CommandDataIB =
[1689139869.914032][10800:10802] CHIP:DMG: {
[1689139869.914097][10800:10802] CHIP:DMG: CommandPathIB =
[1689139869.914172][10800:10802] CHIP:DMG: {
[1689139869.914247][10800:10802] CHIP:DMG: EndpointId = 0x0,
[1689139869.914327][10800:10802] CHIP:DMG: ClusterId = 0x3e,
[1689139869.914405][10800:10802] CHIP:DMG: CommandId = 0x5,
[1689139869.914480][10800:10802] CHIP:DMG: },
[1689139869.914559][10800:10802] CHIP:DMG:
[1689139869.914627][10800:10802] CHIP:DMG: CommandFields =
[1689139869.914701][10800:10802] CHIP:DMG: {
[1689139869.914776][10800:10802] CHIP:DMG: 0x0 = [
[1689139869.915062][10800:10802] CHIP:DMG: 0x15, 0x30, 0x01, 0xcd, 0x30, 0x81, 0xca, 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, 0xc2, 0x48, 0xa3, 0xfb, 0x15, 0xfd, 0xb3, 0x94, 0xf2, 0x3c, 0x80, 0x01, 0xad, 0x4d, 0x23, 0xd3, 0x12, 0xe4, 0x02, 0x58, 0x45, 0x3f, 0xd0, 0xfe, 0x98, 0xe4, 0xec, 0x37, 0x58, 0xbf, 0x09, 0x66, 0x0d, 0x99, 0xde, 0x34, 0xa7, 0xd2, 0x8f, 0x40, 0xc4, 0xb1, 0xf1, 0xad, 0xae, 0xde, 0x9a, 0xff, 0x8e, 0x6c, 0x18, 0xb7, 0x66, 0x2a, 0xe9, 0xe4, 0x68, 0xc6, 0xc2, 0x90, 0x21, 0xc0, 0x91, 0x30, 0xa0, 0x00, 0x30, 0x0c, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x05, 0x00, 0x03, 0x48, 0x00, 0x30, 0x45, 0x02, 0x21, 0x00, 0x9f, 0x86, 0xe7, 0x6a, 0x48, 0x3b, 0xa1, 0xaa, 0x1c, 0x05, 0xcc, 0x3f, 0xab, 0xf3, 0x92, 0x13, 0x3a, 0x4f, 0x95, 0xda, 0xab, 0xed, 0x45, 0xbc, 0x28, 0x13, 0xe8, 0x7f, 0x78, 0x5e, 0x20, 0xb2, 0x02, 0x20, 0x02, 0x9a, 0xd4, 0xee, 0xd8, 0xfd, 0x78, 0xa9, 0xe8, 0xa6, 0x21, 0x02, 0xbc, 0xe7, 0xc5, 0x56, 0x57, 0x2f, 0x5d, 0x36, 0xa6, 0x30, 0x83, 0x36, 0x40, 0xbe, 0xc8, 0x29, 0x05, 0xe6, 0xc9, 0xc4, 0x30, 0x02, 0x20, 0x5f, 0x73, 0x91, 0x64, 0x79, 0xeb, 0xb6, 0x39, 0xd8, 0x28, 0x8e, 0x64, 0x68, 0xc8, 0xf5, 0x96, 0xa9, 0x09, 0xf1, 0x38, 0x11, 0x36, 0xcb, 0x51, 0x70, 0xd4, 0x18, 0x9d, 0x51, 0x77, 0x1f, 0xdd, 0x18,
[1689139869.915228][10800:10802] CHIP:DMG: ] (245 bytes)
[1689139869.915309][10800:10802] CHIP:DMG: 0x1 = [
[1689139869.915440][10800:10802] CHIP:DMG: 0x59, 0xbd, 0x98, 0x38, 0xe3, 0xb4, 0xdc, 0xfc, 0x93, 0x1f, 0x71, 0x46, 0xa9, 0xe3, 0x4e, 0x43, 0x12, 0xac, 0xdc, 0x4b, 0xd1, 0x27, 0x1a, 0x17, 0x5d, 0x10, 0xfb, 0xe4, 0xb2, 0x3a, 0x4d, 0xcb, 0x61, 0xf3, 0x28, 0x70, 0x12, 0x2e, 0x70, 0x3f, 0xf0, 0x43, 0x15, 0x80, 0x74, 0x92, 0x75, 0x30, 0x6a, 0x28, 0x0a, 0xb5, 0xfb, 0x97, 0xb5, 0x09, 0x9e, 0x2b, 0x83, 0x71, 0x99, 0x4e, 0x97, 0x9f,
[1689139869.915538][10800:10802] CHIP:DMG: ] (64 bytes)
[1689139869.915617][10800:10802] CHIP:DMG: },
[1689139869.915687][10800:10802] CHIP:DMG: },
[1689139869.915765][10800:10802] CHIP:DMG:
[1689139869.915824][10800:10802] CHIP:DMG: },
[1689139869.915897][10800:10802] CHIP:DMG:
[1689139869.915953][10800:10802] CHIP:DMG: ],
[1689139869.916025][10800:10802] CHIP:DMG:
[1689139869.916080][10800:10802] CHIP:DMG: InteractionModelRevision = 1
[1689139869.916134][10800:10802] CHIP:DMG: },
[1689139869.916274][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005
[1689139869.916350][10800:10802] CHIP:CTL: Received certificate signing request from the device
[1689139869.916414][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendOpCertSigningRequest'
[1689139869.916466][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR'
[1689139869.916527][10800:10802] CHIP:CTL: Performing next commissioning step 'ValidateCSR'
[1689139869.925228][10800:10802] CHIP:CTL: Successfully finished commissioning step 'ValidateCSR'
[1689139869.925292][10800:10802] CHIP:CTL: Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain'
[1689139869.925328][10800:10802] CHIP:CTL: Performing next commissioning step 'GenerateNOCChain'
[1689139869.925355][10800:10802] CHIP:CTL: Getting certificate chain for the device from the issuer
[1689139869.930035][10800:10802] CHIP:CTL: Verifying Certificate Signing Request
[1689139869.932973][10800:10802] CHIP:CTL: Generating NOC
[1689139869.933358][10800:10802] CHIP:CTL: Providing certificate chain to the commissioner
[1689139869.933395][10800:10802] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status ../examples/chip-tool/third_party/connectedhomeip/src/controller/ExampleOperationalCredentialsIssuer.cpp:396: Success
[1689139869.933432][10800:10802] CHIP:CTL: Successfully finished commissioning step 'GenerateNOCChain'
[1689139869.933622][10800:10802] CHIP:CTL: Performing next commissioning step 'SendTrustedRootCert'
[1689139869.933652][10800:10802] CHIP:CTL: Sending root certificate to the device
[1689139869.933731][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
[1689139869.933764][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
[1689139869.933896][10800:10802] CHIP:EM: <<< [E:60479i M:147197901] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1689139869.933943][10800:10802] CHIP:IN: (S) Sending msg 147197901 on secure session with LSID: 56629
[1689139869.934059][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
[1689139869.934091][10800:10802] CHIP:CTL: Sent root certificate to the device
[1689139869.934602][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
[1689139870.007009][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139870.104087][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139870.351076][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139870.351322][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139870.351595][10800:10802] CHIP:EM: >>> [E:60479i M:256335167] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1689139870.351656][10800:10802] CHIP:EM: Found matching exchange: 60479i, Delegate: 0xffffb4060ec8
[1689139870.351737][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
[1689139870.351815][10800:10802] CHIP:DMG: InvokeResponseMessage =
[1689139870.351856][10800:10802] CHIP:DMG: {
[1689139870.351898][10800:10802] CHIP:DMG: suppressResponse = false,
[1689139870.351940][10800:10802] CHIP:DMG: InvokeResponseIBs =
[1689139870.352015][10800:10802] CHIP:DMG: [
[1689139870.352059][10800:10802] CHIP:DMG: InvokeResponseIB =
[1689139870.352133][10800:10802] CHIP:DMG: {
[1689139870.352180][10800:10802] CHIP:DMG: CommandStatusIB =
[1689139870.352231][10800:10802] CHIP:DMG: {
[1689139870.352301][10800:10802] CHIP:DMG: CommandPathIB =
[1689139870.352359][10800:10802] CHIP:DMG: {
[1689139870.352432][10800:10802] CHIP:DMG: EndpointId = 0x0,
[1689139870.352493][10800:10802] CHIP:DMG: ClusterId = 0x3e,
[1689139870.352565][10800:10802] CHIP:DMG: CommandId = 0xb,
[1689139870.352655][10800:10802] CHIP:DMG: },
[1689139870.352719][10800:10802] CHIP:DMG:
[1689139870.352785][10800:10802] CHIP:DMG: StatusIB =
[1689139870.352842][10800:10802] CHIP:DMG: {
[1689139870.352915][10800:10802] CHIP:DMG: status = 0x00 (SUCCESS),
[1689139870.352972][10800:10802] CHIP:DMG: },
[1689139870.353044][10800:10802] CHIP:DMG:
[1689139870.353095][10800:10802] CHIP:DMG: },
[1689139870.353151][10800:10802] CHIP:DMG:
[1689139870.353213][10800:10802] CHIP:DMG: },
[1689139870.353264][10800:10802] CHIP:DMG:
[1689139870.353305][10800:10802] CHIP:DMG: ],
[1689139870.353374][10800:10802] CHIP:DMG:
[1689139870.353415][10800:10802] CHIP:DMG: InteractionModelRevision = 1
[1689139870.353472][10800:10802] CHIP:DMG: },
[1689139870.353573][10800:10802] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0
[1689139870.353636][10800:10802] CHIP:CTL: Device confirmed that it has received the root certificate
[1689139870.353683][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendTrustedRootCert'
[1689139870.353722][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC'
[1689139870.353784][10800:10802] CHIP:CTL: Performing next commissioning step 'SendNOC'
[1689139870.353889][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
[1689139870.353967][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
[1689139870.354175][10800:10802] CHIP:EM: <<< [E:60480i M:147197902] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1689139870.354256][10800:10802] CHIP:IN: (S) Sending msg 147197902 on secure session with LSID: 56629
[1689139870.354407][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
[1689139870.354475][10800:10802] CHIP:CTL: Sent operational certificate to the device
[1689139870.354551][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
[1689139870.445735][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139870.543064][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139870.640587][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139871.375258][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139871.375576][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139871.375872][10800:10802] CHIP:EM: >>> [E:60480i M:256335168] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1689139871.375952][10800:10802] CHIP:EM: Found matching exchange: 60480i, Delegate: 0xffffb4061248
[1689139871.376035][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
[1689139871.376133][10800:10802] CHIP:DMG: InvokeResponseMessage =
[1689139871.376191][10800:10802] CHIP:DMG: {
[1689139871.376244][10800:10802] CHIP:DMG: suppressResponse = false,
[1689139871.376301][10800:10802] CHIP:DMG: InvokeResponseIBs =
[1689139871.376374][10800:10802] CHIP:DMG: [
[1689139871.376431][10800:10802] CHIP:DMG: InvokeResponseIB =
[1689139871.376509][10800:10802] CHIP:DMG: {
[1689139871.376570][10800:10802] CHIP:DMG: CommandDataIB =
[1689139871.376696][10800:10802] CHIP:DMG: {
[1689139871.376769][10800:10802] CHIP:DMG: CommandPathIB =
[1689139871.376844][10800:10802] CHIP:DMG: {
[1689139871.376921][10800:10802] CHIP:DMG: EndpointId = 0x0,
[1689139871.377000][10800:10802] CHIP:DMG: ClusterId = 0x3e,
[1689139871.377085][10800:10802] CHIP:DMG: CommandId = 0x8,
[1689139871.377156][10800:10802] CHIP:DMG: },
[1689139871.377234][10800:10802] CHIP:DMG:
[1689139871.377300][10800:10802] CHIP:DMG: CommandFields =
[1689139871.377374][10800:10802] CHIP:DMG: {
[1689139871.377451][10800:10802] CHIP:DMG: 0x0 = 0,
[1689139871.377531][10800:10802] CHIP:DMG: 0x1 = 1,
[1689139871.377609][10800:10802] CHIP:DMG: },
[1689139871.377678][10800:10802] CHIP:DMG: },
[1689139871.377752][10800:10802] CHIP:DMG:
[1689139871.377811][10800:10802] CHIP:DMG: },
[1689139871.377883][10800:10802] CHIP:DMG:
[1689139871.377938][10800:10802] CHIP:DMG: ],
[1689139871.378008][10800:10802] CHIP:DMG:
[1689139871.378063][10800:10802] CHIP:DMG: InteractionModelRevision = 1
[1689139871.378117][10800:10802] CHIP:DMG: },
[1689139871.378251][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008
[1689139871.378327][10800:10802] CHIP:CTL: Device returned status 0 on receiving the NOC
[1689139871.378379][10800:10802] CHIP:CTL: Operational credentials provisioned on device 0xffffac034a50
[1689139871.378428][10800:10802] CHIP:TOO: Secure Pairing Success
[1689139871.378472][10800:10802] CHIP:TOO: CASE establishment successful
[1689139871.378527][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendNOC'
[1689139871.378579][10800:10802] CHIP:CTL: No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks
[1689139871.378629][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendNOC' -> 'ThreadNetworkSetup'
[1689139871.378687][10800:10802] CHIP:CTL: Performing next commissioning step 'ThreadNetworkSetup'
[1689139871.378804][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
[1689139871.378961][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
[1689139871.379172][10800:10802] CHIP:EM: <<< [E:60481i M:147197903] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1689139871.379259][10800:10802] CHIP:IN: (S) Sending msg 147197903 on secure session with LSID: 56629
[1689139871.379452][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
[1689139871.379577][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
[1689139871.469499][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139871.566706][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1689139871.570271][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
[1689139871.570506][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1689139871.570768][10800:10802] CHIP:EM: >>> [E:60481i M:256335169] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1689139871.570839][10800:10802] CHIP:EM: Found matching exchange: 60481i, Delegate: 0xffffb4060ec8
[1689139871.570908][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
[1689139871.570990][10800:10802] CHIP:DMG: InvokeResponseMessage =
[1689139871.571032][10800:10802] CHIP:DMG: {
[1689139871.571177][10800:10802] CHIP:DMG: suppressResponse = false,
[1689139871.571220][10800:10802] CHIP:DMG: InvokeResponseIBs =
[1689139871.571275][10800:10802] CHIP:DMG: [
[1689139871.571317][10800:10802] CHIP:DMG: InvokeResponseIB =
[1689139871.571375][10800:10802] CHIP:DMG: {
[1689139871.571420][10800:10802] CHIP:DMG: CommandDataIB =
[1689139871.571471][10800:10802] CHIP:DMG: {
[1689139871.571524][10800:10802] CHIP:DMG: CommandPathIB =
[1689139871.571581][10800:10802] CHIP:DMG: {
[1689139871.571638][10800:10802] CHIP:DMG: EndpointId = 0x0,
[1689139871.571698][10800:10802] CHIP:DMG: ClusterId = 0x31,
[1689139871.571757][10800:10802] CHIP:DMG: CommandId = 0x5,
[1689139871.571813][10800:10802] CHIP:DMG: },
[1689139871.571871][10800:10802] CHIP:DMG:
[1689139871.571921][10800:10802] CHIP:DMG: CommandFields =
[1689139871.571977][10800:10802] CHIP:DMG: {
[1689139871.572043][10800:10802] CHIP:DMG: 0x0 = 1,
[1689139871.572099][10800:10802] CHIP:DMG: },
[1689139871.572155][10800:10802] CHIP:DMG: },
[1689139871.572212][10800:10802] CHIP:DMG:
[1689139871.572256][10800:10802] CHIP:DMG: },
[1689139871.572309][10800:10802] CHIP:DMG:
[1689139871.572350][10800:10802] CHIP:DMG: ],
[1689139871.572401][10800:10802] CHIP:DMG:
[1689139871.572442][10800:10802] CHIP:DMG: InteractionModelRevision = 1
[1689139871.572483][10800:10802] CHIP:DMG: },
[1689139871.572581][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005
[1689139871.572742][10800:10802] CHIP:CTL: Received NetworkConfig response, networkingStatus=1
[1689139871.572808][10800:10802] CHIP:CTL: Error on commissioning step 'ThreadNetworkSetup': '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error'
[1689139871.572850][10800:10802] CHIP:CTL: Failed to perform commissioning step 15
[1689139871.572897][10800:10802] CHIP:CTL: Going from commissioning step 'ThreadNetworkSetup' with lastErr = '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error' -> 'Cleanup'
[1689139871.572956][10800:10802] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error'
[1689139871.573000][10800:10802] CHIP:CTL: Successfully finished commissioning step 'Cleanup'
[1689139871.573054][10800:10802] CHIP:TOO: Device commissioning Failure: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error
[1689139871.573148][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
[1689139871.573511][10800:10800] CHIP:CTL: Shutting down the commissioner
[1689139871.573565][10800:10800] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1689139871.573760][10800:10800] CHIP:DIS: Closing all BLE connections
[1689139871.573804][10800:10800] CHIP:IN: Clearing BLE pending packets.
[1689139871.573910][10800:10800] CHIP:BLE: Auto-closing end point's BLE connection.
[1689139871.573952][10800:10800] CHIP:DL: Closing BLE GATT connection (con 0xffffb405e080)
[1689139871.574032][10800:10800] CHIP:IN: SecureSession[0xffffac014f10]: MarkForEviction Type:1 LSID:56629
[1689139871.574074][10800:10800] CHIP:SC: SecureSession[0xffffac014f10]: Moving from state 'kActive' --> 'kPendingEviction'
[1689139871.574154][10800:10800] CHIP:IN: SecureSession[0xffffac014f10]: Released - Type:1 LSID:56629
[1689139871.574204][10800:10800] CHIP:CTL: Shutting down the controller
[1689139871.574246][10800:10800] CHIP:IN: Expiring all sessions for fabric 0x1!!
[1689139871.574284][10800:10800] CHIP:FP: Forgetting fabric 0x1
[1689139871.574344][10800:10800] CHIP:TS: Pending Last Known Good Time: 2023-07-12T09:42:48
[1689139871.574391][10800:10801] CHIP:DL: BluezDisconnect peer=75:19:7E:C1:BD:91
[1689139871.574581][10800:10800] CHIP:TS: Previous Last Known Good Time: 2023-07-12T09:42:48
[1689139871.574632][10800:10800] CHIP:TS: Reverted Last Known Good Time to previous value
[1689139871.574701][10800:10800] CHIP:CTL: Shutting down the commissioner
[1689139871.574738][10800:10800] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1689139871.574933][10800:10800] CHIP:CTL: Shutting down the controller
[1689139871.574975][10800:10800] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack
[1689139871.575722][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
[1689139871.575777][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
[1689139871.575823][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
[1689139871.575865][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
[1689139871.575903][10800:10800] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet
[1689139871.576068][10800:10800] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented.
[1689139871.576126][10800:10800] CHIP:FP: Shutting down FabricTable
[1689139871.576174][10800:10800] CHIP:TS: Pending Last Known Good Time: 2023-07-12T09:42:48
[1689139871.576341][10800:10800] CHIP:TS: Previous Last Known Good Time: 2023-07-12T09:42:48
[1689139871.576389][10800:10800] CHIP:TS: Reverted Last Known Good Time to previous value
[1689139871.576832][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-whpZh2)
[1689139871.581286][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1689139871.581397][10800:10800] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1689139871.581442][10800:10800] CHIP:DL: Inet Layer shutdown
[1689139871.581482][10800:10800] CHIP:DL: BLE shutdown
[1689139871.583467][10800:10800] CHIP:DL: System Layer shutdown
[1689139873.616336][10800:10800] CHIP:TOO: Run command failure: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error
ubuntu@raspberrypi:~/matter/out$
Xshell 7 (Build 0099) Copyright (c) 2020 NetSarang Computer, Inc. All rights reserved. Type `help' to learn how to use Xshell prompt. [C:\~]$ Connecting to COM4... Connected. ÿOpenThread started: OK Setting OpenThread device type to MINIMAL END DEVICE Initialize Server Server initializing... Last Known Good Time: 2023-07-03T16:02:44 AccessControl: initializing Examples::AccessControlDelegate::Init AccessControl: setting DefaultAclStorage: initializing DefaultAclStorage: 0 entries loaded Using ZAP configuration... AccessControlCluster: initializing Initiating Admin Commissioning cluster. Door Lock server initialized Cluster callback: 0x0000_0030 Cluster callback: 0x0000_002B Updating services using commissioning mode 0 Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=0 Failed to advertise extended commissionable node: 3 Failed to finalize service update: 1c Delaying proxy of operational discovery: missing delegate CASE Server enabling CASE session setups Joining Multicast groups Server Listening... Initialize LEDs Initialize buttons Lock completed Device Configuration: Serial Number: TEST_SN Vendor Id: 65521 (0xFFF1) Product Id: 32774 (0x8006) Hardware Version: 0 Setup Pin Code (0 for UNKNOWN/ERROR): 20202021 Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00) Manufacturing Date: (not set) Device Type: 65535 (0xFFFF) Stopping the watchdog timer Starting the periodic query timer, timeout: 86400 seconds SetupQRCode: [MT:8IXS142C00KA0648G00] Copy/paste the below URL in a browser to see the QR Code: https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A8IXS142C00KA0648G00 Manual pairing code: [34970112332] Unlock initiated Cluster callback: 0x0000_0101 Door lock cluster: 0x0000_0101 Unlock completed Updating services using commissioning mode 1 Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=1 Failed to advertise commissionable node: 3 Failed to finalize service update: 1c Enabled BLE Advertisements local and remote recv window sizes = 6 selected BTP version 4 using BTP fragment sizes rx 244 / tx 244. >>> [E:60471r M:199606471] (U) Msg RX from 0:3DE92DF1A0FC06D4 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) <<< [E:60471r M:20804041] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) (U) Sending msg 20804041 to IP address 'BLE' >>> [E:60471r M:199606472] (U) Msg RX from 0:3DE92DF1A0FC06D4 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1) <<< [E:60471r M:20804042] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) (U) Sending msg 20804042 to IP address 'BLE' Long dispatch time: 5152 ms, for event type 7 >>> [E:60471r M:199606473] (U) Msg RX from 0:3DE92DF1A0FC06D4 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3) <<< [E:60471r M:20804043] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) (U) Sending msg 20804043 to IP address 'BLE' SecureSession[0x200121b8]: Moving from state 'kEstablishing' --> 'kActive' Commissioning completed session establishment step Updating services using commissioning mode 0 Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=0 Failed to advertise extended commissionable node: 3 Failed to finalize service update: 1c Device completed Rendezvous process >>> [E:60472r M:147197894] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) <<< [E:60472r M:256335160] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) (S) Sending msg 256335160 on secure session with LSID: 8216 >>> [E:60473r M:147197895] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) GeneralCommissioning: Received ArmFailSafe (60s) Cluster callback: 0x0000_0030 <<< [E:60473r M:256335161] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) (S) Sending msg 256335161 on secure session with LSID: 8216 >>> [E:60474r M:147197896] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) Cluster callback: 0x0000_0030 <<< [E:60474r M:256335162] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) (S) Sending msg 256335162 on secure session with LSID: 8216 >>> [E:60475r M:147197897] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) OpCreds: Certificate Chain request received for PAI <<< [E:60475r M:256335163] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) (S) Sending msg 256335163 on secure session with LSID: 8216 >>> [E:60476r M:147197898] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) OpCreds: Certificate Chain request received for DAC <<< [E:60476r M:256335164] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) (S) Sending msg 256335164 on secure session with LSID: 8216 >>> [E:60477r M:147197899] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) OpCreds: Received an AttestationRequest command OpCreds: AttestationRequest successful. <<< [E:60477r M:256335165] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) (S) Sending msg 256335165 on secure session with LSID: 8216 Long dispatch time: 159 ms, for event type 7 >>> [E:60478r M:147197900] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) OpCreds: Received a CSRRequest command OpCreds: AllocatePendingOperationalKey succeeded OpCreds: CSRRequest successful. <<< [E:60478r M:256335166] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) (S) Sending msg 256335166 on secure session with LSID: 8216 Long dispatch time: 1290 ms, for event type 7 >>> [E:60479r M:147197901] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) OpCreds: Received an AddTrustedRootCertificate command OpCreds: AddTrustedRootCertificate successful. <<< [E:60479r M:256335167] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) (S) Sending msg 256335167 on secure session with LSID: 8216 Long dispatch time: 278 ms, for event type 7 >>> [E:60480r M:147197902] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) OpCreds: Received an AddNOC command Validating NOC chain NOC chain validation successful Added new fabric at index: 0x1 Assigned compressed fabric ID: 0xB85C55CBB67A0454, node ID: 0x0000000000000001 Last Known Good Time: 2023-07-03T16:02:44 New proposed Last Known Good Time: 2021-01-01T00:00:00 Retaining current Last Known Good Time OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x000000000001B669 Advertise operational node B85C55CBB67A0454-0000000000000001 OpCreds: successfully created fabric index 0x1 via AddNOC <<< [E:60480r M:256335168] (S) Msg TX to 1:FFFFFFFB00000000 [0454] --- Type 0001:09 (IM:InvokeCommandResponse) (S) Sending msg 256335168 on secure session with LSID: 8216 Long dispatch time: 718 ms, for event type 7 >>> [E:60481r M:147197903] (S) Msg RX from 1:FFFFFFFB00000000 [0454] --- Type 0001:08 (IM:InvokeCommandRequest) <<< [E:60481r M:256335169] (S) Msg TX to 1:FFFFFFFB00000000 [0454] --- Type 0001:09 (IM:InvokeCommandResponse) (S) Sending msg 256335169 on secure session with LSID: 8216 Releasing end point's BLE connection back to application. Fail-safe timer expired Failsafe timer expired SecureSession[0x200121b8]: Moving from state 'kActive' --> 'kPendingEviction' Commissioning failed (attempt 1): 32 Updating services using commissioning mode 1 Advertise operational node B85C55CBB67A0454-0000000000000001 Failed to advertise operational node: 3 Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=1 Failed to advertise commissionable node: 3 Failed to finalize service update: 1c OpCreds: Got FailSafeTimerExpired OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry! Reverting pending fabric data for fabric 0x1 Warning: metadata not found during delete of fabric 0x1 Fabric (0x1) deleted. UserLabel: Last Fabric index 0x1 was removed OpCreds: Fabric index 0x1 was removed Updating services using commissioning mode 1 Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=1 Failed to advertise commissionable node: 3 Failed to finalize service update: 1c [OnFabricRemoved] Handling a fabric removal from the door lock server [endpointId=1,fabricIndex=1] Door Lock App: LockManager::GetUser [endpoint=1,userIndex=0] Door Lock App: LockManager::GetUser [endpoint=1,userIndex=1] Door Lock App: LockManager::GetUser [endpoint=1,userIndex=2] Door Lock App: LockManager::GetUser [endpoint=1,userIndex=3] Door Lock App: LockManager::GetUser [endpoint=1,userIndex=4] Door Lock App: LockManager::GetUser [endpoint=1,userIndex=5] Door Lock App: LockManager::GetUser [endpoint=1,userIndex=6] Door Lock App: LockManager::GetUser [endpoint=1,userIndex=7] Door Lock App: LockManager::GetUser [endpoint=1,userIndex=8] Door Lock App: LockManager::GetUser [endpoint=1,userIndex=9] Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=0 Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=1 Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=2 Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=3 Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=4 Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=5 Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=6 Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=7 Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=8 Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=9 Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=0 Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=1 Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=2 Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=3 Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=4 Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=5 Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=6 Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=7 Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=8 Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=9 Lock App: LockManager::GetCredential [credentialType=0], credentialIndex=0 Pending Last Known Good Time: 2023-07-03T16:02:44 Previous Last Known Good Time: 2023-07-03T16:02:44 Reverted Last Known Good Time to previous value Warning: metadata not found during delete of fabric 0x1 OpCreds: failed to delete fabric at index 1: d8 Cluster callback: 0x0000_0030 Long dispatch time: 504 ms, for event type 32782 Fail-safe cleanly disarmed
您好，
最直接的问题是matter chip-tool 的主机无法访问OTBR thread network。您可以查看chip-tool日志中的 "CHIP Error 0x000000AC: Internal error"
[1689139871.572581][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005
[1689139871.572742][10800:10802] CHIP:CTL: Received NetworkConfig response, networkingStatus=1
[1689139871.572808][10800:10802] CHIP:CTL: Error on commissioning step 'ThreadNetworkSetup': '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error'
[1689139871.572850][10800:10802] CHIP:CTL: Failed to perform commissioning step 15
[1689139871.572897][10800:10802] CHIP:CTL: Going from commissioning step 'ThreadNetworkSetup' with lastErr = '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error' -> 'Cleanup'
[1689139871.572956][10800:10802] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error'
[1689139871.573000][10800:10802] CHIP:CTL: Successfully finished commissioning step 'Cleanup'
[1689139871.573054][10800:10802] CHIP:TOO: Device commissioning Failure: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error
[1689139871.573148][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
[1689139871.573511][10800:10800] CHIP:CTL: Shutting down the commissioner
[1689139871.573565][10800:10800] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1689139871.573760][10800:10800] CHIP:DIS: Closing all BLE connections
[1689139871.573804][10800:10800] CHIP:IN: Clearing BLE pending packets.
[1689139871.573910][10800:10800] CHIP:BLE: Auto-closing end point's BLE connection.
[1689139871.573952][10800:10800] CHIP:DL: Closing BLE GATT connection (con 0xffffb405e080)
[1689139871.574032][10800:10800] CHIP:IN: SecureSession[0xffffac014f10]: MarkForEviction Type:1 LSID:56629
[1689139871.574074][10800:10800] CHIP:SC: SecureSession[0xffffac014f10]: Moving from state 'kActive' --> 'kPendingEviction'
[1689139871.574154][10800:10800] CHIP:IN: SecureSession[0xffffac014f10]: Released - Type:1 LSID:56629
[1689139871.574204][10800:10800] CHIP:CTL: Shutting down the controller
[1689139871.574246][10800:10800] CHIP:IN: Expiring all sessions for fabric 0x1!!
[1689139871.574284][10800:10800] CHIP:FP: Forgetting fabric 0x1
[1689139871.574344][10800:10800] CHIP:TS: Pending Last Known Good Time: 2023-07-12T09:42:48
[1689139871.574391][10800:10801] CHIP:DL: BluezDisconnect peer=75:19:7E:C1:BD:91
[1689139871.574581][10800:10800] CHIP:TS: Previous Last Known Good Time: 2023-07-12T09:42:48
[1689139871.574632][10800:10800] CHIP:TS: Reverted Last Known Good Time to previous value
[1689139871.574701][10800:10800] CHIP:CTL: Shutting down the commissioner
[1689139871.574738][10800:10800] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1689139871.574933][10800:10800] CHIP:CTL: Shutting down the controller
[1689139871.574975][10800:10800] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack
[1689139871.575722][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
[1689139871.575777][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
[1689139871.575823][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
[1689139871.575865][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
[1689139871.575903][10800:10800] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet
[1689139871.576068][10800:10800] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented.
[1689139871.576126][10800:10800] CHIP:FP: Shutting down FabricTable
[1689139871.576174][10800:10800] CHIP:TS: Pending Last Known Good Time: 2023-07-12T09:42:48
[1689139871.576341][10800:10800] CHIP:TS: Previous Last Known Good Time: 2023-07-12T09:42:48
[1689139871.576389][10800:10800] CHIP:TS: Reverted Last Known Good Time to previous value
[1689139871.576832][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-whpZh2)
[1689139871.581286][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1689139871.581397][10800:10800] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1689139871.581442][10800:10800] CHIP:DL: Inet Layer shutdown
[1689139871.581482][10800:10800] CHIP:DL: BLE shutdown
[1689139871.583467][10800:10800] CHIP:DL: System Layer shutdown
[1689139873.616336][10800:10800] CHIP:TOO: Run command failure: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error
如果您使用的是最新的TI matter repository，那么 CHIPDeviceController.cpp 会失败并在 OnNetworkConfigResponse 内报错 CHIP_ERROR_INTERNAL。
void DeviceCommissioner::OnNetworkConfigResponse(void * context,
const NetworkCommissioning::Commands::NetworkConfigResponse::DecodableType & data)
{
CommissioningDelegate::CommissioningReport report;
CHIP_ERROR err = CHIP_NO_ERROR;
ChipLogProgress(Controller, "Received NetworkConfig response, networkingStatus=%u", to_underlying(data.networkingStatus));
if (data.networkingStatus != NetworkCommissioning::NetworkCommissioningStatus::kSuccess)
{
err = CHIP_ERROR_INTERNAL;
report.Set<NetworkCommissioningStatusInfo>(data.networkingStatus);
}
DeviceCommissioner * commissioner = static_cast<DeviceCommissioner *>(context);
commissioner->CommissioningStageComplete(err, report);
}
您正在测试的树莓派上有多少可用 RAM？运行 OTBR 的是同一个设备吗？ Chip-tool 和OTBR是否连接到同一路由下？
./chip-tool pairing ble-thread <nodeID - e.g. 1> hex:<complete dataset from starting the OTBR> 20202021 3840
这条命令里面nodeID我应该写什么，是随便写一个还是，要根据matter Device的ID来写，例如下面是我的Matter Device信息
Device Configuration: Serial Number: TEST_SN Vendor Id: 65521 (0xFFF1) Product Id: 32774 (0x8006) Hardware Version: 0 Setup Pin Code (0 for UNKNOWN/ERROR): 20202021 Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00) Manufacturing Date: (not set) Device Type: 65535 (0xFFFF)
我应该在这个用Prodeuct id或者 Vendor Id来替换这个node ID吗
您好，
我曾经也使用过和您一样的软硬件去配置没有发生什么问题。我想我们来排除一些干扰。您使用的RCP是我们给的还是您自己编译的？您是否能启动OTBR并加入thread设备？您可以使用 Thread Border Router Setup SimpleLink Academy Lab (Task 3到Task 5) 和 Thread 示例来做评估。树莓派是通过 WiFi 还是网线连接到路由器？
最新的chiptool的指令模式略有更新所以您需要将 "hex:" 放在Thread 操作数据集之前。
之前我由遇到过相同的错误，因为chip-tool日志并没有指出错误，但在调试第15步（查找 Thread 网络）时会失败。所以您的命令代码应如下：
./chip-tool pairing ble-thread 1 hex:000300000f35060004001fffe00208dead00beef00cafe0708fde2f55f8122e830051000112233445566778899aabbccddeeff030f4f70656e5468726561642d37386237010212340410104810e2315100afd6bc9215a6bfac530c0402a0f7f80e080000000000010000 20202021 3840
这是matter设备打印出来的日志
ÿOpenThread ifconfig up and thread start OpenThread started: OK Setting OpenThread device type to MINIMAL END DEVICE Initialize Server Server initializing... Last Known Good Time: 2023-07-03T16:02:44 Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xB07ED91A051673C2, FabricId 0x0000000000000001, NodeId 0x0000000000000001, VendorId 0xFFF1 AccessControl: initializing Examples::AccessControlDelegate::Init AccessControl: setting DefaultAclStorage: initializing DefaultAclStorage: 1 entries loaded Using ZAP configuration... AccessControlCluster: initializing Initiating Admin Commissioning cluster. Door Lock server initialized Cluster callback: 0x0000_0030 Cluster callback: 0x0000_002B Fabric already commissioned. Disabling BLE advertisement Updating services using commissioning mode 0 Advertise operational node B07ED91A051673C2-0000000000000001 Failed to advertise operational node: 3 Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=0 Failed to advertise extended commissionable node: 3 Failed to finalize service update: 1c Delaying proxy of operational discovery: missing delegate CASE Server enabling CASE session setups Joining Multicast groups Server Listening... Initialize LEDs Initialize buttons Unlock completed Device Configuration: Serial Number: TEST_SN Vendor Id: 65521 (0xFFF1) Product Id: 32774 (0x8006) Hardware Version: 0 Setup Pin Code (0 for UNKNOWN/ERROR): 20202021 Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00) Manufacturing Date: (not set) Device Type: 65535 (0xFFFF) Stopping the watchdog timer Starting the periodic query timer, timeout: 86400 seconds SRP Client was started, detected server: fde2:f55f:8122:e830:6366:0c88IXS142C00KA0Cluster callback: 0x0000_002A Cluster callback: 0x0000_002A Setting operational delegate post init Updating services using commissioning mode 0 Advertise operational node B07ED91A051673C2-0000000000000001 advertising srp service: B07ED91A051673C2-0000000000000001._matter._tcp Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=0 advertising srp service: 3812229523780283._matterc._udp
您好，
从您的matter 设备log来说没有发现什么明显的问题。您可以使用额外的log记录来看是否有记录下一些意外情况:matter\examples\lock-app\cc13x2x7_26x2x7\args.gni
chip_progress_logging = true
chip_detail_logging = true
chip_automation_logging = true
wifi不是必须的，但是您的设备必须连接到路由器，没有互联网连接也没事。
您好
前三张图表示与OTBR建立连接时存在问题，就像之前的chip-tool logs所表示的一样。
后面的几张图表示Matter设备测试成功。
README中提供了chip-tool与lock-app示例交互的示例。您也可以参考CHIP Tool Guide。如果节点是endpoint1，那么可以将这两个参数设置为一个参数来测试您的环境是否正确。
您好，
这个命令里面的attribute-values是什么
这取决于所引用的attribute-name，CHIP Tool Guide 第8章中有一节就是关于读取Matter设备信息，the lock-app README 提供了写入属性的示例（锁门和开锁）。您还可以参考Matter设备规范，了解有关可能的设备类型、属性和命令的更多信息。
我运行./chiptool doorlock lock-door 10 endpoint确实不知道怎么获取，没看到教程提到
您这边运行什么，获取什么，能明确一下吗？
这张图表示我连接成功了 我在下次要调试的时候还需要重连吗 比如我的边界路由和设备关机了之后 我想重新调试 应该怎么操作