Hi
I am currently working on a Matter project.
I configured OTBR with Raspberry Pi, completed pairing with nRF52840 board (Light bulb), and confirmed that Light on/off works (use to chiptool command).
However, after rebooting the OTBR, the Chip tool light on/off command is not delivered to the Enddevice.
I checked the topology of Otbr web and found it was still connecting.
When Otbr is rebooted, the n52840 board log is also output as shown below.
D: 4171091 [DL]OpenThread State Changed (Flags: 0x00000200) I: 4171101 [DL]SRP Client was stopped, because current server is no longer detected. D: 4171109 [DL]OpenThread State Changed (Flags: 0x00000200) D: 4171116 [DL]OpenThread State Changed (Flags: 0x00000002) D: 4171121 [DL] Thread Unicast Addresses: D: 4171125 [DL] fd15:f73b:9669:466a:0:ff:fe00:0/64 valid rloc D: 4171131 [DL] fd15:f73b:9669:466a:5d79:1733:146f:bf/64 valid D: 4171138 [DL] fe80::90b7:f629:a2c8:a8a9/64 valid preferred D: 4171733 [DL]OpenThread State Changed (Flags: 0x00000200) D: 4171740 [DL]OpenThread State Changed (Flags: 0x00000001) D: 4171745 [DL] Thread Unicast Addresses: D: 4171749 [DL] fdfa:abe6:eb46:1:bbd8:7c9:d4cd:5521/64 valid preferred D: 4171756 [DL] fd15:f73b:9669:466a:0:ff:fe00:0/64 valid rloc D: 4171763 [DL] fd15:f73b:9669:466a:5d79:1733:146f:bf/64 valid D: 4171769 [DL] fe80::90b7:f629:a2c8:a8a9/64 valid preferred D: 4172039 [DL]OpenThread State Changed (Flags: 0x00000200) I: 4172062 [DL]SRP Client was started, detected server: fd15:f73b:9669:466a:34a3:c700:bb6a:851f D: 4172071 [DL]OpenThread State Changed (Flags: 0x00000200) D: 4172383 [DL]OpenThread State Changed (Flags: 0x00000200) D: 4173224 [DL]OnSrpClientNotification: Last requested operation completed successfully D: 4174363 [DL]OpenThread State Changed (Flags: 0x00000200) |
OTBT Chip tool command error log
coway@raspberrypi:~/Matter-aws-iot-device-sdk-python-v2/samples $ ./chip-tool-release onoff on 303 1 [1661331506.060391][826:826] CHIP:DL: writing settings to file (/tmp/chip_kvs-d8q5SE) [1661331506.061100][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1661331506.062555][826:826] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-L5eWAG) [1661331506.062972][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1661331506.063473][826:826] CHIP:DL: writing settings to file (/tmp/chip_config.ini-r8I1FC) [1661331506.063824][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1661331506.064257][826:826] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-sWonJC) [1661331506.064720][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1661331506.065443][826:826] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-ZB9L9F) [1661331506.066898][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1661331506.067057][826:826] CHIP:DL: NVS set: chip-factory/unique-id = "4DCFB3E2508D3059" [1661331506.067436][826:826] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-TIFWUC) [1661331506.069748][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1661331506.069907][826:826] CHIP:DL: NVS set: chip-factory/vendor-id = 65521 (0xFFF1) [1661331506.070303][826:826] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-bVMcVC) [1661331506.076736][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1661331506.076896][826:826] CHIP:DL: NVS set: chip-factory/product-id = 32769 (0x8001) [1661331506.077319][826:826] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-Lcql9C) [1661331506.078694][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1661331506.078841][826:826] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1) [1661331506.079222][826:826] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-1sWe9E) [1661331506.080811][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1661331506.080997][826:826] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1661331506.081600][826:826] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-eaTgJE) [1661331506.083198][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1661331506.083437][826:826] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0) [1661331506.083822][826:826] CHIP:DL: writing settings to file (/tmp/chip_config.ini-uShRTC) [1661331506.085155][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1661331506.085305][826:826] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0) [1661331506.085710][826:826] CHIP:DL: writing settings to file (/tmp/chip_config.ini-LMMjJG) [1661331506.087302][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1661331506.087487][826:826] CHIP:DL: NVS set: chip-config/location-capability = 2 (0x2) [1661331506.384761][826:826] CHIP:DL: Got Ethernet interface: eth0 [1661331506.386038][826:826] CHIP:DL: Found the primary Ethernet interface:eth0 [1661331506.387166][826:826] CHIP:DL: Got WiFi interface: wlan0 [1661331506.394028][826:826] CHIP:DL: Found the primary WiFi interface:wlan0 [1661331506.406148][826:826] CHIP:ZCL: Using ZAP configuration... [1661331506.419038][826:826] CHIP:DL: Avahi client registered [1661331506.429245][826:826] CHIP:CTL: Setting attestation nonce to random value [1661331506.429468][826:826] CHIP:CTL: Setting CSR nonce to random value [1661331506.431702][826:826] CHIP:CTL: Setting attestation nonce to random value [1661331506.431889][826:826] CHIP:CTL: Setting CSR nonce to random value [1661331506.433076][826:826] CHIP:CTL: Couldn't get ExampleOpCredsCAKey from storage: ../../examples/chip-tool/co nfig/PersistentStorage.cpp:108: CHIP Error 0x000000A0: Value not found in the persisted storage [1661331506.444114][826:826] CHIP:CTL: Couldn't get ExampleOpCredsICAKey from storage: ../../examples/chip-tool/c onfig/PersistentStorage.cpp:108: CHIP Error 0x000000A0: Value not found in the persisted storage [1661331506.451870][826:826] CHIP:CTL: Generating RCAC [1661331506.518041][826:826] CHIP:CTL: Generating ICAC [1661331506.521138][826:826] CHIP:CTL: Generating NOC [1661331506.525146][826:826] CHIP:DIS: Verifying the received credentials [1661331506.533023][826:826] CHIP:DIS: Added new fabric at index: 0x1, Initialized: 1 [1661331506.533156][826:826] CHIP:DIS: Assigned compressed fabric ID: 0x0BB67AF65BEC5973, node ID: 0x000000000001 B669 [1661331506.553574][826:826] CHIP:DIS: Fabric (1) persisted to storage. Calling OnFabricPersistedToStorage [1661331506.556021][826:826] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x0BB67AF65BEC5973 [1661331506.556344][826:826] CHIP:CTL: Setting attestation nonce to random value [1661331506.556660][826:826] CHIP:CTL: Setting CSR nonce to random value [1661331506.561272][826:826] CHIP:CTL: Generating NOC [1661331506.564180][826:826] CHIP:DIS: Verifying the received credentials [1661331506.571487][826:826] CHIP:DIS: Added new fabric at index: 0x2, Initialized: 1 [1661331506.571611][826:826] CHIP:DIS: Assigned compressed fabric ID: 0x09FDD1D7A4163087, node ID: 0x000000000001 B669 [1661331506.586235][826:826] CHIP:DIS: Fabric (2) persisted to storage. Calling OnFabricPersistedToStorage [1661331506.600002][826:826] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x09FDD1D7A4163087 [1661331506.600364][826:826] CHIP:CTL: Setting attestation nonce to random value [1661331506.600978][826:826] CHIP:CTL: Setting CSR nonce to random value [1661331506.605343][826:826] CHIP:CTL: Generating NOC [1661331506.609513][826:826] CHIP:DIS: Verifying the received credentials [1661331506.617269][826:826] CHIP:DIS: Added new fabric at index: 0x3, Initialized: 1 [1661331506.617523][826:826] CHIP:DIS: Assigned compressed fabric ID: 0xE2E7646293B8FDDD, node ID: 0x000000000001 B669 [1661331506.632867][826:826] CHIP:DIS: Fabric (3) persisted to storage. Calling OnFabricPersistedToStorage [1661331506.635891][826:826] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0xE2E7646293B8FDDD [1661331508.993454][826:831] CHIP:TOO: Sending command to node 0x12f [1661331508.993700][826:831] CHIP:DIS: Resolving 0BB67AF65BEC5973:000000000000012F ... [1661331509.194093][826:831] CHIP:DIS: Checking node lookup status after 200 ms [1661331513.997950][826:831] CHIP:DL: Re-trying resolve [1661331518.993731][826:826] CHIP:DIS: Cancelling incomplete address resolution as device is being deleted. [1661331518.993900][826:826] CHIP:DIS: Discovery does not require any more timeouts [1661331518.996741][826:826] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1661331518.997508][826:826] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-pSjY4C) [1661331519.000148][826:826] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1661331519.000335][826:826] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1661331519.000415][826:826] CHIP:DL: Inet Layer shutdown [1661331519.000477][826:826] CHIP:DL: BLE shutdown [1661331519.000609][826:826] CHIP:DL: System Layer shutdown [1661331519.000919][826:826] CHIP:TOO: Run command failure: ../../examples/chip-tool/commands/common/CHIPCommand.cpp:397: CHIP Error 0x00000032: Timeout |
After enddevice and Otbr are paired, if Otbr is rebooted, do I have to unconditionally pair again?
When tested using the Chip tool Android app, it was confirmed that the on/off command was delivered to the enddevice without re-pairing even if otbr was rebooted.
After pairing enddevcie and Otbr, even if Otbr reboots, please guide how to transfer to enddevice with chip tool command without re-pairing process.
Plesae guide
Thank you
Thank you