Replies: 2 comments
-
Check out this Running OTBR using Docker guide, which should also support the Matter use case. |
Beta Was this translation helpful? Give feedback.
-
I tried to follow that guide, but when I run the chip-tool now I got: [1702038669.285248][1707:1707] CHIP:TOO: Run command failure: src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable This was basically my steps:
Result: I guess that it might be related to how the networking for the Docker container is working. |
Beta Was this translation helpful? Give feedback.
-
I'm struggling to get OpenThread Border Router running in a Docker container on a Raspberry Pi 4 to work with Matter over Thread devices.
I have installed a Raspberry Pi 4 with the latest version of Raspberry Pi OS and Docker by following the guide here:
https://openthread.io/guides/border-router/docker
I'm using a Silicon Labs EFR32xG24 Dev Kit Board as RCP.
When the docker container is up and running, I can launch the OTBR Web GUI on http://ipAddress:8080 and Form new new Thread network. This works fine.
I get the Thread dataset with the command:
docker exec -it 0f0868c7e5a4 sh -c "sudo ot-ctl dataset active -x"
Then I try (from another computer):
$ /chip-tool pairing ble-thread 5535 hex:0e08000000000001000035060004001fffe00708fdfde6a8889b83ba0c0402a0f7f8051000112233445566778899aabbccddeeff030e4f70656e54687265616444656d6f0410445f2b5ca6f2a93a55ce570a70efeecb000300000f0208111111112222222201021234 20202021 3840
This does not succeed.
Do I miss some configuration step?
If I run OTBR directly on the Raspberry Pi 4, it's working.
Here's the output from the chip-too command:
./chip-tool pairing ble-thread 5535 hex:0e08000000000001000035060004001f
ffe00708fdfde6a8889b83ba0c0402a0f7f8051000112233445566778899aabbccddeeff030e4f70656e54687265616444656d6f0410445f2b5ca6f2
a93a55ce570a70efeecb000300000f0208111111112222222201021234 20202021 3840
[1701713254.706945][1919:1919] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
[1701713254.707179][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_kvs-8zX2m7)
[1701713254.707391][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1701713254.712729][1919:1919] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini
[1701713254.712982][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-b2hzIg)
[1701713254.713156][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
[1701713254.713262][1919:1919] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini
[1701713254.713371][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_config.ini-JcFaQ6)
[1701713254.713489][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1701713254.713575][1919:1919] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini
[1701713254.713683][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-BupW3X)
[1701713254.713799][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1701713254.714011][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-YAkq9f)
[1701713254.714469][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
[1701713254.714518][1919:1919] CHIP:DL: NVS set: chip-factory/unique-id = "9E9E54323961D1E6"
[1701713254.714652][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-DFBnGF)
[1701713254.715478][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
[1701713254.715532][1919:1919] CHIP:DL: NVS set: chip-factory/vendor-id = 65521 (0xFFF1)
[1701713254.715672][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-NYZPOr)
[1701713254.716111][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
[1701713254.716162][1919:1919] CHIP:DL: NVS set: chip-factory/product-id = 32769 (0x8001)
[1701713254.716290][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-uCMU9Q)
[1701713254.716659][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1701713254.716711][1919:1919] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1)
[1701713254.716836][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-P8ENyp)
[1701713254.717241][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1701713254.717288][1919:1919] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1701713254.717416][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-uva2uv)
[1701713254.717824][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1701713254.717878][1919:1919] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0)
[1701713254.718031][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_config.ini-pl7VxW)
[1701713254.718415][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1701713254.718463][1919:1919] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0)
[1701713254.718590][1919:1919] CHIP:DL: writing settings to file (/tmp/chip_config.ini-Y2AH6V)
[1701713254.718985][1919:1919] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1701713254.719032][1919:1919] CHIP:DL: NVS set: chip-config/location-capability = 2 (0x2)
[1701713254.719565][1919:1919] CHIP:DL: Got Ethernet interface: eth0
[1701713254.719874][1919:1919] CHIP:DL: Found the primary Ethernet interface:eth0
[1701713254.720224][1919:1919] CHIP:DL: Got WiFi interface: wlan0
[1701713254.720286][1919:1919] CHIP:DL: Failed to reset WiFi statistic counts
[1701713254.724419][1919:1919] CHIP:IN: UDP::Init bind&listen port=0
[1701713254.724640][1919:1919] CHIP:IN: UDP::Init bound to port=33574
[1701713254.724666][1919:1919] CHIP:IN: UDP::Init bind&listen port=0
[1701713254.724791][1919:1919] CHIP:IN: UDP::Init bound to port=43522
[1701713254.724814][1919:1919] CHIP:IN: BLEBase::Init - setting/overriding transport
[1701713254.724833][1919:1919] CHIP:IN: TransportMgr initialized
[1701713254.724891][1919:1919] CHIP:FP: Initializing FabricTable from persistent storage
[1701713254.724940][1919:1919] CHIP:TS: Last Known Good Time: [unknown]
[1701713254.724968][1919:1919] CHIP:TS: Setting Last Known Good Time to firmware build time 2023-07-24T17:52:58
[1701713254.727502][1919:1919] CHIP:ZCL: Using ZAP configuration...
[1701713254.728549][1919:1919] CHIP:CTL: System State Initialized...
[1701713254.728647][1919:1919] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1701713254.728704][1919:1919] CHIP:CTL: Setting attestation nonce to random value
[1701713254.728764][1919:1919] CHIP:CTL: Setting CSR nonce to random value
[1701713254.728831][1919:1919] CHIP:IN: UDP::Init bind&listen port=5550
[1701713254.728973][1919:1919] CHIP:IN: UDP::Init bound to port=5550
[1701713254.729002][1919:1919] CHIP:IN: UDP::Init bind&listen port=5550
[1701713254.729123][1919:1919] CHIP:IN: UDP::Init bound to port=5550
[1701713254.729143][1919:1919] CHIP:IN: TransportMgr initialized
[1701713254.729386][1919:1921] CHIP:DL: CHIP task running
[1701713254.729477][1919:1921] CHIP:DL: HandlePlatformSpecificBLEEvent 32784
[1701713254.729986][1919:1921] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1701713254.730031][1919:1921] CHIP:CTL: Setting attestation nonce to random value
[1701713254.730194][1919:1921] CHIP:CTL: Setting CSR nonce to random value
[1701713254.730449][1919:1921] 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
[1701713254.731316][1919:1921] 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
[1701713254.735549][1919:1921] CHIP:CTL: Generating RCAC
[1701713254.741477][1919:1921] CHIP:CTL: Generating ICAC
[1701713254.743964][1919:1921] CHIP:CTL: Generating NOC
[1701713254.744726][1919:1921] CHIP:FP: Validating NOC chain
[1701713254.746843][1919:1921] CHIP:FP: NOC chain validation successful
[1701713254.751657][1919:1921] CHIP:FP: Added new fabric at index: 0x1
[1701713254.751698][1919:1921] CHIP:FP: Assigned compressed fabric ID: 0xA1CCBDDD6F29A946, node ID: 0x000000000001B669
[1701713254.751725][1919:1921] CHIP:TS: Last Known Good Time: 2023-07-24T17:52:58
[1701713254.751743][1919:1921] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00
[1701713254.751760][1919:1921] CHIP:TS: Retaining current Last Known Good Time
[1701713254.754459][1919:1921] CHIP:FP: Metadata for Fabric 0x1 persisted to storage.
[1701713254.756017][1919:1921] CHIP:TS: Committing Last Known Good Time to storage: 2023-07-24T17:52:58
[1701713254.757348][1919:1921] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: A1CCBDDD6F29A946)
[1701713254.757398][1919:1921] CHIP:IN: UDP::Init bind&listen port=5550
[1701713254.757505][1919:1921] CHIP:IN: UDP::Init bound to port=5550
[1701713254.757527][1919:1921] CHIP:IN: UDP::Init bind&listen port=5550
[1701713254.757622][1919:1921] CHIP:IN: UDP::Init bound to port=5550
[1701713254.757642][1919:1921] CHIP:IN: TransportMgr initialized
[1701713254.783325][1919:1921] CHIP:CTL: Setting thread operational dataset from parameters
[1701713254.783389][1919:1921] CHIP:CTL: Setting attestation nonce to random value
[1701713254.783444][1919:1921] CHIP:CTL: Setting CSR nonce to random value
[1701713254.783474][1919:1921] CHIP:CTL: Commission called for node ID 0x000000000000159F
[1701713254.787673][1919:1920] CHIP:DL: TRACE: Bus acquired for name C-077f
[1701713254.795587][1919:1921] CHIP:DL: PlatformBlueZInit init success
[1701713254.800526][1919:1920] CHIP:BLE: BLE removing known devices.
[1701713254.801948][1919:1920] CHIP:BLE: BLE initiating scan.
[1701713254.869100][1919:1920] CHIP:BLE: Device 57:6B:3E:5B:D5:1A does not look like a CHIP device.
[1701713254.934362][1919:1920] CHIP:BLE: Device 1B:6D:3F:85:98:9D does not look like a CHIP device.
[1701713255.001567][1919:1920] CHIP:BLE: Device 32:95:3D:A7:04:2E does not look like a CHIP device.
[1701713255.508277][1919:1920] CHIP:BLE: Device 4A:35:FB:51:17:ED does not look like a CHIP device.
[1701713255.509276][1919:1920] CHIP:BLE: Device FD:FA:50:7E:37:5A does not look like a CHIP device.
[1701713256.267997][1919:1920] CHIP:BLE: Device 4A:35:FB:51:17:ED does not look like a CHIP device.
[1701713258.952220][1919:1920] CHIP:BLE: Device 3D:82:56:BD:EC:8B does not look like a CHIP device.
[1701713260.145453][1919:1920] CHIP:BLE: Device C7:CE:46:61:20:18 does not look like a CHIP device.
[1701713264.813707][1919:1920] CHIP:DIS: Closing all BLE connections
^C
ubuntu@ubuntu:~/connectedhomeip/out/standalone$ ./chip-tool pairing ble-thread 5535 hex:0e08000000000001000035060004001fffe00708fdfde6a8889b83ba0c0402a0f7f8051000112233445566778899aabbccddeeff030e4f70656e54687265616444656d6f0410445f2b5ca6f2a93a55ce570a70efeecb000300000f0208111111112222222201021234 20202021 3840
[1701713320.070979][1952:1952] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
[1701713320.076453][1952:1952] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini
[1701713320.076726][1952:1952] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini
[1701713320.076846][1952:1952] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini
[1701713320.077160][1952:1952] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-dyhJj0)
[1701713320.077710][1952:1952] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1701713320.077767][1952:1952] CHIP:DL: NVS set: chip-counters/reboot-count = 2 (0x2)
[1701713320.078318][1952:1952] CHIP:DL: Got Ethernet interface: eth0
[1701713320.078643][1952:1952] CHIP:DL: Found the primary Ethernet interface:eth0
[1701713320.079001][1952:1952] CHIP:DL: Got WiFi interface: wlan0
[1701713320.079060][1952:1952] CHIP:DL: Failed to reset WiFi statistic counts
[1701713320.079132][1952:1952] CHIP:IN: UDP::Init bind&listen port=0
[1701713320.079237][1952:1952] CHIP:IN: UDP::Init bound to port=54269
[1701713320.079259][1952:1952] CHIP:IN: UDP::Init bind&listen port=0
[1701713320.079338][1952:1952] CHIP:IN: UDP::Init bound to port=49771
[1701713320.079386][1952:1952] CHIP:IN: BLEBase::Init - setting/overriding transport
[1701713320.079404][1952:1952] CHIP:IN: TransportMgr initialized
[1701713320.079432][1952:1952] CHIP:FP: Initializing FabricTable from persistent storage
[1701713320.079581][1952:1952] CHIP:TS: Last Known Good Time: 2023-07-24T17:52:58
[1701713320.081001][1952:1952] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xA1CCBDDD6F29A946, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1
[1701713320.082679][1952:1952] CHIP:ZCL: Using ZAP configuration...
[1701713320.083753][1952:1952] CHIP:CTL: System State Initialized...
[1701713320.083817][1952:1952] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1701713320.083858][1952:1952] CHIP:CTL: Setting attestation nonce to random value
[1701713320.083903][1952:1952] CHIP:CTL: Setting CSR nonce to random value
[1701713320.083962][1952:1952] CHIP:IN: UDP::Init bind&listen port=5550
[1701713320.084041][1952:1952] CHIP:IN: UDP::Init bound to port=5550
[1701713320.084063][1952:1952] CHIP:IN: UDP::Init bind&listen port=5550
[1701713320.084133][1952:1952] CHIP:IN: UDP::Init bound to port=5550
[1701713320.084152][1952:1952] CHIP:IN: TransportMgr initialized
[1701713320.084441][1952:1954] CHIP:DL: CHIP task running
[1701713320.084541][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 32784
[1701713320.084860][1952:1954] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1701713320.084903][1952:1954] CHIP:CTL: Setting attestation nonce to random value
[1701713320.085054][1952:1954] CHIP:CTL: Setting CSR nonce to random value
[1701713320.085846][1952:1954] CHIP:CTL: Generating NOC
[1701713320.086687][1952:1954] CHIP:FP: Validating NOC chain
[1701713320.088304][1952:1954] CHIP:FP: NOC chain validation successful
[1701713320.088455][1952:1954] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669
[1701713320.088483][1952:1954] CHIP:TS: Last Known Good Time: 2023-07-24T17:52:58
[1701713320.088502][1952:1954] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00
[1701713320.088519][1952:1954] CHIP:TS: Retaining current Last Known Good Time
[1701713320.090174][1952:1954] CHIP:FP: Metadata for Fabric 0x1 persisted to storage.
[1701713320.091583][1952:1954] CHIP:TS: Committing Last Known Good Time to storage: 2023-07-24T17:52:58
[1701713320.092925][1952:1954] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: A1CCBDDD6F29A946)
[1701713320.092976][1952:1954] CHIP:IN: UDP::Init bind&listen port=5550
[1701713320.093083][1952:1954] CHIP:IN: UDP::Init bound to port=5550
[1701713320.093105][1952:1954] CHIP:IN: UDP::Init bind&listen port=5550
[1701713320.093181][1952:1954] CHIP:IN: UDP::Init bound to port=5550
[1701713320.093200][1952:1954] CHIP:IN: TransportMgr initialized
[1701713320.105124][1952:1954] CHIP:CTL: Setting thread operational dataset from parameters
[1701713320.105169][1952:1954] CHIP:CTL: Setting attestation nonce to random value
[1701713320.105219][1952:1954] CHIP:CTL: Setting CSR nonce to random value
[1701713320.105250][1952:1954] CHIP:CTL: Commission called for node ID 0x000000000000159F
[1701713320.110034][1952:1953] CHIP:DL: TRACE: Bus acquired for name C-07a0
[1701713320.116246][1952:1954] CHIP:DL: PlatformBlueZInit init success
[1701713320.120878][1952:1953] CHIP:BLE: BLE removing known devices.
[1701713320.122480][1952:1953] CHIP:BLE: BLE initiating scan.
[1701713320.129522][1952:1953] CHIP:BLE: Device 1B:6D:3F:85:98:9D does not look like a CHIP device.
[1701713320.148676][1952:1953] CHIP:BLE: New device scanned: B4:3A:31:EB:2A:87
[1701713320.148723][1952:1953] CHIP:BLE: Device discriminator match. Attempting to connect.
[1701713320.151661][1952:1953] CHIP:BLE: Scan complete notification without an active scan.
[1701713320.252833][1952:1953] CHIP:DL: ConnectDevice complete
[1701713321.376887][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.376941][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.376977][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.376995][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377020][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0001
[1701713321.377038][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377058][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0001
[1701713321.377075][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377094][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0001
[1701713321.377111][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377133][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377150][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377170][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377186][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377206][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377223][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377246][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service000e
[1701713321.377263][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377282][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service000e
[1701713321.377298][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377318][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service000e
[1701713321.377334][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377361][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377378][1952:1953] CHIP:DL: Char1 /org/bluez/hci0/dev_B4_3A_31_EB_2A_87/service0015
[1701713321.377402][1952:1953] CHIP:DL: New BLE connection 0xffff8004efe0, device B4:3A:31:EB:2A:87, path /org/bluez/hci0/dev_B4_3A_31_EB_2A_87
[1701713321.377526][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16385
[1701713321.377594][1952:1954] CHIP:DIS: Closing all BLE connections
[1701713321.377648][1952:1954] CHIP:IN: BleConnectionComplete: endPoint 0xaaaab3cf43c8
[1701713321.377988][1952:1954] CHIP:IN: SecureSession[0xffff7800b170]: Allocated Type:1 LSID:10026
[1701713321.378028][1952:1954] CHIP:SC: Assigned local session key ID 10026
[1701713321.378174][1952:1954] CHIP:EM: <<< [E:56981i S:0 M:224627034] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
[1701713321.378231][1952:1954] CHIP:IN: (U) Sending msg 224627034 to IP address 'BLE'
[1701713321.378413][1952:1954] CHIP:IN: Message appended to BLE send queue
[1701713321.378442][1952:1954] CHIP:SC: Sent PBKDF param request
[1701713321.811120][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713321.909200][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713321.909202][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16388
[1701713321.909319][1952:1954] CHIP:BLE: subscribe complete, ep = 0xaaaab3cf43c8
[1701713321.909365][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713321.909388][1952:1954] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4
[1701713321.909406][1952:1954] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244.
[1701713321.909422][1952:1954] CHIP:BLE: local and remote recv window size = 5
[1701713321.909505][1952:1954] CHIP:IN: BLE EndPoint 0xaaaab3cf43c8 Connection Complete
[1701713322.006138][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713322.008498][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713322.008625][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713322.008767][1952:1954] CHIP:EM: >>> [E:56981i S:0 M:173523597] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
[1701713322.008798][1952:1954] CHIP:EM: Found matching exchange: 56981i, Delegate: 0xffff7800d890
[1701713322.008835][1952:1954] CHIP:SC: Received PBKDF param response
[1701713322.008877][1952:1954] CHIP:SC: Peer assigned session ID 44318
[1701713322.008905][1952:1954] CHIP:SC: Found MRP parameters in the message
[1701713322.014518][1952:1954] CHIP:EM: <<< [E:56981i S:0 M:224627035] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
[1701713322.014582][1952:1954] CHIP:IN: (U) Sending msg 224627035 to IP address 'BLE'
[1701713322.014673][1952:1954] CHIP:SC: Sent spake2p msg1
[1701713322.103610][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713322.154186][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713322.154334][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713322.154454][1952:1954] CHIP:EM: >>> [E:56981i S:0 M:173523598] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
[1701713322.154481][1952:1954] CHIP:EM: Found matching exchange: 56981i, Delegate: 0xffff7800d890
[1701713322.154513][1952:1954] CHIP:SC: Received spake2p msg2
[1701713322.155743][1952:1954] CHIP:EM: <<< [E:56981i S:0 M:224627036] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
[1701713322.155792][1952:1954] CHIP:IN: (U) Sending msg 224627036 to IP address 'BLE'
[1701713322.155873][1952:1954] CHIP:SC: Sent spake2p msg3
[1701713322.249867][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713322.251184][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713322.251440][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713322.251560][1952:1954] CHIP:EM: >>> [E:56981i S:0 M:173523599] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
[1701713322.251591][1952:1954] CHIP:EM: Found matching exchange: 56981i, Delegate: 0xffff7800d890
[1701713322.251797][1952:1954] CHIP:SC: SecureSession[0xffff7800b170]: Moving from state 'kEstablishing' --> 'kActive'
[1701713322.251827][1952:1954] CHIP:IN: SecureSession[0xffff7800b170]: Activated - Type:1 LSID:10026
[1701713322.251845][1952:1954] CHIP:IN: New secure session activated for device <FFFFFFFB00000000, 0>, LSID:10026 PSID:44318!
[1701713322.251870][1952:1954] CHIP:CTL: Remote device completed SPAKE2+ handshake
[1701713322.251887][1952:1954] CHIP:TOO: Pairing Success
[1701713322.251903][1952:1954] CHIP:TOO: PASE establishment successful
[1701713322.251922][1952:1954] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo'
[1701713322.251944][1952:1954] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo'
[1701713322.251962][1952:1954] CHIP:CTL: Sending request for commissioning information
[1701713322.252036][1952:1954] CHIP:DMG: SendReadRequest ReadClient[0xffff78012670]: Sending Read Request
[1701713322.252190][1952:1954] CHIP:EM: <<< [E:56982i S:10026 M:122954595] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
[1701713322.252229][1952:1954] CHIP:IN: (S) Sending msg 122954595 on secure session with LSID: 10026
[1701713322.252321][1952:1954] CHIP:DMG: MoveToState ReadClient[0xffff78012670]: Moving to [AwaitingIn]
[1701713322.347390][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713322.350775][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713322.350999][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713322.445606][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713322.445949][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713322.446100][1952:1954] CHIP:EM: >>> [E:56982i S:10026 M:49570997] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
[1701713322.446132][1952:1954] CHIP:EM: Found matching exchange: 56982i, Delegate: 0xffff78012680
[1701713322.446220][1952:1954] CHIP:DMG: ReportDataMessage =
[1701713322.446241][1952:1954] CHIP:DMG: {
[1701713322.446258][1952:1954] CHIP:DMG: AttributeReportIBs =
[1701713322.446283][1952:1954] CHIP:DMG: [
[1701713322.446303][1952:1954] CHIP:DMG: AttributeReportIB =
[1701713322.446330][1952:1954] CHIP:DMG: {
[1701713322.446351][1952:1954] CHIP:DMG: AttributeDataIB =
[1701713322.446382][1952:1954] CHIP:DMG: {
[1701713322.446407][1952:1954] CHIP:DMG: DataVersion = 0xc5b76dd8,
[1701713322.446432][1952:1954] CHIP:DMG: AttributePathIB =
[1701713322.446457][1952:1954] CHIP:DMG: {
[1701713322.446483][1952:1954] CHIP:DMG: Endpoint = 0x0,
[1701713322.446510][1952:1954] CHIP:DMG: Cluster = 0x31,
[1701713322.446537][1952:1954] CHIP:DMG: Attribute = 0x0000_0003,
[1701713322.446562][1952:1954] CHIP:DMG: }
[1701713322.446588][1952:1954] CHIP:DMG:
[1701713322.446615][1952:1954] CHIP:DMG: Data = 20,
[1701713322.446638][1952:1954] CHIP:DMG: },
[1701713322.446664][1952:1954] CHIP:DMG:
[1701713322.446684][1952:1954] CHIP:DMG: },
[1701713322.446713][1952:1954] CHIP:DMG:
[1701713322.446732][1952:1954] CHIP:DMG: AttributeReportIB =
[1701713322.446758][1952:1954] CHIP:DMG: {
[1701713322.446779][1952:1954] CHIP:DMG: AttributeDataIB =
[1701713322.446801][1952:1954] CHIP:DMG: {
[1701713322.446825][1952:1954] CHIP:DMG: DataVersion = 0xb585847f,
[1701713322.446848][1952:1954] CHIP:DMG: AttributePathIB =
[1701713322.446872][1952:1954] CHIP:DMG: {
[1701713322.446897][1952:1954] CHIP:DMG: Endpoint = 0x0,
[1701713322.446924][1952:1954] CHIP:DMG: Cluster = 0x28,
[1701713322.446950][1952:1954] CHIP:DMG: Attribute = 0x0000_0004,
[1701713322.446976][1952:1954] CHIP:DMG: }
[1701713322.447001][1952:1954] CHIP:DMG:
[1701713322.447027][1952:1954] CHIP:DMG: Data = 32773,
[1701713322.447050][1952:1954] CHIP:DMG: },
[1701713322.447075][1952:1954] CHIP:DMG:
[1701713322.447096][1952:1954] CHIP:DMG: },
[1701713322.447124][1952:1954] CHIP:DMG:
[1701713322.447143][1952:1954] CHIP:DMG: AttributeReportIB =
[1701713322.447169][1952:1954] CHIP:DMG: {
[1701713322.447190][1952:1954] CHIP:DMG: AttributeDataIB =
[1701713322.447213][1952:1954] CHIP:DMG: {
[1701713322.447236][1952:1954] CHIP:DMG: DataVersion = 0xb585847f,
[1701713322.447260][1952:1954] CHIP:DMG: AttributePathIB =
[1701713322.447284][1952:1954] CHIP:DMG: {
[1701713322.447309][1952:1954] CHIP:DMG: Endpoint = 0x0,
[1701713322.447335][1952:1954] CHIP:DMG: Cluster = 0x28,
[1701713322.447395][1952:1954] CHIP:DMG: Attribute = 0x0000_0002,
[1701713322.447420][1952:1954] CHIP:DMG: }
[1701713322.447446][1952:1954] CHIP:DMG:
[1701713322.447472][1952:1954] CHIP:DMG: Data = 65521,
[1701713322.447495][1952:1954] CHIP:DMG: },
[1701713322.447520][1952:1954] CHIP:DMG:
[1701713322.447541][1952:1954] CHIP:DMG: },
[1701713322.447569][1952:1954] CHIP:DMG:
[1701713322.447589][1952:1954] CHIP:DMG: AttributeReportIB =
[1701713322.447614][1952:1954] CHIP:DMG: {
[1701713322.447634][1952:1954] CHIP:DMG: AttributeDataIB =
[1701713322.447658][1952:1954] CHIP:DMG: {
[1701713322.447682][1952:1954] CHIP:DMG: DataVersion = 0xdd9492fe,
[1701713322.447705][1952:1954] CHIP:DMG: AttributePathIB =
[1701713322.447729][1952:1954] CHIP:DMG: {
[1701713322.447754][1952:1954] CHIP:DMG: Endpoint = 0x0,
[1701713322.447781][1952:1954] CHIP:DMG: Cluster = 0x30,
[1701713322.447807][1952:1954] CHIP:DMG: Attribute = 0x0000_0003,
[1701713322.447832][1952:1954] CHIP:DMG: }
[1701713322.447857][1952:1954] CHIP:DMG:
[1701713322.447882][1952:1954] CHIP:DMG: Data = 0,
[1701713322.447905][1952:1954] CHIP:DMG: },
[1701713322.447931][1952:1954] CHIP:DMG:
[1701713322.447951][1952:1954] CHIP:DMG: },
[1701713322.447979][1952:1954] CHIP:DMG:
[1701713322.447999][1952:1954] CHIP:DMG: AttributeReportIB =
[1701713322.448024][1952:1954] CHIP:DMG: {
[1701713322.448044][1952:1954] CHIP:DMG: AttributeDataIB =
[1701713322.448067][1952:1954] CHIP:DMG: {
[1701713322.448090][1952:1954] CHIP:DMG: DataVersion = 0xdd9492fe,
[1701713322.448114][1952:1954] CHIP:DMG: AttributePathIB =
[1701713322.448138][1952:1954] CHIP:DMG: {
[1701713322.448163][1952:1954] CHIP:DMG: Endpoint = 0x0,
[1701713322.448189][1952:1954] CHIP:DMG: Cluster = 0x30,
[1701713322.448215][1952:1954] CHIP:DMG: Attribute = 0x0000_0002,
[1701713322.448240][1952:1954] CHIP:DMG: }
[1701713322.448266][1952:1954] CHIP:DMG:
[1701713322.448291][1952:1954] CHIP:DMG: Data = 0,
[1701713322.448313][1952:1954] CHIP:DMG: },
[1701713322.448339][1952:1954] CHIP:DMG:
[1701713322.448359][1952:1954] CHIP:DMG: },
[1701713322.448389][1952:1954] CHIP:DMG:
[1701713322.448408][1952:1954] CHIP:DMG: AttributeReportIB =
[1701713322.448434][1952:1954] CHIP:DMG: {
[1701713322.448455][1952:1954] CHIP:DMG: AttributeDataIB =
[1701713322.448477][1952:1954] CHIP:DMG: {
[1701713322.448500][1952:1954] CHIP:DMG: DataVersion = 0xdd9492fe,
[1701713322.448524][1952:1954] CHIP:DMG: AttributePathIB =
[1701713322.448548][1952:1954] CHIP:DMG: {
[1701713322.448572][1952:1954] CHIP:DMG: Endpoint = 0x0,
[1701713322.448599][1952:1954] CHIP:DMG: Cluster = 0x30,
[1701713322.448625][1952:1954] CHIP:DMG: Attribute = 0x0000_0001,
[1701713322.448649][1952:1954] CHIP:DMG: }
[1701713322.448675][1952:1954] CHIP:DMG:
[1701713322.448699][1952:1954] CHIP:DMG: Data =
[1701713322.448723][1952:1954] CHIP:DMG: {
[1701713322.448779][1952:1954] CHIP:DMG: 0x0 = 60,
[1701713322.448836][1952:1954] CHIP:DMG: 0x1 = 900,
[1701713322.448869][1952:1954] CHIP:DMG: },
[1701713322.448896][1952:1954] CHIP:DMG: },
[1701713322.448923][1952:1954] CHIP:DMG:
[1701713322.448943][1952:1954] CHIP:DMG: },
[1701713322.448973][1952:1954] CHIP:DMG:
[1701713322.448992][1952:1954] CHIP:DMG: AttributeReportIB =
[1701713322.449017][1952:1954] CHIP:DMG: {
[1701713322.449038][1952:1954] CHIP:DMG: AttributeDataIB =
[1701713322.449063][1952:1954] CHIP:DMG: {
[1701713322.449090][1952:1954] CHIP:DMG: DataVersion = 0xdd9492fe,
[1701713322.449119][1952:1954] CHIP:DMG: AttributePathIB =
[1701713322.449150][1952:1954] CHIP:DMG: {
[1701713322.449181][1952:1954] CHIP:DMG: Endpoint = 0x0,
[1701713322.449214][1952:1954] CHIP:DMG: Cluster = 0x30,
[1701713322.449247][1952:1954] CHIP:DMG: Attribute = 0x0000_0000,
[1701713322.449278][1952:1954] CHIP:DMG: }
[1701713322.449309][1952:1954] CHIP:DMG:
[1701713322.449338][1952:1954] CHIP:DMG: Data = 0,
[1701713322.449366][1952:1954] CHIP:DMG: },
[1701713322.449394][1952:1954] CHIP:DMG:
[1701713322.449415][1952:1954] CHIP:DMG: },
[1701713322.449443][1952:1954] CHIP:DMG:
[1701713322.449462][1952:1954] CHIP:DMG: AttributeReportIB =
[1701713322.449487][1952:1954] CHIP:DMG: {
[1701713322.449508][1952:1954] CHIP:DMG: AttributeDataIB =
[1701713322.449533][1952:1954] CHIP:DMG: {
[1701713322.449559][1952:1954] CHIP:DMG: DataVersion = 0xc5b76dd8,
[1701713322.449588][1952:1954] CHIP:DMG: AttributePathIB =
[1701713322.449618][1952:1954] CHIP:DMG: {
[1701713322.449649][1952:1954] CHIP:DMG: Endpoint = 0x0,
[1701713322.449682][1952:1954] CHIP:DMG: Cluster = 0x31,
[1701713322.449714][1952:1954] CHIP:DMG: Attribute = 0x0000_FFFC,
[1701713322.449744][1952:1954] CHIP:DMG: }
[1701713322.449776][1952:1954] CHIP:DMG:
[1701713322.449807][1952:1954] CHIP:DMG: Data = 2,
[1701713322.449836][1952:1954] CHIP:DMG: },
[1701713322.449864][1952:1954] CHIP:DMG:
[1701713322.449885][1952:1954] CHIP:DMG: },
[1701713322.449909][1952:1954] CHIP:DMG:
[1701713322.449928][1952:1954] CHIP:DMG: ],
[1701713322.449975][1952:1954] CHIP:DMG:
[1701713322.449995][1952:1954] CHIP:DMG: SuppressResponse = true,
[1701713322.450015][1952:1954] CHIP:DMG: InteractionModelRevision = 1
[1701713322.450034][1952:1954] CHIP:DMG: }
[1701713322.450684][1952:1954] CHIP:CTL: ----- NetworkCommissioning Features: has Thread. endpointid = 0
[1701713322.450747][1952:1954] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo'
[1701713322.450774][1952:1954] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe'
[1701713322.450800][1952:1954] CHIP:CTL: Performing next commissioning step 'ArmFailSafe'
[1701713322.450818][1952:1954] CHIP:CTL: Arming failsafe (60 seconds)
[1701713322.450875][1952:1954] CHIP:DMG: ICR moving to [AddingComm]
[1701713322.450901][1952:1954] CHIP:DMG: ICR moving to [AddedComma]
[1701713322.451011][1952:1954] CHIP:EM: <<< [E:56983i S:10026 M:122954596] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1701713322.451048][1952:1954] CHIP:IN: (S) Sending msg 122954596 on secure session with LSID: 10026
[1701713322.451142][1952:1954] CHIP:DMG: ICR moving to [CommandSen]
[1701713322.542404][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713322.543876][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713322.544000][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713322.544192][1952:1954] CHIP:EM: >>> [E:56983i S:10026 M:49570998] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1701713322.544222][1952:1954] CHIP:EM: Found matching exchange: 56983i, Delegate: 0xffff8001e808
[1701713322.544254][1952:1954] CHIP:DMG: ICR moving to [ResponseRe]
[1701713322.544298][1952:1954] CHIP:DMG: InvokeResponseMessage =
[1701713322.544318][1952:1954] CHIP:DMG: {
[1701713322.544338][1952:1954] CHIP:DMG: suppressResponse = false,
[1701713322.544358][1952:1954] CHIP:DMG: InvokeResponseIBs =
[1701713322.544384][1952:1954] CHIP:DMG: [
[1701713322.544404][1952:1954] CHIP:DMG: InvokeResponseIB =
[1701713322.544430][1952:1954] CHIP:DMG: {
[1701713322.544451][1952:1954] CHIP:DMG: CommandDataIB =
[1701713322.544481][1952:1954] CHIP:DMG: {
[1701713322.544507][1952:1954] CHIP:DMG: CommandPathIB =
[1701713322.544539][1952:1954] CHIP:DMG: {
[1701713322.544571][1952:1954] CHIP:DMG: EndpointId = 0x0,
[1701713322.544604][1952:1954] CHIP:DMG: ClusterId = 0x30,
[1701713322.544636][1952:1954] CHIP:DMG: CommandId = 0x1,
[1701713322.544667][1952:1954] CHIP:DMG: },
[1701713322.544699][1952:1954] CHIP:DMG:
[1701713322.544725][1952:1954] CHIP:DMG: CommandFields =
[1701713322.544756][1952:1954] CHIP:DMG: {
[1701713322.544789][1952:1954] CHIP:DMG: 0x0 = 0,
[1701713322.544824][1952:1954] CHIP:DMG: 0x1 = "" (0 chars),
[1701713322.544856][1952:1954] CHIP:DMG: },
[1701713322.544885][1952:1954] CHIP:DMG: },
[1701713322.544913][1952:1954] CHIP:DMG:
[1701713322.544934][1952:1954] CHIP:DMG: },
[1701713322.544959][1952:1954] CHIP:DMG:
[1701713322.544978][1952:1954] CHIP:DMG: ],
[1701713322.545002][1952:1954] CHIP:DMG:
[1701713322.545021][1952:1954] CHIP:DMG: InteractionModelRevision = 1
[1701713322.545040][1952:1954] CHIP:DMG: },
[1701713322.545091][1952:1954] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1701713322.545156][1952:1954] CHIP:CTL: Received ArmFailSafe response errorCode=0
[1701713322.545183][1952:1954] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe'
[1701713322.545201][1952:1954] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory'
[1701713322.545227][1952:1954] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory'
[1701713322.545244][1952:1954] CHIP:CTL: Setting Regulatory Config
[1701713322.545261][1952:1954] CHIP:CTL: Device does not support configurable regulatory location
[1701713322.545323][1952:1954] CHIP:DMG: ICR moving to [AddingComm]
[1701713322.545369][1952:1954] CHIP:DMG: ICR moving to [AddedComma]
[1701713322.545460][1952:1954] CHIP:EM: <<< [E:56984i S:10026 M:122954597] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1701713322.545496][1952:1954] CHIP:IN: (S) Sending msg 122954597 on secure session with LSID: 10026
[1701713322.545580][1952:1954] CHIP:DMG: ICR moving to [CommandSen]
[1701713322.545634][1952:1954] CHIP:DMG: ICR moving to [AwaitingDe]
[1701713322.639864][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713322.641349][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713322.641566][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713322.641734][1952:1954] CHIP:EM: >>> [E:56984i S:10026 M:49570999] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1701713322.641767][1952:1954] CHIP:EM: Found matching exchange: 56984i, Delegate: 0xffff78015408
[1701713322.641799][1952:1954] CHIP:DMG: ICR moving to [ResponseRe]
[1701713322.641844][1952:1954] CHIP:DMG: InvokeResponseMessage =
[1701713322.641864][1952:1954] CHIP:DMG: {
[1701713322.641883][1952:1954] CHIP:DMG: suppressResponse = false,
[1701713322.641903][1952:1954] CHIP:DMG: InvokeResponseIBs =
[1701713322.641929][1952:1954] CHIP:DMG: [
[1701713322.641949][1952:1954] CHIP:DMG: InvokeResponseIB =
[1701713322.641976][1952:1954] CHIP:DMG: {
[1701713322.641997][1952:1954] CHIP:DMG: CommandDataIB =
[1701713322.642045][1952:1954] CHIP:DMG: {
[1701713322.642069][1952:1954] CHIP:DMG: CommandPathIB =
[1701713322.642101][1952:1954] CHIP:DMG: {
[1701713322.642130][1952:1954] CHIP:DMG: EndpointId = 0x0,
[1701713322.642164][1952:1954] CHIP:DMG: ClusterId = 0x30,
[1701713322.642196][1952:1954] CHIP:DMG: CommandId = 0x3,
[1701713322.642227][1952:1954] CHIP:DMG: },
[1701713322.642256][1952:1954] CHIP:DMG:
[1701713322.642282][1952:1954] CHIP:DMG: CommandFields =
[1701713322.642310][1952:1954] CHIP:DMG: {
[1701713322.642342][1952:1954] CHIP:DMG: 0x0 = 0,
[1701713322.642378][1952:1954] CHIP:DMG: 0x1 = "" (0 chars),
[1701713322.642410][1952:1954] CHIP:DMG: },
[1701713322.642439][1952:1954] CHIP:DMG: },
[1701713322.642468][1952:1954] CHIP:DMG:
[1701713322.642488][1952:1954] CHIP:DMG: },
[1701713322.642513][1952:1954] CHIP:DMG:
[1701713322.642532][1952:1954] CHIP:DMG: ],
[1701713322.642557][1952:1954] CHIP:DMG:
[1701713322.642576][1952:1954] CHIP:DMG: InteractionModelRevision = 1
[1701713322.642595][1952:1954] CHIP:DMG: },
[1701713322.642647][1952:1954] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003
[1701713322.646769][1952:1954] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0
[1701713322.646814][1952:1954] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory'
[1701713322.646834][1952:1954] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest'
[1701713322.646862][1952:1954] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest'
[1701713322.646880][1952:1954] CHIP:CTL: Sending request for PAI certificate
[1701713322.646895][1952:1954] CHIP:CTL: Sending Certificate Chain request to 0xffff7800d840 device
[1701713322.646961][1952:1954] CHIP:DMG: ICR moving to [AddingComm]
[1701713322.648457][1952:1954] CHIP:DMG: ICR moving to [AddedComma]
[1701713322.648599][1952:1954] CHIP:EM: <<< [E:56985i S:10026 M:122954598] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1701713322.648640][1952:1954] CHIP:IN: (S) Sending msg 122954598 on secure session with LSID: 10026
[1701713322.648741][1952:1954] CHIP:DMG: ICR moving to [CommandSen]
[1701713322.648805][1952:1954] CHIP:DMG: ICR moving to [AwaitingDe]
[1701713322.737367][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713322.740752][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713322.741081][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713322.837897][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713322.838042][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713322.933299][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713322.933431][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713322.933628][1952:1954] CHIP:EM: >>> [E:56985i S:10026 M:49571000] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1701713322.933662][1952:1954] CHIP:EM: Found matching exchange: 56985i, Delegate: 0xffff8001e808
[1701713322.933697][1952:1954] CHIP:DMG: ICR moving to [ResponseRe]
[1701713322.933740][1952:1954] CHIP:DMG: InvokeResponseMessage =
[1701713322.933760][1952:1954] CHIP:DMG: {
[1701713322.933778][1952:1954] CHIP:DMG: suppressResponse = false,
[1701713322.933798][1952:1954] CHIP:DMG: InvokeResponseIBs =
[1701713322.933824][1952:1954] CHIP:DMG: [
[1701713322.933844][1952:1954] CHIP:DMG: InvokeResponseIB =
[1701713322.933870][1952:1954] CHIP:DMG: {
[1701713322.933891][1952:1954] CHIP:DMG: CommandDataIB =
[1701713322.933937][1952:1954] CHIP:DMG: {
[1701713322.933961][1952:1954] CHIP:DMG: CommandPathIB =
[1701713322.933993][1952:1954] CHIP:DMG: {
[1701713322.934025][1952:1954] CHIP:DMG: EndpointId = 0x0,
[1701713322.934057][1952:1954] CHIP:DMG: ClusterId = 0x3e,
[1701713322.934088][1952:1954] CHIP:DMG: CommandId = 0x3,
[1701713322.934118][1952:1954] CHIP:DMG: },
[1701713322.934150][1952:1954] CHIP:DMG:
[1701713322.934175][1952:1954] CHIP:DMG: CommandFields =
[1701713322.934202][1952:1954] CHIP:DMG: {
[1701713322.934231][1952:1954] CHIP:DMG: 0x0 = [
[1701713322.934348][1952:1954] CHIP:DMG: 0x30, 0x82, 0x01, 0xcb, 0x30, 0x82, 0x01, 0x71, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x56, 0xad, 0x82, 0x22, 0xad, 0x94, 0x5b, 0x64, 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, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 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, 0x41, 0x9a, 0x93, 0x15, 0xc2, 0x17, 0x3e, 0x0c, 0x8c, 0x87, 0x6d, 0x03, 0xcc, 0xfc, 0x94, 0x48, 0x52, 0x64, 0x7f, 0x7f, 0xec, 0x5e, 0x50, 0x82, 0xf4, 0x05, 0x99, 0x28, 0xec, 0xa8, 0x94, 0xc5, 0x94, 0x15, 0x13, 0x09, 0xac, 0x63, 0x1e, 0x4c, 0xb0, 0x33, 0x92, 0xaf, 0x68, 0x4b, 0x0b, 0xaf, 0xb7, 0xe6, 0x5b, 0x3b, 0x81, 0x62, 0xc2, 0xf5, 0x2b, 0xf9, 0x31, 0xb8, 0xe7, 0x7a, 0xaa, 0x82, 0xa3, 0x66, 0x30, 0x64, 0x30, 0x12, 0x06, 0x03, 0x55, 0x1d, 0x
[1701713322.934413][1952:1954] CHIP:DMG: ] (463 bytes)
[1701713322.934445][1952:1954] CHIP:DMG: },
[1701713322.934474][1952:1954] CHIP:DMG: },
[1701713322.934503][1952:1954] CHIP:DMG:
[1701713322.934523][1952:1954] CHIP:DMG: },
[1701713322.934548][1952:1954] CHIP:DMG:
[1701713322.934567][1952:1954] CHIP:DMG: ],
[1701713322.934591][1952:1954] CHIP:DMG:
[1701713322.934611][1952:1954] CHIP:DMG: InteractionModelRevision = 1
[1701713322.934629][1952:1954] CHIP:DMG: },
[1701713322.934680][1952:1954] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1701713322.934710][1952:1954] CHIP:CTL: Received certificate chain from the device
[1701713322.934735][1952:1954] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest'
[1701713322.939451][1952:1954] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest'
[1701713322.939498][1952:1954] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest'
[1701713322.939516][1952:1954] CHIP:CTL: Sending request for DAC certificate
[1701713322.939532][1952:1954] CHIP:CTL: Sending Certificate Chain request to 0xffff7800d840 device
[1701713322.939598][1952:1954] CHIP:DMG: ICR moving to [AddingComm]
[1701713322.939623][1952:1954] CHIP:DMG: ICR moving to [AddedComma]
[1701713322.939742][1952:1954] CHIP:EM: <<< [E:56986i S:10026 M:122954599] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1701713322.939780][1952:1954] CHIP:IN: (S) Sending msg 122954599 on secure session with LSID: 10026
[1701713322.939873][1952:1954] CHIP:DMG: ICR moving to [CommandSen]
[1701713322.939933][1952:1954] CHIP:DMG: ICR moving to [AwaitingDe]
[1701713323.029846][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713323.033354][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713323.033584][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713323.130432][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713323.130566][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713323.225936][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713323.226073][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713323.226270][1952:1954] CHIP:EM: >>> [E:56986i S:10026 M:49571001] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1701713323.226304][1952:1954] CHIP:EM: Found matching exchange: 56986i, Delegate: 0xffff80050ca8
[1701713323.226340][1952:1954] CHIP:DMG: ICR moving to [ResponseRe]
[1701713323.226385][1952:1954] CHIP:DMG: InvokeResponseMessage =
[1701713323.226404][1952:1954] CHIP:DMG: {
[1701713323.226423][1952:1954] CHIP:DMG: suppressResponse = false,
[1701713323.226443][1952:1954] CHIP:DMG: InvokeResponseIBs =
[1701713323.226468][1952:1954] CHIP:DMG: [
[1701713323.226489][1952:1954] CHIP:DMG: InvokeResponseIB =
[1701713323.226515][1952:1954] CHIP:DMG: {
[1701713323.226536][1952:1954] CHIP:DMG: CommandDataIB =
[1701713323.226566][1952:1954] CHIP:DMG: {
[1701713323.226592][1952:1954] CHIP:DMG: CommandPathIB =
[1701713323.226623][1952:1954] CHIP:DMG: {
[1701713323.226655][1952:1954] CHIP:DMG: EndpointId = 0x0,
[1701713323.226688][1952:1954] CHIP:DMG: ClusterId = 0x3e,
[1701713323.226720][1952:1954] CHIP:DMG: CommandId = 0x3,
[1701713323.226751][1952:1954] CHIP:DMG: },
[1701713323.226780][1952:1954] CHIP:DMG:
[1701713323.226806][1952:1954] CHIP:DMG: CommandFields =
[1701713323.226837][1952:1954] CHIP:DMG: {
[1701713323.226870][1952:1954] CHIP:DMG: 0x0 = [
[1701713323.226987][1952:1954] CHIP:DMG: 0x30, 0x82, 0x01, 0xe8, 0x30, 0x82, 0x01, 0x8e, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x6f, 0xdc, 0xb6, 0xed, 0x06, 0xf3, 0x58, 0xf9, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 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, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x53, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x2f, 0x30, 0x78, 0x38, 0x30, 0x30, 0x35, 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, 0x35, 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, 0x7e, 0xf2, 0xf1, 0x08, 0x2b, 0x2a, 0x56, 0x4a, 0x05, 0x44, 0xc0, 0xa6, 0x13, 0xd2, 0x1b, 0x15, 0x75, 0x00, 0x55, 0x2d, 0x03, 0x5f, 0x23, 0x02, 0x25, 0x2f, 0xce, 0xe8, 0x1d, 0x6e, 0xc6, 0x74, 0x17, 0x34, 0x18, 0xa9, 0x4a, 0x88, 0xdb, 0x
[1701713323.227053][1952:1954] CHIP:DMG: ] (492 bytes)
[1701713323.227086][1952:1954] CHIP:DMG: },
[1701713323.227115][1952:1954] CHIP:DMG: },
[1701713323.227143][1952:1954] CHIP:DMG:
[1701713323.227164][1952:1954] CHIP:DMG: },
[1701713323.227189][1952:1954] CHIP:DMG:
[1701713323.227209][1952:1954] CHIP:DMG: ],
[1701713323.227234][1952:1954] CHIP:DMG:
[1701713323.227253][1952:1954] CHIP:DMG: InteractionModelRevision = 1
[1701713323.227272][1952:1954] CHIP:DMG: },
[1701713323.227323][1952:1954] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1701713323.227416][1952:1954] CHIP:CTL: Received certificate chain from the device
[1701713323.227447][1952:1954] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest'
[1701713323.227474][1952:1954] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest'
[1701713323.227501][1952:1954] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest'
[1701713323.227518][1952:1954] CHIP:CTL: Sending Attestation Request to the device.
[1701713323.227535][1952:1954] CHIP:CTL: Sending Attestation request to 0xffff7800d840 device
[1701713323.227593][1952:1954] CHIP:DMG: ICR moving to [AddingComm]
[1701713323.227620][1952:1954] CHIP:DMG: ICR moving to [AddedComma]
[1701713323.227724][1952:1954] CHIP:EM: <<< [E:56987i S:10026 M:122954600] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1701713323.227761][1952:1954] CHIP:IN: (S) Sending msg 122954600 on secure session with LSID: 10026
[1701713323.227851][1952:1954] CHIP:DMG: ICR moving to [CommandSen]
[1701713323.227886][1952:1954] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information
[1701713323.227928][1952:1954] CHIP:DMG: ICR moving to [AwaitingDe]
[1701713323.322372][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713323.325790][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713323.326121][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713323.422873][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713323.423223][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713323.520293][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713323.520431][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713323.520605][1952:1954] CHIP:EM: >>> [E:56987i S:10026 M:49571002] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1701713323.520638][1952:1954] CHIP:EM: Found matching exchange: 56987i, Delegate: 0xffff80050ff8
[1701713323.520674][1952:1954] CHIP:DMG: ICR moving to [ResponseRe]
[1701713323.520719][1952:1954] CHIP:DMG: InvokeResponseMessage =
[1701713323.520739][1952:1954] CHIP:DMG: {
[1701713323.520757][1952:1954] CHIP:DMG: suppressResponse = false,
[1701713323.520776][1952:1954] CHIP:DMG: InvokeResponseIBs =
[1701713323.520802][1952:1954] CHIP:DMG: [
[1701713323.520822][1952:1954] CHIP:DMG: InvokeResponseIB =
[1701713323.520849][1952:1954] CHIP:DMG: {
[1701713323.520870][1952:1954] CHIP:DMG: CommandDataIB =
[1701713323.520923][1952:1954] CHIP:DMG: {
[1701713323.520946][1952:1954] CHIP:DMG: CommandPathIB =
[1701713323.520979][1952:1954] CHIP:DMG: {
[1701713323.521007][1952:1954] CHIP:DMG: EndpointId = 0x0,
[1701713323.521040][1952:1954] CHIP:DMG: ClusterId = 0x3e,
[1701713323.521072][1952:1954] CHIP:DMG: CommandId = 0x1,
[1701713323.521102][1952:1954] CHIP:DMG: },
[1701713323.521134][1952:1954] CHIP:DMG:
[1701713323.521159][1952:1954] CHIP:DMG: CommandFields =
[1701713323.521189][1952:1954] CHIP:DMG: {
[1701713323.521221][1952:1954] CHIP:DMG: 0x0 = [
[1701713323.521342][1952:1954] CHIP:DMG: 0x15, 0x31, 0x01, 0x1b, 0x02, 0x30, 0x82, 0x02, 0x17, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x02, 0xa0, 0x82, 0x02, 0x08, 0x30, 0x82, 0x02, 0x04, 0x02, 0x01, 0x03, 0x31, 0x0d, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x82, 0x01, 0x70, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x01, 0xa0, 0x82, 0x01, 0x61, 0x04, 0x82, 0x01, 0x5d, 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
[1701713323.521409][1952:1954] CHIP:DMG: ] (583 bytes)
[1701713323.521441][1952:1954] CHIP:DMG: 0x1 = [
[1701713323.521489][1952:1954] CHIP:DMG: 0x3e, 0xe3, 0x9e, 0xd6, 0xa1, 0x8f, 0x12, 0xa8, 0x24, 0x8a, 0x95, 0x72, 0x32, 0x9b, 0x36, 0x8f, 0x09, 0x17, 0x22, 0xf7, 0x42, 0x4f, 0xf4, 0x37, 0x6d, 0x3f, 0xca, 0x54, 0x95, 0x23, 0x9a, 0x53, 0x7b, 0xe6, 0xd2, 0x05, 0x24, 0xae, 0x7f, 0xf6, 0xfc, 0x8e, 0x8b, 0x0d, 0x08, 0xd3, 0x05, 0x0c, 0x67, 0xfe, 0xc2, 0xce, 0xe6, 0x88, 0xee, 0x6d, 0xd7, 0x5e, 0x9f, 0x9c, 0x97, 0x1d, 0xb1, 0x43,
[1701713323.521529][1952:1954] CHIP:DMG: ] (64 bytes)
[1701713323.521561][1952:1954] CHIP:DMG: },
[1701713323.521590][1952:1954] CHIP:DMG: },
[1701713323.521617][1952:1954] CHIP:DMG:
[1701713323.521637][1952:1954] CHIP:DMG: },
[1701713323.521662][1952:1954] CHIP:DMG:
[1701713323.521681][1952:1954] CHIP:DMG: ],
[1701713323.521705][1952:1954] CHIP:DMG:
[1701713323.521724][1952:1954] CHIP:DMG: InteractionModelRevision = 1
[1701713323.521742][1952:1954] CHIP:DMG: },
[1701713323.521795][1952:1954] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001
[1701713323.521826][1952:1954] CHIP:CTL: Received Attestation Information from the device
[1701713323.521852][1952:1954] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest'
[1701713323.521874][1952:1954] CHIP:CTL: AutoCommissioner setting attestationElements buffer size 583/583
[1701713323.521923][1952:1954] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification'
[1701713323.521950][1952:1954] CHIP:CTL: Performing next commissioning step 'AttestationVerification'
[1701713323.521967][1952:1954] CHIP:CTL: Verifying attestation
[1701713323.551597][1952:1954] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device.
[1701713323.551664][1952:1954] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification'
[1701713323.551683][1952:1954] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest'
[1701713323.551711][1952:1954] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest'
[1701713323.551730][1952:1954] CHIP:CTL: Sending CSR request to 0xffff7800d840 device
[1701713323.551801][1952:1954] CHIP:DMG: ICR moving to [AddingComm]
[1701713323.551831][1952:1954] CHIP:DMG: ICR moving to [AddedComma]
[1701713323.551955][1952:1954] CHIP:EM: <<< [E:56988i S:10026 M:122954601] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1701713323.551996][1952:1954] CHIP:IN: (S) Sending msg 122954601 on secure session with LSID: 10026
[1701713323.552092][1952:1954] CHIP:DMG: ICR moving to [CommandSen]
[1701713323.552128][1952:1954] CHIP:CTL: Sent CSR request, waiting for the CSR
[1701713323.552178][1952:1954] CHIP:DMG: ICR moving to [AwaitingDe]
[1701713323.614906][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713323.618252][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713323.618565][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713323.763157][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713323.763303][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713323.763529][1952:1954] CHIP:EM: >>> [E:56988i S:10026 M:49571003] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1701713323.763564][1952:1954] CHIP:EM: Found matching exchange: 56988i, Delegate: 0xffff80050ca8
[1701713323.763601][1952:1954] CHIP:DMG: ICR moving to [ResponseRe]
[1701713323.763646][1952:1954] CHIP:DMG: InvokeResponseMessage =
[1701713323.763666][1952:1954] CHIP:DMG: {
[1701713323.763684][1952:1954] CHIP:DMG: suppressResponse = false,
[1701713323.763704][1952:1954] CHIP:DMG: InvokeResponseIBs =
[1701713323.763730][1952:1954] CHIP:DMG: [
[1701713323.763750][1952:1954] CHIP:DMG: InvokeResponseIB =
[1701713323.763777][1952:1954] CHIP:DMG: {
[1701713323.763797][1952:1954] CHIP:DMG: CommandDataIB =
[1701713323.763827][1952:1954] CHIP:DMG: {
[1701713323.763853][1952:1954] CHIP:DMG: CommandPathIB =
[1701713323.763884][1952:1954] CHIP:DMG: {
[1701713323.763916][1952:1954] CHIP:DMG: EndpointId = 0x0,
[1701713323.763948][1952:1954] CHIP:DMG: ClusterId = 0x3e,
[1701713323.763981][1952:1954] CHIP:DMG: CommandId = 0x5,
[1701713323.764011][1952:1954] CHIP:DMG: },
[1701713323.764043][1952:1954] CHIP:DMG:
[1701713323.764069][1952:1954] CHIP:DMG: CommandFields =
[1701713323.764100][1952:1954] CHIP:DMG: {
[1701713323.764131][1952:1954] CHIP:DMG: 0x0 = [
[1701713323.764236][1952:1954] CHIP:DMG: 0x15, 0x30, 0x01, 0xde, 0x30, 0x81, 0xdb, 0x30, 0x81, 0x81, 0x02, 0x01, 0x00, 0x30, 0x0e, 0x31, 0x0c, 0x30, 0x0a, 0x06, 0x03, 0x55, 0x04, 0x0b, 0x0c, 0x03, 0x43, 0x53, 0x41, 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, 0x79, 0xd2, 0x48, 0xef, 0xcc, 0x2b, 0x17, 0x6f, 0x37, 0xd8, 0x1b, 0xe9, 0x17, 0x24, 0x7a, 0x1d, 0x8c, 0xcd, 0x9f, 0x97, 0x6f, 0x08, 0x80, 0x7b, 0x70, 0x60, 0x28, 0xd1, 0xa4, 0x86, 0x80, 0x61, 0x2b, 0xf9, 0x6f, 0x2e, 0x96, 0xbf, 0x76, 0xd5, 0xc9, 0x9d, 0x89, 0x11, 0x42, 0x79, 0xf2, 0xac, 0x61, 0xd7, 0x3b, 0x88, 0xa5, 0x00, 0x81, 0x3a, 0x89, 0x12, 0x01, 0x53, 0x4a, 0x71, 0x12, 0xf8, 0xa0, 0x11, 0x30, 0x0f, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x09, 0x0e, 0x31, 0x02, 0x30, 0x00, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x03, 0x49, 0x00, 0x30, 0x46, 0x02, 0x21, 0x00, 0xfc, 0xee, 0x56, 0xda, 0xe8, 0x47, 0xe3, 0x96, 0xfd, 0x3d, 0x82, 0x3a, 0x47, 0x97, 0x6e, 0x31, 0xa0, 0x63, 0xc8, 0xb6, 0x63, 0x5d, 0xea, 0x9d, 0xc1, 0x10, 0x9c, 0xad, 0xe3, 0xe3, 0xa7, 0x57, 0x02, 0x21, 0x00, 0xa5, 0x77, 0x21, 0xc3, 0x75, 0xba, 0x8e, 0x33, 0x7d, 0x11, 0x7a, 0x39, 0x97, 0xae, 0x3c, 0xe2, 0xee, 0xa6, 0x5b, 0xf4, 0x5e, 0xd9, 0xdb, 0x16, 0x8b, 0x67, 0xe7, 0xca, 0x60, 0x38, 0x73, 0x47, 0x30, 0x02, 0x20, 0x7d, 0xdf, 0x66, 0x12, 0xa9, 0xf6, 0xd0, 0x12, 0xa7, 0xab, 0xcc, 0x60, 0xa4, 0xf9, 0x86, 0x4c, 0xe2, 0x06, 0xe4, 0xb9, 0x4c, 0x80, 0xa0, 0x25, 0x35, 0x15, 0x37, 0x38, 0x91, 0x0e, 0xc0, 0xbb, 0x18,
[1701713323.764296][1952:1954] CHIP:DMG: ] (262 bytes)
[1701713323.764328][1952:1954] CHIP:DMG: 0x1 = [
[1701713323.764376][1952:1954] CHIP:DMG: 0x35, 0xd3, 0x8f, 0xd5, 0x23, 0x94, 0xf8, 0xa8, 0xe8, 0x73, 0x71, 0xb9, 0x8e, 0x2a, 0x72, 0x95, 0xe5, 0x19, 0x12, 0xd5, 0x9a, 0x40, 0x6a, 0xa0, 0xc2, 0x5f, 0xf1, 0x92, 0xd2, 0x72, 0x8d, 0x0a, 0x7f, 0xa7, 0x89, 0x6c, 0x3a, 0x0c, 0x66, 0xd7, 0x8b, 0x23, 0xad, 0xff, 0xd9, 0x12, 0x6f, 0x62, 0x4d, 0x5a, 0x3a, 0x88, 0x4a, 0x33, 0x95, 0x4e, 0xc7, 0xc0, 0x7b, 0x6c, 0x11, 0xf8, 0x41, 0x02,
[1701713323.764415][1952:1954] CHIP:DMG: ] (64 bytes)
[1701713323.764447][1952:1954] CHIP:DMG: },
[1701713323.764473][1952:1954] CHIP:DMG: },
[1701713323.764502][1952:1954] CHIP:DMG:
[1701713323.764522][1952:1954] CHIP:DMG: },
[1701713323.764547][1952:1954] CHIP:DMG:
[1701713323.764566][1952:1954] CHIP:DMG: ],
[1701713323.764591][1952:1954] CHIP:DMG:
[1701713323.764611][1952:1954] CHIP:DMG: InteractionModelRevision = 1
[1701713323.764629][1952:1954] CHIP:DMG: },
[1701713323.764683][1952:1954] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005
[1701713323.764713][1952:1954] CHIP:CTL: Received certificate signing request from the device
[1701713323.764739][1952:1954] CHIP:CTL: Successfully finished commissioning step 'SendOpCertSigningRequest'
[1701713323.764759][1952:1954] CHIP:CTL: Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR'
[1701713323.764783][1952:1954] CHIP:CTL: Performing next commissioning step 'ValidateCSR'
[1701713323.769096][1952:1954] CHIP:CTL: Successfully finished commissioning step 'ValidateCSR'
[1701713323.769153][1952:1954] CHIP:CTL: Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain'
[1701713323.769180][1952:1954] CHIP:CTL: Performing next commissioning step 'GenerateNOCChain'
[1701713323.769200][1952:1954] CHIP:CTL: Getting certificate chain for the device from the issuer
[1701713323.772722][1952:1954] CHIP:CTL: Verifying Certificate Signing Request
[1701713323.774928][1952:1954] CHIP:CTL: Generating NOC
[1701713323.775267][1952:1954] CHIP:CTL: Providing certificate chain to the commissioner
[1701713323.775301][1952:1954] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status ../../examples/chip-tool/third_party/connectedhomeip/src/controller/ExampleOperationalCredentialsIssuer.cpp:396: Success
[1701713323.775330][1952:1954] CHIP:CTL: Successfully finished commissioning step 'GenerateNOCChain'
[1701713323.775512][1952:1954] CHIP:CTL: Performing next commissioning step 'SendTrustedRootCert'
[1701713323.775535][1952:1954] CHIP:CTL: Sending root certificate to the device
[1701713323.775607][1952:1954] CHIP:DMG: ICR moving to [AddingComm]
[1701713323.775634][1952:1954] CHIP:DMG: ICR moving to [AddedComma]
[1701713323.775754][1952:1954] CHIP:EM: <<< [E:56989i S:10026 M:122954602] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1701713323.775791][1952:1954] CHIP:IN: (S) Sending msg 122954602 on secure session with LSID: 10026
[1701713323.775886][1952:1954] CHIP:DMG: ICR moving to [CommandSen]
[1701713323.775921][1952:1954] CHIP:CTL: Sent root certificate to the device
[1701713323.776348][1952:1954] CHIP:DMG: ICR moving to [AwaitingDe]
[1701713323.858672][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713323.956126][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713323.957584][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713323.957706][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713323.957881][1952:1954] CHIP:EM: >>> [E:56989i S:10026 M:49571004] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1701713323.957917][1952:1954] CHIP:EM: Found matching exchange: 56989i, Delegate: 0xffff80050ff8
[1701713323.957949][1952:1954] CHIP:DMG: ICR moving to [ResponseRe]
[1701713323.957992][1952:1954] CHIP:DMG: InvokeResponseMessage =
[1701713323.958011][1952:1954] CHIP:DMG: {
[1701713323.958030][1952:1954] CHIP:DMG: suppressResponse = false,
[1701713323.958049][1952:1954] CHIP:DMG: InvokeResponseIBs =
[1701713323.958074][1952:1954] CHIP:DMG: [
[1701713323.958094][1952:1954] CHIP:DMG: InvokeResponseIB =
[1701713323.958120][1952:1954] CHIP:DMG: {
[1701713323.958141][1952:1954] CHIP:DMG: CommandStatusIB =
[1701713323.958172][1952:1954] CHIP:DMG: {
[1701713323.958196][1952:1954] CHIP:DMG: CommandPathIB =
[1701713323.958228][1952:1954] CHIP:DMG: {
[1701713323.958261][1952:1954] CHIP:DMG: EndpointId = 0x0,
[1701713323.958293][1952:1954] CHIP:DMG: ClusterId = 0x3e,
[1701713323.958325][1952:1954] CHIP:DMG: CommandId = 0xb,
[1701713323.958355][1952:1954] CHIP:DMG: },
[1701713323.958389][1952:1954] CHIP:DMG:
[1701713323.958411][1952:1954] CHIP:DMG: StatusIB =
[1701713323.958442][1952:1954] CHIP:DMG: {
[1701713323.958473][1952:1954] CHIP:DMG: status = 0x00 (SUCCESS),
[1701713323.958504][1952:1954] CHIP:DMG: },
[1701713323.958534][1952:1954] CHIP:DMG:
[1701713323.958559][1952:1954] CHIP:DMG: },
[1701713323.958586][1952:1954] CHIP:DMG:
[1701713323.958607][1952:1954] CHIP:DMG: },
[1701713323.958630][1952:1954] CHIP:DMG:
[1701713323.958649][1952:1954] CHIP:DMG: ],
[1701713323.958673][1952:1954] CHIP:DMG:
[1701713323.958692][1952:1954] CHIP:DMG: InteractionModelRevision = 1
[1701713323.958711][1952:1954] CHIP:DMG: },
[1701713323.958760][1952:1954] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0
[1701713323.958783][1952:1954] CHIP:CTL: Device confirmed that it has received the root certificate
[1701713323.958807][1952:1954] CHIP:CTL: Successfully finished commissioning step 'SendTrustedRootCert'
[1701713323.958825][1952:1954] CHIP:CTL: Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC'
[1701713323.958850][1952:1954] CHIP:CTL: Performing next commissioning step 'SendNOC'
[1701713323.958919][1952:1954] CHIP:DMG: ICR moving to [AddingComm]
[1701713323.958951][1952:1954] CHIP:DMG: ICR moving to [AddedComma]
[1701713323.959065][1952:1954] CHIP:EM: <<< [E:56990i S:10026 M:122954603] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1701713323.959103][1952:1954] CHIP:IN: (S) Sending msg 122954603 on secure session with LSID: 10026
[1701713323.959189][1952:1954] CHIP:DMG: ICR moving to [CommandSen]
[1701713323.959223][1952:1954] CHIP:CTL: Sent operational certificate to the device
[1701713323.959265][1952:1954] CHIP:DMG: ICR moving to [AwaitingDe]
[1701713324.053664][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713324.151174][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713324.248678][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713324.250120][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713324.250437][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713324.250611][1952:1954] CHIP:EM: >>> [E:56990i S:10026 M:49571005] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1701713324.250644][1952:1954] CHIP:EM: Found matching exchange: 56990i, Delegate: 0xffff80050ca8
[1701713324.250677][1952:1954] CHIP:DMG: ICR moving to [ResponseRe]
[1701713324.250720][1952:1954] CHIP:DMG: InvokeResponseMessage =
[1701713324.250744][1952:1954] CHIP:DMG: {
[1701713324.250762][1952:1954] CHIP:DMG: suppressResponse = false,
[1701713324.250780][1952:1954] CHIP:DMG: InvokeResponseIBs =
[1701713324.250804][1952:1954] CHIP:DMG: [
[1701713324.250822][1952:1954] CHIP:DMG: InvokeResponseIB =
[1701713324.250847][1952:1954] CHIP:DMG: {
[1701713324.250866][1952:1954] CHIP:DMG: CommandDataIB =
[1701713324.250889][1952:1954] CHIP:DMG: {
[1701713324.250909][1952:1954] CHIP:DMG: CommandPathIB =
[1701713324.250932][1952:1954] CHIP:DMG: {
[1701713324.250959][1952:1954] CHIP:DMG: EndpointId = 0x0,
[1701713324.250991][1952:1954] CHIP:DMG: ClusterId = 0x3e,
[1701713324.251023][1952:1954] CHIP:DMG: CommandId = 0x8,
[1701713324.251054][1952:1954] CHIP:DMG: },
[1701713324.251086][1952:1954] CHIP:DMG:
[1701713324.251112][1952:1954] CHIP:DMG: CommandFields =
[1701713324.251142][1952:1954] CHIP:DMG: {
[1701713324.251174][1952:1954] CHIP:DMG: 0x0 = 0,
[1701713324.251207][1952:1954] CHIP:DMG: 0x1 = 1,
[1701713324.251239][1952:1954] CHIP:DMG: },
[1701713324.251267][1952:1954] CHIP:DMG: },
[1701713324.251296][1952:1954] CHIP:DMG:
[1701713324.251317][1952:1954] CHIP:DMG: },
[1701713324.251371][1952:1954] CHIP:DMG:
[1701713324.251392][1952:1954] CHIP:DMG: ],
[1701713324.251418][1952:1954] CHIP:DMG:
[1701713324.251438][1952:1954] CHIP:DMG: InteractionModelRevision = 1
[1701713324.251456][1952:1954] CHIP:DMG: },
[1701713324.251509][1952:1954] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008
[1701713324.251540][1952:1954] CHIP:CTL: Device returned status 0 on receiving the NOC
[1701713324.251559][1952:1954] CHIP:CTL: Operational credentials provisioned on device 0xffff7800d840
[1701713324.251576][1952:1954] CHIP:TOO: Secure Pairing Success
[1701713324.251591][1952:1954] CHIP:TOO: CASE establishment successful
[1701713324.251615][1952:1954] CHIP:CTL: Successfully finished commissioning step 'SendNOC'
[1701713324.251635][1952:1954] CHIP:CTL: No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks
[1701713324.251653][1952:1954] CHIP:CTL: Commissioning stage next step: 'SendNOC' -> 'ThreadNetworkSetup'
[1701713324.251678][1952:1954] CHIP:CTL: Performing next commissioning step 'ThreadNetworkSetup'
[1701713324.251745][1952:1954] CHIP:DMG: ICR moving to [AddingComm]
[1701713324.251815][1952:1954] CHIP:DMG: ICR moving to [AddedComma]
[1701713324.251922][1952:1954] CHIP:EM: <<< [E:56991i S:10026 M:122954604] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1701713324.251958][1952:1954] CHIP:IN: (S) Sending msg 122954604 on secure session with LSID: 10026
[1701713324.252053][1952:1954] CHIP:DMG: ICR moving to [CommandSen]
[1701713324.252112][1952:1954] CHIP:DMG: ICR moving to [AwaitingDe]
[1701713324.346152][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713324.347671][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713324.348002][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713324.348179][1952:1954] CHIP:EM: >>> [E:56991i S:10026 M:49571006] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1701713324.348214][1952:1954] CHIP:EM: Found matching exchange: 56991i, Delegate: 0xffff80050ff8
[1701713324.348247][1952:1954] CHIP:DMG: ICR moving to [ResponseRe]
[1701713324.348291][1952:1954] CHIP:DMG: InvokeResponseMessage =
[1701713324.348311][1952:1954] CHIP:DMG: {
[1701713324.348329][1952:1954] CHIP:DMG: suppressResponse = false,
[1701713324.348349][1952:1954] CHIP:DMG: InvokeResponseIBs =
[1701713324.348373][1952:1954] CHIP:DMG: [
[1701713324.348393][1952:1954] CHIP:DMG: InvokeResponseIB =
[1701713324.348420][1952:1954] CHIP:DMG: {
[1701713324.348440][1952:1954] CHIP:DMG: CommandDataIB =
[1701713324.348470][1952:1954] CHIP:DMG: {
[1701713324.348495][1952:1954] CHIP:DMG: CommandPathIB =
[1701713324.348527][1952:1954] CHIP:DMG: {
[1701713324.348559][1952:1954] CHIP:DMG: EndpointId = 0x0,
[1701713324.348592][1952:1954] CHIP:DMG: ClusterId = 0x31,
[1701713324.348623][1952:1954] CHIP:DMG: CommandId = 0x5,
[1701713324.348653][1952:1954] CHIP:DMG: },
[1701713324.348685][1952:1954] CHIP:DMG:
[1701713324.348711][1952:1954] CHIP:DMG: CommandFields =
[1701713324.348741][1952:1954] CHIP:DMG: {
[1701713324.348773][1952:1954] CHIP:DMG: 0x0 = 0,
[1701713324.348806][1952:1954] CHIP:DMG: 0x2 = 0,
[1701713324.348838][1952:1954] CHIP:DMG: },
[1701713324.348866][1952:1954] CHIP:DMG: },
[1701713324.348895][1952:1954] CHIP:DMG:
[1701713324.348915][1952:1954] CHIP:DMG: },
[1701713324.348940][1952:1954] CHIP:DMG:
[1701713324.348959][1952:1954] CHIP:DMG: ],
[1701713324.348984][1952:1954] CHIP:DMG:
[1701713324.349003][1952:1954] CHIP:DMG: InteractionModelRevision = 1
[1701713324.349022][1952:1954] CHIP:DMG: },
[1701713324.349073][1952:1954] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005
[1701713324.353590][1952:1954] CHIP:CTL: Received NetworkConfig response, networkingStatus=0
[1701713324.353631][1952:1954] CHIP:CTL: Successfully finished commissioning step 'ThreadNetworkSetup'
[1701713324.353651][1952:1954] CHIP:CTL: Commissioning stage next step: 'ThreadNetworkSetup' -> 'ThreadNetworkEnable'
[1701713324.353678][1952:1954] CHIP:CTL: Performing next commissioning step 'ThreadNetworkEnable'
[1701713324.353750][1952:1954] CHIP:DMG: ICR moving to [AddingComm]
[1701713324.353779][1952:1954] CHIP:DMG: ICR moving to [AddedComma]
[1701713324.353894][1952:1954] CHIP:EM: <<< [E:56992i S:10026 M:122954605] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1701713324.353932][1952:1954] CHIP:IN: (S) Sending msg 122954605 on secure session with LSID: 10026
[1701713324.354031][1952:1954] CHIP:DMG: ICR moving to [CommandSen]
[1701713324.354088][1952:1954] CHIP:DMG: ICR moving to [AwaitingDe]
[1701713324.443726][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713325.762067][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713325.762618][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713325.762933][1952:1954] CHIP:EM: >>> [E:56992i S:10026 M:49571007] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1701713325.763014][1952:1954] CHIP:EM: Found matching exchange: 56992i, Delegate: 0xffff80050ca8
[1701713325.763097][1952:1954] CHIP:DMG: ICR moving to [ResponseRe]
[1701713325.763200][1952:1954] CHIP:DMG: InvokeResponseMessage =
[1701713325.763258][1952:1954] CHIP:DMG: {
[1701713325.763318][1952:1954] CHIP:DMG: suppressResponse = false,
[1701713325.763424][1952:1954] CHIP:DMG: InvokeResponseIBs =
[1701713325.763503][1952:1954] CHIP:DMG: [
[1701713325.763562][1952:1954] CHIP:DMG: InvokeResponseIB =
[1701713325.763641][1952:1954] CHIP:DMG: {
[1701713325.763702][1952:1954] CHIP:DMG: CommandDataIB =
[1701713325.763774][1952:1954] CHIP:DMG: {
[1701713325.763841][1952:1954] CHIP:DMG: CommandPathIB =
[1701713325.763922][1952:1954] CHIP:DMG: {
[1701713325.764001][1952:1954] CHIP:DMG: EndpointId = 0x0,
[1701713325.764082][1952:1954] CHIP:DMG: ClusterId = 0x31,
[1701713325.764158][1952:1954] CHIP:DMG: CommandId = 0x7,
[1701713325.764251][1952:1954] CHIP:DMG: },
[1701713325.764331][1952:1954] CHIP:DMG:
[1701713325.764401][1952:1954] CHIP:DMG: CommandFields =
[1701713325.764476][1952:1954] CHIP:DMG: {
[1701713325.764551][1952:1954] CHIP:DMG: 0x0 = 0,
[1701713325.764639][1952:1954] CHIP:DMG: 0x2 = NULL
[1701713325.764718][1952:1954] CHIP:DMG: },
[1701713325.764790][1952:1954] CHIP:DMG: },
[1701713325.764866][1952:1954] CHIP:DMG:
[1701713325.764929][1952:1954] CHIP:DMG: },
[1701713325.765003][1952:1954] CHIP:DMG:
[1701713325.765060][1952:1954] CHIP:DMG: ],
[1701713325.765132][1952:1954] CHIP:DMG:
[1701713325.765189][1952:1954] CHIP:DMG: InteractionModelRevision = 1
[1701713325.765245][1952:1954] CHIP:DMG: },
[1701713325.765383][1952:1954] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0007
[1701713325.765538][1952:1954] CHIP:CTL: Received ConnectNetwork response, networkingStatus=0
[1701713325.765607][1952:1954] CHIP:CTL: Successfully finished commissioning step 'ThreadNetworkEnable'
[1701713325.765663][1952:1954] CHIP:CTL: Commissioning stage next step: 'ThreadNetworkEnable' -> 'FindOperational'
[1701713325.765726][1952:1954] CHIP:CTL: Performing next commissioning step 'FindOperational'
[1701713325.765778][1952:1954] CHIP:CSM: FindOrEstablishSession: PeerId = [1:000000000000159F]
[1701713325.765830][1952:1954] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found
[1701713325.765900][1952:1954] CHIP:DIS: OperationalSessionSetup[1:000000000000159F]: State change 1 --> 2
[1701713325.766586][1952:1954] CHIP:DMG: ICR moving to [AwaitingDe]
[1701713325.966369][1952:1954] CHIP:DIS: Checking node lookup status after 201 ms
[1701713328.344588][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713330.830327][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713330.830689][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713333.413909][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713335.900425][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713335.900562][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713338.484006][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713340.971449][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713340.971933][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713343.554722][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713346.040684][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713346.040926][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713348.624211][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713351.110773][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713351.110914][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713353.694360][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713356.180903][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713356.181140][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713356.768987][1952:1954] CHIP:DIS: Timeout waiting for mDNS resolution.
[1701713358.764851][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713361.251869][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713361.252121][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713363.834553][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713366.321128][1952:1953] CHIP:DL: Indication received, conn = 0xffff8004efe0
[1701713366.321489][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1701713368.904669][1952:1954] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1701713370.767662][1952:1954] CHIP:DIS: Checking node lookup status after 45002 ms
[1701713370.767728][1952:1954] CHIP:DIS: OperationalSessionSetup[1:000000000000159F]: operational discovery failed: ../../examples/chip-tool/third_party/connectedhomeip/src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:96: CHIP Error 0x00000032: Timeout
[1701713370.767755][1952:1954] CHIP:CTL: Device connection failed. Error ../../examples/chip-tool/third_party/connectedhomeip/src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:96: CHIP Error 0x00000032: Timeout
[1701713370.767788][1952:1954] CHIP:CTL: Error on commissioning step 'FindOperational': '../../examples/chip-tool/third_party/connectedhomeip/src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:96: CHIP Error 0x00000032: Timeout'
[1701713370.767807][1952:1954] CHIP:CTL: Failed to perform commissioning step 18
[1701713370.767828][1952:1954] CHIP:CTL: Going from commissioning step 'FindOperational' with lastErr = '../../examples/chip-tool/third_party/connectedhomeip/src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:96: CHIP Error 0x00000032: Timeout' -> 'Cleanup'
[1701713370.767860][1952:1954] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = '../../examples/chip-tool/third_party/connectedhomeip/src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:96: CHIP Error 0x00000032: Timeout'
[1701713370.767881][1952:1954] CHIP:CTL: Successfully finished commissioning step 'Cleanup'
[1701713370.767909][1952:1954] CHIP:TOO: Device commissioning Failure: ../../examples/chip-tool/third_party/connectedhomeip/src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:96: CHIP Error 0x00000032: Timeout
[1701713370.768265][1952:1952] CHIP:CTL: Shutting down the commissioner
[1701713370.768300][1952:1952] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1701713370.768417][1952:1952] CHIP:DIS: Closing all BLE connections
[1701713370.768444][1952:1952] CHIP:IN: Clearing BLE pending packets.
[1701713370.768510][1952:1952] CHIP:BLE: Auto-closing end point's BLE connection.
[1701713370.768531][1952:1952] CHIP:DL: Closing BLE GATT connection (con 0xffff8004efe0)
[1701713370.768570][1952:1952] CHIP:IN: SecureSession[0xffff7800b170]: MarkForEviction Type:1 LSID:10026
[1701713370.768588][1952:1952] CHIP:SC: SecureSession[0xffff7800b170]: Moving from state 'kActive' --> 'kPendingEviction'
[1701713370.768636][1952:1952] CHIP:IN: SecureSession[0xffff7800b170]: Released - Type:1 LSID:10026
[1701713370.768660][1952:1952] CHIP:CTL: Shutting down the controller
[1701713370.768680][1952:1952] CHIP:IN: Expiring all sessions for fabric 0x1!!
[1701713370.768696][1952:1952] CHIP:FP: Forgetting fabric 0x1
[1701713370.768724][1952:1952] CHIP:TS: Pending Last Known Good Time: 2023-07-24T17:52:58
[1701713370.768819][1952:1953] CHIP:DL: BluezDisconnect peer=B4:3A:31:EB:2A:87
[1701713370.768875][1952:1952] CHIP:TS: Previous Last Known Good Time: 2023-07-24T17:52:58
[1701713370.768893][1952:1952] CHIP:TS: Reverted Last Known Good Time to previous value
[1701713370.768934][1952:1952] CHIP:CTL: Shutting down the commissioner
[1701713370.768951][1952:1952] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1701713370.769051][1952:1952] CHIP:CTL: Shutting down the controller
[1701713370.769070][1952:1952] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack
[1701713370.769238][1952:1952] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet
[1701713370.769327][1952:1952] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented.
[1701713370.769360][1952:1952] CHIP:FP: Shutting down FabricTable
[1701713370.769386][1952:1952] CHIP:TS: Pending Last Known Good Time: 2023-07-24T17:52:58
[1701713370.769474][1952:1952] CHIP:TS: Previous Last Known Good Time: 2023-07-24T17:52:58
[1701713370.769494][1952:1952] CHIP:TS: Reverted Last Known Good Time to previous value
[1701713370.769766][1952:1952] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-tE7bEC)
[1701713370.770499][1952:1952] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1701713370.770565][1952:1952] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1701713370.770586][1952:1952] CHIP:DL: Inet Layer shutdown
[1701713370.770602][1952:1952] CHIP:DL: BLE shutdown
[1701713370.771619][1952:1952] CHIP:DL: System Layer shutdown
[1701713372.661141][1952:1952] CHIP:TOO: Run command failure: ../../examples/chip-tool/third_party/connectedhomeip/src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:96: CHIP Error 0x00000032: Timeout
Beta Was this translation helpful? Give feedback.
All reactions